builder: mozilla-aurora_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-034 starttime: 1470860561.99 results: success (0) buildid: 20160810103821 builduid: 08a2541c282a49d7a818d473b26f8ad8 revision: f5a9d33557dd860e74994a3a650e743569883cbb ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-08-10 13:22:41.990608) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-08-10 13:22:41.991043) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-10 13:22:41.991329) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-10 13:22:42.005673) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-10 13:22:42.005931) ========= 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=d6bbd613908ccac0c6c9cb47000001f7-1470860560.207097-532244655 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-08-10 13:22:42-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 46.3M=0s 2016-08-10 13:22:42 (46.3 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.151358 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-10 13:22:42.172119) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-10 13:22:42.172443) ========= 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=d6bbd613908ccac0c6c9cb47000001f7-1470860560.207097-532244655 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.015630 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-10 13:22:42.221907) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-08-10 13:22:42.222271) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev f5a9d33557dd860e74994a3a650e743569883cbb --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 f5a9d33557dd860e74994a3a650e743569883cbb --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=d6bbd613908ccac0c6c9cb47000001f7-1470860560.207097-532244655 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-08-10 13:22:42,712 truncating revision to first 12 chars 2016-08-10 13:22:42,712 Setting DEBUG logging. 2016-08-10 13:22:42,712 attempt 1/10 2016-08-10 13:22:42,712 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/f5a9d33557dd?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-08-10 13:22:44,294 unpacking tar archive at: mozilla-aurora-f5a9d33557dd/testing/mozharness/ program finished with exit code 0 elapsedTime=1.906662 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-08-10 13:22:44.142944) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-10 13:22:44.143284) ========= script_repo_revision: f5a9d33557dd860e74994a3a650e743569883cbb ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-10 13:22:44.143697) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-10 13:22:44.143962) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-10 13:22:44.153057) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 28 mins, 9 secs) (at 2016-08-10 13:22:44.153392) ========= /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=d6bbd613908ccac0c6c9cb47000001f7-1470860560.207097-532244655 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 13:22:44 INFO - MultiFileLogger online at 20160810 13:22:44 in /builds/slave/test 13:22:44 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 13:22:44 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 13:22:44 INFO - {'append_to_log': False, 13:22:44 INFO - 'base_work_dir': '/builds/slave/test', 13:22:44 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 13:22:44 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 13:22:44 INFO - 'branch': 'Mozilla-Aurora', 13:22:44 INFO - 'buildbot_json_path': 'buildprops.json', 13:22:44 INFO - 'config_files': ('talos/linux_config.py',), 13:22:44 INFO - 'default_actions': ('clobber', 13:22:44 INFO - 'read-buildbot-config', 13:22:44 INFO - 'download-and-extract', 13:22:44 INFO - 'populate-webroot', 13:22:44 INFO - 'create-virtualenv', 13:22:44 INFO - 'install', 13:22:44 INFO - 'run-tests'), 13:22:44 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 13:22:44 INFO - 'download_minidump_stackwalk': True, 13:22:44 INFO - 'download_symbols': 'ondemand', 13:22:44 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 13:22:44 INFO - 'tooltool.py': '/tools/tooltool.py', 13:22:44 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 13:22:44 INFO - '/tools/misc-python/virtualenv.py')}, 13:22:44 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 13:22:44 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 13:22:44 INFO - 'installer_path': 'installer.exe', 13:22:44 INFO - 'log_level': 'info', 13:22:44 INFO - 'log_name': 'talos', 13:22:44 INFO - 'log_to_console': True, 13:22:44 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 13:22:44 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 13:22:44 INFO - 'opt_config_files': (), 13:22:44 INFO - 'pip_index': False, 13:22:44 INFO - 'sps_profile': False, 13:22:44 INFO - 'sps_profile_interval': 0, 13:22:44 INFO - 'suite': 'dromaeojs', 13:22:44 INFO - 'system_bits': '32', 13:22:44 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 13:22:44 INFO - 'title': 'talos-linux64-ix-034', 13:22:44 INFO - 'tooltool_cache': '/builds/tooltool_cache', 13:22:44 INFO - 'use_talos_json': True, 13:22:44 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 13:22:44 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 13:22:44 INFO - 'work_dir': 'build'} 13:22:44 INFO - [mozharness: 2016-08-10 20:22:44.735237Z] Running clobber step. 13:22:44 INFO - Running pre-action listener: _resource_record_pre_action 13:22:44 INFO - Running main action method: clobber 13:22:44 INFO - rmtree: /builds/slave/test/build 13:22:44 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 13:22:45 INFO - Running post-action listener: _resource_record_post_action 13:22:45 INFO - [mozharness: 2016-08-10 20:22:45.197483Z] Finished clobber step (success) 13:22:45 INFO - [mozharness: 2016-08-10 20:22:45.197586Z] Running read-buildbot-config step. 13:22:45 INFO - Running pre-action listener: _resource_record_pre_action 13:22:45 INFO - Running main action method: read_buildbot_config 13:22:45 INFO - Using buildbot properties: 13:22:45 INFO - { 13:22:45 INFO - "project": "", 13:22:45 INFO - "product": "firefox", 13:22:45 INFO - "script_repo_revision": "production", 13:22:45 INFO - "builddir": "mozilla-aurora_ubuntu64_hw_test-dromaeojs", 13:22:45 INFO - "repository": "", 13:22:45 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-aurora pgo talos dromaeojs", 13:22:45 INFO - "buildid": "20160810103821", 13:22:45 INFO - "pgo_build": "True", 13:22:45 INFO - "basedir": "/builds/slave/test", 13:22:45 INFO - "buildnumber": 458, 13:22:45 INFO - "slavename": "talos-linux64-ix-034", 13:22:45 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 13:22:45 INFO - "platform": "ubuntu64_hw", 13:22:45 INFO - "scheduler": "tests-mozilla-aurora-linux64-pgo-talos", 13:22:45 INFO - "branch": "mozilla-aurora", 13:22:45 INFO - "repo_path": "releases/mozilla-aurora", 13:22:45 INFO - "revision": "f5a9d33557dd860e74994a3a650e743569883cbb", 13:22:45 INFO - "stage_platform": "linux64-pgo", 13:22:45 INFO - "builduid": "08a2541c282a49d7a818d473b26f8ad8", 13:22:45 INFO - "slavebuilddir": "test" 13:22:45 INFO - } 13:22:45 INFO - Found installer url https://queue.taskcluster.net/v1/task/Py85bT1pTG6mykdB315W-g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2. 13:22:45 INFO - Running post-action listener: _resource_record_post_action 13:22:45 INFO - [mozharness: 2016-08-10 20:22:45.200039Z] Finished read-buildbot-config step (success) 13:22:45 INFO - [mozharness: 2016-08-10 20:22:45.200129Z] Running download-and-extract step. 13:22:45 INFO - Running pre-action listener: _resource_record_pre_action 13:22:45 INFO - Running main action method: download_and_extract 13:22:45 INFO - mkdir: /builds/slave/test/build/tests 13:22:45 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')]} 13:22:45 INFO - https://queue.taskcluster.net/v1/task/Py85bT1pTG6mykdB315W-g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 13:22:45 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Py85bT1pTG6mykdB315W-g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 13:22:45 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Py85bT1pTG6mykdB315W-g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 13:22:45 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Py85bT1pTG6mykdB315W-g/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 13:22:45 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Py85bT1pTG6mykdB315W-g/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 13:22:45 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Py85bT1pTG6mykdB315W-g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 13:22:45 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 13:22:45 INFO - retry: Failed, sleeping 30 seconds before retrying 13:23:15 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Py85bT1pTG6mykdB315W-g/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 13:23:15 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Py85bT1pTG6mykdB315W-g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 13:23:15 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 13:23:15 INFO - retry: Failed, sleeping 60 seconds before retrying 13:24:15 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Py85bT1pTG6mykdB315W-g/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 13:24:15 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Py85bT1pTG6mykdB315W-g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 13:24:15 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 13:24:15 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Py85bT1pTG6mykdB315W-g/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! 13:24:15 INFO - Caught exception: HTTP Error 404: Not Found 13:24:15 INFO - Caught exception: HTTP Error 404: Not Found 13:24:15 INFO - Caught exception: HTTP Error 404: Not Found 13:24:15 INFO - trying https://queue.taskcluster.net/v1/task/Py85bT1pTG6mykdB315W-g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 13:24:15 INFO - Downloading https://queue.taskcluster.net/v1/task/Py85bT1pTG6mykdB315W-g/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 13:24:15 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Py85bT1pTG6mykdB315W-g/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 13:24:15 INFO - Downloaded 1361 bytes. 13:24:15 INFO - Reading from file /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 13:24:15 INFO - Using the following test package requirements: 13:24:15 INFO - {u'common': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'], 13:24:15 INFO - u'cppunittest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 13:24:15 INFO - u'firefox-50.0a2.en-US.linux-x86_64.cppunittest.tests.zip'], 13:24:15 INFO - u'gtest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 13:24:15 INFO - u'firefox-50.0a2.en-US.linux-x86_64.gtest.tests.zip'], 13:24:15 INFO - u'jittest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 13:24:15 INFO - u'jsshell-linux-x86_64.zip'], 13:24:15 INFO - u'mochitest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 13:24:15 INFO - u'firefox-50.0a2.en-US.linux-x86_64.mochitest.tests.zip'], 13:24:15 INFO - u'mozbase': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'], 13:24:15 INFO - u'reftest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 13:24:15 INFO - u'firefox-50.0a2.en-US.linux-x86_64.reftest.tests.zip'], 13:24:15 INFO - u'talos': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 13:24:15 INFO - u'firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip'], 13:24:15 INFO - u'web-platform': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 13:24:15 INFO - u'firefox-50.0a2.en-US.linux-x86_64.web-platform.tests.zip'], 13:24:15 INFO - u'xpcshell': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 13:24:15 INFO - u'firefox-50.0a2.en-US.linux-x86_64.xpcshell.tests.zip']} 13:24:15 INFO - Downloading packages: [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 13:24:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:24:15 INFO - https://queue.taskcluster.net/v1/task/Py85bT1pTG6mykdB315W-g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 13:24:15 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Py85bT1pTG6mykdB315W-g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 13:24:15 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Py85bT1pTG6mykdB315W-g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 13:24:15 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Py85bT1pTG6mykdB315W-g/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 13:24:15 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Py85bT1pTG6mykdB315W-g/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 13:24:15 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Py85bT1pTG6mykdB315W-g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 13:24:15 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 13:24:15 INFO - retry: Failed, sleeping 30 seconds before retrying 13:24:45 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Py85bT1pTG6mykdB315W-g/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 13:24:45 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Py85bT1pTG6mykdB315W-g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 13:24:45 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 13:24:45 INFO - retry: Failed, sleeping 60 seconds before retrying 13:25:46 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Py85bT1pTG6mykdB315W-g/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 13:25:46 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Py85bT1pTG6mykdB315W-g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 13:25:46 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 13:25:46 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Py85bT1pTG6mykdB315W-g/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! 13:25:46 INFO - Caught exception: HTTP Error 404: Not Found 13:25:46 INFO - Caught exception: HTTP Error 404: Not Found 13:25:46 INFO - Caught exception: HTTP Error 404: Not Found 13:25:46 INFO - trying https://queue.taskcluster.net/v1/task/Py85bT1pTG6mykdB315W-g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 13:25:46 INFO - Downloading https://queue.taskcluster.net/v1/task/Py85bT1pTG6mykdB315W-g/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 13:25:46 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Py85bT1pTG6mykdB315W-g/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 13:25:47 INFO - Downloaded 21766251 bytes. 13:25:47 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 13:25:47 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 13:25:48 INFO - Return code: 0 13:25:48 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 13:25: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')]} 13:25:48 INFO - https://queue.taskcluster.net/v1/task/Py85bT1pTG6mykdB315W-g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 13:25:48 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Py85bT1pTG6mykdB315W-g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 13:25:48 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Py85bT1pTG6mykdB315W-g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 13:25:48 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Py85bT1pTG6mykdB315W-g/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 13:25:48 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Py85bT1pTG6mykdB315W-g/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 13:25:48 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Py85bT1pTG6mykdB315W-g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 13:25:48 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 13:25:48 INFO - retry: Failed, sleeping 30 seconds before retrying 13:26:18 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Py85bT1pTG6mykdB315W-g/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 13:26:18 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Py85bT1pTG6mykdB315W-g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 13:26:18 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 13:26:18 INFO - retry: Failed, sleeping 60 seconds before retrying 13:27:18 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Py85bT1pTG6mykdB315W-g/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 13:27:18 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Py85bT1pTG6mykdB315W-g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 13:27:18 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 13:27:18 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Py85bT1pTG6mykdB315W-g/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! 13:27:18 INFO - Caught exception: HTTP Error 404: Not Found 13:27:18 INFO - Caught exception: HTTP Error 404: Not Found 13:27:18 INFO - Caught exception: HTTP Error 404: Not Found 13:27:18 INFO - trying https://queue.taskcluster.net/v1/task/Py85bT1pTG6mykdB315W-g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 13:27:18 INFO - Downloading https://queue.taskcluster.net/v1/task/Py85bT1pTG6mykdB315W-g/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 13:27:18 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Py85bT1pTG6mykdB315W-g/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 13:27:19 INFO - Downloaded 21766251 bytes. 13:27:19 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'] 13:27:19 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 13:27:21 INFO - Return code: 0 13:27:21 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')]} 13:27:21 INFO - https://queue.taskcluster.net/v1/task/Py85bT1pTG6mykdB315W-g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 13:27:21 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Py85bT1pTG6mykdB315W-g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 13:27:21 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Py85bT1pTG6mykdB315W-g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 13:27:21 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Py85bT1pTG6mykdB315W-g/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 13:27:21 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Py85bT1pTG6mykdB315W-g/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 13:27:21 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Py85bT1pTG6mykdB315W-g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 13:27:21 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 13:27:21 INFO - retry: Failed, sleeping 30 seconds before retrying 13:27:51 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Py85bT1pTG6mykdB315W-g/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 13:27:51 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Py85bT1pTG6mykdB315W-g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 13:27:51 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 13:27:51 INFO - retry: Failed, sleeping 60 seconds before retrying 13:28:51 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Py85bT1pTG6mykdB315W-g/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 13:28:51 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Py85bT1pTG6mykdB315W-g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 13:28:51 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 13:28:51 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Py85bT1pTG6mykdB315W-g/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! 13:28:51 INFO - Caught exception: HTTP Error 404: Not Found 13:28:51 INFO - Caught exception: HTTP Error 404: Not Found 13:28:51 INFO - Caught exception: HTTP Error 404: Not Found 13:28:51 INFO - trying https://queue.taskcluster.net/v1/task/Py85bT1pTG6mykdB315W-g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 13:28:51 INFO - Downloading https://queue.taskcluster.net/v1/task/Py85bT1pTG6mykdB315W-g/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 13:28:51 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Py85bT1pTG6mykdB315W-g/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 13:28:59 INFO - Downloaded 14413723 bytes. 13:28:59 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'] 13:28:59 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 13:28:59 INFO - Return code: 0 13:28: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')]} 13:28:59 INFO - https://queue.taskcluster.net/v1/task/Py85bT1pTG6mykdB315W-g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 13:28:59 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Py85bT1pTG6mykdB315W-g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 13:28:59 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Py85bT1pTG6mykdB315W-g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 13:28:59 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Py85bT1pTG6mykdB315W-g/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 13:28:59 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Py85bT1pTG6mykdB315W-g/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 13:28:59 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Py85bT1pTG6mykdB315W-g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 13:28:59 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 13:28:59 INFO - retry: Failed, sleeping 30 seconds before retrying 13:29:29 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Py85bT1pTG6mykdB315W-g/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 13:29:29 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Py85bT1pTG6mykdB315W-g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 13:29:29 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 13:29:29 INFO - retry: Failed, sleeping 60 seconds before retrying 13:30:29 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Py85bT1pTG6mykdB315W-g/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 13:30:29 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Py85bT1pTG6mykdB315W-g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 13:30:29 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 13:30:29 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Py85bT1pTG6mykdB315W-g/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! 13:30:29 INFO - Caught exception: HTTP Error 404: Not Found 13:30:29 INFO - Caught exception: HTTP Error 404: Not Found 13:30:29 INFO - Caught exception: HTTP Error 404: Not Found 13:30:29 INFO - trying https://queue.taskcluster.net/v1/task/Py85bT1pTG6mykdB315W-g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 13:30:29 INFO - Downloading https://queue.taskcluster.net/v1/task/Py85bT1pTG6mykdB315W-g/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 13:30:29 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Py85bT1pTG6mykdB315W-g/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 13:30:30 INFO - Downloaded 63762404 bytes. 13:30:30 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Py85bT1pTG6mykdB315W-g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 13:30:30 INFO - mkdir: /builds/slave/test/properties 13:30:30 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 13:30:30 INFO - Writing to file /builds/slave/test/properties/build_url 13:30:30 INFO - Contents: 13:30:30 INFO - build_url:https://queue.taskcluster.net/v1/task/Py85bT1pTG6mykdB315W-g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 13:30:35 INFO - Running post-action listener: _resource_record_post_action 13:30:35 INFO - Running post-action listener: set_extra_try_arguments 13:30:35 INFO - [mozharness: 2016-08-10 20:30:35.582318Z] Finished download-and-extract step (success) 13:30:35 INFO - [mozharness: 2016-08-10 20:30:35.582549Z] Running populate-webroot step. 13:30:35 INFO - Running pre-action listener: _resource_record_pre_action 13:30:35 INFO - Running main action method: populate_webroot 13:30:35 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 13:30:35 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 13:30:35 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 13:30:35 INFO - '--tppagecycles', 13:30:35 INFO - '7'], 13:30:35 INFO - 'tests': ['tsvgm']}}, 13:30:35 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 13:30:35 INFO - 'tests': ['tresize', 'tcanvasmark']}, 13:30:35 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 13:30:35 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 13:30:35 INFO - 'tests': ['dromaeo_css', 'kraken']}, 13:30:35 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 13:30:35 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:30:35 INFO - 'talos_options': ['--disable-e10s'], 13:30:35 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 13:30:35 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:30:35 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 13:30:35 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:30:35 INFO - 'talos_options': ['--disable-e10s'], 13:30:35 INFO - 'tests': ['damp', 'tps']}, 13:30:35 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:30:35 INFO - 'tests': ['damp', 'tps']}, 13:30:35 INFO - 'g3': {'talos_options': ['--disable-e10s'], 13:30:35 INFO - 'tests': ['dromaeo_dom']}, 13:30:35 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 13:30:35 INFO - 'g4': {'talos_options': ['--disable-e10s'], 13:30:35 INFO - 'tests': ['basic_compositor_video']}, 13:30:35 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 13:30:35 INFO - 'other': {'talos_options': ['--disable-e10s'], 13:30:35 INFO - 'tests': ['a11yr', 13:30:35 INFO - 'ts_paint', 13:30:35 INFO - 'tpaint', 13:30:35 INFO - 'sessionrestore', 13:30:35 INFO - 'sessionrestore_no_auto_restore', 13:30:35 INFO - 'tabpaint']}, 13:30:35 INFO - 'other-e10s': {'tests': ['a11yr', 13:30:35 INFO - 'ts_paint', 13:30:35 INFO - 'tpaint', 13:30:35 INFO - 'sessionrestore', 13:30:35 INFO - 'sessionrestore_no_auto_restore', 13:30:35 INFO - 'tabpaint']}, 13:30:35 INFO - 'other-e10s_l64': {'tests': ['a11yr', 13:30:35 INFO - 'ts_paint', 13:30:35 INFO - 'tpaint', 13:30:35 INFO - 'sessionrestore', 13:30:35 INFO - 'sessionrestore_no_auto_restore', 13:30:35 INFO - 'tabpaint']}, 13:30:35 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 13:30:35 INFO - 'ts_paint', 13:30:35 INFO - 'tpaint', 13:30:35 INFO - 'sessionrestore', 13:30:35 INFO - 'sessionrestore_no_auto_restore', 13:30:35 INFO - 'tabpaint']}, 13:30:35 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 13:30:35 INFO - 'tests': ['a11yr', 13:30:35 INFO - 'ts_paint', 13:30:35 INFO - 'tpaint', 13:30:35 INFO - 'sessionrestore', 13:30:35 INFO - 'sessionrestore_no_auto_restore', 13:30:35 INFO - 'tabpaint']}, 13:30:35 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 13:30:35 INFO - 'tests': ['a11yr', 13:30:35 INFO - 'ts_paint', 13:30:35 INFO - 'tpaint', 13:30:35 INFO - 'sessionrestore', 13:30:35 INFO - 'sessionrestore_no_auto_restore', 13:30:35 INFO - 'tabpaint']}, 13:30:35 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 13:30:35 INFO - 'tests': ['tsvgx', 13:30:35 INFO - 'tsvgr_opacity', 13:30:35 INFO - 'tart', 13:30:35 INFO - 'tscrollx', 13:30:35 INFO - 'cart']}, 13:30:35 INFO - 'svgr-e10s': {'tests': ['tsvgx', 13:30:35 INFO - 'tsvgr_opacity', 13:30:35 INFO - 'tart', 13:30:35 INFO - 'tscrollx', 13:30:35 INFO - 'cart']}, 13:30:35 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:30:35 INFO - 'talos_options': ['--disable-e10s'], 13:30:35 INFO - 'tests': ['tp5o']}, 13:30:35 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:30:35 INFO - 'tests': ['tp5o']}, 13:30:35 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:30:35 INFO - 'talos_options': ['--disable-e10s', 13:30:35 INFO - '--xperf_path', 13:30:35 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 13:30:35 INFO - 'tests': ['tp5n']}, 13:30:35 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:30:35 INFO - 'talos_options': ['--xperf_path', 13:30:35 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 13:30:35 INFO - 'tests': ['tp5n']}}, 13:30:35 INFO - 'talos.zip': {'path': '', 13:30:35 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 13:30:35 INFO - Running post-action listener: _resource_record_post_action 13:30:35 INFO - [mozharness: 2016-08-10 20:30:35.598878Z] Finished populate-webroot step (success) 13:30:35 INFO - [mozharness: 2016-08-10 20:30:35.598966Z] Running create-virtualenv step. 13:30:35 INFO - Running pre-action listener: _resource_record_pre_action 13:30:35 INFO - Running main action method: create_virtualenv 13:30:35 INFO - Creating virtualenv /builds/slave/test/build/venv 13:30:35 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 13:30:35 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 13:30:35 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 13:30:35 INFO - Using real prefix '/usr' 13:30:35 INFO - New python executable in /builds/slave/test/build/venv/bin/python 13:30:36 INFO - Installing distribute.............................................................................................................................................................................................done. 13:30:38 INFO - Installing pip.................done. 13:30:38 INFO - Return code: 0 13:30:38 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 13:30:38 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')]} 13:30:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:30:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:30:38 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')]} 13:30:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:30:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:30:38 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 0x1746030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18a98a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a07180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a053b0>, '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 0x1a062b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a06740>, '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': 'd6bbd613908ccac0c6c9cb47000001f7-1470860560.207097-532244655', '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 13:30:38 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 13:30:38 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 13:30:38 INFO - Using env: {'DISPLAY': ':0', 13:30:38 INFO - 'HOME': '/home/cltbld', 13:30:38 INFO - 'LANG': 'en_US.UTF-8', 13:30:38 INFO - 'LANGUAGE': 'en_US:en', 13:30:38 INFO - 'LOGNAME': 'cltbld', 13:30:38 INFO - 'MAIL': '/var/mail/cltbld', 13:30:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:30:38 INFO - 'MOZ_NO_REMOTE': '1', 13:30:38 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:30:38 INFO - 'NO_EM_RESTART': '1', 13:30:38 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:30:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:30:38 INFO - 'PWD': '/builds/slave/test', 13:30:38 INFO - 'SHELL': '/bin/bash', 13:30:38 INFO - 'SHLVL': '1', 13:30:38 INFO - 'TERM': 'linux', 13:30:38 INFO - 'TMOUT': '86400', 13:30:38 INFO - 'USER': 'cltbld', 13:30:38 INFO - 'XDG_SESSION_COOKIE': 'd6bbd613908ccac0c6c9cb47000001f7-1470860560.207097-532244655', 13:30:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:30:38 INFO - '_': '/tools/buildbot/bin/python'} 13:30:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:30:38 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 13:30:38 INFO - Cleaning up... 13:30:38 INFO - Return code: 0 13:30:38 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 13:30:38 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')]} 13:30:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:30:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:30:38 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')]} 13:30:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:30:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:30:38 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 0x1746030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18a98a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a07180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a053b0>, '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 0x1a062b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a06740>, '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': 'd6bbd613908ccac0c6c9cb47000001f7-1470860560.207097-532244655', '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 13:30:38 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 13:30:38 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 13:30:38 INFO - Using env: {'DISPLAY': ':0', 13:30:38 INFO - 'HOME': '/home/cltbld', 13:30:38 INFO - 'LANG': 'en_US.UTF-8', 13:30:38 INFO - 'LANGUAGE': 'en_US:en', 13:30:38 INFO - 'LOGNAME': 'cltbld', 13:30:38 INFO - 'MAIL': '/var/mail/cltbld', 13:30:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:30:38 INFO - 'MOZ_NO_REMOTE': '1', 13:30:38 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:30:38 INFO - 'NO_EM_RESTART': '1', 13:30:38 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:30:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:30:38 INFO - 'PWD': '/builds/slave/test', 13:30:38 INFO - 'SHELL': '/bin/bash', 13:30:38 INFO - 'SHLVL': '1', 13:30:38 INFO - 'TERM': 'linux', 13:30:38 INFO - 'TMOUT': '86400', 13:30:38 INFO - 'USER': 'cltbld', 13:30:38 INFO - 'XDG_SESSION_COOKIE': 'd6bbd613908ccac0c6c9cb47000001f7-1470860560.207097-532244655', 13:30:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:30:38 INFO - '_': '/tools/buildbot/bin/python'} 13:30:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:30:38 INFO - Downloading/unpacking psutil>=3.1.1 13:30:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:30:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:30:38 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:30:38 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:30:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:30:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:30:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 13:30:40 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 13:30:40 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 13:30:40 INFO - Installing collected packages: psutil 13:30:40 INFO - Running setup.py install for psutil 13:30:40 INFO - building 'psutil._psutil_linux' extension 13:30:40 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 13:30:40 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 13:30:40 INFO - building 'psutil._psutil_posix' extension 13:30:40 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 13:30:41 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 13:30:41 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 13:30:41 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 13:30:41 INFO - Successfully installed psutil 13:30:41 INFO - Cleaning up... 13:30:41 INFO - Return code: 0 13:30:41 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 13:30:41 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')]} 13:30:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:30:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:30:41 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')]} 13:30:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:30:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:30:41 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 0x1746030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18a98a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a07180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a053b0>, '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 0x1a062b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a06740>, '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': 'd6bbd613908ccac0c6c9cb47000001f7-1470860560.207097-532244655', '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 13:30:41 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 13:30:41 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 13:30:41 INFO - Using env: {'DISPLAY': ':0', 13:30:41 INFO - 'HOME': '/home/cltbld', 13:30:41 INFO - 'LANG': 'en_US.UTF-8', 13:30:41 INFO - 'LANGUAGE': 'en_US:en', 13:30:41 INFO - 'LOGNAME': 'cltbld', 13:30:41 INFO - 'MAIL': '/var/mail/cltbld', 13:30:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:30:41 INFO - 'MOZ_NO_REMOTE': '1', 13:30:41 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:30:41 INFO - 'NO_EM_RESTART': '1', 13:30:41 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:30:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:30:41 INFO - 'PWD': '/builds/slave/test', 13:30:41 INFO - 'SHELL': '/bin/bash', 13:30:41 INFO - 'SHLVL': '1', 13:30:41 INFO - 'TERM': 'linux', 13:30:41 INFO - 'TMOUT': '86400', 13:30:41 INFO - 'USER': 'cltbld', 13:30:41 INFO - 'XDG_SESSION_COOKIE': 'd6bbd613908ccac0c6c9cb47000001f7-1470860560.207097-532244655', 13:30:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:30:41 INFO - '_': '/tools/buildbot/bin/python'} 13:30:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:30:41 INFO - Downloading/unpacking mozsystemmonitor==0.3 13:30:41 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 13:30:41 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 13:30:41 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 13:30:41 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 13:30:41 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 13:30:41 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 13:30:43 INFO - Downloading mozsystemmonitor-0.3.tar.gz 13:30:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 13:30:43 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 13:30:43 INFO - Installing collected packages: mozsystemmonitor 13:30:43 INFO - Running setup.py install for mozsystemmonitor 13:30:43 INFO - Successfully installed mozsystemmonitor 13:30:43 INFO - Cleaning up... 13:30:43 INFO - Return code: 0 13:30:43 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 13:30:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:30:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:30:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:30:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:30:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:30:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:30:43 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 0x1746030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18a98a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a07180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a053b0>, '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 0x1a062b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a06740>, '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': 'd6bbd613908ccac0c6c9cb47000001f7-1470860560.207097-532244655', '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 13:30:43 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 13:30:43 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 13:30:43 INFO - Using env: {'DISPLAY': ':0', 13:30:43 INFO - 'HOME': '/home/cltbld', 13:30:43 INFO - 'LANG': 'en_US.UTF-8', 13:30:43 INFO - 'LANGUAGE': 'en_US:en', 13:30:43 INFO - 'LOGNAME': 'cltbld', 13:30:43 INFO - 'MAIL': '/var/mail/cltbld', 13:30:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:30:43 INFO - 'MOZ_NO_REMOTE': '1', 13:30:43 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:30:43 INFO - 'NO_EM_RESTART': '1', 13:30:43 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:30:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:30:43 INFO - 'PWD': '/builds/slave/test', 13:30:43 INFO - 'SHELL': '/bin/bash', 13:30:43 INFO - 'SHLVL': '1', 13:30:43 INFO - 'TERM': 'linux', 13:30:43 INFO - 'TMOUT': '86400', 13:30:43 INFO - 'USER': 'cltbld', 13:30:43 INFO - 'XDG_SESSION_COOKIE': 'd6bbd613908ccac0c6c9cb47000001f7-1470860560.207097-532244655', 13:30:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:30:43 INFO - '_': '/tools/buildbot/bin/python'} 13:30:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:30:43 INFO - Downloading/unpacking jsonschema==2.5.1 13:30:43 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 13:30:43 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 13:30:43 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 13:30:43 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 13:30:43 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 13:30:43 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 13:30:45 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 13:30:45 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 13:30:45 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 13:30:45 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 13:30:45 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 13:30:45 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 13:30:45 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 13:30:45 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 13:30:45 INFO - Downloading functools32-3.2.3-2.tar.gz 13:30:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 13:30:45 INFO - warning: no files found matching '*.txt' 13:30:45 INFO - no previously-included directories found matching 'build' 13:30:45 INFO - no previously-included directories found matching 'dist' 13:30:45 INFO - no previously-included directories found matching '.git*' 13:30:45 INFO - Installing collected packages: jsonschema, functools32 13:30:45 INFO - Running setup.py install for functools32 13:30:45 INFO - warning: no files found matching '*.txt' 13:30:45 INFO - no previously-included directories found matching 'build' 13:30:45 INFO - no previously-included directories found matching 'dist' 13:30:45 INFO - no previously-included directories found matching '.git*' 13:30:45 INFO - Successfully installed jsonschema functools32 13:30:45 INFO - Cleaning up... 13:30:46 INFO - Return code: 0 13:30:46 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 13:30:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:30:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:30:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:30:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:30:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:30:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:30:46 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 0x1746030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18a98a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a07180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a053b0>, '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 0x1a062b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a06740>, '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': 'd6bbd613908ccac0c6c9cb47000001f7-1470860560.207097-532244655', '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 13:30:46 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 13:30:46 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 13:30:46 INFO - Using env: {'DISPLAY': ':0', 13:30:46 INFO - 'HOME': '/home/cltbld', 13:30:46 INFO - 'LANG': 'en_US.UTF-8', 13:30:46 INFO - 'LANGUAGE': 'en_US:en', 13:30:46 INFO - 'LOGNAME': 'cltbld', 13:30:46 INFO - 'MAIL': '/var/mail/cltbld', 13:30:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:30:46 INFO - 'MOZ_NO_REMOTE': '1', 13:30:46 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:30:46 INFO - 'NO_EM_RESTART': '1', 13:30:46 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:30:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:30:46 INFO - 'PWD': '/builds/slave/test', 13:30:46 INFO - 'SHELL': '/bin/bash', 13:30:46 INFO - 'SHLVL': '1', 13:30:46 INFO - 'TERM': 'linux', 13:30:46 INFO - 'TMOUT': '86400', 13:30:46 INFO - 'USER': 'cltbld', 13:30:46 INFO - 'XDG_SESSION_COOKIE': 'd6bbd613908ccac0c6c9cb47000001f7-1470860560.207097-532244655', 13:30:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:30:46 INFO - '_': '/tools/buildbot/bin/python'} 13:30:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:30:46 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 13:30:46 INFO - Cleaning up... 13:30:46 INFO - Return code: 0 13:30:46 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 13:30:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:30:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:30:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:30:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:30:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:30:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:30:46 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 0x1746030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18a98a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a07180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a053b0>, '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 0x1a062b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a06740>, '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': 'd6bbd613908ccac0c6c9cb47000001f7-1470860560.207097-532244655', '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 13:30:46 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 13:30:46 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 13:30:46 INFO - Using env: {'DISPLAY': ':0', 13:30:46 INFO - 'HOME': '/home/cltbld', 13:30:46 INFO - 'LANG': 'en_US.UTF-8', 13:30:46 INFO - 'LANGUAGE': 'en_US:en', 13:30:46 INFO - 'LOGNAME': 'cltbld', 13:30:46 INFO - 'MAIL': '/var/mail/cltbld', 13:30:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:30:46 INFO - 'MOZ_NO_REMOTE': '1', 13:30:46 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:30:46 INFO - 'NO_EM_RESTART': '1', 13:30:46 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:30:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:30:46 INFO - 'PWD': '/builds/slave/test', 13:30:46 INFO - 'SHELL': '/bin/bash', 13:30:46 INFO - 'SHLVL': '1', 13:30:46 INFO - 'TERM': 'linux', 13:30:46 INFO - 'TMOUT': '86400', 13:30:46 INFO - 'USER': 'cltbld', 13:30:46 INFO - 'XDG_SESSION_COOKIE': 'd6bbd613908ccac0c6c9cb47000001f7-1470860560.207097-532244655', 13:30:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:30:46 INFO - '_': '/tools/buildbot/bin/python'} 13:30:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:30:46 INFO - Downloading/unpacking blobuploader==1.2.4 13:30:46 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 13:30:46 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 13:30:46 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 13:30:46 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 13:30:46 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 13:30:46 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 13:30:48 INFO - Downloading blobuploader-1.2.4.tar.gz 13:30:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 13:30:48 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 13:30: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 13:30: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 13:30: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 13:30: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 13:30: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 13:30: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 13:30:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 13:30:48 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 13:30: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 13:30: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 13:30: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 13:30: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 13:30: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 13:30: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 13:30:49 INFO - Downloading docopt-0.6.1.tar.gz 13:30:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 13:30:49 INFO - Installing collected packages: blobuploader, requests, docopt 13:30:49 INFO - Running setup.py install for blobuploader 13:30:49 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 13:30:49 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 13:30:49 INFO - Running setup.py install for requests 13:30:49 INFO - Running setup.py install for docopt 13:30:49 INFO - Successfully installed blobuploader requests docopt 13:30:49 INFO - Cleaning up... 13:30:49 INFO - Return code: 0 13:30:49 INFO - Installing None into virtualenv /builds/slave/test/build/venv 13:30:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:30:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:30:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:30:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:30:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:30:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:30:49 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 0x1746030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18a98a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a07180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a053b0>, '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 0x1a062b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a06740>, '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': 'd6bbd613908ccac0c6c9cb47000001f7-1470860560.207097-532244655', '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 13:30:49 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 13:30:49 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 13:30:49 INFO - Using env: {'DISPLAY': ':0', 13:30:49 INFO - 'HOME': '/home/cltbld', 13:30:49 INFO - 'LANG': 'en_US.UTF-8', 13:30:49 INFO - 'LANGUAGE': 'en_US:en', 13:30:49 INFO - 'LOGNAME': 'cltbld', 13:30:49 INFO - 'MAIL': '/var/mail/cltbld', 13:30:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:30:49 INFO - 'MOZ_NO_REMOTE': '1', 13:30:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:30:49 INFO - 'NO_EM_RESTART': '1', 13:30:49 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:30:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:30:49 INFO - 'PWD': '/builds/slave/test', 13:30:49 INFO - 'SHELL': '/bin/bash', 13:30:49 INFO - 'SHLVL': '1', 13:30:49 INFO - 'TERM': 'linux', 13:30:49 INFO - 'TMOUT': '86400', 13:30:49 INFO - 'USER': 'cltbld', 13:30:49 INFO - 'XDG_SESSION_COOKIE': 'd6bbd613908ccac0c6c9cb47000001f7-1470860560.207097-532244655', 13:30:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:30:49 INFO - '_': '/tools/buildbot/bin/python'} 13:30:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:30:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 13:30:49 INFO - Running setup.py (path:/tmp/pip-FMBmEh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 13:30:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 13:30:50 INFO - Running setup.py (path:/tmp/pip-Z0Cdow-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 13:30:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 13:30:50 INFO - Running setup.py (path:/tmp/pip-CZjzqo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 13:30:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 13:30:50 INFO - Running setup.py (path:/tmp/pip-HeKEN_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 13:30:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 13:30:50 INFO - Running setup.py (path:/tmp/pip-FcDCI8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 13:30:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 13:30:50 INFO - Running setup.py (path:/tmp/pip-mx0FGb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 13:30:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 13:30:50 INFO - Running setup.py (path:/tmp/pip-BiRtbs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 13:30:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 13:30:50 INFO - Running setup.py (path:/tmp/pip-B2ElHQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 13:30:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 13:30:50 INFO - Running setup.py (path:/tmp/pip-JwFAQ2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 13:30:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 13:30:50 INFO - Running setup.py (path:/tmp/pip-lEDz83-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 13:30:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 13:30:50 INFO - Running setup.py (path:/tmp/pip-NsmAhr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 13:30:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 13:30:50 INFO - Running setup.py (path:/tmp/pip-rDzyA5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 13:30:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 13:30:50 INFO - Running setup.py (path:/tmp/pip-f2lDn1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 13:30:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 13:30:50 INFO - Running setup.py (path:/tmp/pip-W5XAJj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 13:30:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 13:30:50 INFO - Running setup.py (path:/tmp/pip-NKlZo5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 13:30:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 13:30:51 INFO - Running setup.py (path:/tmp/pip-h16cgn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 13:30:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 13:30:51 INFO - Running setup.py (path:/tmp/pip-YkZY5a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 13:30:51 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 13:30:51 INFO - Running setup.py install for manifestparser 13:30:51 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 13:30:51 INFO - Running setup.py install for mozcrash 13:30:51 INFO - Running setup.py install for mozdebug 13:30:51 INFO - Running setup.py install for mozdevice 13:30:51 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 13:30:51 INFO - Installing dm script to /builds/slave/test/build/venv/bin 13:30:51 INFO - Running setup.py install for mozfile 13:30:51 INFO - Running setup.py install for mozhttpd 13:30:51 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 13:30:51 INFO - Running setup.py install for mozinfo 13:30:51 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 13:30:51 INFO - Running setup.py install for mozInstall 13:30:51 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 13:30:51 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 13:30:51 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 13:30:51 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 13:30:52 INFO - Running setup.py install for mozleak 13:30:52 INFO - Running setup.py install for mozlog 13:30:52 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 13:30:52 INFO - Running setup.py install for moznetwork 13:30:52 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 13:30:52 INFO - Running setup.py install for mozprocess 13:30:52 INFO - Running setup.py install for mozprofile 13:30:52 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 13:30:52 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 13:30:52 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 13:30:52 INFO - Running setup.py install for mozrunner 13:30:52 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 13:30:52 INFO - Running setup.py install for mozscreenshot 13:30:52 INFO - Running setup.py install for moztest 13:30:52 INFO - Running setup.py install for mozversion 13:30:52 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 13:30:52 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 13:30:52 INFO - Cleaning up... 13:30:52 INFO - Return code: 0 13:30:52 INFO - Installing None into virtualenv /builds/slave/test/build/venv 13:30:52 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')]} 13:30:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:30:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:30:52 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')]} 13:30:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:30:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:30:52 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 0x1746030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18a98a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a07180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a053b0>, '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 0x1a062b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a06740>, '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': 'd6bbd613908ccac0c6c9cb47000001f7-1470860560.207097-532244655', '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 13:30:52 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 13:30:52 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 13:30:52 INFO - Using env: {'DISPLAY': ':0', 13:30:52 INFO - 'HOME': '/home/cltbld', 13:30:52 INFO - 'LANG': 'en_US.UTF-8', 13:30:52 INFO - 'LANGUAGE': 'en_US:en', 13:30:52 INFO - 'LOGNAME': 'cltbld', 13:30:53 INFO - 'MAIL': '/var/mail/cltbld', 13:30:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:30:53 INFO - 'MOZ_NO_REMOTE': '1', 13:30:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:30:53 INFO - 'NO_EM_RESTART': '1', 13:30:53 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:30:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:30:53 INFO - 'PWD': '/builds/slave/test', 13:30:53 INFO - 'SHELL': '/bin/bash', 13:30:53 INFO - 'SHLVL': '1', 13:30:53 INFO - 'TERM': 'linux', 13:30:53 INFO - 'TMOUT': '86400', 13:30:53 INFO - 'USER': 'cltbld', 13:30:53 INFO - 'XDG_SESSION_COOKIE': 'd6bbd613908ccac0c6c9cb47000001f7-1470860560.207097-532244655', 13:30:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:30:53 INFO - '_': '/tools/buildbot/bin/python'} 13:30:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:30:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 13:30:53 INFO - Running setup.py (path:/tmp/pip-uniQ74-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 13:30:53 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)) 13:30:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 13:30:53 INFO - Running setup.py (path:/tmp/pip-EtKyHH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 13:30:53 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)) 13:30:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 13:30:53 INFO - Running setup.py (path:/tmp/pip-aL2o2d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 13:30:53 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)) 13:30:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 13:30:53 INFO - Running setup.py (path:/tmp/pip-RPC3Ch-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 13:30:53 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)) 13:30:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 13:30:53 INFO - Running setup.py (path:/tmp/pip-mm_eEo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 13:30:53 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)) 13:30:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 13:30:53 INFO - Running setup.py (path:/tmp/pip-EbpeeL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 13:30:53 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)) 13:30:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 13:30:53 INFO - Running setup.py (path:/tmp/pip-_DaGVt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 13:30:53 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)) 13:30:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 13:30:53 INFO - Running setup.py (path:/tmp/pip-xeSOy3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 13:30:53 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)) 13:30:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 13:30:53 INFO - Running setup.py (path:/tmp/pip-xJ3KdL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 13:30:53 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)) 13:30:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 13:30:53 INFO - Running setup.py (path:/tmp/pip-XEltMT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 13:30:53 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)) 13:30:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 13:30:53 INFO - Running setup.py (path:/tmp/pip-YDKezn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 13:30:54 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)) 13:30:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 13:30:54 INFO - Running setup.py (path:/tmp/pip-VWLJT6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 13:30:54 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)) 13:30:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 13:30:54 INFO - Running setup.py (path:/tmp/pip-FlezrJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 13:30:54 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)) 13:30:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 13:30:54 INFO - Running setup.py (path:/tmp/pip-1iEf1x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 13:30:54 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)) 13:30:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 13:30:54 INFO - Running setup.py (path:/tmp/pip-URntsE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 13:30:54 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)) 13:30:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 13:30:54 INFO - Running setup.py (path:/tmp/pip-rkKhMt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 13:30:54 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)) 13:30:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 13:30:54 INFO - Running setup.py (path:/tmp/pip-hEcses-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 13:30:54 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)) 13:30:54 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)) 13:30:54 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)) 13:30:54 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)) 13:30:54 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)) 13:30:54 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)) 13:30:54 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 13:30:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:30:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:30:54 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:30:54 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:30:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:30:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:30:56 INFO - Downloading blessings-1.6.tar.gz 13:30:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 13:30:56 INFO - Installing collected packages: blessings 13:30:56 INFO - Running setup.py install for blessings 13:30:56 INFO - Successfully installed blessings 13:30:56 INFO - Cleaning up... 13:30:56 INFO - Return code: 0 13:30:56 INFO - Done creating virtualenv /builds/slave/test/build/venv. 13:30:56 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 13:30:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 13:30:56 INFO - Reading from file tmpfile_stdout 13:30:56 INFO - Current package versions: 13:30:56 INFO - argparse == 1.2.1 13:30:56 INFO - blessings == 1.6 13:30:56 INFO - blobuploader == 1.2.4 13:30:56 INFO - docopt == 0.6.1 13:30:56 INFO - functools32 == 3.2.3-2 13:30:56 INFO - jsonschema == 2.5.1 13:30:56 INFO - manifestparser == 1.1 13:30:56 INFO - mozInstall == 1.12 13:30:56 INFO - mozcrash == 0.17 13:30:56 INFO - mozdebug == 0.1 13:30:56 INFO - mozdevice == 0.48 13:30:56 INFO - mozfile == 1.2 13:30:56 INFO - mozhttpd == 0.7 13:30:56 INFO - mozinfo == 0.9 13:30:56 INFO - mozleak == 0.1 13:30:56 INFO - mozlog == 3.3 13:30:56 INFO - moznetwork == 0.27 13:30:56 INFO - mozprocess == 0.23 13:30:56 INFO - mozprofile == 0.28 13:30:56 INFO - mozrunner == 6.12 13:30:56 INFO - mozscreenshot == 0.1 13:30:56 INFO - mozsystemmonitor == 0.3 13:30:56 INFO - moztest == 0.7 13:30:56 INFO - mozversion == 1.4 13:30:56 INFO - psutil == 3.1.1 13:30:56 INFO - requests == 1.2.3 13:30:56 INFO - wsgiref == 0.1.2 13:30:56 INFO - Installing None into virtualenv /builds/slave/test/build/venv 13:30: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')]} 13:30:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:30:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:30: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')]} 13:30:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:30:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:30:56 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 0x1746030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18a98a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a07180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a053b0>, '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 0x1a062b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a06740>, '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': 'd6bbd613908ccac0c6c9cb47000001f7-1470860560.207097-532244655', '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 13:30:56 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 13:30:56 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 13:30:56 INFO - Using env: {'DISPLAY': ':0', 13:30:56 INFO - 'HOME': '/home/cltbld', 13:30:56 INFO - 'LANG': 'en_US.UTF-8', 13:30:56 INFO - 'LANGUAGE': 'en_US:en', 13:30:56 INFO - 'LOGNAME': 'cltbld', 13:30:56 INFO - 'MAIL': '/var/mail/cltbld', 13:30:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:30:56 INFO - 'MOZ_NO_REMOTE': '1', 13:30:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:30:56 INFO - 'NO_EM_RESTART': '1', 13:30:56 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:30:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:30:56 INFO - 'PWD': '/builds/slave/test', 13:30:56 INFO - 'SHELL': '/bin/bash', 13:30:56 INFO - 'SHLVL': '1', 13:30:56 INFO - 'TERM': 'linux', 13:30:56 INFO - 'TMOUT': '86400', 13:30:56 INFO - 'USER': 'cltbld', 13:30:56 INFO - 'XDG_SESSION_COOKIE': 'd6bbd613908ccac0c6c9cb47000001f7-1470860560.207097-532244655', 13:30:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:30:56 INFO - '_': '/tools/buildbot/bin/python'} 13:30:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:30:57 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)) 13:30:57 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)) 13:30:57 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)) 13:30:57 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)) 13:30:57 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)) 13:30:57 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)) 13:30:57 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)) 13:30:57 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)) 13:30:57 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)) 13:30:57 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)) 13:30:57 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)) 13:30:57 INFO - Cleaning up... 13:30:57 INFO - Return code: 0 13:30:57 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 13:30:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:30:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:30:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:30:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:30:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:30:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:30:57 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1746030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18a98a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a07180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a053b0>, '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 0x1a062b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a06740>, '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': 'd6bbd613908ccac0c6c9cb47000001f7-1470860560.207097-532244655', '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 13:30:57 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 13:30:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 13:30:57 INFO - Using env: {'DISPLAY': ':0', 13:30:57 INFO - 'HOME': '/home/cltbld', 13:30:57 INFO - 'LANG': 'en_US.UTF-8', 13:30:57 INFO - 'LANGUAGE': 'en_US:en', 13:30:57 INFO - 'LOGNAME': 'cltbld', 13:30:57 INFO - 'MAIL': '/var/mail/cltbld', 13:30:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:30:57 INFO - 'MOZ_NO_REMOTE': '1', 13:30:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:30:57 INFO - 'NO_EM_RESTART': '1', 13:30:57 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:30:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:30:57 INFO - 'PWD': '/builds/slave/test', 13:30:57 INFO - 'SHELL': '/bin/bash', 13:30:57 INFO - 'SHLVL': '1', 13:30:57 INFO - 'TERM': 'linux', 13:30:57 INFO - 'TMOUT': '86400', 13:30:57 INFO - 'USER': 'cltbld', 13:30:57 INFO - 'XDG_SESSION_COOKIE': 'd6bbd613908ccac0c6c9cb47000001f7-1470860560.207097-532244655', 13:30:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:30:57 INFO - '_': '/tools/buildbot/bin/python'} 13:30:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:30:57 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 13:30:57 INFO - Cleaning up... 13:30:57 INFO - Return code: 0 13:30:57 INFO - Running post-action listener: _resource_record_post_action 13:30:57 INFO - Running post-action listener: _start_resource_monitoring 13:30:57 INFO - Starting resource monitoring. 13:30:57 INFO - [mozharness: 2016-08-10 20:30:57.272261Z] Finished create-virtualenv step (success) 13:30:57 INFO - [mozharness: 2016-08-10 20:30:57.272691Z] Running install step. 13:30:57 INFO - Running pre-action listener: _resource_record_pre_action 13:30:57 INFO - Running main action method: install 13:30:57 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 13:30:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 13:30:57 INFO - Reading from file tmpfile_stdout 13:30:57 INFO - Detecting whether we're running mozinstall >=1.0... 13:30:57 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 13:30:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 13:30:57 INFO - Reading from file tmpfile_stdout 13:30:57 INFO - Output received: 13:30:57 INFO - Usage: mozinstall [options] installer 13:30:57 INFO - Options: 13:30:57 INFO - -h, --help show this help message and exit 13:30:57 INFO - -d DEST, --destination=DEST 13:30:57 INFO - Directory to install application into. [default: 13:30:57 INFO - "/builds/slave/test"] 13:30:57 INFO - --app=APP Application being installed. [default: firefox] 13:30:57 INFO - mkdir: /builds/slave/test/build/application 13:30:57 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'] 13:30:57 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 13:31:13 INFO - Reading from file tmpfile_stdout 13:31:13 INFO - Output received: 13:31:13 INFO - /builds/slave/test/build/application/firefox/firefox 13:31:13 INFO - Running post-action listener: _resource_record_post_action 13:31:13 INFO - [mozharness: 2016-08-10 20:31:13.639234Z] Finished install step (success) 13:31:13 INFO - [mozharness: 2016-08-10 20:31:13.639591Z] Running run-tests step. 13:31:13 INFO - Running pre-action listener: _resource_record_pre_action 13:31:13 INFO - Running main action method: run_tests 13:31:13 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 13:31:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 13:31:13 INFO - Python 2.7.3 13:31:13 INFO - Return code: 0 13:31:13 INFO - grabbing minidump binary from tooltool 13:31: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')]} 13:31:13 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 0x1a053b0>, '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 0x1a062b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a06740>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 13:31:13 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 13:31:13 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 13:31:13 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 13:31:13 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 13:31:13 INFO - Return code: 0 13:31:13 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 13:31:13 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 13:31:13 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 13:31:13 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 13:31:13 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 13:31:13 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/Py85bT1pTG6mykdB315W-g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-034', '--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 13:31:13 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/Py85bT1pTG6mykdB315W-g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-034 --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 13:31:13 INFO - Using env: {'DISPLAY': ':0', 13:31:13 INFO - 'HOME': '/home/cltbld', 13:31:13 INFO - 'LANG': 'en_US.UTF-8', 13:31:13 INFO - 'LANGUAGE': 'en_US:en', 13:31:13 INFO - 'LOGNAME': 'cltbld', 13:31:13 INFO - 'MAIL': '/var/mail/cltbld', 13:31:13 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 13:31:13 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 13:31:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:31:13 INFO - 'MOZ_NO_REMOTE': '1', 13:31:13 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 13:31:13 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:31:13 INFO - 'NO_EM_RESTART': '1', 13:31:13 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:31:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:31:13 INFO - 'PWD': '/builds/slave/test', 13:31:13 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 13:31:13 INFO - 'SHELL': '/bin/bash', 13:31:13 INFO - 'SHLVL': '1', 13:31:13 INFO - 'TERM': 'linux', 13:31:13 INFO - 'TMOUT': '86400', 13:31:13 INFO - 'USER': 'cltbld', 13:31:13 INFO - 'XDG_SESSION_COOKIE': 'd6bbd613908ccac0c6c9cb47000001f7-1470860560.207097-532244655', 13:31:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:31:13 INFO - '_': '/tools/buildbot/bin/python'} 13:31:13 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/Py85bT1pTG6mykdB315W-g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-034', '--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 13:31:13 INFO - mozversion application_buildid: 20160810103821 13:31:13 INFO - mozversion application_changeset: f5a9d33557dd860e74994a3a650e743569883cbb 13:31:13 INFO - mozversion application_display_name: FirefoxDeveloperEdition 13:31:13 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 13:31:13 INFO - mozversion application_name: Firefox 13:31:13 INFO - mozversion application_remotingname: firefox-dev 13:31:13 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 13:31:13 INFO - mozversion application_vendor: Mozilla 13:31:13 INFO - mozversion application_version: 50.0a2 13:31:13 INFO - mozversion platform_buildid: 20160810103821 13:31:13 INFO - mozversion platform_changeset: f5a9d33557dd860e74994a3a650e743569883cbb 13:31:13 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 13:31:13 INFO - mozversion platform_version: 50.0a2 13:31:13 INFO - using testdate: 1470861073 13:31:13 INFO - actual date: 1470861073 13:31:13 INFO - starting webserver on 'localhost:55996' 13:31:13 INFO - SUITE-START | Running 2 tests 13:31:13 INFO - TEST-START | dromaeo_css 13:31:13 INFO - operating with platform_type : linux_ 13:31:13 INFO - Initialising browser for dromaeo_css test... 13:31:13 INFO - TEST-INFO | started process 25955 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpkq6c3x/profile http://localhost:55996/getInfo.html) 13:31:18 INFO - PROCESS | 25955 | __metrics Screen width/height:1600/1200 13:31:18 INFO - PROCESS | 25955 | colorDepth:24 13:31:18 INFO - PROCESS | 25955 | Browser inner width/height: 1024/697 13:31:18 INFO - PROCESS | 25955 | __metrics 13:31:19 INFO - PROCESS | 25955 | 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" 13:31:19 INFO - PROCESS | 25955 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 122: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 13:31:19 INFO - TEST-INFO | 25955: exit 0 13:31:19 INFO - Browser initialized. 13:31:19 INFO - Running cycle 1/1 for dromaeo_css test... 13:31:19 INFO - TEST-INFO | started process 26041 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpkq6c3x/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 13:31:31 INFO - PROCESS | 26041 | RSS: Main: 144797696 13:31:31 INFO - PROCESS | 26041 | 13:32:23 INFO - PROCESS | 26041 | 1470861143317 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] 13:34:31 INFO - PROCESS | 26041 | Cycle 1(1): loaded http://localhost:55996/tests/dromaeo/cssquery-dojo.html (next: http://localhost:55996/tests/dromaeo/cssquery-ext.html) 13:34:32 INFO - PROCESS | 26041 | RSS: Main: 224403456 13:34:32 INFO - PROCESS | 26041 | 13:37:34 INFO - PROCESS | 26041 | Cycle 1(1): loaded http://localhost:55996/tests/dromaeo/cssquery-ext.html (next: http://localhost:55996/tests/dromaeo/cssquery-jquery.html) 13:37:35 INFO - PROCESS | 26041 | RSS: Main: 228753408 13:37:35 INFO - PROCESS | 26041 | 13:40:37 INFO - PROCESS | 26041 | Cycle 1(1): loaded http://localhost:55996/tests/dromaeo/cssquery-jquery.html (next: http://localhost:55996/tests/dromaeo/cssquery-mootools.html) 13:40:37 INFO - PROCESS | 26041 | RSS: Main: 176664576 13:40:37 INFO - PROCESS | 26041 | 13:43:39 INFO - PROCESS | 26041 | Cycle 1(1): loaded http://localhost:55996/tests/dromaeo/cssquery-mootools.html (next: http://localhost:55996/tests/dromaeo/cssquery-prototype.html) 13:43:40 INFO - PROCESS | 26041 | RSS: Main: 222990336 13:43:40 INFO - PROCESS | 26041 | 13:46:42 INFO - PROCESS | 26041 | Cycle 1(1): loaded http://localhost:55996/tests/dromaeo/cssquery-prototype.html (next: http://localhost:55996/tests/dromaeo/cssquery-yui.html) 13:46:43 INFO - PROCESS | 26041 | RSS: Main: 177979392 13:46:43 INFO - PROCESS | 26041 | 13:49:46 INFO - PROCESS | 26041 | Cycle 1(1): loaded http://localhost:55996/tests/dromaeo/cssquery-yui.html (next: http://localhost:55996/tests/dromaeo/cssquery-dojo.html) 13:49:46 INFO - PROCESS | 26041 | RSS: Main: 266129408 13:49:46 INFO - PROCESS | 26041 | 13:49:46 INFO - PROCESS | 26041 | __start_tp_report 13:49:46 INFO - PROCESS | 26041 | _x_x_mozilla_page_load 13:49:46 INFO - PROCESS | 26041 | _x_x_mozilla_page_load_details 13:49:46 INFO - PROCESS | 26041 | |i|pagename|runs| 13:49:46 INFO - PROCESS | 26041 | |0;dojo.html;3205.7114859662624;3213.5179723041542;3228.5641438405837;3247.8213698246595;3248.658890816464;5596.4403559644015;5664.5468362531;5672.546196304296;5676.035074037423;5699.572532060091;5610.13042978338;5645.2096706461125;5659.122835960422;5667.149927510865;5695.544356451484;5053.9241911371355;5091.618128640348;5098.949010509903;5121.5390614844655;5186.299849520323;5178.663386879841;5235.869103272411;5246.632735708502;5247.973760131213;5254.054270231357;5107.106256405133;5135.152699804527;5137.5633071188995;5160.664556803796;5178.300929374524;5242.9213561796605;5253.763580638881;5258.526732594085;5264.789408423659;5279.709615971139;5009.874753131165;5023.924641130386;5041.873953151164;5167;5174.508421699932;5592.189132575794;5603.80386686464;5648.661080335193;5651.8304450866535;5680.6591604503865;6124.387561243884;6127.908081378783;6128.662923539204;6138.47822935051;6144.139820425144;6007.309159446676;6058.969705151446;6066.302375226817;6110.08348747687;6163.96918015407;5683.971580142114;5733.598648094635;5735.795482948544;5736.3403208631125;5742;6061.818145455643;6254.655993920287;6258.092576576371;6262.12330273762;6263.655498947511;5807.5644326675665;5855.970720146457;5869.677167755729;5950.553708471795;5979.222701048836;6038.849028774228;6040.214772079602;6077.301110372276;6079.209702738615;6081.331053584102;5830.737616807254;5970.731317090741;6010.759569617254;6014.578979471483;6014.969925150346;5963.463288304073;6019.458248757633;6025.849353766191;6100.328963813977;6107.572469927062;4667.183242932541;4672.649551283667;4676.953230467651;4677.181493238738;4686.695364801277;4098.692598055158;4141.565135660712;4171.270027745132;4185.309423945076;4193.895152621209;5587.189857470726;5638.661680299113;5650.971745141248;5697.6296540724725;5720.8283751487525;6052.636841789506;6089.8477538061015;6096.0855871619615;6098.786542470993;6124.632522048602;5539.750711218005;5675.233843431167;5687.289088863892;5698.458646428583;5703.372629010805;5086.287919691246;5101.770420331094;5104.540591346722;5140.922886156711;5166.5866730661455;6288.779892703825;6299.685015749286;6301.873962520724;6302.65335406557;6303.338149494329;5933.436323549256;6084.269887613515;6084.878302433838;6086.02623580225;6087.299960504599;6156.507479401726;6219.191505104308;6227.314995350417;6281.434670879643;6317.7157027932935;5935.643861368331;5991.940080599138;6000.039993601003;6001.519878409629;6033.456988871023;5939.67331796738;6097.542684298606;6099.054646529795;6107.603005804608;6115.510759139347;5836.970815146067;5890.204822349099;5891.941080589139;5938.6436813791315;5976.252968378953;5906.084556893688;5912.113183022581;5914.378990205966;5916.585838991229;5919.881602367843;5719.2279042328755;5843.649381037151;5856.619319744203;5883.146943693055;5884.029135192816;5851.61964472308;5920.526357891445;5924.437178468039;5959.940400595939;6013.248343957005;5801.82594522165;5806.158107074535;5807.245058142415;5809.912851307318;5836.182934389274 13:49:46 INFO - PROCESS | 26041 | |1;ext.html;601.8134378342714;607.7508221629133;636.2460484326072;637.941017910269;646.5538778243015;34040.31919361611;37673.81163094188;37774;43942.56057439424;44773.776131119295;48031.75984120083;48498.75750621251;48655.51344486563;48662;48771.75614121933;9189.494577798201;9313.906860931405;9325.906740932605;9326.580303886307;9331.766705832375;8497.150284971483;9195.862062069074;10982.890171098266;11226.607068752595;11237.83143252852;11312.208145429782;11380.943095284554;11385.715357116096;11387.715307117347;11392.715182120472;11300.208985370986;11369.829452558259;11379.089672826154;11379.544818207345;11390.71523211918;11228.15788815834;11276.548938042468;11285.379304138269;11292.20954533183;11304.660860174208;45268.77365613184;45493.090138197054;45518.77240613776;45539.31691024637;45602.77198613986;3938.8424463021443;4132.173565286925;4267.018585725301;4437.090396468716;4699.95300047002;35602.465963010574;35723.46414803754;35899.64100358989;36294.8185259072;36892.63107368946;4636.976815115903;4652.092841895832;4660.347551342815;4682.672212945095;4703.764811759398;366819;371208;371515;371672;372440;62025;62353;62361;62416.68791656013;62464.37535624586;38758.6124138755;39565.208695825924;39685.40471892924;42083.7895810519;65424.345756542774;19328.226870925286;19629.411117666492;19631.901840490707;19634.803651963583;19648.11583478747;13046.739065218642;13105.14816536922;13110.803337949937;13141;13237.867621323856;19918.402447926586;20745.688814667792;20851.8957405212;21494;21559.56880862374;7701.730439434593;7737.651805668758;7779.899427154636;7807.882881756778;7874.763757087182;1026.7535791410007;1082.4858192358568;1143.4682872464343;1143.5425829668034;1171.308927732642;34788.478172827425;34957.42691578885;34976;35223.64776352255;35294.64705352914;1141.4578075414113;1174.4069245031205;1195.7548702515962;1225.8467691538558;1276.9361531923553;17497.47507574775;18304.542386440444;18329.556684668063;18443.72334414985;18571.16429760664;633067;635538;637361;640858;641804;63750.36249637537;63852.68073659602;64484.35515644877;64648;64920.67539662271;1357.830271216098;1446.2551785830306;1461.8159290974345;1462.8317743459638;1478.5933868186119;34983.65016349804;36199.44719957751;36550.26899461943;36716.816415918816;36815.81592092022;54335.72832135972;54741.452585473635;54793.72603137118;54821.177682334;54930;456369;456479;456582;456983;458997;30478.085657430314;30599.23501912381;30630.846845765627;31062;33071.50392744063;53975.730121350716;54116;54271;54284;54986.45013549813;44821;45683.77158114188;45700;46239.53760462487;47037.52962470332;44620.77689611531;46241.53758462372;46405;46664.7666761664;47505.52494475011;44776.77611612051;45523.77238113788;45694;45750;46110.76944615255;38492.42261366026;40099;40204;40805.002070888666;41185.176296473306;30155.69844301617;30451.543226851172;30608.540871886493;30918.845405772827;30933.535996959614 13:49:46 INFO - PROCESS | 26041 | |2;jquery.html;386.28344421098876;409.85245311687805;424.4270235182504;424.43126210877426;434.25308469432565;17551.946883187004;19866.106025228863;20090.39728808138;20502.794972050277;20818.89590552047;19383.90308048458;19660.803391966077;19770.30803921863;19976.500587485283;20133;5417.187421886709;5567.081431563787;5768.336641286264;5794.101914203306;5835.708214589275;5748.281464816898;5750.856228594277;5783.768649254046;5787.045137552299;5825.912611310834;5817.389174136718;5900.734466948998;6083.969580152093;6089.78685745999;6091.543134264925;5443.618946673734;5687.800926967557;5727;5871.412858714094;5886.529077673776;5669.489745922887;5696.458836410535;5701.914471282934;5708.71456427177;5713.5714821388565;24209.757902420926;24240.272791816096;24482.26553203389;24766.63783491907;24901.87549062243;2541.4154744408706;2552.3236342369287;2624.463965614562;2724.0193530329066;3052.86262118203;10050.899491005142;10137.239707021929;10444.529996150191;10532.894671053346;10576.206784491194;1935.9903200484048;2021.777604463508;2038.5820906714237;2072.958540829175;2100.6008858317023;61983;77822;77878.61060694717;82311.17688823096;88645.99999999936;10635.415052172204;10693.732656683645;11145.944270278758;11516.124556500487;11615.128865334964;16006.119663418624;16010.599735006717;16018.439354622766;16028.358865645243;16061.678766424606;8522.190391912758;8713.782155446164;8717.215450609348;8981.68599756341;9073.863892041625;8701.21689047976;8711.520866352284;8777;8835.22699374443;9038.86441703375;4736.621070314367;4762.380890484214;4778.928316075262;4783.473817880031;4852.2479015752615;2054.969175462369;2099.737532808399;2106.1575369851876;2127.648937925225;2206.79035491628;902.8239493298744;992.2657233647193;1001.329109496639;1010.5856598794606;1084.6203828659907;20526.38420847377;21439.78560214378;21483;21537;21604.45988850291;989.8069876374038;1011.0849681038671;1020.8264595018865;1041.5052849896238;1082.0153660169208;10843.349399036084;11750.118741094451;12124;12145.757084858253;12155.574554890713;509737;510677;534304;556612;559317;61978.64754565771;63216.367836321966;86979;100690.4965475168;102633.48683256537;998.2662742883974;1005.4520286443906;1009.7879445316565;1024.5594394410475;1046.1735229168871;17703.734443983092;18003;18430.723539146657;18781.530461738566;18971;31709.682903170673;37175.62824371722;40506.79746601248;41166.99320729235;43498.56501434945;60537.394626053174;61262.693686533064;61495.69252153711;61518;62465.687671561354;8300.792480187802;8435.704750333709;8455.069942306218;8483.618237179218;8517.74446766598;12591.811122832982;12676.239425634123;13214.603561893158;13607.523736669169;13752.381142848795;11025.44872756375;11753.706157346134;11807.350595716975;11845.170838041255;11937.522499100287;11693.239939403911;11787.528498859949;11997.880021200026;12295.938520307342;12439.626811195678;11846.644600661994;11851.170418070653;12126.696832579257;12172.087093467848;12286.631401057983;8318.45930014547;8390.580470976549;8538.530380829114;8549.786255343666;8589.398742087993;9753.268504862022;11462.885371146182;11922.642320730392;11929.761404772029;11992.940035299767 13:49:46 INFO - PROCESS | 26041 | |3;mootools.html;1409.7110092431053;1559.2905228121206;1599.4401959314241;1776.9378071767483;1902.714592811078;7996.12042675306;8904.065073167314;9119.544022798867;9164.404313719611;9451.858222126673;9283.11810378013;9342.859857102132;9344.719658410242;9348.485833279166;9351.345405821596;2425.114833085924;2452.0682140786475;2454.840435371704;2457.5699252630807;2458.754124587536;2355.858648481088;2377.2036367816804;2436.719777225615;2445.205308274809;2448.3144719478573;2393.581123303415;2407.6990376202975;2417.9965314394594;2421.6125419932814;2423.3335832646076;2357.363511851799;2364.9881750591308;2365.562370961369;2371.4427109629223;2420.0440825873857;2310.491691827812;2334.684817549643;2339.3099035784485;2342.8008619267384;2345.413646588353;8936.195742383217;9116.088391160898;9186.219171370441;9186.632534698603;9203.309751768576;5355.491228333302;5549.139883318092;5550.611457197998;5561.193626924073;5561.304836895388;3552.2007548301685;3584.301061293049;3592.676659100667;3600.8559657613664;3635.8727444539577;1124.162498938288;1137.1585027079984;1139.9601013964555;1152.7752088342777;1156.3119943633524;12636.178648387826;12668.429920653592;12687.175333603287;12699.238045717286;12725.936370318183;4350.630196433339;4467.128909862546;4581.289900065429;4648.581627653527;4653.348531205634;10270.897291027144;10279.691609251735;10280.537375818107;10295.845562316572;10308.484575771332;5401.243825864382;5424.810131645373;5436.21174929633;5438.83683489496;5447.809326673627;4428.291473364311;4843.951560484421;4950.133726597903;4950.777215025332;5069.011542749202;3751.643593858579;3818.873432337467;3819.7708137511836;3854.2484215577692;3904.2386734586485;1132.2074547816562;1137.066986123593;1146.369496776786;1146.4439746722833;1154.8440960470398;1084.8643919510062;1097.9231453798318;1099.71957150926;1111.7554138089608;1113.4043286841502;6265.216847894013;6423.518236132309;6488.416042556192;6498.480121590262;6698.765543205965;439.2094230385294;449.1623122875813;450.12675409705065;455.7083466581324;472.775431669954;4421.204183246982;4432.357308190359;4464.843730469418;4553.3397657340165;4562.338460923148;11345.319280843176;11439.942800286111;11525.423728813526;11560.132990025782;11577.247478913843;10603.416812075257;11028.338299702044;11041.116710663127;11306.830397544043;11424.885751142383;8478.74563763088;8635.179657932485;8636.697715579925;8651.437656552303;8691;6377.4579160771855;6489.707963141765;6590.077389165616;6642.701078451385;6675.165604299463;10427.426491543038;10543.051125398752;10546.683599492027;10628.043476087189;10992.890071099187;10844.945775271073;11043.061339786209;11054.944725276322;11059;11065.280756751108;7598.848023039619;7810.218978102144;7811.765647030598;8313.293370063613;8467.068622451403;8437.198466145706;8746.25656819152;8978.595963181802;8997.820043599222;9275.21160701348;8138.064122625974;8232.34141268685;8267.586620669063;8307;8431.156884311766;7740.612969351622;7919.12889582157;8023.117457079833;8031.799205019921;8042.83914321722;7417.962910185631;7498.287662672037;7530.811729706582;7556.811079723051;7582.962085189539;5512.944870551403;5670.262865827581;5675.290588676416;5692.637555414457;5845.649261044351;5087.745612719275;5096.1336572781975;5102.617303702163;5300.893982120413;5838.095095260242 13:49:46 INFO - PROCESS | 26041 | |4;prototype.html;322.9386416580849;325.5296097139633;335.26576796814976;337.8513454080203;337.89694143286306;5340.786368545254;5525.668459892409;5535.363433205182;5547.639403438774;5571.303587051619;5484.957858006976;5494.835154945348;5508.228847961289;5517.531009864157;5545.611807173499;4322.9624890026325;4327.0480494291205;4339.783010849461;4349.565043495657;4370.8907277318;4230.751928181194;4299.978500107495;4331.545187755288;4334.5448727883595;4341.1317736452675;4291.506476755182;4294.7974567121255;4301.827926882921;4303.526612072669;4306.138772245547;4286.549912259215;4293.35599660052;4321.719088259253;4329.978350108229;4344.9348259776125;3717.5167228260425;3838.328292548793;3865.9613403865883;3866.130120722843;3871.9225615487812;5386.649867758583;5394.02907476654;5413.566914646819;5424.674519528801;5459.94243223792;4936.259561065833;4971.676841005323;4976.950230497685;5007.84976450707;5010.248462730583;5569.936534905826;5702.486776190163;5717.371089180187;5718.59969802114;5752.5110365618975;4172.269852775782;4179.143275628519;4179.561146079648;4185.539590645027;4199.0342221288965;5564.387917329131;5658.490735833753;5947.762089516415;5953.2856057272975;5971.313298970631;4253.723507971972;4253.914921701548;4276.957230427718;4286.657067434598;4297.957020429756;4341.283688191413;4347.217500849877;4357.564243575681;4387.4954380246045;4391.736495810262;4609.516000819933;4745.738984355825;4918.827841025619;4926.310316555685;4956.603471722254;5640.266765320482;5696.287964004499;5733.741981610838;5735.1970724098655;5773.538116950634;1483.1101339196612;1497.1615895098562;1500.647347873249;1506.1414993453627;1515.3786947351532;933.5938866593053;967.2068903499063;971.8590804333473;977.3549457358251;996.6710985374809;4791.760411979387;4878;4880.414350277918;4895.632827537877;4939.802407903644;5114.769835357418;5160.329157209614;5161.638685291993;5172.715500647425;5203.271541984125;4571.474280457724;4888.706677599355;4910.238912968495;4926;4941.962187940573;2781.8191817531797;2786.5402208635755;2794.413173233603;2801.3836955869674;2825.6044153818484;5360.3031605891765;5394.595405344615;5411.621186516984;5426.75579598919;5461.8803145355105;5121.516064708613;5272.472752724697;5276.683398996072;5286.577073834084;5308.469153084738;5360.740226046876;5385.973070134624;5392.757325920343;5395.758975435671;5397.677568995699;5225.372955245395;5253.63767189007;5270.49930256625;5289.656172348785;5297.337832770903;4900.770149582003;5143.5627971622835;5149.2276158576515;5172.637915345889;5176.4305926348825;5387;5410.810621628224;5879.7060146993335;5993.3706960768495;6117.938820611737;4235.7035007549175;4251.447311849563;4285.7000009999;4310.719803212781;4320.719153255029;5955.851103722442;5960.284765828128;5961.360625827932;5977.701114944322;5979.760809567742;5590.664560126405;5735.512481439125;5737.598368114359;5740.885182296247;5754.251947246831;5746.655200687972;5750.763585829067;5774.509166720875;5784.768609255582;5805.709714514342;5475.917861232165;5648.915266271091;5680.289963754531;5689.857753556194;5736.541076713769;5808.303003639421;5808.30300363959;5808.796692115756;5812.21535092757;5815.9709201453725;5633.971830140823;5650.745716442688;5654.8690261948595;5661.065924122474;5685.715714214355 13:49:46 INFO - PROCESS | 26041 | |5;yui.html;846.1031306814775;861.2248975921669;887.2547060469204;899.9820003599929;938.4416272317968;16826.579335516617;18119.909400452994;18331.541711457186;18334.449966500993;18476;15082.547523574283;15096.47162349318;15206.087634741923;15528.68942621147;16880.1559922004;754.528419737664;905.642271302835;916.3768637326615;916.9587368568397;918.3250311021394;855.7347222361088;899.8740176375313;904.9638014479445;910.4810258152855;910.7723069232692;956.9234461243084;963.1909196275127;963.9903600964005;966.6954909203579;966.8114717630065;766.5285849202756;948.8481842905137;950.1068995144542;950.3680052764937;959.2709540749012;662.3310456438983;691.2223748283182;691.8616276744672;701.1796198447828;701.4879138229121;16711.164441777863;16743.24655390498;16756.916215418845;16758.413455528997;16760.161991900357;15463.768043479356;15771.842281577152;16141.757873631905;16225.2698628562;16509;14452.710945781131;14622.488212912496;14631.561053168421;14631.63420914475;15484.30320635574;4756.476787553366;4772.80908763649;4836.540528649773;4863.319135321058;4934.3338649282605;89964.55017724934;90425;90720.5463972676;90989.09010909872;91015.54492227563;11608.477618507188;11720.941395292968;11966.282023078644;12177.269363838199;12208.145429819828;21128.47178820511;21753.45616359572;21811.23660671869;24613.876930615592;24664.383390415383;9699.612015519442;9771.755706107262;9830.115289623967;9999.400035997864;10028.949855250678;5072.087024335655;5266.2890509781455;5344.35867695879;5370.194470829407;5412.079946409041;3932.3315036443873;4079.2453396121814;4098.200850834059;4098.508179018478;4099.733517321364;627.4823270801606;712.262807993724;715.9928400715926;718.3714250031222;734.709789633092;699.3321378083917;704.2781149321986;734.6657270941708;770.4760762681318;806.8184658451802;10620.415877126832;10924.977462528434;10999.780004399867;11333.206675532634;11808.822867656992;728.5046168605294;784.8391079828622;786.8032991752062;800.5756948817136;866.4065115395974;5208.296879921162;5223.634345595848;5274.604404669665;5312.814051508179;5350.6503799248885;102030.97969020215;119457;122203;124087;127285.72714272924;99560;104967;106103;108328;110942.44528777305;80528.59735701284;92624.53687731519;93983.53008235052;94354.5282273598;95560;9886.824898522314;10315.793684126425;10836;11308.717282068013;11550.191486596193;52881.47118528766;53708;55829.72085139548;56290.718546407006;56395.71802140963;76686.61656691904;77390.22609774055;78731.21268787468;78965;80804;12926.418311176209;13808.36755698127;13831.723365532835;13915.58253252404;14419.711605767616;16232;16383.689304855345;16613;16679.66640667204;16705.91647041757;14352.56942291733;15141.772873407128;15432.459863904709;16329.183540823149;16347.427840025543;15529.45646902353;15788.763168552252;16351.264193111101;16863.494095177164;16938.661226775148;14213.715725685222;15619.84380156184;15905.204739762734;16049.111159991313;16052.117133557766;11043.950824671643;11592.826107608224;11812.291262524275;11859.407029648655;12140.39298035077;11967.581134660246;12531.93734031324;12576.119671622899;12640.367981600699;13047.34763261814 13:49:46 INFO - PROCESS | 26041 | __end_tp_report 13:49:46 INFO - PROCESS | 26041 | __start_cc_report 13:49:46 INFO - PROCESS | 26041 | _x_x_mozilla_cycle_collect,1086 13:49:46 INFO - PROCESS | 26041 | __end_cc_report 13:49:46 INFO - PROCESS | 26041 | __startTimestamp1470862186352__endTimestamp 13:49:46 INFO - PROCESS | 26041 | 13:49:46 INFO - PROCESS | 26041 | ------- Summary: start ------- 13:49:46 INFO - PROCESS | 26041 | Number of tests: 6 13:49:46 INFO - PROCESS | 26041 | 13:49:46 INFO - PROCESS | 26041 | [#0] dojo.html Cycles:165 Average:5623.33 Median:5836.18 stddev:641.33 (11.0%) stddev-sans-first:614.62 13:49:46 INFO - PROCESS | 26041 | Values: 3205.7 3213.5 3228.6 3247.8 3248.7 5596.4 5664.5 5672.5 5676.0 5699.6 5610.1 5645.2 5659.1 5667.1 5695.5 5053.9 5091.6 5098.9 5121.5 5186.3 5178.7 5235.9 5246.6 5248.0 5254.1 5107.1 5135.2 5137.6 5160.7 5178.3 5242.9 5253.8 5258.5 5264.8 5279.7 5009.9 5023.9 5041.9 5167.0 5174.5 5592.2 5603.8 5648.7 5651.8 5680.7 6124.4 6127.9 6128.7 6138.5 6144.1 6007.3 6059.0 6066.3 6110.1 6164.0 5684.0 5733.6 5735.8 5736.3 5742.0 6061.8 6254.7 6258.1 6262.1 6263.7 5807.6 5856.0 5869.7 5950.6 5979.2 6038.8 6040.2 6077.3 6079.2 6081.3 5830.7 5970.7 6010.8 6014.6 6015.0 5963.5 6019.5 6025.8 6100.3 6107.6 4667.2 4672.6 4677.0 4677.2 4686.7 4098.7 4141.6 4171.3 4185.3 4193.9 5587.2 5638.7 5651.0 5697.6 5720.8 6052.6 6089.8 6096.1 6098.8 6124.6 5539.8 5675.2 5687.3 5698.5 5703.4 5086.3 5101.8 5104.5 5140.9 5166.6 6288.8 6299.7 6301.9 6302.7 6303.3 5933.4 6084.3 6084.9 6086.0 6087.3 6156.5 6219.2 6227.3 6281.4 6317.7 5935.6 5991.9 6000.0 6001.5 6033.5 5939.7 6097.5 6099.1 6107.6 6115.5 5837.0 5890.2 5891.9 5938.6 5976.3 5906.1 5912.1 5914.4 5916.6 5919.9 5719.2 5843.6 5856.6 5883.1 5884.0 5851.6 5920.5 5924.4 5959.9 6013.2 5801.8 5806.2 5807.2 5809.9 5836.2 13:49:46 INFO - PROCESS | 26041 | 13:49:46 INFO - PROCESS | 26041 | [#1] ext.html Cycles:180 Average:65932.96 Median:39892.20 stddev:133119.27 (333.7%) stddev-sans-first:133402.33 13:49:46 INFO - PROCESS | 26041 | Values: 601.8 607.8 636.2 637.9 646.6 34040.3 37673.8 37774.0 43942.6 44773.8 48031.8 48498.8 48655.5 48662.0 48771.8 9189.5 9313.9 9325.9 9326.6 9331.8 8497.2 9195.9 10982.9 11226.6 11237.8 11312.2 11380.9 11385.7 11387.7 11392.7 11300.2 11369.8 11379.1 11379.5 11390.7 11228.2 11276.5 11285.4 11292.2 11304.7 45268.8 45493.1 45518.8 45539.3 45602.8 3938.8 4132.2 4267.0 4437.1 4700.0 35602.5 35723.5 35899.6 36294.8 36892.6 4637.0 4652.1 4660.3 4682.7 4703.8 366819.0 371208.0 371515.0 371672.0 372440.0 62025.0 62353.0 62361.0 62416.7 62464.4 38758.6 39565.2 39685.4 42083.8 65424.3 19328.2 19629.4 19631.9 19634.8 19648.1 13046.7 13105.1 13110.8 13141.0 13237.9 19918.4 20745.7 20851.9 21494.0 21559.6 7701.7 7737.7 7779.9 7807.9 7874.8 1026.8 1082.5 1143.5 1143.5 1171.3 34788.5 34957.4 34976.0 35223.6 35294.6 1141.5 1174.4 1195.8 1225.8 1276.9 17497.5 18304.5 18329.6 18443.7 18571.2 633067.0 635538.0 637361.0 640858.0 641804.0 63750.4 63852.7 64484.4 64648.0 64920.7 1357.8 1446.3 1461.8 1462.8 1478.6 34983.7 36199.4 36550.3 36716.8 36815.8 54335.7 54741.5 54793.7 54821.2 54930.0 456369.0 456479.0 456582.0 456983.0 458997.0 30478.1 30599.2 30630.8 31062.0 33071.5 53975.7 54116.0 54271.0 54284.0 54986.5 44821.0 45683.8 45700.0 46239.5 47037.5 44620.8 46241.5 46405.0 46664.8 47505.5 44776.8 45523.8 45694.0 45750.0 46110.8 38492.4 40099.0 40204.0 40805.0 41185.2 30155.7 30451.5 30608.5 30918.8 30933.5 13:49:46 INFO - PROCESS | 26041 | 13:49:46 INFO - PROCESS | 26041 | [#2] jquery.html Cycles:180 Average:30198.43 Median:24834.26 stddev:87739.99 (353.3%) stddev-sans-first:87957.56 13:49:46 INFO - PROCESS | 26041 | Values: 386.3 409.9 424.4 424.4 434.3 17551.9 19866.1 20090.4 20502.8 20818.9 19383.9 19660.8 19770.3 19976.5 20133.0 5417.2 5567.1 5768.3 5794.1 5835.7 5748.3 5750.9 5783.8 5787.0 5825.9 5817.4 5900.7 6084.0 6089.8 6091.5 5443.6 5687.8 5727.0 5871.4 5886.5 5669.5 5696.5 5701.9 5708.7 5713.6 24209.8 24240.3 24482.3 24766.6 24901.9 2541.4 2552.3 2624.5 2724.0 3052.9 10050.9 10137.2 10444.5 10532.9 10576.2 1936.0 2021.8 2038.6 2073.0 2100.6 61983.0 77822.0 77878.6 82311.2 88646.0 10635.4 10693.7 11145.9 11516.1 11615.1 16006.1 16010.6 16018.4 16028.4 16061.7 8522.2 8713.8 8717.2 8981.7 9073.9 8701.2 8711.5 8777.0 8835.2 9038.9 4736.6 4762.4 4778.9 4783.5 4852.2 2055.0 2099.7 2106.2 2127.6 2206.8 902.8 992.3 1001.3 1010.6 1084.6 20526.4 21439.8 21483.0 21537.0 21604.5 989.8 1011.1 1020.8 1041.5 1082.0 10843.3 11750.1 12124.0 12145.8 12155.6 509737.0 510677.0 534304.0 556612.0 559317.0 61978.6 63216.4 86979.0 100690.5 102633.5 998.3 1005.5 1009.8 1024.6 1046.2 17703.7 18003.0 18430.7 18781.5 18971.0 31709.7 37175.6 40506.8 41167.0 43498.6 60537.4 61262.7 61495.7 61518.0 62465.7 8300.8 8435.7 8455.1 8483.6 8517.7 12591.8 12676.2 13214.6 13607.5 13752.4 11025.4 11753.7 11807.4 11845.2 11937.5 11693.2 11787.5 11997.9 12295.9 12439.6 11846.6 11851.2 12126.7 12172.1 12286.6 8318.5 8390.6 8538.5 8549.8 8589.4 9753.3 11462.9 11922.6 11929.8 11992.9 13:49:46 INFO - PROCESS | 26041 | 13:49:46 INFO - PROCESS | 26041 | [#3] mootools.html Cycles:180 Average:6042.58 Median:4465.99 stddev:3465.72 (77.6%) stddev-sans-first:3457.96 13:49:46 INFO - PROCESS | 26041 | Values: 1409.7 1559.3 1599.4 1776.9 1902.7 7996.1 8904.1 9119.5 9164.4 9451.9 9283.1 9342.9 9344.7 9348.5 9351.3 2425.1 2452.1 2454.8 2457.6 2458.8 2355.9 2377.2 2436.7 2445.2 2448.3 2393.6 2407.7 2418.0 2421.6 2423.3 2357.4 2365.0 2365.6 2371.4 2420.0 2310.5 2334.7 2339.3 2342.8 2345.4 8936.2 9116.1 9186.2 9186.6 9203.3 5355.5 5549.1 5550.6 5561.2 5561.3 3552.2 3584.3 3592.7 3600.9 3635.9 1124.2 1137.2 1140.0 1152.8 1156.3 12636.2 12668.4 12687.2 12699.2 12725.9 4350.6 4467.1 4581.3 4648.6 4653.3 10270.9 10279.7 10280.5 10295.8 10308.5 5401.2 5424.8 5436.2 5438.8 5447.8 4428.3 4844.0 4950.1 4950.8 5069.0 3751.6 3818.9 3819.8 3854.2 3904.2 1132.2 1137.1 1146.4 1146.4 1154.8 1084.9 1097.9 1099.7 1111.8 1113.4 6265.2 6423.5 6488.4 6498.5 6698.8 439.2 449.2 450.1 455.7 472.8 4421.2 4432.4 4464.8 4553.3 4562.3 11345.3 11439.9 11525.4 11560.1 11577.2 10603.4 11028.3 11041.1 11306.8 11424.9 8478.7 8635.2 8636.7 8651.4 8691.0 6377.5 6489.7 6590.1 6642.7 6675.2 10427.4 10543.1 10546.7 10628.0 10992.9 10844.9 11043.1 11054.9 11059.0 11065.3 7598.8 7810.2 7811.8 8313.3 8467.1 8437.2 8746.3 8978.6 8997.8 9275.2 8138.1 8232.3 8267.6 8307.0 8431.2 7740.6 7919.1 8023.1 8031.8 8042.8 7418.0 7498.3 7530.8 7556.8 7583.0 5512.9 5670.3 5675.3 5692.6 5845.6 5087.7 5096.1 5102.6 5300.9 5838.1 13:49:46 INFO - PROCESS | 26041 | 13:49:46 INFO - PROCESS | 26041 | [#4] prototype.html Cycles:180 Average:4691.82 Median:5214.32 stddev:1340.27 (25.7%) stddev-sans-first:1303.30 13:49:46 INFO - PROCESS | 26041 | Values: 322.9 325.5 335.3 337.9 337.9 5340.8 5525.7 5535.4 5547.6 5571.3 5485.0 5494.8 5508.2 5517.5 5545.6 4323.0 4327.0 4339.8 4349.6 4370.9 4230.8 4300.0 4331.5 4334.5 4341.1 4291.5 4294.8 4301.8 4303.5 4306.1 4286.5 4293.4 4321.7 4330.0 4344.9 3717.5 3838.3 3866.0 3866.1 3871.9 5386.6 5394.0 5413.6 5424.7 5459.9 4936.3 4971.7 4977.0 5007.8 5010.2 5569.9 5702.5 5717.4 5718.6 5752.5 4172.3 4179.1 4179.6 4185.5 4199.0 5564.4 5658.5 5947.8 5953.3 5971.3 4253.7 4253.9 4277.0 4286.7 4298.0 4341.3 4347.2 4357.6 4387.5 4391.7 4609.5 4745.7 4918.8 4926.3 4956.6 5640.3 5696.3 5733.7 5735.2 5773.5 1483.1 1497.2 1500.6 1506.1 1515.4 933.6 967.2 971.9 977.4 996.7 4791.8 4878.0 4880.4 4895.6 4939.8 5114.8 5160.3 5161.6 5172.7 5203.3 4571.5 4888.7 4910.2 4926.0 4942.0 2781.8 2786.5 2794.4 2801.4 2825.6 5360.3 5394.6 5411.6 5426.8 5461.9 5121.5 5272.5 5276.7 5286.6 5308.5 5360.7 5386.0 5392.8 5395.8 5397.7 5225.4 5253.6 5270.5 5289.7 5297.3 4900.8 5143.6 5149.2 5172.6 5176.4 5387.0 5410.8 5879.7 5993.4 6117.9 4235.7 4251.4 4285.7 4310.7 4320.7 5955.9 5960.3 5961.4 5977.7 5979.8 5590.7 5735.5 5737.6 5740.9 5754.3 5746.7 5750.8 5774.5 5784.8 5805.7 5475.9 5648.9 5680.3 5689.9 5736.5 5808.3 5808.3 5808.8 5812.2 5816.0 5634.0 5650.7 5654.9 5661.1 5685.7 13:49:46 INFO - PROCESS | 26041 | 13:49:46 INFO - PROCESS | 26041 | [#5] yui.html Cycles:180 Average:22695.75 Median:4088.72 stddev:32007.23 (782.8%) stddev-sans-first:32054.97 13:49:46 INFO - PROCESS | 26041 | Values: 846.1 861.2 887.3 900.0 938.4 16826.6 18119.9 18331.5 18334.4 18476.0 15082.5 15096.5 15206.1 15528.7 16880.2 754.5 905.6 916.4 917.0 918.3 855.7 899.9 905.0 910.5 910.8 956.9 963.2 964.0 966.7 966.8 766.5 948.8 950.1 950.4 959.3 662.3 691.2 691.9 701.2 701.5 16711.2 16743.2 16756.9 16758.4 16760.2 15463.8 15771.8 16141.8 16225.3 16509.0 14452.7 14622.5 14631.6 14631.6 15484.3 4756.5 4772.8 4836.5 4863.3 4934.3 89964.6 90425.0 90720.5 90989.1 91015.5 11608.5 11720.9 11966.3 12177.3 12208.1 21128.5 21753.5 21811.2 24613.9 24664.4 9699.6 9771.8 9830.1 9999.4 10028.9 5072.1 5266.3 5344.4 5370.2 5412.1 3932.3 4079.2 4098.2 4098.5 4099.7 627.5 712.3 716.0 718.4 734.7 699.3 704.3 734.7 770.5 806.8 10620.4 10925.0 10999.8 11333.2 11808.8 728.5 784.8 786.8 800.6 866.4 5208.3 5223.6 5274.6 5312.8 5350.7 102031.0 119457.0 122203.0 124087.0 127285.7 99560.0 104967.0 106103.0 108328.0 110942.4 80528.6 92624.5 93983.5 94354.5 95560.0 9886.8 10315.8 10836.0 11308.7 11550.2 52881.5 53708.0 55829.7 56290.7 56395.7 76686.6 77390.2 78731.2 78965.0 80804.0 12926.4 13808.4 13831.7 13915.6 14419.7 16232.0 16383.7 16613.0 16679.7 16705.9 14352.6 15141.8 15432.5 16329.2 16347.4 15529.5 15788.8 16351.3 16863.5 16938.7 14213.7 15619.8 15905.2 16049.1 16052.1 11044.0 11592.8 11812.3 11859.4 12140.4 11967.6 12531.9 12576.1 12640.4 13047.3 13:49:46 INFO - PROCESS | 26041 | -------- Summary: end -------- 13:49:46 INFO - PROCESS | 26041 | 13:49:47 INFO - TEST-INFO | 26041: exit 0 13:49:47 INFO - TEST-OK | dromaeo_css | took 1113245ms 13:49:47 INFO - TEST-START | kraken 13:49:47 INFO - operating with platform_type : linux_ 13:49:47 INFO - Initialising browser for kraken test... 13:49:47 INFO - TEST-INFO | started process 26244 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpDOr8sP/profile http://localhost:55996/getInfo.html) 13:49:50 INFO - PROCESS | 26244 | __metrics Screen width/height:1600/1200 13:49:50 INFO - PROCESS | 26244 | colorDepth:24 13:49:50 INFO - PROCESS | 26244 | Browser inner width/height: 1024/697 13:49:50 INFO - PROCESS | 26244 | __metrics 13:49:51 INFO - PROCESS | 26244 | 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" 13:49:51 INFO - PROCESS | 26244 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 122: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 13:49:52 INFO - TEST-INFO | 26244: exit 0 13:49:52 INFO - Browser initialized. 13:49:52 INFO - Running cycle 1/1 for kraken test... 13:49:52 INFO - TEST-INFO | started process 26333 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpDOr8sP/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 13:50:04 INFO - PROCESS | 26333 | RSS: Main: 145043456 13:50:04 INFO - PROCESS | 26333 | 13:50:06 INFO - PROCESS | 26333 | Cycle 1(1): loaded http://localhost:55996/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:55996/tests/kraken/driver.html?testName=audio-beat-detection) 13:50:07 INFO - PROCESS | 26333 | RSS: Main: 191311872 13:50:07 INFO - PROCESS | 26333 | 13:50:10 INFO - PROCESS | 26333 | Cycle 1(1): loaded http://localhost:55996/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:55996/tests/kraken/driver.html?testName=audio-dft) 13:50:10 INFO - PROCESS | 26333 | RSS: Main: 181129216 13:50:10 INFO - PROCESS | 26333 | 13:50:13 INFO - PROCESS | 26333 | Cycle 1(1): loaded http://localhost:55996/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:55996/tests/kraken/driver.html?testName=audio-fft) 13:50:14 INFO - PROCESS | 26333 | RSS: Main: 269934592 13:50:14 INFO - PROCESS | 26333 | 13:50:16 INFO - PROCESS | 26333 | Cycle 1(1): loaded http://localhost:55996/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:55996/tests/kraken/driver.html?testName=audio-oscillator) 13:50:16 INFO - PROCESS | 26333 | RSS: Main: 175968256 13:50:16 INFO - PROCESS | 26333 | 13:50:19 INFO - PROCESS | 26333 | Cycle 1(1): loaded http://localhost:55996/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:55996/tests/kraken/driver.html?testName=imaging-gaussian-blur) 13:50:19 INFO - PROCESS | 26333 | RSS: Main: 176885760 13:50:19 INFO - PROCESS | 26333 | 13:50:22 INFO - PROCESS | 26333 | Cycle 1(1): loaded http://localhost:55996/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:55996/tests/kraken/driver.html?testName=imaging-darkroom) 13:50:23 INFO - PROCESS | 26333 | RSS: Main: 212893696 13:50:23 INFO - PROCESS | 26333 | 13:50:26 INFO - PROCESS | 26333 | Cycle 1(1): loaded http://localhost:55996/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:55996/tests/kraken/driver.html?testName=imaging-desaturate) 13:50:27 INFO - PROCESS | 26333 | RSS: Main: 212336640 13:50:27 INFO - PROCESS | 26333 | 13:50:30 INFO - PROCESS | 26333 | Cycle 1(1): loaded http://localhost:55996/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:55996/tests/kraken/driver.html?testName=json-parse-financial) 13:50:30 INFO - PROCESS | 26333 | RSS: Main: 212480000 13:50:30 INFO - PROCESS | 26333 | 13:50:32 INFO - PROCESS | 26333 | Cycle 1(1): loaded http://localhost:55996/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:55996/tests/kraken/driver.html?testName=json-stringify-tinderbox) 13:50:32 INFO - PROCESS | 26333 | RSS: Main: 175947776 13:50:32 INFO - PROCESS | 26333 | 13:50:34 INFO - PROCESS | 26333 | Cycle 1(1): loaded http://localhost:55996/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:55996/tests/kraken/driver.html?testName=stanford-crypto-aes) 13:50:35 INFO - PROCESS | 26333 | RSS: Main: 181571584 13:50:35 INFO - PROCESS | 26333 | 13:50:36 INFO - PROCESS | 26333 | Cycle 1(1): loaded http://localhost:55996/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:55996/tests/kraken/driver.html?testName=stanford-crypto-ccm) 13:50:37 INFO - PROCESS | 26333 | RSS: Main: 185696256 13:50:37 INFO - PROCESS | 26333 | 13:50:40 INFO - PROCESS | 26333 | Cycle 1(1): loaded http://localhost:55996/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:55996/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 13:50:40 INFO - PROCESS | 26333 | RSS: Main: 181395456 13:50:40 INFO - PROCESS | 26333 | 13:50:43 INFO - PROCESS | 26333 | Cycle 1(1): loaded http://localhost:55996/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:55996/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 13:50:43 INFO - PROCESS | 26333 | RSS: Main: 183926784 13:50:43 INFO - PROCESS | 26333 | 13:50:45 INFO - PROCESS | 26333 | Cycle 1(1): loaded http://localhost:55996/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:55996/tests/kraken/driver.html?testName=ai-astar) 13:50:45 INFO - PROCESS | 26333 | RSS: Main: 235741184 13:50:45 INFO - PROCESS | 26333 | 13:50:45 INFO - PROCESS | 26333 | __start_tp_report 13:50:45 INFO - PROCESS | 26333 | _x_x_mozilla_page_load 13:50:45 INFO - PROCESS | 26333 | _x_x_mozilla_page_load_details 13:50:45 INFO - PROCESS | 26333 | |i|pagename|runs| 13:50:45 INFO - PROCESS | 26333 | |0;ai-astar;101;110;110;108;109;109;108;111;109;109 13:50:45 INFO - PROCESS | 26333 | |1;audio-beat-detection;144;115;116;173;116;117;142;119;151;115 13:50:45 INFO - PROCESS | 26333 | |2;audio-dft;160;159;161;161;160;160;161;160;160;160 13:50:45 INFO - PROCESS | 26333 | |3;audio-fft;75;75;75;123;76;75;75;74;103;75 13:50:45 INFO - PROCESS | 26333 | |4;audio-oscillator;82;82;122;82;84;83;131;83;84;124 13:50:45 INFO - PROCESS | 26333 | |5;imaging-gaussian-blur;117;117;118;118;117;117;117;117;117;117 13:50:45 INFO - PROCESS | 26333 | |6;imaging-darkroom;145;146;145;145;146;145;146;146;145;145 13:50:45 INFO - PROCESS | 26333 | |7;imaging-desaturate;94;95;95;94;95;94;94;94;95;95 13:50:45 INFO - PROCESS | 26333 | |8;json-parse-financial;51;51;51;50;51;51;52;50;52;50 13:50:45 INFO - PROCESS | 26333 | |9;json-stringify-tinderbox;60;60;59;60;60;59;60;60;60;60 13:50:45 INFO - PROCESS | 26333 | |10;stanford-crypto-aes;73;71;72;79;73;72;71;71;72;76 13:50:45 INFO - PROCESS | 26333 | |11;stanford-crypto-ccm;132;116;122;118;115;117;116;114;149;125 13:50:45 INFO - PROCESS | 26333 | |12;stanford-crypto-pbkdf2;157;155;151;153;150;149;153;153;155;152 13:50:45 INFO - PROCESS | 26333 | |13;stanford-crypto-sha256-iterative;61;65;62;68;60;60;62;60;59;59 13:50:45 INFO - PROCESS | 26333 | __end_tp_report 13:50:45 INFO - PROCESS | 26333 | __start_cc_report 13:50:45 INFO - PROCESS | 26333 | _x_x_mozilla_cycle_collect,1622 13:50:45 INFO - PROCESS | 26333 | __end_cc_report 13:50:45 INFO - PROCESS | 26333 | __startTimestamp1470862245813__endTimestamp 13:50:45 INFO - PROCESS | 26333 | 13:50:45 INFO - PROCESS | 26333 | ------- Summary: start ------- 13:50:45 INFO - PROCESS | 26333 | Number of tests: 14 13:50:45 INFO - PROCESS | 26333 | 13:50:45 INFO - PROCESS | 26333 | [#0] ai-astar Cycles:10 Average:108.40 Median:109.00 stddev:2.76 (2.5%) stddev-sans-first:0.97 13:50:45 INFO - PROCESS | 26333 | Values: 101.0 110.0 110.0 108.0 109.0 109.0 108.0 111.0 109.0 109.0 13:50:45 INFO - PROCESS | 26333 | 13:50:45 INFO - PROCESS | 26333 | [#1] audio-beat-detection Cycles:10 Average:130.80 Median:130.50 stddev:20.43 (15.7%) stddev-sans-first:21.10 13:50:45 INFO - PROCESS | 26333 | Values: 144.0 115.0 116.0 173.0 116.0 117.0 142.0 119.0 151.0 115.0 13:50:45 INFO - PROCESS | 26333 | 13:50:45 INFO - PROCESS | 26333 | [#2] audio-dft Cycles:10 Average:160.20 Median:160.00 stddev:0.63 (0.4%) stddev-sans-first:0.67 13:50:45 INFO - PROCESS | 26333 | Values: 160.0 159.0 161.0 161.0 160.0 160.0 161.0 160.0 160.0 160.0 13:50:45 INFO - PROCESS | 26333 | 13:50:45 INFO - PROCESS | 26333 | [#3] audio-fft Cycles:10 Average:82.60 Median:75.00 stddev:16.71 (22.3%) stddev-sans-first:17.49 13:50:45 INFO - PROCESS | 26333 | Values: 75.0 75.0 75.0 123.0 76.0 75.0 75.0 74.0 103.0 75.0 13:50:45 INFO - PROCESS | 26333 | 13:50:45 INFO - PROCESS | 26333 | [#4] audio-oscillator Cycles:10 Average:95.70 Median:82.50 stddev:20.81 (25.2%) stddev-sans-first:21.48 13:50:45 INFO - PROCESS | 26333 | Values: 82.0 82.0 122.0 82.0 84.0 83.0 131.0 83.0 84.0 124.0 13:50:45 INFO - PROCESS | 26333 | 13:50:45 INFO - PROCESS | 26333 | [#5] imaging-gaussian-blur Cycles:10 Average:117.20 Median:117.00 stddev:0.42 (0.4%) stddev-sans-first:0.44 13:50:45 INFO - PROCESS | 26333 | Values: 117.0 117.0 118.0 118.0 117.0 117.0 117.0 117.0 117.0 117.0 13:50:45 INFO - PROCESS | 26333 | 13:50:45 INFO - PROCESS | 26333 | [#6] imaging-darkroom Cycles:10 Average:145.40 Median:145.50 stddev:0.52 (0.4%) stddev-sans-first:0.53 13:50:45 INFO - PROCESS | 26333 | Values: 145.0 146.0 145.0 145.0 146.0 145.0 146.0 146.0 145.0 145.0 13:50:45 INFO - PROCESS | 26333 | 13:50:45 INFO - PROCESS | 26333 | [#7] imaging-desaturate Cycles:10 Average:94.50 Median:95.00 stddev:0.53 (0.6%) stddev-sans-first:0.53 13:50:45 INFO - PROCESS | 26333 | Values: 94.0 95.0 95.0 94.0 95.0 94.0 94.0 94.0 95.0 95.0 13:50:45 INFO - PROCESS | 26333 | 13:50:45 INFO - PROCESS | 26333 | [#8] json-parse-financial Cycles:10 Average:50.90 Median:51.00 stddev:0.74 (1.4%) stddev-sans-first:0.78 13:50:45 INFO - PROCESS | 26333 | Values: 51.0 51.0 51.0 50.0 51.0 51.0 52.0 50.0 52.0 50.0 13:50:45 INFO - PROCESS | 26333 | 13:50:45 INFO - PROCESS | 26333 | [#9] json-stringify-tinderbox Cycles:10 Average:59.80 Median:60.00 stddev:0.42 (0.7%) stddev-sans-first:0.44 13:50:45 INFO - PROCESS | 26333 | Values: 60.0 60.0 59.0 60.0 60.0 59.0 60.0 60.0 60.0 60.0 13:50:45 INFO - PROCESS | 26333 | 13:50:45 INFO - PROCESS | 26333 | [#10] stanford-crypto-aes Cycles:10 Average:73.00 Median:72.50 stddev:2.58 (3.6%) stddev-sans-first:2.74 13:50:45 INFO - PROCESS | 26333 | Values: 73.0 71.0 72.0 79.0 73.0 72.0 71.0 71.0 72.0 76.0 13:50:45 INFO - PROCESS | 26333 | 13:50:45 INFO - PROCESS | 26333 | [#11] stanford-crypto-ccm Cycles:10 Average:122.40 Median:120.00 stddev:10.86 (9.1%) stddev-sans-first:10.95 13:50:45 INFO - PROCESS | 26333 | Values: 132.0 116.0 122.0 118.0 115.0 117.0 116.0 114.0 149.0 125.0 13:50:45 INFO - PROCESS | 26333 | 13:50:45 INFO - PROCESS | 26333 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:152.80 Median:153.00 stddev:2.44 (1.6%) stddev-sans-first:2.06 13:50:45 INFO - PROCESS | 26333 | Values: 157.0 155.0 151.0 153.0 150.0 149.0 153.0 153.0 155.0 152.0 13:50:45 INFO - PROCESS | 26333 | 13:50:45 INFO - PROCESS | 26333 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:61.60 Median:61.50 stddev:2.88 (4.7%) stddev-sans-first:3.04 13:50:45 INFO - PROCESS | 26333 | Values: 61.0 65.0 62.0 68.0 60.0 60.0 62.0 60.0 59.0 59.0 13:50:45 INFO - PROCESS | 26333 | -------- Summary: end -------- 13:50:45 INFO - PROCESS | 26333 | 13:50:46 INFO - TEST-INFO | 26333: exit 0 13:50:46 INFO - TEST-OK | kraken | took 59816ms 13:50:46 INFO - SUITE-END | took 1173s 13:50:47 INFO - Completed test suite (00:19:33) 13:50:47 INFO - javascript benchmark 13:50:47 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3205.7114859662624, 3213.5179723041542, 3228.5641438405837, 3247.8213698246595, 3248.658890816464, 5596.4403559644015, 5664.5468362531, 5672.546196304296, 5676.035074037423, 5699.572532060091, 5610.13042978338, 5645.2096706461125, 5659.122835960422, 5667.149927510865, 5695.544356451484, 5053.9241911371355, 5091.618128640348, 5098.949010509903, 5121.5390614844655, 5186.299849520323, 5178.663386879841, 5235.869103272411, 5246.632735708502, 5247.973760131213, 5254.054270231357, 5107.106256405133, 5135.152699804527, 5137.5633071188995, 5160.664556803796, 5178.300929374524, 5242.9213561796605, 5253.763580638881, 5258.526732594085, 5264.789408423659, 5279.709615971139, 5009.874753131165, 5023.924641130386, 5041.873953151164, 5167.0, 5174.508421699932, 5592.189132575794, 5603.80386686464, 5648.661080335193, 5651.8304450866535, 5680.6591604503865, 6124.387561243884, 6127.908081378783, 6128.662923539204, 6138.47822935051, 6144.139820425144, 6007.309159446676, 6058.969705151446, 6066.302375226817, 6110.08348747687, 6163.96918015407, 5683.971580142114, 5733.598648094635, 5735.795482948544, 5736.3403208631125, 5742.0, 6061.818145455643, 6254.655993920287, 6258.092576576371, 6262.12330273762, 6263.655498947511, 5807.5644326675665, 5855.970720146457, 5869.677167755729, 5950.553708471795, 5979.222701048836, 6038.849028774228, 6040.214772079602, 6077.301110372276, 6079.209702738615, 6081.331053584102, 5830.737616807254, 5970.731317090741, 6010.759569617254, 6014.578979471483, 6014.969925150346, 5963.463288304073, 6019.458248757633, 6025.849353766191, 6100.328963813977, 6107.572469927062, 4667.183242932541, 4672.649551283667, 4676.953230467651, 4677.181493238738, 4686.695364801277, 4098.692598055158, 4141.565135660712, 4171.270027745132, 4185.309423945076, 4193.895152621209, 5587.189857470726, 5638.661680299113, 5650.971745141248, 5697.6296540724725, 5720.8283751487525, 6052.636841789506, 6089.8477538061015, 6096.0855871619615, 6098.786542470993, 6124.632522048602, 5539.750711218005, 5675.233843431167, 5687.289088863892, 5698.458646428583, 5703.372629010805, 5086.287919691246, 5101.770420331094, 5104.540591346722, 5140.922886156711, 5166.5866730661455, 6288.779892703825, 6299.685015749286, 6301.873962520724, 6302.65335406557, 6303.338149494329, 5933.436323549256, 6084.269887613515, 6084.878302433838, 6086.02623580225, 6087.299960504599, 6156.507479401726, 6219.191505104308, 6227.314995350417, 6281.434670879643, 6317.7157027932935, 5935.643861368331, 5991.940080599138, 6000.039993601003, 6001.519878409629, 6033.456988871023, 5939.67331796738, 6097.542684298606, 6099.054646529795, 6107.603005804608, 6115.510759139347, 5836.970815146067, 5890.204822349099, 5891.941080589139, 5938.6436813791315, 5976.252968378953, 5906.084556893688, 5912.113183022581, 5914.378990205966, 5916.585838991229, 5919.881602367843, 5719.2279042328755, 5843.649381037151, 5856.619319744203, 5883.146943693055, 5884.029135192816, 5851.61964472308, 5920.526357891445, 5924.437178468039, 5959.940400595939, 6013.248343957005, 5801.82594522165, 5806.158107074535, 5807.245058142415, 5809.912851307318, 5836.182934389274], "value": 5579.505340986605, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [601.8134378342714, 607.7508221629133, 636.2460484326072, 637.941017910269, 646.5538778243015, 34040.31919361611, 37673.81163094188, 37774.0, 43942.56057439424, 44773.776131119295, 48031.75984120083, 48498.75750621251, 48655.51344486563, 48662.0, 48771.75614121933, 9189.494577798201, 9313.906860931405, 9325.906740932605, 9326.580303886307, 9331.766705832375, 8497.150284971483, 9195.862062069074, 10982.890171098266, 11226.607068752595, 11237.83143252852, 11312.208145429782, 11380.943095284554, 11385.715357116096, 11387.715307117347, 11392.715182120472, 11300.208985370986, 11369.829452558259, 11379.089672826154, 11379.544818207345, 11390.71523211918, 11228.15788815834, 11276.548938042468, 11285.379304138269, 11292.20954533183, 11304.660860174208, 45268.77365613184, 45493.090138197054, 45518.77240613776, 45539.31691024637, 45602.77198613986, 3938.8424463021443, 4132.173565286925, 4267.018585725301, 4437.090396468716, 4699.95300047002, 35602.465963010574, 35723.46414803754, 35899.64100358989, 36294.8185259072, 36892.63107368946, 4636.976815115903, 4652.092841895832, 4660.347551342815, 4682.672212945095, 4703.764811759398, 366819.0, 371208.0, 371515.0, 371672.0, 372440.0, 62025.0, 62353.0, 62361.0, 62416.68791656013, 62464.37535624586, 38758.6124138755, 39565.208695825924, 39685.40471892924, 42083.7895810519, 65424.345756542774, 19328.226870925286, 19629.411117666492, 19631.901840490707, 19634.803651963583, 19648.11583478747, 13046.739065218642, 13105.14816536922, 13110.803337949937, 13141.0, 13237.867621323856, 19918.402447926586, 20745.688814667792, 20851.8957405212, 21494.0, 21559.56880862374, 7701.730439434593, 7737.651805668758, 7779.899427154636, 7807.882881756778, 7874.763757087182, 1026.7535791410007, 1082.4858192358568, 1143.4682872464343, 1143.5425829668034, 1171.308927732642, 34788.478172827425, 34957.42691578885, 34976.0, 35223.64776352255, 35294.64705352914, 1141.4578075414113, 1174.4069245031205, 1195.7548702515962, 1225.8467691538558, 1276.9361531923553, 17497.47507574775, 18304.542386440444, 18329.556684668063, 18443.72334414985, 18571.16429760664, 633067.0, 635538.0, 637361.0, 640858.0, 641804.0, 63750.36249637537, 63852.68073659602, 64484.35515644877, 64648.0, 64920.67539662271, 1357.830271216098, 1446.2551785830306, 1461.8159290974345, 1462.8317743459638, 1478.5933868186119, 34983.65016349804, 36199.44719957751, 36550.26899461943, 36716.816415918816, 36815.81592092022, 54335.72832135972, 54741.452585473635, 54793.72603137118, 54821.177682334, 54930.0, 456369.0, 456479.0, 456582.0, 456983.0, 458997.0, 30478.085657430314, 30599.23501912381, 30630.846845765627, 31062.0, 33071.50392744063, 53975.730121350716, 54116.0, 54271.0, 54284.0, 54986.45013549813, 44821.0, 45683.77158114188, 45700.0, 46239.53760462487, 47037.52962470332, 44620.77689611531, 46241.53758462372, 46405.0, 46664.7666761664, 47505.52494475011, 44776.77611612051, 45523.77238113788, 45694.0, 45750.0, 46110.76944615255, 38492.42261366026, 40099.0, 40204.0, 40805.002070888666, 41185.176296473306, 30155.69844301617, 30451.543226851172, 30608.540871886493, 30918.845405772827, 30933.535996959614], "value": 22027.455834803422, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [386.28344421098876, 409.85245311687805, 424.4270235182504, 424.43126210877426, 434.25308469432565, 17551.946883187004, 19866.106025228863, 20090.39728808138, 20502.794972050277, 20818.89590552047, 19383.90308048458, 19660.803391966077, 19770.30803921863, 19976.500587485283, 20133.0, 5417.187421886709, 5567.081431563787, 5768.336641286264, 5794.101914203306, 5835.708214589275, 5748.281464816898, 5750.856228594277, 5783.768649254046, 5787.045137552299, 5825.912611310834, 5817.389174136718, 5900.734466948998, 6083.969580152093, 6089.78685745999, 6091.543134264925, 5443.618946673734, 5687.800926967557, 5727.0, 5871.412858714094, 5886.529077673776, 5669.489745922887, 5696.458836410535, 5701.914471282934, 5708.71456427177, 5713.5714821388565, 24209.757902420926, 24240.272791816096, 24482.26553203389, 24766.63783491907, 24901.87549062243, 2541.4154744408706, 2552.3236342369287, 2624.463965614562, 2724.0193530329066, 3052.86262118203, 10050.899491005142, 10137.239707021929, 10444.529996150191, 10532.894671053346, 10576.206784491194, 1935.9903200484048, 2021.777604463508, 2038.5820906714237, 2072.958540829175, 2100.6008858317023, 61983.0, 77822.0, 77878.61060694717, 82311.17688823096, 88645.99999999936, 10635.415052172204, 10693.732656683645, 11145.944270278758, 11516.124556500487, 11615.128865334964, 16006.119663418624, 16010.599735006717, 16018.439354622766, 16028.358865645243, 16061.678766424606, 8522.190391912758, 8713.782155446164, 8717.215450609348, 8981.68599756341, 9073.863892041625, 8701.21689047976, 8711.520866352284, 8777.0, 8835.22699374443, 9038.86441703375, 4736.621070314367, 4762.380890484214, 4778.928316075262, 4783.473817880031, 4852.2479015752615, 2054.969175462369, 2099.737532808399, 2106.1575369851876, 2127.648937925225, 2206.79035491628, 902.8239493298744, 992.2657233647193, 1001.329109496639, 1010.5856598794606, 1084.6203828659907, 20526.38420847377, 21439.78560214378, 21483.0, 21537.0, 21604.45988850291, 989.8069876374038, 1011.0849681038671, 1020.8264595018865, 1041.5052849896238, 1082.0153660169208, 10843.349399036084, 11750.118741094451, 12124.0, 12145.757084858253, 12155.574554890713, 509737.0, 510677.0, 534304.0, 556612.0, 559317.0, 61978.64754565771, 63216.367836321966, 86979.0, 100690.4965475168, 102633.48683256537, 998.2662742883974, 1005.4520286443906, 1009.7879445316565, 1024.5594394410475, 1046.1735229168871, 17703.734443983092, 18003.0, 18430.723539146657, 18781.530461738566, 18971.0, 31709.682903170673, 37175.62824371722, 40506.79746601248, 41166.99320729235, 43498.56501434945, 60537.394626053174, 61262.693686533064, 61495.69252153711, 61518.0, 62465.687671561354, 8300.792480187802, 8435.704750333709, 8455.069942306218, 8483.618237179218, 8517.74446766598, 12591.811122832982, 12676.239425634123, 13214.603561893158, 13607.523736669169, 13752.381142848795, 11025.44872756375, 11753.706157346134, 11807.350595716975, 11845.170838041255, 11937.522499100287, 11693.239939403911, 11787.528498859949, 11997.880021200026, 12295.938520307342, 12439.626811195678, 11846.644600661994, 11851.170418070653, 12126.696832579257, 12172.087093467848, 12286.631401057983, 8318.45930014547, 8390.580470976549, 8538.530380829114, 8549.786255343666, 8589.398742087993, 9753.268504862022, 11462.885371146182, 11922.642320730392, 11929.761404772029, 11992.940035299767], "value": 9483.693621913275, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1409.7110092431053, 1559.2905228121206, 1599.4401959314241, 1776.9378071767483, 1902.714592811078, 7996.12042675306, 8904.065073167314, 9119.544022798867, 9164.404313719611, 9451.858222126673, 9283.11810378013, 9342.859857102132, 9344.719658410242, 9348.485833279166, 9351.345405821596, 2425.114833085924, 2452.0682140786475, 2454.840435371704, 2457.5699252630807, 2458.754124587536, 2355.858648481088, 2377.2036367816804, 2436.719777225615, 2445.205308274809, 2448.3144719478573, 2393.581123303415, 2407.6990376202975, 2417.9965314394594, 2421.6125419932814, 2423.3335832646076, 2357.363511851799, 2364.9881750591308, 2365.562370961369, 2371.4427109629223, 2420.0440825873857, 2310.491691827812, 2334.684817549643, 2339.3099035784485, 2342.8008619267384, 2345.413646588353, 8936.195742383217, 9116.088391160898, 9186.219171370441, 9186.632534698603, 9203.309751768576, 5355.491228333302, 5549.139883318092, 5550.611457197998, 5561.193626924073, 5561.304836895388, 3552.2007548301685, 3584.301061293049, 3592.676659100667, 3600.8559657613664, 3635.8727444539577, 1124.162498938288, 1137.1585027079984, 1139.9601013964555, 1152.7752088342777, 1156.3119943633524, 12636.178648387826, 12668.429920653592, 12687.175333603287, 12699.238045717286, 12725.936370318183, 4350.630196433339, 4467.128909862546, 4581.289900065429, 4648.581627653527, 4653.348531205634, 10270.897291027144, 10279.691609251735, 10280.537375818107, 10295.845562316572, 10308.484575771332, 5401.243825864382, 5424.810131645373, 5436.21174929633, 5438.83683489496, 5447.809326673627, 4428.291473364311, 4843.951560484421, 4950.133726597903, 4950.777215025332, 5069.011542749202, 3751.643593858579, 3818.873432337467, 3819.7708137511836, 3854.2484215577692, 3904.2386734586485, 1132.2074547816562, 1137.066986123593, 1146.369496776786, 1146.4439746722833, 1154.8440960470398, 1084.8643919510062, 1097.9231453798318, 1099.71957150926, 1111.7554138089608, 1113.4043286841502, 6265.216847894013, 6423.518236132309, 6488.416042556192, 6498.480121590262, 6698.765543205965, 439.2094230385294, 449.1623122875813, 450.12675409705065, 455.7083466581324, 472.775431669954, 4421.204183246982, 4432.357308190359, 4464.843730469418, 4553.3397657340165, 4562.338460923148, 11345.319280843176, 11439.942800286111, 11525.423728813526, 11560.132990025782, 11577.247478913843, 10603.416812075257, 11028.338299702044, 11041.116710663127, 11306.830397544043, 11424.885751142383, 8478.74563763088, 8635.179657932485, 8636.697715579925, 8651.437656552303, 8691.0, 6377.4579160771855, 6489.707963141765, 6590.077389165616, 6642.701078451385, 6675.165604299463, 10427.426491543038, 10543.051125398752, 10546.683599492027, 10628.043476087189, 10992.890071099187, 10844.945775271073, 11043.061339786209, 11054.944725276322, 11059.0, 11065.280756751108, 7598.848023039619, 7810.218978102144, 7811.765647030598, 8313.293370063613, 8467.068622451403, 8437.198466145706, 8746.25656819152, 8978.595963181802, 8997.820043599222, 9275.21160701348, 8138.064122625974, 8232.34141268685, 8267.586620669063, 8307.0, 8431.156884311766, 7740.612969351622, 7919.12889582157, 8023.117457079833, 8031.799205019921, 8042.83914321722, 7417.962910185631, 7498.287662672037, 7530.811729706582, 7556.811079723051, 7582.962085189539, 5512.944870551403, 5670.262865827581, 5675.290588676416, 5692.637555414457, 5845.649261044351, 5087.745612719275, 5096.1336572781975, 5102.617303702163, 5300.893982120413, 5838.095095260242], "value": 4730.831453627822, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [322.9386416580849, 325.5296097139633, 335.26576796814976, 337.8513454080203, 337.89694143286306, 5340.786368545254, 5525.668459892409, 5535.363433205182, 5547.639403438774, 5571.303587051619, 5484.957858006976, 5494.835154945348, 5508.228847961289, 5517.531009864157, 5545.611807173499, 4322.9624890026325, 4327.0480494291205, 4339.783010849461, 4349.565043495657, 4370.8907277318, 4230.751928181194, 4299.978500107495, 4331.545187755288, 4334.5448727883595, 4341.1317736452675, 4291.506476755182, 4294.7974567121255, 4301.827926882921, 4303.526612072669, 4306.138772245547, 4286.549912259215, 4293.35599660052, 4321.719088259253, 4329.978350108229, 4344.9348259776125, 3717.5167228260425, 3838.328292548793, 3865.9613403865883, 3866.130120722843, 3871.9225615487812, 5386.649867758583, 5394.02907476654, 5413.566914646819, 5424.674519528801, 5459.94243223792, 4936.259561065833, 4971.676841005323, 4976.950230497685, 5007.84976450707, 5010.248462730583, 5569.936534905826, 5702.486776190163, 5717.371089180187, 5718.59969802114, 5752.5110365618975, 4172.269852775782, 4179.143275628519, 4179.561146079648, 4185.539590645027, 4199.0342221288965, 5564.387917329131, 5658.490735833753, 5947.762089516415, 5953.2856057272975, 5971.313298970631, 4253.723507971972, 4253.914921701548, 4276.957230427718, 4286.657067434598, 4297.957020429756, 4341.283688191413, 4347.217500849877, 4357.564243575681, 4387.4954380246045, 4391.736495810262, 4609.516000819933, 4745.738984355825, 4918.827841025619, 4926.310316555685, 4956.603471722254, 5640.266765320482, 5696.287964004499, 5733.741981610838, 5735.1970724098655, 5773.538116950634, 1483.1101339196612, 1497.1615895098562, 1500.647347873249, 1506.1414993453627, 1515.3786947351532, 933.5938866593053, 967.2068903499063, 971.8590804333473, 977.3549457358251, 996.6710985374809, 4791.760411979387, 4878.0, 4880.414350277918, 4895.632827537877, 4939.802407903644, 5114.769835357418, 5160.329157209614, 5161.638685291993, 5172.715500647425, 5203.271541984125, 4571.474280457724, 4888.706677599355, 4910.238912968495, 4926.0, 4941.962187940573, 2781.8191817531797, 2786.5402208635755, 2794.413173233603, 2801.3836955869674, 2825.6044153818484, 5360.3031605891765, 5394.595405344615, 5411.621186516984, 5426.75579598919, 5461.8803145355105, 5121.516064708613, 5272.472752724697, 5276.683398996072, 5286.577073834084, 5308.469153084738, 5360.740226046876, 5385.973070134624, 5392.757325920343, 5395.758975435671, 5397.677568995699, 5225.372955245395, 5253.63767189007, 5270.49930256625, 5289.656172348785, 5297.337832770903, 4900.770149582003, 5143.5627971622835, 5149.2276158576515, 5172.637915345889, 5176.4305926348825, 5387.0, 5410.810621628224, 5879.7060146993335, 5993.3706960768495, 6117.938820611737, 4235.7035007549175, 4251.447311849563, 4285.7000009999, 4310.719803212781, 4320.719153255029, 5955.851103722442, 5960.284765828128, 5961.360625827932, 5977.701114944322, 5979.760809567742, 5590.664560126405, 5735.512481439125, 5737.598368114359, 5740.885182296247, 5754.251947246831, 5746.655200687972, 5750.763585829067, 5774.509166720875, 5784.768609255582, 5805.709714514342, 5475.917861232165, 5648.915266271091, 5680.289963754531, 5689.857753556194, 5736.541076713769, 5808.303003639421, 5808.30300363959, 5808.796692115756, 5812.21535092757, 5815.9709201453725, 5633.971830140823, 5650.745716442688, 5654.8690261948595, 5661.065924122474, 5685.715714214355], "value": 4265.184298954661, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [846.1031306814775, 861.2248975921669, 887.2547060469204, 899.9820003599929, 938.4416272317968, 16826.579335516617, 18119.909400452994, 18331.541711457186, 18334.449966500993, 18476.0, 15082.547523574283, 15096.47162349318, 15206.087634741923, 15528.68942621147, 16880.1559922004, 754.528419737664, 905.642271302835, 916.3768637326615, 916.9587368568397, 918.3250311021394, 855.7347222361088, 899.8740176375313, 904.9638014479445, 910.4810258152855, 910.7723069232692, 956.9234461243084, 963.1909196275127, 963.9903600964005, 966.6954909203579, 966.8114717630065, 766.5285849202756, 948.8481842905137, 950.1068995144542, 950.3680052764937, 959.2709540749012, 662.3310456438983, 691.2223748283182, 691.8616276744672, 701.1796198447828, 701.4879138229121, 16711.164441777863, 16743.24655390498, 16756.916215418845, 16758.413455528997, 16760.161991900357, 15463.768043479356, 15771.842281577152, 16141.757873631905, 16225.2698628562, 16509.0, 14452.710945781131, 14622.488212912496, 14631.561053168421, 14631.63420914475, 15484.30320635574, 4756.476787553366, 4772.80908763649, 4836.540528649773, 4863.319135321058, 4934.3338649282605, 89964.55017724934, 90425.0, 90720.5463972676, 90989.09010909872, 91015.54492227563, 11608.477618507188, 11720.941395292968, 11966.282023078644, 12177.269363838199, 12208.145429819828, 21128.47178820511, 21753.45616359572, 21811.23660671869, 24613.876930615592, 24664.383390415383, 9699.612015519442, 9771.755706107262, 9830.115289623967, 9999.400035997864, 10028.949855250678, 5072.087024335655, 5266.2890509781455, 5344.35867695879, 5370.194470829407, 5412.079946409041, 3932.3315036443873, 4079.2453396121814, 4098.200850834059, 4098.508179018478, 4099.733517321364, 627.4823270801606, 712.262807993724, 715.9928400715926, 718.3714250031222, 734.709789633092, 699.3321378083917, 704.2781149321986, 734.6657270941708, 770.4760762681318, 806.8184658451802, 10620.415877126832, 10924.977462528434, 10999.780004399867, 11333.206675532634, 11808.822867656992, 728.5046168605294, 784.8391079828622, 786.8032991752062, 800.5756948817136, 866.4065115395974, 5208.296879921162, 5223.634345595848, 5274.604404669665, 5312.814051508179, 5350.6503799248885, 102030.97969020215, 119457.0, 122203.0, 124087.0, 127285.72714272924, 99560.0, 104967.0, 106103.0, 108328.0, 110942.44528777305, 80528.59735701284, 92624.53687731519, 93983.53008235052, 94354.5282273598, 95560.0, 9886.824898522314, 10315.793684126425, 10836.0, 11308.717282068013, 11550.191486596193, 52881.47118528766, 53708.0, 55829.72085139548, 56290.718546407006, 56395.71802140963, 76686.61656691904, 77390.22609774055, 78731.21268787468, 78965.0, 80804.0, 12926.418311176209, 13808.36755698127, 13831.723365532835, 13915.58253252404, 14419.711605767616, 16232.0, 16383.689304855345, 16613.0, 16679.66640667204, 16705.91647041757, 14352.56942291733, 15141.772873407128, 15432.459863904709, 16329.183540823149, 16347.427840025543, 15529.45646902353, 15788.763168552252, 16351.264193111101, 16863.494095177164, 16938.661226775148, 14213.715725685222, 15619.84380156184, 15905.204739762734, 16049.111159991313, 16052.117133557766, 11043.950824671643, 11592.826107608224, 11812.291262524275, 11859.407029648655, 12140.39298035077, 11967.581134660246, 12531.93734031324, 12576.119671622899, 12640.367981600699, 13047.34763261814], "value": 8422.796927535906, "unit": "score"}], "name": "dromaeo_css", "value": 7635.182098220255, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [101.0, 110.0, 110.0, 108.0, 109.0, 109.0, 108.0, 111.0, 109.0, 109.0], "value": 108.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [144.0, 115.0, 116.0, 173.0, 116.0, 117.0, 142.0, 119.0, 151.0, 115.0], "value": 130.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 159.0, 161.0, 161.0, 160.0, 160.0, 161.0, 160.0, 160.0, 160.0], "value": 160.2, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 75.0, 75.0, 123.0, 76.0, 75.0, 75.0, 74.0, 103.0, 75.0], "value": 82.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 82.0, 122.0, 82.0, 84.0, 83.0, 131.0, 83.0, 84.0, 124.0], "value": 95.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 118.0, 118.0, 117.0, 117.0, 117.0, 117.0, 117.0, 117.0], "value": 117.2, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 146.0, 145.0, 145.0, 146.0, 145.0, 146.0, 146.0, 145.0, 145.0], "value": 145.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 95.0, 95.0, 94.0, 95.0, 94.0, 94.0, 94.0, 95.0, 95.0], "value": 94.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [51.0, 51.0, 51.0, 50.0, 51.0, 51.0, 52.0, 50.0, 52.0, 50.0], "value": 50.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 60.0, 59.0, 60.0, 60.0, 59.0, 60.0, 60.0, 60.0, 60.0], "value": 59.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 71.0, 72.0, 79.0, 73.0, 72.0, 71.0, 71.0, 72.0, 76.0], "value": 73.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [132.0, 116.0, 122.0, 118.0, 115.0, 117.0, 116.0, 114.0, 149.0, 125.0], "value": 122.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [157.0, 155.0, 151.0, 153.0, 150.0, 149.0, 153.0, 153.0, 155.0, 152.0], "value": 152.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 65.0, 62.0, 68.0, 60.0, 60.0, 62.0, 60.0, 59.0, 59.0], "value": 61.6, "unit": "score"}], "name": "kraken", "value": 1455.3, "alertThreshold": 2.0}]} 13:50:47 INFO - Return code: 0 13:50:47 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 13:50:47 INFO - # TBPL SUCCESS # 13:50:47 INFO - Running post-action listener: _resource_record_post_action 13:50:47 INFO - [mozharness: 2016-08-10 20:50:47.624130Z] Finished run-tests step (success) 13:50:47 INFO - Running post-run listener: _resource_record_post_run 13:50:47 INFO - Total resource usage - Wall time: 1190s; CPU: 13.0%; Read bytes: 274432; Write bytes: 329175040; Read time: 260; Write time: 297476 13:50:47 INFO - TinderboxPrint: CPU usage
13.0% 13:50:47 INFO - TinderboxPrint: I/O read bytes / time
274,432 / 260 13:50:47 INFO - TinderboxPrint: I/O write bytes / time
329,175,040 / 297,476 13:50:47 INFO - TinderboxPrint: CPU idle
8,262.8 (87.0%) 13:50:47 INFO - TinderboxPrint: CPU user
1,189.1 (12.5%) 13:50:47 INFO - TinderboxPrint: Swap in / out
0 / 0 13:50:47 INFO - install - Wall time: 16s; CPU: 13.0%; Read bytes: 0; Write bytes: 78581760; Read time: 0; Write time: 45940 13:50:47 INFO - run-tests - Wall time: 1174s; CPU: 13.0%; Read bytes: 270336; Write bytes: 250593280; Read time: 252; Write time: 251536 13:50:48 INFO - Running post-run listener: _upload_blobber_files 13:50:48 INFO - Blob upload gear active. 13:50:48 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 13:50:48 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 13:50:48 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'] 13:50:48 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 13:50:48 INFO - (blobuploader) - INFO - Open directory for files ... 13:50:48 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 13:50:48 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 13:50:48 INFO - (blobuploader) - INFO - Uploading, attempt #1. 13:50:49 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 13:50:49 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 13:50:49 INFO - (blobuploader) - INFO - Done attempting. 13:50:49 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 13:50:49 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 13:50:49 INFO - (blobuploader) - INFO - Uploading, attempt #1. 13:50:50 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 13:50:50 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 13:50:50 INFO - (blobuploader) - INFO - Done attempting. 13:50:50 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 13:50:50 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 13:50:50 INFO - (blobuploader) - INFO - Uploading, attempt #1. 13:50:54 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 13:50:54 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 13:50:54 INFO - (blobuploader) - INFO - Done attempting. 13:50:54 INFO - (blobuploader) - INFO - Iteration through files over. 13:50:54 INFO - Return code: 0 13:50:54 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 13:50:54 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 13:50:54 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/8c20b370366208078d7b2cc7513e88e9dcab6f04f528d92c16052d9be0f6628913e97f60fde6276137ac7b6e6e7e7c0a168c5665a788764de30f9e1304dfe421", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/bd116e32154974b138456bf2bcb4950e0fe2cdacea00edda9591e94f7366319d09f0bfd5042bc65dcdc47d21860276f5ac8c7cc9926c9d3249cd92e03043bf1b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/510450f9394c85fcb20eb1bfd93114b78223f75f05fec2ce14042e26504f5f315df773e0991fcd535abacb1128e715f52db9a1cbb1420badab6369be372b9ef7"} 13:50:54 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 13:50:54 INFO - Writing to file /builds/slave/test/properties/blobber_files 13:50:54 INFO - Contents: 13:50:54 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/8c20b370366208078d7b2cc7513e88e9dcab6f04f528d92c16052d9be0f6628913e97f60fde6276137ac7b6e6e7e7c0a168c5665a788764de30f9e1304dfe421", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/bd116e32154974b138456bf2bcb4950e0fe2cdacea00edda9591e94f7366319d09f0bfd5042bc65dcdc47d21860276f5ac8c7cc9926c9d3249cd92e03043bf1b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/510450f9394c85fcb20eb1bfd93114b78223f75f05fec2ce14042e26504f5f315df773e0991fcd535abacb1128e715f52db9a1cbb1420badab6369be372b9ef7"} 13:50:54 INFO - Running post-run listener: copy_logs_to_upload_dir 13:50:54 INFO - Copying logs to upload dir... 13:50:54 INFO - mkdir: /builds/slave/test/build/upload/logs 13:50:54 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1689.466912 ========= master_lag: 0.00 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 28 mins, 9 secs) (at 2016-08-10 13:50:53.623674) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-10 13:50:53.627571) ========= 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=d6bbd613908ccac0c6c9cb47000001f7-1470860560.207097-532244655 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/8c20b370366208078d7b2cc7513e88e9dcab6f04f528d92c16052d9be0f6628913e97f60fde6276137ac7b6e6e7e7c0a168c5665a788764de30f9e1304dfe421", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/bd116e32154974b138456bf2bcb4950e0fe2cdacea00edda9591e94f7366319d09f0bfd5042bc65dcdc47d21860276f5ac8c7cc9926c9d3249cd92e03043bf1b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/510450f9394c85fcb20eb1bfd93114b78223f75f05fec2ce14042e26504f5f315df773e0991fcd535abacb1128e715f52db9a1cbb1420badab6369be372b9ef7"} build_url:https://queue.taskcluster.net/v1/task/Py85bT1pTG6mykdB315W-g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011238 build_url: 'https://queue.taskcluster.net/v1/task/Py85bT1pTG6mykdB315W-g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/8c20b370366208078d7b2cc7513e88e9dcab6f04f528d92c16052d9be0f6628913e97f60fde6276137ac7b6e6e7e7c0a168c5665a788764de30f9e1304dfe421", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/bd116e32154974b138456bf2bcb4950e0fe2cdacea00edda9591e94f7366319d09f0bfd5042bc65dcdc47d21860276f5ac8c7cc9926c9d3249cd92e03043bf1b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/510450f9394c85fcb20eb1bfd93114b78223f75f05fec2ce14042e26504f5f315df773e0991fcd535abacb1128e715f52db9a1cbb1420badab6369be372b9ef7"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-10 13:50:53.652443) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-10 13:50:53.652805) ========= 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=d6bbd613908ccac0c6c9cb47000001f7-1470860560.207097-532244655 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004426 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-10 13:50:53.735971) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-10 13:50:53.736291) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-10 13:50:53.736572) ========= ========= Total master_lag: 0.16 =========