builder: mozilla-beta_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-056 starttime: 1476403928.04 results: success (0) buildid: 20161013141419 builduid: e90c80ed81e34d80bbf9b89659b91749 revision: 6efc0964ec62bc4abfdc4cb1dc7cc461c3238634 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-10-13 17:12:08.040174) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-10-13 17:12:08.040611) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-13 17:12:08.040892) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-13 17:12:08.055336) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-13 17:12:08.055591) ========= 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=52faae308a556c440401f8e5000001e8-1476403927.519022-1795746679 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-10-13 17:12:08-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 219M=0s 2016-10-13 17:12:08 (219 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.074971 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-13 17:12:08.144022) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-13 17:12:08.144328) ========= 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=52faae308a556c440401f8e5000001e8-1476403927.519022-1795746679 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.019058 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-13 17:12:08.193180) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-10-13 17:12:08.193500) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 6efc0964ec62bc4abfdc4cb1dc7cc461c3238634 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 6efc0964ec62bc4abfdc4cb1dc7cc461c3238634 --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=52faae308a556c440401f8e5000001e8-1476403927.519022-1795746679 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-10-13 17:12:08,383 truncating revision to first 12 chars 2016-10-13 17:12:08,383 Setting DEBUG logging. 2016-10-13 17:12:08,383 attempt 1/10 2016-10-13 17:12:08,383 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/6efc0964ec62?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-10-13 17:12:09,076 unpacking tar archive at: mozilla-beta-6efc0964ec62/testing/mozharness/ program finished with exit code 0 elapsedTime=1.025439 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-10-13 17:12:09.232143) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-13 17:12:09.232459) ========= script_repo_revision: 6efc0964ec62bc4abfdc4cb1dc7cc461c3238634 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-13 17:12:09.232865) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-13 17:12:09.233131) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-13 17:12:09.241639) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 27 mins, 55 secs) (at 2016-10-13 17:12:09.241921) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta 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', 'Mozilla-Beta', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Beta'] 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=52faae308a556c440401f8e5000001e8-1476403927.519022-1795746679 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 17:12:09 INFO - MultiFileLogger online at 20161013 17:12:09 in /builds/slave/test 17:12:09 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta 17:12:09 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 17:12:09 INFO - {'append_to_log': False, 17:12:09 INFO - 'base_work_dir': '/builds/slave/test', 17:12:09 INFO - 'blob_upload_branch': 'Mozilla-Beta', 17:12:09 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 17:12:09 INFO - 'branch': 'Mozilla-Beta', 17:12:09 INFO - 'buildbot_json_path': 'buildprops.json', 17:12:09 INFO - 'config_files': ('talos/linux_config.py',), 17:12:09 INFO - 'default_actions': ('clobber', 17:12:09 INFO - 'read-buildbot-config', 17:12:09 INFO - 'download-and-extract', 17:12:09 INFO - 'populate-webroot', 17:12:09 INFO - 'create-virtualenv', 17:12:09 INFO - 'install', 17:12:09 INFO - 'run-tests'), 17:12:09 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 17:12:09 INFO - 'download_minidump_stackwalk': True, 17:12:09 INFO - 'download_symbols': 'ondemand', 17:12:09 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 17:12:09 INFO - 'tooltool.py': '/tools/tooltool.py', 17:12:09 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 17:12:09 INFO - '/tools/misc-python/virtualenv.py')}, 17:12:09 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 17:12:09 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 17:12:09 INFO - 'installer_path': 'installer.exe', 17:12:09 INFO - 'log_level': 'info', 17:12:09 INFO - 'log_name': 'talos', 17:12:09 INFO - 'log_to_console': True, 17:12:09 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 17:12:09 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 17:12:09 INFO - 'opt_config_files': (), 17:12:09 INFO - 'pip_index': False, 17:12:09 INFO - 'sps_profile': False, 17:12:09 INFO - 'sps_profile_interval': 0, 17:12:09 INFO - 'suite': 'dromaeojs', 17:12:09 INFO - 'system_bits': '32', 17:12:09 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 17:12:09 INFO - 'title': 'talos-linux64-ix-056', 17:12:09 INFO - 'tooltool_cache': '/builds/tooltool_cache', 17:12:09 INFO - 'use_talos_json': True, 17:12:09 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 17:12:09 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 17:12:09 INFO - 'work_dir': 'build'} 17:12:09 INFO - [mozharness: 2016-10-14 00:12:09.527726Z] Running clobber step. 17:12:09 INFO - Running pre-action listener: _resource_record_pre_action 17:12:09 INFO - Running main action method: clobber 17:12:09 INFO - rmtree: /builds/slave/test/build 17:12:09 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 17:12:09 INFO - Running post-action listener: _resource_record_post_action 17:12:09 INFO - [mozharness: 2016-10-14 00:12:09.989796Z] Finished clobber step (success) 17:12:09 INFO - [mozharness: 2016-10-14 00:12:09.989897Z] Running read-buildbot-config step. 17:12:09 INFO - Running pre-action listener: _resource_record_pre_action 17:12:09 INFO - Running main action method: read_buildbot_config 17:12:09 INFO - Using buildbot properties: 17:12:09 INFO - { 17:12:09 INFO - "project": "", 17:12:09 INFO - "product": "firefox", 17:12:09 INFO - "script_repo_revision": "production", 17:12:09 INFO - "builddir": "mozilla-beta_ubuntu64_hw_test-dromaeojs", 17:12:09 INFO - "repository": "", 17:12:09 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-beta pgo talos dromaeojs", 17:12:09 INFO - "buildid": "20161013141419", 17:12:09 INFO - "pgo_build": "True", 17:12:09 INFO - "basedir": "/builds/slave/test", 17:12:09 INFO - "buildnumber": 278, 17:12:09 INFO - "slavename": "talos-linux64-ix-056", 17:12:09 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 17:12:09 INFO - "platform": "ubuntu64_hw", 17:12:09 INFO - "scheduler": "tests-mozilla-beta-linux64-pgo-talos", 17:12:09 INFO - "branch": "mozilla-beta", 17:12:09 INFO - "repo_path": "releases/mozilla-beta", 17:12:09 INFO - "revision": "6efc0964ec62bc4abfdc4cb1dc7cc461c3238634", 17:12:09 INFO - "stage_platform": "linux64-pgo", 17:12:09 INFO - "builduid": "e90c80ed81e34d80bbf9b89659b91749", 17:12:09 INFO - "slavebuilddir": "test" 17:12:09 INFO - } 17:12:09 INFO - Found installer url https://queue.taskcluster.net/v1/task/Vls0TAmiRpOLcQL-8gSWBQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2. 17:12:09 INFO - Running post-action listener: _resource_record_post_action 17:12:09 INFO - [mozharness: 2016-10-14 00:12:09.992348Z] Finished read-buildbot-config step (success) 17:12:09 INFO - [mozharness: 2016-10-14 00:12:09.992438Z] Running download-and-extract step. 17:12:09 INFO - Running pre-action listener: _resource_record_pre_action 17:12:09 INFO - Running main action method: download_and_extract 17:12:09 INFO - mkdir: /builds/slave/test/build/tests 17:12:09 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:12:09 INFO - https://queue.taskcluster.net/v1/task/Vls0TAmiRpOLcQL-8gSWBQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 17:12:09 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Vls0TAmiRpOLcQL-8gSWBQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 17:12:09 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Vls0TAmiRpOLcQL-8gSWBQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 17:12:09 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Vls0TAmiRpOLcQL-8gSWBQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 17:12:09 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Vls0TAmiRpOLcQL-8gSWBQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.test_packages.json'}, attempt #1 17:12:09 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Vls0TAmiRpOLcQL-8gSWBQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 17:12:10 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 17:12:10 INFO - retry: Failed, sleeping 30 seconds before retrying 17:12:40 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Vls0TAmiRpOLcQL-8gSWBQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.test_packages.json'}, attempt #2 17:12:40 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Vls0TAmiRpOLcQL-8gSWBQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 17:12:40 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 17:12:40 INFO - retry: Failed, sleeping 60 seconds before retrying 17:13:40 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Vls0TAmiRpOLcQL-8gSWBQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.test_packages.json'}, attempt #3 17:13:40 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Vls0TAmiRpOLcQL-8gSWBQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 17:13:40 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 17:13:40 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Vls0TAmiRpOLcQL-8gSWBQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.test_packages.json! 17:13:40 INFO - Caught exception: HTTP Error 404: Not Found 17:13:40 INFO - Caught exception: HTTP Error 404: Not Found 17:13:40 INFO - Caught exception: HTTP Error 404: Not Found 17:13:40 INFO - trying https://queue.taskcluster.net/v1/task/Vls0TAmiRpOLcQL-8gSWBQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 17:13:40 INFO - Downloading https://queue.taskcluster.net/v1/task/Vls0TAmiRpOLcQL-8gSWBQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 17:13:40 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Vls0TAmiRpOLcQL-8gSWBQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.test_packages.json'}, attempt #1 17:13:40 INFO - Downloaded 1327 bytes. 17:13:40 INFO - Reading from file /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 17:13:40 INFO - Using the following test package requirements: 17:13:40 INFO - {u'common': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip'], 17:13:40 INFO - u'cppunittest': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 17:13:40 INFO - u'firefox-50.0.en-US.linux-x86_64.cppunittest.tests.zip'], 17:13:40 INFO - u'gtest': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 17:13:40 INFO - u'firefox-50.0.en-US.linux-x86_64.gtest.tests.zip'], 17:13:40 INFO - u'jittest': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 17:13:40 INFO - u'jsshell-linux-x86_64.zip'], 17:13:40 INFO - u'mochitest': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 17:13:40 INFO - u'firefox-50.0.en-US.linux-x86_64.mochitest.tests.zip'], 17:13:40 INFO - u'mozbase': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip'], 17:13:40 INFO - u'reftest': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 17:13:40 INFO - u'firefox-50.0.en-US.linux-x86_64.reftest.tests.zip'], 17:13:40 INFO - u'talos': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 17:13:40 INFO - u'firefox-50.0.en-US.linux-x86_64.talos.tests.zip'], 17:13:40 INFO - u'web-platform': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 17:13:40 INFO - u'firefox-50.0.en-US.linux-x86_64.web-platform.tests.zip'], 17:13:40 INFO - u'xpcshell': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 17:13:40 INFO - u'firefox-50.0.en-US.linux-x86_64.xpcshell.tests.zip']} 17:13:40 INFO - Downloading packages: [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 17:13:40 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:13:40 INFO - https://queue.taskcluster.net/v1/task/Vls0TAmiRpOLcQL-8gSWBQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 17:13:40 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Vls0TAmiRpOLcQL-8gSWBQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 17:13:40 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Vls0TAmiRpOLcQL-8gSWBQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 17:13:40 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Vls0TAmiRpOLcQL-8gSWBQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 17:13:40 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Vls0TAmiRpOLcQL-8gSWBQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip'}, attempt #1 17:13:40 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Vls0TAmiRpOLcQL-8gSWBQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 17:13:40 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 17:13:40 INFO - retry: Failed, sleeping 30 seconds before retrying 17:14:10 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Vls0TAmiRpOLcQL-8gSWBQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip'}, attempt #2 17:14:10 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Vls0TAmiRpOLcQL-8gSWBQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 17:14:10 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 17:14:10 INFO - retry: Failed, sleeping 60 seconds before retrying 17:15:10 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Vls0TAmiRpOLcQL-8gSWBQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip'}, attempt #3 17:15:10 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Vls0TAmiRpOLcQL-8gSWBQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 17:15:10 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 17:15:10 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Vls0TAmiRpOLcQL-8gSWBQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip! 17:15:10 INFO - Caught exception: HTTP Error 404: Not Found 17:15:10 INFO - Caught exception: HTTP Error 404: Not Found 17:15:10 INFO - Caught exception: HTTP Error 404: Not Found 17:15:10 INFO - trying https://queue.taskcluster.net/v1/task/Vls0TAmiRpOLcQL-8gSWBQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 17:15:10 INFO - Downloading https://queue.taskcluster.net/v1/task/Vls0TAmiRpOLcQL-8gSWBQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 17:15:10 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Vls0TAmiRpOLcQL-8gSWBQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip'}, attempt #1 17:15:11 INFO - Downloaded 21773249 bytes. 17:15:11 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 17:15:11 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 17:15:12 INFO - Return code: 0 17:15:12 INFO - Downloading packages: [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', u'firefox-50.0.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 17:15:12 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:15:12 INFO - https://queue.taskcluster.net/v1/task/Vls0TAmiRpOLcQL-8gSWBQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 17:15:12 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Vls0TAmiRpOLcQL-8gSWBQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 17:15:12 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Vls0TAmiRpOLcQL-8gSWBQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 17:15:12 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Vls0TAmiRpOLcQL-8gSWBQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 17:15:12 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Vls0TAmiRpOLcQL-8gSWBQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip'}, attempt #1 17:15:12 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Vls0TAmiRpOLcQL-8gSWBQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 17:15:12 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 17:15:12 INFO - retry: Failed, sleeping 30 seconds before retrying 17:15:42 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Vls0TAmiRpOLcQL-8gSWBQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip'}, attempt #2 17:15:42 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Vls0TAmiRpOLcQL-8gSWBQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 17:15:42 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 17:15:42 INFO - retry: Failed, sleeping 60 seconds before retrying 17:16:42 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Vls0TAmiRpOLcQL-8gSWBQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip'}, attempt #3 17:16:42 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Vls0TAmiRpOLcQL-8gSWBQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 17:16:42 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 17:16:42 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Vls0TAmiRpOLcQL-8gSWBQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip! 17:16:42 INFO - Caught exception: HTTP Error 404: Not Found 17:16:42 INFO - Caught exception: HTTP Error 404: Not Found 17:16:42 INFO - Caught exception: HTTP Error 404: Not Found 17:16:42 INFO - trying https://queue.taskcluster.net/v1/task/Vls0TAmiRpOLcQL-8gSWBQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 17:16:42 INFO - Downloading https://queue.taskcluster.net/v1/task/Vls0TAmiRpOLcQL-8gSWBQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 17:16:42 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Vls0TAmiRpOLcQL-8gSWBQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip'}, attempt #1 17:16:43 INFO - Downloaded 21773249 bytes. 17:16:43 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 17:16:43 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 17:16:45 INFO - Return code: 0 17:16:45 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:16:45 INFO - https://queue.taskcluster.net/v1/task/Vls0TAmiRpOLcQL-8gSWBQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 17:16:45 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Vls0TAmiRpOLcQL-8gSWBQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 17:16:45 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Vls0TAmiRpOLcQL-8gSWBQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 17:16:45 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Vls0TAmiRpOLcQL-8gSWBQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 17:16:45 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Vls0TAmiRpOLcQL-8gSWBQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 17:16:45 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Vls0TAmiRpOLcQL-8gSWBQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 17:16:45 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 17:16:45 INFO - retry: Failed, sleeping 30 seconds before retrying 17:17:15 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Vls0TAmiRpOLcQL-8gSWBQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip'}, attempt #2 17:17:15 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Vls0TAmiRpOLcQL-8gSWBQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 17:17:15 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 17:17:15 INFO - retry: Failed, sleeping 60 seconds before retrying 17:18:15 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Vls0TAmiRpOLcQL-8gSWBQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip'}, attempt #3 17:18:15 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Vls0TAmiRpOLcQL-8gSWBQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 17:18:15 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 17:18:15 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Vls0TAmiRpOLcQL-8gSWBQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip! 17:18:15 INFO - Caught exception: HTTP Error 404: Not Found 17:18:15 INFO - Caught exception: HTTP Error 404: Not Found 17:18:15 INFO - Caught exception: HTTP Error 404: Not Found 17:18:15 INFO - trying https://queue.taskcluster.net/v1/task/Vls0TAmiRpOLcQL-8gSWBQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 17:18:15 INFO - Downloading https://queue.taskcluster.net/v1/task/Vls0TAmiRpOLcQL-8gSWBQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 17:18:15 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Vls0TAmiRpOLcQL-8gSWBQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 17:18:16 INFO - Downloaded 14413740 bytes. 17:18:16 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 17:18:16 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip -d /builds/slave/test/build/tests 17:18:16 INFO - Return code: 0 17:18:16 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:18:16 INFO - https://queue.taskcluster.net/v1/task/Vls0TAmiRpOLcQL-8gSWBQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 17:18:16 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Vls0TAmiRpOLcQL-8gSWBQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 17:18:16 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Vls0TAmiRpOLcQL-8gSWBQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 17:18:16 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Vls0TAmiRpOLcQL-8gSWBQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 17:18:16 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Vls0TAmiRpOLcQL-8gSWBQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.tar.bz2'}, attempt #1 17:18:16 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Vls0TAmiRpOLcQL-8gSWBQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 17:18:16 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 17:18:16 INFO - retry: Failed, sleeping 30 seconds before retrying 17:18:46 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Vls0TAmiRpOLcQL-8gSWBQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.tar.bz2'}, attempt #2 17:18:46 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Vls0TAmiRpOLcQL-8gSWBQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 17:18:46 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 17:18:46 INFO - retry: Failed, sleeping 60 seconds before retrying 17:19:46 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Vls0TAmiRpOLcQL-8gSWBQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.tar.bz2'}, attempt #3 17:19:46 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Vls0TAmiRpOLcQL-8gSWBQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 17:19:46 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 17:19:46 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Vls0TAmiRpOLcQL-8gSWBQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.tar.bz2! 17:19:46 INFO - Caught exception: HTTP Error 404: Not Found 17:19:46 INFO - Caught exception: HTTP Error 404: Not Found 17:19:46 INFO - Caught exception: HTTP Error 404: Not Found 17:19:46 INFO - trying https://queue.taskcluster.net/v1/task/Vls0TAmiRpOLcQL-8gSWBQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 17:19:46 INFO - Downloading https://queue.taskcluster.net/v1/task/Vls0TAmiRpOLcQL-8gSWBQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 17:19:46 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Vls0TAmiRpOLcQL-8gSWBQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.tar.bz2'}, attempt #1 17:19:47 INFO - Downloaded 56802325 bytes. 17:19:47 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Vls0TAmiRpOLcQL-8gSWBQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 17:19:47 INFO - mkdir: /builds/slave/test/properties 17:19:47 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 17:19:47 INFO - Writing to file /builds/slave/test/properties/build_url 17:19:47 INFO - Contents: 17:19:47 INFO - build_url:https://queue.taskcluster.net/v1/task/Vls0TAmiRpOLcQL-8gSWBQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 17:19:48 INFO - Running post-action listener: _resource_record_post_action 17:19:48 INFO - Running post-action listener: set_extra_try_arguments 17:19:48 INFO - [mozharness: 2016-10-14 00:19:48.143306Z] Finished download-and-extract step (success) 17:19:48 INFO - [mozharness: 2016-10-14 00:19:48.143541Z] Running populate-webroot step. 17:19:48 INFO - Running pre-action listener: _resource_record_pre_action 17:19:48 INFO - Running main action method: populate_webroot 17:19:48 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 17:19:48 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 17:19:48 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 17:19:48 INFO - '--tppagecycles', 17:19:48 INFO - '7'], 17:19:48 INFO - 'tests': ['tsvgm']}}, 17:19:48 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 17:19:48 INFO - 'tests': ['tresize', 'tcanvasmark']}, 17:19:48 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 17:19:48 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 17:19:48 INFO - 'tests': ['dromaeo_css', 'kraken']}, 17:19:48 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 17:19:48 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:19:48 INFO - 'talos_options': ['--disable-e10s'], 17:19:48 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 17:19:48 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:19:48 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 17:19:48 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:19:48 INFO - 'talos_options': ['--disable-e10s'], 17:19:48 INFO - 'tests': ['damp', 'tps']}, 17:19:48 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:19:48 INFO - 'tests': ['damp', 'tps']}, 17:19:48 INFO - 'g3': {'talos_options': ['--disable-e10s'], 17:19:48 INFO - 'tests': ['dromaeo_dom']}, 17:19:48 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 17:19:48 INFO - 'g4': {'talos_options': ['--disable-e10s'], 17:19:48 INFO - 'tests': ['basic_compositor_video']}, 17:19:48 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 17:19:48 INFO - 'other': {'talos_options': ['--disable-e10s'], 17:19:48 INFO - 'tests': ['a11yr', 17:19:48 INFO - 'ts_paint', 17:19:48 INFO - 'tpaint', 17:19:48 INFO - 'sessionrestore', 17:19:48 INFO - 'sessionrestore_no_auto_restore', 17:19:48 INFO - 'tabpaint']}, 17:19:48 INFO - 'other-e10s': {'tests': ['a11yr', 17:19:48 INFO - 'ts_paint', 17:19:48 INFO - 'tpaint', 17:19:48 INFO - 'sessionrestore', 17:19:48 INFO - 'sessionrestore_no_auto_restore', 17:19:48 INFO - 'tabpaint']}, 17:19:48 INFO - 'other-e10s_l64': {'tests': ['a11yr', 17:19:48 INFO - 'ts_paint', 17:19:48 INFO - 'tpaint', 17:19:48 INFO - 'sessionrestore', 17:19:48 INFO - 'sessionrestore_no_auto_restore', 17:19:48 INFO - 'tabpaint']}, 17:19:48 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 17:19:48 INFO - 'ts_paint', 17:19:48 INFO - 'tpaint', 17:19:48 INFO - 'sessionrestore', 17:19:48 INFO - 'sessionrestore_no_auto_restore', 17:19:48 INFO - 'tabpaint']}, 17:19:48 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 17:19:48 INFO - 'tests': ['a11yr', 17:19:48 INFO - 'ts_paint', 17:19:48 INFO - 'tpaint', 17:19:48 INFO - 'sessionrestore', 17:19:48 INFO - 'sessionrestore_no_auto_restore', 17:19:48 INFO - 'tabpaint']}, 17:19:48 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 17:19:48 INFO - 'tests': ['a11yr', 17:19:48 INFO - 'ts_paint', 17:19:48 INFO - 'tpaint', 17:19:48 INFO - 'sessionrestore', 17:19:48 INFO - 'sessionrestore_no_auto_restore', 17:19:48 INFO - 'tabpaint']}, 17:19:48 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 17:19:48 INFO - 'tests': ['tsvgx', 17:19:48 INFO - 'tsvgr_opacity', 17:19:48 INFO - 'tart', 17:19:48 INFO - 'tscrollx', 17:19:48 INFO - 'cart']}, 17:19:48 INFO - 'svgr-e10s': {'tests': ['tsvgx', 17:19:48 INFO - 'tsvgr_opacity', 17:19:48 INFO - 'tart', 17:19:48 INFO - 'tscrollx', 17:19:48 INFO - 'cart']}, 17:19:48 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:19:48 INFO - 'talos_options': ['--disable-e10s'], 17:19:48 INFO - 'tests': ['tp5o']}, 17:19:48 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:19:48 INFO - 'tests': ['tp5o']}, 17:19:48 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:19:48 INFO - 'talos_options': ['--disable-e10s', 17:19:48 INFO - '--xperf_path', 17:19:48 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 17:19:48 INFO - 'tests': ['tp5n']}, 17:19:48 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:19:48 INFO - 'talos_options': ['--xperf_path', 17:19:48 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 17:19:48 INFO - 'tests': ['tp5n']}}, 17:19:48 INFO - 'talos.zip': {'path': '', 17:19:48 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 17:19:48 INFO - Running post-action listener: _resource_record_post_action 17:19:48 INFO - [mozharness: 2016-10-14 00:19:48.163600Z] Finished populate-webroot step (success) 17:19:48 INFO - [mozharness: 2016-10-14 00:19:48.163690Z] Running create-virtualenv step. 17:19:48 INFO - Running pre-action listener: _resource_record_pre_action 17:19:48 INFO - Running main action method: create_virtualenv 17:19:48 INFO - Creating virtualenv /builds/slave/test/build/venv 17:19:48 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 17:19:48 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 17:19:48 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 17:19:48 INFO - Using real prefix '/usr' 17:19:48 INFO - New python executable in /builds/slave/test/build/venv/bin/python 17:19:48 INFO - Installing distribute.............................................................................................................................................................................................done. 17:19:50 INFO - Installing pip.................done. 17:19:50 INFO - Return code: 0 17:19:50 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 17:19:50 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:19:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:19:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:19:50 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:19:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:19:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:19:50 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 0x1c1f030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d828a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ee0180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ede3b0>, '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 0x1edf2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1edf740>, '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': '52faae308a556c440401f8e5000001e8-1476403927.519022-1795746679', '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 17:19:50 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 17:19:50 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 17:19:50 INFO - Using env: {'DISPLAY': ':0', 17:19:50 INFO - 'HOME': '/home/cltbld', 17:19:50 INFO - 'LANG': 'en_US.UTF-8', 17:19:50 INFO - 'LANGUAGE': 'en_US:en', 17:19:50 INFO - 'LOGNAME': 'cltbld', 17:19:50 INFO - 'MAIL': '/var/mail/cltbld', 17:19:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:19:50 INFO - 'MOZ_NO_REMOTE': '1', 17:19:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:19:50 INFO - 'NO_EM_RESTART': '1', 17:19:50 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:19:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:19:50 INFO - 'PWD': '/builds/slave/test', 17:19:50 INFO - 'SHELL': '/bin/bash', 17:19:50 INFO - 'SHLVL': '1', 17:19:50 INFO - 'TERM': 'linux', 17:19:50 INFO - 'TMOUT': '86400', 17:19:50 INFO - 'USER': 'cltbld', 17:19:50 INFO - 'XDG_SESSION_COOKIE': '52faae308a556c440401f8e5000001e8-1476403927.519022-1795746679', 17:19:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:19:50 INFO - '_': '/tools/buildbot/bin/python'} 17:19:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:19:50 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 17:19:50 INFO - Cleaning up... 17:19:50 INFO - Return code: 0 17:19:50 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 17:19:50 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:19:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:19:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:19:50 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:19:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:19:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:19:50 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 0x1c1f030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d828a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ee0180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ede3b0>, '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 0x1edf2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1edf740>, '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': '52faae308a556c440401f8e5000001e8-1476403927.519022-1795746679', '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 17:19:50 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 17:19:50 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 17:19:50 INFO - Using env: {'DISPLAY': ':0', 17:19:50 INFO - 'HOME': '/home/cltbld', 17:19:50 INFO - 'LANG': 'en_US.UTF-8', 17:19:50 INFO - 'LANGUAGE': 'en_US:en', 17:19:50 INFO - 'LOGNAME': 'cltbld', 17:19:50 INFO - 'MAIL': '/var/mail/cltbld', 17:19:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:19:50 INFO - 'MOZ_NO_REMOTE': '1', 17:19:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:19:50 INFO - 'NO_EM_RESTART': '1', 17:19:50 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:19:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:19:50 INFO - 'PWD': '/builds/slave/test', 17:19:50 INFO - 'SHELL': '/bin/bash', 17:19:50 INFO - 'SHLVL': '1', 17:19:50 INFO - 'TERM': 'linux', 17:19:50 INFO - 'TMOUT': '86400', 17:19:50 INFO - 'USER': 'cltbld', 17:19:50 INFO - 'XDG_SESSION_COOKIE': '52faae308a556c440401f8e5000001e8-1476403927.519022-1795746679', 17:19:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:19:50 INFO - '_': '/tools/buildbot/bin/python'} 17:19:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:19:51 INFO - Downloading/unpacking psutil>=3.1.1 17:19:51 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 17:19:51 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 17:19:51 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 17:19:51 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 17:19:51 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 17:19:51 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 17:19:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 17:19:53 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 17:19:53 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 17:19:53 INFO - Installing collected packages: psutil 17:19:53 INFO - Running setup.py install for psutil 17:19:53 INFO - building 'psutil._psutil_linux' extension 17:19:53 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 17:19:53 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 17:19:53 INFO - building 'psutil._psutil_posix' extension 17:19:53 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 17:19:53 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 17:19:53 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 17:19:53 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 17:19:53 INFO - Successfully installed psutil 17:19:53 INFO - Cleaning up... 17:19:53 INFO - Return code: 0 17:19:53 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 17:19:53 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:19:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:19:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:19:53 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:19:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:19:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:19:53 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 0x1c1f030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d828a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ee0180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ede3b0>, '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 0x1edf2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1edf740>, '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': '52faae308a556c440401f8e5000001e8-1476403927.519022-1795746679', '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 17:19:53 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 17:19:53 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 17:19:53 INFO - Using env: {'DISPLAY': ':0', 17:19:53 INFO - 'HOME': '/home/cltbld', 17:19:53 INFO - 'LANG': 'en_US.UTF-8', 17:19:53 INFO - 'LANGUAGE': 'en_US:en', 17:19:53 INFO - 'LOGNAME': 'cltbld', 17:19:53 INFO - 'MAIL': '/var/mail/cltbld', 17:19:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:19:53 INFO - 'MOZ_NO_REMOTE': '1', 17:19:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:19:53 INFO - 'NO_EM_RESTART': '1', 17:19:53 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:19:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:19:53 INFO - 'PWD': '/builds/slave/test', 17:19:53 INFO - 'SHELL': '/bin/bash', 17:19:53 INFO - 'SHLVL': '1', 17:19:53 INFO - 'TERM': 'linux', 17:19:53 INFO - 'TMOUT': '86400', 17:19:53 INFO - 'USER': 'cltbld', 17:19:53 INFO - 'XDG_SESSION_COOKIE': '52faae308a556c440401f8e5000001e8-1476403927.519022-1795746679', 17:19:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:19:53 INFO - '_': '/tools/buildbot/bin/python'} 17:19:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:19:53 INFO - Downloading/unpacking mozsystemmonitor==0.3 17:19:53 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 17:19:53 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 17:19:53 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 17:19:53 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 17:19:53 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 17:19:53 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 17:19:55 INFO - Downloading mozsystemmonitor-0.3.tar.gz 17:19:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 17:19:55 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 17:19:55 INFO - Installing collected packages: mozsystemmonitor 17:19:55 INFO - Running setup.py install for mozsystemmonitor 17:19:56 INFO - Successfully installed mozsystemmonitor 17:19:56 INFO - Cleaning up... 17:19:56 INFO - Return code: 0 17:19:56 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 17:19:56 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:19:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:19:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:19:56 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:19:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:19:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:19:56 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 0x1c1f030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d828a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ee0180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ede3b0>, '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 0x1edf2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1edf740>, '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': '52faae308a556c440401f8e5000001e8-1476403927.519022-1795746679', '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 17:19:56 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 17:19:56 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 17:19:56 INFO - Using env: {'DISPLAY': ':0', 17:19:56 INFO - 'HOME': '/home/cltbld', 17:19:56 INFO - 'LANG': 'en_US.UTF-8', 17:19:56 INFO - 'LANGUAGE': 'en_US:en', 17:19:56 INFO - 'LOGNAME': 'cltbld', 17:19:56 INFO - 'MAIL': '/var/mail/cltbld', 17:19:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:19:56 INFO - 'MOZ_NO_REMOTE': '1', 17:19:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:19:56 INFO - 'NO_EM_RESTART': '1', 17:19:56 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:19:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:19:56 INFO - 'PWD': '/builds/slave/test', 17:19:56 INFO - 'SHELL': '/bin/bash', 17:19:56 INFO - 'SHLVL': '1', 17:19:56 INFO - 'TERM': 'linux', 17:19:56 INFO - 'TMOUT': '86400', 17:19:56 INFO - 'USER': 'cltbld', 17:19:56 INFO - 'XDG_SESSION_COOKIE': '52faae308a556c440401f8e5000001e8-1476403927.519022-1795746679', 17:19:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:19:56 INFO - '_': '/tools/buildbot/bin/python'} 17:19:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:19:56 INFO - Downloading/unpacking jsonschema==2.5.1 17:19:56 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 17:19:56 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 17:19:56 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 17:19:56 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 17:19:56 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 17:19:56 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 17:19:58 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 17:19:58 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 17:19:58 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 17:19:58 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 17:19:58 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 17:19:58 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 17:19:58 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 17:19:58 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 17:19:58 INFO - Downloading functools32-3.2.3-2.tar.gz 17:19:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 17:19:58 INFO - warning: no files found matching '*.txt' 17:19:58 INFO - no previously-included directories found matching 'build' 17:19:58 INFO - no previously-included directories found matching 'dist' 17:19:58 INFO - no previously-included directories found matching '.git*' 17:19:58 INFO - Installing collected packages: jsonschema, functools32 17:19:58 INFO - Running setup.py install for functools32 17:19:58 INFO - warning: no files found matching '*.txt' 17:19:58 INFO - no previously-included directories found matching 'build' 17:19:58 INFO - no previously-included directories found matching 'dist' 17:19:58 INFO - no previously-included directories found matching '.git*' 17:19:58 INFO - Successfully installed jsonschema functools32 17:19:58 INFO - Cleaning up... 17:19:58 INFO - Return code: 0 17:19:58 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 17:19:58 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:19:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:19:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:19:58 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:19:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:19:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:19:58 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 0x1c1f030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d828a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ee0180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ede3b0>, '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 0x1edf2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1edf740>, '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': '52faae308a556c440401f8e5000001e8-1476403927.519022-1795746679', '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 17:19:58 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 17:19:58 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 17:19:58 INFO - Using env: {'DISPLAY': ':0', 17:19:58 INFO - 'HOME': '/home/cltbld', 17:19:58 INFO - 'LANG': 'en_US.UTF-8', 17:19:58 INFO - 'LANGUAGE': 'en_US:en', 17:19:58 INFO - 'LOGNAME': 'cltbld', 17:19:58 INFO - 'MAIL': '/var/mail/cltbld', 17:19:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:19:58 INFO - 'MOZ_NO_REMOTE': '1', 17:19:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:19:58 INFO - 'NO_EM_RESTART': '1', 17:19:58 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:19:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:19:58 INFO - 'PWD': '/builds/slave/test', 17:19:58 INFO - 'SHELL': '/bin/bash', 17:19:58 INFO - 'SHLVL': '1', 17:19:58 INFO - 'TERM': 'linux', 17:19:58 INFO - 'TMOUT': '86400', 17:19:58 INFO - 'USER': 'cltbld', 17:19:58 INFO - 'XDG_SESSION_COOKIE': '52faae308a556c440401f8e5000001e8-1476403927.519022-1795746679', 17:19:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:19:58 INFO - '_': '/tools/buildbot/bin/python'} 17:19:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:19:58 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 17:19:58 INFO - Cleaning up... 17:19:58 INFO - Return code: 0 17:19:58 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 17:19:58 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:19:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:19:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:19:58 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:19:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:19:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:19:58 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 0x1c1f030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d828a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ee0180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ede3b0>, '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 0x1edf2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1edf740>, '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': '52faae308a556c440401f8e5000001e8-1476403927.519022-1795746679', '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 17:19:58 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 17:19:58 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 17:19:58 INFO - Using env: {'DISPLAY': ':0', 17:19:58 INFO - 'HOME': '/home/cltbld', 17:19:58 INFO - 'LANG': 'en_US.UTF-8', 17:19:58 INFO - 'LANGUAGE': 'en_US:en', 17:19:58 INFO - 'LOGNAME': 'cltbld', 17:19:58 INFO - 'MAIL': '/var/mail/cltbld', 17:19:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:19:58 INFO - 'MOZ_NO_REMOTE': '1', 17:19:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:19:58 INFO - 'NO_EM_RESTART': '1', 17:19:58 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:19:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:19:58 INFO - 'PWD': '/builds/slave/test', 17:19:58 INFO - 'SHELL': '/bin/bash', 17:19:58 INFO - 'SHLVL': '1', 17:19:58 INFO - 'TERM': 'linux', 17:19:58 INFO - 'TMOUT': '86400', 17:19:58 INFO - 'USER': 'cltbld', 17:19:58 INFO - 'XDG_SESSION_COOKIE': '52faae308a556c440401f8e5000001e8-1476403927.519022-1795746679', 17:19:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:19:58 INFO - '_': '/tools/buildbot/bin/python'} 17:19:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:19:59 INFO - Downloading/unpacking blobuploader==1.2.4 17:19:59 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 17:19:59 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 17:19:59 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 17:19:59 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 17:19:59 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 17:19:59 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 17:20:01 INFO - Downloading blobuploader-1.2.4.tar.gz 17:20:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 17:20:01 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 17:20:01 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 17:20:01 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 17:20:01 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 17:20:01 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 17:20:01 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 17:20:01 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 17:20:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 17:20:01 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 17:20:01 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 17:20:01 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 17:20:01 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 17:20:01 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 17:20:01 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 17:20:01 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 17:20:01 INFO - Downloading docopt-0.6.1.tar.gz 17:20:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 17:20:01 INFO - Installing collected packages: blobuploader, requests, docopt 17:20:01 INFO - Running setup.py install for blobuploader 17:20:01 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 17:20:01 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 17:20:01 INFO - Running setup.py install for requests 17:20:02 INFO - Running setup.py install for docopt 17:20:02 INFO - Successfully installed blobuploader requests docopt 17:20:02 INFO - Cleaning up... 17:20:02 INFO - Return code: 0 17:20:02 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:20:02 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:20:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:20:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:20:02 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:20:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:20:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:20:02 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 0x1c1f030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d828a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ee0180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ede3b0>, '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 0x1edf2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1edf740>, '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': '52faae308a556c440401f8e5000001e8-1476403927.519022-1795746679', '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 17:20:02 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 17:20:02 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 17:20:02 INFO - Using env: {'DISPLAY': ':0', 17:20:02 INFO - 'HOME': '/home/cltbld', 17:20:02 INFO - 'LANG': 'en_US.UTF-8', 17:20:02 INFO - 'LANGUAGE': 'en_US:en', 17:20:02 INFO - 'LOGNAME': 'cltbld', 17:20:02 INFO - 'MAIL': '/var/mail/cltbld', 17:20:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:20:02 INFO - 'MOZ_NO_REMOTE': '1', 17:20:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:20:02 INFO - 'NO_EM_RESTART': '1', 17:20:02 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:20:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:20:02 INFO - 'PWD': '/builds/slave/test', 17:20:02 INFO - 'SHELL': '/bin/bash', 17:20:02 INFO - 'SHLVL': '1', 17:20:02 INFO - 'TERM': 'linux', 17:20:02 INFO - 'TMOUT': '86400', 17:20:02 INFO - 'USER': 'cltbld', 17:20:02 INFO - 'XDG_SESSION_COOKIE': '52faae308a556c440401f8e5000001e8-1476403927.519022-1795746679', 17:20:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:20:02 INFO - '_': '/tools/buildbot/bin/python'} 17:20:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:20:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 17:20:02 INFO - Running setup.py (path:/tmp/pip-H8H2jJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 17:20:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 17:20:02 INFO - Running setup.py (path:/tmp/pip-4eSKvi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 17:20:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 17:20:02 INFO - Running setup.py (path:/tmp/pip-QFwz0T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 17:20:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 17:20:02 INFO - Running setup.py (path:/tmp/pip-g4YoOV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 17:20:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 17:20:02 INFO - Running setup.py (path:/tmp/pip-R8sGzW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 17:20:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 17:20:03 INFO - Running setup.py (path:/tmp/pip-0CG7o0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 17:20:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 17:20:03 INFO - Running setup.py (path:/tmp/pip-X21l28-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 17:20:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 17:20:03 INFO - Running setup.py (path:/tmp/pip-aSppWV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 17:20:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 17:20:03 INFO - Running setup.py (path:/tmp/pip-Arvb5X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 17:20:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 17:20:03 INFO - Running setup.py (path:/tmp/pip-sf5G32-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 17:20:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 17:20:03 INFO - Running setup.py (path:/tmp/pip-hQRWIr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 17:20:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 17:20:03 INFO - Running setup.py (path:/tmp/pip-sb1gkm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 17:20:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 17:20:03 INFO - Running setup.py (path:/tmp/pip-mgvis2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 17:20:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 17:20:03 INFO - Running setup.py (path:/tmp/pip-KsjfHQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 17:20:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 17:20:03 INFO - Running setup.py (path:/tmp/pip-zmNUeF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 17:20:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 17:20:03 INFO - Running setup.py (path:/tmp/pip-JAzDsh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 17:20:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 17:20:03 INFO - Running setup.py (path:/tmp/pip-btrioj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 17:20:03 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 17:20:03 INFO - Running setup.py install for manifestparser 17:20:03 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 17:20:03 INFO - Running setup.py install for mozcrash 17:20:04 INFO - Running setup.py install for mozdebug 17:20:04 INFO - Running setup.py install for mozdevice 17:20:04 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 17:20:04 INFO - Installing dm script to /builds/slave/test/build/venv/bin 17:20:04 INFO - Running setup.py install for mozfile 17:20:04 INFO - Running setup.py install for mozhttpd 17:20:04 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 17:20:04 INFO - Running setup.py install for mozinfo 17:20:04 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 17:20:04 INFO - Running setup.py install for mozInstall 17:20:04 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 17:20:04 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 17:20:04 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 17:20:04 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 17:20:04 INFO - Running setup.py install for mozleak 17:20:04 INFO - Running setup.py install for mozlog 17:20:04 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 17:20:04 INFO - Running setup.py install for moznetwork 17:20:05 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 17:20:05 INFO - Running setup.py install for mozprocess 17:20:05 INFO - Running setup.py install for mozprofile 17:20:05 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 17:20:05 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 17:20:05 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 17:20:05 INFO - Running setup.py install for mozrunner 17:20:05 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 17:20:05 INFO - Running setup.py install for mozscreenshot 17:20:05 INFO - Running setup.py install for moztest 17:20:05 INFO - Running setup.py install for mozversion 17:20:05 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 17:20:05 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 17:20:05 INFO - Cleaning up... 17:20:05 INFO - Return code: 0 17:20:05 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:20: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:20:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:20:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:20: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:20:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:20:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:20:05 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 0x1c1f030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d828a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ee0180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ede3b0>, '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 0x1edf2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1edf740>, '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': '52faae308a556c440401f8e5000001e8-1476403927.519022-1795746679', '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 17:20:05 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 17:20:05 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 17:20:05 INFO - Using env: {'DISPLAY': ':0', 17:20:05 INFO - 'HOME': '/home/cltbld', 17:20:05 INFO - 'LANG': 'en_US.UTF-8', 17:20:05 INFO - 'LANGUAGE': 'en_US:en', 17:20:05 INFO - 'LOGNAME': 'cltbld', 17:20:05 INFO - 'MAIL': '/var/mail/cltbld', 17:20:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:20:05 INFO - 'MOZ_NO_REMOTE': '1', 17:20:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:20:05 INFO - 'NO_EM_RESTART': '1', 17:20:05 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:20:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:20:05 INFO - 'PWD': '/builds/slave/test', 17:20:05 INFO - 'SHELL': '/bin/bash', 17:20:05 INFO - 'SHLVL': '1', 17:20:05 INFO - 'TERM': 'linux', 17:20:05 INFO - 'TMOUT': '86400', 17:20:05 INFO - 'USER': 'cltbld', 17:20:05 INFO - 'XDG_SESSION_COOKIE': '52faae308a556c440401f8e5000001e8-1476403927.519022-1795746679', 17:20:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:20:05 INFO - '_': '/tools/buildbot/bin/python'} 17:20:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:20:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 17:20:05 INFO - Running setup.py (path:/tmp/pip-_JREkt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 17:20:05 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)) 17:20:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 17:20:05 INFO - Running setup.py (path:/tmp/pip-1aC7h_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 17:20:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 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)) 17:20:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 17:20:06 INFO - Running setup.py (path:/tmp/pip-tfuAgH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 17:20:06 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)) 17:20:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 17:20:06 INFO - Running setup.py (path:/tmp/pip-8GSJpj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 17:20:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 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)) 17:20:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 17:20:06 INFO - Running setup.py (path:/tmp/pip-NZ0AZx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 17:20:06 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)) 17:20:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 17:20:06 INFO - Running setup.py (path:/tmp/pip-dDNhF7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 17:20:06 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)) 17:20:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 17:20:06 INFO - Running setup.py (path:/tmp/pip-AyYCYT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 17:20:06 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)) 17:20:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 17:20:06 INFO - Running setup.py (path:/tmp/pip-SK82MM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 17:20:06 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)) 17:20:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 17:20:06 INFO - Running setup.py (path:/tmp/pip-A9NVnC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 17:20:06 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)) 17:20:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 17:20:06 INFO - Running setup.py (path:/tmp/pip-oT4zIL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 17:20:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.3 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)) 17:20:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 17:20:06 INFO - Running setup.py (path:/tmp/pip-kpn78N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 17:20:06 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)) 17:20:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 17:20:06 INFO - Running setup.py (path:/tmp/pip-YGOdIo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 17:20:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 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)) 17:20:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 17:20:06 INFO - Running setup.py (path:/tmp/pip-fDRgQm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 17:20:06 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)) 17:20:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 17:20:06 INFO - Running setup.py (path:/tmp/pip-aFh0i1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 17:20:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 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)) 17:20:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 17:20:06 INFO - Running setup.py (path:/tmp/pip-A7OreG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 17:20:07 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)) 17:20:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 17:20:07 INFO - Running setup.py (path:/tmp/pip-6YpIqf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 17:20:07 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 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)) 17:20:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 17:20:07 INFO - Running setup.py (path:/tmp/pip-3_SdV7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 17:20:07 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)) 17:20:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 17:20:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 17:20:07 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)) 17:20:07 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.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 17:20:07 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.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 17:20:07 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 17:20:07 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 17:20:07 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 17:20:07 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 17:20:07 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 17:20:07 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 17:20:07 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 17:20:09 INFO - Downloading blessings-1.6.tar.gz 17:20:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 17:20:09 INFO - Installing collected packages: blessings 17:20:09 INFO - Running setup.py install for blessings 17:20:09 INFO - Successfully installed blessings 17:20:09 INFO - Cleaning up... 17:20:09 INFO - Return code: 0 17:20:09 INFO - Done creating virtualenv /builds/slave/test/build/venv. 17:20:09 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 17:20:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 17:20:09 INFO - Reading from file tmpfile_stdout 17:20:09 INFO - Current package versions: 17:20:09 INFO - argparse == 1.2.1 17:20:09 INFO - blessings == 1.6 17:20:09 INFO - blobuploader == 1.2.4 17:20:09 INFO - docopt == 0.6.1 17:20:09 INFO - functools32 == 3.2.3-2 17:20:09 INFO - jsonschema == 2.5.1 17:20:09 INFO - manifestparser == 1.1 17:20:09 INFO - mozInstall == 1.12 17:20:09 INFO - mozcrash == 0.17 17:20:09 INFO - mozdebug == 0.1 17:20:09 INFO - mozdevice == 0.48 17:20:09 INFO - mozfile == 1.2 17:20:09 INFO - mozhttpd == 0.7 17:20:09 INFO - mozinfo == 0.9 17:20:09 INFO - mozleak == 0.1 17:20:09 INFO - mozlog == 3.3 17:20:09 INFO - moznetwork == 0.27 17:20:09 INFO - mozprocess == 0.23 17:20:09 INFO - mozprofile == 0.28 17:20:09 INFO - mozrunner == 6.12 17:20:09 INFO - mozscreenshot == 0.1 17:20:09 INFO - mozsystemmonitor == 0.3 17:20:09 INFO - moztest == 0.7 17:20:09 INFO - mozversion == 1.4 17:20:09 INFO - psutil == 3.1.1 17:20:09 INFO - requests == 1.2.3 17:20:09 INFO - wsgiref == 0.1.2 17:20:09 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:20:09 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:20:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:20:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:20:09 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:20:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:20:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:20:09 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 0x1c1f030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d828a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ee0180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ede3b0>, '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 0x1edf2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1edf740>, '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': '52faae308a556c440401f8e5000001e8-1476403927.519022-1795746679', '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 17:20:09 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 17:20:09 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 17:20:09 INFO - Using env: {'DISPLAY': ':0', 17:20:09 INFO - 'HOME': '/home/cltbld', 17:20:09 INFO - 'LANG': 'en_US.UTF-8', 17:20:09 INFO - 'LANGUAGE': 'en_US:en', 17:20:09 INFO - 'LOGNAME': 'cltbld', 17:20:09 INFO - 'MAIL': '/var/mail/cltbld', 17:20:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:20:09 INFO - 'MOZ_NO_REMOTE': '1', 17:20:09 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:20:09 INFO - 'NO_EM_RESTART': '1', 17:20:09 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:20:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:20:09 INFO - 'PWD': '/builds/slave/test', 17:20:09 INFO - 'SHELL': '/bin/bash', 17:20:09 INFO - 'SHLVL': '1', 17:20:09 INFO - 'TERM': 'linux', 17:20:09 INFO - 'TMOUT': '86400', 17:20:09 INFO - 'USER': 'cltbld', 17:20:09 INFO - 'XDG_SESSION_COOKIE': '52faae308a556c440401f8e5000001e8-1476403927.519022-1795746679', 17:20:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:20:09 INFO - '_': '/tools/buildbot/bin/python'} 17:20:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:20:09 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)) 17:20:09 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)) 17:20:09 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)) 17:20:09 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)) 17:20:09 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)) 17:20:09 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)) 17:20:09 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)) 17:20:09 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)) 17:20:09 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)) 17:20:09 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)) 17:20:09 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)) 17:20:09 INFO - Cleaning up... 17:20:09 INFO - Return code: 0 17:20:09 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 17:20:09 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:20:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:20:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:20:09 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:20:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:20:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:20:09 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 0x1c1f030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d828a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ee0180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ede3b0>, '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 0x1edf2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1edf740>, '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': '52faae308a556c440401f8e5000001e8-1476403927.519022-1795746679', '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 17:20:09 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 17:20:09 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 17:20:09 INFO - Using env: {'DISPLAY': ':0', 17:20:09 INFO - 'HOME': '/home/cltbld', 17:20:09 INFO - 'LANG': 'en_US.UTF-8', 17:20:09 INFO - 'LANGUAGE': 'en_US:en', 17:20:09 INFO - 'LOGNAME': 'cltbld', 17:20:09 INFO - 'MAIL': '/var/mail/cltbld', 17:20:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:20:09 INFO - 'MOZ_NO_REMOTE': '1', 17:20:09 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:20:09 INFO - 'NO_EM_RESTART': '1', 17:20:09 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:20:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:20:09 INFO - 'PWD': '/builds/slave/test', 17:20:09 INFO - 'SHELL': '/bin/bash', 17:20:09 INFO - 'SHLVL': '1', 17:20:09 INFO - 'TERM': 'linux', 17:20:09 INFO - 'TMOUT': '86400', 17:20:09 INFO - 'USER': 'cltbld', 17:20:09 INFO - 'XDG_SESSION_COOKIE': '52faae308a556c440401f8e5000001e8-1476403927.519022-1795746679', 17:20:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:20:09 INFO - '_': '/tools/buildbot/bin/python'} 17:20:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:20:09 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 17:20:09 INFO - Cleaning up... 17:20:10 INFO - Return code: 0 17:20:10 INFO - Running post-action listener: _resource_record_post_action 17:20:10 INFO - Running post-action listener: _start_resource_monitoring 17:20:10 INFO - Starting resource monitoring. 17:20:10 INFO - [mozharness: 2016-10-14 00:20:10.044526Z] Finished create-virtualenv step (success) 17:20:10 INFO - [mozharness: 2016-10-14 00:20:10.044960Z] Running install step. 17:20:10 INFO - Running pre-action listener: _resource_record_pre_action 17:20:10 INFO - Running main action method: install 17:20:10 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 17:20:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 17:20:10 INFO - Reading from file tmpfile_stdout 17:20:10 INFO - Detecting whether we're running mozinstall >=1.0... 17:20:10 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 17:20:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 17:20:10 INFO - Reading from file tmpfile_stdout 17:20:10 INFO - Output received: 17:20:10 INFO - Usage: mozinstall [options] installer 17:20:10 INFO - Options: 17:20:10 INFO - -h, --help show this help message and exit 17:20:10 INFO - -d DEST, --destination=DEST 17:20:10 INFO - Directory to install application into. [default: 17:20:10 INFO - "/builds/slave/test"] 17:20:10 INFO - --app=APP Application being installed. [default: firefox] 17:20:10 INFO - mkdir: /builds/slave/test/build/application 17:20:10 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 17:20:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 17:20:23 INFO - Reading from file tmpfile_stdout 17:20:23 INFO - Output received: 17:20:23 INFO - /builds/slave/test/build/application/firefox/firefox 17:20:23 INFO - Running post-action listener: _resource_record_post_action 17:20:23 INFO - [mozharness: 2016-10-14 00:20:23.833968Z] Finished install step (success) 17:20:23 INFO - [mozharness: 2016-10-14 00:20:23.834310Z] Running run-tests step. 17:20:23 INFO - Running pre-action listener: _resource_record_pre_action 17:20:23 INFO - Running main action method: run_tests 17:20:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 17:20:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 17:20:23 INFO - Python 2.7.3 17:20:23 INFO - Return code: 0 17:20:23 INFO - grabbing minidump binary from tooltool 17:20: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:20:23 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 0x1ede3b0>, '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 0x1edf2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1edf740>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 17:20:23 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 17:20:23 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 17:20:23 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 17:20:23 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 17:20:23 INFO - Return code: 0 17:20:23 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 17:20:23 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 17:20:23 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 17:20:23 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 17:20:23 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 17:20:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Vls0TAmiRpOLcQL-8gSWBQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-056', '--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 17:20:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Beta --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/Vls0TAmiRpOLcQL-8gSWBQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-056 --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 17:20:23 INFO - Using env: {'DISPLAY': ':0', 17:20:23 INFO - 'HOME': '/home/cltbld', 17:20:23 INFO - 'LANG': 'en_US.UTF-8', 17:20:23 INFO - 'LANGUAGE': 'en_US:en', 17:20:23 INFO - 'LOGNAME': 'cltbld', 17:20:23 INFO - 'MAIL': '/var/mail/cltbld', 17:20:23 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 17:20:23 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 17:20:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:20:23 INFO - 'MOZ_NO_REMOTE': '1', 17:20:23 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 17:20:23 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:20:23 INFO - 'NO_EM_RESTART': '1', 17:20:23 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:20:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:20:23 INFO - 'PWD': '/builds/slave/test', 17:20:23 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 17:20:23 INFO - 'SHELL': '/bin/bash', 17:20:23 INFO - 'SHLVL': '1', 17:20:23 INFO - 'TERM': 'linux', 17:20:23 INFO - 'TMOUT': '86400', 17:20:23 INFO - 'USER': 'cltbld', 17:20:23 INFO - 'XDG_SESSION_COOKIE': '52faae308a556c440401f8e5000001e8-1476403927.519022-1795746679', 17:20:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:20:23 INFO - '_': '/tools/buildbot/bin/python'} 17:20:23 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Vls0TAmiRpOLcQL-8gSWBQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-056', '--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 17:20:24 INFO - mozversion application_buildid: 20161013141419 17:20:24 INFO - mozversion application_changeset: 6efc0964ec62bc4abfdc4cb1dc7cc461c3238634 17:20:24 INFO - mozversion application_display_name: Firefox 17:20:24 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 17:20:24 INFO - mozversion application_name: Firefox 17:20:24 INFO - mozversion application_remotingname: firefox 17:20:24 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 17:20:24 INFO - mozversion application_vendor: Mozilla 17:20:24 INFO - mozversion application_version: 50.0 17:20:24 INFO - mozversion platform_buildid: 20161013141419 17:20:24 INFO - mozversion platform_changeset: 6efc0964ec62bc4abfdc4cb1dc7cc461c3238634 17:20:24 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 17:20:24 INFO - mozversion platform_version: 50.0 17:20:24 INFO - using testdate: 1476404424 17:20:24 INFO - actual date: 1476404424 17:20:24 INFO - starting webserver on 'localhost:57997' 17:20:24 INFO - SUITE-START | Running 2 tests 17:20:24 INFO - TEST-START | dromaeo_css 17:20:24 INFO - operating with platform_type : linux_ 17:20:24 INFO - Initialising browser for dromaeo_css test... 17:20:24 INFO - TEST-INFO | started process 13654 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpU9An_a/profile http://localhost:57997/getInfo.html) 17:20:28 INFO - PROCESS | 13654 | __metrics Screen width/height:1600/1200 17:20:28 INFO - PROCESS | 13654 | colorDepth:24 17:20:28 INFO - PROCESS | 13654 | Browser inner width/height: 1024/697 17:20:28 INFO - PROCESS | 13654 | __metrics 17:20:29 INFO - PROCESS | 13654 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 17:20:29 INFO - PROCESS | 13654 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 17:20:29 INFO - PROCESS | 13654 | console.error: PushService: 17:20:29 INFO - PROCESS | 13654 | stateChangeProcessEnqueue: Error transitioning state 17:20:29 INFO - PROCESS | 13654 | UnknownError 17:20:30 INFO - TEST-INFO | 13654: exit 0 17:20:30 INFO - Browser initialized. 17:20:30 INFO - Running cycle 1/1 for dromaeo_css test... 17:20:30 INFO - TEST-INFO | started process 13745 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpU9An_a/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 17:20:42 INFO - PROCESS | 13745 | RSS: Main: 144019456 17:20:42 INFO - PROCESS | 13745 | 17:21:33 INFO - PROCESS | 13745 | 1476404493837 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 17:23:41 INFO - PROCESS | 13745 | Cycle 1(1): loaded http://localhost:57997/tests/dromaeo/cssquery-dojo.html (next: http://localhost:57997/tests/dromaeo/cssquery-ext.html) 17:23:42 INFO - PROCESS | 13745 | RSS: Main: 270462976 17:23:42 INFO - PROCESS | 13745 | 17:26:45 INFO - PROCESS | 13745 | Cycle 1(1): loaded http://localhost:57997/tests/dromaeo/cssquery-ext.html (next: http://localhost:57997/tests/dromaeo/cssquery-jquery.html) 17:26:45 INFO - PROCESS | 13745 | RSS: Main: 234831872 17:26:45 INFO - PROCESS | 13745 | 17:29:47 INFO - PROCESS | 13745 | Cycle 1(1): loaded http://localhost:57997/tests/dromaeo/cssquery-jquery.html (next: http://localhost:57997/tests/dromaeo/cssquery-mootools.html) 17:29:48 INFO - PROCESS | 13745 | RSS: Main: 177090560 17:29:48 INFO - PROCESS | 13745 | 17:32:50 INFO - PROCESS | 13745 | Cycle 1(1): loaded http://localhost:57997/tests/dromaeo/cssquery-mootools.html (next: http://localhost:57997/tests/dromaeo/cssquery-prototype.html) 17:32:50 INFO - PROCESS | 13745 | RSS: Main: 174972928 17:32:50 INFO - PROCESS | 13745 | 17:35:53 INFO - PROCESS | 13745 | Cycle 1(1): loaded http://localhost:57997/tests/dromaeo/cssquery-prototype.html (next: http://localhost:57997/tests/dromaeo/cssquery-yui.html) 17:35:53 INFO - PROCESS | 13745 | RSS: Main: 175546368 17:35:53 INFO - PROCESS | 13745 | 17:38:56 INFO - PROCESS | 13745 | Cycle 1(1): loaded http://localhost:57997/tests/dromaeo/cssquery-yui.html (next: http://localhost:57997/tests/dromaeo/cssquery-dojo.html) 17:38:56 INFO - PROCESS | 13745 | RSS: Main: 259231744 17:38:56 INFO - PROCESS | 13745 | 17:38:56 INFO - PROCESS | 13745 | __start_tp_report 17:38:56 INFO - PROCESS | 13745 | _x_x_mozilla_page_load 17:38:56 INFO - PROCESS | 13745 | _x_x_mozilla_page_load_details 17:38:56 INFO - PROCESS | 13745 | |i|pagename|runs| 17:38:56 INFO - PROCESS | 13745 | |0;dojo.html;3184.219866132798;3197.8241196734184;3214.839258037099;3244.9188770280753;3248.5289633003217;5612.719364031797;5680.943190568103;5683.772649094037;5685.800996965107;5704.914426283609;5614.242077319561;5666.291713535808;5681.943180568193;5682.090865461527;5687.317521897378;5099.260607211952;5125.8462246132485;5137.691738495683;5181.300524429211;5189.584833213353;4984.576311013569;5077.441481437039;5201.0898092833795;5204.687718736869;5245.108331583621;5100.617453690969;5101.362329708786;5103.336566246401;5111.539961403473;5114.488551144893;5130.230465430197;5225.190095535198;5232.738363081869;5235.947640523584;5264.789408423659;5000.0999820032375;5067.68655346716;5145.588352931756;5166.741662916839;5203.739813009334;5417.241586177938;5471.23402723619;5508.724563771795;5514.6139770216105;5516.282883225195;5956.076808094752;5985.820425387245;5989.401059894019;5990.790322338741;5991.131285963511;5846.824595262149;6003.6697981611;6058.606190597597;6085.178500902366;6088.056351265516;5590.105583106683;5679.574031947621;5691.943080569225;5706.686132262724;5712.65724056558;6220.409061139275;6224.408681175281;6232.906506402408;6234.625922444668;6235.844103897439;5729.627574207663;5806.65160090396;5857.941420585825;5888.882222355614;5916.319623243296;5992.700364981733;5994.580379373402;6006.249218847644;6021.638701677825;6026.45761881424;5965.940340596625;5967.820965370959;5972.492338151219;5972.701364931736;5985.012472942003;5667.829965101054;5767.163761254678;5920.31916329619;5953;5969.850753731192;4486.371907932892;4600.079984003212;4603.125406172817;4636.11913736389;4636.466806317317;4123.463949686521;4137.482814648169;4138.317177665712;4145.2124096421585;4164.770937598461;5593.244911936837;5614.831555053355;5768.2501274834;5774.133879917962;5790.10253410722;5981.850453738604;6048.0625503047095;6055.848603784941;6063.060225665029;6070.271567411939;5714.457126572969;5766.192733017381;5786.247787787561;5786.826395208151;5787.131930210502;5092.770825312869;5116.692998420107;5230.424653288135;5239.716269513993;5242;6091.48222401092;6177.135201071854;6184.072389141665;6193.318734939153;6200.100985357098;5940.049592065422;5985.670788106696;5988.513133184863;6005.069214271794;6034.185384972972;5943.791967281124;6087.543434242361;6107.267127944675;6110.694465276807;6166.229221347332;5909.113632954919;6012.428819262163;6014.458698717136;6017.18767966319;6041.063635136561;6099.115628233792;6103.298120716175;6103.42017508336;6108.358622344767;6110.052941794029;5709.343425505954;5783.8554036149435;5973.790917317873;5975.820725378246;5977.1034344846885;5802.796902108406;5881.794137205177;5881.852953676193;5908.615939963888;5909.674967876808;5800.24596802413;5802.941970580409;5806.4193580641595;5811.56413269015;5816.185733997159;5746.597738158289;5818.505427038749;5844.444777746277;5844.766209351578;5913.704314784158;5750.913736294043;5865;5875.941240587539;5900.64596124234;5901.173835663097 17:38:56 INFO - PROCESS | 13745 | |1;ext.html;576.6338375131791;622.1973653986357;653.4347789162374;659.4032400677389;668.231533736203;34787.478187827204;45244;46336.304955425694;46437.30344044842;50808.74595627026;48219.51780482194;48509;48759.51240487594;48766.51233487664;48935.75532122335;8462.15378462152;9263.583138758771;9419.48192849393;9424.05759424059;10874.130069594454;10843.783124337548;11210.66368008961;11222.887771122307;11227.438628068605;11229.157813164005;11347.489362978644;11387.316760994325;11388.088952883749;11390.715232119222;11397.373144477051;11243.83134252987;11266.943665281704;11291.49188286529;11305.773884522263;11319.20765546412;11223.719407014809;11281.210315277935;11288.435578221057;11298.096152307795;11305.773884522347;45604.315935260995;45850;45965.08069838618;45972;46077;3630.0743310455928;3739.0652336915773;3777.5466943966626;3817.522809648794;3890.9221815563815;33199.33601327984;33934.49098263528;34009.48985765215;34125.48811767826;34326;4575.519570445122;4592.609628181609;4612.215923293048;4727.432708075019;4773.11697335994;380561;384379;386064.06967965263;386757;388707;61754;61844.690776546726;61873.381266187665;62154.37845621486;62330.688346557974;38674.41988370177;39612.80193599014;39672.80163599221;42124.368134478005;65811;14969.176695281647;15103;15147.091174529563;15151.848481515044;15202;11146.832797508045;11925.70185745346;12250.448729807002;12272.38638068093;12407.93796031014;20539.4865128373;20659.45054120947;20746.79253207479;20769;20841.68737468939;7449;7623.809404764815;7741.18717534662;7757.9224207758325;7791.220877912164;973.3332667123043;1068.7595291059604;1077.5205033760058;1100.389283947417;1174.618249069056;35131.824340878135;35152.29695406129;35391;35610.64389356125;35643.10892227715;1130.0676941523277;1145.7536629624503;1161.6050542815483;1202.1284568687631;1208.3414539076225;16285.430009949594;16533.33866645345;16571.6685666286;16581.751273730904;16667.749983750255;687636;698371;698874;699850;700120;62312;62639.373606264264;62742.68628656828;62846;62850;1260.9432575534122;1386.4107754204422;1420.779779134236;1423.943042278318;1436.6049336432357;33835.323293533496;35695.46456803202;36137;36428.45357319695;36902.630973691;54343;54790.45209547853;54946;54988;55018.72490637681;491416;491543;491722;493763;495290;25838.354041149123;30118;30184.849075754482;32013.679863202;32209.194770130933;51630;51685.22472162845;52105.47894521158;52438;53513.732431337594;44412.77793611011;45277;45293;45358.54641453675;46571.30143047789;43493.56506434895;44087.11825763403;44813.32780008237;45172.32241516445;45252.321215182455;44207.33688994603;45067.54932450633;45528.317075243234;45652.771736141105;45710.31434528418;35374.292514149056;38602;38872.805635971636;39366.26429479089;39585.406218906166;29114.27214319659;29444.558331624616;29596;29774.851125744233;29879.850600746857 17:38:56 INFO - PROCESS | 13745 | |2;jquery.html;405.1512082187819;407.4336672025885;408.91412803311306;409.4084048549845;417.2447869356465;19221.903890480546;20645.174193032297;21030.474238144074;21064.052117654704;21311.680324795103;13234.338283085855;13786.724265514684;20115.692479988826;20317;20426.591468170627;5137.280780690697;5460.645058071233;5542.722863856811;5547.306586676666;5557.944420555783;4133.772642504661;5444.455554444544;5507;5508.9724551377185;5511.22842802006;4463.107378524292;6613.437857782094;6628.635425051621;6704.899426508607;6711.899321510181;6049.455549000567;6214.0989556514505;6531.150950376445;6675.632840193787;6682.465402767767;6032.758689652409;6047.879042419127;6056.06131049688;6056.394360563909;6057.939420605826;14780.113193208334;14829.480968166063;15101.773473397909;15252;15330.616734581614;2359.6814430051895;2390.5696974544576;2536.8731563421757;2547.46503234321;2871.3826527296733;10956.90430956898;11497.885021149765;12482.81275780864;14885.70228595422;15177.317020734094;1978.8416926645978;1995.720599116125;2043.5402034542258;2075.6782698681727;2087.2381580723077;62249;74721.6263918677;89643.55178224132;90175;90424;10804.78390432187;11047.447627618587;11052.447377631086;11302.491387887565;11365;15200.771988420183;15267.847321526864;15268.083914965138;15324.540263792103;15362.231888405535;7808.882866757003;7937.682492700227;8055.63749631268;8113.959430202811;8152;7325.5970921598755;7448.925510744932;7617.390608751287;7681.347085497795;7858.0570331560575;4549.522300158435;4575.633949284049;4772.0455908817685;5026.321446604661;5121.129408000649;1900;1943.8542109341856;1960.784313725489;1982.603479304145;1997.530580313625;950.3490109275168;1000.2448151645461;1018.6994836523244;1051.206339213904;1072.9356238625708;20486.897565512376;21008.89495552543;21074.78925210759;21165.365039048855;21236.044378003025;990.8018396320765;991.7917237380086;997.1474389396961;1021.2646894236138;1075.4353964168718;10801.297915635616;10993.835092473619;11521.596744113916;11993.700157496132;12463.252204867737;419529;421934;422617;539405;561899;94051.52974235085;100178;102847.97152028384;103002;107619;1025.1696126137851;1041.3699711674478;1076.4025965589017;1077.6928575355985;1086.9130469562574;18853.717194241824;18910.810891890906;19210.231590736214;19220.519487012378;19240.23039078421;42394.57605423906;43498.565014350716;45354.09291814079;47998.760006199744;48679.756601216766;59966.700166498886;62819.6859015702;63069.68465157645;63809.68095159494;63819;8546.444481108709;9069.501177435304;9094.363394562317;9130.543472826464;9169.174774270348;12174.39128043612;13929.860701392856;13933.651658708613;14044.297785110908;14352.641183970067;12034.338111403607;12265.509379625071;12310.692232694255;12312.507499699912;12416.317102559085;11788.823167652321;11954.342511161622;12072.879271207526;12541.122121451412;12615.621531354074;12434.502619895104;12604.30676312775;12747.298898560297;13121.08152429359;13208.141470804367;8955.865662015205;9102.089791020844;9137.817243654958;9212.078792121003;9348.53257337144;9673.226141908772;11982.161248712855;12298.139130260795;12394.256344619353;12594.3073130975 17:38:56 INFO - PROCESS | 13745 | |3;mootools.html;1407;1532.6168457885528;1564.4915402494191;1684.2420910590238;1805.277888844463;8806.42758220716;9224.8616270756;9234.353595248336;9522;9531.666391676292;9237.722868313962;9330.53347332634;9352.812943741139;9375.296852736055;9383.671571494999;2431.963520547193;2438.9390265243424;2447.608382658775;2457.3242358351417;2459.409741661997;2419.511032314778;2467.654528366021;2469.80241580674;2478.47951930094;2482.6648402465707;2424.2363655448567;2435.3789783605157;2471.7157526884457;2473.7773600376054;2477.1082410332265;2396.808255339569;2417.190241269168;2421.576224160765;2431.489387228684;2440.877956102188;2356.15178535727;2374.2639781667567;2395.3293077938206;2398.052769156191;2399.0403838464754;9216.49309287989;9343.71968840929;9457.668981585612;9469.857952130724;9470.431774093508;5491.505764481216;5705.400932902027;5719.885602287973;5725.541956643458;5729.627574207663;3416.6583341665882;3421.3328400961823;3496.1958749487762;3504.54440922681;3537.8231088445473;1223.443333283354;1238.1271203801298;1256.9371531423392;1260.974780504394;1275.5535562553125;12984.740305193938;13110.213387196798;13130.146540474838;13181.60455186346;13236.86763132366;4594.747288899142;4670.276107203389;4868.707877527359;4881.389826271716;4884.389451318585;10366.740831479274;10387.065164135263;10401.895981040245;10403.791924161475;10413.895861041292;5542.140968149944;5573.498385145356;5573.665580065209;5576.9163462548095;5586.581006424534;4580.198465268565;4661.696989695659;4741.668083234141;5131.179011358165;5240.554552862973;4081.775502347399;4099.85650502231;4191.371294305878;4191.706580539333;4330.7401555906745;1177.6339446242318;1202.9338386388833;1204.0427859851438;1208.275034979023;1212.751385965875;1036.6268143468346;1059.9629012984508;1060.2472244706191;1061.2093989977361;1079.7084787107437;6445.581037232565;6486.318936511692;6525.836854078686;6597.80206593803;6729.865402692017;471.38013512231305;471.52376100139105;476.8903152274996;478.8946431785002;480.17649730712;4600.4479462464715;4638.536146385402;4673.182193116191;4808.06242782654;4974.328465657162;11551.76896462066;11968.281903085843;12130.393480325949;12215.572454964034;12227.938860305641;11844.763104737856;12065.457054432572;12070.155089143676;12082;12223.572174973833;8971.865422018675;9189.77025574366;9190.724278271662;9197.678081267124;9206.355555111211;6738.898916516158;6850.177978642396;6857.27998560144;6872.41584465326;6886.380225779704;10758.515866785858;10894.400807955639;11205.439728013404;11350.148738844455;11383.715407114889;9934;10840.783184336111;11155.163362747664;11218.495167717309;11389.373584452935;7980.760577182694;8278.586070696561;8700.78248043804;8701.651933922572;8702.434341767765;8791.296696264155;9059.909400905906;9103.726888193365;9127.315451341043;9147.63409463614;7803.258690424401;7876.173001834725;7877.881831772643;7996.360291176576;8144.185581441809;7825.3739700824935;8010.8397832041865;8055.395845311742;8071.394645401736;8175.550344731097;6934.375906168469;7068.11648543932;7087.787366379017;7095.82260443493;7108.573485590674;5174.741262936914;5318.7340632967425;5351.892962140813;5502.58730595215;5512.889742205213;5817.534597232296;5862.501687356623;5921.2006379138265;5974.342822289458;6007.879842403215 17:38:56 INFO - PROCESS | 13745 | |4;prototype.html;346.2123668653812;347.08542989223395;348.75064329004755;350.39381869365997;354.3709914901052;5448.591355648327;5490.80782172624;5526;5537.418571050042;5541.342713808892;5393.649412788176;5415.13357862742;5471.5896307776875;5476.561875049996;5479.1781232815;3891.6108389161;4097.160082183145;4261.914761704764;4307.1601037797645;4332.7833608319615;4278.5721427857125;4295.892602684927;4323;4329.199098166833;4330.458692663417;4267.893302667427;4277.529471758104;4302.48370195577;4317.71934824237;4328.740275583459;4210.894727631803;4246.660267178619;4261.570904584388;4327.588879056485;4348.7825608719595;3774.735768496206;3784.299904517673;3787.981060094682;3789.5642001169945;3817.8663746769007;5243.737813109367;5253;5285.55072818807;5311.698633834735;5313.973430132824;4740.762961851894;4879.731614761186;4946.826861059882;4954.405471343426;4962.900741985176;5660.716964151776;5665.688387138705;5674.950134225141;5676.602637815355;5691;4167.187398457302;4181.811818468146;4196.916061678749;4207.852725154605;4208.936865947013;5797.73910174039;5869.383714709937;5905.734241959122;5912.17229587858;5932.228810254683;4249.617534421917;4255.29787585045;4261.403403523447;4263.27337428342;4268.253055715238;4308.91382172361;4327.437433133736;4338.305871060616;4357.562004538495;4360.4767427908855;4686.976565117153;4854.271859221074;4887.535684110004;4938.802447902043;4941.975290123526;5659.547236221092;5672.602917795714;5684.204211410406;5694.572907031989;5752.194692742935;1474.2776039740666;1493.3951749541452;1511.9244037798287;1512.7277090123882;1515.204517628232;919.0946917286467;980.2648013989508;984.8030393921244;985.7387792235063;1001.7095042437758;4846.704241116005;4893.510648935078;4912.410510738734;4925.522343296997;4966.230234313615;5104.668196567285;5118.539331460112;5129.487051294916;5143.7685304161405;5156.74216289184;4667.066586682677;4890.95109048912;4927.704337739747;4957.330760347307;4966;2731.7951153663157;2741.383188782508;2752.325680208362;2775.764060054918;2786.4984302825687;5263.526282634582;5323.281357016753;5331.733413329395;5342.813001544927;5374.220737992969;5226.792432092202;5230.764615592308;5522.806701765499;5992.591740940876;6017.247844019497;5783.826485205451;5928.644281343133;5937.643741375531;5958.314793798682;5972.5520585955355;5890.14592884038;5909.55678324136;5914.556408269483;5937.851553711019;5955.970220148872;5670.659760414388;5797.188363643714;5842.561807864342;5849.64902105875;5871.853203669772;6078.331383547717;6085.204864564876;6096.664683442454;6102.786402475892;6110.816675499742;4263.211305908417;4271.359296105609;4290.1419716057535;4306.935395969125;4329;5871.794487192928;5885;5904.763809447574;5921.940780592311;5930.96208163561;5710.172025056427;5730.627509211888;5754.769809207585;5757.65454072757;5760.135979603093;5549.611527193058;5728.684922329146;5773.624714393551;5777.595568310178;5778.653280803165;5703.5722320826935;5713.600047996601;5716.256886604881;5717.799877004451;5720.799772007959;5714.342850572238;5763.884722305446;5771.538276937918;5804.649117107579;5805.94194058054;5331.733413329395;5608.9158662619275;5629.662220266797;5649.491545760901;5653.643125649896 17:38:56 INFO - PROCESS | 13745 | |5;yui.html;804.5574933786417;814.857399955008;816.2979837339886;874.7681864305958;928.9117533834292;13641.522546710867;15576.610584735387;15612.453564125259;15629.609259768496;15635.84364156358;12987.80518292224;13003.219806811598;13004.414801333938;13034.543790967318;13045.08684392093;532.5952276270043;836.1554829622065;854.6026097864492;860.6901515454463;870.7648934787603;860.2859626509982;860.328943424127;860.5396113079504;861.1216559109704;861.3109512390095;898.0615257056359;901.0944001278706;902.8871391076116;905.0858632780905;905.642271302835;901.0268909577641;901.292485398962;901.5987885391004;902.4585248850702;902.8735976963263;664.02720015178;664.4119953840838;664.451827242522;665.6039656404431;665.9567128136704;13156.736865262641;13167.209967401986;13207;13209.33953302331;13258.337083145803;15855.76216356744;15867.44463943768;16177;16402.67194656112;16854.157292135344;12086.395680215954;12086.939565302206;12218.511259549607;12269.93865030678;12458.563950261698;4021.939670904939;4027;4046.413270075852;4115.670746340315;4140.37894315852;84356.15643843605;84475;86024.5698771502;86047;89260;12653.17754345983;12746.553870614483;12839.293838838954;12852.357382131044;12852.807207891889;19707.408777736404;24333.75666243315;24377.26868193957;24389.756102439103;24445.388865278153;8221.136780638066;8320.8335833283;8360.372972027122;8515;8721.738347849574;4742.786574604151;4749.691270067435;4765.642576806684;4892;4907.509249075064;3683.9263214735556;3835.9424608630893;3836.4245363195746;3840.7503512271614;3841.1741475582326;624.7282432142033;629.0846817879974;639.2137670665107;645.6836150286326;666.1573110015834;644.4779728419995;698.472653146881;722.9349358557755;744.5421066044344;751.4176513202202;9618.51907404627;9968;10136.2904596679;10372.636957706443;10630.46847657614;658.2101478226148;724.9275072492709;740.1562219070264;772.563501621582;776.2470403708394;4694.131585656664;4721.881952951204;4744.786484608201;4785.617150627942;4786.64100192487;75269;76188.61905690435;76730.23269767342;96714.03285967051;129040.35479822541;110478;112644.43677781723;112690;113512;115733;98285.50857245667;98488.50755746319;101292;103154;104224;8726.214640682369;9142.953693856616;9241;9314.161725444743;9344.392614480037;51135;59652.10521842089;59893.502662433784;60539;62011.68994155;50400.49599503958;52345.21482177846;53351.46648533465;60200;64107.35892641014;13231.404586793426;13427;13564.525241616497;13764.93117534446;14145.858541414866;15256.313465894284;15826.841731582997;16005.759913601538;16037.839621604102;16276.755848662042;15155.457136331606;15208.011479254255;15279.388824446898;15344.386224550894;15630.062196267805;15255;15496.767548487007;15500.147491887603;15584.688306234038;16628.3348666052;13778.793318100437;14117.2235527047;14405.855941440452;14515.564533063602;15442;10652;10960.232783705063;11503.367314797766;11682.47428865686;11761.353125577833;11616;11937.82093268619;12154.696132596755;12160.391980401122;12180.695482612651 17:38:56 INFO - PROCESS | 13745 | __end_tp_report 17:38:56 INFO - PROCESS | 13745 | __start_cc_report 17:38:56 INFO - PROCESS | 13745 | _x_x_mozilla_cycle_collect,1071 17:38:56 INFO - PROCESS | 13745 | __end_cc_report 17:38:56 INFO - PROCESS | 13745 | __startTimestamp1476405536499__endTimestamp 17:38:56 INFO - PROCESS | 13745 | 17:38:56 INFO - PROCESS | 13745 | ------- Summary: start ------- 17:38:56 INFO - PROCESS | 13745 | Number of tests: 6 17:38:56 INFO - PROCESS | 13745 | 17:38:56 INFO - PROCESS | 13745 | [#0] dojo.html Cycles:165 Average:5587.93 Median:5800.25 stddev:628.97 (10.8%) stddev-sans-first:601.97 17:38:56 INFO - PROCESS | 13745 | Values: 3184.2 3197.8 3214.8 3244.9 3248.5 5612.7 5680.9 5683.8 5685.8 5704.9 5614.2 5666.3 5681.9 5682.1 5687.3 5099.3 5125.8 5137.7 5181.3 5189.6 4984.6 5077.4 5201.1 5204.7 5245.1 5100.6 5101.4 5103.3 5111.5 5114.5 5130.2 5225.2 5232.7 5235.9 5264.8 5000.1 5067.7 5145.6 5166.7 5203.7 5417.2 5471.2 5508.7 5514.6 5516.3 5956.1 5985.8 5989.4 5990.8 5991.1 5846.8 6003.7 6058.6 6085.2 6088.1 5590.1 5679.6 5691.9 5706.7 5712.7 6220.4 6224.4 6232.9 6234.6 6235.8 5729.6 5806.7 5857.9 5888.9 5916.3 5992.7 5994.6 6006.2 6021.6 6026.5 5965.9 5967.8 5972.5 5972.7 5985.0 5667.8 5767.2 5920.3 5953.0 5969.9 4486.4 4600.1 4603.1 4636.1 4636.5 4123.5 4137.5 4138.3 4145.2 4164.8 5593.2 5614.8 5768.3 5774.1 5790.1 5981.9 6048.1 6055.8 6063.1 6070.3 5714.5 5766.2 5786.2 5786.8 5787.1 5092.8 5116.7 5230.4 5239.7 5242.0 6091.5 6177.1 6184.1 6193.3 6200.1 5940.0 5985.7 5988.5 6005.1 6034.2 5943.8 6087.5 6107.3 6110.7 6166.2 5909.1 6012.4 6014.5 6017.2 6041.1 6099.1 6103.3 6103.4 6108.4 6110.1 5709.3 5783.9 5973.8 5975.8 5977.1 5802.8 5881.8 5881.9 5908.6 5909.7 5800.2 5802.9 5806.4 5811.6 5816.2 5746.6 5818.5 5844.4 5844.8 5913.7 5750.9 5865.0 5875.9 5900.6 5901.2 17:38:56 INFO - PROCESS | 13745 | 17:38:56 INFO - PROCESS | 13745 | [#1] ext.html Cycles:180 Average:68577.54 Median:212715.71 stddev:144229.29 (67.8%) stddev-sans-first:144543.53 17:38:56 INFO - PROCESS | 13745 | Values: 576.6 622.2 653.4 659.4 668.2 34787.5 45244.0 46336.3 46437.3 50808.7 48219.5 48509.0 48759.5 48766.5 48935.8 8462.2 9263.6 9419.5 9424.1 10874.1 10843.8 11210.7 11222.9 11227.4 11229.2 11347.5 11387.3 11388.1 11390.7 11397.4 11243.8 11266.9 11291.5 11305.8 11319.2 11223.7 11281.2 11288.4 11298.1 11305.8 45604.3 45850.0 45965.1 45972.0 46077.0 3630.1 3739.1 3777.5 3817.5 3890.9 33199.3 33934.5 34009.5 34125.5 34326.0 4575.5 4592.6 4612.2 4727.4 4773.1 380561.0 384379.0 386064.1 386757.0 388707.0 61754.0 61844.7 61873.4 62154.4 62330.7 38674.4 39612.8 39672.8 42124.4 65811.0 14969.2 15103.0 15147.1 15151.8 15202.0 11146.8 11925.7 12250.4 12272.4 12407.9 20539.5 20659.5 20746.8 20769.0 20841.7 7449.0 7623.8 7741.2 7757.9 7791.2 973.3 1068.8 1077.5 1100.4 1174.6 35131.8 35152.3 35391.0 35610.6 35643.1 1130.1 1145.8 1161.6 1202.1 1208.3 16285.4 16533.3 16571.7 16581.8 16667.7 687636.0 698371.0 698874.0 699850.0 700120.0 62312.0 62639.4 62742.7 62846.0 62850.0 1260.9 1386.4 1420.8 1423.9 1436.6 33835.3 35695.5 36137.0 36428.5 36902.6 54343.0 54790.5 54946.0 54988.0 55018.7 491416.0 491543.0 491722.0 493763.0 495290.0 25838.4 30118.0 30184.8 32013.7 32209.2 51630.0 51685.2 52105.5 52438.0 53513.7 44412.8 45277.0 45293.0 45358.5 46571.3 43493.6 44087.1 44813.3 45172.3 45252.3 44207.3 45067.5 45528.3 45652.8 45710.3 35374.3 38602.0 38872.8 39366.3 39585.4 29114.3 29444.6 29596.0 29774.9 29879.9 17:38:56 INFO - PROCESS | 13745 | 17:38:56 INFO - PROCESS | 13745 | [#2] jquery.html Cycles:180 Average:29150.11 Median:21120.08 stddev:79122.12 (374.6%) stddev-sans-first:79314.64 17:38:56 INFO - PROCESS | 13745 | Values: 405.2 407.4 408.9 409.4 417.2 19221.9 20645.2 21030.5 21064.1 21311.7 13234.3 13786.7 20115.7 20317.0 20426.6 5137.3 5460.6 5542.7 5547.3 5557.9 4133.8 5444.5 5507.0 5509.0 5511.2 4463.1 6613.4 6628.6 6704.9 6711.9 6049.5 6214.1 6531.2 6675.6 6682.5 6032.8 6047.9 6056.1 6056.4 6057.9 14780.1 14829.5 15101.8 15252.0 15330.6 2359.7 2390.6 2536.9 2547.5 2871.4 10956.9 11497.9 12482.8 14885.7 15177.3 1978.8 1995.7 2043.5 2075.7 2087.2 62249.0 74721.6 89643.6 90175.0 90424.0 10804.8 11047.4 11052.4 11302.5 11365.0 15200.8 15267.8 15268.1 15324.5 15362.2 7808.9 7937.7 8055.6 8114.0 8152.0 7325.6 7448.9 7617.4 7681.3 7858.1 4549.5 4575.6 4772.0 5026.3 5121.1 1900.0 1943.9 1960.8 1982.6 1997.5 950.3 1000.2 1018.7 1051.2 1072.9 20486.9 21008.9 21074.8 21165.4 21236.0 990.8 991.8 997.1 1021.3 1075.4 10801.3 10993.8 11521.6 11993.7 12463.3 419529.0 421934.0 422617.0 539405.0 561899.0 94051.5 100178.0 102848.0 103002.0 107619.0 1025.2 1041.4 1076.4 1077.7 1086.9 18853.7 18910.8 19210.2 19220.5 19240.2 42394.6 43498.6 45354.1 47998.8 48679.8 59966.7 62819.7 63069.7 63809.7 63819.0 8546.4 9069.5 9094.4 9130.5 9169.2 12174.4 13929.9 13933.7 14044.3 14352.6 12034.3 12265.5 12310.7 12312.5 12416.3 11788.8 11954.3 12072.9 12541.1 12615.6 12434.5 12604.3 12747.3 13121.1 13208.1 8955.9 9102.1 9137.8 9212.1 9348.5 9673.2 11982.2 12298.1 12394.3 12594.3 17:38:56 INFO - PROCESS | 13745 | 17:38:56 INFO - PROCESS | 13745 | [#3] mootools.html Cycles:180 Average:6195.80 Median:2572.28 stddev:3582.88 (139.3%) stddev-sans-first:3574.86 17:38:56 INFO - PROCESS | 13745 | Values: 1407.0 1532.6 1564.5 1684.2 1805.3 8806.4 9224.9 9234.4 9522.0 9531.7 9237.7 9330.5 9352.8 9375.3 9383.7 2432.0 2438.9 2447.6 2457.3 2459.4 2419.5 2467.7 2469.8 2478.5 2482.7 2424.2 2435.4 2471.7 2473.8 2477.1 2396.8 2417.2 2421.6 2431.5 2440.9 2356.2 2374.3 2395.3 2398.1 2399.0 9216.5 9343.7 9457.7 9469.9 9470.4 5491.5 5705.4 5719.9 5725.5 5729.6 3416.7 3421.3 3496.2 3504.5 3537.8 1223.4 1238.1 1256.9 1261.0 1275.6 12984.7 13110.2 13130.1 13181.6 13236.9 4594.7 4670.3 4868.7 4881.4 4884.4 10366.7 10387.1 10401.9 10403.8 10413.9 5542.1 5573.5 5573.7 5576.9 5586.6 4580.2 4661.7 4741.7 5131.2 5240.6 4081.8 4099.9 4191.4 4191.7 4330.7 1177.6 1202.9 1204.0 1208.3 1212.8 1036.6 1060.0 1060.2 1061.2 1079.7 6445.6 6486.3 6525.8 6597.8 6729.9 471.4 471.5 476.9 478.9 480.2 4600.4 4638.5 4673.2 4808.1 4974.3 11551.8 11968.3 12130.4 12215.6 12227.9 11844.8 12065.5 12070.2 12082.0 12223.6 8971.9 9189.8 9190.7 9197.7 9206.4 6738.9 6850.2 6857.3 6872.4 6886.4 10758.5 10894.4 11205.4 11350.1 11383.7 9934.0 10840.8 11155.2 11218.5 11389.4 7980.8 8278.6 8700.8 8701.7 8702.4 8791.3 9059.9 9103.7 9127.3 9147.6 7803.3 7876.2 7877.9 7996.4 8144.2 7825.4 8010.8 8055.4 8071.4 8175.6 6934.4 7068.1 7087.8 7095.8 7108.6 5174.7 5318.7 5351.9 5502.6 5512.9 5817.5 5862.5 5921.2 5974.3 6007.9 17:38:56 INFO - PROCESS | 13745 | 17:38:56 INFO - PROCESS | 13745 | [#4] prototype.html Cycles:180 Average:4744.00 Median:5274.54 stddev:1382.06 (26.2%) stddev-sans-first:1345.95 17:38:56 INFO - PROCESS | 13745 | Values: 346.2 347.1 348.8 350.4 354.4 5448.6 5490.8 5526.0 5537.4 5541.3 5393.6 5415.1 5471.6 5476.6 5479.2 3891.6 4097.2 4261.9 4307.2 4332.8 4278.6 4295.9 4323.0 4329.2 4330.5 4267.9 4277.5 4302.5 4317.7 4328.7 4210.9 4246.7 4261.6 4327.6 4348.8 3774.7 3784.3 3788.0 3789.6 3817.9 5243.7 5253.0 5285.6 5311.7 5314.0 4740.8 4879.7 4946.8 4954.4 4962.9 5660.7 5665.7 5675.0 5676.6 5691.0 4167.2 4181.8 4196.9 4207.9 4208.9 5797.7 5869.4 5905.7 5912.2 5932.2 4249.6 4255.3 4261.4 4263.3 4268.3 4308.9 4327.4 4338.3 4357.6 4360.5 4687.0 4854.3 4887.5 4938.8 4942.0 5659.5 5672.6 5684.2 5694.6 5752.2 1474.3 1493.4 1511.9 1512.7 1515.2 919.1 980.3 984.8 985.7 1001.7 4846.7 4893.5 4912.4 4925.5 4966.2 5104.7 5118.5 5129.5 5143.8 5156.7 4667.1 4891.0 4927.7 4957.3 4966.0 2731.8 2741.4 2752.3 2775.8 2786.5 5263.5 5323.3 5331.7 5342.8 5374.2 5226.8 5230.8 5522.8 5992.6 6017.2 5783.8 5928.6 5937.6 5958.3 5972.6 5890.1 5909.6 5914.6 5937.9 5956.0 5670.7 5797.2 5842.6 5849.6 5871.9 6078.3 6085.2 6096.7 6102.8 6110.8 4263.2 4271.4 4290.1 4306.9 4329.0 5871.8 5885.0 5904.8 5921.9 5931.0 5710.2 5730.6 5754.8 5757.7 5760.1 5549.6 5728.7 5773.6 5777.6 5778.7 5703.6 5713.6 5716.3 5717.8 5720.8 5714.3 5763.9 5771.5 5804.6 5805.9 5331.7 5608.9 5629.7 5649.5 5653.6 17:38:56 INFO - PROCESS | 13745 | 17:38:56 INFO - PROCESS | 13745 | [#5] yui.html Cycles:180 Average:21174.85 Median:4128.02 stddev:30364.86 (735.6%) stddev-sans-first:30411.52 17:38:56 INFO - PROCESS | 13745 | Values: 804.6 814.9 816.3 874.8 928.9 13641.5 15576.6 15612.5 15629.6 15635.8 12987.8 13003.2 13004.4 13034.5 13045.1 532.6 836.2 854.6 860.7 870.8 860.3 860.3 860.5 861.1 861.3 898.1 901.1 902.9 905.1 905.6 901.0 901.3 901.6 902.5 902.9 664.0 664.4 664.5 665.6 666.0 13156.7 13167.2 13207.0 13209.3 13258.3 15855.8 15867.4 16177.0 16402.7 16854.2 12086.4 12086.9 12218.5 12269.9 12458.6 4021.9 4027.0 4046.4 4115.7 4140.4 84356.2 84475.0 86024.6 86047.0 89260.0 12653.2 12746.6 12839.3 12852.4 12852.8 19707.4 24333.8 24377.3 24389.8 24445.4 8221.1 8320.8 8360.4 8515.0 8721.7 4742.8 4749.7 4765.6 4892.0 4907.5 3683.9 3835.9 3836.4 3840.8 3841.2 624.7 629.1 639.2 645.7 666.2 644.5 698.5 722.9 744.5 751.4 9618.5 9968.0 10136.3 10372.6 10630.5 658.2 724.9 740.2 772.6 776.2 4694.1 4721.9 4744.8 4785.6 4786.6 75269.0 76188.6 76730.2 96714.0 129040.4 110478.0 112644.4 112690.0 113512.0 115733.0 98285.5 98488.5 101292.0 103154.0 104224.0 8726.2 9143.0 9241.0 9314.2 9344.4 51135.0 59652.1 59893.5 60539.0 62011.7 50400.5 52345.2 53351.5 60200.0 64107.4 13231.4 13427.0 13564.5 13764.9 14145.9 15256.3 15826.8 16005.8 16037.8 16276.8 15155.5 15208.0 15279.4 15344.4 15630.1 15255.0 15496.8 15500.1 15584.7 16628.3 13778.8 14117.2 14405.9 14515.6 15442.0 10652.0 10960.2 11503.4 11682.5 11761.4 11616.0 11937.8 12154.7 12160.4 12180.7 17:38:56 INFO - PROCESS | 13745 | -------- Summary: end -------- 17:38:56 INFO - PROCESS | 13745 | 17:38:57 INFO - TEST-INFO | 13745: exit 0 17:38:57 INFO - TEST-OK | dromaeo_css | took 1113199ms 17:38:57 INFO - TEST-START | kraken 17:38:57 INFO - operating with platform_type : linux_ 17:38:57 INFO - Initialising browser for kraken test... 17:38:57 INFO - TEST-INFO | started process 13931 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpY0IqMI/profile http://localhost:57997/getInfo.html) 17:39:01 INFO - PROCESS | 13931 | __metrics Screen width/height:1600/1200 17:39:01 INFO - PROCESS | 13931 | colorDepth:24 17:39:01 INFO - PROCESS | 13931 | Browser inner width/height: 1024/697 17:39:01 INFO - PROCESS | 13931 | __metrics 17:39:02 INFO - PROCESS | 13931 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 17:39:02 INFO - PROCESS | 13931 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 17:39:02 INFO - PROCESS | 13931 | console.error: PushService: 17:39:02 INFO - PROCESS | 13931 | stateChangeProcessEnqueue: Error transitioning state 17:39:02 INFO - PROCESS | 13931 | UnknownError 17:39:02 INFO - TEST-INFO | 13931: exit 0 17:39:02 INFO - Browser initialized. 17:39:02 INFO - Running cycle 1/1 for kraken test... 17:39:02 INFO - TEST-INFO | started process 14015 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpY0IqMI/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 17:39:14 INFO - PROCESS | 14015 | RSS: Main: 144076800 17:39:14 INFO - PROCESS | 14015 | 17:39:17 INFO - PROCESS | 14015 | Cycle 1(1): loaded http://localhost:57997/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:57997/tests/kraken/driver.html?testName=audio-beat-detection) 17:39:18 INFO - PROCESS | 14015 | RSS: Main: 199036928 17:39:18 INFO - PROCESS | 14015 | 17:39:20 INFO - PROCESS | 14015 | Cycle 1(1): loaded http://localhost:57997/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:57997/tests/kraken/driver.html?testName=audio-dft) 17:39:21 INFO - PROCESS | 14015 | RSS: Main: 183316480 17:39:21 INFO - PROCESS | 14015 | 17:39:23 INFO - PROCESS | 14015 | Cycle 1(1): loaded http://localhost:57997/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:57997/tests/kraken/driver.html?testName=audio-fft) 17:39:24 INFO - PROCESS | 14015 | RSS: Main: 275132416 17:39:24 INFO - PROCESS | 14015 | 17:39:26 INFO - PROCESS | 14015 | Cycle 1(1): loaded http://localhost:57997/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:57997/tests/kraken/driver.html?testName=audio-oscillator) 17:39:27 INFO - PROCESS | 14015 | RSS: Main: 180699136 17:39:27 INFO - PROCESS | 14015 | 17:39:29 INFO - PROCESS | 14015 | Cycle 1(1): loaded http://localhost:57997/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:57997/tests/kraken/driver.html?testName=imaging-gaussian-blur) 17:39:30 INFO - PROCESS | 14015 | RSS: Main: 180113408 17:39:30 INFO - PROCESS | 14015 | 17:39:33 INFO - PROCESS | 14015 | Cycle 1(1): loaded http://localhost:57997/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:57997/tests/kraken/driver.html?testName=imaging-darkroom) 17:39:33 INFO - PROCESS | 14015 | RSS: Main: 215113728 17:39:33 INFO - PROCESS | 14015 | 17:39:37 INFO - PROCESS | 14015 | Cycle 1(1): loaded http://localhost:57997/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:57997/tests/kraken/driver.html?testName=imaging-desaturate) 17:39:37 INFO - PROCESS | 14015 | RSS: Main: 214597632 17:39:37 INFO - PROCESS | 14015 | 17:39:40 INFO - PROCESS | 14015 | Cycle 1(1): loaded http://localhost:57997/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:57997/tests/kraken/driver.html?testName=json-parse-financial) 17:39:41 INFO - PROCESS | 14015 | RSS: Main: 214220800 17:39:41 INFO - PROCESS | 14015 | 17:39:42 INFO - PROCESS | 14015 | Cycle 1(1): loaded http://localhost:57997/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:57997/tests/kraken/driver.html?testName=json-stringify-tinderbox) 17:39:43 INFO - PROCESS | 14015 | RSS: Main: 175824896 17:39:43 INFO - PROCESS | 14015 | 17:39:44 INFO - PROCESS | 14015 | Cycle 1(1): loaded http://localhost:57997/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:57997/tests/kraken/driver.html?testName=stanford-crypto-aes) 17:39:45 INFO - PROCESS | 14015 | RSS: Main: 182366208 17:39:45 INFO - PROCESS | 14015 | 17:39:47 INFO - PROCESS | 14015 | Cycle 1(1): loaded http://localhost:57997/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:57997/tests/kraken/driver.html?testName=stanford-crypto-ccm) 17:39:48 INFO - PROCESS | 14015 | RSS: Main: 189579264 17:39:48 INFO - PROCESS | 14015 | 17:39:50 INFO - PROCESS | 14015 | Cycle 1(1): loaded http://localhost:57997/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:57997/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 17:39:51 INFO - PROCESS | 14015 | RSS: Main: 192827392 17:39:51 INFO - PROCESS | 14015 | 17:39:53 INFO - PROCESS | 14015 | Cycle 1(1): loaded http://localhost:57997/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:57997/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 17:39:54 INFO - PROCESS | 14015 | RSS: Main: 194523136 17:39:54 INFO - PROCESS | 14015 | 17:39:56 INFO - PROCESS | 14015 | Cycle 1(1): loaded http://localhost:57997/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:57997/tests/kraken/driver.html?testName=ai-astar) 17:39:56 INFO - PROCESS | 14015 | RSS: Main: 243703808 17:39:56 INFO - PROCESS | 14015 | 17:39:56 INFO - PROCESS | 14015 | __start_tp_report 17:39:56 INFO - PROCESS | 14015 | _x_x_mozilla_page_load 17:39:56 INFO - PROCESS | 14015 | _x_x_mozilla_page_load_details 17:39:56 INFO - PROCESS | 14015 | |i|pagename|runs| 17:39:56 INFO - PROCESS | 14015 | |0;ai-astar;112;110;120;111;112;111;110;113;113;112 17:39:56 INFO - PROCESS | 14015 | |1;audio-beat-detection;146;117;146;173;116;147;143;117;181;119 17:39:56 INFO - PROCESS | 14015 | |2;audio-dft;162;160;160;159;163;161;160;160;161;160 17:39:56 INFO - PROCESS | 14015 | |3;audio-fft;75;77;75;178;76;75;76;76;167;82 17:39:56 INFO - PROCESS | 14015 | |4;audio-oscillator;84;83;155;84;84;84;137;83;83;149 17:39:56 INFO - PROCESS | 14015 | |5;imaging-gaussian-blur;118;117;117;117;116;118;119;116;117;117 17:39:56 INFO - PROCESS | 14015 | |6;imaging-darkroom;146;147;146;147;147;146;146;146;146;147 17:39:56 INFO - PROCESS | 14015 | |7;imaging-desaturate;96;96;96;96;95;96;95;96;96;96 17:39:56 INFO - PROCESS | 14015 | |8;json-parse-financial;52;51;51;51;51;52;51;51;51;50 17:39:56 INFO - PROCESS | 14015 | |9;json-stringify-tinderbox;61;61;61;60;60;60;60;61;60;61 17:39:56 INFO - PROCESS | 14015 | |10;stanford-crypto-aes;79;81;75;76;80;85;85;77;83;75 17:39:56 INFO - PROCESS | 14015 | |11;stanford-crypto-ccm;128;120;133;130;121;121;118;124;178;126 17:39:56 INFO - PROCESS | 14015 | |12;stanford-crypto-pbkdf2;157;154;156;156;161;161;161;159;156;154 17:39:56 INFO - PROCESS | 14015 | |13;stanford-crypto-sha256-iterative;63;62;67;64;63;63;61;61;67;61 17:39:56 INFO - PROCESS | 14015 | __end_tp_report 17:39:56 INFO - PROCESS | 14015 | __start_cc_report 17:39:56 INFO - PROCESS | 14015 | _x_x_mozilla_cycle_collect,1687 17:39:56 INFO - PROCESS | 14015 | __end_cc_report 17:39:56 INFO - PROCESS | 14015 | __startTimestamp1476405596378__endTimestamp 17:39:56 INFO - PROCESS | 14015 | 17:39:56 INFO - PROCESS | 14015 | ------- Summary: start ------- 17:39:56 INFO - PROCESS | 14015 | Number of tests: 14 17:39:56 INFO - PROCESS | 14015 | 17:39:56 INFO - PROCESS | 14015 | [#0] ai-astar Cycles:10 Average:112.40 Median:112.00 stddev:2.88 (2.6%) stddev-sans-first:3.05 17:39:56 INFO - PROCESS | 14015 | Values: 112.0 110.0 120.0 111.0 112.0 111.0 110.0 113.0 113.0 112.0 17:39:56 INFO - PROCESS | 14015 | 17:39:56 INFO - PROCESS | 14015 | [#1] audio-beat-detection Cycles:10 Average:140.50 Median:146.00 stddev:23.51 (16.1%) stddev-sans-first:24.85 17:39:56 INFO - PROCESS | 14015 | Values: 146.0 117.0 146.0 173.0 116.0 147.0 143.0 117.0 181.0 119.0 17:39:56 INFO - PROCESS | 14015 | 17:39:56 INFO - PROCESS | 14015 | [#2] audio-dft Cycles:10 Average:160.60 Median:160.50 stddev:1.17 (0.7%) stddev-sans-first:1.13 17:39:56 INFO - PROCESS | 14015 | Values: 162.0 160.0 160.0 159.0 163.0 161.0 160.0 160.0 161.0 160.0 17:39:56 INFO - PROCESS | 14015 | 17:39:56 INFO - PROCESS | 14015 | [#3] audio-fft Cycles:10 Average:95.70 Median:76.00 stddev:40.61 (53.4%) stddev-sans-first:42.38 17:39:56 INFO - PROCESS | 14015 | Values: 75.0 77.0 75.0 178.0 76.0 75.0 76.0 76.0 167.0 82.0 17:39:56 INFO - PROCESS | 14015 | 17:39:56 INFO - PROCESS | 14015 | [#4] audio-oscillator Cycles:10 Average:102.60 Median:83.50 stddev:30.95 (37.1%) stddev-sans-first:32.08 17:39:56 INFO - PROCESS | 14015 | Values: 84.0 83.0 155.0 84.0 84.0 84.0 137.0 83.0 83.0 149.0 17:39:56 INFO - PROCESS | 14015 | 17:39:56 INFO - PROCESS | 14015 | [#5] imaging-gaussian-blur Cycles:10 Average:117.20 Median:117.00 stddev:0.92 (0.8%) stddev-sans-first:0.93 17:39:56 INFO - PROCESS | 14015 | Values: 118.0 117.0 117.0 117.0 116.0 118.0 119.0 116.0 117.0 117.0 17:39:56 INFO - PROCESS | 14015 | 17:39:56 INFO - PROCESS | 14015 | [#6] imaging-darkroom Cycles:10 Average:146.40 Median:146.50 stddev:0.52 (0.4%) stddev-sans-first:0.53 17:39:56 INFO - PROCESS | 14015 | Values: 146.0 147.0 146.0 147.0 147.0 146.0 146.0 146.0 146.0 147.0 17:39:56 INFO - PROCESS | 14015 | 17:39:56 INFO - PROCESS | 14015 | [#7] imaging-desaturate Cycles:10 Average:95.80 Median:96.00 stddev:0.42 (0.4%) stddev-sans-first:0.44 17:39:56 INFO - PROCESS | 14015 | Values: 96.0 96.0 96.0 96.0 95.0 96.0 95.0 96.0 96.0 96.0 17:39:56 INFO - PROCESS | 14015 | 17:39:56 INFO - PROCESS | 14015 | [#8] json-parse-financial Cycles:10 Average:51.10 Median:51.00 stddev:0.57 (1.1%) stddev-sans-first:0.50 17:39:56 INFO - PROCESS | 14015 | Values: 52.0 51.0 51.0 51.0 51.0 52.0 51.0 51.0 51.0 50.0 17:39:56 INFO - PROCESS | 14015 | 17:39:56 INFO - PROCESS | 14015 | [#9] json-stringify-tinderbox Cycles:10 Average:60.50 Median:61.00 stddev:0.53 (0.9%) stddev-sans-first:0.53 17:39:56 INFO - PROCESS | 14015 | Values: 61.0 61.0 61.0 60.0 60.0 60.0 60.0 61.0 60.0 61.0 17:39:56 INFO - PROCESS | 14015 | 17:39:56 INFO - PROCESS | 14015 | [#10] stanford-crypto-aes Cycles:10 Average:79.60 Median:80.50 stddev:3.86 (4.8%) stddev-sans-first:4.09 17:39:56 INFO - PROCESS | 14015 | Values: 79.0 81.0 75.0 76.0 80.0 85.0 85.0 77.0 83.0 75.0 17:39:56 INFO - PROCESS | 14015 | 17:39:56 INFO - PROCESS | 14015 | [#11] stanford-crypto-ccm Cycles:10 Average:129.90 Median:127.00 stddev:17.56 (13.8%) stddev-sans-first:18.61 17:39:56 INFO - PROCESS | 14015 | Values: 128.0 120.0 133.0 130.0 121.0 121.0 118.0 124.0 178.0 126.0 17:39:56 INFO - PROCESS | 14015 | 17:39:56 INFO - PROCESS | 14015 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:157.50 Median:158.00 stddev:2.80 (1.8%) stddev-sans-first:2.96 17:39:56 INFO - PROCESS | 14015 | Values: 157.0 154.0 156.0 156.0 161.0 161.0 161.0 159.0 156.0 154.0 17:39:56 INFO - PROCESS | 14015 | 17:39:56 INFO - PROCESS | 14015 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:63.20 Median:63.00 stddev:2.25 (3.6%) stddev-sans-first:2.39 17:39:56 INFO - PROCESS | 14015 | Values: 63.0 62.0 67.0 64.0 63.0 63.0 61.0 61.0 67.0 61.0 17:39:56 INFO - PROCESS | 14015 | -------- Summary: end -------- 17:39:56 INFO - PROCESS | 14015 | 17:39:57 INFO - TEST-INFO | 14015: exit 0 17:39:57 INFO - TEST-OK | kraken | took 60141ms 17:39:57 INFO - SUITE-END | took 1173s 17:39:57 INFO - Completed test suite (00:19:33) 17:39:57 INFO - javascript benchmark 17:39:57 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3184.219866132798, 3197.8241196734184, 3214.839258037099, 3244.9188770280753, 3248.5289633003217, 5612.719364031797, 5680.943190568103, 5683.772649094037, 5685.800996965107, 5704.914426283609, 5614.242077319561, 5666.291713535808, 5681.943180568193, 5682.090865461527, 5687.317521897378, 5099.260607211952, 5125.8462246132485, 5137.691738495683, 5181.300524429211, 5189.584833213353, 4984.576311013569, 5077.441481437039, 5201.0898092833795, 5204.687718736869, 5245.108331583621, 5100.617453690969, 5101.362329708786, 5103.336566246401, 5111.539961403473, 5114.488551144893, 5130.230465430197, 5225.190095535198, 5232.738363081869, 5235.947640523584, 5264.789408423659, 5000.0999820032375, 5067.68655346716, 5145.588352931756, 5166.741662916839, 5203.739813009334, 5417.241586177938, 5471.23402723619, 5508.724563771795, 5514.6139770216105, 5516.282883225195, 5956.076808094752, 5985.820425387245, 5989.401059894019, 5990.790322338741, 5991.131285963511, 5846.824595262149, 6003.6697981611, 6058.606190597597, 6085.178500902366, 6088.056351265516, 5590.105583106683, 5679.574031947621, 5691.943080569225, 5706.686132262724, 5712.65724056558, 6220.409061139275, 6224.408681175281, 6232.906506402408, 6234.625922444668, 6235.844103897439, 5729.627574207663, 5806.65160090396, 5857.941420585825, 5888.882222355614, 5916.319623243296, 5992.700364981733, 5994.580379373402, 6006.249218847644, 6021.638701677825, 6026.45761881424, 5965.940340596625, 5967.820965370959, 5972.492338151219, 5972.701364931736, 5985.012472942003, 5667.829965101054, 5767.163761254678, 5920.31916329619, 5953.0, 5969.850753731192, 4486.371907932892, 4600.079984003212, 4603.125406172817, 4636.11913736389, 4636.466806317317, 4123.463949686521, 4137.482814648169, 4138.317177665712, 4145.2124096421585, 4164.770937598461, 5593.244911936837, 5614.831555053355, 5768.2501274834, 5774.133879917962, 5790.10253410722, 5981.850453738604, 6048.0625503047095, 6055.848603784941, 6063.060225665029, 6070.271567411939, 5714.457126572969, 5766.192733017381, 5786.247787787561, 5786.826395208151, 5787.131930210502, 5092.770825312869, 5116.692998420107, 5230.424653288135, 5239.716269513993, 5242.0, 6091.48222401092, 6177.135201071854, 6184.072389141665, 6193.318734939153, 6200.100985357098, 5940.049592065422, 5985.670788106696, 5988.513133184863, 6005.069214271794, 6034.185384972972, 5943.791967281124, 6087.543434242361, 6107.267127944675, 6110.694465276807, 6166.229221347332, 5909.113632954919, 6012.428819262163, 6014.458698717136, 6017.18767966319, 6041.063635136561, 6099.115628233792, 6103.298120716175, 6103.42017508336, 6108.358622344767, 6110.052941794029, 5709.343425505954, 5783.8554036149435, 5973.790917317873, 5975.820725378246, 5977.1034344846885, 5802.796902108406, 5881.794137205177, 5881.852953676193, 5908.615939963888, 5909.674967876808, 5800.24596802413, 5802.941970580409, 5806.4193580641595, 5811.56413269015, 5816.185733997159, 5746.597738158289, 5818.505427038749, 5844.444777746277, 5844.766209351578, 5913.704314784158, 5750.913736294043, 5865.0, 5875.941240587539, 5900.64596124234, 5901.173835663097], "value": 5545.4337860589585, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [576.6338375131791, 622.1973653986357, 653.4347789162374, 659.4032400677389, 668.231533736203, 34787.478187827204, 45244.0, 46336.304955425694, 46437.30344044842, 50808.74595627026, 48219.51780482194, 48509.0, 48759.51240487594, 48766.51233487664, 48935.75532122335, 8462.15378462152, 9263.583138758771, 9419.48192849393, 9424.05759424059, 10874.130069594454, 10843.783124337548, 11210.66368008961, 11222.887771122307, 11227.438628068605, 11229.157813164005, 11347.489362978644, 11387.316760994325, 11388.088952883749, 11390.715232119222, 11397.373144477051, 11243.83134252987, 11266.943665281704, 11291.49188286529, 11305.773884522263, 11319.20765546412, 11223.719407014809, 11281.210315277935, 11288.435578221057, 11298.096152307795, 11305.773884522347, 45604.315935260995, 45850.0, 45965.08069838618, 45972.0, 46077.0, 3630.0743310455928, 3739.0652336915773, 3777.5466943966626, 3817.522809648794, 3890.9221815563815, 33199.33601327984, 33934.49098263528, 34009.48985765215, 34125.48811767826, 34326.0, 4575.519570445122, 4592.609628181609, 4612.215923293048, 4727.432708075019, 4773.11697335994, 380561.0, 384379.0, 386064.06967965263, 386757.0, 388707.0, 61754.0, 61844.690776546726, 61873.381266187665, 62154.37845621486, 62330.688346557974, 38674.41988370177, 39612.80193599014, 39672.80163599221, 42124.368134478005, 65811.0, 14969.176695281647, 15103.0, 15147.091174529563, 15151.848481515044, 15202.0, 11146.832797508045, 11925.70185745346, 12250.448729807002, 12272.38638068093, 12407.93796031014, 20539.4865128373, 20659.45054120947, 20746.79253207479, 20769.0, 20841.68737468939, 7449.0, 7623.809404764815, 7741.18717534662, 7757.9224207758325, 7791.220877912164, 973.3332667123043, 1068.7595291059604, 1077.5205033760058, 1100.389283947417, 1174.618249069056, 35131.824340878135, 35152.29695406129, 35391.0, 35610.64389356125, 35643.10892227715, 1130.0676941523277, 1145.7536629624503, 1161.6050542815483, 1202.1284568687631, 1208.3414539076225, 16285.430009949594, 16533.33866645345, 16571.6685666286, 16581.751273730904, 16667.749983750255, 687636.0, 698371.0, 698874.0, 699850.0, 700120.0, 62312.0, 62639.373606264264, 62742.68628656828, 62846.0, 62850.0, 1260.9432575534122, 1386.4107754204422, 1420.779779134236, 1423.943042278318, 1436.6049336432357, 33835.323293533496, 35695.46456803202, 36137.0, 36428.45357319695, 36902.630973691, 54343.0, 54790.45209547853, 54946.0, 54988.0, 55018.72490637681, 491416.0, 491543.0, 491722.0, 493763.0, 495290.0, 25838.354041149123, 30118.0, 30184.849075754482, 32013.679863202, 32209.194770130933, 51630.0, 51685.22472162845, 52105.47894521158, 52438.0, 53513.732431337594, 44412.77793611011, 45277.0, 45293.0, 45358.54641453675, 46571.30143047789, 43493.56506434895, 44087.11825763403, 44813.32780008237, 45172.32241516445, 45252.321215182455, 44207.33688994603, 45067.54932450633, 45528.317075243234, 45652.771736141105, 45710.31434528418, 35374.292514149056, 38602.0, 38872.805635971636, 39366.26429479089, 39585.406218906166, 29114.27214319659, 29444.558331624616, 29596.0, 29774.851125744233, 29879.850600746857], "value": 21690.277092016946, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [405.1512082187819, 407.4336672025885, 408.91412803311306, 409.4084048549845, 417.2447869356465, 19221.903890480546, 20645.174193032297, 21030.474238144074, 21064.052117654704, 21311.680324795103, 13234.338283085855, 13786.724265514684, 20115.692479988826, 20317.0, 20426.591468170627, 5137.280780690697, 5460.645058071233, 5542.722863856811, 5547.306586676666, 5557.944420555783, 4133.772642504661, 5444.455554444544, 5507.0, 5508.9724551377185, 5511.22842802006, 4463.107378524292, 6613.437857782094, 6628.635425051621, 6704.899426508607, 6711.899321510181, 6049.455549000567, 6214.0989556514505, 6531.150950376445, 6675.632840193787, 6682.465402767767, 6032.758689652409, 6047.879042419127, 6056.06131049688, 6056.394360563909, 6057.939420605826, 14780.113193208334, 14829.480968166063, 15101.773473397909, 15252.0, 15330.616734581614, 2359.6814430051895, 2390.5696974544576, 2536.8731563421757, 2547.46503234321, 2871.3826527296733, 10956.90430956898, 11497.885021149765, 12482.81275780864, 14885.70228595422, 15177.317020734094, 1978.8416926645978, 1995.720599116125, 2043.5402034542258, 2075.6782698681727, 2087.2381580723077, 62249.0, 74721.6263918677, 89643.55178224132, 90175.0, 90424.0, 10804.78390432187, 11047.447627618587, 11052.447377631086, 11302.491387887565, 11365.0, 15200.771988420183, 15267.847321526864, 15268.083914965138, 15324.540263792103, 15362.231888405535, 7808.882866757003, 7937.682492700227, 8055.63749631268, 8113.959430202811, 8152.0, 7325.5970921598755, 7448.925510744932, 7617.390608751287, 7681.347085497795, 7858.0570331560575, 4549.522300158435, 4575.633949284049, 4772.0455908817685, 5026.321446604661, 5121.129408000649, 1900.0, 1943.8542109341856, 1960.784313725489, 1982.603479304145, 1997.530580313625, 950.3490109275168, 1000.2448151645461, 1018.6994836523244, 1051.206339213904, 1072.9356238625708, 20486.897565512376, 21008.89495552543, 21074.78925210759, 21165.365039048855, 21236.044378003025, 990.8018396320765, 991.7917237380086, 997.1474389396961, 1021.2646894236138, 1075.4353964168718, 10801.297915635616, 10993.835092473619, 11521.596744113916, 11993.700157496132, 12463.252204867737, 419529.0, 421934.0, 422617.0, 539405.0, 561899.0, 94051.52974235085, 100178.0, 102847.97152028384, 103002.0, 107619.0, 1025.1696126137851, 1041.3699711674478, 1076.4025965589017, 1077.6928575355985, 1086.9130469562574, 18853.717194241824, 18910.810891890906, 19210.231590736214, 19220.519487012378, 19240.23039078421, 42394.57605423906, 43498.565014350716, 45354.09291814079, 47998.760006199744, 48679.756601216766, 59966.700166498886, 62819.6859015702, 63069.68465157645, 63809.68095159494, 63819.0, 8546.444481108709, 9069.501177435304, 9094.363394562317, 9130.543472826464, 9169.174774270348, 12174.39128043612, 13929.860701392856, 13933.651658708613, 14044.297785110908, 14352.641183970067, 12034.338111403607, 12265.509379625071, 12310.692232694255, 12312.507499699912, 12416.317102559085, 11788.823167652321, 11954.342511161622, 12072.879271207526, 12541.122121451412, 12615.621531354074, 12434.502619895104, 12604.30676312775, 12747.298898560297, 13121.08152429359, 13208.141470804367, 8955.865662015205, 9102.089791020844, 9137.817243654958, 9212.078792121003, 9348.53257337144, 9673.226141908772, 11982.161248712855, 12298.139130260795, 12394.256344619353, 12594.3073130975], "value": 9481.35237410903, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1407.0, 1532.6168457885528, 1564.4915402494191, 1684.2420910590238, 1805.277888844463, 8806.42758220716, 9224.8616270756, 9234.353595248336, 9522.0, 9531.666391676292, 9237.722868313962, 9330.53347332634, 9352.812943741139, 9375.296852736055, 9383.671571494999, 2431.963520547193, 2438.9390265243424, 2447.608382658775, 2457.3242358351417, 2459.409741661997, 2419.511032314778, 2467.654528366021, 2469.80241580674, 2478.47951930094, 2482.6648402465707, 2424.2363655448567, 2435.3789783605157, 2471.7157526884457, 2473.7773600376054, 2477.1082410332265, 2396.808255339569, 2417.190241269168, 2421.576224160765, 2431.489387228684, 2440.877956102188, 2356.15178535727, 2374.2639781667567, 2395.3293077938206, 2398.052769156191, 2399.0403838464754, 9216.49309287989, 9343.71968840929, 9457.668981585612, 9469.857952130724, 9470.431774093508, 5491.505764481216, 5705.400932902027, 5719.885602287973, 5725.541956643458, 5729.627574207663, 3416.6583341665882, 3421.3328400961823, 3496.1958749487762, 3504.54440922681, 3537.8231088445473, 1223.443333283354, 1238.1271203801298, 1256.9371531423392, 1260.974780504394, 1275.5535562553125, 12984.740305193938, 13110.213387196798, 13130.146540474838, 13181.60455186346, 13236.86763132366, 4594.747288899142, 4670.276107203389, 4868.707877527359, 4881.389826271716, 4884.389451318585, 10366.740831479274, 10387.065164135263, 10401.895981040245, 10403.791924161475, 10413.895861041292, 5542.140968149944, 5573.498385145356, 5573.665580065209, 5576.9163462548095, 5586.581006424534, 4580.198465268565, 4661.696989695659, 4741.668083234141, 5131.179011358165, 5240.554552862973, 4081.775502347399, 4099.85650502231, 4191.371294305878, 4191.706580539333, 4330.7401555906745, 1177.6339446242318, 1202.9338386388833, 1204.0427859851438, 1208.275034979023, 1212.751385965875, 1036.6268143468346, 1059.9629012984508, 1060.2472244706191, 1061.2093989977361, 1079.7084787107437, 6445.581037232565, 6486.318936511692, 6525.836854078686, 6597.80206593803, 6729.865402692017, 471.38013512231305, 471.52376100139105, 476.8903152274996, 478.8946431785002, 480.17649730712, 4600.4479462464715, 4638.536146385402, 4673.182193116191, 4808.06242782654, 4974.328465657162, 11551.76896462066, 11968.281903085843, 12130.393480325949, 12215.572454964034, 12227.938860305641, 11844.763104737856, 12065.457054432572, 12070.155089143676, 12082.0, 12223.572174973833, 8971.865422018675, 9189.77025574366, 9190.724278271662, 9197.678081267124, 9206.355555111211, 6738.898916516158, 6850.177978642396, 6857.27998560144, 6872.41584465326, 6886.380225779704, 10758.515866785858, 10894.400807955639, 11205.439728013404, 11350.148738844455, 11383.715407114889, 9934.0, 10840.783184336111, 11155.163362747664, 11218.495167717309, 11389.373584452935, 7980.760577182694, 8278.586070696561, 8700.78248043804, 8701.651933922572, 8702.434341767765, 8791.296696264155, 9059.909400905906, 9103.726888193365, 9127.315451341043, 9147.63409463614, 7803.258690424401, 7876.173001834725, 7877.881831772643, 7996.360291176576, 8144.185581441809, 7825.3739700824935, 8010.8397832041865, 8055.395845311742, 8071.394645401736, 8175.550344731097, 6934.375906168469, 7068.11648543932, 7087.787366379017, 7095.82260443493, 7108.573485590674, 5174.741262936914, 5318.7340632967425, 5351.892962140813, 5502.58730595215, 5512.889742205213, 5817.534597232296, 5862.501687356623, 5921.2006379138265, 5974.342822289458, 6007.879842403215], "value": 4843.006248586593, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [346.2123668653812, 347.08542989223395, 348.75064329004755, 350.39381869365997, 354.3709914901052, 5448.591355648327, 5490.80782172624, 5526.0, 5537.418571050042, 5541.342713808892, 5393.649412788176, 5415.13357862742, 5471.5896307776875, 5476.561875049996, 5479.1781232815, 3891.6108389161, 4097.160082183145, 4261.914761704764, 4307.1601037797645, 4332.7833608319615, 4278.5721427857125, 4295.892602684927, 4323.0, 4329.199098166833, 4330.458692663417, 4267.893302667427, 4277.529471758104, 4302.48370195577, 4317.71934824237, 4328.740275583459, 4210.894727631803, 4246.660267178619, 4261.570904584388, 4327.588879056485, 4348.7825608719595, 3774.735768496206, 3784.299904517673, 3787.981060094682, 3789.5642001169945, 3817.8663746769007, 5243.737813109367, 5253.0, 5285.55072818807, 5311.698633834735, 5313.973430132824, 4740.762961851894, 4879.731614761186, 4946.826861059882, 4954.405471343426, 4962.900741985176, 5660.716964151776, 5665.688387138705, 5674.950134225141, 5676.602637815355, 5691.0, 4167.187398457302, 4181.811818468146, 4196.916061678749, 4207.852725154605, 4208.936865947013, 5797.73910174039, 5869.383714709937, 5905.734241959122, 5912.17229587858, 5932.228810254683, 4249.617534421917, 4255.29787585045, 4261.403403523447, 4263.27337428342, 4268.253055715238, 4308.91382172361, 4327.437433133736, 4338.305871060616, 4357.562004538495, 4360.4767427908855, 4686.976565117153, 4854.271859221074, 4887.535684110004, 4938.802447902043, 4941.975290123526, 5659.547236221092, 5672.602917795714, 5684.204211410406, 5694.572907031989, 5752.194692742935, 1474.2776039740666, 1493.3951749541452, 1511.9244037798287, 1512.7277090123882, 1515.204517628232, 919.0946917286467, 980.2648013989508, 984.8030393921244, 985.7387792235063, 1001.7095042437758, 4846.704241116005, 4893.510648935078, 4912.410510738734, 4925.522343296997, 4966.230234313615, 5104.668196567285, 5118.539331460112, 5129.487051294916, 5143.7685304161405, 5156.74216289184, 4667.066586682677, 4890.95109048912, 4927.704337739747, 4957.330760347307, 4966.0, 2731.7951153663157, 2741.383188782508, 2752.325680208362, 2775.764060054918, 2786.4984302825687, 5263.526282634582, 5323.281357016753, 5331.733413329395, 5342.813001544927, 5374.220737992969, 5226.792432092202, 5230.764615592308, 5522.806701765499, 5992.591740940876, 6017.247844019497, 5783.826485205451, 5928.644281343133, 5937.643741375531, 5958.314793798682, 5972.5520585955355, 5890.14592884038, 5909.55678324136, 5914.556408269483, 5937.851553711019, 5955.970220148872, 5670.659760414388, 5797.188363643714, 5842.561807864342, 5849.64902105875, 5871.853203669772, 6078.331383547717, 6085.204864564876, 6096.664683442454, 6102.786402475892, 6110.816675499742, 4263.211305908417, 4271.359296105609, 4290.1419716057535, 4306.935395969125, 4329.0, 5871.794487192928, 5885.0, 5904.763809447574, 5921.940780592311, 5930.96208163561, 5710.172025056427, 5730.627509211888, 5754.769809207585, 5757.65454072757, 5760.135979603093, 5549.611527193058, 5728.684922329146, 5773.624714393551, 5777.595568310178, 5778.653280803165, 5703.5722320826935, 5713.600047996601, 5716.256886604881, 5717.799877004451, 5720.799772007959, 5714.342850572238, 5763.884722305446, 5771.538276937918, 5804.649117107579, 5805.94194058054, 5331.733413329395, 5608.9158662619275, 5629.662220266797, 5649.491545760901, 5653.643125649896], "value": 4308.727627636322, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [804.5574933786417, 814.857399955008, 816.2979837339886, 874.7681864305958, 928.9117533834292, 13641.522546710867, 15576.610584735387, 15612.453564125259, 15629.609259768496, 15635.84364156358, 12987.80518292224, 13003.219806811598, 13004.414801333938, 13034.543790967318, 13045.08684392093, 532.5952276270043, 836.1554829622065, 854.6026097864492, 860.6901515454463, 870.7648934787603, 860.2859626509982, 860.328943424127, 860.5396113079504, 861.1216559109704, 861.3109512390095, 898.0615257056359, 901.0944001278706, 902.8871391076116, 905.0858632780905, 905.642271302835, 901.0268909577641, 901.292485398962, 901.5987885391004, 902.4585248850702, 902.8735976963263, 664.02720015178, 664.4119953840838, 664.451827242522, 665.6039656404431, 665.9567128136704, 13156.736865262641, 13167.209967401986, 13207.0, 13209.33953302331, 13258.337083145803, 15855.76216356744, 15867.44463943768, 16177.0, 16402.67194656112, 16854.157292135344, 12086.395680215954, 12086.939565302206, 12218.511259549607, 12269.93865030678, 12458.563950261698, 4021.939670904939, 4027.0, 4046.413270075852, 4115.670746340315, 4140.37894315852, 84356.15643843605, 84475.0, 86024.5698771502, 86047.0, 89260.0, 12653.17754345983, 12746.553870614483, 12839.293838838954, 12852.357382131044, 12852.807207891889, 19707.408777736404, 24333.75666243315, 24377.26868193957, 24389.756102439103, 24445.388865278153, 8221.136780638066, 8320.8335833283, 8360.372972027122, 8515.0, 8721.738347849574, 4742.786574604151, 4749.691270067435, 4765.642576806684, 4892.0, 4907.509249075064, 3683.9263214735556, 3835.9424608630893, 3836.4245363195746, 3840.7503512271614, 3841.1741475582326, 624.7282432142033, 629.0846817879974, 639.2137670665107, 645.6836150286326, 666.1573110015834, 644.4779728419995, 698.472653146881, 722.9349358557755, 744.5421066044344, 751.4176513202202, 9618.51907404627, 9968.0, 10136.2904596679, 10372.636957706443, 10630.46847657614, 658.2101478226148, 724.9275072492709, 740.1562219070264, 772.563501621582, 776.2470403708394, 4694.131585656664, 4721.881952951204, 4744.786484608201, 4785.617150627942, 4786.64100192487, 75269.0, 76188.61905690435, 76730.23269767342, 96714.03285967051, 129040.35479822541, 110478.0, 112644.43677781723, 112690.0, 113512.0, 115733.0, 98285.50857245667, 98488.50755746319, 101292.0, 103154.0, 104224.0, 8726.214640682369, 9142.953693856616, 9241.0, 9314.161725444743, 9344.392614480037, 51135.0, 59652.10521842089, 59893.502662433784, 60539.0, 62011.68994155, 50400.49599503958, 52345.21482177846, 53351.46648533465, 60200.0, 64107.35892641014, 13231.404586793426, 13427.0, 13564.525241616497, 13764.93117534446, 14145.858541414866, 15256.313465894284, 15826.841731582997, 16005.759913601538, 16037.839621604102, 16276.755848662042, 15155.457136331606, 15208.011479254255, 15279.388824446898, 15344.386224550894, 15630.062196267805, 15255.0, 15496.767548487007, 15500.147491887603, 15584.688306234038, 16628.3348666052, 13778.793318100437, 14117.2235527047, 14405.855941440452, 14515.564533063602, 15442.0, 10652.0, 10960.232783705063, 11503.367314797766, 11682.47428865686, 11761.353125577833, 11616.0, 11937.82093268619, 12154.696132596755, 12160.391980401122, 12180.695482612651], "value": 7810.101089950987, "unit": "score"}], "name": "dromaeo_css", "value": 7554.507430105411, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 110.0, 120.0, 111.0, 112.0, 111.0, 110.0, 113.0, 113.0, 112.0], "value": 112.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 117.0, 146.0, 173.0, 116.0, 147.0, 143.0, 117.0, 181.0, 119.0], "value": 140.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 160.0, 160.0, 159.0, 163.0, 161.0, 160.0, 160.0, 161.0, 160.0], "value": 160.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 77.0, 75.0, 178.0, 76.0, 75.0, 76.0, 76.0, 167.0, 82.0], "value": 95.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 83.0, 155.0, 84.0, 84.0, 84.0, 137.0, 83.0, 83.0, 149.0], "value": 102.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [118.0, 117.0, 117.0, 117.0, 116.0, 118.0, 119.0, 116.0, 117.0, 117.0], "value": 117.2, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 147.0, 146.0, 147.0, 147.0, 146.0, 146.0, 146.0, 146.0, 147.0], "value": 146.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 96.0, 96.0, 96.0, 95.0, 96.0, 95.0, 96.0, 96.0, 96.0], "value": 95.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [52.0, 51.0, 51.0, 51.0, 51.0, 52.0, 51.0, 51.0, 51.0, 50.0], "value": 51.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 61.0, 61.0, 60.0, 60.0, 60.0, 60.0, 61.0, 60.0, 61.0], "value": 60.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 81.0, 75.0, 76.0, 80.0, 85.0, 85.0, 77.0, 83.0, 75.0], "value": 79.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [128.0, 120.0, 133.0, 130.0, 121.0, 121.0, 118.0, 124.0, 178.0, 126.0], "value": 129.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [157.0, 154.0, 156.0, 156.0, 161.0, 161.0, 161.0, 159.0, 156.0, 154.0], "value": 157.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 62.0, 67.0, 64.0, 63.0, 63.0, 61.0, 61.0, 67.0, 61.0], "value": 63.2, "unit": "score"}], "name": "kraken", "value": 1513.0, "alertThreshold": 2.0}]} 17:39:57 INFO - Return code: 0 17:39:57 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 17:39:57 INFO - # TBPL SUCCESS # 17:39:57 INFO - Running post-action listener: _resource_record_post_action 17:39:57 INFO - [mozharness: 2016-10-14 00:39:57.682559Z] Finished run-tests step (success) 17:39:57 INFO - Running post-run listener: _resource_record_post_run 17:39:57 INFO - Total resource usage - Wall time: 1187s; CPU: 13.0%; Read bytes: 4096; Write bytes: 289890304; Read time: 12; Write time: 308620 17:39:57 INFO - TinderboxPrint: CPU usage
12.9% 17:39:57 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 12 17:39:57 INFO - TinderboxPrint: I/O write bytes / time
289,890,304 / 308,620 17:39:57 INFO - TinderboxPrint: CPU idle
8,247.3 (87.0%) 17:39:57 INFO - TinderboxPrint: CPU user
1,182.2 (12.5%) 17:39:57 INFO - TinderboxPrint: Swap in / out
0 / 0 17:39:57 INFO - install - Wall time: 14s; CPU: 13.0%; Read bytes: 0; Write bytes: 70111232; Read time: 0; Write time: 122540 17:39:57 INFO - run-tests - Wall time: 1174s; CPU: 13.0%; Read bytes: 4096; Write bytes: 219779072; Read time: 12; Write time: 186080 17:39:58 INFO - Running post-run listener: _upload_blobber_files 17:39:58 INFO - Blob upload gear active. 17:39:58 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 17:39:58 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 17:39:58 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', 'Mozilla-Beta', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 17:39:58 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 Mozilla-Beta -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 17:39:58 INFO - (blobuploader) - INFO - Open directory for files ... 17:39:58 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 17:39:58 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:39:58 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:40:02 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 17:40:02 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:40:02 INFO - (blobuploader) - INFO - Done attempting. 17:40:02 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 17:40:02 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:40:02 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:40:03 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 17:40:03 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:40:03 INFO - (blobuploader) - INFO - Done attempting. 17:40:03 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 17:40:03 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:40:03 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:40:04 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 17:40:04 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:40:04 INFO - (blobuploader) - INFO - Done attempting. 17:40:04 INFO - (blobuploader) - INFO - Iteration through files over. 17:40:04 INFO - Return code: 0 17:40:04 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 17:40:04 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 17:40:04 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/f0d87d9f4590dc71679af5df6ddbb77f23f9a954fe77777f19dde0957c08ee3c84e33a3ca374bc3dcc7d7a9b41fad90bbd407a02348ef3aa6a37b2cdeb603d92", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/b91c16c3e9e70bd3cff0611fbe7d7d81cda59f32e9d4e245bc73431d53a3cc5218e20c7af64e5471fece8c33ffc5ca823a13be4a9390db38eaa9c65f98c50ac5", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/c3ce5400900451bc1e6ba9142dbc6e4dbdd6c5365be89bc7aa332e01e669883515ccc0e787a5356f75b88e60e84f4224ba55bb9ea2912786bea9695d3515ab54"} 17:40:04 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 17:40:04 INFO - Writing to file /builds/slave/test/properties/blobber_files 17:40:04 INFO - Contents: 17:40:04 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/f0d87d9f4590dc71679af5df6ddbb77f23f9a954fe77777f19dde0957c08ee3c84e33a3ca374bc3dcc7d7a9b41fad90bbd407a02348ef3aa6a37b2cdeb603d92", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/b91c16c3e9e70bd3cff0611fbe7d7d81cda59f32e9d4e245bc73431d53a3cc5218e20c7af64e5471fece8c33ffc5ca823a13be4a9390db38eaa9c65f98c50ac5", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/c3ce5400900451bc1e6ba9142dbc6e4dbdd6c5365be89bc7aa332e01e669883515ccc0e787a5356f75b88e60e84f4224ba55bb9ea2912786bea9695d3515ab54"} 17:40:04 INFO - Running post-run listener: copy_logs_to_upload_dir 17:40:04 INFO - Copying logs to upload dir... 17:40:04 INFO - mkdir: /builds/slave/test/build/upload/logs 17:40:04 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1675.081345 ========= master_lag: -0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 27 mins, 55 secs) (at 2016-10-13 17:40:04.315570) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-13 17:40:04.319203) ========= 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=52faae308a556c440401f8e5000001e8-1476403927.519022-1795746679 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/f0d87d9f4590dc71679af5df6ddbb77f23f9a954fe77777f19dde0957c08ee3c84e33a3ca374bc3dcc7d7a9b41fad90bbd407a02348ef3aa6a37b2cdeb603d92", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/b91c16c3e9e70bd3cff0611fbe7d7d81cda59f32e9d4e245bc73431d53a3cc5218e20c7af64e5471fece8c33ffc5ca823a13be4a9390db38eaa9c65f98c50ac5", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/c3ce5400900451bc1e6ba9142dbc6e4dbdd6c5365be89bc7aa332e01e669883515ccc0e787a5356f75b88e60e84f4224ba55bb9ea2912786bea9695d3515ab54"} build_url:https://queue.taskcluster.net/v1/task/Vls0TAmiRpOLcQL-8gSWBQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011233 build_url: 'https://queue.taskcluster.net/v1/task/Vls0TAmiRpOLcQL-8gSWBQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/f0d87d9f4590dc71679af5df6ddbb77f23f9a954fe77777f19dde0957c08ee3c84e33a3ca374bc3dcc7d7a9b41fad90bbd407a02348ef3aa6a37b2cdeb603d92", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/b91c16c3e9e70bd3cff0611fbe7d7d81cda59f32e9d4e245bc73431d53a3cc5218e20c7af64e5471fece8c33ffc5ca823a13be4a9390db38eaa9c65f98c50ac5", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/c3ce5400900451bc1e6ba9142dbc6e4dbdd6c5365be89bc7aa332e01e669883515ccc0e787a5356f75b88e60e84f4224ba55bb9ea2912786bea9695d3515ab54"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-13 17:40:04.349277) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-13 17:40:04.349629) ========= 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=52faae308a556c440401f8e5000001e8-1476403927.519022-1795746679 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004410 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-13 17:40:04.434732) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-13 17:40:04.435466) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-13 17:40:04.436148) ========= ========= Total master_lag: 0.15 =========