builder: mozilla-beta_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-035 starttime: 1477024243.6 results: success (0) buildid: 20161020185050 builduid: d643df0dba384192a90d393b16a49c37 revision: d69e6eb5d19f81e22ddd026857e2b5b7b57d37b5 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-10-20 21:30:43.603949) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-10-20 21:30:43.606434) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-20 21:30:43.606873) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-20 21:30:43.653156) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-20 21:30:43.653468) ========= 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=ed14279e38b05397cf4aa391000001a4-1477023971.670634-1232523364 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-10-20 21:30:43-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 16.0M=0.001s 2016-10-20 21:30:43 (16.0 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.196231 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-20 21:30:43.864196) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-20 21:30:43.864516) ========= 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=ed14279e38b05397cf4aa391000001a4-1477023971.670634-1232523364 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.019880 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-20 21:30:43.923258) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-20 21:30:43.923651) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev d69e6eb5d19f81e22ddd026857e2b5b7b57d37b5 --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 d69e6eb5d19f81e22ddd026857e2b5b7b57d37b5 --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=ed14279e38b05397cf4aa391000001a4-1477023971.670634-1232523364 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-10-20 21:30:44,054 truncating revision to first 12 chars 2016-10-20 21:30:44,054 Setting DEBUG logging. 2016-10-20 21:30:44,054 attempt 1/10 2016-10-20 21:30:44,054 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/d69e6eb5d19f?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-10-20 21:30:44,632 unpacking tar archive at: mozilla-beta-d69e6eb5d19f/testing/mozharness/ program finished with exit code 0 elapsedTime=0.895704 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-20 21:30:44.834801) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-20 21:30:44.835172) ========= script_repo_revision: d69e6eb5d19f81e22ddd026857e2b5b7b57d37b5 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-20 21:30:44.835668) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-20 21:30:44.835983) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-20 21:30:44.850289) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 27 mins, 55 secs) (at 2016-10-20 21:30:44.850629) ========= /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=ed14279e38b05397cf4aa391000001a4-1477023971.670634-1232523364 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 21:30:45 INFO - MultiFileLogger online at 20161020 21:30:45 in /builds/slave/test 21:30:45 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 21:30:45 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 21:30:45 INFO - {'append_to_log': False, 21:30:45 INFO - 'base_work_dir': '/builds/slave/test', 21:30:45 INFO - 'blob_upload_branch': 'Mozilla-Beta', 21:30:45 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 21:30:45 INFO - 'branch': 'Mozilla-Beta', 21:30:45 INFO - 'buildbot_json_path': 'buildprops.json', 21:30:45 INFO - 'config_files': ('talos/linux_config.py',), 21:30:45 INFO - 'default_actions': ('clobber', 21:30:45 INFO - 'read-buildbot-config', 21:30:45 INFO - 'download-and-extract', 21:30:45 INFO - 'populate-webroot', 21:30:45 INFO - 'create-virtualenv', 21:30:45 INFO - 'install', 21:30:45 INFO - 'run-tests'), 21:30:45 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 21:30:45 INFO - 'download_minidump_stackwalk': True, 21:30:45 INFO - 'download_symbols': 'ondemand', 21:30:45 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 21:30:45 INFO - 'tooltool.py': '/tools/tooltool.py', 21:30:45 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 21:30:45 INFO - '/tools/misc-python/virtualenv.py')}, 21:30:45 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 21:30:45 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 21:30:45 INFO - 'installer_path': 'installer.exe', 21:30:45 INFO - 'log_level': 'info', 21:30:45 INFO - 'log_name': 'talos', 21:30:45 INFO - 'log_to_console': True, 21:30:45 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 21:30:45 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 21:30:45 INFO - 'opt_config_files': (), 21:30:45 INFO - 'pip_index': False, 21:30:45 INFO - 'sps_profile': False, 21:30:45 INFO - 'sps_profile_interval': 0, 21:30:45 INFO - 'suite': 'dromaeojs', 21:30:45 INFO - 'system_bits': '32', 21:30:45 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 21:30:45 INFO - 'title': 'talos-linux64-ix-035', 21:30:45 INFO - 'tooltool_cache': '/builds/tooltool_cache', 21:30:45 INFO - 'use_talos_json': True, 21:30:45 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 21:30:45 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 21:30:45 INFO - 'work_dir': 'build'} 21:30:45 INFO - [mozharness: 2016-10-21 04:30:45.076142Z] Running clobber step. 21:30:45 INFO - Running pre-action listener: _resource_record_pre_action 21:30:45 INFO - Running main action method: clobber 21:30:45 INFO - rmtree: /builds/slave/test/build 21:30:45 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 21:30:46 INFO - Running post-action listener: _resource_record_post_action 21:30:46 INFO - [mozharness: 2016-10-21 04:30:46.033888Z] Finished clobber step (success) 21:30:46 INFO - [mozharness: 2016-10-21 04:30:46.033988Z] Running read-buildbot-config step. 21:30:46 INFO - Running pre-action listener: _resource_record_pre_action 21:30:46 INFO - Running main action method: read_buildbot_config 21:30:46 INFO - Using buildbot properties: 21:30:46 INFO - { 21:30:46 INFO - "project": "", 21:30:46 INFO - "product": "firefox", 21:30:46 INFO - "script_repo_revision": "production", 21:30:46 INFO - "builddir": "mozilla-beta_ubuntu64_hw_test-dromaeojs", 21:30:46 INFO - "repository": "", 21:30:46 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-beta pgo talos dromaeojs", 21:30:46 INFO - "buildid": "20161020185050", 21:30:46 INFO - "pgo_build": "True", 21:30:46 INFO - "basedir": "/builds/slave/test", 21:30:46 INFO - "buildnumber": 437, 21:30:46 INFO - "slavename": "talos-linux64-ix-035", 21:30:46 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 21:30:46 INFO - "platform": "ubuntu64_hw", 21:30:46 INFO - "scheduler": "tests-mozilla-beta-linux64-pgo-talos", 21:30:46 INFO - "branch": "mozilla-beta", 21:30:46 INFO - "repo_path": "releases/mozilla-beta", 21:30:46 INFO - "revision": "d69e6eb5d19f81e22ddd026857e2b5b7b57d37b5", 21:30:46 INFO - "stage_platform": "linux64-pgo", 21:30:46 INFO - "builduid": "d643df0dba384192a90d393b16a49c37", 21:30:46 INFO - "slavebuilddir": "test" 21:30:46 INFO - } 21:30:46 INFO - Found installer url https://queue.taskcluster.net/v1/task/bwlu0zmBSsyZpm4qeJimlA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2. 21:30:46 INFO - Running post-action listener: _resource_record_post_action 21:30:46 INFO - [mozharness: 2016-10-21 04:30:46.036459Z] Finished read-buildbot-config step (success) 21:30:46 INFO - [mozharness: 2016-10-21 04:30:46.036549Z] Running download-and-extract step. 21:30:46 INFO - Running pre-action listener: _resource_record_pre_action 21:30:46 INFO - Running main action method: download_and_extract 21:30:46 INFO - mkdir: /builds/slave/test/build/tests 21:30:46 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')]} 21:30:46 INFO - https://queue.taskcluster.net/v1/task/bwlu0zmBSsyZpm4qeJimlA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 21:30:46 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bwlu0zmBSsyZpm4qeJimlA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 21:30:46 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bwlu0zmBSsyZpm4qeJimlA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 21:30:46 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bwlu0zmBSsyZpm4qeJimlA/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 21:30:46 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bwlu0zmBSsyZpm4qeJimlA/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 21:30:46 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bwlu0zmBSsyZpm4qeJimlA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 21:30:46 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 21:30:46 INFO - retry: Failed, sleeping 30 seconds before retrying 21:31:16 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bwlu0zmBSsyZpm4qeJimlA/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 21:31:16 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bwlu0zmBSsyZpm4qeJimlA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 21:31:16 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 21:31:16 INFO - retry: Failed, sleeping 60 seconds before retrying 21:32:16 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bwlu0zmBSsyZpm4qeJimlA/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 21:32:16 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bwlu0zmBSsyZpm4qeJimlA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 21:32:16 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 21:32:16 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bwlu0zmBSsyZpm4qeJimlA/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! 21:32:16 INFO - Caught exception: HTTP Error 404: Not Found 21:32:16 INFO - Caught exception: HTTP Error 404: Not Found 21:32:16 INFO - Caught exception: HTTP Error 404: Not Found 21:32:16 INFO - trying https://queue.taskcluster.net/v1/task/bwlu0zmBSsyZpm4qeJimlA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 21:32:16 INFO - Downloading https://queue.taskcluster.net/v1/task/bwlu0zmBSsyZpm4qeJimlA/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 21:32:16 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/bwlu0zmBSsyZpm4qeJimlA/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 21:32:16 INFO - Downloaded 1327 bytes. 21:32:16 INFO - Reading from file /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 21:32:16 INFO - Using the following test package requirements: 21:32:16 INFO - {u'common': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip'], 21:32:16 INFO - u'cppunittest': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 21:32:16 INFO - u'firefox-50.0.en-US.linux-x86_64.cppunittest.tests.zip'], 21:32:16 INFO - u'gtest': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 21:32:16 INFO - u'firefox-50.0.en-US.linux-x86_64.gtest.tests.zip'], 21:32:16 INFO - u'jittest': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 21:32:16 INFO - u'jsshell-linux-x86_64.zip'], 21:32:16 INFO - u'mochitest': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 21:32:16 INFO - u'firefox-50.0.en-US.linux-x86_64.mochitest.tests.zip'], 21:32:16 INFO - u'mozbase': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip'], 21:32:16 INFO - u'reftest': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 21:32:16 INFO - u'firefox-50.0.en-US.linux-x86_64.reftest.tests.zip'], 21:32:16 INFO - u'talos': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 21:32:16 INFO - u'firefox-50.0.en-US.linux-x86_64.talos.tests.zip'], 21:32:16 INFO - u'web-platform': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 21:32:16 INFO - u'firefox-50.0.en-US.linux-x86_64.web-platform.tests.zip'], 21:32:16 INFO - u'xpcshell': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 21:32:16 INFO - u'firefox-50.0.en-US.linux-x86_64.xpcshell.tests.zip']} 21:32:16 INFO - Downloading packages: [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 21:32:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:32:16 INFO - https://queue.taskcluster.net/v1/task/bwlu0zmBSsyZpm4qeJimlA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 21:32:16 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bwlu0zmBSsyZpm4qeJimlA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 21:32:16 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bwlu0zmBSsyZpm4qeJimlA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 21:32:16 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bwlu0zmBSsyZpm4qeJimlA/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 21:32:16 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bwlu0zmBSsyZpm4qeJimlA/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 21:32:16 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bwlu0zmBSsyZpm4qeJimlA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 21:32:16 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 21:32:16 INFO - retry: Failed, sleeping 30 seconds before retrying 21:32:46 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bwlu0zmBSsyZpm4qeJimlA/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 21:32:46 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bwlu0zmBSsyZpm4qeJimlA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 21:32:46 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 21:32:46 INFO - retry: Failed, sleeping 60 seconds before retrying 21:33:46 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bwlu0zmBSsyZpm4qeJimlA/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 21:33:46 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bwlu0zmBSsyZpm4qeJimlA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 21:33:46 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 21:33:46 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bwlu0zmBSsyZpm4qeJimlA/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! 21:33:46 INFO - Caught exception: HTTP Error 404: Not Found 21:33:46 INFO - Caught exception: HTTP Error 404: Not Found 21:33:46 INFO - Caught exception: HTTP Error 404: Not Found 21:33:46 INFO - trying https://queue.taskcluster.net/v1/task/bwlu0zmBSsyZpm4qeJimlA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 21:33:46 INFO - Downloading https://queue.taskcluster.net/v1/task/bwlu0zmBSsyZpm4qeJimlA/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 21:33:46 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/bwlu0zmBSsyZpm4qeJimlA/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 21:33:48 INFO - Downloaded 21774592 bytes. 21:33:48 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'] 21:33:48 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 21:33:49 INFO - Return code: 0 21:33:49 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 21:33:49 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')]} 21:33:49 INFO - https://queue.taskcluster.net/v1/task/bwlu0zmBSsyZpm4qeJimlA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 21:33:49 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bwlu0zmBSsyZpm4qeJimlA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 21:33:49 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bwlu0zmBSsyZpm4qeJimlA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 21:33:49 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bwlu0zmBSsyZpm4qeJimlA/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 21:33:49 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bwlu0zmBSsyZpm4qeJimlA/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 21:33:49 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bwlu0zmBSsyZpm4qeJimlA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 21:33:49 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 21:33:49 INFO - retry: Failed, sleeping 30 seconds before retrying 21:34:19 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bwlu0zmBSsyZpm4qeJimlA/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 21:34:19 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bwlu0zmBSsyZpm4qeJimlA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 21:34:19 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 21:34:19 INFO - retry: Failed, sleeping 60 seconds before retrying 21:35:19 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bwlu0zmBSsyZpm4qeJimlA/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 21:35:19 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bwlu0zmBSsyZpm4qeJimlA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 21:35:19 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 21:35:19 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bwlu0zmBSsyZpm4qeJimlA/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! 21:35:19 INFO - Caught exception: HTTP Error 404: Not Found 21:35:19 INFO - Caught exception: HTTP Error 404: Not Found 21:35:19 INFO - Caught exception: HTTP Error 404: Not Found 21:35:19 INFO - trying https://queue.taskcluster.net/v1/task/bwlu0zmBSsyZpm4qeJimlA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 21:35:19 INFO - Downloading https://queue.taskcluster.net/v1/task/bwlu0zmBSsyZpm4qeJimlA/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 21:35:19 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/bwlu0zmBSsyZpm4qeJimlA/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 21:35:20 INFO - Downloaded 21774592 bytes. 21:35:20 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'] 21:35:20 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 21:35:22 INFO - Return code: 0 21:35:22 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')]} 21:35:22 INFO - https://queue.taskcluster.net/v1/task/bwlu0zmBSsyZpm4qeJimlA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 21:35:22 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bwlu0zmBSsyZpm4qeJimlA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 21:35:22 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bwlu0zmBSsyZpm4qeJimlA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 21:35:22 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bwlu0zmBSsyZpm4qeJimlA/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 21:35:22 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bwlu0zmBSsyZpm4qeJimlA/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 21:35:22 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bwlu0zmBSsyZpm4qeJimlA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 21:35:22 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 21:35:22 INFO - retry: Failed, sleeping 30 seconds before retrying 21:35:52 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bwlu0zmBSsyZpm4qeJimlA/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 21:35:52 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bwlu0zmBSsyZpm4qeJimlA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 21:35:52 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 21:35:52 INFO - retry: Failed, sleeping 60 seconds before retrying 21:36:52 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bwlu0zmBSsyZpm4qeJimlA/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 21:36:52 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bwlu0zmBSsyZpm4qeJimlA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 21:36:52 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 21:36:52 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bwlu0zmBSsyZpm4qeJimlA/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! 21:36:52 INFO - Caught exception: HTTP Error 404: Not Found 21:36:52 INFO - Caught exception: HTTP Error 404: Not Found 21:36:52 INFO - Caught exception: HTTP Error 404: Not Found 21:36:52 INFO - trying https://queue.taskcluster.net/v1/task/bwlu0zmBSsyZpm4qeJimlA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 21:36:52 INFO - Downloading https://queue.taskcluster.net/v1/task/bwlu0zmBSsyZpm4qeJimlA/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 21:36:52 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/bwlu0zmBSsyZpm4qeJimlA/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 21:36:53 INFO - Downloaded 14413762 bytes. 21:36:53 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'] 21:36:53 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 21:36:53 INFO - Return code: 0 21:36: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')]} 21:36:53 INFO - https://queue.taskcluster.net/v1/task/bwlu0zmBSsyZpm4qeJimlA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 21:36:53 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bwlu0zmBSsyZpm4qeJimlA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 21:36:53 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bwlu0zmBSsyZpm4qeJimlA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 21:36:53 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bwlu0zmBSsyZpm4qeJimlA/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 21:36:53 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bwlu0zmBSsyZpm4qeJimlA/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 21:36:53 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bwlu0zmBSsyZpm4qeJimlA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 21:36:53 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 21:36:53 INFO - retry: Failed, sleeping 30 seconds before retrying 21:37:24 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bwlu0zmBSsyZpm4qeJimlA/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 21:37:24 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bwlu0zmBSsyZpm4qeJimlA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 21:37:24 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 21:37:24 INFO - retry: Failed, sleeping 60 seconds before retrying 21:38:24 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bwlu0zmBSsyZpm4qeJimlA/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 21:38:24 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bwlu0zmBSsyZpm4qeJimlA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 21:38:24 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 21:38:24 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bwlu0zmBSsyZpm4qeJimlA/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! 21:38:24 INFO - Caught exception: HTTP Error 404: Not Found 21:38:24 INFO - Caught exception: HTTP Error 404: Not Found 21:38:24 INFO - Caught exception: HTTP Error 404: Not Found 21:38:24 INFO - trying https://queue.taskcluster.net/v1/task/bwlu0zmBSsyZpm4qeJimlA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 21:38:24 INFO - Downloading https://queue.taskcluster.net/v1/task/bwlu0zmBSsyZpm4qeJimlA/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 21:38:24 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/bwlu0zmBSsyZpm4qeJimlA/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 21:38:25 INFO - Downloaded 56798856 bytes. 21:38:25 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/bwlu0zmBSsyZpm4qeJimlA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 21:38:25 INFO - mkdir: /builds/slave/test/properties 21:38:25 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 21:38:25 INFO - Writing to file /builds/slave/test/properties/build_url 21:38:25 INFO - Contents: 21:38:25 INFO - build_url:https://queue.taskcluster.net/v1/task/bwlu0zmBSsyZpm4qeJimlA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 21:38:26 INFO - Running post-action listener: _resource_record_post_action 21:38:26 INFO - Running post-action listener: set_extra_try_arguments 21:38:26 INFO - [mozharness: 2016-10-21 04:38:26.034347Z] Finished download-and-extract step (success) 21:38:26 INFO - [mozharness: 2016-10-21 04:38:26.034582Z] Running populate-webroot step. 21:38:26 INFO - Running pre-action listener: _resource_record_pre_action 21:38:26 INFO - Running main action method: populate_webroot 21:38:26 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 21:38:26 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 21:38:26 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 21:38:26 INFO - '--tppagecycles', 21:38:26 INFO - '7'], 21:38:26 INFO - 'tests': ['tsvgm']}}, 21:38:26 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 21:38:26 INFO - 'tests': ['tresize', 'tcanvasmark']}, 21:38:26 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 21:38:26 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 21:38:26 INFO - 'tests': ['dromaeo_css', 'kraken']}, 21:38:26 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 21:38:26 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:38:26 INFO - 'talos_options': ['--disable-e10s'], 21:38:26 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 21:38:26 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:38:26 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 21:38:26 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:38:26 INFO - 'talos_options': ['--disable-e10s'], 21:38:26 INFO - 'tests': ['damp', 'tps']}, 21:38:26 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:38:26 INFO - 'tests': ['damp', 'tps']}, 21:38:26 INFO - 'g3': {'talos_options': ['--disable-e10s'], 21:38:26 INFO - 'tests': ['dromaeo_dom']}, 21:38:26 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 21:38:26 INFO - 'g4': {'talos_options': ['--disable-e10s'], 21:38:26 INFO - 'tests': ['basic_compositor_video']}, 21:38:26 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 21:38:26 INFO - 'other': {'talos_options': ['--disable-e10s'], 21:38:26 INFO - 'tests': ['a11yr', 21:38:26 INFO - 'ts_paint', 21:38:26 INFO - 'tpaint', 21:38:26 INFO - 'sessionrestore', 21:38:26 INFO - 'sessionrestore_no_auto_restore', 21:38:26 INFO - 'tabpaint']}, 21:38:26 INFO - 'other-e10s': {'tests': ['a11yr', 21:38:26 INFO - 'ts_paint', 21:38:26 INFO - 'tpaint', 21:38:26 INFO - 'sessionrestore', 21:38:26 INFO - 'sessionrestore_no_auto_restore', 21:38:26 INFO - 'tabpaint']}, 21:38:26 INFO - 'other-e10s_l64': {'tests': ['a11yr', 21:38:26 INFO - 'ts_paint', 21:38:26 INFO - 'tpaint', 21:38:26 INFO - 'sessionrestore', 21:38:26 INFO - 'sessionrestore_no_auto_restore', 21:38:26 INFO - 'tabpaint']}, 21:38:26 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 21:38:26 INFO - 'ts_paint', 21:38:26 INFO - 'tpaint', 21:38:26 INFO - 'sessionrestore', 21:38:26 INFO - 'sessionrestore_no_auto_restore', 21:38:26 INFO - 'tabpaint']}, 21:38:26 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 21:38:26 INFO - 'tests': ['a11yr', 21:38:26 INFO - 'ts_paint', 21:38:26 INFO - 'tpaint', 21:38:26 INFO - 'sessionrestore', 21:38:26 INFO - 'sessionrestore_no_auto_restore', 21:38:26 INFO - 'tabpaint']}, 21:38:26 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 21:38:26 INFO - 'tests': ['a11yr', 21:38:26 INFO - 'ts_paint', 21:38:26 INFO - 'tpaint', 21:38:26 INFO - 'sessionrestore', 21:38:26 INFO - 'sessionrestore_no_auto_restore', 21:38:26 INFO - 'tabpaint']}, 21:38:26 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 21:38:26 INFO - 'tests': ['tsvgx', 21:38:26 INFO - 'tsvgr_opacity', 21:38:26 INFO - 'tart', 21:38:26 INFO - 'tscrollx', 21:38:26 INFO - 'cart']}, 21:38:26 INFO - 'svgr-e10s': {'tests': ['tsvgx', 21:38:26 INFO - 'tsvgr_opacity', 21:38:26 INFO - 'tart', 21:38:26 INFO - 'tscrollx', 21:38:26 INFO - 'cart']}, 21:38:26 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:38:26 INFO - 'talos_options': ['--disable-e10s'], 21:38:26 INFO - 'tests': ['tp5o']}, 21:38:26 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:38:26 INFO - 'tests': ['tp5o']}, 21:38:26 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:38:26 INFO - 'talos_options': ['--disable-e10s', 21:38:26 INFO - '--xperf_path', 21:38:26 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 21:38:26 INFO - 'tests': ['tp5n']}, 21:38:26 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:38:26 INFO - 'talos_options': ['--xperf_path', 21:38:26 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 21:38:26 INFO - 'tests': ['tp5n']}}, 21:38:26 INFO - 'talos.zip': {'path': '', 21:38:26 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 21:38:26 INFO - Running post-action listener: _resource_record_post_action 21:38:26 INFO - [mozharness: 2016-10-21 04:38:26.053641Z] Finished populate-webroot step (success) 21:38:26 INFO - [mozharness: 2016-10-21 04:38:26.053730Z] Running create-virtualenv step. 21:38:26 INFO - Running pre-action listener: _resource_record_pre_action 21:38:26 INFO - Running main action method: create_virtualenv 21:38:26 INFO - Creating virtualenv /builds/slave/test/build/venv 21:38:26 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 21:38:26 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 21:38:26 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 21:38:26 INFO - Using real prefix '/usr' 21:38:26 INFO - New python executable in /builds/slave/test/build/venv/bin/python 21:38:26 INFO - Installing distribute.............................................................................................................................................................................................done. 21:38:28 INFO - Installing pip.................done. 21:38:28 INFO - Return code: 0 21:38:28 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 21:38:28 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')]} 21:38:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:38:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:38:28 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')]} 21:38:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:38:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:38:28 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 0xd1e030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe818a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xfdf180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfdd3b0>, '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 0xfde2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xfde740>, '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': 'ed14279e38b05397cf4aa391000001a4-1477023971.670634-1232523364', '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 21:38:28 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 21:38:28 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 21:38:28 INFO - Using env: {'DISPLAY': ':0', 21:38:28 INFO - 'HOME': '/home/cltbld', 21:38:28 INFO - 'LANG': 'en_US.UTF-8', 21:38:28 INFO - 'LANGUAGE': 'en_US:en', 21:38:28 INFO - 'LOGNAME': 'cltbld', 21:38:28 INFO - 'MAIL': '/var/mail/cltbld', 21:38:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:38:28 INFO - 'MOZ_NO_REMOTE': '1', 21:38:28 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:38:28 INFO - 'NO_EM_RESTART': '1', 21:38:28 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:38:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:38:28 INFO - 'PWD': '/builds/slave/test', 21:38:28 INFO - 'SHELL': '/bin/bash', 21:38:28 INFO - 'SHLVL': '1', 21:38:28 INFO - 'TERM': 'linux', 21:38:28 INFO - 'TMOUT': '86400', 21:38:28 INFO - 'USER': 'cltbld', 21:38:28 INFO - 'XDG_SESSION_COOKIE': 'ed14279e38b05397cf4aa391000001a4-1477023971.670634-1232523364', 21:38:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:38:28 INFO - '_': '/tools/buildbot/bin/python'} 21:38:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:38:28 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 21:38:28 INFO - Cleaning up... 21:38:28 INFO - Return code: 0 21:38:28 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 21:38:28 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')]} 21:38:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:38:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:38:28 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')]} 21:38:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:38:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:38:28 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 0xd1e030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe818a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xfdf180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfdd3b0>, '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 0xfde2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xfde740>, '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': 'ed14279e38b05397cf4aa391000001a4-1477023971.670634-1232523364', '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 21:38:28 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 21:38:28 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 21:38:28 INFO - Using env: {'DISPLAY': ':0', 21:38:28 INFO - 'HOME': '/home/cltbld', 21:38:28 INFO - 'LANG': 'en_US.UTF-8', 21:38:28 INFO - 'LANGUAGE': 'en_US:en', 21:38:28 INFO - 'LOGNAME': 'cltbld', 21:38:28 INFO - 'MAIL': '/var/mail/cltbld', 21:38:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:38:28 INFO - 'MOZ_NO_REMOTE': '1', 21:38:28 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:38:28 INFO - 'NO_EM_RESTART': '1', 21:38:28 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:38:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:38:28 INFO - 'PWD': '/builds/slave/test', 21:38:28 INFO - 'SHELL': '/bin/bash', 21:38:28 INFO - 'SHLVL': '1', 21:38:28 INFO - 'TERM': 'linux', 21:38:28 INFO - 'TMOUT': '86400', 21:38:28 INFO - 'USER': 'cltbld', 21:38:28 INFO - 'XDG_SESSION_COOKIE': 'ed14279e38b05397cf4aa391000001a4-1477023971.670634-1232523364', 21:38:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:38:28 INFO - '_': '/tools/buildbot/bin/python'} 21:38:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:38:29 INFO - Downloading/unpacking psutil>=3.1.1 21:38:29 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 21:38:29 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 21:38:29 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 21:38:29 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 21:38:29 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 21:38:29 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 21:38:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 21:38:31 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 21:38:31 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 21:38:31 INFO - Installing collected packages: psutil 21:38:31 INFO - Running setup.py install for psutil 21:38:31 INFO - building 'psutil._psutil_linux' extension 21:38:31 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 21:38:31 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 21:38:31 INFO - building 'psutil._psutil_posix' extension 21:38:31 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 21:38:31 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 21:38:31 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 21:38:31 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 21:38:31 INFO - Successfully installed psutil 21:38:31 INFO - Cleaning up... 21:38:31 INFO - Return code: 0 21:38:31 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 21:38:31 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')]} 21:38:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:38:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:38:31 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')]} 21:38:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:38:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:38:31 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 0xd1e030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe818a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xfdf180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfdd3b0>, '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 0xfde2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xfde740>, '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': 'ed14279e38b05397cf4aa391000001a4-1477023971.670634-1232523364', '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 21:38:31 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 21:38:31 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 21:38:31 INFO - Using env: {'DISPLAY': ':0', 21:38:31 INFO - 'HOME': '/home/cltbld', 21:38:31 INFO - 'LANG': 'en_US.UTF-8', 21:38:31 INFO - 'LANGUAGE': 'en_US:en', 21:38:31 INFO - 'LOGNAME': 'cltbld', 21:38:31 INFO - 'MAIL': '/var/mail/cltbld', 21:38:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:38:31 INFO - 'MOZ_NO_REMOTE': '1', 21:38:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:38:31 INFO - 'NO_EM_RESTART': '1', 21:38:31 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:38:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:38:31 INFO - 'PWD': '/builds/slave/test', 21:38:31 INFO - 'SHELL': '/bin/bash', 21:38:31 INFO - 'SHLVL': '1', 21:38:31 INFO - 'TERM': 'linux', 21:38:31 INFO - 'TMOUT': '86400', 21:38:31 INFO - 'USER': 'cltbld', 21:38:31 INFO - 'XDG_SESSION_COOKIE': 'ed14279e38b05397cf4aa391000001a4-1477023971.670634-1232523364', 21:38:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:38:31 INFO - '_': '/tools/buildbot/bin/python'} 21:38:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:38:31 INFO - Downloading/unpacking mozsystemmonitor==0.3 21:38:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:38:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:38:31 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:38:31 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:38:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:38:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:38:33 INFO - Downloading mozsystemmonitor-0.3.tar.gz 21:38:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 21:38:33 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 21:38:33 INFO - Installing collected packages: mozsystemmonitor 21:38:33 INFO - Running setup.py install for mozsystemmonitor 21:38:33 INFO - Successfully installed mozsystemmonitor 21:38:33 INFO - Cleaning up... 21:38:33 INFO - Return code: 0 21:38:33 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 21:38:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 21:38:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:38:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:38:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 21:38:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:38:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:38:33 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xd1e030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe818a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xfdf180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfdd3b0>, '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 0xfde2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xfde740>, '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': 'ed14279e38b05397cf4aa391000001a4-1477023971.670634-1232523364', '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 21:38:33 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 21:38:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 21:38:33 INFO - Using env: {'DISPLAY': ':0', 21:38:33 INFO - 'HOME': '/home/cltbld', 21:38:33 INFO - 'LANG': 'en_US.UTF-8', 21:38:33 INFO - 'LANGUAGE': 'en_US:en', 21:38:33 INFO - 'LOGNAME': 'cltbld', 21:38:33 INFO - 'MAIL': '/var/mail/cltbld', 21:38:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:38:33 INFO - 'MOZ_NO_REMOTE': '1', 21:38:33 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:38:33 INFO - 'NO_EM_RESTART': '1', 21:38:33 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:38:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:38:33 INFO - 'PWD': '/builds/slave/test', 21:38:33 INFO - 'SHELL': '/bin/bash', 21:38:33 INFO - 'SHLVL': '1', 21:38:33 INFO - 'TERM': 'linux', 21:38:33 INFO - 'TMOUT': '86400', 21:38:33 INFO - 'USER': 'cltbld', 21:38:33 INFO - 'XDG_SESSION_COOKIE': 'ed14279e38b05397cf4aa391000001a4-1477023971.670634-1232523364', 21:38:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:38:33 INFO - '_': '/tools/buildbot/bin/python'} 21:38:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:38:34 INFO - Downloading/unpacking jsonschema==2.5.1 21:38:34 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 21:38:34 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 21:38:34 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 21:38:34 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 21:38:34 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 21:38:34 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 21:38:36 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 21:38:36 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 21:38:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:38:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:38:36 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:38:36 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:38:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:38:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:38:36 INFO - Downloading functools32-3.2.3-2.tar.gz 21:38:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 21:38:36 INFO - warning: no files found matching '*.txt' 21:38:36 INFO - no previously-included directories found matching 'build' 21:38:36 INFO - no previously-included directories found matching 'dist' 21:38:36 INFO - no previously-included directories found matching '.git*' 21:38:36 INFO - Installing collected packages: jsonschema, functools32 21:38:36 INFO - Running setup.py install for functools32 21:38:36 INFO - warning: no files found matching '*.txt' 21:38:36 INFO - no previously-included directories found matching 'build' 21:38:36 INFO - no previously-included directories found matching 'dist' 21:38:36 INFO - no previously-included directories found matching '.git*' 21:38:36 INFO - Successfully installed jsonschema functools32 21:38:36 INFO - Cleaning up... 21:38:36 INFO - Return code: 0 21:38:36 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 21:38:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 21:38:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:38:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:38:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 21:38:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:38:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:38:36 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0xd1e030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe818a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xfdf180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfdd3b0>, '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 0xfde2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xfde740>, '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': 'ed14279e38b05397cf4aa391000001a4-1477023971.670634-1232523364', '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 21:38:36 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 21:38:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 21:38:36 INFO - Using env: {'DISPLAY': ':0', 21:38:36 INFO - 'HOME': '/home/cltbld', 21:38:36 INFO - 'LANG': 'en_US.UTF-8', 21:38:36 INFO - 'LANGUAGE': 'en_US:en', 21:38:36 INFO - 'LOGNAME': 'cltbld', 21:38:36 INFO - 'MAIL': '/var/mail/cltbld', 21:38:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:38:36 INFO - 'MOZ_NO_REMOTE': '1', 21:38:36 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:38:36 INFO - 'NO_EM_RESTART': '1', 21:38:36 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:38:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:38:36 INFO - 'PWD': '/builds/slave/test', 21:38:36 INFO - 'SHELL': '/bin/bash', 21:38:36 INFO - 'SHLVL': '1', 21:38:36 INFO - 'TERM': 'linux', 21:38:36 INFO - 'TMOUT': '86400', 21:38:36 INFO - 'USER': 'cltbld', 21:38:36 INFO - 'XDG_SESSION_COOKIE': 'ed14279e38b05397cf4aa391000001a4-1477023971.670634-1232523364', 21:38:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:38:36 INFO - '_': '/tools/buildbot/bin/python'} 21:38:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:38:36 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 21:38:36 INFO - Cleaning up... 21:38:36 INFO - Return code: 0 21:38:36 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 21:38:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 21:38:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:38:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:38:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 21:38:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:38:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:38:36 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xd1e030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe818a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xfdf180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfdd3b0>, '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 0xfde2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xfde740>, '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': 'ed14279e38b05397cf4aa391000001a4-1477023971.670634-1232523364', '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 21:38:36 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 21:38:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 21:38:36 INFO - Using env: {'DISPLAY': ':0', 21:38:36 INFO - 'HOME': '/home/cltbld', 21:38:36 INFO - 'LANG': 'en_US.UTF-8', 21:38:36 INFO - 'LANGUAGE': 'en_US:en', 21:38:36 INFO - 'LOGNAME': 'cltbld', 21:38:36 INFO - 'MAIL': '/var/mail/cltbld', 21:38:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:38:36 INFO - 'MOZ_NO_REMOTE': '1', 21:38:36 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:38:36 INFO - 'NO_EM_RESTART': '1', 21:38:36 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:38:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:38:36 INFO - 'PWD': '/builds/slave/test', 21:38:36 INFO - 'SHELL': '/bin/bash', 21:38:36 INFO - 'SHLVL': '1', 21:38:36 INFO - 'TERM': 'linux', 21:38:36 INFO - 'TMOUT': '86400', 21:38:36 INFO - 'USER': 'cltbld', 21:38:36 INFO - 'XDG_SESSION_COOKIE': 'ed14279e38b05397cf4aa391000001a4-1477023971.670634-1232523364', 21:38:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:38:36 INFO - '_': '/tools/buildbot/bin/python'} 21:38:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:38:37 INFO - Downloading/unpacking blobuploader==1.2.4 21:38:37 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 21:38:37 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 21:38:37 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 21:38:37 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 21:38:37 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 21:38:37 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 21:38:38 INFO - Downloading blobuploader-1.2.4.tar.gz 21:38:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 21:38:38 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 21:38:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:38:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:38:38 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:38:38 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:38:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:38:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:38:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 21:38:39 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 21:38:39 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 21:38:39 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 21:38:39 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 21:38:39 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 21:38:39 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 21:38:39 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 21:38:39 INFO - Downloading docopt-0.6.1.tar.gz 21:38:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 21:38:39 INFO - Installing collected packages: blobuploader, requests, docopt 21:38:39 INFO - Running setup.py install for blobuploader 21:38:39 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 21:38:39 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 21:38:39 INFO - Running setup.py install for requests 21:38:40 INFO - Running setup.py install for docopt 21:38:40 INFO - Successfully installed blobuploader requests docopt 21:38:40 INFO - Cleaning up... 21:38:40 INFO - Return code: 0 21:38:40 INFO - Installing None into virtualenv /builds/slave/test/build/venv 21:38:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:38:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:38:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:38:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:38:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:38:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:38:40 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 0xd1e030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe818a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xfdf180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfdd3b0>, '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 0xfde2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xfde740>, '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': 'ed14279e38b05397cf4aa391000001a4-1477023971.670634-1232523364', '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 21:38:40 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 21:38:40 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 21:38:40 INFO - Using env: {'DISPLAY': ':0', 21:38:40 INFO - 'HOME': '/home/cltbld', 21:38:40 INFO - 'LANG': 'en_US.UTF-8', 21:38:40 INFO - 'LANGUAGE': 'en_US:en', 21:38:40 INFO - 'LOGNAME': 'cltbld', 21:38:40 INFO - 'MAIL': '/var/mail/cltbld', 21:38:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:38:40 INFO - 'MOZ_NO_REMOTE': '1', 21:38:40 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:38:40 INFO - 'NO_EM_RESTART': '1', 21:38:40 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:38:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:38:40 INFO - 'PWD': '/builds/slave/test', 21:38:40 INFO - 'SHELL': '/bin/bash', 21:38:40 INFO - 'SHLVL': '1', 21:38:40 INFO - 'TERM': 'linux', 21:38:40 INFO - 'TMOUT': '86400', 21:38:40 INFO - 'USER': 'cltbld', 21:38:40 INFO - 'XDG_SESSION_COOKIE': 'ed14279e38b05397cf4aa391000001a4-1477023971.670634-1232523364', 21:38:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:38:40 INFO - '_': '/tools/buildbot/bin/python'} 21:38:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:38:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 21:38:40 INFO - Running setup.py (path:/tmp/pip-cFLtZT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 21:38:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 21:38:40 INFO - Running setup.py (path:/tmp/pip-YjEosu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 21:38:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 21:38:40 INFO - Running setup.py (path:/tmp/pip-VC4oWS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 21:38:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 21:38:40 INFO - Running setup.py (path:/tmp/pip-5uzIYT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 21:38:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 21:38:40 INFO - Running setup.py (path:/tmp/pip-30Cte7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 21:38:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 21:38:40 INFO - Running setup.py (path:/tmp/pip-lUlR9d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 21:38:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 21:38:40 INFO - Running setup.py (path:/tmp/pip-w7x9f3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 21:38:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 21:38:41 INFO - Running setup.py (path:/tmp/pip-8qDV6v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 21:38:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 21:38:41 INFO - Running setup.py (path:/tmp/pip-8uzMEw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 21:38:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 21:38:41 INFO - Running setup.py (path:/tmp/pip-my6Nq2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 21:38:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 21:38:41 INFO - Running setup.py (path:/tmp/pip-19T0ja-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 21:38:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 21:38:41 INFO - Running setup.py (path:/tmp/pip-pVCw36-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 21:38:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 21:38:41 INFO - Running setup.py (path:/tmp/pip-f89z1x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 21:38:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 21:38:41 INFO - Running setup.py (path:/tmp/pip-UV7ujr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 21:38:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 21:38:41 INFO - Running setup.py (path:/tmp/pip-iyAibS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 21:38:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 21:38:41 INFO - Running setup.py (path:/tmp/pip-5Qne34-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 21:38:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 21:38:41 INFO - Running setup.py (path:/tmp/pip-nACilE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 21:38:41 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 21:38:41 INFO - Running setup.py install for manifestparser 21:38:41 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 21:38:41 INFO - Running setup.py install for mozcrash 21:38:41 INFO - Running setup.py install for mozdebug 21:38:42 INFO - Running setup.py install for mozdevice 21:38:42 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 21:38:42 INFO - Installing dm script to /builds/slave/test/build/venv/bin 21:38:42 INFO - Running setup.py install for mozfile 21:38:42 INFO - Running setup.py install for mozhttpd 21:38:42 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 21:38:42 INFO - Running setup.py install for mozinfo 21:38:42 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 21:38:42 INFO - Running setup.py install for mozInstall 21:38:42 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 21:38:42 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 21:38:42 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 21:38:42 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 21:38:42 INFO - Running setup.py install for mozleak 21:38:42 INFO - Running setup.py install for mozlog 21:38:42 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 21:38:42 INFO - Running setup.py install for moznetwork 21:38:42 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 21:38:42 INFO - Running setup.py install for mozprocess 21:38:42 INFO - Running setup.py install for mozprofile 21:38:43 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 21:38:43 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 21:38:43 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 21:38:43 INFO - Running setup.py install for mozrunner 21:38:43 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 21:38:43 INFO - Running setup.py install for mozscreenshot 21:38:43 INFO - Running setup.py install for moztest 21:38:43 INFO - Running setup.py install for mozversion 21:38:43 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 21:38:43 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 21:38:43 INFO - Cleaning up... 21:38:43 INFO - Return code: 0 21:38:43 INFO - Installing None into virtualenv /builds/slave/test/build/venv 21:38: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')]} 21:38:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:38:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:38: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')]} 21:38:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:38:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:38:43 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xd1e030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe818a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xfdf180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfdd3b0>, '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 0xfde2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xfde740>, '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': 'ed14279e38b05397cf4aa391000001a4-1477023971.670634-1232523364', '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 21:38:43 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 21:38:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 21:38:43 INFO - Using env: {'DISPLAY': ':0', 21:38:43 INFO - 'HOME': '/home/cltbld', 21:38:43 INFO - 'LANG': 'en_US.UTF-8', 21:38:43 INFO - 'LANGUAGE': 'en_US:en', 21:38:43 INFO - 'LOGNAME': 'cltbld', 21:38:43 INFO - 'MAIL': '/var/mail/cltbld', 21:38:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:38:43 INFO - 'MOZ_NO_REMOTE': '1', 21:38:43 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:38:43 INFO - 'NO_EM_RESTART': '1', 21:38:43 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:38:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:38:43 INFO - 'PWD': '/builds/slave/test', 21:38:43 INFO - 'SHELL': '/bin/bash', 21:38:43 INFO - 'SHLVL': '1', 21:38:43 INFO - 'TERM': 'linux', 21:38:43 INFO - 'TMOUT': '86400', 21:38:43 INFO - 'USER': 'cltbld', 21:38:43 INFO - 'XDG_SESSION_COOKIE': 'ed14279e38b05397cf4aa391000001a4-1477023971.670634-1232523364', 21:38:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:38:43 INFO - '_': '/tools/buildbot/bin/python'} 21:38:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:38:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 21:38:43 INFO - Running setup.py (path:/tmp/pip-vpblZe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 21:38:43 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 21:38:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 21:38:43 INFO - Running setup.py (path:/tmp/pip-kfBq7e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 21:38:43 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)) 21:38:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 21:38:43 INFO - Running setup.py (path:/tmp/pip-7mx6xl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 21:38:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 21:38:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 21:38:43 INFO - Running setup.py (path:/tmp/pip-EpSAgF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 21:38:44 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)) 21:38:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 21:38:44 INFO - Running setup.py (path:/tmp/pip-bAi_0N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 21:38:44 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)) 21:38:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 21:38:44 INFO - Running setup.py (path:/tmp/pip-se7aes-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 21:38:44 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)) 21:38:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 21:38:44 INFO - Running setup.py (path:/tmp/pip-q64xa_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 21:38:44 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)) 21:38:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 21:38:44 INFO - Running setup.py (path:/tmp/pip-NM6zJD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 21:38:44 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)) 21:38:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 21:38:44 INFO - Running setup.py (path:/tmp/pip-VSklh6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 21:38:44 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)) 21:38:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 21:38:44 INFO - Running setup.py (path:/tmp/pip-IcmF9z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 21:38:44 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)) 21:38:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 21:38:44 INFO - Running setup.py (path:/tmp/pip-YzCqqh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 21:38:44 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 21:38:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 21:38:44 INFO - Running setup.py (path:/tmp/pip-g12gRR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 21:38:44 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)) 21:38:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 21:38:44 INFO - Running setup.py (path:/tmp/pip-XaEU0e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 21:38:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 21:38:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 21:38:44 INFO - Running setup.py (path:/tmp/pip-CF8VXu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 21:38:44 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)) 21:38:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 21:38:44 INFO - Running setup.py (path:/tmp/pip-15WIDE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 21:38:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 21:38:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 21:38:44 INFO - Running setup.py (path:/tmp/pip-lvsm2j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 21:38:44 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)) 21:38:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 21:38:44 INFO - Running setup.py (path:/tmp/pip-GtipHu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 21:38:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 21:38:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 21:38:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 21:38:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 21:38:44 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 21:38:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 21:38:44 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 21:38:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:38:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:38:44 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:38:44 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:38:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:38:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:38:46 INFO - Downloading blessings-1.6.tar.gz 21:38:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 21:38:46 INFO - Installing collected packages: blessings 21:38:46 INFO - Running setup.py install for blessings 21:38:47 INFO - Successfully installed blessings 21:38:47 INFO - Cleaning up... 21:38:47 INFO - Return code: 0 21:38:47 INFO - Done creating virtualenv /builds/slave/test/build/venv. 21:38:47 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 21:38:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 21:38:47 INFO - Reading from file tmpfile_stdout 21:38:47 INFO - Current package versions: 21:38:47 INFO - argparse == 1.2.1 21:38:47 INFO - blessings == 1.6 21:38:47 INFO - blobuploader == 1.2.4 21:38:47 INFO - docopt == 0.6.1 21:38:47 INFO - functools32 == 3.2.3-2 21:38:47 INFO - jsonschema == 2.5.1 21:38:47 INFO - manifestparser == 1.1 21:38:47 INFO - mozInstall == 1.12 21:38:47 INFO - mozcrash == 0.17 21:38:47 INFO - mozdebug == 0.1 21:38:47 INFO - mozdevice == 0.48 21:38:47 INFO - mozfile == 1.2 21:38:47 INFO - mozhttpd == 0.7 21:38:47 INFO - mozinfo == 0.9 21:38:47 INFO - mozleak == 0.1 21:38:47 INFO - mozlog == 3.3 21:38:47 INFO - moznetwork == 0.27 21:38:47 INFO - mozprocess == 0.23 21:38:47 INFO - mozprofile == 0.28 21:38:47 INFO - mozrunner == 6.12 21:38:47 INFO - mozscreenshot == 0.1 21:38:47 INFO - mozsystemmonitor == 0.3 21:38:47 INFO - moztest == 0.7 21:38:47 INFO - mozversion == 1.4 21:38:47 INFO - psutil == 3.1.1 21:38:47 INFO - requests == 1.2.3 21:38:47 INFO - wsgiref == 0.1.2 21:38:47 INFO - Installing None into virtualenv /builds/slave/test/build/venv 21:38: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')]} 21:38:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:38:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:38: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')]} 21:38:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:38:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:38:47 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xd1e030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe818a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xfdf180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfdd3b0>, '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 0xfde2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xfde740>, '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': 'ed14279e38b05397cf4aa391000001a4-1477023971.670634-1232523364', '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 21:38:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 21:38:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 21:38:47 INFO - Using env: {'DISPLAY': ':0', 21:38:47 INFO - 'HOME': '/home/cltbld', 21:38:47 INFO - 'LANG': 'en_US.UTF-8', 21:38:47 INFO - 'LANGUAGE': 'en_US:en', 21:38:47 INFO - 'LOGNAME': 'cltbld', 21:38:47 INFO - 'MAIL': '/var/mail/cltbld', 21:38:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:38:47 INFO - 'MOZ_NO_REMOTE': '1', 21:38:47 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:38:47 INFO - 'NO_EM_RESTART': '1', 21:38:47 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:38:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:38:47 INFO - 'PWD': '/builds/slave/test', 21:38:47 INFO - 'SHELL': '/bin/bash', 21:38:47 INFO - 'SHLVL': '1', 21:38:47 INFO - 'TERM': 'linux', 21:38:47 INFO - 'TMOUT': '86400', 21:38:47 INFO - 'USER': 'cltbld', 21:38:47 INFO - 'XDG_SESSION_COOKIE': 'ed14279e38b05397cf4aa391000001a4-1477023971.670634-1232523364', 21:38:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:38:47 INFO - '_': '/tools/buildbot/bin/python'} 21:38:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:38:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 21:38:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 21:38:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 21:38:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 21:38:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 21:38:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 21:38:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 21:38:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 21:38:47 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 21:38:47 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 21:38:47 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 21:38:47 INFO - Cleaning up... 21:38:47 INFO - Return code: 0 21:38:47 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 21:38: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')]} 21:38:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:38:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:38: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')]} 21:38:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:38:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:38:47 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xd1e030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe818a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xfdf180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfdd3b0>, '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 0xfde2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xfde740>, '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': 'ed14279e38b05397cf4aa391000001a4-1477023971.670634-1232523364', '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 21:38:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 21:38:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 21:38:47 INFO - Using env: {'DISPLAY': ':0', 21:38:47 INFO - 'HOME': '/home/cltbld', 21:38:47 INFO - 'LANG': 'en_US.UTF-8', 21:38:47 INFO - 'LANGUAGE': 'en_US:en', 21:38:47 INFO - 'LOGNAME': 'cltbld', 21:38:47 INFO - 'MAIL': '/var/mail/cltbld', 21:38:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:38:47 INFO - 'MOZ_NO_REMOTE': '1', 21:38:47 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:38:47 INFO - 'NO_EM_RESTART': '1', 21:38:47 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:38:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:38:47 INFO - 'PWD': '/builds/slave/test', 21:38:47 INFO - 'SHELL': '/bin/bash', 21:38:47 INFO - 'SHLVL': '1', 21:38:47 INFO - 'TERM': 'linux', 21:38:47 INFO - 'TMOUT': '86400', 21:38:47 INFO - 'USER': 'cltbld', 21:38:47 INFO - 'XDG_SESSION_COOKIE': 'ed14279e38b05397cf4aa391000001a4-1477023971.670634-1232523364', 21:38:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:38:47 INFO - '_': '/tools/buildbot/bin/python'} 21:38:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:38:47 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 21:38:47 INFO - Cleaning up... 21:38:47 INFO - Return code: 0 21:38:47 INFO - Running post-action listener: _resource_record_post_action 21:38:47 INFO - Running post-action listener: _start_resource_monitoring 21:38:47 INFO - Starting resource monitoring. 21:38:47 INFO - [mozharness: 2016-10-21 04:38:47.829769Z] Finished create-virtualenv step (success) 21:38:47 INFO - [mozharness: 2016-10-21 04:38:47.830246Z] Running install step. 21:38:47 INFO - Running pre-action listener: _resource_record_pre_action 21:38:47 INFO - Running main action method: install 21:38:47 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 21:38:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 21:38:48 INFO - Reading from file tmpfile_stdout 21:38:48 INFO - Detecting whether we're running mozinstall >=1.0... 21:38:48 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 21:38:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 21:38:48 INFO - Reading from file tmpfile_stdout 21:38:48 INFO - Output received: 21:38:48 INFO - Usage: mozinstall [options] installer 21:38:48 INFO - Options: 21:38:48 INFO - -h, --help show this help message and exit 21:38:48 INFO - -d DEST, --destination=DEST 21:38:48 INFO - Directory to install application into. [default: 21:38:48 INFO - "/builds/slave/test"] 21:38:48 INFO - --app=APP Application being installed. [default: firefox] 21:38:48 INFO - mkdir: /builds/slave/test/build/application 21:38:48 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'] 21:38:48 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 21:39:02 INFO - Reading from file tmpfile_stdout 21:39:02 INFO - Output received: 21:39:02 INFO - /builds/slave/test/build/application/firefox/firefox 21:39:02 INFO - Running post-action listener: _resource_record_post_action 21:39:02 INFO - [mozharness: 2016-10-21 04:39:02.212365Z] Finished install step (success) 21:39:02 INFO - [mozharness: 2016-10-21 04:39:02.212646Z] Running run-tests step. 21:39:02 INFO - Running pre-action listener: _resource_record_pre_action 21:39:02 INFO - Running main action method: run_tests 21:39:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 21:39:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 21:39:02 INFO - Python 2.7.3 21:39:02 INFO - Return code: 0 21:39:02 INFO - grabbing minidump binary from tooltool 21:39:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:39:02 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 0xfdd3b0>, '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 0xfde2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xfde740>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 21:39:02 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 21:39:02 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 21:39:02 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 21:39:02 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 21:39:02 INFO - Return code: 0 21:39:02 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 21:39:02 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 21:39:02 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 21:39:02 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 21:39:02 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 21:39:02 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/bwlu0zmBSsyZpm4qeJimlA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-035', '--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 21:39:02 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/bwlu0zmBSsyZpm4qeJimlA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-035 --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 21:39:02 INFO - Using env: {'DISPLAY': ':0', 21:39:02 INFO - 'HOME': '/home/cltbld', 21:39:02 INFO - 'LANG': 'en_US.UTF-8', 21:39:02 INFO - 'LANGUAGE': 'en_US:en', 21:39:02 INFO - 'LOGNAME': 'cltbld', 21:39:02 INFO - 'MAIL': '/var/mail/cltbld', 21:39:02 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 21:39:02 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 21:39:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:39:02 INFO - 'MOZ_NO_REMOTE': '1', 21:39:02 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 21:39:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:39:02 INFO - 'NO_EM_RESTART': '1', 21:39:02 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:39:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:39:02 INFO - 'PWD': '/builds/slave/test', 21:39:02 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 21:39:02 INFO - 'SHELL': '/bin/bash', 21:39:02 INFO - 'SHLVL': '1', 21:39:02 INFO - 'TERM': 'linux', 21:39:02 INFO - 'TMOUT': '86400', 21:39:02 INFO - 'USER': 'cltbld', 21:39:02 INFO - 'XDG_SESSION_COOKIE': 'ed14279e38b05397cf4aa391000001a4-1477023971.670634-1232523364', 21:39:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:39:02 INFO - '_': '/tools/buildbot/bin/python'} 21:39:02 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/bwlu0zmBSsyZpm4qeJimlA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-035', '--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 21:39:02 INFO - mozversion application_buildid: 20161020185050 21:39:02 INFO - mozversion application_changeset: d69e6eb5d19f81e22ddd026857e2b5b7b57d37b5 21:39:02 INFO - mozversion application_display_name: Firefox 21:39:02 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 21:39:02 INFO - mozversion application_name: Firefox 21:39:02 INFO - mozversion application_remotingname: firefox 21:39:02 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 21:39:02 INFO - mozversion application_vendor: Mozilla 21:39:02 INFO - mozversion application_version: 50.0 21:39:02 INFO - mozversion platform_buildid: 20161020185050 21:39:02 INFO - mozversion platform_changeset: d69e6eb5d19f81e22ddd026857e2b5b7b57d37b5 21:39:02 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 21:39:02 INFO - mozversion platform_version: 50.0 21:39:02 INFO - using testdate: 1477024742 21:39:02 INFO - actual date: 1477024742 21:39:02 INFO - starting webserver on 'localhost:38025' 21:39:02 INFO - SUITE-START | Running 2 tests 21:39:02 INFO - TEST-START | dromaeo_css 21:39:02 INFO - operating with platform_type : linux_ 21:39:02 INFO - Initialising browser for dromaeo_css test... 21:39:02 INFO - TEST-INFO | started process 1922 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpDinJyu/profile http://localhost:38025/getInfo.html) 21:39:06 INFO - PROCESS | 1922 | __metrics Screen width/height:1600/1200 21:39:06 INFO - PROCESS | 1922 | colorDepth:24 21:39:06 INFO - PROCESS | 1922 | Browser inner width/height: 1024/697 21:39:06 INFO - PROCESS | 1922 | __metrics 21:39:07 INFO - PROCESS | 1922 | 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" 21:39:07 INFO - PROCESS | 1922 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 21:39:07 INFO - PROCESS | 1922 | console.error: PushService: 21:39:07 INFO - PROCESS | 1922 | stateChangeProcessEnqueue: Error transitioning state 21:39:07 INFO - PROCESS | 1922 | UnknownError 21:39:07 INFO - TEST-INFO | 1922: exit 0 21:39:07 INFO - Browser initialized. 21:39:07 INFO - Running cycle 1/1 for dromaeo_css test... 21:39:07 INFO - TEST-INFO | started process 2010 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpDinJyu/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 21:39:19 INFO - PROCESS | 2010 | RSS: Main: 143908864 21:39:19 INFO - PROCESS | 2010 | 21:40:10 INFO - PROCESS | 2010 | 1477024810465 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] 21:42:19 INFO - PROCESS | 2010 | Cycle 1(1): loaded http://localhost:38025/tests/dromaeo/cssquery-dojo.html (next: http://localhost:38025/tests/dromaeo/cssquery-ext.html) 21:42:20 INFO - PROCESS | 2010 | RSS: Main: 259346432 21:42:20 INFO - PROCESS | 2010 | 21:45:22 INFO - PROCESS | 2010 | Cycle 1(1): loaded http://localhost:38025/tests/dromaeo/cssquery-ext.html (next: http://localhost:38025/tests/dromaeo/cssquery-jquery.html) 21:45:23 INFO - PROCESS | 2010 | RSS: Main: 234622976 21:45:23 INFO - PROCESS | 2010 | 21:48:25 INFO - PROCESS | 2010 | Cycle 1(1): loaded http://localhost:38025/tests/dromaeo/cssquery-jquery.html (next: http://localhost:38025/tests/dromaeo/cssquery-mootools.html) 21:48:25 INFO - PROCESS | 2010 | RSS: Main: 218796032 21:48:25 INFO - PROCESS | 2010 | 21:51:27 INFO - PROCESS | 2010 | Cycle 1(1): loaded http://localhost:38025/tests/dromaeo/cssquery-mootools.html (next: http://localhost:38025/tests/dromaeo/cssquery-prototype.html) 21:51:28 INFO - PROCESS | 2010 | RSS: Main: 230309888 21:51:28 INFO - PROCESS | 2010 | 21:54:30 INFO - PROCESS | 2010 | Cycle 1(1): loaded http://localhost:38025/tests/dromaeo/cssquery-prototype.html (next: http://localhost:38025/tests/dromaeo/cssquery-yui.html) 21:54:31 INFO - PROCESS | 2010 | RSS: Main: 178012160 21:54:31 INFO - PROCESS | 2010 | 21:57:33 INFO - PROCESS | 2010 | Cycle 1(1): loaded http://localhost:38025/tests/dromaeo/cssquery-yui.html (next: http://localhost:38025/tests/dromaeo/cssquery-dojo.html) 21:57:33 INFO - PROCESS | 2010 | RSS: Main: 260689920 21:57:33 INFO - PROCESS | 2010 | 21:57:33 INFO - PROCESS | 2010 | __start_tp_report 21:57:33 INFO - PROCESS | 2010 | _x_x_mozilla_page_load 21:57:33 INFO - PROCESS | 2010 | _x_x_mozilla_page_load_details 21:57:33 INFO - PROCESS | 2010 | |i|pagename|runs| 21:57:33 INFO - PROCESS | 2010 | |0;dojo.html;3249.1552196428925;3261.282517846074;3288.408086544424;3313.436715758321;3319.4854797506396;5648.293963254593;5734.885302293952;5740.540756739461;5742.28221472316;5756.107803290492;5655.688937108457;5713.371529131792;5718.199452076713;5719.4566516181;5722.256106706132;5194.298769666085;5199.662021968579;5208.791648334071;5248.317718696583;5280.5247527722495;5122.974385128069;5166.819161329354;5285.682859028451;5295.602829787762;5332.840014799543;5145.459726728695;5161;5185.585153187736;5189.273501709745;5231.503007214327;5237.240600113;5240.580753539708;5311.442298558634;5314.60140489461;5316.843298272161;5062.139436295838;5134.922976155361;5217.008768334004;5255.894882102375;5260.368755749335;5491.807786727445;5500.064988951888;5502.504774570308;5531.280933478622;5546.140348246028;5922.31893332268;6044.788432404889;6072.817815465543;6078.270607527125;6078.574499785017;5984.431479009487;6096.024636058254;6107.358727333655;6119.326874043852;6121.632702037848;5678.801241956512;5731.914021289684;5755.568332375088;5784.942150578482;5785.595008349375;6318.494520438354;6324.968375158095;6330.177076979963;6333.778317758856;6335.366463353628;5893.675847828411;5899.498542623839;5943.10853371989;6056.57603967718;6067.908981365283;6101.328853826076;6106.2061931948565;6107.84730381744;6117.388261173847;6117.663528505886;6042.577019608585;6043.184170137063;6046.486048685911;6053.27360716708;6053.848653783691;5897.233359663216;5980.55145864062;5985.281766188085;6016.518678505709;6074.75700971965;4576.954230457719;4628.375169352162;4637.234856248681;4646.95353046472;4649.860504184812;4154.480689913761;4159.667226621863;4163.646090082317;4164.6251837334785;4184.832606695759;5734.139879018097;5747.48272655463;5748.993926062923;5750.2237197978575;5750.453706897922;5919.348871624118;5994.460498555151;6056.9697151513965;6064.4541991221;6120.540959427972;5728.427157284238;5731.684757338303;5733;5733.799317023884;5751.884962300731;5191.740412979336;5234.9476505235225;5244.396894357198;5249.501297376743;5281.102212623863;6118.010103775246;6129.847850912876;6275.309715931244;6308.116863639094;6323.241211054703;6114.419130182626;6137.9286238596105;6165.599236049642;6168.136460895388;6172.783952561639;6371.331010243857;6381.234251889618;6391.552591318749;6392;6399.712012959335;5802.303723553201;5882.029465138376;5913.438223368773;5916.083007133901;6064.636121832704;6076.848078798065;6092.634441933498;6092.695365231632;6098.603590766764;6128.724207410766;5874.177615133799;5930.584859059824;5941.049432090845;5945.732442040205;5947.464728174485;5825.563082768725;5925.703714814328;5938.168656388023;5970.3432622412365;5984;5953.821385358447;5991.67045812467;5999.640021598718;6000.939990600038;6011.549133815069;6023.066424704178;6035.78874739382;6037.69811509414;6042.788502402394;6060.666663333559;5798.971005144947;5813.593048486566;5868.677222752619;5871.1486834409625;5919.881602368015 21:57:33 INFO - PROCESS | 2010 | |1;ext.html;585.8798946216026;629.3046183966717;631.6904716688821;650.5218664281749;650.6584043377227;34742.65257347426;46189;47309.29036064453;47341;47449.76275118624;47893.52106478934;48159;48345;48376.75811620946;48460;8618.827623447527;9355.251579873611;9489.098535639103;9519.857202141973;11089.33463992159;11158.442077896072;11276;11276.943615281953;11289.266197697165;11292.717682057932;11348.546058157664;11387.829182562267;11396.544138234462;11400.885991140065;11411.771764564704;11284.943575282112;11306.604268850591;11321.377324247163;11329.830052549218;11330.546778128864;11173.385463799488;11192.552297908074;11206.831897521462;11233.66299011031;11240.775184496264;44987.775061124485;45343;45395;45491.54508454906;45496.54503454956;3773.28569233896;3948.3879998600264;3971.086650070471;4059.6143366380147;4730.432348118248;35022.47466288008;35041.29917401638;35227.64772352269;35731.4640280396;36847.4472882907;4624.630029597624;4651.837185698518;4708.952910470885;4737.73942433166;4794.760261986887;393891;395078;395172.0241398803;395319;396990;66323.66838165779;66613.66693166504;66820.33179668238;66898;66899.33100669028;39640.405393919114;40982.79508602498;40991.590084098774;43060.354094688606;43063;17583.120843958008;17700.026498542447;17703.20335584876;17716.82283177152;17728.46814595564;12763.361831908367;13687.657808554866;13899.096558723853;13948.511802086878;13999.16005039701;20756.89621551913;21223;21248.78751212468;21257.149714011266;21299.8935005324;7249.891251631229;7267.292186336207;7410.888836667455;7415.99884015651;7531.510451820614;1037.3723897042332;1099.6206308823444;1126.9661910142709;1145.3242586873785;1146.18620779246;35308.64691353105;35991.640083599355;36000.45999310012;36013.41619611969;36192.09519761975;1114.136544178252;1140.7946569617382;1142.4344949250174;1170.6722117807028;1227.1042139238407;18525.166367513495;18562.535936601424;18791.436256912315;18871.528211794815;18901.243950241835;592124;599817;601633;602239;602937;66365;66925.66537167381;66959.33040669629;66961.66519167373;66979.99530007054;1286.202554416249;1371.163590209971;1412.1385954567706;1429.9714005719827;1441.54591303739;33875.83062084772;36276;36335.636643633225;36443.63556364403;36589.634103658624;55844.44155558392;60830.39169608424;63298.68350658401;63659.363406365344;69511.65244173747;576700;583095;584199;584923;585514;40231.396529051504;46369;46540.7672961633;46551.76724116358;49619.75190124026;52834;52835;53632;53745.73127134496;54163.18755218596;44766;44914.32628510641;45806.54193458156;46121;47652.761736191096;45348.31977520274;46065;46262.07475850532;46303.30545041894;47234.055318892744;44557.77721111374;45217;45562.31656525088;45783.77108114438;45882.77058614686;38378;40156.598434015286;40246.79876600598;40551;40921.79539102405;29704.70295297106;29730.554041689826;29811.10475100189;30352.54471182978;30524.236894076937 21:57:33 INFO - PROCESS | 2010 | |2;jquery.html;427.9607669455047;429.4159942478227;436.62693262120376;442.43589423485054;445.6835646690847;19190.520236994063;20505.589888202227;21049.47376315591;21107.366778996617;21268.14927402902;19429.12568934398;19434.41696749096;19839.10724017419;19978.60042799143;19984.9000754996;5947.078202878561;6181.13464115022;6199.504039676837;6206.441420272175;6207.906881396782;5846.3861294563885;6004.759809607611;6028.276606807167;6070.150178974947;6071.544634152434;6042.365551617083;6042.486388656947;6050.183225264599;6053.485453736438;6054.303755068179;5844.532437405019;5990.700464976756;6014.578979471439;6018.097285407181;6023.337432882379;5381.354237491487;5457.372402173761;5478.096114141202;5481.506664400223;5482.259894914176;24462.26613201607;24530.01879924801;24531.754682453124;24572.631410528676;24699.38251543708;2389.856608603472;2730.6586676665415;2735.9589606155923;2742.053991372973;3171.666974967641;11030.393328366936;11305.943470282678;11308.660740177807;11311.886881131166;11376.033037191848;2024.8278896293832;2065.297798748418;2085.895705214733;2123.6708310211943;2174.6194415977297;84612;87353.56323218407;88375;90670.54664726634;90791.54604227003;11846.88153118475;11958.940205298917;11998;12076.094292928066;12332.81500777489;17216.827831721523;17243.310267589408;17264.913675431544;17278.4816455504;17284.70364722651;8052.436329456882;8184.795380115425;8187.140350263256;8210.384221183316;8288.295494882881;7490.887636685454;7584.469087163956;7668.079830420385;7845.37237021037;7861.977942867505;5008.198688209942;5018.473060328686;5050.444451110375;5110.770015349318;5337.2527846101575;1990.4227773945393;2091.6473087537547;2122.564874200785;2240.20472732181;2251.842371034045;920.963161473547;1040.557762950743;1049.275999560301;1052.768390953989;1120.5461787975883;20321;20852.895735521226;21009.529332946546;21059.578808424052;21197.89401052985;1012.8126296635146;1024.1601886453186;1061.3790932304535;1063.3726101600093;1074.2533938912381;11091.889081109086;11487.182549754261;11709.121815863844;11774;11825.40872956366;537648;543300;576095;576337;588225;100533.49733251287;102535.4873225644;104020;105729.47135264275;106643.46678266714;1036.1658864613908;1040.6149724601794;1045.0594464981425;1051.3849398102043;1052.9473526323807;17672.823271767116;17814.376496822548;18095.185716643045;18610.720839187692;19113.617727645647;46535.76732116453;46697;46972.530274696815;47397.52602473931;48557.5144248553;60234.39765602288;63423.36576634175;63823.6808815953;65270.67364663146;66017.66991165014;8261.421700480909;8696.391252612257;8720.869186962074;8799.956000219958;8960.238379737795;11455.65633030977;12307;12568.685782855502;12653.367331633197;12669.429875655802;11087.72280692989;11195.216334856483;11414.372209528556;11450.312981221154;11778.58774942907;10804.67585972422;11012.614558490415;11236.719082023015;11382.146339024772;11565.537378504765;11186.720331991766;11371.488283027118;11528.711782205512;11565.59520416815;11660.53357865676;8523.445976011539;8662.307015438875;8719.956400217958;8822.2060014599;8875.822483550422;9073.546322683971;11379.772404552028;11463.885361146282;11529.654110376701;11765.411729413667 21:57:33 INFO - PROCESS | 2010 | |3;mootools.html;1400.4958215042577;1510.7356212662785;1532.9080255184688;1682.9747553786688;1799.6400719856033;8978.685745998882;9359.578818953154;9402.341836071479;9697.175740062086;9716.75708107296;9594.712158635251;9706.369086009405;9713.07725766051;9727.902720972788;9731.610735570568;2495.4759500504915;2547.694276686791;2551.5152121097026;2551.872406379683;2555.4505781257026;2448.436859522311;2519.48350588129;2520.7479252074736;2522.1550780488465;2522.751238137125;2483.7392073832257;2498.562751518486;2543.656606358146;2544.1477105169793;2549.235229431163;2432.221689059502;2436.7319594844553;2442.560339138954;2491.1281051631786;2494.064725727852;2403.579373609611;2430.040134147002;2433.914812981537;2440.45089854783;2472.678551788274;9684.08001239881;9703;9776.853347199798;9784.364016338915;9791.118799308026;5573.721313934287;5727.8568035799435;5740.225069615591;5825.912611310791;5827.912581311284;3572.8034958077296;3598.838052287653;3607.531020967284;3678.4114541673202;3700.7964561949084;1292.8577856435784;1299.0711641176604;1315.15829868884;1342.812006319116;1343.7514059898851;13707;13757.449702011907;13773.724525509533;13853.445862165501;13919.02566820323;4842.128416884924;4916.262560615936;4968.552830245295;5066.392032955995;5099.847004589868;10791.082757965662;10816.459177041274;10823;10835.079018283377;10838.67483975482;5614.073677843192;5653.095504719226;5653.717314134276;5658.63218890779;5663.122216056518;4765.428148622187;4786.760661966888;5073.949260507347;5254.527092561688;5316.7075810830775;4218.831246750157;4223.68322375823;4277.465316835395;4300.333448315516;4309.762963037;1272.783626783449;1291.9089615649566;1297.8961683065331;1300.7447812860446;1303.1920209470188;993.6174572789528;1059.1156384418941;1060.5657008119488;1060.8408738689259;1094.179365475893;6534.836629084216;6663.300353462817;6699.229588597276;6774.491913106537;6858.622775747282;513.8766695992935;515.2297315513332;519.5973120831386;522.2688236468974;534.2120372450604;4517.593416592523;4629.421322334708;4631.791569379386;4714.929276060861;4819.614430845524;11573.826392604118;11733.589324373606;11980.700482488008;12047.51809927593;12084.637460876187;11703;11830.112741544419;11852.940735296268;11854.822177667342;11857.466414011391;8692.174243446863;8700.651973920973;8750.91249087501;8838.779030524289;8887.200151986352;6622.867542649024;6791.151106111736;6811.863762724817;6821;6838.487113466609;10967.122630189726;11256.943715281372;11292.491837867152;11355.716107097389;11408.94295528517;11200.43997800123;11316.37759923212;11473.713157170803;11521;11617.186796924134;7972.36221102322;8050.476719013245;8180.877286840583;8246.463979841292;8331.208535189147;9486.28852836054;9538.14156725898;9560;9565.713028609152;9568.186704130227;8440.535770532433;8570.314374849871;8583.914160858312;8652.82694346097;8755.518446485505;8355.164483551596;8403.495790252604;8453.408261421888;8463.57682115904;8647.783805404915;7324.81687957784;7443.218462061405;7498.287662672037;7520;7535.73624923125;5558.796109341225;5626.409227031103;5671.574631902707;5803.883922321445;5946.345901950868;5690.971545142414;5700.543956483554;5842.38654941225;5871.941280587311;5989.730462129128 21:57:33 INFO - PROCESS | 2010 | |4;prototype.html;357.9606243313235;362.80227276134514;363.6563447542072;365.4846666200654;373.5778570215654;5516.862078448035;5546.722663866801;5546.889062218758;5552.666839989593;5582.190582365554;5459.208414779864;5476.479734425223;5484.396716361197;5489.478499542547;5568.610197286192;3994.2211268802594;4058.208649313385;4381.474223093233;4419.469663640351;4471.434997750785;4317.935230971538;4349.652027837781;4362.629176519984;4392.34114882767;4397.12057588482;4359.193549193394;4360.542143074979;4362.323839804835;4366.97816510917;4368.97815510922;4276.567349937774;4315.697901146921;4360.171567402183;4361.9749358900635;4367.890802729926;3896.5324161100566;3897.5322961244547;3902.804859757029;3920.2551515212017;3946.467226924357;5351.946480535184;5356.7321633918145;5376.440832158669;5395;5433.375161856397;4877.111756883212;4925.881870795556;5029.823956161516;5045.2432135180015;5046.343975283226;5656;5718.25662663851;5767.36558978512;5770.9422905770825;5827.504662103726;4254.680898932562;4268.807903644343;4280.614744672995;4305.332673435622;4332.133573285356;5731.828045158652;6046.606970546901;6051.515878729691;6062.9090563641585;6072.51419886408;4291.313389857608;4314.784260786948;4319.222539942841;4320.589543993316;4322.7190232634775;4417.646588272994;4421.778911054499;4426.8671939841215;4428.047969686533;4434.623057040123;4875.878103047452;4981.726005069756;4987.526185012418;5008.198688209869;5026.949730502722;5771.105478650816;5783.479486846204;5790.71046447676;5794.536437085023;5850.795222167204;1491.4258010665756;1506.6007508010387;1514.6289159155856;1525.7329967255907;1526.8702160316275;931.3992474853817;964.4261664309724;978.9867487150765;982.4203719805349;1000.3444299684035;4934.309196712463;5356.60728210668;5389.191621256843;5581.5255703265675;5625.409332020161;5912.46787789101;5917.289925209003;5922.3713478792815;5926.288845338587;5928.822135335947;5455;5578.63738856973;5583.664980101207;5620.185073164368;5624.156376543551;3075.138961090853;3135.8902438415003;3150.952735708966;3161.873525058972;3161.9841900790807;6155.969220153871;6200.875982480416;6221.191245138103;6241.531885108635;6265.310815810257;5733.397993210653;6016.488598469092;6025.758969641253;6035.637861728311;6041.365656606081;6047.427668806142;6094.268687757498;6115.694215289218;6126.23422072241;6126.877462450726;5698.857528561819;5795.246617939641;5947.197128387612;5960.314563825215;5977.372375900468;5740.885182296247;5829.358770535153;5831.94168058314;5857.707114644336;5874.676892770939;5954.702264886825;6047.969760151171;6081.26002508463;6109.389061093855;6124.663143527148;4224.197402493516;4285.871423857289;4294.506131794884;4306.41863348444;4324.481062272547;5876.265466816648;5936;5950.404959504015;5985.162077309267;6040.516758659384;5567.665940043448;5674.687892165971;5773;5835.67903765297;5879.911801323069;5652.576056795675;5721.313442386941;5758.424157584208;5783.421657834183;5796.681182535001;5693.971530142323;5759.56803239767;5772.509336706427;5808.94191058084;5812.593118481835;5624.521915637217;5769.365369809401;5815.94184058154;5834.94165058361;5835.708214589169;5675.631083979528;5688.354914964561;5693.28833895763;5707.800226992035;5711.600187986801 21:57:33 INFO - PROCESS | 2010 | |5;yui.html;800.3037357498973;803.6062329458559;822.407866336238;858.257607169798;869.4739682492092;16190.190490475474;17615.47153585393;18157.09214539271;18359.908200459013;18408.723869141973;16129.193540322965;17326.306947722074;17436.56408589786;17456.912715436403;17466.301347946068;816.9468984516002;846.4244313866568;847.6524624903791;847.9236868681818;849.0363437498422;859.222403724627;861.7027125641644;862.5644049754865;863.6718047142077;863.7279257034014;907.4963395315597;908.600215905003;909.2816674826879;910.1171863292595;911.5351170902854;902.467544148952;902.4900930974044;904.317240483434;904.7647611621026;905.0632595263903;661.4642139866676;661.5137873662854;661.8014595621295;662.1491383572087;662.2285037930853;16021.279042443002;16905.49283521496;16907.492775216764;16965.66068678632;17028.063456509888;16036;16561.6687666246;16934.91532542342;17023.82976170235;17199.828001719947;13534.390952407166;13551.932240338834;13627;13827.308634568331;13892.791608125886;4141.81361838718;4201.873943781661;4232.195882782293;4267.871963841089;4309.82760689572;91608.08391916062;91981.54009229911;93184.53407732985;94825;95139.52430237805;11637.53449861996;12637.43131559082;12690.80963785544;12713.110082294335;12989.610311690665;23432.414189645395;23471.41321466977;23472.7652723474;23521.17675881336;23543.41141471477;9059.909400906039;9183;9225.40034897743;9400.858987115198;9509.66716164931;5031.7987280509105;5244.921326180111;5299.49654782795;5428.131498960148;5536.944630553723;3727.5340582427198;3850.9229815403537;3857.6528112470014;3859.9228015439535;3859.96140038596;662.8806814773291;671.9529632925746;671.96976136074;688.703857341348;697.2504557600498;696.5959743348845;754.2532892436558;785.4698078796789;806.1092492795377;806.846699127164;10283.573412570253;10350.275480716427;10568.841467377995;10581.206409519316;10961.890381096087;672.3175976383974;760.9885851712229;783.4829012851491;792.6948124971811;796.1520980156115;4818.4217893852965;4938.802447902115;4995.600351971833;5010.774515146754;5096.745162741848;133904.330478347;134894;134986;137936.31031844777;140193;68556.6572167146;90631;113459;114025;114981;52494.21258681123;53566.7321663397;62591.6870415645;64612;76951;10043.949780251052;10969.835452468378;11027.83458248143;11199.66401007971;11231.4384280784;47525;47576.524234757206;49231;54829.45170548403;55111;72542.27457725356;78650.6067469659;80406.59796700979;81944;85315;13908.860911390757;14168.716625667636;14203.172393217807;14315.14109153454;14408.495702650776;16197.271122799266;16294;16557.83442165563;16602.418915337905;16710.24803883804;15148;15153.393864245307;15251;15994.920025399799;16184.352625895302;12722.681932951751;13356.866431335562;13596.048276620542;14138;14739.55781326562;13543.59369218925;13571.79642305386;13602.319884005563;13664.111832730841;13945.3724582392;12740.68148296263;12828.166169199345;13016.349182541024;13126.934365328112;13641.38613762403;12702.936485317514;12725.87274127247;12734.74530509366;13063.477460901457;13246.66883327886 21:57:33 INFO - PROCESS | 2010 | __end_tp_report 21:57:33 INFO - PROCESS | 2010 | __start_cc_report 21:57:33 INFO - PROCESS | 2010 | _x_x_mozilla_cycle_collect,1001 21:57:33 INFO - PROCESS | 2010 | __end_cc_report 21:57:33 INFO - PROCESS | 2010 | __startTimestamp1477025853977__endTimestamp 21:57:33 INFO - PROCESS | 2010 | 21:57:33 INFO - PROCESS | 2010 | ------- Summary: start ------- 21:57:33 INFO - PROCESS | 2010 | Number of tests: 6 21:57:33 INFO - PROCESS | 2010 | 21:57:33 INFO - PROCESS | 2010 | [#0] dojo.html Cycles:165 Average:5658.96 Median:5882.03 stddev:640.11 (10.9%) stddev-sans-first:613.52 21:57:33 INFO - PROCESS | 2010 | Values: 3249.2 3261.3 3288.4 3313.4 3319.5 5648.3 5734.9 5740.5 5742.3 5756.1 5655.7 5713.4 5718.2 5719.5 5722.3 5194.3 5199.7 5208.8 5248.3 5280.5 5123.0 5166.8 5285.7 5295.6 5332.8 5145.5 5161.0 5185.6 5189.3 5231.5 5237.2 5240.6 5311.4 5314.6 5316.8 5062.1 5134.9 5217.0 5255.9 5260.4 5491.8 5500.1 5502.5 5531.3 5546.1 5922.3 6044.8 6072.8 6078.3 6078.6 5984.4 6096.0 6107.4 6119.3 6121.6 5678.8 5731.9 5755.6 5784.9 5785.6 6318.5 6325.0 6330.2 6333.8 6335.4 5893.7 5899.5 5943.1 6056.6 6067.9 6101.3 6106.2 6107.8 6117.4 6117.7 6042.6 6043.2 6046.5 6053.3 6053.8 5897.2 5980.6 5985.3 6016.5 6074.8 4577.0 4628.4 4637.2 4647.0 4649.9 4154.5 4159.7 4163.6 4164.6 4184.8 5734.1 5747.5 5749.0 5750.2 5750.5 5919.3 5994.5 6057.0 6064.5 6120.5 5728.4 5731.7 5733.0 5733.8 5751.9 5191.7 5234.9 5244.4 5249.5 5281.1 6118.0 6129.8 6275.3 6308.1 6323.2 6114.4 6137.9 6165.6 6168.1 6172.8 6371.3 6381.2 6391.6 6392.0 6399.7 5802.3 5882.0 5913.4 5916.1 6064.6 6076.8 6092.6 6092.7 6098.6 6128.7 5874.2 5930.6 5941.0 5945.7 5947.5 5825.6 5925.7 5938.2 5970.3 5984.0 5953.8 5991.7 5999.6 6000.9 6011.5 6023.1 6035.8 6037.7 6042.8 6060.7 5799.0 5813.6 5868.7 5871.1 5919.9 21:57:33 INFO - PROCESS | 2010 | 21:57:33 INFO - PROCESS | 2010 | [#1] ext.html Cycles:180 Average:69849.61 Median:22305.31 stddev:141885.20 (636.1%) stddev-sans-first:142187.92 21:57:33 INFO - PROCESS | 2010 | Values: 585.9 629.3 631.7 650.5 650.7 34742.7 46189.0 47309.3 47341.0 47449.8 47893.5 48159.0 48345.0 48376.8 48460.0 8618.8 9355.3 9489.1 9519.9 11089.3 11158.4 11276.0 11276.9 11289.3 11292.7 11348.5 11387.8 11396.5 11400.9 11411.8 11284.9 11306.6 11321.4 11329.8 11330.5 11173.4 11192.6 11206.8 11233.7 11240.8 44987.8 45343.0 45395.0 45491.5 45496.5 3773.3 3948.4 3971.1 4059.6 4730.4 35022.5 35041.3 35227.6 35731.5 36847.4 4624.6 4651.8 4709.0 4737.7 4794.8 393891.0 395078.0 395172.0 395319.0 396990.0 66323.7 66613.7 66820.3 66898.0 66899.3 39640.4 40982.8 40991.6 43060.4 43063.0 17583.1 17700.0 17703.2 17716.8 17728.5 12763.4 13687.7 13899.1 13948.5 13999.2 20756.9 21223.0 21248.8 21257.1 21299.9 7249.9 7267.3 7410.9 7416.0 7531.5 1037.4 1099.6 1127.0 1145.3 1146.2 35308.6 35991.6 36000.5 36013.4 36192.1 1114.1 1140.8 1142.4 1170.7 1227.1 18525.2 18562.5 18791.4 18871.5 18901.2 592124.0 599817.0 601633.0 602239.0 602937.0 66365.0 66925.7 66959.3 66961.7 66980.0 1286.2 1371.2 1412.1 1430.0 1441.5 33875.8 36276.0 36335.6 36443.6 36589.6 55844.4 60830.4 63298.7 63659.4 69511.7 576700.0 583095.0 584199.0 584923.0 585514.0 40231.4 46369.0 46540.8 46551.8 49619.8 52834.0 52835.0 53632.0 53745.7 54163.2 44766.0 44914.3 45806.5 46121.0 47652.8 45348.3 46065.0 46262.1 46303.3 47234.1 44557.8 45217.0 45562.3 45783.8 45882.8 38378.0 40156.6 40246.8 40551.0 40921.8 29704.7 29730.6 29811.1 30352.5 30524.2 21:57:33 INFO - PROCESS | 2010 | 21:57:33 INFO - PROCESS | 2010 | [#2] jquery.html Cycles:180 Average:32215.82 Median:2207.41 stddev:93124.29 (4218.7%) stddev-sans-first:93354.94 21:57:33 INFO - PROCESS | 2010 | Values: 428.0 429.4 436.6 442.4 445.7 19190.5 20505.6 21049.5 21107.4 21268.1 19429.1 19434.4 19839.1 19978.6 19984.9 5947.1 6181.1 6199.5 6206.4 6207.9 5846.4 6004.8 6028.3 6070.2 6071.5 6042.4 6042.5 6050.2 6053.5 6054.3 5844.5 5990.7 6014.6 6018.1 6023.3 5381.4 5457.4 5478.1 5481.5 5482.3 24462.3 24530.0 24531.8 24572.6 24699.4 2389.9 2730.7 2736.0 2742.1 3171.7 11030.4 11305.9 11308.7 11311.9 11376.0 2024.8 2065.3 2085.9 2123.7 2174.6 84612.0 87353.6 88375.0 90670.5 90791.5 11846.9 11958.9 11998.0 12076.1 12332.8 17216.8 17243.3 17264.9 17278.5 17284.7 8052.4 8184.8 8187.1 8210.4 8288.3 7490.9 7584.5 7668.1 7845.4 7862.0 5008.2 5018.5 5050.4 5110.8 5337.3 1990.4 2091.6 2122.6 2240.2 2251.8 921.0 1040.6 1049.3 1052.8 1120.5 20321.0 20852.9 21009.5 21059.6 21197.9 1012.8 1024.2 1061.4 1063.4 1074.3 11091.9 11487.2 11709.1 11774.0 11825.4 537648.0 543300.0 576095.0 576337.0 588225.0 100533.5 102535.5 104020.0 105729.5 106643.5 1036.2 1040.6 1045.1 1051.4 1052.9 17672.8 17814.4 18095.2 18610.7 19113.6 46535.8 46697.0 46972.5 47397.5 48557.5 60234.4 63423.4 63823.7 65270.7 66017.7 8261.4 8696.4 8720.9 8800.0 8960.2 11455.7 12307.0 12568.7 12653.4 12669.4 11087.7 11195.2 11414.4 11450.3 11778.6 10804.7 11012.6 11236.7 11382.1 11565.5 11186.7 11371.5 11528.7 11565.6 11660.5 8523.4 8662.3 8720.0 8822.2 8875.8 9073.5 11379.8 11463.9 11529.7 11765.4 21:57:33 INFO - PROCESS | 2010 | 21:57:33 INFO - PROCESS | 2010 | [#3] mootools.html Cycles:180 Average:6336.51 Median:4830.87 stddev:3656.47 (75.7%) stddev-sans-first:3647.91 21:57:33 INFO - PROCESS | 2010 | Values: 1400.5 1510.7 1532.9 1683.0 1799.6 8978.7 9359.6 9402.3 9697.2 9716.8 9594.7 9706.4 9713.1 9727.9 9731.6 2495.5 2547.7 2551.5 2551.9 2555.5 2448.4 2519.5 2520.7 2522.2 2522.8 2483.7 2498.6 2543.7 2544.1 2549.2 2432.2 2436.7 2442.6 2491.1 2494.1 2403.6 2430.0 2433.9 2440.5 2472.7 9684.1 9703.0 9776.9 9784.4 9791.1 5573.7 5727.9 5740.2 5825.9 5827.9 3572.8 3598.8 3607.5 3678.4 3700.8 1292.9 1299.1 1315.2 1342.8 1343.8 13707.0 13757.4 13773.7 13853.4 13919.0 4842.1 4916.3 4968.6 5066.4 5099.8 10791.1 10816.5 10823.0 10835.1 10838.7 5614.1 5653.1 5653.7 5658.6 5663.1 4765.4 4786.8 5073.9 5254.5 5316.7 4218.8 4223.7 4277.5 4300.3 4309.8 1272.8 1291.9 1297.9 1300.7 1303.2 993.6 1059.1 1060.6 1060.8 1094.2 6534.8 6663.3 6699.2 6774.5 6858.6 513.9 515.2 519.6 522.3 534.2 4517.6 4629.4 4631.8 4714.9 4819.6 11573.8 11733.6 11980.7 12047.5 12084.6 11703.0 11830.1 11852.9 11854.8 11857.5 8692.2 8700.7 8750.9 8838.8 8887.2 6622.9 6791.2 6811.9 6821.0 6838.5 10967.1 11256.9 11292.5 11355.7 11408.9 11200.4 11316.4 11473.7 11521.0 11617.2 7972.4 8050.5 8180.9 8246.5 8331.2 9486.3 9538.1 9560.0 9565.7 9568.2 8440.5 8570.3 8583.9 8652.8 8755.5 8355.2 8403.5 8453.4 8463.6 8647.8 7324.8 7443.2 7498.3 7520.0 7535.7 5558.8 5626.4 5671.6 5803.9 5946.3 5691.0 5700.5 5842.4 5871.9 5989.7 21:57:33 INFO - PROCESS | 2010 | 21:57:33 INFO - PROCESS | 2010 | [#4] prototype.html Cycles:180 Average:4891.25 Median:5580.08 stddev:1418.42 (25.4%) stddev-sans-first:1380.99 21:57:33 INFO - PROCESS | 2010 | Values: 358.0 362.8 363.7 365.5 373.6 5516.9 5546.7 5546.9 5552.7 5582.2 5459.2 5476.5 5484.4 5489.5 5568.6 3994.2 4058.2 4381.5 4419.5 4471.4 4317.9 4349.7 4362.6 4392.3 4397.1 4359.2 4360.5 4362.3 4367.0 4369.0 4276.6 4315.7 4360.2 4362.0 4367.9 3896.5 3897.5 3902.8 3920.3 3946.5 5351.9 5356.7 5376.4 5395.0 5433.4 4877.1 4925.9 5029.8 5045.2 5046.3 5656.0 5718.3 5767.4 5770.9 5827.5 4254.7 4268.8 4280.6 4305.3 4332.1 5731.8 6046.6 6051.5 6062.9 6072.5 4291.3 4314.8 4319.2 4320.6 4322.7 4417.6 4421.8 4426.9 4428.0 4434.6 4875.9 4981.7 4987.5 5008.2 5026.9 5771.1 5783.5 5790.7 5794.5 5850.8 1491.4 1506.6 1514.6 1525.7 1526.9 931.4 964.4 979.0 982.4 1000.3 4934.3 5356.6 5389.2 5581.5 5625.4 5912.5 5917.3 5922.4 5926.3 5928.8 5455.0 5578.6 5583.7 5620.2 5624.2 3075.1 3135.9 3151.0 3161.9 3162.0 6156.0 6200.9 6221.2 6241.5 6265.3 5733.4 6016.5 6025.8 6035.6 6041.4 6047.4 6094.3 6115.7 6126.2 6126.9 5698.9 5795.2 5947.2 5960.3 5977.4 5740.9 5829.4 5831.9 5857.7 5874.7 5954.7 6048.0 6081.3 6109.4 6124.7 4224.2 4285.9 4294.5 4306.4 4324.5 5876.3 5936.0 5950.4 5985.2 6040.5 5567.7 5674.7 5773.0 5835.7 5879.9 5652.6 5721.3 5758.4 5783.4 5796.7 5694.0 5759.6 5772.5 5808.9 5812.6 5624.5 5769.4 5815.9 5834.9 5835.7 5675.6 5688.4 5693.3 5707.8 5711.6 21:57:33 INFO - PROCESS | 2010 | 21:57:33 INFO - PROCESS | 2010 | [#5] yui.html Cycles:180 Average:22130.75 Median:3858.79 stddev:32106.08 (832.0%) stddev-sans-first:32156.20 21:57:33 INFO - PROCESS | 2010 | Values: 800.3 803.6 822.4 858.3 869.5 16190.2 17615.5 18157.1 18359.9 18408.7 16129.2 17326.3 17436.6 17456.9 17466.3 816.9 846.4 847.7 847.9 849.0 859.2 861.7 862.6 863.7 863.7 907.5 908.6 909.3 910.1 911.5 902.5 902.5 904.3 904.8 905.1 661.5 661.5 661.8 662.1 662.2 16021.3 16905.5 16907.5 16965.7 17028.1 16036.0 16561.7 16934.9 17023.8 17199.8 13534.4 13551.9 13627.0 13827.3 13892.8 4141.8 4201.9 4232.2 4267.9 4309.8 91608.1 91981.5 93184.5 94825.0 95139.5 11637.5 12637.4 12690.8 12713.1 12989.6 23432.4 23471.4 23472.8 23521.2 23543.4 9059.9 9183.0 9225.4 9400.9 9509.7 5031.8 5244.9 5299.5 5428.1 5536.9 3727.5 3850.9 3857.7 3859.9 3860.0 662.9 672.0 672.0 688.7 697.3 696.6 754.3 785.5 806.1 806.8 10283.6 10350.3 10568.8 10581.2 10961.9 672.3 761.0 783.5 792.7 796.2 4818.4 4938.8 4995.6 5010.8 5096.7 133904.3 134894.0 134986.0 137936.3 140193.0 68556.7 90631.0 113459.0 114025.0 114981.0 52494.2 53566.7 62591.7 64612.0 76951.0 10043.9 10969.8 11027.8 11199.7 11231.4 47525.0 47576.5 49231.0 54829.5 55111.0 72542.3 78650.6 80406.6 81944.0 85315.0 13908.9 14168.7 14203.2 14315.1 14408.5 16197.3 16294.0 16557.8 16602.4 16710.2 15148.0 15153.4 15251.0 15994.9 16184.4 12722.7 13356.9 13596.0 14138.0 14739.6 13543.6 13571.8 13602.3 13664.1 13945.4 12740.7 12828.2 13016.3 13126.9 13641.4 12702.9 12725.9 12734.7 13063.5 13246.7 21:57:33 INFO - PROCESS | 2010 | -------- Summary: end -------- 21:57:33 INFO - PROCESS | 2010 | 21:57:34 INFO - TEST-INFO | 2010: exit 0 21:57:34 INFO - TEST-OK | dromaeo_css | took 1112186ms 21:57:34 INFO - TEST-START | kraken 21:57:34 INFO - operating with platform_type : linux_ 21:57:34 INFO - Initialising browser for kraken test... 21:57:34 INFO - TEST-INFO | started process 2202 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp0uXpTe/profile http://localhost:38025/getInfo.html) 21:57:38 INFO - PROCESS | 2202 | __metrics Screen width/height:1600/1200 21:57:38 INFO - PROCESS | 2202 | colorDepth:24 21:57:38 INFO - PROCESS | 2202 | Browser inner width/height: 1024/697 21:57:38 INFO - PROCESS | 2202 | __metrics 21:57:39 INFO - PROCESS | 2202 | 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" 21:57:39 INFO - PROCESS | 2202 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 21:57:40 INFO - TEST-INFO | 2202: exit 0 21:57:40 INFO - Browser initialized. 21:57:40 INFO - Running cycle 1/1 for kraken test... 21:57:40 INFO - TEST-INFO | started process 2288 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp0uXpTe/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 21:57:51 INFO - PROCESS | 2288 | RSS: Main: 144277504 21:57:51 INFO - PROCESS | 2288 | 21:57:54 INFO - PROCESS | 2288 | Cycle 1(1): loaded http://localhost:38025/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:38025/tests/kraken/driver.html?testName=audio-beat-detection) 21:57:55 INFO - PROCESS | 2288 | RSS: Main: 199557120 21:57:55 INFO - PROCESS | 2288 | 21:57:57 INFO - PROCESS | 2288 | Cycle 1(1): loaded http://localhost:38025/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:38025/tests/kraken/driver.html?testName=audio-dft) 21:57:58 INFO - PROCESS | 2288 | RSS: Main: 184500224 21:57:58 INFO - PROCESS | 2288 | 21:58:00 INFO - PROCESS | 2288 | Cycle 1(1): loaded http://localhost:38025/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:38025/tests/kraken/driver.html?testName=audio-fft) 21:58:01 INFO - PROCESS | 2288 | RSS: Main: 275599360 21:58:01 INFO - PROCESS | 2288 | 21:58:03 INFO - PROCESS | 2288 | Cycle 1(1): loaded http://localhost:38025/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:38025/tests/kraken/driver.html?testName=audio-oscillator) 21:58:04 INFO - PROCESS | 2288 | RSS: Main: 180539392 21:58:04 INFO - PROCESS | 2288 | 21:58:06 INFO - PROCESS | 2288 | Cycle 1(1): loaded http://localhost:38025/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:38025/tests/kraken/driver.html?testName=imaging-gaussian-blur) 21:58:06 INFO - PROCESS | 2288 | RSS: Main: 179187712 21:58:06 INFO - PROCESS | 2288 | 21:58:09 INFO - PROCESS | 2288 | Cycle 1(1): loaded http://localhost:38025/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:38025/tests/kraken/driver.html?testName=imaging-darkroom) 21:58:10 INFO - PROCESS | 2288 | RSS: Main: 215896064 21:58:10 INFO - PROCESS | 2288 | 21:58:13 INFO - PROCESS | 2288 | Cycle 1(1): loaded http://localhost:38025/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:38025/tests/kraken/driver.html?testName=imaging-desaturate) 21:58:14 INFO - PROCESS | 2288 | RSS: Main: 213979136 21:58:14 INFO - PROCESS | 2288 | 21:58:17 INFO - PROCESS | 2288 | Cycle 1(1): loaded http://localhost:38025/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:38025/tests/kraken/driver.html?testName=json-parse-financial) 21:58:17 INFO - PROCESS | 2288 | RSS: Main: 214274048 21:58:17 INFO - PROCESS | 2288 | 21:58:19 INFO - PROCESS | 2288 | Cycle 1(1): loaded http://localhost:38025/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:38025/tests/kraken/driver.html?testName=json-stringify-tinderbox) 21:58:19 INFO - PROCESS | 2288 | RSS: Main: 176398336 21:58:19 INFO - PROCESS | 2288 | 21:58:21 INFO - PROCESS | 2288 | Cycle 1(1): loaded http://localhost:38025/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:38025/tests/kraken/driver.html?testName=stanford-crypto-aes) 21:58:22 INFO - PROCESS | 2288 | RSS: Main: 183234560 21:58:22 INFO - PROCESS | 2288 | 21:58:24 INFO - PROCESS | 2288 | Cycle 1(1): loaded http://localhost:38025/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:38025/tests/kraken/driver.html?testName=stanford-crypto-ccm) 21:58:24 INFO - PROCESS | 2288 | RSS: Main: 189108224 21:58:24 INFO - PROCESS | 2288 | 21:58:27 INFO - PROCESS | 2288 | Cycle 1(1): loaded http://localhost:38025/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:38025/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 21:58:27 INFO - PROCESS | 2288 | RSS: Main: 197926912 21:58:27 INFO - PROCESS | 2288 | 21:58:30 INFO - PROCESS | 2288 | Cycle 1(1): loaded http://localhost:38025/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:38025/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 21:58:31 INFO - PROCESS | 2288 | RSS: Main: 194056192 21:58:31 INFO - PROCESS | 2288 | 21:58:32 INFO - PROCESS | 2288 | Cycle 1(1): loaded http://localhost:38025/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:38025/tests/kraken/driver.html?testName=ai-astar) 21:58:33 INFO - PROCESS | 2288 | RSS: Main: 245387264 21:58:33 INFO - PROCESS | 2288 | 21:58:33 INFO - PROCESS | 2288 | __start_tp_report 21:58:33 INFO - PROCESS | 2288 | _x_x_mozilla_page_load 21:58:33 INFO - PROCESS | 2288 | _x_x_mozilla_page_load_details 21:58:33 INFO - PROCESS | 2288 | |i|pagename|runs| 21:58:33 INFO - PROCESS | 2288 | |0;ai-astar;110;110;119;109;109;110;110;112;111;111 21:58:33 INFO - PROCESS | 2288 | |1;audio-beat-detection;142;116;141;160;124;145;138;118;166;115 21:58:33 INFO - PROCESS | 2288 | |2;audio-dft;161;159;160;160;161;161;160;159;158;159 21:58:33 INFO - PROCESS | 2288 | |3;audio-fft;75;75;75;183;76;75;75;74;160;75 21:58:33 INFO - PROCESS | 2288 | |4;audio-oscillator;83;83;162;83;84;83;143;83;83;143 21:58:33 INFO - PROCESS | 2288 | |5;imaging-gaussian-blur;116;116;116;117;116;116;117;117;117;117 21:58:33 INFO - PROCESS | 2288 | |6;imaging-darkroom;146;146;146;146;146;147;145;146;145;145 21:58:33 INFO - PROCESS | 2288 | |7;imaging-desaturate;95;94;95;95;94;94;95;95;94;94 21:58:33 INFO - PROCESS | 2288 | |8;json-parse-financial;50;50;51;51;52;50;53;52;51;52 21:58:33 INFO - PROCESS | 2288 | |9;json-stringify-tinderbox;61;61;62;61;60;60;60;60;61;61 21:58:33 INFO - PROCESS | 2288 | |10;stanford-crypto-aes;72;73;72;78;78;74;74;74;74;73 21:58:33 INFO - PROCESS | 2288 | |11;stanford-crypto-ccm;130;119;119;119;119;119;116;130;156;121 21:58:33 INFO - PROCESS | 2288 | |12;stanford-crypto-pbkdf2;151;150;153;155;156;152;153;150;152;151 21:58:33 INFO - PROCESS | 2288 | |13;stanford-crypto-sha256-iterative;62;64;63;65;61;61;62;63;61;59 21:58:33 INFO - PROCESS | 2288 | __end_tp_report 21:58:33 INFO - PROCESS | 2288 | __start_cc_report 21:58:33 INFO - PROCESS | 2288 | _x_x_mozilla_cycle_collect,1708 21:58:33 INFO - PROCESS | 2288 | __end_cc_report 21:58:33 INFO - PROCESS | 2288 | __startTimestamp1477025913045__endTimestamp 21:58:33 INFO - PROCESS | 2288 | 21:58:33 INFO - PROCESS | 2288 | ------- Summary: start ------- 21:58:33 INFO - PROCESS | 2288 | Number of tests: 14 21:58:33 INFO - PROCESS | 2288 | 21:58:33 INFO - PROCESS | 2288 | [#0] ai-astar Cycles:10 Average:111.10 Median:110.50 stddev:2.92 (2.6%) stddev-sans-first:3.07 21:58:33 INFO - PROCESS | 2288 | Values: 110.0 110.0 119.0 109.0 109.0 110.0 110.0 112.0 111.0 111.0 21:58:33 INFO - PROCESS | 2288 | 21:58:33 INFO - PROCESS | 2288 | [#1] audio-beat-detection Cycles:10 Average:136.50 Median:141.50 stddev:18.04 (12.7%) stddev-sans-first:19.02 21:58:33 INFO - PROCESS | 2288 | Values: 142.0 116.0 141.0 160.0 124.0 145.0 138.0 118.0 166.0 115.0 21:58:33 INFO - PROCESS | 2288 | 21:58:33 INFO - PROCESS | 2288 | [#2] audio-dft Cycles:10 Average:159.80 Median:160.00 stddev:1.03 (0.6%) stddev-sans-first:1.00 21:58:33 INFO - PROCESS | 2288 | Values: 161.0 159.0 160.0 160.0 161.0 161.0 160.0 159.0 158.0 159.0 21:58:33 INFO - PROCESS | 2288 | 21:58:33 INFO - PROCESS | 2288 | [#3] audio-fft Cycles:10 Average:94.30 Median:75.00 stddev:41.05 (54.7%) stddev-sans-first:42.94 21:58:33 INFO - PROCESS | 2288 | Values: 75.0 75.0 75.0 183.0 76.0 75.0 75.0 74.0 160.0 75.0 21:58:33 INFO - PROCESS | 2288 | 21:58:33 INFO - PROCESS | 2288 | [#4] audio-oscillator Cycles:10 Average:103.00 Median:83.00 stddev:32.39 (39.0%) stddev-sans-first:33.54 21:58:33 INFO - PROCESS | 2288 | Values: 83.0 83.0 162.0 83.0 84.0 83.0 143.0 83.0 83.0 143.0 21:58:33 INFO - PROCESS | 2288 | 21:58:33 INFO - PROCESS | 2288 | [#5] imaging-gaussian-blur Cycles:10 Average:116.50 Median:117.00 stddev:0.53 (0.5%) stddev-sans-first:0.53 21:58:33 INFO - PROCESS | 2288 | Values: 116.0 116.0 116.0 117.0 116.0 116.0 117.0 117.0 117.0 117.0 21:58:33 INFO - PROCESS | 2288 | 21:58:33 INFO - PROCESS | 2288 | [#6] imaging-darkroom Cycles:10 Average:145.80 Median:146.00 stddev:0.63 (0.4%) stddev-sans-first:0.67 21:58:33 INFO - PROCESS | 2288 | Values: 146.0 146.0 146.0 146.0 146.0 147.0 145.0 146.0 145.0 145.0 21:58:33 INFO - PROCESS | 2288 | 21:58:33 INFO - PROCESS | 2288 | [#7] imaging-desaturate Cycles:10 Average:94.50 Median:95.00 stddev:0.53 (0.6%) stddev-sans-first:0.53 21:58:33 INFO - PROCESS | 2288 | Values: 95.0 94.0 95.0 95.0 94.0 94.0 95.0 95.0 94.0 94.0 21:58:33 INFO - PROCESS | 2288 | 21:58:33 INFO - PROCESS | 2288 | [#8] json-parse-financial Cycles:10 Average:51.20 Median:51.50 stddev:1.03 (2.0%) stddev-sans-first:1.00 21:58:33 INFO - PROCESS | 2288 | Values: 50.0 50.0 51.0 51.0 52.0 50.0 53.0 52.0 51.0 52.0 21:58:33 INFO - PROCESS | 2288 | 21:58:33 INFO - PROCESS | 2288 | [#9] json-stringify-tinderbox Cycles:10 Average:60.70 Median:61.00 stddev:0.67 (1.1%) stddev-sans-first:0.71 21:58:33 INFO - PROCESS | 2288 | Values: 61.0 61.0 62.0 61.0 60.0 60.0 60.0 60.0 61.0 61.0 21:58:33 INFO - PROCESS | 2288 | 21:58:33 INFO - PROCESS | 2288 | [#10] stanford-crypto-aes Cycles:10 Average:74.20 Median:74.00 stddev:2.15 (2.9%) stddev-sans-first:2.13 21:58:33 INFO - PROCESS | 2288 | Values: 72.0 73.0 72.0 78.0 78.0 74.0 74.0 74.0 74.0 73.0 21:58:33 INFO - PROCESS | 2288 | 21:58:33 INFO - PROCESS | 2288 | [#11] stanford-crypto-ccm Cycles:10 Average:124.80 Median:120.00 stddev:11.96 (10.0%) stddev-sans-first:12.54 21:58:33 INFO - PROCESS | 2288 | Values: 130.0 119.0 119.0 119.0 119.0 119.0 116.0 130.0 156.0 121.0 21:58:33 INFO - PROCESS | 2288 | 21:58:33 INFO - PROCESS | 2288 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:152.30 Median:152.50 stddev:2.00 (1.3%) stddev-sans-first:2.07 21:58:33 INFO - PROCESS | 2288 | Values: 151.0 150.0 153.0 155.0 156.0 152.0 153.0 150.0 152.0 151.0 21:58:33 INFO - PROCESS | 2288 | 21:58:33 INFO - PROCESS | 2288 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:62.10 Median:62.50 stddev:1.73 (2.8%) stddev-sans-first:1.83 21:58:33 INFO - PROCESS | 2288 | Values: 62.0 64.0 63.0 65.0 61.0 61.0 62.0 63.0 61.0 59.0 21:58:33 INFO - PROCESS | 2288 | -------- Summary: end -------- 21:58:33 INFO - PROCESS | 2288 | 21:58:33 INFO - TEST-INFO | 2288: exit 0 21:58:34 INFO - TEST-OK | kraken | took 59350ms 21:58:34 INFO - SUITE-END | took 1171s 21:58:34 INFO - Completed test suite (00:19:31) 21:58:34 INFO - javascript benchmark 21:58:34 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3249.1552196428925, 3261.282517846074, 3288.408086544424, 3313.436715758321, 3319.4854797506396, 5648.293963254593, 5734.885302293952, 5740.540756739461, 5742.28221472316, 5756.107803290492, 5655.688937108457, 5713.371529131792, 5718.199452076713, 5719.4566516181, 5722.256106706132, 5194.298769666085, 5199.662021968579, 5208.791648334071, 5248.317718696583, 5280.5247527722495, 5122.974385128069, 5166.819161329354, 5285.682859028451, 5295.602829787762, 5332.840014799543, 5145.459726728695, 5161.0, 5185.585153187736, 5189.273501709745, 5231.503007214327, 5237.240600113, 5240.580753539708, 5311.442298558634, 5314.60140489461, 5316.843298272161, 5062.139436295838, 5134.922976155361, 5217.008768334004, 5255.894882102375, 5260.368755749335, 5491.807786727445, 5500.064988951888, 5502.504774570308, 5531.280933478622, 5546.140348246028, 5922.31893332268, 6044.788432404889, 6072.817815465543, 6078.270607527125, 6078.574499785017, 5984.431479009487, 6096.024636058254, 6107.358727333655, 6119.326874043852, 6121.632702037848, 5678.801241956512, 5731.914021289684, 5755.568332375088, 5784.942150578482, 5785.595008349375, 6318.494520438354, 6324.968375158095, 6330.177076979963, 6333.778317758856, 6335.366463353628, 5893.675847828411, 5899.498542623839, 5943.10853371989, 6056.57603967718, 6067.908981365283, 6101.328853826076, 6106.2061931948565, 6107.84730381744, 6117.388261173847, 6117.663528505886, 6042.577019608585, 6043.184170137063, 6046.486048685911, 6053.27360716708, 6053.848653783691, 5897.233359663216, 5980.55145864062, 5985.281766188085, 6016.518678505709, 6074.75700971965, 4576.954230457719, 4628.375169352162, 4637.234856248681, 4646.95353046472, 4649.860504184812, 4154.480689913761, 4159.667226621863, 4163.646090082317, 4164.6251837334785, 4184.832606695759, 5734.139879018097, 5747.48272655463, 5748.993926062923, 5750.2237197978575, 5750.453706897922, 5919.348871624118, 5994.460498555151, 6056.9697151513965, 6064.4541991221, 6120.540959427972, 5728.427157284238, 5731.684757338303, 5733.0, 5733.799317023884, 5751.884962300731, 5191.740412979336, 5234.9476505235225, 5244.396894357198, 5249.501297376743, 5281.102212623863, 6118.010103775246, 6129.847850912876, 6275.309715931244, 6308.116863639094, 6323.241211054703, 6114.419130182626, 6137.9286238596105, 6165.599236049642, 6168.136460895388, 6172.783952561639, 6371.331010243857, 6381.234251889618, 6391.552591318749, 6392.0, 6399.712012959335, 5802.303723553201, 5882.029465138376, 5913.438223368773, 5916.083007133901, 6064.636121832704, 6076.848078798065, 6092.634441933498, 6092.695365231632, 6098.603590766764, 6128.724207410766, 5874.177615133799, 5930.584859059824, 5941.049432090845, 5945.732442040205, 5947.464728174485, 5825.563082768725, 5925.703714814328, 5938.168656388023, 5970.3432622412365, 5984.0, 5953.821385358447, 5991.67045812467, 5999.640021598718, 6000.939990600038, 6011.549133815069, 6023.066424704178, 6035.78874739382, 6037.69811509414, 6042.788502402394, 6060.666663333559, 5798.971005144947, 5813.593048486566, 5868.677222752619, 5871.1486834409625, 5919.881602368015], "value": 5615.698070309048, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [585.8798946216026, 629.3046183966717, 631.6904716688821, 650.5218664281749, 650.6584043377227, 34742.65257347426, 46189.0, 47309.29036064453, 47341.0, 47449.76275118624, 47893.52106478934, 48159.0, 48345.0, 48376.75811620946, 48460.0, 8618.827623447527, 9355.251579873611, 9489.098535639103, 9519.857202141973, 11089.33463992159, 11158.442077896072, 11276.0, 11276.943615281953, 11289.266197697165, 11292.717682057932, 11348.546058157664, 11387.829182562267, 11396.544138234462, 11400.885991140065, 11411.771764564704, 11284.943575282112, 11306.604268850591, 11321.377324247163, 11329.830052549218, 11330.546778128864, 11173.385463799488, 11192.552297908074, 11206.831897521462, 11233.66299011031, 11240.775184496264, 44987.775061124485, 45343.0, 45395.0, 45491.54508454906, 45496.54503454956, 3773.28569233896, 3948.3879998600264, 3971.086650070471, 4059.6143366380147, 4730.432348118248, 35022.47466288008, 35041.29917401638, 35227.64772352269, 35731.4640280396, 36847.4472882907, 4624.630029597624, 4651.837185698518, 4708.952910470885, 4737.73942433166, 4794.760261986887, 393891.0, 395078.0, 395172.0241398803, 395319.0, 396990.0, 66323.66838165779, 66613.66693166504, 66820.33179668238, 66898.0, 66899.33100669028, 39640.405393919114, 40982.79508602498, 40991.590084098774, 43060.354094688606, 43063.0, 17583.120843958008, 17700.026498542447, 17703.20335584876, 17716.82283177152, 17728.46814595564, 12763.361831908367, 13687.657808554866, 13899.096558723853, 13948.511802086878, 13999.16005039701, 20756.89621551913, 21223.0, 21248.78751212468, 21257.149714011266, 21299.8935005324, 7249.891251631229, 7267.292186336207, 7410.888836667455, 7415.99884015651, 7531.510451820614, 1037.3723897042332, 1099.6206308823444, 1126.9661910142709, 1145.3242586873785, 1146.18620779246, 35308.64691353105, 35991.640083599355, 36000.45999310012, 36013.41619611969, 36192.09519761975, 1114.136544178252, 1140.7946569617382, 1142.4344949250174, 1170.6722117807028, 1227.1042139238407, 18525.166367513495, 18562.535936601424, 18791.436256912315, 18871.528211794815, 18901.243950241835, 592124.0, 599817.0, 601633.0, 602239.0, 602937.0, 66365.0, 66925.66537167381, 66959.33040669629, 66961.66519167373, 66979.99530007054, 1286.202554416249, 1371.163590209971, 1412.1385954567706, 1429.9714005719827, 1441.54591303739, 33875.83062084772, 36276.0, 36335.636643633225, 36443.63556364403, 36589.634103658624, 55844.44155558392, 60830.39169608424, 63298.68350658401, 63659.363406365344, 69511.65244173747, 576700.0, 583095.0, 584199.0, 584923.0, 585514.0, 40231.396529051504, 46369.0, 46540.7672961633, 46551.76724116358, 49619.75190124026, 52834.0, 52835.0, 53632.0, 53745.73127134496, 54163.18755218596, 44766.0, 44914.32628510641, 45806.54193458156, 46121.0, 47652.761736191096, 45348.31977520274, 46065.0, 46262.07475850532, 46303.30545041894, 47234.055318892744, 44557.77721111374, 45217.0, 45562.31656525088, 45783.77108114438, 45882.77058614686, 38378.0, 40156.598434015286, 40246.79876600598, 40551.0, 40921.79539102405, 29704.70295297106, 29730.554041689826, 29811.10475100189, 30352.54471182978, 30524.236894076937], "value": 22515.966885914873, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [427.9607669455047, 429.4159942478227, 436.62693262120376, 442.43589423485054, 445.6835646690847, 19190.520236994063, 20505.589888202227, 21049.47376315591, 21107.366778996617, 21268.14927402902, 19429.12568934398, 19434.41696749096, 19839.10724017419, 19978.60042799143, 19984.9000754996, 5947.078202878561, 6181.13464115022, 6199.504039676837, 6206.441420272175, 6207.906881396782, 5846.3861294563885, 6004.759809607611, 6028.276606807167, 6070.150178974947, 6071.544634152434, 6042.365551617083, 6042.486388656947, 6050.183225264599, 6053.485453736438, 6054.303755068179, 5844.532437405019, 5990.700464976756, 6014.578979471439, 6018.097285407181, 6023.337432882379, 5381.354237491487, 5457.372402173761, 5478.096114141202, 5481.506664400223, 5482.259894914176, 24462.26613201607, 24530.01879924801, 24531.754682453124, 24572.631410528676, 24699.38251543708, 2389.856608603472, 2730.6586676665415, 2735.9589606155923, 2742.053991372973, 3171.666974967641, 11030.393328366936, 11305.943470282678, 11308.660740177807, 11311.886881131166, 11376.033037191848, 2024.8278896293832, 2065.297798748418, 2085.895705214733, 2123.6708310211943, 2174.6194415977297, 84612.0, 87353.56323218407, 88375.0, 90670.54664726634, 90791.54604227003, 11846.88153118475, 11958.940205298917, 11998.0, 12076.094292928066, 12332.81500777489, 17216.827831721523, 17243.310267589408, 17264.913675431544, 17278.4816455504, 17284.70364722651, 8052.436329456882, 8184.795380115425, 8187.140350263256, 8210.384221183316, 8288.295494882881, 7490.887636685454, 7584.469087163956, 7668.079830420385, 7845.37237021037, 7861.977942867505, 5008.198688209942, 5018.473060328686, 5050.444451110375, 5110.770015349318, 5337.2527846101575, 1990.4227773945393, 2091.6473087537547, 2122.564874200785, 2240.20472732181, 2251.842371034045, 920.963161473547, 1040.557762950743, 1049.275999560301, 1052.768390953989, 1120.5461787975883, 20321.0, 20852.895735521226, 21009.529332946546, 21059.578808424052, 21197.89401052985, 1012.8126296635146, 1024.1601886453186, 1061.3790932304535, 1063.3726101600093, 1074.2533938912381, 11091.889081109086, 11487.182549754261, 11709.121815863844, 11774.0, 11825.40872956366, 537648.0, 543300.0, 576095.0, 576337.0, 588225.0, 100533.49733251287, 102535.4873225644, 104020.0, 105729.47135264275, 106643.46678266714, 1036.1658864613908, 1040.6149724601794, 1045.0594464981425, 1051.3849398102043, 1052.9473526323807, 17672.823271767116, 17814.376496822548, 18095.185716643045, 18610.720839187692, 19113.617727645647, 46535.76732116453, 46697.0, 46972.530274696815, 47397.52602473931, 48557.5144248553, 60234.39765602288, 63423.36576634175, 63823.6808815953, 65270.67364663146, 66017.66991165014, 8261.421700480909, 8696.391252612257, 8720.869186962074, 8799.956000219958, 8960.238379737795, 11455.65633030977, 12307.0, 12568.685782855502, 12653.367331633197, 12669.429875655802, 11087.72280692989, 11195.216334856483, 11414.372209528556, 11450.312981221154, 11778.58774942907, 10804.67585972422, 11012.614558490415, 11236.719082023015, 11382.146339024772, 11565.537378504765, 11186.720331991766, 11371.488283027118, 11528.711782205512, 11565.59520416815, 11660.53357865676, 8523.445976011539, 8662.307015438875, 8719.956400217958, 8822.2060014599, 8875.822483550422, 9073.546322683971, 11379.772404552028, 11463.885361146282, 11529.654110376701, 11765.411729413667], "value": 9712.341246159247, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1400.4958215042577, 1510.7356212662785, 1532.9080255184688, 1682.9747553786688, 1799.6400719856033, 8978.685745998882, 9359.578818953154, 9402.341836071479, 9697.175740062086, 9716.75708107296, 9594.712158635251, 9706.369086009405, 9713.07725766051, 9727.902720972788, 9731.610735570568, 2495.4759500504915, 2547.694276686791, 2551.5152121097026, 2551.872406379683, 2555.4505781257026, 2448.436859522311, 2519.48350588129, 2520.7479252074736, 2522.1550780488465, 2522.751238137125, 2483.7392073832257, 2498.562751518486, 2543.656606358146, 2544.1477105169793, 2549.235229431163, 2432.221689059502, 2436.7319594844553, 2442.560339138954, 2491.1281051631786, 2494.064725727852, 2403.579373609611, 2430.040134147002, 2433.914812981537, 2440.45089854783, 2472.678551788274, 9684.08001239881, 9703.0, 9776.853347199798, 9784.364016338915, 9791.118799308026, 5573.721313934287, 5727.8568035799435, 5740.225069615591, 5825.912611310791, 5827.912581311284, 3572.8034958077296, 3598.838052287653, 3607.531020967284, 3678.4114541673202, 3700.7964561949084, 1292.8577856435784, 1299.0711641176604, 1315.15829868884, 1342.812006319116, 1343.7514059898851, 13707.0, 13757.449702011907, 13773.724525509533, 13853.445862165501, 13919.02566820323, 4842.128416884924, 4916.262560615936, 4968.552830245295, 5066.392032955995, 5099.847004589868, 10791.082757965662, 10816.459177041274, 10823.0, 10835.079018283377, 10838.67483975482, 5614.073677843192, 5653.095504719226, 5653.717314134276, 5658.63218890779, 5663.122216056518, 4765.428148622187, 4786.760661966888, 5073.949260507347, 5254.527092561688, 5316.7075810830775, 4218.831246750157, 4223.68322375823, 4277.465316835395, 4300.333448315516, 4309.762963037, 1272.783626783449, 1291.9089615649566, 1297.8961683065331, 1300.7447812860446, 1303.1920209470188, 993.6174572789528, 1059.1156384418941, 1060.5657008119488, 1060.8408738689259, 1094.179365475893, 6534.836629084216, 6663.300353462817, 6699.229588597276, 6774.491913106537, 6858.622775747282, 513.8766695992935, 515.2297315513332, 519.5973120831386, 522.2688236468974, 534.2120372450604, 4517.593416592523, 4629.421322334708, 4631.791569379386, 4714.929276060861, 4819.614430845524, 11573.826392604118, 11733.589324373606, 11980.700482488008, 12047.51809927593, 12084.637460876187, 11703.0, 11830.112741544419, 11852.940735296268, 11854.822177667342, 11857.466414011391, 8692.174243446863, 8700.651973920973, 8750.91249087501, 8838.779030524289, 8887.200151986352, 6622.867542649024, 6791.151106111736, 6811.863762724817, 6821.0, 6838.487113466609, 10967.122630189726, 11256.943715281372, 11292.491837867152, 11355.716107097389, 11408.94295528517, 11200.43997800123, 11316.37759923212, 11473.713157170803, 11521.0, 11617.186796924134, 7972.36221102322, 8050.476719013245, 8180.877286840583, 8246.463979841292, 8331.208535189147, 9486.28852836054, 9538.14156725898, 9560.0, 9565.713028609152, 9568.186704130227, 8440.535770532433, 8570.314374849871, 8583.914160858312, 8652.82694346097, 8755.518446485505, 8355.164483551596, 8403.495790252604, 8453.408261421888, 8463.57682115904, 8647.783805404915, 7324.81687957784, 7443.218462061405, 7498.287662672037, 7520.0, 7535.73624923125, 5558.796109341225, 5626.409227031103, 5671.574631902707, 5803.883922321445, 5946.345901950868, 5690.971545142414, 5700.543956483554, 5842.38654941225, 5871.941280587311, 5989.730462129128], "value": 4965.9689543622435, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [357.9606243313235, 362.80227276134514, 363.6563447542072, 365.4846666200654, 373.5778570215654, 5516.862078448035, 5546.722663866801, 5546.889062218758, 5552.666839989593, 5582.190582365554, 5459.208414779864, 5476.479734425223, 5484.396716361197, 5489.478499542547, 5568.610197286192, 3994.2211268802594, 4058.208649313385, 4381.474223093233, 4419.469663640351, 4471.434997750785, 4317.935230971538, 4349.652027837781, 4362.629176519984, 4392.34114882767, 4397.12057588482, 4359.193549193394, 4360.542143074979, 4362.323839804835, 4366.97816510917, 4368.97815510922, 4276.567349937774, 4315.697901146921, 4360.171567402183, 4361.9749358900635, 4367.890802729926, 3896.5324161100566, 3897.5322961244547, 3902.804859757029, 3920.2551515212017, 3946.467226924357, 5351.946480535184, 5356.7321633918145, 5376.440832158669, 5395.0, 5433.375161856397, 4877.111756883212, 4925.881870795556, 5029.823956161516, 5045.2432135180015, 5046.343975283226, 5656.0, 5718.25662663851, 5767.36558978512, 5770.9422905770825, 5827.504662103726, 4254.680898932562, 4268.807903644343, 4280.614744672995, 4305.332673435622, 4332.133573285356, 5731.828045158652, 6046.606970546901, 6051.515878729691, 6062.9090563641585, 6072.51419886408, 4291.313389857608, 4314.784260786948, 4319.222539942841, 4320.589543993316, 4322.7190232634775, 4417.646588272994, 4421.778911054499, 4426.8671939841215, 4428.047969686533, 4434.623057040123, 4875.878103047452, 4981.726005069756, 4987.526185012418, 5008.198688209869, 5026.949730502722, 5771.105478650816, 5783.479486846204, 5790.71046447676, 5794.536437085023, 5850.795222167204, 1491.4258010665756, 1506.6007508010387, 1514.6289159155856, 1525.7329967255907, 1526.8702160316275, 931.3992474853817, 964.4261664309724, 978.9867487150765, 982.4203719805349, 1000.3444299684035, 4934.309196712463, 5356.60728210668, 5389.191621256843, 5581.5255703265675, 5625.409332020161, 5912.46787789101, 5917.289925209003, 5922.3713478792815, 5926.288845338587, 5928.822135335947, 5455.0, 5578.63738856973, 5583.664980101207, 5620.185073164368, 5624.156376543551, 3075.138961090853, 3135.8902438415003, 3150.952735708966, 3161.873525058972, 3161.9841900790807, 6155.969220153871, 6200.875982480416, 6221.191245138103, 6241.531885108635, 6265.310815810257, 5733.397993210653, 6016.488598469092, 6025.758969641253, 6035.637861728311, 6041.365656606081, 6047.427668806142, 6094.268687757498, 6115.694215289218, 6126.23422072241, 6126.877462450726, 5698.857528561819, 5795.246617939641, 5947.197128387612, 5960.314563825215, 5977.372375900468, 5740.885182296247, 5829.358770535153, 5831.94168058314, 5857.707114644336, 5874.676892770939, 5954.702264886825, 6047.969760151171, 6081.26002508463, 6109.389061093855, 6124.663143527148, 4224.197402493516, 4285.871423857289, 4294.506131794884, 4306.41863348444, 4324.481062272547, 5876.265466816648, 5936.0, 5950.404959504015, 5985.162077309267, 6040.516758659384, 5567.665940043448, 5674.687892165971, 5773.0, 5835.67903765297, 5879.911801323069, 5652.576056795675, 5721.313442386941, 5758.424157584208, 5783.421657834183, 5796.681182535001, 5693.971530142323, 5759.56803239767, 5772.509336706427, 5808.94191058084, 5812.593118481835, 5624.521915637217, 5769.365369809401, 5815.94184058154, 5834.94165058361, 5835.708214589169, 5675.631083979528, 5688.354914964561, 5693.28833895763, 5707.800226992035, 5711.600187986801], "value": 4442.603881871036, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [800.3037357498973, 803.6062329458559, 822.407866336238, 858.257607169798, 869.4739682492092, 16190.190490475474, 17615.47153585393, 18157.09214539271, 18359.908200459013, 18408.723869141973, 16129.193540322965, 17326.306947722074, 17436.56408589786, 17456.912715436403, 17466.301347946068, 816.9468984516002, 846.4244313866568, 847.6524624903791, 847.9236868681818, 849.0363437498422, 859.222403724627, 861.7027125641644, 862.5644049754865, 863.6718047142077, 863.7279257034014, 907.4963395315597, 908.600215905003, 909.2816674826879, 910.1171863292595, 911.5351170902854, 902.467544148952, 902.4900930974044, 904.317240483434, 904.7647611621026, 905.0632595263903, 661.4642139866676, 661.5137873662854, 661.8014595621295, 662.1491383572087, 662.2285037930853, 16021.279042443002, 16905.49283521496, 16907.492775216764, 16965.66068678632, 17028.063456509888, 16036.0, 16561.6687666246, 16934.91532542342, 17023.82976170235, 17199.828001719947, 13534.390952407166, 13551.932240338834, 13627.0, 13827.308634568331, 13892.791608125886, 4141.81361838718, 4201.873943781661, 4232.195882782293, 4267.871963841089, 4309.82760689572, 91608.08391916062, 91981.54009229911, 93184.53407732985, 94825.0, 95139.52430237805, 11637.53449861996, 12637.43131559082, 12690.80963785544, 12713.110082294335, 12989.610311690665, 23432.414189645395, 23471.41321466977, 23472.7652723474, 23521.17675881336, 23543.41141471477, 9059.909400906039, 9183.0, 9225.40034897743, 9400.858987115198, 9509.66716164931, 5031.7987280509105, 5244.921326180111, 5299.49654782795, 5428.131498960148, 5536.944630553723, 3727.5340582427198, 3850.9229815403537, 3857.6528112470014, 3859.9228015439535, 3859.96140038596, 662.8806814773291, 671.9529632925746, 671.96976136074, 688.703857341348, 697.2504557600498, 696.5959743348845, 754.2532892436558, 785.4698078796789, 806.1092492795377, 806.846699127164, 10283.573412570253, 10350.275480716427, 10568.841467377995, 10581.206409519316, 10961.890381096087, 672.3175976383974, 760.9885851712229, 783.4829012851491, 792.6948124971811, 796.1520980156115, 4818.4217893852965, 4938.802447902115, 4995.600351971833, 5010.774515146754, 5096.745162741848, 133904.330478347, 134894.0, 134986.0, 137936.31031844777, 140193.0, 68556.6572167146, 90631.0, 113459.0, 114025.0, 114981.0, 52494.21258681123, 53566.7321663397, 62591.6870415645, 64612.0, 76951.0, 10043.949780251052, 10969.835452468378, 11027.83458248143, 11199.66401007971, 11231.4384280784, 47525.0, 47576.524234757206, 49231.0, 54829.45170548403, 55111.0, 72542.27457725356, 78650.6067469659, 80406.59796700979, 81944.0, 85315.0, 13908.860911390757, 14168.716625667636, 14203.172393217807, 14315.14109153454, 14408.495702650776, 16197.271122799266, 16294.0, 16557.83442165563, 16602.418915337905, 16710.24803883804, 15148.0, 15153.393864245307, 15251.0, 15994.920025399799, 16184.352625895302, 12722.681932951751, 13356.866431335562, 13596.048276620542, 14138.0, 14739.55781326562, 13543.59369218925, 13571.79642305386, 13602.319884005563, 13664.111832730841, 13945.3724582392, 12740.68148296263, 12828.166169199345, 13016.349182541024, 13126.934365328112, 13641.38613762403, 12702.936485317514, 12725.87274127247, 12734.74530509366, 13063.477460901457, 13246.66883327886], "value": 8192.44010254208, "unit": "score"}], "name": "dromaeo_css", "value": 7781.314544589538, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [110.0, 110.0, 119.0, 109.0, 109.0, 110.0, 110.0, 112.0, 111.0, 111.0], "value": 111.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [142.0, 116.0, 141.0, 160.0, 124.0, 145.0, 138.0, 118.0, 166.0, 115.0], "value": 136.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 159.0, 160.0, 160.0, 161.0, 161.0, 160.0, 159.0, 158.0, 159.0], "value": 159.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 75.0, 75.0, 183.0, 76.0, 75.0, 75.0, 74.0, 160.0, 75.0], "value": 94.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 83.0, 162.0, 83.0, 84.0, 83.0, 143.0, 83.0, 83.0, 143.0], "value": 103.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 116.0, 116.0, 117.0, 116.0, 116.0, 117.0, 117.0, 117.0, 117.0], "value": 116.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 146.0, 146.0, 146.0, 146.0, 147.0, 145.0, 146.0, 145.0, 145.0], "value": 145.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 94.0, 95.0, 95.0, 94.0, 94.0, 95.0, 95.0, 94.0, 94.0], "value": 94.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [50.0, 50.0, 51.0, 51.0, 52.0, 50.0, 53.0, 52.0, 51.0, 52.0], "value": 51.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 61.0, 62.0, 61.0, 60.0, 60.0, 60.0, 60.0, 61.0, 61.0], "value": 60.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [72.0, 73.0, 72.0, 78.0, 78.0, 74.0, 74.0, 74.0, 74.0, 73.0], "value": 74.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [130.0, 119.0, 119.0, 119.0, 119.0, 119.0, 116.0, 130.0, 156.0, 121.0], "value": 124.8, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [151.0, 150.0, 153.0, 155.0, 156.0, 152.0, 153.0, 150.0, 152.0, 151.0], "value": 152.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 64.0, 63.0, 65.0, 61.0, 61.0, 62.0, 63.0, 61.0, 59.0], "value": 62.1, "unit": "score"}], "name": "kraken", "value": 1486.8, "alertThreshold": 2.0}]} 21:58:34 INFO - Return code: 0 21:58:34 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 21:58:34 INFO - # TBPL SUCCESS # 21:58:34 INFO - Running post-action listener: _resource_record_post_action 21:58:34 INFO - [mozharness: 2016-10-21 04:58:34.336954Z] Finished run-tests step (success) 21:58:34 INFO - Running post-run listener: _resource_record_post_run 21:58:34 INFO - Total resource usage - Wall time: 1186s; CPU: 13.0%; Read bytes: 4096; Write bytes: 291774464; Read time: 8; Write time: 184264 21:58:34 INFO - TinderboxPrint: CPU usage
12.9% 21:58:34 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 8 21:58:34 INFO - TinderboxPrint: I/O write bytes / time
291,774,464 / 184,264 21:58:34 INFO - TinderboxPrint: CPU idle
8,246.2 (87.1%) 21:58:34 INFO - TinderboxPrint: CPU user
1,179.3 (12.5%) 21:58:34 INFO - TinderboxPrint: Swap in / out
0 / 0 21:58:34 INFO - install - Wall time: 14s; CPU: 13.0%; Read bytes: 0; Write bytes: 71303168; Read time: 0; Write time: 44072 21:58:34 INFO - run-tests - Wall time: 1172s; CPU: 13.0%; Read bytes: 0; Write bytes: 220471296; Read time: 0; Write time: 140192 21:58:34 INFO - Running post-run listener: _upload_blobber_files 21:58:34 INFO - Blob upload gear active. 21:58:34 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 21:58:34 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 21:58:34 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'] 21:58:34 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 21:58:35 INFO - (blobuploader) - INFO - Open directory for files ... 21:58:35 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 21:58:35 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 21:58:35 INFO - (blobuploader) - INFO - Uploading, attempt #1. 21:58:36 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 21:58:36 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 21:58:36 INFO - (blobuploader) - INFO - Done attempting. 21:58:36 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 21:58:36 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 21:58:36 INFO - (blobuploader) - INFO - Uploading, attempt #1. 21:58:39 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 21:58:39 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 21:58:39 INFO - (blobuploader) - INFO - Done attempting. 21:58:39 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 21:58:39 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 21:58:39 INFO - (blobuploader) - INFO - Uploading, attempt #1. 21:58:40 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 21:58:40 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 21:58:40 INFO - (blobuploader) - INFO - Done attempting. 21:58:40 INFO - (blobuploader) - INFO - Iteration through files over. 21:58:40 INFO - Return code: 0 21:58:40 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 21:58:40 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 21:58:40 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/00f8b3e09713f2a4bf4cd20be8b108664f7337dd27d6507310ab17a950ff51f327fbe0fd2da91a6d942b0363b8d357a8ea8384497cad8d15ae956d128b4ad7d3", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/eeff916df31dd91611a73b1eab6f0a44a5422d51654bfe4e1683e2c93abc25236ea08313e53eabf6379728338b8b2fe495631d4acdb73c7c4dc64ce8350b1f12", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/43549ced14078352e45f774b8987a8267e1b86e1100ddec8580838f02d37dfc7d93ae70750f74bd16c66872c7d39de0fe096549dc4b8d7a209132a6d27f5edfb"} 21:58:40 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 21:58:40 INFO - Writing to file /builds/slave/test/properties/blobber_files 21:58:40 INFO - Contents: 21:58:40 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/00f8b3e09713f2a4bf4cd20be8b108664f7337dd27d6507310ab17a950ff51f327fbe0fd2da91a6d942b0363b8d357a8ea8384497cad8d15ae956d128b4ad7d3", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/eeff916df31dd91611a73b1eab6f0a44a5422d51654bfe4e1683e2c93abc25236ea08313e53eabf6379728338b8b2fe495631d4acdb73c7c4dc64ce8350b1f12", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/43549ced14078352e45f774b8987a8267e1b86e1100ddec8580838f02d37dfc7d93ae70750f74bd16c66872c7d39de0fe096549dc4b8d7a209132a6d27f5edfb"} 21:58:40 INFO - Running post-run listener: copy_logs_to_upload_dir 21:58:40 INFO - Copying logs to upload dir... 21:58:40 INFO - mkdir: /builds/slave/test/build/upload/logs 21:58:40 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1675.456056 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 27 mins, 55 secs) (at 2016-10-20 21:58:40.323972) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-20 21:58:40.328154) ========= 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=ed14279e38b05397cf4aa391000001a4-1477023971.670634-1232523364 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/00f8b3e09713f2a4bf4cd20be8b108664f7337dd27d6507310ab17a950ff51f327fbe0fd2da91a6d942b0363b8d357a8ea8384497cad8d15ae956d128b4ad7d3", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/eeff916df31dd91611a73b1eab6f0a44a5422d51654bfe4e1683e2c93abc25236ea08313e53eabf6379728338b8b2fe495631d4acdb73c7c4dc64ce8350b1f12", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/43549ced14078352e45f774b8987a8267e1b86e1100ddec8580838f02d37dfc7d93ae70750f74bd16c66872c7d39de0fe096549dc4b8d7a209132a6d27f5edfb"} build_url:https://queue.taskcluster.net/v1/task/bwlu0zmBSsyZpm4qeJimlA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011380 build_url: 'https://queue.taskcluster.net/v1/task/bwlu0zmBSsyZpm4qeJimlA/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/00f8b3e09713f2a4bf4cd20be8b108664f7337dd27d6507310ab17a950ff51f327fbe0fd2da91a6d942b0363b8d357a8ea8384497cad8d15ae956d128b4ad7d3", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/eeff916df31dd91611a73b1eab6f0a44a5422d51654bfe4e1683e2c93abc25236ea08313e53eabf6379728338b8b2fe495631d4acdb73c7c4dc64ce8350b1f12", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/43549ced14078352e45f774b8987a8267e1b86e1100ddec8580838f02d37dfc7d93ae70750f74bd16c66872c7d39de0fe096549dc4b8d7a209132a6d27f5edfb"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-20 21:58:40.353368) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-20 21:58:40.353700) ========= 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=ed14279e38b05397cf4aa391000001a4-1477023971.670634-1232523364 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004475 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-20 21:58:40.437603) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-20 21:58:40.437921) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-20 21:58:40.438235) ========= ========= Total master_lag: 0.18 =========