builder: mozilla-aurora_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-054 starttime: 1474255329.54 results: success (0) buildid: 20160918173718 builduid: c62987ef87904a68aa0d432851135612 revision: 896a7672b83764829bbfc93e285d03bf318d3a5b ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-09-18 20:22:09.537361) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-09-18 20:22:09.537819) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-18 20:22:09.538116) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-18 20:22:09.599638) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-18 20:22:09.600230) ========= 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=cbe409c6de289db6ac707de3000001d4-1474254081.944973-20371291 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-09-18 20:22:10-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 221M=0s 2016-09-18 20:22:10 (221 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.108486 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-18 20:22:09.722412) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-18 20:22:09.722716) ========= 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=cbe409c6de289db6ac707de3000001d4-1474254081.944973-20371291 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.014231 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-18 20:22:09.772249) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-18 20:22:09.772579) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 896a7672b83764829bbfc93e285d03bf318d3a5b --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 896a7672b83764829bbfc93e285d03bf318d3a5b --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=cbe409c6de289db6ac707de3000001d4-1474254081.944973-20371291 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-09-18 20:22:10,291 truncating revision to first 12 chars 2016-09-18 20:22:10,291 Setting DEBUG logging. 2016-09-18 20:22:10,291 attempt 1/10 2016-09-18 20:22:10,291 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/896a7672b837?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-09-18 20:22:10,714 unpacking tar archive at: mozilla-aurora-896a7672b837/testing/mozharness/ program finished with exit code 0 elapsedTime=0.774075 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-18 20:22:10.563714) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-18 20:22:10.564003) ========= script_repo_revision: 896a7672b83764829bbfc93e285d03bf318d3a5b ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-18 20:22:10.564360) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-18 20:22:10.564638) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-18 20:22:10.577089) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 28 mins, 0 secs) (at 2016-09-18 20:22:10.577357) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --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', '--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=cbe409c6de289db6ac707de3000001d4-1474254081.944973-20371291 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 20:22:11 INFO - MultiFileLogger online at 20160918 20:22:11 in /builds/slave/test 20:22:11 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --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:22:11 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 20:22:11 INFO - {'append_to_log': False, 20:22:11 INFO - 'base_work_dir': '/builds/slave/test', 20:22:11 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 20:22:11 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 20:22:11 INFO - 'branch': 'Mozilla-Aurora', 20:22:11 INFO - 'buildbot_json_path': 'buildprops.json', 20:22:11 INFO - 'config_files': ('talos/linux_config.py',), 20:22:11 INFO - 'default_actions': ('clobber', 20:22:11 INFO - 'read-buildbot-config', 20:22:11 INFO - 'download-and-extract', 20:22:11 INFO - 'populate-webroot', 20:22:11 INFO - 'create-virtualenv', 20:22:11 INFO - 'install', 20:22:11 INFO - 'run-tests'), 20:22:11 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 20:22:11 INFO - 'download_minidump_stackwalk': True, 20:22:11 INFO - 'download_symbols': 'ondemand', 20:22:11 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 20:22:11 INFO - 'tooltool.py': '/tools/tooltool.py', 20:22:11 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 20:22:11 INFO - '/tools/misc-python/virtualenv.py')}, 20:22:11 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 20:22:11 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 20:22:11 INFO - 'installer_path': 'installer.exe', 20:22:11 INFO - 'log_level': 'info', 20:22:11 INFO - 'log_name': 'talos', 20:22:11 INFO - 'log_to_console': True, 20:22:11 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 20:22:11 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 20:22:11 INFO - 'opt_config_files': (), 20:22:11 INFO - 'pip_index': False, 20:22:11 INFO - 'sps_profile': False, 20:22:11 INFO - 'sps_profile_interval': 0, 20:22:11 INFO - 'suite': 'dromaeojs', 20:22:11 INFO - 'system_bits': '32', 20:22:11 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 20:22:11 INFO - 'title': 'talos-linux64-ix-054', 20:22:11 INFO - 'tooltool_cache': '/builds/tooltool_cache', 20:22:11 INFO - 'use_talos_json': True, 20:22:11 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 20:22:11 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 20:22:11 INFO - 'work_dir': 'build'} 20:22:11 INFO - [mozharness: 2016-09-19 03:22:11.186402Z] Running clobber step. 20:22:11 INFO - Running pre-action listener: _resource_record_pre_action 20:22:11 INFO - Running main action method: clobber 20:22:11 INFO - rmtree: /builds/slave/test/build 20:22:11 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 20:22:12 INFO - Running post-action listener: _resource_record_post_action 20:22:12 INFO - [mozharness: 2016-09-19 03:22:12.148086Z] Finished clobber step (success) 20:22:12 INFO - [mozharness: 2016-09-19 03:22:12.148187Z] Running read-buildbot-config step. 20:22:12 INFO - Running pre-action listener: _resource_record_pre_action 20:22:12 INFO - Running main action method: read_buildbot_config 20:22:12 INFO - Using buildbot properties: 20:22:12 INFO - { 20:22:12 INFO - "project": "", 20:22:12 INFO - "product": "firefox", 20:22:12 INFO - "script_repo_revision": "production", 20:22:12 INFO - "builddir": "mozilla-aurora_ubuntu64_hw_test-dromaeojs", 20:22:12 INFO - "repository": "", 20:22:12 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-aurora pgo talos dromaeojs", 20:22:12 INFO - "buildid": "20160918173718", 20:22:12 INFO - "pgo_build": "True", 20:22:12 INFO - "basedir": "/builds/slave/test", 20:22:12 INFO - "buildnumber": 756, 20:22:12 INFO - "slavename": "talos-linux64-ix-054", 20:22:12 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 20:22:12 INFO - "platform": "ubuntu64_hw", 20:22:12 INFO - "scheduler": "tests-mozilla-aurora-linux64-pgo-talos", 20:22:12 INFO - "branch": "mozilla-aurora", 20:22:12 INFO - "repo_path": "releases/mozilla-aurora", 20:22:12 INFO - "revision": "896a7672b83764829bbfc93e285d03bf318d3a5b", 20:22:12 INFO - "stage_platform": "linux64-pgo", 20:22:12 INFO - "builduid": "c62987ef87904a68aa0d432851135612", 20:22:12 INFO - "slavebuilddir": "test" 20:22:12 INFO - } 20:22:12 INFO - Found installer url https://queue.taskcluster.net/v1/task/ePIxxufxTeCzWVOohoB-9w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2. 20:22:12 INFO - Running post-action listener: _resource_record_post_action 20:22:12 INFO - [mozharness: 2016-09-19 03:22:12.150629Z] Finished read-buildbot-config step (success) 20:22:12 INFO - [mozharness: 2016-09-19 03:22:12.150718Z] Running download-and-extract step. 20:22:12 INFO - Running pre-action listener: _resource_record_pre_action 20:22:12 INFO - Running main action method: download_and_extract 20:22:12 INFO - mkdir: /builds/slave/test/build/tests 20:22:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:22:12 INFO - https://queue.taskcluster.net/v1/task/ePIxxufxTeCzWVOohoB-9w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 20:22:12 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ePIxxufxTeCzWVOohoB-9w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 20:22:12 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ePIxxufxTeCzWVOohoB-9w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 20:22:12 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ePIxxufxTeCzWVOohoB-9w/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:22:12 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ePIxxufxTeCzWVOohoB-9w/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:22:12 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ePIxxufxTeCzWVOohoB-9w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 20:22:12 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 20:22:12 INFO - retry: Failed, sleeping 30 seconds before retrying 20:22:42 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ePIxxufxTeCzWVOohoB-9w/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:22:42 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ePIxxufxTeCzWVOohoB-9w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 20:22:42 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 20:22:42 INFO - retry: Failed, sleeping 60 seconds before retrying 20:23:42 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ePIxxufxTeCzWVOohoB-9w/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:23:42 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ePIxxufxTeCzWVOohoB-9w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 20:23:42 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 20:23:42 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ePIxxufxTeCzWVOohoB-9w/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:23:42 INFO - Caught exception: HTTP Error 404: Not Found 20:23:42 INFO - Caught exception: HTTP Error 404: Not Found 20:23:42 INFO - Caught exception: HTTP Error 404: Not Found 20:23:42 INFO - trying https://queue.taskcluster.net/v1/task/ePIxxufxTeCzWVOohoB-9w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 20:23:42 INFO - Downloading https://queue.taskcluster.net/v1/task/ePIxxufxTeCzWVOohoB-9w/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:23:42 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/ePIxxufxTeCzWVOohoB-9w/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:23:42 INFO - Downloaded 1361 bytes. 20:23:42 INFO - Reading from file /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 20:23:42 INFO - Using the following test package requirements: 20:23:42 INFO - {u'common': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'], 20:23:42 INFO - u'cppunittest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 20:23:42 INFO - u'firefox-50.0a2.en-US.linux-x86_64.cppunittest.tests.zip'], 20:23:42 INFO - u'gtest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 20:23:42 INFO - u'firefox-50.0a2.en-US.linux-x86_64.gtest.tests.zip'], 20:23:42 INFO - u'jittest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 20:23:42 INFO - u'jsshell-linux-x86_64.zip'], 20:23:42 INFO - u'mochitest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 20:23:42 INFO - u'firefox-50.0a2.en-US.linux-x86_64.mochitest.tests.zip'], 20:23:42 INFO - u'mozbase': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'], 20:23:42 INFO - u'reftest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 20:23:42 INFO - u'firefox-50.0a2.en-US.linux-x86_64.reftest.tests.zip'], 20:23:42 INFO - u'talos': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 20:23:42 INFO - u'firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip'], 20:23:42 INFO - u'web-platform': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 20:23:42 INFO - u'firefox-50.0a2.en-US.linux-x86_64.web-platform.tests.zip'], 20:23:42 INFO - u'xpcshell': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 20:23:42 INFO - u'firefox-50.0a2.en-US.linux-x86_64.xpcshell.tests.zip']} 20:23:42 INFO - Downloading packages: [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 20:23:42 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:23:42 INFO - https://queue.taskcluster.net/v1/task/ePIxxufxTeCzWVOohoB-9w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 20:23:42 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ePIxxufxTeCzWVOohoB-9w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 20:23:42 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ePIxxufxTeCzWVOohoB-9w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 20:23:42 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ePIxxufxTeCzWVOohoB-9w/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:23:42 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ePIxxufxTeCzWVOohoB-9w/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:23:42 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ePIxxufxTeCzWVOohoB-9w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 20:23:42 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 20:23:42 INFO - retry: Failed, sleeping 30 seconds before retrying 20:24:12 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ePIxxufxTeCzWVOohoB-9w/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:24:12 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ePIxxufxTeCzWVOohoB-9w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 20:24:12 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 20:24:12 INFO - retry: Failed, sleeping 60 seconds before retrying 20:25:12 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ePIxxufxTeCzWVOohoB-9w/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:25:12 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ePIxxufxTeCzWVOohoB-9w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 20:25:12 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 20:25:12 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ePIxxufxTeCzWVOohoB-9w/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:25:12 INFO - Caught exception: HTTP Error 404: Not Found 20:25:12 INFO - Caught exception: HTTP Error 404: Not Found 20:25:12 INFO - Caught exception: HTTP Error 404: Not Found 20:25:12 INFO - trying https://queue.taskcluster.net/v1/task/ePIxxufxTeCzWVOohoB-9w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 20:25:12 INFO - Downloading https://queue.taskcluster.net/v1/task/ePIxxufxTeCzWVOohoB-9w/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:25:12 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/ePIxxufxTeCzWVOohoB-9w/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:25:14 INFO - Downloaded 21770968 bytes. 20:25:14 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:25:14 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:25:15 INFO - Return code: 0 20:25:15 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:25:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:25:15 INFO - https://queue.taskcluster.net/v1/task/ePIxxufxTeCzWVOohoB-9w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 20:25:15 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ePIxxufxTeCzWVOohoB-9w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 20:25:15 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ePIxxufxTeCzWVOohoB-9w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 20:25:15 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ePIxxufxTeCzWVOohoB-9w/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:25:15 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ePIxxufxTeCzWVOohoB-9w/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:25:15 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ePIxxufxTeCzWVOohoB-9w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 20:25:15 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 20:25:15 INFO - retry: Failed, sleeping 30 seconds before retrying 20:25:45 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ePIxxufxTeCzWVOohoB-9w/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:25:45 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ePIxxufxTeCzWVOohoB-9w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 20:25:45 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 20:25:45 INFO - retry: Failed, sleeping 60 seconds before retrying 20:26:45 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ePIxxufxTeCzWVOohoB-9w/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:26:45 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ePIxxufxTeCzWVOohoB-9w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 20:26:45 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 20:26:45 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ePIxxufxTeCzWVOohoB-9w/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:26:45 INFO - Caught exception: HTTP Error 404: Not Found 20:26:45 INFO - Caught exception: HTTP Error 404: Not Found 20:26:45 INFO - Caught exception: HTTP Error 404: Not Found 20:26:45 INFO - trying https://queue.taskcluster.net/v1/task/ePIxxufxTeCzWVOohoB-9w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 20:26:45 INFO - Downloading https://queue.taskcluster.net/v1/task/ePIxxufxTeCzWVOohoB-9w/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:26:45 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/ePIxxufxTeCzWVOohoB-9w/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:26:46 INFO - Downloaded 21770968 bytes. 20:26:46 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:26:46 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:26:48 INFO - Return code: 0 20:26:48 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:26:48 INFO - https://queue.taskcluster.net/v1/task/ePIxxufxTeCzWVOohoB-9w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 20:26:48 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ePIxxufxTeCzWVOohoB-9w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 20:26:48 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ePIxxufxTeCzWVOohoB-9w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 20:26:48 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ePIxxufxTeCzWVOohoB-9w/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:26:48 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ePIxxufxTeCzWVOohoB-9w/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:26:48 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ePIxxufxTeCzWVOohoB-9w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 20:26:48 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 20:26:48 INFO - retry: Failed, sleeping 30 seconds before retrying 20:27:18 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ePIxxufxTeCzWVOohoB-9w/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:27:18 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ePIxxufxTeCzWVOohoB-9w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 20:27:18 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 20:27:18 INFO - retry: Failed, sleeping 60 seconds before retrying 20:28:18 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ePIxxufxTeCzWVOohoB-9w/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:28:18 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ePIxxufxTeCzWVOohoB-9w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 20:28:18 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 20:28:18 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ePIxxufxTeCzWVOohoB-9w/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:28:18 INFO - Caught exception: HTTP Error 404: Not Found 20:28:18 INFO - Caught exception: HTTP Error 404: Not Found 20:28:18 INFO - Caught exception: HTTP Error 404: Not Found 20:28:18 INFO - trying https://queue.taskcluster.net/v1/task/ePIxxufxTeCzWVOohoB-9w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 20:28:18 INFO - Downloading https://queue.taskcluster.net/v1/task/ePIxxufxTeCzWVOohoB-9w/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:28:18 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/ePIxxufxTeCzWVOohoB-9w/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:28:19 INFO - Downloaded 14413740 bytes. 20:28:19 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:28:19 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:28:19 INFO - Return code: 0 20:28:19 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:28:19 INFO - https://queue.taskcluster.net/v1/task/ePIxxufxTeCzWVOohoB-9w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 20:28:19 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ePIxxufxTeCzWVOohoB-9w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 20:28:19 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ePIxxufxTeCzWVOohoB-9w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 20:28:19 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ePIxxufxTeCzWVOohoB-9w/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:28:19 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ePIxxufxTeCzWVOohoB-9w/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:28:19 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ePIxxufxTeCzWVOohoB-9w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 20:28:19 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 20:28:19 INFO - retry: Failed, sleeping 30 seconds before retrying 20:28:49 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ePIxxufxTeCzWVOohoB-9w/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:28:49 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ePIxxufxTeCzWVOohoB-9w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 20:28:49 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 20:28:49 INFO - retry: Failed, sleeping 60 seconds before retrying 20:29:50 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ePIxxufxTeCzWVOohoB-9w/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:29:50 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ePIxxufxTeCzWVOohoB-9w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 20:29:50 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 20:29:50 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ePIxxufxTeCzWVOohoB-9w/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:29:50 INFO - Caught exception: HTTP Error 404: Not Found 20:29:50 INFO - Caught exception: HTTP Error 404: Not Found 20:29:50 INFO - Caught exception: HTTP Error 404: Not Found 20:29:50 INFO - trying https://queue.taskcluster.net/v1/task/ePIxxufxTeCzWVOohoB-9w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 20:29:50 INFO - Downloading https://queue.taskcluster.net/v1/task/ePIxxufxTeCzWVOohoB-9w/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:29:50 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/ePIxxufxTeCzWVOohoB-9w/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:29:51 INFO - Downloaded 63903027 bytes. 20:29:51 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/ePIxxufxTeCzWVOohoB-9w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 20:29:51 INFO - mkdir: /builds/slave/test/properties 20:29:51 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 20:29:51 INFO - Writing to file /builds/slave/test/properties/build_url 20:29:51 INFO - Contents: 20:29:51 INFO - build_url:https://queue.taskcluster.net/v1/task/ePIxxufxTeCzWVOohoB-9w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 20:29:51 INFO - Running post-action listener: _resource_record_post_action 20:29:51 INFO - Running post-action listener: set_extra_try_arguments 20:29:51 INFO - [mozharness: 2016-09-19 03:29:51.951065Z] Finished download-and-extract step (success) 20:29:51 INFO - [mozharness: 2016-09-19 03:29:51.951300Z] Running populate-webroot step. 20:29:51 INFO - Running pre-action listener: _resource_record_pre_action 20:29:51 INFO - Running main action method: populate_webroot 20:29:51 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 20:29:51 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 20:29:51 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 20:29:51 INFO - '--tppagecycles', 20:29:51 INFO - '7'], 20:29:51 INFO - 'tests': ['tsvgm']}}, 20:29:51 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 20:29:51 INFO - 'tests': ['tresize', 'tcanvasmark']}, 20:29:51 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 20:29:51 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 20:29:51 INFO - 'tests': ['dromaeo_css', 'kraken']}, 20:29:51 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 20:29:51 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:29:51 INFO - 'talos_options': ['--disable-e10s'], 20:29:51 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 20:29:51 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:29:51 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 20:29:51 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:29:51 INFO - 'talos_options': ['--disable-e10s'], 20:29:51 INFO - 'tests': ['damp', 'tps']}, 20:29:51 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:29:51 INFO - 'tests': ['damp', 'tps']}, 20:29:51 INFO - 'g3': {'talos_options': ['--disable-e10s'], 20:29:51 INFO - 'tests': ['dromaeo_dom']}, 20:29:51 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 20:29:51 INFO - 'g4': {'talos_options': ['--disable-e10s'], 20:29:51 INFO - 'tests': ['basic_compositor_video']}, 20:29:51 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 20:29:51 INFO - 'other': {'talos_options': ['--disable-e10s'], 20:29:51 INFO - 'tests': ['a11yr', 20:29:51 INFO - 'ts_paint', 20:29:51 INFO - 'tpaint', 20:29:51 INFO - 'sessionrestore', 20:29:51 INFO - 'sessionrestore_no_auto_restore', 20:29:51 INFO - 'tabpaint']}, 20:29:51 INFO - 'other-e10s': {'tests': ['a11yr', 20:29:51 INFO - 'ts_paint', 20:29:51 INFO - 'tpaint', 20:29:51 INFO - 'sessionrestore', 20:29:51 INFO - 'sessionrestore_no_auto_restore', 20:29:51 INFO - 'tabpaint']}, 20:29:51 INFO - 'other-e10s_l64': {'tests': ['a11yr', 20:29:51 INFO - 'ts_paint', 20:29:51 INFO - 'tpaint', 20:29:51 INFO - 'sessionrestore', 20:29:51 INFO - 'sessionrestore_no_auto_restore', 20:29:51 INFO - 'tabpaint']}, 20:29:51 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 20:29:51 INFO - 'ts_paint', 20:29:51 INFO - 'tpaint', 20:29:51 INFO - 'sessionrestore', 20:29:51 INFO - 'sessionrestore_no_auto_restore', 20:29:51 INFO - 'tabpaint']}, 20:29:51 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 20:29:51 INFO - 'tests': ['a11yr', 20:29:51 INFO - 'ts_paint', 20:29:51 INFO - 'tpaint', 20:29:51 INFO - 'sessionrestore', 20:29:51 INFO - 'sessionrestore_no_auto_restore', 20:29:51 INFO - 'tabpaint']}, 20:29:51 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 20:29:51 INFO - 'tests': ['a11yr', 20:29:51 INFO - 'ts_paint', 20:29:51 INFO - 'tpaint', 20:29:51 INFO - 'sessionrestore', 20:29:51 INFO - 'sessionrestore_no_auto_restore', 20:29:51 INFO - 'tabpaint']}, 20:29:51 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 20:29:51 INFO - 'tests': ['tsvgx', 20:29:51 INFO - 'tsvgr_opacity', 20:29:51 INFO - 'tart', 20:29:51 INFO - 'tscrollx', 20:29:51 INFO - 'cart']}, 20:29:51 INFO - 'svgr-e10s': {'tests': ['tsvgx', 20:29:51 INFO - 'tsvgr_opacity', 20:29:51 INFO - 'tart', 20:29:51 INFO - 'tscrollx', 20:29:51 INFO - 'cart']}, 20:29:51 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:29:51 INFO - 'talos_options': ['--disable-e10s'], 20:29:51 INFO - 'tests': ['tp5o']}, 20:29:51 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:29:51 INFO - 'tests': ['tp5o']}, 20:29:51 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:29:51 INFO - 'talos_options': ['--disable-e10s', 20:29:51 INFO - '--xperf_path', 20:29:51 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 20:29:51 INFO - 'tests': ['tp5n']}, 20:29:51 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:29:51 INFO - 'talos_options': ['--xperf_path', 20:29:51 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 20:29:51 INFO - 'tests': ['tp5n']}}, 20:29:51 INFO - 'talos.zip': {'path': '', 20:29:51 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 20:29:51 INFO - Running post-action listener: _resource_record_post_action 20:29:51 INFO - [mozharness: 2016-09-19 03:29:51.968477Z] Finished populate-webroot step (success) 20:29:51 INFO - [mozharness: 2016-09-19 03:29:51.968567Z] Running create-virtualenv step. 20:29:51 INFO - Running pre-action listener: _resource_record_pre_action 20:29:51 INFO - Running main action method: create_virtualenv 20:29:51 INFO - Creating virtualenv /builds/slave/test/build/venv 20:29:51 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:29:51 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 20:29:52 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 20:29:52 INFO - Using real prefix '/usr' 20:29:52 INFO - New python executable in /builds/slave/test/build/venv/bin/python 20:29:52 INFO - Installing distribute.............................................................................................................................................................................................done. 20:29:54 INFO - Installing pip.................done. 20:29:54 INFO - Return code: 0 20:29:54 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 20:29:54 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:29:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:29:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:29:54 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:29:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:29:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:29:54 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 0x1bfa030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d5d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ebb180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1eb93b0>, '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 0x1eba2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1eba740>, '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': 'cbe409c6de289db6ac707de3000001d4-1474254081.944973-20371291', '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:29:54 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:29:54 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:29:54 INFO - Using env: {'DISPLAY': ':0', 20:29:54 INFO - 'HOME': '/home/cltbld', 20:29:54 INFO - 'LANG': 'en_US.UTF-8', 20:29:54 INFO - 'LANGUAGE': 'en_US:en', 20:29:54 INFO - 'LOGNAME': 'cltbld', 20:29:54 INFO - 'MAIL': '/var/mail/cltbld', 20:29:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:29:54 INFO - 'MOZ_NO_REMOTE': '1', 20:29:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:29:54 INFO - 'NO_EM_RESTART': '1', 20:29:54 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:29:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:29:54 INFO - 'PWD': '/builds/slave/test', 20:29:54 INFO - 'SHELL': '/bin/bash', 20:29:54 INFO - 'SHLVL': '1', 20:29:54 INFO - 'TERM': 'linux', 20:29:54 INFO - 'TMOUT': '86400', 20:29:54 INFO - 'USER': 'cltbld', 20:29:54 INFO - 'XDG_SESSION_COOKIE': 'cbe409c6de289db6ac707de3000001d4-1474254081.944973-20371291', 20:29:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:29:54 INFO - '_': '/tools/buildbot/bin/python'} 20:29:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:29:54 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:29:54 INFO - Cleaning up... 20:29:54 INFO - Return code: 0 20:29:54 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 20:29:54 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:29:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:29:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:29:54 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:29:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:29:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:29:54 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 0x1bfa030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d5d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ebb180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1eb93b0>, '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 0x1eba2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1eba740>, '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': 'cbe409c6de289db6ac707de3000001d4-1474254081.944973-20371291', '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:29:54 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:29:54 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:29:54 INFO - Using env: {'DISPLAY': ':0', 20:29:54 INFO - 'HOME': '/home/cltbld', 20:29:54 INFO - 'LANG': 'en_US.UTF-8', 20:29:54 INFO - 'LANGUAGE': 'en_US:en', 20:29:54 INFO - 'LOGNAME': 'cltbld', 20:29:54 INFO - 'MAIL': '/var/mail/cltbld', 20:29:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:29:54 INFO - 'MOZ_NO_REMOTE': '1', 20:29:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:29:54 INFO - 'NO_EM_RESTART': '1', 20:29:54 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:29:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:29:54 INFO - 'PWD': '/builds/slave/test', 20:29:54 INFO - 'SHELL': '/bin/bash', 20:29:54 INFO - 'SHLVL': '1', 20:29:54 INFO - 'TERM': 'linux', 20:29:54 INFO - 'TMOUT': '86400', 20:29:54 INFO - 'USER': 'cltbld', 20:29:54 INFO - 'XDG_SESSION_COOKIE': 'cbe409c6de289db6ac707de3000001d4-1474254081.944973-20371291', 20:29:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:29:54 INFO - '_': '/tools/buildbot/bin/python'} 20:29:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:29:54 INFO - Downloading/unpacking psutil>=3.1.1 20:29:54 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:29:54 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:29:54 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:29:54 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:29:54 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:29:54 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:29:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 20:29:56 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 20:29:56 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 20:29:56 INFO - Installing collected packages: psutil 20:29:56 INFO - Running setup.py install for psutil 20:29:57 INFO - building 'psutil._psutil_linux' extension 20:29: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:29:57 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:29:57 INFO - building 'psutil._psutil_posix' extension 20:29:57 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:29:57 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:29:57 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 20:29:57 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 20:29:57 INFO - Successfully installed psutil 20:29:57 INFO - Cleaning up... 20:29:57 INFO - Return code: 0 20:29:57 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 20:29:57 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:29:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:29:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:29:57 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:29:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:29:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:29:57 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 0x1bfa030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d5d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ebb180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1eb93b0>, '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 0x1eba2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1eba740>, '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': 'cbe409c6de289db6ac707de3000001d4-1474254081.944973-20371291', '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:29:57 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:29:57 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:29:57 INFO - Using env: {'DISPLAY': ':0', 20:29:57 INFO - 'HOME': '/home/cltbld', 20:29:57 INFO - 'LANG': 'en_US.UTF-8', 20:29:57 INFO - 'LANGUAGE': 'en_US:en', 20:29:57 INFO - 'LOGNAME': 'cltbld', 20:29:57 INFO - 'MAIL': '/var/mail/cltbld', 20:29:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:29:57 INFO - 'MOZ_NO_REMOTE': '1', 20:29:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:29:57 INFO - 'NO_EM_RESTART': '1', 20:29:57 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:29:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:29:57 INFO - 'PWD': '/builds/slave/test', 20:29:57 INFO - 'SHELL': '/bin/bash', 20:29:57 INFO - 'SHLVL': '1', 20:29:57 INFO - 'TERM': 'linux', 20:29:57 INFO - 'TMOUT': '86400', 20:29:57 INFO - 'USER': 'cltbld', 20:29:57 INFO - 'XDG_SESSION_COOKIE': 'cbe409c6de289db6ac707de3000001d4-1474254081.944973-20371291', 20:29:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:29:57 INFO - '_': '/tools/buildbot/bin/python'} 20:29:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:29:57 INFO - Downloading/unpacking mozsystemmonitor==0.3 20:29:57 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:29:57 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:29:57 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:29:57 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:29:57 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:29:57 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:29:59 INFO - Downloading mozsystemmonitor-0.3.tar.gz 20:29:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 20:29:59 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 20:29:59 INFO - Installing collected packages: mozsystemmonitor 20:29:59 INFO - Running setup.py install for mozsystemmonitor 20:29:59 INFO - Successfully installed mozsystemmonitor 20:29:59 INFO - Cleaning up... 20:29:59 INFO - Return code: 0 20:29:59 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 20:29:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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:29:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:29:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:29:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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:29:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:29:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:29:59 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 0x1bfa030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d5d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ebb180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1eb93b0>, '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 0x1eba2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1eba740>, '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': 'cbe409c6de289db6ac707de3000001d4-1474254081.944973-20371291', '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:29:59 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:29:59 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:29:59 INFO - Using env: {'DISPLAY': ':0', 20:29:59 INFO - 'HOME': '/home/cltbld', 20:29:59 INFO - 'LANG': 'en_US.UTF-8', 20:29:59 INFO - 'LANGUAGE': 'en_US:en', 20:29:59 INFO - 'LOGNAME': 'cltbld', 20:29:59 INFO - 'MAIL': '/var/mail/cltbld', 20:29:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:29:59 INFO - 'MOZ_NO_REMOTE': '1', 20:29:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:29:59 INFO - 'NO_EM_RESTART': '1', 20:29:59 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:29:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:29:59 INFO - 'PWD': '/builds/slave/test', 20:29:59 INFO - 'SHELL': '/bin/bash', 20:29:59 INFO - 'SHLVL': '1', 20:29:59 INFO - 'TERM': 'linux', 20:29:59 INFO - 'TMOUT': '86400', 20:29:59 INFO - 'USER': 'cltbld', 20:29:59 INFO - 'XDG_SESSION_COOKIE': 'cbe409c6de289db6ac707de3000001d4-1474254081.944973-20371291', 20:29:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:29:59 INFO - '_': '/tools/buildbot/bin/python'} 20:30:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:30:00 INFO - Downloading/unpacking jsonschema==2.5.1 20:30: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:30: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:30: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:30: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:30: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:30: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:30:01 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 20:30:01 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 20:30:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:30:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:30:01 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:30:01 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:30:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:30:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:30:02 INFO - Downloading functools32-3.2.3-2.tar.gz 20:30:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 20:30:02 INFO - warning: no files found matching '*.txt' 20:30:02 INFO - no previously-included directories found matching 'build' 20:30:02 INFO - no previously-included directories found matching 'dist' 20:30:02 INFO - no previously-included directories found matching '.git*' 20:30:02 INFO - Installing collected packages: jsonschema, functools32 20:30:02 INFO - Running setup.py install for functools32 20:30:02 INFO - warning: no files found matching '*.txt' 20:30:02 INFO - no previously-included directories found matching 'build' 20:30:02 INFO - no previously-included directories found matching 'dist' 20:30:02 INFO - no previously-included directories found matching '.git*' 20:30:02 INFO - Successfully installed jsonschema functools32 20:30:02 INFO - Cleaning up... 20:30:02 INFO - Return code: 0 20:30:02 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 20:30:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:30:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:30:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:30:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:30:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:30:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:30:02 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 0x1bfa030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d5d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ebb180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1eb93b0>, '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 0x1eba2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1eba740>, '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': 'cbe409c6de289db6ac707de3000001d4-1474254081.944973-20371291', '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:30:02 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:30:02 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:30:02 INFO - Using env: {'DISPLAY': ':0', 20:30:02 INFO - 'HOME': '/home/cltbld', 20:30:02 INFO - 'LANG': 'en_US.UTF-8', 20:30:02 INFO - 'LANGUAGE': 'en_US:en', 20:30:02 INFO - 'LOGNAME': 'cltbld', 20:30:02 INFO - 'MAIL': '/var/mail/cltbld', 20:30:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:30:02 INFO - 'MOZ_NO_REMOTE': '1', 20:30:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:30:02 INFO - 'NO_EM_RESTART': '1', 20:30:02 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:30:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:30:02 INFO - 'PWD': '/builds/slave/test', 20:30:02 INFO - 'SHELL': '/bin/bash', 20:30:02 INFO - 'SHLVL': '1', 20:30:02 INFO - 'TERM': 'linux', 20:30:02 INFO - 'TMOUT': '86400', 20:30:02 INFO - 'USER': 'cltbld', 20:30:02 INFO - 'XDG_SESSION_COOKIE': 'cbe409c6de289db6ac707de3000001d4-1474254081.944973-20371291', 20:30:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:30:02 INFO - '_': '/tools/buildbot/bin/python'} 20:30:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:30:02 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 20:30:02 INFO - Cleaning up... 20:30:02 INFO - Return code: 0 20:30:02 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 20:30:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:30:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:30:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:30:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:30:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:30:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:30:02 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 0x1bfa030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d5d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ebb180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1eb93b0>, '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 0x1eba2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1eba740>, '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': 'cbe409c6de289db6ac707de3000001d4-1474254081.944973-20371291', '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:30:02 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:30:02 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:30:02 INFO - Using env: {'DISPLAY': ':0', 20:30:02 INFO - 'HOME': '/home/cltbld', 20:30:02 INFO - 'LANG': 'en_US.UTF-8', 20:30:02 INFO - 'LANGUAGE': 'en_US:en', 20:30:02 INFO - 'LOGNAME': 'cltbld', 20:30:02 INFO - 'MAIL': '/var/mail/cltbld', 20:30:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:30:02 INFO - 'MOZ_NO_REMOTE': '1', 20:30:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:30:02 INFO - 'NO_EM_RESTART': '1', 20:30:02 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:30:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:30:02 INFO - 'PWD': '/builds/slave/test', 20:30:02 INFO - 'SHELL': '/bin/bash', 20:30:02 INFO - 'SHLVL': '1', 20:30:02 INFO - 'TERM': 'linux', 20:30:02 INFO - 'TMOUT': '86400', 20:30:02 INFO - 'USER': 'cltbld', 20:30:02 INFO - 'XDG_SESSION_COOKIE': 'cbe409c6de289db6ac707de3000001d4-1474254081.944973-20371291', 20:30:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:30:02 INFO - '_': '/tools/buildbot/bin/python'} 20:30:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:30:02 INFO - Downloading/unpacking blobuploader==1.2.4 20:30: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:30: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:30: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:30: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:30: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:30: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:30:04 INFO - Downloading blobuploader-1.2.4.tar.gz 20:30:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 20:30:04 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 20:30:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:30:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:30:04 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:30:04 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:30:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:30:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:30:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 20:30:05 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 20:30: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:30: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:30: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:30: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:30: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:30: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:30:05 INFO - Downloading docopt-0.6.1.tar.gz 20:30:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 20:30:05 INFO - Installing collected packages: blobuploader, requests, docopt 20:30:05 INFO - Running setup.py install for blobuploader 20:30:05 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 20:30:05 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 20:30:05 INFO - Running setup.py install for requests 20:30:05 INFO - Running setup.py install for docopt 20:30:05 INFO - Successfully installed blobuploader requests docopt 20:30:05 INFO - Cleaning up... 20:30:06 INFO - Return code: 0 20:30:06 INFO - Installing None into virtualenv /builds/slave/test/build/venv 20:30: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:30:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:30:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:30: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:30:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:30:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:30: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 0x1bfa030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d5d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ebb180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1eb93b0>, '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 0x1eba2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1eba740>, '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': 'cbe409c6de289db6ac707de3000001d4-1474254081.944973-20371291', '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:30: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:30: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:30:06 INFO - Using env: {'DISPLAY': ':0', 20:30:06 INFO - 'HOME': '/home/cltbld', 20:30:06 INFO - 'LANG': 'en_US.UTF-8', 20:30:06 INFO - 'LANGUAGE': 'en_US:en', 20:30:06 INFO - 'LOGNAME': 'cltbld', 20:30:06 INFO - 'MAIL': '/var/mail/cltbld', 20:30:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:30:06 INFO - 'MOZ_NO_REMOTE': '1', 20:30:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:30:06 INFO - 'NO_EM_RESTART': '1', 20:30:06 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:30:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:30:06 INFO - 'PWD': '/builds/slave/test', 20:30:06 INFO - 'SHELL': '/bin/bash', 20:30:06 INFO - 'SHLVL': '1', 20:30:06 INFO - 'TERM': 'linux', 20:30:06 INFO - 'TMOUT': '86400', 20:30:06 INFO - 'USER': 'cltbld', 20:30:06 INFO - 'XDG_SESSION_COOKIE': 'cbe409c6de289db6ac707de3000001d4-1474254081.944973-20371291', 20:30:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:30:06 INFO - '_': '/tools/buildbot/bin/python'} 20:30:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:30:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 20:30:06 INFO - Running setup.py (path:/tmp/pip-Dj6rj6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 20:30:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 20:30:06 INFO - Running setup.py (path:/tmp/pip-i20hak-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 20:30:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 20:30:06 INFO - Running setup.py (path:/tmp/pip-YkLNGQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 20:30:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 20:30:06 INFO - Running setup.py (path:/tmp/pip-_0aZhR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 20:30:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 20:30:06 INFO - Running setup.py (path:/tmp/pip-UFNH5D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 20:30:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 20:30:06 INFO - Running setup.py (path:/tmp/pip-TM2kEC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 20:30:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 20:30:06 INFO - Running setup.py (path:/tmp/pip-dbEfoX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 20:30:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 20:30:06 INFO - Running setup.py (path:/tmp/pip-jRgzPH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 20:30:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 20:30:06 INFO - Running setup.py (path:/tmp/pip-AlwcN0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 20:30:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 20:30:06 INFO - Running setup.py (path:/tmp/pip-y0sVof-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 20:30:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 20:30:07 INFO - Running setup.py (path:/tmp/pip-zmTbgE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 20:30:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 20:30:07 INFO - Running setup.py (path:/tmp/pip-ASKXLT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 20:30:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 20:30:07 INFO - Running setup.py (path:/tmp/pip-8X8Gfh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 20:30:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 20:30:07 INFO - Running setup.py (path:/tmp/pip-yJrxuK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 20:30:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 20:30:07 INFO - Running setup.py (path:/tmp/pip-phzoR0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 20:30:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 20:30:07 INFO - Running setup.py (path:/tmp/pip-SlW0XH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 20:30:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 20:30:07 INFO - Running setup.py (path:/tmp/pip-jWOpRA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 20:30:07 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 20:30:07 INFO - Running setup.py install for manifestparser 20:30:07 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 20:30:07 INFO - Running setup.py install for mozcrash 20:30:07 INFO - Running setup.py install for mozdebug 20:30:07 INFO - Running setup.py install for mozdevice 20:30:07 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 20:30:07 INFO - Installing dm script to /builds/slave/test/build/venv/bin 20:30:07 INFO - Running setup.py install for mozfile 20:30:08 INFO - Running setup.py install for mozhttpd 20:30:08 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 20:30:08 INFO - Running setup.py install for mozinfo 20:30:08 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 20:30:08 INFO - Running setup.py install for mozInstall 20:30:08 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 20:30:08 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 20:30:08 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 20:30:08 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 20:30:08 INFO - Running setup.py install for mozleak 20:30:08 INFO - Running setup.py install for mozlog 20:30:08 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 20:30:08 INFO - Running setup.py install for moznetwork 20:30:08 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 20:30:08 INFO - Running setup.py install for mozprocess 20:30:08 INFO - Running setup.py install for mozprofile 20:30:08 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 20:30:08 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 20:30:08 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 20:30:08 INFO - Running setup.py install for mozrunner 20:30:08 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 20:30:08 INFO - Running setup.py install for mozscreenshot 20:30:09 INFO - Running setup.py install for moztest 20:30:09 INFO - Running setup.py install for mozversion 20:30:09 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 20:30:09 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 20:30:09 INFO - Cleaning up... 20:30:09 INFO - Return code: 0 20:30:09 INFO - Installing None into virtualenv /builds/slave/test/build/venv 20:30:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:30:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:30:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:30:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:30:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:30:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:30:09 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 0x1bfa030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d5d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ebb180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1eb93b0>, '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 0x1eba2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1eba740>, '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': 'cbe409c6de289db6ac707de3000001d4-1474254081.944973-20371291', '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:30:09 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:30:09 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:30:09 INFO - Using env: {'DISPLAY': ':0', 20:30:09 INFO - 'HOME': '/home/cltbld', 20:30:09 INFO - 'LANG': 'en_US.UTF-8', 20:30:09 INFO - 'LANGUAGE': 'en_US:en', 20:30:09 INFO - 'LOGNAME': 'cltbld', 20:30:09 INFO - 'MAIL': '/var/mail/cltbld', 20:30:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:30:09 INFO - 'MOZ_NO_REMOTE': '1', 20:30:09 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:30:09 INFO - 'NO_EM_RESTART': '1', 20:30:09 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:30:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:30:09 INFO - 'PWD': '/builds/slave/test', 20:30:09 INFO - 'SHELL': '/bin/bash', 20:30:09 INFO - 'SHLVL': '1', 20:30:09 INFO - 'TERM': 'linux', 20:30:09 INFO - 'TMOUT': '86400', 20:30:09 INFO - 'USER': 'cltbld', 20:30:09 INFO - 'XDG_SESSION_COOKIE': 'cbe409c6de289db6ac707de3000001d4-1474254081.944973-20371291', 20:30:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:30:09 INFO - '_': '/tools/buildbot/bin/python'} 20:30:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:30:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 20:30:09 INFO - Running setup.py (path:/tmp/pip-WKeRBa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 20:30:09 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:30:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 20:30:09 INFO - Running setup.py (path:/tmp/pip-tCpIOt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 20:30:09 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:30:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 20:30:09 INFO - Running setup.py (path:/tmp/pip-iaFY36-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 20:30:09 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:30:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 20:30:09 INFO - Running setup.py (path:/tmp/pip-aXkX_3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 20:30:09 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:30:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 20:30:09 INFO - Running setup.py (path:/tmp/pip-OvA2z5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 20:30:09 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:30:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 20:30:09 INFO - Running setup.py (path:/tmp/pip-L95rwE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 20:30:09 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:30:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 20:30:09 INFO - Running setup.py (path:/tmp/pip-XxnDbS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 20:30: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:30:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 20:30:10 INFO - Running setup.py (path:/tmp/pip-EPpXZ3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 20:30: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:30:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 20:30:10 INFO - Running setup.py (path:/tmp/pip-XjOO9z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 20:30:10 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:30:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 20:30:10 INFO - Running setup.py (path:/tmp/pip-a9S_xG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 20:30:10 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:30:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 20:30:10 INFO - Running setup.py (path:/tmp/pip-yup2xu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 20:30:10 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:30:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 20:30:10 INFO - Running setup.py (path:/tmp/pip-vrVCAa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 20:30:10 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:30:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 20:30:10 INFO - Running setup.py (path:/tmp/pip-IRJlnW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 20:30:10 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:30:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 20:30:10 INFO - Running setup.py (path:/tmp/pip-aYfvuF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 20:30:10 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:30:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 20:30:10 INFO - Running setup.py (path:/tmp/pip-wc0sbZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 20:30:10 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:30:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 20:30:10 INFO - Running setup.py (path:/tmp/pip-8LT_j8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 20:30:10 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:30:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 20:30:10 INFO - Running setup.py (path:/tmp/pip-BkJaTO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 20:30:10 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:30:10 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:30:10 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:30:10 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:30:10 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:30:10 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:30:10 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 20:30:10 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:30:10 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:30:10 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:30:10 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:30:10 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:30:10 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:30:12 INFO - Downloading blessings-1.6.tar.gz 20:30:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 20:30:12 INFO - Installing collected packages: blessings 20:30:12 INFO - Running setup.py install for blessings 20:30:12 INFO - Successfully installed blessings 20:30:12 INFO - Cleaning up... 20:30:12 INFO - Return code: 0 20:30:12 INFO - Done creating virtualenv /builds/slave/test/build/venv. 20:30:12 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 20:30:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 20:30:13 INFO - Reading from file tmpfile_stdout 20:30:13 INFO - Current package versions: 20:30:13 INFO - argparse == 1.2.1 20:30:13 INFO - blessings == 1.6 20:30:13 INFO - blobuploader == 1.2.4 20:30:13 INFO - docopt == 0.6.1 20:30:13 INFO - functools32 == 3.2.3-2 20:30:13 INFO - jsonschema == 2.5.1 20:30:13 INFO - manifestparser == 1.1 20:30:13 INFO - mozInstall == 1.12 20:30:13 INFO - mozcrash == 0.17 20:30:13 INFO - mozdebug == 0.1 20:30:13 INFO - mozdevice == 0.48 20:30:13 INFO - mozfile == 1.2 20:30:13 INFO - mozhttpd == 0.7 20:30:13 INFO - mozinfo == 0.9 20:30:13 INFO - mozleak == 0.1 20:30:13 INFO - mozlog == 3.3 20:30:13 INFO - moznetwork == 0.27 20:30:13 INFO - mozprocess == 0.23 20:30:13 INFO - mozprofile == 0.28 20:30:13 INFO - mozrunner == 6.12 20:30:13 INFO - mozscreenshot == 0.1 20:30:13 INFO - mozsystemmonitor == 0.3 20:30:13 INFO - moztest == 0.7 20:30:13 INFO - mozversion == 1.4 20:30:13 INFO - psutil == 3.1.1 20:30:13 INFO - requests == 1.2.3 20:30:13 INFO - wsgiref == 0.1.2 20:30:13 INFO - Installing None into virtualenv /builds/slave/test/build/venv 20:30: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:30:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:30:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:30: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:30:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:30:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:30: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 0x1bfa030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d5d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ebb180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1eb93b0>, '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 0x1eba2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1eba740>, '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': 'cbe409c6de289db6ac707de3000001d4-1474254081.944973-20371291', '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:30: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:30: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:30:13 INFO - Using env: {'DISPLAY': ':0', 20:30:13 INFO - 'HOME': '/home/cltbld', 20:30:13 INFO - 'LANG': 'en_US.UTF-8', 20:30:13 INFO - 'LANGUAGE': 'en_US:en', 20:30:13 INFO - 'LOGNAME': 'cltbld', 20:30:13 INFO - 'MAIL': '/var/mail/cltbld', 20:30:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:30:13 INFO - 'MOZ_NO_REMOTE': '1', 20:30:13 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:30:13 INFO - 'NO_EM_RESTART': '1', 20:30:13 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:30:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:30:13 INFO - 'PWD': '/builds/slave/test', 20:30:13 INFO - 'SHELL': '/bin/bash', 20:30:13 INFO - 'SHLVL': '1', 20:30:13 INFO - 'TERM': 'linux', 20:30:13 INFO - 'TMOUT': '86400', 20:30:13 INFO - 'USER': 'cltbld', 20:30:13 INFO - 'XDG_SESSION_COOKIE': 'cbe409c6de289db6ac707de3000001d4-1474254081.944973-20371291', 20:30:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:30:13 INFO - '_': '/tools/buildbot/bin/python'} 20:30:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:30:13 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:30:13 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:30:13 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:30:13 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:30:13 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:30:13 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:30:13 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:30:13 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:30:13 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:30:13 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:30:13 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:30:13 INFO - Cleaning up... 20:30:13 INFO - Return code: 0 20:30:13 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 20:30: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:30:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:30:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:30: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:30:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:30:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:30:13 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 0x1bfa030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d5d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ebb180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1eb93b0>, '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 0x1eba2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1eba740>, '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': 'cbe409c6de289db6ac707de3000001d4-1474254081.944973-20371291', '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:30:13 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:30:13 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:30:13 INFO - Using env: {'DISPLAY': ':0', 20:30:13 INFO - 'HOME': '/home/cltbld', 20:30:13 INFO - 'LANG': 'en_US.UTF-8', 20:30:13 INFO - 'LANGUAGE': 'en_US:en', 20:30:13 INFO - 'LOGNAME': 'cltbld', 20:30:13 INFO - 'MAIL': '/var/mail/cltbld', 20:30:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:30:13 INFO - 'MOZ_NO_REMOTE': '1', 20:30:13 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:30:13 INFO - 'NO_EM_RESTART': '1', 20:30:13 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:30:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:30:13 INFO - 'PWD': '/builds/slave/test', 20:30:13 INFO - 'SHELL': '/bin/bash', 20:30:13 INFO - 'SHLVL': '1', 20:30:13 INFO - 'TERM': 'linux', 20:30:13 INFO - 'TMOUT': '86400', 20:30:13 INFO - 'USER': 'cltbld', 20:30:13 INFO - 'XDG_SESSION_COOKIE': 'cbe409c6de289db6ac707de3000001d4-1474254081.944973-20371291', 20:30:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:30:13 INFO - '_': '/tools/buildbot/bin/python'} 20:30:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:30:13 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 20:30:13 INFO - Cleaning up... 20:30:13 INFO - Return code: 0 20:30:13 INFO - Running post-action listener: _resource_record_post_action 20:30:13 INFO - Running post-action listener: _start_resource_monitoring 20:30:13 INFO - Starting resource monitoring. 20:30:13 INFO - [mozharness: 2016-09-19 03:30:13.574387Z] Finished create-virtualenv step (success) 20:30:13 INFO - [mozharness: 2016-09-19 03:30:13.574816Z] Running install step. 20:30:13 INFO - Running pre-action listener: _resource_record_pre_action 20:30:13 INFO - Running main action method: install 20:30:13 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 20:30:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 20:30:13 INFO - Reading from file tmpfile_stdout 20:30:13 INFO - Detecting whether we're running mozinstall >=1.0... 20:30:13 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 20:30:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 20:30:13 INFO - Reading from file tmpfile_stdout 20:30:13 INFO - Output received: 20:30:13 INFO - Usage: mozinstall [options] installer 20:30:13 INFO - Options: 20:30:13 INFO - -h, --help show this help message and exit 20:30:13 INFO - -d DEST, --destination=DEST 20:30:13 INFO - Directory to install application into. [default: 20:30:13 INFO - "/builds/slave/test"] 20:30:13 INFO - --app=APP Application being installed. [default: firefox] 20:30:13 INFO - mkdir: /builds/slave/test/build/application 20:30:13 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:30:13 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:30:29 INFO - Reading from file tmpfile_stdout 20:30:29 INFO - Output received: 20:30:29 INFO - /builds/slave/test/build/application/firefox/firefox 20:30:29 INFO - Running post-action listener: _resource_record_post_action 20:30:29 INFO - [mozharness: 2016-09-19 03:30:29.887153Z] Finished install step (success) 20:30:29 INFO - [mozharness: 2016-09-19 03:30:29.887431Z] Running run-tests step. 20:30:29 INFO - Running pre-action listener: _resource_record_pre_action 20:30:29 INFO - Running main action method: run_tests 20:30:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 20:30:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 20:30:29 INFO - Python 2.7.3 20:30:29 INFO - Return code: 0 20:30:29 INFO - grabbing minidump binary from tooltool 20:30:29 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:30:29 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 0x1eb93b0>, '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 0x1eba2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1eba740>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 20:30:29 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:30:29 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:30:29 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:30:29 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 20:30:29 INFO - Return code: 0 20:30:29 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 20:30:29 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 20:30:29 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 20:30:29 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 20:30:29 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 20:30:29 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', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/ePIxxufxTeCzWVOohoB-9w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-054', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 20:30:29 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 --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/ePIxxufxTeCzWVOohoB-9w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-054 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 20:30:29 INFO - Using env: {'DISPLAY': ':0', 20:30:29 INFO - 'HOME': '/home/cltbld', 20:30:29 INFO - 'LANG': 'en_US.UTF-8', 20:30:29 INFO - 'LANGUAGE': 'en_US:en', 20:30:29 INFO - 'LOGNAME': 'cltbld', 20:30:29 INFO - 'MAIL': '/var/mail/cltbld', 20:30:29 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 20:30:29 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 20:30:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:30:29 INFO - 'MOZ_NO_REMOTE': '1', 20:30:29 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 20:30:29 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:30:29 INFO - 'NO_EM_RESTART': '1', 20:30:29 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:30:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:30:29 INFO - 'PWD': '/builds/slave/test', 20:30:29 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 20:30:29 INFO - 'SHELL': '/bin/bash', 20:30:29 INFO - 'SHLVL': '1', 20:30:29 INFO - 'TERM': 'linux', 20:30:29 INFO - 'TMOUT': '86400', 20:30:29 INFO - 'USER': 'cltbld', 20:30:29 INFO - 'XDG_SESSION_COOKIE': 'cbe409c6de289db6ac707de3000001d4-1474254081.944973-20371291', 20:30:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:30:29 INFO - '_': '/tools/buildbot/bin/python'} 20:30:29 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/ePIxxufxTeCzWVOohoB-9w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-054', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 20:30:30 INFO - mozversion application_buildid: 20160918173718 20:30:30 INFO - mozversion application_changeset: 896a7672b83764829bbfc93e285d03bf318d3a5b 20:30:30 INFO - mozversion application_display_name: FirefoxDeveloperEdition 20:30:30 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 20:30:30 INFO - mozversion application_name: Firefox 20:30:30 INFO - mozversion application_remotingname: firefox-dev 20:30:30 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 20:30:30 INFO - mozversion application_vendor: Mozilla 20:30:30 INFO - mozversion application_version: 50.0a2 20:30:30 INFO - mozversion platform_buildid: 20160918173718 20:30:30 INFO - mozversion platform_changeset: 896a7672b83764829bbfc93e285d03bf318d3a5b 20:30:30 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 20:30:30 INFO - mozversion platform_version: 50.0a2 20:30:30 INFO - using testdate: 1474255830 20:30:30 INFO - actual date: 1474255830 20:30:30 INFO - starting webserver on 'localhost:51653' 20:30:30 INFO - SUITE-START | Running 2 tests 20:30:30 INFO - TEST-START | dromaeo_css 20:30:30 INFO - operating with platform_type : linux_ 20:30:30 INFO - Initialising browser for dromaeo_css test... 20:30:30 INFO - TEST-INFO | started process 25384 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpmRkj7t/profile http://localhost:51653/getInfo.html) 20:30:34 INFO - PROCESS | 25384 | __metrics Screen width/height:1600/1200 20:30:34 INFO - PROCESS | 25384 | colorDepth:24 20:30:34 INFO - PROCESS | 25384 | Browser inner width/height: 1024/697 20:30:34 INFO - PROCESS | 25384 | __metrics 20:30:35 INFO - PROCESS | 25384 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 20:30:35 INFO - PROCESS | 25384 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 20:30:36 INFO - TEST-INFO | 25384: exit 0 20:30:36 INFO - Browser initialized. 20:30:36 INFO - Running cycle 1/1 for dromaeo_css test... 20:30:36 INFO - TEST-INFO | started process 25470 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpmRkj7t/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 20:30:48 INFO - PROCESS | 25470 | RSS: Main: 146153472 20:30:48 INFO - PROCESS | 25470 | 20:31:38 INFO - PROCESS | 25470 | 1474255898800 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:33:48 INFO - PROCESS | 25470 | Cycle 1(1): loaded http://localhost:51653/tests/dromaeo/cssquery-dojo.html (next: http://localhost:51653/tests/dromaeo/cssquery-ext.html) 20:33:49 INFO - PROCESS | 25470 | RSS: Main: 267173888 20:33:49 INFO - PROCESS | 25470 | 20:36:51 INFO - PROCESS | 25470 | Cycle 1(1): loaded http://localhost:51653/tests/dromaeo/cssquery-ext.html (next: http://localhost:51653/tests/dromaeo/cssquery-jquery.html) 20:36:52 INFO - PROCESS | 25470 | RSS: Main: 234704896 20:36:52 INFO - PROCESS | 25470 | 20:39:54 INFO - PROCESS | 25470 | Cycle 1(1): loaded http://localhost:51653/tests/dromaeo/cssquery-jquery.html (next: http://localhost:51653/tests/dromaeo/cssquery-mootools.html) 20:39:54 INFO - PROCESS | 25470 | RSS: Main: 229330944 20:39:54 INFO - PROCESS | 25470 | 20:42:56 INFO - PROCESS | 25470 | Cycle 1(1): loaded http://localhost:51653/tests/dromaeo/cssquery-mootools.html (next: http://localhost:51653/tests/dromaeo/cssquery-prototype.html) 20:42:57 INFO - PROCESS | 25470 | RSS: Main: 223367168 20:42:57 INFO - PROCESS | 25470 | 20:45:59 INFO - PROCESS | 25470 | Cycle 1(1): loaded http://localhost:51653/tests/dromaeo/cssquery-prototype.html (next: http://localhost:51653/tests/dromaeo/cssquery-yui.html) 20:46:00 INFO - PROCESS | 25470 | RSS: Main: 177254400 20:46:00 INFO - PROCESS | 25470 | 20:49:02 INFO - PROCESS | 25470 | Cycle 1(1): loaded http://localhost:51653/tests/dromaeo/cssquery-yui.html (next: http://localhost:51653/tests/dromaeo/cssquery-dojo.html) 20:49:02 INFO - PROCESS | 25470 | RSS: Main: 265826304 20:49:02 INFO - PROCESS | 25470 | 20:49:02 INFO - PROCESS | 25470 | __start_tp_report 20:49:02 INFO - PROCESS | 25470 | _x_x_mozilla_page_load 20:49:02 INFO - PROCESS | 25470 | _x_x_mozilla_page_load_details 20:49:02 INFO - PROCESS | 25470 | |i|pagename|runs| 20:49:02 INFO - PROCESS | 25470 | |0;dojo.html;3233.725133363664;3250.2361944942936;3264.6572109928443;3298.455754800458;3309.8345082745873;5591.832245032645;5703.828885133442;5727.627704199234;5746.798862039829;5758.5393168546525;5692.715364231793;5737.282839645044;5737.540996720263;5744.310682718069;5749.827505174841;4968.677035992667;5093.286939828427;5177.844664660066;5178.792848286064;5214.921776173361;5103.2090026045835;5106.463821298766;5171.663841850268;5185.274061631375;5187.325647665817;5147.279380886679;5150.587952963772;5168.948310516903;5169.1987741899875;5169.56058735008;5223;5229.346331708536;5229.450907654679;5243.056249875021;5266.4733526647415;4996.500349964975;5063.0380227756605;5082.466341034212;5126;5132.5893928486;5641.210230567724;5657;5662.348829884575;5687.630304030225;5699.373068962411;6096.024636058254;6100.298465676459;6101.969490152521;6105.755769769204;6120.234970628671;5893.852653683649;6064.60580062299;6077.574569780118;6088.269407671063;6161.383861613847;5718.199452076713;5721.113227449752;5752.7986520472;5758.7120643968055;5761.53907687384;6225;6264.029075493306;6265.8120256392385;6270.372962703693;6275.497960163176;5717.371089180187;5935.050391937356;5976.760929562769;5977.820665380045;6024.307204671519;6082.878342433127;6125.479334256549;6130.846728831815;6132.816015519542;6136.9079463808075;6084.726187321581;6084.803960712311;6106.419890110433;6107.725152368154;6133.662648554372;5868.23712917318;5874.73563689635;5940.227770389908;6046.244219472566;6069.726862291119;4543.318502224627;4655.44134703831;4659.95340046602;4664.626829853603;4689.413823272091;4146.502419709594;4147.834086636561;4153.813078411479;4176.77027763476;4179.226843034048;5607.5513958884;5845.9707701462075;5851.297844258631;5859.912101318484;5877.441643043904;6027.758889644453;6052.515798736091;6089.269287685506;6147.66187859663;6151.384861513813;5760.135979603093;5805.82582522525;5808.448197421239;5808.738606762706;5836.88326233473;5153.020926024044;5221.269022336875;5267;5268;5284.04887120322;6240.063990401476;6280.842978925563;6301.14934483848;6377.904331434939;6392;6180.783672571618;6184.412480814315;6185.628862268279;6203.937960620336;6208.596441231305;6113.354685976905;6342.207224096988;6347.0479428086155;6361.077643741724;6363.331850155667;5904.291485021825;5939.673317967553;5954.642721436728;6001.4598686116715;6049.606775559574;6091.086337049478;6123;6129.233845769279;6136.079588061648;6137.723802428991;5814.04068328721;5921.052631578927;5978.372270911492;5989;5993.4006599341465;5875.823725288248;5933.347331793586;5974.61165024272;6061.575689701678;6081.239845019372;5966.164736936746;5992.43071908168;5993.101034844808;6000.8799824004145;6004.519638429003;5756.107803290497;6080.69596520181;6084;6084.969575152096;6094.634321940521;5650.898074875524;5833.533317334518;5854.795082172103;5864.091065884624;5914.674692891932 20:49:02 INFO - PROCESS | 25470 | |1;ext.html;583.3262581718117;629.1506466270534;664.7307840324669;671.9832004199898;673.9427148692364;34702.82648586757;46551.76724116379;47304.52695473044;47380.52619473804;47491.52508474914;48086.75956620221;48326.03347933039;48439.273410898866;48455.51544484554;48485.757571212096;8464.873026904601;9231.95384023079;9393.436393816393;9397.483138427384;9417.76455588609;10847.132229421628;11120.165987550925;11127.276727012759;11458.427078646077;11465.312081275108;11320.660380188567;11426.942865285704;11427.200095993283;11430.199886007982;11437.085033197323;11201.439928003609;11267.26762760418;11270.830937535984;11275.436228188557;11283.37941413222;10953.17851161166;10956;10992.450377481095;11019.173561982803;11033.227674062819;45485.545144548465;45882.311765323546;45919.08161836778;45948.77025614884;45949.77025114887;3839.347310957116;4056.898577535556;4213.073123912734;4244.5967633075115;4606.309053641947;34856.12859678503;34958.82520587381;35124.12189695252;35220;35336.64663353385;4029.3351596986463;4040.7777572233517;4061.1065565575527;4094.099298154431;4167.874963751093;391910;393037;393465;394941;397025;63218.36781632217;63241.36758632447;63756.36243637504;63766;63928.360716393174;39713.40429893554;40441.39337909934;40672.18655626871;42867.142657147306;42918.78540607277;19080.236790528223;19249.711254331196;19255.32606358799;19260.614787704446;19269;13615.591532254048;13917.164970101827;14139.929300353433;14985.32566034531;15067.4726384576;20936.790632093787;20986.895065524575;20991.265305714227;21023.78976210249;21300.467488312606;7373.520721153215;7477.588732619649;7520.21037791035;7641.961790191013;7679.961600191963;1057.7937302225992;1075.408525311076;1101.4712937789907;1106.5850306135198;1121.7083558274908;34835.303293934485;35206.119847003516;35211.85066959255;35296.29407411837;35667.28665426677;1126.69016020595;1127.1658972360558;1193.7135087578918;1205.475618106109;1261.4638778519093;18121.18454669543;18394.448166555027;18522;18645;18683.90658046701;612834;616167;617434;617695;619056;64565.35434645593;64958.35041649618;64961.67519162374;65066.34933650603;65136;1334.9265790381621;1456.2209218068283;1477.4385733421232;1478.2165452310078;1482.384810303712;33900.49149262714;36299.27401452009;36453.73412421131;36677.63322366742;36752.448713269856;54436;54860.72569637286;54903;54915.45084549103;55063;460858;461209;463297;464205;464210;31635;31677.14479635782;31852.840735796173;31895.521567176045;33106.17234569155;53990.73004634952;54034.45965540294;54168.45831541792;54504.727476362365;54767.45232547623;45503.544964551256;45570;46285.30572041489;46380.536194638975;47377.28934065923;46054.53945460502;46796;46959;47504.287435689184;47521.762391187825;45723.31415028711;45948.310775339065;46290.30564541602;46668.299975501075;47742.283865741345;38263.61736382712;40794.38808417936;40906.79546602248;41119.588804111576;41736.3739543901;30759;30807.383852323277;30853.382932341676;30990.69009309968;31102.222444438165 20:49:02 INFO - PROCESS | 25470 | |2;jquery.html;397.8527944660476;409.7951024487754;421.21023081721773;432.3838530094615;441.15959097918477;15235.390584376612;20521.281755138574;20754.89622551889;21080.789192108074;21422.785772142273;20175.49561260969;20438;21611.891940540274;21883.781162188374;21931.890340548274;5391.676499410048;5667.319921609413;5700.515456186229;5761.395053519383;5773.2494775679315;4235.6187943085115;4492.213862574053;5696.9745445819735;5729.197912292282;5733.082706766918;6956.617386043766;7084.893726594105;7093;7099.396551293146;7099.964500177492;5888.234529511179;6119;6163.691815409256;7062.470314726391;7069.646517674121;6087.695615219265;6098.451139397475;6101.359357267512;6102.389761023906;6102.420270074336;15421.383144674199;15567.766483502757;15680.21598920061;15722.37110515578;18860.905695471436;2402.87985600721;2479.727230004716;2511.8657167487463;2700.96013034981;3115.5482455044116;11101.611443599435;11286.54853805855;11559.94220028903;11607.361595112265;11774.352410617412;2032.1363420546209;2034.8677335973114;2053.9075741591514;2084.6351888419617;2148.6239908016037;82001.58999204966;89713;90673;91283.54358228167;91594;11640.650780476599;11876;11927;12174.452149653287;12218.633440996606;17067;17138.228779704943;17154.742678859828;17160.742588861176;17167.484975450756;8688.565571721389;8737.956310218407;8893.644254229888;8948.552572371485;9153.633854645872;8164.550949697824;8290.751277461564;8539.871901921477;8866.73399798007;9070.365074444726;5048.368953880765;5156.871078223075;5157.4584668610005;5170.793168273302;5229.869253268623;2041.3365656161807;2123.235635171337;2136.935891923245;2139.882306473159;2153.224839057938;964.609333220047;1051.731808388856;1071.212658695858;1080.0873262093519;1087.8041952448634;20010.29963951255;21944.45138871509;21961.67057494139;21965.34103976883;22125.778742212693;1017.414986382818;1039.5841663334727;1047.073340094007;1053.599632139782;1053.957841686324;11211.94394028041;12031.75936481283;12070.336131512675;12214.816777748341;12249.510019599295;550131;560783;565636;566497;574797;60873.39126608766;78057;95389.76614031526;100201;104617;1022.9590816367411;1046.6650671785108;1056.334509259162;1070.288258308232;1076.4617691154422;18516.81483185151;18762.155702993125;19051.809481905002;19220.423387298404;19262.32581859628;46049.76975115103;46108.769456152506;46333.76833115813;46397;46668.299975499714;59664.403355967625;63509.68245158745;64152;64268.357316426234;66024.33975660375;9030.36787424874;9151.633934642807;9161.49611771359;9407.341486095907;9544.475053872104;10767.40779257117;14171.078879872775;14181.929090354482;14392.712145757236;14442.061266018096;12470.376481176087;12516.436760345625;12624.936875315565;12660.17708848922;12675.80986285225;12248.571300004458;12452.937735311265;12606.369681515704;12837.037222208184;13187.293111063871;12803.29581873006;12845.465938993393;12863.742725145632;12967.416466258852;13578.660533486742;8870.600822963112;8876.511791851251;9252.583633736363;9560;9762.902370976199;8981.685641002534;12100.273983561014;12126.818097728365;12391.75216495683;12431.564895228985 20:49:02 INFO - PROCESS | 25470 | |3;mootools.html;1427.7644188708864;1568.6470544127574;1585.857272845444;1747.5805806606418;1845.3910209630824;9056.547172641367;9348.85976710349;9514.571844267017;9768.560414781334;9793.7551561211;9628.759281017978;9736.75658108546;9752.12230899219;9754.561044752985;9754.902450975487;2533.949321013574;2572.3826281692445;2572.7041390240083;2573.987130064347;2577.226831950417;2499.987500062506;2539.987300063497;2552.757488038643;2566.5251928393213;2568.666073410454;2528.5448619248527;2531.8227724059325;2570.4602033572974;2577.445849142434;2578.8839502222445;2475.282168171228;2500.549901017816;2510.0587279770084;2521.495700859817;2523.4322277487604;2427.8057755379527;2452.7179374371817;2461.88921498533;2464.7781699647117;2514.9874250628814;9564.760880977961;9620.086091821266;9624.711258662252;9659.71020869375;9721.125098741077;5700.344460387026;5884.617499862496;5891.204687367193;5891.469767720926;5894.351621321651;3653.926921461556;3690.7231957603285;3705.6664900158844;3723.2739615774935;3726.9068273293115;1142.3888219802366;1142.4402042998954;1161.558607729066;1178.6464060781816;2071.8964051797348;13462.394192261372;13979.510717124953;14027.438902443992;14039.157650541;14056.36746346427;4682.052626113268;4843.782029808667;4903.4606193318705;5016.698998060128;5137.486251374833;10692.572297108029;10700.41147736882;10700.678979630624;10704.464776761131;10715.785684286271;5831.125331200354;5849.2103566018895;5853.180554722342;5856.0630299151935;5860.824175274749;4758.286257061399;4796.064767370399;5325.600579956519;5334.839954801362;5482.396936337;4235.682323825664;4294.098239369705;4307.719998200169;4345.174416860787;4352.129574085196;1255.9623211303492;1294.9482020719065;1294.9676258093434;1300.2718477652356;1306.8366454193226;953.4517652349788;1016.8169729448771;1020.4183615339185;1024.9436281004616;1030.3096925060227;6220.377962203834;6246.6876656167005;6308.621482710961;6378.52161087911;6399.616023038632;483.76779146010114;489.17573887998833;489.71107046842536;499.14147666014395;500.85475212188055;4118.19695159441;4192.3082691355585;4249.978750106292;4305.655547556188;4324.983628847219;12002;12211.389430528438;12254.958328541996;12366.948809351126;12388.380580970916;12256.387180640932;12288.324142172267;12303.630891073282;12308.81536776949;12314.50741970329;8535.487870727777;8908.465492070496;8919.643214271486;8924;9077.727668169833;6067.211262536019;6137.478314343331;6335.239771227482;6344.0333496616195;6682;10443.321184123008;11011;11090;11116.499757511094;11213.66359009231;11080.667579972614;11257.831132532854;11287.604933827277;11521.539138434704;11684.18210725241;8640.524771137647;9150.679726209552;9209.769755755891;9214.2628589714;9241.491717955578;8854.867176992222;9124.817503650023;9276.675316363895;9363.063693630855;9373.390729602554;8094.757157285291;8286.668533258844;8339.45793523419;8428.4942903426;8498.617562209593;8060.798480038046;8110.391720620859;8111.472754270953;8160.5103693778565;8208.58957052133;7468.925310746823;7471.476996610185;7529.548227106391;7530.962345188239;7534.472586918863;5116.053530096944;5336.17289320156;5525.226468294522;5659.801906933238;5707.857303567444;5595.608307418442;5674.460926212003;5690.146478028329;5701.315842098808;5758.193852860687 20:49:02 INFO - PROCESS | 25470 | |4;prototype.html;356.2678695281198;363.76718899904057;365.6197554543275;369.7060836634875;372.97016238700905;5444.40111587725;5533.08704063829;5536.806211782588;5538.418466061066;5551.195076713879;5152.562032227265;5263.105272103742;5314.840554783353;5316.76074576645;5461.890762184754;4204.159168166364;4286.485621725397;4321.200577893084;4379.102284031782;4384.276594361926;4313.892152696177;4332.393464914914;4335.1329734053315;4336.479622445311;4354.8911277218;4057.1885622875398;4246.704755049708;4247.299195632732;4273.551277115905;4312.331588603772;4253.829846806124;4261.680373971964;4266.360045993095;4266.786660666954;4267.935980960288;3747.962520374816;3763.1156678180605;3778.471014058034;3779.111908701453;3790.3177428062936;5253.290805741214;5292.735363231862;5370.91943620846;5378.650387724785;5383.67697938125;4844.37023186987;4873.34209881665;4873.902521949542;4877.561019508225;4887.3890763654545;5650.802221922254;5662.745176467069;5700.201971723962;5702.686352250625;5703.686297253649;4040.4545386372756;4082.8775136745944;4130.925959250587;4134.090500089976;4164.313453051489;5876.882462350772;5920.437558431942;5932.762689492415;5934.940650593482;5943.851403714899;4267.871963841089;4277.850275240352;4278.251306021434;4281.785910704514;4286.2284788738325;4126.484189476316;4310.590493903074;4315.525292217853;4325.653947684178;4332.415123958288;4907.705537667752;4908.2637604358915;4909.975450122798;4913;4918.48355922623;5770.307563092372;5774.740136693859;5778.219940308089;5796.42035796417;5798.797042103506;1469.9853001469849;1491.1202390589604;1493.8580834820675;1493.9775903361458;1500.129924643704;933.8739270198572;958.8897276813116;961.5913236874301;962.9229661627053;964.6061441217373;5002.2237658099375;5007.924881126786;5022.874428139253;5047.637137972727;5049.192129259301;5196.45437229093;5215.843524694265;5228.63399562027;5235.921461178085;5239.397469291081;4661.229891977646;4970.925436118461;4998.725070121105;4999.500049994971;5025.547700706954;2771.9029833955715;2773.7226277372506;2782.0803645484552;2782.443511297708;2799.328161241328;5289.709066001406;5309.442508536626;5312.768637172397;5313.94448192291;5341.893162136736;5273.6572122811895;5298.496642819003;5613.877224555105;6072.180255665428;6072.574919755663;6069.725357674938;6113.144159817629;6144.201253836973;6159.476444502267;6177.073438984188;5691.971540142273;5945.31628862669;5990.921634105728;6003.939960600338;6023.006203976295;5862.970685146547;5887.264091988502;5916.556258280732;5929.005852044862;5942.584019118794;6137.202163718688;6169.876602468015;6174.25908890936;6177.84555386101;6184.721687523982;4205.306124489425;4250.596193361625;4399.032212913154;4400.735955842531;4409.250427427409;5812.854678633068;5831.35855055935;5872.706364681834;5907.9704601476715;5923.08192230205;5782.85542861415;5801.738921748447;5806.332271788799;5819.621724587888;5823.592348535732;5652.85867853307;5690.430956904442;5757.625754325955;5769.365369809401;5799.536037117105;5755.913661295168;5767.048437008015;5767.798127065533;5778.566607504538;5797.478097027806;5566.860828479321;5800.1299805029585;5801.245838041028;5811.779526299524;5837.3870743571315;5598.384177740364;5636.392708666374;5652.943470565242;5656.2929633795775;5670.603057785916 20:49:02 INFO - PROCESS | 25470 | |5;yui.html;813.3655748516156;838.8909441772572;879.3273146043277;901.526698483296;908.1281969309463;16460.0946947918;17482.65034699307;17531.82468175318;17568.560785980324;17594.20826062827;15766.290516926738;15885.205739713027;16016.59958501038;16245.51263462095;16403.753943690823;727.6652739739726;871.1810897756089;889.915458031486;893.3478560650728;894.4365050018479;843.3843294395095;880.6257340630207;883.0771843423638;883.1786438612088;883.8630012348096;937.8780758501385;939.192294626624;941.1482608239554;941.3928016429398;941.6562954521619;768.3852917665901;936.4896131608222;936.8032713130217;937.0582564522679;937.5593471068587;665.5640555436148;666.6100331306143;667.5494041522002;674.6053558668185;674.8582797612507;15721.528354149394;15744.212789360601;15778.921105394515;15784.763228551581;15823.208839557976;15053.849461505355;15458.304376303093;15577.922110389489;15697.293621787047;15697.764533532007;14288.214148221947;14311.928440357835;14563.344649490798;14704.926475367662;14786.334614942354;4747.8575642730775;4764.332993380929;4766.428028636551;4771.141194584973;4846.36997190363;84864.57567712184;85628;85972;89591.55204223937;90101.54949225212;12410.193337433038;12824.615261542169;12853.778891005339;13107.672308192372;13108.737825243441;24037;24060;24062.51874962491;24129.034838606258;24221.63667544988;9185.724428267162;9255.213306868856;9354.20489258407;9511;9544.331896767299;4961.82633607822;5069.483377075704;5083.87290317745;5183.974080129575;5258.789648414097;3832.904177395532;3958.6239307265764;3975.7813320267664;3977.443157957888;3981.9845939285296;642.2389468479867;656.0814859197162;656.409231691472;670.5607826873405;672.9427998620172;513.064321229772;575.8243735660664;630.2783313106468;646.6863571167979;736.8194792275926;9854.260930430246;10434.113100386552;10447.68656940293;10573.418461984666;10624.309419887837;596.4244504053609;628.8050704281687;682.0825989044727;706.2372637551432;710.1052673631159;4909.533594308535;4967.726775027336;4969.254611808257;5030.346055012825;5099.923501147486;76064.23935760572;76134.61932690413;76155.23844761592;76273.23726762773;76808.61595692097;67208.6639566799;91441.54279228694;109907.45046274718;111735.44132279449;112426.43786781178;97658.51170744101;98027.50986245023;98386.5080674592;98791;100186;9531.285153613368;9548.952255238679;9688.224942004763;9737.14994151109;9787.36382135159;57753.422465776486;59384.40615593789;59544.70227648834;59940;61513.384866150765;82067.17932820834;89745.55127224584;90186.0981390204;92168.07831921594;95521;11955;12167.087468439726;12836.16564923277;12911.935440322739;13225.603231903058;13889.305534723426;13929.651758705708;14024;14060.929695351868;14162.787558186841;12371.75256494883;12844.165129266568;12944.546940857024;13023.260430469643;13527.594172174851;12983.480660773463;13166.736665266833;13408.463661453432;13421.127626704232;14187.57437276842;12806.551770687982;13187.868121318665;13361.198328100345;13682.31588420555;13695.520656776966;10443.425611591436;10535.15718742514;10602.575896964036;10827.837582436112;10828.404437755684;9673.854892176481;10283.84574231402;10304.587816487257;10367.533460994424;10377.117944974761 20:49:02 INFO - PROCESS | 25470 | __end_tp_report 20:49:02 INFO - PROCESS | 25470 | __start_cc_report 20:49:02 INFO - PROCESS | 25470 | _x_x_mozilla_cycle_collect,1056 20:49:02 INFO - PROCESS | 25470 | __end_cc_report 20:49:02 INFO - PROCESS | 25470 | __startTimestamp1474256942925__endTimestamp 20:49:02 INFO - PROCESS | 25470 | 20:49:02 INFO - PROCESS | 25470 | ------- Summary: start ------- 20:49:02 INFO - PROCESS | 25470 | Number of tests: 6 20:49:02 INFO - PROCESS | 25470 | 20:49:02 INFO - PROCESS | 25470 | [#0] dojo.html Cycles:165 Average:5661.50 Median:5874.74 stddev:653.66 (11.1%) stddev-sans-first:627.30 20:49:02 INFO - PROCESS | 25470 | Values: 3233.7 3250.2 3264.7 3298.5 3309.8 5591.8 5703.8 5727.6 5746.8 5758.5 5692.7 5737.3 5737.5 5744.3 5749.8 4968.7 5093.3 5177.8 5178.8 5214.9 5103.2 5106.5 5171.7 5185.3 5187.3 5147.3 5150.6 5168.9 5169.2 5169.6 5223.0 5229.3 5229.5 5243.1 5266.5 4996.5 5063.0 5082.5 5126.0 5132.6 5641.2 5657.0 5662.3 5687.6 5699.4 6096.0 6100.3 6102.0 6105.8 6120.2 5893.9 6064.6 6077.6 6088.3 6161.4 5718.2 5721.1 5752.8 5758.7 5761.5 6225.0 6264.0 6265.8 6270.4 6275.5 5717.4 5935.1 5976.8 5977.8 6024.3 6082.9 6125.5 6130.8 6132.8 6136.9 6084.7 6084.8 6106.4 6107.7 6133.7 5868.2 5874.7 5940.2 6046.2 6069.7 4543.3 4655.4 4660.0 4664.6 4689.4 4146.5 4147.8 4153.8 4176.8 4179.2 5607.6 5846.0 5851.3 5859.9 5877.4 6027.8 6052.5 6089.3 6147.7 6151.4 5760.1 5805.8 5808.4 5808.7 5836.9 5153.0 5221.3 5267.0 5268.0 5284.0 6240.1 6280.8 6301.1 6377.9 6392.0 6180.8 6184.4 6185.6 6203.9 6208.6 6113.4 6342.2 6347.0 6361.1 6363.3 5904.3 5939.7 5954.6 6001.5 6049.6 6091.1 6123.0 6129.2 6136.1 6137.7 5814.0 5921.1 5978.4 5989.0 5993.4 5875.8 5933.3 5974.6 6061.6 6081.2 5966.2 5992.4 5993.1 6000.9 6004.5 5756.1 6080.7 6084.0 6085.0 6094.6 5650.9 5833.5 5854.8 5864.1 5914.7 20:49:02 INFO - PROCESS | 25470 | 20:49:02 INFO - PROCESS | 25470 | [#1] ext.html Cycles:180 Average:66337.61 Median:22241.09 stddev:132614.34 (596.3%) stddev-sans-first:132894.46 20:49:02 INFO - PROCESS | 25470 | Values: 583.3 629.2 664.7 672.0 673.9 34702.8 46551.8 47304.5 47380.5 47491.5 48086.8 48326.0 48439.3 48455.5 48485.8 8464.9 9232.0 9393.4 9397.5 9417.8 10847.1 11120.2 11127.3 11458.4 11465.3 11320.7 11426.9 11427.2 11430.2 11437.1 11201.4 11267.3 11270.8 11275.4 11283.4 10953.2 10956.0 10992.5 11019.2 11033.2 45485.5 45882.3 45919.1 45948.8 45949.8 3839.3 4056.9 4213.1 4244.6 4606.3 34856.1 34958.8 35124.1 35220.0 35336.6 4029.3 4040.8 4061.1 4094.1 4167.9 391910.0 393037.0 393465.0 394941.0 397025.0 63218.4 63241.4 63756.4 63766.0 63928.4 39713.4 40441.4 40672.2 42867.1 42918.8 19080.2 19249.7 19255.3 19260.6 19269.0 13615.6 13917.2 14139.9 14985.3 15067.5 20936.8 20986.9 20991.3 21023.8 21300.5 7373.5 7477.6 7520.2 7642.0 7680.0 1057.8 1075.4 1101.5 1106.6 1121.7 34835.3 35206.1 35211.9 35296.3 35667.3 1126.7 1127.2 1193.7 1205.5 1261.5 18121.2 18394.4 18522.0 18645.0 18683.9 612834.0 616167.0 617434.0 617695.0 619056.0 64565.4 64958.4 64961.7 65066.3 65136.0 1334.9 1456.2 1477.4 1478.2 1482.4 33900.5 36299.3 36453.7 36677.6 36752.4 54436.0 54860.7 54903.0 54915.5 55063.0 460858.0 461209.0 463297.0 464205.0 464210.0 31635.0 31677.1 31852.8 31895.5 33106.2 53990.7 54034.5 54168.5 54504.7 54767.5 45503.5 45570.0 46285.3 46380.5 47377.3 46054.5 46796.0 46959.0 47504.3 47521.8 45723.3 45948.3 46290.3 46668.3 47742.3 38263.6 40794.4 40906.8 41119.6 41736.4 30759.0 30807.4 30853.4 30990.7 31102.2 20:49:02 INFO - PROCESS | 25470 | 20:49:02 INFO - PROCESS | 25470 | [#2] jquery.html Cycles:180 Average:31836.45 Median:21938.17 stddev:92650.89 (422.3%) stddev-sans-first:92880.73 20:49:02 INFO - PROCESS | 25470 | Values: 397.9 409.8 421.2 432.4 441.2 15235.4 20521.3 20754.9 21080.8 21422.8 20175.5 20438.0 21611.9 21883.8 21931.9 5391.7 5667.3 5700.5 5761.4 5773.2 4235.6 4492.2 5697.0 5729.2 5733.1 6956.6 7084.9 7093.0 7099.4 7100.0 5888.2 6119.0 6163.7 7062.5 7069.6 6087.7 6098.5 6101.4 6102.4 6102.4 15421.4 15567.8 15680.2 15722.4 18860.9 2402.9 2479.7 2511.9 2701.0 3115.5 11101.6 11286.5 11559.9 11607.4 11774.4 2032.1 2034.9 2053.9 2084.6 2148.6 82001.6 89713.0 90673.0 91283.5 91594.0 11640.7 11876.0 11927.0 12174.5 12218.6 17067.0 17138.2 17154.7 17160.7 17167.5 8688.6 8738.0 8893.6 8948.6 9153.6 8164.6 8290.8 8539.9 8866.7 9070.4 5048.4 5156.9 5157.5 5170.8 5229.9 2041.3 2123.2 2136.9 2139.9 2153.2 964.6 1051.7 1071.2 1080.1 1087.8 20010.3 21944.5 21961.7 21965.3 22125.8 1017.4 1039.6 1047.1 1053.6 1054.0 11211.9 12031.8 12070.3 12214.8 12249.5 550131.0 560783.0 565636.0 566497.0 574797.0 60873.4 78057.0 95389.8 100201.0 104617.0 1023.0 1046.7 1056.3 1070.3 1076.5 18516.8 18762.2 19051.8 19220.4 19262.3 46049.8 46108.8 46333.8 46397.0 46668.3 59664.4 63509.7 64152.0 64268.4 66024.3 9030.4 9151.6 9161.5 9407.3 9544.5 10767.4 14171.1 14181.9 14392.7 14442.1 12470.4 12516.4 12624.9 12660.2 12675.8 12248.6 12452.9 12606.4 12837.0 13187.3 12803.3 12845.5 12863.7 12967.4 13578.7 8870.6 8876.5 9252.6 9560.0 9762.9 8981.7 12100.3 12126.8 12391.8 12431.6 20:49:02 INFO - PROCESS | 25470 | 20:49:02 INFO - PROCESS | 25470 | [#3] mootools.html Cycles:180 Average:6324.26 Median:4777.18 stddev:3683.74 (77.1%) stddev-sans-first:3675.69 20:49:02 INFO - PROCESS | 25470 | Values: 1427.8 1568.6 1585.9 1747.6 1845.4 9056.5 9348.9 9514.6 9768.6 9793.8 9628.8 9736.8 9752.1 9754.6 9754.9 2533.9 2572.4 2572.7 2574.0 2577.2 2500.0 2540.0 2552.8 2566.5 2568.7 2528.5 2531.8 2570.5 2577.4 2578.9 2475.3 2500.5 2510.1 2521.5 2523.4 2427.8 2452.7 2461.9 2464.8 2515.0 9564.8 9620.1 9624.7 9659.7 9721.1 5700.3 5884.6 5891.2 5891.5 5894.4 3653.9 3690.7 3705.7 3723.3 3726.9 1142.4 1142.4 1161.6 1178.6 2071.9 13462.4 13979.5 14027.4 14039.2 14056.4 4682.1 4843.8 4903.5 5016.7 5137.5 10692.6 10700.4 10700.7 10704.5 10715.8 5831.1 5849.2 5853.2 5856.1 5860.8 4758.3 4796.1 5325.6 5334.8 5482.4 4235.7 4294.1 4307.7 4345.2 4352.1 1256.0 1294.9 1295.0 1300.3 1306.8 953.5 1016.8 1020.4 1024.9 1030.3 6220.4 6246.7 6308.6 6378.5 6399.6 483.8 489.2 489.7 499.1 500.9 4118.2 4192.3 4250.0 4305.7 4325.0 12002.0 12211.4 12255.0 12366.9 12388.4 12256.4 12288.3 12303.6 12308.8 12314.5 8535.5 8908.5 8919.6 8924.0 9077.7 6067.2 6137.5 6335.2 6344.0 6682.0 10443.3 11011.0 11090.0 11116.5 11213.7 11080.7 11257.8 11287.6 11521.5 11684.2 8640.5 9150.7 9209.8 9214.3 9241.5 8854.9 9124.8 9276.7 9363.1 9373.4 8094.8 8286.7 8339.5 8428.5 8498.6 8060.8 8110.4 8111.5 8160.5 8208.6 7468.9 7471.5 7529.5 7531.0 7534.5 5116.1 5336.2 5525.2 5659.8 5707.9 5595.6 5674.5 5690.1 5701.3 5758.2 20:49:02 INFO - PROCESS | 25470 | 20:49:02 INFO - PROCESS | 25470 | [#4] prototype.html Cycles:180 Average:4770.31 Median:5295.62 stddev:1399.64 (26.4%) stddev-sans-first:1363.79 20:49:02 INFO - PROCESS | 25470 | Values: 356.3 363.8 365.6 369.7 373.0 5444.4 5533.1 5536.8 5538.4 5551.2 5152.6 5263.1 5314.8 5316.8 5461.9 4204.2 4286.5 4321.2 4379.1 4384.3 4313.9 4332.4 4335.1 4336.5 4354.9 4057.2 4246.7 4247.3 4273.6 4312.3 4253.8 4261.7 4266.4 4266.8 4267.9 3748.0 3763.1 3778.5 3779.1 3790.3 5253.3 5292.7 5370.9 5378.7 5383.7 4844.4 4873.3 4873.9 4877.6 4887.4 5650.8 5662.7 5700.2 5702.7 5703.7 4040.5 4082.9 4130.9 4134.1 4164.3 5876.9 5920.4 5932.8 5934.9 5943.9 4267.9 4277.9 4278.3 4281.8 4286.2 4126.5 4310.6 4315.5 4325.7 4332.4 4907.7 4908.3 4910.0 4913.0 4918.5 5770.3 5774.7 5778.2 5796.4 5798.8 1470.0 1491.1 1493.9 1494.0 1500.1 933.9 958.9 961.6 962.9 964.6 5002.2 5007.9 5022.9 5047.6 5049.2 5196.5 5215.8 5228.6 5235.9 5239.4 4661.2 4970.9 4998.7 4999.5 5025.5 2771.9 2773.7 2782.1 2782.4 2799.3 5289.7 5309.4 5312.8 5313.9 5341.9 5273.7 5298.5 5613.9 6072.2 6072.6 6069.7 6113.1 6144.2 6159.5 6177.1 5692.0 5945.3 5990.9 6003.9 6023.0 5863.0 5887.3 5916.6 5929.0 5942.6 6137.2 6169.9 6174.3 6177.8 6184.7 4205.3 4250.6 4399.0 4400.7 4409.3 5812.9 5831.4 5872.7 5908.0 5923.1 5782.9 5801.7 5806.3 5819.6 5823.6 5652.9 5690.4 5757.6 5769.4 5799.5 5755.9 5767.0 5767.8 5778.6 5797.5 5566.9 5800.1 5801.2 5811.8 5837.4 5598.4 5636.4 5652.9 5656.3 5670.6 20:49:02 INFO - PROCESS | 25470 | 20:49:02 INFO - PROCESS | 25470 | [#5] yui.html Cycles:180 Average:21358.26 Median:4999.80 stddev:29854.03 (597.1%) stddev-sans-first:29897.92 20:49:02 INFO - PROCESS | 25470 | Values: 813.4 838.9 879.3 901.5 908.1 16460.1 17482.7 17531.8 17568.6 17594.2 15766.3 15885.2 16016.6 16245.5 16403.8 727.7 871.2 889.9 893.3 894.4 843.4 880.6 883.1 883.2 883.9 937.9 939.2 941.1 941.4 941.7 768.4 936.5 936.8 937.1 937.6 665.6 666.6 667.5 674.6 674.9 15721.5 15744.2 15778.9 15784.8 15823.2 15053.8 15458.3 15577.9 15697.3 15697.8 14288.2 14311.9 14563.3 14704.9 14786.3 4747.9 4764.3 4766.4 4771.1 4846.4 84864.6 85628.0 85972.0 89591.6 90101.5 12410.2 12824.6 12853.8 13107.7 13108.7 24037.0 24060.0 24062.5 24129.0 24221.6 9185.7 9255.2 9354.2 9511.0 9544.3 4961.8 5069.5 5083.9 5184.0 5258.8 3832.9 3958.6 3975.8 3977.4 3982.0 642.2 656.1 656.4 670.6 672.9 513.1 575.8 630.3 646.7 736.8 9854.3 10434.1 10447.7 10573.4 10624.3 596.4 628.8 682.1 706.2 710.1 4909.5 4967.7 4969.3 5030.3 5099.9 76064.2 76134.6 76155.2 76273.2 76808.6 67208.7 91441.5 109907.5 111735.4 112426.4 97658.5 98027.5 98386.5 98791.0 100186.0 9531.3 9549.0 9688.2 9737.1 9787.4 57753.4 59384.4 59544.7 59940.0 61513.4 82067.2 89745.6 90186.1 92168.1 95521.0 11955.0 12167.1 12836.2 12911.9 13225.6 13889.3 13929.7 14024.0 14060.9 14162.8 12371.8 12844.2 12944.5 13023.3 13527.6 12983.5 13166.7 13408.5 13421.1 14187.6 12806.6 13187.9 13361.2 13682.3 13695.5 10443.4 10535.2 10602.6 10827.8 10828.4 9673.9 10283.8 10304.6 10367.5 10377.1 20:49:02 INFO - PROCESS | 25470 | -------- Summary: end -------- 20:49:02 INFO - PROCESS | 25470 | 20:49:03 INFO - TEST-INFO | 25470: exit 0 20:49:03 INFO - TEST-OK | dromaeo_css | took 1113544ms 20:49:03 INFO - TEST-START | kraken 20:49:03 INFO - operating with platform_type : linux_ 20:49:03 INFO - Initialising browser for kraken test... 20:49:03 INFO - TEST-INFO | started process 25673 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpjoYHie/profile http://localhost:51653/getInfo.html) 20:49:07 INFO - PROCESS | 25673 | __metrics Screen width/height:1600/1200 20:49:07 INFO - PROCESS | 25673 | colorDepth:24 20:49:07 INFO - PROCESS | 25673 | Browser inner width/height: 1024/697 20:49:07 INFO - PROCESS | 25673 | __metrics 20:49:08 INFO - PROCESS | 25673 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 20:49:08 INFO - PROCESS | 25673 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 20:49:09 INFO - TEST-INFO | 25673: exit 0 20:49:09 INFO - Browser initialized. 20:49:09 INFO - Running cycle 1/1 for kraken test... 20:49:09 INFO - TEST-INFO | started process 25759 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpjoYHie/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 20:49:21 INFO - PROCESS | 25759 | RSS: Main: 145870848 20:49:21 INFO - PROCESS | 25759 | 20:49:23 INFO - PROCESS | 25759 | Cycle 1(1): loaded http://localhost:51653/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:51653/tests/kraken/driver.html?testName=audio-beat-detection) 20:49:24 INFO - PROCESS | 25759 | RSS: Main: 200986624 20:49:24 INFO - PROCESS | 25759 | 20:49:27 INFO - PROCESS | 25759 | Cycle 1(1): loaded http://localhost:51653/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:51653/tests/kraken/driver.html?testName=audio-dft) 20:49:27 INFO - PROCESS | 25759 | RSS: Main: 185057280 20:49:27 INFO - PROCESS | 25759 | 20:49:30 INFO - PROCESS | 25759 | Cycle 1(1): loaded http://localhost:51653/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:51653/tests/kraken/driver.html?testName=audio-fft) 20:49:31 INFO - PROCESS | 25759 | RSS: Main: 276111360 20:49:31 INFO - PROCESS | 25759 | 20:49:33 INFO - PROCESS | 25759 | Cycle 1(1): loaded http://localhost:51653/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:51653/tests/kraken/driver.html?testName=audio-oscillator) 20:49:34 INFO - PROCESS | 25759 | RSS: Main: 182415360 20:49:34 INFO - PROCESS | 25759 | 20:49:36 INFO - PROCESS | 25759 | Cycle 1(1): loaded http://localhost:51653/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:51653/tests/kraken/driver.html?testName=imaging-gaussian-blur) 20:49:36 INFO - PROCESS | 25759 | RSS: Main: 180662272 20:49:36 INFO - PROCESS | 25759 | 20:49:39 INFO - PROCESS | 25759 | Cycle 1(1): loaded http://localhost:51653/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:51653/tests/kraken/driver.html?testName=imaging-darkroom) 20:49:40 INFO - PROCESS | 25759 | RSS: Main: 216576000 20:49:40 INFO - PROCESS | 25759 | 20:49:43 INFO - PROCESS | 25759 | Cycle 1(1): loaded http://localhost:51653/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:51653/tests/kraken/driver.html?testName=imaging-desaturate) 20:49:44 INFO - PROCESS | 25759 | RSS: Main: 215650304 20:49:44 INFO - PROCESS | 25759 | 20:49:47 INFO - PROCESS | 25759 | Cycle 1(1): loaded http://localhost:51653/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:51653/tests/kraken/driver.html?testName=json-parse-financial) 20:49:47 INFO - PROCESS | 25759 | RSS: Main: 215527424 20:49:47 INFO - PROCESS | 25759 | 20:49:49 INFO - PROCESS | 25759 | Cycle 1(1): loaded http://localhost:51653/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:51653/tests/kraken/driver.html?testName=json-stringify-tinderbox) 20:49:49 INFO - PROCESS | 25759 | RSS: Main: 177975296 20:49:49 INFO - PROCESS | 25759 | 20:49:51 INFO - PROCESS | 25759 | Cycle 1(1): loaded http://localhost:51653/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:51653/tests/kraken/driver.html?testName=stanford-crypto-aes) 20:49:52 INFO - PROCESS | 25759 | RSS: Main: 185442304 20:49:52 INFO - PROCESS | 25759 | 20:49:53 INFO - PROCESS | 25759 | Cycle 1(1): loaded http://localhost:51653/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:51653/tests/kraken/driver.html?testName=stanford-crypto-ccm) 20:49:54 INFO - PROCESS | 25759 | RSS: Main: 187543552 20:49:54 INFO - PROCESS | 25759 | 20:49:57 INFO - PROCESS | 25759 | Cycle 1(1): loaded http://localhost:51653/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:51653/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 20:49:57 INFO - PROCESS | 25759 | RSS: Main: 182444032 20:49:57 INFO - PROCESS | 25759 | 20:50:00 INFO - PROCESS | 25759 | Cycle 1(1): loaded http://localhost:51653/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:51653/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 20:50:01 INFO - PROCESS | 25759 | RSS: Main: 185401344 20:50:01 INFO - PROCESS | 25759 | 20:50:02 INFO - PROCESS | 25759 | Cycle 1(1): loaded http://localhost:51653/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:51653/tests/kraken/driver.html?testName=ai-astar) 20:50:02 INFO - PROCESS | 25759 | RSS: Main: 235970560 20:50:02 INFO - PROCESS | 25759 | 20:50:02 INFO - PROCESS | 25759 | __start_tp_report 20:50:02 INFO - PROCESS | 25759 | _x_x_mozilla_page_load 20:50:02 INFO - PROCESS | 25759 | _x_x_mozilla_page_load_details 20:50:02 INFO - PROCESS | 25759 | |i|pagename|runs| 20:50:02 INFO - PROCESS | 25759 | |0;ai-astar;112;110;118;110;110;110;110;111;111;112 20:50:02 INFO - PROCESS | 25759 | |1;audio-beat-detection;150;116;143;170;117;142;149;120;172;119 20:50:02 INFO - PROCESS | 25759 | |2;audio-dft;159;160;159;160;160;160;161;159;160;159 20:50:02 INFO - PROCESS | 25759 | |3;audio-fft;74;75;74;173;76;75;74;74;168;76 20:50:02 INFO - PROCESS | 25759 | |4;audio-oscillator;83;83;143;84;83;84;145;84;84;143 20:50:02 INFO - PROCESS | 25759 | |5;imaging-gaussian-blur;117;116;117;117;116;116;117;116;117;117 20:50:02 INFO - PROCESS | 25759 | |6;imaging-darkroom;146;146;145;146;147;147;145;146;145;146 20:50:02 INFO - PROCESS | 25759 | |7;imaging-desaturate;95;95;96;96;95;95;95;95;96;96 20:50:02 INFO - PROCESS | 25759 | |8;json-parse-financial;52;50;55;50;51;50;49;50;49;50 20:50:02 INFO - PROCESS | 25759 | |9;json-stringify-tinderbox;60;61;60;60;61;61;60;61;61;61 20:50:02 INFO - PROCESS | 25759 | |10;stanford-crypto-aes;73;72;72;71;76;71;73;77;82;73 20:50:02 INFO - PROCESS | 25759 | |11;stanford-crypto-ccm;119;125;131;121;122;126;115;116;154;120 20:50:02 INFO - PROCESS | 25759 | |12;stanford-crypto-pbkdf2;155;155;156;162;152;152;153;155;152;156 20:50:02 INFO - PROCESS | 25759 | |13;stanford-crypto-sha256-iterative;71;61;63;62;63;62;60;63;60;63 20:50:02 INFO - PROCESS | 25759 | __end_tp_report 20:50:02 INFO - PROCESS | 25759 | __start_cc_report 20:50:02 INFO - PROCESS | 25759 | _x_x_mozilla_cycle_collect,1645 20:50:02 INFO - PROCESS | 25759 | __end_cc_report 20:50:02 INFO - PROCESS | 25759 | __startTimestamp1474257002890__endTimestamp 20:50:02 INFO - PROCESS | 25759 | 20:50:02 INFO - PROCESS | 25759 | ------- Summary: start ------- 20:50:02 INFO - PROCESS | 25759 | Number of tests: 14 20:50:02 INFO - PROCESS | 25759 | 20:50:02 INFO - PROCESS | 25759 | [#0] ai-astar Cycles:10 Average:111.40 Median:111.00 stddev:2.46 (2.2%) stddev-sans-first:2.60 20:50:02 INFO - PROCESS | 25759 | Values: 112.0 110.0 118.0 110.0 110.0 110.0 110.0 111.0 111.0 112.0 20:50:02 INFO - PROCESS | 25759 | 20:50:02 INFO - PROCESS | 25759 | [#1] audio-beat-detection Cycles:10 Average:139.80 Median:146.00 stddev:21.25 (14.6%) stddev-sans-first:22.21 20:50:02 INFO - PROCESS | 25759 | Values: 150.0 116.0 143.0 170.0 117.0 142.0 149.0 120.0 172.0 119.0 20:50:02 INFO - PROCESS | 25759 | 20:50:02 INFO - PROCESS | 25759 | [#2] audio-dft Cycles:10 Average:159.70 Median:160.00 stddev:0.67 (0.4%) stddev-sans-first:0.67 20:50:02 INFO - PROCESS | 25759 | Values: 159.0 160.0 159.0 160.0 160.0 160.0 161.0 159.0 160.0 159.0 20:50:02 INFO - PROCESS | 25759 | 20:50:02 INFO - PROCESS | 25759 | [#3] audio-fft Cycles:10 Average:93.90 Median:74.50 stddev:40.40 (54.2%) stddev-sans-first:42.20 20:50:02 INFO - PROCESS | 25759 | Values: 74.0 75.0 74.0 173.0 76.0 75.0 74.0 74.0 168.0 76.0 20:50:02 INFO - PROCESS | 25759 | 20:50:02 INFO - PROCESS | 25759 | [#4] audio-oscillator Cycles:10 Average:101.60 Median:83.50 stddev:29.04 (34.8%) stddev-sans-first:30.01 20:50:02 INFO - PROCESS | 25759 | Values: 83.0 83.0 143.0 84.0 83.0 84.0 145.0 84.0 84.0 143.0 20:50:02 INFO - PROCESS | 25759 | 20:50:02 INFO - PROCESS | 25759 | [#5] imaging-gaussian-blur Cycles:10 Average:116.60 Median:117.00 stddev:0.52 (0.4%) stddev-sans-first:0.53 20:50:02 INFO - PROCESS | 25759 | Values: 117.0 116.0 117.0 117.0 116.0 116.0 117.0 116.0 117.0 117.0 20:50:02 INFO - PROCESS | 25759 | 20:50:02 INFO - PROCESS | 25759 | [#6] imaging-darkroom Cycles:10 Average:145.90 Median:146.00 stddev:0.74 (0.5%) stddev-sans-first:0.78 20:50:02 INFO - PROCESS | 25759 | Values: 146.0 146.0 145.0 146.0 147.0 147.0 145.0 146.0 145.0 146.0 20:50:02 INFO - PROCESS | 25759 | 20:50:02 INFO - PROCESS | 25759 | [#7] imaging-desaturate Cycles:10 Average:95.40 Median:95.50 stddev:0.52 (0.5%) stddev-sans-first:0.53 20:50:02 INFO - PROCESS | 25759 | Values: 95.0 95.0 96.0 96.0 95.0 95.0 95.0 95.0 96.0 96.0 20:50:02 INFO - PROCESS | 25759 | 20:50:02 INFO - PROCESS | 25759 | [#8] json-parse-financial Cycles:10 Average:50.60 Median:50.00 stddev:1.78 (3.6%) stddev-sans-first:1.81 20:50:02 INFO - PROCESS | 25759 | Values: 52.0 50.0 55.0 50.0 51.0 50.0 49.0 50.0 49.0 50.0 20:50:02 INFO - PROCESS | 25759 | 20:50:02 INFO - PROCESS | 25759 | [#9] json-stringify-tinderbox Cycles:10 Average:60.60 Median:61.00 stddev:0.52 (0.8%) stddev-sans-first:0.50 20:50:02 INFO - PROCESS | 25759 | Values: 60.0 61.0 60.0 60.0 61.0 61.0 60.0 61.0 61.0 61.0 20:50:02 INFO - PROCESS | 25759 | 20:50:02 INFO - PROCESS | 25759 | [#10] stanford-crypto-aes Cycles:10 Average:74.00 Median:73.00 stddev:3.43 (4.7%) stddev-sans-first:3.62 20:50:02 INFO - PROCESS | 25759 | Values: 73.0 72.0 72.0 71.0 76.0 71.0 73.0 77.0 82.0 73.0 20:50:02 INFO - PROCESS | 25759 | 20:50:02 INFO - PROCESS | 25759 | [#11] stanford-crypto-ccm Cycles:10 Average:124.90 Median:123.50 stddev:11.28 (9.1%) stddev-sans-first:11.76 20:50:02 INFO - PROCESS | 25759 | Values: 119.0 125.0 131.0 121.0 122.0 126.0 115.0 116.0 154.0 120.0 20:50:02 INFO - PROCESS | 25759 | 20:50:02 INFO - PROCESS | 25759 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:154.80 Median:155.00 stddev:3.01 (1.9%) stddev-sans-first:3.19 20:50:02 INFO - PROCESS | 25759 | Values: 155.0 155.0 156.0 162.0 152.0 152.0 153.0 155.0 152.0 156.0 20:50:02 INFO - PROCESS | 25759 | 20:50:02 INFO - PROCESS | 25759 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:62.80 Median:63.00 stddev:3.12 (5.0%) stddev-sans-first:1.27 20:50:02 INFO - PROCESS | 25759 | Values: 71.0 61.0 63.0 62.0 63.0 62.0 60.0 63.0 60.0 63.0 20:50:02 INFO - PROCESS | 25759 | -------- Summary: end -------- 20:50:02 INFO - PROCESS | 25759 | 20:50:03 INFO - TEST-INFO | 25759: exit 0 20:50:03 INFO - TEST-OK | kraken | took 60175ms 20:50:03 INFO - SUITE-END | took 1173s 20:50:04 INFO - Completed test suite (00:19:33) 20:50:04 INFO - javascript benchmark 20:50:04 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3233.725133363664, 3250.2361944942936, 3264.6572109928443, 3298.455754800458, 3309.8345082745873, 5591.832245032645, 5703.828885133442, 5727.627704199234, 5746.798862039829, 5758.5393168546525, 5692.715364231793, 5737.282839645044, 5737.540996720263, 5744.310682718069, 5749.827505174841, 4968.677035992667, 5093.286939828427, 5177.844664660066, 5178.792848286064, 5214.921776173361, 5103.2090026045835, 5106.463821298766, 5171.663841850268, 5185.274061631375, 5187.325647665817, 5147.279380886679, 5150.587952963772, 5168.948310516903, 5169.1987741899875, 5169.56058735008, 5223.0, 5229.346331708536, 5229.450907654679, 5243.056249875021, 5266.4733526647415, 4996.500349964975, 5063.0380227756605, 5082.466341034212, 5126.0, 5132.5893928486, 5641.210230567724, 5657.0, 5662.348829884575, 5687.630304030225, 5699.373068962411, 6096.024636058254, 6100.298465676459, 6101.969490152521, 6105.755769769204, 6120.234970628671, 5893.852653683649, 6064.60580062299, 6077.574569780118, 6088.269407671063, 6161.383861613847, 5718.199452076713, 5721.113227449752, 5752.7986520472, 5758.7120643968055, 5761.53907687384, 6225.0, 6264.029075493306, 6265.8120256392385, 6270.372962703693, 6275.497960163176, 5717.371089180187, 5935.050391937356, 5976.760929562769, 5977.820665380045, 6024.307204671519, 6082.878342433127, 6125.479334256549, 6130.846728831815, 6132.816015519542, 6136.9079463808075, 6084.726187321581, 6084.803960712311, 6106.419890110433, 6107.725152368154, 6133.662648554372, 5868.23712917318, 5874.73563689635, 5940.227770389908, 6046.244219472566, 6069.726862291119, 4543.318502224627, 4655.44134703831, 4659.95340046602, 4664.626829853603, 4689.413823272091, 4146.502419709594, 4147.834086636561, 4153.813078411479, 4176.77027763476, 4179.226843034048, 5607.5513958884, 5845.9707701462075, 5851.297844258631, 5859.912101318484, 5877.441643043904, 6027.758889644453, 6052.515798736091, 6089.269287685506, 6147.66187859663, 6151.384861513813, 5760.135979603093, 5805.82582522525, 5808.448197421239, 5808.738606762706, 5836.88326233473, 5153.020926024044, 5221.269022336875, 5267.0, 5268.0, 5284.04887120322, 6240.063990401476, 6280.842978925563, 6301.14934483848, 6377.904331434939, 6392.0, 6180.783672571618, 6184.412480814315, 6185.628862268279, 6203.937960620336, 6208.596441231305, 6113.354685976905, 6342.207224096988, 6347.0479428086155, 6361.077643741724, 6363.331850155667, 5904.291485021825, 5939.673317967553, 5954.642721436728, 6001.4598686116715, 6049.606775559574, 6091.086337049478, 6123.0, 6129.233845769279, 6136.079588061648, 6137.723802428991, 5814.04068328721, 5921.052631578927, 5978.372270911492, 5989.0, 5993.4006599341465, 5875.823725288248, 5933.347331793586, 5974.61165024272, 6061.575689701678, 6081.239845019372, 5966.164736936746, 5992.43071908168, 5993.101034844808, 6000.8799824004145, 6004.519638429003, 5756.107803290497, 6080.69596520181, 6084.0, 6084.969575152096, 6094.634321940521, 5650.898074875524, 5833.533317334518, 5854.795082172103, 5864.091065884624, 5914.674692891932], "value": 5616.622364054418, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [583.3262581718117, 629.1506466270534, 664.7307840324669, 671.9832004199898, 673.9427148692364, 34702.82648586757, 46551.76724116379, 47304.52695473044, 47380.52619473804, 47491.52508474914, 48086.75956620221, 48326.03347933039, 48439.273410898866, 48455.51544484554, 48485.757571212096, 8464.873026904601, 9231.95384023079, 9393.436393816393, 9397.483138427384, 9417.76455588609, 10847.132229421628, 11120.165987550925, 11127.276727012759, 11458.427078646077, 11465.312081275108, 11320.660380188567, 11426.942865285704, 11427.200095993283, 11430.199886007982, 11437.085033197323, 11201.439928003609, 11267.26762760418, 11270.830937535984, 11275.436228188557, 11283.37941413222, 10953.17851161166, 10956.0, 10992.450377481095, 11019.173561982803, 11033.227674062819, 45485.545144548465, 45882.311765323546, 45919.08161836778, 45948.77025614884, 45949.77025114887, 3839.347310957116, 4056.898577535556, 4213.073123912734, 4244.5967633075115, 4606.309053641947, 34856.12859678503, 34958.82520587381, 35124.12189695252, 35220.0, 35336.64663353385, 4029.3351596986463, 4040.7777572233517, 4061.1065565575527, 4094.099298154431, 4167.874963751093, 391910.0, 393037.0, 393465.0, 394941.0, 397025.0, 63218.36781632217, 63241.36758632447, 63756.36243637504, 63766.0, 63928.360716393174, 39713.40429893554, 40441.39337909934, 40672.18655626871, 42867.142657147306, 42918.78540607277, 19080.236790528223, 19249.711254331196, 19255.32606358799, 19260.614787704446, 19269.0, 13615.591532254048, 13917.164970101827, 14139.929300353433, 14985.32566034531, 15067.4726384576, 20936.790632093787, 20986.895065524575, 20991.265305714227, 21023.78976210249, 21300.467488312606, 7373.520721153215, 7477.588732619649, 7520.21037791035, 7641.961790191013, 7679.961600191963, 1057.7937302225992, 1075.408525311076, 1101.4712937789907, 1106.5850306135198, 1121.7083558274908, 34835.303293934485, 35206.119847003516, 35211.85066959255, 35296.29407411837, 35667.28665426677, 1126.69016020595, 1127.1658972360558, 1193.7135087578918, 1205.475618106109, 1261.4638778519093, 18121.18454669543, 18394.448166555027, 18522.0, 18645.0, 18683.90658046701, 612834.0, 616167.0, 617434.0, 617695.0, 619056.0, 64565.35434645593, 64958.35041649618, 64961.67519162374, 65066.34933650603, 65136.0, 1334.9265790381621, 1456.2209218068283, 1477.4385733421232, 1478.2165452310078, 1482.384810303712, 33900.49149262714, 36299.27401452009, 36453.73412421131, 36677.63322366742, 36752.448713269856, 54436.0, 54860.72569637286, 54903.0, 54915.45084549103, 55063.0, 460858.0, 461209.0, 463297.0, 464205.0, 464210.0, 31635.0, 31677.14479635782, 31852.840735796173, 31895.521567176045, 33106.17234569155, 53990.73004634952, 54034.45965540294, 54168.45831541792, 54504.727476362365, 54767.45232547623, 45503.544964551256, 45570.0, 46285.30572041489, 46380.536194638975, 47377.28934065923, 46054.53945460502, 46796.0, 46959.0, 47504.287435689184, 47521.762391187825, 45723.31415028711, 45948.310775339065, 46290.30564541602, 46668.299975501075, 47742.283865741345, 38263.61736382712, 40794.38808417936, 40906.79546602248, 41119.588804111576, 41736.3739543901, 30759.0, 30807.383852323277, 30853.382932341676, 30990.69009309968, 31102.222444438165], "value": 22094.58674811282, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [397.8527944660476, 409.7951024487754, 421.21023081721773, 432.3838530094615, 441.15959097918477, 15235.390584376612, 20521.281755138574, 20754.89622551889, 21080.789192108074, 21422.785772142273, 20175.49561260969, 20438.0, 21611.891940540274, 21883.781162188374, 21931.890340548274, 5391.676499410048, 5667.319921609413, 5700.515456186229, 5761.395053519383, 5773.2494775679315, 4235.6187943085115, 4492.213862574053, 5696.9745445819735, 5729.197912292282, 5733.082706766918, 6956.617386043766, 7084.893726594105, 7093.0, 7099.396551293146, 7099.964500177492, 5888.234529511179, 6119.0, 6163.691815409256, 7062.470314726391, 7069.646517674121, 6087.695615219265, 6098.451139397475, 6101.359357267512, 6102.389761023906, 6102.420270074336, 15421.383144674199, 15567.766483502757, 15680.21598920061, 15722.37110515578, 18860.905695471436, 2402.87985600721, 2479.727230004716, 2511.8657167487463, 2700.96013034981, 3115.5482455044116, 11101.611443599435, 11286.54853805855, 11559.94220028903, 11607.361595112265, 11774.352410617412, 2032.1363420546209, 2034.8677335973114, 2053.9075741591514, 2084.6351888419617, 2148.6239908016037, 82001.58999204966, 89713.0, 90673.0, 91283.54358228167, 91594.0, 11640.650780476599, 11876.0, 11927.0, 12174.452149653287, 12218.633440996606, 17067.0, 17138.228779704943, 17154.742678859828, 17160.742588861176, 17167.484975450756, 8688.565571721389, 8737.956310218407, 8893.644254229888, 8948.552572371485, 9153.633854645872, 8164.550949697824, 8290.751277461564, 8539.871901921477, 8866.73399798007, 9070.365074444726, 5048.368953880765, 5156.871078223075, 5157.4584668610005, 5170.793168273302, 5229.869253268623, 2041.3365656161807, 2123.235635171337, 2136.935891923245, 2139.882306473159, 2153.224839057938, 964.609333220047, 1051.731808388856, 1071.212658695858, 1080.0873262093519, 1087.8041952448634, 20010.29963951255, 21944.45138871509, 21961.67057494139, 21965.34103976883, 22125.778742212693, 1017.414986382818, 1039.5841663334727, 1047.073340094007, 1053.599632139782, 1053.957841686324, 11211.94394028041, 12031.75936481283, 12070.336131512675, 12214.816777748341, 12249.510019599295, 550131.0, 560783.0, 565636.0, 566497.0, 574797.0, 60873.39126608766, 78057.0, 95389.76614031526, 100201.0, 104617.0, 1022.9590816367411, 1046.6650671785108, 1056.334509259162, 1070.288258308232, 1076.4617691154422, 18516.81483185151, 18762.155702993125, 19051.809481905002, 19220.423387298404, 19262.32581859628, 46049.76975115103, 46108.769456152506, 46333.76833115813, 46397.0, 46668.299975499714, 59664.403355967625, 63509.68245158745, 64152.0, 64268.357316426234, 66024.33975660375, 9030.36787424874, 9151.633934642807, 9161.49611771359, 9407.341486095907, 9544.475053872104, 10767.40779257117, 14171.078879872775, 14181.929090354482, 14392.712145757236, 14442.061266018096, 12470.376481176087, 12516.436760345625, 12624.936875315565, 12660.17708848922, 12675.80986285225, 12248.571300004458, 12452.937735311265, 12606.369681515704, 12837.037222208184, 13187.293111063871, 12803.29581873006, 12845.465938993393, 12863.742725145632, 12967.416466258852, 13578.660533486742, 8870.600822963112, 8876.511791851251, 9252.583633736363, 9560.0, 9762.902370976199, 8981.685641002534, 12100.273983561014, 12126.818097728365, 12391.75216495683, 12431.564895228985], "value": 9819.783025950048, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1427.7644188708864, 1568.6470544127574, 1585.857272845444, 1747.5805806606418, 1845.3910209630824, 9056.547172641367, 9348.85976710349, 9514.571844267017, 9768.560414781334, 9793.7551561211, 9628.759281017978, 9736.75658108546, 9752.12230899219, 9754.561044752985, 9754.902450975487, 2533.949321013574, 2572.3826281692445, 2572.7041390240083, 2573.987130064347, 2577.226831950417, 2499.987500062506, 2539.987300063497, 2552.757488038643, 2566.5251928393213, 2568.666073410454, 2528.5448619248527, 2531.8227724059325, 2570.4602033572974, 2577.445849142434, 2578.8839502222445, 2475.282168171228, 2500.549901017816, 2510.0587279770084, 2521.495700859817, 2523.4322277487604, 2427.8057755379527, 2452.7179374371817, 2461.88921498533, 2464.7781699647117, 2514.9874250628814, 9564.760880977961, 9620.086091821266, 9624.711258662252, 9659.71020869375, 9721.125098741077, 5700.344460387026, 5884.617499862496, 5891.204687367193, 5891.469767720926, 5894.351621321651, 3653.926921461556, 3690.7231957603285, 3705.6664900158844, 3723.2739615774935, 3726.9068273293115, 1142.3888219802366, 1142.4402042998954, 1161.558607729066, 1178.6464060781816, 2071.8964051797348, 13462.394192261372, 13979.510717124953, 14027.438902443992, 14039.157650541, 14056.36746346427, 4682.052626113268, 4843.782029808667, 4903.4606193318705, 5016.698998060128, 5137.486251374833, 10692.572297108029, 10700.41147736882, 10700.678979630624, 10704.464776761131, 10715.785684286271, 5831.125331200354, 5849.2103566018895, 5853.180554722342, 5856.0630299151935, 5860.824175274749, 4758.286257061399, 4796.064767370399, 5325.600579956519, 5334.839954801362, 5482.396936337, 4235.682323825664, 4294.098239369705, 4307.719998200169, 4345.174416860787, 4352.129574085196, 1255.9623211303492, 1294.9482020719065, 1294.9676258093434, 1300.2718477652356, 1306.8366454193226, 953.4517652349788, 1016.8169729448771, 1020.4183615339185, 1024.9436281004616, 1030.3096925060227, 6220.377962203834, 6246.6876656167005, 6308.621482710961, 6378.52161087911, 6399.616023038632, 483.76779146010114, 489.17573887998833, 489.71107046842536, 499.14147666014395, 500.85475212188055, 4118.19695159441, 4192.3082691355585, 4249.978750106292, 4305.655547556188, 4324.983628847219, 12002.0, 12211.389430528438, 12254.958328541996, 12366.948809351126, 12388.380580970916, 12256.387180640932, 12288.324142172267, 12303.630891073282, 12308.81536776949, 12314.50741970329, 8535.487870727777, 8908.465492070496, 8919.643214271486, 8924.0, 9077.727668169833, 6067.211262536019, 6137.478314343331, 6335.239771227482, 6344.0333496616195, 6682.0, 10443.321184123008, 11011.0, 11090.0, 11116.499757511094, 11213.66359009231, 11080.667579972614, 11257.831132532854, 11287.604933827277, 11521.539138434704, 11684.18210725241, 8640.524771137647, 9150.679726209552, 9209.769755755891, 9214.2628589714, 9241.491717955578, 8854.867176992222, 9124.817503650023, 9276.675316363895, 9363.063693630855, 9373.390729602554, 8094.757157285291, 8286.668533258844, 8339.45793523419, 8428.4942903426, 8498.617562209593, 8060.798480038046, 8110.391720620859, 8111.472754270953, 8160.5103693778565, 8208.58957052133, 7468.925310746823, 7471.476996610185, 7529.548227106391, 7530.962345188239, 7534.472586918863, 5116.053530096944, 5336.17289320156, 5525.226468294522, 5659.801906933238, 5707.857303567444, 5595.608307418442, 5674.460926212003, 5690.146478028329, 5701.315842098808, 5758.193852860687], "value": 4946.5773924174255, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [356.2678695281198, 363.76718899904057, 365.6197554543275, 369.7060836634875, 372.97016238700905, 5444.40111587725, 5533.08704063829, 5536.806211782588, 5538.418466061066, 5551.195076713879, 5152.562032227265, 5263.105272103742, 5314.840554783353, 5316.76074576645, 5461.890762184754, 4204.159168166364, 4286.485621725397, 4321.200577893084, 4379.102284031782, 4384.276594361926, 4313.892152696177, 4332.393464914914, 4335.1329734053315, 4336.479622445311, 4354.8911277218, 4057.1885622875398, 4246.704755049708, 4247.299195632732, 4273.551277115905, 4312.331588603772, 4253.829846806124, 4261.680373971964, 4266.360045993095, 4266.786660666954, 4267.935980960288, 3747.962520374816, 3763.1156678180605, 3778.471014058034, 3779.111908701453, 3790.3177428062936, 5253.290805741214, 5292.735363231862, 5370.91943620846, 5378.650387724785, 5383.67697938125, 4844.37023186987, 4873.34209881665, 4873.902521949542, 4877.561019508225, 4887.3890763654545, 5650.802221922254, 5662.745176467069, 5700.201971723962, 5702.686352250625, 5703.686297253649, 4040.4545386372756, 4082.8775136745944, 4130.925959250587, 4134.090500089976, 4164.313453051489, 5876.882462350772, 5920.437558431942, 5932.762689492415, 5934.940650593482, 5943.851403714899, 4267.871963841089, 4277.850275240352, 4278.251306021434, 4281.785910704514, 4286.2284788738325, 4126.484189476316, 4310.590493903074, 4315.525292217853, 4325.653947684178, 4332.415123958288, 4907.705537667752, 4908.2637604358915, 4909.975450122798, 4913.0, 4918.48355922623, 5770.307563092372, 5774.740136693859, 5778.219940308089, 5796.42035796417, 5798.797042103506, 1469.9853001469849, 1491.1202390589604, 1493.8580834820675, 1493.9775903361458, 1500.129924643704, 933.8739270198572, 958.8897276813116, 961.5913236874301, 962.9229661627053, 964.6061441217373, 5002.2237658099375, 5007.924881126786, 5022.874428139253, 5047.637137972727, 5049.192129259301, 5196.45437229093, 5215.843524694265, 5228.63399562027, 5235.921461178085, 5239.397469291081, 4661.229891977646, 4970.925436118461, 4998.725070121105, 4999.500049994971, 5025.547700706954, 2771.9029833955715, 2773.7226277372506, 2782.0803645484552, 2782.443511297708, 2799.328161241328, 5289.709066001406, 5309.442508536626, 5312.768637172397, 5313.94448192291, 5341.893162136736, 5273.6572122811895, 5298.496642819003, 5613.877224555105, 6072.180255665428, 6072.574919755663, 6069.725357674938, 6113.144159817629, 6144.201253836973, 6159.476444502267, 6177.073438984188, 5691.971540142273, 5945.31628862669, 5990.921634105728, 6003.939960600338, 6023.006203976295, 5862.970685146547, 5887.264091988502, 5916.556258280732, 5929.005852044862, 5942.584019118794, 6137.202163718688, 6169.876602468015, 6174.25908890936, 6177.84555386101, 6184.721687523982, 4205.306124489425, 4250.596193361625, 4399.032212913154, 4400.735955842531, 4409.250427427409, 5812.854678633068, 5831.35855055935, 5872.706364681834, 5907.9704601476715, 5923.08192230205, 5782.85542861415, 5801.738921748447, 5806.332271788799, 5819.621724587888, 5823.592348535732, 5652.85867853307, 5690.430956904442, 5757.625754325955, 5769.365369809401, 5799.536037117105, 5755.913661295168, 5767.048437008015, 5767.798127065533, 5778.566607504538, 5797.478097027806, 5566.860828479321, 5800.1299805029585, 5801.245838041028, 5811.779526299524, 5837.3870743571315, 5598.384177740364, 5636.392708666374, 5652.943470565242, 5656.2929633795775, 5670.603057785916], "value": 4331.763393566465, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [813.3655748516156, 838.8909441772572, 879.3273146043277, 901.526698483296, 908.1281969309463, 16460.0946947918, 17482.65034699307, 17531.82468175318, 17568.560785980324, 17594.20826062827, 15766.290516926738, 15885.205739713027, 16016.59958501038, 16245.51263462095, 16403.753943690823, 727.6652739739726, 871.1810897756089, 889.915458031486, 893.3478560650728, 894.4365050018479, 843.3843294395095, 880.6257340630207, 883.0771843423638, 883.1786438612088, 883.8630012348096, 937.8780758501385, 939.192294626624, 941.1482608239554, 941.3928016429398, 941.6562954521619, 768.3852917665901, 936.4896131608222, 936.8032713130217, 937.0582564522679, 937.5593471068587, 665.5640555436148, 666.6100331306143, 667.5494041522002, 674.6053558668185, 674.8582797612507, 15721.528354149394, 15744.212789360601, 15778.921105394515, 15784.763228551581, 15823.208839557976, 15053.849461505355, 15458.304376303093, 15577.922110389489, 15697.293621787047, 15697.764533532007, 14288.214148221947, 14311.928440357835, 14563.344649490798, 14704.926475367662, 14786.334614942354, 4747.8575642730775, 4764.332993380929, 4766.428028636551, 4771.141194584973, 4846.36997190363, 84864.57567712184, 85628.0, 85972.0, 89591.55204223937, 90101.54949225212, 12410.193337433038, 12824.615261542169, 12853.778891005339, 13107.672308192372, 13108.737825243441, 24037.0, 24060.0, 24062.51874962491, 24129.034838606258, 24221.63667544988, 9185.724428267162, 9255.213306868856, 9354.20489258407, 9511.0, 9544.331896767299, 4961.82633607822, 5069.483377075704, 5083.87290317745, 5183.974080129575, 5258.789648414097, 3832.904177395532, 3958.6239307265764, 3975.7813320267664, 3977.443157957888, 3981.9845939285296, 642.2389468479867, 656.0814859197162, 656.409231691472, 670.5607826873405, 672.9427998620172, 513.064321229772, 575.8243735660664, 630.2783313106468, 646.6863571167979, 736.8194792275926, 9854.260930430246, 10434.113100386552, 10447.68656940293, 10573.418461984666, 10624.309419887837, 596.4244504053609, 628.8050704281687, 682.0825989044727, 706.2372637551432, 710.1052673631159, 4909.533594308535, 4967.726775027336, 4969.254611808257, 5030.346055012825, 5099.923501147486, 76064.23935760572, 76134.61932690413, 76155.23844761592, 76273.23726762773, 76808.61595692097, 67208.6639566799, 91441.54279228694, 109907.45046274718, 111735.44132279449, 112426.43786781178, 97658.51170744101, 98027.50986245023, 98386.5080674592, 98791.0, 100186.0, 9531.285153613368, 9548.952255238679, 9688.224942004763, 9737.14994151109, 9787.36382135159, 57753.422465776486, 59384.40615593789, 59544.70227648834, 59940.0, 61513.384866150765, 82067.17932820834, 89745.55127224584, 90186.0981390204, 92168.07831921594, 95521.0, 11955.0, 12167.087468439726, 12836.16564923277, 12911.935440322739, 13225.603231903058, 13889.305534723426, 13929.651758705708, 14024.0, 14060.929695351868, 14162.787558186841, 12371.75256494883, 12844.165129266568, 12944.546940857024, 13023.260430469643, 13527.594172174851, 12983.480660773463, 13166.736665266833, 13408.463661453432, 13421.127626704232, 14187.57437276842, 12806.551770687982, 13187.868121318665, 13361.198328100345, 13682.31588420555, 13695.520656776966, 10443.425611591436, 10535.15718742514, 10602.575896964036, 10827.837582436112, 10828.404437755684, 9673.854892176481, 10283.84574231402, 10304.587816487257, 10367.533460994424, 10377.117944974761], "value": 7911.666052068313, "unit": "score"}], "name": "dromaeo_css", "value": 7688.783282651524, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 110.0, 118.0, 110.0, 110.0, 110.0, 110.0, 111.0, 111.0, 112.0], "value": 111.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 116.0, 143.0, 170.0, 117.0, 142.0, 149.0, 120.0, 172.0, 119.0], "value": 139.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [159.0, 160.0, 159.0, 160.0, 160.0, 160.0, 161.0, 159.0, 160.0, 159.0], "value": 159.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 75.0, 74.0, 173.0, 76.0, 75.0, 74.0, 74.0, 168.0, 76.0], "value": 93.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 83.0, 143.0, 84.0, 83.0, 84.0, 145.0, 84.0, 84.0, 143.0], "value": 101.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 116.0, 117.0, 117.0, 116.0, 116.0, 117.0, 116.0, 117.0, 117.0], "value": 116.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 146.0, 145.0, 146.0, 147.0, 147.0, 145.0, 146.0, 145.0, 146.0], "value": 145.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 96.0, 96.0, 95.0, 95.0, 95.0, 95.0, 96.0, 96.0], "value": 95.4, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [52.0, 50.0, 55.0, 50.0, 51.0, 50.0, 49.0, 50.0, 49.0, 50.0], "value": 50.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 61.0, 60.0, 60.0, 61.0, 61.0, 60.0, 61.0, 61.0, 61.0], "value": 60.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 72.0, 72.0, 71.0, 76.0, 71.0, 73.0, 77.0, 82.0, 73.0], "value": 74.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [119.0, 125.0, 131.0, 121.0, 122.0, 126.0, 115.0, 116.0, 154.0, 120.0], "value": 124.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [155.0, 155.0, 156.0, 162.0, 152.0, 152.0, 153.0, 155.0, 152.0, 156.0], "value": 154.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [71.0, 61.0, 63.0, 62.0, 63.0, 62.0, 60.0, 63.0, 60.0, 63.0], "value": 62.8, "unit": "score"}], "name": "kraken", "value": 1492.0, "alertThreshold": 2.0}]} 20:50:04 INFO - Return code: 0 20:50:04 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 20:50:04 INFO - # TBPL SUCCESS # 20:50:04 INFO - Running post-action listener: _resource_record_post_action 20:50:04 INFO - [mozharness: 2016-09-19 03:50:04.199105Z] Finished run-tests step (success) 20:50:04 INFO - Running post-run listener: _resource_record_post_run 20:50:04 INFO - Total resource usage - Wall time: 1190s; CPU: 13.0%; Read bytes: 12288; Write bytes: 328634368; Read time: 320; Write time: 221868 20:50:04 INFO - TinderboxPrint: CPU usage
13.0% 20:50:04 INFO - TinderboxPrint: I/O read bytes / time
12,288 / 320 20:50:04 INFO - TinderboxPrint: I/O write bytes / time
328,634,368 / 221,868 20:50:04 INFO - TinderboxPrint: CPU idle
8,266.3 (87.0%) 20:50:04 INFO - TinderboxPrint: CPU user
1,185.3 (12.5%) 20:50:04 INFO - TinderboxPrint: Swap in / out
0 / 0 20:50:04 INFO - install - Wall time: 16s; CPU: 13.0%; Read bytes: 4096; Write bytes: 77209600; Read time: 172; Write time: 61400 20:50:04 INFO - run-tests - Wall time: 1174s; CPU: 13.0%; Read bytes: 4096; Write bytes: 248938496; Read time: 140; Write time: 160244 20:50:04 INFO - Running post-run listener: _upload_blobber_files 20:50:04 INFO - Blob upload gear active. 20:50:04 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 20:50:04 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:50:04 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:50:04 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:50:05 INFO - (blobuploader) - INFO - Open directory for files ... 20:50:05 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 20:50:05 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 20:50:05 INFO - (blobuploader) - INFO - Uploading, attempt #1. 20:50:06 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 20:50:06 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 20:50:06 INFO - (blobuploader) - INFO - Done attempting. 20:50:06 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 20:50:06 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 20:50:06 INFO - (blobuploader) - INFO - Uploading, attempt #1. 20:50:09 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 20:50:09 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 20:50:09 INFO - (blobuploader) - INFO - Done attempting. 20:50:09 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 20:50:09 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 20:50:09 INFO - (blobuploader) - INFO - Uploading, attempt #1. 20:50:10 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 20:50:10 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 20:50:10 INFO - (blobuploader) - INFO - Done attempting. 20:50:10 INFO - (blobuploader) - INFO - Iteration through files over. 20:50:10 INFO - Return code: 0 20:50:10 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 20:50:10 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 20:50:10 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/11f07575390e5fe8a46b20cb77768eee763c169f943f35238cba81533da9977c7428ca9456376e3d416f6e291e9314526b21b406debaf61a13986b8fd7738109", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/84ba5be0237e9367ff7cc37b7a3beccbfb8b9eb8c471e69f3c4b605c54a568cc015a191c74e329b33934f48cb31b093a72e7eeaf5955f5fbfa178ec4c2e9e510", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/3d2d063f2203963d2a2890b5b28e9bcc195df9693f5af2ded8e85705ad07aeb4322d5231a21e68a3d3aa63aa9dfdbe35c8bc5a70a6763c1da0fe78afce6cfb5c"} 20:50:10 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 20:50:10 INFO - Writing to file /builds/slave/test/properties/blobber_files 20:50:10 INFO - Contents: 20:50:10 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/11f07575390e5fe8a46b20cb77768eee763c169f943f35238cba81533da9977c7428ca9456376e3d416f6e291e9314526b21b406debaf61a13986b8fd7738109", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/84ba5be0237e9367ff7cc37b7a3beccbfb8b9eb8c471e69f3c4b605c54a568cc015a191c74e329b33934f48cb31b093a72e7eeaf5955f5fbfa178ec4c2e9e510", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/3d2d063f2203963d2a2890b5b28e9bcc195df9693f5af2ded8e85705ad07aeb4322d5231a21e68a3d3aa63aa9dfdbe35c8bc5a70a6763c1da0fe78afce6cfb5c"} 20:50:10 INFO - Running post-run listener: copy_logs_to_upload_dir 20:50:10 INFO - Copying logs to upload dir... 20:50:10 INFO - mkdir: /builds/slave/test/build/upload/logs 20:50:10 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1679.239418 ========= master_lag: 0.78 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 28 mins, 0 secs) (at 2016-09-18 20:50:10.595933) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-18 20:50:10.599058) ========= 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=cbe409c6de289db6ac707de3000001d4-1474254081.944973-20371291 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/11f07575390e5fe8a46b20cb77768eee763c169f943f35238cba81533da9977c7428ca9456376e3d416f6e291e9314526b21b406debaf61a13986b8fd7738109", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/84ba5be0237e9367ff7cc37b7a3beccbfb8b9eb8c471e69f3c4b605c54a568cc015a191c74e329b33934f48cb31b093a72e7eeaf5955f5fbfa178ec4c2e9e510", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/3d2d063f2203963d2a2890b5b28e9bcc195df9693f5af2ded8e85705ad07aeb4322d5231a21e68a3d3aa63aa9dfdbe35c8bc5a70a6763c1da0fe78afce6cfb5c"} build_url:https://queue.taskcluster.net/v1/task/ePIxxufxTeCzWVOohoB-9w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011220 build_url: 'https://queue.taskcluster.net/v1/task/ePIxxufxTeCzWVOohoB-9w/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/11f07575390e5fe8a46b20cb77768eee763c169f943f35238cba81533da9977c7428ca9456376e3d416f6e291e9314526b21b406debaf61a13986b8fd7738109", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/84ba5be0237e9367ff7cc37b7a3beccbfb8b9eb8c471e69f3c4b605c54a568cc015a191c74e329b33934f48cb31b093a72e7eeaf5955f5fbfa178ec4c2e9e510", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/3d2d063f2203963d2a2890b5b28e9bcc195df9693f5af2ded8e85705ad07aeb4322d5231a21e68a3d3aa63aa9dfdbe35c8bc5a70a6763c1da0fe78afce6cfb5c"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-18 20:50:10.627699) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-18 20:50:10.628030) ========= 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=cbe409c6de289db6ac707de3000001d4-1474254081.944973-20371291 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004547 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-18 20:50:10.677441) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-18 20:50:10.680801) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-18 20:50:10.681093) ========= ========= Total master_lag: 0.91 =========