builder: mozilla-aurora_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-001 starttime: 1472383336.96 results: success (0) buildid: 20160827004001 builduid: 5275c599c6904397a1806c151c356c46 revision: 4d38a69eda61f663463af8f020447e9afbb118b1 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-08-28 04:22:16.958026) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-08-28 04:22:16.958473) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-28 04:22:16.958744) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-28 04:22:16.973340) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-28 04:22:16.973696) ========= 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=8887e36bb8e381bfd44124ac000001a8-1472383336.503430-1427678062 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-08-28 04:22:17-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 46.2M=0s 2016-08-28 04:22:17 (46.2 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.285025 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-28 04:22:17.272791) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-28 04:22:17.273146) ========= 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=8887e36bb8e381bfd44124ac000001a8-1472383336.503430-1427678062 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.018186 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-28 04:22:17.323235) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-28 04:22:17.323622) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 4d38a69eda61f663463af8f020447e9afbb118b1 --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 4d38a69eda61f663463af8f020447e9afbb118b1 --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=8887e36bb8e381bfd44124ac000001a8-1472383336.503430-1427678062 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-08-28 04:22:17,536 truncating revision to first 12 chars 2016-08-28 04:22:17,536 Setting DEBUG logging. 2016-08-28 04:22:17,536 attempt 1/10 2016-08-28 04:22:17,536 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/4d38a69eda61?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-08-28 04:22:17,968 unpacking tar archive at: mozilla-aurora-4d38a69eda61/testing/mozharness/ program finished with exit code 0 elapsedTime=0.821182 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-28 04:22:18.158652) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-28 04:22:18.158978) ========= script_repo_revision: 4d38a69eda61f663463af8f020447e9afbb118b1 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-28 04:22:18.159383) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-28 04:22:18.159644) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-28 04:22:18.168640) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 27 mins, 53 secs) (at 2016-08-28 04:22:18.169018) ========= /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=8887e36bb8e381bfd44124ac000001a8-1472383336.503430-1427678062 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 04:22:18 INFO - MultiFileLogger online at 20160828 04:22:18 in /builds/slave/test 04:22:18 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 04:22:18 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 04:22:18 INFO - {'append_to_log': False, 04:22:18 INFO - 'base_work_dir': '/builds/slave/test', 04:22:18 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 04:22:18 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 04:22:18 INFO - 'branch': 'Mozilla-Aurora', 04:22:18 INFO - 'buildbot_json_path': 'buildprops.json', 04:22:18 INFO - 'config_files': ('talos/linux_config.py',), 04:22:18 INFO - 'default_actions': ('clobber', 04:22:18 INFO - 'read-buildbot-config', 04:22:18 INFO - 'download-and-extract', 04:22:18 INFO - 'populate-webroot', 04:22:18 INFO - 'create-virtualenv', 04:22:18 INFO - 'install', 04:22:18 INFO - 'run-tests'), 04:22:18 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 04:22:18 INFO - 'download_minidump_stackwalk': True, 04:22:18 INFO - 'download_symbols': 'ondemand', 04:22:18 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 04:22:18 INFO - 'tooltool.py': '/tools/tooltool.py', 04:22:18 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 04:22:18 INFO - '/tools/misc-python/virtualenv.py')}, 04:22:18 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 04:22:18 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 04:22:18 INFO - 'installer_path': 'installer.exe', 04:22:18 INFO - 'log_level': 'info', 04:22:18 INFO - 'log_name': 'talos', 04:22:18 INFO - 'log_to_console': True, 04:22:18 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 04:22:18 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 04:22:18 INFO - 'opt_config_files': (), 04:22:18 INFO - 'pip_index': False, 04:22:18 INFO - 'sps_profile': False, 04:22:18 INFO - 'sps_profile_interval': 0, 04:22:18 INFO - 'suite': 'dromaeojs', 04:22:18 INFO - 'system_bits': '32', 04:22:18 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 04:22:18 INFO - 'title': 'talos-linux64-ix-001', 04:22:18 INFO - 'tooltool_cache': '/builds/tooltool_cache', 04:22:18 INFO - 'use_talos_json': True, 04:22:18 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 04:22:18 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 04:22:18 INFO - 'work_dir': 'build'} 04:22:18 INFO - [mozharness: 2016-08-28 11:22:18.478068Z] Running clobber step. 04:22:18 INFO - Running pre-action listener: _resource_record_pre_action 04:22:18 INFO - Running main action method: clobber 04:22:18 INFO - rmtree: /builds/slave/test/build 04:22:18 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 04:22:19 INFO - Running post-action listener: _resource_record_post_action 04:22:19 INFO - [mozharness: 2016-08-28 11:22:19.422320Z] Finished clobber step (success) 04:22:19 INFO - [mozharness: 2016-08-28 11:22:19.422421Z] Running read-buildbot-config step. 04:22:19 INFO - Running pre-action listener: _resource_record_pre_action 04:22:19 INFO - Running main action method: read_buildbot_config 04:22:19 INFO - Using buildbot properties: 04:22:19 INFO - { 04:22:19 INFO - "project": "", 04:22:19 INFO - "product": "firefox", 04:22:19 INFO - "script_repo_revision": "production", 04:22:19 INFO - "builddir": "mozilla-aurora_ubuntu64_hw_test-dromaeojs", 04:22:19 INFO - "repository": "", 04:22:19 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-aurora pgo talos dromaeojs", 04:22:19 INFO - "buildid": "20160827004001", 04:22:19 INFO - "stage_platform": "linux64-pgo", 04:22:19 INFO - "basedir": "/builds/slave/test", 04:22:19 INFO - "nightly_build": "True", 04:22:19 INFO - "buildnumber": 497, 04:22:19 INFO - "slavename": "talos-linux64-ix-001", 04:22:19 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 04:22:19 INFO - "platform": "ubuntu64_hw", 04:22:19 INFO - "scheduler": "tests-mozilla-aurora-linux64-pgo-talos", 04:22:19 INFO - "branch": "mozilla-aurora", 04:22:19 INFO - "repo_path": "releases/mozilla-aurora", 04:22:19 INFO - "revision": "4d38a69eda61f663463af8f020447e9afbb118b1", 04:22:19 INFO - "pgo_build": "True", 04:22:19 INFO - "builduid": "5275c599c6904397a1806c151c356c46", 04:22:19 INFO - "slavebuilddir": "test" 04:22:19 INFO - } 04:22:19 INFO - Found installer url https://queue.taskcluster.net/v1/task/DceP3cLkSRyynuNxN1DWbg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2. 04:22:19 INFO - Running post-action listener: _resource_record_post_action 04:22:19 INFO - [mozharness: 2016-08-28 11:22:19.424932Z] Finished read-buildbot-config step (success) 04:22:19 INFO - [mozharness: 2016-08-28 11:22:19.425020Z] Running download-and-extract step. 04:22:19 INFO - Running pre-action listener: _resource_record_pre_action 04:22:19 INFO - Running main action method: download_and_extract 04:22:19 INFO - mkdir: /builds/slave/test/build/tests 04:22: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')]} 04:22:19 INFO - https://queue.taskcluster.net/v1/task/DceP3cLkSRyynuNxN1DWbg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 04:22:19 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DceP3cLkSRyynuNxN1DWbg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 04:22:19 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DceP3cLkSRyynuNxN1DWbg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 04:22:19 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DceP3cLkSRyynuNxN1DWbg/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 04:22:19 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DceP3cLkSRyynuNxN1DWbg/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 04:22:19 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DceP3cLkSRyynuNxN1DWbg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 04:22:19 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 04:22:19 INFO - retry: Failed, sleeping 30 seconds before retrying 04:22:49 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DceP3cLkSRyynuNxN1DWbg/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 04:22:49 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DceP3cLkSRyynuNxN1DWbg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 04:22:49 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 04:22:49 INFO - retry: Failed, sleeping 60 seconds before retrying 04:23:49 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DceP3cLkSRyynuNxN1DWbg/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 04:23:49 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DceP3cLkSRyynuNxN1DWbg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 04:23:49 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 04:23:49 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DceP3cLkSRyynuNxN1DWbg/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! 04:23:49 INFO - Caught exception: HTTP Error 404: Not Found 04:23:49 INFO - Caught exception: HTTP Error 404: Not Found 04:23:49 INFO - Caught exception: HTTP Error 404: Not Found 04:23:49 INFO - trying https://queue.taskcluster.net/v1/task/DceP3cLkSRyynuNxN1DWbg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 04:23:49 INFO - Downloading https://queue.taskcluster.net/v1/task/DceP3cLkSRyynuNxN1DWbg/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 04:23:49 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/DceP3cLkSRyynuNxN1DWbg/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 04:23:50 INFO - Downloaded 1361 bytes. 04:23:50 INFO - Reading from file /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 04:23:50 INFO - Using the following test package requirements: 04:23:50 INFO - {u'common': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'], 04:23:50 INFO - u'cppunittest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 04:23:50 INFO - u'firefox-50.0a2.en-US.linux-x86_64.cppunittest.tests.zip'], 04:23:50 INFO - u'gtest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 04:23:50 INFO - u'firefox-50.0a2.en-US.linux-x86_64.gtest.tests.zip'], 04:23:50 INFO - u'jittest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 04:23:50 INFO - u'jsshell-linux-x86_64.zip'], 04:23:50 INFO - u'mochitest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 04:23:50 INFO - u'firefox-50.0a2.en-US.linux-x86_64.mochitest.tests.zip'], 04:23:50 INFO - u'mozbase': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'], 04:23:50 INFO - u'reftest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 04:23:50 INFO - u'firefox-50.0a2.en-US.linux-x86_64.reftest.tests.zip'], 04:23:50 INFO - u'talos': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 04:23:50 INFO - u'firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip'], 04:23:50 INFO - u'web-platform': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 04:23:50 INFO - u'firefox-50.0a2.en-US.linux-x86_64.web-platform.tests.zip'], 04:23:50 INFO - u'xpcshell': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 04:23:50 INFO - u'firefox-50.0a2.en-US.linux-x86_64.xpcshell.tests.zip']} 04:23:50 INFO - Downloading packages: [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 04:23:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:23:50 INFO - https://queue.taskcluster.net/v1/task/DceP3cLkSRyynuNxN1DWbg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 04:23:50 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DceP3cLkSRyynuNxN1DWbg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 04:23:50 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DceP3cLkSRyynuNxN1DWbg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 04:23:50 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DceP3cLkSRyynuNxN1DWbg/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 04:23:50 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DceP3cLkSRyynuNxN1DWbg/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 04:23:50 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DceP3cLkSRyynuNxN1DWbg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 04:23:50 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 04:23:50 INFO - retry: Failed, sleeping 30 seconds before retrying 04:24:20 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DceP3cLkSRyynuNxN1DWbg/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 04:24:20 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DceP3cLkSRyynuNxN1DWbg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 04:24:20 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 04:24:20 INFO - retry: Failed, sleeping 60 seconds before retrying 04:25:20 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DceP3cLkSRyynuNxN1DWbg/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 04:25:20 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DceP3cLkSRyynuNxN1DWbg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 04:25:20 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 04:25:20 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DceP3cLkSRyynuNxN1DWbg/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! 04:25:20 INFO - Caught exception: HTTP Error 404: Not Found 04:25:20 INFO - Caught exception: HTTP Error 404: Not Found 04:25:20 INFO - Caught exception: HTTP Error 404: Not Found 04:25:20 INFO - trying https://queue.taskcluster.net/v1/task/DceP3cLkSRyynuNxN1DWbg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 04:25:20 INFO - Downloading https://queue.taskcluster.net/v1/task/DceP3cLkSRyynuNxN1DWbg/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 04:25:20 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/DceP3cLkSRyynuNxN1DWbg/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 04:25:20 INFO - Downloaded 21770875 bytes. 04:25:20 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'] 04:25:20 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 04:25:22 INFO - Return code: 0 04:25:22 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 04:25:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:25:22 INFO - https://queue.taskcluster.net/v1/task/DceP3cLkSRyynuNxN1DWbg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 04:25:22 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DceP3cLkSRyynuNxN1DWbg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 04:25:22 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DceP3cLkSRyynuNxN1DWbg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 04:25:22 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DceP3cLkSRyynuNxN1DWbg/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 04:25:22 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DceP3cLkSRyynuNxN1DWbg/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 04:25:22 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DceP3cLkSRyynuNxN1DWbg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 04:25:22 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 04:25:22 INFO - retry: Failed, sleeping 30 seconds before retrying 04:25:52 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DceP3cLkSRyynuNxN1DWbg/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 04:25:52 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DceP3cLkSRyynuNxN1DWbg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 04:25:52 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 04:25:52 INFO - retry: Failed, sleeping 60 seconds before retrying 04:26:52 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DceP3cLkSRyynuNxN1DWbg/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 04:26:52 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DceP3cLkSRyynuNxN1DWbg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 04:26:52 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 04:26:52 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DceP3cLkSRyynuNxN1DWbg/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! 04:26:52 INFO - Caught exception: HTTP Error 404: Not Found 04:26:52 INFO - Caught exception: HTTP Error 404: Not Found 04:26:52 INFO - Caught exception: HTTP Error 404: Not Found 04:26:52 INFO - trying https://queue.taskcluster.net/v1/task/DceP3cLkSRyynuNxN1DWbg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 04:26:52 INFO - Downloading https://queue.taskcluster.net/v1/task/DceP3cLkSRyynuNxN1DWbg/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 04:26:52 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/DceP3cLkSRyynuNxN1DWbg/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 04:26:53 INFO - Downloaded 21770875 bytes. 04:26:53 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'] 04:26:53 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 04:26:54 INFO - Return code: 0 04:26: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')]} 04:26:54 INFO - https://queue.taskcluster.net/v1/task/DceP3cLkSRyynuNxN1DWbg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 04:26:54 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DceP3cLkSRyynuNxN1DWbg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 04:26:54 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DceP3cLkSRyynuNxN1DWbg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 04:26:54 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DceP3cLkSRyynuNxN1DWbg/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 04:26:54 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DceP3cLkSRyynuNxN1DWbg/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 04:26:54 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DceP3cLkSRyynuNxN1DWbg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 04:26:54 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 04:26:54 INFO - retry: Failed, sleeping 30 seconds before retrying 04:27:24 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DceP3cLkSRyynuNxN1DWbg/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 04:27:24 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DceP3cLkSRyynuNxN1DWbg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 04:27:24 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 04:27:24 INFO - retry: Failed, sleeping 60 seconds before retrying 04:28:24 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DceP3cLkSRyynuNxN1DWbg/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 04:28:24 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DceP3cLkSRyynuNxN1DWbg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 04:28:24 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 04:28:24 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DceP3cLkSRyynuNxN1DWbg/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! 04:28:24 INFO - Caught exception: HTTP Error 404: Not Found 04:28:24 INFO - Caught exception: HTTP Error 404: Not Found 04:28:24 INFO - Caught exception: HTTP Error 404: Not Found 04:28:24 INFO - trying https://queue.taskcluster.net/v1/task/DceP3cLkSRyynuNxN1DWbg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 04:28:24 INFO - Downloading https://queue.taskcluster.net/v1/task/DceP3cLkSRyynuNxN1DWbg/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 04:28:24 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/DceP3cLkSRyynuNxN1DWbg/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 04:28:25 INFO - Downloaded 14413723 bytes. 04:28:25 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'] 04:28:25 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 04:28:25 INFO - Return code: 0 04:28:25 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')]} 04:28:25 INFO - https://queue.taskcluster.net/v1/task/DceP3cLkSRyynuNxN1DWbg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 04:28:25 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DceP3cLkSRyynuNxN1DWbg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 04:28:25 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DceP3cLkSRyynuNxN1DWbg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 04:28:25 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DceP3cLkSRyynuNxN1DWbg/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 04:28:25 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DceP3cLkSRyynuNxN1DWbg/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 04:28:25 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DceP3cLkSRyynuNxN1DWbg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 04:28:25 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 04:28:25 INFO - retry: Failed, sleeping 30 seconds before retrying 04:28:55 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DceP3cLkSRyynuNxN1DWbg/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 04:28:55 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DceP3cLkSRyynuNxN1DWbg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 04:28:55 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 04:28:55 INFO - retry: Failed, sleeping 60 seconds before retrying 04:29:55 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DceP3cLkSRyynuNxN1DWbg/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 04:29:55 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DceP3cLkSRyynuNxN1DWbg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 04:29:55 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 04:29:55 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DceP3cLkSRyynuNxN1DWbg/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! 04:29:55 INFO - Caught exception: HTTP Error 404: Not Found 04:29:55 INFO - Caught exception: HTTP Error 404: Not Found 04:29:55 INFO - Caught exception: HTTP Error 404: Not Found 04:29:55 INFO - trying https://queue.taskcluster.net/v1/task/DceP3cLkSRyynuNxN1DWbg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 04:29:55 INFO - Downloading https://queue.taskcluster.net/v1/task/DceP3cLkSRyynuNxN1DWbg/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 04:29:55 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/DceP3cLkSRyynuNxN1DWbg/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 04:29:57 INFO - Downloaded 63746809 bytes. 04:29:57 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/DceP3cLkSRyynuNxN1DWbg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 04:29:57 INFO - mkdir: /builds/slave/test/properties 04:29:57 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 04:29:57 INFO - Writing to file /builds/slave/test/properties/build_url 04:29:57 INFO - Contents: 04:29:57 INFO - build_url:https://queue.taskcluster.net/v1/task/DceP3cLkSRyynuNxN1DWbg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 04:29:58 INFO - Running post-action listener: _resource_record_post_action 04:29:58 INFO - Running post-action listener: set_extra_try_arguments 04:29:58 INFO - [mozharness: 2016-08-28 11:29:58.129666Z] Finished download-and-extract step (success) 04:29:58 INFO - [mozharness: 2016-08-28 11:29:58.129896Z] Running populate-webroot step. 04:29:58 INFO - Running pre-action listener: _resource_record_pre_action 04:29:58 INFO - Running main action method: populate_webroot 04:29:58 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 04:29:58 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 04:29:58 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 04:29:58 INFO - '--tppagecycles', 04:29:58 INFO - '7'], 04:29:58 INFO - 'tests': ['tsvgm']}}, 04:29:58 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 04:29:58 INFO - 'tests': ['tresize', 'tcanvasmark']}, 04:29:58 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 04:29:58 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 04:29:58 INFO - 'tests': ['dromaeo_css', 'kraken']}, 04:29:58 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 04:29:58 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:29:58 INFO - 'talos_options': ['--disable-e10s'], 04:29:58 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:29:58 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:29:58 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:29:58 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:29:58 INFO - 'talos_options': ['--disable-e10s'], 04:29:58 INFO - 'tests': ['damp', 'tps']}, 04:29:58 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:29:58 INFO - 'tests': ['damp', 'tps']}, 04:29:58 INFO - 'g3': {'talos_options': ['--disable-e10s'], 04:29:58 INFO - 'tests': ['dromaeo_dom']}, 04:29:58 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 04:29:58 INFO - 'g4': {'talos_options': ['--disable-e10s'], 04:29:58 INFO - 'tests': ['basic_compositor_video']}, 04:29:58 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 04:29:58 INFO - 'other': {'talos_options': ['--disable-e10s'], 04:29:58 INFO - 'tests': ['a11yr', 04:29:58 INFO - 'ts_paint', 04:29:58 INFO - 'tpaint', 04:29:58 INFO - 'sessionrestore', 04:29:58 INFO - 'sessionrestore_no_auto_restore', 04:29:58 INFO - 'tabpaint']}, 04:29:58 INFO - 'other-e10s': {'tests': ['a11yr', 04:29:58 INFO - 'ts_paint', 04:29:58 INFO - 'tpaint', 04:29:58 INFO - 'sessionrestore', 04:29:58 INFO - 'sessionrestore_no_auto_restore', 04:29:58 INFO - 'tabpaint']}, 04:29:58 INFO - 'other-e10s_l64': {'tests': ['a11yr', 04:29:58 INFO - 'ts_paint', 04:29:58 INFO - 'tpaint', 04:29:58 INFO - 'sessionrestore', 04:29:58 INFO - 'sessionrestore_no_auto_restore', 04:29:58 INFO - 'tabpaint']}, 04:29:58 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 04:29:58 INFO - 'ts_paint', 04:29:58 INFO - 'tpaint', 04:29:58 INFO - 'sessionrestore', 04:29:58 INFO - 'sessionrestore_no_auto_restore', 04:29:58 INFO - 'tabpaint']}, 04:29:58 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 04:29:58 INFO - 'tests': ['a11yr', 04:29:58 INFO - 'ts_paint', 04:29:58 INFO - 'tpaint', 04:29:58 INFO - 'sessionrestore', 04:29:58 INFO - 'sessionrestore_no_auto_restore', 04:29:58 INFO - 'tabpaint']}, 04:29:58 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 04:29:58 INFO - 'tests': ['a11yr', 04:29:58 INFO - 'ts_paint', 04:29:58 INFO - 'tpaint', 04:29:58 INFO - 'sessionrestore', 04:29:58 INFO - 'sessionrestore_no_auto_restore', 04:29:58 INFO - 'tabpaint']}, 04:29:58 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 04:29:58 INFO - 'tests': ['tsvgx', 04:29:58 INFO - 'tsvgr_opacity', 04:29:58 INFO - 'tart', 04:29:58 INFO - 'tscrollx', 04:29:58 INFO - 'cart']}, 04:29:58 INFO - 'svgr-e10s': {'tests': ['tsvgx', 04:29:58 INFO - 'tsvgr_opacity', 04:29:58 INFO - 'tart', 04:29:58 INFO - 'tscrollx', 04:29:58 INFO - 'cart']}, 04:29:58 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:29:58 INFO - 'talos_options': ['--disable-e10s'], 04:29:58 INFO - 'tests': ['tp5o']}, 04:29:58 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:29:58 INFO - 'tests': ['tp5o']}, 04:29:58 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:29:58 INFO - 'talos_options': ['--disable-e10s', 04:29:58 INFO - '--xperf_path', 04:29:58 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:29:58 INFO - 'tests': ['tp5n']}, 04:29:58 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:29:58 INFO - 'talos_options': ['--xperf_path', 04:29:58 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:29:58 INFO - 'tests': ['tp5n']}}, 04:29:58 INFO - 'talos.zip': {'path': '', 04:29:58 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 04:29:58 INFO - Running post-action listener: _resource_record_post_action 04:29:58 INFO - [mozharness: 2016-08-28 11:29:58.151841Z] Finished populate-webroot step (success) 04:29:58 INFO - [mozharness: 2016-08-28 11:29:58.151930Z] Running create-virtualenv step. 04:29:58 INFO - Running pre-action listener: _resource_record_pre_action 04:29:58 INFO - Running main action method: create_virtualenv 04:29:58 INFO - Creating virtualenv /builds/slave/test/build/venv 04:29:58 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 04:29:58 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 04:29:58 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 04:29:58 INFO - Using real prefix '/usr' 04:29:58 INFO - New python executable in /builds/slave/test/build/venv/bin/python 04:29:58 INFO - Installing distribute.............................................................................................................................................................................................done. 04:30:00 INFO - Installing pip.................done. 04:30:00 INFO - Return code: 0 04:30:00 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 04:30:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:30:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:30:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:30:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:30:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:30:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:30:00 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xacd030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xc308a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xd8e180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd8c3b0>, '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 0xd8d2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xd8d740>, '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': '8887e36bb8e381bfd44124ac000001a8-1472383336.503430-1427678062', '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 04:30:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 04:30:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 04:30:00 INFO - Using env: {'DISPLAY': ':0', 04:30:00 INFO - 'HOME': '/home/cltbld', 04:30:00 INFO - 'LANG': 'en_US.UTF-8', 04:30:00 INFO - 'LANGUAGE': 'en_US:en', 04:30:00 INFO - 'LOGNAME': 'cltbld', 04:30:00 INFO - 'MAIL': '/var/mail/cltbld', 04:30:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:30:00 INFO - 'MOZ_NO_REMOTE': '1', 04:30:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:30:00 INFO - 'NO_EM_RESTART': '1', 04:30:00 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:30:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:30:00 INFO - 'PWD': '/builds/slave/test', 04:30:00 INFO - 'SHELL': '/bin/bash', 04:30:00 INFO - 'SHLVL': '1', 04:30:00 INFO - 'TERM': 'linux', 04:30:00 INFO - 'TMOUT': '86400', 04:30:00 INFO - 'USER': 'cltbld', 04:30:00 INFO - 'XDG_SESSION_COOKIE': '8887e36bb8e381bfd44124ac000001a8-1472383336.503430-1427678062', 04:30:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:30:00 INFO - '_': '/tools/buildbot/bin/python'} 04:30:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:30:00 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 04:30:00 INFO - Cleaning up... 04:30:00 INFO - Return code: 0 04:30:00 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 04:30:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:30:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:30:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:30:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:30:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:30:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:30:00 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0xacd030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xc308a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xd8e180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd8c3b0>, '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 0xd8d2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xd8d740>, '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': '8887e36bb8e381bfd44124ac000001a8-1472383336.503430-1427678062', '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 04:30:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 04:30:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 04:30:00 INFO - Using env: {'DISPLAY': ':0', 04:30:00 INFO - 'HOME': '/home/cltbld', 04:30:00 INFO - 'LANG': 'en_US.UTF-8', 04:30:00 INFO - 'LANGUAGE': 'en_US:en', 04:30:00 INFO - 'LOGNAME': 'cltbld', 04:30:00 INFO - 'MAIL': '/var/mail/cltbld', 04:30:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:30:00 INFO - 'MOZ_NO_REMOTE': '1', 04:30:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:30:00 INFO - 'NO_EM_RESTART': '1', 04:30:00 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:30:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:30:00 INFO - 'PWD': '/builds/slave/test', 04:30:00 INFO - 'SHELL': '/bin/bash', 04:30:00 INFO - 'SHLVL': '1', 04:30:00 INFO - 'TERM': 'linux', 04:30:00 INFO - 'TMOUT': '86400', 04:30:00 INFO - 'USER': 'cltbld', 04:30:00 INFO - 'XDG_SESSION_COOKIE': '8887e36bb8e381bfd44124ac000001a8-1472383336.503430-1427678062', 04:30:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:30:00 INFO - '_': '/tools/buildbot/bin/python'} 04:30:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:30:01 INFO - Downloading/unpacking psutil>=3.1.1 04: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 04: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 04: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 04: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 04: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 04: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 04:30:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 04:30:03 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:30:03 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:30:03 INFO - Installing collected packages: psutil 04:30:03 INFO - Running setup.py install for psutil 04:30:03 INFO - building 'psutil._psutil_linux' extension 04:30:03 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 04:30:03 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 04:30:03 INFO - building 'psutil._psutil_posix' extension 04:30:03 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 04:30:03 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 04:30:03 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:30:03 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:30:03 INFO - Successfully installed psutil 04:30:03 INFO - Cleaning up... 04:30:03 INFO - Return code: 0 04:30:03 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 04:30:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:30:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:30:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:30:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:30:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:30:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:30:03 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xacd030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xc308a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xd8e180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd8c3b0>, '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 0xd8d2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xd8d740>, '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': '8887e36bb8e381bfd44124ac000001a8-1472383336.503430-1427678062', '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 04:30:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 04:30:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 04:30:03 INFO - Using env: {'DISPLAY': ':0', 04:30:03 INFO - 'HOME': '/home/cltbld', 04:30:03 INFO - 'LANG': 'en_US.UTF-8', 04:30:03 INFO - 'LANGUAGE': 'en_US:en', 04:30:03 INFO - 'LOGNAME': 'cltbld', 04:30:03 INFO - 'MAIL': '/var/mail/cltbld', 04:30:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:30:03 INFO - 'MOZ_NO_REMOTE': '1', 04:30:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:30:03 INFO - 'NO_EM_RESTART': '1', 04:30:03 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:30:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:30:03 INFO - 'PWD': '/builds/slave/test', 04:30:03 INFO - 'SHELL': '/bin/bash', 04:30:03 INFO - 'SHLVL': '1', 04:30:03 INFO - 'TERM': 'linux', 04:30:03 INFO - 'TMOUT': '86400', 04:30:03 INFO - 'USER': 'cltbld', 04:30:03 INFO - 'XDG_SESSION_COOKIE': '8887e36bb8e381bfd44124ac000001a8-1472383336.503430-1427678062', 04:30:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:30:03 INFO - '_': '/tools/buildbot/bin/python'} 04:30:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:30:03 INFO - Downloading/unpacking mozsystemmonitor==0.3 04:30:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:30:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:30:03 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:30:03 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:30:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:30:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:30:05 INFO - Downloading mozsystemmonitor-0.3.tar.gz 04:30:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 04:30:05 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 04:30:05 INFO - Installing collected packages: mozsystemmonitor 04:30:05 INFO - Running setup.py install for mozsystemmonitor 04:30:05 INFO - Successfully installed mozsystemmonitor 04:30:05 INFO - Cleaning up... 04:30:05 INFO - Return code: 0 04:30:05 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 04:30:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:30:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:30:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:30:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:30:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:30:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:30:05 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 0xacd030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xc308a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xd8e180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd8c3b0>, '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 0xd8d2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xd8d740>, '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': '8887e36bb8e381bfd44124ac000001a8-1472383336.503430-1427678062', '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 04:30:05 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 04:30:05 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 04:30:05 INFO - Using env: {'DISPLAY': ':0', 04:30:05 INFO - 'HOME': '/home/cltbld', 04:30:05 INFO - 'LANG': 'en_US.UTF-8', 04:30:05 INFO - 'LANGUAGE': 'en_US:en', 04:30:05 INFO - 'LOGNAME': 'cltbld', 04:30:05 INFO - 'MAIL': '/var/mail/cltbld', 04:30:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:30:05 INFO - 'MOZ_NO_REMOTE': '1', 04:30:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:30:05 INFO - 'NO_EM_RESTART': '1', 04:30:05 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:30:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:30:05 INFO - 'PWD': '/builds/slave/test', 04:30:05 INFO - 'SHELL': '/bin/bash', 04:30:05 INFO - 'SHLVL': '1', 04:30:05 INFO - 'TERM': 'linux', 04:30:05 INFO - 'TMOUT': '86400', 04:30:05 INFO - 'USER': 'cltbld', 04:30:05 INFO - 'XDG_SESSION_COOKIE': '8887e36bb8e381bfd44124ac000001a8-1472383336.503430-1427678062', 04:30:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:30:05 INFO - '_': '/tools/buildbot/bin/python'} 04:30:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:30:06 INFO - Downloading/unpacking jsonschema==2.5.1 04:30:06 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 04:30:06 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 04:30:06 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 04:30:06 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 04:30:06 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 04:30:06 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 04:30:08 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 04:30:08 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 04:30:08 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 04:30:08 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 04:30:08 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 04:30:08 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 04:30:08 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 04:30:08 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 04:30:08 INFO - Downloading functools32-3.2.3-2.tar.gz 04:30:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 04:30:08 INFO - warning: no files found matching '*.txt' 04:30:08 INFO - no previously-included directories found matching 'build' 04:30:08 INFO - no previously-included directories found matching 'dist' 04:30:08 INFO - no previously-included directories found matching '.git*' 04:30:08 INFO - Installing collected packages: jsonschema, functools32 04:30:08 INFO - Running setup.py install for functools32 04:30:08 INFO - warning: no files found matching '*.txt' 04:30:08 INFO - no previously-included directories found matching 'build' 04:30:08 INFO - no previously-included directories found matching 'dist' 04:30:08 INFO - no previously-included directories found matching '.git*' 04:30:08 INFO - Successfully installed jsonschema functools32 04:30:08 INFO - Cleaning up... 04:30:08 INFO - Return code: 0 04:30:08 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 04:30:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:30:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:30:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:30:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:30:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:30:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:30:08 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 0xacd030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xc308a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xd8e180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd8c3b0>, '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 0xd8d2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xd8d740>, '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': '8887e36bb8e381bfd44124ac000001a8-1472383336.503430-1427678062', '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 04:30:08 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 04:30:08 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 04:30:08 INFO - Using env: {'DISPLAY': ':0', 04:30:08 INFO - 'HOME': '/home/cltbld', 04:30:08 INFO - 'LANG': 'en_US.UTF-8', 04:30:08 INFO - 'LANGUAGE': 'en_US:en', 04:30:08 INFO - 'LOGNAME': 'cltbld', 04:30:08 INFO - 'MAIL': '/var/mail/cltbld', 04:30:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:30:08 INFO - 'MOZ_NO_REMOTE': '1', 04:30:08 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:30:08 INFO - 'NO_EM_RESTART': '1', 04:30:08 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:30:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:30:08 INFO - 'PWD': '/builds/slave/test', 04:30:08 INFO - 'SHELL': '/bin/bash', 04:30:08 INFO - 'SHLVL': '1', 04:30:08 INFO - 'TERM': 'linux', 04:30:08 INFO - 'TMOUT': '86400', 04:30:08 INFO - 'USER': 'cltbld', 04:30:08 INFO - 'XDG_SESSION_COOKIE': '8887e36bb8e381bfd44124ac000001a8-1472383336.503430-1427678062', 04:30:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:30:08 INFO - '_': '/tools/buildbot/bin/python'} 04:30:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:30:08 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 04:30:08 INFO - Cleaning up... 04:30:08 INFO - Return code: 0 04:30:08 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 04:30:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:30:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:30:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:30:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:30:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:30:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:30:08 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 0xacd030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xc308a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xd8e180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd8c3b0>, '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 0xd8d2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xd8d740>, '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': '8887e36bb8e381bfd44124ac000001a8-1472383336.503430-1427678062', '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 04:30:08 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 04:30:08 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 04:30:08 INFO - Using env: {'DISPLAY': ':0', 04:30:08 INFO - 'HOME': '/home/cltbld', 04:30:08 INFO - 'LANG': 'en_US.UTF-8', 04:30:08 INFO - 'LANGUAGE': 'en_US:en', 04:30:08 INFO - 'LOGNAME': 'cltbld', 04:30:08 INFO - 'MAIL': '/var/mail/cltbld', 04:30:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:30:08 INFO - 'MOZ_NO_REMOTE': '1', 04:30:08 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:30:08 INFO - 'NO_EM_RESTART': '1', 04:30:08 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:30:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:30:08 INFO - 'PWD': '/builds/slave/test', 04:30:08 INFO - 'SHELL': '/bin/bash', 04:30:08 INFO - 'SHLVL': '1', 04:30:08 INFO - 'TERM': 'linux', 04:30:08 INFO - 'TMOUT': '86400', 04:30:08 INFO - 'USER': 'cltbld', 04:30:08 INFO - 'XDG_SESSION_COOKIE': '8887e36bb8e381bfd44124ac000001a8-1472383336.503430-1427678062', 04:30:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:30:08 INFO - '_': '/tools/buildbot/bin/python'} 04:30:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:30:08 INFO - Downloading/unpacking blobuploader==1.2.4 04:30:08 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 04:30:08 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 04:30:08 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 04:30:08 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 04:30:08 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 04:30:08 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 04:30:10 INFO - Downloading blobuploader-1.2.4.tar.gz 04:30:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 04:30:10 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 04: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 04: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 04: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 04: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 04: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 04: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 04:30:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 04:30:11 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 04:30:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:30:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:30:11 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:30:11 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:30:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:30:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:30:11 INFO - Downloading docopt-0.6.1.tar.gz 04:30:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 04:30:11 INFO - Installing collected packages: blobuploader, requests, docopt 04:30:11 INFO - Running setup.py install for blobuploader 04:30:11 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 04:30:11 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 04:30:11 INFO - Running setup.py install for requests 04:30:12 INFO - Running setup.py install for docopt 04:30:12 INFO - Successfully installed blobuploader requests docopt 04:30:12 INFO - Cleaning up... 04:30:12 INFO - Return code: 0 04:30:12 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:30: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')]} 04:30:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:30:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:30: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')]} 04:30:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:30:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:30:12 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 0xacd030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xc308a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xd8e180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd8c3b0>, '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 0xd8d2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xd8d740>, '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': '8887e36bb8e381bfd44124ac000001a8-1472383336.503430-1427678062', '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 04:30:12 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 04:30:12 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 04:30:12 INFO - Using env: {'DISPLAY': ':0', 04:30:12 INFO - 'HOME': '/home/cltbld', 04:30:12 INFO - 'LANG': 'en_US.UTF-8', 04:30:12 INFO - 'LANGUAGE': 'en_US:en', 04:30:12 INFO - 'LOGNAME': 'cltbld', 04:30:12 INFO - 'MAIL': '/var/mail/cltbld', 04:30:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:30:12 INFO - 'MOZ_NO_REMOTE': '1', 04:30:12 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:30:12 INFO - 'NO_EM_RESTART': '1', 04:30:12 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:30:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:30:12 INFO - 'PWD': '/builds/slave/test', 04:30:12 INFO - 'SHELL': '/bin/bash', 04:30:12 INFO - 'SHLVL': '1', 04:30:12 INFO - 'TERM': 'linux', 04:30:12 INFO - 'TMOUT': '86400', 04:30:12 INFO - 'USER': 'cltbld', 04:30:12 INFO - 'XDG_SESSION_COOKIE': '8887e36bb8e381bfd44124ac000001a8-1472383336.503430-1427678062', 04:30:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:30:12 INFO - '_': '/tools/buildbot/bin/python'} 04:30:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:30:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:30:12 INFO - Running setup.py (path:/tmp/pip-K637Cn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:30:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:30:12 INFO - Running setup.py (path:/tmp/pip-xv7LB2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:30:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:30:12 INFO - Running setup.py (path:/tmp/pip-p6lgxb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:30:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:30:12 INFO - Running setup.py (path:/tmp/pip-QQY_Tb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:30:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:30:12 INFO - Running setup.py (path:/tmp/pip-9OUdrq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:30:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:30:12 INFO - Running setup.py (path:/tmp/pip-FrsC3i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:30:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:30:12 INFO - Running setup.py (path:/tmp/pip-UHL38z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:30:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:30:12 INFO - Running setup.py (path:/tmp/pip-cMhZG4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:30:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:30:12 INFO - Running setup.py (path:/tmp/pip-4JtFFd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:30:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:30:13 INFO - Running setup.py (path:/tmp/pip-va80R0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:30:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:30:13 INFO - Running setup.py (path:/tmp/pip-Kmdapm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:30:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:30:13 INFO - Running setup.py (path:/tmp/pip-dX5kUA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:30:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:30:13 INFO - Running setup.py (path:/tmp/pip-wvPixM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:30:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:30:13 INFO - Running setup.py (path:/tmp/pip-PELkIQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:30:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:30:13 INFO - Running setup.py (path:/tmp/pip-NjDmp0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:30:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:30:13 INFO - Running setup.py (path:/tmp/pip-eeTFSA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:30:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:30:13 INFO - Running setup.py (path:/tmp/pip-fy_CpJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:30:13 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 04:30:13 INFO - Running setup.py install for manifestparser 04:30:13 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 04:30:13 INFO - Running setup.py install for mozcrash 04:30:13 INFO - Running setup.py install for mozdebug 04:30:13 INFO - Running setup.py install for mozdevice 04:30:14 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 04:30:14 INFO - Installing dm script to /builds/slave/test/build/venv/bin 04:30:14 INFO - Running setup.py install for mozfile 04:30:14 INFO - Running setup.py install for mozhttpd 04:30:14 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 04:30:14 INFO - Running setup.py install for mozinfo 04:30:14 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 04:30:14 INFO - Running setup.py install for mozInstall 04:30:14 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 04:30:14 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 04:30:14 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 04:30:14 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 04:30:14 INFO - Running setup.py install for mozleak 04:30:14 INFO - Running setup.py install for mozlog 04:30:14 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 04:30:14 INFO - Running setup.py install for moznetwork 04:30:14 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 04:30:14 INFO - Running setup.py install for mozprocess 04:30:14 INFO - Running setup.py install for mozprofile 04:30:14 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 04:30:14 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 04:30:14 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 04:30:14 INFO - Running setup.py install for mozrunner 04:30:15 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 04:30:15 INFO - Running setup.py install for mozscreenshot 04:30:15 INFO - Running setup.py install for moztest 04:30:15 INFO - Running setup.py install for mozversion 04:30:15 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 04:30:15 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 04:30:15 INFO - Cleaning up... 04:30:15 INFO - Return code: 0 04:30:15 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:30: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')]} 04:30:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:30:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:30: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')]} 04:30:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:30:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:30:15 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 0xacd030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xc308a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xd8e180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd8c3b0>, '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 0xd8d2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xd8d740>, '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': '8887e36bb8e381bfd44124ac000001a8-1472383336.503430-1427678062', '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 04:30:15 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 04:30:15 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 04:30:15 INFO - Using env: {'DISPLAY': ':0', 04:30:15 INFO - 'HOME': '/home/cltbld', 04:30:15 INFO - 'LANG': 'en_US.UTF-8', 04:30:15 INFO - 'LANGUAGE': 'en_US:en', 04:30:15 INFO - 'LOGNAME': 'cltbld', 04:30:15 INFO - 'MAIL': '/var/mail/cltbld', 04:30:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:30:15 INFO - 'MOZ_NO_REMOTE': '1', 04:30:15 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:30:15 INFO - 'NO_EM_RESTART': '1', 04:30:15 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:30:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:30:15 INFO - 'PWD': '/builds/slave/test', 04:30:15 INFO - 'SHELL': '/bin/bash', 04:30:15 INFO - 'SHLVL': '1', 04:30:15 INFO - 'TERM': 'linux', 04:30:15 INFO - 'TMOUT': '86400', 04:30:15 INFO - 'USER': 'cltbld', 04:30:15 INFO - 'XDG_SESSION_COOKIE': '8887e36bb8e381bfd44124ac000001a8-1472383336.503430-1427678062', 04:30:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:30:15 INFO - '_': '/tools/buildbot/bin/python'} 04:30:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:30:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:30:15 INFO - Running setup.py (path:/tmp/pip-ruDP3Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:30:15 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)) 04:30:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:30:15 INFO - Running setup.py (path:/tmp/pip-hHA0ND-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:30:15 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)) 04:30:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:30:15 INFO - Running setup.py (path:/tmp/pip-qrFLjb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:30:15 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)) 04:30:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:30:15 INFO - Running setup.py (path:/tmp/pip-i3L5fo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:30:15 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)) 04:30:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:30:15 INFO - Running setup.py (path:/tmp/pip-W2gQz0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:30:15 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)) 04:30:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:30:15 INFO - Running setup.py (path:/tmp/pip-ptbTfg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:30:16 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)) 04:30:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:30:16 INFO - Running setup.py (path:/tmp/pip-j2MsMu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:30:16 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)) 04:30:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:30:16 INFO - Running setup.py (path:/tmp/pip-xw6BzE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:30:16 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)) 04:30:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:30:16 INFO - Running setup.py (path:/tmp/pip-SOYwAe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:30:16 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)) 04:30:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:30:16 INFO - Running setup.py (path:/tmp/pip-s60s2F-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:30:16 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)) 04:30:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:30:16 INFO - Running setup.py (path:/tmp/pip-aI33Zp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:30:16 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)) 04:30:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:30:16 INFO - Running setup.py (path:/tmp/pip-8cMwDN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:30:16 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)) 04:30:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:30:16 INFO - Running setup.py (path:/tmp/pip-KhsQcl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:30:16 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)) 04:30:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:30:16 INFO - Running setup.py (path:/tmp/pip-KdKL5p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:30:16 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)) 04:30:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:30:16 INFO - Running setup.py (path:/tmp/pip-__JukC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:30:16 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)) 04:30:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:30:16 INFO - Running setup.py (path:/tmp/pip-9C3EFQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:30:16 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)) 04:30:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:30:16 INFO - Running setup.py (path:/tmp/pip-3qcZGy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:30:16 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)) 04:30:16 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)) 04:30:16 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)) 04:30:16 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)) 04:30:16 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)) 04:30:16 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)) 04:30:16 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 04:30:16 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 04:30:16 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 04:30:16 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 04:30:16 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 04:30:16 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 04:30:16 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 04:30:18 INFO - Downloading blessings-1.6.tar.gz 04:30:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 04:30:18 INFO - Installing collected packages: blessings 04:30:18 INFO - Running setup.py install for blessings 04:30:18 INFO - Successfully installed blessings 04:30:18 INFO - Cleaning up... 04:30:18 INFO - Return code: 0 04:30:18 INFO - Done creating virtualenv /builds/slave/test/build/venv. 04:30:18 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:30:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:30:19 INFO - Reading from file tmpfile_stdout 04:30:19 INFO - Current package versions: 04:30:19 INFO - argparse == 1.2.1 04:30:19 INFO - blessings == 1.6 04:30:19 INFO - blobuploader == 1.2.4 04:30:19 INFO - docopt == 0.6.1 04:30:19 INFO - functools32 == 3.2.3-2 04:30:19 INFO - jsonschema == 2.5.1 04:30:19 INFO - manifestparser == 1.1 04:30:19 INFO - mozInstall == 1.12 04:30:19 INFO - mozcrash == 0.17 04:30:19 INFO - mozdebug == 0.1 04:30:19 INFO - mozdevice == 0.48 04:30:19 INFO - mozfile == 1.2 04:30:19 INFO - mozhttpd == 0.7 04:30:19 INFO - mozinfo == 0.9 04:30:19 INFO - mozleak == 0.1 04:30:19 INFO - mozlog == 3.3 04:30:19 INFO - moznetwork == 0.27 04:30:19 INFO - mozprocess == 0.23 04:30:19 INFO - mozprofile == 0.28 04:30:19 INFO - mozrunner == 6.12 04:30:19 INFO - mozscreenshot == 0.1 04:30:19 INFO - mozsystemmonitor == 0.3 04:30:19 INFO - moztest == 0.7 04:30:19 INFO - mozversion == 1.4 04:30:19 INFO - psutil == 3.1.1 04:30:19 INFO - requests == 1.2.3 04:30:19 INFO - wsgiref == 0.1.2 04:30:19 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:30: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')]} 04:30:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:30:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:30: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')]} 04:30:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:30:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:30:19 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 0xacd030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xc308a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xd8e180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd8c3b0>, '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 0xd8d2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xd8d740>, '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': '8887e36bb8e381bfd44124ac000001a8-1472383336.503430-1427678062', '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 04:30:19 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 04:30:19 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 04:30:19 INFO - Using env: {'DISPLAY': ':0', 04:30:19 INFO - 'HOME': '/home/cltbld', 04:30:19 INFO - 'LANG': 'en_US.UTF-8', 04:30:19 INFO - 'LANGUAGE': 'en_US:en', 04:30:19 INFO - 'LOGNAME': 'cltbld', 04:30:19 INFO - 'MAIL': '/var/mail/cltbld', 04:30:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:30:19 INFO - 'MOZ_NO_REMOTE': '1', 04:30:19 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:30:19 INFO - 'NO_EM_RESTART': '1', 04:30:19 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:30:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:30:19 INFO - 'PWD': '/builds/slave/test', 04:30:19 INFO - 'SHELL': '/bin/bash', 04:30:19 INFO - 'SHLVL': '1', 04:30:19 INFO - 'TERM': 'linux', 04:30:19 INFO - 'TMOUT': '86400', 04:30:19 INFO - 'USER': 'cltbld', 04:30:19 INFO - 'XDG_SESSION_COOKIE': '8887e36bb8e381bfd44124ac000001a8-1472383336.503430-1427678062', 04:30:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:30:19 INFO - '_': '/tools/buildbot/bin/python'} 04:30:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:30:19 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)) 04:30:19 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)) 04:30:19 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)) 04:30:19 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)) 04:30:19 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)) 04:30:19 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)) 04:30:19 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)) 04:30:19 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)) 04:30:19 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)) 04:30:19 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)) 04:30:19 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)) 04:30:19 INFO - Cleaning up... 04:30:19 INFO - Return code: 0 04:30:19 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 04:30: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')]} 04:30:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:30:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:30: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')]} 04:30:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:30:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:30:19 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 0xacd030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xc308a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xd8e180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd8c3b0>, '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 0xd8d2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xd8d740>, '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': '8887e36bb8e381bfd44124ac000001a8-1472383336.503430-1427678062', '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 04:30:19 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 04:30:19 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 04:30:19 INFO - Using env: {'DISPLAY': ':0', 04:30:19 INFO - 'HOME': '/home/cltbld', 04:30:19 INFO - 'LANG': 'en_US.UTF-8', 04:30:19 INFO - 'LANGUAGE': 'en_US:en', 04:30:19 INFO - 'LOGNAME': 'cltbld', 04:30:19 INFO - 'MAIL': '/var/mail/cltbld', 04:30:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:30:19 INFO - 'MOZ_NO_REMOTE': '1', 04:30:19 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:30:19 INFO - 'NO_EM_RESTART': '1', 04:30:19 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:30:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:30:19 INFO - 'PWD': '/builds/slave/test', 04:30:19 INFO - 'SHELL': '/bin/bash', 04:30:19 INFO - 'SHLVL': '1', 04:30:19 INFO - 'TERM': 'linux', 04:30:19 INFO - 'TMOUT': '86400', 04:30:19 INFO - 'USER': 'cltbld', 04:30:19 INFO - 'XDG_SESSION_COOKIE': '8887e36bb8e381bfd44124ac000001a8-1472383336.503430-1427678062', 04:30:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:30:19 INFO - '_': '/tools/buildbot/bin/python'} 04:30:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:30:19 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 04:30:19 INFO - Cleaning up... 04:30:19 INFO - Return code: 0 04:30:19 INFO - Running post-action listener: _resource_record_post_action 04:30:19 INFO - Running post-action listener: _start_resource_monitoring 04:30:19 INFO - Starting resource monitoring. 04:30:19 INFO - [mozharness: 2016-08-28 11:30:19.650339Z] Finished create-virtualenv step (success) 04:30:19 INFO - [mozharness: 2016-08-28 11:30:19.650819Z] Running install step. 04:30:19 INFO - Running pre-action listener: _resource_record_pre_action 04:30:19 INFO - Running main action method: install 04:30:19 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:30:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:30:19 INFO - Reading from file tmpfile_stdout 04:30:19 INFO - Detecting whether we're running mozinstall >=1.0... 04:30:19 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 04:30:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 04:30:19 INFO - Reading from file tmpfile_stdout 04:30:19 INFO - Output received: 04:30:19 INFO - Usage: mozinstall [options] installer 04:30:19 INFO - Options: 04:30:19 INFO - -h, --help show this help message and exit 04:30:19 INFO - -d DEST, --destination=DEST 04:30:19 INFO - Directory to install application into. [default: 04:30:19 INFO - "/builds/slave/test"] 04:30:19 INFO - --app=APP Application being installed. [default: firefox] 04:30:19 INFO - mkdir: /builds/slave/test/build/application 04:30:19 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'] 04:30:19 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 04:30:36 INFO - Reading from file tmpfile_stdout 04:30:36 INFO - Output received: 04:30:36 INFO - /builds/slave/test/build/application/firefox/firefox 04:30:36 INFO - Running post-action listener: _resource_record_post_action 04:30:36 INFO - [mozharness: 2016-08-28 11:30:36.456139Z] Finished install step (success) 04:30:36 INFO - [mozharness: 2016-08-28 11:30:36.456413Z] Running run-tests step. 04:30:36 INFO - Running pre-action listener: _resource_record_pre_action 04:30:36 INFO - Running main action method: run_tests 04:30:36 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 04:30:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 04:30:36 INFO - Python 2.7.3 04:30:36 INFO - Return code: 0 04:30:36 INFO - grabbing minidump binary from tooltool 04:30:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:30:36 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 0xd8c3b0>, '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 0xd8d2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xd8d740>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 04:30:36 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 04:30:36 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 04:30:36 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 04:30:36 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 04:30:36 INFO - Return code: 0 04:30:36 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 04:30:36 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 04:30:36 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 04:30:36 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 04:30:36 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 04:30:36 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/DceP3cLkSRyynuNxN1DWbg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-001', '--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 04:30:36 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/DceP3cLkSRyynuNxN1DWbg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-001 --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 04:30:36 INFO - Using env: {'DISPLAY': ':0', 04:30:36 INFO - 'HOME': '/home/cltbld', 04:30:36 INFO - 'LANG': 'en_US.UTF-8', 04:30:36 INFO - 'LANGUAGE': 'en_US:en', 04:30:36 INFO - 'LOGNAME': 'cltbld', 04:30:36 INFO - 'MAIL': '/var/mail/cltbld', 04:30:36 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 04:30:36 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 04:30:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:30:36 INFO - 'MOZ_NO_REMOTE': '1', 04:30:36 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 04:30:36 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:30:36 INFO - 'NO_EM_RESTART': '1', 04:30:36 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:30:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:30:36 INFO - 'PWD': '/builds/slave/test', 04:30:36 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 04:30:36 INFO - 'SHELL': '/bin/bash', 04:30:36 INFO - 'SHLVL': '1', 04:30:36 INFO - 'TERM': 'linux', 04:30:36 INFO - 'TMOUT': '86400', 04:30:36 INFO - 'USER': 'cltbld', 04:30:36 INFO - 'XDG_SESSION_COOKIE': '8887e36bb8e381bfd44124ac000001a8-1472383336.503430-1427678062', 04:30:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:30:36 INFO - '_': '/tools/buildbot/bin/python'} 04:30:36 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/DceP3cLkSRyynuNxN1DWbg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-001', '--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 04:30:36 INFO - mozversion application_buildid: 20160827004001 04:30:36 INFO - mozversion application_changeset: 4d38a69eda61f663463af8f020447e9afbb118b1 04:30:36 INFO - mozversion application_display_name: FirefoxDeveloperEdition 04:30:36 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 04:30:36 INFO - mozversion application_name: Firefox 04:30:36 INFO - mozversion application_remotingname: firefox-dev 04:30:36 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 04:30:36 INFO - mozversion application_vendor: Mozilla 04:30:36 INFO - mozversion application_version: 50.0a2 04:30:36 INFO - mozversion platform_buildid: 20160827004001 04:30:36 INFO - mozversion platform_changeset: 4d38a69eda61f663463af8f020447e9afbb118b1 04:30:36 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 04:30:36 INFO - mozversion platform_version: 50.0a2 04:30:36 INFO - using testdate: 1472383836 04:30:36 INFO - actual date: 1472383836 04:30:36 INFO - starting webserver on 'localhost:46302' 04:30:36 INFO - SUITE-START | Running 2 tests 04:30:36 INFO - TEST-START | dromaeo_css 04:30:36 INFO - operating with platform_type : linux_ 04:30:36 INFO - Initialising browser for dromaeo_css test... 04:30:36 INFO - TEST-INFO | started process 13581 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp_LGtIX/profile http://localhost:46302/getInfo.html) 04:30:40 INFO - PROCESS | 13581 | __metrics Screen width/height:1600/1200 04:30:40 INFO - PROCESS | 13581 | colorDepth:24 04:30:40 INFO - PROCESS | 13581 | Browser inner width/height: 1024/697 04:30:40 INFO - PROCESS | 13581 | __metrics 04:30:41 INFO - PROCESS | 13581 | 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" 04:30:41 INFO - PROCESS | 13581 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 04:30:42 INFO - TEST-INFO | 13581: exit 0 04:30:42 INFO - Browser initialized. 04:30:42 INFO - Running cycle 1/1 for dromaeo_css test... 04:30:42 INFO - TEST-INFO | started process 13670 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp_LGtIX/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:30:54 INFO - PROCESS | 13670 | RSS: Main: 145178624 04:30:54 INFO - PROCESS | 13670 | 04:31:45 INFO - PROCESS | 13670 | 1472383905076 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] 04:33:54 INFO - PROCESS | 13670 | Cycle 1(1): loaded http://localhost:46302/tests/dromaeo/cssquery-dojo.html (next: http://localhost:46302/tests/dromaeo/cssquery-ext.html) 04:33:55 INFO - PROCESS | 13670 | RSS: Main: 222556160 04:33:55 INFO - PROCESS | 13670 | 04:36:57 INFO - PROCESS | 13670 | Cycle 1(1): loaded http://localhost:46302/tests/dromaeo/cssquery-ext.html (next: http://localhost:46302/tests/dromaeo/cssquery-jquery.html) 04:36:58 INFO - PROCESS | 13670 | RSS: Main: 241799168 04:36:58 INFO - PROCESS | 13670 | 04:40:00 INFO - PROCESS | 13670 | Cycle 1(1): loaded http://localhost:46302/tests/dromaeo/cssquery-jquery.html (next: http://localhost:46302/tests/dromaeo/cssquery-mootools.html) 04:40:00 INFO - PROCESS | 13670 | RSS: Main: 175214592 04:40:00 INFO - PROCESS | 13670 | 04:43:02 INFO - PROCESS | 13670 | Cycle 1(1): loaded http://localhost:46302/tests/dromaeo/cssquery-mootools.html (next: http://localhost:46302/tests/dromaeo/cssquery-prototype.html) 04:43:03 INFO - PROCESS | 13670 | RSS: Main: 179965952 04:43:03 INFO - PROCESS | 13670 | 04:46:05 INFO - PROCESS | 13670 | Cycle 1(1): loaded http://localhost:46302/tests/dromaeo/cssquery-prototype.html (next: http://localhost:46302/tests/dromaeo/cssquery-yui.html) 04:46:06 INFO - PROCESS | 13670 | RSS: Main: 176549888 04:46:06 INFO - PROCESS | 13670 | 04:49:09 INFO - PROCESS | 13670 | Cycle 1(1): loaded http://localhost:46302/tests/dromaeo/cssquery-yui.html (next: http://localhost:46302/tests/dromaeo/cssquery-dojo.html) 04:49:09 INFO - PROCESS | 13670 | RSS: Main: 262709248 04:49:09 INFO - PROCESS | 13670 | 04:49:09 INFO - PROCESS | 13670 | __start_tp_report 04:49:09 INFO - PROCESS | 13670 | _x_x_mozilla_page_load 04:49:09 INFO - PROCESS | 13670 | _x_x_mozilla_page_load_details 04:49:09 INFO - PROCESS | 13670 | |i|pagename|runs| 04:49:09 INFO - PROCESS | 13670 | |0;dojo.html;3272.1001724525754;3276.81977491238;3284.5894263217115;3308.6525914778963;3325.0357396355043;5715.257016587843;5813.6802475863815;5821.650700957945;5829.154772557982;5833.708314584275;5779.364269930315;5842.328132264791;5852.941470585293;5855.79504717335;5858.20914176586;5123.359580052494;5260.210968354749;5320.095583750753;5363;5370.301860758096;5161.587073034167;5259.500347466985;5303.416624171338;5310.7610157543;5315.73421328932;5229.843104706865;5272.841814745564;5287.286216360781;5316.175992721134;5329.120695085307;5373.382061062969;5376.704281264529;5377.139657654757;5378.946210537903;5409.459054094598;5000.39669946049;5097.515736005073;5133.922991155136;5266.8683282917855;5272.446393128743;5624.29696287964;5632.9436705632825;5658.207850900877;5674.092145256739;5679.517241034517;6104.743712193553;6113.816585502442;6115.679358957691;6118.908216376758;6125.908111378333;6118.541109416767;6143.880361275353;6179.3202747697715;6196.907046394308;6263.78076767311;5807.477327040544;5843.298804143487;5859.501942334864;5862.765489380377;5864.530837532987;6119.197913140417;6411.358864113645;6426.164598602152;6432.292447830735;6437.517186210959;6028.276606807123;6064.696765161724;6091.269047714302;6110.816675499742;6138.386161383826;6225.408586184305;6230.034344676583;6233.438990490787;6233.563650544509;6237.906431403532;6019.789307374221;6181;6183.628982261079;6194.0708893666315;6194.5044396448175;6081.787137450168;6112.989854784112;6114.206128133634;6164.229471316085;6173.506119510428;4644.442666879996;4677.4854765975715;4680.040591678698;4701.177293973609;4710.057988402334;4097.91804163921;4178.12259425518;4186.58134186585;4211.473565804275;4212.389203565527;5780.653160810365;5786.623869448473;5797.159411885253;5895.911561326584;5902.203202567598;6201.348858369896;6202.503799696104;6206.565540412218;6207.6275423474735;6208.068789681493;5718.571107166896;5897;5897;5902.468777810018;5930.703464826742;5135.743212839343;5176.767045482962;5181.81863634771;5261.237120617489;5270.472952704698;6424.196975378078;6445.1621289233035;6472.288048314682;6474.125992990886;6492.902606461002;6202.069689546604;6259.593126446766;6278.905816412849;6286.9371306288185;6288.874222515616;6187.81436556904;6281.183446151971;6348.93651063502;6377.968110159605;6444.8388790280615;6111.786087486917;6124.356942520971;6125.724342404514;6130.203073600582;6133.6319820810895;6249.6562689052535;6254.749810007549;6257.217847769029;6258.812235632939;6261.592996455216;5876.706164691663;6051.122587224916;6087.421694938974;6089.756409743738;6156.445919867234;6112.7800445382245;6116.816495505142;6120.663363515049;6121.908171377522;6125.234345706786;6016.067509536029;6060.121282414113;6074.149619053248;6081.513478921587;6084.33072362031;5881.588288819741;6038.154658347923;6091.573589848668;6098.817035488943;6163.691815409301;6018.3680713524445;6020.066889632114;6028.216331876828;6028.668423236589;6050.667213303311 04:49:09 INFO - PROCESS | 13670 | |1;ext.html;616.7163104971713;626.1703243202757;633.6419922743653;649.9610023398593;663.4924282923563;35159.82420087899;38482.61517384825;38767;38865;38923;39866.8006659967;49099.263511047364;50737;50978.745106274415;54159.729201354035;9257.213136883372;9390.812183756321;9395.812083758356;9396.530173491297;9403.482808445533;9276.11876871696;9380.812383752322;9393.67122150725;9395.06049395062;9396.671116510923;8581.356398270123;11111.888881111166;11393.601223957163;11395.658130256106;11818.940905295462;11294.943525282362;11344.319340839576;11353.375564343958;11363.715907102347;11370.204085714004;8463.195996380395;11238.213325067249;11304.717382065432;11308.264962777394;11317.207795454322;38576.614233857865;38903;45261.77369113133;46211.76894115542;46361;3906.3359228930867;3935.0752573145287;4102.2410853991805;4234.55537168599;4458.331250312447;35535.64464355349;35751.28497430063;35888.64111358905;36306.81846590777;36832;4774.069056533977;4842.951570484285;4844.2733589961435;4878.926816097762;4904.779284932187;400705;403701;404372;404805.9759701212;405652;66146.33853661401;71307.64346178235;71329.64335178291;71692.2830771696;71750.4887328941;67364;67431.32568674348;69761;72226;72592.27407725855;19293.228270869287;19717.60564788725;19731.704024439645;19754.209831606575;19755.50611234702;14981.550553483414;15056.924715376354;15089.773653395208;15100.320485578175;15117.697646047018;18889.149988250563;20713.171473140905;21674.783252167592;21743;21806;7271.527350722292;7476.1776204617445;7567.394608431312;7590.962045189739;7827.843443131105;1080.1682704317632;1094.6332978452149;1105.7346236903086;1118.1557923767502;1177.8645455772526;35676.28647427037;35918;36066.45900311497;36244.20248024345;39649.40525892114;1196.611101392051;1206.9758604827855;1207.215310048458;1209.3953023488257;1221.2428294457493;17702.734458983126;18791;18866.71699924502;18961.146748396357;19014.71477927832;593231;602928;604837;644222;1017270;69271.65364173146;70435.64782176056;70581;70700.2929970704;70837.2916270841;1379.475799196299;1465.904716193444;1473.4106357456888;1497.445945000357;1502.7144842480113;35968.460473092404;36235.45646815247;38961;39034.6096539031;39478;66478;67344;67465.66267168632;67599.66200169164;67606.32393676197;555091;559056;559447;559594;560986;31644;49969.50030499794;51566;51645.22532161945;51805.7409712949;50522;55708.72145639246;56029.159562605775;56282.1557676627;56646.716766415906;46828.76585617186;46887.53112468968;47402.28896566623;47954;48860.75569622129;46740.7662961683;49063;49244.75377623089;49248.261276081605;49529.25706114339;48257.5174248253;48999;49435.50564494309;49477;49580.75209623929;39585.60414395819;41784.164316712886;42221;42483.263535894774;42675;30590.235244119074;31090.37819243557;31220.843895780377;31590.210244744067;31776.523352150198 04:49:09 INFO - PROCESS | 13670 | |2;jquery.html;386.55546121959725;425.49725199691414;429.8151794728267;435.6536553440016;437.141017899827;16234.51296461105;20635.69046464304;20790.272340468087;20794.376168714945;21884.562308753815;20384.8980755096;20499.180032798708;20515.589688206263;20547.794522054774;20558.794412055875;5767.250257466523;5786.363500014995;6030.849228769272;6070.210872586557;6162.414570615807;5816.4474374934525;6069.332373438918;6095.9695201524155;6101.572889897709;6101.877962440749;5925.4963328117165;5955.583109182359;5959.106134079901;5961.88076238475;5965.88068238635;5925.2889653241455;5965.671888046156;5966.01560742477;5966.522678185756;6153.384661533811;6062.818115456544;6079.39206079393;6084.056971169476;6084.4219799119455;6095.817125486243;16244.51266462008;23283.53432931332;23907.64138537906;24756.133535326357;24975.375615609573;2445.730969593343;2580.548404029287;2644.5239856825765;2817.9432712732723;2901.8113822601463;11005.944970275177;11344.781400555754;15779.211039447982;16031.599210019725;16061.83938160615;1916.6837471817134;2081.375587323812;2100.978990210094;2111.493241622015;2119.0464291068924;81583.5920820392;87227.56386218028;88431;89071;91268;11402.429878506042;11496.252743571642;11817.409129543488;11907.52369905194;11995.40022998864;16807.32770689159;16814.747778783327;16821.83178168227;16829.242684079247;16850.494485165218;8749.518776467228;8968.775780605407;9003.324750643727;9066.09339066088;9127.406718563272;8522.446041007315;8525;8630.95684521586;8802.119788021148;8858.73423797287;5058.367704036996;5082.263071854561;5126.154184559506;5126.641135120581;5225.406251093453;2091.184438069154;2091.4666759976108;2100.7164032855676;2130.1053557505884;2153.364757396572;852.3820230332959;1002.3033991376069;1075.1076606416812;1084.219362059316;1091.5251865438559;20575.38273851787;20808.791912080684;20939.58120837575;21107.577848442947;21132.47168820792;1070.0904231403244;1090.6346373964652;1098.4233277529202;1105.9336439813637;1118.7426891814928;10976.451177441097;11750.70623234409;11826.172167948162;11848.348340841336;11883.168178227614;366642;549584;556237;566319;568108;95903.52048239854;98619.50690246503;99502.00497995073;99710.50144749375;101171.49414253028;999.4203362049993;1002.153180562409;1033.7415646088477;1040.354979912444;1041.0110395124511;18195.545111371794;18601;18676.6264674703;18960;19043;44326.33510497281;45345.31982020338;45786.54213457823;45962.540374595825;46351.76824115858;60027.399726003925;60881;62261.68869155625;62504.374956251675;63685;7702.614869256627;7771.92228077712;7843.647035883513;8634.352423568384;8992.910070899206;12848.35758212067;13015.674608134872;13494.257815819832;13719.1082579632;14102.576922692335;7796.337311328601;8733.253349330287;9768.316217864682;11700.648980530597;12083.57707480234;11544.884551154717;11585.768284634429;11746.236494627821;11887.702807429883;12106.09204309698;11828.763424731285;12056.51773929033;12142.210756300812;12318.322492263012;12359.196652217577;8587.05542390349;8733.781655458664;8891.599878005376;8949.552522373986;9038.909610903807;11624.360660163435;11893.226940248856;12077.03383729282;12241.755164896475;12336.938315308365 04:49:09 INFO - PROCESS | 13670 | |3;mootools.html;1538.8076490438696;1603.7754714339994;1752.264049099378;1853.7682789651294;1884.905754712265;9372.437653740772;10084.193264538837;10108;10109.140723038541;10116.291859569834;8358.91641083589;8767.298616110711;8785.736427907157;9299.953500232506;9824.705258842228;2432.3311089450367;2499.8375105618165;2501.524710305045;2517.483915797257;2519.0805356045094;2416.033586565379;2450.5956517174645;2495.650608914744;2504.7370026147264;2528.254165021314;2469.074097213545;2479.0579579759665;2487.2413913756295;2487.315988103277;2489.0914816092177;2250.3586477853896;2378.845375050616;2428.465737537748;2436.683231179953;2439.731629520751;2406.350285422926;2411.2766170148884;2429.4533729910627;2432.0879670123704;2436.6223235398543;9971.501424928725;10042.347247428894;10047.296689231756;10047.799044019079;10048.899511004869;5736.110902810071;5929.792457264019;5933.050711886078;5933.851653708649;5940.435658612468;3768.5289338832645;3833.2716783811265;3836.731428799985;3841.9423708644395;3853.5183102112237;1238.7955987262092;1251.2930194440112;1255.598208573257;1275.9345946134988;1278.520554791953;14195.929020354832;14225.928870355685;14235.928820355935;14256.073355231876;14277.714445711132;4995.925061124086;5107.182850743863;5132.127538318495;5250.684958902478;5360.973195134077;11965.222260553037;11974.521019159136;11977.700557485958;11990.100742444352;12010.819837702442;5857.531397488191;5859.062549991981;5859.238299021185;5861.501772349313;5871.882562348729;4468.396766436489;5355.169948657965;5369.167778994262;5425.9186112208345;5430.348358197041;4262.445882035316;4331.371951067078;4351.543087975717;4400.801963911632;4693.530646935347;1402.8877689784792;1409.3728290910651;1432.3268064009899;1445.927703614815;1448.4133925760084;1006.1799633298838;1011.2365164301053;1018.165104614224;1018.1752780248023;1019.551397385163;6570.638614876132;6736.696848641823;6764.4926630502905;6863.485238607124;6918.861622767517;491.54286513542746;497.1399478901521;497.477648469106;504.1630892718144;506.5694159964001;4519.954800452019;4568.725876447424;4634.513376095529;4694.460137084211;4747.905041899212;12195;12339.197952133083;12382.81425778614;12585.24488530691;12588.81116783249;12091.153619246568;12349.320787356603;12384.56654017105;12403.069769767124;12403.62789116328;9269.582868770922;9319.906800931903;9347.392419492711;9350.737650417106;9367;6417.807465776034;6564.901526477202;6644.10304608891;6651.966740166268;6673.866522669617;11308;11546.19176657626;11623.418829058683;11623.825642615198;11624;11555.4800033997;11591.304521728724;11697.649070527897;11773.52905883755;11838.585649502225;8545.615447304763;8635.87046194295;8711.651533938824;8714.520701361485;8763.211310982042;9500.95249523748;9514.571844266886;9558.952205239208;9565.665201717906;9588.808223835624;8035.196480351918;8099.109097999333;8115.1479094694205;8117.959410202911;8221.753347399588;8380.664773408995;8390.622421990904;8437.746867593982;8442.957785211034;8616.224539791197;7844.882326764989;7941.642626081724;7992.440529162903;7993.800154996172;8022.799430014296;5536.169574563848;5638.971805140948;5660.462256085665;5789.015867302653;5853.824385268449;5817.592768506164;5832.9708351457975;5942.821715348547;5994.160817485468;6013.5189184865985 04:49:09 INFO - PROCESS | 13670 | |4;prototype.html;351.090675151358;353.19295409988166;359.8236863936671;360.38734151941674;367.6580779874717;5623.718814059291;5626.634268772532;5663.575231857607;5664.150377443386;5753.769849206037;5561.387197712658;5568.693721845297;5639.661620302784;5642.943570564293;5647.237622920905;4162.647139679601;4382.430284063067;4383.232934236512;4385.122975404932;4423.712458690191;4303.935440968388;4366.978165109186;4369.126174765044;4374.12517496502;4410.184115938561;4099.303118469852;4188.832446702128;4339.502871509324;4369.6285815705705;4372.978135109336;4301.032267739764;4313.4176886120285;4340.218760623086;4344.826206951734;4361.6510679145595;3832;3832.5209348831395;3833.846646134179;3834.9453900177396;3834.9808250958845;5137.588992880598;5287.576993840522;5447.45525447456;5455;5457.772001299715;4929.1374009548545;4946.925796113062;4948.802047918079;4952.306677065214;4962.826301079481;5756.309242890838;5781.942180578182;5807.506361959238;5831.562632802556;5833.095870140135;4093.85671501499;4139.19285739281;4185.602367775056;4227.894302642459;4244.0663054128045;5947.583669143161;6004.78983235589;6024.427679370497;6033.879322413571;6040.335563088057;4269.487661480643;4298;4306.203352379757;4335.934960975588;4345.956540434619;4280.636145927631;4280.8869693879815;4327.5239723630375;4355.738655680606;4392.956070439319;4896.338994235733;4912;4915.26271059344;4921.064997650435;4940.777665005084;5836.212111364996;5869.618474799125;5876.823695289148;5908.3205431375945;5916.526677865761;1438.26648409312;1497.2513743128436;1500.1898974553837;1521.1481570320657;1527.427214794452;947.2847999760137;965.3435663748578;969.5927710361665;973.4889183178888;993.2401712689798;5010.799568017312;5017.523335283142;5056.620753443506;5062.848114556569;5105.361829771278;5240.563138140783;5286.3392075990505;5291.867703307371;5310.734463276821;5325.3875804282225;5029.597632189416;5059.343844481603;5231.796686762066;5451.991381594417;5506.394296627368;3160.5733226014654;3172.4448221561133;3181.411438883814;3200.7599430042374;3211.277462570903;6132.6627035513475;6178.165947597017;6181.052968314966;6216.968915155395;6219.968900155471;6045.183900173508;6063.333033366326;6068.726907289182;6128.387161283836;6148.538859585549;6183.227096612924;6184.907226391608;6203.906941395973;6210.472109870621;6212.254529456494;5805.709714514342;5841.932985182813;5931.288245410578;6052.757889684363;6057.575969682256;5893.263342082239;5901.409859014064;5921.082232254007;5953.791617293374;5967.671778052249;6074.210352654127;6174.567780255518;6182.876342473215;6216.855184961181;6324.6521441321165;4310.612044915973;4347.434833471629;4351.825926962886;4357.389965404909;4367.170237654836;6016.4585187331595;6034.487068599218;6049.8202850445505;6085.908711369245;6104.908426373519;5700.486956173964;5887.4406931341455;5910.674912879833;5965;5991.910121348271;5809.477147056785;5835.46157421523;5838.153467747238;5844.736986835517;5846.970765146147;5596.244506991667;5779.971100144472;5782.7686892523825;5852.473277405054;5893.852653683521;5507.911701504263;5746.511546518425;5749.281339832521;5766.654000759968;5773.307203135482;5733.340665823317;5737;5795.594308398372;5797.536197104306;5805.245318108618 04:49:09 INFO - PROCESS | 13670 | |5;yui.html;869.4348673362316;872.8472517309469;889.2130464538885;898.4294972692338;910.6903652758064;15716;16342.101184434881;16355.345786168555;16359;16384.91807540962;15628.062316261005;15643.608909777262;15675.059496430194;15681.921590392032;15707.214639268048;733.4462480826968;864.3128712673408;879.604178119847;893.6023469556051;895.6104094718786;905.1355955062932;907.2333877873186;907.6959218661756;907.8093600343935;907.9773005674845;936.063936063936;936.4896131608256;937.4750139921632;937.5078084005884;938.1369140390857;781.527176058485;915.3501014279869;929.6327950459564;937.7046230437459;942.2791564453199;697.8813601687674;698.3540225291636;698.3749544157951;698.5075521757149;698.5564166754148;15000.549983500512;15014.399424023135;15036.248187590576;15038;15046.623834404118;14377.56867293983;15390.615234619112;15508.302126404225;15601.609959750984;15768.921155394264;13746.450141994308;14379.209143497104;14381.496647617283;14510.056846304957;14630;4579.564941330568;4585.28928016158;4593.839215627437;4633.907321853544;4671.85984420468;86848;87810.5609471955;88582;89219.55390223072;90341.54829225877;12555.623331300076;12559.497620095277;12576.37118144089;12636.55772047974;12847.48610055606;23816.40458988539;23941;23952.041918323073;23954.88022559911;23971.760282397303;9230.261579073658;9236.815263694689;9362.719118426457;9401.294902882311;9560.235181185488;5059.797608095709;5113.64204505681;5132.538071573502;5162.92255616166;5247.422783493813;3662.1943172502006;3809.4095415210686;3823.5794062653085;3833.4824798651825;3837.0982819038063;679.1815861886415;679.4462513051847;762.5462849604477;791.8099656082613;806.0770417871504;693.6982412650514;789.2186735132178;811.123986095016;821.5686764448712;829.5105887526388;10618.15795491836;10835.458227088771;11051;11129.276597021168;11318.151138664633;703.9507234493639;775.3874439192974;791.7941335252875;797.533442935889;804.7344376355906;5219.164933610604;5331.306930099062;5360.222767698662;5375.623706340519;5417.26866872975;130775;134465.327673361;135514.1541737691;136000;138810;68008.6599566999;69033;69250;70628;98937.50531247442;98731;104267;104573;105010;105901.47049264704;9510.667126650535;10012.849807253042;10338.741531461774;10490.160787137163;10800.351978881292;54611.453885460636;54792.45207547874;55423.72288138695;56111.71944140253;58287.4171258282;80935.59532202301;81529;81696.18303816886;82939;84111;12750.298733569743;12965.546205882749;13027.934860325639;13223.603291901258;13579.388927498088;14031.789523156956;14347.282635868374;15713.292901819674;15819.683606328039;15928.044317340997;12781.87218127844;13632.04575679693;13736.587902362946;15462.536123916301;15866.286017129487;12692.492300308253;13205.537806176737;14643.267836607914;14933.775993360325;15301.387944482096;14381.568552943429;14714.632134196303;15204.771928420862;15440.68523338027;16393.91803040977;12272.38638068111;12374;12551.184173028723;12655.493780248687;12939.805902911276;12690.619281421572;12834.743305133657;13137.671558211121;13233.404496797859;13631 04:49:09 INFO - PROCESS | 13670 | __end_tp_report 04:49:09 INFO - PROCESS | 13670 | __start_cc_report 04:49:09 INFO - PROCESS | 13670 | _x_x_mozilla_cycle_collect,1078 04:49:09 INFO - PROCESS | 13670 | __end_cc_report 04:49:09 INFO - PROCESS | 13670 | __startTimestamp1472384949352__endTimestamp 04:49:09 INFO - PROCESS | 13670 | 04:49:09 INFO - PROCESS | 13670 | ------- Summary: start ------- 04:49:09 INFO - PROCESS | 13670 | Number of tests: 6 04:49:09 INFO - PROCESS | 13670 | 04:49:09 INFO - PROCESS | 13670 | [#0] dojo.html Cycles:165 Average:5751.73 Median:6028.28 stddev:671.59 (11.1%) stddev-sans-first:644.86 04:49:09 INFO - PROCESS | 13670 | Values: 3272.1 3276.8 3284.6 3308.7 3325.0 5715.3 5813.7 5821.7 5829.2 5833.7 5779.4 5842.3 5852.9 5855.8 5858.2 5123.4 5260.2 5320.1 5363.0 5370.3 5161.6 5259.5 5303.4 5310.8 5315.7 5229.8 5272.8 5287.3 5316.2 5329.1 5373.4 5376.7 5377.1 5378.9 5409.5 5000.4 5097.5 5133.9 5266.9 5272.4 5624.3 5632.9 5658.2 5674.1 5679.5 6104.7 6113.8 6115.7 6118.9 6125.9 6118.5 6143.9 6179.3 6196.9 6263.8 5807.5 5843.3 5859.5 5862.8 5864.5 6119.2 6411.4 6426.2 6432.3 6437.5 6028.3 6064.7 6091.3 6110.8 6138.4 6225.4 6230.0 6233.4 6233.6 6237.9 6019.8 6181.0 6183.6 6194.1 6194.5 6081.8 6113.0 6114.2 6164.2 6173.5 4644.4 4677.5 4680.0 4701.2 4710.1 4097.9 4178.1 4186.6 4211.5 4212.4 5780.7 5786.6 5797.2 5895.9 5902.2 6201.3 6202.5 6206.6 6207.6 6208.1 5718.6 5897.0 5897.0 5902.5 5930.7 5135.7 5176.8 5181.8 5261.2 5270.5 6424.2 6445.2 6472.3 6474.1 6492.9 6202.1 6259.6 6278.9 6286.9 6288.9 6187.8 6281.2 6348.9 6378.0 6444.8 6111.8 6124.4 6125.7 6130.2 6133.6 6249.7 6254.7 6257.2 6258.8 6261.6 5876.7 6051.1 6087.4 6089.8 6156.4 6112.8 6116.8 6120.7 6121.9 6125.2 6016.1 6060.1 6074.1 6081.5 6084.3 5881.6 6038.2 6091.6 6098.8 6163.7 6018.4 6020.1 6028.2 6028.7 6050.7 04:49:09 INFO - PROCESS | 13670 | 04:49:09 INFO - PROCESS | 13670 | [#1] ext.html Cycles:180 Average:73382.85 Median:42579.13 stddev:152638.68 (358.5%) stddev-sans-first:152969.10 04:49:09 INFO - PROCESS | 13670 | Values: 616.7 626.2 633.6 650.0 663.5 35159.8 38482.6 38767.0 38865.0 38923.0 39866.8 49099.3 50737.0 50978.7 54159.7 9257.2 9390.8 9395.8 9396.5 9403.5 9276.1 9380.8 9393.7 9395.1 9396.7 8581.4 11111.9 11393.6 11395.7 11818.9 11294.9 11344.3 11353.4 11363.7 11370.2 8463.2 11238.2 11304.7 11308.3 11317.2 38576.6 38903.0 45261.8 46211.8 46361.0 3906.3 3935.1 4102.2 4234.6 4458.3 35535.6 35751.3 35888.6 36306.8 36832.0 4774.1 4843.0 4844.3 4878.9 4904.8 400705.0 403701.0 404372.0 404806.0 405652.0 66146.3 71307.6 71329.6 71692.3 71750.5 67364.0 67431.3 69761.0 72226.0 72592.3 19293.2 19717.6 19731.7 19754.2 19755.5 14981.6 15056.9 15089.8 15100.3 15117.7 18889.1 20713.2 21674.8 21743.0 21806.0 7271.5 7476.2 7567.4 7591.0 7827.8 1080.2 1094.6 1105.7 1118.2 1177.9 35676.3 35918.0 36066.5 36244.2 39649.4 1196.6 1207.0 1207.2 1209.4 1221.2 17702.7 18791.0 18866.7 18961.1 19014.7 593231.0 602928.0 604837.0 644222.0 1017270.0 69271.7 70435.6 70581.0 70700.3 70837.3 1379.5 1465.9 1473.4 1497.4 1502.7 35968.5 36235.5 38961.0 39034.6 39478.0 66478.0 67344.0 67465.7 67599.7 67606.3 555091.0 559056.0 559447.0 559594.0 560986.0 31644.0 49969.5 51566.0 51645.2 51805.7 50522.0 55708.7 56029.2 56282.2 56646.7 46828.8 46887.5 47402.3 47954.0 48860.8 46740.8 49063.0 49244.8 49248.3 49529.3 48257.5 48999.0 49435.5 49477.0 49580.8 39585.6 41784.2 42221.0 42483.3 42675.0 30590.2 31090.4 31220.8 31590.2 31776.5 04:49:09 INFO - PROCESS | 13670 | 04:49:09 INFO - PROCESS | 13670 | [#2] jquery.html Cycles:180 Average:30889.48 Median:24865.75 stddev:87009.69 (349.9%) stddev-sans-first:87223.63 04:49:09 INFO - PROCESS | 13670 | Values: 386.6 425.5 429.8 435.7 437.1 16234.5 20635.7 20790.3 20794.4 21884.6 20384.9 20499.2 20515.6 20547.8 20558.8 5767.3 5786.4 6030.8 6070.2 6162.4 5816.4 6069.3 6096.0 6101.6 6101.9 5925.5 5955.6 5959.1 5961.9 5965.9 5925.3 5965.7 5966.0 5966.5 6153.4 6062.8 6079.4 6084.1 6084.4 6095.8 16244.5 23283.5 23907.6 24756.1 24975.4 2445.7 2580.5 2644.5 2817.9 2901.8 11005.9 11344.8 15779.2 16031.6 16061.8 1916.7 2081.4 2101.0 2111.5 2119.0 81583.6 87227.6 88431.0 89071.0 91268.0 11402.4 11496.3 11817.4 11907.5 11995.4 16807.3 16814.7 16821.8 16829.2 16850.5 8749.5 8968.8 9003.3 9066.1 9127.4 8522.4 8525.0 8631.0 8802.1 8858.7 5058.4 5082.3 5126.2 5126.6 5225.4 2091.2 2091.5 2100.7 2130.1 2153.4 852.4 1002.3 1075.1 1084.2 1091.5 20575.4 20808.8 20939.6 21107.6 21132.5 1070.1 1090.6 1098.4 1105.9 1118.7 10976.5 11750.7 11826.2 11848.3 11883.2 366642.0 549584.0 556237.0 566319.0 568108.0 95903.5 98619.5 99502.0 99710.5 101171.5 999.4 1002.2 1033.7 1040.4 1041.0 18195.5 18601.0 18676.6 18960.0 19043.0 44326.3 45345.3 45786.5 45962.5 46351.8 60027.4 60881.0 62261.7 62504.4 63685.0 7702.6 7771.9 7843.6 8634.4 8992.9 12848.4 13015.7 13494.3 13719.1 14102.6 7796.3 8733.3 9768.3 11700.6 12083.6 11544.9 11585.8 11746.2 11887.7 12106.1 11828.8 12056.5 12142.2 12318.3 12359.2 8587.1 8733.8 8891.6 8949.6 9038.9 11624.4 11893.2 12077.0 12241.8 12336.9 04:49:09 INFO - PROCESS | 13670 | 04:49:09 INFO - PROCESS | 13670 | [#3] mootools.html Cycles:180 Average:6484.89 Median:4376.17 stddev:3802.19 (86.9%) stddev-sans-first:3794.69 04:49:09 INFO - PROCESS | 13670 | Values: 1538.8 1603.8 1752.3 1853.8 1884.9 9372.4 10084.2 10108.0 10109.1 10116.3 8358.9 8767.3 8785.7 9300.0 9824.7 2432.3 2499.8 2501.5 2517.5 2519.1 2416.0 2450.6 2495.7 2504.7 2528.3 2469.1 2479.1 2487.2 2487.3 2489.1 2250.4 2378.8 2428.5 2436.7 2439.7 2406.4 2411.3 2429.5 2432.1 2436.6 9971.5 10042.3 10047.3 10047.8 10048.9 5736.1 5929.8 5933.1 5933.9 5940.4 3768.5 3833.3 3836.7 3841.9 3853.5 1238.8 1251.3 1255.6 1275.9 1278.5 14195.9 14225.9 14235.9 14256.1 14277.7 4995.9 5107.2 5132.1 5250.7 5361.0 11965.2 11974.5 11977.7 11990.1 12010.8 5857.5 5859.1 5859.2 5861.5 5871.9 4468.4 5355.2 5369.2 5425.9 5430.3 4262.4 4331.4 4351.5 4400.8 4693.5 1402.9 1409.4 1432.3 1445.9 1448.4 1006.2 1011.2 1018.2 1018.2 1019.6 6570.6 6736.7 6764.5 6863.5 6918.9 491.5 497.1 497.5 504.2 506.6 4520.0 4568.7 4634.5 4694.5 4747.9 12195.0 12339.2 12382.8 12585.2 12588.8 12091.2 12349.3 12384.6 12403.1 12403.6 9269.6 9319.9 9347.4 9350.7 9367.0 6417.8 6564.9 6644.1 6652.0 6673.9 11308.0 11546.2 11623.4 11623.8 11624.0 11555.5 11591.3 11697.6 11773.5 11838.6 8545.6 8635.9 8711.7 8714.5 8763.2 9501.0 9514.6 9559.0 9565.7 9588.8 8035.2 8099.1 8115.1 8118.0 8221.8 8380.7 8390.6 8437.7 8443.0 8616.2 7844.9 7941.6 7992.4 7993.8 8022.8 5536.2 5639.0 5660.5 5789.0 5853.8 5817.6 5833.0 5942.8 5994.2 6013.5 04:49:09 INFO - PROCESS | 13670 | 04:49:09 INFO - PROCESS | 13670 | [#4] prototype.html Cycles:180 Average:4880.60 Median:5534.65 stddev:1430.41 (25.8%) stddev-sans-first:1393.44 04:49:09 INFO - PROCESS | 13670 | Values: 351.1 353.2 359.8 360.4 367.7 5623.7 5626.6 5663.6 5664.2 5753.8 5561.4 5568.7 5639.7 5642.9 5647.2 4162.6 4382.4 4383.2 4385.1 4423.7 4303.9 4367.0 4369.1 4374.1 4410.2 4099.3 4188.8 4339.5 4369.6 4373.0 4301.0 4313.4 4340.2 4344.8 4361.7 3832.0 3832.5 3833.8 3834.9 3835.0 5137.6 5287.6 5447.5 5455.0 5457.8 4929.1 4946.9 4948.8 4952.3 4962.8 5756.3 5781.9 5807.5 5831.6 5833.1 4093.9 4139.2 4185.6 4227.9 4244.1 5947.6 6004.8 6024.4 6033.9 6040.3 4269.5 4298.0 4306.2 4335.9 4346.0 4280.6 4280.9 4327.5 4355.7 4393.0 4896.3 4912.0 4915.3 4921.1 4940.8 5836.2 5869.6 5876.8 5908.3 5916.5 1438.3 1497.3 1500.2 1521.1 1527.4 947.3 965.3 969.6 973.5 993.2 5010.8 5017.5 5056.6 5062.8 5105.4 5240.6 5286.3 5291.9 5310.7 5325.4 5029.6 5059.3 5231.8 5452.0 5506.4 3160.6 3172.4 3181.4 3200.8 3211.3 6132.7 6178.2 6181.1 6217.0 6220.0 6045.2 6063.3 6068.7 6128.4 6148.5 6183.2 6184.9 6203.9 6210.5 6212.3 5805.7 5841.9 5931.3 6052.8 6057.6 5893.3 5901.4 5921.1 5953.8 5967.7 6074.2 6174.6 6182.9 6216.9 6324.7 4310.6 4347.4 4351.8 4357.4 4367.2 6016.5 6034.5 6049.8 6085.9 6104.9 5700.5 5887.4 5910.7 5965.0 5991.9 5809.5 5835.5 5838.2 5844.7 5847.0 5596.2 5780.0 5782.8 5852.5 5893.9 5507.9 5746.5 5749.3 5766.7 5773.3 5733.3 5737.0 5795.6 5797.5 5805.2 04:49:09 INFO - PROCESS | 13670 | 04:49:09 INFO - PROCESS | 13670 | [#5] yui.html Cycles:180 Average:22454.38 Median:3828.53 stddev:32615.40 (851.9%) stddev-sans-first:32666.63 04:49:09 INFO - PROCESS | 13670 | Values: 869.4 872.8 889.2 898.4 910.7 15716.0 16342.1 16355.3 16359.0 16384.9 15628.1 15643.6 15675.1 15681.9 15707.2 733.4 864.3 879.6 893.6 895.6 905.1 907.2 907.7 907.8 908.0 936.1 936.5 937.5 937.5 938.1 781.5 915.4 929.6 937.7 942.3 697.9 698.4 698.4 698.5 698.6 15000.5 15014.4 15036.2 15038.0 15046.6 14377.6 15390.6 15508.3 15601.6 15768.9 13746.5 14379.2 14381.5 14510.1 14630.0 4579.6 4585.3 4593.8 4633.9 4671.9 86848.0 87810.6 88582.0 89219.6 90341.5 12555.6 12559.5 12576.4 12636.6 12847.5 23816.4 23941.0 23952.0 23954.9 23971.8 9230.3 9236.8 9362.7 9401.3 9560.2 5059.8 5113.6 5132.5 5162.9 5247.4 3662.2 3809.4 3823.6 3833.5 3837.1 679.2 679.4 762.5 791.8 806.1 693.7 789.2 811.1 821.6 829.5 10618.2 10835.5 11051.0 11129.3 11318.2 704.0 775.4 791.8 797.5 804.7 5219.2 5331.3 5360.2 5375.6 5417.3 130775.0 134465.3 135514.2 136000.0 138810.0 68008.7 69033.0 69250.0 70628.0 98937.5 98731.0 104267.0 104573.0 105010.0 105901.5 9510.7 10012.8 10338.7 10490.2 10800.4 54611.5 54792.5 55423.7 56111.7 58287.4 80935.6 81529.0 81696.2 82939.0 84111.0 12750.3 12965.5 13027.9 13223.6 13579.4 14031.8 14347.3 15713.3 15819.7 15928.0 12781.9 13632.0 13736.6 15462.5 15866.3 12692.5 13205.5 14643.3 14933.8 15301.4 14381.6 14714.6 15204.8 15440.7 16393.9 12272.4 12374.0 12551.2 12655.5 12939.8 12690.6 12834.7 13137.7 13233.4 13631.0 04:49:09 INFO - PROCESS | 13670 | -------- Summary: end -------- 04:49:09 INFO - PROCESS | 13670 | 04:49:10 INFO - TEST-INFO | 13670: exit 0 04:49:10 INFO - TEST-OK | dromaeo_css | took 1113426ms 04:49:10 INFO - TEST-START | kraken 04:49:10 INFO - operating with platform_type : linux_ 04:49:10 INFO - Initialising browser for kraken test... 04:49:10 INFO - TEST-INFO | started process 13879 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpsSKyZG/profile http://localhost:46302/getInfo.html) 04:49:14 INFO - PROCESS | 13879 | __metrics Screen width/height:1600/1200 04:49:14 INFO - PROCESS | 13879 | colorDepth:24 04:49:14 INFO - PROCESS | 13879 | Browser inner width/height: 1024/697 04:49:14 INFO - PROCESS | 13879 | __metrics 04:49:14 INFO - PROCESS | 13879 | 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" 04:49:14 INFO - PROCESS | 13879 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 04:49:15 INFO - TEST-INFO | 13879: exit 0 04:49:15 INFO - Browser initialized. 04:49:15 INFO - Running cycle 1/1 for kraken test... 04:49:15 INFO - TEST-INFO | started process 13967 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpsSKyZG/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:49:27 INFO - PROCESS | 13967 | RSS: Main: 145448960 04:49:27 INFO - PROCESS | 13967 | 04:49:30 INFO - PROCESS | 13967 | Cycle 1(1): loaded http://localhost:46302/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:46302/tests/kraken/driver.html?testName=audio-beat-detection) 04:49:31 INFO - PROCESS | 13967 | RSS: Main: 191369216 04:49:31 INFO - PROCESS | 13967 | 04:49:33 INFO - PROCESS | 13967 | Cycle 1(1): loaded http://localhost:46302/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:46302/tests/kraken/driver.html?testName=audio-dft) 04:49:34 INFO - PROCESS | 13967 | RSS: Main: 181522432 04:49:34 INFO - PROCESS | 13967 | 04:49:37 INFO - PROCESS | 13967 | Cycle 1(1): loaded http://localhost:46302/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:46302/tests/kraken/driver.html?testName=audio-fft) 04:49:37 INFO - PROCESS | 13967 | RSS: Main: 270430208 04:49:37 INFO - PROCESS | 13967 | 04:49:39 INFO - PROCESS | 13967 | Cycle 1(1): loaded http://localhost:46302/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:46302/tests/kraken/driver.html?testName=audio-oscillator) 04:49:40 INFO - PROCESS | 13967 | RSS: Main: 175280128 04:49:40 INFO - PROCESS | 13967 | 04:49:42 INFO - PROCESS | 13967 | Cycle 1(1): loaded http://localhost:46302/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:46302/tests/kraken/driver.html?testName=imaging-gaussian-blur) 04:49:43 INFO - PROCESS | 13967 | RSS: Main: 177491968 04:49:43 INFO - PROCESS | 13967 | 04:49:46 INFO - PROCESS | 13967 | Cycle 1(1): loaded http://localhost:46302/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:46302/tests/kraken/driver.html?testName=imaging-darkroom) 04:49:46 INFO - PROCESS | 13967 | RSS: Main: 213303296 04:49:46 INFO - PROCESS | 13967 | 04:49:49 INFO - PROCESS | 13967 | Cycle 1(1): loaded http://localhost:46302/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:46302/tests/kraken/driver.html?testName=imaging-desaturate) 04:49:50 INFO - PROCESS | 13967 | RSS: Main: 211750912 04:49:50 INFO - PROCESS | 13967 | 04:49:53 INFO - PROCESS | 13967 | Cycle 1(1): loaded http://localhost:46302/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:46302/tests/kraken/driver.html?testName=json-parse-financial) 04:49:53 INFO - PROCESS | 13967 | RSS: Main: 211386368 04:49:53 INFO - PROCESS | 13967 | 04:49:55 INFO - PROCESS | 13967 | Cycle 1(1): loaded http://localhost:46302/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:46302/tests/kraken/driver.html?testName=json-stringify-tinderbox) 04:49:55 INFO - PROCESS | 13967 | RSS: Main: 175673344 04:49:55 INFO - PROCESS | 13967 | 04:49:57 INFO - PROCESS | 13967 | Cycle 1(1): loaded http://localhost:46302/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:46302/tests/kraken/driver.html?testName=stanford-crypto-aes) 04:49:58 INFO - PROCESS | 13967 | RSS: Main: 182800384 04:49:58 INFO - PROCESS | 13967 | 04:50:00 INFO - PROCESS | 13967 | Cycle 1(1): loaded http://localhost:46302/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:46302/tests/kraken/driver.html?testName=stanford-crypto-ccm) 04:50:00 INFO - PROCESS | 13967 | RSS: Main: 185749504 04:50:00 INFO - PROCESS | 13967 | 04:50:03 INFO - PROCESS | 13967 | Cycle 1(1): loaded http://localhost:46302/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:46302/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 04:50:03 INFO - PROCESS | 13967 | RSS: Main: 181460992 04:50:03 INFO - PROCESS | 13967 | 04:50:06 INFO - PROCESS | 13967 | Cycle 1(1): loaded http://localhost:46302/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:46302/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 04:50:07 INFO - PROCESS | 13967 | RSS: Main: 184025088 04:50:07 INFO - PROCESS | 13967 | 04:50:08 INFO - PROCESS | 13967 | Cycle 1(1): loaded http://localhost:46302/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:46302/tests/kraken/driver.html?testName=ai-astar) 04:50:09 INFO - PROCESS | 13967 | RSS: Main: 235708416 04:50:09 INFO - PROCESS | 13967 | 04:50:09 INFO - PROCESS | 13967 | __start_tp_report 04:50:09 INFO - PROCESS | 13967 | _x_x_mozilla_page_load 04:50:09 INFO - PROCESS | 13967 | _x_x_mozilla_page_load_details 04:50:09 INFO - PROCESS | 13967 | |i|pagename|runs| 04:50:09 INFO - PROCESS | 13967 | |0;ai-astar;101;112;111;111;111;110;110;110;110;108 04:50:09 INFO - PROCESS | 13967 | |1;audio-beat-detection;146;116;118;166;115;118;142;115;150;113 04:50:09 INFO - PROCESS | 13967 | |2;audio-dft;161;158;159;159;162;160;159;160;161;158 04:50:09 INFO - PROCESS | 13967 | |3;audio-fft;75;75;76;121;75;75;75;75;108;76 04:50:09 INFO - PROCESS | 13967 | |4;audio-oscillator;83;83;131;83;83;82;120;82;83;120 04:50:09 INFO - PROCESS | 13967 | |5;imaging-gaussian-blur;116;117;116;117;116;117;117;116;117;117 04:50:09 INFO - PROCESS | 13967 | |6;imaging-darkroom;145;145;145;145;146;145;145;145;145;145 04:50:09 INFO - PROCESS | 13967 | |7;imaging-desaturate;95;96;96;95;95;95;96;97;96;94 04:50:09 INFO - PROCESS | 13967 | |8;json-parse-financial;49;51;49;50;61;50;50;47;49;48 04:50:09 INFO - PROCESS | 13967 | |9;json-stringify-tinderbox;59;60;59;59;60;59;60;61;59;59 04:50:09 INFO - PROCESS | 13967 | |10;stanford-crypto-aes;73;73;74;81;73;72;71;72;72;71 04:50:09 INFO - PROCESS | 13967 | |11;stanford-crypto-ccm;119;123;127;121;116;122;116;119;165;116 04:50:09 INFO - PROCESS | 13967 | |12;stanford-crypto-pbkdf2;153;153;154;152;152;154;152;152;152;150 04:50:09 INFO - PROCESS | 13967 | |13;stanford-crypto-sha256-iterative;64;63;62;61;62;60;63;61;62;61 04:50:09 INFO - PROCESS | 13967 | __end_tp_report 04:50:09 INFO - PROCESS | 13967 | __start_cc_report 04:50:09 INFO - PROCESS | 13967 | _x_x_mozilla_cycle_collect,1608 04:50:09 INFO - PROCESS | 13967 | __end_cc_report 04:50:09 INFO - PROCESS | 13967 | __startTimestamp1472385009084__endTimestamp 04:50:09 INFO - PROCESS | 13967 | 04:50:09 INFO - PROCESS | 13967 | ------- Summary: start ------- 04:50:09 INFO - PROCESS | 13967 | Number of tests: 14 04:50:09 INFO - PROCESS | 13967 | 04:50:09 INFO - PROCESS | 13967 | [#0] ai-astar Cycles:10 Average:109.40 Median:110.50 stddev:3.13 (2.8%) stddev-sans-first:1.12 04:50:09 INFO - PROCESS | 13967 | Values: 101.0 112.0 111.0 111.0 111.0 110.0 110.0 110.0 110.0 108.0 04:50:09 INFO - PROCESS | 13967 | 04:50:09 INFO - PROCESS | 13967 | [#1] audio-beat-detection Cycles:10 Average:129.90 Median:130.00 stddev:19.20 (14.8%) stddev-sans-first:19.46 04:50:09 INFO - PROCESS | 13967 | Values: 146.0 116.0 118.0 166.0 115.0 118.0 142.0 115.0 150.0 113.0 04:50:09 INFO - PROCESS | 13967 | 04:50:09 INFO - PROCESS | 13967 | [#2] audio-dft Cycles:10 Average:159.70 Median:160.00 stddev:1.34 (0.8%) stddev-sans-first:1.33 04:50:09 INFO - PROCESS | 13967 | Values: 161.0 158.0 159.0 159.0 162.0 160.0 159.0 160.0 161.0 158.0 04:50:09 INFO - PROCESS | 13967 | 04:50:09 INFO - PROCESS | 13967 | [#3] audio-fft Cycles:10 Average:83.10 Median:75.00 stddev:16.84 (22.4%) stddev-sans-first:17.60 04:50:09 INFO - PROCESS | 13967 | Values: 75.0 75.0 76.0 121.0 75.0 75.0 75.0 75.0 108.0 76.0 04:50:09 INFO - PROCESS | 13967 | 04:50:09 INFO - PROCESS | 13967 | [#4] audio-oscillator Cycles:10 Average:95.00 Median:83.00 stddev:20.01 (24.1%) stddev-sans-first:20.75 04:50:09 INFO - PROCESS | 13967 | Values: 83.0 83.0 131.0 83.0 83.0 82.0 120.0 82.0 83.0 120.0 04:50:09 INFO - PROCESS | 13967 | 04:50:09 INFO - PROCESS | 13967 | [#5] imaging-gaussian-blur Cycles:10 Average:116.60 Median:117.00 stddev:0.52 (0.4%) stddev-sans-first:0.50 04:50:09 INFO - PROCESS | 13967 | Values: 116.0 117.0 116.0 117.0 116.0 117.0 117.0 116.0 117.0 117.0 04:50:09 INFO - PROCESS | 13967 | 04:50:09 INFO - PROCESS | 13967 | [#6] imaging-darkroom Cycles:10 Average:145.10 Median:145.00 stddev:0.32 (0.2%) stddev-sans-first:0.33 04:50:09 INFO - PROCESS | 13967 | Values: 145.0 145.0 145.0 145.0 146.0 145.0 145.0 145.0 145.0 145.0 04:50:09 INFO - PROCESS | 13967 | 04:50:09 INFO - PROCESS | 13967 | [#7] imaging-desaturate Cycles:10 Average:95.50 Median:96.00 stddev:0.85 (0.9%) stddev-sans-first:0.88 04:50:09 INFO - PROCESS | 13967 | Values: 95.0 96.0 96.0 95.0 95.0 95.0 96.0 97.0 96.0 94.0 04:50:09 INFO - PROCESS | 13967 | 04:50:09 INFO - PROCESS | 13967 | [#8] json-parse-financial Cycles:10 Average:50.40 Median:50.00 stddev:3.89 (7.8%) stddev-sans-first:4.10 04:50:09 INFO - PROCESS | 13967 | Values: 49.0 51.0 49.0 50.0 61.0 50.0 50.0 47.0 49.0 48.0 04:50:09 INFO - PROCESS | 13967 | 04:50:09 INFO - PROCESS | 13967 | [#9] json-stringify-tinderbox Cycles:10 Average:59.50 Median:59.50 stddev:0.71 (1.2%) stddev-sans-first:0.73 04:50:09 INFO - PROCESS | 13967 | Values: 59.0 60.0 59.0 59.0 60.0 59.0 60.0 61.0 59.0 59.0 04:50:09 INFO - PROCESS | 13967 | 04:50:09 INFO - PROCESS | 13967 | [#10] stanford-crypto-aes Cycles:10 Average:73.20 Median:73.00 stddev:2.90 (4.0%) stddev-sans-first:3.07 04:50:09 INFO - PROCESS | 13967 | Values: 73.0 73.0 74.0 81.0 73.0 72.0 71.0 72.0 72.0 71.0 04:50:09 INFO - PROCESS | 13967 | 04:50:09 INFO - PROCESS | 13967 | [#11] stanford-crypto-ccm Cycles:10 Average:124.40 Median:121.50 stddev:14.70 (12.1%) stddev-sans-first:15.46 04:50:09 INFO - PROCESS | 13967 | Values: 119.0 123.0 127.0 121.0 116.0 122.0 116.0 119.0 165.0 116.0 04:50:09 INFO - PROCESS | 13967 | 04:50:09 INFO - PROCESS | 13967 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:152.40 Median:152.50 stddev:1.17 (0.8%) stddev-sans-first:1.22 04:50:09 INFO - PROCESS | 13967 | Values: 153.0 153.0 154.0 152.0 152.0 154.0 152.0 152.0 152.0 150.0 04:50:09 INFO - PROCESS | 13967 | 04:50:09 INFO - PROCESS | 13967 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:61.90 Median:62.00 stddev:1.20 (1.9%) stddev-sans-first:1.00 04:50:09 INFO - PROCESS | 13967 | Values: 64.0 63.0 62.0 61.0 62.0 60.0 63.0 61.0 62.0 61.0 04:50:09 INFO - PROCESS | 13967 | -------- Summary: end -------- 04:50:09 INFO - PROCESS | 13967 | 04:50:09 INFO - TEST-INFO | 13967: exit 0 04:50:10 INFO - TEST-OK | kraken | took 59904ms 04:50:10 INFO - SUITE-END | took 1173s 04:50:10 INFO - Completed test suite (00:19:33) 04:50:10 INFO - javascript benchmark 04:50:10 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3272.1001724525754, 3276.81977491238, 3284.5894263217115, 3308.6525914778963, 3325.0357396355043, 5715.257016587843, 5813.6802475863815, 5821.650700957945, 5829.154772557982, 5833.708314584275, 5779.364269930315, 5842.328132264791, 5852.941470585293, 5855.79504717335, 5858.20914176586, 5123.359580052494, 5260.210968354749, 5320.095583750753, 5363.0, 5370.301860758096, 5161.587073034167, 5259.500347466985, 5303.416624171338, 5310.7610157543, 5315.73421328932, 5229.843104706865, 5272.841814745564, 5287.286216360781, 5316.175992721134, 5329.120695085307, 5373.382061062969, 5376.704281264529, 5377.139657654757, 5378.946210537903, 5409.459054094598, 5000.39669946049, 5097.515736005073, 5133.922991155136, 5266.8683282917855, 5272.446393128743, 5624.29696287964, 5632.9436705632825, 5658.207850900877, 5674.092145256739, 5679.517241034517, 6104.743712193553, 6113.816585502442, 6115.679358957691, 6118.908216376758, 6125.908111378333, 6118.541109416767, 6143.880361275353, 6179.3202747697715, 6196.907046394308, 6263.78076767311, 5807.477327040544, 5843.298804143487, 5859.501942334864, 5862.765489380377, 5864.530837532987, 6119.197913140417, 6411.358864113645, 6426.164598602152, 6432.292447830735, 6437.517186210959, 6028.276606807123, 6064.696765161724, 6091.269047714302, 6110.816675499742, 6138.386161383826, 6225.408586184305, 6230.034344676583, 6233.438990490787, 6233.563650544509, 6237.906431403532, 6019.789307374221, 6181.0, 6183.628982261079, 6194.0708893666315, 6194.5044396448175, 6081.787137450168, 6112.989854784112, 6114.206128133634, 6164.229471316085, 6173.506119510428, 4644.442666879996, 4677.4854765975715, 4680.040591678698, 4701.177293973609, 4710.057988402334, 4097.91804163921, 4178.12259425518, 4186.58134186585, 4211.473565804275, 4212.389203565527, 5780.653160810365, 5786.623869448473, 5797.159411885253, 5895.911561326584, 5902.203202567598, 6201.348858369896, 6202.503799696104, 6206.565540412218, 6207.6275423474735, 6208.068789681493, 5718.571107166896, 5897.0, 5897.0, 5902.468777810018, 5930.703464826742, 5135.743212839343, 5176.767045482962, 5181.81863634771, 5261.237120617489, 5270.472952704698, 6424.196975378078, 6445.1621289233035, 6472.288048314682, 6474.125992990886, 6492.902606461002, 6202.069689546604, 6259.593126446766, 6278.905816412849, 6286.9371306288185, 6288.874222515616, 6187.81436556904, 6281.183446151971, 6348.93651063502, 6377.968110159605, 6444.8388790280615, 6111.786087486917, 6124.356942520971, 6125.724342404514, 6130.203073600582, 6133.6319820810895, 6249.6562689052535, 6254.749810007549, 6257.217847769029, 6258.812235632939, 6261.592996455216, 5876.706164691663, 6051.122587224916, 6087.421694938974, 6089.756409743738, 6156.445919867234, 6112.7800445382245, 6116.816495505142, 6120.663363515049, 6121.908171377522, 6125.234345706786, 6016.067509536029, 6060.121282414113, 6074.149619053248, 6081.513478921587, 6084.33072362031, 5881.588288819741, 6038.154658347923, 6091.573589848668, 6098.817035488943, 6163.691815409301, 6018.3680713524445, 6020.066889632114, 6028.216331876828, 6028.668423236589, 6050.667213303311], "value": 5704.76319286157, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [616.7163104971713, 626.1703243202757, 633.6419922743653, 649.9610023398593, 663.4924282923563, 35159.82420087899, 38482.61517384825, 38767.0, 38865.0, 38923.0, 39866.8006659967, 49099.263511047364, 50737.0, 50978.745106274415, 54159.729201354035, 9257.213136883372, 9390.812183756321, 9395.812083758356, 9396.530173491297, 9403.482808445533, 9276.11876871696, 9380.812383752322, 9393.67122150725, 9395.06049395062, 9396.671116510923, 8581.356398270123, 11111.888881111166, 11393.601223957163, 11395.658130256106, 11818.940905295462, 11294.943525282362, 11344.319340839576, 11353.375564343958, 11363.715907102347, 11370.204085714004, 8463.195996380395, 11238.213325067249, 11304.717382065432, 11308.264962777394, 11317.207795454322, 38576.614233857865, 38903.0, 45261.77369113133, 46211.76894115542, 46361.0, 3906.3359228930867, 3935.0752573145287, 4102.2410853991805, 4234.55537168599, 4458.331250312447, 35535.64464355349, 35751.28497430063, 35888.64111358905, 36306.81846590777, 36832.0, 4774.069056533977, 4842.951570484285, 4844.2733589961435, 4878.926816097762, 4904.779284932187, 400705.0, 403701.0, 404372.0, 404805.9759701212, 405652.0, 66146.33853661401, 71307.64346178235, 71329.64335178291, 71692.2830771696, 71750.4887328941, 67364.0, 67431.32568674348, 69761.0, 72226.0, 72592.27407725855, 19293.228270869287, 19717.60564788725, 19731.704024439645, 19754.209831606575, 19755.50611234702, 14981.550553483414, 15056.924715376354, 15089.773653395208, 15100.320485578175, 15117.697646047018, 18889.149988250563, 20713.171473140905, 21674.783252167592, 21743.0, 21806.0, 7271.527350722292, 7476.1776204617445, 7567.394608431312, 7590.962045189739, 7827.843443131105, 1080.1682704317632, 1094.6332978452149, 1105.7346236903086, 1118.1557923767502, 1177.8645455772526, 35676.28647427037, 35918.0, 36066.45900311497, 36244.20248024345, 39649.40525892114, 1196.611101392051, 1206.9758604827855, 1207.215310048458, 1209.3953023488257, 1221.2428294457493, 17702.734458983126, 18791.0, 18866.71699924502, 18961.146748396357, 19014.71477927832, 593231.0, 602928.0, 604837.0, 644222.0, 1017270.0, 69271.65364173146, 70435.64782176056, 70581.0, 70700.2929970704, 70837.2916270841, 1379.475799196299, 1465.904716193444, 1473.4106357456888, 1497.445945000357, 1502.7144842480113, 35968.460473092404, 36235.45646815247, 38961.0, 39034.6096539031, 39478.0, 66478.0, 67344.0, 67465.66267168632, 67599.66200169164, 67606.32393676197, 555091.0, 559056.0, 559447.0, 559594.0, 560986.0, 31644.0, 49969.50030499794, 51566.0, 51645.22532161945, 51805.7409712949, 50522.0, 55708.72145639246, 56029.159562605775, 56282.1557676627, 56646.716766415906, 46828.76585617186, 46887.53112468968, 47402.28896566623, 47954.0, 48860.75569622129, 46740.7662961683, 49063.0, 49244.75377623089, 49248.261276081605, 49529.25706114339, 48257.5174248253, 48999.0, 49435.50564494309, 49477.0, 49580.75209623929, 39585.60414395819, 41784.164316712886, 42221.0, 42483.263535894774, 42675.0, 30590.235244119074, 31090.37819243557, 31220.843895780377, 31590.210244744067, 31776.523352150198], "value": 23209.940618600238, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [386.55546121959725, 425.49725199691414, 429.8151794728267, 435.6536553440016, 437.141017899827, 16234.51296461105, 20635.69046464304, 20790.272340468087, 20794.376168714945, 21884.562308753815, 20384.8980755096, 20499.180032798708, 20515.589688206263, 20547.794522054774, 20558.794412055875, 5767.250257466523, 5786.363500014995, 6030.849228769272, 6070.210872586557, 6162.414570615807, 5816.4474374934525, 6069.332373438918, 6095.9695201524155, 6101.572889897709, 6101.877962440749, 5925.4963328117165, 5955.583109182359, 5959.106134079901, 5961.88076238475, 5965.88068238635, 5925.2889653241455, 5965.671888046156, 5966.01560742477, 5966.522678185756, 6153.384661533811, 6062.818115456544, 6079.39206079393, 6084.056971169476, 6084.4219799119455, 6095.817125486243, 16244.51266462008, 23283.53432931332, 23907.64138537906, 24756.133535326357, 24975.375615609573, 2445.730969593343, 2580.548404029287, 2644.5239856825765, 2817.9432712732723, 2901.8113822601463, 11005.944970275177, 11344.781400555754, 15779.211039447982, 16031.599210019725, 16061.83938160615, 1916.6837471817134, 2081.375587323812, 2100.978990210094, 2111.493241622015, 2119.0464291068924, 81583.5920820392, 87227.56386218028, 88431.0, 89071.0, 91268.0, 11402.429878506042, 11496.252743571642, 11817.409129543488, 11907.52369905194, 11995.40022998864, 16807.32770689159, 16814.747778783327, 16821.83178168227, 16829.242684079247, 16850.494485165218, 8749.518776467228, 8968.775780605407, 9003.324750643727, 9066.09339066088, 9127.406718563272, 8522.446041007315, 8525.0, 8630.95684521586, 8802.119788021148, 8858.73423797287, 5058.367704036996, 5082.263071854561, 5126.154184559506, 5126.641135120581, 5225.406251093453, 2091.184438069154, 2091.4666759976108, 2100.7164032855676, 2130.1053557505884, 2153.364757396572, 852.3820230332959, 1002.3033991376069, 1075.1076606416812, 1084.219362059316, 1091.5251865438559, 20575.38273851787, 20808.791912080684, 20939.58120837575, 21107.577848442947, 21132.47168820792, 1070.0904231403244, 1090.6346373964652, 1098.4233277529202, 1105.9336439813637, 1118.7426891814928, 10976.451177441097, 11750.70623234409, 11826.172167948162, 11848.348340841336, 11883.168178227614, 366642.0, 549584.0, 556237.0, 566319.0, 568108.0, 95903.52048239854, 98619.50690246503, 99502.00497995073, 99710.50144749375, 101171.49414253028, 999.4203362049993, 1002.153180562409, 1033.7415646088477, 1040.354979912444, 1041.0110395124511, 18195.545111371794, 18601.0, 18676.6264674703, 18960.0, 19043.0, 44326.33510497281, 45345.31982020338, 45786.54213457823, 45962.540374595825, 46351.76824115858, 60027.399726003925, 60881.0, 62261.68869155625, 62504.374956251675, 63685.0, 7702.614869256627, 7771.92228077712, 7843.647035883513, 8634.352423568384, 8992.910070899206, 12848.35758212067, 13015.674608134872, 13494.257815819832, 13719.1082579632, 14102.576922692335, 7796.337311328601, 8733.253349330287, 9768.316217864682, 11700.648980530597, 12083.57707480234, 11544.884551154717, 11585.768284634429, 11746.236494627821, 11887.702807429883, 12106.09204309698, 11828.763424731285, 12056.51773929033, 12142.210756300812, 12318.322492263012, 12359.196652217577, 8587.05542390349, 8733.781655458664, 8891.599878005376, 8949.552522373986, 9038.909610903807, 11624.360660163435, 11893.226940248856, 12077.03383729282, 12241.755164896475, 12336.938315308365], "value": 9777.983728547251, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1538.8076490438696, 1603.7754714339994, 1752.264049099378, 1853.7682789651294, 1884.905754712265, 9372.437653740772, 10084.193264538837, 10108.0, 10109.140723038541, 10116.291859569834, 8358.91641083589, 8767.298616110711, 8785.736427907157, 9299.953500232506, 9824.705258842228, 2432.3311089450367, 2499.8375105618165, 2501.524710305045, 2517.483915797257, 2519.0805356045094, 2416.033586565379, 2450.5956517174645, 2495.650608914744, 2504.7370026147264, 2528.254165021314, 2469.074097213545, 2479.0579579759665, 2487.2413913756295, 2487.315988103277, 2489.0914816092177, 2250.3586477853896, 2378.845375050616, 2428.465737537748, 2436.683231179953, 2439.731629520751, 2406.350285422926, 2411.2766170148884, 2429.4533729910627, 2432.0879670123704, 2436.6223235398543, 9971.501424928725, 10042.347247428894, 10047.296689231756, 10047.799044019079, 10048.899511004869, 5736.110902810071, 5929.792457264019, 5933.050711886078, 5933.851653708649, 5940.435658612468, 3768.5289338832645, 3833.2716783811265, 3836.731428799985, 3841.9423708644395, 3853.5183102112237, 1238.7955987262092, 1251.2930194440112, 1255.598208573257, 1275.9345946134988, 1278.520554791953, 14195.929020354832, 14225.928870355685, 14235.928820355935, 14256.073355231876, 14277.714445711132, 4995.925061124086, 5107.182850743863, 5132.127538318495, 5250.684958902478, 5360.973195134077, 11965.222260553037, 11974.521019159136, 11977.700557485958, 11990.100742444352, 12010.819837702442, 5857.531397488191, 5859.062549991981, 5859.238299021185, 5861.501772349313, 5871.882562348729, 4468.396766436489, 5355.169948657965, 5369.167778994262, 5425.9186112208345, 5430.348358197041, 4262.445882035316, 4331.371951067078, 4351.543087975717, 4400.801963911632, 4693.530646935347, 1402.8877689784792, 1409.3728290910651, 1432.3268064009899, 1445.927703614815, 1448.4133925760084, 1006.1799633298838, 1011.2365164301053, 1018.165104614224, 1018.1752780248023, 1019.551397385163, 6570.638614876132, 6736.696848641823, 6764.4926630502905, 6863.485238607124, 6918.861622767517, 491.54286513542746, 497.1399478901521, 497.477648469106, 504.1630892718144, 506.5694159964001, 4519.954800452019, 4568.725876447424, 4634.513376095529, 4694.460137084211, 4747.905041899212, 12195.0, 12339.197952133083, 12382.81425778614, 12585.24488530691, 12588.81116783249, 12091.153619246568, 12349.320787356603, 12384.56654017105, 12403.069769767124, 12403.62789116328, 9269.582868770922, 9319.906800931903, 9347.392419492711, 9350.737650417106, 9367.0, 6417.807465776034, 6564.901526477202, 6644.10304608891, 6651.966740166268, 6673.866522669617, 11308.0, 11546.19176657626, 11623.418829058683, 11623.825642615198, 11624.0, 11555.4800033997, 11591.304521728724, 11697.649070527897, 11773.52905883755, 11838.585649502225, 8545.615447304763, 8635.87046194295, 8711.651533938824, 8714.520701361485, 8763.211310982042, 9500.95249523748, 9514.571844266886, 9558.952205239208, 9565.665201717906, 9588.808223835624, 8035.196480351918, 8099.109097999333, 8115.1479094694205, 8117.959410202911, 8221.753347399588, 8380.664773408995, 8390.622421990904, 8437.746867593982, 8442.957785211034, 8616.224539791197, 7844.882326764989, 7941.642626081724, 7992.440529162903, 7993.800154996172, 8022.799430014296, 5536.169574563848, 5638.971805140948, 5660.462256085665, 5789.015867302653, 5853.824385268449, 5817.592768506164, 5832.9708351457975, 5942.821715348547, 5994.160817485468, 6013.5189184865985], "value": 5048.097162974106, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [351.090675151358, 353.19295409988166, 359.8236863936671, 360.38734151941674, 367.6580779874717, 5623.718814059291, 5626.634268772532, 5663.575231857607, 5664.150377443386, 5753.769849206037, 5561.387197712658, 5568.693721845297, 5639.661620302784, 5642.943570564293, 5647.237622920905, 4162.647139679601, 4382.430284063067, 4383.232934236512, 4385.122975404932, 4423.712458690191, 4303.935440968388, 4366.978165109186, 4369.126174765044, 4374.12517496502, 4410.184115938561, 4099.303118469852, 4188.832446702128, 4339.502871509324, 4369.6285815705705, 4372.978135109336, 4301.032267739764, 4313.4176886120285, 4340.218760623086, 4344.826206951734, 4361.6510679145595, 3832.0, 3832.5209348831395, 3833.846646134179, 3834.9453900177396, 3834.9808250958845, 5137.588992880598, 5287.576993840522, 5447.45525447456, 5455.0, 5457.772001299715, 4929.1374009548545, 4946.925796113062, 4948.802047918079, 4952.306677065214, 4962.826301079481, 5756.309242890838, 5781.942180578182, 5807.506361959238, 5831.562632802556, 5833.095870140135, 4093.85671501499, 4139.19285739281, 4185.602367775056, 4227.894302642459, 4244.0663054128045, 5947.583669143161, 6004.78983235589, 6024.427679370497, 6033.879322413571, 6040.335563088057, 4269.487661480643, 4298.0, 4306.203352379757, 4335.934960975588, 4345.956540434619, 4280.636145927631, 4280.8869693879815, 4327.5239723630375, 4355.738655680606, 4392.956070439319, 4896.338994235733, 4912.0, 4915.26271059344, 4921.064997650435, 4940.777665005084, 5836.212111364996, 5869.618474799125, 5876.823695289148, 5908.3205431375945, 5916.526677865761, 1438.26648409312, 1497.2513743128436, 1500.1898974553837, 1521.1481570320657, 1527.427214794452, 947.2847999760137, 965.3435663748578, 969.5927710361665, 973.4889183178888, 993.2401712689798, 5010.799568017312, 5017.523335283142, 5056.620753443506, 5062.848114556569, 5105.361829771278, 5240.563138140783, 5286.3392075990505, 5291.867703307371, 5310.734463276821, 5325.3875804282225, 5029.597632189416, 5059.343844481603, 5231.796686762066, 5451.991381594417, 5506.394296627368, 3160.5733226014654, 3172.4448221561133, 3181.411438883814, 3200.7599430042374, 3211.277462570903, 6132.6627035513475, 6178.165947597017, 6181.052968314966, 6216.968915155395, 6219.968900155471, 6045.183900173508, 6063.333033366326, 6068.726907289182, 6128.387161283836, 6148.538859585549, 6183.227096612924, 6184.907226391608, 6203.906941395973, 6210.472109870621, 6212.254529456494, 5805.709714514342, 5841.932985182813, 5931.288245410578, 6052.757889684363, 6057.575969682256, 5893.263342082239, 5901.409859014064, 5921.082232254007, 5953.791617293374, 5967.671778052249, 6074.210352654127, 6174.567780255518, 6182.876342473215, 6216.855184961181, 6324.6521441321165, 4310.612044915973, 4347.434833471629, 4351.825926962886, 4357.389965404909, 4367.170237654836, 6016.4585187331595, 6034.487068599218, 6049.8202850445505, 6085.908711369245, 6104.908426373519, 5700.486956173964, 5887.4406931341455, 5910.674912879833, 5965.0, 5991.910121348271, 5809.477147056785, 5835.46157421523, 5838.153467747238, 5844.736986835517, 5846.970765146147, 5596.244506991667, 5779.971100144472, 5782.7686892523825, 5852.473277405054, 5893.852653683521, 5507.911701504263, 5746.511546518425, 5749.281339832521, 5766.654000759968, 5773.307203135482, 5733.340665823317, 5737.0, 5795.594308398372, 5797.536197104306, 5805.245318108618], "value": 4427.714068178063, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [869.4348673362316, 872.8472517309469, 889.2130464538885, 898.4294972692338, 910.6903652758064, 15716.0, 16342.101184434881, 16355.345786168555, 16359.0, 16384.91807540962, 15628.062316261005, 15643.608909777262, 15675.059496430194, 15681.921590392032, 15707.214639268048, 733.4462480826968, 864.3128712673408, 879.604178119847, 893.6023469556051, 895.6104094718786, 905.1355955062932, 907.2333877873186, 907.6959218661756, 907.8093600343935, 907.9773005674845, 936.063936063936, 936.4896131608256, 937.4750139921632, 937.5078084005884, 938.1369140390857, 781.527176058485, 915.3501014279869, 929.6327950459564, 937.7046230437459, 942.2791564453199, 697.8813601687674, 698.3540225291636, 698.3749544157951, 698.5075521757149, 698.5564166754148, 15000.549983500512, 15014.399424023135, 15036.248187590576, 15038.0, 15046.623834404118, 14377.56867293983, 15390.615234619112, 15508.302126404225, 15601.609959750984, 15768.921155394264, 13746.450141994308, 14379.209143497104, 14381.496647617283, 14510.056846304957, 14630.0, 4579.564941330568, 4585.28928016158, 4593.839215627437, 4633.907321853544, 4671.85984420468, 86848.0, 87810.5609471955, 88582.0, 89219.55390223072, 90341.54829225877, 12555.623331300076, 12559.497620095277, 12576.37118144089, 12636.55772047974, 12847.48610055606, 23816.40458988539, 23941.0, 23952.041918323073, 23954.88022559911, 23971.760282397303, 9230.261579073658, 9236.815263694689, 9362.719118426457, 9401.294902882311, 9560.235181185488, 5059.797608095709, 5113.64204505681, 5132.538071573502, 5162.92255616166, 5247.422783493813, 3662.1943172502006, 3809.4095415210686, 3823.5794062653085, 3833.4824798651825, 3837.0982819038063, 679.1815861886415, 679.4462513051847, 762.5462849604477, 791.8099656082613, 806.0770417871504, 693.6982412650514, 789.2186735132178, 811.123986095016, 821.5686764448712, 829.5105887526388, 10618.15795491836, 10835.458227088771, 11051.0, 11129.276597021168, 11318.151138664633, 703.9507234493639, 775.3874439192974, 791.7941335252875, 797.533442935889, 804.7344376355906, 5219.164933610604, 5331.306930099062, 5360.222767698662, 5375.623706340519, 5417.26866872975, 130775.0, 134465.327673361, 135514.1541737691, 136000.0, 138810.0, 68008.6599566999, 69033.0, 69250.0, 70628.0, 98937.50531247442, 98731.0, 104267.0, 104573.0, 105010.0, 105901.47049264704, 9510.667126650535, 10012.849807253042, 10338.741531461774, 10490.160787137163, 10800.351978881292, 54611.453885460636, 54792.45207547874, 55423.72288138695, 56111.71944140253, 58287.4171258282, 80935.59532202301, 81529.0, 81696.18303816886, 82939.0, 84111.0, 12750.298733569743, 12965.546205882749, 13027.934860325639, 13223.603291901258, 13579.388927498088, 14031.789523156956, 14347.282635868374, 15713.292901819674, 15819.683606328039, 15928.044317340997, 12781.87218127844, 13632.04575679693, 13736.587902362946, 15462.536123916301, 15866.286017129487, 12692.492300308253, 13205.537806176737, 14643.267836607914, 14933.775993360325, 15301.387944482096, 14381.568552943429, 14714.632134196303, 15204.771928420862, 15440.68523338027, 16393.91803040977, 12272.38638068111, 12374.0, 12551.184173028723, 12655.493780248687, 12939.805902911276, 12690.619281421572, 12834.743305133657, 13137.671558211121, 13233.404496797859, 13631.0], "value": 8261.081989128448, "unit": "score"}], "name": "dromaeo_css", "value": 7878.164715305731, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [101.0, 112.0, 111.0, 111.0, 111.0, 110.0, 110.0, 110.0, 110.0, 108.0], "value": 109.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 116.0, 118.0, 166.0, 115.0, 118.0, 142.0, 115.0, 150.0, 113.0], "value": 129.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 158.0, 159.0, 159.0, 162.0, 160.0, 159.0, 160.0, 161.0, 158.0], "value": 159.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 75.0, 76.0, 121.0, 75.0, 75.0, 75.0, 75.0, 108.0, 76.0], "value": 83.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 83.0, 131.0, 83.0, 83.0, 82.0, 120.0, 82.0, 83.0, 120.0], "value": 95.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 117.0, 116.0, 117.0, 116.0, 117.0, 117.0, 116.0, 117.0, 117.0], "value": 116.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 145.0, 145.0, 145.0, 146.0, 145.0, 145.0, 145.0, 145.0, 145.0], "value": 145.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 96.0, 96.0, 95.0, 95.0, 95.0, 96.0, 97.0, 96.0, 94.0], "value": 95.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [49.0, 51.0, 49.0, 50.0, 61.0, 50.0, 50.0, 47.0, 49.0, 48.0], "value": 50.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 60.0, 59.0, 59.0, 60.0, 59.0, 60.0, 61.0, 59.0, 59.0], "value": 59.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 73.0, 74.0, 81.0, 73.0, 72.0, 71.0, 72.0, 72.0, 71.0], "value": 73.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [119.0, 123.0, 127.0, 121.0, 116.0, 122.0, 116.0, 119.0, 165.0, 116.0], "value": 124.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [153.0, 153.0, 154.0, 152.0, 152.0, 154.0, 152.0, 152.0, 152.0, 150.0], "value": 152.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 63.0, 62.0, 61.0, 62.0, 60.0, 63.0, 61.0, 62.0, 61.0], "value": 61.9, "unit": "score"}], "name": "kraken", "value": 1456.1000000000004, "alertThreshold": 2.0}]} 04:50:10 INFO - Return code: 0 04:50:10 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 04:50:10 INFO - # TBPL SUCCESS # 04:50:10 INFO - Running post-action listener: _resource_record_post_action 04:50:10 INFO - [mozharness: 2016-08-28 11:50:10.399528Z] Finished run-tests step (success) 04:50:10 INFO - Running post-run listener: _resource_record_post_run 04:50:10 INFO - Total resource usage - Wall time: 1190s; CPU: 13.0%; Read bytes: 270336; Write bytes: 328060928; Read time: 40; Write time: 226144 04:50:10 INFO - TinderboxPrint: CPU usage
12.9% 04:50:10 INFO - TinderboxPrint: I/O read bytes / time
270,336 / 40 04:50:10 INFO - TinderboxPrint: I/O write bytes / time
328,060,928 / 226,144 04:50:10 INFO - TinderboxPrint: CPU idle
8,267.5 (87.0%) 04:50:10 INFO - TinderboxPrint: CPU user
1,185.2 (12.5%) 04:50:10 INFO - TinderboxPrint: Swap in / out
0 / 0 04:50:10 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 78286848; Read time: 0; Write time: 72524 04:50:10 INFO - run-tests - Wall time: 1174s; CPU: 13.0%; Read bytes: 270336; Write bytes: 248946688; Read time: 40; Write time: 153596 04:50:11 INFO - Running post-run listener: _upload_blobber_files 04:50:11 INFO - Blob upload gear active. 04:50:11 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 04:50:11 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 04:50:11 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'] 04:50:11 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 04:50:11 INFO - Traceback (most recent call last): 04:50:11 INFO - File "/builds/slave/test/build/venv/bin/blobberc.py", line 253, in 04:50:11 INFO - main() 04:50:11 INFO - File "/builds/slave/test/build/venv/bin/blobberc.py", line 235, in main 04:50:11 INFO - filetype_whitelist = get_server_whitelist(args['--url']) 04:50:11 INFO - File "/builds/slave/test/build/venv/bin/blobberc.py", line 69, in get_server_whitelist 04:50:11 INFO - return set(response.json().get('whitelist', [])) 04:50:11 INFO - File "/builds/slave/test/build/venv/local/lib/python2.7/site-packages/requests/models.py", line 651, in json 04:50:11 INFO - return json.loads(self.text or self.content, **kwargs) 04:50:11 INFO - File "/usr/lib/python2.7/json/__init__.py", line 326, in loads 04:50:11 INFO - return _default_decoder.decode(s) 04:50:11 INFO - File "/usr/lib/python2.7/json/decoder.py", line 366, in decode 04:50:11 INFO - obj, end = self.raw_decode(s, idx=_w(s, 0).end()) 04:50:11 INFO - File "/usr/lib/python2.7/json/decoder.py", line 384, in raw_decode 04:50:11 INFO - raise ValueError("No JSON object could be decoded") 04:50:11 INFO - ValueError: No JSON object could be decoded 04:50:11 ERROR - Return code: 1 04:50:11 INFO - Setting buildbot property blobber_files to {} 04:50:11 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 04:50:11 INFO - Writing to file /builds/slave/test/properties/blobber_files 04:50:11 INFO - Contents: 04:50:11 INFO - blobber_files:{} 04:50:11 INFO - Running post-run listener: copy_logs_to_upload_dir 04:50:11 INFO - Copying logs to upload dir... 04:50:11 INFO - mkdir: /builds/slave/test/build/upload/logs 04:50:11 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1673.169365 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 27 mins, 53 secs) (at 2016-08-28 04:50:11.358282) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-28 04:50:11.361430) ========= 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=8887e36bb8e381bfd44124ac000001a8-1472383336.503430-1427678062 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{} build_url:https://queue.taskcluster.net/v1/task/DceP3cLkSRyynuNxN1DWbg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011517 build_url: 'https://queue.taskcluster.net/v1/task/DceP3cLkSRyynuNxN1DWbg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2' blobber_files: '{}' ========= master_lag: 0.04 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-28 04:50:11.414825) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-28 04:50:11.415436) ========= 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=8887e36bb8e381bfd44124ac000001a8-1472383336.503430-1427678062 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004495 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-28 04:50:11.466734) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-28 04:50:11.467081) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-28 04:50:11.467367) ========= ========= Total master_lag: 0.17 =========