builder: mozilla-beta_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-011 starttime: 1475532416.24 results: success (0) buildid: 20161003130959 builduid: f49f7d6ae8464bb0bc8fc5b6545f05dd revision: 2f52a44aaffa799845357d77c74b371e81cba917 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-10-03 15:06:56.239181) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-10-03 15:06:56.239722) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-03 15:06:56.240027) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-03 15:06:56.256217) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-03 15:06:56.256532) ========= 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=5cf47e208febbcd81da23b2e0000026f-1475532234.512057-869174694 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-10-03 15:06:57-- 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% 225M=0s 2016-10-03 15:06:57 (225 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.157857 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-03 15:06:56.434840) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-03 15:06:56.435146) ========= 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=5cf47e208febbcd81da23b2e0000026f-1475532234.512057-869174694 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.014881 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-03 15:06:56.487306) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-03 15:06:56.488156) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 2f52a44aaffa799845357d77c74b371e81cba917 --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-beta --rev 2f52a44aaffa799845357d77c74b371e81cba917 --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=5cf47e208febbcd81da23b2e0000026f-1475532234.512057-869174694 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-10-03 15:06:57,367 truncating revision to first 12 chars 2016-10-03 15:06:57,368 Setting DEBUG logging. 2016-10-03 15:06:57,368 attempt 1/10 2016-10-03 15:06:57,368 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/2f52a44aaffa?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-10-03 15:06:57,665 unpacking tar archive at: mozilla-beta-2f52a44aaffa/testing/mozharness/ program finished with exit code 0 elapsedTime=0.618567 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-03 15:06:57.124343) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-03 15:06:57.124941) ========= script_repo_revision: 2f52a44aaffa799845357d77c74b371e81cba917 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-03 15:06:57.125335) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-03 15:06:57.125608) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-03 15:06:57.135337) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 27 mins, 59 secs) (at 2016-10-03 15:06:57.135698) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta 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-Beta', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Beta'] 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=5cf47e208febbcd81da23b2e0000026f-1475532234.512057-869174694 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 15:06:58 INFO - MultiFileLogger online at 20161003 15:06:58 in /builds/slave/test 15:06:58 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta 15:06:58 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 15:06:58 INFO - {'append_to_log': False, 15:06:58 INFO - 'base_work_dir': '/builds/slave/test', 15:06:58 INFO - 'blob_upload_branch': 'Mozilla-Beta', 15:06:58 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 15:06:58 INFO - 'branch': 'Mozilla-Beta', 15:06:58 INFO - 'buildbot_json_path': 'buildprops.json', 15:06:58 INFO - 'config_files': ('talos/linux_config.py',), 15:06:58 INFO - 'default_actions': ('clobber', 15:06:58 INFO - 'read-buildbot-config', 15:06:58 INFO - 'download-and-extract', 15:06:58 INFO - 'populate-webroot', 15:06:58 INFO - 'create-virtualenv', 15:06:58 INFO - 'install', 15:06:58 INFO - 'run-tests'), 15:06:58 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 15:06:58 INFO - 'download_minidump_stackwalk': True, 15:06:58 INFO - 'download_symbols': 'ondemand', 15:06:58 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 15:06:58 INFO - 'tooltool.py': '/tools/tooltool.py', 15:06:58 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 15:06:58 INFO - '/tools/misc-python/virtualenv.py')}, 15:06:58 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 15:06:58 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 15:06:58 INFO - 'installer_path': 'installer.exe', 15:06:58 INFO - 'log_level': 'info', 15:06:58 INFO - 'log_name': 'talos', 15:06:58 INFO - 'log_to_console': True, 15:06:58 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 15:06:58 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 15:06:58 INFO - 'opt_config_files': (), 15:06:58 INFO - 'pip_index': False, 15:06:58 INFO - 'sps_profile': False, 15:06:58 INFO - 'sps_profile_interval': 0, 15:06:58 INFO - 'suite': 'dromaeojs', 15:06:58 INFO - 'system_bits': '32', 15:06:58 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 15:06:58 INFO - 'title': 'talos-linux64-ix-011', 15:06:58 INFO - 'tooltool_cache': '/builds/tooltool_cache', 15:06:58 INFO - 'use_talos_json': True, 15:06:58 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 15:06:58 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 15:06:58 INFO - 'work_dir': 'build'} 15:06:58 INFO - [mozharness: 2016-10-03 22:06:58.113150Z] Running clobber step. 15:06:58 INFO - Running pre-action listener: _resource_record_pre_action 15:06:58 INFO - Running main action method: clobber 15:06:58 INFO - rmtree: /builds/slave/test/build 15:06:58 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 15:06:59 INFO - Running post-action listener: _resource_record_post_action 15:06:59 INFO - [mozharness: 2016-10-03 22:06:59.087853Z] Finished clobber step (success) 15:06:59 INFO - [mozharness: 2016-10-03 22:06:59.087952Z] Running read-buildbot-config step. 15:06:59 INFO - Running pre-action listener: _resource_record_pre_action 15:06:59 INFO - Running main action method: read_buildbot_config 15:06:59 INFO - Using buildbot properties: 15:06:59 INFO - { 15:06:59 INFO - "project": "", 15:06:59 INFO - "product": "firefox", 15:06:59 INFO - "script_repo_revision": "production", 15:06:59 INFO - "builddir": "mozilla-beta_ubuntu64_hw_test-dromaeojs", 15:06:59 INFO - "repository": "", 15:06:59 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-beta pgo talos dromaeojs", 15:06:59 INFO - "buildid": "20161003130959", 15:06:59 INFO - "pgo_build": "True", 15:06:59 INFO - "basedir": "/builds/slave/test", 15:06:59 INFO - "buildnumber": 405, 15:06:59 INFO - "slavename": "talos-linux64-ix-011", 15:06:59 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 15:06:59 INFO - "platform": "ubuntu64_hw", 15:06:59 INFO - "scheduler": "tests-mozilla-beta-linux64-pgo-talos", 15:06:59 INFO - "branch": "mozilla-beta", 15:06:59 INFO - "repo_path": "releases/mozilla-beta", 15:06:59 INFO - "revision": "2f52a44aaffa799845357d77c74b371e81cba917", 15:06:59 INFO - "stage_platform": "linux64-pgo", 15:06:59 INFO - "builduid": "f49f7d6ae8464bb0bc8fc5b6545f05dd", 15:06:59 INFO - "slavebuilddir": "test" 15:06:59 INFO - } 15:06:59 INFO - Found installer url https://queue.taskcluster.net/v1/task/bakuT_8zR-CFGYbgmCQvxg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2. 15:06:59 INFO - Running post-action listener: _resource_record_post_action 15:06:59 INFO - [mozharness: 2016-10-03 22:06:59.090455Z] Finished read-buildbot-config step (success) 15:06:59 INFO - [mozharness: 2016-10-03 22:06:59.090544Z] Running download-and-extract step. 15:06:59 INFO - Running pre-action listener: _resource_record_pre_action 15:06:59 INFO - Running main action method: download_and_extract 15:06:59 INFO - mkdir: /builds/slave/test/build/tests 15:06: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')]} 15:06:59 INFO - https://queue.taskcluster.net/v1/task/bakuT_8zR-CFGYbgmCQvxg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 15:06:59 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bakuT_8zR-CFGYbgmCQvxg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 15:06:59 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bakuT_8zR-CFGYbgmCQvxg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 15:06:59 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bakuT_8zR-CFGYbgmCQvxg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 15:06:59 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bakuT_8zR-CFGYbgmCQvxg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.test_packages.json'}, attempt #1 15:06:59 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bakuT_8zR-CFGYbgmCQvxg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 15:06:59 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 15:06:59 INFO - retry: Failed, sleeping 30 seconds before retrying 15:07:29 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bakuT_8zR-CFGYbgmCQvxg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.test_packages.json'}, attempt #2 15:07:29 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bakuT_8zR-CFGYbgmCQvxg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 15:07:29 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 15:07:29 INFO - retry: Failed, sleeping 60 seconds before retrying 15:08:29 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bakuT_8zR-CFGYbgmCQvxg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.test_packages.json'}, attempt #3 15:08:29 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bakuT_8zR-CFGYbgmCQvxg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 15:08:29 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 15:08:29 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bakuT_8zR-CFGYbgmCQvxg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.test_packages.json! 15:08:29 INFO - Caught exception: HTTP Error 404: Not Found 15:08:29 INFO - Caught exception: HTTP Error 404: Not Found 15:08:29 INFO - Caught exception: HTTP Error 404: Not Found 15:08:29 INFO - trying https://queue.taskcluster.net/v1/task/bakuT_8zR-CFGYbgmCQvxg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 15:08:29 INFO - Downloading https://queue.taskcluster.net/v1/task/bakuT_8zR-CFGYbgmCQvxg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 15:08:29 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/bakuT_8zR-CFGYbgmCQvxg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.test_packages.json'}, attempt #1 15:08:29 INFO - Downloaded 1327 bytes. 15:08:29 INFO - Reading from file /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 15:08:29 INFO - Using the following test package requirements: 15:08:29 INFO - {u'common': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip'], 15:08:29 INFO - u'cppunittest': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 15:08:29 INFO - u'firefox-50.0.en-US.linux-x86_64.cppunittest.tests.zip'], 15:08:29 INFO - u'gtest': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 15:08:29 INFO - u'firefox-50.0.en-US.linux-x86_64.gtest.tests.zip'], 15:08:29 INFO - u'jittest': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 15:08:29 INFO - u'jsshell-linux-x86_64.zip'], 15:08:29 INFO - u'mochitest': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 15:08:29 INFO - u'firefox-50.0.en-US.linux-x86_64.mochitest.tests.zip'], 15:08:29 INFO - u'mozbase': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip'], 15:08:29 INFO - u'reftest': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 15:08:29 INFO - u'firefox-50.0.en-US.linux-x86_64.reftest.tests.zip'], 15:08:29 INFO - u'talos': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 15:08:29 INFO - u'firefox-50.0.en-US.linux-x86_64.talos.tests.zip'], 15:08:29 INFO - u'web-platform': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 15:08:29 INFO - u'firefox-50.0.en-US.linux-x86_64.web-platform.tests.zip'], 15:08:29 INFO - u'xpcshell': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 15:08:29 INFO - u'firefox-50.0.en-US.linux-x86_64.xpcshell.tests.zip']} 15:08:29 INFO - Downloading packages: [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 15:08:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:08:29 INFO - https://queue.taskcluster.net/v1/task/bakuT_8zR-CFGYbgmCQvxg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 15:08:29 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bakuT_8zR-CFGYbgmCQvxg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 15:08:29 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bakuT_8zR-CFGYbgmCQvxg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 15:08:29 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bakuT_8zR-CFGYbgmCQvxg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 15:08:29 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bakuT_8zR-CFGYbgmCQvxg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip'}, attempt #1 15:08:29 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bakuT_8zR-CFGYbgmCQvxg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 15:08:29 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 15:08:29 INFO - retry: Failed, sleeping 30 seconds before retrying 15:08:59 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bakuT_8zR-CFGYbgmCQvxg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip'}, attempt #2 15:08:59 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bakuT_8zR-CFGYbgmCQvxg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 15:08:59 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 15:08:59 INFO - retry: Failed, sleeping 60 seconds before retrying 15:09:59 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bakuT_8zR-CFGYbgmCQvxg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip'}, attempt #3 15:09:59 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bakuT_8zR-CFGYbgmCQvxg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 15:09:59 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 15:09:59 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bakuT_8zR-CFGYbgmCQvxg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip! 15:09:59 INFO - Caught exception: HTTP Error 404: Not Found 15:09:59 INFO - Caught exception: HTTP Error 404: Not Found 15:09:59 INFO - Caught exception: HTTP Error 404: Not Found 15:09:59 INFO - trying https://queue.taskcluster.net/v1/task/bakuT_8zR-CFGYbgmCQvxg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 15:09:59 INFO - Downloading https://queue.taskcluster.net/v1/task/bakuT_8zR-CFGYbgmCQvxg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 15:09:59 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/bakuT_8zR-CFGYbgmCQvxg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip'}, attempt #1 15:10:00 INFO - Downloaded 21773823 bytes. 15:10:00 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 15:10:00 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 15:10:01 INFO - Return code: 0 15:10:01 INFO - Downloading packages: [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', u'firefox-50.0.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 15:10:01 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')]} 15:10:01 INFO - https://queue.taskcluster.net/v1/task/bakuT_8zR-CFGYbgmCQvxg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 15:10:01 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bakuT_8zR-CFGYbgmCQvxg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 15:10:01 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bakuT_8zR-CFGYbgmCQvxg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 15:10:01 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bakuT_8zR-CFGYbgmCQvxg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 15:10:01 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bakuT_8zR-CFGYbgmCQvxg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip'}, attempt #1 15:10:01 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bakuT_8zR-CFGYbgmCQvxg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 15:10:01 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 15:10:01 INFO - retry: Failed, sleeping 30 seconds before retrying 15:10:31 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bakuT_8zR-CFGYbgmCQvxg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip'}, attempt #2 15:10:31 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bakuT_8zR-CFGYbgmCQvxg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 15:10:31 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 15:10:31 INFO - retry: Failed, sleeping 60 seconds before retrying 15:11:31 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bakuT_8zR-CFGYbgmCQvxg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip'}, attempt #3 15:11:31 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bakuT_8zR-CFGYbgmCQvxg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 15:11:31 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 15:11:31 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bakuT_8zR-CFGYbgmCQvxg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip! 15:11:31 INFO - Caught exception: HTTP Error 404: Not Found 15:11:31 INFO - Caught exception: HTTP Error 404: Not Found 15:11:31 INFO - Caught exception: HTTP Error 404: Not Found 15:11:31 INFO - trying https://queue.taskcluster.net/v1/task/bakuT_8zR-CFGYbgmCQvxg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 15:11:31 INFO - Downloading https://queue.taskcluster.net/v1/task/bakuT_8zR-CFGYbgmCQvxg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 15:11:31 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/bakuT_8zR-CFGYbgmCQvxg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip'}, attempt #1 15:11:32 INFO - Downloaded 21773823 bytes. 15:11:32 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 15:11:32 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 15:11:34 INFO - Return code: 0 15:11:34 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')]} 15:11:34 INFO - https://queue.taskcluster.net/v1/task/bakuT_8zR-CFGYbgmCQvxg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 15:11:34 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bakuT_8zR-CFGYbgmCQvxg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 15:11:34 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bakuT_8zR-CFGYbgmCQvxg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 15:11:34 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bakuT_8zR-CFGYbgmCQvxg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 15:11:34 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bakuT_8zR-CFGYbgmCQvxg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 15:11:34 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bakuT_8zR-CFGYbgmCQvxg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 15:11:34 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 15:11:34 INFO - retry: Failed, sleeping 30 seconds before retrying 15:12:04 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bakuT_8zR-CFGYbgmCQvxg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip'}, attempt #2 15:12:04 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bakuT_8zR-CFGYbgmCQvxg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 15:12:04 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 15:12:04 INFO - retry: Failed, sleeping 60 seconds before retrying 15:13:04 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bakuT_8zR-CFGYbgmCQvxg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip'}, attempt #3 15:13:04 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bakuT_8zR-CFGYbgmCQvxg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 15:13:04 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 15:13:04 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bakuT_8zR-CFGYbgmCQvxg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip! 15:13:04 INFO - Caught exception: HTTP Error 404: Not Found 15:13:04 INFO - Caught exception: HTTP Error 404: Not Found 15:13:04 INFO - Caught exception: HTTP Error 404: Not Found 15:13:04 INFO - trying https://queue.taskcluster.net/v1/task/bakuT_8zR-CFGYbgmCQvxg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 15:13:04 INFO - Downloading https://queue.taskcluster.net/v1/task/bakuT_8zR-CFGYbgmCQvxg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 15:13:04 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/bakuT_8zR-CFGYbgmCQvxg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 15:13:05 INFO - Downloaded 14413740 bytes. 15:13:05 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 15:13:05 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip -d /builds/slave/test/build/tests 15:13:05 INFO - Return code: 0 15:13:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:13:05 INFO - https://queue.taskcluster.net/v1/task/bakuT_8zR-CFGYbgmCQvxg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 15:13:05 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bakuT_8zR-CFGYbgmCQvxg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 15:13:05 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bakuT_8zR-CFGYbgmCQvxg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 15:13:05 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bakuT_8zR-CFGYbgmCQvxg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 15:13:05 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bakuT_8zR-CFGYbgmCQvxg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.tar.bz2'}, attempt #1 15:13:05 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bakuT_8zR-CFGYbgmCQvxg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 15:13:05 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 15:13:05 INFO - retry: Failed, sleeping 30 seconds before retrying 15:13:35 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bakuT_8zR-CFGYbgmCQvxg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.tar.bz2'}, attempt #2 15:13:35 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bakuT_8zR-CFGYbgmCQvxg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 15:13:35 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 15:13:35 INFO - retry: Failed, sleeping 60 seconds before retrying 15:14:35 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bakuT_8zR-CFGYbgmCQvxg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.tar.bz2'}, attempt #3 15:14:35 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bakuT_8zR-CFGYbgmCQvxg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 15:14:35 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 15:14:35 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bakuT_8zR-CFGYbgmCQvxg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.tar.bz2! 15:14:35 INFO - Caught exception: HTTP Error 404: Not Found 15:14:35 INFO - Caught exception: HTTP Error 404: Not Found 15:14:35 INFO - Caught exception: HTTP Error 404: Not Found 15:14:35 INFO - trying https://queue.taskcluster.net/v1/task/bakuT_8zR-CFGYbgmCQvxg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 15:14:35 INFO - Downloading https://queue.taskcluster.net/v1/task/bakuT_8zR-CFGYbgmCQvxg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 15:14:35 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/bakuT_8zR-CFGYbgmCQvxg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.tar.bz2'}, attempt #1 15:14:36 INFO - Downloaded 56784716 bytes. 15:14:36 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/bakuT_8zR-CFGYbgmCQvxg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 15:14:36 INFO - mkdir: /builds/slave/test/properties 15:14:36 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 15:14:36 INFO - Writing to file /builds/slave/test/properties/build_url 15:14:36 INFO - Contents: 15:14:36 INFO - build_url:https://queue.taskcluster.net/v1/task/bakuT_8zR-CFGYbgmCQvxg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 15:14:37 INFO - Running post-action listener: _resource_record_post_action 15:14:37 INFO - Running post-action listener: set_extra_try_arguments 15:14:37 INFO - [mozharness: 2016-10-03 22:14:37.321427Z] Finished download-and-extract step (success) 15:14:37 INFO - [mozharness: 2016-10-03 22:14:37.321661Z] Running populate-webroot step. 15:14:37 INFO - Running pre-action listener: _resource_record_pre_action 15:14:37 INFO - Running main action method: populate_webroot 15:14:37 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 15:14:37 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 15:14:37 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 15:14:37 INFO - '--tppagecycles', 15:14:37 INFO - '7'], 15:14:37 INFO - 'tests': ['tsvgm']}}, 15:14:37 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 15:14:37 INFO - 'tests': ['tresize', 'tcanvasmark']}, 15:14:37 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 15:14:37 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 15:14:37 INFO - 'tests': ['dromaeo_css', 'kraken']}, 15:14:37 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 15:14:37 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:14:37 INFO - 'talos_options': ['--disable-e10s'], 15:14:37 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:14:37 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:14:37 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:14:37 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:14:37 INFO - 'talos_options': ['--disable-e10s'], 15:14:37 INFO - 'tests': ['damp', 'tps']}, 15:14:37 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:14:37 INFO - 'tests': ['damp', 'tps']}, 15:14:37 INFO - 'g3': {'talos_options': ['--disable-e10s'], 15:14:37 INFO - 'tests': ['dromaeo_dom']}, 15:14:37 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 15:14:37 INFO - 'g4': {'talos_options': ['--disable-e10s'], 15:14:37 INFO - 'tests': ['basic_compositor_video']}, 15:14:37 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 15:14:37 INFO - 'other': {'talos_options': ['--disable-e10s'], 15:14:37 INFO - 'tests': ['a11yr', 15:14:37 INFO - 'ts_paint', 15:14:37 INFO - 'tpaint', 15:14:37 INFO - 'sessionrestore', 15:14:37 INFO - 'sessionrestore_no_auto_restore', 15:14:37 INFO - 'tabpaint']}, 15:14:37 INFO - 'other-e10s': {'tests': ['a11yr', 15:14:37 INFO - 'ts_paint', 15:14:37 INFO - 'tpaint', 15:14:37 INFO - 'sessionrestore', 15:14:37 INFO - 'sessionrestore_no_auto_restore', 15:14:37 INFO - 'tabpaint']}, 15:14:37 INFO - 'other-e10s_l64': {'tests': ['a11yr', 15:14:37 INFO - 'ts_paint', 15:14:37 INFO - 'tpaint', 15:14:37 INFO - 'sessionrestore', 15:14:37 INFO - 'sessionrestore_no_auto_restore', 15:14:37 INFO - 'tabpaint']}, 15:14:37 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 15:14:37 INFO - 'ts_paint', 15:14:37 INFO - 'tpaint', 15:14:37 INFO - 'sessionrestore', 15:14:37 INFO - 'sessionrestore_no_auto_restore', 15:14:37 INFO - 'tabpaint']}, 15:14:37 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 15:14:37 INFO - 'tests': ['a11yr', 15:14:37 INFO - 'ts_paint', 15:14:37 INFO - 'tpaint', 15:14:37 INFO - 'sessionrestore', 15:14:37 INFO - 'sessionrestore_no_auto_restore', 15:14:37 INFO - 'tabpaint']}, 15:14:37 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 15:14:37 INFO - 'tests': ['a11yr', 15:14:37 INFO - 'ts_paint', 15:14:37 INFO - 'tpaint', 15:14:37 INFO - 'sessionrestore', 15:14:37 INFO - 'sessionrestore_no_auto_restore', 15:14:37 INFO - 'tabpaint']}, 15:14:37 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 15:14:37 INFO - 'tests': ['tsvgx', 15:14:37 INFO - 'tsvgr_opacity', 15:14:37 INFO - 'tart', 15:14:37 INFO - 'tscrollx', 15:14:37 INFO - 'cart']}, 15:14:37 INFO - 'svgr-e10s': {'tests': ['tsvgx', 15:14:37 INFO - 'tsvgr_opacity', 15:14:37 INFO - 'tart', 15:14:37 INFO - 'tscrollx', 15:14:37 INFO - 'cart']}, 15:14:37 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:14:37 INFO - 'talos_options': ['--disable-e10s'], 15:14:37 INFO - 'tests': ['tp5o']}, 15:14:37 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:14:37 INFO - 'tests': ['tp5o']}, 15:14:37 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:14:37 INFO - 'talos_options': ['--disable-e10s', 15:14:37 INFO - '--xperf_path', 15:14:37 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:14:37 INFO - 'tests': ['tp5n']}, 15:14:37 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:14:37 INFO - 'talos_options': ['--xperf_path', 15:14:37 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:14:37 INFO - 'tests': ['tp5n']}}, 15:14:37 INFO - 'talos.zip': {'path': '', 15:14:37 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 15:14:37 INFO - Running post-action listener: _resource_record_post_action 15:14:37 INFO - [mozharness: 2016-10-03 22:14:37.340003Z] Finished populate-webroot step (success) 15:14:37 INFO - [mozharness: 2016-10-03 22:14:37.340092Z] Running create-virtualenv step. 15:14:37 INFO - Running pre-action listener: _resource_record_pre_action 15:14:37 INFO - Running main action method: create_virtualenv 15:14:37 INFO - Creating virtualenv /builds/slave/test/build/venv 15:14:37 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 15:14:37 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 15:14:37 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 15:14:37 INFO - Using real prefix '/usr' 15:14:37 INFO - New python executable in /builds/slave/test/build/venv/bin/python 15:14:37 INFO - Installing distribute.............................................................................................................................................................................................done. 15:14:39 INFO - Installing pip.................done. 15:14:39 INFO - Return code: 0 15:14:39 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 15:14:39 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')]} 15:14:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:14:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:14:39 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')]} 15:14:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:14:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:14:39 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 0x2213030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23768a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x24d4180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24d23b0>, '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 0x24d32b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24d3740>, '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': '5cf47e208febbcd81da23b2e0000026f-1475532234.512057-869174694', '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 15:14:39 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 15:14:39 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 15:14:39 INFO - Using env: {'DISPLAY': ':0', 15:14:39 INFO - 'HOME': '/home/cltbld', 15:14:39 INFO - 'LANG': 'en_US.UTF-8', 15:14:39 INFO - 'LANGUAGE': 'en_US:en', 15:14:39 INFO - 'LOGNAME': 'cltbld', 15:14:39 INFO - 'MAIL': '/var/mail/cltbld', 15:14:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:14:39 INFO - 'MOZ_NO_REMOTE': '1', 15:14:39 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:14:39 INFO - 'NO_EM_RESTART': '1', 15:14:39 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:14:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:14:39 INFO - 'PWD': '/builds/slave/test', 15:14:39 INFO - 'SHELL': '/bin/bash', 15:14:39 INFO - 'SHLVL': '1', 15:14:39 INFO - 'TERM': 'linux', 15:14:39 INFO - 'TMOUT': '86400', 15:14:39 INFO - 'USER': 'cltbld', 15:14:39 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1475532234.512057-869174694', 15:14:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:14:39 INFO - '_': '/tools/buildbot/bin/python'} 15:14:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:14:40 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 15:14:40 INFO - Cleaning up... 15:14:40 INFO - Return code: 0 15:14:40 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 15:14:40 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')]} 15:14:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:14:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:14:40 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')]} 15:14:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:14:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:14:40 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 0x2213030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23768a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x24d4180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24d23b0>, '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 0x24d32b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24d3740>, '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': '5cf47e208febbcd81da23b2e0000026f-1475532234.512057-869174694', '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 15:14:40 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 15:14:40 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 15:14:40 INFO - Using env: {'DISPLAY': ':0', 15:14:40 INFO - 'HOME': '/home/cltbld', 15:14:40 INFO - 'LANG': 'en_US.UTF-8', 15:14:40 INFO - 'LANGUAGE': 'en_US:en', 15:14:40 INFO - 'LOGNAME': 'cltbld', 15:14:40 INFO - 'MAIL': '/var/mail/cltbld', 15:14:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:14:40 INFO - 'MOZ_NO_REMOTE': '1', 15:14:40 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:14:40 INFO - 'NO_EM_RESTART': '1', 15:14:40 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:14:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:14:40 INFO - 'PWD': '/builds/slave/test', 15:14:40 INFO - 'SHELL': '/bin/bash', 15:14:40 INFO - 'SHLVL': '1', 15:14:40 INFO - 'TERM': 'linux', 15:14:40 INFO - 'TMOUT': '86400', 15:14:40 INFO - 'USER': 'cltbld', 15:14:40 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1475532234.512057-869174694', 15:14:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:14:40 INFO - '_': '/tools/buildbot/bin/python'} 15:14:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:14:40 INFO - Downloading/unpacking psutil>=3.1.1 15:14:40 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 15:14:40 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 15:14:40 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 15:14:40 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 15:14:40 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 15:14:40 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 15:14:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 15:14:42 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:14:42 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:14:42 INFO - Installing collected packages: psutil 15:14:42 INFO - Running setup.py install for psutil 15:14:42 INFO - building 'psutil._psutil_linux' extension 15:14:42 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 15:14:42 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 15:14:42 INFO - building 'psutil._psutil_posix' extension 15:14:42 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 15:14:42 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 15:14:42 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:14:42 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:14:42 INFO - Successfully installed psutil 15:14:42 INFO - Cleaning up... 15:14:42 INFO - Return code: 0 15:14:42 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 15:14:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:14:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:14:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:14:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:14:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:14:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:14:42 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 0x2213030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23768a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x24d4180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24d23b0>, '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 0x24d32b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24d3740>, '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': '5cf47e208febbcd81da23b2e0000026f-1475532234.512057-869174694', '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 15:14:42 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 15:14:42 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 15:14:42 INFO - Using env: {'DISPLAY': ':0', 15:14:42 INFO - 'HOME': '/home/cltbld', 15:14:42 INFO - 'LANG': 'en_US.UTF-8', 15:14:42 INFO - 'LANGUAGE': 'en_US:en', 15:14:42 INFO - 'LOGNAME': 'cltbld', 15:14:42 INFO - 'MAIL': '/var/mail/cltbld', 15:14:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:14:42 INFO - 'MOZ_NO_REMOTE': '1', 15:14:42 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:14:42 INFO - 'NO_EM_RESTART': '1', 15:14:42 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:14:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:14:42 INFO - 'PWD': '/builds/slave/test', 15:14:42 INFO - 'SHELL': '/bin/bash', 15:14:42 INFO - 'SHLVL': '1', 15:14:42 INFO - 'TERM': 'linux', 15:14:42 INFO - 'TMOUT': '86400', 15:14:42 INFO - 'USER': 'cltbld', 15:14:42 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1475532234.512057-869174694', 15:14:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:14:42 INFO - '_': '/tools/buildbot/bin/python'} 15:14:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:14:43 INFO - Downloading/unpacking mozsystemmonitor==0.3 15:14: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 15:14: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 15:14: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 15:14: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 15:14: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 15:14: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 15:14:44 INFO - Downloading mozsystemmonitor-0.3.tar.gz 15:14:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 15:14:45 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 15:14:45 INFO - Installing collected packages: mozsystemmonitor 15:14:45 INFO - Running setup.py install for mozsystemmonitor 15:14:45 INFO - Successfully installed mozsystemmonitor 15:14:45 INFO - Cleaning up... 15:14:45 INFO - Return code: 0 15:14:45 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 15:14: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')]} 15:14:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:14:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:14: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')]} 15:14:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:14:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:14:45 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 0x2213030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23768a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x24d4180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24d23b0>, '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 0x24d32b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24d3740>, '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': '5cf47e208febbcd81da23b2e0000026f-1475532234.512057-869174694', '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 15:14:45 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 15:14:45 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 15:14:45 INFO - Using env: {'DISPLAY': ':0', 15:14:45 INFO - 'HOME': '/home/cltbld', 15:14:45 INFO - 'LANG': 'en_US.UTF-8', 15:14:45 INFO - 'LANGUAGE': 'en_US:en', 15:14:45 INFO - 'LOGNAME': 'cltbld', 15:14:45 INFO - 'MAIL': '/var/mail/cltbld', 15:14:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:14:45 INFO - 'MOZ_NO_REMOTE': '1', 15:14:45 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:14:45 INFO - 'NO_EM_RESTART': '1', 15:14:45 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:14:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:14:45 INFO - 'PWD': '/builds/slave/test', 15:14:45 INFO - 'SHELL': '/bin/bash', 15:14:45 INFO - 'SHLVL': '1', 15:14:45 INFO - 'TERM': 'linux', 15:14:45 INFO - 'TMOUT': '86400', 15:14:45 INFO - 'USER': 'cltbld', 15:14:45 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1475532234.512057-869174694', 15:14:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:14:45 INFO - '_': '/tools/buildbot/bin/python'} 15:14:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:14:45 INFO - Downloading/unpacking jsonschema==2.5.1 15:14: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 15:14: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 15:14: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 15:14: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 15:14: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 15:14: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 15:14:47 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 15:14:47 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 15:14:47 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 15:14:47 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 15:14:47 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 15:14:47 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 15:14:47 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 15:14:47 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 15:14:47 INFO - Downloading functools32-3.2.3-2.tar.gz 15:14:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 15:14:47 INFO - warning: no files found matching '*.txt' 15:14:47 INFO - no previously-included directories found matching 'build' 15:14:47 INFO - no previously-included directories found matching 'dist' 15:14:47 INFO - no previously-included directories found matching '.git*' 15:14:47 INFO - Installing collected packages: jsonschema, functools32 15:14:47 INFO - Running setup.py install for functools32 15:14:47 INFO - warning: no files found matching '*.txt' 15:14:47 INFO - no previously-included directories found matching 'build' 15:14:47 INFO - no previously-included directories found matching 'dist' 15:14:47 INFO - no previously-included directories found matching '.git*' 15:14:47 INFO - Successfully installed jsonschema functools32 15:14:47 INFO - Cleaning up... 15:14:47 INFO - Return code: 0 15:14:47 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 15:14:47 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')]} 15:14:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:14:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:14:47 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')]} 15:14:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:14:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:14:47 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 0x2213030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23768a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x24d4180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24d23b0>, '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 0x24d32b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24d3740>, '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': '5cf47e208febbcd81da23b2e0000026f-1475532234.512057-869174694', '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 15:14:47 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 15:14:47 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 15:14:47 INFO - Using env: {'DISPLAY': ':0', 15:14:47 INFO - 'HOME': '/home/cltbld', 15:14:47 INFO - 'LANG': 'en_US.UTF-8', 15:14:47 INFO - 'LANGUAGE': 'en_US:en', 15:14:47 INFO - 'LOGNAME': 'cltbld', 15:14:47 INFO - 'MAIL': '/var/mail/cltbld', 15:14:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:14:47 INFO - 'MOZ_NO_REMOTE': '1', 15:14:47 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:14:47 INFO - 'NO_EM_RESTART': '1', 15:14:47 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:14:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:14:47 INFO - 'PWD': '/builds/slave/test', 15:14:47 INFO - 'SHELL': '/bin/bash', 15:14:47 INFO - 'SHLVL': '1', 15:14:47 INFO - 'TERM': 'linux', 15:14:47 INFO - 'TMOUT': '86400', 15:14:47 INFO - 'USER': 'cltbld', 15:14:47 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1475532234.512057-869174694', 15:14:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:14:47 INFO - '_': '/tools/buildbot/bin/python'} 15:14:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:14:47 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 15:14:47 INFO - Cleaning up... 15:14:47 INFO - Return code: 0 15:14:47 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 15:14:47 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')]} 15:14:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:14:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:14:47 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')]} 15:14:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:14:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:14:47 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 0x2213030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23768a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x24d4180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24d23b0>, '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 0x24d32b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24d3740>, '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': '5cf47e208febbcd81da23b2e0000026f-1475532234.512057-869174694', '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 15:14:47 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 15:14:47 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 15:14:47 INFO - Using env: {'DISPLAY': ':0', 15:14:47 INFO - 'HOME': '/home/cltbld', 15:14:47 INFO - 'LANG': 'en_US.UTF-8', 15:14:47 INFO - 'LANGUAGE': 'en_US:en', 15:14:47 INFO - 'LOGNAME': 'cltbld', 15:14:47 INFO - 'MAIL': '/var/mail/cltbld', 15:14:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:14:47 INFO - 'MOZ_NO_REMOTE': '1', 15:14:47 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:14:47 INFO - 'NO_EM_RESTART': '1', 15:14:47 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:14:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:14:47 INFO - 'PWD': '/builds/slave/test', 15:14:47 INFO - 'SHELL': '/bin/bash', 15:14:47 INFO - 'SHLVL': '1', 15:14:47 INFO - 'TERM': 'linux', 15:14:47 INFO - 'TMOUT': '86400', 15:14:47 INFO - 'USER': 'cltbld', 15:14:47 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1475532234.512057-869174694', 15:14:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:14:47 INFO - '_': '/tools/buildbot/bin/python'} 15:14:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:14:48 INFO - Downloading/unpacking blobuploader==1.2.4 15:14: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 15:14: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 15:14: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 15:14: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 15:14: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 15:14: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 15:14:50 INFO - Downloading blobuploader-1.2.4.tar.gz 15:14:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 15:14:50 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 15:14:50 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 15:14:50 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 15:14:50 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 15:14:50 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 15:14:50 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 15:14:50 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 15:14:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 15:14:50 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 15:14:50 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 15:14:50 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 15:14:50 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 15:14:50 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 15:14:50 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 15:14:50 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 15:14:50 INFO - Downloading docopt-0.6.1.tar.gz 15:14:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 15:14:50 INFO - Installing collected packages: blobuploader, requests, docopt 15:14:50 INFO - Running setup.py install for blobuploader 15:14:50 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 15:14:50 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 15:14:50 INFO - Running setup.py install for requests 15:14:51 INFO - Running setup.py install for docopt 15:14:51 INFO - Successfully installed blobuploader requests docopt 15:14:51 INFO - Cleaning up... 15:14:51 INFO - Return code: 0 15:14:51 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:14:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:14:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:14:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:14:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:14:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:14:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:14:51 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 0x2213030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23768a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x24d4180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24d23b0>, '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 0x24d32b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24d3740>, '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': '5cf47e208febbcd81da23b2e0000026f-1475532234.512057-869174694', '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 15:14:51 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 15:14:51 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 15:14:51 INFO - Using env: {'DISPLAY': ':0', 15:14:51 INFO - 'HOME': '/home/cltbld', 15:14:51 INFO - 'LANG': 'en_US.UTF-8', 15:14:51 INFO - 'LANGUAGE': 'en_US:en', 15:14:51 INFO - 'LOGNAME': 'cltbld', 15:14:51 INFO - 'MAIL': '/var/mail/cltbld', 15:14:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:14:51 INFO - 'MOZ_NO_REMOTE': '1', 15:14:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:14:51 INFO - 'NO_EM_RESTART': '1', 15:14:51 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:14:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:14:51 INFO - 'PWD': '/builds/slave/test', 15:14:51 INFO - 'SHELL': '/bin/bash', 15:14:51 INFO - 'SHLVL': '1', 15:14:51 INFO - 'TERM': 'linux', 15:14:51 INFO - 'TMOUT': '86400', 15:14:51 INFO - 'USER': 'cltbld', 15:14:51 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1475532234.512057-869174694', 15:14:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:14:51 INFO - '_': '/tools/buildbot/bin/python'} 15:14:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:14:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:14:51 INFO - Running setup.py (path:/tmp/pip-WPFLe2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:14:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:14:51 INFO - Running setup.py (path:/tmp/pip-Wauo9e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:14:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:14:51 INFO - Running setup.py (path:/tmp/pip-osgtoy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:14:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:14:51 INFO - Running setup.py (path:/tmp/pip-cR58Tx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:14:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:14:51 INFO - Running setup.py (path:/tmp/pip-_NYaBP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:14:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:14:52 INFO - Running setup.py (path:/tmp/pip-uJFbJ6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:14:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:14:52 INFO - Running setup.py (path:/tmp/pip-2pYJJ4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:14:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:14:52 INFO - Running setup.py (path:/tmp/pip-jc__RV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:14:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:14:52 INFO - Running setup.py (path:/tmp/pip-bJKeK3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:14:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:14:52 INFO - Running setup.py (path:/tmp/pip-27hkTn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:14:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:14:52 INFO - Running setup.py (path:/tmp/pip-eIIUs4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:14:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:14:52 INFO - Running setup.py (path:/tmp/pip-joeerD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:14:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:14:52 INFO - Running setup.py (path:/tmp/pip-N8uoRg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:14:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:14:52 INFO - Running setup.py (path:/tmp/pip-DTGuwm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:14:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:14:52 INFO - Running setup.py (path:/tmp/pip-lcVd8M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:14:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:14:52 INFO - Running setup.py (path:/tmp/pip-KqBGv1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:14:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:14:52 INFO - Running setup.py (path:/tmp/pip-670tTY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:14:52 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 15:14:52 INFO - Running setup.py install for manifestparser 15:14:53 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 15:14:53 INFO - Running setup.py install for mozcrash 15:14:53 INFO - Running setup.py install for mozdebug 15:14:53 INFO - Running setup.py install for mozdevice 15:14:53 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 15:14:53 INFO - Installing dm script to /builds/slave/test/build/venv/bin 15:14:53 INFO - Running setup.py install for mozfile 15:14:53 INFO - Running setup.py install for mozhttpd 15:14:53 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 15:14:53 INFO - Running setup.py install for mozinfo 15:14:53 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 15:14:53 INFO - Running setup.py install for mozInstall 15:14:53 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 15:14:53 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 15:14:53 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 15:14:53 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 15:14:53 INFO - Running setup.py install for mozleak 15:14:53 INFO - Running setup.py install for mozlog 15:14:53 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 15:14:53 INFO - Running setup.py install for moznetwork 15:14:54 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 15:14:54 INFO - Running setup.py install for mozprocess 15:14:54 INFO - Running setup.py install for mozprofile 15:14:54 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 15:14:54 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 15:14:54 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 15:14:54 INFO - Running setup.py install for mozrunner 15:14:54 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 15:14:54 INFO - Running setup.py install for mozscreenshot 15:14:54 INFO - Running setup.py install for moztest 15:14:54 INFO - Running setup.py install for mozversion 15:14:54 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 15:14:54 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 15:14:54 INFO - Cleaning up... 15:14:54 INFO - Return code: 0 15:14:54 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:14:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:14:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:14:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:14:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:14:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:14:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:14:54 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 0x2213030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23768a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x24d4180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24d23b0>, '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 0x24d32b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24d3740>, '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': '5cf47e208febbcd81da23b2e0000026f-1475532234.512057-869174694', '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 15:14:54 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 15:14:54 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 15:14:54 INFO - Using env: {'DISPLAY': ':0', 15:14:54 INFO - 'HOME': '/home/cltbld', 15:14:54 INFO - 'LANG': 'en_US.UTF-8', 15:14:54 INFO - 'LANGUAGE': 'en_US:en', 15:14:54 INFO - 'LOGNAME': 'cltbld', 15:14:54 INFO - 'MAIL': '/var/mail/cltbld', 15:14:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:14:54 INFO - 'MOZ_NO_REMOTE': '1', 15:14:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:14:54 INFO - 'NO_EM_RESTART': '1', 15:14:54 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:14:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:14:54 INFO - 'PWD': '/builds/slave/test', 15:14:54 INFO - 'SHELL': '/bin/bash', 15:14:54 INFO - 'SHLVL': '1', 15:14:54 INFO - 'TERM': 'linux', 15:14:54 INFO - 'TMOUT': '86400', 15:14:54 INFO - 'USER': 'cltbld', 15:14:54 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1475532234.512057-869174694', 15:14:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:14:54 INFO - '_': '/tools/buildbot/bin/python'} 15:14:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:14:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:14:54 INFO - Running setup.py (path:/tmp/pip-DWIOTE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:14:54 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)) 15:14:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:14:54 INFO - Running setup.py (path:/tmp/pip-keyYMq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:14:55 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)) 15:14:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:14:55 INFO - Running setup.py (path:/tmp/pip-eWltgN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:14:55 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)) 15:14:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:14:55 INFO - Running setup.py (path:/tmp/pip-NcFeqI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:14:55 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)) 15:14:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:14:55 INFO - Running setup.py (path:/tmp/pip-97f4SQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:14:55 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)) 15:14:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:14:55 INFO - Running setup.py (path:/tmp/pip-k7lMdP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:14:55 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)) 15:14:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:14:55 INFO - Running setup.py (path:/tmp/pip-CMmtju-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:14:55 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)) 15:14:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:14:55 INFO - Running setup.py (path:/tmp/pip-2Ul7P7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:14:55 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)) 15:14:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:14:55 INFO - Running setup.py (path:/tmp/pip-TroRdg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:14:55 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)) 15:14:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:14:55 INFO - Running setup.py (path:/tmp/pip-ZrHYz0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:14:55 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)) 15:14:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:14:55 INFO - Running setup.py (path:/tmp/pip-D2urMP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:14:55 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)) 15:14:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:14:55 INFO - Running setup.py (path:/tmp/pip-nd2nBz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:14:55 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)) 15:14:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:14:55 INFO - Running setup.py (path:/tmp/pip-kWJUY5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:14:55 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)) 15:14:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:14:55 INFO - Running setup.py (path:/tmp/pip-kLuSeF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:14:55 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)) 15:14:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:14:55 INFO - Running setup.py (path:/tmp/pip-vm0kQG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:14:56 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)) 15:14:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:14:56 INFO - Running setup.py (path:/tmp/pip-eQwIXh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:14:56 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)) 15:14:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:14:56 INFO - Running setup.py (path:/tmp/pip-Uef7cO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:14:56 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)) 15:14:56 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)) 15:14:56 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)) 15:14:56 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)) 15:14:56 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)) 15:14:56 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)) 15:14:56 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 15:14:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:14:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:14:56 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:14:56 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:14:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:14:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:14:58 INFO - Downloading blessings-1.6.tar.gz 15:14:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 15:14:58 INFO - Installing collected packages: blessings 15:14:58 INFO - Running setup.py install for blessings 15:14:58 INFO - Successfully installed blessings 15:14:58 INFO - Cleaning up... 15:14:58 INFO - Return code: 0 15:14:58 INFO - Done creating virtualenv /builds/slave/test/build/venv. 15:14:58 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:14:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:14:58 INFO - Reading from file tmpfile_stdout 15:14:58 INFO - Current package versions: 15:14:58 INFO - argparse == 1.2.1 15:14:58 INFO - blessings == 1.6 15:14:58 INFO - blobuploader == 1.2.4 15:14:58 INFO - docopt == 0.6.1 15:14:58 INFO - functools32 == 3.2.3-2 15:14:58 INFO - jsonschema == 2.5.1 15:14:58 INFO - manifestparser == 1.1 15:14:58 INFO - mozInstall == 1.12 15:14:58 INFO - mozcrash == 0.17 15:14:58 INFO - mozdebug == 0.1 15:14:58 INFO - mozdevice == 0.48 15:14:58 INFO - mozfile == 1.2 15:14:58 INFO - mozhttpd == 0.7 15:14:58 INFO - mozinfo == 0.9 15:14:58 INFO - mozleak == 0.1 15:14:58 INFO - mozlog == 3.3 15:14:58 INFO - moznetwork == 0.27 15:14:58 INFO - mozprocess == 0.23 15:14:58 INFO - mozprofile == 0.28 15:14:58 INFO - mozrunner == 6.12 15:14:58 INFO - mozscreenshot == 0.1 15:14:58 INFO - mozsystemmonitor == 0.3 15:14:58 INFO - moztest == 0.7 15:14:58 INFO - mozversion == 1.4 15:14:58 INFO - psutil == 3.1.1 15:14:58 INFO - requests == 1.2.3 15:14:58 INFO - wsgiref == 0.1.2 15:14:58 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:14:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:14:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:14:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:14:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:14:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:14:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:14:58 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 0x2213030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23768a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x24d4180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24d23b0>, '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 0x24d32b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24d3740>, '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': '5cf47e208febbcd81da23b2e0000026f-1475532234.512057-869174694', '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 15:14:58 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 15:14:58 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 15:14:58 INFO - Using env: {'DISPLAY': ':0', 15:14:58 INFO - 'HOME': '/home/cltbld', 15:14:58 INFO - 'LANG': 'en_US.UTF-8', 15:14:58 INFO - 'LANGUAGE': 'en_US:en', 15:14:58 INFO - 'LOGNAME': 'cltbld', 15:14:58 INFO - 'MAIL': '/var/mail/cltbld', 15:14:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:14:58 INFO - 'MOZ_NO_REMOTE': '1', 15:14:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:14:58 INFO - 'NO_EM_RESTART': '1', 15:14:58 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:14:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:14:58 INFO - 'PWD': '/builds/slave/test', 15:14:58 INFO - 'SHELL': '/bin/bash', 15:14:58 INFO - 'SHLVL': '1', 15:14:58 INFO - 'TERM': 'linux', 15:14:58 INFO - 'TMOUT': '86400', 15:14:58 INFO - 'USER': 'cltbld', 15:14:58 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1475532234.512057-869174694', 15:14:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:14:58 INFO - '_': '/tools/buildbot/bin/python'} 15:14:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:14:58 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)) 15:14:58 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)) 15:14:58 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)) 15:14:58 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)) 15:14:58 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)) 15:14:58 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)) 15:14:58 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)) 15:14:58 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)) 15:14:58 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)) 15:14:58 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)) 15:14:58 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)) 15:14:58 INFO - Cleaning up... 15:14:58 INFO - Return code: 0 15:14:58 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 15:14:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:14:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:14:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:14:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:14:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:14:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:14:58 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2213030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23768a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x24d4180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24d23b0>, '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 0x24d32b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24d3740>, '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': '5cf47e208febbcd81da23b2e0000026f-1475532234.512057-869174694', '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 15:14:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 15:14:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 15:14:58 INFO - Using env: {'DISPLAY': ':0', 15:14:58 INFO - 'HOME': '/home/cltbld', 15:14:58 INFO - 'LANG': 'en_US.UTF-8', 15:14:58 INFO - 'LANGUAGE': 'en_US:en', 15:14:58 INFO - 'LOGNAME': 'cltbld', 15:14:58 INFO - 'MAIL': '/var/mail/cltbld', 15:14:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:14:58 INFO - 'MOZ_NO_REMOTE': '1', 15:14:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:14:58 INFO - 'NO_EM_RESTART': '1', 15:14:58 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:14:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:14:58 INFO - 'PWD': '/builds/slave/test', 15:14:58 INFO - 'SHELL': '/bin/bash', 15:14:58 INFO - 'SHLVL': '1', 15:14:58 INFO - 'TERM': 'linux', 15:14:58 INFO - 'TMOUT': '86400', 15:14:58 INFO - 'USER': 'cltbld', 15:14:58 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1475532234.512057-869174694', 15:14:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:14:58 INFO - '_': '/tools/buildbot/bin/python'} 15:14:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:14:58 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 15:14:58 INFO - Cleaning up... 15:14:58 INFO - Return code: 0 15:14:58 INFO - Running post-action listener: _resource_record_post_action 15:14:58 INFO - Running post-action listener: _start_resource_monitoring 15:14:58 INFO - Starting resource monitoring. 15:14:58 INFO - [mozharness: 2016-10-03 22:14:58.969840Z] Finished create-virtualenv step (success) 15:14:58 INFO - [mozharness: 2016-10-03 22:14:58.970317Z] Running install step. 15:14:58 INFO - Running pre-action listener: _resource_record_pre_action 15:14:58 INFO - Running main action method: install 15:14:58 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:14:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:14:59 INFO - Reading from file tmpfile_stdout 15:14:59 INFO - Detecting whether we're running mozinstall >=1.0... 15:14:59 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 15:14:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 15:14:59 INFO - Reading from file tmpfile_stdout 15:14:59 INFO - Output received: 15:14:59 INFO - Usage: mozinstall [options] installer 15:14:59 INFO - Options: 15:14:59 INFO - -h, --help show this help message and exit 15:14:59 INFO - -d DEST, --destination=DEST 15:14:59 INFO - Directory to install application into. [default: 15:14:59 INFO - "/builds/slave/test"] 15:14:59 INFO - --app=APP Application being installed. [default: firefox] 15:14:59 INFO - mkdir: /builds/slave/test/build/application 15:14:59 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 15:14:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 15:15:14 INFO - Reading from file tmpfile_stdout 15:15:14 INFO - Output received: 15:15:14 INFO - /builds/slave/test/build/application/firefox/firefox 15:15:14 INFO - Running post-action listener: _resource_record_post_action 15:15:14 INFO - [mozharness: 2016-10-03 22:15:14.047922Z] Finished install step (success) 15:15:14 INFO - [mozharness: 2016-10-03 22:15:14.048204Z] Running run-tests step. 15:15:14 INFO - Running pre-action listener: _resource_record_pre_action 15:15:14 INFO - Running main action method: run_tests 15:15:14 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 15:15:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 15:15:14 INFO - Python 2.7.3 15:15:14 INFO - Return code: 0 15:15:14 INFO - grabbing minidump binary from tooltool 15:15:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:15:14 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 0x24d23b0>, '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 0x24d32b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24d3740>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 15:15:14 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 15:15:14 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 15:15:14 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 15:15:14 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 15:15:14 INFO - Return code: 0 15:15:14 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 15:15:14 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 15:15:14 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 15:15:14 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 15:15:14 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 15:15:14 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/bakuT_8zR-CFGYbgmCQvxg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-011', '--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 15:15:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Beta --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/bakuT_8zR-CFGYbgmCQvxg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-011 --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 15:15:14 INFO - Using env: {'DISPLAY': ':0', 15:15:14 INFO - 'HOME': '/home/cltbld', 15:15:14 INFO - 'LANG': 'en_US.UTF-8', 15:15:14 INFO - 'LANGUAGE': 'en_US:en', 15:15:14 INFO - 'LOGNAME': 'cltbld', 15:15:14 INFO - 'MAIL': '/var/mail/cltbld', 15:15:14 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 15:15:14 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 15:15:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:15:14 INFO - 'MOZ_NO_REMOTE': '1', 15:15:14 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 15:15:14 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:15:14 INFO - 'NO_EM_RESTART': '1', 15:15:14 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:15:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:15:14 INFO - 'PWD': '/builds/slave/test', 15:15:14 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 15:15:14 INFO - 'SHELL': '/bin/bash', 15:15:14 INFO - 'SHLVL': '1', 15:15:14 INFO - 'TERM': 'linux', 15:15:14 INFO - 'TMOUT': '86400', 15:15:14 INFO - 'USER': 'cltbld', 15:15:14 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1475532234.512057-869174694', 15:15:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:15:14 INFO - '_': '/tools/buildbot/bin/python'} 15:15:14 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/bakuT_8zR-CFGYbgmCQvxg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-011', '--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 15:15:14 INFO - mozversion application_buildid: 20161003130959 15:15:14 INFO - mozversion application_changeset: 2f52a44aaffa799845357d77c74b371e81cba917 15:15:14 INFO - mozversion application_display_name: Firefox 15:15:14 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 15:15:14 INFO - mozversion application_name: Firefox 15:15:14 INFO - mozversion application_remotingname: firefox 15:15:14 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 15:15:14 INFO - mozversion application_vendor: Mozilla 15:15:14 INFO - mozversion application_version: 50.0 15:15:14 INFO - mozversion platform_buildid: 20161003130959 15:15:14 INFO - mozversion platform_changeset: 2f52a44aaffa799845357d77c74b371e81cba917 15:15:14 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 15:15:14 INFO - mozversion platform_version: 50.0 15:15:14 INFO - using testdate: 1475532914 15:15:14 INFO - actual date: 1475532914 15:15:14 INFO - starting webserver on 'localhost:36852' 15:15:14 INFO - SUITE-START | Running 2 tests 15:15:14 INFO - TEST-START | dromaeo_css 15:15:14 INFO - operating with platform_type : linux_ 15:15:14 INFO - Initialising browser for dromaeo_css test... 15:15:14 INFO - TEST-INFO | started process 16302 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpZN5p3e/profile http://localhost:36852/getInfo.html) 15:15:19 INFO - PROCESS | 16302 | __metrics Screen width/height:1600/1200 15:15:19 INFO - PROCESS | 16302 | colorDepth:24 15:15:19 INFO - PROCESS | 16302 | Browser inner width/height: 1024/697 15:15:19 INFO - PROCESS | 16302 | __metrics 15:15:20 INFO - PROCESS | 16302 | 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" 15:15:20 INFO - PROCESS | 16302 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 15:15:20 INFO - PROCESS | 16302 | console.error: PushService: 15:15:20 INFO - PROCESS | 16302 | stateChangeProcessEnqueue: Error transitioning state 15:15:20 INFO - PROCESS | 16302 | UnknownError 15:15:21 INFO - TEST-INFO | 16302: exit 0 15:15:21 INFO - Browser initialized. 15:15:21 INFO - Running cycle 1/1 for dromaeo_css test... 15:15:21 INFO - TEST-INFO | started process 16391 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpZN5p3e/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 15:15:33 INFO - PROCESS | 16391 | RSS: Main: 144072704 15:15:33 INFO - PROCESS | 16391 | 15:16:24 INFO - PROCESS | 16391 | 1475532984026 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] 15:18:33 INFO - PROCESS | 16391 | Cycle 1(1): loaded http://localhost:36852/tests/dromaeo/cssquery-dojo.html (next: http://localhost:36852/tests/dromaeo/cssquery-ext.html) 15:18:34 INFO - PROCESS | 16391 | RSS: Main: 271429632 15:18:34 INFO - PROCESS | 16391 | 15:21:36 INFO - PROCESS | 16391 | Cycle 1(1): loaded http://localhost:36852/tests/dromaeo/cssquery-ext.html (next: http://localhost:36852/tests/dromaeo/cssquery-jquery.html) 15:21:37 INFO - PROCESS | 16391 | RSS: Main: 231858176 15:21:37 INFO - PROCESS | 16391 | 15:24:39 INFO - PROCESS | 16391 | Cycle 1(1): loaded http://localhost:36852/tests/dromaeo/cssquery-jquery.html (next: http://localhost:36852/tests/dromaeo/cssquery-mootools.html) 15:24:39 INFO - PROCESS | 16391 | RSS: Main: 176283648 15:24:39 INFO - PROCESS | 16391 | 15:27:41 INFO - PROCESS | 16391 | Cycle 1(1): loaded http://localhost:36852/tests/dromaeo/cssquery-mootools.html (next: http://localhost:36852/tests/dromaeo/cssquery-prototype.html) 15:27:42 INFO - PROCESS | 16391 | RSS: Main: 176918528 15:27:42 INFO - PROCESS | 16391 | 15:30:45 INFO - PROCESS | 16391 | Cycle 1(1): loaded http://localhost:36852/tests/dromaeo/cssquery-prototype.html (next: http://localhost:36852/tests/dromaeo/cssquery-yui.html) 15:30:45 INFO - PROCESS | 16391 | RSS: Main: 176717824 15:30:45 INFO - PROCESS | 16391 | 15:33:48 INFO - PROCESS | 16391 | Cycle 1(1): loaded http://localhost:36852/tests/dromaeo/cssquery-yui.html (next: http://localhost:36852/tests/dromaeo/cssquery-dojo.html) 15:33:48 INFO - PROCESS | 16391 | RSS: Main: 265613312 15:33:48 INFO - PROCESS | 16391 | 15:33:48 INFO - PROCESS | 16391 | __start_tp_report 15:33:48 INFO - PROCESS | 16391 | _x_x_mozilla_page_load 15:33:48 INFO - PROCESS | 16391 | _x_x_mozilla_page_load_details 15:33:48 INFO - PROCESS | 16391 | |i|pagename|runs| 15:33:48 INFO - PROCESS | 16391 | |0;dojo.html;3278.03298027082;3284.556584861043;3296.439605267106;3311.8840840570556;3332.4168270552664;5650.773969041238;5717.742701578434;5747.655140691561;5747.942520574804;5756.539476841853;5729.770809167629;5795.739191736371;5795.884082318351;5797.333306669734;5808.680522571257;5225.6864588124645;5261.1056120459425;5281.15501519755;5306.973465132669;5326.094563924123;5051.217061355495;5293.602979776513;5334.386545547273;5336.4129945705945;5362.088444964365;5040.36995375578;5119.436861945183;5148.150555158394;5163.406208286057;5201;5177.482251774812;5212.113940630083;5241.764120614582;5317.308749862493;5320.467953204688;5137.897242055175;5169.379674439054;5252.343457067866;5261.237120617489;5316.627836051478;5491.945080549223;5567.721613919288;5570.554355651539;5573.554115670737;5619.915701264444;6006.429389208019;6010.909836352458;6123.632582045047;6150.477209437203;6156.6613836238985;5939.346671866091;6160;6167.876642467126;6181.9690901545655;6184.288806787187;5643.7460314285545;5701.0308247597595;5710.657360558422;5749.223854779635;5801.94198058014;6341.936580634135;6358.777442789573;6366.745330186834;6375.171227740364;6377.808665740035;5863.061910094364;5922.911156332659;6071.787487438007;6076.058210977306;6078.544109191828;6048.304444988795;6128.105930682418;6134.069235337226;6138.051223665364;6139.154842350747;6092.908606370908;6103.6032657877095;6108.51131909455;6111.84720381994;6142;5903.675297858659;6011.368806275278;6011.549133814981;6065.211522502134;6088.452039316483;4658.930116048262;4717.22165842639;4721.480637129913;4742.596879265232;4746.976265118721;4155.91688166235;4189.518205406356;4190.120074784269;4204.117135401539;4217.852375166915;5659.5755318351285;5851.970740146272;5942.64344139353;5943.197668314809;5948;6112.74665817949;6220.564560480723;6232.189815323979;6251.812445626639;6254.937450625526;5919.289685237713;5928.79249226275;5931.703414829328;5954.047352423678;5968.253968253968;5008.974955125201;5068.82259120929;5214.035403450373;5218.243354713622;5229.921551176735;6302.306746257908;6396.1684980953105;6418.550701450854;6425.389587989133;6438.195225596801;6112.052631842072;6158.692065396802;6172.660503672341;6176.938230617637;6180.721867516063;6130.3369889931655;6220.066989951543;6422.325655806073;6422.518311126591;6429.22849258092;5969.432903874125;6012.939870601413;6023.367546407564;6025.337212906489;6062.757489700362;6082.422169893853;6110.816675499742;6111.174991376108;6127.387261273837;6130.049842274455;5828.53371730269;5954.612950158225;6003.8198854034445;6019.367966363644;6028.517718582415;5983;6075.726592303268;6094.512439004958;6101.267847858107;6115.908261375994;5985.880282394415;6019.6990150493175;6034.547408944259;6040.6979651018155;6043.969780151071;5825.825225243249;5908.3500814911185;5977.3126090500145;6030.879382412415;6096.359882212481;5885.55858310637;5890.9116363253725;5891.116332550152;5892.057270836645;5923.970380148072 15:33:48 INFO - PROCESS | 16391 | |1;ext.html;592.881423715257;626.6522080245463;648.730776727658;652.6019128331719;656.668382466854;35952.99999999997;47025;48640.51359486413;48658.51341486584;48679.75660121694;49550.25674614875;50012.249816252785;50044.49955500444;50069.49930500694;50101.49898501023;9321.813563728705;9480.194183494412;9485.146336829685;9485.857712134324;9486.715398538056;9324.06759324069;9407.905920940806;9428.622855085789;9435.245180385587;9435.481048542328;9413.294002949771;9465.432074075543;9495.240380769523;9497;9525.952370238174;9332.206762425201;9412.529373531295;9416.62333506659;9419.576119074623;9421.952890235574;9290.163885250293;9378.109079637492;9381;9381.671641492516;9383.671571495033;46872.187668739985;47188;47209;47217.527824721656;47230;3827.6937844972335;3874.9806250968563;4045.656119229869;4344.847930322455;4880.633952453581;34314.6568534314;36323.63676363229;36687.816560917025;37309.44035839465;38488.422673659916;4605.171069207541;4665.626749860037;4673.299005149221;4694.647901407408;4752.619790416793;388018;393781;395013;396236.9999999971;396345;72383.63808180926;73268.26731732614;73285.33573321313;73302.63348683223;73327;41956.58043419527;42894.57105428906;43192.784036080244;45344.54655453469;77210.61394692991;18402.631947360976;18661.81338186628;18670.159842807105;18670.159842807105;18751.718724219147;14526.201058941648;14665.266736663125;14730.484433044792;14803.777943330859;14827.18450485212;22669.659955100688;22772.886135569217;22846.771532284463;22947.655785163235;23076.769232307463;8191.221833925887;8198.467099638505;8410.369222308229;8499.575021248913;8510.446820956618;1033.204432586904;1064.4784055812595;1127.785720713062;1144.296257801446;1191.8986886114603;34702;35813.46279805805;35991.82004089963;36050.45924311138;36142.8192859034;1057.6985559115615;1158.4671051316489;1192.2518594639266;1199.9800169855728;1216.0818581970732;18114.81885181158;18125.274989000292;18134;18393.448196554124;18415.90792046031;592253;593781;595324;595372;596247;65828.05031698018;66397.68415053005;66490;66690.33309666842;66734;1338.6653336665834;1443.7112577484336;1444.4077928049448;1479;1496.91018538888;35545;37101.44347834839;37176;37388.813055934545;37434.43848342331;44301.77849110734;44813;44839.103217934804;44852.102957940006;44949;477509;481628;482617;483960;484319;28337.716622834334;31850.20374490656;32880.34239315248;33137.33725325528;33295.167620809676;53200;53594.73202634118;53863.730681346344;53928.46071539234;54079.72960135331;44743.776281118386;46341.30488042615;46791.76604117094;47010.529894700616;48075.5192448071;44510.554894450644;46208.76895615501;46492.30261546012;47045.76477117593;47691;39281.607183928936;44832;44953.32570011518;45185.0962980732;45639.54360456353;37312;41401.1719765597;41527.377089344285;41679.27081674806;41748.373774394015;30361.24096897595;30952.380952381278;30958.845205774727;31403.21491962628;31538 15:33:48 INFO - PROCESS | 16391 | |2;jquery.html;422.19361020450907;426.76304078278343;431.7948974237237;434.86953913825846;443.7226733291695;18646;19429.805701942958;20402.89798551007;20507.282245121423;20686.27598034069;19787.18745023094;19895.900520497376;20472.18111275551;20665.38003859883;20715.37853864383;6035.637861728299;6256.906146407808;6263.185785847833;6266.060090986366;6266.310705822356;6094.4586326547915;6117.2659280886355;6140.385961403868;6146.17026701394;6164.66094364812;6104.298005729332;6106.938930610704;6112.449879510843;6112.969435152818;6114.327423983358;6022.096685497167;6025;6051.4553690168095;6060.878782424327;6061.45446909778;5374.7850085996515;5460.153676180159;5519.089350257203;5529.668219906778;5530.834074977757;24012.759872401228;24263.393415164584;24518.387040323956;24701.87649061761;25098.749012509823;2648.0864101884818;2675.7993150513594;2709.905153319644;2765.612814206013;3041.6654168041496;15358.155301458415;15543;15870.44453444124;15910.284037218353;16047.197640117947;1924;1935.7193206984907;1976.4465949534153;1985.811347921945;2049.6720524715975;68543.3145668542;72364.63817680931;74189.62905185494;82477.17522824755;84381.15618843795;11436.256643318158;11581.88418115825;11699.532018719327;11790.292582445081;11829.940850295865;16766;16780.580485487717;16789.41237056697;16811.747823782654;16814;7751.844963100706;8179.713710020121;8226.876596851052;8243.25810677042;8642.265407440313;8044.074931382849;8087.069986951576;8240.546769927712;8248.340132789363;8264.380171487162;4596.632269418438;4766.356541866804;4775.952240477551;4840.298156767249;4911.656184067081;2032.2379107834563;2083.229205194088;2085.6558667819945;2164.4741105566727;2216.445888527868;915.4873270968279;968.2205824311496;976.1068622210598;980.8479685648736;1030.7783826477344;20291.594168116553;21263.36209913705;21444;21458.892705536684;21545.353639390843;966.9323147379756;1112.0491979357532;1122.831575263717;1129.5594718059963;1131.1177281720106;10810.351378917132;11694.239874408137;12101.939490302491;12108.152429329863;12155.149139560146;541176;551266;560279;599111;599450;93098;94675;100289;102311;104002;494.6216144649346;507.82733870484213;512.2009664922732;525.4377815737201;525.9500347466984;11162;11638.767224655629;11729;11806.704832378915;11987.160898737005;37728.81135594304;42498.575014249465;43012.569874300854;43654.78172609117;44276.99999999871;60512.697436512535;60543.16846608088;60689;61186;61228.08157877546;8954.776130596787;8994.59524321394;9022.188003079755;9192.080791920755;9358.4384936904;13081.280529570964;13561.86438135606;13749.518766843112;14021.579352619437;14492.637684057561;11388.487518061542;12151.756964860477;12707.046971476992;12737.872621274038;12852.485900563872;11770.117241207116;11822.231554948901;11854.94072529632;12084.214526055779;12407.131500794858;12130.454129564367;12177.147599668293;12300.507979680713;12358.134930554776;12584.307863067253;8605.569721513773;8915.910840891767;9000.684976025808;9013.909860901307;9146.039665834991;9135.451872887648;11923.761524769283;12195.146339756131;12499.687507812378;12787.424565894371 15:33:48 INFO - PROCESS | 16391 | |3;mootools.html;1533.4173014254582;1558.361071960496;1728.7406888966652;1801.468566772802;1821.5992481654039;9161.679341223058;9822.165115965152;9868.555914983825;9872.45701486418;9893.109620134186;8187.836243275131;8827.29381649468;9051.185393314601;9085.40944838585;9927.305088643798;2533.366658335416;2573.073693470349;2580.148550978182;2582.1995181493703;2582.573875310581;2510.535550923076;2580.7419258074137;2581.5740402833508;2581.7547333003336;2582.5738753105716;2529.064246228898;2534.8986040558357;2562.4746926879943;2573.523898078852;2575.793936485086;2460.0897667862914;2468.259522143359;2471.8269721119527;2488.738682438336;2507.924762257135;2459.348272707734;2471.480988992314;2479.776820086183;2480.677511923456;2485.7887079598254;9678.17735492484;9876.851847222297;9876.95061524695;9877;9884.159846413064;5716.1997320375185;5844.970775146139;5889.911651325234;5889.941100588982;5891.263592050994;3640;3727.850885964558;3762.2099359134613;3787.20468701573;3790.374588192945;1272.5418849214275;1298.1886321049344;1322.3190057120494;1335.2389138191236;1350.6015725360944;13487.527936522174;13619.591412257649;13633.659158521017;13635.045546811727;13641.181529108286;4907.116718990616;4947.505249475023;4962.082014827269;5051.191809310492;5137.743112844343;11400.315981041163;11427.48576314068;11436.942815286036;11437.142214333959;11439.942800285946;5795.82612521625;5795.971020144872;5796.768129274782;5801.854953626109;5802.738876750556;4627.236505976543;5156.1750119980625;5250.65870718402;5279.762410691451;5307.310049693592;4273.850415235514;4319.784010799447;4367.956320436819;4403.031333106712;4403.185410699031;1421.4953691439596;1431.799548063272;1437.6765227823864;1451.7749748788954;1455.2141843404443;1017.7150397888603;1026.8357062869904;1029.5212726082407;1032.4115254305125;1039.61534232333;6139.416755408229;6282.717277722513;6311.936880631227;6318.399752023551;6487.448566871869;475.9952400475951;482.54881685624053;485.5654189500448;503.05927914798826;509.70691852185143;4470.150671372494;4643.837465688753;4656.557627025427;4733.5503127202865;4834.9604834960655;12394.31831249272;12497.312647804458;12664.113512054068;12674.366281686063;12680.80978785319;12013.399330033464;12502.49990000408;12523.624291271277;12536.81194782079;12542.122051456492;9040.954795226113;9202.999999999865;9207.677731279373;9254.305927055499;9258.814823703622;6443.710033048618;6496.7726129585235;6497.512686548623;6553.803385898431;6564.770233041821;10836.458177090955;11255.36797164423;11314.547418103184;11326.660200194008;11375;11442.256253343507;11585.88414115848;11633.243839150427;11650.417479125841;11716.472758725708;7766.534007959541;8282.047564529916;8439.156084391758;8531.445456045338;8667.4366166199;9403.576839042124;9510.809783804147;9512.381695190068;9515.238780897651;9522.619095236112;8504.702335418478;8677.392582519416;8722.476651400937;8751.299896008431;8808.295336372947;8125.4718443301;8284.585770711561;8358.749237522885;8455.8731619027;8501.10738372462;7956.761297161094;8076.555789431402;8127.471714338549;8129.75610731679;8162.142974987541;5508.641938274;5690.9430905690415;5735.655860648374;5766.538676905921;5897.144913987534;5473.261109750292;5486.149646804752;5508.669479831223;5516.310461192351;5765.7693692253515 15:33:48 INFO - PROCESS | 16391 | |4;prototype.html;349.1985892376995;353.04677371098023;356.8768774772703;361.45239961458395;362.5685434333145;5438.320209973754;5454.754536045877;5485.588580856437;5501.927124210785;5514.724263786805;5413.404525502192;5417.702026388547;5437.429069947658;5463.48096930792;5485.314335708036;3869.6323849234277;4084.305668036426;4333.133373325331;4341.956580434203;4353.172897149548;4321.54623764505;4335.956640433603;4341.479022517302;4346.826126954918;4374.9781251093855;4338.197433474817;4341.41390912226;4361.520232774392;4376.146651402978;4399.934000989988;4101.031504877646;4272.786360681985;4304.698671093025;4365.672574556905;4369.759663218538;3796.2976849282973;3796.373598356268;3801.3917773156163;3815.9809200954096;3816.8091595420397;5312.840614781524;5326;5334.91997620036;5338.145896656516;5338.199270109476;4787.928181077286;4896.485868983741;4913.656044076916;4915.852524424273;4945.703257804507;5698.373178950312;5701.828945131652;5702.258706368146;5704.543636509069;5719.885602287973;4159.031789382246;4198.299688626112;4205.11692544563;4205.158968206371;4211.663066954637;5589.267327128603;5757.524264566193;5833.620814647034;5912.881742365129;5944.940550594525;4126.6794625719485;4130.958690413117;4131.876043718693;4230.132822771325;4292.849750258726;4324.870253892388;4325.8269669213505;4346.630536404441;4349.195398851222;4358.433403657505;4715.316279139505;4924.138275801792;4924.433690125681;4926.1379258629595;4941.629377796679;5713.085906255063;5737.074092213002;5739.311282646109;5742.885142297131;5862.648241105548;1481.0225251334068;1483.5475180070182;1489.9627509312354;1498.9700205996037;1506.8041154649825;927.7541451515353;957.6600306890954;961.850588546694;976.184297207194;981.622075500923;4929.531694489017;4958.735522441754;5007.7230306272395;5009.44896061433;5034.672746271481;5150.913879878655;5184.559312458483;5191.714455704897;5203.687778733288;5205.817796377109;4976.8755781105765;4985.130576033987;4986.90026199474;5200.5059519345605;5616.971915140398;3109.647303423018;3134.467140586106;3134.5141503066607;3142.2929840786096;3143.732782713495;6048.15531262969;6088.452039316483;6116.449519543174;6117.357677443893;6129.816105516842;5692.918345514339;5991.7902873399225;6000.279966404059;6011.819645410645;6018;6131.877362452726;6133.417325354084;6135.84660383494;6150.261968563801;6171.1360409543595;5555.777768889199;5870.853228669317;5889.175515427758;5918.644881307136;5945.613535120204;5800.50695690871;5827.4172582741385;5846.912296315644;5871.266091738533;5875.471207591338;5509.862253443696;5884.58807883444;5918.23063001807;5953.434423729739;6127.479164270909;4248.426462427533;4270.743755374688;4279.828806847691;4296.591823776736;4307.526172121127;5998.49012833914;6012.076135636555;6012.819615411545;6023.042511183795;6056.66688332146;5590.993621148233;5752.6836024019085;5821.301443826598;5887.673464492548;5899.292084949833;5704.286964129484;5719.5710321726865;5724.685142317213;5736.021072729902;5743;5707.914381284367;5737.282839645044;5749.7125143741805;5751.798687045934;5754.712264386848;5498.45015498447;5704;5758.827235182951;5774.884502310014;5803.82588522345;5655.462731040545;5655.575831812548;5670.177824215548;5678.630888992202;5681 15:33:48 INFO - PROCESS | 16391 | |5;yui.html;771.756896577578;779.1974266505501;842.3345557009962;849.5667209723041;900.9684661036865;16238.51284461465;17996.460106196817;19067.523311917208;19086.99999999998;19111.044447777625;15993.680126397465;17777.822221777777;17995.460136195903;18021.729674054866;18022.369217077405;893.9240164586001;921.4839689773748;927.958241879117;928.2852203803101;930.1907340613642;753.0323534258479;875.6672464463527;912.3066469483207;926.1757036237753;930.0559931669361;969.6751588217955;970.2916870684404;972.0668158567748;972.6595691507987;973.6251543155865;967;967.2938754709066;967.3567077893192;967.4292167621066;967.6129548180713;709.0852799888138;709.6487238816819;710.2364957670525;710.8471678589076;710.9964450177716;16360.482850051349;16517.421890233905;16518.587035324093;16544.83455165445;16562.420315288906;14886.330115144736;15314.463993760277;15587.142707151103;15606.453774117852;15798.526044218692;12368.505259789597;12543.184692994926;12915.22508649484;13216.273104979222;13480.595582132551;3993.3810259409547;4038;4071.816768245436;4130.814113364906;4197;68590.65704671494;70835.64582177108;89290.55354723184;90061.54969225112;90075.54962225212;11971.465629053142;12104.87895121055;12127.75744485123;12474.75050498985;12653.620391388273;21880.781192187875;22050.33848984533;22090.33728988133;22117.225897093525;22169.667454988186;8945.910540894638;9062.728118156467;9155.77110572241;9214.539273036322;9463.810723785486;5129.897402052013;5190.662606930538;5214.843554693365;5319.015982043257;5502.8349149525575;3994.4807175067053;4116.732412393185;4121.216968775983;4143.875683729493;4147.668186545069;678.5521555773265;679.2494293805373;689.2934741889503;699.3565919354206;716.9175544812309;775.464929198851;777.8444311137682;804.4007214625025;811.9675212991532;817.595290331278;10011.699649010541;10549.578016879239;10826.945865270623;10865.945670271756;11028.2280240384;671.7514519627769;725.4957804325943;766.1227894061269;773.5668025905511;782.4210084537515;4926.802927882916;5133.499927676121;5188.092083885305;5287.841364759063;5322.813701520428;74906;76129.61935190289;76229;134691;134905.325473372;109359.45320273348;112848;113747;115354.42322788332;119599;98228.27516062529;99178;99676.5016174929;102172.48913755384;102438.48780756048;9276.536173191178;10245.948770256102;10274.589016439259;10355.482225888825;10423.374597524173;57827;58635.70682146562;58945.41054589399;59383.406165937784;59761;78479;86142.69286535523;89301.55349223212;89368.5531572338;91837;12587.370631468573;12612.936935315265;12644.683882903;12716.491340346283;12816.038797090441;13776.380062896993;13990;14216.431342746175;14430.83537469388;15668;13662.726745464835;13663.521776737767;14062.296885155905;14285.714285714019;14362.066465679698;12748;13768.86231137716;13772.517961871286;13793.586192414243;14189.07770994882;12356.567520136752;12660.493580256687;13271.66820829487;13592.388342524808;14838.851611484179;11946.880531194576;12035.699107522381;12542.12205145631;12670.113092083835;12815.231086134861;11623.941880290544;12066.396680166132;12523.049548452685;12637.684057898627;12689.746205076031 15:33:48 INFO - PROCESS | 16391 | __end_tp_report 15:33:48 INFO - PROCESS | 16391 | __start_cc_report 15:33:48 INFO - PROCESS | 16391 | _x_x_mozilla_cycle_collect,1126 15:33:48 INFO - PROCESS | 16391 | __end_cc_report 15:33:48 INFO - PROCESS | 16391 | __startTimestamp1475534028532__endTimestamp 15:33:48 INFO - PROCESS | 16391 | 15:33:48 INFO - PROCESS | 16391 | ------- Summary: start ------- 15:33:48 INFO - PROCESS | 16391 | Number of tests: 6 15:33:48 INFO - PROCESS | 16391 | 15:33:48 INFO - PROCESS | 16391 | [#0] dojo.html Cycles:165 Average:5700.60 Median:5942.64 stddev:653.23 (11.0%) stddev-sans-first:626.98 15:33:48 INFO - PROCESS | 16391 | Values: 3278.0 3284.6 3296.4 3311.9 3332.4 5650.8 5717.7 5747.7 5747.9 5756.5 5729.8 5795.7 5795.9 5797.3 5808.7 5225.7 5261.1 5281.2 5307.0 5326.1 5051.2 5293.6 5334.4 5336.4 5362.1 5040.4 5119.4 5148.2 5163.4 5201.0 5177.5 5212.1 5241.8 5317.3 5320.5 5137.9 5169.4 5252.3 5261.2 5316.6 5491.9 5567.7 5570.6 5573.6 5619.9 6006.4 6010.9 6123.6 6150.5 6156.7 5939.3 6160.0 6167.9 6182.0 6184.3 5643.7 5701.0 5710.7 5749.2 5801.9 6341.9 6358.8 6366.7 6375.2 6377.8 5863.1 5922.9 6071.8 6076.1 6078.5 6048.3 6128.1 6134.1 6138.1 6139.2 6092.9 6103.6 6108.5 6111.8 6142.0 5903.7 6011.4 6011.5 6065.2 6088.5 4658.9 4717.2 4721.5 4742.6 4747.0 4155.9 4189.5 4190.1 4204.1 4217.9 5659.6 5852.0 5942.6 5943.2 5948.0 6112.7 6220.6 6232.2 6251.8 6254.9 5919.3 5928.8 5931.7 5954.0 5968.3 5009.0 5068.8 5214.0 5218.2 5229.9 6302.3 6396.2 6418.6 6425.4 6438.2 6112.1 6158.7 6172.7 6176.9 6180.7 6130.3 6220.1 6422.3 6422.5 6429.2 5969.4 6012.9 6023.4 6025.3 6062.8 6082.4 6110.8 6111.2 6127.4 6130.0 5828.5 5954.6 6003.8 6019.4 6028.5 5983.0 6075.7 6094.5 6101.3 6115.9 5985.9 6019.7 6034.5 6040.7 6044.0 5825.8 5908.4 5977.3 6030.9 6096.4 5885.6 5890.9 5891.1 5892.1 5924.0 15:33:48 INFO - PROCESS | 16391 | 15:33:48 INFO - PROCESS | 16391 | [#1] ext.html Cycles:180 Average:66516.07 Median:25122.36 stddev:131791.82 (524.6%) stddev-sans-first:132068.58 15:33:48 INFO - PROCESS | 16391 | Values: 592.9 626.7 648.7 652.6 656.7 35953.0 47025.0 48640.5 48658.5 48679.8 49550.3 50012.2 50044.5 50069.5 50101.5 9321.8 9480.2 9485.1 9485.9 9486.7 9324.1 9407.9 9428.6 9435.2 9435.5 9413.3 9465.4 9495.2 9497.0 9526.0 9332.2 9412.5 9416.6 9419.6 9422.0 9290.2 9378.1 9381.0 9381.7 9383.7 46872.2 47188.0 47209.0 47217.5 47230.0 3827.7 3875.0 4045.7 4344.8 4880.6 34314.7 36323.6 36687.8 37309.4 38488.4 4605.2 4665.6 4673.3 4694.6 4752.6 388018.0 393781.0 395013.0 396237.0 396345.0 72383.6 73268.3 73285.3 73302.6 73327.0 41956.6 42894.6 43192.8 45344.5 77210.6 18402.6 18661.8 18670.2 18670.2 18751.7 14526.2 14665.3 14730.5 14803.8 14827.2 22669.7 22772.9 22846.8 22947.7 23076.8 8191.2 8198.5 8410.4 8499.6 8510.4 1033.2 1064.5 1127.8 1144.3 1191.9 34702.0 35813.5 35991.8 36050.5 36142.8 1057.7 1158.5 1192.3 1200.0 1216.1 18114.8 18125.3 18134.0 18393.4 18415.9 592253.0 593781.0 595324.0 595372.0 596247.0 65828.1 66397.7 66490.0 66690.3 66734.0 1338.7 1443.7 1444.4 1479.0 1496.9 35545.0 37101.4 37176.0 37388.8 37434.4 44301.8 44813.0 44839.1 44852.1 44949.0 477509.0 481628.0 482617.0 483960.0 484319.0 28337.7 31850.2 32880.3 33137.3 33295.2 53200.0 53594.7 53863.7 53928.5 54079.7 44743.8 46341.3 46791.8 47010.5 48075.5 44510.6 46208.8 46492.3 47045.8 47691.0 39281.6 44832.0 44953.3 45185.1 45639.5 37312.0 41401.2 41527.4 41679.3 41748.4 30361.2 30952.4 30958.8 31403.2 31538.0 15:33:48 INFO - PROCESS | 16391 | 15:33:48 INFO - PROCESS | 16391 | [#2] jquery.html Cycles:180 Average:31761.46 Median:2903.64 stddev:93762.11 (3229.1%) stddev-sans-first:93995.61 15:33:48 INFO - PROCESS | 16391 | Values: 422.2 426.8 431.8 434.9 443.7 18646.0 19429.8 20402.9 20507.3 20686.3 19787.2 19895.9 20472.2 20665.4 20715.4 6035.6 6256.9 6263.2 6266.1 6266.3 6094.5 6117.3 6140.4 6146.2 6164.7 6104.3 6106.9 6112.4 6113.0 6114.3 6022.1 6025.0 6051.5 6060.9 6061.5 5374.8 5460.2 5519.1 5529.7 5530.8 24012.8 24263.4 24518.4 24701.9 25098.7 2648.1 2675.8 2709.9 2765.6 3041.7 15358.2 15543.0 15870.4 15910.3 16047.2 1924.0 1935.7 1976.4 1985.8 2049.7 68543.3 72364.6 74189.6 82477.2 84381.2 11436.3 11581.9 11699.5 11790.3 11829.9 16766.0 16780.6 16789.4 16811.7 16814.0 7751.8 8179.7 8226.9 8243.3 8642.3 8044.1 8087.1 8240.5 8248.3 8264.4 4596.6 4766.4 4776.0 4840.3 4911.7 2032.2 2083.2 2085.7 2164.5 2216.4 915.5 968.2 976.1 980.8 1030.8 20291.6 21263.4 21444.0 21458.9 21545.4 966.9 1112.0 1122.8 1129.6 1131.1 10810.4 11694.2 12101.9 12108.2 12155.1 541176.0 551266.0 560279.0 599111.0 599450.0 93098.0 94675.0 100289.0 102311.0 104002.0 494.6 507.8 512.2 525.4 526.0 11162.0 11638.8 11729.0 11806.7 11987.2 37728.8 42498.6 43012.6 43654.8 44277.0 60512.7 60543.2 60689.0 61186.0 61228.1 8954.8 8994.6 9022.2 9192.1 9358.4 13081.3 13561.9 13749.5 14021.6 14492.6 11388.5 12151.8 12707.0 12737.9 12852.5 11770.1 11822.2 11854.9 12084.2 12407.1 12130.5 12177.1 12300.5 12358.1 12584.3 8605.6 8915.9 9000.7 9013.9 9146.0 9135.5 11923.8 12195.1 12499.7 12787.4 15:33:48 INFO - PROCESS | 16391 | 15:33:48 INFO - PROCESS | 16391 | [#3] mootools.html Cycles:180 Average:6411.93 Median:479.27 stddev:3724.39 (777.1%) stddev-sans-first:3716.79 15:33:48 INFO - PROCESS | 16391 | Values: 1533.4 1558.4 1728.7 1801.5 1821.6 9161.7 9822.2 9868.6 9872.5 9893.1 8187.8 8827.3 9051.2 9085.4 9927.3 2533.4 2573.1 2580.1 2582.2 2582.6 2510.5 2580.7 2581.6 2581.8 2582.6 2529.1 2534.9 2562.5 2573.5 2575.8 2460.1 2468.3 2471.8 2488.7 2507.9 2459.3 2471.5 2479.8 2480.7 2485.8 9678.2 9876.9 9877.0 9877.0 9884.2 5716.2 5845.0 5889.9 5889.9 5891.3 3640.0 3727.9 3762.2 3787.2 3790.4 1272.5 1298.2 1322.3 1335.2 1350.6 13487.5 13619.6 13633.7 13635.0 13641.2 4907.1 4947.5 4962.1 5051.2 5137.7 11400.3 11427.5 11436.9 11437.1 11439.9 5795.8 5796.0 5796.8 5801.9 5802.7 4627.2 5156.2 5250.7 5279.8 5307.3 4273.9 4319.8 4368.0 4403.0 4403.2 1421.5 1431.8 1437.7 1451.8 1455.2 1017.7 1026.8 1029.5 1032.4 1039.6 6139.4 6282.7 6311.9 6318.4 6487.4 476.0 482.5 485.6 503.1 509.7 4470.2 4643.8 4656.6 4733.6 4835.0 12394.3 12497.3 12664.1 12674.4 12680.8 12013.4 12502.5 12523.6 12536.8 12542.1 9041.0 9203.0 9207.7 9254.3 9258.8 6443.7 6496.8 6497.5 6553.8 6564.8 10836.5 11255.4 11314.5 11326.7 11375.0 11442.3 11585.9 11633.2 11650.4 11716.5 7766.5 8282.0 8439.2 8531.4 8667.4 9403.6 9510.8 9512.4 9515.2 9522.6 8504.7 8677.4 8722.5 8751.3 8808.3 8125.5 8284.6 8358.7 8455.9 8501.1 7956.8 8076.6 8127.5 8129.8 8162.1 5508.6 5690.9 5735.7 5766.5 5897.1 5473.3 5486.1 5508.7 5516.3 5765.8 15:33:48 INFO - PROCESS | 16391 | 15:33:48 INFO - PROCESS | 16391 | [#4] prototype.html Cycles:180 Average:4804.61 Median:5427.57 stddev:1396.91 (25.7%) stddev-sans-first:1360.21 15:33:48 INFO - PROCESS | 16391 | Values: 349.2 353.0 356.9 361.5 362.6 5438.3 5454.8 5485.6 5501.9 5514.7 5413.4 5417.7 5437.4 5463.5 5485.3 3869.6 4084.3 4333.1 4342.0 4353.2 4321.5 4336.0 4341.5 4346.8 4375.0 4338.2 4341.4 4361.5 4376.1 4399.9 4101.0 4272.8 4304.7 4365.7 4369.8 3796.3 3796.4 3801.4 3816.0 3816.8 5312.8 5326.0 5334.9 5338.1 5338.2 4787.9 4896.5 4913.7 4915.9 4945.7 5698.4 5701.8 5702.3 5704.5 5719.9 4159.0 4198.3 4205.1 4205.2 4211.7 5589.3 5757.5 5833.6 5912.9 5944.9 4126.7 4131.0 4131.9 4230.1 4292.8 4324.9 4325.8 4346.6 4349.2 4358.4 4715.3 4924.1 4924.4 4926.1 4941.6 5713.1 5737.1 5739.3 5742.9 5862.6 1481.0 1483.5 1490.0 1499.0 1506.8 927.8 957.7 961.9 976.2 981.6 4929.5 4958.7 5007.7 5009.4 5034.7 5150.9 5184.6 5191.7 5203.7 5205.8 4976.9 4985.1 4986.9 5200.5 5617.0 3109.6 3134.5 3134.5 3142.3 3143.7 6048.2 6088.5 6116.4 6117.4 6129.8 5692.9 5991.8 6000.3 6011.8 6018.0 6131.9 6133.4 6135.8 6150.3 6171.1 5555.8 5870.9 5889.2 5918.6 5945.6 5800.5 5827.4 5846.9 5871.3 5875.5 5509.9 5884.6 5918.2 5953.4 6127.5 4248.4 4270.7 4279.8 4296.6 4307.5 5998.5 6012.1 6012.8 6023.0 6056.7 5591.0 5752.7 5821.3 5887.7 5899.3 5704.3 5719.6 5724.7 5736.0 5743.0 5707.9 5737.3 5749.7 5751.8 5754.7 5498.5 5704.0 5758.8 5774.9 5803.8 5655.5 5655.6 5670.2 5678.6 5681.0 15:33:48 INFO - PROCESS | 16391 | 15:33:48 INFO - PROCESS | 16391 | [#5] yui.html Cycles:180 Average:22375.78 Median:4054.91 stddev:32255.75 (795.5%) stddev-sans-first:32305.45 15:33:48 INFO - PROCESS | 16391 | Values: 771.8 779.2 842.3 849.6 901.0 16238.5 17996.5 19067.5 19087.0 19111.0 15993.7 17777.8 17995.5 18021.7 18022.4 893.9 921.5 928.0 928.3 930.2 753.0 875.7 912.3 926.2 930.1 969.7 970.3 972.1 972.7 973.6 967.0 967.3 967.4 967.4 967.6 709.1 709.6 710.2 710.8 711.0 16360.5 16517.4 16518.6 16544.8 16562.4 14886.3 15314.5 15587.1 15606.5 15798.5 12368.5 12543.2 12915.2 13216.3 13480.6 3993.4 4038.0 4071.8 4130.8 4197.0 68590.7 70835.6 89290.6 90061.5 90075.5 11971.5 12104.9 12127.8 12474.8 12653.6 21880.8 22050.3 22090.3 22117.2 22169.7 8945.9 9062.7 9155.8 9214.5 9463.8 5129.9 5190.7 5214.8 5319.0 5502.8 3994.5 4116.7 4121.2 4143.9 4147.7 678.6 679.2 689.3 699.4 716.9 775.5 777.8 804.4 812.0 817.6 10011.7 10549.6 10826.9 10865.9 11028.2 671.8 725.5 766.1 773.6 782.4 4926.8 5133.5 5188.1 5287.8 5322.8 74906.0 76129.6 76229.0 134691.0 134905.3 109359.5 112848.0 113747.0 115354.4 119599.0 98228.3 99178.0 99676.5 102172.5 102438.5 9276.5 10245.9 10274.6 10355.5 10423.4 57827.0 58635.7 58945.4 59383.4 59761.0 78479.0 86142.7 89301.6 89368.6 91837.0 12587.4 12612.9 12644.7 12716.5 12816.0 13776.4 13990.0 14216.4 14430.8 15668.0 13662.7 13663.5 14062.3 14285.7 14362.1 12748.0 13768.9 13772.5 13793.6 14189.1 12356.6 12660.5 13271.7 13592.4 14838.9 11946.9 12035.7 12542.1 12670.1 12815.2 11623.9 12066.4 12523.0 12637.7 12689.7 15:33:48 INFO - PROCESS | 16391 | -------- Summary: end -------- 15:33:48 INFO - PROCESS | 16391 | 15:33:49 INFO - TEST-INFO | 16391: exit 0 15:33:49 INFO - TEST-OK | dromaeo_css | took 1114932ms 15:33:49 INFO - TEST-START | kraken 15:33:49 INFO - operating with platform_type : linux_ 15:33:49 INFO - Initialising browser for kraken test... 15:33:49 INFO - TEST-INFO | started process 16592 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpPJYufN/profile http://localhost:36852/getInfo.html) 15:33:54 INFO - PROCESS | 16592 | __metrics Screen width/height:1600/1200 15:33:54 INFO - PROCESS | 16592 | colorDepth:24 15:33:54 INFO - PROCESS | 16592 | Browser inner width/height: 1024/697 15:33:54 INFO - PROCESS | 16592 | __metrics 15:33:55 INFO - PROCESS | 16592 | 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" 15:33:55 INFO - PROCESS | 16592 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 15:33:55 INFO - PROCESS | 16592 | console.error: PushService: 15:33:55 INFO - PROCESS | 16592 | stateChangeProcessEnqueue: Error transitioning state 15:33:55 INFO - PROCESS | 16592 | UnknownError 15:33:56 INFO - TEST-INFO | 16592: exit 0 15:33:56 INFO - Browser initialized. 15:33:56 INFO - Running cycle 1/1 for kraken test... 15:33:56 INFO - TEST-INFO | started process 16677 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpPJYufN/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 15:34:07 INFO - PROCESS | 16677 | RSS: Main: 143749120 15:34:07 INFO - PROCESS | 16677 | 15:34:10 INFO - PROCESS | 16677 | Cycle 1(1): loaded http://localhost:36852/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:36852/tests/kraken/driver.html?testName=audio-beat-detection) 15:34:11 INFO - PROCESS | 16677 | RSS: Main: 198885376 15:34:11 INFO - PROCESS | 16677 | 15:34:13 INFO - PROCESS | 16677 | Cycle 1(1): loaded http://localhost:36852/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:36852/tests/kraken/driver.html?testName=audio-dft) 15:34:14 INFO - PROCESS | 16677 | RSS: Main: 182808576 15:34:14 INFO - PROCESS | 16677 | 15:34:17 INFO - PROCESS | 16677 | Cycle 1(1): loaded http://localhost:36852/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:36852/tests/kraken/driver.html?testName=audio-fft) 15:34:17 INFO - PROCESS | 16677 | RSS: Main: 273588224 15:34:17 INFO - PROCESS | 16677 | 15:34:19 INFO - PROCESS | 16677 | Cycle 1(1): loaded http://localhost:36852/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:36852/tests/kraken/driver.html?testName=audio-oscillator) 15:34:20 INFO - PROCESS | 16677 | RSS: Main: 179236864 15:34:20 INFO - PROCESS | 16677 | 15:34:22 INFO - PROCESS | 16677 | Cycle 1(1): loaded http://localhost:36852/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:36852/tests/kraken/driver.html?testName=imaging-gaussian-blur) 15:34:23 INFO - PROCESS | 16677 | RSS: Main: 178651136 15:34:23 INFO - PROCESS | 16677 | 15:34:26 INFO - PROCESS | 16677 | Cycle 1(1): loaded http://localhost:36852/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:36852/tests/kraken/driver.html?testName=imaging-darkroom) 15:34:26 INFO - PROCESS | 16677 | RSS: Main: 214310912 15:34:26 INFO - PROCESS | 16677 | 15:34:30 INFO - PROCESS | 16677 | Cycle 1(1): loaded http://localhost:36852/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:36852/tests/kraken/driver.html?testName=imaging-desaturate) 15:34:30 INFO - PROCESS | 16677 | RSS: Main: 213725184 15:34:30 INFO - PROCESS | 16677 | 15:34:33 INFO - PROCESS | 16677 | Cycle 1(1): loaded http://localhost:36852/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:36852/tests/kraken/driver.html?testName=json-parse-financial) 15:34:34 INFO - PROCESS | 16677 | RSS: Main: 213098496 15:34:34 INFO - PROCESS | 16677 | 15:34:35 INFO - PROCESS | 16677 | Cycle 1(1): loaded http://localhost:36852/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:36852/tests/kraken/driver.html?testName=json-stringify-tinderbox) 15:34:36 INFO - PROCESS | 16677 | RSS: Main: 176009216 15:34:36 INFO - PROCESS | 16677 | 15:34:37 INFO - PROCESS | 16677 | Cycle 1(1): loaded http://localhost:36852/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:36852/tests/kraken/driver.html?testName=stanford-crypto-aes) 15:34:38 INFO - PROCESS | 16677 | RSS: Main: 183500800 15:34:38 INFO - PROCESS | 16677 | 15:34:40 INFO - PROCESS | 16677 | Cycle 1(1): loaded http://localhost:36852/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:36852/tests/kraken/driver.html?testName=stanford-crypto-ccm) 15:34:41 INFO - PROCESS | 16677 | RSS: Main: 185032704 15:34:41 INFO - PROCESS | 16677 | 15:34:43 INFO - PROCESS | 16677 | Cycle 1(1): loaded http://localhost:36852/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:36852/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 15:34:44 INFO - PROCESS | 16677 | RSS: Main: 185475072 15:34:44 INFO - PROCESS | 16677 | 15:34:46 INFO - PROCESS | 16677 | Cycle 1(1): loaded http://localhost:36852/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:36852/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 15:34:47 INFO - PROCESS | 16677 | RSS: Main: 183308288 15:34:47 INFO - PROCESS | 16677 | 15:34:49 INFO - PROCESS | 16677 | Cycle 1(1): loaded http://localhost:36852/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:36852/tests/kraken/driver.html?testName=ai-astar) 15:34:49 INFO - PROCESS | 16677 | RSS: Main: 234270720 15:34:49 INFO - PROCESS | 16677 | 15:34:49 INFO - PROCESS | 16677 | __start_tp_report 15:34:49 INFO - PROCESS | 16677 | _x_x_mozilla_page_load 15:34:49 INFO - PROCESS | 16677 | _x_x_mozilla_page_load_details 15:34:49 INFO - PROCESS | 16677 | |i|pagename|runs| 15:34:49 INFO - PROCESS | 16677 | |0;ai-astar;111;110;120;109;111;111;111;112;111;111 15:34:49 INFO - PROCESS | 16677 | |1;audio-beat-detection;149;116;145;182;114;147;140;115;164;113 15:34:49 INFO - PROCESS | 16677 | |2;audio-dft;159;161;160;161;160;168;159;161;160;160 15:34:49 INFO - PROCESS | 16677 | |3;audio-fft;75;74;74;177;76;74;73;74;172;75 15:34:49 INFO - PROCESS | 16677 | |4;audio-oscillator;82;83;158;82;83;83;143;82;83;158 15:34:49 INFO - PROCESS | 16677 | |5;imaging-gaussian-blur;116;116;116;117;117;116;117;116;118;117 15:34:49 INFO - PROCESS | 16677 | |6;imaging-darkroom;146;146;146;146;146;147;146;146;146;146 15:34:49 INFO - PROCESS | 16677 | |7;imaging-desaturate;95;95;95;95;94;95;94;95;95;95 15:34:49 INFO - PROCESS | 16677 | |8;json-parse-financial;50;51;50;52;52;50;51;51;51;51 15:34:49 INFO - PROCESS | 16677 | |9;json-stringify-tinderbox;60;60;60;59;60;59;59;60;60;60 15:34:49 INFO - PROCESS | 16677 | |10;stanford-crypto-aes;78;72;72;71;79;72;72;71;81;72 15:34:49 INFO - PROCESS | 16677 | |11;stanford-crypto-ccm;129;120;121;121;118;126;120;127;153;119 15:34:49 INFO - PROCESS | 16677 | |12;stanford-crypto-pbkdf2;149;152;150;149;153;151;151;154;151;154 15:34:49 INFO - PROCESS | 16677 | |13;stanford-crypto-sha256-iterative;60;61;62;61;61;63;60;63;60;62 15:34:49 INFO - PROCESS | 16677 | __end_tp_report 15:34:49 INFO - PROCESS | 16677 | __start_cc_report 15:34:49 INFO - PROCESS | 16677 | _x_x_mozilla_cycle_collect,1642 15:34:49 INFO - PROCESS | 16677 | __end_cc_report 15:34:49 INFO - PROCESS | 16677 | __startTimestamp1475534089354__endTimestamp 15:34:49 INFO - PROCESS | 16677 | 15:34:49 INFO - PROCESS | 16677 | ------- Summary: start ------- 15:34:49 INFO - PROCESS | 16677 | Number of tests: 14 15:34:49 INFO - PROCESS | 16677 | 15:34:49 INFO - PROCESS | 16677 | [#0] ai-astar Cycles:10 Average:111.70 Median:111.00 stddev:3.02 (2.7%) stddev-sans-first:3.19 15:34:49 INFO - PROCESS | 16677 | Values: 111.0 110.0 120.0 109.0 111.0 111.0 111.0 112.0 111.0 111.0 15:34:49 INFO - PROCESS | 16677 | 15:34:49 INFO - PROCESS | 16677 | [#1] audio-beat-detection Cycles:10 Average:138.50 Median:146.00 stddev:23.75 (16.3%) stddev-sans-first:24.89 15:34:49 INFO - PROCESS | 16677 | Values: 149.0 116.0 145.0 182.0 114.0 147.0 140.0 115.0 164.0 113.0 15:34:49 INFO - PROCESS | 16677 | 15:34:49 INFO - PROCESS | 16677 | [#2] audio-dft Cycles:10 Average:160.90 Median:160.50 stddev:2.60 (1.6%) stddev-sans-first:2.67 15:34:49 INFO - PROCESS | 16677 | Values: 159.0 161.0 160.0 161.0 160.0 168.0 159.0 161.0 160.0 160.0 15:34:49 INFO - PROCESS | 16677 | 15:34:49 INFO - PROCESS | 16677 | [#3] audio-fft Cycles:10 Average:94.40 Median:74.00 stddev:42.24 (57.1%) stddev-sans-first:44.22 15:34:49 INFO - PROCESS | 16677 | Values: 75.0 74.0 74.0 177.0 76.0 74.0 73.0 74.0 172.0 75.0 15:34:49 INFO - PROCESS | 16677 | 15:34:49 INFO - PROCESS | 16677 | [#4] audio-oscillator Cycles:10 Average:103.70 Median:82.50 stddev:34.27 (41.5%) stddev-sans-first:35.43 15:34:49 INFO - PROCESS | 16677 | Values: 82.0 83.0 158.0 82.0 83.0 83.0 143.0 82.0 83.0 158.0 15:34:49 INFO - PROCESS | 16677 | 15:34:49 INFO - PROCESS | 16677 | [#5] imaging-gaussian-blur Cycles:10 Average:116.60 Median:117.00 stddev:0.70 (0.6%) stddev-sans-first:0.71 15:34:49 INFO - PROCESS | 16677 | Values: 116.0 116.0 116.0 117.0 117.0 116.0 117.0 116.0 118.0 117.0 15:34:49 INFO - PROCESS | 16677 | 15:34:49 INFO - PROCESS | 16677 | [#6] imaging-darkroom Cycles:10 Average:146.10 Median:146.00 stddev:0.32 (0.2%) stddev-sans-first:0.33 15:34:49 INFO - PROCESS | 16677 | Values: 146.0 146.0 146.0 146.0 146.0 147.0 146.0 146.0 146.0 146.0 15:34:49 INFO - PROCESS | 16677 | 15:34:49 INFO - PROCESS | 16677 | [#7] imaging-desaturate Cycles:10 Average:94.80 Median:95.00 stddev:0.42 (0.4%) stddev-sans-first:0.44 15:34:49 INFO - PROCESS | 16677 | Values: 95.0 95.0 95.0 95.0 94.0 95.0 94.0 95.0 95.0 95.0 15:34:49 INFO - PROCESS | 16677 | 15:34:49 INFO - PROCESS | 16677 | [#8] json-parse-financial Cycles:10 Average:50.90 Median:51.00 stddev:0.74 (1.4%) stddev-sans-first:0.71 15:34:49 INFO - PROCESS | 16677 | Values: 50.0 51.0 50.0 52.0 52.0 50.0 51.0 51.0 51.0 51.0 15:34:49 INFO - PROCESS | 16677 | 15:34:49 INFO - PROCESS | 16677 | [#9] json-stringify-tinderbox Cycles:10 Average:59.70 Median:60.00 stddev:0.48 (0.8%) stddev-sans-first:0.50 15:34:49 INFO - PROCESS | 16677 | Values: 60.0 60.0 60.0 59.0 60.0 59.0 59.0 60.0 60.0 60.0 15:34:49 INFO - PROCESS | 16677 | 15:34:49 INFO - PROCESS | 16677 | [#10] stanford-crypto-aes Cycles:10 Average:74.00 Median:72.00 stddev:3.77 (5.2%) stddev-sans-first:3.71 15:34:49 INFO - PROCESS | 16677 | Values: 78.0 72.0 72.0 71.0 79.0 72.0 72.0 71.0 81.0 72.0 15:34:49 INFO - PROCESS | 16677 | 15:34:49 INFO - PROCESS | 16677 | [#11] stanford-crypto-ccm Cycles:10 Average:125.40 Median:123.50 stddev:10.38 (8.4%) stddev-sans-first:10.93 15:34:49 INFO - PROCESS | 16677 | Values: 129.0 120.0 121.0 121.0 118.0 126.0 120.0 127.0 153.0 119.0 15:34:49 INFO - PROCESS | 16677 | 15:34:49 INFO - PROCESS | 16677 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:151.40 Median:151.50 stddev:1.84 (1.2%) stddev-sans-first:1.73 15:34:49 INFO - PROCESS | 16677 | Values: 149.0 152.0 150.0 149.0 153.0 151.0 151.0 154.0 151.0 154.0 15:34:49 INFO - PROCESS | 16677 | 15:34:49 INFO - PROCESS | 16677 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:61.30 Median:61.50 stddev:1.16 (1.9%) stddev-sans-first:1.13 15:34:49 INFO - PROCESS | 16677 | Values: 60.0 61.0 62.0 61.0 61.0 63.0 60.0 63.0 60.0 62.0 15:34:49 INFO - PROCESS | 16677 | -------- Summary: end -------- 15:34:49 INFO - PROCESS | 16677 | 15:34:50 INFO - TEST-INFO | 16677: exit 0 15:34:50 INFO - TEST-OK | kraken | took 61114ms 15:34:50 INFO - SUITE-END | took 1176s 15:34:50 INFO - Completed test suite (00:19:36) 15:34:50 INFO - javascript benchmark 15:34:50 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3278.03298027082, 3284.556584861043, 3296.439605267106, 3311.8840840570556, 3332.4168270552664, 5650.773969041238, 5717.742701578434, 5747.655140691561, 5747.942520574804, 5756.539476841853, 5729.770809167629, 5795.739191736371, 5795.884082318351, 5797.333306669734, 5808.680522571257, 5225.6864588124645, 5261.1056120459425, 5281.15501519755, 5306.973465132669, 5326.094563924123, 5051.217061355495, 5293.602979776513, 5334.386545547273, 5336.4129945705945, 5362.088444964365, 5040.36995375578, 5119.436861945183, 5148.150555158394, 5163.406208286057, 5201.0, 5177.482251774812, 5212.113940630083, 5241.764120614582, 5317.308749862493, 5320.467953204688, 5137.897242055175, 5169.379674439054, 5252.343457067866, 5261.237120617489, 5316.627836051478, 5491.945080549223, 5567.721613919288, 5570.554355651539, 5573.554115670737, 5619.915701264444, 6006.429389208019, 6010.909836352458, 6123.632582045047, 6150.477209437203, 6156.6613836238985, 5939.346671866091, 6160.0, 6167.876642467126, 6181.9690901545655, 6184.288806787187, 5643.7460314285545, 5701.0308247597595, 5710.657360558422, 5749.223854779635, 5801.94198058014, 6341.936580634135, 6358.777442789573, 6366.745330186834, 6375.171227740364, 6377.808665740035, 5863.061910094364, 5922.911156332659, 6071.787487438007, 6076.058210977306, 6078.544109191828, 6048.304444988795, 6128.105930682418, 6134.069235337226, 6138.051223665364, 6139.154842350747, 6092.908606370908, 6103.6032657877095, 6108.51131909455, 6111.84720381994, 6142.0, 5903.675297858659, 6011.368806275278, 6011.549133814981, 6065.211522502134, 6088.452039316483, 4658.930116048262, 4717.22165842639, 4721.480637129913, 4742.596879265232, 4746.976265118721, 4155.91688166235, 4189.518205406356, 4190.120074784269, 4204.117135401539, 4217.852375166915, 5659.5755318351285, 5851.970740146272, 5942.64344139353, 5943.197668314809, 5948.0, 6112.74665817949, 6220.564560480723, 6232.189815323979, 6251.812445626639, 6254.937450625526, 5919.289685237713, 5928.79249226275, 5931.703414829328, 5954.047352423678, 5968.253968253968, 5008.974955125201, 5068.82259120929, 5214.035403450373, 5218.243354713622, 5229.921551176735, 6302.306746257908, 6396.1684980953105, 6418.550701450854, 6425.389587989133, 6438.195225596801, 6112.052631842072, 6158.692065396802, 6172.660503672341, 6176.938230617637, 6180.721867516063, 6130.3369889931655, 6220.066989951543, 6422.325655806073, 6422.518311126591, 6429.22849258092, 5969.432903874125, 6012.939870601413, 6023.367546407564, 6025.337212906489, 6062.757489700362, 6082.422169893853, 6110.816675499742, 6111.174991376108, 6127.387261273837, 6130.049842274455, 5828.53371730269, 5954.612950158225, 6003.8198854034445, 6019.367966363644, 6028.517718582415, 5983.0, 6075.726592303268, 6094.512439004958, 6101.267847858107, 6115.908261375994, 5985.880282394415, 6019.6990150493175, 6034.547408944259, 6040.6979651018155, 6043.969780151071, 5825.825225243249, 5908.3500814911185, 5977.3126090500145, 6030.879382412415, 6096.359882212481, 5885.55858310637, 5890.9116363253725, 5891.116332550152, 5892.057270836645, 5923.970380148072], "value": 5656.128194950994, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [592.881423715257, 626.6522080245463, 648.730776727658, 652.6019128331719, 656.668382466854, 35952.99999999997, 47025.0, 48640.51359486413, 48658.51341486584, 48679.75660121694, 49550.25674614875, 50012.249816252785, 50044.49955500444, 50069.49930500694, 50101.49898501023, 9321.813563728705, 9480.194183494412, 9485.146336829685, 9485.857712134324, 9486.715398538056, 9324.06759324069, 9407.905920940806, 9428.622855085789, 9435.245180385587, 9435.481048542328, 9413.294002949771, 9465.432074075543, 9495.240380769523, 9497.0, 9525.952370238174, 9332.206762425201, 9412.529373531295, 9416.62333506659, 9419.576119074623, 9421.952890235574, 9290.163885250293, 9378.109079637492, 9381.0, 9381.671641492516, 9383.671571495033, 46872.187668739985, 47188.0, 47209.0, 47217.527824721656, 47230.0, 3827.6937844972335, 3874.9806250968563, 4045.656119229869, 4344.847930322455, 4880.633952453581, 34314.6568534314, 36323.63676363229, 36687.816560917025, 37309.44035839465, 38488.422673659916, 4605.171069207541, 4665.626749860037, 4673.299005149221, 4694.647901407408, 4752.619790416793, 388018.0, 393781.0, 395013.0, 396236.9999999971, 396345.0, 72383.63808180926, 73268.26731732614, 73285.33573321313, 73302.63348683223, 73327.0, 41956.58043419527, 42894.57105428906, 43192.784036080244, 45344.54655453469, 77210.61394692991, 18402.631947360976, 18661.81338186628, 18670.159842807105, 18670.159842807105, 18751.718724219147, 14526.201058941648, 14665.266736663125, 14730.484433044792, 14803.777943330859, 14827.18450485212, 22669.659955100688, 22772.886135569217, 22846.771532284463, 22947.655785163235, 23076.769232307463, 8191.221833925887, 8198.467099638505, 8410.369222308229, 8499.575021248913, 8510.446820956618, 1033.204432586904, 1064.4784055812595, 1127.785720713062, 1144.296257801446, 1191.8986886114603, 34702.0, 35813.46279805805, 35991.82004089963, 36050.45924311138, 36142.8192859034, 1057.6985559115615, 1158.4671051316489, 1192.2518594639266, 1199.9800169855728, 1216.0818581970732, 18114.81885181158, 18125.274989000292, 18134.0, 18393.448196554124, 18415.90792046031, 592253.0, 593781.0, 595324.0, 595372.0, 596247.0, 65828.05031698018, 66397.68415053005, 66490.0, 66690.33309666842, 66734.0, 1338.6653336665834, 1443.7112577484336, 1444.4077928049448, 1479.0, 1496.91018538888, 35545.0, 37101.44347834839, 37176.0, 37388.813055934545, 37434.43848342331, 44301.77849110734, 44813.0, 44839.103217934804, 44852.102957940006, 44949.0, 477509.0, 481628.0, 482617.0, 483960.0, 484319.0, 28337.716622834334, 31850.20374490656, 32880.34239315248, 33137.33725325528, 33295.167620809676, 53200.0, 53594.73202634118, 53863.730681346344, 53928.46071539234, 54079.72960135331, 44743.776281118386, 46341.30488042615, 46791.76604117094, 47010.529894700616, 48075.5192448071, 44510.554894450644, 46208.76895615501, 46492.30261546012, 47045.76477117593, 47691.0, 39281.607183928936, 44832.0, 44953.32570011518, 45185.0962980732, 45639.54360456353, 37312.0, 41401.1719765597, 41527.377089344285, 41679.27081674806, 41748.373774394015, 30361.24096897595, 30952.380952381278, 30958.845205774727, 31403.21491962628, 31538.0], "value": 22018.133951880027, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [422.19361020450907, 426.76304078278343, 431.7948974237237, 434.86953913825846, 443.7226733291695, 18646.0, 19429.805701942958, 20402.89798551007, 20507.282245121423, 20686.27598034069, 19787.18745023094, 19895.900520497376, 20472.18111275551, 20665.38003859883, 20715.37853864383, 6035.637861728299, 6256.906146407808, 6263.185785847833, 6266.060090986366, 6266.310705822356, 6094.4586326547915, 6117.2659280886355, 6140.385961403868, 6146.17026701394, 6164.66094364812, 6104.298005729332, 6106.938930610704, 6112.449879510843, 6112.969435152818, 6114.327423983358, 6022.096685497167, 6025.0, 6051.4553690168095, 6060.878782424327, 6061.45446909778, 5374.7850085996515, 5460.153676180159, 5519.089350257203, 5529.668219906778, 5530.834074977757, 24012.759872401228, 24263.393415164584, 24518.387040323956, 24701.87649061761, 25098.749012509823, 2648.0864101884818, 2675.7993150513594, 2709.905153319644, 2765.612814206013, 3041.6654168041496, 15358.155301458415, 15543.0, 15870.44453444124, 15910.284037218353, 16047.197640117947, 1924.0, 1935.7193206984907, 1976.4465949534153, 1985.811347921945, 2049.6720524715975, 68543.3145668542, 72364.63817680931, 74189.62905185494, 82477.17522824755, 84381.15618843795, 11436.256643318158, 11581.88418115825, 11699.532018719327, 11790.292582445081, 11829.940850295865, 16766.0, 16780.580485487717, 16789.41237056697, 16811.747823782654, 16814.0, 7751.844963100706, 8179.713710020121, 8226.876596851052, 8243.25810677042, 8642.265407440313, 8044.074931382849, 8087.069986951576, 8240.546769927712, 8248.340132789363, 8264.380171487162, 4596.632269418438, 4766.356541866804, 4775.952240477551, 4840.298156767249, 4911.656184067081, 2032.2379107834563, 2083.229205194088, 2085.6558667819945, 2164.4741105566727, 2216.445888527868, 915.4873270968279, 968.2205824311496, 976.1068622210598, 980.8479685648736, 1030.7783826477344, 20291.594168116553, 21263.36209913705, 21444.0, 21458.892705536684, 21545.353639390843, 966.9323147379756, 1112.0491979357532, 1122.831575263717, 1129.5594718059963, 1131.1177281720106, 10810.351378917132, 11694.239874408137, 12101.939490302491, 12108.152429329863, 12155.149139560146, 541176.0, 551266.0, 560279.0, 599111.0, 599450.0, 93098.0, 94675.0, 100289.0, 102311.0, 104002.0, 494.6216144649346, 507.82733870484213, 512.2009664922732, 525.4377815737201, 525.9500347466984, 11162.0, 11638.767224655629, 11729.0, 11806.704832378915, 11987.160898737005, 37728.81135594304, 42498.575014249465, 43012.569874300854, 43654.78172609117, 44276.99999999871, 60512.697436512535, 60543.16846608088, 60689.0, 61186.0, 61228.08157877546, 8954.776130596787, 8994.59524321394, 9022.188003079755, 9192.080791920755, 9358.4384936904, 13081.280529570964, 13561.86438135606, 13749.518766843112, 14021.579352619437, 14492.637684057561, 11388.487518061542, 12151.756964860477, 12707.046971476992, 12737.872621274038, 12852.485900563872, 11770.117241207116, 11822.231554948901, 11854.94072529632, 12084.214526055779, 12407.131500794858, 12130.454129564367, 12177.147599668293, 12300.507979680713, 12358.134930554776, 12584.307863067253, 8605.569721513773, 8915.910840891767, 9000.684976025808, 9013.909860901307, 9146.039665834991, 9135.451872887648, 11923.761524769283, 12195.146339756131, 12499.687507812378, 12787.424565894371], "value": 9504.92288192334, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1533.4173014254582, 1558.361071960496, 1728.7406888966652, 1801.468566772802, 1821.5992481654039, 9161.679341223058, 9822.165115965152, 9868.555914983825, 9872.45701486418, 9893.109620134186, 8187.836243275131, 8827.29381649468, 9051.185393314601, 9085.40944838585, 9927.305088643798, 2533.366658335416, 2573.073693470349, 2580.148550978182, 2582.1995181493703, 2582.573875310581, 2510.535550923076, 2580.7419258074137, 2581.5740402833508, 2581.7547333003336, 2582.5738753105716, 2529.064246228898, 2534.8986040558357, 2562.4746926879943, 2573.523898078852, 2575.793936485086, 2460.0897667862914, 2468.259522143359, 2471.8269721119527, 2488.738682438336, 2507.924762257135, 2459.348272707734, 2471.480988992314, 2479.776820086183, 2480.677511923456, 2485.7887079598254, 9678.17735492484, 9876.851847222297, 9876.95061524695, 9877.0, 9884.159846413064, 5716.1997320375185, 5844.970775146139, 5889.911651325234, 5889.941100588982, 5891.263592050994, 3640.0, 3727.850885964558, 3762.2099359134613, 3787.20468701573, 3790.374588192945, 1272.5418849214275, 1298.1886321049344, 1322.3190057120494, 1335.2389138191236, 1350.6015725360944, 13487.527936522174, 13619.591412257649, 13633.659158521017, 13635.045546811727, 13641.181529108286, 4907.116718990616, 4947.505249475023, 4962.082014827269, 5051.191809310492, 5137.743112844343, 11400.315981041163, 11427.48576314068, 11436.942815286036, 11437.142214333959, 11439.942800285946, 5795.82612521625, 5795.971020144872, 5796.768129274782, 5801.854953626109, 5802.738876750556, 4627.236505976543, 5156.1750119980625, 5250.65870718402, 5279.762410691451, 5307.310049693592, 4273.850415235514, 4319.784010799447, 4367.956320436819, 4403.031333106712, 4403.185410699031, 1421.4953691439596, 1431.799548063272, 1437.6765227823864, 1451.7749748788954, 1455.2141843404443, 1017.7150397888603, 1026.8357062869904, 1029.5212726082407, 1032.4115254305125, 1039.61534232333, 6139.416755408229, 6282.717277722513, 6311.936880631227, 6318.399752023551, 6487.448566871869, 475.9952400475951, 482.54881685624053, 485.5654189500448, 503.05927914798826, 509.70691852185143, 4470.150671372494, 4643.837465688753, 4656.557627025427, 4733.5503127202865, 4834.9604834960655, 12394.31831249272, 12497.312647804458, 12664.113512054068, 12674.366281686063, 12680.80978785319, 12013.399330033464, 12502.49990000408, 12523.624291271277, 12536.81194782079, 12542.122051456492, 9040.954795226113, 9202.999999999865, 9207.677731279373, 9254.305927055499, 9258.814823703622, 6443.710033048618, 6496.7726129585235, 6497.512686548623, 6553.803385898431, 6564.770233041821, 10836.458177090955, 11255.36797164423, 11314.547418103184, 11326.660200194008, 11375.0, 11442.256253343507, 11585.88414115848, 11633.243839150427, 11650.417479125841, 11716.472758725708, 7766.534007959541, 8282.047564529916, 8439.156084391758, 8531.445456045338, 8667.4366166199, 9403.576839042124, 9510.809783804147, 9512.381695190068, 9515.238780897651, 9522.619095236112, 8504.702335418478, 8677.392582519416, 8722.476651400937, 8751.299896008431, 8808.295336372947, 8125.4718443301, 8284.585770711561, 8358.749237522885, 8455.8731619027, 8501.10738372462, 7956.761297161094, 8076.555789431402, 8127.471714338549, 8129.75610731679, 8162.142974987541, 5508.641938274, 5690.9430905690415, 5735.655860648374, 5766.538676905921, 5897.144913987534, 5473.261109750292, 5486.149646804752, 5508.669479831223, 5516.310461192351, 5765.7693692253515], "value": 5020.546296241901, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [349.1985892376995, 353.04677371098023, 356.8768774772703, 361.45239961458395, 362.5685434333145, 5438.320209973754, 5454.754536045877, 5485.588580856437, 5501.927124210785, 5514.724263786805, 5413.404525502192, 5417.702026388547, 5437.429069947658, 5463.48096930792, 5485.314335708036, 3869.6323849234277, 4084.305668036426, 4333.133373325331, 4341.956580434203, 4353.172897149548, 4321.54623764505, 4335.956640433603, 4341.479022517302, 4346.826126954918, 4374.9781251093855, 4338.197433474817, 4341.41390912226, 4361.520232774392, 4376.146651402978, 4399.934000989988, 4101.031504877646, 4272.786360681985, 4304.698671093025, 4365.672574556905, 4369.759663218538, 3796.2976849282973, 3796.373598356268, 3801.3917773156163, 3815.9809200954096, 3816.8091595420397, 5312.840614781524, 5326.0, 5334.91997620036, 5338.145896656516, 5338.199270109476, 4787.928181077286, 4896.485868983741, 4913.656044076916, 4915.852524424273, 4945.703257804507, 5698.373178950312, 5701.828945131652, 5702.258706368146, 5704.543636509069, 5719.885602287973, 4159.031789382246, 4198.299688626112, 4205.11692544563, 4205.158968206371, 4211.663066954637, 5589.267327128603, 5757.524264566193, 5833.620814647034, 5912.881742365129, 5944.940550594525, 4126.6794625719485, 4130.958690413117, 4131.876043718693, 4230.132822771325, 4292.849750258726, 4324.870253892388, 4325.8269669213505, 4346.630536404441, 4349.195398851222, 4358.433403657505, 4715.316279139505, 4924.138275801792, 4924.433690125681, 4926.1379258629595, 4941.629377796679, 5713.085906255063, 5737.074092213002, 5739.311282646109, 5742.885142297131, 5862.648241105548, 1481.0225251334068, 1483.5475180070182, 1489.9627509312354, 1498.9700205996037, 1506.8041154649825, 927.7541451515353, 957.6600306890954, 961.850588546694, 976.184297207194, 981.622075500923, 4929.531694489017, 4958.735522441754, 5007.7230306272395, 5009.44896061433, 5034.672746271481, 5150.913879878655, 5184.559312458483, 5191.714455704897, 5203.687778733288, 5205.817796377109, 4976.8755781105765, 4985.130576033987, 4986.90026199474, 5200.5059519345605, 5616.971915140398, 3109.647303423018, 3134.467140586106, 3134.5141503066607, 3142.2929840786096, 3143.732782713495, 6048.15531262969, 6088.452039316483, 6116.449519543174, 6117.357677443893, 6129.816105516842, 5692.918345514339, 5991.7902873399225, 6000.279966404059, 6011.819645410645, 6018.0, 6131.877362452726, 6133.417325354084, 6135.84660383494, 6150.261968563801, 6171.1360409543595, 5555.777768889199, 5870.853228669317, 5889.175515427758, 5918.644881307136, 5945.613535120204, 5800.50695690871, 5827.4172582741385, 5846.912296315644, 5871.266091738533, 5875.471207591338, 5509.862253443696, 5884.58807883444, 5918.23063001807, 5953.434423729739, 6127.479164270909, 4248.426462427533, 4270.743755374688, 4279.828806847691, 4296.591823776736, 4307.526172121127, 5998.49012833914, 6012.076135636555, 6012.819615411545, 6023.042511183795, 6056.66688332146, 5590.993621148233, 5752.6836024019085, 5821.301443826598, 5887.673464492548, 5899.292084949833, 5704.286964129484, 5719.5710321726865, 5724.685142317213, 5736.021072729902, 5743.0, 5707.914381284367, 5737.282839645044, 5749.7125143741805, 5751.798687045934, 5754.712264386848, 5498.45015498447, 5704.0, 5758.827235182951, 5774.884502310014, 5803.82588522345, 5655.462731040545, 5655.575831812548, 5670.177824215548, 5678.630888992202, 5681.0], "value": 4364.138449371082, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [771.756896577578, 779.1974266505501, 842.3345557009962, 849.5667209723041, 900.9684661036865, 16238.51284461465, 17996.460106196817, 19067.523311917208, 19086.99999999998, 19111.044447777625, 15993.680126397465, 17777.822221777777, 17995.460136195903, 18021.729674054866, 18022.369217077405, 893.9240164586001, 921.4839689773748, 927.958241879117, 928.2852203803101, 930.1907340613642, 753.0323534258479, 875.6672464463527, 912.3066469483207, 926.1757036237753, 930.0559931669361, 969.6751588217955, 970.2916870684404, 972.0668158567748, 972.6595691507987, 973.6251543155865, 967.0, 967.2938754709066, 967.3567077893192, 967.4292167621066, 967.6129548180713, 709.0852799888138, 709.6487238816819, 710.2364957670525, 710.8471678589076, 710.9964450177716, 16360.482850051349, 16517.421890233905, 16518.587035324093, 16544.83455165445, 16562.420315288906, 14886.330115144736, 15314.463993760277, 15587.142707151103, 15606.453774117852, 15798.526044218692, 12368.505259789597, 12543.184692994926, 12915.22508649484, 13216.273104979222, 13480.595582132551, 3993.3810259409547, 4038.0, 4071.816768245436, 4130.814113364906, 4197.0, 68590.65704671494, 70835.64582177108, 89290.55354723184, 90061.54969225112, 90075.54962225212, 11971.465629053142, 12104.87895121055, 12127.75744485123, 12474.75050498985, 12653.620391388273, 21880.781192187875, 22050.33848984533, 22090.33728988133, 22117.225897093525, 22169.667454988186, 8945.910540894638, 9062.728118156467, 9155.77110572241, 9214.539273036322, 9463.810723785486, 5129.897402052013, 5190.662606930538, 5214.843554693365, 5319.015982043257, 5502.8349149525575, 3994.4807175067053, 4116.732412393185, 4121.216968775983, 4143.875683729493, 4147.668186545069, 678.5521555773265, 679.2494293805373, 689.2934741889503, 699.3565919354206, 716.9175544812309, 775.464929198851, 777.8444311137682, 804.4007214625025, 811.9675212991532, 817.595290331278, 10011.699649010541, 10549.578016879239, 10826.945865270623, 10865.945670271756, 11028.2280240384, 671.7514519627769, 725.4957804325943, 766.1227894061269, 773.5668025905511, 782.4210084537515, 4926.802927882916, 5133.499927676121, 5188.092083885305, 5287.841364759063, 5322.813701520428, 74906.0, 76129.61935190289, 76229.0, 134691.0, 134905.325473372, 109359.45320273348, 112848.0, 113747.0, 115354.42322788332, 119599.0, 98228.27516062529, 99178.0, 99676.5016174929, 102172.48913755384, 102438.48780756048, 9276.536173191178, 10245.948770256102, 10274.589016439259, 10355.482225888825, 10423.374597524173, 57827.0, 58635.70682146562, 58945.41054589399, 59383.406165937784, 59761.0, 78479.0, 86142.69286535523, 89301.55349223212, 89368.5531572338, 91837.0, 12587.370631468573, 12612.936935315265, 12644.683882903, 12716.491340346283, 12816.038797090441, 13776.380062896993, 13990.0, 14216.431342746175, 14430.83537469388, 15668.0, 13662.726745464835, 13663.521776737767, 14062.296885155905, 14285.714285714019, 14362.066465679698, 12748.0, 13768.86231137716, 13772.517961871286, 13793.586192414243, 14189.07770994882, 12356.567520136752, 12660.493580256687, 13271.66820829487, 13592.388342524808, 14838.851611484179, 11946.880531194576, 12035.699107522381, 12542.12205145631, 12670.113092083835, 12815.231086134861, 11623.941880290544, 12066.396680166132, 12523.049548452685, 12637.684057898627, 12689.746205076031], "value": 8207.24429831025, "unit": "score"}], "name": "dromaeo_css", "value": 7727.218362460319, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [111.0, 110.0, 120.0, 109.0, 111.0, 111.0, 111.0, 112.0, 111.0, 111.0], "value": 111.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 116.0, 145.0, 182.0, 114.0, 147.0, 140.0, 115.0, 164.0, 113.0], "value": 138.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [159.0, 161.0, 160.0, 161.0, 160.0, 168.0, 159.0, 161.0, 160.0, 160.0], "value": 160.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 74.0, 74.0, 177.0, 76.0, 74.0, 73.0, 74.0, 172.0, 75.0], "value": 94.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 83.0, 158.0, 82.0, 83.0, 83.0, 143.0, 82.0, 83.0, 158.0], "value": 103.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 116.0, 116.0, 117.0, 117.0, 116.0, 117.0, 116.0, 118.0, 117.0], "value": 116.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 146.0, 146.0, 146.0, 146.0, 147.0, 146.0, 146.0, 146.0, 146.0], "value": 146.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 95.0, 95.0, 94.0, 95.0, 94.0, 95.0, 95.0, 95.0], "value": 94.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [50.0, 51.0, 50.0, 52.0, 52.0, 50.0, 51.0, 51.0, 51.0, 51.0], "value": 50.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 60.0, 60.0, 59.0, 60.0, 59.0, 59.0, 60.0, 60.0, 60.0], "value": 59.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 72.0, 72.0, 71.0, 79.0, 72.0, 72.0, 71.0, 81.0, 72.0], "value": 74.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [129.0, 120.0, 121.0, 121.0, 118.0, 126.0, 120.0, 127.0, 153.0, 119.0], "value": 125.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 152.0, 150.0, 149.0, 153.0, 151.0, 151.0, 154.0, 151.0, 154.0], "value": 151.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 61.0, 62.0, 61.0, 61.0, 63.0, 60.0, 63.0, 60.0, 62.0], "value": 61.3, "unit": "score"}], "name": "kraken", "value": 1489.4, "alertThreshold": 2.0}]} 15:34:50 INFO - Return code: 0 15:34:50 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 15:34:50 INFO - # TBPL SUCCESS # 15:34:50 INFO - Running post-action listener: _resource_record_post_action 15:34:50 INFO - [mozharness: 2016-10-03 22:34:50.674120Z] Finished run-tests step (success) 15:34:50 INFO - Running post-run listener: _resource_record_post_run 15:34:50 INFO - Total resource usage - Wall time: 1191s; CPU: 13.0%; Read bytes: 1736704; Write bytes: 285966336; Read time: 56; Write time: 367752 15:34:50 INFO - TinderboxPrint: CPU usage
12.9% 15:34:50 INFO - TinderboxPrint: I/O read bytes / time
1,736,704 / 56 15:34:50 INFO - TinderboxPrint: I/O write bytes / time
285,966,336 / 367,752 15:34:50 INFO - TinderboxPrint: CPU idle
8,277.9 (87.1%) 15:34:50 INFO - TinderboxPrint: CPU user
1,178.6 (12.4%) 15:34:50 INFO - TinderboxPrint: Swap in / out
0 / 0 15:34:50 INFO - install - Wall time: 15s; CPU: 13.0%; Read bytes: 0; Write bytes: 70103040; Read time: 0; Write time: 151836 15:34:50 INFO - run-tests - Wall time: 1177s; CPU: 13.0%; Read bytes: 0; Write bytes: 215216128; Read time: 0; Write time: 215752 15:34:51 INFO - Running post-run listener: _upload_blobber_files 15:34:51 INFO - Blob upload gear active. 15:34:51 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 15:34:51 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 15:34:51 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-Beta', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 15:34:51 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-Beta -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 15:34:51 INFO - (blobuploader) - INFO - Open directory for files ... 15:34:51 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 15:34:51 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:34:51 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:34:52 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 15:34:52 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:34:52 INFO - (blobuploader) - INFO - Done attempting. 15:34:52 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 15:34:52 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:34:52 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:34:53 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 15:34:53 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:34:53 INFO - (blobuploader) - INFO - Done attempting. 15:34:53 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 15:34:53 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:34:53 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:34:57 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 15:34:57 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:34:57 INFO - (blobuploader) - INFO - Done attempting. 15:34:57 INFO - (blobuploader) - INFO - Iteration through files over. 15:34:57 INFO - Return code: 0 15:34:57 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 15:34:57 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 15:34:57 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/537e19b43b43f8ca244a235677ba89bcc4bc86eecc402f4bb336e0079f11c2c22e37a958bc16688944a737312e39a3a6af1fc494d0734a86f70999f07cd8b2f3", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/bf2b948839461ca22232ca45ef5f9ae55e8ca0a8d399a36dbed929a09b602343381c304ead4127713fa1ce3256a6a476cdd4d291b22e5de20c1eed3a883d18cc", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/df78000acebbfee4c23dfa2e93493602565e41121631269e92a96e7af85b76aa660eef2ca4c8c475d9b2a502baf05be0ae3936a9af59005944c0d74d337a81c9"} 15:34:57 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 15:34:57 INFO - Writing to file /builds/slave/test/properties/blobber_files 15:34:57 INFO - Contents: 15:34:57 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/537e19b43b43f8ca244a235677ba89bcc4bc86eecc402f4bb336e0079f11c2c22e37a958bc16688944a737312e39a3a6af1fc494d0734a86f70999f07cd8b2f3", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/bf2b948839461ca22232ca45ef5f9ae55e8ca0a8d399a36dbed929a09b602343381c304ead4127713fa1ce3256a6a476cdd4d291b22e5de20c1eed3a883d18cc", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/df78000acebbfee4c23dfa2e93493602565e41121631269e92a96e7af85b76aa660eef2ca4c8c475d9b2a502baf05be0ae3936a9af59005944c0d74d337a81c9"} 15:34:57 INFO - Running post-run listener: copy_logs_to_upload_dir 15:34:57 INFO - Copying logs to upload dir... 15:34:57 INFO - mkdir: /builds/slave/test/build/upload/logs 15:34:57 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1679.098183 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 27 mins, 59 secs) (at 2016-10-03 15:34:56.249131) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-03 15:34:56.253133) ========= 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=5cf47e208febbcd81da23b2e0000026f-1475532234.512057-869174694 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-Beta/sha512/537e19b43b43f8ca244a235677ba89bcc4bc86eecc402f4bb336e0079f11c2c22e37a958bc16688944a737312e39a3a6af1fc494d0734a86f70999f07cd8b2f3", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/bf2b948839461ca22232ca45ef5f9ae55e8ca0a8d399a36dbed929a09b602343381c304ead4127713fa1ce3256a6a476cdd4d291b22e5de20c1eed3a883d18cc", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/df78000acebbfee4c23dfa2e93493602565e41121631269e92a96e7af85b76aa660eef2ca4c8c475d9b2a502baf05be0ae3936a9af59005944c0d74d337a81c9"} build_url:https://queue.taskcluster.net/v1/task/bakuT_8zR-CFGYbgmCQvxg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011321 build_url: 'https://queue.taskcluster.net/v1/task/bakuT_8zR-CFGYbgmCQvxg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/537e19b43b43f8ca244a235677ba89bcc4bc86eecc402f4bb336e0079f11c2c22e37a958bc16688944a737312e39a3a6af1fc494d0734a86f70999f07cd8b2f3", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/bf2b948839461ca22232ca45ef5f9ae55e8ca0a8d399a36dbed929a09b602343381c304ead4127713fa1ce3256a6a476cdd4d291b22e5de20c1eed3a883d18cc", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/df78000acebbfee4c23dfa2e93493602565e41121631269e92a96e7af85b76aa660eef2ca4c8c475d9b2a502baf05be0ae3936a9af59005944c0d74d337a81c9"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-03 15:34:56.280376) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-03 15:34:56.281318) ========= 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=5cf47e208febbcd81da23b2e0000026f-1475532234.512057-869174694 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004491 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-03 15:34:56.362982) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-03 15:34:56.363341) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-03 15:34:56.363717) ========= ========= Total master_lag: 0.18 =========