builder: mozilla-aurora_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-004 starttime: 1472383624.23 results: success (0) buildid: 20160827004001 builduid: 5275c599c6904397a1806c151c356c46 revision: 4d38a69eda61f663463af8f020447e9afbb118b1 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-08-28 04:27:04.235110) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-08-28 04:27:04.235523) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-28 04:27:04.235788) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-28 04:27:04.250130) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-28 04:27:04.250385) ========= 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=c6927cf182745ad25b1d38b9000001fe-1472383624.168346-109470312 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-08-28 04:27:05-- 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% 113M=0s 2016-08-28 04:27:05 (113 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.118526 ========= master_lag: 0.12 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-28 04:27:04.487148) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-28 04:27:04.487486) ========= 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=c6927cf182745ad25b1d38b9000001fe-1472383624.168346-109470312 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.023159 ========= master_lag: 0.01 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-28 04:27:04.522993) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-28 04:27:04.523334) ========= 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=c6927cf182745ad25b1d38b9000001fe-1472383624.168346-109470312 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-08-28 04:27:05,417 truncating revision to first 12 chars 2016-08-28 04:27:05,417 Setting DEBUG logging. 2016-08-28 04:27:05,417 attempt 1/10 2016-08-28 04:27:05,417 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:27:05,836 unpacking tar archive at: mozilla-aurora-4d38a69eda61/testing/mozharness/ program finished with exit code 0 elapsedTime=0.741057 ========= master_lag: 0.08 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-28 04:27:05.339698) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-28 04:27:05.340020) ========= script_repo_revision: 4d38a69eda61f663463af8f020447e9afbb118b1 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-28 04:27:05.340417) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-28 04:27:05.340687) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-28 04:27:05.350335) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 27 mins, 52 secs) (at 2016-08-28 04:27:05.350701) ========= /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=c6927cf182745ad25b1d38b9000001fe-1472383624.168346-109470312 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 04:27:06 INFO - MultiFileLogger online at 20160828 04:27:06 in /builds/slave/test 04:27:06 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:27:06 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 04:27:06 INFO - {'append_to_log': False, 04:27:06 INFO - 'base_work_dir': '/builds/slave/test', 04:27:06 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 04:27:06 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 04:27:06 INFO - 'branch': 'Mozilla-Aurora', 04:27:06 INFO - 'buildbot_json_path': 'buildprops.json', 04:27:06 INFO - 'config_files': ('talos/linux_config.py',), 04:27:06 INFO - 'default_actions': ('clobber', 04:27:06 INFO - 'read-buildbot-config', 04:27:06 INFO - 'download-and-extract', 04:27:06 INFO - 'populate-webroot', 04:27:06 INFO - 'create-virtualenv', 04:27:06 INFO - 'install', 04:27:06 INFO - 'run-tests'), 04:27:06 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 04:27:06 INFO - 'download_minidump_stackwalk': True, 04:27:06 INFO - 'download_symbols': 'ondemand', 04:27:06 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 04:27:06 INFO - 'tooltool.py': '/tools/tooltool.py', 04:27:06 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 04:27:06 INFO - '/tools/misc-python/virtualenv.py')}, 04:27:06 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 04:27:06 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 04:27:06 INFO - 'installer_path': 'installer.exe', 04:27:06 INFO - 'log_level': 'info', 04:27:06 INFO - 'log_name': 'talos', 04:27:06 INFO - 'log_to_console': True, 04:27:06 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 04:27:06 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 04:27:06 INFO - 'opt_config_files': (), 04:27:06 INFO - 'pip_index': False, 04:27:06 INFO - 'sps_profile': False, 04:27:06 INFO - 'sps_profile_interval': 0, 04:27:06 INFO - 'suite': 'dromaeojs', 04:27:06 INFO - 'system_bits': '32', 04:27:06 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 04:27:06 INFO - 'title': 'talos-linux64-ix-004', 04:27:06 INFO - 'tooltool_cache': '/builds/tooltool_cache', 04:27:06 INFO - 'use_talos_json': True, 04:27:06 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 04:27:06 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 04:27:06 INFO - 'work_dir': 'build'} 04:27:06 INFO - [mozharness: 2016-08-28 11:27:06.283019Z] Running clobber step. 04:27:06 INFO - Running pre-action listener: _resource_record_pre_action 04:27:06 INFO - Running main action method: clobber 04:27:06 INFO - rmtree: /builds/slave/test/build 04:27:06 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 04:27:07 INFO - Running post-action listener: _resource_record_post_action 04:27:07 INFO - [mozharness: 2016-08-28 11:27:07.227397Z] Finished clobber step (success) 04:27:07 INFO - [mozharness: 2016-08-28 11:27:07.227498Z] Running read-buildbot-config step. 04:27:07 INFO - Running pre-action listener: _resource_record_pre_action 04:27:07 INFO - Running main action method: read_buildbot_config 04:27:07 INFO - Using buildbot properties: 04:27:07 INFO - { 04:27:07 INFO - "project": "", 04:27:07 INFO - "product": "firefox", 04:27:07 INFO - "script_repo_revision": "production", 04:27:07 INFO - "builddir": "mozilla-aurora_ubuntu64_hw_test-dromaeojs", 04:27:07 INFO - "repository": "", 04:27:07 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-aurora pgo talos dromaeojs", 04:27:07 INFO - "buildid": "20160827004001", 04:27:07 INFO - "stage_platform": "linux64-pgo", 04:27:07 INFO - "basedir": "/builds/slave/test", 04:27:07 INFO - "nightly_build": "True", 04:27:07 INFO - "buildnumber": 499, 04:27:07 INFO - "slavename": "talos-linux64-ix-004", 04:27:07 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 04:27:07 INFO - "platform": "ubuntu64_hw", 04:27:07 INFO - "scheduler": "tests-mozilla-aurora-linux64-pgo-talos", 04:27:07 INFO - "branch": "mozilla-aurora", 04:27:07 INFO - "repo_path": "releases/mozilla-aurora", 04:27:07 INFO - "revision": "4d38a69eda61f663463af8f020447e9afbb118b1", 04:27:07 INFO - "pgo_build": "True", 04:27:07 INFO - "builduid": "5275c599c6904397a1806c151c356c46", 04:27:07 INFO - "slavebuilddir": "test" 04:27:07 INFO - } 04:27:07 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:27:07 INFO - Running post-action listener: _resource_record_post_action 04:27:07 INFO - [mozharness: 2016-08-28 11:27:07.230005Z] Finished read-buildbot-config step (success) 04:27:07 INFO - [mozharness: 2016-08-28 11:27:07.230095Z] Running download-and-extract step. 04:27:07 INFO - Running pre-action listener: _resource_record_pre_action 04:27:07 INFO - Running main action method: download_and_extract 04:27:07 INFO - mkdir: /builds/slave/test/build/tests 04:27:07 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:27:07 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:27:07 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:27:07 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:27:07 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:27:07 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:27:07 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:27:07 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 04:27:07 INFO - retry: Failed, sleeping 30 seconds before retrying 04:27:37 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:27:37 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:27:37 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 04:27:37 INFO - retry: Failed, sleeping 60 seconds before retrying 04:28:37 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:28:37 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:28:37 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 04:28:37 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:28:37 INFO - Caught exception: HTTP Error 404: Not Found 04:28:37 INFO - Caught exception: HTTP Error 404: Not Found 04:28:37 INFO - Caught exception: HTTP Error 404: Not Found 04:28:37 INFO - trying https://queue.taskcluster.net/v1/task/DceP3cLkSRyynuNxN1DWbg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 04:28:37 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:28:37 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:28:37 INFO - Downloaded 1361 bytes. 04:28:37 INFO - Reading from file /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 04:28:37 INFO - Using the following test package requirements: 04:28:37 INFO - {u'common': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'], 04:28:37 INFO - u'cppunittest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 04:28:37 INFO - u'firefox-50.0a2.en-US.linux-x86_64.cppunittest.tests.zip'], 04:28:37 INFO - u'gtest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 04:28:37 INFO - u'firefox-50.0a2.en-US.linux-x86_64.gtest.tests.zip'], 04:28:37 INFO - u'jittest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 04:28:37 INFO - u'jsshell-linux-x86_64.zip'], 04:28:37 INFO - u'mochitest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 04:28:37 INFO - u'firefox-50.0a2.en-US.linux-x86_64.mochitest.tests.zip'], 04:28:37 INFO - u'mozbase': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'], 04:28:37 INFO - u'reftest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 04:28:37 INFO - u'firefox-50.0a2.en-US.linux-x86_64.reftest.tests.zip'], 04:28:37 INFO - u'talos': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 04:28:37 INFO - u'firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip'], 04:28:37 INFO - u'web-platform': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 04:28:37 INFO - u'firefox-50.0a2.en-US.linux-x86_64.web-platform.tests.zip'], 04:28:37 INFO - u'xpcshell': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 04:28:37 INFO - u'firefox-50.0a2.en-US.linux-x86_64.xpcshell.tests.zip']} 04:28:37 INFO - Downloading packages: [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 04:28:37 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:37 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:28:37 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:28:37 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:28:37 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:28:37 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:28:37 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:28:37 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 04:28:37 INFO - retry: Failed, sleeping 30 seconds before retrying 04:29:07 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:29:07 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:29:07 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 04:29:07 INFO - retry: Failed, sleeping 60 seconds before retrying 04:30:08 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:30:08 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:30:08 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 04:30:08 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:30:08 INFO - Caught exception: HTTP Error 404: Not Found 04:30:08 INFO - Caught exception: HTTP Error 404: Not Found 04:30:08 INFO - Caught exception: HTTP Error 404: Not Found 04:30:08 INFO - trying https://queue.taskcluster.net/v1/task/DceP3cLkSRyynuNxN1DWbg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 04:30:08 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:30:08 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:30:08 INFO - Downloaded 21770875 bytes. 04:30:08 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:30:08 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:30:10 INFO - Return code: 0 04:30:10 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:30:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:30:10 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:30:10 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:30:10 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:30:10 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:30:10 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:30:10 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:30:10 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 04:30:10 INFO - retry: Failed, sleeping 30 seconds before retrying 04:30:40 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:30:40 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:30:40 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 04:30:40 INFO - retry: Failed, sleeping 60 seconds before retrying 04:31:40 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:31:40 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:31:40 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 04:31:40 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:31:40 INFO - Caught exception: HTTP Error 404: Not Found 04:31:40 INFO - Caught exception: HTTP Error 404: Not Found 04:31:40 INFO - Caught exception: HTTP Error 404: Not Found 04:31:40 INFO - trying https://queue.taskcluster.net/v1/task/DceP3cLkSRyynuNxN1DWbg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 04:31:40 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:31:40 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:31:41 INFO - Downloaded 21770875 bytes. 04:31:41 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:31:41 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:31:42 INFO - Return code: 0 04:31:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:31:42 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:31:42 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:31:42 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:31:42 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:31:42 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:31:42 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:31:42 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 04:31:42 INFO - retry: Failed, sleeping 30 seconds before retrying 04:32:12 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:32:12 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:32:12 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 04:32:12 INFO - retry: Failed, sleeping 60 seconds before retrying 04:33:12 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:33:12 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:33:12 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 04:33:12 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:33:12 INFO - Caught exception: HTTP Error 404: Not Found 04:33:12 INFO - Caught exception: HTTP Error 404: Not Found 04:33:12 INFO - Caught exception: HTTP Error 404: Not Found 04:33:12 INFO - trying https://queue.taskcluster.net/v1/task/DceP3cLkSRyynuNxN1DWbg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 04:33:12 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:33:12 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:33:13 INFO - Downloaded 14413723 bytes. 04:33:13 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:33:13 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:33:13 INFO - Return code: 0 04:33:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:33:13 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:33:13 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:33:13 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:33:13 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:33:13 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:33:13 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:33:13 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 04:33:13 INFO - retry: Failed, sleeping 30 seconds before retrying 04:33:43 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:33:43 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:33:43 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 04:33:43 INFO - retry: Failed, sleeping 60 seconds before retrying 04:34:43 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:34:43 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:34:43 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 04:34:43 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:34:43 INFO - Caught exception: HTTP Error 404: Not Found 04:34:43 INFO - Caught exception: HTTP Error 404: Not Found 04:34:43 INFO - Caught exception: HTTP Error 404: Not Found 04:34:43 INFO - trying https://queue.taskcluster.net/v1/task/DceP3cLkSRyynuNxN1DWbg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 04:34:43 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:34:43 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:34:44 INFO - Downloaded 63746809 bytes. 04:34:44 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:34:44 INFO - mkdir: /builds/slave/test/properties 04:34:44 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 04:34:44 INFO - Writing to file /builds/slave/test/properties/build_url 04:34:44 INFO - Contents: 04:34:44 INFO - build_url:https://queue.taskcluster.net/v1/task/DceP3cLkSRyynuNxN1DWbg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 04:34:45 INFO - Running post-action listener: _resource_record_post_action 04:34:45 INFO - Running post-action listener: set_extra_try_arguments 04:34:45 INFO - [mozharness: 2016-08-28 11:34:45.544173Z] Finished download-and-extract step (success) 04:34:45 INFO - [mozharness: 2016-08-28 11:34:45.544406Z] Running populate-webroot step. 04:34:45 INFO - Running pre-action listener: _resource_record_pre_action 04:34:45 INFO - Running main action method: populate_webroot 04:34:45 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 04:34:45 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 04:34:45 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 04:34:45 INFO - '--tppagecycles', 04:34:45 INFO - '7'], 04:34:45 INFO - 'tests': ['tsvgm']}}, 04:34:45 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 04:34:45 INFO - 'tests': ['tresize', 'tcanvasmark']}, 04:34:45 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 04:34:45 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 04:34:45 INFO - 'tests': ['dromaeo_css', 'kraken']}, 04:34:45 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 04:34:45 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:34:45 INFO - 'talos_options': ['--disable-e10s'], 04:34:45 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:34:45 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:34:45 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:34:45 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:34:45 INFO - 'talos_options': ['--disable-e10s'], 04:34:45 INFO - 'tests': ['damp', 'tps']}, 04:34:45 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:34:45 INFO - 'tests': ['damp', 'tps']}, 04:34:45 INFO - 'g3': {'talos_options': ['--disable-e10s'], 04:34:45 INFO - 'tests': ['dromaeo_dom']}, 04:34:45 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 04:34:45 INFO - 'g4': {'talos_options': ['--disable-e10s'], 04:34:45 INFO - 'tests': ['basic_compositor_video']}, 04:34:45 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 04:34:45 INFO - 'other': {'talos_options': ['--disable-e10s'], 04:34:45 INFO - 'tests': ['a11yr', 04:34:45 INFO - 'ts_paint', 04:34:45 INFO - 'tpaint', 04:34:45 INFO - 'sessionrestore', 04:34:45 INFO - 'sessionrestore_no_auto_restore', 04:34:45 INFO - 'tabpaint']}, 04:34:45 INFO - 'other-e10s': {'tests': ['a11yr', 04:34:45 INFO - 'ts_paint', 04:34:45 INFO - 'tpaint', 04:34:45 INFO - 'sessionrestore', 04:34:45 INFO - 'sessionrestore_no_auto_restore', 04:34:45 INFO - 'tabpaint']}, 04:34:45 INFO - 'other-e10s_l64': {'tests': ['a11yr', 04:34:45 INFO - 'ts_paint', 04:34:45 INFO - 'tpaint', 04:34:45 INFO - 'sessionrestore', 04:34:45 INFO - 'sessionrestore_no_auto_restore', 04:34:45 INFO - 'tabpaint']}, 04:34:45 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 04:34:45 INFO - 'ts_paint', 04:34:45 INFO - 'tpaint', 04:34:45 INFO - 'sessionrestore', 04:34:45 INFO - 'sessionrestore_no_auto_restore', 04:34:45 INFO - 'tabpaint']}, 04:34:45 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 04:34:45 INFO - 'tests': ['a11yr', 04:34:45 INFO - 'ts_paint', 04:34:45 INFO - 'tpaint', 04:34:45 INFO - 'sessionrestore', 04:34:45 INFO - 'sessionrestore_no_auto_restore', 04:34:45 INFO - 'tabpaint']}, 04:34:45 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 04:34:45 INFO - 'tests': ['a11yr', 04:34:45 INFO - 'ts_paint', 04:34:45 INFO - 'tpaint', 04:34:45 INFO - 'sessionrestore', 04:34:45 INFO - 'sessionrestore_no_auto_restore', 04:34:45 INFO - 'tabpaint']}, 04:34:45 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 04:34:45 INFO - 'tests': ['tsvgx', 04:34:45 INFO - 'tsvgr_opacity', 04:34:45 INFO - 'tart', 04:34:45 INFO - 'tscrollx', 04:34:45 INFO - 'cart']}, 04:34:45 INFO - 'svgr-e10s': {'tests': ['tsvgx', 04:34:45 INFO - 'tsvgr_opacity', 04:34:45 INFO - 'tart', 04:34:45 INFO - 'tscrollx', 04:34:45 INFO - 'cart']}, 04:34:45 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:34:45 INFO - 'talos_options': ['--disable-e10s'], 04:34:45 INFO - 'tests': ['tp5o']}, 04:34:45 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:34:45 INFO - 'tests': ['tp5o']}, 04:34:45 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:34:45 INFO - 'talos_options': ['--disable-e10s', 04:34:45 INFO - '--xperf_path', 04:34:45 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:34:45 INFO - 'tests': ['tp5n']}, 04:34:45 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:34:45 INFO - 'talos_options': ['--xperf_path', 04:34:45 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:34:45 INFO - 'tests': ['tp5n']}}, 04:34:45 INFO - 'talos.zip': {'path': '', 04:34:45 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 04:34:45 INFO - Running post-action listener: _resource_record_post_action 04:34:45 INFO - [mozharness: 2016-08-28 11:34:45.566837Z] Finished populate-webroot step (success) 04:34:45 INFO - [mozharness: 2016-08-28 11:34:45.566927Z] Running create-virtualenv step. 04:34:45 INFO - Running pre-action listener: _resource_record_pre_action 04:34:45 INFO - Running main action method: create_virtualenv 04:34:45 INFO - Creating virtualenv /builds/slave/test/build/venv 04:34:45 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:34:45 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 04:34:45 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 04:34:45 INFO - Using real prefix '/usr' 04:34:45 INFO - New python executable in /builds/slave/test/build/venv/bin/python 04:34:46 INFO - Installing distribute.............................................................................................................................................................................................done. 04:34:48 INFO - Installing pip.................done. 04:34:48 INFO - Return code: 0 04:34:48 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 04:34:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:34:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:34:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:34:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:34:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:34:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:34:48 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 0x1a52030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1bb58a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d13180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d113b0>, '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 0x1d122b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d12740>, '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': 'c6927cf182745ad25b1d38b9000001fe-1472383624.168346-109470312', '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:34:48 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:34:48 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:34:48 INFO - Using env: {'DISPLAY': ':0', 04:34:48 INFO - 'HOME': '/home/cltbld', 04:34:48 INFO - 'LANG': 'en_US.UTF-8', 04:34:48 INFO - 'LANGUAGE': 'en_US:en', 04:34:48 INFO - 'LOGNAME': 'cltbld', 04:34:48 INFO - 'MAIL': '/var/mail/cltbld', 04:34:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:34:48 INFO - 'MOZ_NO_REMOTE': '1', 04:34:48 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:34:48 INFO - 'NO_EM_RESTART': '1', 04:34:48 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:34:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:34:48 INFO - 'PWD': '/builds/slave/test', 04:34:48 INFO - 'SHELL': '/bin/bash', 04:34:48 INFO - 'SHLVL': '1', 04:34:48 INFO - 'TERM': 'linux', 04:34:48 INFO - 'TMOUT': '86400', 04:34:48 INFO - 'USER': 'cltbld', 04:34:48 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1472383624.168346-109470312', 04:34:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:34:48 INFO - '_': '/tools/buildbot/bin/python'} 04:34:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:34:48 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:34:48 INFO - Cleaning up... 04:34:48 INFO - Return code: 0 04:34:48 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 04:34:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:34:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:34:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:34:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:34:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:34:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:34:48 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 0x1a52030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1bb58a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d13180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d113b0>, '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 0x1d122b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d12740>, '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': 'c6927cf182745ad25b1d38b9000001fe-1472383624.168346-109470312', '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:34:48 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:34:48 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:34:48 INFO - Using env: {'DISPLAY': ':0', 04:34:48 INFO - 'HOME': '/home/cltbld', 04:34:48 INFO - 'LANG': 'en_US.UTF-8', 04:34:48 INFO - 'LANGUAGE': 'en_US:en', 04:34:48 INFO - 'LOGNAME': 'cltbld', 04:34:48 INFO - 'MAIL': '/var/mail/cltbld', 04:34:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:34:48 INFO - 'MOZ_NO_REMOTE': '1', 04:34:48 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:34:48 INFO - 'NO_EM_RESTART': '1', 04:34:48 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:34:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:34:48 INFO - 'PWD': '/builds/slave/test', 04:34:48 INFO - 'SHELL': '/bin/bash', 04:34:48 INFO - 'SHLVL': '1', 04:34:48 INFO - 'TERM': 'linux', 04:34:48 INFO - 'TMOUT': '86400', 04:34:48 INFO - 'USER': 'cltbld', 04:34:48 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1472383624.168346-109470312', 04:34:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:34:48 INFO - '_': '/tools/buildbot/bin/python'} 04:34:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:34:48 INFO - Downloading/unpacking psutil>=3.1.1 04:34:48 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:34:48 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:34:48 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:34:48 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:34:48 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:34:48 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:34:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 04:34:50 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:34:50 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:34:50 INFO - Installing collected packages: psutil 04:34:50 INFO - Running setup.py install for psutil 04:34:50 INFO - building 'psutil._psutil_linux' extension 04:34:50 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:34:50 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:34:50 INFO - building 'psutil._psutil_posix' extension 04:34:50 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:34:50 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:34:51 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:34:51 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:34:51 INFO - Successfully installed psutil 04:34:51 INFO - Cleaning up... 04:34:51 INFO - Return code: 0 04:34:51 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 04:34:51 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:34:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:34:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:34:51 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:34:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:34:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:34:51 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 0x1a52030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1bb58a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d13180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d113b0>, '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 0x1d122b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d12740>, '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': 'c6927cf182745ad25b1d38b9000001fe-1472383624.168346-109470312', '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:34:51 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:34:51 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:34:51 INFO - Using env: {'DISPLAY': ':0', 04:34:51 INFO - 'HOME': '/home/cltbld', 04:34:51 INFO - 'LANG': 'en_US.UTF-8', 04:34:51 INFO - 'LANGUAGE': 'en_US:en', 04:34:51 INFO - 'LOGNAME': 'cltbld', 04:34:51 INFO - 'MAIL': '/var/mail/cltbld', 04:34:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:34:51 INFO - 'MOZ_NO_REMOTE': '1', 04:34:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:34:51 INFO - 'NO_EM_RESTART': '1', 04:34:51 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:34:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:34:51 INFO - 'PWD': '/builds/slave/test', 04:34:51 INFO - 'SHELL': '/bin/bash', 04:34:51 INFO - 'SHLVL': '1', 04:34:51 INFO - 'TERM': 'linux', 04:34:51 INFO - 'TMOUT': '86400', 04:34:51 INFO - 'USER': 'cltbld', 04:34:51 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1472383624.168346-109470312', 04:34:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:34:51 INFO - '_': '/tools/buildbot/bin/python'} 04:34:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:34:51 INFO - Downloading/unpacking mozsystemmonitor==0.3 04:34:51 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:34:51 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:34:51 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:34:51 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:34:51 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:34:51 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:34:53 INFO - Downloading mozsystemmonitor-0.3.tar.gz 04:34:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 04:34:53 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 04:34:53 INFO - Installing collected packages: mozsystemmonitor 04:34:53 INFO - Running setup.py install for mozsystemmonitor 04:34:53 INFO - Successfully installed mozsystemmonitor 04:34:53 INFO - Cleaning up... 04:34:53 INFO - Return code: 0 04:34:53 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 04:34:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:34:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:34:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:34:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:34:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:34:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:34:53 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1a52030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1bb58a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d13180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d113b0>, '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 0x1d122b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d12740>, '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': 'c6927cf182745ad25b1d38b9000001fe-1472383624.168346-109470312', '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:34:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 04:34:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 04:34:53 INFO - Using env: {'DISPLAY': ':0', 04:34:53 INFO - 'HOME': '/home/cltbld', 04:34:53 INFO - 'LANG': 'en_US.UTF-8', 04:34:53 INFO - 'LANGUAGE': 'en_US:en', 04:34:53 INFO - 'LOGNAME': 'cltbld', 04:34:53 INFO - 'MAIL': '/var/mail/cltbld', 04:34:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:34:53 INFO - 'MOZ_NO_REMOTE': '1', 04:34:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:34:53 INFO - 'NO_EM_RESTART': '1', 04:34:53 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:34:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:34:53 INFO - 'PWD': '/builds/slave/test', 04:34:53 INFO - 'SHELL': '/bin/bash', 04:34:53 INFO - 'SHLVL': '1', 04:34:53 INFO - 'TERM': 'linux', 04:34:53 INFO - 'TMOUT': '86400', 04:34:53 INFO - 'USER': 'cltbld', 04:34:53 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1472383624.168346-109470312', 04:34:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:34:53 INFO - '_': '/tools/buildbot/bin/python'} 04:34:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:34:53 INFO - Downloading/unpacking jsonschema==2.5.1 04:34:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:34:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:34:53 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:34:53 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:34:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:34:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:34:55 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 04:34:55 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 04:34:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:34:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:34:55 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:34:55 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:34:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:34:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:34:55 INFO - Downloading functools32-3.2.3-2.tar.gz 04:34:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 04:34:55 INFO - warning: no files found matching '*.txt' 04:34:55 INFO - no previously-included directories found matching 'build' 04:34:55 INFO - no previously-included directories found matching 'dist' 04:34:55 INFO - no previously-included directories found matching '.git*' 04:34:55 INFO - Installing collected packages: jsonschema, functools32 04:34:55 INFO - Running setup.py install for functools32 04:34:55 INFO - warning: no files found matching '*.txt' 04:34:55 INFO - no previously-included directories found matching 'build' 04:34:55 INFO - no previously-included directories found matching 'dist' 04:34:55 INFO - no previously-included directories found matching '.git*' 04:34:55 INFO - Successfully installed jsonschema functools32 04:34:55 INFO - Cleaning up... 04:34:55 INFO - Return code: 0 04:34:55 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 04:34:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:34:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:34:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:34:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:34:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:34:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:34:55 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1a52030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1bb58a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d13180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d113b0>, '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 0x1d122b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d12740>, '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': 'c6927cf182745ad25b1d38b9000001fe-1472383624.168346-109470312', '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:34:55 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 04:34:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 04:34:55 INFO - Using env: {'DISPLAY': ':0', 04:34:55 INFO - 'HOME': '/home/cltbld', 04:34:55 INFO - 'LANG': 'en_US.UTF-8', 04:34:55 INFO - 'LANGUAGE': 'en_US:en', 04:34:55 INFO - 'LOGNAME': 'cltbld', 04:34:55 INFO - 'MAIL': '/var/mail/cltbld', 04:34:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:34:55 INFO - 'MOZ_NO_REMOTE': '1', 04:34:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:34:55 INFO - 'NO_EM_RESTART': '1', 04:34:55 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:34:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:34:55 INFO - 'PWD': '/builds/slave/test', 04:34:55 INFO - 'SHELL': '/bin/bash', 04:34:55 INFO - 'SHLVL': '1', 04:34:55 INFO - 'TERM': 'linux', 04:34:55 INFO - 'TMOUT': '86400', 04:34:55 INFO - 'USER': 'cltbld', 04:34:55 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1472383624.168346-109470312', 04:34:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:34:55 INFO - '_': '/tools/buildbot/bin/python'} 04:34:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:34:56 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 04:34:56 INFO - Cleaning up... 04:34:56 INFO - Return code: 0 04:34:56 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 04:34:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:34:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:34:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:34:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:34:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:34:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:34:56 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1a52030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1bb58a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d13180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d113b0>, '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 0x1d122b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d12740>, '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': 'c6927cf182745ad25b1d38b9000001fe-1472383624.168346-109470312', '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:34:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 04:34:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 04:34:56 INFO - Using env: {'DISPLAY': ':0', 04:34:56 INFO - 'HOME': '/home/cltbld', 04:34:56 INFO - 'LANG': 'en_US.UTF-8', 04:34:56 INFO - 'LANGUAGE': 'en_US:en', 04:34:56 INFO - 'LOGNAME': 'cltbld', 04:34:56 INFO - 'MAIL': '/var/mail/cltbld', 04:34:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:34:56 INFO - 'MOZ_NO_REMOTE': '1', 04:34:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:34:56 INFO - 'NO_EM_RESTART': '1', 04:34:56 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:34:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:34:56 INFO - 'PWD': '/builds/slave/test', 04:34:56 INFO - 'SHELL': '/bin/bash', 04:34:56 INFO - 'SHLVL': '1', 04:34:56 INFO - 'TERM': 'linux', 04:34:56 INFO - 'TMOUT': '86400', 04:34:56 INFO - 'USER': 'cltbld', 04:34:56 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1472383624.168346-109470312', 04:34:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:34:56 INFO - '_': '/tools/buildbot/bin/python'} 04:34:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:34:56 INFO - Downloading/unpacking blobuploader==1.2.4 04:34:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:34:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:34:56 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:34:56 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:34:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:34:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:34:58 INFO - Downloading blobuploader-1.2.4.tar.gz 04:34:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 04:34:58 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 04:34:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:34:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:34:58 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:34:58 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:34:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:34:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:34:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 04:34:58 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 04:34:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:34:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:34:58 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:34:58 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:34:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:34:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:34:58 INFO - Downloading docopt-0.6.1.tar.gz 04:34:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 04:34:59 INFO - Installing collected packages: blobuploader, requests, docopt 04:34:59 INFO - Running setup.py install for blobuploader 04:34:59 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 04:34:59 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 04:34:59 INFO - Running setup.py install for requests 04:34:59 INFO - Running setup.py install for docopt 04:34:59 INFO - Successfully installed blobuploader requests docopt 04:34:59 INFO - Cleaning up... 04:34:59 INFO - Return code: 0 04:34:59 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:34:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:34:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:34:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:34:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:34:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:34:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:34:59 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 0x1a52030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1bb58a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d13180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d113b0>, '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 0x1d122b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d12740>, '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': 'c6927cf182745ad25b1d38b9000001fe-1472383624.168346-109470312', '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:34:59 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:34:59 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:34:59 INFO - Using env: {'DISPLAY': ':0', 04:34:59 INFO - 'HOME': '/home/cltbld', 04:34:59 INFO - 'LANG': 'en_US.UTF-8', 04:34:59 INFO - 'LANGUAGE': 'en_US:en', 04:34:59 INFO - 'LOGNAME': 'cltbld', 04:34:59 INFO - 'MAIL': '/var/mail/cltbld', 04:34:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:34:59 INFO - 'MOZ_NO_REMOTE': '1', 04:34:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:34:59 INFO - 'NO_EM_RESTART': '1', 04:34:59 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:34:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:34:59 INFO - 'PWD': '/builds/slave/test', 04:34:59 INFO - 'SHELL': '/bin/bash', 04:34:59 INFO - 'SHLVL': '1', 04:34:59 INFO - 'TERM': 'linux', 04:34:59 INFO - 'TMOUT': '86400', 04:34:59 INFO - 'USER': 'cltbld', 04:34:59 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1472383624.168346-109470312', 04:34:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:34:59 INFO - '_': '/tools/buildbot/bin/python'} 04:34:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:34:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:34:59 INFO - Running setup.py (path:/tmp/pip-a410mW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:34:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:34:59 INFO - Running setup.py (path:/tmp/pip-86UNEx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:34:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:34:59 INFO - Running setup.py (path:/tmp/pip-0K2B32-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:35:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:35:00 INFO - Running setup.py (path:/tmp/pip-HgcOlo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:35:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:35:00 INFO - Running setup.py (path:/tmp/pip-K9vTnz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:35:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:35:00 INFO - Running setup.py (path:/tmp/pip-wHzn0e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:35:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:35:00 INFO - Running setup.py (path:/tmp/pip-vVidk9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:35:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:35:00 INFO - Running setup.py (path:/tmp/pip-PvKbLl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:35:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:35:00 INFO - Running setup.py (path:/tmp/pip-qvQ2u8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:35:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:35:00 INFO - Running setup.py (path:/tmp/pip-uLvnfm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:35:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:35:00 INFO - Running setup.py (path:/tmp/pip-PEF8CT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:35:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:35:00 INFO - Running setup.py (path:/tmp/pip-Rk2q8O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:35:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:35:00 INFO - Running setup.py (path:/tmp/pip-G22isb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:35:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:35:00 INFO - Running setup.py (path:/tmp/pip-cUmGIZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:35:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:35:00 INFO - Running setup.py (path:/tmp/pip-6uPVLo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:35:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:35:00 INFO - Running setup.py (path:/tmp/pip-28S55a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:35:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:35:01 INFO - Running setup.py (path:/tmp/pip-d4AoU3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:35:01 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 04:35:01 INFO - Running setup.py install for manifestparser 04:35:01 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 04:35:01 INFO - Running setup.py install for mozcrash 04:35:01 INFO - Running setup.py install for mozdebug 04:35:01 INFO - Running setup.py install for mozdevice 04:35:01 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 04:35:01 INFO - Installing dm script to /builds/slave/test/build/venv/bin 04:35:01 INFO - Running setup.py install for mozfile 04:35:01 INFO - Running setup.py install for mozhttpd 04:35:01 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 04:35:01 INFO - Running setup.py install for mozinfo 04:35:01 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 04:35:01 INFO - Running setup.py install for mozInstall 04:35:01 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 04:35:01 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 04:35:01 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 04:35:01 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 04:35:01 INFO - Running setup.py install for mozleak 04:35:01 INFO - Running setup.py install for mozlog 04:35:02 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 04:35:02 INFO - Running setup.py install for moznetwork 04:35:02 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 04:35:02 INFO - Running setup.py install for mozprocess 04:35:02 INFO - Running setup.py install for mozprofile 04:35:02 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 04:35:02 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 04:35:02 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 04:35:02 INFO - Running setup.py install for mozrunner 04:35:02 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 04:35:02 INFO - Running setup.py install for mozscreenshot 04:35:02 INFO - Running setup.py install for moztest 04:35:02 INFO - Running setup.py install for mozversion 04:35:02 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 04:35:02 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 04:35:02 INFO - Cleaning up... 04:35:02 INFO - Return code: 0 04:35:02 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:35:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:35:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:35:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:35:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:35:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:35:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:35:02 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 0x1a52030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1bb58a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d13180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d113b0>, '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 0x1d122b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d12740>, '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': 'c6927cf182745ad25b1d38b9000001fe-1472383624.168346-109470312', '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:35:02 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:35:02 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:35:02 INFO - Using env: {'DISPLAY': ':0', 04:35:02 INFO - 'HOME': '/home/cltbld', 04:35:02 INFO - 'LANG': 'en_US.UTF-8', 04:35:02 INFO - 'LANGUAGE': 'en_US:en', 04:35:02 INFO - 'LOGNAME': 'cltbld', 04:35:02 INFO - 'MAIL': '/var/mail/cltbld', 04:35:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:35:02 INFO - 'MOZ_NO_REMOTE': '1', 04:35:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:35:02 INFO - 'NO_EM_RESTART': '1', 04:35:02 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:35:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:35:02 INFO - 'PWD': '/builds/slave/test', 04:35:02 INFO - 'SHELL': '/bin/bash', 04:35:02 INFO - 'SHLVL': '1', 04:35:02 INFO - 'TERM': 'linux', 04:35:02 INFO - 'TMOUT': '86400', 04:35:02 INFO - 'USER': 'cltbld', 04:35:02 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1472383624.168346-109470312', 04:35:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:35:02 INFO - '_': '/tools/buildbot/bin/python'} 04:35:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:35:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:35:03 INFO - Running setup.py (path:/tmp/pip-B6ieEX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:35:03 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:35:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:35:03 INFO - Running setup.py (path:/tmp/pip-GoW8ii-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:35:03 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:35:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:35:03 INFO - Running setup.py (path:/tmp/pip-Q0RU_Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:35:03 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:35:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:35:03 INFO - Running setup.py (path:/tmp/pip-OXN7l6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:35:03 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:35:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:35:03 INFO - Running setup.py (path:/tmp/pip-z_rDgh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:35:03 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:35:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:35:03 INFO - Running setup.py (path:/tmp/pip-0hTeVX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:35:03 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:35:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:35:03 INFO - Running setup.py (path:/tmp/pip-lGBeKh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:35:03 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:35:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:35:03 INFO - Running setup.py (path:/tmp/pip-oHyZMw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:35:03 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:35:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:35:03 INFO - Running setup.py (path:/tmp/pip-IfdBln-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:35:03 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:35:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:35:03 INFO - Running setup.py (path:/tmp/pip-pwAgw6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:35:03 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:35:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:35:03 INFO - Running setup.py (path:/tmp/pip-lcPSx8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:35:03 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:35:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:35:03 INFO - Running setup.py (path:/tmp/pip-xnGEPr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:35:03 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:35:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:35:03 INFO - Running setup.py (path:/tmp/pip-OIkoys-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:35:04 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:35:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:35:04 INFO - Running setup.py (path:/tmp/pip-3nz6Ob-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:35:04 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:35:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:35:04 INFO - Running setup.py (path:/tmp/pip-7boXPf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:35:04 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:35:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:35:04 INFO - Running setup.py (path:/tmp/pip-2HBBom-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:35:04 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:35:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:35:04 INFO - Running setup.py (path:/tmp/pip-6gamnK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:35:04 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:35:04 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:35:04 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:35:04 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:35:04 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:35:04 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:35:04 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 04:35:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:35:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:35:04 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:35:04 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:35:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:35:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:35:06 INFO - Downloading blessings-1.6.tar.gz 04:35:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 04:35:06 INFO - Installing collected packages: blessings 04:35:06 INFO - Running setup.py install for blessings 04:35:06 INFO - Successfully installed blessings 04:35:06 INFO - Cleaning up... 04:35:06 INFO - Return code: 0 04:35:06 INFO - Done creating virtualenv /builds/slave/test/build/venv. 04:35:06 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:35:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:35:06 INFO - Reading from file tmpfile_stdout 04:35:06 INFO - Current package versions: 04:35:06 INFO - argparse == 1.2.1 04:35:06 INFO - blessings == 1.6 04:35:06 INFO - blobuploader == 1.2.4 04:35:06 INFO - docopt == 0.6.1 04:35:06 INFO - functools32 == 3.2.3-2 04:35:06 INFO - jsonschema == 2.5.1 04:35:06 INFO - manifestparser == 1.1 04:35:06 INFO - mozInstall == 1.12 04:35:06 INFO - mozcrash == 0.17 04:35:06 INFO - mozdebug == 0.1 04:35:06 INFO - mozdevice == 0.48 04:35:06 INFO - mozfile == 1.2 04:35:06 INFO - mozhttpd == 0.7 04:35:06 INFO - mozinfo == 0.9 04:35:06 INFO - mozleak == 0.1 04:35:06 INFO - mozlog == 3.3 04:35:06 INFO - moznetwork == 0.27 04:35:06 INFO - mozprocess == 0.23 04:35:06 INFO - mozprofile == 0.28 04:35:06 INFO - mozrunner == 6.12 04:35:06 INFO - mozscreenshot == 0.1 04:35:06 INFO - mozsystemmonitor == 0.3 04:35:06 INFO - moztest == 0.7 04:35:06 INFO - mozversion == 1.4 04:35:06 INFO - psutil == 3.1.1 04:35:06 INFO - requests == 1.2.3 04:35:06 INFO - wsgiref == 0.1.2 04:35:06 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:35:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:35:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:35:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:35:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:35:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:35:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:35:06 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 0x1a52030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1bb58a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d13180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d113b0>, '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 0x1d122b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d12740>, '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': 'c6927cf182745ad25b1d38b9000001fe-1472383624.168346-109470312', '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:35:06 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:35:06 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:35:06 INFO - Using env: {'DISPLAY': ':0', 04:35:06 INFO - 'HOME': '/home/cltbld', 04:35:06 INFO - 'LANG': 'en_US.UTF-8', 04:35:06 INFO - 'LANGUAGE': 'en_US:en', 04:35:06 INFO - 'LOGNAME': 'cltbld', 04:35:06 INFO - 'MAIL': '/var/mail/cltbld', 04:35:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:35:06 INFO - 'MOZ_NO_REMOTE': '1', 04:35:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:35:06 INFO - 'NO_EM_RESTART': '1', 04:35:06 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:35:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:35:06 INFO - 'PWD': '/builds/slave/test', 04:35:06 INFO - 'SHELL': '/bin/bash', 04:35:06 INFO - 'SHLVL': '1', 04:35:06 INFO - 'TERM': 'linux', 04:35:06 INFO - 'TMOUT': '86400', 04:35:06 INFO - 'USER': 'cltbld', 04:35:06 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1472383624.168346-109470312', 04:35:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:35:06 INFO - '_': '/tools/buildbot/bin/python'} 04:35:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:35:06 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:35:06 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:35:06 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:35:06 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:35:06 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:35:06 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:35:06 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:35:06 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:35:06 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:35:06 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:35:06 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:35:06 INFO - Cleaning up... 04:35:06 INFO - Return code: 0 04:35:06 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 04:35:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:35:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:35:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:35:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:35:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:35:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:35:06 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 0x1a52030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1bb58a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d13180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d113b0>, '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 0x1d122b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d12740>, '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': 'c6927cf182745ad25b1d38b9000001fe-1472383624.168346-109470312', '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:35:06 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:35:06 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:35:06 INFO - Using env: {'DISPLAY': ':0', 04:35:06 INFO - 'HOME': '/home/cltbld', 04:35:06 INFO - 'LANG': 'en_US.UTF-8', 04:35:06 INFO - 'LANGUAGE': 'en_US:en', 04:35:06 INFO - 'LOGNAME': 'cltbld', 04:35:06 INFO - 'MAIL': '/var/mail/cltbld', 04:35:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:35:06 INFO - 'MOZ_NO_REMOTE': '1', 04:35:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:35:06 INFO - 'NO_EM_RESTART': '1', 04:35:06 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:35:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:35:06 INFO - 'PWD': '/builds/slave/test', 04:35:06 INFO - 'SHELL': '/bin/bash', 04:35:06 INFO - 'SHLVL': '1', 04:35:06 INFO - 'TERM': 'linux', 04:35:06 INFO - 'TMOUT': '86400', 04:35:06 INFO - 'USER': 'cltbld', 04:35:06 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1472383624.168346-109470312', 04:35:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:35:06 INFO - '_': '/tools/buildbot/bin/python'} 04:35:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:35:07 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 04:35:07 INFO - Cleaning up... 04:35:07 INFO - Return code: 0 04:35:07 INFO - Running post-action listener: _resource_record_post_action 04:35:07 INFO - Running post-action listener: _start_resource_monitoring 04:35:07 INFO - Starting resource monitoring. 04:35:07 INFO - [mozharness: 2016-08-28 11:35:07.135704Z] Finished create-virtualenv step (success) 04:35:07 INFO - [mozharness: 2016-08-28 11:35:07.136179Z] Running install step. 04:35:07 INFO - Running pre-action listener: _resource_record_pre_action 04:35:07 INFO - Running main action method: install 04:35:07 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:35:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:35:07 INFO - Reading from file tmpfile_stdout 04:35:07 INFO - Detecting whether we're running mozinstall >=1.0... 04:35:07 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 04:35:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 04:35:07 INFO - Reading from file tmpfile_stdout 04:35:07 INFO - Output received: 04:35:07 INFO - Usage: mozinstall [options] installer 04:35:07 INFO - Options: 04:35:07 INFO - -h, --help show this help message and exit 04:35:07 INFO - -d DEST, --destination=DEST 04:35:07 INFO - Directory to install application into. [default: 04:35:07 INFO - "/builds/slave/test"] 04:35:07 INFO - --app=APP Application being installed. [default: firefox] 04:35:07 INFO - mkdir: /builds/slave/test/build/application 04:35:07 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:35:07 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:35:23 INFO - Reading from file tmpfile_stdout 04:35:23 INFO - Output received: 04:35:23 INFO - /builds/slave/test/build/application/firefox/firefox 04:35:23 INFO - Running post-action listener: _resource_record_post_action 04:35:23 INFO - [mozharness: 2016-08-28 11:35:23.420722Z] Finished install step (success) 04:35:23 INFO - [mozharness: 2016-08-28 11:35:23.420998Z] Running run-tests step. 04:35:23 INFO - Running pre-action listener: _resource_record_pre_action 04:35:23 INFO - Running main action method: run_tests 04:35:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 04:35:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 04:35:23 INFO - Python 2.7.3 04:35:23 INFO - Return code: 0 04:35:23 INFO - grabbing minidump binary from tooltool 04:35:23 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:35:23 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 0x1d113b0>, '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 0x1d122b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d12740>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 04:35:23 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:35:23 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:35:23 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:35:23 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 04:35:23 INFO - Return code: 0 04:35:23 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 04:35:23 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 04:35:23 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 04:35:23 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 04:35:23 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 04:35:23 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-004', '--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:35:23 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-004 --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:35:23 INFO - Using env: {'DISPLAY': ':0', 04:35:23 INFO - 'HOME': '/home/cltbld', 04:35:23 INFO - 'LANG': 'en_US.UTF-8', 04:35:23 INFO - 'LANGUAGE': 'en_US:en', 04:35:23 INFO - 'LOGNAME': 'cltbld', 04:35:23 INFO - 'MAIL': '/var/mail/cltbld', 04:35:23 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 04:35:23 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 04:35:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:35:23 INFO - 'MOZ_NO_REMOTE': '1', 04:35:23 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 04:35:23 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:35:23 INFO - 'NO_EM_RESTART': '1', 04:35:23 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:35:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:35:23 INFO - 'PWD': '/builds/slave/test', 04:35:23 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 04:35:23 INFO - 'SHELL': '/bin/bash', 04:35:23 INFO - 'SHLVL': '1', 04:35:23 INFO - 'TERM': 'linux', 04:35:23 INFO - 'TMOUT': '86400', 04:35:23 INFO - 'USER': 'cltbld', 04:35:23 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1472383624.168346-109470312', 04:35:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:35:23 INFO - '_': '/tools/buildbot/bin/python'} 04:35:23 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-004', '--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:35:23 INFO - mozversion application_buildid: 20160827004001 04:35:23 INFO - mozversion application_changeset: 4d38a69eda61f663463af8f020447e9afbb118b1 04:35:23 INFO - mozversion application_display_name: FirefoxDeveloperEdition 04:35:23 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 04:35:23 INFO - mozversion application_name: Firefox 04:35:23 INFO - mozversion application_remotingname: firefox-dev 04:35:23 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 04:35:23 INFO - mozversion application_vendor: Mozilla 04:35:23 INFO - mozversion application_version: 50.0a2 04:35:23 INFO - mozversion platform_buildid: 20160827004001 04:35:23 INFO - mozversion platform_changeset: 4d38a69eda61f663463af8f020447e9afbb118b1 04:35:23 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 04:35:23 INFO - mozversion platform_version: 50.0a2 04:35:23 INFO - using testdate: 1472384123 04:35:23 INFO - actual date: 1472384123 04:35:23 INFO - starting webserver on 'localhost:59289' 04:35:23 INFO - SUITE-START | Running 2 tests 04:35:23 INFO - TEST-START | dromaeo_css 04:35:23 INFO - operating with platform_type : linux_ 04:35:23 INFO - Initialising browser for dromaeo_css test... 04:35:23 INFO - TEST-INFO | started process 28750 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpKxAMz0/profile http://localhost:59289/getInfo.html) 04:35:27 INFO - PROCESS | 28750 | __metrics Screen width/height:1600/1200 04:35:27 INFO - PROCESS | 28750 | colorDepth:24 04:35:27 INFO - PROCESS | 28750 | Browser inner width/height: 1024/697 04:35:27 INFO - PROCESS | 28750 | __metrics 04:35:28 INFO - PROCESS | 28750 | 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:35:28 INFO - PROCESS | 28750 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 04:35:29 INFO - TEST-INFO | 28750: exit 0 04:35:29 INFO - Browser initialized. 04:35:29 INFO - Running cycle 1/1 for dromaeo_css test... 04:35:29 INFO - TEST-INFO | started process 28836 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpKxAMz0/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:35:41 INFO - PROCESS | 28836 | RSS: Main: 145006592 04:35:41 INFO - PROCESS | 28836 | 04:36:32 INFO - PROCESS | 28836 | 1472384192216 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:38:41 INFO - PROCESS | 28836 | Cycle 1(1): loaded http://localhost:59289/tests/dromaeo/cssquery-dojo.html (next: http://localhost:59289/tests/dromaeo/cssquery-ext.html) 04:38:42 INFO - PROCESS | 28836 | RSS: Main: 228777984 04:38:42 INFO - PROCESS | 28836 | 04:41:44 INFO - PROCESS | 28836 | Cycle 1(1): loaded http://localhost:59289/tests/dromaeo/cssquery-ext.html (next: http://localhost:59289/tests/dromaeo/cssquery-jquery.html) 04:41:45 INFO - PROCESS | 28836 | RSS: Main: 232210432 04:41:45 INFO - PROCESS | 28836 | 04:44:47 INFO - PROCESS | 28836 | Cycle 1(1): loaded http://localhost:59289/tests/dromaeo/cssquery-jquery.html (next: http://localhost:59289/tests/dromaeo/cssquery-mootools.html) 04:44:47 INFO - PROCESS | 28836 | RSS: Main: 221863936 04:44:47 INFO - PROCESS | 28836 | 04:47:49 INFO - PROCESS | 28836 | Cycle 1(1): loaded http://localhost:59289/tests/dromaeo/cssquery-mootools.html (next: http://localhost:59289/tests/dromaeo/cssquery-prototype.html) 04:47:50 INFO - PROCESS | 28836 | RSS: Main: 198983680 04:47:50 INFO - PROCESS | 28836 | 04:50:52 INFO - PROCESS | 28836 | Cycle 1(1): loaded http://localhost:59289/tests/dromaeo/cssquery-prototype.html (next: http://localhost:59289/tests/dromaeo/cssquery-yui.html) 04:50:53 INFO - PROCESS | 28836 | RSS: Main: 176844800 04:50:53 INFO - PROCESS | 28836 | 04:53:56 INFO - PROCESS | 28836 | Cycle 1(1): loaded http://localhost:59289/tests/dromaeo/cssquery-yui.html (next: http://localhost:59289/tests/dromaeo/cssquery-dojo.html) 04:53:56 INFO - PROCESS | 28836 | RSS: Main: 265240576 04:53:56 INFO - PROCESS | 28836 | 04:53:56 INFO - PROCESS | 28836 | __start_tp_report 04:53:56 INFO - PROCESS | 28836 | _x_x_mozilla_page_load 04:53:56 INFO - PROCESS | 28836 | _x_x_mozilla_page_load_details 04:53:56 INFO - PROCESS | 28836 | |i|pagename|runs| 04:53:56 INFO - PROCESS | 28836 | |0;dojo.html;3266.1671273825173;3272.6563710810365;3287.2275015371383;3292.588426446694;3323.3519463704574;5681.517071048961;5732.885342293157;5754.597178197528;5769.913451298228;5775.797847075353;5763.798267060653;5810.070388737803;5822.621529600573;5826.854328641786;5832.445917637829;5149.871253218662;5206.869828254305;5250.816221432251;5307.893842123155;5315.548178404821;5287.656302340355;5344.144936810111;5346.893062138755;5347.171188465794;5348.518633323;5242.135047717122;5276.182191760283;5288.418273989858;5294.470552944694;5346.064438723208;5362.678239305635;5372.167314066326;5381.273528073699;5381.596380271456;5381.973090134564;5122.154844450662;5132.101882170643;5162.5095615916425;5275.630705850592;5286.709230992294;5736.713164341766;5740.08158694611;5746.051901436258;5776.682282474462;5808.912866307009;6218.844528886769;6229.781957631507;6236.937630623726;6242;6247.562670613059;6116.969415152896;6162.383761623847;6169.660668663222;6254.093156492283;6268.749250030039;5834.154047663107;5848.502877255439;5858.707064646751;5862.472377485961;5864.677442740647;6111.174991376196;6407.551471397051;6417.518686098468;6420.871582568322;6421.678916054272;6032.245969253843;6039.758409663652;6069.423404776539;6109.7387008107025;6112.358202388774;6193.628382297076;6196;6200.627962322275;6204.038374052029;6207.162033125561;5903.232579764689;6178.289496707936;6188.44304012641;6192.81421557354;6206.534509911775;6094.603850749687;6101.237345331833;6138.815835524942;6192.380761923771;6194.473469755121;4683.461401938753;4713.080949214938;4718.551737584924;4740.928886066712;4741.146593613183;4118.5057793064425;4219.6202341789385;4231.4710661167355;4243.087736136684;4243.745375277494;5873.9412605873395;5925.34821169671;5937.2578427696535;5977.372375900555;6011.939880601226;6276.372362763778;6277.027060805583;6278.277998030194;6284.151639528696;6286.9056964145575;5744.138379243147;5969.582129250911;5969.731362088716;5970.462134332906;5974.970125149433;5112.105381558287;5176.663516871391;5176.689398636094;5216.37403511581;5270.051390749625;6359.268684101387;6362;6400.295967443578;6408.359164083555;6410.967945160244;6090.451859332681;6195.504359651307;6198.752049917953;6203;6206.844828879314;6329.525285603506;6367.363263673781;6400.583962042452;6418.839529011812;6476.125723027331;6081.513478921587;6094.878102438015;6105.14527966094;6112.786052488142;6119.143319935123;6000.21997140369;6205.6586887721605;6225.31521532622;6230.813075607739;6235.033569796688;5969.432903874125;6012;6050.2437195350585;6109.236345456818;6138.907916381348;6009.669468179292;6042.214512113573;6062.090686397076;6065.3934606539;6065.8180254592435;5858.238429004202;5978.8206353809455;6020.819375418744;6034.577579569388;6040.909386359121;5945.34601193877;6029.336772955059;6035.094735789667;6083.6653984029535;6127.571070025056;5950.702464876826;5957.374475680164;5958.463738263577;5960.672163031075;5963.373845746308 04:53:56 INFO - PROCESS | 28836 | |1;ext.html;620.8820324138413;622.4024936061381;668.4585485756536;668.7458765669045;680.7106979533697;39077.21845563091;40032.79983600081;50005.999999999956;50803.74598127004;51020.48979510204;51786.22320665193;52364.214536781976;52487;52492;52517.737411312984;8653.177948094952;9405.529723513831;9564.617415303379;9570.234381249484;9572.425654460754;10969.725756856102;11301.660950171467;11319.264247823907;11660.883391166108;11664.7667046659;11555.133364997659;11624.593139240129;11625.593104241354;11658.417079146051;11674.299542027506;11416.600418985337;11450.885491145065;11487.65537033882;11490.540378384896;11491.42542872853;11227.157963152715;11265.60570380041;11288.492017859217;11292.887071129266;11294.265872718248;48503;48774.26838597424;48928.26607600889;48949.75525122387;48952;3942.7831469269177;3987.3819557968563;4580.862574122782;4708.270218116198;4724.95275047252;36523;36529.45205821915;36605.81697091497;37099.81450092732;38896.41655375172;4578.061497393026;4668.229742092551;4855.32025516428;4865.464798872121;4906.288588154697;403279;404196;405522;406371;408863;39628.40557391642;40529.59470405258;70763.64618176877;71598;71694;41691.583084169375;41794.79102604467;41966.37050444246;42014.57985420168;42045.579544204775;17555.29778808859;17755.201015954313;17799.288028478717;17800;17812.198451069733;12031;14471.49349772753;14917.552473425814;15012.924935375253;15119.924400377928;9399.530023498797;9416.95291523538;9418.858717119248;9426.198773104363;9494.335396522178;3554.733394995386;3556.78659280444;3575.606683264839;3604.13500759821;3611.981940090283;1082.875469321038;1105.3147048829776;1112.527175950218;1113.8663360396804;1155.1047937848066;35400;36362.63637363645;36560;36704;36735.08162295964;1180.2859270141612;1192.493190394079;1248.4881198708485;1262.5265525427965;1277.2655722959335;18801.15394807237;18844.623107538046;18972.14625341863;18982.71525927112;19164.425067248005;593704;595748;596979;599242;606192;66675.33324666691;67229;67317.30730770715;67339.66330168318;67719;1374.271636032904;1446.2696338349067;1478.977815332771;1498.3107770425509;1511.04803973496;35116.82441587776;37328.813355933045;37377.4393384094;37474.8126259367;38060;57259.42740572541;63635.681821590595;65941;66053;71241.6437917807;588046;588292;590565;590715;591868;28759.856200718863;29224.56163157597;29341.26646833846;30815.69184308218;50556.24165637445;50921.74539127281;51006.48993510017;51500;51515.48484515107;52255.73872130615;43023;43598.346024810286;43873;44537.10925781531;44948.325775114055;43835.12329753323;45027.774861126796;45158;45908.311375330064;46192.53807462017;42994;43323;43814.34278485889;44637.776811115735;44817.551824481336;36710.63289367072;38943.610563894;38976;39009.69256171953;39718;28809.711902880703;29213.7078629211;29287.12138635844;31743.52384714185;32817.34365312728 04:53:56 INFO - PROCESS | 28836 | |2;jquery.html;400.15367497742284;411.36238829813783;424.17286291731125;430.1354277901418;443.86018404202673;15598.376064957401;20690.172393104258;20963.16147354106;21371.03830327635;21376;17142.742858857127;17198.828011719877;17206.655866882687;17248.56878578036;17352.132393380343;4354.412154359168;5678.971605141968;5877.2947246330505;5892.086726557391;6394.296627370985;6524.641144737037;6650.5012124090645;6680.899786503206;6766.221884483273;6766.526343155981;6469.914895734823;6509;6530.2816690164045;6530.44491218247;6536.248331441874;6352.872942541123;6545.116409284733;6588;6629.9668501657425;6630.171228596426;5879.706014699291;5884.029135192731;5884.235049443545;5885.058390657517;5886.175935369051;24717.50564988708;24793.752062479325;24798;24799.5040099197;24832.875835620885;2642.4186678930605;2668.2929023808706;2735.931601709953;2823.618811460447;3037.6202974628172;14899.925500372428;15704.685906281924;15836.920815395964;15964.281607327699;16045.839541604551;2085.343116918166;2222.5665995130958;2441.816863735209;2484.714257860351;2491.190363131989;78061.82907256339;80887.59556202182;83533;83690.16309836885;90277.54861225652;18031.909840450975;18521.444356669323;19059.237630494627;19076;19531.609367812565;34953;35006.47490287648;35242.47136292957;35337.823310883796;35465.46801797975;10695.251332406657;10864.456777161111;10942.671719848417;10988.120950323955;11130.664320281618;9067.410618309788;9137.63449462014;9161.862572061424;9295.605659151046;9370.67202647904;9384.390014649027;9425.858612120825;9559.187469065208;9588.232941364675;9808.607655693835;3334.583177102862;3384.526166336715;3422.7775194612273;3425.2122011936895;3767.7362584618813;799.3724925933114;891.7057371067532;892.1023580542774;925.509479975614;1021.065724861758;20600.690989635168;20760.377188684364;20831.479213019797;20982.160713571287;21406;1081.1404933078222;1103.2553026706942;1105.2926127278547;1137.4028634966544;1145.473082382097;11319.717007074725;11389.487473063731;11588.536458541565;11654;11684.298942063333;549191;554318.2215125095;560159;568800;585570;59764.402355975886;95843.9779425375;95901.52049239849;100076.49885251727;100746;1034.0900007993068;1047.5914393386433;1049.5935446501724;1056.1286938275953;1068.2628985999636;17727.202275897358;18030.819691802913;18285.908570457064;18362.72455913189;18467.538311542317;42653;43433.565664342954;43545;43960.853079148066;45562.31656525088;58777;61397.693011534655;62740.37259627345;63118.368816311246;63256.68371658112;9113.225375842863;9214.677486287948;9223.723288301362;9267.165955064076;9358.625654973725;9025.774355641162;10186.933111517414;10330.380177189392;13604.455821767018;13785.448582056606;11335.263207891461;11362.829557556808;11687.88312116868;12032.09759268041;12278.201916875374;12180.878191217975;12257.387130643254;12426.378681066091;12429.564965226175;13058.934705326412;10828.350298982086;11050.723731906766;12244.387780611112;12676.87323126757;12773.808392873929;8041.798955025937;8407.95796021016;8563.529005904735;9208.263338932735;9622.615095396106;9870.753231169278;12221.205621634565;12340.382980851102;12505.499780008699;12612.684682883002 04:53:56 INFO - PROCESS | 28836 | |3;mootools.html;1513.591330340808;1549.418967887042;1725.9568510787228;1866.6360059788346;1882.6611209982207;9288.44269343839;10149.949250253756;10162.64430744923;10163.339382940103;10163.390196588201;8533.743987680364;8737.825243495126;8754.343424243176;8963.46219226847;9822.950885245564;2459.9877000615015;2528.683914510686;2528.88620012099;2554.706208785995;2554.7189809120982;2526.5325914705745;2553.144696526666;2554.8211625186245;2570.807189460789;2583.147561304765;2494.1395218649627;2496.7128780190237;2504.9749502505015;2507.4608959073794;2508.686414198225;2434.9513009739794;2440.731519532859;2441.9389515262174;2444.633305004246;2451.3871532116973;2362.976370236293;2455.6316552517087;2456.1280745335325;2476.0343466048257;2477.6531285620026;10000.149987251092;10058.949705251427;10083.647072352433;10100.444475553842;10113.69658910234;5648.774049038034;5869.148973398833;5893.734781934823;5894.58737888348;5901.763929442817;3564.429691249388;3660.396034654279;3842.1931394407206;3871.8644847430205;3878.8836334909715;1191.350713860948;1213.708709909624;2357.834951553392;2374.25211058516;2424.7090349158216;14067.577972660836;14088.225147616877;14116.647083822883;14161.22113283769;14161.504347347896;4902.823322402598;4923.261510773341;4924.556789888928;4998.125328067574;5176.559992400613;10958.835617465744;10996.450177491093;10996.560137594406;10996.560137594406;11008;5662.943370566324;5668.404817494186;5674.971625141848;5677.77288908439;5679;4835.347228124228;5235.633505654644;5272.92090618641;5291.708956007379;5436;4153.937690934638;4243.257429949746;4287.506936702319;4294.098239369768;4295.548967358445;1243.6517775023008;1280.6734282757836;1290.1098242212843;1297.9805302920463;1308.6793735534663;1044.2690116918188;1097.1113398147527;1105.7069876482738;1114.9944250278697;1126.9492872820742;6567.310432404529;6580;6618.86762264752;6646.335366463411;6725;1008.5713571732131;1051.332403923514;1058.7353161709573;1059.6715018344337;1060.0883240413239;4526.841560545365;4579.610733087725;4597.195490789099;4609.331646911246;4614.353990441341;12124.575639852563;12656.050796190322;12662.620121396372;12668.619941401772;12676.873231267753;11709.414529273503;12115.15193936416;12170.330631815335;12494.50021999128;12755.93622031884;9182;9326.860097098548;9329.580168892417;9394.060593940689;9410.247180225566;6399.776007839703;6796.286389928986;6865.897011544931;6914.446844252348;6935.757248496278;11314.943425282821;11595.246308989888;11610.303381797119;11644.5342186315;11782.057435404975;11264.83102753443;11434.313941163557;11452.370119643167;11713;11823.704407389883;8805.16350946659;8821.382503224713;8888.644454222018;8994.730158095268;9012.098790120936;9584.28117891175;9714.417134971924;9724.416535007922;9733.513324333897;9737.07497787709;8283.337333013224;8324.875126872981;8565.27195188416;8701.869471958053;8768.167024132952;8513.787155321166;8593.91406085956;8694.260987816106;8721.520316382659;8946.37375383717;7707.460477766503;7823.335016523659;7902.051753789582;7988.560629165451;8126.796830079295;5814.098814683731;5860.619059761102;5919.260092488439;5962.25471816023;6022.36765139654;5817.301923769175;5848.941510584839;6034.758609655564;6074.483668888017;6124.938750612615 04:53:56 INFO - PROCESS | 28836 | |4;prototype.html;339.45347989736536;345.70614977269315;354.37452895639194;354.7605366377696;355.57331202556907;5432.429594892538;5484.698341591211;5556.527695145913;5610.382857885635;5655.359945615772;5463.470228336072;5483.259759932408;5519.089350257203;5534.568567488153;5540.9168862466995;4374.365716971038;4385.583369579901;4389.758563279019;4390.121975604876;4392.626626736731;4142.71001029928;4322.308430651096;4347.826086956518;4389.122175564884;4401.427814384126;4280.143971205771;4290.635295999844;4292.527821939584;4295.914081718363;4299.591538803824;4283.464566929134;4286.678499112548;4292.39906413104;4292.785360731951;4297.183535128315;3646.6535679110443;3789.290657180102;3794.9430758538642;3808.619138086169;3810.79040652764;5377.220303056035;5389.51494365509;5395.541378982752;5405.270288511079;5415.431379705153;4939.5554400104165;4939.678351174942;4940.134721861312;4945.873274491967;4953.786322351011;5483.7806487740445;5723.656580605177;5759.308882934075;5769.596128271022;5782.884342313172;4216.3464662976985;4234.724742891703;4243.172581346638;4248.553901840293;4253.553376895444;6016.368281330441;6019.729112189962;6033.577649564532;6046.516278697738;6083.878322433527;4319.265724826787;4354.433923589976;4388.210122177975;4423.159599676052;4430.803683005571;4419.403380543586;4440.866773996785;4455.1535208310315;4458.799354029076;4459;4934.235193545006;5004.1492946198505;5005.874853128628;5010.273510341053;5023.522765337287;5519.9448005520235;5629.24005259293;5782.421757824268;5826.1552074948895;5835.854103647443;1476.0553245922617;1515.886308526865;1530.1660594975765;1533.8619524242872;1534.240550927298;935.1723724503863;980.294188184506;985.8373368394278;987.481572174614;990.9653162139291;4879.072976134523;4928.117051524827;4938.308636790852;4954;4974.37820272466;5205.6616319939085;5211.1922651989;5219.895602087937;5221.929504451578;5229.843104706865;4731.243001119804;4939.802407903716;4957.752112394366;4963.759060234941;4967.379077615298;2768.3771151490755;2772.126780064273;2787.8884844605986;2791.3300807806386;2795.259256297083;5410.48600382963;5412.945870541323;5412.971535408338;5415.6479828811;5424.701641409761;5116.234898959853;5283.417240572228;5290;5305.681659100466;5377.489138531833;5301;5302.310699609026;5321.281626980308;5328.946710532922;5359.839204823861;5142.8971420572125;5145.993062705473;5159.896802063862;5226.764795584132;5270.762815673381;4924.178054119999;5184.170532714747;5185.948140518546;5186.06650802844;5217.686938783685;5369.3556773187465;5370.812021579226;5376.381716102698;5389.946100538945;5429.945700543102;3966.7619942803526;3972.3445631470486;3973.9931334675803;3976.7415118018394;3996.460477835455;4998.850034498971;5141.38303403579;5206.323177986837;5737.254156959569;5849.128278950865;5762.798302059407;5786.276715410574;5790.768369265183;5793.826185214451;5878.676672782867;5790.565707571865;5798.217240672624;5800.477956983892;5804.622699524517;5804.6807425592;5583.804566840141;5762.481376676119;5788.507976821849;5799.391063938395;5814.703192238957;5830.329512105993;5835.9708201458725;5850.356460789395;5854.853628659318;5882.205902203148;5614.6631202128;5660.848877426716;5694;5708.65748055118;5715.342735585295 04:53:56 INFO - PROCESS | 28836 | |5;yui.html;853.7310747114658;872.3936863879604;884.0452311503577;895.9552022398879;933.1368484152159;16060;17765.91117044413;17814.465566033006;17833.28666853326;17842.91078544607;15935.44225952092;15978.201089945485;16563.17184140794;17071.658566828657;17186.05476698781;742.6027075514601;883.3639779358851;890.4345740454809;906.0893801729253;907.7866701325216;896.7578753736487;900.8333458310201;904.9095090490931;907.6097278170384;907.63694522191;917.8256131335058;918.9586468608928;919.071737545081;919.3840127114815;919.5769945824895;782.581318994341;912.4935660708304;912.6988093928987;913.1644545241096;917.0645941140067;690.6167077272112;695.3845846425899;695.4818660098208;695.7390978383106;695.9234484206734;16739.16304184798;16761.497155085366;16773.161341932853;16783.076930768802;16805.83194168055;15200.619984500365;15345.616359590987;15371.923140384226;15449;15544.378224870992;14485.203313817736;14578.854211457856;14671.853281467156;14708.264586770618;14769.261536923112;4614.838480653161;4730.101280756679;4775.307580400857;4843.4187897452175;4847.103285892122;90628.54685726529;91423;92591;92657.53671231668;92717.53641231818;12473.438695258736;12490.625281241577;12573.748525029449;12823.93588032054;12990.610281691565;22866.885665571564;22867.31398058061;22877;22901.31296061121;22914.427139321317;8669.046404895456;8725.825483490295;8779.20987111163;8975.955120224357;9050.592723327467;4964.429090654548;5007.424146223231;5019.949800502021;5073.086844367976;5080.542751152414;4127.195196936628;4294.162638285567;4298.548652391516;4305.07440900208;4308.870733877989;718.9460790440738;721.1922646635696;737.6791095873313;738.2764890407431;747.0922828763079;719.2735337309251;749.583980890611;750.5271678842404;773.7369294439804;783.2441693765544;10619.575216991234;10725;10997.12023038155;11046.668599942015;11286.266392684449;706.7879636109146;772.3936709682872;773.21132444907;780.2431641307923;814.2590242878952;5260.815871444481;5337.839864804062;5490.864337782824;5540.335159780771;5603.355614104348;107057;107479;111633.44183279194;132597.33701331625;134674;64867.67566162139;88369;96025.5198724002;97453;101391;85507.57246213853;85750.57124714337;90855.54572227097;94885.5255723717;96227;10612.681619551426;11002;11073.446327683423;11249.437528123397;11375.31748095117;48504.51495485;56469;57957.71021144867;58163;58269.12596311144;78257.60871195835;81136;84869;85090.14909850822;86727.56636217031;12119.878801211875;12968.481260749464;12971.935140324238;13321.267330296927;13525.323733813073;15685.13731744765;15933.760993584874;16384.67230655404;16752;16770;11992.940035299767;12437.813432798695;14381.712365752835;15022.849771502582;15240.3903843845;14508.782368264274;14668.486602968846;15533.223338832788;15998.600034998752;16002.359905604111;15063.698726025637;15181.924090379478;15273.312700928707;15342.539723808304;15821.367145314058;12318.445669944695;12612;12746.553870614483;12801.231926084465;12886.226826390446;12544.24734515932;12682.746345072863;12974.156679815782;13065.542706005244;13104.868951310365 04:53:56 INFO - PROCESS | 28836 | __end_tp_report 04:53:56 INFO - PROCESS | 28836 | __start_cc_report 04:53:56 INFO - PROCESS | 28836 | _x_x_mozilla_cycle_collect,1013 04:53:56 INFO - PROCESS | 28836 | __end_cc_report 04:53:56 INFO - PROCESS | 28836 | __startTimestamp1472385236466__endTimestamp 04:53:56 INFO - PROCESS | 28836 | 04:53:56 INFO - PROCESS | 28836 | ------- Summary: start ------- 04:53:56 INFO - PROCESS | 28836 | Number of tests: 6 04:53:56 INFO - PROCESS | 28836 | 04:53:56 INFO - PROCESS | 28836 | [#0] dojo.html Cycles:165 Average:5753.05 Median:5977.37 stddev:663.55 (11.1%) stddev-sans-first:636.26 04:53:56 INFO - PROCESS | 28836 | Values: 3266.2 3272.7 3287.2 3292.6 3323.4 5681.5 5732.9 5754.6 5769.9 5775.8 5763.8 5810.1 5822.6 5826.9 5832.4 5149.9 5206.9 5250.8 5307.9 5315.5 5287.7 5344.1 5346.9 5347.2 5348.5 5242.1 5276.2 5288.4 5294.5 5346.1 5362.7 5372.2 5381.3 5381.6 5382.0 5122.2 5132.1 5162.5 5275.6 5286.7 5736.7 5740.1 5746.1 5776.7 5808.9 6218.8 6229.8 6236.9 6242.0 6247.6 6117.0 6162.4 6169.7 6254.1 6268.7 5834.2 5848.5 5858.7 5862.5 5864.7 6111.2 6407.6 6417.5 6420.9 6421.7 6032.2 6039.8 6069.4 6109.7 6112.4 6193.6 6196.0 6200.6 6204.0 6207.2 5903.2 6178.3 6188.4 6192.8 6206.5 6094.6 6101.2 6138.8 6192.4 6194.5 4683.5 4713.1 4718.6 4740.9 4741.1 4118.5 4219.6 4231.5 4243.1 4243.7 5873.9 5925.3 5937.3 5977.4 6011.9 6276.4 6277.0 6278.3 6284.2 6286.9 5744.1 5969.6 5969.7 5970.5 5975.0 5112.1 5176.7 5176.7 5216.4 5270.1 6359.3 6362.0 6400.3 6408.4 6411.0 6090.5 6195.5 6198.8 6203.0 6206.8 6329.5 6367.4 6400.6 6418.8 6476.1 6081.5 6094.9 6105.1 6112.8 6119.1 6000.2 6205.7 6225.3 6230.8 6235.0 5969.4 6012.0 6050.2 6109.2 6138.9 6009.7 6042.2 6062.1 6065.4 6065.8 5858.2 5978.8 6020.8 6034.6 6040.9 5945.3 6029.3 6035.1 6083.7 6127.6 5950.7 5957.4 5958.5 5960.7 5963.4 04:53:56 INFO - PROCESS | 28836 | 04:53:56 INFO - PROCESS | 28836 | [#1] ext.html Cycles:180 Average:69576.37 Median:222362.80 stddev:143355.77 (64.5%) stddev-sans-first:143664.43 04:53:56 INFO - PROCESS | 28836 | Values: 620.9 622.4 668.5 668.7 680.7 39077.2 40032.8 50006.0 50803.7 51020.5 51786.2 52364.2 52487.0 52492.0 52517.7 8653.2 9405.5 9564.6 9570.2 9572.4 10969.7 11301.7 11319.3 11660.9 11664.8 11555.1 11624.6 11625.6 11658.4 11674.3 11416.6 11450.9 11487.7 11490.5 11491.4 11227.2 11265.6 11288.5 11292.9 11294.3 48503.0 48774.3 48928.3 48949.8 48952.0 3942.8 3987.4 4580.9 4708.3 4725.0 36523.0 36529.5 36605.8 37099.8 38896.4 4578.1 4668.2 4855.3 4865.5 4906.3 403279.0 404196.0 405522.0 406371.0 408863.0 39628.4 40529.6 70763.6 71598.0 71694.0 41691.6 41794.8 41966.4 42014.6 42045.6 17555.3 17755.2 17799.3 17800.0 17812.2 12031.0 14471.5 14917.6 15012.9 15119.9 9399.5 9417.0 9418.9 9426.2 9494.3 3554.7 3556.8 3575.6 3604.1 3612.0 1082.9 1105.3 1112.5 1113.9 1155.1 35400.0 36362.6 36560.0 36704.0 36735.1 1180.3 1192.5 1248.5 1262.5 1277.3 18801.2 18844.6 18972.1 18982.7 19164.4 593704.0 595748.0 596979.0 599242.0 606192.0 66675.3 67229.0 67317.3 67339.7 67719.0 1374.3 1446.3 1479.0 1498.3 1511.0 35116.8 37328.8 37377.4 37474.8 38060.0 57259.4 63635.7 65941.0 66053.0 71241.6 588046.0 588292.0 590565.0 590715.0 591868.0 28759.9 29224.6 29341.3 30815.7 50556.2 50921.7 51006.5 51500.0 51515.5 52255.7 43023.0 43598.3 43873.0 44537.1 44948.3 43835.1 45027.8 45158.0 45908.3 46192.5 42994.0 43323.0 43814.3 44637.8 44817.6 36710.6 38943.6 38976.0 39009.7 39718.0 28809.7 29213.7 29287.1 31743.5 32817.3 04:53:56 INFO - PROCESS | 28836 | 04:53:56 INFO - PROCESS | 28836 | [#2] jquery.html Cycles:180 Average:32663.53 Median:3186.10 stddev:92451.24 (2901.7%) stddev-sans-first:92678.85 04:53:56 INFO - PROCESS | 28836 | Values: 400.2 411.4 424.2 430.1 443.9 15598.4 20690.2 20963.2 21371.0 21376.0 17142.7 17198.8 17206.7 17248.6 17352.1 4354.4 5679.0 5877.3 5892.1 6394.3 6524.6 6650.5 6680.9 6766.2 6766.5 6469.9 6509.0 6530.3 6530.4 6536.2 6352.9 6545.1 6588.0 6630.0 6630.2 5879.7 5884.0 5884.2 5885.1 5886.2 24717.5 24793.8 24798.0 24799.5 24832.9 2642.4 2668.3 2735.9 2823.6 3037.6 14899.9 15704.7 15836.9 15964.3 16045.8 2085.3 2222.6 2441.8 2484.7 2491.2 78061.8 80887.6 83533.0 83690.2 90277.5 18031.9 18521.4 19059.2 19076.0 19531.6 34953.0 35006.5 35242.5 35337.8 35465.5 10695.3 10864.5 10942.7 10988.1 11130.7 9067.4 9137.6 9161.9 9295.6 9370.7 9384.4 9425.9 9559.2 9588.2 9808.6 3334.6 3384.5 3422.8 3425.2 3767.7 799.4 891.7 892.1 925.5 1021.1 20600.7 20760.4 20831.5 20982.2 21406.0 1081.1 1103.3 1105.3 1137.4 1145.5 11319.7 11389.5 11588.5 11654.0 11684.3 549191.0 554318.2 560159.0 568800.0 585570.0 59764.4 95844.0 95901.5 100076.5 100746.0 1034.1 1047.6 1049.6 1056.1 1068.3 17727.2 18030.8 18285.9 18362.7 18467.5 42653.0 43433.6 43545.0 43960.9 45562.3 58777.0 61397.7 62740.4 63118.4 63256.7 9113.2 9214.7 9223.7 9267.2 9358.6 9025.8 10186.9 10330.4 13604.5 13785.4 11335.3 11362.8 11687.9 12032.1 12278.2 12180.9 12257.4 12426.4 12429.6 13058.9 10828.4 11050.7 12244.4 12676.9 12773.8 8041.8 8408.0 8563.5 9208.3 9622.6 9870.8 12221.2 12340.4 12505.5 12612.7 04:53:56 INFO - PROCESS | 28836 | 04:53:56 INFO - PROCESS | 28836 | [#3] mootools.html Cycles:180 Average:6507.62 Median:3751.29 stddev:3732.06 (99.5%) stddev-sans-first:3723.66 04:53:56 INFO - PROCESS | 28836 | Values: 1513.6 1549.4 1726.0 1866.6 1882.7 9288.4 10149.9 10162.6 10163.3 10163.4 8533.7 8737.8 8754.3 8963.5 9823.0 2460.0 2528.7 2528.9 2554.7 2554.7 2526.5 2553.1 2554.8 2570.8 2583.1 2494.1 2496.7 2505.0 2507.5 2508.7 2435.0 2440.7 2441.9 2444.6 2451.4 2363.0 2455.6 2456.1 2476.0 2477.7 10000.1 10058.9 10083.6 10100.4 10113.7 5648.8 5869.1 5893.7 5894.6 5901.8 3564.4 3660.4 3842.2 3871.9 3878.9 1191.4 1213.7 2357.8 2374.3 2424.7 14067.6 14088.2 14116.6 14161.2 14161.5 4902.8 4923.3 4924.6 4998.1 5176.6 10958.8 10996.5 10996.6 10996.6 11008.0 5662.9 5668.4 5675.0 5677.8 5679.0 4835.3 5235.6 5272.9 5291.7 5436.0 4153.9 4243.3 4287.5 4294.1 4295.5 1243.7 1280.7 1290.1 1298.0 1308.7 1044.3 1097.1 1105.7 1115.0 1126.9 6567.3 6580.0 6618.9 6646.3 6725.0 1008.6 1051.3 1058.7 1059.7 1060.1 4526.8 4579.6 4597.2 4609.3 4614.4 12124.6 12656.1 12662.6 12668.6 12676.9 11709.4 12115.2 12170.3 12494.5 12755.9 9182.0 9326.9 9329.6 9394.1 9410.2 6399.8 6796.3 6865.9 6914.4 6935.8 11314.9 11595.2 11610.3 11644.5 11782.1 11264.8 11434.3 11452.4 11713.0 11823.7 8805.2 8821.4 8888.6 8994.7 9012.1 9584.3 9714.4 9724.4 9733.5 9737.1 8283.3 8324.9 8565.3 8701.9 8768.2 8513.8 8593.9 8694.3 8721.5 8946.4 7707.5 7823.3 7902.1 7988.6 8126.8 5814.1 5860.6 5919.3 5962.3 6022.4 5817.3 5848.9 6034.8 6074.5 6124.9 04:53:56 INFO - PROCESS | 28836 | 04:53:56 INFO - PROCESS | 28836 | [#4] prototype.html Cycles:180 Average:4684.69 Median:5205.99 stddev:1334.58 (25.6%) stddev-sans-first:1297.86 04:53:56 INFO - PROCESS | 28836 | Values: 339.5 345.7 354.4 354.8 355.6 5432.4 5484.7 5556.5 5610.4 5655.4 5463.5 5483.3 5519.1 5534.6 5540.9 4374.4 4385.6 4389.8 4390.1 4392.6 4142.7 4322.3 4347.8 4389.1 4401.4 4280.1 4290.6 4292.5 4295.9 4299.6 4283.5 4286.7 4292.4 4292.8 4297.2 3646.7 3789.3 3794.9 3808.6 3810.8 5377.2 5389.5 5395.5 5405.3 5415.4 4939.6 4939.7 4940.1 4945.9 4953.8 5483.8 5723.7 5759.3 5769.6 5782.9 4216.3 4234.7 4243.2 4248.6 4253.6 6016.4 6019.7 6033.6 6046.5 6083.9 4319.3 4354.4 4388.2 4423.2 4430.8 4419.4 4440.9 4455.2 4458.8 4459.0 4934.2 5004.1 5005.9 5010.3 5023.5 5519.9 5629.2 5782.4 5826.2 5835.9 1476.1 1515.9 1530.2 1533.9 1534.2 935.2 980.3 985.8 987.5 991.0 4879.1 4928.1 4938.3 4954.0 4974.4 5205.7 5211.2 5219.9 5221.9 5229.8 4731.2 4939.8 4957.8 4963.8 4967.4 2768.4 2772.1 2787.9 2791.3 2795.3 5410.5 5412.9 5413.0 5415.6 5424.7 5116.2 5283.4 5290.0 5305.7 5377.5 5301.0 5302.3 5321.3 5328.9 5359.8 5142.9 5146.0 5159.9 5226.8 5270.8 4924.2 5184.2 5185.9 5186.1 5217.7 5369.4 5370.8 5376.4 5389.9 5429.9 3966.8 3972.3 3974.0 3976.7 3996.5 4998.9 5141.4 5206.3 5737.3 5849.1 5762.8 5786.3 5790.8 5793.8 5878.7 5790.6 5798.2 5800.5 5804.6 5804.7 5583.8 5762.5 5788.5 5799.4 5814.7 5830.3 5836.0 5850.4 5854.9 5882.2 5614.7 5660.8 5694.0 5708.7 5715.3 04:53:56 INFO - PROCESS | 28836 | 04:53:56 INFO - PROCESS | 28836 | [#5] yui.html Cycles:180 Average:22319.35 Median:4461.85 stddev:31322.69 (702.0%) stddev-sans-first:31369.09 04:53:56 INFO - PROCESS | 28836 | Values: 853.7 872.4 884.0 896.0 933.1 16060.0 17765.9 17814.5 17833.3 17842.9 15935.4 15978.2 16563.2 17071.7 17186.1 742.6 883.4 890.4 906.1 907.8 896.8 900.8 904.9 907.6 907.6 917.8 919.0 919.1 919.4 919.6 782.6 912.5 912.7 913.2 917.1 690.6 695.4 695.5 695.7 695.9 16739.2 16761.5 16773.2 16783.1 16805.8 15200.6 15345.6 15371.9 15449.0 15544.4 14485.2 14578.9 14671.9 14708.3 14769.3 4614.8 4730.1 4775.3 4843.4 4847.1 90628.5 91423.0 92591.0 92657.5 92717.5 12473.4 12490.6 12573.7 12823.9 12990.6 22866.9 22867.3 22877.0 22901.3 22914.4 8669.0 8725.8 8779.2 8976.0 9050.6 4964.4 5007.4 5019.9 5073.1 5080.5 4127.2 4294.2 4298.5 4305.1 4308.9 718.9 721.2 737.7 738.3 747.1 719.3 749.6 750.5 773.7 783.2 10619.6 10725.0 10997.1 11046.7 11286.3 706.8 772.4 773.2 780.2 814.3 5260.8 5337.8 5490.9 5540.3 5603.4 107057.0 107479.0 111633.4 132597.3 134674.0 64867.7 88369.0 96025.5 97453.0 101391.0 85507.6 85750.6 90855.5 94885.5 96227.0 10612.7 11002.0 11073.4 11249.4 11375.3 48504.5 56469.0 57957.7 58163.0 58269.1 78257.6 81136.0 84869.0 85090.1 86727.6 12119.9 12968.5 12971.9 13321.3 13525.3 15685.1 15933.8 16384.7 16752.0 16770.0 11992.9 12437.8 14381.7 15022.8 15240.4 14508.8 14668.5 15533.2 15998.6 16002.4 15063.7 15181.9 15273.3 15342.5 15821.4 12318.4 12612.0 12746.6 12801.2 12886.2 12544.2 12682.7 12974.2 13065.5 13104.9 04:53:56 INFO - PROCESS | 28836 | -------- Summary: end -------- 04:53:56 INFO - PROCESS | 28836 | 04:53:57 INFO - TEST-INFO | 28836: exit 0 04:53:57 INFO - TEST-OK | dromaeo_css | took 1113585ms 04:53:57 INFO - TEST-START | kraken 04:53:57 INFO - operating with platform_type : linux_ 04:53:57 INFO - Initialising browser for kraken test... 04:53:57 INFO - TEST-INFO | started process 29034 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp961xib/profile http://localhost:59289/getInfo.html) 04:54:01 INFO - PROCESS | 29034 | __metrics Screen width/height:1600/1200 04:54:01 INFO - PROCESS | 29034 | colorDepth:24 04:54:01 INFO - PROCESS | 29034 | Browser inner width/height: 1024/697 04:54:01 INFO - PROCESS | 29034 | __metrics 04:54:02 INFO - PROCESS | 29034 | 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:54:02 INFO - PROCESS | 29034 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 04:54:03 INFO - TEST-INFO | 29034: exit 0 04:54:03 INFO - Browser initialized. 04:54:03 INFO - Running cycle 1/1 for kraken test... 04:54:03 INFO - TEST-INFO | started process 29120 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp961xib/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:54:14 INFO - PROCESS | 29120 | RSS: Main: 145604608 04:54:14 INFO - PROCESS | 29120 | 04:54:17 INFO - PROCESS | 29120 | Cycle 1(1): loaded http://localhost:59289/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:59289/tests/kraken/driver.html?testName=audio-beat-detection) 04:54:18 INFO - PROCESS | 29120 | RSS: Main: 191229952 04:54:18 INFO - PROCESS | 29120 | 04:54:20 INFO - PROCESS | 29120 | Cycle 1(1): loaded http://localhost:59289/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:59289/tests/kraken/driver.html?testName=audio-dft) 04:54:21 INFO - PROCESS | 29120 | RSS: Main: 181194752 04:54:21 INFO - PROCESS | 29120 | 04:54:24 INFO - PROCESS | 29120 | Cycle 1(1): loaded http://localhost:59289/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:59289/tests/kraken/driver.html?testName=audio-fft) 04:54:24 INFO - PROCESS | 29120 | RSS: Main: 269295616 04:54:24 INFO - PROCESS | 29120 | 04:54:26 INFO - PROCESS | 29120 | Cycle 1(1): loaded http://localhost:59289/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:59289/tests/kraken/driver.html?testName=audio-oscillator) 04:54:27 INFO - PROCESS | 29120 | RSS: Main: 175337472 04:54:27 INFO - PROCESS | 29120 | 04:54:29 INFO - PROCESS | 29120 | Cycle 1(1): loaded http://localhost:59289/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:59289/tests/kraken/driver.html?testName=imaging-gaussian-blur) 04:54:30 INFO - PROCESS | 29120 | RSS: Main: 176603136 04:54:30 INFO - PROCESS | 29120 | 04:54:33 INFO - PROCESS | 29120 | Cycle 1(1): loaded http://localhost:59289/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:59289/tests/kraken/driver.html?testName=imaging-darkroom) 04:54:33 INFO - PROCESS | 29120 | RSS: Main: 212664320 04:54:33 INFO - PROCESS | 29120 | 04:54:37 INFO - PROCESS | 29120 | Cycle 1(1): loaded http://localhost:59289/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:59289/tests/kraken/driver.html?testName=imaging-desaturate) 04:54:37 INFO - PROCESS | 29120 | RSS: Main: 211460096 04:54:37 INFO - PROCESS | 29120 | 04:54:40 INFO - PROCESS | 29120 | Cycle 1(1): loaded http://localhost:59289/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:59289/tests/kraken/driver.html?testName=json-parse-financial) 04:54:41 INFO - PROCESS | 29120 | RSS: Main: 211230720 04:54:41 INFO - PROCESS | 29120 | 04:54:42 INFO - PROCESS | 29120 | Cycle 1(1): loaded http://localhost:59289/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:59289/tests/kraken/driver.html?testName=json-stringify-tinderbox) 04:54:43 INFO - PROCESS | 29120 | RSS: Main: 175738880 04:54:43 INFO - PROCESS | 29120 | 04:54:44 INFO - PROCESS | 29120 | Cycle 1(1): loaded http://localhost:59289/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:59289/tests/kraken/driver.html?testName=stanford-crypto-aes) 04:54:45 INFO - PROCESS | 29120 | RSS: Main: 182116352 04:54:45 INFO - PROCESS | 29120 | 04:54:47 INFO - PROCESS | 29120 | Cycle 1(1): loaded http://localhost:59289/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:59289/tests/kraken/driver.html?testName=stanford-crypto-ccm) 04:54:48 INFO - PROCESS | 29120 | RSS: Main: 186040320 04:54:48 INFO - PROCESS | 29120 | 04:54:50 INFO - PROCESS | 29120 | Cycle 1(1): loaded http://localhost:59289/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:59289/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 04:54:51 INFO - PROCESS | 29120 | RSS: Main: 181067776 04:54:51 INFO - PROCESS | 29120 | 04:54:53 INFO - PROCESS | 29120 | Cycle 1(1): loaded http://localhost:59289/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:59289/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 04:54:54 INFO - PROCESS | 29120 | RSS: Main: 184107008 04:54:54 INFO - PROCESS | 29120 | 04:54:55 INFO - PROCESS | 29120 | Cycle 1(1): loaded http://localhost:59289/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:59289/tests/kraken/driver.html?testName=ai-astar) 04:54:56 INFO - PROCESS | 29120 | RSS: Main: 235835392 04:54:56 INFO - PROCESS | 29120 | 04:54:56 INFO - PROCESS | 29120 | __start_tp_report 04:54:56 INFO - PROCESS | 29120 | _x_x_mozilla_page_load 04:54:56 INFO - PROCESS | 29120 | _x_x_mozilla_page_load_details 04:54:56 INFO - PROCESS | 29120 | |i|pagename|runs| 04:54:56 INFO - PROCESS | 29120 | |0;ai-astar;99;111;110;109;110;109;111;109;111;109 04:54:56 INFO - PROCESS | 29120 | |1;audio-beat-detection;137;115;115;170;115;121;152;113;157;116 04:54:56 INFO - PROCESS | 29120 | |2;audio-dft;160;160;161;160;159;161;160;160;159;162 04:54:56 INFO - PROCESS | 29120 | |3;audio-fft;76;74;74;114;75;76;75;75;102;75 04:54:56 INFO - PROCESS | 29120 | |4;audio-oscillator;83;83;132;83;82;83;123;83;83;122 04:54:56 INFO - PROCESS | 29120 | |5;imaging-gaussian-blur;117;116;116;116;117;117;117;117;116;116 04:54:56 INFO - PROCESS | 29120 | |6;imaging-darkroom;147;146;145;146;147;145;146;146;146;147 04:54:56 INFO - PROCESS | 29120 | |7;imaging-desaturate;94;95;94;95;94;94;95;95;95;94 04:54:56 INFO - PROCESS | 29120 | |8;json-parse-financial;49;49;49;49;49;49;51;48;50;50 04:54:56 INFO - PROCESS | 29120 | |9;json-stringify-tinderbox;59;60;58;58;60;59;57;59;60;59 04:54:56 INFO - PROCESS | 29120 | |10;stanford-crypto-aes;72;72;81;73;72;72;81;74;72;72 04:54:56 INFO - PROCESS | 29120 | |11;stanford-crypto-ccm;118;116;116;120;115;116;115;123;149;136 04:54:56 INFO - PROCESS | 29120 | |12;stanford-crypto-pbkdf2;149;150;155;154;155;151;152;155;150;148 04:54:56 INFO - PROCESS | 29120 | |13;stanford-crypto-sha256-iterative;62;63;62;61;64;60;61;61;62;59 04:54:56 INFO - PROCESS | 29120 | __end_tp_report 04:54:56 INFO - PROCESS | 29120 | __start_cc_report 04:54:56 INFO - PROCESS | 29120 | _x_x_mozilla_cycle_collect,1658 04:54:56 INFO - PROCESS | 29120 | __end_cc_report 04:54:56 INFO - PROCESS | 29120 | __startTimestamp1472385296159__endTimestamp 04:54:56 INFO - PROCESS | 29120 | 04:54:56 INFO - PROCESS | 29120 | ------- Summary: start ------- 04:54:56 INFO - PROCESS | 29120 | Number of tests: 14 04:54:56 INFO - PROCESS | 29120 | 04:54:56 INFO - PROCESS | 29120 | [#0] ai-astar Cycles:10 Average:108.80 Median:110.50 stddev:3.55 (3.2%) stddev-sans-first:0.93 04:54:56 INFO - PROCESS | 29120 | Values: 99.0 111.0 110.0 109.0 110.0 109.0 111.0 109.0 111.0 109.0 04:54:56 INFO - PROCESS | 29120 | 04:54:56 INFO - PROCESS | 29120 | [#1] audio-beat-detection Cycles:10 Average:131.10 Median:129.00 stddev:21.32 (16.5%) stddev-sans-first:22.51 04:54:56 INFO - PROCESS | 29120 | Values: 137.0 115.0 115.0 170.0 115.0 121.0 152.0 113.0 157.0 116.0 04:54:56 INFO - PROCESS | 29120 | 04:54:56 INFO - PROCESS | 29120 | [#2] audio-dft Cycles:10 Average:160.20 Median:160.00 stddev:0.92 (0.6%) stddev-sans-first:0.97 04:54:56 INFO - PROCESS | 29120 | Values: 160.0 160.0 161.0 160.0 159.0 161.0 160.0 160.0 159.0 162.0 04:54:56 INFO - PROCESS | 29120 | 04:54:56 INFO - PROCESS | 29120 | [#3] audio-fft Cycles:10 Average:81.60 Median:75.00 stddev:14.21 (19.0%) stddev-sans-first:14.93 04:54:56 INFO - PROCESS | 29120 | Values: 76.0 74.0 74.0 114.0 75.0 76.0 75.0 75.0 102.0 75.0 04:54:56 INFO - PROCESS | 29120 | 04:54:56 INFO - PROCESS | 29120 | [#4] audio-oscillator Cycles:10 Average:95.70 Median:83.00 stddev:20.84 (25.1%) stddev-sans-first:21.60 04:54:56 INFO - PROCESS | 29120 | Values: 83.0 83.0 132.0 83.0 82.0 83.0 123.0 83.0 83.0 122.0 04:54:56 INFO - PROCESS | 29120 | 04:54:56 INFO - PROCESS | 29120 | [#5] imaging-gaussian-blur Cycles:10 Average:116.50 Median:117.00 stddev:0.53 (0.5%) stddev-sans-first:0.53 04:54:56 INFO - PROCESS | 29120 | Values: 117.0 116.0 116.0 116.0 117.0 117.0 117.0 117.0 116.0 116.0 04:54:56 INFO - PROCESS | 29120 | 04:54:56 INFO - PROCESS | 29120 | [#6] imaging-darkroom Cycles:10 Average:146.10 Median:146.00 stddev:0.74 (0.5%) stddev-sans-first:0.71 04:54:56 INFO - PROCESS | 29120 | Values: 147.0 146.0 145.0 146.0 147.0 145.0 146.0 146.0 146.0 147.0 04:54:56 INFO - PROCESS | 29120 | 04:54:56 INFO - PROCESS | 29120 | [#7] imaging-desaturate Cycles:10 Average:94.50 Median:95.00 stddev:0.53 (0.6%) stddev-sans-first:0.53 04:54:56 INFO - PROCESS | 29120 | Values: 94.0 95.0 94.0 95.0 94.0 94.0 95.0 95.0 95.0 94.0 04:54:56 INFO - PROCESS | 29120 | 04:54:56 INFO - PROCESS | 29120 | [#8] json-parse-financial Cycles:10 Average:49.30 Median:49.00 stddev:0.82 (1.7%) stddev-sans-first:0.87 04:54:56 INFO - PROCESS | 29120 | Values: 49.0 49.0 49.0 49.0 49.0 49.0 51.0 48.0 50.0 50.0 04:54:56 INFO - PROCESS | 29120 | 04:54:56 INFO - PROCESS | 29120 | [#9] json-stringify-tinderbox Cycles:10 Average:58.90 Median:59.00 stddev:0.99 (1.7%) stddev-sans-first:1.05 04:54:56 INFO - PROCESS | 29120 | Values: 59.0 60.0 58.0 58.0 60.0 59.0 57.0 59.0 60.0 59.0 04:54:56 INFO - PROCESS | 29120 | 04:54:56 INFO - PROCESS | 29120 | [#10] stanford-crypto-aes Cycles:10 Average:74.10 Median:72.50 stddev:3.70 (5.1%) stddev-sans-first:3.84 04:54:56 INFO - PROCESS | 29120 | Values: 72.0 72.0 81.0 73.0 72.0 72.0 81.0 74.0 72.0 72.0 04:54:56 INFO - PROCESS | 29120 | 04:54:56 INFO - PROCESS | 29120 | [#11] stanford-crypto-ccm Cycles:10 Average:122.40 Median:119.00 stddev:11.31 (9.5%) stddev-sans-first:11.88 04:54:56 INFO - PROCESS | 29120 | Values: 118.0 116.0 116.0 120.0 115.0 116.0 115.0 123.0 149.0 136.0 04:54:56 INFO - PROCESS | 29120 | 04:54:56 INFO - PROCESS | 29120 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:151.90 Median:153.00 stddev:2.69 (1.8%) stddev-sans-first:2.64 04:54:56 INFO - PROCESS | 29120 | Values: 149.0 150.0 155.0 154.0 155.0 151.0 152.0 155.0 150.0 148.0 04:54:56 INFO - PROCESS | 29120 | 04:54:56 INFO - PROCESS | 29120 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:61.50 Median:62.00 stddev:1.43 (2.3%) stddev-sans-first:1.51 04:54:56 INFO - PROCESS | 29120 | Values: 62.0 63.0 62.0 61.0 64.0 60.0 61.0 61.0 62.0 59.0 04:54:56 INFO - PROCESS | 29120 | -------- Summary: end -------- 04:54:56 INFO - PROCESS | 29120 | 04:54:57 INFO - TEST-INFO | 29120: exit 0 04:54:57 INFO - TEST-OK | kraken | took 59925ms 04:54:57 INFO - SUITE-END | took 1173s 04:54:57 INFO - Completed test suite (00:19:33) 04:54:57 INFO - javascript benchmark 04:54:57 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3266.1671273825173, 3272.6563710810365, 3287.2275015371383, 3292.588426446694, 3323.3519463704574, 5681.517071048961, 5732.885342293157, 5754.597178197528, 5769.913451298228, 5775.797847075353, 5763.798267060653, 5810.070388737803, 5822.621529600573, 5826.854328641786, 5832.445917637829, 5149.871253218662, 5206.869828254305, 5250.816221432251, 5307.893842123155, 5315.548178404821, 5287.656302340355, 5344.144936810111, 5346.893062138755, 5347.171188465794, 5348.518633323, 5242.135047717122, 5276.182191760283, 5288.418273989858, 5294.470552944694, 5346.064438723208, 5362.678239305635, 5372.167314066326, 5381.273528073699, 5381.596380271456, 5381.973090134564, 5122.154844450662, 5132.101882170643, 5162.5095615916425, 5275.630705850592, 5286.709230992294, 5736.713164341766, 5740.08158694611, 5746.051901436258, 5776.682282474462, 5808.912866307009, 6218.844528886769, 6229.781957631507, 6236.937630623726, 6242.0, 6247.562670613059, 6116.969415152896, 6162.383761623847, 6169.660668663222, 6254.093156492283, 6268.749250030039, 5834.154047663107, 5848.502877255439, 5858.707064646751, 5862.472377485961, 5864.677442740647, 6111.174991376196, 6407.551471397051, 6417.518686098468, 6420.871582568322, 6421.678916054272, 6032.245969253843, 6039.758409663652, 6069.423404776539, 6109.7387008107025, 6112.358202388774, 6193.628382297076, 6196.0, 6200.627962322275, 6204.038374052029, 6207.162033125561, 5903.232579764689, 6178.289496707936, 6188.44304012641, 6192.81421557354, 6206.534509911775, 6094.603850749687, 6101.237345331833, 6138.815835524942, 6192.380761923771, 6194.473469755121, 4683.461401938753, 4713.080949214938, 4718.551737584924, 4740.928886066712, 4741.146593613183, 4118.5057793064425, 4219.6202341789385, 4231.4710661167355, 4243.087736136684, 4243.745375277494, 5873.9412605873395, 5925.34821169671, 5937.2578427696535, 5977.372375900555, 6011.939880601226, 6276.372362763778, 6277.027060805583, 6278.277998030194, 6284.151639528696, 6286.9056964145575, 5744.138379243147, 5969.582129250911, 5969.731362088716, 5970.462134332906, 5974.970125149433, 5112.105381558287, 5176.663516871391, 5176.689398636094, 5216.37403511581, 5270.051390749625, 6359.268684101387, 6362.0, 6400.295967443578, 6408.359164083555, 6410.967945160244, 6090.451859332681, 6195.504359651307, 6198.752049917953, 6203.0, 6206.844828879314, 6329.525285603506, 6367.363263673781, 6400.583962042452, 6418.839529011812, 6476.125723027331, 6081.513478921587, 6094.878102438015, 6105.14527966094, 6112.786052488142, 6119.143319935123, 6000.21997140369, 6205.6586887721605, 6225.31521532622, 6230.813075607739, 6235.033569796688, 5969.432903874125, 6012.0, 6050.2437195350585, 6109.236345456818, 6138.907916381348, 6009.669468179292, 6042.214512113573, 6062.090686397076, 6065.3934606539, 6065.8180254592435, 5858.238429004202, 5978.8206353809455, 6020.819375418744, 6034.577579569388, 6040.909386359121, 5945.34601193877, 6029.336772955059, 6035.094735789667, 6083.6653984029535, 6127.571070025056, 5950.702464876826, 5957.374475680164, 5958.463738263577, 5960.672163031075, 5963.373845746308], "value": 5707.067028365528, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [620.8820324138413, 622.4024936061381, 668.4585485756536, 668.7458765669045, 680.7106979533697, 39077.21845563091, 40032.79983600081, 50005.999999999956, 50803.74598127004, 51020.48979510204, 51786.22320665193, 52364.214536781976, 52487.0, 52492.0, 52517.737411312984, 8653.177948094952, 9405.529723513831, 9564.617415303379, 9570.234381249484, 9572.425654460754, 10969.725756856102, 11301.660950171467, 11319.264247823907, 11660.883391166108, 11664.7667046659, 11555.133364997659, 11624.593139240129, 11625.593104241354, 11658.417079146051, 11674.299542027506, 11416.600418985337, 11450.885491145065, 11487.65537033882, 11490.540378384896, 11491.42542872853, 11227.157963152715, 11265.60570380041, 11288.492017859217, 11292.887071129266, 11294.265872718248, 48503.0, 48774.26838597424, 48928.26607600889, 48949.75525122387, 48952.0, 3942.7831469269177, 3987.3819557968563, 4580.862574122782, 4708.270218116198, 4724.95275047252, 36523.0, 36529.45205821915, 36605.81697091497, 37099.81450092732, 38896.41655375172, 4578.061497393026, 4668.229742092551, 4855.32025516428, 4865.464798872121, 4906.288588154697, 403279.0, 404196.0, 405522.0, 406371.0, 408863.0, 39628.40557391642, 40529.59470405258, 70763.64618176877, 71598.0, 71694.0, 41691.583084169375, 41794.79102604467, 41966.37050444246, 42014.57985420168, 42045.579544204775, 17555.29778808859, 17755.201015954313, 17799.288028478717, 17800.0, 17812.198451069733, 12031.0, 14471.49349772753, 14917.552473425814, 15012.924935375253, 15119.924400377928, 9399.530023498797, 9416.95291523538, 9418.858717119248, 9426.198773104363, 9494.335396522178, 3554.733394995386, 3556.78659280444, 3575.606683264839, 3604.13500759821, 3611.981940090283, 1082.875469321038, 1105.3147048829776, 1112.527175950218, 1113.8663360396804, 1155.1047937848066, 35400.0, 36362.63637363645, 36560.0, 36704.0, 36735.08162295964, 1180.2859270141612, 1192.493190394079, 1248.4881198708485, 1262.5265525427965, 1277.2655722959335, 18801.15394807237, 18844.623107538046, 18972.14625341863, 18982.71525927112, 19164.425067248005, 593704.0, 595748.0, 596979.0, 599242.0, 606192.0, 66675.33324666691, 67229.0, 67317.30730770715, 67339.66330168318, 67719.0, 1374.271636032904, 1446.2696338349067, 1478.977815332771, 1498.3107770425509, 1511.04803973496, 35116.82441587776, 37328.813355933045, 37377.4393384094, 37474.8126259367, 38060.0, 57259.42740572541, 63635.681821590595, 65941.0, 66053.0, 71241.6437917807, 588046.0, 588292.0, 590565.0, 590715.0, 591868.0, 28759.856200718863, 29224.56163157597, 29341.26646833846, 30815.69184308218, 50556.24165637445, 50921.74539127281, 51006.48993510017, 51500.0, 51515.48484515107, 52255.73872130615, 43023.0, 43598.346024810286, 43873.0, 44537.10925781531, 44948.325775114055, 43835.12329753323, 45027.774861126796, 45158.0, 45908.311375330064, 46192.53807462017, 42994.0, 43323.0, 43814.34278485889, 44637.776811115735, 44817.551824481336, 36710.63289367072, 38943.610563894, 38976.0, 39009.69256171953, 39718.0, 28809.711902880703, 29213.7078629211, 29287.12138635844, 31743.52384714185, 32817.34365312728], "value": 21633.206953774745, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [400.15367497742284, 411.36238829813783, 424.17286291731125, 430.1354277901418, 443.86018404202673, 15598.376064957401, 20690.172393104258, 20963.16147354106, 21371.03830327635, 21376.0, 17142.742858857127, 17198.828011719877, 17206.655866882687, 17248.56878578036, 17352.132393380343, 4354.412154359168, 5678.971605141968, 5877.2947246330505, 5892.086726557391, 6394.296627370985, 6524.641144737037, 6650.5012124090645, 6680.899786503206, 6766.221884483273, 6766.526343155981, 6469.914895734823, 6509.0, 6530.2816690164045, 6530.44491218247, 6536.248331441874, 6352.872942541123, 6545.116409284733, 6588.0, 6629.9668501657425, 6630.171228596426, 5879.706014699291, 5884.029135192731, 5884.235049443545, 5885.058390657517, 5886.175935369051, 24717.50564988708, 24793.752062479325, 24798.0, 24799.5040099197, 24832.875835620885, 2642.4186678930605, 2668.2929023808706, 2735.931601709953, 2823.618811460447, 3037.6202974628172, 14899.925500372428, 15704.685906281924, 15836.920815395964, 15964.281607327699, 16045.839541604551, 2085.343116918166, 2222.5665995130958, 2441.816863735209, 2484.714257860351, 2491.190363131989, 78061.82907256339, 80887.59556202182, 83533.0, 83690.16309836885, 90277.54861225652, 18031.909840450975, 18521.444356669323, 19059.237630494627, 19076.0, 19531.609367812565, 34953.0, 35006.47490287648, 35242.47136292957, 35337.823310883796, 35465.46801797975, 10695.251332406657, 10864.456777161111, 10942.671719848417, 10988.120950323955, 11130.664320281618, 9067.410618309788, 9137.63449462014, 9161.862572061424, 9295.605659151046, 9370.67202647904, 9384.390014649027, 9425.858612120825, 9559.187469065208, 9588.232941364675, 9808.607655693835, 3334.583177102862, 3384.526166336715, 3422.7775194612273, 3425.2122011936895, 3767.7362584618813, 799.3724925933114, 891.7057371067532, 892.1023580542774, 925.509479975614, 1021.065724861758, 20600.690989635168, 20760.377188684364, 20831.479213019797, 20982.160713571287, 21406.0, 1081.1404933078222, 1103.2553026706942, 1105.2926127278547, 1137.4028634966544, 1145.473082382097, 11319.717007074725, 11389.487473063731, 11588.536458541565, 11654.0, 11684.298942063333, 549191.0, 554318.2215125095, 560159.0, 568800.0, 585570.0, 59764.402355975886, 95843.9779425375, 95901.52049239849, 100076.49885251727, 100746.0, 1034.0900007993068, 1047.5914393386433, 1049.5935446501724, 1056.1286938275953, 1068.2628985999636, 17727.202275897358, 18030.819691802913, 18285.908570457064, 18362.72455913189, 18467.538311542317, 42653.0, 43433.565664342954, 43545.0, 43960.853079148066, 45562.31656525088, 58777.0, 61397.693011534655, 62740.37259627345, 63118.368816311246, 63256.68371658112, 9113.225375842863, 9214.677486287948, 9223.723288301362, 9267.165955064076, 9358.625654973725, 9025.774355641162, 10186.933111517414, 10330.380177189392, 13604.455821767018, 13785.448582056606, 11335.263207891461, 11362.829557556808, 11687.88312116868, 12032.09759268041, 12278.201916875374, 12180.878191217975, 12257.387130643254, 12426.378681066091, 12429.564965226175, 13058.934705326412, 10828.350298982086, 11050.723731906766, 12244.387780611112, 12676.87323126757, 12773.808392873929, 8041.798955025937, 8407.95796021016, 8563.529005904735, 9208.263338932735, 9622.615095396106, 9870.753231169278, 12221.205621634565, 12340.382980851102, 12505.499780008699, 12612.684682883002], "value": 10592.698089981746, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1513.591330340808, 1549.418967887042, 1725.9568510787228, 1866.6360059788346, 1882.6611209982207, 9288.44269343839, 10149.949250253756, 10162.64430744923, 10163.339382940103, 10163.390196588201, 8533.743987680364, 8737.825243495126, 8754.343424243176, 8963.46219226847, 9822.950885245564, 2459.9877000615015, 2528.683914510686, 2528.88620012099, 2554.706208785995, 2554.7189809120982, 2526.5325914705745, 2553.144696526666, 2554.8211625186245, 2570.807189460789, 2583.147561304765, 2494.1395218649627, 2496.7128780190237, 2504.9749502505015, 2507.4608959073794, 2508.686414198225, 2434.9513009739794, 2440.731519532859, 2441.9389515262174, 2444.633305004246, 2451.3871532116973, 2362.976370236293, 2455.6316552517087, 2456.1280745335325, 2476.0343466048257, 2477.6531285620026, 10000.149987251092, 10058.949705251427, 10083.647072352433, 10100.444475553842, 10113.69658910234, 5648.774049038034, 5869.148973398833, 5893.734781934823, 5894.58737888348, 5901.763929442817, 3564.429691249388, 3660.396034654279, 3842.1931394407206, 3871.8644847430205, 3878.8836334909715, 1191.350713860948, 1213.708709909624, 2357.834951553392, 2374.25211058516, 2424.7090349158216, 14067.577972660836, 14088.225147616877, 14116.647083822883, 14161.22113283769, 14161.504347347896, 4902.823322402598, 4923.261510773341, 4924.556789888928, 4998.125328067574, 5176.559992400613, 10958.835617465744, 10996.450177491093, 10996.560137594406, 10996.560137594406, 11008.0, 5662.943370566324, 5668.404817494186, 5674.971625141848, 5677.77288908439, 5679.0, 4835.347228124228, 5235.633505654644, 5272.92090618641, 5291.708956007379, 5436.0, 4153.937690934638, 4243.257429949746, 4287.506936702319, 4294.098239369768, 4295.548967358445, 1243.6517775023008, 1280.6734282757836, 1290.1098242212843, 1297.9805302920463, 1308.6793735534663, 1044.2690116918188, 1097.1113398147527, 1105.7069876482738, 1114.9944250278697, 1126.9492872820742, 6567.310432404529, 6580.0, 6618.86762264752, 6646.335366463411, 6725.0, 1008.5713571732131, 1051.332403923514, 1058.7353161709573, 1059.6715018344337, 1060.0883240413239, 4526.841560545365, 4579.610733087725, 4597.195490789099, 4609.331646911246, 4614.353990441341, 12124.575639852563, 12656.050796190322, 12662.620121396372, 12668.619941401772, 12676.873231267753, 11709.414529273503, 12115.15193936416, 12170.330631815335, 12494.50021999128, 12755.93622031884, 9182.0, 9326.860097098548, 9329.580168892417, 9394.060593940689, 9410.247180225566, 6399.776007839703, 6796.286389928986, 6865.897011544931, 6914.446844252348, 6935.757248496278, 11314.943425282821, 11595.246308989888, 11610.303381797119, 11644.5342186315, 11782.057435404975, 11264.83102753443, 11434.313941163557, 11452.370119643167, 11713.0, 11823.704407389883, 8805.16350946659, 8821.382503224713, 8888.644454222018, 8994.730158095268, 9012.098790120936, 9584.28117891175, 9714.417134971924, 9724.416535007922, 9733.513324333897, 9737.07497787709, 8283.337333013224, 8324.875126872981, 8565.27195188416, 8701.869471958053, 8768.167024132952, 8513.787155321166, 8593.91406085956, 8694.260987816106, 8721.520316382659, 8946.37375383717, 7707.460477766503, 7823.335016523659, 7902.051753789582, 7988.560629165451, 8126.796830079295, 5814.098814683731, 5860.619059761102, 5919.260092488439, 5962.25471816023, 6022.36765139654, 5817.301923769175, 5848.941510584839, 6034.758609655564, 6074.483668888017, 6124.938750612615], "value": 5209.633615400893, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [339.45347989736536, 345.70614977269315, 354.37452895639194, 354.7605366377696, 355.57331202556907, 5432.429594892538, 5484.698341591211, 5556.527695145913, 5610.382857885635, 5655.359945615772, 5463.470228336072, 5483.259759932408, 5519.089350257203, 5534.568567488153, 5540.9168862466995, 4374.365716971038, 4385.583369579901, 4389.758563279019, 4390.121975604876, 4392.626626736731, 4142.71001029928, 4322.308430651096, 4347.826086956518, 4389.122175564884, 4401.427814384126, 4280.143971205771, 4290.635295999844, 4292.527821939584, 4295.914081718363, 4299.591538803824, 4283.464566929134, 4286.678499112548, 4292.39906413104, 4292.785360731951, 4297.183535128315, 3646.6535679110443, 3789.290657180102, 3794.9430758538642, 3808.619138086169, 3810.79040652764, 5377.220303056035, 5389.51494365509, 5395.541378982752, 5405.270288511079, 5415.431379705153, 4939.5554400104165, 4939.678351174942, 4940.134721861312, 4945.873274491967, 4953.786322351011, 5483.7806487740445, 5723.656580605177, 5759.308882934075, 5769.596128271022, 5782.884342313172, 4216.3464662976985, 4234.724742891703, 4243.172581346638, 4248.553901840293, 4253.553376895444, 6016.368281330441, 6019.729112189962, 6033.577649564532, 6046.516278697738, 6083.878322433527, 4319.265724826787, 4354.433923589976, 4388.210122177975, 4423.159599676052, 4430.803683005571, 4419.403380543586, 4440.866773996785, 4455.1535208310315, 4458.799354029076, 4459.0, 4934.235193545006, 5004.1492946198505, 5005.874853128628, 5010.273510341053, 5023.522765337287, 5519.9448005520235, 5629.24005259293, 5782.421757824268, 5826.1552074948895, 5835.854103647443, 1476.0553245922617, 1515.886308526865, 1530.1660594975765, 1533.8619524242872, 1534.240550927298, 935.1723724503863, 980.294188184506, 985.8373368394278, 987.481572174614, 990.9653162139291, 4879.072976134523, 4928.117051524827, 4938.308636790852, 4954.0, 4974.37820272466, 5205.6616319939085, 5211.1922651989, 5219.895602087937, 5221.929504451578, 5229.843104706865, 4731.243001119804, 4939.802407903716, 4957.752112394366, 4963.759060234941, 4967.379077615298, 2768.3771151490755, 2772.126780064273, 2787.8884844605986, 2791.3300807806386, 2795.259256297083, 5410.48600382963, 5412.945870541323, 5412.971535408338, 5415.6479828811, 5424.701641409761, 5116.234898959853, 5283.417240572228, 5290.0, 5305.681659100466, 5377.489138531833, 5301.0, 5302.310699609026, 5321.281626980308, 5328.946710532922, 5359.839204823861, 5142.8971420572125, 5145.993062705473, 5159.896802063862, 5226.764795584132, 5270.762815673381, 4924.178054119999, 5184.170532714747, 5185.948140518546, 5186.06650802844, 5217.686938783685, 5369.3556773187465, 5370.812021579226, 5376.381716102698, 5389.946100538945, 5429.945700543102, 3966.7619942803526, 3972.3445631470486, 3973.9931334675803, 3976.7415118018394, 3996.460477835455, 4998.850034498971, 5141.38303403579, 5206.323177986837, 5737.254156959569, 5849.128278950865, 5762.798302059407, 5786.276715410574, 5790.768369265183, 5793.826185214451, 5878.676672782867, 5790.565707571865, 5798.217240672624, 5800.477956983892, 5804.622699524517, 5804.6807425592, 5583.804566840141, 5762.481376676119, 5788.507976821849, 5799.391063938395, 5814.703192238957, 5830.329512105993, 5835.9708201458725, 5850.356460789395, 5854.853628659318, 5882.205902203148, 5614.6631202128, 5660.848877426716, 5694.0, 5708.65748055118, 5715.342735585295], "value": 4266.2217102435225, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [853.7310747114658, 872.3936863879604, 884.0452311503577, 895.9552022398879, 933.1368484152159, 16060.0, 17765.91117044413, 17814.465566033006, 17833.28666853326, 17842.91078544607, 15935.44225952092, 15978.201089945485, 16563.17184140794, 17071.658566828657, 17186.05476698781, 742.6027075514601, 883.3639779358851, 890.4345740454809, 906.0893801729253, 907.7866701325216, 896.7578753736487, 900.8333458310201, 904.9095090490931, 907.6097278170384, 907.63694522191, 917.8256131335058, 918.9586468608928, 919.071737545081, 919.3840127114815, 919.5769945824895, 782.581318994341, 912.4935660708304, 912.6988093928987, 913.1644545241096, 917.0645941140067, 690.6167077272112, 695.3845846425899, 695.4818660098208, 695.7390978383106, 695.9234484206734, 16739.16304184798, 16761.497155085366, 16773.161341932853, 16783.076930768802, 16805.83194168055, 15200.619984500365, 15345.616359590987, 15371.923140384226, 15449.0, 15544.378224870992, 14485.203313817736, 14578.854211457856, 14671.853281467156, 14708.264586770618, 14769.261536923112, 4614.838480653161, 4730.101280756679, 4775.307580400857, 4843.4187897452175, 4847.103285892122, 90628.54685726529, 91423.0, 92591.0, 92657.53671231668, 92717.53641231818, 12473.438695258736, 12490.625281241577, 12573.748525029449, 12823.93588032054, 12990.610281691565, 22866.885665571564, 22867.31398058061, 22877.0, 22901.31296061121, 22914.427139321317, 8669.046404895456, 8725.825483490295, 8779.20987111163, 8975.955120224357, 9050.592723327467, 4964.429090654548, 5007.424146223231, 5019.949800502021, 5073.086844367976, 5080.542751152414, 4127.195196936628, 4294.162638285567, 4298.548652391516, 4305.07440900208, 4308.870733877989, 718.9460790440738, 721.1922646635696, 737.6791095873313, 738.2764890407431, 747.0922828763079, 719.2735337309251, 749.583980890611, 750.5271678842404, 773.7369294439804, 783.2441693765544, 10619.575216991234, 10725.0, 10997.12023038155, 11046.668599942015, 11286.266392684449, 706.7879636109146, 772.3936709682872, 773.21132444907, 780.2431641307923, 814.2590242878952, 5260.815871444481, 5337.839864804062, 5490.864337782824, 5540.335159780771, 5603.355614104348, 107057.0, 107479.0, 111633.44183279194, 132597.33701331625, 134674.0, 64867.67566162139, 88369.0, 96025.5198724002, 97453.0, 101391.0, 85507.57246213853, 85750.57124714337, 90855.54572227097, 94885.5255723717, 96227.0, 10612.681619551426, 11002.0, 11073.446327683423, 11249.437528123397, 11375.31748095117, 48504.51495485, 56469.0, 57957.71021144867, 58163.0, 58269.12596311144, 78257.60871195835, 81136.0, 84869.0, 85090.14909850822, 86727.56636217031, 12119.878801211875, 12968.481260749464, 12971.935140324238, 13321.267330296927, 13525.323733813073, 15685.13731744765, 15933.760993584874, 16384.67230655404, 16752.0, 16770.0, 11992.940035299767, 12437.813432798695, 14381.712365752835, 15022.849771502582, 15240.3903843845, 14508.782368264274, 14668.486602968846, 15533.223338832788, 15998.600034998752, 16002.359905604111, 15063.698726025637, 15181.924090379478, 15273.312700928707, 15342.539723808304, 15821.367145314058, 12318.445669944695, 12612.0, 12746.553870614483, 12801.231926084465, 12886.226826390446, 12544.24734515932, 12682.746345072863, 12974.156679815782, 13065.542706005244, 13104.868951310365], "value": 8343.616768177924, "unit": "score"}], "name": "dromaeo_css", "value": 7897.051528464601, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [99.0, 111.0, 110.0, 109.0, 110.0, 109.0, 111.0, 109.0, 111.0, 109.0], "value": 108.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [137.0, 115.0, 115.0, 170.0, 115.0, 121.0, 152.0, 113.0, 157.0, 116.0], "value": 131.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 160.0, 161.0, 160.0, 159.0, 161.0, 160.0, 160.0, 159.0, 162.0], "value": 160.2, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 74.0, 74.0, 114.0, 75.0, 76.0, 75.0, 75.0, 102.0, 75.0], "value": 81.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 83.0, 132.0, 83.0, 82.0, 83.0, 123.0, 83.0, 83.0, 122.0], "value": 95.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 116.0, 116.0, 116.0, 117.0, 117.0, 117.0, 117.0, 116.0, 116.0], "value": 116.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 146.0, 145.0, 146.0, 147.0, 145.0, 146.0, 146.0, 146.0, 147.0], "value": 146.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 95.0, 94.0, 95.0, 94.0, 94.0, 95.0, 95.0, 95.0, 94.0], "value": 94.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [49.0, 49.0, 49.0, 49.0, 49.0, 49.0, 51.0, 48.0, 50.0, 50.0], "value": 49.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 60.0, 58.0, 58.0, 60.0, 59.0, 57.0, 59.0, 60.0, 59.0], "value": 58.9, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [72.0, 72.0, 81.0, 73.0, 72.0, 72.0, 81.0, 74.0, 72.0, 72.0], "value": 74.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [118.0, 116.0, 116.0, 120.0, 115.0, 116.0, 115.0, 123.0, 149.0, 136.0], "value": 122.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 150.0, 155.0, 154.0, 155.0, 151.0, 152.0, 155.0, 150.0, 148.0], "value": 151.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 63.0, 62.0, 61.0, 64.0, 60.0, 61.0, 61.0, 62.0, 59.0], "value": 61.5, "unit": "score"}], "name": "kraken", "value": 1452.6000000000001, "alertThreshold": 2.0}]} 04:54:57 INFO - Return code: 0 04:54:57 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 04:54:57 INFO - # TBPL SUCCESS # 04:54:57 INFO - Running post-action listener: _resource_record_post_action 04:54:57 INFO - [mozharness: 2016-08-28 11:54:57.452725Z] Finished run-tests step (success) 04:54:57 INFO - Running post-run listener: _resource_record_post_run 04:54:57 INFO - Total resource usage - Wall time: 1190s; CPU: 13.0%; Read bytes: 266240; Write bytes: 328196096; Read time: 24; Write time: 329604 04:54:57 INFO - TinderboxPrint: CPU usage
13.0% 04:54:57 INFO - TinderboxPrint: I/O read bytes / time
266,240 / 24 04:54:57 INFO - TinderboxPrint: I/O write bytes / time
328,196,096 / 329,604 04:54:57 INFO - TinderboxPrint: CPU idle
8,259.1 (87.0%) 04:54:57 INFO - TinderboxPrint: CPU user
1,189.7 (12.5%) 04:54:57 INFO - TinderboxPrint: Swap in / out
0 / 0 04:54:57 INFO - install - Wall time: 16s; CPU: 14.0%; Read bytes: 0; Write bytes: 77053952; Read time: 0; Write time: 99400 04:54:57 INFO - run-tests - Wall time: 1174s; CPU: 13.0%; Read bytes: 262144; Write bytes: 248258560; Read time: 16; Write time: 229884 04:54:58 INFO - Running post-run listener: _upload_blobber_files 04:54:58 INFO - Blob upload gear active. 04:54:58 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 04:54:58 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:54:58 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:54:58 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:54:58 INFO - Traceback (most recent call last): 04:54:58 INFO - File "/builds/slave/test/build/venv/bin/blobberc.py", line 253, in 04:54:58 INFO - main() 04:54:58 INFO - File "/builds/slave/test/build/venv/bin/blobberc.py", line 235, in main 04:54:58 INFO - filetype_whitelist = get_server_whitelist(args['--url']) 04:54:58 INFO - File "/builds/slave/test/build/venv/bin/blobberc.py", line 69, in get_server_whitelist 04:54:58 INFO - return set(response.json().get('whitelist', [])) 04:54:58 INFO - File "/builds/slave/test/build/venv/local/lib/python2.7/site-packages/requests/models.py", line 651, in json 04:54:58 INFO - return json.loads(self.text or self.content, **kwargs) 04:54:58 INFO - File "/usr/lib/python2.7/json/__init__.py", line 326, in loads 04:54:58 INFO - return _default_decoder.decode(s) 04:54:58 INFO - File "/usr/lib/python2.7/json/decoder.py", line 366, in decode 04:54:58 INFO - obj, end = self.raw_decode(s, idx=_w(s, 0).end()) 04:54:58 INFO - File "/usr/lib/python2.7/json/decoder.py", line 384, in raw_decode 04:54:58 INFO - raise ValueError("No JSON object could be decoded") 04:54:58 INFO - ValueError: No JSON object could be decoded 04:54:58 ERROR - Return code: 1 04:54:58 INFO - Setting buildbot property blobber_files to {} 04:54:58 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 04:54:58 INFO - Writing to file /builds/slave/test/properties/blobber_files 04:54:58 INFO - Contents: 04:54:58 INFO - blobber_files:{} 04:54:58 INFO - Running post-run listener: copy_logs_to_upload_dir 04:54:58 INFO - Copying logs to upload dir... 04:54:58 INFO - mkdir: /builds/slave/test/build/upload/logs 04:54:58 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1672.375763 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 27 mins, 52 secs) (at 2016-08-28 04:54:57.740641) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-28 04:54:57.744456) ========= 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=c6927cf182745ad25b1d38b9000001fe-1472383624.168346-109470312 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.011337 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:54:57.795062) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-28 04:54:57.795390) ========= 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=c6927cf182745ad25b1d38b9000001fe-1472383624.168346-109470312 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004618 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-28 04:54:57.844934) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-28 04:54:57.845254) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-28 04:54:57.845543) ========= ========= Total master_lag: 0.30 =========