builder: mozilla-beta_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-028 starttime: 1475730489.49 results: success (0) buildid: 20161005190701 builduid: c2228453a02a42859dc206dbaa894f87 revision: d567c767741298d727a5edc8aa1784f8d8e2b6f1 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-10-05 22:08:09.495088) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-10-05 22:08:09.496083) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-05 22:08:09.496430) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-05 22:08:09.529325) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-05 22:08:09.529806) ========= 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=836cfdc75598d54cb747368e000001a9-1475729294.694804-862164654 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-10-05 22:08:10-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 26.4M=0s 2016-10-05 22:08:10 (26.4 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.115664 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-05 22:08:09.661468) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-05 22:08:09.661802) ========= 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=836cfdc75598d54cb747368e000001a9-1475729294.694804-862164654 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.017990 ========= master_lag: 0.05 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-05 22:08:09.726089) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-10-05 22:08:09.726458) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev d567c767741298d727a5edc8aa1784f8d8e2b6f1 --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 d567c767741298d727a5edc8aa1784f8d8e2b6f1 --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=836cfdc75598d54cb747368e000001a9-1475729294.694804-862164654 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-10-05 22:08:10,641 truncating revision to first 12 chars 2016-10-05 22:08:10,641 Setting DEBUG logging. 2016-10-05 22:08:10,641 attempt 1/10 2016-10-05 22:08:10,641 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/d567c7677412?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-10-05 22:08:11,740 unpacking tar archive at: mozilla-beta-d567c7677412/testing/mozharness/ program finished with exit code 0 elapsedTime=1.443472 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-10-05 22:08:11.187531) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-05 22:08:11.187918) ========= script_repo_revision: d567c767741298d727a5edc8aa1784f8d8e2b6f1 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-05 22:08:11.188378) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-05 22:08:11.188816) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-05 22:08:11.199855) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 27 mins, 55 secs) (at 2016-10-05 22:08:11.200193) ========= /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=836cfdc75598d54cb747368e000001a9-1475729294.694804-862164654 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 22:08:12 INFO - MultiFileLogger online at 20161005 22:08:12 in /builds/slave/test 22:08:12 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 22:08:12 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 22:08:12 INFO - {'append_to_log': False, 22:08:12 INFO - 'base_work_dir': '/builds/slave/test', 22:08:12 INFO - 'blob_upload_branch': 'Mozilla-Beta', 22:08:12 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 22:08:12 INFO - 'branch': 'Mozilla-Beta', 22:08:12 INFO - 'buildbot_json_path': 'buildprops.json', 22:08:12 INFO - 'config_files': ('talos/linux_config.py',), 22:08:12 INFO - 'default_actions': ('clobber', 22:08:12 INFO - 'read-buildbot-config', 22:08:12 INFO - 'download-and-extract', 22:08:12 INFO - 'populate-webroot', 22:08:12 INFO - 'create-virtualenv', 22:08:12 INFO - 'install', 22:08:12 INFO - 'run-tests'), 22:08:12 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 22:08:12 INFO - 'download_minidump_stackwalk': True, 22:08:12 INFO - 'download_symbols': 'ondemand', 22:08:12 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 22:08:12 INFO - 'tooltool.py': '/tools/tooltool.py', 22:08:12 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 22:08:12 INFO - '/tools/misc-python/virtualenv.py')}, 22:08:12 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 22:08:12 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 22:08:12 INFO - 'installer_path': 'installer.exe', 22:08:12 INFO - 'log_level': 'info', 22:08:12 INFO - 'log_name': 'talos', 22:08:12 INFO - 'log_to_console': True, 22:08:12 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 22:08:12 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 22:08:12 INFO - 'opt_config_files': (), 22:08:12 INFO - 'pip_index': False, 22:08:12 INFO - 'sps_profile': False, 22:08:12 INFO - 'sps_profile_interval': 0, 22:08:12 INFO - 'suite': 'dromaeojs', 22:08:12 INFO - 'system_bits': '32', 22:08:12 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 22:08:12 INFO - 'title': 'talos-linux64-ix-028', 22:08:12 INFO - 'tooltool_cache': '/builds/tooltool_cache', 22:08:12 INFO - 'use_talos_json': True, 22:08:12 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 22:08:12 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 22:08:12 INFO - 'work_dir': 'build'} 22:08:12 INFO - [mozharness: 2016-10-06 05:08:12.204426Z] Running clobber step. 22:08:12 INFO - Running pre-action listener: _resource_record_pre_action 22:08:12 INFO - Running main action method: clobber 22:08:12 INFO - rmtree: /builds/slave/test/build 22:08:12 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 22:08:12 INFO - Running post-action listener: _resource_record_post_action 22:08:12 INFO - [mozharness: 2016-10-06 05:08:12.660576Z] Finished clobber step (success) 22:08:12 INFO - [mozharness: 2016-10-06 05:08:12.660677Z] Running read-buildbot-config step. 22:08:12 INFO - Running pre-action listener: _resource_record_pre_action 22:08:12 INFO - Running main action method: read_buildbot_config 22:08:12 INFO - Using buildbot properties: 22:08:12 INFO - { 22:08:12 INFO - "project": "", 22:08:12 INFO - "product": "firefox", 22:08:12 INFO - "script_repo_revision": "production", 22:08:12 INFO - "builddir": "mozilla-beta_ubuntu64_hw_test-dromaeojs", 22:08:12 INFO - "repository": "", 22:08:12 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-beta pgo talos dromaeojs", 22:08:12 INFO - "buildid": "20161005190701", 22:08:12 INFO - "pgo_build": "True", 22:08:12 INFO - "basedir": "/builds/slave/test", 22:08:12 INFO - "buildnumber": 266, 22:08:12 INFO - "slavename": "talos-linux64-ix-028", 22:08:12 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 22:08:12 INFO - "platform": "ubuntu64_hw", 22:08:12 INFO - "scheduler": "tests-mozilla-beta-linux64-pgo-talos", 22:08:12 INFO - "branch": "mozilla-beta", 22:08:12 INFO - "repo_path": "releases/mozilla-beta", 22:08:12 INFO - "revision": "d567c767741298d727a5edc8aa1784f8d8e2b6f1", 22:08:12 INFO - "stage_platform": "linux64-pgo", 22:08:12 INFO - "builduid": "c2228453a02a42859dc206dbaa894f87", 22:08:12 INFO - "slavebuilddir": "test" 22:08:12 INFO - } 22:08:12 INFO - Found installer url https://queue.taskcluster.net/v1/task/W0j6x-DDSriCU4DAUoEGzw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2. 22:08:12 INFO - Running post-action listener: _resource_record_post_action 22:08:12 INFO - [mozharness: 2016-10-06 05:08:12.663121Z] Finished read-buildbot-config step (success) 22:08:12 INFO - [mozharness: 2016-10-06 05:08:12.663211Z] Running download-and-extract step. 22:08:12 INFO - Running pre-action listener: _resource_record_pre_action 22:08:12 INFO - Running main action method: download_and_extract 22:08:12 INFO - mkdir: /builds/slave/test/build/tests 22:08: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')]} 22:08:12 INFO - https://queue.taskcluster.net/v1/task/W0j6x-DDSriCU4DAUoEGzw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 22:08:12 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/W0j6x-DDSriCU4DAUoEGzw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 22:08:12 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/W0j6x-DDSriCU4DAUoEGzw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 22:08:12 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/W0j6x-DDSriCU4DAUoEGzw/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 22:08:12 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/W0j6x-DDSriCU4DAUoEGzw/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 22:08:12 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/W0j6x-DDSriCU4DAUoEGzw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 22:08:12 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 22:08:12 INFO - retry: Failed, sleeping 30 seconds before retrying 22:08:42 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/W0j6x-DDSriCU4DAUoEGzw/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 22:08:42 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/W0j6x-DDSriCU4DAUoEGzw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 22:08:42 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 22:08:42 INFO - retry: Failed, sleeping 60 seconds before retrying 22:09:42 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/W0j6x-DDSriCU4DAUoEGzw/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 22:09:42 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/W0j6x-DDSriCU4DAUoEGzw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 22:09:42 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 22:09:42 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/W0j6x-DDSriCU4DAUoEGzw/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! 22:09:42 INFO - Caught exception: HTTP Error 404: Not Found 22:09:42 INFO - Caught exception: HTTP Error 404: Not Found 22:09:42 INFO - Caught exception: HTTP Error 404: Not Found 22:09:42 INFO - trying https://queue.taskcluster.net/v1/task/W0j6x-DDSriCU4DAUoEGzw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 22:09:42 INFO - Downloading https://queue.taskcluster.net/v1/task/W0j6x-DDSriCU4DAUoEGzw/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 22:09:42 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/W0j6x-DDSriCU4DAUoEGzw/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 22:09:43 INFO - Downloaded 1327 bytes. 22:09:43 INFO - Reading from file /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 22:09:43 INFO - Using the following test package requirements: 22:09:43 INFO - {u'common': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip'], 22:09:43 INFO - u'cppunittest': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 22:09:43 INFO - u'firefox-50.0.en-US.linux-x86_64.cppunittest.tests.zip'], 22:09:43 INFO - u'gtest': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 22:09:43 INFO - u'firefox-50.0.en-US.linux-x86_64.gtest.tests.zip'], 22:09:43 INFO - u'jittest': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 22:09:43 INFO - u'jsshell-linux-x86_64.zip'], 22:09:43 INFO - u'mochitest': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 22:09:43 INFO - u'firefox-50.0.en-US.linux-x86_64.mochitest.tests.zip'], 22:09:43 INFO - u'mozbase': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip'], 22:09:43 INFO - u'reftest': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 22:09:43 INFO - u'firefox-50.0.en-US.linux-x86_64.reftest.tests.zip'], 22:09:43 INFO - u'talos': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 22:09:43 INFO - u'firefox-50.0.en-US.linux-x86_64.talos.tests.zip'], 22:09:43 INFO - u'web-platform': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 22:09:43 INFO - u'firefox-50.0.en-US.linux-x86_64.web-platform.tests.zip'], 22:09:43 INFO - u'xpcshell': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 22:09:43 INFO - u'firefox-50.0.en-US.linux-x86_64.xpcshell.tests.zip']} 22:09:43 INFO - Downloading packages: [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 22:09:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 22:09:43 INFO - https://queue.taskcluster.net/v1/task/W0j6x-DDSriCU4DAUoEGzw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 22:09:43 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/W0j6x-DDSriCU4DAUoEGzw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 22:09:43 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/W0j6x-DDSriCU4DAUoEGzw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 22:09:43 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/W0j6x-DDSriCU4DAUoEGzw/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 22:09:43 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/W0j6x-DDSriCU4DAUoEGzw/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 22:09:43 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/W0j6x-DDSriCU4DAUoEGzw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 22:09:43 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 22:09:43 INFO - retry: Failed, sleeping 30 seconds before retrying 22:10:13 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/W0j6x-DDSriCU4DAUoEGzw/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 22:10:13 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/W0j6x-DDSriCU4DAUoEGzw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 22:10:13 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 22:10:13 INFO - retry: Failed, sleeping 60 seconds before retrying 22:11:13 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/W0j6x-DDSriCU4DAUoEGzw/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 22:11:13 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/W0j6x-DDSriCU4DAUoEGzw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 22:11:13 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 22:11:13 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/W0j6x-DDSriCU4DAUoEGzw/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! 22:11:13 INFO - Caught exception: HTTP Error 404: Not Found 22:11:13 INFO - Caught exception: HTTP Error 404: Not Found 22:11:13 INFO - Caught exception: HTTP Error 404: Not Found 22:11:13 INFO - trying https://queue.taskcluster.net/v1/task/W0j6x-DDSriCU4DAUoEGzw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 22:11:13 INFO - Downloading https://queue.taskcluster.net/v1/task/W0j6x-DDSriCU4DAUoEGzw/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 22:11:13 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/W0j6x-DDSriCU4DAUoEGzw/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 22:11:14 INFO - Downloaded 21771858 bytes. 22:11:14 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'] 22:11:14 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 22:11:15 INFO - Return code: 0 22:11:15 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 22:11:15 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')]} 22:11:15 INFO - https://queue.taskcluster.net/v1/task/W0j6x-DDSriCU4DAUoEGzw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 22:11:15 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/W0j6x-DDSriCU4DAUoEGzw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 22:11:15 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/W0j6x-DDSriCU4DAUoEGzw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 22:11:15 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/W0j6x-DDSriCU4DAUoEGzw/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 22:11:15 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/W0j6x-DDSriCU4DAUoEGzw/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 22:11:15 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/W0j6x-DDSriCU4DAUoEGzw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 22:11:15 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 22:11:15 INFO - retry: Failed, sleeping 30 seconds before retrying 22:11:45 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/W0j6x-DDSriCU4DAUoEGzw/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 22:11:45 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/W0j6x-DDSriCU4DAUoEGzw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 22:11:45 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 22:11:45 INFO - retry: Failed, sleeping 60 seconds before retrying 22:12:45 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/W0j6x-DDSriCU4DAUoEGzw/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 22:12:45 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/W0j6x-DDSriCU4DAUoEGzw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 22:12:45 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 22:12:45 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/W0j6x-DDSriCU4DAUoEGzw/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! 22:12:45 INFO - Caught exception: HTTP Error 404: Not Found 22:12:45 INFO - Caught exception: HTTP Error 404: Not Found 22:12:45 INFO - Caught exception: HTTP Error 404: Not Found 22:12:45 INFO - trying https://queue.taskcluster.net/v1/task/W0j6x-DDSriCU4DAUoEGzw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 22:12:45 INFO - Downloading https://queue.taskcluster.net/v1/task/W0j6x-DDSriCU4DAUoEGzw/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 22:12:45 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/W0j6x-DDSriCU4DAUoEGzw/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 22:12:46 INFO - Downloaded 21771858 bytes. 22:12:46 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'] 22:12:46 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 22:12:47 INFO - Return code: 0 22:12:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 22:12:47 INFO - https://queue.taskcluster.net/v1/task/W0j6x-DDSriCU4DAUoEGzw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 22:12:47 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/W0j6x-DDSriCU4DAUoEGzw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 22:12:47 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/W0j6x-DDSriCU4DAUoEGzw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 22:12:47 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/W0j6x-DDSriCU4DAUoEGzw/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 22:12:47 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/W0j6x-DDSriCU4DAUoEGzw/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 22:12:47 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/W0j6x-DDSriCU4DAUoEGzw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 22:12:47 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 22:12:47 INFO - retry: Failed, sleeping 30 seconds before retrying 22:13:17 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/W0j6x-DDSriCU4DAUoEGzw/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 22:13:17 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/W0j6x-DDSriCU4DAUoEGzw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 22:13:17 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 22:13:17 INFO - retry: Failed, sleeping 60 seconds before retrying 22:14:17 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/W0j6x-DDSriCU4DAUoEGzw/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 22:14:17 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/W0j6x-DDSriCU4DAUoEGzw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 22:14:17 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 22:14:17 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/W0j6x-DDSriCU4DAUoEGzw/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! 22:14:17 INFO - Caught exception: HTTP Error 404: Not Found 22:14:17 INFO - Caught exception: HTTP Error 404: Not Found 22:14:17 INFO - Caught exception: HTTP Error 404: Not Found 22:14:17 INFO - trying https://queue.taskcluster.net/v1/task/W0j6x-DDSriCU4DAUoEGzw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 22:14:17 INFO - Downloading https://queue.taskcluster.net/v1/task/W0j6x-DDSriCU4DAUoEGzw/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 22:14:17 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/W0j6x-DDSriCU4DAUoEGzw/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 22:14:18 INFO - Downloaded 14413740 bytes. 22:14:18 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'] 22:14:18 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 22:14:18 INFO - Return code: 0 22:14:18 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')]} 22:14:18 INFO - https://queue.taskcluster.net/v1/task/W0j6x-DDSriCU4DAUoEGzw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 22:14:18 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/W0j6x-DDSriCU4DAUoEGzw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 22:14:18 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/W0j6x-DDSriCU4DAUoEGzw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 22:14:18 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/W0j6x-DDSriCU4DAUoEGzw/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 22:14:18 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/W0j6x-DDSriCU4DAUoEGzw/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 22:14:18 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/W0j6x-DDSriCU4DAUoEGzw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 22:14:18 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 22:14:18 INFO - retry: Failed, sleeping 30 seconds before retrying 22:14:48 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/W0j6x-DDSriCU4DAUoEGzw/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 22:14:48 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/W0j6x-DDSriCU4DAUoEGzw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 22:14:48 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 22:14:48 INFO - retry: Failed, sleeping 60 seconds before retrying 22:15:48 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/W0j6x-DDSriCU4DAUoEGzw/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 22:15:49 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/W0j6x-DDSriCU4DAUoEGzw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 22:15:49 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 22:15:49 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/W0j6x-DDSriCU4DAUoEGzw/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! 22:15:49 INFO - Caught exception: HTTP Error 404: Not Found 22:15:49 INFO - Caught exception: HTTP Error 404: Not Found 22:15:49 INFO - Caught exception: HTTP Error 404: Not Found 22:15:49 INFO - trying https://queue.taskcluster.net/v1/task/W0j6x-DDSriCU4DAUoEGzw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 22:15:49 INFO - Downloading https://queue.taskcluster.net/v1/task/W0j6x-DDSriCU4DAUoEGzw/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 22:15:49 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/W0j6x-DDSriCU4DAUoEGzw/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 22:15:50 INFO - Downloaded 56798681 bytes. 22:15:50 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/W0j6x-DDSriCU4DAUoEGzw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 22:15:50 INFO - mkdir: /builds/slave/test/properties 22:15:50 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 22:15:50 INFO - Writing to file /builds/slave/test/properties/build_url 22:15:50 INFO - Contents: 22:15:50 INFO - build_url:https://queue.taskcluster.net/v1/task/W0j6x-DDSriCU4DAUoEGzw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 22:15:50 INFO - Running post-action listener: _resource_record_post_action 22:15:50 INFO - Running post-action listener: set_extra_try_arguments 22:15:50 INFO - [mozharness: 2016-10-06 05:15:50.876813Z] Finished download-and-extract step (success) 22:15:50 INFO - [mozharness: 2016-10-06 05:15:50.877047Z] Running populate-webroot step. 22:15:50 INFO - Running pre-action listener: _resource_record_pre_action 22:15:50 INFO - Running main action method: populate_webroot 22:15:50 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 22:15:50 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 22:15:50 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 22:15:50 INFO - '--tppagecycles', 22:15:50 INFO - '7'], 22:15:50 INFO - 'tests': ['tsvgm']}}, 22:15:50 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 22:15:50 INFO - 'tests': ['tresize', 'tcanvasmark']}, 22:15:50 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 22:15:50 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 22:15:50 INFO - 'tests': ['dromaeo_css', 'kraken']}, 22:15:50 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 22:15:50 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:15:50 INFO - 'talos_options': ['--disable-e10s'], 22:15:50 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 22:15:50 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:15:50 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 22:15:50 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:15:50 INFO - 'talos_options': ['--disable-e10s'], 22:15:50 INFO - 'tests': ['damp', 'tps']}, 22:15:50 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:15:50 INFO - 'tests': ['damp', 'tps']}, 22:15:50 INFO - 'g3': {'talos_options': ['--disable-e10s'], 22:15:50 INFO - 'tests': ['dromaeo_dom']}, 22:15:50 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 22:15:50 INFO - 'g4': {'talos_options': ['--disable-e10s'], 22:15:50 INFO - 'tests': ['basic_compositor_video']}, 22:15:50 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 22:15:50 INFO - 'other': {'talos_options': ['--disable-e10s'], 22:15:50 INFO - 'tests': ['a11yr', 22:15:50 INFO - 'ts_paint', 22:15:50 INFO - 'tpaint', 22:15:50 INFO - 'sessionrestore', 22:15:50 INFO - 'sessionrestore_no_auto_restore', 22:15:50 INFO - 'tabpaint']}, 22:15:50 INFO - 'other-e10s': {'tests': ['a11yr', 22:15:50 INFO - 'ts_paint', 22:15:50 INFO - 'tpaint', 22:15:50 INFO - 'sessionrestore', 22:15:50 INFO - 'sessionrestore_no_auto_restore', 22:15:50 INFO - 'tabpaint']}, 22:15:50 INFO - 'other-e10s_l64': {'tests': ['a11yr', 22:15:50 INFO - 'ts_paint', 22:15:50 INFO - 'tpaint', 22:15:50 INFO - 'sessionrestore', 22:15:50 INFO - 'sessionrestore_no_auto_restore', 22:15:50 INFO - 'tabpaint']}, 22:15:50 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 22:15:50 INFO - 'ts_paint', 22:15:50 INFO - 'tpaint', 22:15:50 INFO - 'sessionrestore', 22:15:50 INFO - 'sessionrestore_no_auto_restore', 22:15:50 INFO - 'tabpaint']}, 22:15:50 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 22:15:50 INFO - 'tests': ['a11yr', 22:15:50 INFO - 'ts_paint', 22:15:50 INFO - 'tpaint', 22:15:50 INFO - 'sessionrestore', 22:15:50 INFO - 'sessionrestore_no_auto_restore', 22:15:50 INFO - 'tabpaint']}, 22:15:50 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 22:15:50 INFO - 'tests': ['a11yr', 22:15:50 INFO - 'ts_paint', 22:15:50 INFO - 'tpaint', 22:15:50 INFO - 'sessionrestore', 22:15:50 INFO - 'sessionrestore_no_auto_restore', 22:15:50 INFO - 'tabpaint']}, 22:15:50 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 22:15:50 INFO - 'tests': ['tsvgx', 22:15:50 INFO - 'tsvgr_opacity', 22:15:50 INFO - 'tart', 22:15:50 INFO - 'tscrollx', 22:15:50 INFO - 'cart']}, 22:15:50 INFO - 'svgr-e10s': {'tests': ['tsvgx', 22:15:50 INFO - 'tsvgr_opacity', 22:15:50 INFO - 'tart', 22:15:50 INFO - 'tscrollx', 22:15:50 INFO - 'cart']}, 22:15:50 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:15:50 INFO - 'talos_options': ['--disable-e10s'], 22:15:50 INFO - 'tests': ['tp5o']}, 22:15:50 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:15:50 INFO - 'tests': ['tp5o']}, 22:15:50 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:15:50 INFO - 'talos_options': ['--disable-e10s', 22:15:50 INFO - '--xperf_path', 22:15:50 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 22:15:50 INFO - 'tests': ['tp5n']}, 22:15:50 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:15:50 INFO - 'talos_options': ['--xperf_path', 22:15:50 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 22:15:50 INFO - 'tests': ['tp5n']}}, 22:15:50 INFO - 'talos.zip': {'path': '', 22:15:50 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 22:15:50 INFO - Running post-action listener: _resource_record_post_action 22:15:50 INFO - [mozharness: 2016-10-06 05:15:50.899521Z] Finished populate-webroot step (success) 22:15:50 INFO - [mozharness: 2016-10-06 05:15:50.899612Z] Running create-virtualenv step. 22:15:50 INFO - Running pre-action listener: _resource_record_pre_action 22:15:50 INFO - Running main action method: create_virtualenv 22:15:50 INFO - Creating virtualenv /builds/slave/test/build/venv 22:15:50 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 22:15:50 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 22:15:50 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 22:15:50 INFO - Using real prefix '/usr' 22:15:50 INFO - New python executable in /builds/slave/test/build/venv/bin/python 22:15:51 INFO - Installing distribute.............................................................................................................................................................................................done. 22:15:53 INFO - Installing pip.................done. 22:15:53 INFO - Return code: 0 22:15:53 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 22:15: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')]} 22:15:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:15:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:15: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')]} 22:15:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:15:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:15: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', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2746030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28a98a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a07180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a053b0>, '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 0x2a062b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a06740>, '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': '836cfdc75598d54cb747368e000001a9-1475729294.694804-862164654', '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 22:15: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', 'pip>=1.5'] in /builds/slave/test/build 22:15: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 pip>=1.5 22:15:53 INFO - Using env: {'DISPLAY': ':0', 22:15:53 INFO - 'HOME': '/home/cltbld', 22:15:53 INFO - 'LANG': 'en_US.UTF-8', 22:15:53 INFO - 'LANGUAGE': 'en_US:en', 22:15:53 INFO - 'LOGNAME': 'cltbld', 22:15:53 INFO - 'MAIL': '/var/mail/cltbld', 22:15:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:15:53 INFO - 'MOZ_NO_REMOTE': '1', 22:15:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:15:53 INFO - 'NO_EM_RESTART': '1', 22:15:53 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:15:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:15:53 INFO - 'PWD': '/builds/slave/test', 22:15:53 INFO - 'SHELL': '/bin/bash', 22:15:53 INFO - 'SHLVL': '1', 22:15:53 INFO - 'TERM': 'linux', 22:15:53 INFO - 'TMOUT': '86400', 22:15:53 INFO - 'USER': 'cltbld', 22:15:53 INFO - 'XDG_SESSION_COOKIE': '836cfdc75598d54cb747368e000001a9-1475729294.694804-862164654', 22:15:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:15:53 INFO - '_': '/tools/buildbot/bin/python'} 22:15:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:15:53 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 22:15:53 INFO - Cleaning up... 22:15:53 INFO - Return code: 0 22:15:53 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 22:15: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')]} 22:15:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:15:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:15: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')]} 22:15:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:15:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:15: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', '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 0x2746030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28a98a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a07180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a053b0>, '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 0x2a062b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a06740>, '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': '836cfdc75598d54cb747368e000001a9-1475729294.694804-862164654', '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 22:15: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', 'psutil>=3.1.1'] in /builds/slave/test/build 22:15: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 psutil>=3.1.1 22:15:53 INFO - Using env: {'DISPLAY': ':0', 22:15:53 INFO - 'HOME': '/home/cltbld', 22:15:53 INFO - 'LANG': 'en_US.UTF-8', 22:15:53 INFO - 'LANGUAGE': 'en_US:en', 22:15:53 INFO - 'LOGNAME': 'cltbld', 22:15:53 INFO - 'MAIL': '/var/mail/cltbld', 22:15:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:15:53 INFO - 'MOZ_NO_REMOTE': '1', 22:15:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:15:53 INFO - 'NO_EM_RESTART': '1', 22:15:53 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:15:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:15:53 INFO - 'PWD': '/builds/slave/test', 22:15:53 INFO - 'SHELL': '/bin/bash', 22:15:53 INFO - 'SHLVL': '1', 22:15:53 INFO - 'TERM': 'linux', 22:15:53 INFO - 'TMOUT': '86400', 22:15:53 INFO - 'USER': 'cltbld', 22:15:53 INFO - 'XDG_SESSION_COOKIE': '836cfdc75598d54cb747368e000001a9-1475729294.694804-862164654', 22:15:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:15:53 INFO - '_': '/tools/buildbot/bin/python'} 22:15:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:15:53 INFO - Downloading/unpacking psutil>=3.1.1 22:15: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 22:15: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 22:15: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 22:15: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 22:15: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 22:15: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 22:15:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 22:15:55 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 22:15:55 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 22:15:55 INFO - Installing collected packages: psutil 22:15:55 INFO - Running setup.py install for psutil 22:15:55 INFO - building 'psutil._psutil_linux' extension 22:15:55 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 22:15:56 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 22:15:56 INFO - building 'psutil._psutil_posix' extension 22:15:56 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 22:15:56 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 22:15:56 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 22:15:56 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 22:15:56 INFO - Successfully installed psutil 22:15:56 INFO - Cleaning up... 22:15:56 INFO - Return code: 0 22:15:56 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 22:15: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')]} 22:15:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:15:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:15: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')]} 22:15:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:15:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:15: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', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2746030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28a98a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a07180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a053b0>, '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 0x2a062b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a06740>, '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': '836cfdc75598d54cb747368e000001a9-1475729294.694804-862164654', '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 22:15: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', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 22:15: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 mozsystemmonitor==0.3 22:15:56 INFO - Using env: {'DISPLAY': ':0', 22:15:56 INFO - 'HOME': '/home/cltbld', 22:15:56 INFO - 'LANG': 'en_US.UTF-8', 22:15:56 INFO - 'LANGUAGE': 'en_US:en', 22:15:56 INFO - 'LOGNAME': 'cltbld', 22:15:56 INFO - 'MAIL': '/var/mail/cltbld', 22:15:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:15:56 INFO - 'MOZ_NO_REMOTE': '1', 22:15:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:15:56 INFO - 'NO_EM_RESTART': '1', 22:15:56 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:15:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:15:56 INFO - 'PWD': '/builds/slave/test', 22:15:56 INFO - 'SHELL': '/bin/bash', 22:15:56 INFO - 'SHLVL': '1', 22:15:56 INFO - 'TERM': 'linux', 22:15:56 INFO - 'TMOUT': '86400', 22:15:56 INFO - 'USER': 'cltbld', 22:15:56 INFO - 'XDG_SESSION_COOKIE': '836cfdc75598d54cb747368e000001a9-1475729294.694804-862164654', 22:15:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:15:56 INFO - '_': '/tools/buildbot/bin/python'} 22:15:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:15:56 INFO - Downloading/unpacking mozsystemmonitor==0.3 22:15: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 22:15: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 22:15: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 22:15: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 22:15: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 22:15: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 22:15:58 INFO - Downloading mozsystemmonitor-0.3.tar.gz 22:15:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 22:15:58 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 22:15:58 INFO - Installing collected packages: mozsystemmonitor 22:15:58 INFO - Running setup.py install for mozsystemmonitor 22:15:58 INFO - Successfully installed mozsystemmonitor 22:15:58 INFO - Cleaning up... 22:15:58 INFO - Return code: 0 22:15:58 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 22:15: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')]} 22:15:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:15:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:15: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')]} 22:15:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:15:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:15: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', '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 0x2746030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28a98a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a07180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a053b0>, '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 0x2a062b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a06740>, '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': '836cfdc75598d54cb747368e000001a9-1475729294.694804-862164654', '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 22:15: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', 'jsonschema==2.5.1'] in /builds/slave/test/build 22:15: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 jsonschema==2.5.1 22:15:58 INFO - Using env: {'DISPLAY': ':0', 22:15:58 INFO - 'HOME': '/home/cltbld', 22:15:58 INFO - 'LANG': 'en_US.UTF-8', 22:15:58 INFO - 'LANGUAGE': 'en_US:en', 22:15:58 INFO - 'LOGNAME': 'cltbld', 22:15:58 INFO - 'MAIL': '/var/mail/cltbld', 22:15:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:15:58 INFO - 'MOZ_NO_REMOTE': '1', 22:15:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:15:58 INFO - 'NO_EM_RESTART': '1', 22:15:58 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:15:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:15:58 INFO - 'PWD': '/builds/slave/test', 22:15:58 INFO - 'SHELL': '/bin/bash', 22:15:58 INFO - 'SHLVL': '1', 22:15:58 INFO - 'TERM': 'linux', 22:15:58 INFO - 'TMOUT': '86400', 22:15:58 INFO - 'USER': 'cltbld', 22:15:58 INFO - 'XDG_SESSION_COOKIE': '836cfdc75598d54cb747368e000001a9-1475729294.694804-862164654', 22:15:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:15:58 INFO - '_': '/tools/buildbot/bin/python'} 22:15:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:15:58 INFO - Downloading/unpacking jsonschema==2.5.1 22:15: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 22:15: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 22:15: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 22:15: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 22:15: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 22:15: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 22:16:00 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 22:16:00 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 22:16:00 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 22:16:00 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 22:16:00 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 22:16:00 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 22:16:00 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 22:16:00 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 22:16:01 INFO - Downloading functools32-3.2.3-2.tar.gz 22:16:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 22:16:01 INFO - warning: no files found matching '*.txt' 22:16:01 INFO - no previously-included directories found matching 'build' 22:16:01 INFO - no previously-included directories found matching 'dist' 22:16:01 INFO - no previously-included directories found matching '.git*' 22:16:01 INFO - Installing collected packages: jsonschema, functools32 22:16:01 INFO - Running setup.py install for functools32 22:16:01 INFO - warning: no files found matching '*.txt' 22:16:01 INFO - no previously-included directories found matching 'build' 22:16:01 INFO - no previously-included directories found matching 'dist' 22:16:01 INFO - no previously-included directories found matching '.git*' 22:16:01 INFO - Successfully installed jsonschema functools32 22:16:01 INFO - Cleaning up... 22:16:01 INFO - Return code: 0 22:16:01 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 22:16:01 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')]} 22:16:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:16:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:16:01 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')]} 22:16:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:16:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:16:01 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 0x2746030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28a98a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a07180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a053b0>, '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 0x2a062b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a06740>, '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': '836cfdc75598d54cb747368e000001a9-1475729294.694804-862164654', '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 22:16:01 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 22:16:01 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 22:16:01 INFO - Using env: {'DISPLAY': ':0', 22:16:01 INFO - 'HOME': '/home/cltbld', 22:16:01 INFO - 'LANG': 'en_US.UTF-8', 22:16:01 INFO - 'LANGUAGE': 'en_US:en', 22:16:01 INFO - 'LOGNAME': 'cltbld', 22:16:01 INFO - 'MAIL': '/var/mail/cltbld', 22:16:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:16:01 INFO - 'MOZ_NO_REMOTE': '1', 22:16:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:16:01 INFO - 'NO_EM_RESTART': '1', 22:16:01 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:16:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:16:01 INFO - 'PWD': '/builds/slave/test', 22:16:01 INFO - 'SHELL': '/bin/bash', 22:16:01 INFO - 'SHLVL': '1', 22:16:01 INFO - 'TERM': 'linux', 22:16:01 INFO - 'TMOUT': '86400', 22:16:01 INFO - 'USER': 'cltbld', 22:16:01 INFO - 'XDG_SESSION_COOKIE': '836cfdc75598d54cb747368e000001a9-1475729294.694804-862164654', 22:16:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:16:01 INFO - '_': '/tools/buildbot/bin/python'} 22:16:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:16:01 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 22:16:01 INFO - Cleaning up... 22:16:01 INFO - Return code: 0 22:16:01 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 22:16:01 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')]} 22:16:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:16:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:16:01 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')]} 22:16:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:16:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:16:01 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 0x2746030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28a98a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a07180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a053b0>, '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 0x2a062b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a06740>, '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': '836cfdc75598d54cb747368e000001a9-1475729294.694804-862164654', '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 22:16:01 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 22:16:01 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 22:16:01 INFO - Using env: {'DISPLAY': ':0', 22:16:01 INFO - 'HOME': '/home/cltbld', 22:16:01 INFO - 'LANG': 'en_US.UTF-8', 22:16:01 INFO - 'LANGUAGE': 'en_US:en', 22:16:01 INFO - 'LOGNAME': 'cltbld', 22:16:01 INFO - 'MAIL': '/var/mail/cltbld', 22:16:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:16:01 INFO - 'MOZ_NO_REMOTE': '1', 22:16:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:16:01 INFO - 'NO_EM_RESTART': '1', 22:16:01 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:16:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:16:01 INFO - 'PWD': '/builds/slave/test', 22:16:01 INFO - 'SHELL': '/bin/bash', 22:16:01 INFO - 'SHLVL': '1', 22:16:01 INFO - 'TERM': 'linux', 22:16:01 INFO - 'TMOUT': '86400', 22:16:01 INFO - 'USER': 'cltbld', 22:16:01 INFO - 'XDG_SESSION_COOKIE': '836cfdc75598d54cb747368e000001a9-1475729294.694804-862164654', 22:16:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:16:01 INFO - '_': '/tools/buildbot/bin/python'} 22:16:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:16:01 INFO - Downloading/unpacking blobuploader==1.2.4 22:16: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 22:16: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 22:16: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 22:16: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 22:16: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 22:16: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 22:16:03 INFO - Downloading blobuploader-1.2.4.tar.gz 22:16:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 22:16:03 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 22:16:03 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 22:16:03 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 22:16:03 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 22:16:03 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 22:16:03 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 22:16:03 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 22:16:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 22:16:04 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 22:16:04 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 22:16:04 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 22:16:04 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 22:16:04 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 22:16:04 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 22:16:04 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 22:16:04 INFO - Downloading docopt-0.6.1.tar.gz 22:16:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 22:16:04 INFO - Installing collected packages: blobuploader, requests, docopt 22:16:04 INFO - Running setup.py install for blobuploader 22:16:04 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 22:16:04 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 22:16:04 INFO - Running setup.py install for requests 22:16:04 INFO - Running setup.py install for docopt 22:16:04 INFO - Successfully installed blobuploader requests docopt 22:16:04 INFO - Cleaning up... 22:16:05 INFO - Return code: 0 22:16:05 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:16: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')]} 22:16:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:16:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:16: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')]} 22:16:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:16:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:16:05 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 0x2746030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28a98a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a07180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a053b0>, '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 0x2a062b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a06740>, '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': '836cfdc75598d54cb747368e000001a9-1475729294.694804-862164654', '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 22:16:05 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 22:16:05 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 22:16:05 INFO - Using env: {'DISPLAY': ':0', 22:16:05 INFO - 'HOME': '/home/cltbld', 22:16:05 INFO - 'LANG': 'en_US.UTF-8', 22:16:05 INFO - 'LANGUAGE': 'en_US:en', 22:16:05 INFO - 'LOGNAME': 'cltbld', 22:16:05 INFO - 'MAIL': '/var/mail/cltbld', 22:16:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:16:05 INFO - 'MOZ_NO_REMOTE': '1', 22:16:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:16:05 INFO - 'NO_EM_RESTART': '1', 22:16:05 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:16:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:16:05 INFO - 'PWD': '/builds/slave/test', 22:16:05 INFO - 'SHELL': '/bin/bash', 22:16:05 INFO - 'SHLVL': '1', 22:16:05 INFO - 'TERM': 'linux', 22:16:05 INFO - 'TMOUT': '86400', 22:16:05 INFO - 'USER': 'cltbld', 22:16:05 INFO - 'XDG_SESSION_COOKIE': '836cfdc75598d54cb747368e000001a9-1475729294.694804-862164654', 22:16:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:16:05 INFO - '_': '/tools/buildbot/bin/python'} 22:16:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:16:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 22:16:05 INFO - Running setup.py (path:/tmp/pip-m2dmss-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 22:16:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 22:16:05 INFO - Running setup.py (path:/tmp/pip-CvI6Jf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 22:16:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 22:16:05 INFO - Running setup.py (path:/tmp/pip-AMwoQB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 22:16:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 22:16:05 INFO - Running setup.py (path:/tmp/pip-16ssKu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 22:16:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 22:16:05 INFO - Running setup.py (path:/tmp/pip-1Aigm9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 22:16:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 22:16:05 INFO - Running setup.py (path:/tmp/pip-WtzgfY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 22:16:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 22:16:05 INFO - Running setup.py (path:/tmp/pip-TQQQWP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 22:16:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 22:16:05 INFO - Running setup.py (path:/tmp/pip-HhrA7j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 22:16:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 22:16:05 INFO - Running setup.py (path:/tmp/pip-wX70Bm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 22:16:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 22:16:05 INFO - Running setup.py (path:/tmp/pip-HJpe90-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 22:16:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 22:16:05 INFO - Running setup.py (path:/tmp/pip-ickSCi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 22:16:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 22:16:06 INFO - Running setup.py (path:/tmp/pip-qNkBYb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 22:16:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 22:16:06 INFO - Running setup.py (path:/tmp/pip-Gz2CyG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 22:16:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 22:16:06 INFO - Running setup.py (path:/tmp/pip-RDy6kB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 22:16:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 22:16:06 INFO - Running setup.py (path:/tmp/pip-EYv8y_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 22:16:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 22:16:06 INFO - Running setup.py (path:/tmp/pip-5TtkVg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 22:16:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 22:16:06 INFO - Running setup.py (path:/tmp/pip-VIzPnt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 22:16:06 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 22:16:06 INFO - Running setup.py install for manifestparser 22:16:06 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 22:16:06 INFO - Running setup.py install for mozcrash 22:16:06 INFO - Running setup.py install for mozdebug 22:16:06 INFO - Running setup.py install for mozdevice 22:16:06 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 22:16:06 INFO - Installing dm script to /builds/slave/test/build/venv/bin 22:16:06 INFO - Running setup.py install for mozfile 22:16:06 INFO - Running setup.py install for mozhttpd 22:16:07 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 22:16:07 INFO - Running setup.py install for mozinfo 22:16:07 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 22:16:07 INFO - Running setup.py install for mozInstall 22:16:07 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 22:16:07 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 22:16:07 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 22:16:07 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 22:16:07 INFO - Running setup.py install for mozleak 22:16:07 INFO - Running setup.py install for mozlog 22:16:07 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 22:16:07 INFO - Running setup.py install for moznetwork 22:16:07 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 22:16:07 INFO - Running setup.py install for mozprocess 22:16:07 INFO - Running setup.py install for mozprofile 22:16:07 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 22:16:07 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 22:16:07 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 22:16:07 INFO - Running setup.py install for mozrunner 22:16:07 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 22:16:07 INFO - Running setup.py install for mozscreenshot 22:16:08 INFO - Running setup.py install for moztest 22:16:08 INFO - Running setup.py install for mozversion 22:16:08 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 22:16:08 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 22:16:08 INFO - Cleaning up... 22:16:08 INFO - Return code: 0 22:16:08 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:16:08 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')]} 22:16:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:16:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:16:08 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')]} 22:16:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:16:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:16:08 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 0x2746030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28a98a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a07180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a053b0>, '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 0x2a062b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a06740>, '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': '836cfdc75598d54cb747368e000001a9-1475729294.694804-862164654', '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 22:16:08 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 22:16:08 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 22:16:08 INFO - Using env: {'DISPLAY': ':0', 22:16:08 INFO - 'HOME': '/home/cltbld', 22:16:08 INFO - 'LANG': 'en_US.UTF-8', 22:16:08 INFO - 'LANGUAGE': 'en_US:en', 22:16:08 INFO - 'LOGNAME': 'cltbld', 22:16:08 INFO - 'MAIL': '/var/mail/cltbld', 22:16:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:16:08 INFO - 'MOZ_NO_REMOTE': '1', 22:16:08 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:16:08 INFO - 'NO_EM_RESTART': '1', 22:16:08 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:16:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:16:08 INFO - 'PWD': '/builds/slave/test', 22:16:08 INFO - 'SHELL': '/bin/bash', 22:16:08 INFO - 'SHLVL': '1', 22:16:08 INFO - 'TERM': 'linux', 22:16:08 INFO - 'TMOUT': '86400', 22:16:08 INFO - 'USER': 'cltbld', 22:16:08 INFO - 'XDG_SESSION_COOKIE': '836cfdc75598d54cb747368e000001a9-1475729294.694804-862164654', 22:16:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:16:08 INFO - '_': '/tools/buildbot/bin/python'} 22:16:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:16:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 22:16:08 INFO - Running setup.py (path:/tmp/pip-suqA3h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 22:16:08 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)) 22:16:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 22:16:08 INFO - Running setup.py (path:/tmp/pip-cgnpxt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 22:16:08 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)) 22:16:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 22:16:08 INFO - Running setup.py (path:/tmp/pip-pZ82WC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 22:16:08 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)) 22:16:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 22:16:08 INFO - Running setup.py (path:/tmp/pip-iqLaDR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 22:16:08 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)) 22:16:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 22:16:08 INFO - Running setup.py (path:/tmp/pip-IcpKum-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 22:16:08 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)) 22:16:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 22:16:08 INFO - Running setup.py (path:/tmp/pip-CHHpKb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 22:16:08 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)) 22:16:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 22:16:08 INFO - Running setup.py (path:/tmp/pip-ZlcEkK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 22:16:09 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)) 22:16:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 22:16:09 INFO - Running setup.py (path:/tmp/pip-gHteqG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 22:16:09 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)) 22:16:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 22:16:09 INFO - Running setup.py (path:/tmp/pip-KSn3WZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 22:16:09 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)) 22:16:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 22:16:09 INFO - Running setup.py (path:/tmp/pip-GgiOjB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 22:16:09 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)) 22:16:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 22:16:09 INFO - Running setup.py (path:/tmp/pip-Q4CLFH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 22:16:09 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)) 22:16:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 22:16:09 INFO - Running setup.py (path:/tmp/pip-te4HG7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 22:16:09 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)) 22:16:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 22:16:09 INFO - Running setup.py (path:/tmp/pip-u6UbBj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 22:16:09 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)) 22:16:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 22:16:09 INFO - Running setup.py (path:/tmp/pip-TP4uWF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 22:16:09 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)) 22:16:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 22:16:09 INFO - Running setup.py (path:/tmp/pip-2DuTQa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 22:16:09 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)) 22:16:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 22:16:09 INFO - Running setup.py (path:/tmp/pip-VrVq1o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 22:16:09 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)) 22:16:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 22:16:09 INFO - Running setup.py (path:/tmp/pip-oBUb24-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 22:16:09 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)) 22:16:09 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)) 22:16:09 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)) 22:16:09 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)) 22:16:09 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)) 22:16:09 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)) 22:16:09 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 22:16:09 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 22:16:09 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 22:16:09 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 22:16:09 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 22:16:09 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 22:16:09 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 22:16:11 INFO - Downloading blessings-1.6.tar.gz 22:16:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 22:16:11 INFO - Installing collected packages: blessings 22:16:11 INFO - Running setup.py install for blessings 22:16:11 INFO - Successfully installed blessings 22:16:11 INFO - Cleaning up... 22:16:11 INFO - Return code: 0 22:16:11 INFO - Done creating virtualenv /builds/slave/test/build/venv. 22:16:11 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 22:16:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 22:16:12 INFO - Reading from file tmpfile_stdout 22:16:12 INFO - Current package versions: 22:16:12 INFO - argparse == 1.2.1 22:16:12 INFO - blessings == 1.6 22:16:12 INFO - blobuploader == 1.2.4 22:16:12 INFO - docopt == 0.6.1 22:16:12 INFO - functools32 == 3.2.3-2 22:16:12 INFO - jsonschema == 2.5.1 22:16:12 INFO - manifestparser == 1.1 22:16:12 INFO - mozInstall == 1.12 22:16:12 INFO - mozcrash == 0.17 22:16:12 INFO - mozdebug == 0.1 22:16:12 INFO - mozdevice == 0.48 22:16:12 INFO - mozfile == 1.2 22:16:12 INFO - mozhttpd == 0.7 22:16:12 INFO - mozinfo == 0.9 22:16:12 INFO - mozleak == 0.1 22:16:12 INFO - mozlog == 3.3 22:16:12 INFO - moznetwork == 0.27 22:16:12 INFO - mozprocess == 0.23 22:16:12 INFO - mozprofile == 0.28 22:16:12 INFO - mozrunner == 6.12 22:16:12 INFO - mozscreenshot == 0.1 22:16:12 INFO - mozsystemmonitor == 0.3 22:16:12 INFO - moztest == 0.7 22:16:12 INFO - mozversion == 1.4 22:16:12 INFO - psutil == 3.1.1 22:16:12 INFO - requests == 1.2.3 22:16:12 INFO - wsgiref == 0.1.2 22:16:12 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:16: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')]} 22:16:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:16:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:16: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')]} 22:16:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:16:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:16:12 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 0x2746030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28a98a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a07180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a053b0>, '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 0x2a062b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a06740>, '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': '836cfdc75598d54cb747368e000001a9-1475729294.694804-862164654', '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 22:16:12 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 22:16:12 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 22:16:12 INFO - Using env: {'DISPLAY': ':0', 22:16:12 INFO - 'HOME': '/home/cltbld', 22:16:12 INFO - 'LANG': 'en_US.UTF-8', 22:16:12 INFO - 'LANGUAGE': 'en_US:en', 22:16:12 INFO - 'LOGNAME': 'cltbld', 22:16:12 INFO - 'MAIL': '/var/mail/cltbld', 22:16:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:16:12 INFO - 'MOZ_NO_REMOTE': '1', 22:16:12 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:16:12 INFO - 'NO_EM_RESTART': '1', 22:16:12 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:16:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:16:12 INFO - 'PWD': '/builds/slave/test', 22:16:12 INFO - 'SHELL': '/bin/bash', 22:16:12 INFO - 'SHLVL': '1', 22:16:12 INFO - 'TERM': 'linux', 22:16:12 INFO - 'TMOUT': '86400', 22:16:12 INFO - 'USER': 'cltbld', 22:16:12 INFO - 'XDG_SESSION_COOKIE': '836cfdc75598d54cb747368e000001a9-1475729294.694804-862164654', 22:16:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:16:12 INFO - '_': '/tools/buildbot/bin/python'} 22:16:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:16:12 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)) 22:16:12 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)) 22:16:12 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)) 22:16:12 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)) 22:16:12 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)) 22:16:12 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)) 22:16:12 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)) 22:16:12 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)) 22:16:12 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)) 22:16:12 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)) 22:16:12 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)) 22:16:12 INFO - Cleaning up... 22:16:12 INFO - Return code: 0 22:16:12 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 22:16: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')]} 22:16:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:16:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:16: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')]} 22:16:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:16:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:16:12 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 0x2746030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28a98a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a07180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a053b0>, '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 0x2a062b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a06740>, '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': '836cfdc75598d54cb747368e000001a9-1475729294.694804-862164654', '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 22:16:12 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 22:16:12 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 22:16:12 INFO - Using env: {'DISPLAY': ':0', 22:16:12 INFO - 'HOME': '/home/cltbld', 22:16:12 INFO - 'LANG': 'en_US.UTF-8', 22:16:12 INFO - 'LANGUAGE': 'en_US:en', 22:16:12 INFO - 'LOGNAME': 'cltbld', 22:16:12 INFO - 'MAIL': '/var/mail/cltbld', 22:16:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:16:12 INFO - 'MOZ_NO_REMOTE': '1', 22:16:12 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:16:12 INFO - 'NO_EM_RESTART': '1', 22:16:12 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:16:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:16:12 INFO - 'PWD': '/builds/slave/test', 22:16:12 INFO - 'SHELL': '/bin/bash', 22:16:12 INFO - 'SHLVL': '1', 22:16:12 INFO - 'TERM': 'linux', 22:16:12 INFO - 'TMOUT': '86400', 22:16:12 INFO - 'USER': 'cltbld', 22:16:12 INFO - 'XDG_SESSION_COOKIE': '836cfdc75598d54cb747368e000001a9-1475729294.694804-862164654', 22:16:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:16:12 INFO - '_': '/tools/buildbot/bin/python'} 22:16:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:16:12 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 22:16:12 INFO - Cleaning up... 22:16:12 INFO - Return code: 0 22:16:12 INFO - Running post-action listener: _resource_record_post_action 22:16:12 INFO - Running post-action listener: _start_resource_monitoring 22:16:12 INFO - Starting resource monitoring. 22:16:12 INFO - [mozharness: 2016-10-06 05:16:12.590158Z] Finished create-virtualenv step (success) 22:16:12 INFO - [mozharness: 2016-10-06 05:16:12.590583Z] Running install step. 22:16:12 INFO - Running pre-action listener: _resource_record_pre_action 22:16:12 INFO - Running main action method: install 22:16:12 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 22:16:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 22:16:12 INFO - Reading from file tmpfile_stdout 22:16:12 INFO - Detecting whether we're running mozinstall >=1.0... 22:16:12 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 22:16:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 22:16:12 INFO - Reading from file tmpfile_stdout 22:16:12 INFO - Output received: 22:16:12 INFO - Usage: mozinstall [options] installer 22:16:12 INFO - Options: 22:16:12 INFO - -h, --help show this help message and exit 22:16:12 INFO - -d DEST, --destination=DEST 22:16:12 INFO - Directory to install application into. [default: 22:16:12 INFO - "/builds/slave/test"] 22:16:12 INFO - --app=APP Application being installed. [default: firefox] 22:16:12 INFO - mkdir: /builds/slave/test/build/application 22:16:12 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'] 22:16:12 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 22:16:26 INFO - Reading from file tmpfile_stdout 22:16:26 INFO - Output received: 22:16:26 INFO - /builds/slave/test/build/application/firefox/firefox 22:16:26 INFO - Running post-action listener: _resource_record_post_action 22:16:26 INFO - [mozharness: 2016-10-06 05:16:26.374840Z] Finished install step (success) 22:16:26 INFO - [mozharness: 2016-10-06 05:16:26.375120Z] Running run-tests step. 22:16:26 INFO - Running pre-action listener: _resource_record_pre_action 22:16:26 INFO - Running main action method: run_tests 22:16:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 22:16:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 22:16:26 INFO - Python 2.7.3 22:16:26 INFO - Return code: 0 22:16:26 INFO - grabbing minidump binary from tooltool 22:16:26 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')]} 22:16:26 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 0x2a053b0>, '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 0x2a062b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a06740>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 22:16:26 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 22:16:26 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 22:16:26 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 22:16:26 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 22:16:26 INFO - Return code: 0 22:16:26 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 22:16:26 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 22:16:26 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 22:16:26 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 22:16:26 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 22:16:26 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/W0j6x-DDSriCU4DAUoEGzw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-028', '--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 22:16:26 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/W0j6x-DDSriCU4DAUoEGzw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-028 --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 22:16:26 INFO - Using env: {'DISPLAY': ':0', 22:16:26 INFO - 'HOME': '/home/cltbld', 22:16:26 INFO - 'LANG': 'en_US.UTF-8', 22:16:26 INFO - 'LANGUAGE': 'en_US:en', 22:16:26 INFO - 'LOGNAME': 'cltbld', 22:16:26 INFO - 'MAIL': '/var/mail/cltbld', 22:16:26 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 22:16:26 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 22:16:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:16:26 INFO - 'MOZ_NO_REMOTE': '1', 22:16:26 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 22:16:26 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:16:26 INFO - 'NO_EM_RESTART': '1', 22:16:26 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:16:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:16:26 INFO - 'PWD': '/builds/slave/test', 22:16:26 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 22:16:26 INFO - 'SHELL': '/bin/bash', 22:16:26 INFO - 'SHLVL': '1', 22:16:26 INFO - 'TERM': 'linux', 22:16:26 INFO - 'TMOUT': '86400', 22:16:26 INFO - 'USER': 'cltbld', 22:16:26 INFO - 'XDG_SESSION_COOKIE': '836cfdc75598d54cb747368e000001a9-1475729294.694804-862164654', 22:16:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:16:26 INFO - '_': '/tools/buildbot/bin/python'} 22:16:26 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/W0j6x-DDSriCU4DAUoEGzw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-028', '--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 22:16:26 INFO - mozversion application_buildid: 20161005190701 22:16:26 INFO - mozversion application_changeset: d567c767741298d727a5edc8aa1784f8d8e2b6f1 22:16:26 INFO - mozversion application_display_name: Firefox 22:16:26 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 22:16:26 INFO - mozversion application_name: Firefox 22:16:26 INFO - mozversion application_remotingname: firefox 22:16:26 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 22:16:26 INFO - mozversion application_vendor: Mozilla 22:16:26 INFO - mozversion application_version: 50.0 22:16:26 INFO - mozversion platform_buildid: 20161005190701 22:16:26 INFO - mozversion platform_changeset: d567c767741298d727a5edc8aa1784f8d8e2b6f1 22:16:26 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 22:16:26 INFO - mozversion platform_version: 50.0 22:16:26 INFO - using testdate: 1475730986 22:16:26 INFO - actual date: 1475730986 22:16:26 INFO - starting webserver on 'localhost:60628' 22:16:26 INFO - SUITE-START | Running 2 tests 22:16:26 INFO - TEST-START | dromaeo_css 22:16:26 INFO - operating with platform_type : linux_ 22:16:26 INFO - Initialising browser for dromaeo_css test... 22:16:26 INFO - TEST-INFO | started process 16740 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpH9a4RS/profile http://localhost:60628/getInfo.html) 22:16:30 INFO - PROCESS | 16740 | __metrics Screen width/height:1600/1200 22:16:30 INFO - PROCESS | 16740 | colorDepth:24 22:16:30 INFO - PROCESS | 16740 | Browser inner width/height: 1024/697 22:16:30 INFO - PROCESS | 16740 | __metrics 22:16:31 INFO - PROCESS | 16740 | 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" 22:16:31 INFO - PROCESS | 16740 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 22:16:32 INFO - TEST-INFO | 16740: exit 0 22:16:32 INFO - Browser initialized. 22:16:32 INFO - Running cycle 1/1 for dromaeo_css test... 22:16:32 INFO - TEST-INFO | started process 16830 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpH9a4RS/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 22:16:44 INFO - PROCESS | 16830 | RSS: Main: 143847424 22:16:44 INFO - PROCESS | 16830 | 22:17:35 INFO - PROCESS | 16830 | 1475731055085 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] 22:19:44 INFO - PROCESS | 16830 | Cycle 1(1): loaded http://localhost:60628/tests/dromaeo/cssquery-dojo.html (next: http://localhost:60628/tests/dromaeo/cssquery-ext.html) 22:19:45 INFO - PROCESS | 16830 | RSS: Main: 232869888 22:19:45 INFO - PROCESS | 16830 | 22:22:47 INFO - PROCESS | 16830 | Cycle 1(1): loaded http://localhost:60628/tests/dromaeo/cssquery-ext.html (next: http://localhost:60628/tests/dromaeo/cssquery-jquery.html) 22:22:48 INFO - PROCESS | 16830 | RSS: Main: 240721920 22:22:48 INFO - PROCESS | 16830 | 22:25:49 INFO - PROCESS | 16830 | Cycle 1(1): loaded http://localhost:60628/tests/dromaeo/cssquery-jquery.html (next: http://localhost:60628/tests/dromaeo/cssquery-mootools.html) 22:25:50 INFO - PROCESS | 16830 | RSS: Main: 245940224 22:25:50 INFO - PROCESS | 16830 | 22:28:52 INFO - PROCESS | 16830 | Cycle 1(1): loaded http://localhost:60628/tests/dromaeo/cssquery-mootools.html (next: http://localhost:60628/tests/dromaeo/cssquery-prototype.html) 22:28:52 INFO - PROCESS | 16830 | RSS: Main: 208670720 22:28:52 INFO - PROCESS | 16830 | 22:31:55 INFO - PROCESS | 16830 | Cycle 1(1): loaded http://localhost:60628/tests/dromaeo/cssquery-prototype.html (next: http://localhost:60628/tests/dromaeo/cssquery-yui.html) 22:31:56 INFO - PROCESS | 16830 | RSS: Main: 182136832 22:31:56 INFO - PROCESS | 16830 | 22:34:58 INFO - PROCESS | 16830 | Cycle 1(1): loaded http://localhost:60628/tests/dromaeo/cssquery-yui.html (next: http://localhost:60628/tests/dromaeo/cssquery-dojo.html) 22:34:58 INFO - PROCESS | 16830 | RSS: Main: 274948096 22:34:58 INFO - PROCESS | 16830 | 22:34:58 INFO - PROCESS | 16830 | __start_tp_report 22:34:58 INFO - PROCESS | 16830 | _x_x_mozilla_page_load 22:34:58 INFO - PROCESS | 16830 | _x_x_mozilla_page_load_details 22:34:58 INFO - PROCESS | 16830 | |i|pagename|runs| 22:34:58 INFO - PROCESS | 16830 | |0;dojo.html;3223.5003574445964;3235.158858696738;3243.1243564237657;3265.0368141398285;3291.753118516112;5611.186377975191;5622.859428514289;5635.239242702234;5641.351244606872;5669.518090962268;5626.12795016772;5690.4025077366905;5695.401982791809;5696.857578560528;5702.77188912443;5174.379074511064;5183.533481986639;5186.740662966856;5238.423773384925;5262.973685131569;5089.058524173021;5108.4636113208135;5276.55149312307;5282.2076688496645;5302.708351040691;5042.117629414856;5043.193089105744;5143.81996630118;5153.484651534854;5170.2503137045105;5177.715225662587;5270.209468579706;5273.578113750891;5274.208868669692;5302.761375738101;4993.275974983643;5098.923516147223;5183.922241166386;5222.033923724085;5248.029114613809;5485.040117979377;5546.195801608785;5551;5565.888682226373;5571.220029195916;5976.880462390772;6080.574359794816;6085.0568161934525;6086.056661217518;6096.4817990470865;5767.798127065575;6008.669523176223;6118.265808103056;6128.448439640454;6132.754689812402;5645.745941432645;5659.179418984224;5666.036773748473;5679.829605111895;5682.687452190128;6176.227971503562;6183.412575805291;6184.103305020747;6187.504999600022;6195.349488303754;5794.942050579524;5840.474357307863;5877.265341832271;5888.470037696542;5911.40885911414;6020.187274717945;6044.455998960027;6062.151298818169;6062.969685151635;6068.575199736064;6010.939890601038;6016.789412370546;6017;6019.428154325332;6023.156758053876;5844.47399734026;5969.432903874125;5974.3726908674835;6011.368806275278;6023.879522409527;4640.443146822336;4644.605208557243;4644.953550464452;4645.883852903705;4646.395968524071;4082.9183416331507;4103.097318589906;4121.175764847043;4152.979235103805;4168.7290326128705;5715.371309155989;5773.364929857712;5774.48029677331;5778.855528611734;5808.07070868659;6057.303410107894;6064.727087281083;6111.297200821962;6134.938650613437;6139.10982907476;5561.972190139104;5658.85852853682;5699.487046165782;5751.741171647286;5757.280339957505;5127.051495473349;5135;5141.897162056738;5157.123289040872;5170.844874653766;6301.306856245809;6323.683815809191;6338.397852204033;6342.904856427157;6357.809265722036;5882.147088672033;5890.1164825276555;5916.940830591639;6127.050307202212;6143.109249158936;6207.875842483035;6226.844328891814;6231.563790534619;6236.469900058546;6238.282597501346;5913.615614985012;5915.704214789157;5916.645001299936;5918.881622367615;5953.791617293374;5867.765289388377;5979.701014949148;5984.16221728967;5988.28140623128;5999.040153575582;5878.059510478303;5941.970290148694;5970.283565972111;5994.820155395345;6001.699915004145;5900.616459930091;5941.049432090845;5969.671668058299;5976.312724036791;5984.491318237999;5728.79949201776;5870.148828419769;5887.234659494238;5939.821805345847;5950.583459157818;5938.6436813791315;6008.909866351921;6022.608530445507;6025.87948241024;6027.221969518915;5855.531557475307;5861.706914654335;5863.882722345615;5880.882382352414;5884.941150588439 22:34:58 INFO - PROCESS | 16830 | |1;ext.html;601.0233370772495;614.339584946183;628.3182746719809;644.7968889799715;651.9739210431583;35608;47817.28274075887;49088.5091149088;49093;50830.49169508304;49405.25892111621;49855.50144498554;50042.499575004236;50072.74963625186;50152;9066.32002599806;9212.585433655468;9217.907820921771;9222.446653200795;9226.67706630268;8535.658573657078;11091.389973551451;11326.71683207918;11330.09359251262;11605.593804216855;11436.714082147972;11521.48153333102;11527.884721152765;11532.827007594893;11536.884631153707;11317.603883864067;11382.430878456127;11388;11395.487203075798;11407.942960285187;11169.0506306964;11250.64992200933;11268.661940141808;11270.211085224038;11271.605493807678;46022.309665355046;46095.53904460946;46270;46275.76862115702;46317.53682463166;3869.110104675912;3953.7627742335553;4089.631933126033;4235.53409124996;4572.856785803549;35355.7258926877;35507;35581.82209088938;35621;36465.81767091174;4639.373684552576;4643.605293550018;4664.067186562667;4708.529147085298;4805.351277577517;401350;401798;402072;403084;405073.99999999703;66413.33586664071;66860;66930.33069669241;66978.33021669819;67042;40495.19009619791;41149.58850411518;41343;69252.653736731;69412.3058769416;19206.711899321523;19455.610887782164;19503.414897553095;19516.804831951784;19531.707024394644;14123.293835308194;15270.923645381701;15503.84496155024;15714;15719.842801572067;21114.89442552808;21295.148194072375;21409.25067622657;21458.463538411666;21581.892090539448;7648.623247815335;7751.806204844924;7798.766037018899;7898.131205567383;7904.288614024766;1039.29328056922;1056.4189695667353;1076.2358725304964;1205.7287110400266;1242.0560374115737;36178.638213618055;36237.63762362395;36437.63562364395;36580.451293230624;36701.63298366982;1134.4441223800443;1135.7671677306132;1156.346664134761;1169.842071320378;1192.022541515966;18621.255149794128;19009.904950475437;19014.52463688391;19090.80909190918;19096.71354929677;608291;879706;896907;896992;898181;68409.3159068413;69206.65396672985;69231;69249.65375173092;69433.65283173653;1334.3995202159067;1408.6337552236491;1446.096189881324;1450.1371683848095;1483.6290927268183;34820;35891.64108358883;35950.12030630735;36920.44619330658;38403.807980959915;62090;68998;69012;69077;69112;558903;565168;565517;565581;566298;43783.34324985192;51531;51596.48403515917;52154.478455214965;52398.21402678887;55644.44355556393;56110.438895610525;56787;57123.714381427824;57187.714061429426;46040.53959460362;48211.75894120647;48680.2697959538;48890;49832.750836245585;47903;47968;48140.759296203294;48147;48308.75845620749;47793.76103119462;47934.76032619815;48061.27908081312;48354.274685880446;48444;39007.804960975016;41408.792956035024;42155.36766948437;42223;45254.54745452503;31196.22009449691;31475.527867081553;31540.526892097096;31735.52396714005;31999.36001279915 22:34:58 INFO - PROCESS | 16830 | |2;jquery.html;426.709204655916;436.62232169173654;439.8856297362686;442.90034742183;449.97975091120895;19431.222751089957;20254.29109981151;20656.793432065675;20867;20896.89551552244;19305.324313649024;20231.898840505815;20312.289069882558;20330;20341.694874576857;5959.61262517936;6134.631922084667;6179.227596550431;6186.876262474748;6191.473724733403;5977.850553736148;6039.6980150992495;6063.060225665029;6066.150738896558;6066.454019138277;5944.970275148618;6028.698565071751;6035.486983606399;6039.788607398742;6040.426159514861;5932.851678708045;5978.432048955342;5992;5992.610480318787;6000;5208.765605547722;5298.602604804617;5428.4028756836715;5447.1829225616475;5474.863128421782;14729.263536823117;14858.851411485963;14997.550073497812;15139.697206055816;15140.470083547023;2317.6871122398597;2383.1063351243283;2459.348272707734;2502.962455563168;2784.039506370299;10744.516496757664;11419.086473082216;11425.31448113116;11857.940710296394;15773.290201940825;1881.9435416937376;1890.3100368365585;1973.4572992427054;1982.583657431941;2066.984689927309;75389;79303;85816.14183858143;87951;88948;11487.942560287313;11827.2903625781;11954.16320857549;12169.817452738216;12245.081618878441;16928.661426771396;16992.57518562021;16996.915015425093;17000.829991699924;17024.48926532206;8667.523286219192;8854.911450885538;9059.36584439096;9100.317476189311;9163.541822908828;8495.532745699045;8591.785205369915;8638.09300023501;8798.252148567315;8825.117488251124;4973.477784832613;5032.257419706269;5034.320366750515;5147.687339728345;5226.5818734501145;2131.1688441507827;2131.435169680036;2189.136174651741;2199;2214.4574579228192;1018.9032041956002;1033.751899544119;1057.49768859791;1087.152021423291;1099.8405231241425;20459.38621841347;21143.154273828874;21156.78843211579;21159.471013224793;21178.47053823636;1039.6101461951769;1048.0253364371056;1062.5059347403494;1070.4968664727567;1077.6389909380446;11150.386728729836;11613.709657258467;11730.472128754227;11752.118591105702;11822.52709891612;506400;517188;519560;521026;521117;79173;95526;98096.50951745195;98605;99699.50150249347;1028.9742756431149;1039.0648416425279;1069.1660504806264;1069.8555694981233;1073.3023534702506;17696.823031769516;18015.459536213933;18324.90837545804;18342.724859126858;18653.347132850286;31423.842880786364;33522.49716254307;43560.782196090084;43605.34591981059;43699.34450983301;60404.39595604163;62672;63228.367716322246;64829.67585162044;64917.35082649113;8993.775155621162;9017.909820901707;9114.589843457194;9119.407238529475;9248.537573121452;7333.972285451757;10424.42665653366;11626;13671.38478768438;13816.516421925184;12068.517259309529;12124.090693198223;12217.69455763613;12420.316882571184;12439.253644781342;11789.76420471603;12027.27836329823;12081.456334464794;12123.272603643809;12328.506859725869;12256;12273;12439.37803109859;12489.18820276679;12545.435455404346;9053.456792592466;9056.818863622559;9072.716475114916;9249;9293.767655808388;9486.478243696663;12007.099467539794;12566.371681416076;12599.87400125987;12792.232466052066 22:34:58 INFO - PROCESS | 16830 | |3;mootools.html;1392.853750356212;1579.7551379536171;1589.848964348387;1781.7861856577204;1881.407356682644;9000.189982901538;9135.862962055566;9258.120478554534;9472.242220622351;9483.146516813486;8941.284697224222;9155.496447695374;9222.492762898039;9304.488253146092;9317.627294908196;2499.7250302466714;2564.7563481469324;2565.089393265392;2565.961510577343;2569.177863083814;2291.3927809130596;2527.8609676467786;2529.6458495810602;2532.5061612985473;2532.962005569918;2464.53173896959;2474.059857254241;2516.937076573091;2523.2682522068576;2524.1670248817845;2454.4232105455117;2459.2007597530783;2464.186818349949;2493.0775613023116;2497.5254701606636;2383.8212134090013;2383.940401489959;2387.9641805372758;2389.545986262622;2480.6403071554705;9283.25733941283;9331.206847417978;9459.10138536838;9486.051394860528;9513.66702165421;5663.603547751659;5785.132230165466;5803.5357171426185;5807.390224026459;5807.477327040587;3657.6159503252306;3716.7769933804057;3727.683146932514;3751.4560388743753;3784.848606055782;1141.4863311509853;1141.6632093532428;1149.230015889356;1173.436750359832;2277.9202727904444;12753.553625623152;13271.270080145589;13284.867151328459;13291.136076155019;13335.266415366037;5092.159793634083;5105.489451054939;5157.613179011589;5169.715665638426;5226.719453733859;11204.719882003015;11253.212275140662;11272.549098035986;11272.774544509064;11278.041366483942;5799.88400231993;5811.56413268998;5814.941850581525;5818.912716309174;5840.036393995028;4414.536473670219;4520.367148599199;4987.501249874984;5029.874253143627;5254.474552544715;4331.220380331508;4360.236958532308;4382.69321147523;4425.269830478;4425.911481770411;1350.129166687481;1363.7068030373516;1371.8559551247174;1412.5762271318563;1418.1349376880096;878.5870640798814;980.4313498171043;1009.6365308489085;1054.6519648516125;1054.8312270036756;6257.124002639634;6326.177596912372;6327.841803954846;6500.934990650033;6548.639824809681;475.6123759136293;480.0351293899218;502.1989926067916;505.3809083872227;514.7323391836225;4581.931271030937;4623.157726564703;4632.745199014019;4772.3557319761385;4805.13507568634;11133.332000080021;11343.716407089723;11459.942700286445;11479;11483.655490335303;11149.108071354272;11406.486708098155;11409.315441073562;11587;11604.593839215755;8480.872786908201;8650.264727498106;8653.913460865311;8662.480251184948;8694.869576956351;6526.216853977553;6531.771388001397;6611.768588099586;6663.333666633298;6800.965995169992;10583.259171858204;10763.569457221623;10857.511411986321;10890.727731806768;10974.451277436256;9503.192228660364;10621.734456638645;10991.780164396827;11127.666170014912;11150.052245559218;8230.917690823015;8586.699465518677;8621;8635.093315201813;8660.566971651519;9033.728988130366;9124.087591240823;9160.908390916273;9183.17351438347;9207.76980575491;7874.291313781787;7978.122406535161;8101.473404228706;8110.351171906115;8225.547594882339;7645;7914.5646989413835;8060.879086813585;8097.959510202647;8171.468854524437;7748.418868584991;7835;7852.37181025528;7856.25365590268;7868.409869259714;5428.972855135699;5670.886582268414;5765.827025189251;5767.798127065533;5797.623154494944;5783.479486846204;5818.650880947157;5831.446012628793;5930.318013428511;6031.5476339273855 22:34:58 INFO - PROCESS | 16830 | |4;prototype.html;337.6555912968772;345.5438820756602;347.00817308648334;348.2790623409542;353.7753526510665;5427.972860135694;5436.320459942507;5463.3717122530925;5475.863103422416;5489.1217405215175;5058.645894787357;5171.715555644438;5287.867803304919;5300.231466437364;5379.946200537994;4276.97861510692;4289.4852617685765;4296.935545966813;4313.649061261604;4318.524962254165;4263.850765223202;4289.528151903288;4293.463317085364;4315.82736690532;4319.308910574308;4016.2569924563904;4228.957710422887;4247.936280955788;4269.274223382017;4290.249206388885;4212.452381190456;4219.578042195786;4225.852095176685;4255.808488617989;4278.4438023056955;3758.5677647070665;3763.3065120695524;3763.4919285896326;3765.8681946131755;3782.905427364338;5251.632385733;5265.262863199156;5276.572776522804;5365.248865158881;5366.195070739382;4793.760311984387;4814.374131362935;4831.396075490564;4835.347228124193;4836.22620380741;5607.859803504904;5615.494605485526;5655.604107712462;5686.748915238641;5719.19931209631;3886.378179491268;4075.1849630074103;4103.891949173736;4110.527289361732;4125.97937010316;5807.796727114573;5815.214945982323;5815.621984571032;5832.154337621064;5851.3270973838125;4156.147989662112;4160.687948403821;4165.541790403053;4172.77049762266;4186.748795072305;4137.668986481134;4164.250434921743;4203.306454434984;4248.957510424856;4264.232438161161;4756.072565849695;4773.64197685175;4789.425268967746;4801.663883528189;4826.903461930741;5591.944080559224;5607.66354018752;5635.718214089279;5640.5487560995025;5661.49046585801;1428.957131286063;1436.3392839293833;1457.5991602309239;1460.5983354577577;1466.809314789092;913.3925939250457;932.9020452852488;933.659214386744;950.1306304731231;961.2598299309493;4799.904001919942;4824.34871292378;4835.564799168092;4907.607391408679;4911.238757992517;5061.292997512349;5109.8722531937;5121.8463446096675;5127.66701643927;5154.484551544815;4484.529124441952;4785.306130611042;4859.489753575895;4866.537678920497;4880.243562247857;2713.2809805401585;2730.57676060211;2735.2204621682727;2743.7667798236976;2749.848758318312;5258.897250622075;5279.205070276124;5289.656172348785;5304.416514183436;5330.520253177233;4974.901843844725;5031.345925029723;5140.5606430199605;5160.716160611202;5181.341970569457;5147.922781158286;5211.84364469059;5215.331094049868;5230.189320655304;5242.7116508591635;5019.7490125494305;5083.738330827576;5114.948850511447;5139.203423469368;5183.559397451108;5027.572656324107;5091.236314552847;5310.6813591184655;5773.220615216892;5786.276715410574;6009.767997560577;6046.334903160561;6046.395360463918;6046.788362407295;6047.546434017554;4224.767637779952;4246.363045543069;4249.511306199703;4252.4897012358715;4272;5419;5575.316733943507;5786.855328616818;5793.14619321813;5805.82582522525;5677.432256774289;5680.147977803363;5688.7440065198;5693.544516438592;5697.800576979786;5550.7779688812;5610.9158362623775;5673.631213971079;5680.375158732453;5694.1458781183155;5616.85957851057;5643.407442218508;5647.774089036392;5673.319201695823;5676.801311954062;5707.20099186106;5711.601127526532;5721.284839395075;5744.913826292693;5759.424057594207;5436.700981446058;5498.835034948958;5564.554835613222;5572.637778544382;5573.498385145356 22:34:58 INFO - PROCESS | 16830 | |5;yui.html;796.8366484870601;835.9791005224869;838.9748307550772;844.8056946902209;898.5227170848816;13282;14807.777883331732;14816.703665926689;14923.92538037311;14953.626159346022;13976.301184940738;14025.298735063232;14053.929730351334;14081.507147249848;14082.859171408283;775.5463054113336;888.3825741109931;895.0333639669175;907.2424525521164;908.5638893331172;889.7063968890251;897.6723495923974;898.5372533145435;900.6622516556291;901.0944001278706;917.687986084731;922.5018490015384;922.8615707643874;924.4037595750737;924.4499522783966;915.4736026784559;920.8250432417819;921.9354645174841;922.2529750901757;922.9861552076724;680.1735890892539;681.9147606549182;682.1098466501214;682.3654001778344;683.565935630873;13992.230427326493;14106.71786564263;14138.646533836634;14183.219922904236;14207.218602976833;16094.75857862133;16547.66904661912;16954.3218271269;17063.744043839353;17569.385071522436;12438.626841194779;12518.561850335194;12665.430055647517;12718.364081795873;12864.614061578168;4130;4143.8135283912;4147.6474499667565;4225.68307376948;4259.914801703949;86471.13528864694;88040;88082.55958720166;88624;93919.06080939171;11767.588134415255;12026.639200823989;12080;12564.371781410893;13101.017423693262;22142.778572214072;22415.32754017382;22422.887885560467;22440.775592244194;22444.66333005006;8375.120612335739;8466.280366168945;8540.402171848034;8582.313414926792;8698.869516957251;5058.797648094109;5096.133657278272;5133.589312855037;5209.6874187548865;5222.955408918231;3887.5334959805004;4003.3394489908837;4015.19696060789;4024.8188831502653;4026.778527181033;678.4775722693498;694.5589550635282;695.5930780493367;695.6695569604398;704.3519961635308;751.6692655231681;765.7932358263349;769.9384049276044;776.3905334312537;780.0717146595533;9044.547772611342;9508.239340852715;9642.662506812228;9670.468124253093;9677.177439917545;693.7606525748608;743.5204293230836;755.0674916478146;767.5739964319856;775.3680750188578;4518.683692141519;4625.583697467244;4690.15577196108;4790.784414701347;4833.927491087637;115162;116809;117521;120176.39911800386;138487.30756346154;92249;94950;104935.22856242827;107754;111083;58683.413165868646;78887.60556197182;85375.573122134;85508.57245713731;91554.54222728977;9905.653302134391;10138.341007834468;10159.288849780445;10411.895881041093;10474.947625261824;48387.75806120947;51894;52392.47607523876;53224.73387633037;53380.73309633427;70358.64820675863;72492.27507724855;76821.61589192018;79053.60473197597;80679;14641.341139648528;14700.191489467754;14807.185604791839;14955.327010284345;15016.099034057992;14717.705645887236;14731.852681473049;15204.391824326904;15660.92169539145;15992.840071599601;13401.463941442233;14274.857251427768;14706.852931470976;15161.166135862191;15264.923675381551;13276.601701948957;13381.799273011107;13460.865391346353;13877.65305867321;14830.406783728962;13654.180749155083;13797.724045519235;13938.163710177421;13950.441982320594;14042.78935815943;10629.058642740358;10642.680719578426;10712.839307410552;11026.0076593107;11120.666380008613;10489.370637761758;10774.892251077388;11051.281666691639;11576.131790115607;11631.6510504685 22:34:58 INFO - PROCESS | 16830 | __end_tp_report 22:34:58 INFO - PROCESS | 16830 | __start_cc_report 22:34:58 INFO - PROCESS | 16830 | _x_x_mozilla_cycle_collect,866 22:34:58 INFO - PROCESS | 16830 | __end_cc_report 22:34:58 INFO - PROCESS | 16830 | __startTimestamp1475732098852__endTimestamp 22:34:58 INFO - PROCESS | 16830 | 22:34:58 INFO - PROCESS | 16830 | ------- Summary: start ------- 22:34:58 INFO - PROCESS | 16830 | Number of tests: 6 22:34:58 INFO - PROCESS | 16830 | 22:34:58 INFO - PROCESS | 16830 | [#0] dojo.html Cycles:165 Average:5620.44 Median:5863.88 stddev:635.84 (10.8%) stddev-sans-first:609.35 22:34:58 INFO - PROCESS | 16830 | Values: 3223.5 3235.2 3243.1 3265.0 3291.8 5611.2 5622.9 5635.2 5641.4 5669.5 5626.1 5690.4 5695.4 5696.9 5702.8 5174.4 5183.5 5186.7 5238.4 5263.0 5089.1 5108.5 5276.6 5282.2 5302.7 5042.1 5043.2 5143.8 5153.5 5170.3 5177.7 5270.2 5273.6 5274.2 5302.8 4993.3 5098.9 5183.9 5222.0 5248.0 5485.0 5546.2 5551.0 5565.9 5571.2 5976.9 6080.6 6085.1 6086.1 6096.5 5767.8 6008.7 6118.3 6128.4 6132.8 5645.7 5659.2 5666.0 5679.8 5682.7 6176.2 6183.4 6184.1 6187.5 6195.3 5794.9 5840.5 5877.3 5888.5 5911.4 6020.2 6044.5 6062.2 6063.0 6068.6 6010.9 6016.8 6017.0 6019.4 6023.2 5844.5 5969.4 5974.4 6011.4 6023.9 4640.4 4644.6 4645.0 4645.9 4646.4 4082.9 4103.1 4121.2 4153.0 4168.7 5715.4 5773.4 5774.5 5778.9 5808.1 6057.3 6064.7 6111.3 6134.9 6139.1 5562.0 5658.9 5699.5 5751.7 5757.3 5127.1 5135.0 5141.9 5157.1 5170.8 6301.3 6323.7 6338.4 6342.9 6357.8 5882.1 5890.1 5916.9 6127.1 6143.1 6207.9 6226.8 6231.6 6236.5 6238.3 5913.6 5915.7 5916.6 5918.9 5953.8 5867.8 5979.7 5984.2 5988.3 5999.0 5878.1 5942.0 5970.3 5994.8 6001.7 5900.6 5941.0 5969.7 5976.3 5984.5 5728.8 5870.1 5887.2 5939.8 5950.6 5938.6 6008.9 6022.6 6025.9 6027.2 5855.5 5861.7 5863.9 5880.9 5884.9 22:34:58 INFO - PROCESS | 16830 | 22:34:58 INFO - PROCESS | 16830 | [#1] ext.html Cycles:180 Average:77365.83 Median:24009.44 stddev:168971.77 (703.8%) stddev-sans-first:169347.48 22:34:58 INFO - PROCESS | 16830 | Values: 601.0 614.3 628.3 644.8 652.0 35608.0 47817.3 49088.5 49093.0 50830.5 49405.3 49855.5 50042.5 50072.7 50152.0 9066.3 9212.6 9217.9 9222.4 9226.7 8535.7 11091.4 11326.7 11330.1 11605.6 11436.7 11521.5 11527.9 11532.8 11536.9 11317.6 11382.4 11388.0 11395.5 11407.9 11169.1 11250.6 11268.7 11270.2 11271.6 46022.3 46095.5 46270.0 46275.8 46317.5 3869.1 3953.8 4089.6 4235.5 4572.9 35355.7 35507.0 35581.8 35621.0 36465.8 4639.4 4643.6 4664.1 4708.5 4805.4 401350.0 401798.0 402072.0 403084.0 405074.0 66413.3 66860.0 66930.3 66978.3 67042.0 40495.2 41149.6 41343.0 69252.7 69412.3 19206.7 19455.6 19503.4 19516.8 19531.7 14123.3 15270.9 15503.8 15714.0 15719.8 21114.9 21295.1 21409.3 21458.5 21581.9 7648.6 7751.8 7798.8 7898.1 7904.3 1039.3 1056.4 1076.2 1205.7 1242.1 36178.6 36237.6 36437.6 36580.5 36701.6 1134.4 1135.8 1156.3 1169.8 1192.0 18621.3 19009.9 19014.5 19090.8 19096.7 608291.0 879706.0 896907.0 896992.0 898181.0 68409.3 69206.7 69231.0 69249.7 69433.7 1334.4 1408.6 1446.1 1450.1 1483.6 34820.0 35891.6 35950.1 36920.4 38403.8 62090.0 68998.0 69012.0 69077.0 69112.0 558903.0 565168.0 565517.0 565581.0 566298.0 43783.3 51531.0 51596.5 52154.5 52398.2 55644.4 56110.4 56787.0 57123.7 57187.7 46040.5 48211.8 48680.3 48890.0 49832.8 47903.0 47968.0 48140.8 48147.0 48308.8 47793.8 47934.8 48061.3 48354.3 48444.0 39007.8 41408.8 42155.4 42223.0 45254.5 31196.2 31475.5 31540.5 31735.5 31999.4 22:34:58 INFO - PROCESS | 16830 | 22:34:58 INFO - PROCESS | 16830 | [#2] jquery.html Cycles:180 Average:30149.26 Median:2194.07 stddev:85213.97 (3883.8%) stddev-sans-first:85423.80 22:34:58 INFO - PROCESS | 16830 | Values: 426.7 436.6 439.9 442.9 450.0 19431.2 20254.3 20656.8 20867.0 20896.9 19305.3 20231.9 20312.3 20330.0 20341.7 5959.6 6134.6 6179.2 6186.9 6191.5 5977.9 6039.7 6063.1 6066.2 6066.5 5945.0 6028.7 6035.5 6039.8 6040.4 5932.9 5978.4 5992.0 5992.6 6000.0 5208.8 5298.6 5428.4 5447.2 5474.9 14729.3 14858.9 14997.6 15139.7 15140.5 2317.7 2383.1 2459.3 2503.0 2784.0 10744.5 11419.1 11425.3 11857.9 15773.3 1881.9 1890.3 1973.5 1982.6 2067.0 75389.0 79303.0 85816.1 87951.0 88948.0 11487.9 11827.3 11954.2 12169.8 12245.1 16928.7 16992.6 16996.9 17000.8 17024.5 8667.5 8854.9 9059.4 9100.3 9163.5 8495.5 8591.8 8638.1 8798.3 8825.1 4973.5 5032.3 5034.3 5147.7 5226.6 2131.2 2131.4 2189.1 2199.0 2214.5 1018.9 1033.8 1057.5 1087.2 1099.8 20459.4 21143.2 21156.8 21159.5 21178.5 1039.6 1048.0 1062.5 1070.5 1077.6 11150.4 11613.7 11730.5 11752.1 11822.5 506400.0 517188.0 519560.0 521026.0 521117.0 79173.0 95526.0 98096.5 98605.0 99699.5 1029.0 1039.1 1069.2 1069.9 1073.3 17696.8 18015.5 18324.9 18342.7 18653.3 31423.8 33522.5 43560.8 43605.3 43699.3 60404.4 62672.0 63228.4 64829.7 64917.4 8993.8 9017.9 9114.6 9119.4 9248.5 7334.0 10424.4 11626.0 13671.4 13816.5 12068.5 12124.1 12217.7 12420.3 12439.3 11789.8 12027.3 12081.5 12123.3 12328.5 12256.0 12273.0 12439.4 12489.2 12545.4 9053.5 9056.8 9072.7 9249.0 9293.8 9486.5 12007.1 12566.4 12599.9 12792.2 22:34:58 INFO - PROCESS | 16830 | 22:34:58 INFO - PROCESS | 16830 | [#3] mootools.html Cycles:180 Average:6224.43 Median:4896.32 stddev:3507.75 (71.6%) stddev-sans-first:3498.79 22:34:58 INFO - PROCESS | 16830 | Values: 1392.9 1579.8 1589.8 1781.8 1881.4 9000.2 9135.9 9258.1 9472.2 9483.1 8941.3 9155.5 9222.5 9304.5 9317.6 2499.7 2564.8 2565.1 2566.0 2569.2 2291.4 2527.9 2529.6 2532.5 2533.0 2464.5 2474.1 2516.9 2523.3 2524.2 2454.4 2459.2 2464.2 2493.1 2497.5 2383.8 2383.9 2388.0 2389.5 2480.6 9283.3 9331.2 9459.1 9486.1 9513.7 5663.6 5785.1 5803.5 5807.4 5807.5 3657.6 3716.8 3727.7 3751.5 3784.8 1141.5 1141.7 1149.2 1173.4 2277.9 12753.6 13271.3 13284.9 13291.1 13335.3 5092.2 5105.5 5157.6 5169.7 5226.7 11204.7 11253.2 11272.5 11272.8 11278.0 5799.9 5811.6 5814.9 5818.9 5840.0 4414.5 4520.4 4987.5 5029.9 5254.5 4331.2 4360.2 4382.7 4425.3 4425.9 1350.1 1363.7 1371.9 1412.6 1418.1 878.6 980.4 1009.6 1054.7 1054.8 6257.1 6326.2 6327.8 6500.9 6548.6 475.6 480.0 502.2 505.4 514.7 4581.9 4623.2 4632.7 4772.4 4805.1 11133.3 11343.7 11459.9 11479.0 11483.7 11149.1 11406.5 11409.3 11587.0 11604.6 8480.9 8650.3 8653.9 8662.5 8694.9 6526.2 6531.8 6611.8 6663.3 6801.0 10583.3 10763.6 10857.5 10890.7 10974.5 9503.2 10621.7 10991.8 11127.7 11150.1 8230.9 8586.7 8621.0 8635.1 8660.6 9033.7 9124.1 9160.9 9183.2 9207.8 7874.3 7978.1 8101.5 8110.4 8225.5 7645.0 7914.6 8060.9 8098.0 8171.5 7748.4 7835.0 7852.4 7856.3 7868.4 5429.0 5670.9 5765.8 5767.8 5797.6 5783.5 5818.7 5831.4 5930.3 6031.5 22:34:58 INFO - PROCESS | 16830 | 22:34:58 INFO - PROCESS | 16830 | [#4] prototype.html Cycles:180 Average:4624.79 Median:5124.76 stddev:1331.93 (26.0%) stddev-sans-first:1296.21 22:34:58 INFO - PROCESS | 16830 | Values: 337.7 345.5 347.0 348.3 353.8 5428.0 5436.3 5463.4 5475.9 5489.1 5058.6 5171.7 5287.9 5300.2 5379.9 4277.0 4289.5 4296.9 4313.6 4318.5 4263.9 4289.5 4293.5 4315.8 4319.3 4016.3 4229.0 4247.9 4269.3 4290.2 4212.5 4219.6 4225.9 4255.8 4278.4 3758.6 3763.3 3763.5 3765.9 3782.9 5251.6 5265.3 5276.6 5365.2 5366.2 4793.8 4814.4 4831.4 4835.3 4836.2 5607.9 5615.5 5655.6 5686.7 5719.2 3886.4 4075.2 4103.9 4110.5 4126.0 5807.8 5815.2 5815.6 5832.2 5851.3 4156.1 4160.7 4165.5 4172.8 4186.7 4137.7 4164.3 4203.3 4249.0 4264.2 4756.1 4773.6 4789.4 4801.7 4826.9 5591.9 5607.7 5635.7 5640.5 5661.5 1429.0 1436.3 1457.6 1460.6 1466.8 913.4 932.9 933.7 950.1 961.3 4799.9 4824.3 4835.6 4907.6 4911.2 5061.3 5109.9 5121.8 5127.7 5154.5 4484.5 4785.3 4859.5 4866.5 4880.2 2713.3 2730.6 2735.2 2743.8 2749.8 5258.9 5279.2 5289.7 5304.4 5330.5 4974.9 5031.3 5140.6 5160.7 5181.3 5147.9 5211.8 5215.3 5230.2 5242.7 5019.7 5083.7 5114.9 5139.2 5183.6 5027.6 5091.2 5310.7 5773.2 5786.3 6009.8 6046.3 6046.4 6046.8 6047.5 4224.8 4246.4 4249.5 4252.5 4272.0 5419.0 5575.3 5786.9 5793.1 5805.8 5677.4 5680.1 5688.7 5693.5 5697.8 5550.8 5610.9 5673.6 5680.4 5694.1 5616.9 5643.4 5647.8 5673.3 5676.8 5707.2 5711.6 5721.3 5744.9 5759.4 5436.7 5498.8 5564.6 5572.6 5573.5 22:34:58 INFO - PROCESS | 16830 | 22:34:58 INFO - PROCESS | 16830 | [#5] yui.html Cycles:180 Average:21536.81 Median:4242.80 stddev:31242.68 (736.4%) stddev-sans-first:31291.51 22:34:58 INFO - PROCESS | 16830 | Values: 796.8 836.0 839.0 844.8 898.5 13282.0 14807.8 14816.7 14923.9 14953.6 13976.3 14025.3 14053.9 14081.5 14082.9 775.5 888.4 895.0 907.2 908.6 889.7 897.7 898.5 900.7 901.1 917.7 922.5 922.9 924.4 924.4 915.5 920.8 921.9 922.3 923.0 680.2 681.9 682.1 682.4 683.6 13992.2 14106.7 14138.6 14183.2 14207.2 16094.8 16547.7 16954.3 17063.7 17569.4 12438.6 12518.6 12665.4 12718.4 12864.6 4130.0 4143.8 4147.6 4225.7 4259.9 86471.1 88040.0 88082.6 88624.0 93919.1 11767.6 12026.6 12080.0 12564.4 13101.0 22142.8 22415.3 22422.9 22440.8 22444.7 8375.1 8466.3 8540.4 8582.3 8698.9 5058.8 5096.1 5133.6 5209.7 5223.0 3887.5 4003.3 4015.2 4024.8 4026.8 678.5 694.6 695.6 695.7 704.4 751.7 765.8 769.9 776.4 780.1 9044.5 9508.2 9642.7 9670.5 9677.2 693.8 743.5 755.1 767.6 775.4 4518.7 4625.6 4690.2 4790.8 4833.9 115162.0 116809.0 117521.0 120176.4 138487.3 92249.0 94950.0 104935.2 107754.0 111083.0 58683.4 78887.6 85375.6 85508.6 91554.5 9905.7 10138.3 10159.3 10411.9 10474.9 48387.8 51894.0 52392.5 53224.7 53380.7 70358.6 72492.3 76821.6 79053.6 80679.0 14641.3 14700.2 14807.2 14955.3 15016.1 14717.7 14731.9 15204.4 15660.9 15992.8 13401.5 14274.9 14706.9 15161.2 15264.9 13276.6 13381.8 13460.9 13877.7 14830.4 13654.2 13797.7 13938.2 13950.4 14042.8 10629.1 10642.7 10712.8 11026.0 11120.7 10489.4 10774.9 11051.3 11576.1 11631.7 22:34:58 INFO - PROCESS | 16830 | -------- Summary: end -------- 22:34:58 INFO - PROCESS | 16830 | 22:34:59 INFO - TEST-INFO | 16830: exit 0 22:34:59 INFO - TEST-OK | dromaeo_css | took 1112990ms 22:34:59 INFO - TEST-START | kraken 22:34:59 INFO - operating with platform_type : linux_ 22:34:59 INFO - Initialising browser for kraken test... 22:34:59 INFO - TEST-INFO | started process 17034 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpeoYsHU/profile http://localhost:60628/getInfo.html) 22:35:03 INFO - PROCESS | 17034 | __metrics Screen width/height:1600/1200 22:35:03 INFO - PROCESS | 17034 | colorDepth:24 22:35:03 INFO - PROCESS | 17034 | Browser inner width/height: 1024/697 22:35:03 INFO - PROCESS | 17034 | __metrics 22:35:04 INFO - PROCESS | 17034 | 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" 22:35:04 INFO - PROCESS | 17034 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 22:35:05 INFO - PROCESS | 17034 | console.error: PushService: 22:35:05 INFO - PROCESS | 17034 | stateChangeProcessEnqueue: Error transitioning state 22:35:05 INFO - PROCESS | 17034 | UnknownError 22:35:05 INFO - TEST-INFO | 17034: exit 0 22:35:05 INFO - Browser initialized. 22:35:05 INFO - Running cycle 1/1 for kraken test... 22:35:05 INFO - TEST-INFO | started process 17122 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpeoYsHU/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 22:35:17 INFO - PROCESS | 17122 | RSS: Main: 144437248 22:35:17 INFO - PROCESS | 17122 | 22:35:19 INFO - PROCESS | 17122 | Cycle 1(1): loaded http://localhost:60628/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:60628/tests/kraken/driver.html?testName=audio-beat-detection) 22:35:20 INFO - PROCESS | 17122 | RSS: Main: 198516736 22:35:20 INFO - PROCESS | 17122 | 22:35:23 INFO - PROCESS | 17122 | Cycle 1(1): loaded http://localhost:60628/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:60628/tests/kraken/driver.html?testName=audio-dft) 22:35:23 INFO - PROCESS | 17122 | RSS: Main: 184082432 22:35:23 INFO - PROCESS | 17122 | 22:35:26 INFO - PROCESS | 17122 | Cycle 1(1): loaded http://localhost:60628/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:60628/tests/kraken/driver.html?testName=audio-fft) 22:35:27 INFO - PROCESS | 17122 | RSS: Main: 274706432 22:35:27 INFO - PROCESS | 17122 | 22:35:29 INFO - PROCESS | 17122 | Cycle 1(1): loaded http://localhost:60628/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:60628/tests/kraken/driver.html?testName=audio-oscillator) 22:35:29 INFO - PROCESS | 17122 | RSS: Main: 180563968 22:35:29 INFO - PROCESS | 17122 | 22:35:31 INFO - PROCESS | 17122 | Cycle 1(1): loaded http://localhost:60628/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:60628/tests/kraken/driver.html?testName=imaging-gaussian-blur) 22:35:32 INFO - PROCESS | 17122 | RSS: Main: 179642368 22:35:32 INFO - PROCESS | 17122 | 22:35:35 INFO - PROCESS | 17122 | Cycle 1(1): loaded http://localhost:60628/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:60628/tests/kraken/driver.html?testName=imaging-darkroom) 22:35:36 INFO - PROCESS | 17122 | RSS: Main: 215887872 22:35:36 INFO - PROCESS | 17122 | 22:35:39 INFO - PROCESS | 17122 | Cycle 1(1): loaded http://localhost:60628/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:60628/tests/kraken/driver.html?testName=imaging-desaturate) 22:35:40 INFO - PROCESS | 17122 | RSS: Main: 214700032 22:35:40 INFO - PROCESS | 17122 | 22:35:42 INFO - PROCESS | 17122 | Cycle 1(1): loaded http://localhost:60628/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:60628/tests/kraken/driver.html?testName=json-parse-financial) 22:35:43 INFO - PROCESS | 17122 | RSS: Main: 213901312 22:35:43 INFO - PROCESS | 17122 | 22:35:44 INFO - PROCESS | 17122 | Cycle 1(1): loaded http://localhost:60628/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:60628/tests/kraken/driver.html?testName=json-stringify-tinderbox) 22:35:45 INFO - PROCESS | 17122 | RSS: Main: 175702016 22:35:45 INFO - PROCESS | 17122 | 22:35:47 INFO - PROCESS | 17122 | Cycle 1(1): loaded http://localhost:60628/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:60628/tests/kraken/driver.html?testName=stanford-crypto-aes) 22:35:47 INFO - PROCESS | 17122 | RSS: Main: 182464512 22:35:47 INFO - PROCESS | 17122 | 22:35:49 INFO - PROCESS | 17122 | Cycle 1(1): loaded http://localhost:60628/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:60628/tests/kraken/driver.html?testName=stanford-crypto-ccm) 22:35:50 INFO - PROCESS | 17122 | RSS: Main: 189124608 22:35:50 INFO - PROCESS | 17122 | 22:35:52 INFO - PROCESS | 17122 | Cycle 1(1): loaded http://localhost:60628/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:60628/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 22:35:53 INFO - PROCESS | 17122 | RSS: Main: 189886464 22:35:53 INFO - PROCESS | 17122 | 22:35:56 INFO - PROCESS | 17122 | Cycle 1(1): loaded http://localhost:60628/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:60628/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 22:35:56 INFO - PROCESS | 17122 | RSS: Main: 192864256 22:35:56 INFO - PROCESS | 17122 | 22:35:58 INFO - PROCESS | 17122 | Cycle 1(1): loaded http://localhost:60628/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:60628/tests/kraken/driver.html?testName=ai-astar) 22:35:58 INFO - PROCESS | 17122 | RSS: Main: 242929664 22:35:58 INFO - PROCESS | 17122 | 22:35:58 INFO - PROCESS | 17122 | __start_tp_report 22:35:58 INFO - PROCESS | 17122 | _x_x_mozilla_page_load 22:35:58 INFO - PROCESS | 17122 | _x_x_mozilla_page_load_details 22:35:58 INFO - PROCESS | 17122 | |i|pagename|runs| 22:35:58 INFO - PROCESS | 17122 | |0;ai-astar;111;111;120;111;110;112;111;110;110;110 22:35:58 INFO - PROCESS | 17122 | |1;audio-beat-detection;148;114;151;165;115;147;140;116;183;115 22:35:58 INFO - PROCESS | 17122 | |2;audio-dft;160;160;160;159;160;159;159;160;160;162 22:35:58 INFO - PROCESS | 17122 | |3;audio-fft;74;74;76;174;77;74;75;74;183;74 22:35:58 INFO - PROCESS | 17122 | |4;audio-oscillator;84;83;143;83;83;83;152;83;82;144 22:35:58 INFO - PROCESS | 17122 | |5;imaging-gaussian-blur;117;117;116;117;117;117;117;116;117;117 22:35:58 INFO - PROCESS | 17122 | |6;imaging-darkroom;146;146;146;146;147;146;146;148;147;146 22:35:58 INFO - PROCESS | 17122 | |7;imaging-desaturate;94;94;94;94;94;94;95;94;95;94 22:35:58 INFO - PROCESS | 17122 | |8;json-parse-financial;50;49;49;49;49;52;50;49;49;50 22:35:58 INFO - PROCESS | 17122 | |9;json-stringify-tinderbox;60;61;59;60;60;60;60;60;60;60 22:35:58 INFO - PROCESS | 17122 | |10;stanford-crypto-aes;81;75;77;72;77;73;73;72;72;74 22:35:58 INFO - PROCESS | 17122 | |11;stanford-crypto-ccm;120;117;119;118;115;117;116;131;161;135 22:35:58 INFO - PROCESS | 17122 | |12;stanford-crypto-pbkdf2;151;150;148;149;154;147;151;151;148;151 22:35:58 INFO - PROCESS | 17122 | |13;stanford-crypto-sha256-iterative;64;61;63;61;61;61;60;64;61;61 22:35:58 INFO - PROCESS | 17122 | __end_tp_report 22:35:58 INFO - PROCESS | 17122 | __start_cc_report 22:35:58 INFO - PROCESS | 17122 | _x_x_mozilla_cycle_collect,1659 22:35:58 INFO - PROCESS | 17122 | __end_cc_report 22:35:58 INFO - PROCESS | 17122 | __startTimestamp1475732158531__endTimestamp 22:35:58 INFO - PROCESS | 17122 | 22:35:58 INFO - PROCESS | 17122 | ------- Summary: start ------- 22:35:58 INFO - PROCESS | 17122 | Number of tests: 14 22:35:58 INFO - PROCESS | 17122 | 22:35:58 INFO - PROCESS | 17122 | [#0] ai-astar Cycles:10 Average:111.60 Median:111.00 stddev:3.03 (2.7%) stddev-sans-first:3.20 22:35:58 INFO - PROCESS | 17122 | Values: 111.0 111.0 120.0 111.0 110.0 112.0 111.0 110.0 110.0 110.0 22:35:58 INFO - PROCESS | 17122 | 22:35:58 INFO - PROCESS | 17122 | [#1] audio-beat-detection Cycles:10 Average:139.40 Median:147.50 stddev:24.05 (16.3%) stddev-sans-first:25.31 22:35:58 INFO - PROCESS | 17122 | Values: 148.0 114.0 151.0 165.0 115.0 147.0 140.0 116.0 183.0 115.0 22:35:58 INFO - PROCESS | 17122 | 22:35:58 INFO - PROCESS | 17122 | [#2] audio-dft Cycles:10 Average:159.90 Median:160.00 stddev:0.88 (0.5%) stddev-sans-first:0.93 22:35:58 INFO - PROCESS | 17122 | Values: 160.0 160.0 160.0 159.0 160.0 159.0 159.0 160.0 160.0 162.0 22:35:58 INFO - PROCESS | 17122 | 22:35:58 INFO - PROCESS | 17122 | [#3] audio-fft Cycles:10 Average:95.50 Median:74.00 stddev:43.81 (59.2%) stddev-sans-first:45.77 22:35:58 INFO - PROCESS | 17122 | Values: 74.0 74.0 76.0 174.0 77.0 74.0 75.0 74.0 183.0 74.0 22:35:58 INFO - PROCESS | 17122 | 22:35:58 INFO - PROCESS | 17122 | [#4] audio-oscillator Cycles:10 Average:102.00 Median:83.00 stddev:30.68 (37.0%) stddev-sans-first:31.85 22:35:58 INFO - PROCESS | 17122 | Values: 84.0 83.0 143.0 83.0 83.0 83.0 152.0 83.0 82.0 144.0 22:35:58 INFO - PROCESS | 17122 | 22:35:58 INFO - PROCESS | 17122 | [#5] imaging-gaussian-blur Cycles:10 Average:116.80 Median:117.00 stddev:0.42 (0.4%) stddev-sans-first:0.44 22:35:58 INFO - PROCESS | 17122 | Values: 117.0 117.0 116.0 117.0 117.0 117.0 117.0 116.0 117.0 117.0 22:35:58 INFO - PROCESS | 17122 | 22:35:58 INFO - PROCESS | 17122 | [#6] imaging-darkroom Cycles:10 Average:146.40 Median:146.00 stddev:0.70 (0.5%) stddev-sans-first:0.73 22:35:58 INFO - PROCESS | 17122 | Values: 146.0 146.0 146.0 146.0 147.0 146.0 146.0 148.0 147.0 146.0 22:35:58 INFO - PROCESS | 17122 | 22:35:58 INFO - PROCESS | 17122 | [#7] imaging-desaturate Cycles:10 Average:94.20 Median:94.00 stddev:0.42 (0.4%) stddev-sans-first:0.44 22:35:58 INFO - PROCESS | 17122 | Values: 94.0 94.0 94.0 94.0 94.0 94.0 95.0 94.0 95.0 94.0 22:35:58 INFO - PROCESS | 17122 | 22:35:58 INFO - PROCESS | 17122 | [#8] json-parse-financial Cycles:10 Average:49.60 Median:49.50 stddev:0.97 (2.0%) stddev-sans-first:1.01 22:35:58 INFO - PROCESS | 17122 | Values: 50.0 49.0 49.0 49.0 49.0 52.0 50.0 49.0 49.0 50.0 22:35:58 INFO - PROCESS | 17122 | 22:35:58 INFO - PROCESS | 17122 | [#9] json-stringify-tinderbox Cycles:10 Average:60.00 Median:60.00 stddev:0.47 (0.8%) stddev-sans-first:0.50 22:35:58 INFO - PROCESS | 17122 | Values: 60.0 61.0 59.0 60.0 60.0 60.0 60.0 60.0 60.0 60.0 22:35:58 INFO - PROCESS | 17122 | 22:35:58 INFO - PROCESS | 17122 | [#10] stanford-crypto-aes Cycles:10 Average:74.60 Median:74.50 stddev:2.95 (4.0%) stddev-sans-first:2.03 22:35:58 INFO - PROCESS | 17122 | Values: 81.0 75.0 77.0 72.0 77.0 73.0 73.0 72.0 72.0 74.0 22:35:58 INFO - PROCESS | 17122 | 22:35:58 INFO - PROCESS | 17122 | [#11] stanford-crypto-ccm Cycles:10 Average:124.90 Median:119.50 stddev:14.34 (12.0%) stddev-sans-first:15.10 22:35:58 INFO - PROCESS | 17122 | Values: 120.0 117.0 119.0 118.0 115.0 117.0 116.0 131.0 161.0 135.0 22:35:58 INFO - PROCESS | 17122 | 22:35:58 INFO - PROCESS | 17122 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:150.00 Median:151.00 stddev:2.05 (1.4%) stddev-sans-first:2.15 22:35:58 INFO - PROCESS | 17122 | Values: 151.0 150.0 148.0 149.0 154.0 147.0 151.0 151.0 148.0 151.0 22:35:58 INFO - PROCESS | 17122 | 22:35:58 INFO - PROCESS | 17122 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:61.70 Median:61.00 stddev:1.42 (2.3%) stddev-sans-first:1.24 22:35:58 INFO - PROCESS | 17122 | Values: 64.0 61.0 63.0 61.0 61.0 61.0 60.0 64.0 61.0 61.0 22:35:58 INFO - PROCESS | 17122 | -------- Summary: end -------- 22:35:58 INFO - PROCESS | 17122 | 22:35:59 INFO - TEST-INFO | 17122: exit 0 22:35:59 INFO - TEST-OK | kraken | took 59913ms 22:35:59 INFO - SUITE-END | took 1172s 22:35:59 INFO - Completed test suite (00:19:33) 22:35:59 INFO - javascript benchmark 22:35:59 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3223.5003574445964, 3235.158858696738, 3243.1243564237657, 3265.0368141398285, 3291.753118516112, 5611.186377975191, 5622.859428514289, 5635.239242702234, 5641.351244606872, 5669.518090962268, 5626.12795016772, 5690.4025077366905, 5695.401982791809, 5696.857578560528, 5702.77188912443, 5174.379074511064, 5183.533481986639, 5186.740662966856, 5238.423773384925, 5262.973685131569, 5089.058524173021, 5108.4636113208135, 5276.55149312307, 5282.2076688496645, 5302.708351040691, 5042.117629414856, 5043.193089105744, 5143.81996630118, 5153.484651534854, 5170.2503137045105, 5177.715225662587, 5270.209468579706, 5273.578113750891, 5274.208868669692, 5302.761375738101, 4993.275974983643, 5098.923516147223, 5183.922241166386, 5222.033923724085, 5248.029114613809, 5485.040117979377, 5546.195801608785, 5551.0, 5565.888682226373, 5571.220029195916, 5976.880462390772, 6080.574359794816, 6085.0568161934525, 6086.056661217518, 6096.4817990470865, 5767.798127065575, 6008.669523176223, 6118.265808103056, 6128.448439640454, 6132.754689812402, 5645.745941432645, 5659.179418984224, 5666.036773748473, 5679.829605111895, 5682.687452190128, 6176.227971503562, 6183.412575805291, 6184.103305020747, 6187.504999600022, 6195.349488303754, 5794.942050579524, 5840.474357307863, 5877.265341832271, 5888.470037696542, 5911.40885911414, 6020.187274717945, 6044.455998960027, 6062.151298818169, 6062.969685151635, 6068.575199736064, 6010.939890601038, 6016.789412370546, 6017.0, 6019.428154325332, 6023.156758053876, 5844.47399734026, 5969.432903874125, 5974.3726908674835, 6011.368806275278, 6023.879522409527, 4640.443146822336, 4644.605208557243, 4644.953550464452, 4645.883852903705, 4646.395968524071, 4082.9183416331507, 4103.097318589906, 4121.175764847043, 4152.979235103805, 4168.7290326128705, 5715.371309155989, 5773.364929857712, 5774.48029677331, 5778.855528611734, 5808.07070868659, 6057.303410107894, 6064.727087281083, 6111.297200821962, 6134.938650613437, 6139.10982907476, 5561.972190139104, 5658.85852853682, 5699.487046165782, 5751.741171647286, 5757.280339957505, 5127.051495473349, 5135.0, 5141.897162056738, 5157.123289040872, 5170.844874653766, 6301.306856245809, 6323.683815809191, 6338.397852204033, 6342.904856427157, 6357.809265722036, 5882.147088672033, 5890.1164825276555, 5916.940830591639, 6127.050307202212, 6143.109249158936, 6207.875842483035, 6226.844328891814, 6231.563790534619, 6236.469900058546, 6238.282597501346, 5913.615614985012, 5915.704214789157, 5916.645001299936, 5918.881622367615, 5953.791617293374, 5867.765289388377, 5979.701014949148, 5984.16221728967, 5988.28140623128, 5999.040153575582, 5878.059510478303, 5941.970290148694, 5970.283565972111, 5994.820155395345, 6001.699915004145, 5900.616459930091, 5941.049432090845, 5969.671668058299, 5976.312724036791, 5984.491318237999, 5728.79949201776, 5870.148828419769, 5887.234659494238, 5939.821805345847, 5950.583459157818, 5938.6436813791315, 6008.909866351921, 6022.608530445507, 6025.87948241024, 6027.221969518915, 5855.531557475307, 5861.706914654335, 5863.882722345615, 5880.882382352414, 5884.941150588439], "value": 5577.407362727536, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [601.0233370772495, 614.339584946183, 628.3182746719809, 644.7968889799715, 651.9739210431583, 35608.0, 47817.28274075887, 49088.5091149088, 49093.0, 50830.49169508304, 49405.25892111621, 49855.50144498554, 50042.499575004236, 50072.74963625186, 50152.0, 9066.32002599806, 9212.585433655468, 9217.907820921771, 9222.446653200795, 9226.67706630268, 8535.658573657078, 11091.389973551451, 11326.71683207918, 11330.09359251262, 11605.593804216855, 11436.714082147972, 11521.48153333102, 11527.884721152765, 11532.827007594893, 11536.884631153707, 11317.603883864067, 11382.430878456127, 11388.0, 11395.487203075798, 11407.942960285187, 11169.0506306964, 11250.64992200933, 11268.661940141808, 11270.211085224038, 11271.605493807678, 46022.309665355046, 46095.53904460946, 46270.0, 46275.76862115702, 46317.53682463166, 3869.110104675912, 3953.7627742335553, 4089.631933126033, 4235.53409124996, 4572.856785803549, 35355.7258926877, 35507.0, 35581.82209088938, 35621.0, 36465.81767091174, 4639.373684552576, 4643.605293550018, 4664.067186562667, 4708.529147085298, 4805.351277577517, 401350.0, 401798.0, 402072.0, 403084.0, 405073.99999999703, 66413.33586664071, 66860.0, 66930.33069669241, 66978.33021669819, 67042.0, 40495.19009619791, 41149.58850411518, 41343.0, 69252.653736731, 69412.3058769416, 19206.711899321523, 19455.610887782164, 19503.414897553095, 19516.804831951784, 19531.707024394644, 14123.293835308194, 15270.923645381701, 15503.84496155024, 15714.0, 15719.842801572067, 21114.89442552808, 21295.148194072375, 21409.25067622657, 21458.463538411666, 21581.892090539448, 7648.623247815335, 7751.806204844924, 7798.766037018899, 7898.131205567383, 7904.288614024766, 1039.29328056922, 1056.4189695667353, 1076.2358725304964, 1205.7287110400266, 1242.0560374115737, 36178.638213618055, 36237.63762362395, 36437.63562364395, 36580.451293230624, 36701.63298366982, 1134.4441223800443, 1135.7671677306132, 1156.346664134761, 1169.842071320378, 1192.022541515966, 18621.255149794128, 19009.904950475437, 19014.52463688391, 19090.80909190918, 19096.71354929677, 608291.0, 879706.0, 896907.0, 896992.0, 898181.0, 68409.3159068413, 69206.65396672985, 69231.0, 69249.65375173092, 69433.65283173653, 1334.3995202159067, 1408.6337552236491, 1446.096189881324, 1450.1371683848095, 1483.6290927268183, 34820.0, 35891.64108358883, 35950.12030630735, 36920.44619330658, 38403.807980959915, 62090.0, 68998.0, 69012.0, 69077.0, 69112.0, 558903.0, 565168.0, 565517.0, 565581.0, 566298.0, 43783.34324985192, 51531.0, 51596.48403515917, 52154.478455214965, 52398.21402678887, 55644.44355556393, 56110.438895610525, 56787.0, 57123.714381427824, 57187.714061429426, 46040.53959460362, 48211.75894120647, 48680.2697959538, 48890.0, 49832.750836245585, 47903.0, 47968.0, 48140.759296203294, 48147.0, 48308.75845620749, 47793.76103119462, 47934.76032619815, 48061.27908081312, 48354.274685880446, 48444.0, 39007.804960975016, 41408.792956035024, 42155.36766948437, 42223.0, 45254.54745452503, 31196.22009449691, 31475.527867081553, 31540.526892097096, 31735.52396714005, 31999.36001279915], "value": 23436.85377402314, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [426.709204655916, 436.62232169173654, 439.8856297362686, 442.90034742183, 449.97975091120895, 19431.222751089957, 20254.29109981151, 20656.793432065675, 20867.0, 20896.89551552244, 19305.324313649024, 20231.898840505815, 20312.289069882558, 20330.0, 20341.694874576857, 5959.61262517936, 6134.631922084667, 6179.227596550431, 6186.876262474748, 6191.473724733403, 5977.850553736148, 6039.6980150992495, 6063.060225665029, 6066.150738896558, 6066.454019138277, 5944.970275148618, 6028.698565071751, 6035.486983606399, 6039.788607398742, 6040.426159514861, 5932.851678708045, 5978.432048955342, 5992.0, 5992.610480318787, 6000.0, 5208.765605547722, 5298.602604804617, 5428.4028756836715, 5447.1829225616475, 5474.863128421782, 14729.263536823117, 14858.851411485963, 14997.550073497812, 15139.697206055816, 15140.470083547023, 2317.6871122398597, 2383.1063351243283, 2459.348272707734, 2502.962455563168, 2784.039506370299, 10744.516496757664, 11419.086473082216, 11425.31448113116, 11857.940710296394, 15773.290201940825, 1881.9435416937376, 1890.3100368365585, 1973.4572992427054, 1982.583657431941, 2066.984689927309, 75389.0, 79303.0, 85816.14183858143, 87951.0, 88948.0, 11487.942560287313, 11827.2903625781, 11954.16320857549, 12169.817452738216, 12245.081618878441, 16928.661426771396, 16992.57518562021, 16996.915015425093, 17000.829991699924, 17024.48926532206, 8667.523286219192, 8854.911450885538, 9059.36584439096, 9100.317476189311, 9163.541822908828, 8495.532745699045, 8591.785205369915, 8638.09300023501, 8798.252148567315, 8825.117488251124, 4973.477784832613, 5032.257419706269, 5034.320366750515, 5147.687339728345, 5226.5818734501145, 2131.1688441507827, 2131.435169680036, 2189.136174651741, 2199.0, 2214.4574579228192, 1018.9032041956002, 1033.751899544119, 1057.49768859791, 1087.152021423291, 1099.8405231241425, 20459.38621841347, 21143.154273828874, 21156.78843211579, 21159.471013224793, 21178.47053823636, 1039.6101461951769, 1048.0253364371056, 1062.5059347403494, 1070.4968664727567, 1077.6389909380446, 11150.386728729836, 11613.709657258467, 11730.472128754227, 11752.118591105702, 11822.52709891612, 506400.0, 517188.0, 519560.0, 521026.0, 521117.0, 79173.0, 95526.0, 98096.50951745195, 98605.0, 99699.50150249347, 1028.9742756431149, 1039.0648416425279, 1069.1660504806264, 1069.8555694981233, 1073.3023534702506, 17696.823031769516, 18015.459536213933, 18324.90837545804, 18342.724859126858, 18653.347132850286, 31423.842880786364, 33522.49716254307, 43560.782196090084, 43605.34591981059, 43699.34450983301, 60404.39595604163, 62672.0, 63228.367716322246, 64829.67585162044, 64917.35082649113, 8993.775155621162, 9017.909820901707, 9114.589843457194, 9119.407238529475, 9248.537573121452, 7333.972285451757, 10424.42665653366, 11626.0, 13671.38478768438, 13816.516421925184, 12068.517259309529, 12124.090693198223, 12217.69455763613, 12420.316882571184, 12439.253644781342, 11789.76420471603, 12027.27836329823, 12081.456334464794, 12123.272603643809, 12328.506859725869, 12256.0, 12273.0, 12439.37803109859, 12489.18820276679, 12545.435455404346, 9053.456792592466, 9056.818863622559, 9072.716475114916, 9249.0, 9293.767655808388, 9486.478243696663, 12007.099467539794, 12566.371681416076, 12599.87400125987, 12792.232466052066], "value": 9589.731000140006, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1392.853750356212, 1579.7551379536171, 1589.848964348387, 1781.7861856577204, 1881.407356682644, 9000.189982901538, 9135.862962055566, 9258.120478554534, 9472.242220622351, 9483.146516813486, 8941.284697224222, 9155.496447695374, 9222.492762898039, 9304.488253146092, 9317.627294908196, 2499.7250302466714, 2564.7563481469324, 2565.089393265392, 2565.961510577343, 2569.177863083814, 2291.3927809130596, 2527.8609676467786, 2529.6458495810602, 2532.5061612985473, 2532.962005569918, 2464.53173896959, 2474.059857254241, 2516.937076573091, 2523.2682522068576, 2524.1670248817845, 2454.4232105455117, 2459.2007597530783, 2464.186818349949, 2493.0775613023116, 2497.5254701606636, 2383.8212134090013, 2383.940401489959, 2387.9641805372758, 2389.545986262622, 2480.6403071554705, 9283.25733941283, 9331.206847417978, 9459.10138536838, 9486.051394860528, 9513.66702165421, 5663.603547751659, 5785.132230165466, 5803.5357171426185, 5807.390224026459, 5807.477327040587, 3657.6159503252306, 3716.7769933804057, 3727.683146932514, 3751.4560388743753, 3784.848606055782, 1141.4863311509853, 1141.6632093532428, 1149.230015889356, 1173.436750359832, 2277.9202727904444, 12753.553625623152, 13271.270080145589, 13284.867151328459, 13291.136076155019, 13335.266415366037, 5092.159793634083, 5105.489451054939, 5157.613179011589, 5169.715665638426, 5226.719453733859, 11204.719882003015, 11253.212275140662, 11272.549098035986, 11272.774544509064, 11278.041366483942, 5799.88400231993, 5811.56413268998, 5814.941850581525, 5818.912716309174, 5840.036393995028, 4414.536473670219, 4520.367148599199, 4987.501249874984, 5029.874253143627, 5254.474552544715, 4331.220380331508, 4360.236958532308, 4382.69321147523, 4425.269830478, 4425.911481770411, 1350.129166687481, 1363.7068030373516, 1371.8559551247174, 1412.5762271318563, 1418.1349376880096, 878.5870640798814, 980.4313498171043, 1009.6365308489085, 1054.6519648516125, 1054.8312270036756, 6257.124002639634, 6326.177596912372, 6327.841803954846, 6500.934990650033, 6548.639824809681, 475.6123759136293, 480.0351293899218, 502.1989926067916, 505.3809083872227, 514.7323391836225, 4581.931271030937, 4623.157726564703, 4632.745199014019, 4772.3557319761385, 4805.13507568634, 11133.332000080021, 11343.716407089723, 11459.942700286445, 11479.0, 11483.655490335303, 11149.108071354272, 11406.486708098155, 11409.315441073562, 11587.0, 11604.593839215755, 8480.872786908201, 8650.264727498106, 8653.913460865311, 8662.480251184948, 8694.869576956351, 6526.216853977553, 6531.771388001397, 6611.768588099586, 6663.333666633298, 6800.965995169992, 10583.259171858204, 10763.569457221623, 10857.511411986321, 10890.727731806768, 10974.451277436256, 9503.192228660364, 10621.734456638645, 10991.780164396827, 11127.666170014912, 11150.052245559218, 8230.917690823015, 8586.699465518677, 8621.0, 8635.093315201813, 8660.566971651519, 9033.728988130366, 9124.087591240823, 9160.908390916273, 9183.17351438347, 9207.76980575491, 7874.291313781787, 7978.122406535161, 8101.473404228706, 8110.351171906115, 8225.547594882339, 7645.0, 7914.5646989413835, 8060.879086813585, 8097.959510202647, 8171.468854524437, 7748.418868584991, 7835.0, 7852.37181025528, 7856.25365590268, 7868.409869259714, 5428.972855135699, 5670.886582268414, 5765.827025189251, 5767.798127065533, 5797.623154494944, 5783.479486846204, 5818.650880947157, 5831.446012628793, 5930.318013428511, 6031.5476339273855], "value": 4918.621737574024, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [337.6555912968772, 345.5438820756602, 347.00817308648334, 348.2790623409542, 353.7753526510665, 5427.972860135694, 5436.320459942507, 5463.3717122530925, 5475.863103422416, 5489.1217405215175, 5058.645894787357, 5171.715555644438, 5287.867803304919, 5300.231466437364, 5379.946200537994, 4276.97861510692, 4289.4852617685765, 4296.935545966813, 4313.649061261604, 4318.524962254165, 4263.850765223202, 4289.528151903288, 4293.463317085364, 4315.82736690532, 4319.308910574308, 4016.2569924563904, 4228.957710422887, 4247.936280955788, 4269.274223382017, 4290.249206388885, 4212.452381190456, 4219.578042195786, 4225.852095176685, 4255.808488617989, 4278.4438023056955, 3758.5677647070665, 3763.3065120695524, 3763.4919285896326, 3765.8681946131755, 3782.905427364338, 5251.632385733, 5265.262863199156, 5276.572776522804, 5365.248865158881, 5366.195070739382, 4793.760311984387, 4814.374131362935, 4831.396075490564, 4835.347228124193, 4836.22620380741, 5607.859803504904, 5615.494605485526, 5655.604107712462, 5686.748915238641, 5719.19931209631, 3886.378179491268, 4075.1849630074103, 4103.891949173736, 4110.527289361732, 4125.97937010316, 5807.796727114573, 5815.214945982323, 5815.621984571032, 5832.154337621064, 5851.3270973838125, 4156.147989662112, 4160.687948403821, 4165.541790403053, 4172.77049762266, 4186.748795072305, 4137.668986481134, 4164.250434921743, 4203.306454434984, 4248.957510424856, 4264.232438161161, 4756.072565849695, 4773.64197685175, 4789.425268967746, 4801.663883528189, 4826.903461930741, 5591.944080559224, 5607.66354018752, 5635.718214089279, 5640.5487560995025, 5661.49046585801, 1428.957131286063, 1436.3392839293833, 1457.5991602309239, 1460.5983354577577, 1466.809314789092, 913.3925939250457, 932.9020452852488, 933.659214386744, 950.1306304731231, 961.2598299309493, 4799.904001919942, 4824.34871292378, 4835.564799168092, 4907.607391408679, 4911.238757992517, 5061.292997512349, 5109.8722531937, 5121.8463446096675, 5127.66701643927, 5154.484551544815, 4484.529124441952, 4785.306130611042, 4859.489753575895, 4866.537678920497, 4880.243562247857, 2713.2809805401585, 2730.57676060211, 2735.2204621682727, 2743.7667798236976, 2749.848758318312, 5258.897250622075, 5279.205070276124, 5289.656172348785, 5304.416514183436, 5330.520253177233, 4974.901843844725, 5031.345925029723, 5140.5606430199605, 5160.716160611202, 5181.341970569457, 5147.922781158286, 5211.84364469059, 5215.331094049868, 5230.189320655304, 5242.7116508591635, 5019.7490125494305, 5083.738330827576, 5114.948850511447, 5139.203423469368, 5183.559397451108, 5027.572656324107, 5091.236314552847, 5310.6813591184655, 5773.220615216892, 5786.276715410574, 6009.767997560577, 6046.334903160561, 6046.395360463918, 6046.788362407295, 6047.546434017554, 4224.767637779952, 4246.363045543069, 4249.511306199703, 4252.4897012358715, 4272.0, 5419.0, 5575.316733943507, 5786.855328616818, 5793.14619321813, 5805.82582522525, 5677.432256774289, 5680.147977803363, 5688.7440065198, 5693.544516438592, 5697.800576979786, 5550.7779688812, 5610.9158362623775, 5673.631213971079, 5680.375158732453, 5694.1458781183155, 5616.85957851057, 5643.407442218508, 5647.774089036392, 5673.319201695823, 5676.801311954062, 5707.20099186106, 5711.601127526532, 5721.284839395075, 5744.913826292693, 5759.424057594207, 5436.700981446058, 5498.835034948958, 5564.554835613222, 5572.637778544382, 5573.498385145356], "value": 4204.966024877814, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [796.8366484870601, 835.9791005224869, 838.9748307550772, 844.8056946902209, 898.5227170848816, 13282.0, 14807.777883331732, 14816.703665926689, 14923.92538037311, 14953.626159346022, 13976.301184940738, 14025.298735063232, 14053.929730351334, 14081.507147249848, 14082.859171408283, 775.5463054113336, 888.3825741109931, 895.0333639669175, 907.2424525521164, 908.5638893331172, 889.7063968890251, 897.6723495923974, 898.5372533145435, 900.6622516556291, 901.0944001278706, 917.687986084731, 922.5018490015384, 922.8615707643874, 924.4037595750737, 924.4499522783966, 915.4736026784559, 920.8250432417819, 921.9354645174841, 922.2529750901757, 922.9861552076724, 680.1735890892539, 681.9147606549182, 682.1098466501214, 682.3654001778344, 683.565935630873, 13992.230427326493, 14106.71786564263, 14138.646533836634, 14183.219922904236, 14207.218602976833, 16094.75857862133, 16547.66904661912, 16954.3218271269, 17063.744043839353, 17569.385071522436, 12438.626841194779, 12518.561850335194, 12665.430055647517, 12718.364081795873, 12864.614061578168, 4130.0, 4143.8135283912, 4147.6474499667565, 4225.68307376948, 4259.914801703949, 86471.13528864694, 88040.0, 88082.55958720166, 88624.0, 93919.06080939171, 11767.588134415255, 12026.639200823989, 12080.0, 12564.371781410893, 13101.017423693262, 22142.778572214072, 22415.32754017382, 22422.887885560467, 22440.775592244194, 22444.66333005006, 8375.120612335739, 8466.280366168945, 8540.402171848034, 8582.313414926792, 8698.869516957251, 5058.797648094109, 5096.133657278272, 5133.589312855037, 5209.6874187548865, 5222.955408918231, 3887.5334959805004, 4003.3394489908837, 4015.19696060789, 4024.8188831502653, 4026.778527181033, 678.4775722693498, 694.5589550635282, 695.5930780493367, 695.6695569604398, 704.3519961635308, 751.6692655231681, 765.7932358263349, 769.9384049276044, 776.3905334312537, 780.0717146595533, 9044.547772611342, 9508.239340852715, 9642.662506812228, 9670.468124253093, 9677.177439917545, 693.7606525748608, 743.5204293230836, 755.0674916478146, 767.5739964319856, 775.3680750188578, 4518.683692141519, 4625.583697467244, 4690.15577196108, 4790.784414701347, 4833.927491087637, 115162.0, 116809.0, 117521.0, 120176.39911800386, 138487.30756346154, 92249.0, 94950.0, 104935.22856242827, 107754.0, 111083.0, 58683.413165868646, 78887.60556197182, 85375.573122134, 85508.57245713731, 91554.54222728977, 9905.653302134391, 10138.341007834468, 10159.288849780445, 10411.895881041093, 10474.947625261824, 48387.75806120947, 51894.0, 52392.47607523876, 53224.73387633037, 53380.73309633427, 70358.64820675863, 72492.27507724855, 76821.61589192018, 79053.60473197597, 80679.0, 14641.341139648528, 14700.191489467754, 14807.185604791839, 14955.327010284345, 15016.099034057992, 14717.705645887236, 14731.852681473049, 15204.391824326904, 15660.92169539145, 15992.840071599601, 13401.463941442233, 14274.857251427768, 14706.852931470976, 15161.166135862191, 15264.923675381551, 13276.601701948957, 13381.799273011107, 13460.865391346353, 13877.65305867321, 14830.406783728962, 13654.180749155083, 13797.724045519235, 13938.163710177421, 13950.441982320594, 14042.78935815943, 10629.058642740358, 10642.680719578426, 10712.839307410552, 11026.0076593107, 11120.666380008613, 10489.370637761758, 10774.892251077388, 11051.281666691639, 11576.131790115607, 11631.6510504685], "value": 7923.610324249572, "unit": "score"}], "name": "dromaeo_css", "value": 7681.617195375344, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [111.0, 111.0, 120.0, 111.0, 110.0, 112.0, 111.0, 110.0, 110.0, 110.0], "value": 111.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 114.0, 151.0, 165.0, 115.0, 147.0, 140.0, 116.0, 183.0, 115.0], "value": 139.4, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 160.0, 160.0, 159.0, 160.0, 159.0, 159.0, 160.0, 160.0, 162.0], "value": 159.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 74.0, 76.0, 174.0, 77.0, 74.0, 75.0, 74.0, 183.0, 74.0], "value": 95.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 83.0, 143.0, 83.0, 83.0, 83.0, 152.0, 83.0, 82.0, 144.0], "value": 102.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 116.0, 117.0, 117.0, 117.0, 117.0, 116.0, 117.0, 117.0], "value": 116.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 146.0, 146.0, 146.0, 147.0, 146.0, 146.0, 148.0, 147.0, 146.0], "value": 146.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 94.0, 94.0, 94.0, 94.0, 95.0, 94.0, 95.0, 94.0], "value": 94.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [50.0, 49.0, 49.0, 49.0, 49.0, 52.0, 50.0, 49.0, 49.0, 50.0], "value": 49.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 61.0, 59.0, 60.0, 60.0, 60.0, 60.0, 60.0, 60.0, 60.0], "value": 60.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 75.0, 77.0, 72.0, 77.0, 73.0, 73.0, 72.0, 72.0, 74.0], "value": 74.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [120.0, 117.0, 119.0, 118.0, 115.0, 117.0, 116.0, 131.0, 161.0, 135.0], "value": 124.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [151.0, 150.0, 148.0, 149.0, 154.0, 147.0, 151.0, 151.0, 148.0, 151.0], "value": 150.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 61.0, 63.0, 61.0, 61.0, 61.0, 60.0, 64.0, 61.0, 61.0], "value": 61.7, "unit": "score"}], "name": "kraken", "value": 1486.6000000000001, "alertThreshold": 2.0}]} 22:35:59 INFO - Return code: 0 22:35:59 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 22:35:59 INFO - # TBPL SUCCESS # 22:35:59 INFO - Running post-action listener: _resource_record_post_action 22:35:59 INFO - [mozharness: 2016-10-06 05:35:59.873047Z] Finished run-tests step (success) 22:35:59 INFO - Running post-run listener: _resource_record_post_run 22:35:59 INFO - Total resource usage - Wall time: 1187s; CPU: 13.0%; Read bytes: 12288; Write bytes: 289759232; Read time: 208; Write time: 165728 22:35:59 INFO - TinderboxPrint: CPU usage
13.0% 22:35:59 INFO - TinderboxPrint: I/O read bytes / time
12,288 / 208 22:35:59 INFO - TinderboxPrint: I/O write bytes / time
289,759,232 / 165,728 22:35:59 INFO - TinderboxPrint: CPU idle
8,247.7 (87.0%) 22:35:59 INFO - TinderboxPrint: CPU user
1,184.1 (12.5%) 22:35:59 INFO - TinderboxPrint: Swap in / out
0 / 0 22:35:59 INFO - install - Wall time: 14s; CPU: 14.0%; Read bytes: 4096; Write bytes: 71311360; Read time: 176; Write time: 65644 22:35:59 INFO - run-tests - Wall time: 1173s; CPU: 13.0%; Read bytes: 8192; Write bytes: 218447872; Read time: 32; Write time: 100084 22:36:00 INFO - Running post-run listener: _upload_blobber_files 22:36:00 INFO - Blob upload gear active. 22:36:00 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 22:36:00 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 22:36:00 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'] 22:36:00 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 22:36:00 INFO - (blobuploader) - INFO - Open directory for files ... 22:36:00 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 22:36:00 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:36:00 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:36:01 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 22:36:01 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:36:01 INFO - (blobuploader) - INFO - Done attempting. 22:36:01 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 22:36:01 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:36:01 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:36:05 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 22:36:05 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:36:05 INFO - (blobuploader) - INFO - Done attempting. 22:36:05 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 22:36:05 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:36:05 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:36:05 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 22:36:05 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:36:05 INFO - (blobuploader) - INFO - Done attempting. 22:36:05 INFO - (blobuploader) - INFO - Iteration through files over. 22:36:05 INFO - Return code: 0 22:36:05 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 22:36:05 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 22:36:05 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/ce189cb6a604f38bdabbd582c8f553e5872db9a07dcbe19bb2ade624b040bb4f686451b46a8125ed8449e5e56b516b134df83d2bdeee8aca9028c45a5fe96dd9", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/0254c4b1ce5b9806ad856a737f8eb278ddcabeb12f34cabd0c7fe3a416b09c896aea71cbb60a68374584a1460d0117f8f56b53a3b06e0ceadd4830259db5da69", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/e3d367ee20e9b3538e43db815aa8048ec67355a9feb73a5be6d863b9fa62cfec8e6b414b190756b241c7aca3b7eeb15debffdd85f36a6f875a501f8ad63c6a4b"} 22:36:05 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 22:36:05 INFO - Writing to file /builds/slave/test/properties/blobber_files 22:36:05 INFO - Contents: 22:36:05 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/ce189cb6a604f38bdabbd582c8f553e5872db9a07dcbe19bb2ade624b040bb4f686451b46a8125ed8449e5e56b516b134df83d2bdeee8aca9028c45a5fe96dd9", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/0254c4b1ce5b9806ad856a737f8eb278ddcabeb12f34cabd0c7fe3a416b09c896aea71cbb60a68374584a1460d0117f8f56b53a3b06e0ceadd4830259db5da69", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/e3d367ee20e9b3538e43db815aa8048ec67355a9feb73a5be6d863b9fa62cfec8e6b414b190756b241c7aca3b7eeb15debffdd85f36a6f875a501f8ad63c6a4b"} 22:36:05 INFO - Running post-run listener: copy_logs_to_upload_dir 22:36:05 INFO - Copying logs to upload dir... 22:36:05 INFO - mkdir: /builds/slave/test/build/upload/logs 22:36:05 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1673.929096 ========= master_lag: 1.13 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 27 mins, 55 secs) (at 2016-10-05 22:36:06.261131) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-05 22:36:06.269062) ========= 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=836cfdc75598d54cb747368e000001a9-1475729294.694804-862164654 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/ce189cb6a604f38bdabbd582c8f553e5872db9a07dcbe19bb2ade624b040bb4f686451b46a8125ed8449e5e56b516b134df83d2bdeee8aca9028c45a5fe96dd9", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/0254c4b1ce5b9806ad856a737f8eb278ddcabeb12f34cabd0c7fe3a416b09c896aea71cbb60a68374584a1460d0117f8f56b53a3b06e0ceadd4830259db5da69", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/e3d367ee20e9b3538e43db815aa8048ec67355a9feb73a5be6d863b9fa62cfec8e6b414b190756b241c7aca3b7eeb15debffdd85f36a6f875a501f8ad63c6a4b"} build_url:https://queue.taskcluster.net/v1/task/W0j6x-DDSriCU4DAUoEGzw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011240 build_url: 'https://queue.taskcluster.net/v1/task/W0j6x-DDSriCU4DAUoEGzw/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/ce189cb6a604f38bdabbd582c8f553e5872db9a07dcbe19bb2ade624b040bb4f686451b46a8125ed8449e5e56b516b134df83d2bdeee8aca9028c45a5fe96dd9", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/0254c4b1ce5b9806ad856a737f8eb278ddcabeb12f34cabd0c7fe3a416b09c896aea71cbb60a68374584a1460d0117f8f56b53a3b06e0ceadd4830259db5da69", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/e3d367ee20e9b3538e43db815aa8048ec67355a9feb73a5be6d863b9fa62cfec8e6b414b190756b241c7aca3b7eeb15debffdd85f36a6f875a501f8ad63c6a4b"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-05 22:36:06.293668) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-05 22:36:06.293965) ========= 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=836cfdc75598d54cb747368e000001a9-1475729294.694804-862164654 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004454 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-05 22:36:06.343291) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-05 22:36:06.343589) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-05 22:36:06.346898) ========= ========= Total master_lag: 1.27 =========