builder: mozilla-central_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-032 starttime: 1487269795.08 results: success (0) revision: bf6b9caab2c7eb3ebc642afd82bc19598829c6f3 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-02-16 10:29:55.082585) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-02-16 10:29:55.083042) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-02-16 10:29:55.083313) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-02-16 10:29:55.099219) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-16 10:29:55.099496) ========= bash -c 'wget -Oarchiver_client.py --no-check-certificate --tries=10 --waitretry=3 https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'wget -Oarchiver_client.py --no-check-certificate --tries=10 --waitretry=3 https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=745a4373a2a16df560f216e000000264-1487269794.885407-820900677 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-02-16 10:29:55-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 33.4M=0s 2017-02-16 10:29:55 (33.4 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.098241 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-16 10:29:55.210550) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-02-16 10:29:55.210860) ========= rm -rf scripts properties in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-rf', 'scripts', 'properties'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=745a4373a2a16df560f216e000000264-1487269794.885407-820900677 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.020343 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-02-16 10:29:55.259970) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-02-16 10:29:55.260319) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev bf6b9caab2c7eb3ebc642afd82bc19598829c6f3 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'python archiver_client.py mozharness --repo mozilla-central --rev bf6b9caab2c7eb3ebc642afd82bc19598829c6f3 --destination scripts --debug'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=745a4373a2a16df560f216e000000264-1487269794.885407-820900677 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-02-16 10:29:55,841 truncating revision to first 12 chars 2017-02-16 10:29:55,841 Setting DEBUG logging. 2017-02-16 10:29:55,841 attempt 1/10 2017-02-16 10:29:55,841 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/bf6b9caab2c7?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-02-16 10:29:56,653 unpacking tar archive at: mozilla-central-bf6b9caab2c7/testing/mozharness/ program finished with exit code 0 elapsedTime=1.182615 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-02-16 10:29:56.455927) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-02-16 10:29:56.456275) ========= script_repo_revision: bf6b9caab2c7eb3ebc642afd82bc19598829c6f3 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-02-16 10:29:56.456693) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-02-16 10:29:56.456972) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-02-16 10:29:56.465975) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 56 secs) (at 2017-02-16 10:29:56.466256) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=745a4373a2a16df560f216e000000264-1487269794.885407-820900677 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 10:29:57 INFO - MultiFileLogger online at 20170216 10:29:57 in /builds/slave/test 10:29:57 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 10:29:57 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 10:29:57 INFO - {'append_to_log': False, 10:29:57 INFO - 'base_work_dir': '/builds/slave/test', 10:29:57 INFO - 'blob_upload_branch': 'Firefox', 10:29:57 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 10:29:57 INFO - 'branch': 'Firefox', 10:29:57 INFO - 'buildbot_json_path': 'buildprops.json', 10:29:57 INFO - 'config_files': ('talos/linux_config.py',), 10:29:57 INFO - 'default_actions': ('clobber', 10:29:57 INFO - 'read-buildbot-config', 10:29:57 INFO - 'download-and-extract', 10:29:57 INFO - 'populate-webroot', 10:29:57 INFO - 'create-virtualenv', 10:29:57 INFO - 'install', 10:29:57 INFO - 'run-tests'), 10:29:57 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 10:29:57 INFO - 'download_minidump_stackwalk': True, 10:29:57 INFO - 'download_symbols': 'ondemand', 10:29:57 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 10:29:57 INFO - 'tooltool.py': '/tools/tooltool.py', 10:29:57 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 10:29:57 INFO - '/tools/misc-python/virtualenv.py')}, 10:29:57 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 10:29:57 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 10:29:57 INFO - 'installer_path': 'installer.exe', 10:29:57 INFO - 'log_level': 'info', 10:29:57 INFO - 'log_name': 'talos', 10:29:57 INFO - 'log_to_console': True, 10:29:57 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 10:29:57 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 10:29:57 INFO - 'opt_config_files': (), 10:29:57 INFO - 'pip_index': False, 10:29:57 INFO - 'sps_profile': False, 10:29:57 INFO - 'sps_profile_interval': 0, 10:29:57 INFO - 'suite': 'dromaeojs', 10:29:57 INFO - 'system_bits': '32', 10:29:57 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 10:29:57 INFO - 'title': 'talos-linux64-ix-032', 10:29:57 INFO - 'tooltool_cache': '/builds/tooltool_cache', 10:29:57 INFO - 'use_talos_json': True, 10:29:57 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 10:29:57 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 10:29:57 INFO - 'work_dir': 'build'} 10:29:57 INFO - [mozharness: 2017-02-16 18:29:57.144640Z] Running clobber step. 10:29:57 INFO - Running pre-action listener: _resource_record_pre_action 10:29:57 INFO - Running main action method: clobber 10:29:57 INFO - rmtree: /builds/slave/test/build 10:29:57 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 10:29:58 INFO - Running post-action listener: _resource_record_post_action 10:29:58 INFO - [mozharness: 2017-02-16 18:29:58.078918Z] Finished clobber step (success) 10:29:58 INFO - [mozharness: 2017-02-16 18:29:58.079020Z] Running read-buildbot-config step. 10:29:58 INFO - Running pre-action listener: _resource_record_pre_action 10:29:58 INFO - Running main action method: read_buildbot_config 10:29:58 INFO - Using buildbot properties: 10:29:58 INFO - { 10:29:58 INFO - "project": "", 10:29:58 INFO - "product": "firefox", 10:29:58 INFO - "who": "kwierso@gmail.com", 10:29:58 INFO - "installer_path": "public/build/target.tar.bz2", 10:29:58 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs", 10:29:58 INFO - "repository": "https://hg.mozilla.org/mozilla-central/", 10:29:58 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central pgo talos dromaeojs", 10:29:58 INFO - "stage_platform": "linux64-pgo", 10:29:58 INFO - "basedir": "/builds/slave/test", 10:29:58 INFO - "buildnumber": 34, 10:29:58 INFO - "platform": "ubuntu64_hw", 10:29:58 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 10:29:58 INFO - "slavebuilddir": "test", 10:29:58 INFO - "taskId": "aI93PPZuR7izKyvKxJDR-A", 10:29:58 INFO - "branch": "mozilla-central", 10:29:58 INFO - "script_repo_revision": "production", 10:29:58 INFO - "revision": "bf6b9caab2c7eb3ebc642afd82bc19598829c6f3", 10:29:58 INFO - "slavename": "talos-linux64-ix-032", 10:29:58 INFO - "repo_path": "mozilla-central" 10:29:58 INFO - } 10:29:58 INFO - Finding installer, test and symbols from parent task. 10:29:58 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/aI93PPZuR7izKyvKxJDR-A'}, attempt #1 10:29:58 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/aI93PPZuR7izKyvKxJDR-A'}, attempt #1 10:29:59 INFO - Task dependencies: Sy3PDECGSdmNdwU90aRM8g 10:29:59 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Sy3PDECGSdmNdwU90aRM8g'}, attempt #1 10:29:59 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/Sy3PDECGSdmNdwU90aRM8g/artifacts/public/build/target.tar.bz2 10:29:59 INFO - Set test_url: https://queue.taskcluster.net/v1/task/Sy3PDECGSdmNdwU90aRM8g/artifacts/public/build/test_packages.json 10:29:59 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/Sy3PDECGSdmNdwU90aRM8g/artifacts/public/build/target.crashreporter-symbols.zip 10:29:59 INFO - Running post-action listener: _resource_record_post_action 10:29:59 INFO - [mozharness: 2017-02-16 18:29:59.535531Z] Finished read-buildbot-config step (success) 10:29:59 INFO - [mozharness: 2017-02-16 18:29:59.535747Z] Running download-and-extract step. 10:29:59 INFO - Running pre-action listener: _resource_record_pre_action 10:29:59 INFO - Running main action method: download_and_extract 10:29:59 INFO - mkdir: /builds/slave/test/build/tests 10:29:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:29:59 INFO - trying https://queue.taskcluster.net/v1/task/Sy3PDECGSdmNdwU90aRM8g/artifacts/public/build/target.test_packages.json 10:29:59 INFO - Downloading https://queue.taskcluster.net/v1/task/Sy3PDECGSdmNdwU90aRM8g/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 10:29:59 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Sy3PDECGSdmNdwU90aRM8g/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 10:30:00 INFO - Downloaded 896 bytes. 10:30:00 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 10:30:00 INFO - Using the following test package requirements: 10:30:00 INFO - {u'common': [u'target.common.tests.zip'], 10:30:00 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 10:30:00 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 10:30:00 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 10:30:00 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 10:30:00 INFO - u'mozbase': [u'target.common.tests.zip'], 10:30:00 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 10:30:00 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 10:30:00 INFO - u'web-platform': [u'target.common.tests.zip', 10:30:00 INFO - u'target.web-platform.tests.zip'], 10:30:00 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 10:30:00 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 10:30:00 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Sy3PDECGSdmNdwU90aRM8g/artifacts/public/build/target.common.tests.zip 10:30:00 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Sy3PDECGSdmNdwU90aRM8g/artifacts/public/build/target.common.tests.zip'}, attempt #1 10:30:00 INFO - Fetch https://queue.taskcluster.net/v1/task/Sy3PDECGSdmNdwU90aRM8g/artifacts/public/build/target.common.tests.zip into memory 10:30:01 INFO - Expected file size: 59046498 10:30:01 INFO - Obtained file size: 59046498 10:30:11 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 10:30:11 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Sy3PDECGSdmNdwU90aRM8g/artifacts/public/build/target.common.tests.zip 10:30:11 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Sy3PDECGSdmNdwU90aRM8g/artifacts/public/build/target.common.tests.zip'}, attempt #1 10:30:11 INFO - Fetch https://queue.taskcluster.net/v1/task/Sy3PDECGSdmNdwU90aRM8g/artifacts/public/build/target.common.tests.zip into memory 10:30:12 INFO - Expected file size: 59046498 10:30:12 INFO - Obtained file size: 59046498 10:30:22 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Sy3PDECGSdmNdwU90aRM8g/artifacts/public/build/target.talos.tests.zip 10:30:22 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Sy3PDECGSdmNdwU90aRM8g/artifacts/public/build/target.talos.tests.zip'}, attempt #1 10:30:22 INFO - Fetch https://queue.taskcluster.net/v1/task/Sy3PDECGSdmNdwU90aRM8g/artifacts/public/build/target.talos.tests.zip into memory 10:30:23 INFO - Expected file size: 14403647 10:30:23 INFO - Obtained file size: 14403647 10:30:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:30:23 INFO - trying https://queue.taskcluster.net/v1/task/Sy3PDECGSdmNdwU90aRM8g/artifacts/public/build/target.tar.bz2 10:30:23 INFO - Downloading https://queue.taskcluster.net/v1/task/Sy3PDECGSdmNdwU90aRM8g/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 10:30:23 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Sy3PDECGSdmNdwU90aRM8g/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 10:30:24 INFO - Downloaded 61903687 bytes. 10:30:24 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Sy3PDECGSdmNdwU90aRM8g/artifacts/public/build/target.tar.bz2 10:30:24 INFO - mkdir: /builds/slave/test/properties 10:30:24 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 10:30:24 INFO - Writing to file /builds/slave/test/properties/build_url 10:30:24 INFO - Contents: 10:30:24 INFO - build_url:https://queue.taskcluster.net/v1/task/Sy3PDECGSdmNdwU90aRM8g/artifacts/public/build/target.tar.bz2 10:30:24 INFO - Running post-action listener: _resource_record_post_action 10:30:24 INFO - Running post-action listener: set_extra_try_arguments 10:30:24 INFO - [mozharness: 2017-02-16 18:30:24.830008Z] Finished download-and-extract step (success) 10:30:24 INFO - [mozharness: 2017-02-16 18:30:24.830104Z] Running populate-webroot step. 10:30:24 INFO - Running pre-action listener: _resource_record_pre_action 10:30:24 INFO - Running main action method: populate_webroot 10:30:24 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 10:30:24 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 10:30:24 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 10:30:24 INFO - '--tppagecycles', 10:30:24 INFO - '7'], 10:30:24 INFO - 'tests': ['tsvgm']}}, 10:30:24 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 10:30:24 INFO - 'tests': ['tresize', 'tcanvasmark']}, 10:30:24 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 10:30:24 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 10:30:24 INFO - 'tests': ['dromaeo_css', 'kraken']}, 10:30:24 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 10:30:24 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 10:30:24 INFO - 'talos_options': ['--disable-e10s'], 10:30:24 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 10:30:24 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 10:30:24 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 10:30:24 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 10:30:24 INFO - 'talos_options': ['--disable-e10s'], 10:30:24 INFO - 'tests': ['damp', 'tps']}, 10:30:24 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 10:30:24 INFO - 'tests': ['damp', 'tps']}, 10:30:24 INFO - 'g3': {'talos_options': ['--disable-e10s'], 10:30:24 INFO - 'tests': ['dromaeo_dom']}, 10:30:24 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 10:30:24 INFO - 'g4': {'talos_options': ['--disable-e10s'], 10:30:24 INFO - 'tests': ['basic_compositor_video']}, 10:30:24 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 10:30:24 INFO - 'other': {'talos_options': ['--disable-e10s'], 10:30:24 INFO - 'tests': ['a11yr', 10:30:24 INFO - 'ts_paint', 10:30:24 INFO - 'tpaint', 10:30:24 INFO - 'sessionrestore', 10:30:24 INFO - 'sessionrestore_no_auto_restore', 10:30:24 INFO - 'tabpaint']}, 10:30:24 INFO - 'other-e10s': {'tests': ['a11yr', 10:30:24 INFO - 'ts_paint', 10:30:24 INFO - 'tpaint', 10:30:24 INFO - 'sessionrestore', 10:30:24 INFO - 'sessionrestore_no_auto_restore', 10:30:24 INFO - 'tabpaint']}, 10:30:24 INFO - 'other-e10s_l64': {'tests': ['a11yr', 10:30:24 INFO - 'ts_paint', 10:30:24 INFO - 'tpaint', 10:30:24 INFO - 'sessionrestore', 10:30:24 INFO - 'sessionrestore_no_auto_restore', 10:30:24 INFO - 'tabpaint']}, 10:30:24 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 10:30:24 INFO - 'ts_paint', 10:30:24 INFO - 'tpaint', 10:30:24 INFO - 'sessionrestore', 10:30:24 INFO - 'sessionrestore_no_auto_restore', 10:30:24 INFO - 'tabpaint']}, 10:30:24 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 10:30:24 INFO - 'tests': ['a11yr', 10:30:24 INFO - 'ts_paint', 10:30:24 INFO - 'tpaint', 10:30:24 INFO - 'sessionrestore', 10:30:24 INFO - 'sessionrestore_no_auto_restore', 10:30:24 INFO - 'tabpaint']}, 10:30:24 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 10:30:24 INFO - 'tests': ['a11yr', 10:30:24 INFO - 'ts_paint', 10:30:24 INFO - 'tpaint', 10:30:24 INFO - 'sessionrestore', 10:30:24 INFO - 'sessionrestore_no_auto_restore', 10:30:24 INFO - 'tabpaint']}, 10:30:24 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 10:30:24 INFO - 'tests': ['tsvgx', 10:30:24 INFO - 'tsvgr_opacity', 10:30:24 INFO - 'tart', 10:30:24 INFO - 'tscrollx', 10:30:24 INFO - 'cart', 10:30:24 INFO - 'tsvg_static']}, 10:30:24 INFO - 'svgr-e10s': {'tests': ['tsvgx', 10:30:24 INFO - 'tsvgr_opacity', 10:30:24 INFO - 'tart', 10:30:24 INFO - 'tscrollx', 10:30:24 INFO - 'cart', 10:30:24 INFO - 'tsvg_static']}, 10:30:24 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 10:30:24 INFO - 'talos_options': ['--disable-e10s'], 10:30:24 INFO - 'tests': ['tp5o']}, 10:30:24 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 10:30:24 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 10:30:24 INFO - 'talos_options': ['--disable-e10s', 10:30:24 INFO - '--xperf_path', 10:30:24 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 10:30:24 INFO - 'tests': ['tp5n']}, 10:30:24 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 10:30:24 INFO - 'talos_options': ['--xperf_path', 10:30:24 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 10:30:24 INFO - 'tests': ['tp5n']}}, 10:30:24 INFO - 'talos.zip': {'path': '', 10:30:24 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 10:30:24 INFO - Running post-action listener: _resource_record_post_action 10:30:24 INFO - [mozharness: 2017-02-16 18:30:24.843082Z] Finished populate-webroot step (success) 10:30:24 INFO - [mozharness: 2017-02-16 18:30:24.843174Z] Running create-virtualenv step. 10:30:24 INFO - Running pre-action listener: _resource_record_pre_action 10:30:24 INFO - Running main action method: create_virtualenv 10:30:24 INFO - Creating virtualenv /builds/slave/test/build/venv 10:30:24 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 10:30:24 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 10:30:24 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 10:30:24 INFO - Using real prefix '/usr' 10:30:24 INFO - New python executable in /builds/slave/test/build/venv/bin/python 10:30:25 INFO - Installing distribute.............................................................................................................................................................................................done. 10:30:27 INFO - Installing pip.................done. 10:30:27 INFO - Return code: 0 10:30:27 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 10:30:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 10:30:27 INFO - Reading from file tmpfile_stdout 10:30:27 INFO - Output received: 10:30:27 INFO - pip 1.5.5 from /builds/slave/test/build/venv/local/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 10:30:27 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 10:30:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:30:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:30:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:30:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:30:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:30:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:30:27 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xb771f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xc71a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe17ed0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xea1540>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe15f70>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe16400>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1487269794.885407-820900677', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 10:30:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 10:30:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 10:30:27 INFO - Using env: {'DISPLAY': ':0', 10:30:27 INFO - 'HOME': '/home/cltbld', 10:30:27 INFO - 'LANG': 'en_US.UTF-8', 10:30:27 INFO - 'LANGUAGE': 'en_US:en', 10:30:27 INFO - 'LOGNAME': 'cltbld', 10:30:27 INFO - 'MAIL': '/var/mail/cltbld', 10:30:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:30:27 INFO - 'MOZ_NO_REMOTE': '1', 10:30:27 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:30:27 INFO - 'NO_EM_RESTART': '1', 10:30:27 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:30:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:30:27 INFO - 'PWD': '/builds/slave/test', 10:30:27 INFO - 'SHELL': '/bin/bash', 10:30:27 INFO - 'SHLVL': '1', 10:30:27 INFO - 'TERM': 'linux', 10:30:27 INFO - 'TMOUT': '86400', 10:30:27 INFO - 'USER': 'cltbld', 10:30:27 INFO - 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1487269794.885407-820900677', 10:30:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:30:27 INFO - '_': '/tools/buildbot/bin/python'} 10:30:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:30:27 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 10:30:27 INFO - Cleaning up... 10:30:27 INFO - Return code: 0 10:30:27 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 10:30:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:30:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:30:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:30:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:30:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:30:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:30:27 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xb771f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xc71a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe17ed0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xea1540>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe15f70>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe16400>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1487269794.885407-820900677', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 10:30:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 10:30:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 10:30:27 INFO - Using env: {'DISPLAY': ':0', 10:30:27 INFO - 'HOME': '/home/cltbld', 10:30:27 INFO - 'LANG': 'en_US.UTF-8', 10:30:27 INFO - 'LANGUAGE': 'en_US:en', 10:30:27 INFO - 'LOGNAME': 'cltbld', 10:30:27 INFO - 'MAIL': '/var/mail/cltbld', 10:30:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:30:27 INFO - 'MOZ_NO_REMOTE': '1', 10:30:27 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:30:27 INFO - 'NO_EM_RESTART': '1', 10:30:27 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:30:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:30:27 INFO - 'PWD': '/builds/slave/test', 10:30:27 INFO - 'SHELL': '/bin/bash', 10:30:27 INFO - 'SHLVL': '1', 10:30:27 INFO - 'TERM': 'linux', 10:30:27 INFO - 'TMOUT': '86400', 10:30:27 INFO - 'USER': 'cltbld', 10:30:27 INFO - 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1487269794.885407-820900677', 10:30:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:30:27 INFO - '_': '/tools/buildbot/bin/python'} 10:30:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:30:28 INFO - Downloading/unpacking psutil>=3.1.1 10:30:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:30:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:30:28 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:30:28 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:30:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:30:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:30:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 10:30:30 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 10:30:30 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 10:30:30 INFO - Installing collected packages: psutil 10:30:30 INFO - Running setup.py install for psutil 10:30:30 INFO - building 'psutil._psutil_linux' extension 10:30:30 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 10:30:30 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 10:30:30 INFO - building 'psutil._psutil_posix' extension 10:30:30 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 10:30:30 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 10:30:30 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 10:30:30 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 10:30:30 INFO - Successfully installed psutil 10:30:30 INFO - Cleaning up... 10:30:30 INFO - Return code: 0 10:30:30 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 10:30:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:30:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:30:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:30:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:30:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:30:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:30:30 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xb771f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xc71a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe17ed0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xea1540>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe15f70>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe16400>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1487269794.885407-820900677', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 10:30:30 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 10:30:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 10:30:30 INFO - Using env: {'DISPLAY': ':0', 10:30:30 INFO - 'HOME': '/home/cltbld', 10:30:30 INFO - 'LANG': 'en_US.UTF-8', 10:30:30 INFO - 'LANGUAGE': 'en_US:en', 10:30:30 INFO - 'LOGNAME': 'cltbld', 10:30:30 INFO - 'MAIL': '/var/mail/cltbld', 10:30:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:30:30 INFO - 'MOZ_NO_REMOTE': '1', 10:30:30 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:30:30 INFO - 'NO_EM_RESTART': '1', 10:30:30 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:30:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:30:30 INFO - 'PWD': '/builds/slave/test', 10:30:30 INFO - 'SHELL': '/bin/bash', 10:30:30 INFO - 'SHLVL': '1', 10:30:30 INFO - 'TERM': 'linux', 10:30:30 INFO - 'TMOUT': '86400', 10:30:30 INFO - 'USER': 'cltbld', 10:30:30 INFO - 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1487269794.885407-820900677', 10:30:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:30:30 INFO - '_': '/tools/buildbot/bin/python'} 10:30:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:30:31 INFO - Downloading/unpacking mozsystemmonitor==0.3 10:30:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:30:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:30:31 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:30:31 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:30:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:30:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:30:33 INFO - Downloading mozsystemmonitor-0.3.tar.gz 10:30:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 10:30:33 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 10:30:33 INFO - Installing collected packages: mozsystemmonitor 10:30:33 INFO - Running setup.py install for mozsystemmonitor 10:30:33 INFO - Successfully installed mozsystemmonitor 10:30:33 INFO - Cleaning up... 10:30:33 INFO - Return code: 0 10:30:33 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 10:30:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:30:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:30:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:30:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:30:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:30:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:30:33 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xb771f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xc71a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe17ed0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xea1540>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe15f70>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe16400>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1487269794.885407-820900677', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 10:30:33 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 10:30:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 10:30:33 INFO - Using env: {'DISPLAY': ':0', 10:30:33 INFO - 'HOME': '/home/cltbld', 10:30:33 INFO - 'LANG': 'en_US.UTF-8', 10:30:33 INFO - 'LANGUAGE': 'en_US:en', 10:30:33 INFO - 'LOGNAME': 'cltbld', 10:30:33 INFO - 'MAIL': '/var/mail/cltbld', 10:30:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:30:33 INFO - 'MOZ_NO_REMOTE': '1', 10:30:33 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:30:33 INFO - 'NO_EM_RESTART': '1', 10:30:33 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:30:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:30:33 INFO - 'PWD': '/builds/slave/test', 10:30:33 INFO - 'SHELL': '/bin/bash', 10:30:33 INFO - 'SHLVL': '1', 10:30:33 INFO - 'TERM': 'linux', 10:30:33 INFO - 'TMOUT': '86400', 10:30:33 INFO - 'USER': 'cltbld', 10:30:33 INFO - 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1487269794.885407-820900677', 10:30:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:30:33 INFO - '_': '/tools/buildbot/bin/python'} 10:30:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:30:33 INFO - Downloading/unpacking jsonschema==2.5.1 10:30:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:30:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:30:33 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:30:33 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:30:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:30:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:30:35 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 10:30:35 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 10:30:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:30:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:30:35 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:30:35 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:30:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:30:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:30:35 INFO - Downloading functools32-3.2.3-2.tar.gz 10:30:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 10:30:35 INFO - warning: no files found matching '*.txt' 10:30:35 INFO - no previously-included directories found matching 'build' 10:30:35 INFO - no previously-included directories found matching 'dist' 10:30:35 INFO - no previously-included directories found matching '.git*' 10:30:35 INFO - Installing collected packages: jsonschema, functools32 10:30:35 INFO - Running setup.py install for functools32 10:30:35 INFO - warning: no files found matching '*.txt' 10:30:35 INFO - no previously-included directories found matching 'build' 10:30:35 INFO - no previously-included directories found matching 'dist' 10:30:35 INFO - no previously-included directories found matching '.git*' 10:30:35 INFO - Successfully installed jsonschema functools32 10:30:35 INFO - Cleaning up... 10:30:35 INFO - Return code: 0 10:30:35 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 10:30:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:30:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:30:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:30:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:30:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:30:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:30:35 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xb771f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xc71a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe17ed0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xea1540>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe15f70>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe16400>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1487269794.885407-820900677', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 10:30:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 10:30:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 10:30:35 INFO - Using env: {'DISPLAY': ':0', 10:30:35 INFO - 'HOME': '/home/cltbld', 10:30:35 INFO - 'LANG': 'en_US.UTF-8', 10:30:35 INFO - 'LANGUAGE': 'en_US:en', 10:30:35 INFO - 'LOGNAME': 'cltbld', 10:30:35 INFO - 'MAIL': '/var/mail/cltbld', 10:30:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:30:35 INFO - 'MOZ_NO_REMOTE': '1', 10:30:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:30:35 INFO - 'NO_EM_RESTART': '1', 10:30:35 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:30:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:30:35 INFO - 'PWD': '/builds/slave/test', 10:30:35 INFO - 'SHELL': '/bin/bash', 10:30:35 INFO - 'SHLVL': '1', 10:30:35 INFO - 'TERM': 'linux', 10:30:35 INFO - 'TMOUT': '86400', 10:30:35 INFO - 'USER': 'cltbld', 10:30:35 INFO - 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1487269794.885407-820900677', 10:30:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:30:35 INFO - '_': '/tools/buildbot/bin/python'} 10:30:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:30:36 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 10:30:36 INFO - Cleaning up... 10:30:36 INFO - Return code: 0 10:30:36 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 10:30:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:30:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:30:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:30:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:30:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:30:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:30:36 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xb771f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xc71a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe17ed0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xea1540>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe15f70>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe16400>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1487269794.885407-820900677', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 10:30:36 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 10:30:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 10:30:36 INFO - Using env: {'DISPLAY': ':0', 10:30:36 INFO - 'HOME': '/home/cltbld', 10:30:36 INFO - 'LANG': 'en_US.UTF-8', 10:30:36 INFO - 'LANGUAGE': 'en_US:en', 10:30:36 INFO - 'LOGNAME': 'cltbld', 10:30:36 INFO - 'MAIL': '/var/mail/cltbld', 10:30:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:30:36 INFO - 'MOZ_NO_REMOTE': '1', 10:30:36 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:30:36 INFO - 'NO_EM_RESTART': '1', 10:30:36 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:30:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:30:36 INFO - 'PWD': '/builds/slave/test', 10:30:36 INFO - 'SHELL': '/bin/bash', 10:30:36 INFO - 'SHLVL': '1', 10:30:36 INFO - 'TERM': 'linux', 10:30:36 INFO - 'TMOUT': '86400', 10:30:36 INFO - 'USER': 'cltbld', 10:30:36 INFO - 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1487269794.885407-820900677', 10:30:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:30:36 INFO - '_': '/tools/buildbot/bin/python'} 10:30:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:30:36 INFO - Downloading/unpacking blobuploader==1.2.4 10:30:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:30:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:30:36 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:30:36 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:30:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:30:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:30:38 INFO - Downloading blobuploader-1.2.4.tar.gz 10:30:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 10:30:38 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 10:30:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:30:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:30:38 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:30:38 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:30:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:30:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:30:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 10:30:38 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 10:30:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:30:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:30:38 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:30:38 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:30:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:30:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:30:39 INFO - Downloading docopt-0.6.1.tar.gz 10:30:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 10:30:39 INFO - Installing collected packages: blobuploader, requests, docopt 10:30:39 INFO - Running setup.py install for blobuploader 10:30:39 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 10:30:39 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 10:30:39 INFO - Running setup.py install for requests 10:30:39 INFO - Running setup.py install for docopt 10:30:39 INFO - Successfully installed blobuploader requests docopt 10:30:39 INFO - Cleaning up... 10:30:39 INFO - Return code: 0 10:30:39 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:30:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:30:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:30:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:30:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:30:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:30:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:30:39 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xb771f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xc71a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe17ed0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xea1540>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe15f70>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe16400>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1487269794.885407-820900677', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 10:30:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 10:30:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 10:30:39 INFO - Using env: {'DISPLAY': ':0', 10:30:39 INFO - 'HOME': '/home/cltbld', 10:30:39 INFO - 'LANG': 'en_US.UTF-8', 10:30:39 INFO - 'LANGUAGE': 'en_US:en', 10:30:39 INFO - 'LOGNAME': 'cltbld', 10:30:39 INFO - 'MAIL': '/var/mail/cltbld', 10:30:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:30:39 INFO - 'MOZ_NO_REMOTE': '1', 10:30:39 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:30:39 INFO - 'NO_EM_RESTART': '1', 10:30:39 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:30:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:30:39 INFO - 'PWD': '/builds/slave/test', 10:30:39 INFO - 'SHELL': '/bin/bash', 10:30:39 INFO - 'SHLVL': '1', 10:30:39 INFO - 'TERM': 'linux', 10:30:39 INFO - 'TMOUT': '86400', 10:30:39 INFO - 'USER': 'cltbld', 10:30:39 INFO - 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1487269794.885407-820900677', 10:30:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:30:39 INFO - '_': '/tools/buildbot/bin/python'} 10:30:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:30:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 10:30:40 INFO - Running setup.py (path:/tmp/pip-AQhlON-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 10:30:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 10:30:40 INFO - Running setup.py (path:/tmp/pip-zI33ww-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 10:30:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 10:30:40 INFO - Running setup.py (path:/tmp/pip-fkhhHw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 10:30:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 10:30:40 INFO - Running setup.py (path:/tmp/pip-zIxKxc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 10:30:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 10:30:40 INFO - Running setup.py (path:/tmp/pip-EcAmyj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 10:30:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 10:30:40 INFO - Running setup.py (path:/tmp/pip-8GZZtn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 10:30:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 10:30:40 INFO - Running setup.py (path:/tmp/pip-aCa4pY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 10:30:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 10:30:40 INFO - Running setup.py (path:/tmp/pip-I329Ct-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 10:30:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 10:30:40 INFO - Running setup.py (path:/tmp/pip-bWeQWz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 10:30:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 10:30:40 INFO - Running setup.py (path:/tmp/pip-RH_WuW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 10:30:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 10:30:40 INFO - Running setup.py (path:/tmp/pip-8b9qiA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 10:30:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 10:30:40 INFO - Running setup.py (path:/tmp/pip-m7UQSN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 10:30:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 10:30:40 INFO - Running setup.py (path:/tmp/pip-ttkZnO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 10:30:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 10:30:40 INFO - Running setup.py (path:/tmp/pip-AU9Pdd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 10:30:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 10:30:41 INFO - Running setup.py (path:/tmp/pip-7ceoKf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 10:30:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 10:30:41 INFO - Running setup.py (path:/tmp/pip-4vJxrT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 10:30:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 10:30:41 INFO - Running setup.py (path:/tmp/pip-07n4RS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 10:30:41 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 10:30:41 INFO - Running setup.py install for manifestparser 10:30:41 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 10:30:41 INFO - Running setup.py install for mozcrash 10:30:41 INFO - Running setup.py install for mozdebug 10:30:41 INFO - Running setup.py install for mozdevice 10:30:41 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 10:30:41 INFO - Installing dm script to /builds/slave/test/build/venv/bin 10:30:41 INFO - Running setup.py install for mozfile 10:30:41 INFO - Running setup.py install for mozhttpd 10:30:41 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 10:30:41 INFO - Running setup.py install for mozinfo 10:30:41 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 10:30:41 INFO - Running setup.py install for mozInstall 10:30:42 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 10:30:42 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 10:30:42 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 10:30:42 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 10:30:42 INFO - Running setup.py install for mozleak 10:30:42 INFO - Running setup.py install for mozlog 10:30:42 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 10:30:42 INFO - Running setup.py install for moznetwork 10:30:42 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 10:30:42 INFO - Running setup.py install for mozprocess 10:30:42 INFO - Running setup.py install for mozprofile 10:30:42 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 10:30:42 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 10:30:42 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 10:30:42 INFO - Running setup.py install for mozrunner 10:30:42 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 10:30:42 INFO - Running setup.py install for mozscreenshot 10:30:42 INFO - Running setup.py install for moztest 10:30:42 INFO - Running setup.py install for mozversion 10:30:43 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 10:30:43 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 10:30:43 INFO - Cleaning up... 10:30:43 INFO - Return code: 0 10:30:43 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:30:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:30:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:30:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:30:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:30:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:30:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:30:43 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xb771f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xc71a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe17ed0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xea1540>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe15f70>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe16400>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1487269794.885407-820900677', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 10:30:43 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 10:30:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 10:30:43 INFO - Using env: {'DISPLAY': ':0', 10:30:43 INFO - 'HOME': '/home/cltbld', 10:30:43 INFO - 'LANG': 'en_US.UTF-8', 10:30:43 INFO - 'LANGUAGE': 'en_US:en', 10:30:43 INFO - 'LOGNAME': 'cltbld', 10:30:43 INFO - 'MAIL': '/var/mail/cltbld', 10:30:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:30:43 INFO - 'MOZ_NO_REMOTE': '1', 10:30:43 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:30:43 INFO - 'NO_EM_RESTART': '1', 10:30:43 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:30:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:30:43 INFO - 'PWD': '/builds/slave/test', 10:30:43 INFO - 'SHELL': '/bin/bash', 10:30:43 INFO - 'SHLVL': '1', 10:30:43 INFO - 'TERM': 'linux', 10:30:43 INFO - 'TMOUT': '86400', 10:30:43 INFO - 'USER': 'cltbld', 10:30:43 INFO - 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1487269794.885407-820900677', 10:30:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:30:43 INFO - '_': '/tools/buildbot/bin/python'} 10:30:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:30:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 10:30:43 INFO - Running setup.py (path:/tmp/pip-8pCYnc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 10:30:43 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 10:30:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 10:30:43 INFO - Running setup.py (path:/tmp/pip-zvsSbN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 10:30:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 10:30:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 10:30:43 INFO - Running setup.py (path:/tmp/pip-awh6dW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 10:30:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 10:30:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 10:30:43 INFO - Running setup.py (path:/tmp/pip-vRJ00G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 10:30:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 10:30:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 10:30:43 INFO - Running setup.py (path:/tmp/pip-Sq5VTG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 10:30:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 10:30:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 10:30:43 INFO - Running setup.py (path:/tmp/pip-2KA86Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 10:30:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 10:30:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 10:30:43 INFO - Running setup.py (path:/tmp/pip-X8s9d7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 10:30:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 10:30:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 10:30:43 INFO - Running setup.py (path:/tmp/pip-a9_7fp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 10:30:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 10:30:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 10:30:43 INFO - Running setup.py (path:/tmp/pip-9fNWrG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 10:30:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 10:30:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 10:30:43 INFO - Running setup.py (path:/tmp/pip-wxcUtC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 10:30:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 10:30:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 10:30:44 INFO - Running setup.py (path:/tmp/pip-VwAtz6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 10:30:44 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 10:30:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 10:30:44 INFO - Running setup.py (path:/tmp/pip-fXZ5xX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 10:30:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.24 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 10:30:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 10:30:44 INFO - Running setup.py (path:/tmp/pip-CnR4HM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 10:30:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 10:30:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 10:30:44 INFO - Running setup.py (path:/tmp/pip-yj73EU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 10:30:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 10:30:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 10:30:44 INFO - Running setup.py (path:/tmp/pip-cFQkRk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 10:30:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 10:30:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 10:30:44 INFO - Running setup.py (path:/tmp/pip-GWb9fY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 10:30:44 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 10:30:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 10:30:44 INFO - Running setup.py (path:/tmp/pip-gAsCFF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 10:30:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 10:30:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 10:30:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 10:30:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 10:30:44 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 10:30:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 10:30:44 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 10:30:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:30:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:30:44 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:30:44 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:30:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:30:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:30:46 INFO - Downloading blessings-1.6.tar.gz 10:30:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 10:30:46 INFO - Installing collected packages: blessings 10:30:46 INFO - Running setup.py install for blessings 10:30:46 INFO - Successfully installed blessings 10:30:46 INFO - Cleaning up... 10:30:46 INFO - Return code: 0 10:30:46 INFO - Done creating virtualenv /builds/slave/test/build/venv. 10:30:46 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 10:30:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 10:30:46 INFO - Reading from file tmpfile_stdout 10:30:46 INFO - Current package versions: 10:30:46 INFO - argparse == 1.2.1 10:30:46 INFO - blessings == 1.6 10:30:46 INFO - blobuploader == 1.2.4 10:30:46 INFO - docopt == 0.6.1 10:30:46 INFO - functools32 == 3.2.3-2 10:30:46 INFO - jsonschema == 2.5.1 10:30:46 INFO - manifestparser == 1.1 10:30:46 INFO - mozInstall == 1.12 10:30:46 INFO - mozcrash == 1.0 10:30:47 INFO - mozdebug == 0.1 10:30:47 INFO - mozdevice == 0.49 10:30:47 INFO - mozfile == 1.2 10:30:47 INFO - mozhttpd == 0.7 10:30:47 INFO - mozinfo == 0.9 10:30:47 INFO - mozleak == 0.1 10:30:47 INFO - mozlog == 3.4 10:30:47 INFO - moznetwork == 0.27 10:30:47 INFO - mozprocess == 0.24 10:30:47 INFO - mozprofile == 0.28 10:30:47 INFO - mozrunner == 6.13 10:30:47 INFO - mozscreenshot == 0.1 10:30:47 INFO - mozsystemmonitor == 0.3 10:30:47 INFO - moztest == 0.8 10:30:47 INFO - mozversion == 1.4 10:30:47 INFO - psutil == 3.1.1 10:30:47 INFO - requests == 1.2.3 10:30:47 INFO - wsgiref == 0.1.2 10:30:47 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:30:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:30:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:30:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:30:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:30:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:30:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:30:47 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xb771f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xc71a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe17ed0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xea1540>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe15f70>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe16400>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/talos', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1487269794.885407-820900677', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 10:30:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 10:30:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 10:30:47 INFO - Using env: {'DISPLAY': ':0', 10:30:47 INFO - 'HOME': '/home/cltbld', 10:30:47 INFO - 'LANG': 'en_US.UTF-8', 10:30:47 INFO - 'LANGUAGE': 'en_US:en', 10:30:47 INFO - 'LOGNAME': 'cltbld', 10:30:47 INFO - 'MAIL': '/var/mail/cltbld', 10:30:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:30:47 INFO - 'MOZ_NO_REMOTE': '1', 10:30:47 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:30:47 INFO - 'NO_EM_RESTART': '1', 10:30:47 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:30:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:30:47 INFO - 'PWD': '/builds/slave/test', 10:30:47 INFO - 'SHELL': '/bin/bash', 10:30:47 INFO - 'SHLVL': '1', 10:30:47 INFO - 'TERM': 'linux', 10:30:47 INFO - 'TMOUT': '86400', 10:30:47 INFO - 'USER': 'cltbld', 10:30:47 INFO - 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1487269794.885407-820900677', 10:30:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:30:47 INFO - '_': '/tools/buildbot/bin/python'} 10:30:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:30:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 10:30:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 10:30:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 10:30:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 10:30:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 10:30:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 10:30:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 10:30:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 10:30:47 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 10:30:47 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 10:30:47 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 10:30:47 INFO - Cleaning up... 10:30:47 INFO - Return code: 0 10:30:47 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 10:30:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:30:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:30:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:30:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:30:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:30:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:30:47 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xb771f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xc71a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe17ed0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xea1540>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe15f70>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe16400>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1487269794.885407-820900677', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 10:30:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 10:30:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 10:30:47 INFO - Using env: {'DISPLAY': ':0', 10:30:47 INFO - 'HOME': '/home/cltbld', 10:30:47 INFO - 'LANG': 'en_US.UTF-8', 10:30:47 INFO - 'LANGUAGE': 'en_US:en', 10:30:47 INFO - 'LOGNAME': 'cltbld', 10:30:47 INFO - 'MAIL': '/var/mail/cltbld', 10:30:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:30:47 INFO - 'MOZ_NO_REMOTE': '1', 10:30:47 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:30:47 INFO - 'NO_EM_RESTART': '1', 10:30:47 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:30:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:30:47 INFO - 'PWD': '/builds/slave/test', 10:30:47 INFO - 'SHELL': '/bin/bash', 10:30:47 INFO - 'SHLVL': '1', 10:30:47 INFO - 'TERM': 'linux', 10:30:47 INFO - 'TMOUT': '86400', 10:30:47 INFO - 'USER': 'cltbld', 10:30:47 INFO - 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1487269794.885407-820900677', 10:30:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:30:47 INFO - '_': '/tools/buildbot/bin/python'} 10:30:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:30:47 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 10:30:47 INFO - Cleaning up... 10:30:47 INFO - Return code: 0 10:30:47 INFO - Running post-action listener: _resource_record_post_action 10:30:47 INFO - Running post-action listener: _start_resource_monitoring 10:30:47 INFO - Starting resource monitoring. 10:30:47 INFO - [mozharness: 2017-02-16 18:30:47.508645Z] Finished create-virtualenv step (success) 10:30:47 INFO - [mozharness: 2017-02-16 18:30:47.509181Z] Running install step. 10:30:47 INFO - Running pre-action listener: _resource_record_pre_action 10:30:47 INFO - Running main action method: install 10:30:47 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 10:30:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 10:30:47 INFO - Reading from file tmpfile_stdout 10:30:47 INFO - Detecting whether we're running mozinstall >=1.0... 10:30:47 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 10:30:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 10:30:47 INFO - Reading from file tmpfile_stdout 10:30:47 INFO - Output received: 10:30:47 INFO - Usage: mozinstall [options] installer 10:30:47 INFO - Options: 10:30:47 INFO - -h, --help show this help message and exit 10:30:47 INFO - -d DEST, --destination=DEST 10:30:47 INFO - Directory to install application into. [default: 10:30:47 INFO - "/builds/slave/test"] 10:30:47 INFO - --app=APP Application being installed. [default: firefox] 10:30:47 INFO - mkdir: /builds/slave/test/build/application 10:30:47 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/target.tar.bz2', '--destination', '/builds/slave/test/build/application'] 10:30:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 10:31:05 INFO - Reading from file tmpfile_stdout 10:31:05 INFO - Output received: 10:31:05 INFO - /builds/slave/test/build/application/firefox/firefox 10:31:05 INFO - Running post-action listener: _resource_record_post_action 10:31:05 INFO - [mozharness: 2017-02-16 18:31:05.321697Z] Finished install step (success) 10:31:05 INFO - [mozharness: 2017-02-16 18:31:05.321973Z] Running run-tests step. 10:31:05 INFO - Running pre-action listener: _resource_record_pre_action 10:31:05 INFO - Running main action method: run_tests 10:31:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 10:31:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 10:31:05 INFO - Python 2.7.3 10:31:05 INFO - Return code: 0 10:31:05 INFO - grabbing minidump binary from tooltool 10:31:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:31:05 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xea1540>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe15f70>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe16400>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 10:31:05 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 10:31:05 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 10:31:05 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 10:31:05 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 10:31:05 INFO - Return code: 0 10:31:05 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 10:31:05 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 10:31:05 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 10:31:05 INFO - ENV: RUST_BACKTRACE is now 1 10:31:05 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 10:31:05 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 10:31:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Sy3PDECGSdmNdwU90aRM8g/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-032', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 10:31:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/Sy3PDECGSdmNdwU90aRM8g/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-032 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 10:31:05 INFO - Using env: {'DISPLAY': ':0', 10:31:05 INFO - 'HOME': '/home/cltbld', 10:31:05 INFO - 'LANG': 'en_US.UTF-8', 10:31:05 INFO - 'LANGUAGE': 'en_US:en', 10:31:05 INFO - 'LOGNAME': 'cltbld', 10:31:05 INFO - 'MAIL': '/var/mail/cltbld', 10:31:05 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 10:31:05 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 10:31:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:31:05 INFO - 'MOZ_NO_REMOTE': '1', 10:31:05 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 10:31:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:31:05 INFO - 'NO_EM_RESTART': '1', 10:31:05 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:31:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:31:05 INFO - 'PWD': '/builds/slave/test', 10:31:05 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 10:31:05 INFO - 'RUST_BACKTRACE': '1', 10:31:05 INFO - 'SHELL': '/bin/bash', 10:31:05 INFO - 'SHLVL': '1', 10:31:05 INFO - 'TERM': 'linux', 10:31:05 INFO - 'TMOUT': '86400', 10:31:05 INFO - 'USER': 'cltbld', 10:31:05 INFO - 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1487269794.885407-820900677', 10:31:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:31:05 INFO - '_': '/tools/buildbot/bin/python'} 10:31:05 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Sy3PDECGSdmNdwU90aRM8g/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-032', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 10:31:05 INFO - mozversion application_buildid: 20170216172815 10:31:05 INFO - mozversion application_changeset: bf6b9caab2c7eb3ebc642afd82bc19598829c6f3 10:31:05 INFO - mozversion application_display_name: Nightly 10:31:05 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 10:31:05 INFO - mozversion application_name: Firefox 10:31:05 INFO - mozversion application_remotingname: firefox 10:31:05 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 10:31:05 INFO - mozversion application_vendor: Mozilla 10:31:05 INFO - mozversion application_version: 54.0a1 10:31:05 INFO - mozversion platform_buildid: 20170216172815 10:31:05 INFO - mozversion platform_changeset: bf6b9caab2c7eb3ebc642afd82bc19598829c6f3 10:31:05 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 10:31:05 INFO - mozversion platform_version: 54.0a1 10:31:05 INFO - using testdate: 1487269865 10:31:05 INFO - actual date: 1487269865 10:31:05 INFO - starting webserver on 'localhost:43102' 10:31:05 INFO - SUITE-START | Running 2 tests 10:31:05 INFO - TEST-START | dromaeo_css 10:31:05 INFO - operating with platform_type : linux_ 10:31:05 INFO - Initialising browser for dromaeo_css test... 10:31:05 INFO - TEST-INFO | started process 25125 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpWk1EFO/profile http://localhost:43102/getInfo.html) 10:31:08 INFO - PROCESS | 25125 | Iterating over profile /tmp/tmpWk1EFO/profile 10:31:09 INFO - PROCESS | 25125 | __metrics Screen width/height:1600/1200 10:31:09 INFO - PROCESS | 25125 | colorDepth:24 10:31:09 INFO - PROCESS | 25125 | Browser inner width/height: 1024/697 10:31:09 INFO - PROCESS | 25125 | __metrics 10:31:10 INFO - PROCESS | 25125 | No birth date. Using mtime. 10:31:10 INFO - PROCESS | 25125 | Using date: /tmp/tmpWk1EFO/profile/addons.json = Thu Feb 16 2017 10:31:08 GMT-0800 (PST) 10:31:10 INFO - PROCESS | 25125 | No birth date. Using mtime. 10:31:10 INFO - PROCESS | 25125 | Using date: /tmp/tmpWk1EFO/profile/extensions.json = Thu Feb 16 2017 10:31:08 GMT-0800 (PST) 10:31:10 INFO - PROCESS | 25125 | No birth date. Using mtime. 10:31:10 INFO - PROCESS | 25125 | Using date: /tmp/tmpWk1EFO/profile/places.sqlite-wal = Thu Feb 16 2017 10:31:10 GMT-0800 (PST) 10:31:10 INFO - PROCESS | 25125 | No birth date. Using mtime. 10:31:10 INFO - PROCESS | 25125 | Using date: /tmp/tmpWk1EFO/profile/key3.db = Thu Feb 16 2017 10:31:05 GMT-0800 (PST) 10:31:10 INFO - PROCESS | 25125 | No birth date. Using mtime. 10:31:10 INFO - PROCESS | 25125 | Using date: /tmp/tmpWk1EFO/profile/permissions.sqlite = Thu Feb 16 2017 10:31:06 GMT-0800 (PST) 10:31:10 INFO - PROCESS | 25125 | No birth date. Using mtime. 10:31:10 INFO - PROCESS | 25125 | Using date: /tmp/tmpWk1EFO/profile/localstore.rdf = Thu Feb 16 2017 10:30:23 GMT-0800 (PST) 10:31:10 INFO - PROCESS | 25125 | No birth date. Using mtime. 10:31:10 INFO - PROCESS | 25125 | Using date: /tmp/tmpWk1EFO/profile/startupCache = Thu Feb 16 2017 10:31:05 GMT-0800 (PST) 10:31:10 INFO - PROCESS | 25125 | No birth date. Using mtime. 10:31:10 INFO - PROCESS | 25125 | Using date: /tmp/tmpWk1EFO/profile/bookmarkbackups = Thu Feb 16 2017 10:31:08 GMT-0800 (PST) 10:31:10 INFO - PROCESS | 25125 | No birth date. Using mtime. 10:31:10 INFO - PROCESS | 25125 | Using date: /tmp/tmpWk1EFO/profile/thumbnails = Thu Feb 16 2017 10:31:08 GMT-0800 (PST) 10:31:10 INFO - PROCESS | 25125 | No birth date. Using mtime. 10:31:10 INFO - PROCESS | 25125 | Using date: /tmp/tmpWk1EFO/profile/cache2 = Thu Feb 16 2017 10:31:05 GMT-0800 (PST) 10:31:10 INFO - PROCESS | 25125 | No birth date. Using mtime. 10:31:10 INFO - PROCESS | 25125 | Using date: /tmp/tmpWk1EFO/profile/crashes = Thu Feb 16 2017 10:31:05 GMT-0800 (PST) 10:31:10 INFO - PROCESS | 25125 | No birth date. Using mtime. 10:31:10 INFO - PROCESS | 25125 | Using date: /tmp/tmpWk1EFO/profile/mimeTypes.rdf = Thu Feb 16 2017 10:31:09 GMT-0800 (PST) 10:31:10 INFO - PROCESS | 25125 | No birth date. Using mtime. 10:31:10 INFO - PROCESS | 25125 | Using date: /tmp/tmpWk1EFO/profile/prefs.js = Thu Feb 16 2017 10:30:23 GMT-0800 (PST) 10:31:10 INFO - PROCESS | 25125 | No birth date. Using mtime. 10:31:10 INFO - PROCESS | 25125 | Using date: /tmp/tmpWk1EFO/profile/places.sqlite-shm = Thu Feb 16 2017 10:31:07 GMT-0800 (PST) 10:31:10 INFO - PROCESS | 25125 | No birth date. Using mtime. 10:31:10 INFO - PROCESS | 25125 | Using date: /tmp/tmpWk1EFO/profile/pluginreg.dat = Thu Feb 16 2017 10:31:08 GMT-0800 (PST) 10:31:10 INFO - PROCESS | 25125 | No birth date. Using mtime. 10:31:10 INFO - PROCESS | 25125 | Using date: /tmp/tmpWk1EFO/profile/.parentlock = Thu Feb 16 2017 10:31:05 GMT-0800 (PST) 10:31:10 INFO - PROCESS | 25125 | No birth date. Using mtime. 10:31:10 INFO - PROCESS | 25125 | Using date: /tmp/tmpWk1EFO/profile/cookies.sqlite = Thu Feb 16 2017 10:31:09 GMT-0800 (PST) 10:31:10 INFO - PROCESS | 25125 | No birth date. Using mtime. 10:31:10 INFO - PROCESS | 25125 | Using date: /tmp/tmpWk1EFO/profile/blocklist.xml = Thu Feb 16 2017 10:31:05 GMT-0800 (PST) 10:31:10 INFO - PROCESS | 25125 | No birth date. Using mtime. 10:31:10 INFO - PROCESS | 25125 | Using date: /tmp/tmpWk1EFO/profile/webappsstore.sqlite = Thu Feb 16 2017 10:31:09 GMT-0800 (PST) 10:31:10 INFO - PROCESS | 25125 | No birth date. Using mtime. 10:31:10 INFO - PROCESS | 25125 | Using date: /tmp/tmpWk1EFO/profile/safebrowsing = Thu Feb 16 2017 10:31:10 GMT-0800 (PST) 10:31:10 INFO - PROCESS | 25125 | No birth date. Using mtime. 10:31:10 INFO - PROCESS | 25125 | Using date: /tmp/tmpWk1EFO/profile/extensions = Thu Feb 16 2017 10:31:05 GMT-0800 (PST) 10:31:10 INFO - PROCESS | 25125 | No birth date. Using mtime. 10:31:10 INFO - PROCESS | 25125 | Using date: /tmp/tmpWk1EFO/profile/frequencyCap.json = Thu Feb 16 2017 10:31:08 GMT-0800 (PST) 10:31:10 INFO - PROCESS | 25125 | No birth date. Using mtime. 10:31:10 INFO - PROCESS | 25125 | Using date: /tmp/tmpWk1EFO/profile/storage.sqlite-journal = Thu Feb 16 2017 10:31:10 GMT-0800 (PST) 10:31:10 INFO - PROCESS | 25125 | No birth date. Using mtime. 10:31:10 INFO - PROCESS | 25125 | Using date: /tmp/tmpWk1EFO/profile/storage.sqlite = Thu Feb 16 2017 10:31:10 GMT-0800 (PST) 10:31:10 INFO - PROCESS | 25125 | No birth date. Using mtime. 10:31:10 INFO - PROCESS | 25125 | Using date: /tmp/tmpWk1EFO/profile/sessionCheckpoints.json = Thu Feb 16 2017 10:31:09 GMT-0800 (PST) 10:31:10 INFO - PROCESS | 25125 | No birth date. Using mtime. 10:31:10 INFO - PROCESS | 25125 | Using date: /tmp/tmpWk1EFO/profile/compatibility.ini = Thu Feb 16 2017 10:31:06 GMT-0800 (PST) 10:31:10 INFO - PROCESS | 25125 | Stat failure 10:31:10 INFO - PROCESS | 25125 | No birth date. Using mtime. 10:31:10 INFO - PROCESS | 25125 | Using date: /tmp/tmpWk1EFO/profile/cert8.db = Thu Feb 16 2017 10:31:05 GMT-0800 (PST) 10:31:10 INFO - PROCESS | 25125 | No birth date. Using mtime. 10:31:10 INFO - PROCESS | 25125 | Using date: /tmp/tmpWk1EFO/profile/user.js = Thu Feb 16 2017 10:31:05 GMT-0800 (PST) 10:31:10 INFO - PROCESS | 25125 | No birth date. Using mtime. 10:31:10 INFO - PROCESS | 25125 | Using date: /tmp/tmpWk1EFO/profile/extensions.ini = Thu Feb 16 2017 10:31:06 GMT-0800 (PST) 10:31:10 INFO - PROCESS | 25125 | No birth date. Using mtime. 10:31:10 INFO - PROCESS | 25125 | Using date: /tmp/tmpWk1EFO/profile/content-prefs.sqlite = Thu Feb 16 2017 10:31:08 GMT-0800 (PST) 10:31:10 INFO - PROCESS | 25125 | No birth date. Using mtime. 10:31:10 INFO - PROCESS | 25125 | Using date: /tmp/tmpWk1EFO/profile/directoryLinks.json = Thu Feb 16 2017 10:31:08 GMT-0800 (PST) 10:31:10 INFO - PROCESS | 25125 | No birth date. Using mtime. 10:31:10 INFO - PROCESS | 25125 | Using date: /tmp/tmpWk1EFO/profile/secmod.db = Thu Feb 16 2017 10:31:05 GMT-0800 (PST) 10:31:10 INFO - PROCESS | 25125 | No birth date. Using mtime. 10:31:10 INFO - PROCESS | 25125 | Using date: /tmp/tmpWk1EFO/profile/gmp = Thu Feb 16 2017 10:31:06 GMT-0800 (PST) 10:31:10 INFO - PROCESS | 25125 | No birth date. Using mtime. 10:31:10 INFO - PROCESS | 25125 | Using date: /tmp/tmpWk1EFO/profile/minidumps = Thu Feb 16 2017 10:31:05 GMT-0800 (PST) 10:31:10 INFO - PROCESS | 25125 | No birth date. Using mtime. 10:31:10 INFO - PROCESS | 25125 | Using date: /tmp/tmpWk1EFO/profile/places.sqlite = Thu Feb 16 2017 10:31:10 GMT-0800 (PST) 10:31:10 INFO - PROCESS | 25125 | Stat failure 10:31:11 INFO - PROCESS | 25125 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 10:31:11 INFO - PROCESS | 25125 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 10:31:12 INFO - PROCESS | 25125 | console.error: PushService: 10:31:12 INFO - PROCESS | 25125 | stateChangeProcessEnqueue: Error transitioning state 10:31:12 INFO - PROCESS | 25125 | UnknownError 10:31:12 INFO - TEST-INFO | 25125: exit 0 10:31:12 INFO - Browser initialized. 10:31:12 INFO - Running cycle 1/1 for dromaeo_css test... 10:31:12 INFO - TEST-INFO | started process 25211 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpWk1EFO/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 10:31:24 INFO - PROCESS | 25211 | RSS: Main: 149909504 10:31:24 INFO - PROCESS | 25211 | 10:34:26 INFO - PROCESS | 25211 | Cycle 1(1): loaded http://localhost:43102/tests/dromaeo/cssquery-dojo.html (next: http://localhost:43102/tests/dromaeo/cssquery-ext.html) 10:34:28 INFO - PROCESS | 25211 | RSS: Main: 267743232 10:34:28 INFO - PROCESS | 25211 | 10:36:15 INFO - PROCESS | 25211 | ************************* 10:36:15 INFO - PROCESS | 25211 | A coding exception was thrown and uncaught in a Task. 10:36:15 INFO - PROCESS | 25211 | 10:36:15 ERROR - PROCESS | 25211 | Full message: TypeError: NetworkError when attempting to fetch resource. 10:36:15 INFO - PROCESS | 25211 | Full stack: 10:36:15 INFO - PROCESS | 25211 | ************************* 10:37:30 INFO - PROCESS | 25211 | Cycle 1(1): loaded http://localhost:43102/tests/dromaeo/cssquery-ext.html (next: http://localhost:43102/tests/dromaeo/cssquery-jquery.html) 10:37:31 INFO - PROCESS | 25211 | RSS: Main: 243601408 10:37:31 INFO - PROCESS | 25211 | 10:40:33 INFO - PROCESS | 25211 | Cycle 1(1): loaded http://localhost:43102/tests/dromaeo/cssquery-jquery.html (next: http://localhost:43102/tests/dromaeo/cssquery-mootools.html) 10:40:34 INFO - PROCESS | 25211 | RSS: Main: 186863616 10:40:34 INFO - PROCESS | 25211 | 10:43:36 INFO - PROCESS | 25211 | Cycle 1(1): loaded http://localhost:43102/tests/dromaeo/cssquery-mootools.html (next: http://localhost:43102/tests/dromaeo/cssquery-prototype.html) 10:43:37 INFO - PROCESS | 25211 | RSS: Main: 185790464 10:43:37 INFO - PROCESS | 25211 | 10:46:39 INFO - PROCESS | 25211 | Cycle 1(1): loaded http://localhost:43102/tests/dromaeo/cssquery-prototype.html (next: http://localhost:43102/tests/dromaeo/cssquery-yui.html) 10:46:40 INFO - PROCESS | 25211 | RSS: Main: 186626048 10:46:40 INFO - PROCESS | 25211 | 10:49:43 INFO - PROCESS | 25211 | Cycle 1(1): loaded http://localhost:43102/tests/dromaeo/cssquery-yui.html (next: http://localhost:43102/tests/dromaeo/cssquery-dojo.html) 10:49:43 INFO - PROCESS | 25211 | RSS: Main: 288915456 10:49:43 INFO - PROCESS | 25211 | 10:49:43 INFO - PROCESS | 25211 | __start_tp_report 10:49:43 INFO - PROCESS | 25211 | _x_x_mozilla_page_load 10:49:43 INFO - PROCESS | 25211 | _x_x_mozilla_page_load_details 10:49:43 INFO - PROCESS | 25211 | |i|pagename|runs| 10:49:43 INFO - PROCESS | 25211 | |0;dojo.html;3152.3380090181067;3153.448146574349;3164.9841750791243;3208.7272581830543;3210.2134976930643;5185.274061631375;5275.235090911816;5319.388270348906;5398.730063496824;5406.594505412095;5302.2311764794085;5314.654547454413;5327.413984461716;5364.195370694388;5410.729463526827;4860.538248866361;4877.756112194394;4896.412430508344;4916.950830491685;4949.752512374385;4749.572538471537;4979.402471703401;4994.650374473788;5016.874578135557;5052.696838189702;4781.832635857746;4841.564259216652;4845.4185497740145;4863.2461968394955;4866.3673722416215;4817.0365926814775;4896.192128298826;4909.975450122726;4913.385826771653;4992.126377883892;4873.488283730193;4882.3408839806525;4897.642263069079;4902.632302577321;4904.60763138948;5165.896682066337;5441.972790136064;5471.808486702986;5479.726013699339;5482.725863706839;5612.07400778871;5681.914771278434;5682.17608446777;5682.318121825366;5682.6306290091425;5726.3700992890745;5731.942680573183;5744.3341430984965;5746;5839.678817665027;5440.347158341025;5488.286522752057;5491.505764481176;5491.917621235684;5493.725313734337;5900.085486749561;5948.643081415129;5949.583529153005;5954.8809023819285;5955.612885162451;5526.226328314039;5568.777248910079;5584.21820945068;5736.397678243724;5751.309842818889;5695.515881150149;5720.570957178145;5736.426357364314;5772;5772.307323121252;5775.364709881909;5809.622374545641;5815.651060936358;5817.50551203144;5836.358000619928;5817.825465236049;5820.854478637983;5821.563382746311;5823.126531020296;5825.300963884361;4501.864944051684;4503.189425903303;4511.075229577928;4515.187266292099;4545.499995000548;3894.2600905827803;3928.4107383892647;3936.2324346752107;3968.2460332536725;3987.7009224307712;5484.396716361197;5488.231647569343;5501.587380946445;5510.586705996986;5537.972310138423;5788.507976821933;5801.883962320814;5845;5852.4440178183;5854.590178687451;5232.764525596282;5280.788768449219;5488.039593071197;5501.202325662756;5503.587230957694;4857.441394239708;4870.196417591129;4870.878228044327;4917.311576379309;4974.030064137531;6096.980278560325;6097.464185086759;6139.447449729546;6143.846403840029;6144.41628045335;5904.350521442552;5906.822795316148;5910.438508341701;5911.881762364643;5919.4080591940465;6049.576529642883;6051.425114614105;6094.54290928191;6104.69476526163;6105.236845394325;5464.890702185855;5486.890262194654;5488.094464413327;5558.138488534284;5565.109582466948;5706.258186435737;5729.341125770423;5760.0783874579865;5813.738381772746;5864.794732184353;5597.944020559905;5634.098544232903;5634.802781902614;5643.1535269709875;5653.462921022497;5476.205950137128;5669.603127781016;5671.744771485375;5673.574481913955;5679.602427830012;5475.452454754652;5553.139263414178;5556.083246264483;5642.069058605284;5694.544436444991;5710.5146062585145;5711.857203569778;5713.914291285717;5739.253896993364;5762.049261871744;5512.80705175317;5536.889262214814;5566.4990150886615;5581.609287350009;5584.218209450599 10:49:43 INFO - PROCESS | 25211 | |1;ext.html;566.4703502225419;574.4026496333097;592.2005292854645;600.3846057790764;603.9818805435839;30633;33154.33691326175;33199.33601327972;41606.58393416065;43962.7801860991;44718.776406118006;45124.77437612807;45214.77392613032;45237;45265.77367113168;9009.234215091725;9210.815783684322;9211.078892110801;9212.263018958502;9213.078692130766;7515.699372025139;7698;9105.135012173832;9212.585433655502;10397.896021039805;10575.682729518127;10862.782744345108;10971.23201375904;10975.83536246953;10977.176711746612;10782.191335649819;10811.621593244277;10838;10853.891461085366;10858.945705271502;10486.475676216234;10578.68263952075;10579.259451838374;10583;10587.629432969808;41866.37200441996;42123.7893810529;42144;42211.788941055405;42221.15557688829;3790.2798468290935;3872.7289089763726;3907.37482002881;3909;3973.4238535412205;31402.685973140204;31604.103753774616;31635.525467118012;31643.20891977696;32351.51472727911;4029.133736246721;4105.7331273467125;4154.729942553724;4172.123853990665;4295.2912769393015;297415;300387.49806251045;300523;300841;301807;58272.01471646774;58662.70668646629;58831.411685882595;58833;58839.349539849136;60574;60650;60663;60707.39292607106;60727;15574.922125389301;15772.74135983301;15781;15799.052056876624;15829.445969391016;12246.510139594317;12301.446434910451;12319.93840030812;12338.074644401706;12340.321282329378;13150;13738.313084345742;14176.85823141776;17039.92167953878;21250.70614054824;7565.394768418623;7569.205233450519;7661.808454788675;7801.684284179201;8158.469699469634;861.2679222660688;866.7573079537614;906.5376657904516;908.922741566961;927.9814403711888;31231.375372492425;32089.358212835614;32210.677893221236;32287.677123228466;32598.511022334686;1033.617561502234;1054.3357684658615;1075.3870293932384;1081.9567217311376;1100.658795773313;13886.722265554632;14757.335919883632;14855.554333370017;15143;15166.924165379103;453321;455752;457525;458118;458510;44788.55211447909;45628.54371456309;50495.24257136146;51843.22235166476;51992;1349.6355983884494;1372.945082196701;1382.10853999172;1401.054288355356;1403.4666826605824;28293.57559636648;29305.706942930297;29831.254218644706;29836.85081574665;30529.54205686961;46092;46930;46975;46994.29508557306;47078.52921470878;381118;381561;382265;382502;382565;26250.212493625222;26316.342091447867;26910.730892691605;26979.730202698505;28516.287092822844;34907;36469;43449.78275108731;44450;46300;36375.45436818397;36574;37599.81200093982;39295.607043929194;39365.6063439362;37144.25711485809;37328.813355933045;37856.62143378531;37880.810595946845;39171.2165756689;37266.25467490689;37950.24099517939;37968.24063518659;38018.80990595029;38881.416778748906;30858.69141308648;32715.182120447178;33306.5004024935;33436.66563334336;33597.832010840764;25258;25459.236222913343;25500.872495637403;26028.609570856832;26071.478570428866 10:49:43 INFO - PROCESS | 25211 | |2;jquery.html;383.33874067234007;396.82738008966095;399.9520057593089;411.7067931620871;412.7544111253804;18098.81901180988;19342.226310947546;19812.30656927008;19836.50408739782;20051.69922451161;13421.127626704281;13669.384877680503;17791;20798.168073277087;20852.061657225422;5445.128779395288;5620.775168993236;7250.093738282715;7528.47300688952;7578.242175782433;7432.368248698867;7630.885536716926;7654.158042615308;7805.492642978188;7807.570583617899;7756.961215193916;7768.805779855492;7769.0677118745825;7769.689212431496;7771.455998080137;7498.2126876677985;7796.4932279401655;7857.489263197874;7870.921290787133;7878.448508604342;7491.32578067971;7552.5846078465665;7582.49218312549;7641.656125474367;7645.617719114022;26488.60267095995;26953.865230673917;26998.190054298404;27059.729402706114;27366.179014629593;2743.492453896036;2787.9303017424522;2802.341449759305;2865.813722108077;2876.7123287671066;14988.700225995419;15653.608659783482;15935.521934341989;16154.83845161557;16451.506454806375;1985.1463870535663;2020.7272018277492;2028.614563232981;2039.5512987142804;2057.609054279697;91058.5447072767;92738.5363073187;97137.5143124287;100872.49563752208;103904.48047759788;19446.70829937552;19594.314199002965;19744.901275493532;19961.800381996283;20238.595228095357;36800;36844.81577592095;37024.81487592545;37074.814625926694;37661;10138.847917281246;10256.641017564349;10267;10512.63205787809;10655.413952232702;8354;8380.497170169809;8409.117042710428;8641.135886411308;8645.56772161402;9804.068613481708;10028.949855250678;10144.695659130239;10332.483375831329;10430.217733670004;3752.7373083883867;3863.3818589025623;3924.17592305619;3959.742616729903;3992.7803970781306;831.4161522300028;860.6901515454431;869.3132425383876;873.554487211514;890.8218356328631;19283.132259048376;20171.294004709765;20320.491987700425;20448.284310049075;20499.4875128123;857.2798848966897;862.3403096630957;872.5026734761125;874.776931882366;880.1990188928045;10533.525991330409;11254.83117753218;11294.830577541343;11322.830157547643;11417;518432;559463;574610;578499;580586;80388.59805701052;101760.49119754354;103003.4849825761;103369.48315258375;104789.47605261925;1023.1967905194387;1067.64767626683;1086.8587083679072;1091.4270008245576;1101.5758932810766;16502.42241521541;16877.40929067477;17429.912850435667;17559.473215803548;17906.955817510792;36910.446343304335;41985.79007105067;42226.788866056704;42914.57085429231;43078;56541.71729141493;56877.431225687214;57516.42483575111;58266.126008109066;58386.24841254769;8799;8868.201861832207;8921.330900182642;9200.033996430278;9204.631814727336;12338.691532711755;12831.10182287231;13031.283279419344;13572.592822215349;14629.414823406945;11359.977602015528;12187.695307617381;12241.326727030098;12465.563705270271;12547.560835370718;11955.103367247666;12060.336681482602;12106.576269830513;12236.449359778653;12588.937055315031;12428.937855310665;12479.750404992032;12571.874281257436;12700.682482938;12759.362031898183;8936.910630893608;8963.641454341754;9098.363114581916;9220.907790922005;9283.675071372469;9494.192993595621;11620.593279235525;11883;12052.337121458053;12062.879371206527 10:49:43 INFO - PROCESS | 25211 | |3;mootools.html;1205.276833899661;1384.3355189509036;1397.7623803953327;1408.542223777272;1424.8860091192705;7034.22623511414;7915.089764677064;8167.550784706838;8171.509709417439;8341.33269338453;8160.183981601836;8218.137095604965;8229.300509456703;8236.052853921787;8243.587820608975;2188.223180770827;2233.3300009997015;2235.7205349331334;2238.250186187629;2238.9440263993447;1955.4622478818296;2033.9694904576443;2129.7337832770904;2221.5445833604153;2224.71078759761;2179.2154824263253;2217.135317226283;2222.944426389337;2225.688403623494;2225.9332220033425;2205.0628482894704;2224.3327001899447;2226.142934970032;2226.265332440291;2228.855124416924;2171.1098449635574;2205.9669104963436;2223.5219427823095;2231.5760005599045;2261.197274967379;8224.712135075244;8246.422750407473;8253.380996425292;8299.95850020746;8301.29438997686;5143.459936706667;5283.63014588979;5289.338832645919;5290.603204759658;5296.443873393277;3149.118246890874;3282.7045565899184;3328.783629064103;3374.8650053997812;3443.3113377324385;1321.2600943471687;1329.4988449389432;1341.5170538606094;1913.292081929681;1956.1295223625493;11029.283096598696;11170.776584468347;11217.10263178952;11223.887761122365;11225.550977960871;4350.869473915787;4536.070105628338;4615.5845973862515;4679.602233810097;4700.647451441156;9481.09929556691;9481.24150067993;9490.952545237229;9491.38306010107;9497.477638729937;5155.74221288934;5155.896882062413;5162.174052151638;5168;5176.094183517869;4728.078024785203;4826.300186472942;4868.075065737498;4908.067467181296;4918.409790825052;3312.751543634237;3409.2329225924464;3685.152414944524;3710.165212827092;3811.447340135665;1187.6615164678026;1205.8613259475096;1207.7463732616072;1216.2641601830942;1225.8651548329676;860.5138096975189;868.2229404682773;885.7962668586262;886.2378354615026;902.4359775140537;5323.760430780624;5462.890742185134;5529.557635389159;5667.914981275284;5770.88458230833;934.6354921580589;934.8130373925242;952.476138124029;956.1967946924617;975.7365511311906;3662.7436079474182;3679.871204507883;3680.613535578779;3814.1418180909072;3930.253251882133;9843.901560984441;10073.848892266622;10120.392776433437;10164.440955747357;10216.744581385525;9393.295502837314;9827.11555959967;9948.204143668489;9961.900380996241;9970.102690757722;7516.549007059594;7683.462157649023;7691.769246922601;7696.846063078707;7699.230076992368;5306.389765176899;5381.300430943952;5401.837944861661;5528.170774383714;5718.628289161191;8808.603612837565;9140.314476414163;9167.541622918961;9235.1688348049;9320.72037838866;9119.726408207764;9372.67195648149;9383.249340052917;9434.339596228197;9489.145976862115;7107.573545587281;7330.523515971445;7475.962620186864;7487.2138425464545;7503.737369192052;7773.766786996399;7945.483543569881;7980.441369104107;7983.081945576101;7992.600369981361;7090.822729431806;7113.502054856111;7206.56760594366;7244.782656520313;7424.703011879465;6774.898376524455;6794;6823.590584564942;6855.760048398282;6878.312168783082;5568.610197286151;5810.796622118206;5864.120381942743;5945.078512830518;5978.461938425563;4584.564466375689;4677.2750223715375;4713.81144754206;4721.504242054535;4819.180739274402;4803.279508073817;4905.435874874435;4931.2849636803185;4953.083679519301;4980.775865086008 10:49:43 INFO - PROCESS | 25211 | |4;prototype.html;392.7231301932138;394.2017414735161;394.3867286369694;399.45873341622104;403.79810094952524;5113.48865113489;5196.454372290912;5199.246109314147;5203.713795741238;5259.342582177228;5078.365204349457;5081.618878584102;5136.512031357024;5170.612204084699;5180.171172612383;4144.9585504145025;4172.624463798257;4174.726708353951;4189.601987811168;4194.937075943863;4196.202721482909;4204.621584057434;4205.570106163904;4208.305629571117;4217.915641687165;4091.9181616367655;4143.116872850872;4175.478065241845;4189.183109293689;4193.748375097489;4167.979160104211;4169.353750168729;4177.436046133764;4192.616436575922;4199.118185181115;3777.715576703934;3777.773333599993;3779.5653499847595;3783.881026625099;3796.886093417202;5064.366954130734;5074.767102474899;5075.6447048706605;5077.390713114413;5086.516780905808;4561.087782443525;4776.641751868624;4779.689320194177;4783.641226907961;4790.928136077961;5494.395616482184;5502.477264659851;5504.697241651707;5506.229127922094;5521.917171242435;4107.856225032109;4137.937930931039;4140.3168477201225;4146.564610715892;4155.792210389468;5463.972680136613;5492.697901615409;5573.554115670737;5666.631668941505;5691.467297052814;4165.4584903962295;4227.51383590891;4237.279662457389;4265.954841063897;4293.656507479394;4303.18141617235;4313.805878735465;4323.200207961537;4335.349697545394;4337.9132417351475;4694.976525117352;4818.855434336975;4823.517648235148;4825.710457372569;4842.201036828884;5492.148716948879;5519.420460851633;5525.392206857243;5547.972260138674;5556.361018482846;1444.812174417319;1456.4028748213182;1457.4243173946443;1459.8467160947948;1465.2087872548918;963.0995019656643;1030.6189705794272;1034.2243317511866;1035.984460233097;1047.7799662070897;5139.254808052886;5145.228215767665;5149.897002059938;5152.845414637567;5179.792808287626;5455.727213639302;5483.232347471358;5483.314585676791;5492.80775172867;5510.834674959758;4792.544708252711;5141.460146684619;5169.224616307509;5189.221616757516;5221.947780522146;2945.365322246174;2955.4384666913647;2959.3045634275927;2961.9111426657237;2970.435617232719;5673.375928647845;5701;5703.093381034005;5728.255326807488;5757.6833274169485;5309.5752339812725;5540.63985840919;5544.528715059185;5566.359868615161;5570.693611851387;5572.47061529146;5614.550835933197;5647.068233741469;5651.321841379061;5654.9151762723595;5133.460986596354;5415.620906536505;5433.266509021231;5444.891102178013;5555.527780138734;5368.812091576776;5374.892502150014;5381.838544843661;5461.699606521679;5494.203340515683;5562.388137304974;5647.971760141338;5684.744186511534;5693.3168019837885;5708.686022268815;4044.5348784890116;4133.689973252078;4157.210130075276;4259.041715613962;4304.569543045671;5590.217369568345;5635.436456354331;5682.943170568242;5683.488486036276;5689.8862022758485;5349.689326115126;5534.501894829485;5554.805581804618;5565.666060036411;5629.352624448241;5535.833924982257;5546.805861794818;5556.166575013619;5567.524605979338;5596.972015139898;5289.809792796744;5536.4186760389575;5547.725432572626;5556.916156441334;5572.387037425961;5594.664320140804;5599.888002239851;5601.327840659147;5606.084835885398;5619.690916999604;5422.376426710979;5435.728213589226;5477.20580515831;5479.863003424787;5493.835184944458 10:49:43 INFO - PROCESS | 25211 | |5;yui.html;735.4410647907589;756.7011030642897;779.6569509415857;839.1817977471966;851.7104184577245;15649.765253521193;16513.091779952098;16566.171691415428;16567.171641417943;16621.50135495934;14004.527261007072;14004.649883752885;14050.227237501958;14064;14081.436742530312;800.103883650311;800.1278606319111;802.9156938521459;810.2586133687669;814.5560669435172;803.7950322667712;805.4844899264447;805.8106345008947;806.7942674617964;809.9230573095547;856.3662889461784;856.4818284937617;857.6097875466679;857.7684025313162;857.8412993596205;847.5931552854634;848.6859861851093;848.9193526614993;849.4860609331328;850.1413572291999;614.0267675733967;617.200725061047;618.5731845026917;618.938106189382;619.7830759234276;15898.284577194054;16222.675546489123;16223;16230.431934882341;16503.33986640533;15258.160801155933;15263.771043434357;15468.845311546853;15646.9217653911;15840.603984900354;11861.46623401949;12027.57903473383;12042.337671428068;12075.516979320817;12169.756604867942;3731.7014638828828;3757.530308711411;3763.41667041609;3814.275287695357;3816.6755825754844;72198.63900680463;74437;74769.62615186944;75307.62346188234;76221.23778762252;11346.319220846775;11467.827982580267;11471.426428678533;11472.885271147348;11974.101942354184;23316.300510984696;23548.88225558861;23576.882115589655;23738.287851364486;23816.523669526512;7817.257360550739;7818.296353328228;7913;7913.881291780628;8052.235037671412;4164.68764842638;4254.446921900195;4279.828806847691;4368.6068253856665;4440.600345968878;3554.235839294564;3666.944995825065;3670.908227294339;3671.284099600552;3674.706023518112;643.7103303513344;675.2774531251612;684.3020119478203;687.8864987277143;698.88468402714;753.932146106853;758.2152472191366;759.2786852490157;778.7002004228412;790.9248621380959;10196.847047294297;10320.277580569287;10653.45153899797;10682.77101217805;10824.670131947285;740.5038624121852;755.3013462547121;769.4959801330137;770.6262462705583;771.1396059278957;4676.1625887140935;4997.375328083989;5006.199008158677;5012.448630650625;5021.221710634857;59224.703876480344;63860;76116.23883761202;76811;77326.61336693281;52717.47282527202;60855;64437.71124577482;64932.67533662301;68081.65959170173;59569.40430595725;59597;59765.70117149386;60265.67210442928;60361.69819150876;9584.568694408908;9788;9788.755281118029;9825.950870245602;9826.606935722777;38265.80867095758;38670;38942;40438.36054754969;41309.58690413057;48555;50348.49651503438;50569.74715126401;50757;52548.73725631347;11900.523979041087;12001.39993000329;12124.936807985641;12392.442340094898;12983.480660773841;13336.266505342299;13348.532801351907;13908.235047072509;14210.857891420954;14235.572932812032;12289.815652765037;12412.286147046265;12708.428120734405;12747.681307967376;13179.802302965272;12208.877911220774;12340.259584424963;12795.552155674506;12798.424070917017;13528;12518.561850335194;12647.367631618567;12708.173968692005;12977.870221298044;13102.737945241233;9743.512824358611;9867.802643947225;10079.395236285847;10186.745331366776;10662.494313836087;9283.62865485373;9580.712578622653;9638.807223855343;10466.424346661006;10564.366138031743 10:49:43 INFO - PROCESS | 25211 | __end_tp_report 10:49:43 INFO - PROCESS | 25211 | __start_cc_report 10:49:43 INFO - PROCESS | 25211 | _x_x_mozilla_cycle_collect,1259 10:49:43 INFO - PROCESS | 25211 | __end_cc_report 10:49:43 INFO - PROCESS | 25211 | __startTimestamp1487270983527__endTimestamp 10:49:43 INFO - PROCESS | 25211 | 10:49:43 INFO - PROCESS | 25211 | ------- Summary: start ------- 10:49:43 INFO - PROCESS | 25211 | Number of tests: 6 10:49:43 INFO - PROCESS | 25211 | 10:49:43 INFO - PROCESS | 25211 | [#0] dojo.html Cycles:165 Average:5370.52 Median:5537.97 stddev:612.80 (11.1%) stddev-sans-first:589.46 10:49:43 INFO - PROCESS | 25211 | Values: 3152.3 3153.4 3165.0 3208.7 3210.2 5185.3 5275.2 5319.4 5398.7 5406.6 5302.2 5314.7 5327.4 5364.2 5410.7 4860.5 4877.8 4896.4 4917.0 4949.8 4749.6 4979.4 4994.7 5016.9 5052.7 4781.8 4841.6 4845.4 4863.2 4866.4 4817.0 4896.2 4910.0 4913.4 4992.1 4873.5 4882.3 4897.6 4902.6 4904.6 5165.9 5442.0 5471.8 5479.7 5482.7 5612.1 5681.9 5682.2 5682.3 5682.6 5726.4 5731.9 5744.3 5746.0 5839.7 5440.3 5488.3 5491.5 5491.9 5493.7 5900.1 5948.6 5949.6 5954.9 5955.6 5526.2 5568.8 5584.2 5736.4 5751.3 5695.5 5720.6 5736.4 5772.0 5772.3 5775.4 5809.6 5815.7 5817.5 5836.4 5817.8 5820.9 5821.6 5823.1 5825.3 4501.9 4503.2 4511.1 4515.2 4545.5 3894.3 3928.4 3936.2 3968.2 3987.7 5484.4 5488.2 5501.6 5510.6 5538.0 5788.5 5801.9 5845.0 5852.4 5854.6 5232.8 5280.8 5488.0 5501.2 5503.6 4857.4 4870.2 4870.9 4917.3 4974.0 6097.0 6097.5 6139.4 6143.8 6144.4 5904.4 5906.8 5910.4 5911.9 5919.4 6049.6 6051.4 6094.5 6104.7 6105.2 5464.9 5486.9 5488.1 5558.1 5565.1 5706.3 5729.3 5760.1 5813.7 5864.8 5597.9 5634.1 5634.8 5643.2 5653.5 5476.2 5669.6 5671.7 5673.6 5679.6 5475.5 5553.1 5556.1 5642.1 5694.5 5710.5 5711.9 5713.9 5739.3 5762.0 5512.8 5536.9 5566.5 5581.6 5584.2 10:49:43 INFO - PROCESS | 25211 | 10:49:43 INFO - PROCESS | 25211 | [#1] ext.html Cycles:180 Average:53888.84 Median:37464.31 stddev:101635.44 (271.3%) stddev-sans-first:101841.70 10:49:43 INFO - PROCESS | 25211 | Values: 566.5 574.4 592.2 600.4 604.0 30633.0 33154.3 33199.3 41606.6 43962.8 44718.8 45124.8 45214.8 45237.0 45265.8 9009.2 9210.8 9211.1 9212.3 9213.1 7515.7 7698.0 9105.1 9212.6 10397.9 10575.7 10862.8 10971.2 10975.8 10977.2 10782.2 10811.6 10838.0 10853.9 10858.9 10486.5 10578.7 10579.3 10583.0 10587.6 41866.4 42123.8 42144.0 42211.8 42221.2 3790.3 3872.7 3907.4 3909.0 3973.4 31402.7 31604.1 31635.5 31643.2 32351.5 4029.1 4105.7 4154.7 4172.1 4295.3 297415.0 300387.5 300523.0 300841.0 301807.0 58272.0 58662.7 58831.4 58833.0 58839.3 60574.0 60650.0 60663.0 60707.4 60727.0 15574.9 15772.7 15781.0 15799.1 15829.4 12246.5 12301.4 12319.9 12338.1 12340.3 13150.0 13738.3 14176.9 17039.9 21250.7 7565.4 7569.2 7661.8 7801.7 8158.5 861.3 866.8 906.5 908.9 928.0 31231.4 32089.4 32210.7 32287.7 32598.5 1033.6 1054.3 1075.4 1082.0 1100.7 13886.7 14757.3 14855.6 15143.0 15166.9 453321.0 455752.0 457525.0 458118.0 458510.0 44788.6 45628.5 50495.2 51843.2 51992.0 1349.6 1372.9 1382.1 1401.1 1403.5 28293.6 29305.7 29831.3 29836.9 30529.5 46092.0 46930.0 46975.0 46994.3 47078.5 381118.0 381561.0 382265.0 382502.0 382565.0 26250.2 26316.3 26910.7 26979.7 28516.3 34907.0 36469.0 43449.8 44450.0 46300.0 36375.5 36574.0 37599.8 39295.6 39365.6 37144.3 37328.8 37856.6 37880.8 39171.2 37266.3 37950.2 37968.2 38018.8 38881.4 30858.7 32715.2 33306.5 33436.7 33597.8 25258.0 25459.2 25500.9 26028.6 26071.5 10:49:43 INFO - PROCESS | 25211 | 10:49:43 INFO - PROCESS | 25211 | [#2] jquery.html Cycles:180 Average:33339.51 Median:2795.14 stddev:92575.92 (3312.0%) stddev-sans-first:92802.55 10:49:43 INFO - PROCESS | 25211 | Values: 383.3 396.8 400.0 411.7 412.8 18098.8 19342.2 19812.3 19836.5 20051.7 13421.1 13669.4 17791.0 20798.2 20852.1 5445.1 5620.8 7250.1 7528.5 7578.2 7432.4 7630.9 7654.2 7805.5 7807.6 7757.0 7768.8 7769.1 7769.7 7771.5 7498.2 7796.5 7857.5 7870.9 7878.4 7491.3 7552.6 7582.5 7641.7 7645.6 26488.6 26953.9 26998.2 27059.7 27366.2 2743.5 2787.9 2802.3 2865.8 2876.7 14988.7 15653.6 15935.5 16154.8 16451.5 1985.1 2020.7 2028.6 2039.6 2057.6 91058.5 92738.5 97137.5 100872.5 103904.5 19446.7 19594.3 19744.9 19961.8 20238.6 36800.0 36844.8 37024.8 37074.8 37661.0 10138.8 10256.6 10267.0 10512.6 10655.4 8354.0 8380.5 8409.1 8641.1 8645.6 9804.1 10028.9 10144.7 10332.5 10430.2 3752.7 3863.4 3924.2 3959.7 3992.8 831.4 860.7 869.3 873.6 890.8 19283.1 20171.3 20320.5 20448.3 20499.5 857.3 862.3 872.5 874.8 880.2 10533.5 11254.8 11294.8 11322.8 11417.0 518432.0 559463.0 574610.0 578499.0 580586.0 80388.6 101760.5 103003.5 103369.5 104789.5 1023.2 1067.6 1086.9 1091.4 1101.6 16502.4 16877.4 17429.9 17559.5 17907.0 36910.4 41985.8 42226.8 42914.6 43078.0 56541.7 56877.4 57516.4 58266.1 58386.2 8799.0 8868.2 8921.3 9200.0 9204.6 12338.7 12831.1 13031.3 13572.6 14629.4 11360.0 12187.7 12241.3 12465.6 12547.6 11955.1 12060.3 12106.6 12236.4 12588.9 12428.9 12479.8 12571.9 12700.7 12759.4 8936.9 8963.6 9098.4 9220.9 9283.7 9494.2 11620.6 11883.0 12052.3 12062.9 10:49:43 INFO - PROCESS | 25211 | 10:49:43 INFO - PROCESS | 25211 | [#3] mootools.html Cycles:180 Average:5400.85 Median:5286.48 stddev:2977.62 (56.3%) stddev-sans-first:2969.28 10:49:43 INFO - PROCESS | 25211 | Values: 1205.3 1384.3 1397.8 1408.5 1424.9 7034.2 7915.1 8167.6 8171.5 8341.3 8160.2 8218.1 8229.3 8236.1 8243.6 2188.2 2233.3 2235.7 2238.3 2238.9 1955.5 2034.0 2129.7 2221.5 2224.7 2179.2 2217.1 2222.9 2225.7 2225.9 2205.1 2224.3 2226.1 2226.3 2228.9 2171.1 2206.0 2223.5 2231.6 2261.2 8224.7 8246.4 8253.4 8300.0 8301.3 5143.5 5283.6 5289.3 5290.6 5296.4 3149.1 3282.7 3328.8 3374.9 3443.3 1321.3 1329.5 1341.5 1913.3 1956.1 11029.3 11170.8 11217.1 11223.9 11225.6 4350.9 4536.1 4615.6 4679.6 4700.6 9481.1 9481.2 9491.0 9491.4 9497.5 5155.7 5155.9 5162.2 5168.0 5176.1 4728.1 4826.3 4868.1 4908.1 4918.4 3312.8 3409.2 3685.2 3710.2 3811.4 1187.7 1205.9 1207.7 1216.3 1225.9 860.5 868.2 885.8 886.2 902.4 5323.8 5462.9 5529.6 5667.9 5770.9 934.6 934.8 952.5 956.2 975.7 3662.7 3679.9 3680.6 3814.1 3930.3 9843.9 10073.8 10120.4 10164.4 10216.7 9393.3 9827.1 9948.2 9961.9 9970.1 7516.5 7683.5 7691.8 7696.8 7699.2 5306.4 5381.3 5401.8 5528.2 5718.6 8808.6 9140.3 9167.5 9235.2 9320.7 9119.7 9372.7 9383.2 9434.3 9489.1 7107.6 7330.5 7476.0 7487.2 7503.7 7773.8 7945.5 7980.4 7983.1 7992.6 7090.8 7113.5 7206.6 7244.8 7424.7 6774.9 6794.0 6823.6 6855.8 6878.3 5568.6 5810.8 5864.1 5945.1 5978.5 4584.6 4677.3 4713.8 4721.5 4819.2 4803.3 4905.4 4931.3 4953.1 4980.8 10:49:43 INFO - PROCESS | 25211 | 10:49:43 INFO - PROCESS | 25211 | [#4] prototype.html Cycles:180 Average:4642.78 Median:5169.92 stddev:1305.00 (25.2%) stddev-sans-first:1269.07 10:49:43 INFO - PROCESS | 25211 | Values: 392.7 394.2 394.4 399.5 403.8 5113.5 5196.5 5199.2 5203.7 5259.3 5078.4 5081.6 5136.5 5170.6 5180.2 4145.0 4172.6 4174.7 4189.6 4194.9 4196.2 4204.6 4205.6 4208.3 4217.9 4091.9 4143.1 4175.5 4189.2 4193.7 4168.0 4169.4 4177.4 4192.6 4199.1 3777.7 3777.8 3779.6 3783.9 3796.9 5064.4 5074.8 5075.6 5077.4 5086.5 4561.1 4776.6 4779.7 4783.6 4790.9 5494.4 5502.5 5504.7 5506.2 5521.9 4107.9 4137.9 4140.3 4146.6 4155.8 5464.0 5492.7 5573.6 5666.6 5691.5 4165.5 4227.5 4237.3 4266.0 4293.7 4303.2 4313.8 4323.2 4335.3 4337.9 4695.0 4818.9 4823.5 4825.7 4842.2 5492.1 5519.4 5525.4 5548.0 5556.4 1444.8 1456.4 1457.4 1459.8 1465.2 963.1 1030.6 1034.2 1036.0 1047.8 5139.3 5145.2 5149.9 5152.8 5179.8 5455.7 5483.2 5483.3 5492.8 5510.8 4792.5 5141.5 5169.2 5189.2 5221.9 2945.4 2955.4 2959.3 2961.9 2970.4 5673.4 5701.0 5703.1 5728.3 5757.7 5309.6 5540.6 5544.5 5566.4 5570.7 5572.5 5614.6 5647.1 5651.3 5654.9 5133.5 5415.6 5433.3 5444.9 5555.5 5368.8 5374.9 5381.8 5461.7 5494.2 5562.4 5648.0 5684.7 5693.3 5708.7 4044.5 4133.7 4157.2 4259.0 4304.6 5590.2 5635.4 5682.9 5683.5 5689.9 5349.7 5534.5 5554.8 5565.7 5629.4 5535.8 5546.8 5556.2 5567.5 5597.0 5289.8 5536.4 5547.7 5556.9 5572.4 5594.7 5599.9 5601.3 5606.1 5619.7 5422.4 5435.7 5477.2 5479.9 5493.8 10:49:43 INFO - PROCESS | 25211 | 10:49:43 INFO - PROCESS | 25211 | [#5] yui.html Cycles:180 Average:16746.42 Median:26615.58 stddev:20677.41 (77.7%) stddev-sans-first:20700.46 10:49:43 INFO - PROCESS | 25211 | Values: 735.4 756.7 779.7 839.2 851.7 15649.8 16513.1 16566.2 16567.2 16621.5 14004.5 14004.6 14050.2 14064.0 14081.4 800.1 800.1 802.9 810.3 814.6 803.8 805.5 805.8 806.8 809.9 856.4 856.5 857.6 857.8 857.8 847.6 848.7 848.9 849.5 850.1 614.0 617.2 618.6 618.9 619.8 15898.3 16222.7 16223.0 16230.4 16503.3 15258.2 15263.8 15468.8 15646.9 15840.6 11861.5 12027.6 12042.3 12075.5 12169.8 3731.7 3757.5 3763.4 3814.3 3816.7 72198.6 74437.0 74769.6 75307.6 76221.2 11346.3 11467.8 11471.4 11472.9 11974.1 23316.3 23548.9 23576.9 23738.3 23816.5 7817.3 7818.3 7913.0 7913.9 8052.2 4164.7 4254.4 4279.8 4368.6 4440.6 3554.2 3666.9 3670.9 3671.3 3674.7 643.7 675.3 684.3 687.9 698.9 753.9 758.2 759.3 778.7 790.9 10196.8 10320.3 10653.5 10682.8 10824.7 740.5 755.3 769.5 770.6 771.1 4676.2 4997.4 5006.2 5012.4 5021.2 59224.7 63860.0 76116.2 76811.0 77326.6 52717.5 60855.0 64437.7 64932.7 68081.7 59569.4 59597.0 59765.7 60265.7 60361.7 9584.6 9788.0 9788.8 9826.0 9826.6 38265.8 38670.0 38942.0 40438.4 41309.6 48555.0 50348.5 50569.7 50757.0 52548.7 11900.5 12001.4 12124.9 12392.4 12983.5 13336.3 13348.5 13908.2 14210.9 14235.6 12289.8 12412.3 12708.4 12747.7 13179.8 12208.9 12340.3 12795.6 12798.4 13528.0 12518.6 12647.4 12708.2 12977.9 13102.7 9743.5 9867.8 10079.4 10186.7 10662.5 9283.6 9580.7 9638.8 10466.4 10564.4 10:49:43 INFO - PROCESS | 25211 | -------- Summary: end -------- 10:49:43 INFO - PROCESS | 25211 | 10:49:44 INFO - TEST-INFO | 25211: exit 0 10:49:44 INFO - TEST-OK | dromaeo_css | took 1118802ms 10:49:44 INFO - TEST-START | kraken 10:49:44 INFO - operating with platform_type : linux_ 10:49:44 INFO - Initialising browser for kraken test... 10:49:44 INFO - TEST-INFO | started process 25513 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpHD5Fe1/profile http://localhost:43102/getInfo.html) 10:49:47 INFO - PROCESS | 25513 | Iterating over profile /tmp/tmpHD5Fe1/profile 10:49:48 INFO - PROCESS | 25513 | __metrics Screen width/height:1600/1200 10:49:48 INFO - PROCESS | 25513 | colorDepth:24 10:49:48 INFO - PROCESS | 25513 | Browser inner width/height: 1024/697 10:49:48 INFO - PROCESS | 25513 | __metrics 10:49:48 INFO - PROCESS | 25513 | No birth date. Using mtime. 10:49:48 INFO - PROCESS | 25513 | Using date: /tmp/tmpHD5Fe1/profile/addons.json = Thu Feb 16 2017 10:49:47 GMT-0800 (PST) 10:49:48 INFO - PROCESS | 25513 | No birth date. Using mtime. 10:49:48 INFO - PROCESS | 25513 | Using date: /tmp/tmpHD5Fe1/profile/extensions.json = Thu Feb 16 2017 10:49:47 GMT-0800 (PST) 10:49:48 INFO - PROCESS | 25513 | No birth date. Using mtime. 10:49:48 INFO - PROCESS | 25513 | Using date: /tmp/tmpHD5Fe1/profile/places.sqlite-wal = Thu Feb 16 2017 10:49:48 GMT-0800 (PST) 10:49:48 INFO - PROCESS | 25513 | No birth date. Using mtime. 10:49:48 INFO - PROCESS | 25513 | Using date: /tmp/tmpHD5Fe1/profile/key3.db = Thu Feb 16 2017 10:49:44 GMT-0800 (PST) 10:49:49 INFO - PROCESS | 25513 | No birth date. Using mtime. 10:49:49 INFO - PROCESS | 25513 | Using date: /tmp/tmpHD5Fe1/profile/permissions.sqlite = Thu Feb 16 2017 10:49:45 GMT-0800 (PST) 10:49:49 INFO - PROCESS | 25513 | No birth date. Using mtime. 10:49:49 INFO - PROCESS | 25513 | Using date: /tmp/tmpHD5Fe1/profile/localstore.rdf = Thu Feb 16 2017 10:30:23 GMT-0800 (PST) 10:49:49 INFO - PROCESS | 25513 | No birth date. Using mtime. 10:49:49 INFO - PROCESS | 25513 | Using date: /tmp/tmpHD5Fe1/profile/startupCache = Thu Feb 16 2017 10:49:44 GMT-0800 (PST) 10:49:49 INFO - PROCESS | 25513 | No birth date. Using mtime. 10:49:49 INFO - PROCESS | 25513 | Using date: /tmp/tmpHD5Fe1/profile/bookmarkbackups = Thu Feb 16 2017 10:49:47 GMT-0800 (PST) 10:49:49 INFO - PROCESS | 25513 | No birth date. Using mtime. 10:49:49 INFO - PROCESS | 25513 | Using date: /tmp/tmpHD5Fe1/profile/thumbnails = Thu Feb 16 2017 10:49:47 GMT-0800 (PST) 10:49:49 INFO - PROCESS | 25513 | No birth date. Using mtime. 10:49:49 INFO - PROCESS | 25513 | Using date: /tmp/tmpHD5Fe1/profile/cache2 = Thu Feb 16 2017 10:49:44 GMT-0800 (PST) 10:49:49 INFO - PROCESS | 25513 | No birth date. Using mtime. 10:49:49 INFO - PROCESS | 25513 | Using date: /tmp/tmpHD5Fe1/profile/crashes = Thu Feb 16 2017 10:49:44 GMT-0800 (PST) 10:49:49 INFO - PROCESS | 25513 | No birth date. Using mtime. 10:49:49 INFO - PROCESS | 25513 | Using date: /tmp/tmpHD5Fe1/profile/mimeTypes.rdf = Thu Feb 16 2017 10:49:48 GMT-0800 (PST) 10:49:49 INFO - PROCESS | 25513 | No birth date. Using mtime. 10:49:49 INFO - PROCESS | 25513 | Using date: /tmp/tmpHD5Fe1/profile/prefs.js = Thu Feb 16 2017 10:30:23 GMT-0800 (PST) 10:49:49 INFO - PROCESS | 25513 | No birth date. Using mtime. 10:49:49 INFO - PROCESS | 25513 | Using date: /tmp/tmpHD5Fe1/profile/places.sqlite-shm = Thu Feb 16 2017 10:49:45 GMT-0800 (PST) 10:49:49 INFO - PROCESS | 25513 | No birth date. Using mtime. 10:49:49 INFO - PROCESS | 25513 | Using date: /tmp/tmpHD5Fe1/profile/pluginreg.dat = Thu Feb 16 2017 10:49:47 GMT-0800 (PST) 10:49:49 INFO - PROCESS | 25513 | No birth date. Using mtime. 10:49:49 INFO - PROCESS | 25513 | Using date: /tmp/tmpHD5Fe1/profile/.parentlock = Thu Feb 16 2017 10:49:44 GMT-0800 (PST) 10:49:49 INFO - PROCESS | 25513 | No birth date. Using mtime. 10:49:49 INFO - PROCESS | 25513 | Using date: /tmp/tmpHD5Fe1/profile/cookies.sqlite = Thu Feb 16 2017 10:49:48 GMT-0800 (PST) 10:49:49 INFO - PROCESS | 25513 | No birth date. Using mtime. 10:49:49 INFO - PROCESS | 25513 | Using date: /tmp/tmpHD5Fe1/profile/blocklist.xml = Thu Feb 16 2017 10:49:44 GMT-0800 (PST) 10:49:49 INFO - PROCESS | 25513 | No birth date. Using mtime. 10:49:49 INFO - PROCESS | 25513 | Using date: /tmp/tmpHD5Fe1/profile/webappsstore.sqlite = Thu Feb 16 2017 10:49:48 GMT-0800 (PST) 10:49:49 INFO - PROCESS | 25513 | No birth date. Using mtime. 10:49:49 INFO - PROCESS | 25513 | Using date: /tmp/tmpHD5Fe1/profile/safebrowsing = Thu Feb 16 2017 10:49:49 GMT-0800 (PST) 10:49:49 INFO - PROCESS | 25513 | No birth date. Using mtime. 10:49:49 INFO - PROCESS | 25513 | Using date: /tmp/tmpHD5Fe1/profile/extensions = Thu Feb 16 2017 10:49:44 GMT-0800 (PST) 10:49:49 INFO - PROCESS | 25513 | No birth date. Using mtime. 10:49:49 INFO - PROCESS | 25513 | Using date: /tmp/tmpHD5Fe1/profile/frequencyCap.json = Thu Feb 16 2017 10:49:47 GMT-0800 (PST) 10:49:49 INFO - PROCESS | 25513 | No birth date. Using mtime. 10:49:49 INFO - PROCESS | 25513 | Using date: /tmp/tmpHD5Fe1/profile/sessionCheckpoints.json = Thu Feb 16 2017 10:49:48 GMT-0800 (PST) 10:49:49 INFO - PROCESS | 25513 | No birth date. Using mtime. 10:49:49 INFO - PROCESS | 25513 | Using date: /tmp/tmpHD5Fe1/profile/compatibility.ini = Thu Feb 16 2017 10:49:44 GMT-0800 (PST) 10:49:49 INFO - PROCESS | 25513 | Stat failure 10:49:49 INFO - PROCESS | 25513 | No birth date. Using mtime. 10:49:49 INFO - PROCESS | 25513 | Using date: /tmp/tmpHD5Fe1/profile/cert8.db = Thu Feb 16 2017 10:49:44 GMT-0800 (PST) 10:49:49 INFO - PROCESS | 25513 | No birth date. Using mtime. 10:49:49 INFO - PROCESS | 25513 | Using date: /tmp/tmpHD5Fe1/profile/user.js = Thu Feb 16 2017 10:49:44 GMT-0800 (PST) 10:49:49 INFO - PROCESS | 25513 | No birth date. Using mtime. 10:49:49 INFO - PROCESS | 25513 | Using date: /tmp/tmpHD5Fe1/profile/extensions.ini = Thu Feb 16 2017 10:49:44 GMT-0800 (PST) 10:49:49 INFO - PROCESS | 25513 | No birth date. Using mtime. 10:49:49 INFO - PROCESS | 25513 | Using date: /tmp/tmpHD5Fe1/profile/content-prefs.sqlite = Thu Feb 16 2017 10:49:46 GMT-0800 (PST) 10:49:49 INFO - PROCESS | 25513 | No birth date. Using mtime. 10:49:49 INFO - PROCESS | 25513 | Using date: /tmp/tmpHD5Fe1/profile/directoryLinks.json = Thu Feb 16 2017 10:49:47 GMT-0800 (PST) 10:49:49 INFO - PROCESS | 25513 | No birth date. Using mtime. 10:49:49 INFO - PROCESS | 25513 | Using date: /tmp/tmpHD5Fe1/profile/secmod.db = Thu Feb 16 2017 10:49:44 GMT-0800 (PST) 10:49:49 INFO - PROCESS | 25513 | No birth date. Using mtime. 10:49:49 INFO - PROCESS | 25513 | Using date: /tmp/tmpHD5Fe1/profile/gmp = Thu Feb 16 2017 10:49:44 GMT-0800 (PST) 10:49:49 INFO - PROCESS | 25513 | No birth date. Using mtime. 10:49:49 INFO - PROCESS | 25513 | Using date: /tmp/tmpHD5Fe1/profile/minidumps = Thu Feb 16 2017 10:49:44 GMT-0800 (PST) 10:49:49 INFO - PROCESS | 25513 | No birth date. Using mtime. 10:49:49 INFO - PROCESS | 25513 | Using date: /tmp/tmpHD5Fe1/profile/places.sqlite = Thu Feb 16 2017 10:49:48 GMT-0800 (PST) 10:49:49 INFO - PROCESS | 25513 | Stat failure 10:49:49 INFO - PROCESS | 25513 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 10:49:49 INFO - PROCESS | 25513 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 10:49:51 INFO - TEST-INFO | 25513: exit 0 10:49:51 INFO - Browser initialized. 10:49:51 INFO - Running cycle 1/1 for kraken test... 10:49:51 INFO - TEST-INFO | started process 25598 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpHD5Fe1/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 10:50:03 INFO - PROCESS | 25598 | RSS: Main: 150081536 10:50:03 INFO - PROCESS | 25598 | 10:50:05 INFO - PROCESS | 25598 | Cycle 1(1): loaded http://localhost:43102/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:43102/tests/kraken/driver.html?testName=audio-beat-detection) 10:50:06 INFO - PROCESS | 25598 | RSS: Main: 206385152 10:50:06 INFO - PROCESS | 25598 | 10:50:09 INFO - PROCESS | 25598 | Cycle 1(1): loaded http://localhost:43102/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:43102/tests/kraken/driver.html?testName=audio-dft) 10:50:09 INFO - PROCESS | 25598 | RSS: Main: 184791040 10:50:09 INFO - PROCESS | 25598 | 10:50:12 INFO - PROCESS | 25598 | Cycle 1(1): loaded http://localhost:43102/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:43102/tests/kraken/driver.html?testName=audio-fft) 10:50:13 INFO - PROCESS | 25598 | RSS: Main: 267169792 10:50:13 INFO - PROCESS | 25598 | 10:50:15 INFO - PROCESS | 25598 | Cycle 1(1): loaded http://localhost:43102/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:43102/tests/kraken/driver.html?testName=audio-oscillator) 10:50:15 INFO - PROCESS | 25598 | RSS: Main: 177790976 10:50:15 INFO - PROCESS | 25598 | 10:50:18 INFO - PROCESS | 25598 | Cycle 1(1): loaded http://localhost:43102/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:43102/tests/kraken/driver.html?testName=imaging-gaussian-blur) 10:50:18 INFO - PROCESS | 25598 | RSS: Main: 182108160 10:50:18 INFO - PROCESS | 25598 | 10:50:21 INFO - PROCESS | 25598 | Cycle 1(1): loaded http://localhost:43102/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:43102/tests/kraken/driver.html?testName=imaging-darkroom) 10:50:22 INFO - PROCESS | 25598 | RSS: Main: 221696000 10:50:22 INFO - PROCESS | 25598 | 10:50:25 INFO - PROCESS | 25598 | Cycle 1(1): loaded http://localhost:43102/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:43102/tests/kraken/driver.html?testName=imaging-desaturate) 10:50:26 INFO - PROCESS | 25598 | RSS: Main: 221503488 10:50:26 INFO - PROCESS | 25598 | 10:50:28 INFO - PROCESS | 25598 | Cycle 1(1): loaded http://localhost:43102/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:43102/tests/kraken/driver.html?testName=json-parse-financial) 10:50:29 INFO - PROCESS | 25598 | RSS: Main: 219582464 10:50:29 INFO - PROCESS | 25598 | 10:50:31 INFO - PROCESS | 25598 | Cycle 1(1): loaded http://localhost:43102/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:43102/tests/kraken/driver.html?testName=json-stringify-tinderbox) 10:50:31 INFO - PROCESS | 25598 | RSS: Main: 182345728 10:50:31 INFO - PROCESS | 25598 | 10:50:33 INFO - PROCESS | 25598 | Cycle 1(1): loaded http://localhost:43102/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:43102/tests/kraken/driver.html?testName=stanford-crypto-aes) 10:50:34 INFO - PROCESS | 25598 | RSS: Main: 189628416 10:50:34 INFO - PROCESS | 25598 | 10:50:36 INFO - PROCESS | 25598 | Cycle 1(1): loaded http://localhost:43102/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:43102/tests/kraken/driver.html?testName=stanford-crypto-ccm) 10:50:36 INFO - PROCESS | 25598 | RSS: Main: 198606848 10:50:36 INFO - PROCESS | 25598 | 10:50:39 INFO - PROCESS | 25598 | Cycle 1(1): loaded http://localhost:43102/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:43102/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 10:50:40 INFO - PROCESS | 25598 | RSS: Main: 208752640 10:50:40 INFO - PROCESS | 25598 | 10:50:43 INFO - PROCESS | 25598 | Cycle 1(1): loaded http://localhost:43102/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:43102/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 10:50:43 INFO - PROCESS | 25598 | RSS: Main: 204345344 10:50:43 INFO - PROCESS | 25598 | 10:50:45 INFO - PROCESS | 25598 | Cycle 1(1): loaded http://localhost:43102/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:43102/tests/kraken/driver.html?testName=ai-astar) 10:50:45 INFO - PROCESS | 25598 | RSS: Main: 256294912 10:50:45 INFO - PROCESS | 25598 | 10:50:45 INFO - PROCESS | 25598 | __start_tp_report 10:50:45 INFO - PROCESS | 25598 | _x_x_mozilla_page_load 10:50:45 INFO - PROCESS | 25598 | _x_x_mozilla_page_load_details 10:50:45 INFO - PROCESS | 25598 | |i|pagename|runs| 10:50:45 INFO - PROCESS | 25598 | |0;ai-astar;111;112;112;112;112;116;112;113;112;114 10:50:45 INFO - PROCESS | 25598 | |1;audio-beat-detection;145;121;120;151;123;121;122;124;145;123 10:50:45 INFO - PROCESS | 25598 | |2;audio-dft;164;161;162;161;161;162;163;164;164;164 10:50:45 INFO - PROCESS | 25598 | |3;audio-fft;74;76;76;115;88;76;77;76;102;75 10:50:45 INFO - PROCESS | 25598 | |4;audio-oscillator;83;83;105;83;82;83;94;83;82;111 10:50:45 INFO - PROCESS | 25598 | |5;imaging-gaussian-blur;114;113;113;112;114;114;113;113;114;113 10:50:45 INFO - PROCESS | 25598 | |6;imaging-darkroom;146;148;147;147;146;148;147;148;147;147 10:50:45 INFO - PROCESS | 25598 | |7;imaging-desaturate;96;96;96;96;96;96;97;96;96;96 10:50:45 INFO - PROCESS | 25598 | |8;json-parse-financial;57;56;56;57;58;57;58;58;56;57 10:50:45 INFO - PROCESS | 25598 | |9;json-stringify-tinderbox;65;66;65;65;65;65;66;65;65;66 10:50:45 INFO - PROCESS | 25598 | |10;stanford-crypto-aes;95;84;93;84;86;85;84;84;85;92 10:50:45 INFO - PROCESS | 25598 | |11;stanford-crypto-ccm;140;132;134;132;128;136;129;138;171;147 10:50:45 INFO - PROCESS | 25598 | |12;stanford-crypto-pbkdf2;172;172;171;173;177;171;172;171;169;169 10:50:45 INFO - PROCESS | 25598 | |13;stanford-crypto-sha256-iterative;63;63;64;66;63;63;69;64;63;63 10:50:45 INFO - PROCESS | 25598 | __end_tp_report 10:50:45 INFO - PROCESS | 25598 | __start_cc_report 10:50:45 INFO - PROCESS | 25598 | _x_x_mozilla_cycle_collect,1727 10:50:45 INFO - PROCESS | 25598 | __end_cc_report 10:50:45 INFO - PROCESS | 25598 | __startTimestamp1487271045564__endTimestamp 10:50:45 INFO - PROCESS | 25598 | 10:50:45 INFO - PROCESS | 25598 | ------- Summary: start ------- 10:50:45 INFO - PROCESS | 25598 | Number of tests: 14 10:50:45 INFO - PROCESS | 25598 | 10:50:45 INFO - PROCESS | 25598 | [#0] ai-astar Cycles:10 Average:112.60 Median:112.00 stddev:1.43 (1.3%) stddev-sans-first:1.39 10:50:45 INFO - PROCESS | 25598 | Values: 111.0 112.0 112.0 112.0 112.0 116.0 112.0 113.0 112.0 114.0 10:50:45 INFO - PROCESS | 25598 | 10:50:45 INFO - PROCESS | 25598 | [#1] audio-beat-detection Cycles:10 Average:129.50 Median:123.50 stddev:12.24 (9.9%) stddev-sans-first:11.63 10:50:45 INFO - PROCESS | 25598 | Values: 145.0 121.0 120.0 151.0 123.0 121.0 122.0 124.0 145.0 123.0 10:50:45 INFO - PROCESS | 25598 | 10:50:45 INFO - PROCESS | 25598 | [#2] audio-dft Cycles:10 Average:162.60 Median:163.50 stddev:1.35 (0.8%) stddev-sans-first:1.33 10:50:45 INFO - PROCESS | 25598 | Values: 164.0 161.0 162.0 161.0 161.0 162.0 163.0 164.0 164.0 164.0 10:50:45 INFO - PROCESS | 25598 | 10:50:45 INFO - PROCESS | 25598 | [#3] audio-fft Cycles:10 Average:83.50 Median:76.00 stddev:14.08 (18.5%) stddev-sans-first:14.51 10:50:45 INFO - PROCESS | 25598 | Values: 74.0 76.0 76.0 115.0 88.0 76.0 77.0 76.0 102.0 75.0 10:50:45 INFO - PROCESS | 25598 | 10:50:45 INFO - PROCESS | 25598 | [#4] audio-oscillator Cycles:10 Average:88.90 Median:83.00 stddev:10.76 (13.0%) stddev-sans-first:11.20 10:50:45 INFO - PROCESS | 25598 | Values: 83.0 83.0 105.0 83.0 82.0 83.0 94.0 83.0 82.0 111.0 10:50:45 INFO - PROCESS | 25598 | 10:50:45 INFO - PROCESS | 25598 | [#5] imaging-gaussian-blur Cycles:10 Average:113.30 Median:113.50 stddev:0.67 (0.6%) stddev-sans-first:0.67 10:50:45 INFO - PROCESS | 25598 | Values: 114.0 113.0 113.0 112.0 114.0 114.0 113.0 113.0 114.0 113.0 10:50:45 INFO - PROCESS | 25598 | 10:50:45 INFO - PROCESS | 25598 | [#6] imaging-darkroom Cycles:10 Average:147.10 Median:147.00 stddev:0.74 (0.5%) stddev-sans-first:0.67 10:50:45 INFO - PROCESS | 25598 | Values: 146.0 148.0 147.0 147.0 146.0 148.0 147.0 148.0 147.0 147.0 10:50:45 INFO - PROCESS | 25598 | 10:50:45 INFO - PROCESS | 25598 | [#7] imaging-desaturate Cycles:10 Average:96.10 Median:96.00 stddev:0.32 (0.3%) stddev-sans-first:0.33 10:50:45 INFO - PROCESS | 25598 | Values: 96.0 96.0 96.0 96.0 96.0 96.0 97.0 96.0 96.0 96.0 10:50:45 INFO - PROCESS | 25598 | 10:50:45 INFO - PROCESS | 25598 | [#8] json-parse-financial Cycles:10 Average:57.00 Median:57.00 stddev:0.82 (1.4%) stddev-sans-first:0.87 10:50:45 INFO - PROCESS | 25598 | Values: 57.0 56.0 56.0 57.0 58.0 57.0 58.0 58.0 56.0 57.0 10:50:45 INFO - PROCESS | 25598 | 10:50:45 INFO - PROCESS | 25598 | [#9] json-stringify-tinderbox Cycles:10 Average:65.30 Median:65.00 stddev:0.48 (0.7%) stddev-sans-first:0.50 10:50:45 INFO - PROCESS | 25598 | Values: 65.0 66.0 65.0 65.0 65.0 65.0 66.0 65.0 65.0 66.0 10:50:45 INFO - PROCESS | 25598 | 10:50:45 INFO - PROCESS | 25598 | [#10] stanford-crypto-aes Cycles:10 Average:87.20 Median:85.50 stddev:4.34 (5.1%) stddev-sans-first:3.57 10:50:45 INFO - PROCESS | 25598 | Values: 95.0 84.0 93.0 84.0 86.0 85.0 84.0 84.0 85.0 92.0 10:50:45 INFO - PROCESS | 25598 | 10:50:45 INFO - PROCESS | 25598 | [#11] stanford-crypto-ccm Cycles:10 Average:138.70 Median:137.00 stddev:12.66 (9.2%) stddev-sans-first:13.42 10:50:45 INFO - PROCESS | 25598 | Values: 140.0 132.0 134.0 132.0 128.0 136.0 129.0 138.0 171.0 147.0 10:50:45 INFO - PROCESS | 25598 | 10:50:45 INFO - PROCESS | 25598 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:171.70 Median:172.00 stddev:2.26 (1.3%) stddev-sans-first:2.40 10:50:45 INFO - PROCESS | 25598 | Values: 172.0 172.0 171.0 173.0 177.0 171.0 172.0 171.0 169.0 169.0 10:50:45 INFO - PROCESS | 25598 | 10:50:45 INFO - PROCESS | 25598 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:64.10 Median:63.50 stddev:1.97 (3.1%) stddev-sans-first:2.05 10:50:45 INFO - PROCESS | 25598 | Values: 63.0 63.0 64.0 66.0 63.0 63.0 69.0 64.0 63.0 63.0 10:50:45 INFO - PROCESS | 25598 | -------- Summary: end -------- 10:50:45 INFO - PROCESS | 25598 | 10:50:46 INFO - TEST-INFO | 25598: exit 0 10:50:46 INFO - TEST-OK | kraken | took 62304ms 10:50:46 INFO - SUITE-END | took 1181s 10:50:46 INFO - Completed test suite (00:19:41) 10:50:46 INFO - javascript benchmark 10:50:46 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": [], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7280.755903702169, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3152.3380090181067, 3153.448146574349, 3164.9841750791243, 3208.7272581830543, 3210.2134976930643, 5185.274061631375, 5275.235090911816, 5319.388270348906, 5398.730063496824, 5406.594505412095, 5302.2311764794085, 5314.654547454413, 5327.413984461716, 5364.195370694388, 5410.729463526827, 4860.538248866361, 4877.756112194394, 4896.412430508344, 4916.950830491685, 4949.752512374385, 4749.572538471537, 4979.402471703401, 4994.650374473788, 5016.874578135557, 5052.696838189702, 4781.832635857746, 4841.564259216652, 4845.4185497740145, 4863.2461968394955, 4866.3673722416215, 4817.0365926814775, 4896.192128298826, 4909.975450122726, 4913.385826771653, 4992.126377883892, 4873.488283730193, 4882.3408839806525, 4897.642263069079, 4902.632302577321, 4904.60763138948, 5165.896682066337, 5441.972790136064, 5471.808486702986, 5479.726013699339, 5482.725863706839, 5612.07400778871, 5681.914771278434, 5682.17608446777, 5682.318121825366, 5682.6306290091425, 5726.3700992890745, 5731.942680573183, 5744.3341430984965, 5746.0, 5839.678817665027, 5440.347158341025, 5488.286522752057, 5491.505764481176, 5491.917621235684, 5493.725313734337, 5900.085486749561, 5948.643081415129, 5949.583529153005, 5954.8809023819285, 5955.612885162451, 5526.226328314039, 5568.777248910079, 5584.21820945068, 5736.397678243724, 5751.309842818889, 5695.515881150149, 5720.570957178145, 5736.426357364314, 5772.0, 5772.307323121252, 5775.364709881909, 5809.622374545641, 5815.651060936358, 5817.50551203144, 5836.358000619928, 5817.825465236049, 5820.854478637983, 5821.563382746311, 5823.126531020296, 5825.300963884361, 4501.864944051684, 4503.189425903303, 4511.075229577928, 4515.187266292099, 4545.499995000548, 3894.2600905827803, 3928.4107383892647, 3936.2324346752107, 3968.2460332536725, 3987.7009224307712, 5484.396716361197, 5488.231647569343, 5501.587380946445, 5510.586705996986, 5537.972310138423, 5788.507976821933, 5801.883962320814, 5845.0, 5852.4440178183, 5854.590178687451, 5232.764525596282, 5280.788768449219, 5488.039593071197, 5501.202325662756, 5503.587230957694, 4857.441394239708, 4870.196417591129, 4870.878228044327, 4917.311576379309, 4974.030064137531, 6096.980278560325, 6097.464185086759, 6139.447449729546, 6143.846403840029, 6144.41628045335, 5904.350521442552, 5906.822795316148, 5910.438508341701, 5911.881762364643, 5919.4080591940465, 6049.576529642883, 6051.425114614105, 6094.54290928191, 6104.69476526163, 6105.236845394325, 5464.890702185855, 5486.890262194654, 5488.094464413327, 5558.138488534284, 5565.109582466948, 5706.258186435737, 5729.341125770423, 5760.0783874579865, 5813.738381772746, 5864.794732184353, 5597.944020559905, 5634.098544232903, 5634.802781902614, 5643.1535269709875, 5653.462921022497, 5476.205950137128, 5669.603127781016, 5671.744771485375, 5673.574481913955, 5679.602427830012, 5475.452454754652, 5553.139263414178, 5556.083246264483, 5642.069058605284, 5694.544436444991, 5710.5146062585145, 5711.857203569778, 5713.914291285717, 5739.253896993364, 5762.049261871744, 5512.80705175317, 5536.889262214814, 5566.4990150886615, 5581.609287350009, 5584.218209450599], "value": 5329.604133525992, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [566.4703502225419, 574.4026496333097, 592.2005292854645, 600.3846057790764, 603.9818805435839, 30633.0, 33154.33691326175, 33199.33601327972, 41606.58393416065, 43962.7801860991, 44718.776406118006, 45124.77437612807, 45214.77392613032, 45237.0, 45265.77367113168, 9009.234215091725, 9210.815783684322, 9211.078892110801, 9212.263018958502, 9213.078692130766, 7515.699372025139, 7698.0, 9105.135012173832, 9212.585433655502, 10397.896021039805, 10575.682729518127, 10862.782744345108, 10971.23201375904, 10975.83536246953, 10977.176711746612, 10782.191335649819, 10811.621593244277, 10838.0, 10853.891461085366, 10858.945705271502, 10486.475676216234, 10578.68263952075, 10579.259451838374, 10583.0, 10587.629432969808, 41866.37200441996, 42123.7893810529, 42144.0, 42211.788941055405, 42221.15557688829, 3790.2798468290935, 3872.7289089763726, 3907.37482002881, 3909.0, 3973.4238535412205, 31402.685973140204, 31604.103753774616, 31635.525467118012, 31643.20891977696, 32351.51472727911, 4029.133736246721, 4105.7331273467125, 4154.729942553724, 4172.123853990665, 4295.2912769393015, 297415.0, 300387.49806251045, 300523.0, 300841.0, 301807.0, 58272.01471646774, 58662.70668646629, 58831.411685882595, 58833.0, 58839.349539849136, 60574.0, 60650.0, 60663.0, 60707.39292607106, 60727.0, 15574.922125389301, 15772.74135983301, 15781.0, 15799.052056876624, 15829.445969391016, 12246.510139594317, 12301.446434910451, 12319.93840030812, 12338.074644401706, 12340.321282329378, 13150.0, 13738.313084345742, 14176.85823141776, 17039.92167953878, 21250.70614054824, 7565.394768418623, 7569.205233450519, 7661.808454788675, 7801.684284179201, 8158.469699469634, 861.2679222660688, 866.7573079537614, 906.5376657904516, 908.922741566961, 927.9814403711888, 31231.375372492425, 32089.358212835614, 32210.677893221236, 32287.677123228466, 32598.511022334686, 1033.617561502234, 1054.3357684658615, 1075.3870293932384, 1081.9567217311376, 1100.658795773313, 13886.722265554632, 14757.335919883632, 14855.554333370017, 15143.0, 15166.924165379103, 453321.0, 455752.0, 457525.0, 458118.0, 458510.0, 44788.55211447909, 45628.54371456309, 50495.24257136146, 51843.22235166476, 51992.0, 1349.6355983884494, 1372.945082196701, 1382.10853999172, 1401.054288355356, 1403.4666826605824, 28293.57559636648, 29305.706942930297, 29831.254218644706, 29836.85081574665, 30529.54205686961, 46092.0, 46930.0, 46975.0, 46994.29508557306, 47078.52921470878, 381118.0, 381561.0, 382265.0, 382502.0, 382565.0, 26250.212493625222, 26316.342091447867, 26910.730892691605, 26979.730202698505, 28516.287092822844, 34907.0, 36469.0, 43449.78275108731, 44450.0, 46300.0, 36375.45436818397, 36574.0, 37599.81200093982, 39295.607043929194, 39365.6063439362, 37144.25711485809, 37328.813355933045, 37856.62143378531, 37880.810595946845, 39171.2165756689, 37266.25467490689, 37950.24099517939, 37968.24063518659, 38018.80990595029, 38881.416778748906, 30858.69141308648, 32715.182120447178, 33306.5004024935, 33436.66563334336, 33597.832010840764, 25258.0, 25459.236222913343, 25500.872495637403, 26028.609570856832, 26071.478570428866], "value": 19330.209180453505, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [383.33874067234007, 396.82738008966095, 399.9520057593089, 411.7067931620871, 412.7544111253804, 18098.81901180988, 19342.226310947546, 19812.30656927008, 19836.50408739782, 20051.69922451161, 13421.127626704281, 13669.384877680503, 17791.0, 20798.168073277087, 20852.061657225422, 5445.128779395288, 5620.775168993236, 7250.093738282715, 7528.47300688952, 7578.242175782433, 7432.368248698867, 7630.885536716926, 7654.158042615308, 7805.492642978188, 7807.570583617899, 7756.961215193916, 7768.805779855492, 7769.0677118745825, 7769.689212431496, 7771.455998080137, 7498.2126876677985, 7796.4932279401655, 7857.489263197874, 7870.921290787133, 7878.448508604342, 7491.32578067971, 7552.5846078465665, 7582.49218312549, 7641.656125474367, 7645.617719114022, 26488.60267095995, 26953.865230673917, 26998.190054298404, 27059.729402706114, 27366.179014629593, 2743.492453896036, 2787.9303017424522, 2802.341449759305, 2865.813722108077, 2876.7123287671066, 14988.700225995419, 15653.608659783482, 15935.521934341989, 16154.83845161557, 16451.506454806375, 1985.1463870535663, 2020.7272018277492, 2028.614563232981, 2039.5512987142804, 2057.609054279697, 91058.5447072767, 92738.5363073187, 97137.5143124287, 100872.49563752208, 103904.48047759788, 19446.70829937552, 19594.314199002965, 19744.901275493532, 19961.800381996283, 20238.595228095357, 36800.0, 36844.81577592095, 37024.81487592545, 37074.814625926694, 37661.0, 10138.847917281246, 10256.641017564349, 10267.0, 10512.63205787809, 10655.413952232702, 8354.0, 8380.497170169809, 8409.117042710428, 8641.135886411308, 8645.56772161402, 9804.068613481708, 10028.949855250678, 10144.695659130239, 10332.483375831329, 10430.217733670004, 3752.7373083883867, 3863.3818589025623, 3924.17592305619, 3959.742616729903, 3992.7803970781306, 831.4161522300028, 860.6901515454431, 869.3132425383876, 873.554487211514, 890.8218356328631, 19283.132259048376, 20171.294004709765, 20320.491987700425, 20448.284310049075, 20499.4875128123, 857.2798848966897, 862.3403096630957, 872.5026734761125, 874.776931882366, 880.1990188928045, 10533.525991330409, 11254.83117753218, 11294.830577541343, 11322.830157547643, 11417.0, 518432.0, 559463.0, 574610.0, 578499.0, 580586.0, 80388.59805701052, 101760.49119754354, 103003.4849825761, 103369.48315258375, 104789.47605261925, 1023.1967905194387, 1067.64767626683, 1086.8587083679072, 1091.4270008245576, 1101.5758932810766, 16502.42241521541, 16877.40929067477, 17429.912850435667, 17559.473215803548, 17906.955817510792, 36910.446343304335, 41985.79007105067, 42226.788866056704, 42914.57085429231, 43078.0, 56541.71729141493, 56877.431225687214, 57516.42483575111, 58266.126008109066, 58386.24841254769, 8799.0, 8868.201861832207, 8921.330900182642, 9200.033996430278, 9204.631814727336, 12338.691532711755, 12831.10182287231, 13031.283279419344, 13572.592822215349, 14629.414823406945, 11359.977602015528, 12187.695307617381, 12241.326727030098, 12465.563705270271, 12547.560835370718, 11955.103367247666, 12060.336681482602, 12106.576269830513, 12236.449359778653, 12588.937055315031, 12428.937855310665, 12479.750404992032, 12571.874281257436, 12700.682482938, 12759.362031898183, 8936.910630893608, 8963.641454341754, 9098.363114581916, 9220.907790922005, 9283.675071372469, 9494.192993595621, 11620.593279235525, 11883.0, 12052.337121458053, 12062.879371206527], "value": 10844.200607228555, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1205.276833899661, 1384.3355189509036, 1397.7623803953327, 1408.542223777272, 1424.8860091192705, 7034.22623511414, 7915.089764677064, 8167.550784706838, 8171.509709417439, 8341.33269338453, 8160.183981601836, 8218.137095604965, 8229.300509456703, 8236.052853921787, 8243.587820608975, 2188.223180770827, 2233.3300009997015, 2235.7205349331334, 2238.250186187629, 2238.9440263993447, 1955.4622478818296, 2033.9694904576443, 2129.7337832770904, 2221.5445833604153, 2224.71078759761, 2179.2154824263253, 2217.135317226283, 2222.944426389337, 2225.688403623494, 2225.9332220033425, 2205.0628482894704, 2224.3327001899447, 2226.142934970032, 2226.265332440291, 2228.855124416924, 2171.1098449635574, 2205.9669104963436, 2223.5219427823095, 2231.5760005599045, 2261.197274967379, 8224.712135075244, 8246.422750407473, 8253.380996425292, 8299.95850020746, 8301.29438997686, 5143.459936706667, 5283.63014588979, 5289.338832645919, 5290.603204759658, 5296.443873393277, 3149.118246890874, 3282.7045565899184, 3328.783629064103, 3374.8650053997812, 3443.3113377324385, 1321.2600943471687, 1329.4988449389432, 1341.5170538606094, 1913.292081929681, 1956.1295223625493, 11029.283096598696, 11170.776584468347, 11217.10263178952, 11223.887761122365, 11225.550977960871, 4350.869473915787, 4536.070105628338, 4615.5845973862515, 4679.602233810097, 4700.647451441156, 9481.09929556691, 9481.24150067993, 9490.952545237229, 9491.38306010107, 9497.477638729937, 5155.74221288934, 5155.896882062413, 5162.174052151638, 5168.0, 5176.094183517869, 4728.078024785203, 4826.300186472942, 4868.075065737498, 4908.067467181296, 4918.409790825052, 3312.751543634237, 3409.2329225924464, 3685.152414944524, 3710.165212827092, 3811.447340135665, 1187.6615164678026, 1205.8613259475096, 1207.7463732616072, 1216.2641601830942, 1225.8651548329676, 860.5138096975189, 868.2229404682773, 885.7962668586262, 886.2378354615026, 902.4359775140537, 5323.760430780624, 5462.890742185134, 5529.557635389159, 5667.914981275284, 5770.88458230833, 934.6354921580589, 934.8130373925242, 952.476138124029, 956.1967946924617, 975.7365511311906, 3662.7436079474182, 3679.871204507883, 3680.613535578779, 3814.1418180909072, 3930.253251882133, 9843.901560984441, 10073.848892266622, 10120.392776433437, 10164.440955747357, 10216.744581385525, 9393.295502837314, 9827.11555959967, 9948.204143668489, 9961.900380996241, 9970.102690757722, 7516.549007059594, 7683.462157649023, 7691.769246922601, 7696.846063078707, 7699.230076992368, 5306.389765176899, 5381.300430943952, 5401.837944861661, 5528.170774383714, 5718.628289161191, 8808.603612837565, 9140.314476414163, 9167.541622918961, 9235.1688348049, 9320.72037838866, 9119.726408207764, 9372.67195648149, 9383.249340052917, 9434.339596228197, 9489.145976862115, 7107.573545587281, 7330.523515971445, 7475.962620186864, 7487.2138425464545, 7503.737369192052, 7773.766786996399, 7945.483543569881, 7980.441369104107, 7983.081945576101, 7992.600369981361, 7090.822729431806, 7113.502054856111, 7206.56760594366, 7244.782656520313, 7424.703011879465, 6774.898376524455, 6794.0, 6823.590584564942, 6855.760048398282, 6878.312168783082, 5568.610197286151, 5810.796622118206, 5864.120381942743, 5945.078512830518, 5978.461938425563, 4584.564466375689, 4677.2750223715375, 4713.81144754206, 4721.504242054535, 4819.180739274402, 4803.279508073817, 4905.435874874435, 4931.2849636803185, 4953.083679519301, 4980.775865086008], "value": 4387.154950132122, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [392.7231301932138, 394.2017414735161, 394.3867286369694, 399.45873341622104, 403.79810094952524, 5113.48865113489, 5196.454372290912, 5199.246109314147, 5203.713795741238, 5259.342582177228, 5078.365204349457, 5081.618878584102, 5136.512031357024, 5170.612204084699, 5180.171172612383, 4144.9585504145025, 4172.624463798257, 4174.726708353951, 4189.601987811168, 4194.937075943863, 4196.202721482909, 4204.621584057434, 4205.570106163904, 4208.305629571117, 4217.915641687165, 4091.9181616367655, 4143.116872850872, 4175.478065241845, 4189.183109293689, 4193.748375097489, 4167.979160104211, 4169.353750168729, 4177.436046133764, 4192.616436575922, 4199.118185181115, 3777.715576703934, 3777.773333599993, 3779.5653499847595, 3783.881026625099, 3796.886093417202, 5064.366954130734, 5074.767102474899, 5075.6447048706605, 5077.390713114413, 5086.516780905808, 4561.087782443525, 4776.641751868624, 4779.689320194177, 4783.641226907961, 4790.928136077961, 5494.395616482184, 5502.477264659851, 5504.697241651707, 5506.229127922094, 5521.917171242435, 4107.856225032109, 4137.937930931039, 4140.3168477201225, 4146.564610715892, 4155.792210389468, 5463.972680136613, 5492.697901615409, 5573.554115670737, 5666.631668941505, 5691.467297052814, 4165.4584903962295, 4227.51383590891, 4237.279662457389, 4265.954841063897, 4293.656507479394, 4303.18141617235, 4313.805878735465, 4323.200207961537, 4335.349697545394, 4337.9132417351475, 4694.976525117352, 4818.855434336975, 4823.517648235148, 4825.710457372569, 4842.201036828884, 5492.148716948879, 5519.420460851633, 5525.392206857243, 5547.972260138674, 5556.361018482846, 1444.812174417319, 1456.4028748213182, 1457.4243173946443, 1459.8467160947948, 1465.2087872548918, 963.0995019656643, 1030.6189705794272, 1034.2243317511866, 1035.984460233097, 1047.7799662070897, 5139.254808052886, 5145.228215767665, 5149.897002059938, 5152.845414637567, 5179.792808287626, 5455.727213639302, 5483.232347471358, 5483.314585676791, 5492.80775172867, 5510.834674959758, 4792.544708252711, 5141.460146684619, 5169.224616307509, 5189.221616757516, 5221.947780522146, 2945.365322246174, 2955.4384666913647, 2959.3045634275927, 2961.9111426657237, 2970.435617232719, 5673.375928647845, 5701.0, 5703.093381034005, 5728.255326807488, 5757.6833274169485, 5309.5752339812725, 5540.63985840919, 5544.528715059185, 5566.359868615161, 5570.693611851387, 5572.47061529146, 5614.550835933197, 5647.068233741469, 5651.321841379061, 5654.9151762723595, 5133.460986596354, 5415.620906536505, 5433.266509021231, 5444.891102178013, 5555.527780138734, 5368.812091576776, 5374.892502150014, 5381.838544843661, 5461.699606521679, 5494.203340515683, 5562.388137304974, 5647.971760141338, 5684.744186511534, 5693.3168019837885, 5708.686022268815, 4044.5348784890116, 4133.689973252078, 4157.210130075276, 4259.041715613962, 4304.569543045671, 5590.217369568345, 5635.436456354331, 5682.943170568242, 5683.488486036276, 5689.8862022758485, 5349.689326115126, 5534.501894829485, 5554.805581804618, 5565.666060036411, 5629.352624448241, 5535.833924982257, 5546.805861794818, 5556.166575013619, 5567.524605979338, 5596.972015139898, 5289.809792796744, 5536.4186760389575, 5547.725432572626, 5556.916156441334, 5572.387037425961, 5594.664320140804, 5599.888002239851, 5601.327840659147, 5606.084835885398, 5619.690916999604, 5422.376426710979, 5435.728213589226, 5477.20580515831, 5479.863003424787, 5493.835184944458], "value": 4248.780528407997, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [735.4410647907589, 756.7011030642897, 779.6569509415857, 839.1817977471966, 851.7104184577245, 15649.765253521193, 16513.091779952098, 16566.171691415428, 16567.171641417943, 16621.50135495934, 14004.527261007072, 14004.649883752885, 14050.227237501958, 14064.0, 14081.436742530312, 800.103883650311, 800.1278606319111, 802.9156938521459, 810.2586133687669, 814.5560669435172, 803.7950322667712, 805.4844899264447, 805.8106345008947, 806.7942674617964, 809.9230573095547, 856.3662889461784, 856.4818284937617, 857.6097875466679, 857.7684025313162, 857.8412993596205, 847.5931552854634, 848.6859861851093, 848.9193526614993, 849.4860609331328, 850.1413572291999, 614.0267675733967, 617.200725061047, 618.5731845026917, 618.938106189382, 619.7830759234276, 15898.284577194054, 16222.675546489123, 16223.0, 16230.431934882341, 16503.33986640533, 15258.160801155933, 15263.771043434357, 15468.845311546853, 15646.9217653911, 15840.603984900354, 11861.46623401949, 12027.57903473383, 12042.337671428068, 12075.516979320817, 12169.756604867942, 3731.7014638828828, 3757.530308711411, 3763.41667041609, 3814.275287695357, 3816.6755825754844, 72198.63900680463, 74437.0, 74769.62615186944, 75307.62346188234, 76221.23778762252, 11346.319220846775, 11467.827982580267, 11471.426428678533, 11472.885271147348, 11974.101942354184, 23316.300510984696, 23548.88225558861, 23576.882115589655, 23738.287851364486, 23816.523669526512, 7817.257360550739, 7818.296353328228, 7913.0, 7913.881291780628, 8052.235037671412, 4164.68764842638, 4254.446921900195, 4279.828806847691, 4368.6068253856665, 4440.600345968878, 3554.235839294564, 3666.944995825065, 3670.908227294339, 3671.284099600552, 3674.706023518112, 643.7103303513344, 675.2774531251612, 684.3020119478203, 687.8864987277143, 698.88468402714, 753.932146106853, 758.2152472191366, 759.2786852490157, 778.7002004228412, 790.9248621380959, 10196.847047294297, 10320.277580569287, 10653.45153899797, 10682.77101217805, 10824.670131947285, 740.5038624121852, 755.3013462547121, 769.4959801330137, 770.6262462705583, 771.1396059278957, 4676.1625887140935, 4997.375328083989, 5006.199008158677, 5012.448630650625, 5021.221710634857, 59224.703876480344, 63860.0, 76116.23883761202, 76811.0, 77326.61336693281, 52717.47282527202, 60855.0, 64437.71124577482, 64932.67533662301, 68081.65959170173, 59569.40430595725, 59597.0, 59765.70117149386, 60265.67210442928, 60361.69819150876, 9584.568694408908, 9788.0, 9788.755281118029, 9825.950870245602, 9826.606935722777, 38265.80867095758, 38670.0, 38942.0, 40438.36054754969, 41309.58690413057, 48555.0, 50348.49651503438, 50569.74715126401, 50757.0, 52548.73725631347, 11900.523979041087, 12001.39993000329, 12124.936807985641, 12392.442340094898, 12983.480660773841, 13336.266505342299, 13348.532801351907, 13908.235047072509, 14210.857891420954, 14235.572932812032, 12289.815652765037, 12412.286147046265, 12708.428120734405, 12747.681307967376, 13179.802302965272, 12208.877911220774, 12340.259584424963, 12795.552155674506, 12798.424070917017, 13528.0, 12518.561850335194, 12647.367631618567, 12708.173968692005, 12977.870221298044, 13102.737945241233, 9743.512824358611, 9867.802643947225, 10079.395236285847, 10186.745331366776, 10662.494313836087, 9283.62865485373, 9580.712578622653, 9638.807223855343, 10466.424346661006, 10564.366138031743], "value": 7152.11489577262, "unit": "score"}]}, {"extraOptions": [], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1517.6000000000001, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [111.0, 112.0, 112.0, 112.0, 112.0, 116.0, 112.0, 113.0, 112.0, 114.0], "value": 112.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 121.0, 120.0, 151.0, 123.0, 121.0, 122.0, 124.0, 145.0, 123.0], "value": 129.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [164.0, 161.0, 162.0, 161.0, 161.0, 162.0, 163.0, 164.0, 164.0, 164.0], "value": 162.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 76.0, 76.0, 115.0, 88.0, 76.0, 77.0, 76.0, 102.0, 75.0], "value": 83.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 83.0, 105.0, 83.0, 82.0, 83.0, 94.0, 83.0, 82.0, 111.0], "value": 88.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 113.0, 113.0, 112.0, 114.0, 114.0, 113.0, 113.0, 114.0, 113.0], "value": 113.3, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 148.0, 147.0, 147.0, 146.0, 148.0, 147.0, 148.0, 147.0, 147.0], "value": 147.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 96.0, 96.0, 96.0, 96.0, 96.0, 97.0, 96.0, 96.0, 96.0], "value": 96.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 56.0, 56.0, 57.0, 58.0, 57.0, 58.0, 58.0, 56.0, 57.0], "value": 57.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 66.0, 65.0, 65.0, 65.0, 65.0, 66.0, 65.0, 65.0, 66.0], "value": 65.3, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 84.0, 93.0, 84.0, 86.0, 85.0, 84.0, 84.0, 85.0, 92.0], "value": 87.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [140.0, 132.0, 134.0, 132.0, 128.0, 136.0, 129.0, 138.0, 171.0, 147.0], "value": 138.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [172.0, 172.0, 171.0, 173.0, 177.0, 171.0, 172.0, 171.0, 169.0, 169.0], "value": 171.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 63.0, 64.0, 66.0, 63.0, 63.0, 69.0, 64.0, 63.0, 63.0], "value": 64.1, "unit": "score"}]}]} 10:50:46 INFO - Return code: 0 10:50:46 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 10:50:46 ERROR - # TBPL SUCCESS # 10:50:46 INFO - Running post-action listener: _resource_record_post_action 10:50:46 INFO - [mozharness: 2017-02-16 18:50:46.877631Z] Finished run-tests step (success) 10:50:46 INFO - Running post-run listener: _resource_record_post_run 10:50:46 INFO - Total resource usage - Wall time: 1199s; CPU: 13.0%; Read bytes: 8192; Write bytes: 402702336; Read time: 400; Write time: 707212 10:50:46 INFO - TinderboxPrint: CPU usage
13.2% 10:50:46 INFO - TinderboxPrint: I/O read bytes / time
8,192 / 400 10:50:46 INFO - TinderboxPrint: I/O write bytes / time
402,702,336 / 707,212 10:50:46 INFO - TinderboxPrint: CPU idle
8,309.1 (86.8%) 10:50:46 INFO - TinderboxPrint: CPU user
1,205.4 (12.6%) 10:50:46 INFO - TinderboxPrint: Swap in / out
0 / 0 10:50:46 INFO - install - Wall time: 18s; CPU: 13.0%; Read bytes: 0; Write bytes: 105205760; Read time: 0; Write time: 149412 10:50:46 INFO - run-tests - Wall time: 1182s; CPU: 13.0%; Read bytes: 8192; Write bytes: 296415232; Read time: 400; Write time: 557712 10:50:47 INFO - Running post-run listener: _upload_blobber_files 10:50:47 INFO - Blob upload gear active. 10:50:47 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 10:50:47 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 10:50:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 10:50:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 10:50:47 INFO - (blobuploader) - INFO - Open directory for files ... 10:50:47 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 10:50:47 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:50:47 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:50:48 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 10:50:48 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:50:48 INFO - (blobuploader) - INFO - Done attempting. 10:50:48 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 10:50:48 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:50:48 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:50:52 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 10:50:52 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:50:52 INFO - (blobuploader) - INFO - Done attempting. 10:50:52 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 10:50:52 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:50:52 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:50:53 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 10:50:53 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:50:53 INFO - (blobuploader) - INFO - Done attempting. 10:50:53 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 10:50:53 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:50:53 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:50:53 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 10:50:53 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:50:53 INFO - (blobuploader) - INFO - Done attempting. 10:50:53 INFO - (blobuploader) - INFO - Iteration through files over. 10:50:53 INFO - Return code: 0 10:50:53 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 10:50:53 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 10:50:53 INFO - Setting buildbot property blobber_files to {"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/cc750e717016d89edfbd872bd61946397d0a577f00d40b4d752e61bdcdd32e3427d691c3739db0e38013b2c9e3b0352b7aa7be4398321c805bcc0262f917d176", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3009f42114fc995bacecf594d096f5e0871d436269bd90d9991f6561d4e2fcbb10c4a28e09b661ecffac995440acacac0803843d45848dd4e97e418d9eb0fea4", "dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2f59b6cc394c52a2ca785dad9a1ef9111b7d7b3cff777a69dd2e27edcbaddc002ed76ae3e8335ba3a04e70cdd639cea4ae0d2d1e8c2b2929a0c1548582994775", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/03d278a7adada91bb8cd82801feefe59eb49b961c36d12d8c52ec0ffba03bdf1184cd564413156e72ab33658306fc1783d512b1089102cb6776a1c0a93a81d7b"} 10:50:53 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 10:50:53 INFO - Writing to file /builds/slave/test/properties/blobber_files 10:50:53 INFO - Contents: 10:50:53 INFO - blobber_files:{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/cc750e717016d89edfbd872bd61946397d0a577f00d40b4d752e61bdcdd32e3427d691c3739db0e38013b2c9e3b0352b7aa7be4398321c805bcc0262f917d176", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3009f42114fc995bacecf594d096f5e0871d436269bd90d9991f6561d4e2fcbb10c4a28e09b661ecffac995440acacac0803843d45848dd4e97e418d9eb0fea4", "dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2f59b6cc394c52a2ca785dad9a1ef9111b7d7b3cff777a69dd2e27edcbaddc002ed76ae3e8335ba3a04e70cdd639cea4ae0d2d1e8c2b2929a0c1548582994775", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/03d278a7adada91bb8cd82801feefe59eb49b961c36d12d8c52ec0ffba03bdf1184cd564413156e72ab33658306fc1783d512b1089102cb6776a1c0a93a81d7b"} 10:50:53 INFO - Running post-run listener: copy_logs_to_upload_dir 10:50:53 INFO - Copying logs to upload dir... 10:50:53 INFO - mkdir: /builds/slave/test/build/upload/logs 10:50:53 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1256.974429 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 56 secs) (at 2017-02-16 10:50:53.453709) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-02-16 10:50:53.456719) ========= bash -c 'for file in `ls -1`; do cat $file; done' in dir /builds/slave/test/properties (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'for file in `ls -1`; do cat $file; done'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test/properties SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=745a4373a2a16df560f216e000000264-1487269794.885407-820900677 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/cc750e717016d89edfbd872bd61946397d0a577f00d40b4d752e61bdcdd32e3427d691c3739db0e38013b2c9e3b0352b7aa7be4398321c805bcc0262f917d176", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3009f42114fc995bacecf594d096f5e0871d436269bd90d9991f6561d4e2fcbb10c4a28e09b661ecffac995440acacac0803843d45848dd4e97e418d9eb0fea4", "dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2f59b6cc394c52a2ca785dad9a1ef9111b7d7b3cff777a69dd2e27edcbaddc002ed76ae3e8335ba3a04e70cdd639cea4ae0d2d1e8c2b2929a0c1548582994775", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/03d278a7adada91bb8cd82801feefe59eb49b961c36d12d8c52ec0ffba03bdf1184cd564413156e72ab33658306fc1783d512b1089102cb6776a1c0a93a81d7b"} build_url:https://queue.taskcluster.net/v1/task/Sy3PDECGSdmNdwU90aRM8g/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011203 build_url: 'https://queue.taskcluster.net/v1/task/Sy3PDECGSdmNdwU90aRM8g/artifacts/public/build/target.tar.bz2' blobber_files: '{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/cc750e717016d89edfbd872bd61946397d0a577f00d40b4d752e61bdcdd32e3427d691c3739db0e38013b2c9e3b0352b7aa7be4398321c805bcc0262f917d176", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3009f42114fc995bacecf594d096f5e0871d436269bd90d9991f6561d4e2fcbb10c4a28e09b661ecffac995440acacac0803843d45848dd4e97e418d9eb0fea4", "dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2f59b6cc394c52a2ca785dad9a1ef9111b7d7b3cff777a69dd2e27edcbaddc002ed76ae3e8335ba3a04e70cdd639cea4ae0d2d1e8c2b2929a0c1548582994775", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/03d278a7adada91bb8cd82801feefe59eb49b961c36d12d8c52ec0ffba03bdf1184cd564413156e72ab33658306fc1783d512b1089102cb6776a1c0a93a81d7b"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-02-16 10:50:53.483871) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-02-16 10:50:53.484147) ========= rm -f oauth.txt in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-f', 'oauth.txt'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=745a4373a2a16df560f216e000000264-1487269794.885407-820900677 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004534 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-02-16 10:50:53.568014) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-02-16 10:50:53.568872) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-02-16 10:50:53.569456) ========= ========= Total master_lag: 0.16 =========