builder: mozilla-aurora_ubuntu64_hw_test-dromaeojs-e10s-pgo slave: talos-linux64-ix-005 starttime: 1473044889.46 results: success (0) buildid: 20160904171919 builduid: 77297d43100e4038ab68960df429a761 revision: 2231ab09a51c3b21dae6fa1627c1a64cfd43b3b0 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-09-04 20:08:09.455427) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-09-04 20:08:09.455911) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-04 20:08:09.456225) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-04 20:08:09.575109) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-04 20:08:09.575451) ========= 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=7fc86f70bae03e018da0b1d8000001c4-1473033471.633764-940869890 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-09-04 20:08:11-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 224M=0s 2016-09-04 20:08:11 (224 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.076623 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-04 20:08:09.670135) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-04 20:08:09.670452) ========= 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=7fc86f70bae03e018da0b1d8000001c4-1473033471.633764-940869890 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.020648 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-04 20:08:09.724899) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-04 20:08:09.725278) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 2231ab09a51c3b21dae6fa1627c1a64cfd43b3b0 --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-aurora --rev 2231ab09a51c3b21dae6fa1627c1a64cfd43b3b0 --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=7fc86f70bae03e018da0b1d8000001c4-1473033471.633764-940869890 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-09-04 20:08:11,323 truncating revision to first 12 chars 2016-09-04 20:08:11,323 Setting DEBUG logging. 2016-09-04 20:08:11,323 attempt 1/10 2016-09-04 20:08:11,323 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/2231ab09a51c?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-09-04 20:08:12,092 unpacking tar archive at: mozilla-aurora-2231ab09a51c/testing/mozharness/ program finished with exit code 0 elapsedTime=1.114733 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-04 20:08:10.859862) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-04 20:08:10.860270) ========= script_repo_revision: 2231ab09a51c3b21dae6fa1627c1a64cfd43b3b0 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-04 20:08:10.860728) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-04 20:08:10.861280) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-04 20:08:10.873311) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 27 mins, 56 secs) (at 2016-09-04 20:08:10.873840) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 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-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Aurora', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] 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=7fc86f70bae03e018da0b1d8000001c4-1473033471.633764-940869890 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 20:08:12 INFO - MultiFileLogger online at 20160904 20:08:12 in /builds/slave/test 20:08:12 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 20:08:12 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 20:08:12 INFO - {'append_to_log': False, 20:08:12 INFO - 'base_work_dir': '/builds/slave/test', 20:08:12 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 20:08:12 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 20:08:12 INFO - 'branch': 'Mozilla-Aurora', 20:08:12 INFO - 'buildbot_json_path': 'buildprops.json', 20:08:12 INFO - 'config_files': ('talos/linux_config.py',), 20:08:12 INFO - 'default_actions': ('clobber', 20:08:12 INFO - 'read-buildbot-config', 20:08:12 INFO - 'download-and-extract', 20:08:12 INFO - 'populate-webroot', 20:08:12 INFO - 'create-virtualenv', 20:08:12 INFO - 'install', 20:08:12 INFO - 'run-tests'), 20:08:12 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 20:08:12 INFO - 'download_minidump_stackwalk': True, 20:08:12 INFO - 'download_symbols': 'ondemand', 20:08:12 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 20:08:12 INFO - 'tooltool.py': '/tools/tooltool.py', 20:08:12 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 20:08:12 INFO - '/tools/misc-python/virtualenv.py')}, 20:08:12 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 20:08:12 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 20:08:12 INFO - 'installer_path': 'installer.exe', 20:08:12 INFO - 'log_level': 'info', 20:08:12 INFO - 'log_name': 'talos', 20:08:12 INFO - 'log_to_console': True, 20:08:12 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 20:08:12 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 20:08:12 INFO - 'opt_config_files': (), 20:08:12 INFO - 'pip_index': False, 20:08:12 INFO - 'sps_profile': False, 20:08:12 INFO - 'sps_profile_interval': 0, 20:08:12 INFO - 'suite': 'dromaeojs-e10s', 20:08:12 INFO - 'system_bits': '32', 20:08:12 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 20:08:12 INFO - 'title': 'talos-linux64-ix-005', 20:08:12 INFO - 'tooltool_cache': '/builds/tooltool_cache', 20:08:12 INFO - 'use_talos_json': True, 20:08:12 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 20:08:12 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 20:08:12 INFO - 'work_dir': 'build'} 20:08:12 INFO - [mozharness: 2016-09-05 03:08:12.566379Z] Running clobber step. 20:08:12 INFO - Running pre-action listener: _resource_record_pre_action 20:08:12 INFO - Running main action method: clobber 20:08:12 INFO - rmtree: /builds/slave/test/build 20:08:12 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 20:08:13 INFO - Running post-action listener: _resource_record_post_action 20:08:13 INFO - [mozharness: 2016-09-05 03:08:13.541186Z] Finished clobber step (success) 20:08:13 INFO - [mozharness: 2016-09-05 03:08:13.541286Z] Running read-buildbot-config step. 20:08:13 INFO - Running pre-action listener: _resource_record_pre_action 20:08:13 INFO - Running main action method: read_buildbot_config 20:08:13 INFO - Using buildbot properties: 20:08:13 INFO - { 20:08:13 INFO - "project": "", 20:08:13 INFO - "product": "firefox", 20:08:13 INFO - "script_repo_revision": "production", 20:08:13 INFO - "builddir": "mozilla-aurora_ubuntu64_hw_test-dromaeojs-e10s", 20:08:13 INFO - "repository": "", 20:08:13 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-aurora pgo talos dromaeojs-e10s", 20:08:13 INFO - "buildid": "20160904171919", 20:08:13 INFO - "pgo_build": "True", 20:08:13 INFO - "basedir": "/builds/slave/test", 20:08:13 INFO - "buildnumber": 534, 20:08:13 INFO - "slavename": "talos-linux64-ix-005", 20:08:13 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 20:08:13 INFO - "platform": "ubuntu64_hw", 20:08:13 INFO - "scheduler": "tests-mozilla-aurora-linux64-pgo-talos", 20:08:13 INFO - "branch": "mozilla-aurora", 20:08:13 INFO - "repo_path": "releases/mozilla-aurora", 20:08:13 INFO - "revision": "2231ab09a51c3b21dae6fa1627c1a64cfd43b3b0", 20:08:13 INFO - "stage_platform": "linux64-pgo", 20:08:13 INFO - "builduid": "77297d43100e4038ab68960df429a761", 20:08:13 INFO - "slavebuilddir": "test" 20:08:13 INFO - } 20:08:13 INFO - Found installer url https://queue.taskcluster.net/v1/task/EafjAuEhRbCkfE34LowciQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2. 20:08:13 INFO - Running post-action listener: _resource_record_post_action 20:08:13 INFO - [mozharness: 2016-09-05 03:08:13.543723Z] Finished read-buildbot-config step (success) 20:08:13 INFO - [mozharness: 2016-09-05 03:08:13.543811Z] Running download-and-extract step. 20:08:13 INFO - Running pre-action listener: _resource_record_pre_action 20:08:13 INFO - Running main action method: download_and_extract 20:08:13 INFO - mkdir: /builds/slave/test/build/tests 20:08:13 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')]} 20:08:13 INFO - https://queue.taskcluster.net/v1/task/EafjAuEhRbCkfE34LowciQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 20:08:13 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EafjAuEhRbCkfE34LowciQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 20:08:13 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EafjAuEhRbCkfE34LowciQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 20:08:13 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EafjAuEhRbCkfE34LowciQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 20:08:13 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EafjAuEhRbCkfE34LowciQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json'}, attempt #1 20:08:13 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EafjAuEhRbCkfE34LowciQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 20:08:13 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 20:08:13 INFO - retry: Failed, sleeping 30 seconds before retrying 20:08:43 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EafjAuEhRbCkfE34LowciQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json'}, attempt #2 20:08:43 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EafjAuEhRbCkfE34LowciQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 20:08:43 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 20:08:43 INFO - retry: Failed, sleeping 60 seconds before retrying 20:09:43 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EafjAuEhRbCkfE34LowciQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json'}, attempt #3 20:09:43 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EafjAuEhRbCkfE34LowciQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 20:09:43 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 20:09:43 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EafjAuEhRbCkfE34LowciQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json! 20:09:43 INFO - Caught exception: HTTP Error 404: Not Found 20:09:43 INFO - Caught exception: HTTP Error 404: Not Found 20:09:43 INFO - Caught exception: HTTP Error 404: Not Found 20:09:43 INFO - trying https://queue.taskcluster.net/v1/task/EafjAuEhRbCkfE34LowciQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 20:09:43 INFO - Downloading https://queue.taskcluster.net/v1/task/EafjAuEhRbCkfE34LowciQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 20:09:43 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/EafjAuEhRbCkfE34LowciQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json'}, attempt #1 20:09:44 INFO - Downloaded 1361 bytes. 20:09:44 INFO - Reading from file /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 20:09:44 INFO - Using the following test package requirements: 20:09:44 INFO - {u'common': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'], 20:09:44 INFO - u'cppunittest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 20:09:44 INFO - u'firefox-50.0a2.en-US.linux-x86_64.cppunittest.tests.zip'], 20:09:44 INFO - u'gtest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 20:09:44 INFO - u'firefox-50.0a2.en-US.linux-x86_64.gtest.tests.zip'], 20:09:44 INFO - u'jittest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 20:09:44 INFO - u'jsshell-linux-x86_64.zip'], 20:09:44 INFO - u'mochitest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 20:09:44 INFO - u'firefox-50.0a2.en-US.linux-x86_64.mochitest.tests.zip'], 20:09:44 INFO - u'mozbase': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'], 20:09:44 INFO - u'reftest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 20:09:44 INFO - u'firefox-50.0a2.en-US.linux-x86_64.reftest.tests.zip'], 20:09:44 INFO - u'talos': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 20:09:44 INFO - u'firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip'], 20:09:44 INFO - u'web-platform': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 20:09:44 INFO - u'firefox-50.0a2.en-US.linux-x86_64.web-platform.tests.zip'], 20:09:44 INFO - u'xpcshell': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 20:09:44 INFO - u'firefox-50.0a2.en-US.linux-x86_64.xpcshell.tests.zip']} 20:09:44 INFO - Downloading packages: [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 20:09:44 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')]} 20:09:44 INFO - https://queue.taskcluster.net/v1/task/EafjAuEhRbCkfE34LowciQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 20:09:44 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EafjAuEhRbCkfE34LowciQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 20:09:44 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EafjAuEhRbCkfE34LowciQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 20:09:44 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EafjAuEhRbCkfE34LowciQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 20:09:44 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EafjAuEhRbCkfE34LowciQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 20:09:44 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EafjAuEhRbCkfE34LowciQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 20:09:44 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 20:09:44 INFO - retry: Failed, sleeping 30 seconds before retrying 20:10:14 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EafjAuEhRbCkfE34LowciQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #2 20:10:14 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EafjAuEhRbCkfE34LowciQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 20:10:14 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 20:10:14 INFO - retry: Failed, sleeping 60 seconds before retrying 20:11:14 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EafjAuEhRbCkfE34LowciQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #3 20:11:14 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EafjAuEhRbCkfE34LowciQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 20:11:14 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 20:11:14 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EafjAuEhRbCkfE34LowciQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip! 20:11:14 INFO - Caught exception: HTTP Error 404: Not Found 20:11:14 INFO - Caught exception: HTTP Error 404: Not Found 20:11:14 INFO - Caught exception: HTTP Error 404: Not Found 20:11:14 INFO - trying https://queue.taskcluster.net/v1/task/EafjAuEhRbCkfE34LowciQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 20:11:14 INFO - Downloading https://queue.taskcluster.net/v1/task/EafjAuEhRbCkfE34LowciQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 20:11:14 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/EafjAuEhRbCkfE34LowciQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 20:11:15 INFO - Downloaded 21770704 bytes. 20:11:15 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 20:11:15 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 20:11:16 INFO - Return code: 0 20:11:16 INFO - Downloading packages: [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', u'firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 20:11: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')]} 20:11:16 INFO - https://queue.taskcluster.net/v1/task/EafjAuEhRbCkfE34LowciQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 20:11:16 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EafjAuEhRbCkfE34LowciQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 20:11:16 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EafjAuEhRbCkfE34LowciQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 20:11:16 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EafjAuEhRbCkfE34LowciQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 20:11:16 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EafjAuEhRbCkfE34LowciQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 20:11:16 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EafjAuEhRbCkfE34LowciQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 20:11:16 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 20:11:16 INFO - retry: Failed, sleeping 30 seconds before retrying 20:11:46 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EafjAuEhRbCkfE34LowciQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #2 20:11:46 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EafjAuEhRbCkfE34LowciQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 20:11:46 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 20:11:46 INFO - retry: Failed, sleeping 60 seconds before retrying 20:12:46 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EafjAuEhRbCkfE34LowciQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #3 20:12:46 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EafjAuEhRbCkfE34LowciQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 20:12:46 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 20:12:46 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EafjAuEhRbCkfE34LowciQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip! 20:12:46 INFO - Caught exception: HTTP Error 404: Not Found 20:12:46 INFO - Caught exception: HTTP Error 404: Not Found 20:12:46 INFO - Caught exception: HTTP Error 404: Not Found 20:12:46 INFO - trying https://queue.taskcluster.net/v1/task/EafjAuEhRbCkfE34LowciQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 20:12:46 INFO - Downloading https://queue.taskcluster.net/v1/task/EafjAuEhRbCkfE34LowciQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 20:12:46 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/EafjAuEhRbCkfE34LowciQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 20:12:47 INFO - Downloaded 21770704 bytes. 20:12:47 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 20:12:47 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 20:12:49 INFO - Return code: 0 20:12: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')]} 20:12:49 INFO - https://queue.taskcluster.net/v1/task/EafjAuEhRbCkfE34LowciQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 20:12:49 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EafjAuEhRbCkfE34LowciQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 20:12:49 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EafjAuEhRbCkfE34LowciQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 20:12:49 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EafjAuEhRbCkfE34LowciQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 20:12:49 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EafjAuEhRbCkfE34LowciQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 20:12:49 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EafjAuEhRbCkfE34LowciQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 20:12:49 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 20:12:49 INFO - retry: Failed, sleeping 30 seconds before retrying 20:13:19 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EafjAuEhRbCkfE34LowciQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip'}, attempt #2 20:13:19 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EafjAuEhRbCkfE34LowciQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 20:13:19 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 20:13:19 INFO - retry: Failed, sleeping 60 seconds before retrying 20:14:19 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EafjAuEhRbCkfE34LowciQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip'}, attempt #3 20:14:19 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EafjAuEhRbCkfE34LowciQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 20:14:19 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 20:14:19 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EafjAuEhRbCkfE34LowciQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip! 20:14:19 INFO - Caught exception: HTTP Error 404: Not Found 20:14:19 INFO - Caught exception: HTTP Error 404: Not Found 20:14:19 INFO - Caught exception: HTTP Error 404: Not Found 20:14:19 INFO - trying https://queue.taskcluster.net/v1/task/EafjAuEhRbCkfE34LowciQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 20:14:19 INFO - Downloading https://queue.taskcluster.net/v1/task/EafjAuEhRbCkfE34LowciQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 20:14:19 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/EafjAuEhRbCkfE34LowciQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 20:14:20 INFO - Downloaded 14413740 bytes. 20:14:20 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 20:14:20 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip -d /builds/slave/test/build/tests 20:14:20 INFO - Return code: 0 20:14:20 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')]} 20:14:20 INFO - https://queue.taskcluster.net/v1/task/EafjAuEhRbCkfE34LowciQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 20:14:20 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EafjAuEhRbCkfE34LowciQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 20:14:20 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EafjAuEhRbCkfE34LowciQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 20:14:20 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EafjAuEhRbCkfE34LowciQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 20:14:20 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EafjAuEhRbCkfE34LowciQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2'}, attempt #1 20:14:20 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EafjAuEhRbCkfE34LowciQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 20:14:20 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 20:14:20 INFO - retry: Failed, sleeping 30 seconds before retrying 20:14:50 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EafjAuEhRbCkfE34LowciQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2'}, attempt #2 20:14:50 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EafjAuEhRbCkfE34LowciQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 20:14:50 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 20:14:50 INFO - retry: Failed, sleeping 60 seconds before retrying 20:15:50 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EafjAuEhRbCkfE34LowciQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2'}, attempt #3 20:15:50 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EafjAuEhRbCkfE34LowciQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 20:15:50 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 20:15:50 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EafjAuEhRbCkfE34LowciQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2! 20:15:50 INFO - Caught exception: HTTP Error 404: Not Found 20:15:50 INFO - Caught exception: HTTP Error 404: Not Found 20:15:50 INFO - Caught exception: HTTP Error 404: Not Found 20:15:50 INFO - trying https://queue.taskcluster.net/v1/task/EafjAuEhRbCkfE34LowciQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 20:15:50 INFO - Downloading https://queue.taskcluster.net/v1/task/EafjAuEhRbCkfE34LowciQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 20:15:50 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/EafjAuEhRbCkfE34LowciQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2'}, attempt #1 20:15:52 INFO - Downloaded 63890438 bytes. 20:15:52 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/EafjAuEhRbCkfE34LowciQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 20:15:52 INFO - mkdir: /builds/slave/test/properties 20:15:52 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 20:15:52 INFO - Writing to file /builds/slave/test/properties/build_url 20:15:52 INFO - Contents: 20:15:52 INFO - build_url:https://queue.taskcluster.net/v1/task/EafjAuEhRbCkfE34LowciQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 20:15:52 INFO - Running post-action listener: _resource_record_post_action 20:15:52 INFO - Running post-action listener: set_extra_try_arguments 20:15:52 INFO - [mozharness: 2016-09-05 03:15:52.778622Z] Finished download-and-extract step (success) 20:15:52 INFO - [mozharness: 2016-09-05 03:15:52.778853Z] Running populate-webroot step. 20:15:52 INFO - Running pre-action listener: _resource_record_pre_action 20:15:52 INFO - Running main action method: populate_webroot 20:15:52 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 20:15:52 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 20:15:52 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 20:15:52 INFO - '--tppagecycles', 20:15:52 INFO - '7'], 20:15:52 INFO - 'tests': ['tsvgm']}}, 20:15:52 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 20:15:52 INFO - 'tests': ['tresize', 'tcanvasmark']}, 20:15:52 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 20:15:52 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 20:15:52 INFO - 'tests': ['dromaeo_css', 'kraken']}, 20:15:52 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 20:15:52 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:15:52 INFO - 'talos_options': ['--disable-e10s'], 20:15:52 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 20:15:52 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:15:52 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 20:15:52 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:15:52 INFO - 'talos_options': ['--disable-e10s'], 20:15:52 INFO - 'tests': ['damp', 'tps']}, 20:15:52 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:15:52 INFO - 'tests': ['damp', 'tps']}, 20:15:52 INFO - 'g3': {'talos_options': ['--disable-e10s'], 20:15:52 INFO - 'tests': ['dromaeo_dom']}, 20:15:52 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 20:15:52 INFO - 'g4': {'talos_options': ['--disable-e10s'], 20:15:52 INFO - 'tests': ['basic_compositor_video']}, 20:15:52 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 20:15:52 INFO - 'other': {'talos_options': ['--disable-e10s'], 20:15:52 INFO - 'tests': ['a11yr', 20:15:52 INFO - 'ts_paint', 20:15:52 INFO - 'tpaint', 20:15:52 INFO - 'sessionrestore', 20:15:52 INFO - 'sessionrestore_no_auto_restore', 20:15:52 INFO - 'tabpaint']}, 20:15:52 INFO - 'other-e10s': {'tests': ['a11yr', 20:15:52 INFO - 'ts_paint', 20:15:52 INFO - 'tpaint', 20:15:52 INFO - 'sessionrestore', 20:15:52 INFO - 'sessionrestore_no_auto_restore', 20:15:52 INFO - 'tabpaint']}, 20:15:52 INFO - 'other-e10s_l64': {'tests': ['a11yr', 20:15:52 INFO - 'ts_paint', 20:15:52 INFO - 'tpaint', 20:15:52 INFO - 'sessionrestore', 20:15:52 INFO - 'sessionrestore_no_auto_restore', 20:15:52 INFO - 'tabpaint']}, 20:15:52 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 20:15:52 INFO - 'ts_paint', 20:15:52 INFO - 'tpaint', 20:15:52 INFO - 'sessionrestore', 20:15:52 INFO - 'sessionrestore_no_auto_restore', 20:15:52 INFO - 'tabpaint']}, 20:15:52 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 20:15:52 INFO - 'tests': ['a11yr', 20:15:52 INFO - 'ts_paint', 20:15:52 INFO - 'tpaint', 20:15:52 INFO - 'sessionrestore', 20:15:52 INFO - 'sessionrestore_no_auto_restore', 20:15:52 INFO - 'tabpaint']}, 20:15:52 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 20:15:52 INFO - 'tests': ['a11yr', 20:15:52 INFO - 'ts_paint', 20:15:52 INFO - 'tpaint', 20:15:52 INFO - 'sessionrestore', 20:15:52 INFO - 'sessionrestore_no_auto_restore', 20:15:52 INFO - 'tabpaint']}, 20:15:52 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 20:15:52 INFO - 'tests': ['tsvgx', 20:15:52 INFO - 'tsvgr_opacity', 20:15:52 INFO - 'tart', 20:15:52 INFO - 'tscrollx', 20:15:52 INFO - 'cart']}, 20:15:52 INFO - 'svgr-e10s': {'tests': ['tsvgx', 20:15:52 INFO - 'tsvgr_opacity', 20:15:52 INFO - 'tart', 20:15:52 INFO - 'tscrollx', 20:15:52 INFO - 'cart']}, 20:15:52 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:15:52 INFO - 'talos_options': ['--disable-e10s'], 20:15:52 INFO - 'tests': ['tp5o']}, 20:15:52 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:15:52 INFO - 'tests': ['tp5o']}, 20:15:52 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:15:52 INFO - 'talos_options': ['--disable-e10s', 20:15:52 INFO - '--xperf_path', 20:15:52 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 20:15:52 INFO - 'tests': ['tp5n']}, 20:15:52 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:15:52 INFO - 'talos_options': ['--xperf_path', 20:15:52 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 20:15:52 INFO - 'tests': ['tp5n']}}, 20:15:52 INFO - 'talos.zip': {'path': '', 20:15:52 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 20:15:52 INFO - Running post-action listener: _resource_record_post_action 20:15:52 INFO - [mozharness: 2016-09-05 03:15:52.798816Z] Finished populate-webroot step (success) 20:15:52 INFO - [mozharness: 2016-09-05 03:15:52.798904Z] Running create-virtualenv step. 20:15:52 INFO - Running pre-action listener: _resource_record_pre_action 20:15:52 INFO - Running main action method: create_virtualenv 20:15:52 INFO - Creating virtualenv /builds/slave/test/build/venv 20:15:52 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 20:15:52 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 20:15:52 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 20:15:52 INFO - Using real prefix '/usr' 20:15:52 INFO - New python executable in /builds/slave/test/build/venv/bin/python 20:15:53 INFO - Installing distribute.............................................................................................................................................................................................done. 20:15:55 INFO - Installing pip.................done. 20:15:55 INFO - Return code: 0 20:15:55 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 20:15:55 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')]} 20:15:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:15:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:15:55 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')]} 20:15:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:15:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:15:55 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 0x1b83030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ce68a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1db2400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e41570>, '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 0x1e42470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e42900>, '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': '7fc86f70bae03e018da0b1d8000001c4-1473033471.633764-940869890', '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 20:15:55 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 20:15:55 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 20:15:55 INFO - Using env: {'DISPLAY': ':0', 20:15:55 INFO - 'HOME': '/home/cltbld', 20:15:55 INFO - 'LANG': 'en_US.UTF-8', 20:15:55 INFO - 'LANGUAGE': 'en_US:en', 20:15:55 INFO - 'LOGNAME': 'cltbld', 20:15:55 INFO - 'MAIL': '/var/mail/cltbld', 20:15:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:15:55 INFO - 'MOZ_NO_REMOTE': '1', 20:15:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:15:55 INFO - 'NO_EM_RESTART': '1', 20:15:55 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:15:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:15:55 INFO - 'PWD': '/builds/slave/test', 20:15:55 INFO - 'SHELL': '/bin/bash', 20:15:55 INFO - 'SHLVL': '1', 20:15:55 INFO - 'TERM': 'linux', 20:15:55 INFO - 'TMOUT': '86400', 20:15:55 INFO - 'USER': 'cltbld', 20:15:55 INFO - 'XDG_SESSION_COOKIE': '7fc86f70bae03e018da0b1d8000001c4-1473033471.633764-940869890', 20:15:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:15:55 INFO - '_': '/tools/buildbot/bin/python'} 20:15:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:15:55 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 20:15:55 INFO - Cleaning up... 20:15:55 INFO - Return code: 0 20:15:55 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 20:15:55 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')]} 20:15:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:15:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:15:55 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')]} 20:15:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:15:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:15:55 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 0x1b83030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ce68a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1db2400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e41570>, '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 0x1e42470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e42900>, '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': '7fc86f70bae03e018da0b1d8000001c4-1473033471.633764-940869890', '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 20:15:55 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 20:15:55 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 20:15:55 INFO - Using env: {'DISPLAY': ':0', 20:15:55 INFO - 'HOME': '/home/cltbld', 20:15:55 INFO - 'LANG': 'en_US.UTF-8', 20:15:55 INFO - 'LANGUAGE': 'en_US:en', 20:15:55 INFO - 'LOGNAME': 'cltbld', 20:15:55 INFO - 'MAIL': '/var/mail/cltbld', 20:15:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:15:55 INFO - 'MOZ_NO_REMOTE': '1', 20:15:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:15:55 INFO - 'NO_EM_RESTART': '1', 20:15:55 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:15:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:15:55 INFO - 'PWD': '/builds/slave/test', 20:15:55 INFO - 'SHELL': '/bin/bash', 20:15:55 INFO - 'SHLVL': '1', 20:15:55 INFO - 'TERM': 'linux', 20:15:55 INFO - 'TMOUT': '86400', 20:15:55 INFO - 'USER': 'cltbld', 20:15:55 INFO - 'XDG_SESSION_COOKIE': '7fc86f70bae03e018da0b1d8000001c4-1473033471.633764-940869890', 20:15:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:15:55 INFO - '_': '/tools/buildbot/bin/python'} 20:15:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:15:55 INFO - Downloading/unpacking psutil>=3.1.1 20:15:55 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 20:15:55 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 20:15:55 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 20:15:55 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 20:15:55 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 20:15:55 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 20:15:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 20:15:57 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 20:15:57 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 20:15:57 INFO - Installing collected packages: psutil 20:15:57 INFO - Running setup.py install for psutil 20:15:57 INFO - building 'psutil._psutil_linux' extension 20:15:57 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 20:15:58 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 20:15:58 INFO - building 'psutil._psutil_posix' extension 20:15:58 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 20:15:58 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 20:15:58 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 20:15:58 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 20:15:58 INFO - Successfully installed psutil 20:15:58 INFO - Cleaning up... 20:15:58 INFO - Return code: 0 20:15:58 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 20:15:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:15:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:15:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:15:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:15:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:15:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:15:58 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b83030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ce68a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1db2400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e41570>, '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 0x1e42470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e42900>, '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': '7fc86f70bae03e018da0b1d8000001c4-1473033471.633764-940869890', '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 20:15:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 20:15:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 20:15:58 INFO - Using env: {'DISPLAY': ':0', 20:15:58 INFO - 'HOME': '/home/cltbld', 20:15:58 INFO - 'LANG': 'en_US.UTF-8', 20:15:58 INFO - 'LANGUAGE': 'en_US:en', 20:15:58 INFO - 'LOGNAME': 'cltbld', 20:15:58 INFO - 'MAIL': '/var/mail/cltbld', 20:15:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:15:58 INFO - 'MOZ_NO_REMOTE': '1', 20:15:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:15:58 INFO - 'NO_EM_RESTART': '1', 20:15:58 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:15:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:15:58 INFO - 'PWD': '/builds/slave/test', 20:15:58 INFO - 'SHELL': '/bin/bash', 20:15:58 INFO - 'SHLVL': '1', 20:15:58 INFO - 'TERM': 'linux', 20:15:58 INFO - 'TMOUT': '86400', 20:15:58 INFO - 'USER': 'cltbld', 20:15:58 INFO - 'XDG_SESSION_COOKIE': '7fc86f70bae03e018da0b1d8000001c4-1473033471.633764-940869890', 20:15:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:15:58 INFO - '_': '/tools/buildbot/bin/python'} 20:15:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:15:58 INFO - Downloading/unpacking mozsystemmonitor==0.3 20:15:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:15:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:15:58 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:15:58 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:15:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:15:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:16:00 INFO - Downloading mozsystemmonitor-0.3.tar.gz 20:16:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 20:16:00 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 20:16:00 INFO - Installing collected packages: mozsystemmonitor 20:16:00 INFO - Running setup.py install for mozsystemmonitor 20:16:00 INFO - Successfully installed mozsystemmonitor 20:16:00 INFO - Cleaning up... 20:16:00 INFO - Return code: 0 20:16:00 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 20:16:00 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')]} 20:16:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:16:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:16:00 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')]} 20:16:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:16:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:16:00 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 0x1b83030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ce68a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1db2400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e41570>, '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 0x1e42470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e42900>, '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': '7fc86f70bae03e018da0b1d8000001c4-1473033471.633764-940869890', '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 20:16:00 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 20:16:00 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 20:16:00 INFO - Using env: {'DISPLAY': ':0', 20:16:00 INFO - 'HOME': '/home/cltbld', 20:16:00 INFO - 'LANG': 'en_US.UTF-8', 20:16:00 INFO - 'LANGUAGE': 'en_US:en', 20:16:00 INFO - 'LOGNAME': 'cltbld', 20:16:00 INFO - 'MAIL': '/var/mail/cltbld', 20:16:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:16:00 INFO - 'MOZ_NO_REMOTE': '1', 20:16:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:16:00 INFO - 'NO_EM_RESTART': '1', 20:16:00 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:16:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:16:00 INFO - 'PWD': '/builds/slave/test', 20:16:00 INFO - 'SHELL': '/bin/bash', 20:16:00 INFO - 'SHLVL': '1', 20:16:00 INFO - 'TERM': 'linux', 20:16:00 INFO - 'TMOUT': '86400', 20:16:00 INFO - 'USER': 'cltbld', 20:16:00 INFO - 'XDG_SESSION_COOKIE': '7fc86f70bae03e018da0b1d8000001c4-1473033471.633764-940869890', 20:16:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:16:00 INFO - '_': '/tools/buildbot/bin/python'} 20:16:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:16:00 INFO - Downloading/unpacking jsonschema==2.5.1 20:16:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:16:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:16:00 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:16:00 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:16:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:16:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:16:02 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 20:16:02 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 20:16:02 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 20:16:02 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 20:16:02 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 20:16:02 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 20:16:02 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 20:16:02 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 20:16:02 INFO - Downloading functools32-3.2.3-2.tar.gz 20:16:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 20:16:02 INFO - warning: no files found matching '*.txt' 20:16:02 INFO - no previously-included directories found matching 'build' 20:16:02 INFO - no previously-included directories found matching 'dist' 20:16:02 INFO - no previously-included directories found matching '.git*' 20:16:02 INFO - Installing collected packages: jsonschema, functools32 20:16:03 INFO - Running setup.py install for functools32 20:16:03 INFO - warning: no files found matching '*.txt' 20:16:03 INFO - no previously-included directories found matching 'build' 20:16:03 INFO - no previously-included directories found matching 'dist' 20:16:03 INFO - no previously-included directories found matching '.git*' 20:16:03 INFO - Successfully installed jsonschema functools32 20:16:03 INFO - Cleaning up... 20:16:03 INFO - Return code: 0 20:16:03 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 20:16:03 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')]} 20:16:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:16:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:16:03 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')]} 20:16:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:16:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:16:03 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 0x1b83030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ce68a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1db2400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e41570>, '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 0x1e42470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e42900>, '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': '7fc86f70bae03e018da0b1d8000001c4-1473033471.633764-940869890', '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 20:16:03 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 20:16:03 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 20:16:03 INFO - Using env: {'DISPLAY': ':0', 20:16:03 INFO - 'HOME': '/home/cltbld', 20:16:03 INFO - 'LANG': 'en_US.UTF-8', 20:16:03 INFO - 'LANGUAGE': 'en_US:en', 20:16:03 INFO - 'LOGNAME': 'cltbld', 20:16:03 INFO - 'MAIL': '/var/mail/cltbld', 20:16:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:16:03 INFO - 'MOZ_NO_REMOTE': '1', 20:16:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:16:03 INFO - 'NO_EM_RESTART': '1', 20:16:03 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:16:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:16:03 INFO - 'PWD': '/builds/slave/test', 20:16:03 INFO - 'SHELL': '/bin/bash', 20:16:03 INFO - 'SHLVL': '1', 20:16:03 INFO - 'TERM': 'linux', 20:16:03 INFO - 'TMOUT': '86400', 20:16:03 INFO - 'USER': 'cltbld', 20:16:03 INFO - 'XDG_SESSION_COOKIE': '7fc86f70bae03e018da0b1d8000001c4-1473033471.633764-940869890', 20:16:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:16:03 INFO - '_': '/tools/buildbot/bin/python'} 20:16:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:16:03 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 20:16:03 INFO - Cleaning up... 20:16:03 INFO - Return code: 0 20:16:03 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 20:16:03 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')]} 20:16:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:16:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:16:03 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')]} 20:16:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:16:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:16:03 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 0x1b83030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ce68a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1db2400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e41570>, '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 0x1e42470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e42900>, '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': '7fc86f70bae03e018da0b1d8000001c4-1473033471.633764-940869890', '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 20:16:03 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 20:16:03 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 20:16:03 INFO - Using env: {'DISPLAY': ':0', 20:16:03 INFO - 'HOME': '/home/cltbld', 20:16:03 INFO - 'LANG': 'en_US.UTF-8', 20:16:03 INFO - 'LANGUAGE': 'en_US:en', 20:16:03 INFO - 'LOGNAME': 'cltbld', 20:16:03 INFO - 'MAIL': '/var/mail/cltbld', 20:16:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:16:03 INFO - 'MOZ_NO_REMOTE': '1', 20:16:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:16:03 INFO - 'NO_EM_RESTART': '1', 20:16:03 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:16:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:16:03 INFO - 'PWD': '/builds/slave/test', 20:16:03 INFO - 'SHELL': '/bin/bash', 20:16:03 INFO - 'SHLVL': '1', 20:16:03 INFO - 'TERM': 'linux', 20:16:03 INFO - 'TMOUT': '86400', 20:16:03 INFO - 'USER': 'cltbld', 20:16:03 INFO - 'XDG_SESSION_COOKIE': '7fc86f70bae03e018da0b1d8000001c4-1473033471.633764-940869890', 20:16:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:16:03 INFO - '_': '/tools/buildbot/bin/python'} 20:16:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:16:03 INFO - Downloading/unpacking blobuploader==1.2.4 20:16:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:16:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:16:03 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:16:03 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:16:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:16:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:16:05 INFO - Downloading blobuploader-1.2.4.tar.gz 20:16:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 20:16:05 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 20:16:05 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 20:16:05 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 20:16:05 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 20:16:05 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 20:16:05 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 20:16:05 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 20:16:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 20:16:05 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 20:16:05 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 20:16:05 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 20:16:05 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 20:16:05 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 20:16:05 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 20:16:05 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 20:16:06 INFO - Downloading docopt-0.6.1.tar.gz 20:16:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 20:16:06 INFO - Installing collected packages: blobuploader, requests, docopt 20:16:06 INFO - Running setup.py install for blobuploader 20:16:06 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 20:16:06 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 20:16:06 INFO - Running setup.py install for requests 20:16:06 INFO - Running setup.py install for docopt 20:16:06 INFO - Successfully installed blobuploader requests docopt 20:16:06 INFO - Cleaning up... 20:16:06 INFO - Return code: 0 20:16:06 INFO - Installing None into virtualenv /builds/slave/test/build/venv 20:16:06 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')]} 20:16:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:16:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:16:06 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')]} 20:16:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:16:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:16:06 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 0x1b83030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ce68a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1db2400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e41570>, '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 0x1e42470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e42900>, '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': '7fc86f70bae03e018da0b1d8000001c4-1473033471.633764-940869890', '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 20:16:06 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 20:16:06 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 20:16:06 INFO - Using env: {'DISPLAY': ':0', 20:16:06 INFO - 'HOME': '/home/cltbld', 20:16:06 INFO - 'LANG': 'en_US.UTF-8', 20:16:06 INFO - 'LANGUAGE': 'en_US:en', 20:16:06 INFO - 'LOGNAME': 'cltbld', 20:16:06 INFO - 'MAIL': '/var/mail/cltbld', 20:16:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:16:06 INFO - 'MOZ_NO_REMOTE': '1', 20:16:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:16:06 INFO - 'NO_EM_RESTART': '1', 20:16:06 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:16:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:16:06 INFO - 'PWD': '/builds/slave/test', 20:16:06 INFO - 'SHELL': '/bin/bash', 20:16:06 INFO - 'SHLVL': '1', 20:16:06 INFO - 'TERM': 'linux', 20:16:06 INFO - 'TMOUT': '86400', 20:16:06 INFO - 'USER': 'cltbld', 20:16:06 INFO - 'XDG_SESSION_COOKIE': '7fc86f70bae03e018da0b1d8000001c4-1473033471.633764-940869890', 20:16:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:16:06 INFO - '_': '/tools/buildbot/bin/python'} 20:16:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:16:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 20:16:07 INFO - Running setup.py (path:/tmp/pip-9uCZo_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 20:16:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 20:16:07 INFO - Running setup.py (path:/tmp/pip-oeffe4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 20:16:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 20:16:07 INFO - Running setup.py (path:/tmp/pip-WMy9LV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 20:16:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 20:16:07 INFO - Running setup.py (path:/tmp/pip-cs9rUf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 20:16:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 20:16:07 INFO - Running setup.py (path:/tmp/pip-5HxGUt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 20:16:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 20:16:07 INFO - Running setup.py (path:/tmp/pip-8rH289-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 20:16:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 20:16:07 INFO - Running setup.py (path:/tmp/pip-9xNTc6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 20:16:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 20:16:07 INFO - Running setup.py (path:/tmp/pip-1jovAv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 20:16:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 20:16:07 INFO - Running setup.py (path:/tmp/pip-aYc6yj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 20:16:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 20:16:07 INFO - Running setup.py (path:/tmp/pip-MvDTOs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 20:16:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 20:16:07 INFO - Running setup.py (path:/tmp/pip-T3El1G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 20:16:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 20:16:07 INFO - Running setup.py (path:/tmp/pip-iGLxFA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 20:16:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 20:16:07 INFO - Running setup.py (path:/tmp/pip-YqRUTJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 20:16:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 20:16:08 INFO - Running setup.py (path:/tmp/pip-se93bN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 20:16:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 20:16:08 INFO - Running setup.py (path:/tmp/pip-APifrU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 20:16:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 20:16:08 INFO - Running setup.py (path:/tmp/pip-_Cf33K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 20:16:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 20:16:08 INFO - Running setup.py (path:/tmp/pip-40JJrx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 20:16:08 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 20:16:08 INFO - Running setup.py install for manifestparser 20:16:08 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 20:16:08 INFO - Running setup.py install for mozcrash 20:16:08 INFO - Running setup.py install for mozdebug 20:16:08 INFO - Running setup.py install for mozdevice 20:16:08 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 20:16:08 INFO - Installing dm script to /builds/slave/test/build/venv/bin 20:16:08 INFO - Running setup.py install for mozfile 20:16:08 INFO - Running setup.py install for mozhttpd 20:16:08 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 20:16:08 INFO - Running setup.py install for mozinfo 20:16:09 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 20:16:09 INFO - Running setup.py install for mozInstall 20:16:09 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 20:16:09 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 20:16:09 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 20:16:09 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 20:16:09 INFO - Running setup.py install for mozleak 20:16:09 INFO - Running setup.py install for mozlog 20:16:09 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 20:16:09 INFO - Running setup.py install for moznetwork 20:16:09 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 20:16:09 INFO - Running setup.py install for mozprocess 20:16:09 INFO - Running setup.py install for mozprofile 20:16:09 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 20:16:09 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 20:16:09 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 20:16:09 INFO - Running setup.py install for mozrunner 20:16:09 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 20:16:09 INFO - Running setup.py install for mozscreenshot 20:16:09 INFO - Running setup.py install for moztest 20:16:10 INFO - Running setup.py install for mozversion 20:16:10 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 20:16:10 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 20:16:10 INFO - Cleaning up... 20:16:10 INFO - Return code: 0 20:16:10 INFO - Installing None into virtualenv /builds/slave/test/build/venv 20:16:10 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')]} 20:16:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:16:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:16:10 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')]} 20:16:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:16:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:16:10 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 0x1b83030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ce68a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1db2400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e41570>, '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 0x1e42470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e42900>, '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': '7fc86f70bae03e018da0b1d8000001c4-1473033471.633764-940869890', '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 20:16:10 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 20:16:10 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 20:16:10 INFO - Using env: {'DISPLAY': ':0', 20:16:10 INFO - 'HOME': '/home/cltbld', 20:16:10 INFO - 'LANG': 'en_US.UTF-8', 20:16:10 INFO - 'LANGUAGE': 'en_US:en', 20:16:10 INFO - 'LOGNAME': 'cltbld', 20:16:10 INFO - 'MAIL': '/var/mail/cltbld', 20:16:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:16:10 INFO - 'MOZ_NO_REMOTE': '1', 20:16:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:16:10 INFO - 'NO_EM_RESTART': '1', 20:16:10 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:16:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:16:10 INFO - 'PWD': '/builds/slave/test', 20:16:10 INFO - 'SHELL': '/bin/bash', 20:16:10 INFO - 'SHLVL': '1', 20:16:10 INFO - 'TERM': 'linux', 20:16:10 INFO - 'TMOUT': '86400', 20:16:10 INFO - 'USER': 'cltbld', 20:16:10 INFO - 'XDG_SESSION_COOKIE': '7fc86f70bae03e018da0b1d8000001c4-1473033471.633764-940869890', 20:16:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:16:10 INFO - '_': '/tools/buildbot/bin/python'} 20:16:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:16:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 20:16:10 INFO - Running setup.py (path:/tmp/pip-2pfsLt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 20:16:10 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)) 20:16:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 20:16:10 INFO - Running setup.py (path:/tmp/pip-EW4CVP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 20:16:10 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)) 20:16:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 20:16:10 INFO - Running setup.py (path:/tmp/pip-G1PV0A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 20:16:10 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)) 20:16:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 20:16:10 INFO - Running setup.py (path:/tmp/pip-nqLG1H-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 20:16:10 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)) 20:16:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 20:16:10 INFO - Running setup.py (path:/tmp/pip-8gciC9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 20:16:10 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)) 20:16:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 20:16:10 INFO - Running setup.py (path:/tmp/pip-qteTjX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 20:16:10 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)) 20:16:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 20:16:10 INFO - Running setup.py (path:/tmp/pip-yD25Pa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 20:16:10 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)) 20:16:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 20:16:10 INFO - Running setup.py (path:/tmp/pip-Tkirr9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 20:16:10 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)) 20:16:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 20:16:10 INFO - Running setup.py (path:/tmp/pip-ttLdBi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 20:16:11 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)) 20:16:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 20:16:11 INFO - Running setup.py (path:/tmp/pip-jSt1nJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 20:16:11 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)) 20:16:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 20:16:11 INFO - Running setup.py (path:/tmp/pip-03O0X2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 20:16:11 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)) 20:16:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 20:16:11 INFO - Running setup.py (path:/tmp/pip-5LjYBB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 20:16:11 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)) 20:16:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 20:16:11 INFO - Running setup.py (path:/tmp/pip-pb3F1E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 20:16:11 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)) 20:16:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 20:16:11 INFO - Running setup.py (path:/tmp/pip-NdyVtv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 20:16:11 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)) 20:16:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 20:16:11 INFO - Running setup.py (path:/tmp/pip-rci7kc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 20:16:11 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)) 20:16:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 20:16:11 INFO - Running setup.py (path:/tmp/pip-3mIof5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 20:16:11 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)) 20:16:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 20:16:11 INFO - Running setup.py (path:/tmp/pip-3txb0Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 20:16:11 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)) 20:16:11 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)) 20:16:11 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)) 20:16:11 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)) 20:16:11 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)) 20:16:11 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)) 20:16:11 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 20:16:11 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 20:16:11 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 20:16:11 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 20:16:11 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 20:16:11 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 20:16:11 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 20:16:13 INFO - Downloading blessings-1.6.tar.gz 20:16:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 20:16:13 INFO - Installing collected packages: blessings 20:16:13 INFO - Running setup.py install for blessings 20:16:13 INFO - Successfully installed blessings 20:16:13 INFO - Cleaning up... 20:16:13 INFO - Return code: 0 20:16:13 INFO - Done creating virtualenv /builds/slave/test/build/venv. 20:16:13 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 20:16:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 20:16:13 INFO - Reading from file tmpfile_stdout 20:16:13 INFO - Current package versions: 20:16:13 INFO - argparse == 1.2.1 20:16:13 INFO - blessings == 1.6 20:16:13 INFO - blobuploader == 1.2.4 20:16:13 INFO - docopt == 0.6.1 20:16:13 INFO - functools32 == 3.2.3-2 20:16:13 INFO - jsonschema == 2.5.1 20:16:13 INFO - manifestparser == 1.1 20:16:13 INFO - mozInstall == 1.12 20:16:13 INFO - mozcrash == 0.17 20:16:13 INFO - mozdebug == 0.1 20:16:13 INFO - mozdevice == 0.48 20:16:13 INFO - mozfile == 1.2 20:16:13 INFO - mozhttpd == 0.7 20:16:13 INFO - mozinfo == 0.9 20:16:13 INFO - mozleak == 0.1 20:16:13 INFO - mozlog == 3.3 20:16:13 INFO - moznetwork == 0.27 20:16:13 INFO - mozprocess == 0.23 20:16:13 INFO - mozprofile == 0.28 20:16:13 INFO - mozrunner == 6.12 20:16:13 INFO - mozscreenshot == 0.1 20:16:13 INFO - mozsystemmonitor == 0.3 20:16:13 INFO - moztest == 0.7 20:16:13 INFO - mozversion == 1.4 20:16:13 INFO - psutil == 3.1.1 20:16:13 INFO - requests == 1.2.3 20:16:13 INFO - wsgiref == 0.1.2 20:16:13 INFO - Installing None into virtualenv /builds/slave/test/build/venv 20:16:13 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')]} 20:16:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:16:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:16:13 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')]} 20:16:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:16:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:16:13 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 0x1b83030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ce68a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1db2400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e41570>, '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 0x1e42470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e42900>, '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': '7fc86f70bae03e018da0b1d8000001c4-1473033471.633764-940869890', '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 20:16:13 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 20:16:13 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 20:16:13 INFO - Using env: {'DISPLAY': ':0', 20:16:13 INFO - 'HOME': '/home/cltbld', 20:16:13 INFO - 'LANG': 'en_US.UTF-8', 20:16:13 INFO - 'LANGUAGE': 'en_US:en', 20:16:13 INFO - 'LOGNAME': 'cltbld', 20:16:13 INFO - 'MAIL': '/var/mail/cltbld', 20:16:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:16:13 INFO - 'MOZ_NO_REMOTE': '1', 20:16:13 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:16:13 INFO - 'NO_EM_RESTART': '1', 20:16:13 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:16:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:16:13 INFO - 'PWD': '/builds/slave/test', 20:16:13 INFO - 'SHELL': '/bin/bash', 20:16:13 INFO - 'SHLVL': '1', 20:16:13 INFO - 'TERM': 'linux', 20:16:13 INFO - 'TMOUT': '86400', 20:16:13 INFO - 'USER': 'cltbld', 20:16:13 INFO - 'XDG_SESSION_COOKIE': '7fc86f70bae03e018da0b1d8000001c4-1473033471.633764-940869890', 20:16:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:16:13 INFO - '_': '/tools/buildbot/bin/python'} 20:16:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:16:14 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)) 20:16:14 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)) 20:16:14 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)) 20:16:14 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)) 20:16:14 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)) 20:16:14 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)) 20:16:14 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)) 20:16:14 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)) 20:16:14 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)) 20:16:14 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)) 20:16:14 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)) 20:16:14 INFO - Cleaning up... 20:16:14 INFO - Return code: 0 20:16:14 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 20:16:14 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')]} 20:16:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:16:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:16:14 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')]} 20:16:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:16:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:16:14 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 0x1b83030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ce68a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1db2400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e41570>, '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 0x1e42470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e42900>, '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': '7fc86f70bae03e018da0b1d8000001c4-1473033471.633764-940869890', '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 20:16:14 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 20:16:14 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 20:16:14 INFO - Using env: {'DISPLAY': ':0', 20:16:14 INFO - 'HOME': '/home/cltbld', 20:16:14 INFO - 'LANG': 'en_US.UTF-8', 20:16:14 INFO - 'LANGUAGE': 'en_US:en', 20:16:14 INFO - 'LOGNAME': 'cltbld', 20:16:14 INFO - 'MAIL': '/var/mail/cltbld', 20:16:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:16:14 INFO - 'MOZ_NO_REMOTE': '1', 20:16:14 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:16:14 INFO - 'NO_EM_RESTART': '1', 20:16:14 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:16:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:16:14 INFO - 'PWD': '/builds/slave/test', 20:16:14 INFO - 'SHELL': '/bin/bash', 20:16:14 INFO - 'SHLVL': '1', 20:16:14 INFO - 'TERM': 'linux', 20:16:14 INFO - 'TMOUT': '86400', 20:16:14 INFO - 'USER': 'cltbld', 20:16:14 INFO - 'XDG_SESSION_COOKIE': '7fc86f70bae03e018da0b1d8000001c4-1473033471.633764-940869890', 20:16:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:16:14 INFO - '_': '/tools/buildbot/bin/python'} 20:16:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:16:14 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 20:16:14 INFO - Cleaning up... 20:16:14 INFO - Return code: 0 20:16:14 INFO - Running post-action listener: _resource_record_post_action 20:16:14 INFO - Running post-action listener: _start_resource_monitoring 20:16:14 INFO - Starting resource monitoring. 20:16:14 INFO - [mozharness: 2016-09-05 03:16:14.432236Z] Finished create-virtualenv step (success) 20:16:14 INFO - [mozharness: 2016-09-05 03:16:14.432699Z] Running install step. 20:16:14 INFO - Running pre-action listener: _resource_record_pre_action 20:16:14 INFO - Running main action method: install 20:16:14 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 20:16:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 20:16:14 INFO - Reading from file tmpfile_stdout 20:16:14 INFO - Detecting whether we're running mozinstall >=1.0... 20:16:14 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 20:16:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 20:16:14 INFO - Reading from file tmpfile_stdout 20:16:14 INFO - Output received: 20:16:14 INFO - Usage: mozinstall [options] installer 20:16:14 INFO - Options: 20:16:14 INFO - -h, --help show this help message and exit 20:16:14 INFO - -d DEST, --destination=DEST 20:16:14 INFO - Directory to install application into. [default: 20:16:14 INFO - "/builds/slave/test"] 20:16:14 INFO - --app=APP Application being installed. [default: firefox] 20:16:14 INFO - mkdir: /builds/slave/test/build/application 20:16:14 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 20:16:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 20:16:31 INFO - Reading from file tmpfile_stdout 20:16:31 INFO - Output received: 20:16:31 INFO - /builds/slave/test/build/application/firefox/firefox 20:16:31 INFO - Running post-action listener: _resource_record_post_action 20:16:31 INFO - [mozharness: 2016-09-05 03:16:31.277983Z] Finished install step (success) 20:16:31 INFO - [mozharness: 2016-09-05 03:16:31.278261Z] Running run-tests step. 20:16:31 INFO - Running pre-action listener: _resource_record_pre_action 20:16:31 INFO - Running main action method: run_tests 20:16:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 20:16:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 20:16:31 INFO - Python 2.7.3 20:16:31 INFO - Return code: 0 20:16:31 INFO - grabbing minidump binary from tooltool 20:16: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')]} 20:16:31 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 0x1e41570>, '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 0x1e42470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e42900>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 20:16:31 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 20:16:31 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 20:16:31 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 20:16:31 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 20:16:31 INFO - Return code: 0 20:16:31 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 20:16:31 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 20:16:31 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 20:16:31 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 20:16:31 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 20:16:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/EafjAuEhRbCkfE34LowciQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-005', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 20:16:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/EafjAuEhRbCkfE34LowciQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-005 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 20:16:31 INFO - Using env: {'DISPLAY': ':0', 20:16:31 INFO - 'HOME': '/home/cltbld', 20:16:31 INFO - 'LANG': 'en_US.UTF-8', 20:16:31 INFO - 'LANGUAGE': 'en_US:en', 20:16:31 INFO - 'LOGNAME': 'cltbld', 20:16:31 INFO - 'MAIL': '/var/mail/cltbld', 20:16:31 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 20:16:31 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 20:16:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:16:31 INFO - 'MOZ_NO_REMOTE': '1', 20:16:31 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 20:16:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:16:31 INFO - 'NO_EM_RESTART': '1', 20:16:31 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:16:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:16:31 INFO - 'PWD': '/builds/slave/test', 20:16:31 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 20:16:31 INFO - 'SHELL': '/bin/bash', 20:16:31 INFO - 'SHLVL': '1', 20:16:31 INFO - 'TERM': 'linux', 20:16:31 INFO - 'TMOUT': '86400', 20:16:31 INFO - 'USER': 'cltbld', 20:16:31 INFO - 'XDG_SESSION_COOKIE': '7fc86f70bae03e018da0b1d8000001c4-1473033471.633764-940869890', 20:16:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:16:31 INFO - '_': '/tools/buildbot/bin/python'} 20:16:31 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/EafjAuEhRbCkfE34LowciQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-005', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 20:16:31 INFO - mozversion application_buildid: 20160904171919 20:16:31 INFO - mozversion application_changeset: 2231ab09a51c3b21dae6fa1627c1a64cfd43b3b0 20:16:31 INFO - mozversion application_display_name: FirefoxDeveloperEdition 20:16:31 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 20:16:31 INFO - mozversion application_name: Firefox 20:16:31 INFO - mozversion application_remotingname: firefox-dev 20:16:31 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 20:16:31 INFO - mozversion application_vendor: Mozilla 20:16:31 INFO - mozversion application_version: 50.0a2 20:16:31 INFO - mozversion platform_buildid: 20160904171919 20:16:31 INFO - mozversion platform_changeset: 2231ab09a51c3b21dae6fa1627c1a64cfd43b3b0 20:16:31 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 20:16:31 INFO - mozversion platform_version: 50.0a2 20:16:31 INFO - using testdate: 1473045391 20:16:31 INFO - actual date: 1473045391 20:16:31 INFO - starting webserver on 'localhost:47429' 20:16:31 INFO - SUITE-START | Running 2 tests 20:16:31 INFO - TEST-START | dromaeo_css 20:16:31 INFO - operating with platform_type : linux_ 20:16:31 INFO - Initialising browser for dromaeo_css test... 20:16:31 INFO - TEST-INFO | started process 1298 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp4egC8g/profile http://localhost:47429/getInfo.html) 20:16:35 INFO - PROCESS | 1298 | __metrics Screen width/height:1600/1200 20:16:35 INFO - PROCESS | 1298 | colorDepth:24 20:16:35 INFO - PROCESS | 1298 | Browser inner width/height: 1024/697 20:16:35 INFO - PROCESS | 1298 | __metrics 20:16:36 ERROR - PROCESS | 1298 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2967: TypeError: this.tabs is undefined 20:16:37 INFO - TEST-INFO | 1298: exit 0 20:16:37 INFO - Browser initialized. 20:16:37 INFO - Running cycle 1/1 for dromaeo_css test... 20:16:37 INFO - TEST-INFO | started process 1426 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp4egC8g/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 20:16:48 INFO - PROCESS | 1426 | RSS: Main: 152612864 20:16:48 INFO - PROCESS | 1426 | 20:17:38 INFO - PROCESS | 1426 | 1473045458567 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] 20:19:46 INFO - PROCESS | 1426 | Cycle 1(1): loaded http://localhost:47429/tests/dromaeo/cssquery-dojo.html (next: http://localhost:47429/tests/dromaeo/cssquery-ext.html) 20:19:48 INFO - PROCESS | 1426 | RSS: Main: 158703616 20:19:48 INFO - PROCESS | 1426 | 20:22:50 INFO - PROCESS | 1426 | Cycle 1(1): loaded http://localhost:47429/tests/dromaeo/cssquery-ext.html (next: http://localhost:47429/tests/dromaeo/cssquery-jquery.html) 20:22:50 INFO - PROCESS | 1426 | RSS: Main: 154644480 20:22:50 INFO - PROCESS | 1426 | 20:25:52 INFO - PROCESS | 1426 | Cycle 1(1): loaded http://localhost:47429/tests/dromaeo/cssquery-jquery.html (next: http://localhost:47429/tests/dromaeo/cssquery-mootools.html) 20:25:53 INFO - PROCESS | 1426 | RSS: Main: 154615808 20:25:53 INFO - PROCESS | 1426 | 20:28:55 INFO - PROCESS | 1426 | Cycle 1(1): loaded http://localhost:47429/tests/dromaeo/cssquery-mootools.html (next: http://localhost:47429/tests/dromaeo/cssquery-prototype.html) 20:28:55 INFO - PROCESS | 1426 | RSS: Main: 153387008 20:28:55 INFO - PROCESS | 1426 | 20:31:58 INFO - PROCESS | 1426 | Cycle 1(1): loaded http://localhost:47429/tests/dromaeo/cssquery-prototype.html (next: http://localhost:47429/tests/dromaeo/cssquery-yui.html) 20:31:58 INFO - PROCESS | 1426 | RSS: Main: 155635712 20:31:58 INFO - PROCESS | 1426 | 20:35:01 INFO - PROCESS | 1426 | Cycle 1(1): loaded http://localhost:47429/tests/dromaeo/cssquery-yui.html (next: http://localhost:47429/tests/dromaeo/cssquery-dojo.html) 20:35:01 INFO - PROCESS | 1426 | RSS: Main: 156004352 20:35:01 INFO - PROCESS | 1426 | 20:35:01 INFO - PROCESS | 1426 | __start_tp_report 20:35:01 INFO - PROCESS | 1426 | _x_x_mozilla_page_load 20:35:01 INFO - PROCESS | 1426 | _x_x_mozilla_page_load_details 20:35:01 INFO - PROCESS | 1426 | |i|pagename|runs| 20:35:01 INFO - PROCESS | 1426 | |0;dojo.html;3311.5694959655243;3341.7326613870887;3344.1305260632244;3357.0264623259254;3375.628680845108;5786.045302525084;5809.390014048522;5827.737751801168;5856.970715146429;5871.089981052933;5773.3937936516695;5784.3058832940005;5788.884222315552;5816.563757718167;5831.271091113611;5292.259083728271;5302.787888484475;5352.090144675414;5358.946410535903;5375.274337964363;5136.717480538568;5161.7161056141895;5175.974120129413;5319.627626066177;5346.6524675896135;5274.736263186845;5307.071262529062;5312.760925758331;5411;5465.316835395575;5390.649607775502;5392.811251606175;5396.541293989996;5401.43284955078;5419.83740487786;5073.239014147871;5111.795528178868;5217.608679349064;5289.656172348824;5334.706591137486;5126.487351264844;5652.378238393773;5693.572982026323;5720.227769251181;5793.884122317572;6173.59871608344;6224.813255602339;6226.62640241587;6228.065790131472;6233.812985610393;6231.688415579249;6293.811185664437;6300.27546832111;6301.212348456443;6313.053042043684;5598.692071936042;5755.510781583571;5786.5370770338695;5798.014337562625;5898.705064746746;6355.237371515448;6363.33185015576;6373.458256048288;6382.680865956683;6386.712597933104;6143.815685529441;6151.415615516519;6155.076738489173;6171.691415429211;6180.505559555225;5930.9406905931255;6110.327863934964;6114.358196333954;6120.355709576858;6214.974529202631;6182.690865456799;6191.473724733448;6227.06594010902;6229.283632382243;6230.034344676583;6176.8764624707255;6178.320384757673;6193.535484838655;6199.132121502993;6205.193324867739;4562.384078149474;4683.039976804746;4723.858284251478;4727.078219747185;4794.7362895040405;4211.873643790691;4228.91542169161;4234.237837189336;4245.80893859777;4267.423897773823;5952.958232309328;6033.698315084228;6036.396360364016;6042.879142417215;6044.546659000593;6155.47678447337;6192.380761923861;6193.969030154821;6284.245890493079;6285.560010799291;5965.701714914237;6009.849753756103;6012.458878700938;6023.548233882477;6073.787417440369;5156.252343410185;5342.171963345688;5346.492083252163;5351.09031464652;5352.973235133877;6296.685165741695;6415.037744338387;6419.422251997343;6520.5435619507125;6524.021396790425;6261.624302541771;6273.466755325849;6287.685615719287;6301.684915754102;6313.368663133833;6076.51387888959;6306.873862522632;6478.449331806849;6497.18785151856;6510.544261901621;6000.699965001645;6116.877662446637;6118.558266176107;6124.472479235402;6128.8774224516155;6177.9691101546005;6193.969030154821;6215.81352559424;6235.532335074979;6253.499720022478;5952.523798096228;6089.177960975212;6105.14527966094;6115.449609535163;6122.173506576555;6060.787872424444;6126.9168432863735;6138.75626760427;6141.884937092248;6143.232095988002;6103.145559621567;6151.753929842936;6155.815325540241;6172.660503672341;6186.288576813544;5966.105084237399;6007.849803754941;6176.9073463897175;6199.628022318675;6215;5871.089981052944;5935.5548333875995;5984.431479009487;5988.520918326436;6012.188354572252 20:35:01 INFO - PROCESS | 1426 | |1;ext.html;581.7760162337498;644.9677516124194;649.9707513161904;659.567982971154;707.2185235314973;38699.80650096749;41890.790546047225;42016.369754453706;42034;42271.15457690844;46703.29945050827;47350.526494735044;47420;47483.28775068368;47499.52500475003;9182.2654187665;9279.164875161234;9297.116773906502;9314.86027709585;9329.207017403529;9186.908130918706;9217.308701847354;9264.861027084566;9270.582823772913;9301;9234.584443700049;9300.20948219402;9304;9311.76720581984;9345.252379809634;9234.261259099256;9265.814683706321;9303.395279306824;9328.813423731555;9348.392354496937;9261.259099272042;9309.115634014757;9317.534123293808;9331.20684741791;9370.062993700576;44242.115157696986;44437.11125777499;44588.10823783506;44615.33077003815;44781.55218447807;3869.671077958377;4079.5920407959266;4185.853495127656;4286.87139385819;4438.134563760068;35175.64824351749;35251.1187220319;35563;35787.82106089479;36199;4839.27410888366;4847.51524847516;4863.076015557032;4900.6569540132205;4913.31213630092;397677;398529;398849;399339;401321;62819.371806282266;63603.6819815898;63617.68191159107;63720;63804.68097659482;39628.40557391642;39925.60074399277;39966.40050399247;42612.57387426148;69000.6549967257;17242.310307587555;17586.736198957027;17599.29602815873;17599.384021559184;17724.291028358723;13193.670158246121;13194.670133246746;13305.135166214164;13308.600741977756;13380.264085475197;20872.79127208739;20951.58096838085;21066.578668426544;21227.575448490945;21296.574068518854;7460.216677248919;7648.847023059619;7707.190744971809;7740.380769538423;7752.302292793675;1054.847047178155;1126.2003977176296;1150.4017910686564;1214.1622280626339;1245.894099001577;34051.318973620386;35190.888546686765;35641.1089722759;35709.157626036846;36195;1146.5986904583497;1173.2373956927895;1177.5525300385798;1209.1354681402838;1271.580378475101;18546.44360669182;18646.906765466356;18779.81220187808;18914.05429728508;18922.716159257623;600402;607338;609367;610731;610751;65177.348226517126;65379;65819;65875.67062164754;66240.66879665667;1404.4803422733653;1438.8201474815648;1442;1451.4919778077588;1454.1275234859;33814.323713526086;34286.31427371489;35062.82468587641;35187.82406087953;35308.29383412369;58861;58978.41021589729;59331;59336.999999998276;59435.81128377495;460554;462054;462811;463915;466788;31082.378352433276;31672.841635791672;31915.202119947186;32095.839520802245;32156.356872862878;54043.72978135084;54086;54100.729496352265;54211.176150081475;55069;44349.782510874975;47439;47611.52388476209;47675.284870726275;48089.75955120202;46929.765351173024;46932;47067;47102.29346559873;47860;39629.40555891606;44715.435078598835;46248.768756156;49605.25592116049;50134;38934.41598376083;40464.1907161861;40970.385444218955;41205.175896481305;41356.37965430581;30930.53604195984;31396;31643.52534711935;31869.20326991844;32228.516572251905 20:35:01 INFO - PROCESS | 1426 | |2;jquery.html;415.8690012646014;426.9933197764565;429.5747210261841;438.58991842627137;443.35270505062584;19531.609367812634;21122;21219.787802121973;21290;21492.462688432795;16979;20354.592908141865;20852;20887.268945586908;21069.789302106976;5531.806386776454;5742.827715168531;5773.826785196451;5784.103463963072;5785.16115163301;5676.517496012844;5733.512651424634;5822.7088645567765;6114.1745864308195;6156.6921653917125;6036.034234522477;6097.878042439149;6109.816705498843;6115.785947491839;6116.938830611703;5784.508316793078;6028.788992385245;6081.665508397036;6088.66512341821;6097.786577469789;5771.855703607402;5916.497097746696;5920.141579470953;5924.851878703067;5941.732622031976;15213.239338033054;15510.30203640839;15603.063816171067;15611.687766244726;15623.296951637203;2355.6819829322994;2433.2943446400523;2474.4556197636493;2803.8317700937805;2859.7569206617254;11050.060744836699;11300.039496642796;11415.600453984154;11477;11512.25170363932;1987.3441764217919;2124.320217530391;2152.7309086364203;2160.2979031814725;2181.454636340915;78583.99999999943;83851;85334.57332713359;86921;92289;11687.065034797195;11727.120465965087;11788.528458861549;12155.9334913065;12299.569515066929;17168.484945451655;17282.308707651555;17294.394696185573;17316.913415432842;17326.653466930842;8563.186497282748;8573.399862009725;8681;8724.302055835518;8850.20348168668;8075.676972921017;8217.096119426858;8234.423590348739;8363.456375335583;8525.74422767318;5009.223570346602;5041.747912604428;5063.670861394071;5079.974600126976;5199.844004679866;2095.727555417786;2156.342315593759;2180.8364372672113;2203.2398822406244;2210.491586935014;454.4841604778534;461.3356766256647;468.73516463198183;476.89269914269704;919.7700574856286;12585.119041667178;13397.33013349348;13436.798448023288;13498.122622029538;13515.121517101357;487.8609596265047;493.0607193296775;498.7930009046278;506.19767668246243;507.2897942879924;7084.539504932163;7131.92868071323;7230.74692385764;7265.636718164071;7304.853902922018;523568;527547;533168;535401;538359;61661;63711;63929.68035159887;104219.47890260651;104653;1041.9947900260602;1071.7320669832543;1082.9783404332027;1084.967450976472;1091.44881834673;18980.71528927095;19167.32914347991;19228;19263.22947082101;19466;44196.779016104716;44475;45676.314855277866;45782;46740.065198695156;63147.368526315986;63724;65354.67322663356;65624.34375656182;67361.3263867355;9176.174144327042;9203.309751768511;9231.169194772234;9270.119338662817;9344.766380840534;10883.564657413932;13800.654983625489;14225.644358890697;14249.145051296955;14403.711925761636;11663.708407289885;11736.589219377282;12188;12322.568710095104;12618.369081545703;12301.75396492083;12349.32078735678;12605.054620903653;12720;12983.156094853804;12319.938400307941;12411.193272437262;12496.000319974562;12652.304123273309;12752.4261408235;8682.869756953776;8860.512671803113;8955.776105597151;9058.909410905806;9208.079192081006;9226.861597075915;11770.941145294219;12105.818412723993;12294.077944154045;12344.567940122412 20:35:01 INFO - PROCESS | 1426 | |3;mootools.html;1561.8516240957108;1589.920503974801;1763.805981342052;1851.7870444898833;1860.2930886263218;8748.8687669685;9233.44599324041;9419.434833909962;10202.336848104876;10302.690919272414;9523.047695230474;9937.00629937006;9945.602175912954;9952.452615106165;9961.501924903761;2456.6806315179047;2507.912223072193;2516.421223118684;2517.748225177486;2521.1680145551923;2490.340059884123;2557.2200478853943;2557.360659835041;2570.974290257092;2571.7171111177754;2524.0787112703915;2526.166365099522;2534.2270607464716;2534.974650253492;2539.0478570536047;2471.4439251168524;2471.5180539794746;2481.6649752283392;2482.7517248275117;2496.4133428644254;2466.654668346433;2477.343503971449;2477.9008839646394;2488.029668429314;2522.785563227128;9936.304458687895;10110.9494452528;10121.949390253076;10125.797484050277;10140.949295253477;5770.567207459458;5909.6158749681135;5919.467247947662;5919.6152250103605;5921.378255283176;3806;3840.615938406165;3928.3125453045604;3952.5256969163597;4002.7198096133284;1208.5609704150172;1228.5761412312827;1232.2975903734875;1239.9442025108892;1263.6714454241871;14100.717985640333;14190.503332383318;14250.2162381069;14281.642958926006;14355.282235888164;5083.694978301313;5098.260752190911;5188.948110518922;5240.266362709223;5314.627976041717;12008.459619317151;12012.819807702892;12019.459124339426;12019.699507512381;12021.278723276631;5965.463108320272;5975.790847320323;5982.641041537521;5989;6004.789832355847;4775.33145359643;5226;5295.947040529545;5366.382865970385;5445.183222516575;4488.079943611552;4503.617192538671;4577.450705915311;4585.151746926829;4602.7468489233415;1415.709779495201;1436.7557515222438;1438.518096437705;1446;1464.2166440954252;1088.9292196007234;1092.8360745888021;1098.7363032872054;1114.8606424196976;1115.8884111588777;6751.25736169021;6775.254721980578;6847.863042739217;6903.309669033057;6917.792466226021;490.2768416585579;507.0315697018677;509.9056674515227;510.89271253037276;515.2220147577185;4670.8365207217585;4689.320048592935;4690;4725.3384526166365;4765.309030190672;12411.317377544321;13005.739885202433;13006.154599950973;13045.608631741063;13048;11596.130290228266;12177.878221217852;12833.486660533474;12896.742065158644;12983.480660773652;8830;8955.820883582292;9005.189532941935;9010.684626038057;9014.143656352637;6811.65941702903;6877.22442071745;6896.724131034703;6959.512834101564;6976.09450452968;11424.885751142714;11465.31208127515;11474.254173478697;11514.712132196762;11619.88380116222;11799.52801887915;11816.468258928197;11830.881691183322;11856.822147667965;11893.583724569598;8021.197880211932;8648;8847.557622118997;8950.507722075348;9097;9747.658831940847;9777.657781977596;9798.167155791838;9803.607855685692;9807.166390856857;8745.693900713444;8831.205191532792;8922;8942.821143576963;8946.239569636653;8501.787455313419;8585.957070214608;8596.35527335465;8718.869216961624;8907.153820387053;7772.028496437945;7907.525548467111;7941.682332706858;7948;7948.443608947318;5771.971140144273;5796.971015144897;5895.734691938787;5896.49879760208;5948.345681974893;5851.561132914963;5975;5998.25021872266;6051.848703782441;6121 20:35:01 INFO - PROCESS | 1426 | |4;prototype.html;330.84866645204426;341.11480707563874;346.14674826552556;347.16853136737507;350.0251798740508;5639.407862174474;5672.574556908238;5868.853278668035;5888.381719919411;5944.22725045744;5637.689927054021;5721.485066344028;5749.677574157946;5780.219670344512;5799.855003624901;4513.729176249419;4541.750203738793;4560.33875088112;4574.176648203322;4576.885577860564;4434.268345722952;4458.621017213541;4488.618467430272;4500.414946057024;4573.7713114344315;4428.7121337112985;4437.534058923815;4446.622037126848;4468.195724769556;4482.484514280867;4490.146872094308;4495.595396414322;4505.36924830524;4507.557581573898;4527.438033878339;3819.0452386903276;3896.7467114637457;3896.941545876814;3917.745346552465;3922.2939870823525;5541.556675465953;5628.240187574666;5635.267415236034;5645.971770141123;5692.5161361284345;5018.419197952633;5128.769205385766;5132.255822905658;5140.380780054308;5143.691378517301;5969.060055481986;5999.610025348338;6041.939580604182;6044.304904935944;6055.5761096723245;4238.194742998851;4238.258304796679;4246;4249.915001699979;4266.0827921996915;6138.539609529304;6310.368963103699;6314.842128946767;6337.271213810379;6349.904751428732;4286.87139385819;4291;4324.032565182834;4333.609975102256;4341.110072435206;4375.912481750411;4381.21138195128;4381.364702118175;4389.18800021997;4393.846215382447;5004;5077.213031980049;5084.415292241366;5102.132637451642;5130.410002849645;5916.674582897982;6018.277806663141;6050.667213303311;6104.20645316106;6110.511159107261;1505.6235941014747;1534.3479021415853;1536.078352988198;1539.661274519604;1552.95341139766;947.5877993073035;960.3229723045237;974.2985050763439;975.6829030565095;978.7259567321162;5259.894802104013;5268.552173065255;5278.709670968134;5312.256284120226;5374.381946076252;5444.128939369682;5541.251930989345;5548.9722551387795;5578.191162281447;5589.233329501842;4984.551390374883;5278.181881808249;5279.208118782213;5305.495977882172;5335.813246536352;2970.3465237647683;2995.251187203199;3002.744766694812;3008.849557522115;3020.5318175682364;5687.800926967537;5696.715164241771;5773.383452633269;5799.3040835100055;5820.330661973842;5990.933128070808;6432.324605916311;6448.516361272923;6464.25661048976;6471.5146364022885;6564.803055908426;6576.80269591913;6584.637844918576;6591.571547849375;6607.504437167232;6053.969730151321;6249.500039996698;6273.654948977667;6301.275353334242;6301.40136687014;6184.969075154595;6185.783497577563;6200.387899146164;6227;6262.311145774052;6402.423781859655;6514.674266286762;6576.736930522726;6586.5389422739945;6595.307492713196;4379.189849877783;4389.604935555815;4393.253146964959;4396.714213576107;4406.162829062468;6095.140982000533;6142.723577439116;6351.269603995414;6366.745330186741;6379.202599675041;6132.5400594956445;6138.478229350555;6150;6171.197744293213;6180.783672571618;5959.76160953557;6087.812876489219;6109.725062372115;6160.815175544741;6161.938380616137;6068.726907289094;6073.331933487225;6075.908861366995;6095.878082438415;6119.969400152971;6134.865049965771;6136.355682653257;6175.84560385994;6209.844753881189;6224.502039836892;5819.185314055951;5972.522198224045;5992.910106348496;6014.548908831767;6031.969840150771 20:35:01 INFO - PROCESS | 1426 | |5;yui.html;769.9076110866694;804.2922228438973;809.3039985612376;816.4162623724039;914.995425022875;15372.61568460789;16480.09359485228;16507.83492165078;16573.088480133603;17668.293268269284;15368.615784605363;15590.98658587191;15593.610159746013;15606.297716602752;15680.686386272297;877.214892671059;879.463527248378;892.7991201979568;908.7909780750431;909.0863681999581;905.027095871937;905.8595917632745;909.9818003639956;910.6084383715;912.8813254276935;946.9431834089942;949.9335046546745;952.6570434643522;957.7414098193484;959.7120863740885;934.3552948465536;940.7083804020775;942.3544871762864;945.0832692288469;950.0736781637883;701.5860642221063;702.4871843554183;703.9612821294878;704.0530486495707;705.4074577355046;16588.336466541325;16592.25334859934;16649.25078371464;16676.082815445145;16699.83300166995;14340.713185736227;14648.70702585953;14742;15273.236338183046;15395.538133856002;14737.336819843133;14833.184174870377;14847.628809279746;14938.701225975528;15103.697926041528;4698.8355407560575;4835.178019736653;4893.853184404474;4963.106640804654;5065.848024559269;71404.28595714028;89369.55315223383;90748.54625726896;91198.5440072802;91875.54062229714;11977.221480603734;12686.936565317113;12882.162659427107;12960.611181664564;12990.740185196431;20695.27566535164;20704.58590828175;20878.164873404894;20892.895535522224;20961;9346.11211934865;9424.811503769886;9525.476098814632;9702.660406885867;9839.212862970946;5084.288199652052;5097;5112.233165025202;5157.535821775983;5251.921221181686;3955.980220098881;4124.773137477468;4126.401671757579;4127.484064491939;4141.026858688206;687.6350444367926;691.1740470138137;708.7767353283699;727.3381223086966;731.974380896666;672.9899051514134;723.2875617516742;730.7369347035063;804.601722147529;832.4422636833336;9803.264755143251;10782.305295950157;10821;10864.293820901616;10969.396683182342;585.6047168161474;621.0001896945913;720.9927900720926;765.6975494679571;838;4952.47998960104;5187.325647665854;5242.580593552507;5276.92084618731;5293.973530132402;79708;80819;81429.59285203536;110229;137936;116410;117781;118322.40838795924;120278.3986080064;123245;98923;99052.50473747586;101492.49253753683;101746.98253017523;101827.49086254521;9877.604895804376;10061.597536098474;10100.494975251355;10120.696379108638;10169.338992965433;44675.32987005263;48938.26592601185;60220.69889650524;61016.694916525135;61312.386876132456;75097.62451187709;76284;80490.59754701189;81545.59227203827;82360;13025.934870325587;13254.933725331312;13522.594322170351;13647.522336718168;13858.16850988944;16044.839551604335;16054.43809466663;16320.510384688478;17225;17580.296788128842;14896.329665164876;15102.093874367572;15858.920705396398;15866.60333491672;15873.603159921095;15080;15446.5366039019;15473.767893481832;15475.071495710294;15694.2937567812;13892.16647001183;14161.575152745434;14205;15482.845171548592;15738.606534836721;12223.877761222275;12370.690732731753;12549.686257843627;12608.243505389706;13080.80378794338;12265.489201014736;12372.62882113502;12519.624411267676;12537.749245014866;12836.807447888476 20:35:01 INFO - PROCESS | 1426 | __end_tp_report 20:35:01 INFO - PROCESS | 1426 | __start_cc_report 20:35:01 INFO - PROCESS | 1426 | _x_x_mozilla_cycle_collect,346 20:35:01 INFO - PROCESS | 1426 | __end_cc_report 20:35:01 INFO - PROCESS | 1426 | __startTimestamp1473046501664__endTimestamp 20:35:01 INFO - PROCESS | 1426 | 20:35:01 INFO - PROCESS | 1426 | ------- Summary: start ------- 20:35:01 INFO - PROCESS | 1426 | Number of tests: 6 20:35:01 INFO - PROCESS | 1426 | 20:35:01 INFO - PROCESS | 1426 | [#0] dojo.html Cycles:165 Average:5780.15 Median:6042.88 stddev:667.09 (11.0%) stddev-sans-first:640.41 20:35:01 INFO - PROCESS | 1426 | Values: 3311.6 3341.7 3344.1 3357.0 3375.6 5786.0 5809.4 5827.7 5857.0 5871.1 5773.4 5784.3 5788.9 5816.6 5831.3 5292.3 5302.8 5352.1 5358.9 5375.3 5136.7 5161.7 5176.0 5319.6 5346.7 5274.7 5307.1 5312.8 5411.0 5465.3 5390.6 5392.8 5396.5 5401.4 5419.8 5073.2 5111.8 5217.6 5289.7 5334.7 5126.5 5652.4 5693.6 5720.2 5793.9 6173.6 6224.8 6226.6 6228.1 6233.8 6231.7 6293.8 6300.3 6301.2 6313.1 5598.7 5755.5 5786.5 5798.0 5898.7 6355.2 6363.3 6373.5 6382.7 6386.7 6143.8 6151.4 6155.1 6171.7 6180.5 5930.9 6110.3 6114.4 6120.4 6215.0 6182.7 6191.5 6227.1 6229.3 6230.0 6176.9 6178.3 6193.5 6199.1 6205.2 4562.4 4683.0 4723.9 4727.1 4794.7 4211.9 4228.9 4234.2 4245.8 4267.4 5953.0 6033.7 6036.4 6042.9 6044.5 6155.5 6192.4 6194.0 6284.2 6285.6 5965.7 6009.8 6012.5 6023.5 6073.8 5156.3 5342.2 5346.5 5351.1 5353.0 6296.7 6415.0 6419.4 6520.5 6524.0 6261.6 6273.5 6287.7 6301.7 6313.4 6076.5 6306.9 6478.4 6497.2 6510.5 6000.7 6116.9 6118.6 6124.5 6128.9 6178.0 6194.0 6215.8 6235.5 6253.5 5952.5 6089.2 6105.1 6115.4 6122.2 6060.8 6126.9 6138.8 6141.9 6143.2 6103.1 6151.8 6155.8 6172.7 6186.3 5966.1 6007.8 6176.9 6199.6 6215.0 5871.1 5935.6 5984.4 5988.5 6012.2 20:35:01 INFO - PROCESS | 1426 | 20:35:01 INFO - PROCESS | 1426 | [#1] ext.html Cycles:180 Average:66123.02 Median:44748.49 stddev:132088.24 (295.2%) stddev-sans-first:132367.12 20:35:01 INFO - PROCESS | 1426 | Values: 581.8 645.0 650.0 659.6 707.2 38699.8 41890.8 42016.4 42034.0 42271.2 46703.3 47350.5 47420.0 47483.3 47499.5 9182.3 9279.2 9297.1 9314.9 9329.2 9186.9 9217.3 9264.9 9270.6 9301.0 9234.6 9300.2 9304.0 9311.8 9345.3 9234.3 9265.8 9303.4 9328.8 9348.4 9261.3 9309.1 9317.5 9331.2 9370.1 44242.1 44437.1 44588.1 44615.3 44781.6 3869.7 4079.6 4185.9 4286.9 4438.1 35175.6 35251.1 35563.0 35787.8 36199.0 4839.3 4847.5 4863.1 4900.7 4913.3 397677.0 398529.0 398849.0 399339.0 401321.0 62819.4 63603.7 63617.7 63720.0 63804.7 39628.4 39925.6 39966.4 42612.6 69000.7 17242.3 17586.7 17599.3 17599.4 17724.3 13193.7 13194.7 13305.1 13308.6 13380.3 20872.8 20951.6 21066.6 21227.6 21296.6 7460.2 7648.8 7707.2 7740.4 7752.3 1054.8 1126.2 1150.4 1214.2 1245.9 34051.3 35190.9 35641.1 35709.2 36195.0 1146.6 1173.2 1177.6 1209.1 1271.6 18546.4 18646.9 18779.8 18914.1 18922.7 600402.0 607338.0 609367.0 610731.0 610751.0 65177.3 65379.0 65819.0 65875.7 66240.7 1404.5 1438.8 1442.0 1451.5 1454.1 33814.3 34286.3 35062.8 35187.8 35308.3 58861.0 58978.4 59331.0 59337.0 59435.8 460554.0 462054.0 462811.0 463915.0 466788.0 31082.4 31672.8 31915.2 32095.8 32156.4 54043.7 54086.0 54100.7 54211.2 55069.0 44349.8 47439.0 47611.5 47675.3 48089.8 46929.8 46932.0 47067.0 47102.3 47860.0 39629.4 44715.4 46248.8 49605.3 50134.0 38934.4 40464.2 40970.4 41205.2 41356.4 30930.5 31396.0 31643.5 31869.2 32228.5 20:35:01 INFO - PROCESS | 1426 | 20:35:01 INFO - PROCESS | 1426 | [#2] jquery.html Cycles:180 Average:30149.73 Median:22320.07 stddev:87457.93 (391.8%) stddev-sans-first:87674.77 20:35:01 INFO - PROCESS | 1426 | Values: 415.9 427.0 429.6 438.6 443.4 19531.6 21122.0 21219.8 21290.0 21492.5 16979.0 20354.6 20852.0 20887.3 21069.8 5531.8 5742.8 5773.8 5784.1 5785.2 5676.5 5733.5 5822.7 6114.2 6156.7 6036.0 6097.9 6109.8 6115.8 6116.9 5784.5 6028.8 6081.7 6088.7 6097.8 5771.9 5916.5 5920.1 5924.9 5941.7 15213.2 15510.3 15603.1 15611.7 15623.3 2355.7 2433.3 2474.5 2803.8 2859.8 11050.1 11300.0 11415.6 11477.0 11512.3 1987.3 2124.3 2152.7 2160.3 2181.5 78584.0 83851.0 85334.6 86921.0 92289.0 11687.1 11727.1 11788.5 12155.9 12299.6 17168.5 17282.3 17294.4 17316.9 17326.7 8563.2 8573.4 8681.0 8724.3 8850.2 8075.7 8217.1 8234.4 8363.5 8525.7 5009.2 5041.7 5063.7 5080.0 5199.8 2095.7 2156.3 2180.8 2203.2 2210.5 454.5 461.3 468.7 476.9 919.8 12585.1 13397.3 13436.8 13498.1 13515.1 487.9 493.1 498.8 506.2 507.3 7084.5 7131.9 7230.7 7265.6 7304.9 523568.0 527547.0 533168.0 535401.0 538359.0 61661.0 63711.0 63929.7 104219.5 104653.0 1042.0 1071.7 1083.0 1085.0 1091.4 18980.7 19167.3 19228.0 19263.2 19466.0 44196.8 44475.0 45676.3 45782.0 46740.1 63147.4 63724.0 65354.7 65624.3 67361.3 9176.2 9203.3 9231.2 9270.1 9344.8 10883.6 13800.7 14225.6 14249.1 14403.7 11663.7 11736.6 12188.0 12322.6 12618.4 12301.8 12349.3 12605.1 12720.0 12983.2 12319.9 12411.2 12496.0 12652.3 12752.4 8682.9 8860.5 8955.8 9058.9 9208.1 9226.9 11770.9 12105.8 12294.1 12344.6 20:35:01 INFO - PROCESS | 1426 | 20:35:01 INFO - PROCESS | 1426 | [#3] mootools.html Cycles:180 Average:6584.49 Median:4636.79 stddev:3842.97 (82.9%) stddev-sans-first:3835.21 20:35:01 INFO - PROCESS | 1426 | Values: 1561.9 1589.9 1763.8 1851.8 1860.3 8748.9 9233.4 9419.4 10202.3 10302.7 9523.0 9937.0 9945.6 9952.5 9961.5 2456.7 2507.9 2516.4 2517.7 2521.2 2490.3 2557.2 2557.4 2571.0 2571.7 2524.1 2526.2 2534.2 2535.0 2539.0 2471.4 2471.5 2481.7 2482.8 2496.4 2466.7 2477.3 2477.9 2488.0 2522.8 9936.3 10110.9 10121.9 10125.8 10140.9 5770.6 5909.6 5919.5 5919.6 5921.4 3806.0 3840.6 3928.3 3952.5 4002.7 1208.6 1228.6 1232.3 1239.9 1263.7 14100.7 14190.5 14250.2 14281.6 14355.3 5083.7 5098.3 5188.9 5240.3 5314.6 12008.5 12012.8 12019.5 12019.7 12021.3 5965.5 5975.8 5982.6 5989.0 6004.8 4775.3 5226.0 5295.9 5366.4 5445.2 4488.1 4503.6 4577.5 4585.2 4602.7 1415.7 1436.8 1438.5 1446.0 1464.2 1088.9 1092.8 1098.7 1114.9 1115.9 6751.3 6775.3 6847.9 6903.3 6917.8 490.3 507.0 509.9 510.9 515.2 4670.8 4689.3 4690.0 4725.3 4765.3 12411.3 13005.7 13006.2 13045.6 13048.0 11596.1 12177.9 12833.5 12896.7 12983.5 8830.0 8955.8 9005.2 9010.7 9014.1 6811.7 6877.2 6896.7 6959.5 6976.1 11424.9 11465.3 11474.3 11514.7 11619.9 11799.5 11816.5 11830.9 11856.8 11893.6 8021.2 8648.0 8847.6 8950.5 9097.0 9747.7 9777.7 9798.2 9803.6 9807.2 8745.7 8831.2 8922.0 8942.8 8946.2 8501.8 8586.0 8596.4 8718.9 8907.2 7772.0 7907.5 7941.7 7948.0 7948.4 5772.0 5797.0 5895.7 5896.5 5948.3 5851.6 5975.0 5998.3 6051.8 6121.0 20:35:01 INFO - PROCESS | 1426 | 20:35:01 INFO - PROCESS | 1426 | [#4] prototype.html Cycles:180 Average:5036.08 Median:5659.27 stddev:1511.96 (26.7%) stddev-sans-first:1474.37 20:35:01 INFO - PROCESS | 1426 | Values: 330.8 341.1 346.1 347.2 350.0 5639.4 5672.6 5868.9 5888.4 5944.2 5637.7 5721.5 5749.7 5780.2 5799.9 4513.7 4541.8 4560.3 4574.2 4576.9 4434.3 4458.6 4488.6 4500.4 4573.8 4428.7 4437.5 4446.6 4468.2 4482.5 4490.1 4495.6 4505.4 4507.6 4527.4 3819.0 3896.7 3896.9 3917.7 3922.3 5541.6 5628.2 5635.3 5646.0 5692.5 5018.4 5128.8 5132.3 5140.4 5143.7 5969.1 5999.6 6041.9 6044.3 6055.6 4238.2 4238.3 4246.0 4249.9 4266.1 6138.5 6310.4 6314.8 6337.3 6349.9 4286.9 4291.0 4324.0 4333.6 4341.1 4375.9 4381.2 4381.4 4389.2 4393.8 5004.0 5077.2 5084.4 5102.1 5130.4 5916.7 6018.3 6050.7 6104.2 6110.5 1505.6 1534.3 1536.1 1539.7 1553.0 947.6 960.3 974.3 975.7 978.7 5259.9 5268.6 5278.7 5312.3 5374.4 5444.1 5541.3 5549.0 5578.2 5589.2 4984.6 5278.2 5279.2 5305.5 5335.8 2970.3 2995.3 3002.7 3008.8 3020.5 5687.8 5696.7 5773.4 5799.3 5820.3 5990.9 6432.3 6448.5 6464.3 6471.5 6564.8 6576.8 6584.6 6591.6 6607.5 6054.0 6249.5 6273.7 6301.3 6301.4 6185.0 6185.8 6200.4 6227.0 6262.3 6402.4 6514.7 6576.7 6586.5 6595.3 4379.2 4389.6 4393.3 4396.7 4406.2 6095.1 6142.7 6351.3 6366.7 6379.2 6132.5 6138.5 6150.0 6171.2 6180.8 5959.8 6087.8 6109.7 6160.8 6161.9 6068.7 6073.3 6075.9 6095.9 6120.0 6134.9 6136.4 6175.8 6209.8 6224.5 5819.2 5972.5 5992.9 6014.5 6032.0 20:35:01 INFO - PROCESS | 1426 | 20:35:01 INFO - PROCESS | 1426 | [#5] yui.html Cycles:180 Average:22472.15 Median:4126.94 stddev:32146.40 (778.9%) stddev-sans-first:32195.27 20:35:01 INFO - PROCESS | 1426 | Values: 769.9 804.3 809.3 816.4 915.0 15372.6 16480.1 16507.8 16573.1 17668.3 15368.6 15591.0 15593.6 15606.3 15680.7 877.2 879.5 892.8 908.8 909.1 905.0 905.9 910.0 910.6 912.9 946.9 949.9 952.7 957.7 959.7 934.4 940.7 942.4 945.1 950.1 701.6 702.5 704.0 704.1 705.4 16588.3 16592.3 16649.3 16676.1 16699.8 14340.7 14648.7 14742.0 15273.2 15395.5 14737.3 14833.2 14847.6 14938.7 15103.7 4698.8 4835.2 4893.9 4963.1 5065.8 71404.3 89369.6 90748.5 91198.5 91875.5 11977.2 12686.9 12882.2 12960.6 12990.7 20695.3 20704.6 20878.2 20892.9 20961.0 9346.1 9424.8 9525.5 9702.7 9839.2 5084.3 5097.0 5112.2 5157.5 5251.9 3956.0 4124.8 4126.4 4127.5 4141.0 687.6 691.2 708.8 727.3 732.0 673.0 723.3 730.7 804.6 832.4 9803.3 10782.3 10821.0 10864.3 10969.4 585.6 621.0 721.0 765.7 838.0 4952.5 5187.3 5242.6 5276.9 5294.0 79708.0 80819.0 81429.6 110229.0 137936.0 116410.0 117781.0 118322.4 120278.4 123245.0 98923.0 99052.5 101492.5 101747.0 101827.5 9877.6 10061.6 10100.5 10120.7 10169.3 44675.3 48938.3 60220.7 61016.7 61312.4 75097.6 76284.0 80490.6 81545.6 82360.0 13025.9 13254.9 13522.6 13647.5 13858.2 16044.8 16054.4 16320.5 17225.0 17580.3 14896.3 15102.1 15858.9 15866.6 15873.6 15080.0 15446.5 15473.8 15475.1 15694.3 13892.2 14161.6 14205.0 15482.8 15738.6 12223.9 12370.7 12549.7 12608.2 13080.8 12265.5 12372.6 12519.6 12537.7 12836.8 20:35:01 INFO - PROCESS | 1426 | -------- Summary: end -------- 20:35:01 INFO - PROCESS | 1426 | 20:35:02 INFO - TEST-INFO | 1426: exit 0 20:35:02 INFO - TEST-OK | dromaeo_css | took 1110763ms 20:35:02 INFO - TEST-START | kraken 20:35:02 INFO - operating with platform_type : linux_ 20:35:02 INFO - Initialising browser for kraken test... 20:35:02 INFO - TEST-INFO | started process 1691 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp_cq8a2/profile http://localhost:47429/getInfo.html) 20:35:06 INFO - PROCESS | 1691 | __metrics Screen width/height:1600/1200 20:35:06 INFO - PROCESS | 1691 | colorDepth:24 20:35:06 INFO - PROCESS | 1691 | Browser inner width/height: 1024/697 20:35:06 INFO - PROCESS | 1691 | __metrics 20:35:07 ERROR - PROCESS | 1691 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2967: TypeError: this.tabs is undefined 20:35:07 INFO - TEST-INFO | 1691: exit 0 20:35:07 INFO - Browser initialized. 20:35:07 INFO - Running cycle 1/1 for kraken test... 20:35:07 INFO - TEST-INFO | started process 1812 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp_cq8a2/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 20:35:19 INFO - PROCESS | 1812 | RSS: Main: 152465408 20:35:19 INFO - PROCESS | 1812 | 20:35:22 INFO - PROCESS | 1812 | Cycle 1(1): loaded http://localhost:47429/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:47429/tests/kraken/driver.html?testName=audio-beat-detection) 20:35:23 INFO - PROCESS | 1812 | RSS: Main: 157847552 20:35:23 INFO - PROCESS | 1812 | 20:35:25 INFO - PROCESS | 1812 | Cycle 1(1): loaded http://localhost:47429/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:47429/tests/kraken/driver.html?testName=audio-dft) 20:35:26 INFO - PROCESS | 1812 | RSS: Main: 156536832 20:35:26 INFO - PROCESS | 1812 | 20:35:29 INFO - PROCESS | 1812 | Cycle 1(1): loaded http://localhost:47429/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:47429/tests/kraken/driver.html?testName=audio-fft) 20:35:29 INFO - PROCESS | 1812 | RSS: Main: 157155328 20:35:29 INFO - PROCESS | 1812 | 20:35:31 INFO - PROCESS | 1812 | Cycle 1(1): loaded http://localhost:47429/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:47429/tests/kraken/driver.html?testName=audio-oscillator) 20:35:32 INFO - PROCESS | 1812 | RSS: Main: 157229056 20:35:32 INFO - PROCESS | 1812 | 20:35:34 INFO - PROCESS | 1812 | Cycle 1(1): loaded http://localhost:47429/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:47429/tests/kraken/driver.html?testName=imaging-gaussian-blur) 20:35:35 INFO - PROCESS | 1812 | RSS: Main: 156483584 20:35:35 INFO - PROCESS | 1812 | 20:35:38 INFO - PROCESS | 1812 | Cycle 1(1): loaded http://localhost:47429/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:47429/tests/kraken/driver.html?testName=imaging-darkroom) 20:35:38 INFO - PROCESS | 1812 | RSS: Main: 156082176 20:35:38 INFO - PROCESS | 1812 | 20:35:42 INFO - PROCESS | 1812 | Cycle 1(1): loaded http://localhost:47429/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:47429/tests/kraken/driver.html?testName=imaging-desaturate) 20:35:42 INFO - PROCESS | 1812 | RSS: Main: 156905472 20:35:42 INFO - PROCESS | 1812 | 20:35:45 INFO - PROCESS | 1812 | Cycle 1(1): loaded http://localhost:47429/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:47429/tests/kraken/driver.html?testName=json-parse-financial) 20:35:46 INFO - PROCESS | 1812 | RSS: Main: 157265920 20:35:46 INFO - PROCESS | 1812 | 20:35:47 INFO - PROCESS | 1812 | Cycle 1(1): loaded http://localhost:47429/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:47429/tests/kraken/driver.html?testName=json-stringify-tinderbox) 20:35:48 INFO - PROCESS | 1812 | RSS: Main: 157057024 20:35:48 INFO - PROCESS | 1812 | 20:35:50 INFO - PROCESS | 1812 | Cycle 1(1): loaded http://localhost:47429/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:47429/tests/kraken/driver.html?testName=stanford-crypto-aes) 20:35:50 INFO - PROCESS | 1812 | RSS: Main: 157663232 20:35:50 INFO - PROCESS | 1812 | 20:35:52 INFO - PROCESS | 1812 | Cycle 1(1): loaded http://localhost:47429/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:47429/tests/kraken/driver.html?testName=stanford-crypto-ccm) 20:35:53 INFO - PROCESS | 1812 | RSS: Main: 157622272 20:35:53 INFO - PROCESS | 1812 | 20:35:56 INFO - PROCESS | 1812 | Cycle 1(1): loaded http://localhost:47429/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:47429/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 20:35:56 INFO - PROCESS | 1812 | RSS: Main: 158244864 20:35:56 INFO - PROCESS | 1812 | 20:35:59 INFO - PROCESS | 1812 | Cycle 1(1): loaded http://localhost:47429/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:47429/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 20:36:00 INFO - PROCESS | 1812 | RSS: Main: 157896704 20:36:00 INFO - PROCESS | 1812 | 20:36:01 INFO - PROCESS | 1812 | Cycle 1(1): loaded http://localhost:47429/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:47429/tests/kraken/driver.html?testName=ai-astar) 20:36:01 INFO - PROCESS | 1812 | RSS: Main: 158060544 20:36:01 INFO - PROCESS | 1812 | 20:36:01 INFO - PROCESS | 1812 | __start_tp_report 20:36:01 INFO - PROCESS | 1812 | _x_x_mozilla_page_load 20:36:01 INFO - PROCESS | 1812 | _x_x_mozilla_page_load_details 20:36:01 INFO - PROCESS | 1812 | |i|pagename|runs| 20:36:01 INFO - PROCESS | 1812 | |0;ai-astar;99;110;105;110;111;114;111;111;111;111 20:36:01 INFO - PROCESS | 1812 | |1;audio-beat-detection;149;121;142;162;116;140;148;116;168;116 20:36:01 INFO - PROCESS | 1812 | |2;audio-dft;163;161;162;161;163;164;164;163;161;162 20:36:01 INFO - PROCESS | 1812 | |3;audio-fft;75;76;76;176;77;75;74;75;180;75 20:36:01 INFO - PROCESS | 1812 | |4;audio-oscillator;88;84;155;86;83;85;146;83;86;137 20:36:01 INFO - PROCESS | 1812 | |5;imaging-gaussian-blur;117;118;117;118;118;118;118;117;117;117 20:36:01 INFO - PROCESS | 1812 | |6;imaging-darkroom;147;147;147;147;147;148;148;148;148;147 20:36:01 INFO - PROCESS | 1812 | |7;imaging-desaturate;97;96;96;96;95;99;96;96;96;96 20:36:01 INFO - PROCESS | 1812 | |8;json-parse-financial;51;57;56;54;52;52;52;54;52;52 20:36:01 INFO - PROCESS | 1812 | |9;json-stringify-tinderbox;63;60;60;60;60;60;60;60;60;60 20:36:01 INFO - PROCESS | 1812 | |10;stanford-crypto-aes;73;71;78;72;71;72;72;73;75;73 20:36:01 INFO - PROCESS | 1812 | |11;stanford-crypto-ccm;127;122;121;139;122;123;130;116;147;123 20:36:01 INFO - PROCESS | 1812 | |12;stanford-crypto-pbkdf2;153;157;158;150;156;151;158;154;153;152 20:36:01 INFO - PROCESS | 1812 | |13;stanford-crypto-sha256-iterative;65;63;62;63;62;63;62;63;67;61 20:36:01 INFO - PROCESS | 1812 | __end_tp_report 20:36:01 INFO - PROCESS | 1812 | __start_cc_report 20:36:01 INFO - PROCESS | 1812 | _x_x_mozilla_cycle_collect,841 20:36:01 INFO - PROCESS | 1812 | __end_cc_report 20:36:01 INFO - PROCESS | 1812 | __startTimestamp1473046561970__endTimestamp 20:36:01 INFO - PROCESS | 1812 | 20:36:01 INFO - PROCESS | 1812 | ------- Summary: start ------- 20:36:01 INFO - PROCESS | 1812 | Number of tests: 14 20:36:01 INFO - PROCESS | 1812 | 20:36:01 INFO - PROCESS | 1812 | [#0] ai-astar Cycles:10 Average:109.30 Median:111.00 stddev:4.24 (3.8%) stddev-sans-first:2.35 20:36:01 INFO - PROCESS | 1812 | Values: 99.0 110.0 105.0 110.0 111.0 114.0 111.0 111.0 111.0 111.0 20:36:01 INFO - PROCESS | 1812 | 20:36:01 INFO - PROCESS | 1812 | [#1] audio-beat-detection Cycles:10 Average:137.80 Median:145.00 stddev:19.60 (13.5%) stddev-sans-first:20.37 20:36:01 INFO - PROCESS | 1812 | Values: 149.0 121.0 142.0 162.0 116.0 140.0 148.0 116.0 168.0 116.0 20:36:01 INFO - PROCESS | 1812 | 20:36:01 INFO - PROCESS | 1812 | [#2] audio-dft Cycles:10 Average:162.40 Median:163.00 stddev:1.17 (0.7%) stddev-sans-first:1.22 20:36:01 INFO - PROCESS | 1812 | Values: 163.0 161.0 162.0 161.0 163.0 164.0 164.0 163.0 161.0 162.0 20:36:01 INFO - PROCESS | 1812 | 20:36:01 INFO - PROCESS | 1812 | [#3] audio-fft Cycles:10 Average:95.90 Median:75.00 stddev:43.29 (57.7%) stddev-sans-first:45.25 20:36:01 INFO - PROCESS | 1812 | Values: 75.0 76.0 76.0 176.0 77.0 75.0 74.0 75.0 180.0 75.0 20:36:01 INFO - PROCESS | 1812 | 20:36:01 INFO - PROCESS | 1812 | [#4] audio-oscillator Cycles:10 Average:103.30 Median:84.50 stddev:29.81 (35.3%) stddev-sans-first:31.10 20:36:01 INFO - PROCESS | 1812 | Values: 88.0 84.0 155.0 86.0 83.0 85.0 146.0 83.0 86.0 137.0 20:36:01 INFO - PROCESS | 1812 | 20:36:01 INFO - PROCESS | 1812 | [#5] imaging-gaussian-blur Cycles:10 Average:117.50 Median:118.00 stddev:0.53 (0.4%) stddev-sans-first:0.53 20:36:01 INFO - PROCESS | 1812 | Values: 117.0 118.0 117.0 118.0 118.0 118.0 118.0 117.0 117.0 117.0 20:36:01 INFO - PROCESS | 1812 | 20:36:01 INFO - PROCESS | 1812 | [#6] imaging-darkroom Cycles:10 Average:147.40 Median:147.50 stddev:0.52 (0.4%) stddev-sans-first:0.53 20:36:01 INFO - PROCESS | 1812 | Values: 147.0 147.0 147.0 147.0 147.0 148.0 148.0 148.0 148.0 147.0 20:36:01 INFO - PROCESS | 1812 | 20:36:01 INFO - PROCESS | 1812 | [#7] imaging-desaturate Cycles:10 Average:96.30 Median:96.00 stddev:1.06 (1.1%) stddev-sans-first:1.09 20:36:01 INFO - PROCESS | 1812 | Values: 97.0 96.0 96.0 96.0 95.0 99.0 96.0 96.0 96.0 96.0 20:36:01 INFO - PROCESS | 1812 | 20:36:01 INFO - PROCESS | 1812 | [#8] json-parse-financial Cycles:10 Average:53.20 Median:53.00 stddev:1.99 (3.8%) stddev-sans-first:1.94 20:36:01 INFO - PROCESS | 1812 | Values: 51.0 57.0 56.0 54.0 52.0 52.0 52.0 54.0 52.0 52.0 20:36:01 INFO - PROCESS | 1812 | 20:36:01 INFO - PROCESS | 1812 | [#9] json-stringify-tinderbox Cycles:10 Average:60.30 Median:60.00 stddev:0.95 (1.6%) stddev-sans-first:0.00 20:36:01 INFO - PROCESS | 1812 | Values: 63.0 60.0 60.0 60.0 60.0 60.0 60.0 60.0 60.0 60.0 20:36:01 INFO - PROCESS | 1812 | 20:36:01 INFO - PROCESS | 1812 | [#10] stanford-crypto-aes Cycles:10 Average:73.00 Median:73.00 stddev:2.11 (2.9%) stddev-sans-first:2.24 20:36:01 INFO - PROCESS | 1812 | Values: 73.0 71.0 78.0 72.0 71.0 72.0 72.0 73.0 75.0 73.0 20:36:01 INFO - PROCESS | 1812 | 20:36:01 INFO - PROCESS | 1812 | [#11] stanford-crypto-ccm Cycles:10 Average:127.00 Median:125.00 stddev:9.38 (7.5%) stddev-sans-first:9.95 20:36:01 INFO - PROCESS | 1812 | Values: 127.0 122.0 121.0 139.0 122.0 123.0 130.0 116.0 147.0 123.0 20:36:01 INFO - PROCESS | 1812 | 20:36:01 INFO - PROCESS | 1812 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:154.20 Median:155.00 stddev:2.90 (1.9%) stddev-sans-first:3.04 20:36:01 INFO - PROCESS | 1812 | Values: 153.0 157.0 158.0 150.0 156.0 151.0 158.0 154.0 153.0 152.0 20:36:01 INFO - PROCESS | 1812 | 20:36:01 INFO - PROCESS | 1812 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:63.10 Median:63.00 stddev:1.73 (2.7%) stddev-sans-first:1.69 20:36:01 INFO - PROCESS | 1812 | Values: 65.0 63.0 62.0 63.0 62.0 63.0 62.0 63.0 67.0 61.0 20:36:01 INFO - PROCESS | 1812 | -------- Summary: end -------- 20:36:01 INFO - PROCESS | 1812 | 20:36:02 INFO - TEST-INFO | 1812: exit 0 20:36:02 INFO - TEST-OK | kraken | took 60598ms 20:36:02 INFO - SUITE-END | took 1171s 20:36:03 INFO - Completed test suite (00:19:31) 20:36:03 INFO - javascript benchmark 20:36:03 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7766.080171956315, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3311.5694959655243, 3341.7326613870887, 3344.1305260632244, 3357.0264623259254, 3375.628680845108, 5786.045302525084, 5809.390014048522, 5827.737751801168, 5856.970715146429, 5871.089981052933, 5773.3937936516695, 5784.3058832940005, 5788.884222315552, 5816.563757718167, 5831.271091113611, 5292.259083728271, 5302.787888484475, 5352.090144675414, 5358.946410535903, 5375.274337964363, 5136.717480538568, 5161.7161056141895, 5175.974120129413, 5319.627626066177, 5346.6524675896135, 5274.736263186845, 5307.071262529062, 5312.760925758331, 5411.0, 5465.316835395575, 5390.649607775502, 5392.811251606175, 5396.541293989996, 5401.43284955078, 5419.83740487786, 5073.239014147871, 5111.795528178868, 5217.608679349064, 5289.656172348824, 5334.706591137486, 5126.487351264844, 5652.378238393773, 5693.572982026323, 5720.227769251181, 5793.884122317572, 6173.59871608344, 6224.813255602339, 6226.62640241587, 6228.065790131472, 6233.812985610393, 6231.688415579249, 6293.811185664437, 6300.27546832111, 6301.212348456443, 6313.053042043684, 5598.692071936042, 5755.510781583571, 5786.5370770338695, 5798.014337562625, 5898.705064746746, 6355.237371515448, 6363.33185015576, 6373.458256048288, 6382.680865956683, 6386.712597933104, 6143.815685529441, 6151.415615516519, 6155.076738489173, 6171.691415429211, 6180.505559555225, 5930.9406905931255, 6110.327863934964, 6114.358196333954, 6120.355709576858, 6214.974529202631, 6182.690865456799, 6191.473724733448, 6227.06594010902, 6229.283632382243, 6230.034344676583, 6176.8764624707255, 6178.320384757673, 6193.535484838655, 6199.132121502993, 6205.193324867739, 4562.384078149474, 4683.039976804746, 4723.858284251478, 4727.078219747185, 4794.7362895040405, 4211.873643790691, 4228.91542169161, 4234.237837189336, 4245.80893859777, 4267.423897773823, 5952.958232309328, 6033.698315084228, 6036.396360364016, 6042.879142417215, 6044.546659000593, 6155.47678447337, 6192.380761923861, 6193.969030154821, 6284.245890493079, 6285.560010799291, 5965.701714914237, 6009.849753756103, 6012.458878700938, 6023.548233882477, 6073.787417440369, 5156.252343410185, 5342.171963345688, 5346.492083252163, 5351.09031464652, 5352.973235133877, 6296.685165741695, 6415.037744338387, 6419.422251997343, 6520.5435619507125, 6524.021396790425, 6261.624302541771, 6273.466755325849, 6287.685615719287, 6301.684915754102, 6313.368663133833, 6076.51387888959, 6306.873862522632, 6478.449331806849, 6497.18785151856, 6510.544261901621, 6000.699965001645, 6116.877662446637, 6118.558266176107, 6124.472479235402, 6128.8774224516155, 6177.9691101546005, 6193.969030154821, 6215.81352559424, 6235.532335074979, 6253.499720022478, 5952.523798096228, 6089.177960975212, 6105.14527966094, 6115.449609535163, 6122.173506576555, 6060.787872424444, 6126.9168432863735, 6138.75626760427, 6141.884937092248, 6143.232095988002, 6103.145559621567, 6151.753929842936, 6155.815325540241, 6172.660503672341, 6186.288576813544, 5966.105084237399, 6007.849803754941, 6176.9073463897175, 6199.628022318675, 6215.0, 5871.089981052944, 5935.5548333875995, 5984.431479009487, 5988.520918326436, 6012.188354572252], "value": 5734.500720086085, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [581.7760162337498, 644.9677516124194, 649.9707513161904, 659.567982971154, 707.2185235314973, 38699.80650096749, 41890.790546047225, 42016.369754453706, 42034.0, 42271.15457690844, 46703.29945050827, 47350.526494735044, 47420.0, 47483.28775068368, 47499.52500475003, 9182.2654187665, 9279.164875161234, 9297.116773906502, 9314.86027709585, 9329.207017403529, 9186.908130918706, 9217.308701847354, 9264.861027084566, 9270.582823772913, 9301.0, 9234.584443700049, 9300.20948219402, 9304.0, 9311.76720581984, 9345.252379809634, 9234.261259099256, 9265.814683706321, 9303.395279306824, 9328.813423731555, 9348.392354496937, 9261.259099272042, 9309.115634014757, 9317.534123293808, 9331.20684741791, 9370.062993700576, 44242.115157696986, 44437.11125777499, 44588.10823783506, 44615.33077003815, 44781.55218447807, 3869.671077958377, 4079.5920407959266, 4185.853495127656, 4286.87139385819, 4438.134563760068, 35175.64824351749, 35251.1187220319, 35563.0, 35787.82106089479, 36199.0, 4839.27410888366, 4847.51524847516, 4863.076015557032, 4900.6569540132205, 4913.31213630092, 397677.0, 398529.0, 398849.0, 399339.0, 401321.0, 62819.371806282266, 63603.6819815898, 63617.68191159107, 63720.0, 63804.68097659482, 39628.40557391642, 39925.60074399277, 39966.40050399247, 42612.57387426148, 69000.6549967257, 17242.310307587555, 17586.736198957027, 17599.29602815873, 17599.384021559184, 17724.291028358723, 13193.670158246121, 13194.670133246746, 13305.135166214164, 13308.600741977756, 13380.264085475197, 20872.79127208739, 20951.58096838085, 21066.578668426544, 21227.575448490945, 21296.574068518854, 7460.216677248919, 7648.847023059619, 7707.190744971809, 7740.380769538423, 7752.302292793675, 1054.847047178155, 1126.2003977176296, 1150.4017910686564, 1214.1622280626339, 1245.894099001577, 34051.318973620386, 35190.888546686765, 35641.1089722759, 35709.157626036846, 36195.0, 1146.5986904583497, 1173.2373956927895, 1177.5525300385798, 1209.1354681402838, 1271.580378475101, 18546.44360669182, 18646.906765466356, 18779.81220187808, 18914.05429728508, 18922.716159257623, 600402.0, 607338.0, 609367.0, 610731.0, 610751.0, 65177.348226517126, 65379.0, 65819.0, 65875.67062164754, 66240.66879665667, 1404.4803422733653, 1438.8201474815648, 1442.0, 1451.4919778077588, 1454.1275234859, 33814.323713526086, 34286.31427371489, 35062.82468587641, 35187.82406087953, 35308.29383412369, 58861.0, 58978.41021589729, 59331.0, 59336.999999998276, 59435.81128377495, 460554.0, 462054.0, 462811.0, 463915.0, 466788.0, 31082.378352433276, 31672.841635791672, 31915.202119947186, 32095.839520802245, 32156.356872862878, 54043.72978135084, 54086.0, 54100.729496352265, 54211.176150081475, 55069.0, 44349.782510874975, 47439.0, 47611.52388476209, 47675.284870726275, 48089.75955120202, 46929.765351173024, 46932.0, 47067.0, 47102.29346559873, 47860.0, 39629.40555891606, 44715.435078598835, 46248.768756156, 49605.25592116049, 50134.0, 38934.41598376083, 40464.1907161861, 40970.385444218955, 41205.175896481305, 41356.37965430581, 30930.53604195984, 31396.0, 31643.52534711935, 31869.20326991844, 32228.516572251905], "value": 21757.062672481738, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [415.8690012646014, 426.9933197764565, 429.5747210261841, 438.58991842627137, 443.35270505062584, 19531.609367812634, 21122.0, 21219.787802121973, 21290.0, 21492.462688432795, 16979.0, 20354.592908141865, 20852.0, 20887.268945586908, 21069.789302106976, 5531.806386776454, 5742.827715168531, 5773.826785196451, 5784.103463963072, 5785.16115163301, 5676.517496012844, 5733.512651424634, 5822.7088645567765, 6114.1745864308195, 6156.6921653917125, 6036.034234522477, 6097.878042439149, 6109.816705498843, 6115.785947491839, 6116.938830611703, 5784.508316793078, 6028.788992385245, 6081.665508397036, 6088.66512341821, 6097.786577469789, 5771.855703607402, 5916.497097746696, 5920.141579470953, 5924.851878703067, 5941.732622031976, 15213.239338033054, 15510.30203640839, 15603.063816171067, 15611.687766244726, 15623.296951637203, 2355.6819829322994, 2433.2943446400523, 2474.4556197636493, 2803.8317700937805, 2859.7569206617254, 11050.060744836699, 11300.039496642796, 11415.600453984154, 11477.0, 11512.25170363932, 1987.3441764217919, 2124.320217530391, 2152.7309086364203, 2160.2979031814725, 2181.454636340915, 78583.99999999943, 83851.0, 85334.57332713359, 86921.0, 92289.0, 11687.065034797195, 11727.120465965087, 11788.528458861549, 12155.9334913065, 12299.569515066929, 17168.484945451655, 17282.308707651555, 17294.394696185573, 17316.913415432842, 17326.653466930842, 8563.186497282748, 8573.399862009725, 8681.0, 8724.302055835518, 8850.20348168668, 8075.676972921017, 8217.096119426858, 8234.423590348739, 8363.456375335583, 8525.74422767318, 5009.223570346602, 5041.747912604428, 5063.670861394071, 5079.974600126976, 5199.844004679866, 2095.727555417786, 2156.342315593759, 2180.8364372672113, 2203.2398822406244, 2210.491586935014, 454.4841604778534, 461.3356766256647, 468.73516463198183, 476.89269914269704, 919.7700574856286, 12585.119041667178, 13397.33013349348, 13436.798448023288, 13498.122622029538, 13515.121517101357, 487.8609596265047, 493.0607193296775, 498.7930009046278, 506.19767668246243, 507.2897942879924, 7084.539504932163, 7131.92868071323, 7230.74692385764, 7265.636718164071, 7304.853902922018, 523568.0, 527547.0, 533168.0, 535401.0, 538359.0, 61661.0, 63711.0, 63929.68035159887, 104219.47890260651, 104653.0, 1041.9947900260602, 1071.7320669832543, 1082.9783404332027, 1084.967450976472, 1091.44881834673, 18980.71528927095, 19167.32914347991, 19228.0, 19263.22947082101, 19466.0, 44196.779016104716, 44475.0, 45676.314855277866, 45782.0, 46740.065198695156, 63147.368526315986, 63724.0, 65354.67322663356, 65624.34375656182, 67361.3263867355, 9176.174144327042, 9203.309751768511, 9231.169194772234, 9270.119338662817, 9344.766380840534, 10883.564657413932, 13800.654983625489, 14225.644358890697, 14249.145051296955, 14403.711925761636, 11663.708407289885, 11736.589219377282, 12188.0, 12322.568710095104, 12618.369081545703, 12301.75396492083, 12349.32078735678, 12605.054620903653, 12720.0, 12983.156094853804, 12319.938400307941, 12411.193272437262, 12496.000319974562, 12652.304123273309, 12752.4261408235, 8682.869756953776, 8860.512671803113, 8955.776105597151, 9058.909410905806, 9208.079192081006, 9226.861597075915, 11770.941145294219, 12105.818412723993, 12294.077944154045, 12344.567940122412], "value": 9073.685608133414, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1561.8516240957108, 1589.920503974801, 1763.805981342052, 1851.7870444898833, 1860.2930886263218, 8748.8687669685, 9233.44599324041, 9419.434833909962, 10202.336848104876, 10302.690919272414, 9523.047695230474, 9937.00629937006, 9945.602175912954, 9952.452615106165, 9961.501924903761, 2456.6806315179047, 2507.912223072193, 2516.421223118684, 2517.748225177486, 2521.1680145551923, 2490.340059884123, 2557.2200478853943, 2557.360659835041, 2570.974290257092, 2571.7171111177754, 2524.0787112703915, 2526.166365099522, 2534.2270607464716, 2534.974650253492, 2539.0478570536047, 2471.4439251168524, 2471.5180539794746, 2481.6649752283392, 2482.7517248275117, 2496.4133428644254, 2466.654668346433, 2477.343503971449, 2477.9008839646394, 2488.029668429314, 2522.785563227128, 9936.304458687895, 10110.9494452528, 10121.949390253076, 10125.797484050277, 10140.949295253477, 5770.567207459458, 5909.6158749681135, 5919.467247947662, 5919.6152250103605, 5921.378255283176, 3806.0, 3840.615938406165, 3928.3125453045604, 3952.5256969163597, 4002.7198096133284, 1208.5609704150172, 1228.5761412312827, 1232.2975903734875, 1239.9442025108892, 1263.6714454241871, 14100.717985640333, 14190.503332383318, 14250.2162381069, 14281.642958926006, 14355.282235888164, 5083.694978301313, 5098.260752190911, 5188.948110518922, 5240.266362709223, 5314.627976041717, 12008.459619317151, 12012.819807702892, 12019.459124339426, 12019.699507512381, 12021.278723276631, 5965.463108320272, 5975.790847320323, 5982.641041537521, 5989.0, 6004.789832355847, 4775.33145359643, 5226.0, 5295.947040529545, 5366.382865970385, 5445.183222516575, 4488.079943611552, 4503.617192538671, 4577.450705915311, 4585.151746926829, 4602.7468489233415, 1415.709779495201, 1436.7557515222438, 1438.518096437705, 1446.0, 1464.2166440954252, 1088.9292196007234, 1092.8360745888021, 1098.7363032872054, 1114.8606424196976, 1115.8884111588777, 6751.25736169021, 6775.254721980578, 6847.863042739217, 6903.309669033057, 6917.792466226021, 490.2768416585579, 507.0315697018677, 509.9056674515227, 510.89271253037276, 515.2220147577185, 4670.8365207217585, 4689.320048592935, 4690.0, 4725.3384526166365, 4765.309030190672, 12411.317377544321, 13005.739885202433, 13006.154599950973, 13045.608631741063, 13048.0, 11596.130290228266, 12177.878221217852, 12833.486660533474, 12896.742065158644, 12983.480660773652, 8830.0, 8955.820883582292, 9005.189532941935, 9010.684626038057, 9014.143656352637, 6811.65941702903, 6877.22442071745, 6896.724131034703, 6959.512834101564, 6976.09450452968, 11424.885751142714, 11465.31208127515, 11474.254173478697, 11514.712132196762, 11619.88380116222, 11799.52801887915, 11816.468258928197, 11830.881691183322, 11856.822147667965, 11893.583724569598, 8021.197880211932, 8648.0, 8847.557622118997, 8950.507722075348, 9097.0, 9747.658831940847, 9777.657781977596, 9798.167155791838, 9803.607855685692, 9807.166390856857, 8745.693900713444, 8831.205191532792, 8922.0, 8942.821143576963, 8946.239569636653, 8501.787455313419, 8585.957070214608, 8596.35527335465, 8718.869216961624, 8907.153820387053, 7772.028496437945, 7907.525548467111, 7941.682332706858, 7948.0, 7948.443608947318, 5771.971140144273, 5796.971015144897, 5895.734691938787, 5896.49879760208, 5948.345681974893, 5851.561132914963, 5975.0, 5998.25021872266, 6051.848703782441, 6121.0], "value": 5134.3701190101365, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [330.84866645204426, 341.11480707563874, 346.14674826552556, 347.16853136737507, 350.0251798740508, 5639.407862174474, 5672.574556908238, 5868.853278668035, 5888.381719919411, 5944.22725045744, 5637.689927054021, 5721.485066344028, 5749.677574157946, 5780.219670344512, 5799.855003624901, 4513.729176249419, 4541.750203738793, 4560.33875088112, 4574.176648203322, 4576.885577860564, 4434.268345722952, 4458.621017213541, 4488.618467430272, 4500.414946057024, 4573.7713114344315, 4428.7121337112985, 4437.534058923815, 4446.622037126848, 4468.195724769556, 4482.484514280867, 4490.146872094308, 4495.595396414322, 4505.36924830524, 4507.557581573898, 4527.438033878339, 3819.0452386903276, 3896.7467114637457, 3896.941545876814, 3917.745346552465, 3922.2939870823525, 5541.556675465953, 5628.240187574666, 5635.267415236034, 5645.971770141123, 5692.5161361284345, 5018.419197952633, 5128.769205385766, 5132.255822905658, 5140.380780054308, 5143.691378517301, 5969.060055481986, 5999.610025348338, 6041.939580604182, 6044.304904935944, 6055.5761096723245, 4238.194742998851, 4238.258304796679, 4246.0, 4249.915001699979, 4266.0827921996915, 6138.539609529304, 6310.368963103699, 6314.842128946767, 6337.271213810379, 6349.904751428732, 4286.87139385819, 4291.0, 4324.032565182834, 4333.609975102256, 4341.110072435206, 4375.912481750411, 4381.21138195128, 4381.364702118175, 4389.18800021997, 4393.846215382447, 5004.0, 5077.213031980049, 5084.415292241366, 5102.132637451642, 5130.410002849645, 5916.674582897982, 6018.277806663141, 6050.667213303311, 6104.20645316106, 6110.511159107261, 1505.6235941014747, 1534.3479021415853, 1536.078352988198, 1539.661274519604, 1552.95341139766, 947.5877993073035, 960.3229723045237, 974.2985050763439, 975.6829030565095, 978.7259567321162, 5259.894802104013, 5268.552173065255, 5278.709670968134, 5312.256284120226, 5374.381946076252, 5444.128939369682, 5541.251930989345, 5548.9722551387795, 5578.191162281447, 5589.233329501842, 4984.551390374883, 5278.181881808249, 5279.208118782213, 5305.495977882172, 5335.813246536352, 2970.3465237647683, 2995.251187203199, 3002.744766694812, 3008.849557522115, 3020.5318175682364, 5687.800926967537, 5696.715164241771, 5773.383452633269, 5799.3040835100055, 5820.330661973842, 5990.933128070808, 6432.324605916311, 6448.516361272923, 6464.25661048976, 6471.5146364022885, 6564.803055908426, 6576.80269591913, 6584.637844918576, 6591.571547849375, 6607.504437167232, 6053.969730151321, 6249.500039996698, 6273.654948977667, 6301.275353334242, 6301.40136687014, 6184.969075154595, 6185.783497577563, 6200.387899146164, 6227.0, 6262.311145774052, 6402.423781859655, 6514.674266286762, 6576.736930522726, 6586.5389422739945, 6595.307492713196, 4379.189849877783, 4389.604935555815, 4393.253146964959, 4396.714213576107, 4406.162829062468, 6095.140982000533, 6142.723577439116, 6351.269603995414, 6366.745330186741, 6379.202599675041, 6132.5400594956445, 6138.478229350555, 6150.0, 6171.197744293213, 6180.783672571618, 5959.76160953557, 6087.812876489219, 6109.725062372115, 6160.815175544741, 6161.938380616137, 6068.726907289094, 6073.331933487225, 6075.908861366995, 6095.878082438415, 6119.969400152971, 6134.865049965771, 6136.355682653257, 6175.84560385994, 6209.844753881189, 6224.502039836892, 5819.185314055951, 5972.522198224045, 5992.910106348496, 6014.548908831767, 6031.969840150771], "value": 4546.7143682418655, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [769.9076110866694, 804.2922228438973, 809.3039985612376, 816.4162623724039, 914.995425022875, 15372.61568460789, 16480.09359485228, 16507.83492165078, 16573.088480133603, 17668.293268269284, 15368.615784605363, 15590.98658587191, 15593.610159746013, 15606.297716602752, 15680.686386272297, 877.214892671059, 879.463527248378, 892.7991201979568, 908.7909780750431, 909.0863681999581, 905.027095871937, 905.8595917632745, 909.9818003639956, 910.6084383715, 912.8813254276935, 946.9431834089942, 949.9335046546745, 952.6570434643522, 957.7414098193484, 959.7120863740885, 934.3552948465536, 940.7083804020775, 942.3544871762864, 945.0832692288469, 950.0736781637883, 701.5860642221063, 702.4871843554183, 703.9612821294878, 704.0530486495707, 705.4074577355046, 16588.336466541325, 16592.25334859934, 16649.25078371464, 16676.082815445145, 16699.83300166995, 14340.713185736227, 14648.70702585953, 14742.0, 15273.236338183046, 15395.538133856002, 14737.336819843133, 14833.184174870377, 14847.628809279746, 14938.701225975528, 15103.697926041528, 4698.8355407560575, 4835.178019736653, 4893.853184404474, 4963.106640804654, 5065.848024559269, 71404.28595714028, 89369.55315223383, 90748.54625726896, 91198.5440072802, 91875.54062229714, 11977.221480603734, 12686.936565317113, 12882.162659427107, 12960.611181664564, 12990.740185196431, 20695.27566535164, 20704.58590828175, 20878.164873404894, 20892.895535522224, 20961.0, 9346.11211934865, 9424.811503769886, 9525.476098814632, 9702.660406885867, 9839.212862970946, 5084.288199652052, 5097.0, 5112.233165025202, 5157.535821775983, 5251.921221181686, 3955.980220098881, 4124.773137477468, 4126.401671757579, 4127.484064491939, 4141.026858688206, 687.6350444367926, 691.1740470138137, 708.7767353283699, 727.3381223086966, 731.974380896666, 672.9899051514134, 723.2875617516742, 730.7369347035063, 804.601722147529, 832.4422636833336, 9803.264755143251, 10782.305295950157, 10821.0, 10864.293820901616, 10969.396683182342, 585.6047168161474, 621.0001896945913, 720.9927900720926, 765.6975494679571, 838.0, 4952.47998960104, 5187.325647665854, 5242.580593552507, 5276.92084618731, 5293.973530132402, 79708.0, 80819.0, 81429.59285203536, 110229.0, 137936.0, 116410.0, 117781.0, 118322.40838795924, 120278.3986080064, 123245.0, 98923.0, 99052.50473747586, 101492.49253753683, 101746.98253017523, 101827.49086254521, 9877.604895804376, 10061.597536098474, 10100.494975251355, 10120.696379108638, 10169.338992965433, 44675.32987005263, 48938.26592601185, 60220.69889650524, 61016.694916525135, 61312.386876132456, 75097.62451187709, 76284.0, 80490.59754701189, 81545.59227203827, 82360.0, 13025.934870325587, 13254.933725331312, 13522.594322170351, 13647.522336718168, 13858.16850988944, 16044.839551604335, 16054.43809466663, 16320.510384688478, 17225.0, 17580.296788128842, 14896.329665164876, 15102.093874367572, 15858.920705396398, 15866.60333491672, 15873.603159921095, 15080.0, 15446.5366039019, 15473.767893481832, 15475.071495710294, 15694.2937567812, 13892.16647001183, 14161.575152745434, 14205.0, 15482.845171548592, 15738.606534836721, 12223.877761222275, 12370.690732731753, 12549.686257843627, 12608.243505389706, 13080.80378794338, 12265.489201014736, 12372.62882113502, 12519.624411267676, 12537.749245014866, 12836.807447888476], "value": 8300.53968604916, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1500.6999999999998, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [99.0, 110.0, 105.0, 110.0, 111.0, 114.0, 111.0, 111.0, 111.0, 111.0], "value": 109.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 121.0, 142.0, 162.0, 116.0, 140.0, 148.0, 116.0, 168.0, 116.0], "value": 137.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 161.0, 162.0, 161.0, 163.0, 164.0, 164.0, 163.0, 161.0, 162.0], "value": 162.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 76.0, 76.0, 176.0, 77.0, 75.0, 74.0, 75.0, 180.0, 75.0], "value": 95.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 84.0, 155.0, 86.0, 83.0, 85.0, 146.0, 83.0, 86.0, 137.0], "value": 103.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 118.0, 117.0, 118.0, 118.0, 118.0, 118.0, 117.0, 117.0, 117.0], "value": 117.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 147.0, 147.0, 147.0, 147.0, 148.0, 148.0, 148.0, 148.0, 147.0], "value": 147.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [97.0, 96.0, 96.0, 96.0, 95.0, 99.0, 96.0, 96.0, 96.0, 96.0], "value": 96.3, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [51.0, 57.0, 56.0, 54.0, 52.0, 52.0, 52.0, 54.0, 52.0, 52.0], "value": 53.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 60.0, 60.0, 60.0, 60.0, 60.0, 60.0, 60.0, 60.0, 60.0], "value": 60.3, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 71.0, 78.0, 72.0, 71.0, 72.0, 72.0, 73.0, 75.0, 73.0], "value": 73.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [127.0, 122.0, 121.0, 139.0, 122.0, 123.0, 130.0, 116.0, 147.0, 123.0], "value": 127.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [153.0, 157.0, 158.0, 150.0, 156.0, 151.0, 158.0, 154.0, 153.0, 152.0], "value": 154.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 63.0, 62.0, 63.0, 62.0, 63.0, 62.0, 63.0, 67.0, 61.0], "value": 63.1, "unit": "score"}]}]} 20:36:03 INFO - Return code: 0 20:36:03 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 20:36:03 ERROR - # TBPL SUCCESS # 20:36:03 INFO - Running post-action listener: _resource_record_post_action 20:36:03 INFO - [mozharness: 2016-09-05 03:36:03.302686Z] Finished run-tests step (success) 20:36:03 INFO - Running post-run listener: _resource_record_post_run 20:36:03 INFO - Total resource usage - Wall time: 1188s; CPU: 13.0%; Read bytes: 4096; Write bytes: 329961472; Read time: 16; Write time: 200044 20:36:03 INFO - TinderboxPrint: CPU usage
13.2% 20:36:03 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 16 20:36:03 INFO - TinderboxPrint: I/O write bytes / time
329,961,472 / 200,044 20:36:03 INFO - TinderboxPrint: CPU idle
8,224.0 (86.8%) 20:36:03 INFO - TinderboxPrint: CPU user
1,199.1 (12.7%) 20:36:03 INFO - TinderboxPrint: Swap in / out
0 / 0 20:36:03 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 78782464; Read time: 0; Write time: 42528 20:36:03 INFO - run-tests - Wall time: 1172s; CPU: 13.0%; Read bytes: 4096; Write bytes: 250564608; Read time: 16; Write time: 157516 20:36:03 INFO - Running post-run listener: _upload_blobber_files 20:36:03 INFO - Blob upload gear active. 20:36:03 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 20:36:03 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 20:36:03 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-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 20:36:03 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-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 20:36:04 INFO - (blobuploader) - INFO - Open directory for files ... 20:36:04 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 20:36:04 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 20:36:04 INFO - (blobuploader) - INFO - Uploading, attempt #1. 20:36:05 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 20:36:05 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 20:36:05 INFO - (blobuploader) - INFO - Done attempting. 20:36:05 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 20:36:05 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 20:36:05 INFO - (blobuploader) - INFO - Uploading, attempt #1. 20:36:05 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 20:36:05 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 20:36:05 INFO - (blobuploader) - INFO - Done attempting. 20:36:05 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 20:36:05 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 20:36:05 INFO - (blobuploader) - INFO - Uploading, attempt #1. 20:36:08 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 20:36:08 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 20:36:08 INFO - (blobuploader) - INFO - Done attempting. 20:36:08 INFO - (blobuploader) - INFO - Iteration through files over. 20:36:08 INFO - Return code: 0 20:36:08 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 20:36:08 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 20:36:08 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/abdcd1255dd40e36110713994a45999693157aee589dfd05cc39fe98e12143abc808084fb7250991c5bd740fa0baff767255a1a0cecb193c53cf793f5a9574ba", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/0a7dbac492073cd28fdbc5a953255df6c5ca02f88c7cc2641b454109cd6ad3cac3fe6f8c2ee297f9e71e17ee4603a87984d8382a509794e7e29408192dac092d", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c5cb7f2773a6c529f33dea49a8661938b6fe2492ba22c1b1519ad23179159935a26aad00ff52afcadfc5bdd896f1b7c64493b309ac514a894f89b078798655ca"} 20:36:08 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 20:36:08 INFO - Writing to file /builds/slave/test/properties/blobber_files 20:36:08 INFO - Contents: 20:36:08 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/abdcd1255dd40e36110713994a45999693157aee589dfd05cc39fe98e12143abc808084fb7250991c5bd740fa0baff767255a1a0cecb193c53cf793f5a9574ba", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/0a7dbac492073cd28fdbc5a953255df6c5ca02f88c7cc2641b454109cd6ad3cac3fe6f8c2ee297f9e71e17ee4603a87984d8382a509794e7e29408192dac092d", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c5cb7f2773a6c529f33dea49a8661938b6fe2492ba22c1b1519ad23179159935a26aad00ff52afcadfc5bdd896f1b7c64493b309ac514a894f89b078798655ca"} 20:36:08 INFO - Running post-run listener: copy_logs_to_upload_dir 20:36:08 INFO - Copying logs to upload dir... 20:36:08 INFO - mkdir: /builds/slave/test/build/upload/logs 20:36:08 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1676.476040 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 27 mins, 56 secs) (at 2016-09-04 20:36:07.361564) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-04 20:36:07.365114) ========= 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=7fc86f70bae03e018da0b1d8000001c4-1473033471.633764-940869890 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/abdcd1255dd40e36110713994a45999693157aee589dfd05cc39fe98e12143abc808084fb7250991c5bd740fa0baff767255a1a0cecb193c53cf793f5a9574ba", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/0a7dbac492073cd28fdbc5a953255df6c5ca02f88c7cc2641b454109cd6ad3cac3fe6f8c2ee297f9e71e17ee4603a87984d8382a509794e7e29408192dac092d", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c5cb7f2773a6c529f33dea49a8661938b6fe2492ba22c1b1519ad23179159935a26aad00ff52afcadfc5bdd896f1b7c64493b309ac514a894f89b078798655ca"} build_url:https://queue.taskcluster.net/v1/task/EafjAuEhRbCkfE34LowciQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011511 build_url: 'https://queue.taskcluster.net/v1/task/EafjAuEhRbCkfE34LowciQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/abdcd1255dd40e36110713994a45999693157aee589dfd05cc39fe98e12143abc808084fb7250991c5bd740fa0baff767255a1a0cecb193c53cf793f5a9574ba", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/0a7dbac492073cd28fdbc5a953255df6c5ca02f88c7cc2641b454109cd6ad3cac3fe6f8c2ee297f9e71e17ee4603a87984d8382a509794e7e29408192dac092d", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c5cb7f2773a6c529f33dea49a8661938b6fe2492ba22c1b1519ad23179159935a26aad00ff52afcadfc5bdd896f1b7c64493b309ac514a894f89b078798655ca"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-04 20:36:07.393453) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-04 20:36:07.394035) ========= 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=7fc86f70bae03e018da0b1d8000001c4-1473033471.633764-940869890 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004481 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-04 20:36:07.473595) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-04 20:36:07.473949) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-04 20:36:07.474258) ========= ========= Total master_lag: 0.18 =========