builder: mozilla-beta_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-023 starttime: 1477573935.19 results: success (0) buildid: 20161027030534 builduid: 6e7ad2fab64c421cbec0a5c217abc13b revision: 30092cd6645ff14c7714da97e177f82fee34caf1 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-10-27 06:12:15.186458) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-10-27 06:12:15.186886) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-27 06:12:15.187181) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-27 06:12:15.201939) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-27 06:12:15.202340) ========= 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=6d15e502d8e29d5d2676d664000001c3-1477571525.235682-683999593 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-10-27 06:12:16-- 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: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 51.2M=0s 2016-10-27 06:12:16 (51.2 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.126121 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-27 06:12:15.342802) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-27 06:12:15.343126) ========= 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=6d15e502d8e29d5d2676d664000001c3-1477571525.235682-683999593 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.013862 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-27 06:12:15.392821) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-27 06:12:15.393308) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 30092cd6645ff14c7714da97e177f82fee34caf1 --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 30092cd6645ff14c7714da97e177f82fee34caf1 --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=6d15e502d8e29d5d2676d664000001c3-1477571525.235682-683999593 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-10-27 06:12:16,366 truncating revision to first 12 chars 2016-10-27 06:12:16,366 Setting DEBUG logging. 2016-10-27 06:12:16,366 attempt 1/10 2016-10-27 06:12:16,367 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/30092cd6645f?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-10-27 06:12:16,689 unpacking tar archive at: mozilla-beta-30092cd6645f/testing/mozharness/ program finished with exit code 0 elapsedTime=0.763212 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-27 06:12:16.172141) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-27 06:12:16.172534) ========= script_repo_revision: 30092cd6645ff14c7714da97e177f82fee34caf1 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-27 06:12:16.172952) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-27 06:12:16.173225) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-27 06:12:16.182170) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 27 mins, 53 secs) (at 2016-10-27 06:12:16.182484) ========= /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=6d15e502d8e29d5d2676d664000001c3-1477571525.235682-683999593 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 06:12:17 INFO - MultiFileLogger online at 20161027 06:12:17 in /builds/slave/test 06:12:17 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 06:12:17 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 06:12:17 INFO - {'append_to_log': False, 06:12:17 INFO - 'base_work_dir': '/builds/slave/test', 06:12:17 INFO - 'blob_upload_branch': 'Mozilla-Beta', 06:12:17 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 06:12:17 INFO - 'branch': 'Mozilla-Beta', 06:12:17 INFO - 'buildbot_json_path': 'buildprops.json', 06:12:17 INFO - 'config_files': ('talos/linux_config.py',), 06:12:17 INFO - 'default_actions': ('clobber', 06:12:17 INFO - 'read-buildbot-config', 06:12:17 INFO - 'download-and-extract', 06:12:17 INFO - 'populate-webroot', 06:12:17 INFO - 'create-virtualenv', 06:12:17 INFO - 'install', 06:12:17 INFO - 'run-tests'), 06:12:17 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 06:12:17 INFO - 'download_minidump_stackwalk': True, 06:12:17 INFO - 'download_symbols': 'ondemand', 06:12:17 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 06:12:17 INFO - 'tooltool.py': '/tools/tooltool.py', 06:12:17 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 06:12:17 INFO - '/tools/misc-python/virtualenv.py')}, 06:12:17 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 06:12:17 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 06:12:17 INFO - 'installer_path': 'installer.exe', 06:12:17 INFO - 'log_level': 'info', 06:12:17 INFO - 'log_name': 'talos', 06:12:17 INFO - 'log_to_console': True, 06:12:17 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 06:12:17 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 06:12:17 INFO - 'opt_config_files': (), 06:12:17 INFO - 'pip_index': False, 06:12:17 INFO - 'sps_profile': False, 06:12:17 INFO - 'sps_profile_interval': 0, 06:12:17 INFO - 'suite': 'dromaeojs', 06:12:17 INFO - 'system_bits': '32', 06:12:17 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 06:12:17 INFO - 'title': 'talos-linux64-ix-023', 06:12:17 INFO - 'tooltool_cache': '/builds/tooltool_cache', 06:12:17 INFO - 'use_talos_json': True, 06:12:17 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 06:12:17 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 06:12:17 INFO - 'work_dir': 'build'} 06:12:17 INFO - [mozharness: 2016-10-27 13:12:17.244702Z] Running clobber step. 06:12:17 INFO - Running pre-action listener: _resource_record_pre_action 06:12:17 INFO - Running main action method: clobber 06:12:17 INFO - rmtree: /builds/slave/test/build 06:12:17 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 06:12:17 INFO - Running post-action listener: _resource_record_post_action 06:12:17 INFO - [mozharness: 2016-10-27 13:12:17.709580Z] Finished clobber step (success) 06:12:17 INFO - [mozharness: 2016-10-27 13:12:17.709680Z] Running read-buildbot-config step. 06:12:17 INFO - Running pre-action listener: _resource_record_pre_action 06:12:17 INFO - Running main action method: read_buildbot_config 06:12:17 INFO - Using buildbot properties: 06:12:17 INFO - { 06:12:17 INFO - "project": "", 06:12:17 INFO - "product": "firefox", 06:12:17 INFO - "script_repo_revision": "production", 06:12:17 INFO - "builddir": "mozilla-beta_ubuntu64_hw_test-dromaeojs", 06:12:17 INFO - "repository": "", 06:12:17 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-beta pgo talos dromaeojs", 06:12:17 INFO - "buildid": "20161027030534", 06:12:17 INFO - "pgo_build": "True", 06:12:17 INFO - "basedir": "/builds/slave/test", 06:12:17 INFO - "buildnumber": 286, 06:12:17 INFO - "slavename": "talos-linux64-ix-023", 06:12:17 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 06:12:17 INFO - "platform": "ubuntu64_hw", 06:12:17 INFO - "scheduler": "tests-mozilla-beta-linux64-pgo-talos", 06:12:17 INFO - "branch": "mozilla-beta", 06:12:17 INFO - "repo_path": "releases/mozilla-beta", 06:12:17 INFO - "revision": "30092cd6645ff14c7714da97e177f82fee34caf1", 06:12:17 INFO - "stage_platform": "linux64-pgo", 06:12:17 INFO - "builduid": "6e7ad2fab64c421cbec0a5c217abc13b", 06:12:17 INFO - "slavebuilddir": "test" 06:12:17 INFO - } 06:12:17 INFO - Found installer url https://queue.taskcluster.net/v1/task/NUMy7VlYR3WKCZ7HISXUUw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2. 06:12:17 INFO - Running post-action listener: _resource_record_post_action 06:12:17 INFO - [mozharness: 2016-10-27 13:12:17.712126Z] Finished read-buildbot-config step (success) 06:12:17 INFO - [mozharness: 2016-10-27 13:12:17.712214Z] Running download-and-extract step. 06:12:17 INFO - Running pre-action listener: _resource_record_pre_action 06:12:17 INFO - Running main action method: download_and_extract 06:12:17 INFO - mkdir: /builds/slave/test/build/tests 06:12:17 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')]} 06:12:17 INFO - https://queue.taskcluster.net/v1/task/NUMy7VlYR3WKCZ7HISXUUw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 06:12:17 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NUMy7VlYR3WKCZ7HISXUUw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 06:12:17 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NUMy7VlYR3WKCZ7HISXUUw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 06:12:17 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NUMy7VlYR3WKCZ7HISXUUw/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 06:12:17 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NUMy7VlYR3WKCZ7HISXUUw/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 06:12:17 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NUMy7VlYR3WKCZ7HISXUUw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 06:12:17 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 06:12:17 INFO - retry: Failed, sleeping 30 seconds before retrying 06:12:47 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NUMy7VlYR3WKCZ7HISXUUw/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 06:12:47 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NUMy7VlYR3WKCZ7HISXUUw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 06:12:47 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 06:12:47 INFO - retry: Failed, sleeping 60 seconds before retrying 06:13:47 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NUMy7VlYR3WKCZ7HISXUUw/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 06:13:47 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NUMy7VlYR3WKCZ7HISXUUw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 06:13:47 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 06:13:47 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NUMy7VlYR3WKCZ7HISXUUw/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! 06:13:47 INFO - Caught exception: HTTP Error 404: Not Found 06:13:47 INFO - Caught exception: HTTP Error 404: Not Found 06:13:47 INFO - Caught exception: HTTP Error 404: Not Found 06:13:47 INFO - trying https://queue.taskcluster.net/v1/task/NUMy7VlYR3WKCZ7HISXUUw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 06:13:47 INFO - Downloading https://queue.taskcluster.net/v1/task/NUMy7VlYR3WKCZ7HISXUUw/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 06:13:47 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/NUMy7VlYR3WKCZ7HISXUUw/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 06:13:48 INFO - Downloaded 1327 bytes. 06:13:48 INFO - Reading from file /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 06:13:48 INFO - Using the following test package requirements: 06:13:48 INFO - {u'common': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip'], 06:13:48 INFO - u'cppunittest': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 06:13:48 INFO - u'firefox-50.0.en-US.linux-x86_64.cppunittest.tests.zip'], 06:13:48 INFO - u'gtest': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 06:13:48 INFO - u'firefox-50.0.en-US.linux-x86_64.gtest.tests.zip'], 06:13:48 INFO - u'jittest': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 06:13:48 INFO - u'jsshell-linux-x86_64.zip'], 06:13:48 INFO - u'mochitest': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 06:13:48 INFO - u'firefox-50.0.en-US.linux-x86_64.mochitest.tests.zip'], 06:13:48 INFO - u'mozbase': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip'], 06:13:48 INFO - u'reftest': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 06:13:48 INFO - u'firefox-50.0.en-US.linux-x86_64.reftest.tests.zip'], 06:13:48 INFO - u'talos': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 06:13:48 INFO - u'firefox-50.0.en-US.linux-x86_64.talos.tests.zip'], 06:13:48 INFO - u'web-platform': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 06:13:48 INFO - u'firefox-50.0.en-US.linux-x86_64.web-platform.tests.zip'], 06:13:48 INFO - u'xpcshell': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 06:13:48 INFO - u'firefox-50.0.en-US.linux-x86_64.xpcshell.tests.zip']} 06:13:48 INFO - Downloading packages: [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 06:13:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:13:48 INFO - https://queue.taskcluster.net/v1/task/NUMy7VlYR3WKCZ7HISXUUw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 06:13:48 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NUMy7VlYR3WKCZ7HISXUUw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 06:13:48 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NUMy7VlYR3WKCZ7HISXUUw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 06:13:48 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NUMy7VlYR3WKCZ7HISXUUw/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 06:13:48 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NUMy7VlYR3WKCZ7HISXUUw/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 06:13:48 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NUMy7VlYR3WKCZ7HISXUUw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 06:13:48 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 06:13:48 INFO - retry: Failed, sleeping 30 seconds before retrying 06:14:18 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NUMy7VlYR3WKCZ7HISXUUw/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 06:14:18 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NUMy7VlYR3WKCZ7HISXUUw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 06:14:18 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 06:14:18 INFO - retry: Failed, sleeping 60 seconds before retrying 06:15:18 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NUMy7VlYR3WKCZ7HISXUUw/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 06:15:18 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NUMy7VlYR3WKCZ7HISXUUw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 06:15:18 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 06:15:18 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NUMy7VlYR3WKCZ7HISXUUw/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! 06:15:18 INFO - Caught exception: HTTP Error 404: Not Found 06:15:18 INFO - Caught exception: HTTP Error 404: Not Found 06:15:18 INFO - Caught exception: HTTP Error 404: Not Found 06:15:18 INFO - trying https://queue.taskcluster.net/v1/task/NUMy7VlYR3WKCZ7HISXUUw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 06:15:18 INFO - Downloading https://queue.taskcluster.net/v1/task/NUMy7VlYR3WKCZ7HISXUUw/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 06:15:18 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/NUMy7VlYR3WKCZ7HISXUUw/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 06:15:19 INFO - Downloaded 21856967 bytes. 06:15:19 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'] 06:15:19 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 06:15:20 INFO - Return code: 0 06:15:20 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 06:15:20 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')]} 06:15:20 INFO - https://queue.taskcluster.net/v1/task/NUMy7VlYR3WKCZ7HISXUUw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 06:15:20 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NUMy7VlYR3WKCZ7HISXUUw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 06:15:20 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NUMy7VlYR3WKCZ7HISXUUw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 06:15:20 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NUMy7VlYR3WKCZ7HISXUUw/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 06:15:20 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NUMy7VlYR3WKCZ7HISXUUw/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 06:15:20 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NUMy7VlYR3WKCZ7HISXUUw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 06:15:20 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 06:15:20 INFO - retry: Failed, sleeping 30 seconds before retrying 06:15:50 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NUMy7VlYR3WKCZ7HISXUUw/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 06:15:50 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NUMy7VlYR3WKCZ7HISXUUw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 06:15:50 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 06:15:50 INFO - retry: Failed, sleeping 60 seconds before retrying 06:16:50 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NUMy7VlYR3WKCZ7HISXUUw/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 06:16:50 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NUMy7VlYR3WKCZ7HISXUUw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 06:16:50 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 06:16:50 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NUMy7VlYR3WKCZ7HISXUUw/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! 06:16:50 INFO - Caught exception: HTTP Error 404: Not Found 06:16:50 INFO - Caught exception: HTTP Error 404: Not Found 06:16:50 INFO - Caught exception: HTTP Error 404: Not Found 06:16:50 INFO - trying https://queue.taskcluster.net/v1/task/NUMy7VlYR3WKCZ7HISXUUw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 06:16:50 INFO - Downloading https://queue.taskcluster.net/v1/task/NUMy7VlYR3WKCZ7HISXUUw/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 06:16:50 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/NUMy7VlYR3WKCZ7HISXUUw/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 06:16:51 INFO - Downloaded 21856967 bytes. 06:16:51 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'] 06:16:51 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 06:16:53 INFO - Return code: 0 06:16:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:16:53 INFO - https://queue.taskcluster.net/v1/task/NUMy7VlYR3WKCZ7HISXUUw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 06:16:53 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NUMy7VlYR3WKCZ7HISXUUw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 06:16:53 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NUMy7VlYR3WKCZ7HISXUUw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 06:16:53 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NUMy7VlYR3WKCZ7HISXUUw/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 06:16:53 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NUMy7VlYR3WKCZ7HISXUUw/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 06:16:53 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NUMy7VlYR3WKCZ7HISXUUw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 06:16:53 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 06:16:53 INFO - retry: Failed, sleeping 30 seconds before retrying 06:17:23 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NUMy7VlYR3WKCZ7HISXUUw/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 06:17:23 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NUMy7VlYR3WKCZ7HISXUUw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 06:17:23 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 06:17:23 INFO - retry: Failed, sleeping 60 seconds before retrying 06:18:23 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NUMy7VlYR3WKCZ7HISXUUw/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 06:18:23 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NUMy7VlYR3WKCZ7HISXUUw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 06:18:23 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 06:18:23 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NUMy7VlYR3WKCZ7HISXUUw/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! 06:18:23 INFO - Caught exception: HTTP Error 404: Not Found 06:18:23 INFO - Caught exception: HTTP Error 404: Not Found 06:18:23 INFO - Caught exception: HTTP Error 404: Not Found 06:18:23 INFO - trying https://queue.taskcluster.net/v1/task/NUMy7VlYR3WKCZ7HISXUUw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 06:18:23 INFO - Downloading https://queue.taskcluster.net/v1/task/NUMy7VlYR3WKCZ7HISXUUw/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 06:18:23 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/NUMy7VlYR3WKCZ7HISXUUw/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 06:18:23 INFO - Downloaded 14413762 bytes. 06:18:23 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'] 06:18:23 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 06:18:24 INFO - Return code: 0 06:18:24 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')]} 06:18:24 INFO - https://queue.taskcluster.net/v1/task/NUMy7VlYR3WKCZ7HISXUUw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 06:18:24 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NUMy7VlYR3WKCZ7HISXUUw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 06:18:24 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NUMy7VlYR3WKCZ7HISXUUw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 06:18:24 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NUMy7VlYR3WKCZ7HISXUUw/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 06:18:24 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NUMy7VlYR3WKCZ7HISXUUw/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 06:18:24 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NUMy7VlYR3WKCZ7HISXUUw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 06:18:24 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 06:18:24 INFO - retry: Failed, sleeping 30 seconds before retrying 06:18:54 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NUMy7VlYR3WKCZ7HISXUUw/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 06:18:54 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NUMy7VlYR3WKCZ7HISXUUw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 06:18:54 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 06:18:54 INFO - retry: Failed, sleeping 60 seconds before retrying 06:19:54 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NUMy7VlYR3WKCZ7HISXUUw/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 06:19:54 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NUMy7VlYR3WKCZ7HISXUUw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 06:19:54 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 06:19:54 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NUMy7VlYR3WKCZ7HISXUUw/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! 06:19:54 INFO - Caught exception: HTTP Error 404: Not Found 06:19:54 INFO - Caught exception: HTTP Error 404: Not Found 06:19:54 INFO - Caught exception: HTTP Error 404: Not Found 06:19:54 INFO - trying https://queue.taskcluster.net/v1/task/NUMy7VlYR3WKCZ7HISXUUw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 06:19:54 INFO - Downloading https://queue.taskcluster.net/v1/task/NUMy7VlYR3WKCZ7HISXUUw/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 06:19:54 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/NUMy7VlYR3WKCZ7HISXUUw/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 06:19:55 INFO - Downloaded 56832419 bytes. 06:19:55 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/NUMy7VlYR3WKCZ7HISXUUw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 06:19:55 INFO - mkdir: /builds/slave/test/properties 06:19:55 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 06:19:55 INFO - Writing to file /builds/slave/test/properties/build_url 06:19:55 INFO - Contents: 06:19:55 INFO - build_url:https://queue.taskcluster.net/v1/task/NUMy7VlYR3WKCZ7HISXUUw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 06:19:56 INFO - Running post-action listener: _resource_record_post_action 06:19:56 INFO - Running post-action listener: set_extra_try_arguments 06:19:56 INFO - [mozharness: 2016-10-27 13:19:56.107650Z] Finished download-and-extract step (success) 06:19:56 INFO - [mozharness: 2016-10-27 13:19:56.107924Z] Running populate-webroot step. 06:19:56 INFO - Running pre-action listener: _resource_record_pre_action 06:19:56 INFO - Running main action method: populate_webroot 06:19:56 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 06:19:56 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 06:19:56 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 06:19:56 INFO - '--tppagecycles', 06:19:56 INFO - '7'], 06:19:56 INFO - 'tests': ['tsvgm']}}, 06:19:56 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 06:19:56 INFO - 'tests': ['tresize', 'tcanvasmark']}, 06:19:56 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 06:19:56 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 06:19:56 INFO - 'tests': ['dromaeo_css', 'kraken']}, 06:19:56 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 06:19:56 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:19:56 INFO - 'talos_options': ['--disable-e10s'], 06:19:56 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 06:19:56 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:19:56 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 06:19:56 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:19:56 INFO - 'talos_options': ['--disable-e10s'], 06:19:56 INFO - 'tests': ['damp', 'tps']}, 06:19:56 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:19:56 INFO - 'tests': ['damp', 'tps']}, 06:19:56 INFO - 'g3': {'talos_options': ['--disable-e10s'], 06:19:56 INFO - 'tests': ['dromaeo_dom']}, 06:19:56 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 06:19:56 INFO - 'g4': {'talos_options': ['--disable-e10s'], 06:19:56 INFO - 'tests': ['basic_compositor_video']}, 06:19:56 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 06:19:56 INFO - 'other': {'talos_options': ['--disable-e10s'], 06:19:56 INFO - 'tests': ['a11yr', 06:19:56 INFO - 'ts_paint', 06:19:56 INFO - 'tpaint', 06:19:56 INFO - 'sessionrestore', 06:19:56 INFO - 'sessionrestore_no_auto_restore', 06:19:56 INFO - 'tabpaint']}, 06:19:56 INFO - 'other-e10s': {'tests': ['a11yr', 06:19:56 INFO - 'ts_paint', 06:19:56 INFO - 'tpaint', 06:19:56 INFO - 'sessionrestore', 06:19:56 INFO - 'sessionrestore_no_auto_restore', 06:19:56 INFO - 'tabpaint']}, 06:19:56 INFO - 'other-e10s_l64': {'tests': ['a11yr', 06:19:56 INFO - 'ts_paint', 06:19:56 INFO - 'tpaint', 06:19:56 INFO - 'sessionrestore', 06:19:56 INFO - 'sessionrestore_no_auto_restore', 06:19:56 INFO - 'tabpaint']}, 06:19:56 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 06:19:56 INFO - 'ts_paint', 06:19:56 INFO - 'tpaint', 06:19:56 INFO - 'sessionrestore', 06:19:56 INFO - 'sessionrestore_no_auto_restore', 06:19:56 INFO - 'tabpaint']}, 06:19:56 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 06:19:56 INFO - 'tests': ['a11yr', 06:19:56 INFO - 'ts_paint', 06:19:56 INFO - 'tpaint', 06:19:56 INFO - 'sessionrestore', 06:19:56 INFO - 'sessionrestore_no_auto_restore', 06:19:56 INFO - 'tabpaint']}, 06:19:56 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 06:19:56 INFO - 'tests': ['a11yr', 06:19:56 INFO - 'ts_paint', 06:19:56 INFO - 'tpaint', 06:19:56 INFO - 'sessionrestore', 06:19:56 INFO - 'sessionrestore_no_auto_restore', 06:19:56 INFO - 'tabpaint']}, 06:19:56 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 06:19:56 INFO - 'tests': ['tsvgx', 06:19:56 INFO - 'tsvgr_opacity', 06:19:56 INFO - 'tart', 06:19:56 INFO - 'tscrollx', 06:19:56 INFO - 'cart']}, 06:19:56 INFO - 'svgr-e10s': {'tests': ['tsvgx', 06:19:56 INFO - 'tsvgr_opacity', 06:19:56 INFO - 'tart', 06:19:56 INFO - 'tscrollx', 06:19:56 INFO - 'cart']}, 06:19:56 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:19:56 INFO - 'talos_options': ['--disable-e10s'], 06:19:56 INFO - 'tests': ['tp5o']}, 06:19:56 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:19:56 INFO - 'tests': ['tp5o']}, 06:19:56 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:19:56 INFO - 'talos_options': ['--disable-e10s', 06:19:56 INFO - '--xperf_path', 06:19:56 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 06:19:56 INFO - 'tests': ['tp5n']}, 06:19:56 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:19:56 INFO - 'talos_options': ['--xperf_path', 06:19:56 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 06:19:56 INFO - 'tests': ['tp5n']}}, 06:19:56 INFO - 'talos.zip': {'path': '', 06:19:56 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 06:19:56 INFO - Running post-action listener: _resource_record_post_action 06:19:56 INFO - [mozharness: 2016-10-27 13:19:56.123767Z] Finished populate-webroot step (success) 06:19:56 INFO - [mozharness: 2016-10-27 13:19:56.123863Z] Running create-virtualenv step. 06:19:56 INFO - Running pre-action listener: _resource_record_pre_action 06:19:56 INFO - Running main action method: create_virtualenv 06:19:56 INFO - Creating virtualenv /builds/slave/test/build/venv 06:19:56 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 06:19:56 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 06:19:56 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 06:19:56 INFO - Using real prefix '/usr' 06:19:56 INFO - New python executable in /builds/slave/test/build/venv/bin/python 06:19:56 INFO - Installing distribute.............................................................................................................................................................................................done. 06:19:58 INFO - Installing pip.................done. 06:19:58 INFO - Return code: 0 06:19:58 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 06:19: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')]} 06:19:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:19:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:19: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')]} 06:19:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:19:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:19: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', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1dad030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f108a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x206e180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x206c3b0>, '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 0x206d2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x206d740>, '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': '6d15e502d8e29d5d2676d664000001c3-1477571525.235682-683999593', '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 06:19: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', 'pip>=1.5'] in /builds/slave/test/build 06:19: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 pip>=1.5 06:19:58 INFO - Using env: {'DISPLAY': ':0', 06:19:58 INFO - 'HOME': '/home/cltbld', 06:19:58 INFO - 'LANG': 'en_US.UTF-8', 06:19:58 INFO - 'LANGUAGE': 'en_US:en', 06:19:58 INFO - 'LOGNAME': 'cltbld', 06:19:58 INFO - 'MAIL': '/var/mail/cltbld', 06:19:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:19:58 INFO - 'MOZ_NO_REMOTE': '1', 06:19:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 06:19:58 INFO - 'NO_EM_RESTART': '1', 06:19:58 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 06:19:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:19:58 INFO - 'PWD': '/builds/slave/test', 06:19:58 INFO - 'SHELL': '/bin/bash', 06:19:58 INFO - 'SHLVL': '1', 06:19:58 INFO - 'TERM': 'linux', 06:19:58 INFO - 'TMOUT': '86400', 06:19:58 INFO - 'USER': 'cltbld', 06:19:58 INFO - 'XDG_SESSION_COOKIE': '6d15e502d8e29d5d2676d664000001c3-1477571525.235682-683999593', 06:19:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:19:58 INFO - '_': '/tools/buildbot/bin/python'} 06:19:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:19:58 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 06:19:58 INFO - Cleaning up... 06:19:58 INFO - Return code: 0 06:19:58 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 06:19: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')]} 06:19:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:19:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:19: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')]} 06:19:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:19:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:19: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', '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 0x1dad030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f108a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x206e180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x206c3b0>, '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 0x206d2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x206d740>, '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': '6d15e502d8e29d5d2676d664000001c3-1477571525.235682-683999593', '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 06:19: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', 'psutil>=3.1.1'] in /builds/slave/test/build 06:19: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 psutil>=3.1.1 06:19:58 INFO - Using env: {'DISPLAY': ':0', 06:19:58 INFO - 'HOME': '/home/cltbld', 06:19:58 INFO - 'LANG': 'en_US.UTF-8', 06:19:58 INFO - 'LANGUAGE': 'en_US:en', 06:19:58 INFO - 'LOGNAME': 'cltbld', 06:19:58 INFO - 'MAIL': '/var/mail/cltbld', 06:19:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:19:58 INFO - 'MOZ_NO_REMOTE': '1', 06:19:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 06:19:58 INFO - 'NO_EM_RESTART': '1', 06:19:58 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 06:19:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:19:58 INFO - 'PWD': '/builds/slave/test', 06:19:58 INFO - 'SHELL': '/bin/bash', 06:19:58 INFO - 'SHLVL': '1', 06:19:58 INFO - 'TERM': 'linux', 06:19:58 INFO - 'TMOUT': '86400', 06:19:58 INFO - 'USER': 'cltbld', 06:19:58 INFO - 'XDG_SESSION_COOKIE': '6d15e502d8e29d5d2676d664000001c3-1477571525.235682-683999593', 06:19:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:19:58 INFO - '_': '/tools/buildbot/bin/python'} 06:19:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:19:59 INFO - Downloading/unpacking psutil>=3.1.1 06:19:59 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 06:19:59 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 06:19:59 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 06:19:59 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 06:19:59 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 06:19:59 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 06:20:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 06:20:01 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 06:20:01 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 06:20:01 INFO - Installing collected packages: psutil 06:20:01 INFO - Running setup.py install for psutil 06:20:01 INFO - building 'psutil._psutil_linux' extension 06:20:01 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 06:20:01 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 06:20:01 INFO - building 'psutil._psutil_posix' extension 06:20:01 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 06:20:01 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 06:20:01 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 06:20:01 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 06:20:01 INFO - Successfully installed psutil 06:20:01 INFO - Cleaning up... 06:20:01 INFO - Return code: 0 06:20:01 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 06:20: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')]} 06:20:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:20:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:20: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')]} 06:20:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:20:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:20:01 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 0x1dad030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f108a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x206e180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x206c3b0>, '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 0x206d2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x206d740>, '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': '6d15e502d8e29d5d2676d664000001c3-1477571525.235682-683999593', '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 06:20:01 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 06:20:01 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 06:20:01 INFO - Using env: {'DISPLAY': ':0', 06:20:01 INFO - 'HOME': '/home/cltbld', 06:20:01 INFO - 'LANG': 'en_US.UTF-8', 06:20:01 INFO - 'LANGUAGE': 'en_US:en', 06:20:01 INFO - 'LOGNAME': 'cltbld', 06:20:01 INFO - 'MAIL': '/var/mail/cltbld', 06:20:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:20:01 INFO - 'MOZ_NO_REMOTE': '1', 06:20:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 06:20:01 INFO - 'NO_EM_RESTART': '1', 06:20:01 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 06:20:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:20:01 INFO - 'PWD': '/builds/slave/test', 06:20:01 INFO - 'SHELL': '/bin/bash', 06:20:01 INFO - 'SHLVL': '1', 06:20:01 INFO - 'TERM': 'linux', 06:20:01 INFO - 'TMOUT': '86400', 06:20:01 INFO - 'USER': 'cltbld', 06:20:01 INFO - 'XDG_SESSION_COOKIE': '6d15e502d8e29d5d2676d664000001c3-1477571525.235682-683999593', 06:20:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:20:01 INFO - '_': '/tools/buildbot/bin/python'} 06:20:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:20:01 INFO - Downloading/unpacking mozsystemmonitor==0.3 06:20:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:20:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:20:01 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:20:01 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:20:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:20:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:20:03 INFO - Downloading mozsystemmonitor-0.3.tar.gz 06:20:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 06:20:03 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 06:20:03 INFO - Installing collected packages: mozsystemmonitor 06:20:03 INFO - Running setup.py install for mozsystemmonitor 06:20:03 INFO - Successfully installed mozsystemmonitor 06:20:03 INFO - Cleaning up... 06:20:04 INFO - Return code: 0 06:20:04 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 06:20:04 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')]} 06:20:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:20:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:20:04 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')]} 06:20:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:20:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:20:04 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 0x1dad030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f108a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x206e180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x206c3b0>, '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 0x206d2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x206d740>, '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': '6d15e502d8e29d5d2676d664000001c3-1477571525.235682-683999593', '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 06:20:04 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 06:20:04 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 06:20:04 INFO - Using env: {'DISPLAY': ':0', 06:20:04 INFO - 'HOME': '/home/cltbld', 06:20:04 INFO - 'LANG': 'en_US.UTF-8', 06:20:04 INFO - 'LANGUAGE': 'en_US:en', 06:20:04 INFO - 'LOGNAME': 'cltbld', 06:20:04 INFO - 'MAIL': '/var/mail/cltbld', 06:20:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:20:04 INFO - 'MOZ_NO_REMOTE': '1', 06:20:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 06:20:04 INFO - 'NO_EM_RESTART': '1', 06:20:04 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 06:20:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:20:04 INFO - 'PWD': '/builds/slave/test', 06:20:04 INFO - 'SHELL': '/bin/bash', 06:20:04 INFO - 'SHLVL': '1', 06:20:04 INFO - 'TERM': 'linux', 06:20:04 INFO - 'TMOUT': '86400', 06:20:04 INFO - 'USER': 'cltbld', 06:20:04 INFO - 'XDG_SESSION_COOKIE': '6d15e502d8e29d5d2676d664000001c3-1477571525.235682-683999593', 06:20:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:20:04 INFO - '_': '/tools/buildbot/bin/python'} 06:20:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:20:04 INFO - Downloading/unpacking jsonschema==2.5.1 06:20:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:20:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:20:04 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:20:04 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:20:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:20:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:20:06 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 06:20:06 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 06:20:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:20:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:20:06 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:20:06 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:20:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:20:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:20:06 INFO - Downloading functools32-3.2.3-2.tar.gz 06:20:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 06:20:06 INFO - warning: no files found matching '*.txt' 06:20:06 INFO - no previously-included directories found matching 'build' 06:20:06 INFO - no previously-included directories found matching 'dist' 06:20:06 INFO - no previously-included directories found matching '.git*' 06:20:06 INFO - Installing collected packages: jsonschema, functools32 06:20:06 INFO - Running setup.py install for functools32 06:20:06 INFO - warning: no files found matching '*.txt' 06:20:06 INFO - no previously-included directories found matching 'build' 06:20:06 INFO - no previously-included directories found matching 'dist' 06:20:06 INFO - no previously-included directories found matching '.git*' 06:20:06 INFO - Successfully installed jsonschema functools32 06:20:06 INFO - Cleaning up... 06:20:06 INFO - Return code: 0 06:20:06 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 06:20:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:20:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:20:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:20:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:20:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:20:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:20:06 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1dad030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f108a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x206e180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x206c3b0>, '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 0x206d2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x206d740>, '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': '6d15e502d8e29d5d2676d664000001c3-1477571525.235682-683999593', '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 06:20:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 06:20:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 06:20:06 INFO - Using env: {'DISPLAY': ':0', 06:20:06 INFO - 'HOME': '/home/cltbld', 06:20:06 INFO - 'LANG': 'en_US.UTF-8', 06:20:06 INFO - 'LANGUAGE': 'en_US:en', 06:20:06 INFO - 'LOGNAME': 'cltbld', 06:20:06 INFO - 'MAIL': '/var/mail/cltbld', 06:20:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:20:06 INFO - 'MOZ_NO_REMOTE': '1', 06:20:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 06:20:06 INFO - 'NO_EM_RESTART': '1', 06:20:06 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 06:20:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:20:06 INFO - 'PWD': '/builds/slave/test', 06:20:06 INFO - 'SHELL': '/bin/bash', 06:20:06 INFO - 'SHLVL': '1', 06:20:06 INFO - 'TERM': 'linux', 06:20:06 INFO - 'TMOUT': '86400', 06:20:06 INFO - 'USER': 'cltbld', 06:20:06 INFO - 'XDG_SESSION_COOKIE': '6d15e502d8e29d5d2676d664000001c3-1477571525.235682-683999593', 06:20:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:20:06 INFO - '_': '/tools/buildbot/bin/python'} 06:20:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:20:06 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 06:20:06 INFO - Cleaning up... 06:20:06 INFO - Return code: 0 06:20:06 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 06:20:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:20:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:20:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:20:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:20:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:20:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:20:06 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1dad030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f108a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x206e180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x206c3b0>, '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 0x206d2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x206d740>, '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': '6d15e502d8e29d5d2676d664000001c3-1477571525.235682-683999593', '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 06:20:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 06:20:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 06:20:06 INFO - Using env: {'DISPLAY': ':0', 06:20:06 INFO - 'HOME': '/home/cltbld', 06:20:06 INFO - 'LANG': 'en_US.UTF-8', 06:20:06 INFO - 'LANGUAGE': 'en_US:en', 06:20:06 INFO - 'LOGNAME': 'cltbld', 06:20:06 INFO - 'MAIL': '/var/mail/cltbld', 06:20:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:20:06 INFO - 'MOZ_NO_REMOTE': '1', 06:20:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 06:20:06 INFO - 'NO_EM_RESTART': '1', 06:20:06 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 06:20:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:20:06 INFO - 'PWD': '/builds/slave/test', 06:20:06 INFO - 'SHELL': '/bin/bash', 06:20:06 INFO - 'SHLVL': '1', 06:20:06 INFO - 'TERM': 'linux', 06:20:06 INFO - 'TMOUT': '86400', 06:20:06 INFO - 'USER': 'cltbld', 06:20:06 INFO - 'XDG_SESSION_COOKIE': '6d15e502d8e29d5d2676d664000001c3-1477571525.235682-683999593', 06:20:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:20:06 INFO - '_': '/tools/buildbot/bin/python'} 06:20:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:20:07 INFO - Downloading/unpacking blobuploader==1.2.4 06:20:07 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 06:20:07 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 06:20:07 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 06:20:07 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 06:20:07 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 06:20:07 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 06:20:09 INFO - Downloading blobuploader-1.2.4.tar.gz 06:20:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 06:20:09 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 06:20:09 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 06:20:09 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 06:20:09 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 06:20:09 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 06:20:09 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 06:20:09 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 06:20:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 06:20:09 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 06:20:09 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 06:20:09 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 06:20:09 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 06:20:09 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 06:20:09 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 06:20:09 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 06:20:09 INFO - Downloading docopt-0.6.1.tar.gz 06:20:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 06:20:09 INFO - Installing collected packages: blobuploader, requests, docopt 06:20:09 INFO - Running setup.py install for blobuploader 06:20:09 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 06:20:09 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 06:20:09 INFO - Running setup.py install for requests 06:20:10 INFO - Running setup.py install for docopt 06:20:10 INFO - Successfully installed blobuploader requests docopt 06:20:10 INFO - Cleaning up... 06:20:10 INFO - Return code: 0 06:20:10 INFO - Installing None into virtualenv /builds/slave/test/build/venv 06:20:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:20:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:20:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:20:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:20:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:20:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:20:10 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 0x1dad030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f108a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x206e180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x206c3b0>, '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 0x206d2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x206d740>, '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': '6d15e502d8e29d5d2676d664000001c3-1477571525.235682-683999593', '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 06:20:10 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 06:20:10 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 06:20:10 INFO - Using env: {'DISPLAY': ':0', 06:20:10 INFO - 'HOME': '/home/cltbld', 06:20:10 INFO - 'LANG': 'en_US.UTF-8', 06:20:10 INFO - 'LANGUAGE': 'en_US:en', 06:20:10 INFO - 'LOGNAME': 'cltbld', 06:20:10 INFO - 'MAIL': '/var/mail/cltbld', 06:20:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:20:10 INFO - 'MOZ_NO_REMOTE': '1', 06:20:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 06:20:10 INFO - 'NO_EM_RESTART': '1', 06:20:10 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 06:20:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:20:10 INFO - 'PWD': '/builds/slave/test', 06:20:10 INFO - 'SHELL': '/bin/bash', 06:20:10 INFO - 'SHLVL': '1', 06:20:10 INFO - 'TERM': 'linux', 06:20:10 INFO - 'TMOUT': '86400', 06:20:10 INFO - 'USER': 'cltbld', 06:20:10 INFO - 'XDG_SESSION_COOKIE': '6d15e502d8e29d5d2676d664000001c3-1477571525.235682-683999593', 06:20:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:20:10 INFO - '_': '/tools/buildbot/bin/python'} 06:20:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:20:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 06:20:10 INFO - Running setup.py (path:/tmp/pip-ytN763-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 06:20:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 06:20:10 INFO - Running setup.py (path:/tmp/pip-_lmt1n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 06:20:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 06:20:10 INFO - Running setup.py (path:/tmp/pip-wvbpr8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 06:20:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 06:20:10 INFO - Running setup.py (path:/tmp/pip-Kx80Io-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 06:20:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 06:20:10 INFO - Running setup.py (path:/tmp/pip-Xycvz6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 06:20:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 06:20:11 INFO - Running setup.py (path:/tmp/pip-qS2vbE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 06:20:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 06:20:11 INFO - Running setup.py (path:/tmp/pip-G3Wmcp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 06:20:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 06:20:11 INFO - Running setup.py (path:/tmp/pip-VLYfwk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 06:20:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 06:20:11 INFO - Running setup.py (path:/tmp/pip-dOgggi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 06:20:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 06:20:11 INFO - Running setup.py (path:/tmp/pip-lyJqGc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 06:20:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 06:20:11 INFO - Running setup.py (path:/tmp/pip-cCMJ1g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 06:20:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 06:20:11 INFO - Running setup.py (path:/tmp/pip-euc4_7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 06:20:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 06:20:11 INFO - Running setup.py (path:/tmp/pip-5dJrfc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 06:20:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 06:20:11 INFO - Running setup.py (path:/tmp/pip-EE8T3m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 06:20:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 06:20:11 INFO - Running setup.py (path:/tmp/pip-6PstVH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 06:20:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 06:20:11 INFO - Running setup.py (path:/tmp/pip-hnMAJ1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 06:20:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 06:20:11 INFO - Running setup.py (path:/tmp/pip-Cf4BDr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 06:20:11 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 06:20:11 INFO - Running setup.py install for manifestparser 06:20:11 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 06:20:11 INFO - Running setup.py install for mozcrash 06:20:12 INFO - Running setup.py install for mozdebug 06:20:12 INFO - Running setup.py install for mozdevice 06:20:12 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 06:20:12 INFO - Installing dm script to /builds/slave/test/build/venv/bin 06:20:12 INFO - Running setup.py install for mozfile 06:20:12 INFO - Running setup.py install for mozhttpd 06:20:12 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 06:20:12 INFO - Running setup.py install for mozinfo 06:20:12 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 06:20:12 INFO - Running setup.py install for mozInstall 06:20:12 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 06:20:12 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 06:20:12 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 06:20:12 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 06:20:12 INFO - Running setup.py install for mozleak 06:20:12 INFO - Running setup.py install for mozlog 06:20:12 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 06:20:12 INFO - Running setup.py install for moznetwork 06:20:12 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 06:20:12 INFO - Running setup.py install for mozprocess 06:20:13 INFO - Running setup.py install for mozprofile 06:20:13 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 06:20:13 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 06:20:13 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 06:20:13 INFO - Running setup.py install for mozrunner 06:20:13 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 06:20:13 INFO - Running setup.py install for mozscreenshot 06:20:13 INFO - Running setup.py install for moztest 06:20:13 INFO - Running setup.py install for mozversion 06:20:13 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 06:20:13 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 06:20:13 INFO - Cleaning up... 06:20:13 INFO - Return code: 0 06:20:13 INFO - Installing None into virtualenv /builds/slave/test/build/venv 06:20:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:20:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:20:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:20:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:20:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:20:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:20:13 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 0x1dad030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f108a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x206e180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x206c3b0>, '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 0x206d2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x206d740>, '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': '6d15e502d8e29d5d2676d664000001c3-1477571525.235682-683999593', '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 06:20:13 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 06:20:13 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 06:20:13 INFO - Using env: {'DISPLAY': ':0', 06:20:13 INFO - 'HOME': '/home/cltbld', 06:20:13 INFO - 'LANG': 'en_US.UTF-8', 06:20:13 INFO - 'LANGUAGE': 'en_US:en', 06:20:13 INFO - 'LOGNAME': 'cltbld', 06:20:13 INFO - 'MAIL': '/var/mail/cltbld', 06:20:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:20:13 INFO - 'MOZ_NO_REMOTE': '1', 06:20:13 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 06:20:13 INFO - 'NO_EM_RESTART': '1', 06:20:13 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 06:20:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:20:13 INFO - 'PWD': '/builds/slave/test', 06:20:13 INFO - 'SHELL': '/bin/bash', 06:20:13 INFO - 'SHLVL': '1', 06:20:13 INFO - 'TERM': 'linux', 06:20:13 INFO - 'TMOUT': '86400', 06:20:13 INFO - 'USER': 'cltbld', 06:20:13 INFO - 'XDG_SESSION_COOKIE': '6d15e502d8e29d5d2676d664000001c3-1477571525.235682-683999593', 06:20:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:20:13 INFO - '_': '/tools/buildbot/bin/python'} 06:20:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:20:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 06:20:13 INFO - Running setup.py (path:/tmp/pip-BZhmJ_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 06:20:13 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)) 06:20:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 06:20:13 INFO - Running setup.py (path:/tmp/pip-bFHYHI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 06:20:14 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)) 06:20:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 06:20:14 INFO - Running setup.py (path:/tmp/pip-3ED8mQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 06:20:14 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)) 06:20:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 06:20:14 INFO - Running setup.py (path:/tmp/pip-mUcpR5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 06:20:14 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)) 06:20:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 06:20:14 INFO - Running setup.py (path:/tmp/pip-wCxLKN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 06:20:14 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)) 06:20:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 06:20:14 INFO - Running setup.py (path:/tmp/pip-H4paPA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 06:20:14 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)) 06:20:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 06:20:14 INFO - Running setup.py (path:/tmp/pip-c5yRme-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 06:20:14 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)) 06:20:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 06:20:14 INFO - Running setup.py (path:/tmp/pip-Ph2ig6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 06:20:14 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)) 06:20:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 06:20:14 INFO - Running setup.py (path:/tmp/pip-PsOQNm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 06:20:14 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)) 06:20:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 06:20:14 INFO - Running setup.py (path:/tmp/pip-BzBtNo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 06:20:14 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)) 06:20:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 06:20:14 INFO - Running setup.py (path:/tmp/pip-9IGw9r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 06:20:14 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)) 06:20:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 06:20:14 INFO - Running setup.py (path:/tmp/pip-wJZQEZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 06:20:14 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)) 06:20:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 06:20:14 INFO - Running setup.py (path:/tmp/pip-9hJDJs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 06:20:14 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)) 06:20:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 06:20:14 INFO - Running setup.py (path:/tmp/pip-V9sf5T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 06:20:14 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)) 06:20:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 06:20:14 INFO - Running setup.py (path:/tmp/pip-8kTMTv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 06:20:14 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)) 06:20:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 06:20:14 INFO - Running setup.py (path:/tmp/pip-IeexPB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 06:20:15 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)) 06:20:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 06:20:15 INFO - Running setup.py (path:/tmp/pip-jnU68L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 06:20:15 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)) 06:20:15 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)) 06:20:15 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)) 06:20:15 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)) 06:20:15 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)) 06:20:15 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)) 06:20:15 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 06:20:15 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 06:20:15 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 06:20:15 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 06:20:15 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 06:20:15 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 06:20:15 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 06:20:17 INFO - Downloading blessings-1.6.tar.gz 06:20:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 06:20:17 INFO - Installing collected packages: blessings 06:20:17 INFO - Running setup.py install for blessings 06:20:17 INFO - Successfully installed blessings 06:20:17 INFO - Cleaning up... 06:20:17 INFO - Return code: 0 06:20:17 INFO - Done creating virtualenv /builds/slave/test/build/venv. 06:20:17 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 06:20:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 06:20:17 INFO - Reading from file tmpfile_stdout 06:20:17 INFO - Current package versions: 06:20:17 INFO - argparse == 1.2.1 06:20:17 INFO - blessings == 1.6 06:20:17 INFO - blobuploader == 1.2.4 06:20:17 INFO - docopt == 0.6.1 06:20:17 INFO - functools32 == 3.2.3-2 06:20:17 INFO - jsonschema == 2.5.1 06:20:17 INFO - manifestparser == 1.1 06:20:17 INFO - mozInstall == 1.12 06:20:17 INFO - mozcrash == 0.17 06:20:17 INFO - mozdebug == 0.1 06:20:17 INFO - mozdevice == 0.48 06:20:17 INFO - mozfile == 1.2 06:20:17 INFO - mozhttpd == 0.7 06:20:17 INFO - mozinfo == 0.9 06:20:17 INFO - mozleak == 0.1 06:20:17 INFO - mozlog == 3.3 06:20:17 INFO - moznetwork == 0.27 06:20:17 INFO - mozprocess == 0.23 06:20:17 INFO - mozprofile == 0.28 06:20:17 INFO - mozrunner == 6.12 06:20:17 INFO - mozscreenshot == 0.1 06:20:17 INFO - mozsystemmonitor == 0.3 06:20:17 INFO - moztest == 0.7 06:20:17 INFO - mozversion == 1.4 06:20:17 INFO - psutil == 3.1.1 06:20:17 INFO - requests == 1.2.3 06:20:17 INFO - wsgiref == 0.1.2 06:20:17 INFO - Installing None into virtualenv /builds/slave/test/build/venv 06:20:17 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')]} 06:20:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:20:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:20:17 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')]} 06:20:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:20:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:20:17 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 0x1dad030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f108a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x206e180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x206c3b0>, '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 0x206d2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x206d740>, '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': '6d15e502d8e29d5d2676d664000001c3-1477571525.235682-683999593', '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 06:20:17 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 06:20:17 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 06:20:17 INFO - Using env: {'DISPLAY': ':0', 06:20:17 INFO - 'HOME': '/home/cltbld', 06:20:17 INFO - 'LANG': 'en_US.UTF-8', 06:20:17 INFO - 'LANGUAGE': 'en_US:en', 06:20:17 INFO - 'LOGNAME': 'cltbld', 06:20:17 INFO - 'MAIL': '/var/mail/cltbld', 06:20:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:20:17 INFO - 'MOZ_NO_REMOTE': '1', 06:20:17 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 06:20:17 INFO - 'NO_EM_RESTART': '1', 06:20:17 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 06:20:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:20:17 INFO - 'PWD': '/builds/slave/test', 06:20:17 INFO - 'SHELL': '/bin/bash', 06:20:17 INFO - 'SHLVL': '1', 06:20:17 INFO - 'TERM': 'linux', 06:20:17 INFO - 'TMOUT': '86400', 06:20:17 INFO - 'USER': 'cltbld', 06:20:17 INFO - 'XDG_SESSION_COOKIE': '6d15e502d8e29d5d2676d664000001c3-1477571525.235682-683999593', 06:20:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:20:17 INFO - '_': '/tools/buildbot/bin/python'} 06:20:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:20:17 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)) 06:20:17 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)) 06:20:17 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)) 06:20:17 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)) 06:20:17 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)) 06:20:17 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)) 06:20:17 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)) 06:20:17 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)) 06:20:17 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)) 06:20:17 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)) 06:20:17 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)) 06:20:17 INFO - Cleaning up... 06:20:17 INFO - Return code: 0 06:20:17 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 06:20:17 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')]} 06:20:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:20:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:20:17 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')]} 06:20:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:20:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:20:17 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 0x1dad030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f108a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x206e180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x206c3b0>, '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 0x206d2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x206d740>, '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': '6d15e502d8e29d5d2676d664000001c3-1477571525.235682-683999593', '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 06:20:17 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 06:20:17 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 06:20:17 INFO - Using env: {'DISPLAY': ':0', 06:20:17 INFO - 'HOME': '/home/cltbld', 06:20:17 INFO - 'LANG': 'en_US.UTF-8', 06:20:17 INFO - 'LANGUAGE': 'en_US:en', 06:20:17 INFO - 'LOGNAME': 'cltbld', 06:20:17 INFO - 'MAIL': '/var/mail/cltbld', 06:20:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:20:17 INFO - 'MOZ_NO_REMOTE': '1', 06:20:17 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 06:20:17 INFO - 'NO_EM_RESTART': '1', 06:20:17 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 06:20:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:20:17 INFO - 'PWD': '/builds/slave/test', 06:20:17 INFO - 'SHELL': '/bin/bash', 06:20:17 INFO - 'SHLVL': '1', 06:20:17 INFO - 'TERM': 'linux', 06:20:17 INFO - 'TMOUT': '86400', 06:20:17 INFO - 'USER': 'cltbld', 06:20:17 INFO - 'XDG_SESSION_COOKIE': '6d15e502d8e29d5d2676d664000001c3-1477571525.235682-683999593', 06:20:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:20:17 INFO - '_': '/tools/buildbot/bin/python'} 06:20:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:20:17 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 06:20:17 INFO - Cleaning up... 06:20:17 INFO - Return code: 0 06:20:17 INFO - Running post-action listener: _resource_record_post_action 06:20:17 INFO - Running post-action listener: _start_resource_monitoring 06:20:17 INFO - Starting resource monitoring. 06:20:17 INFO - [mozharness: 2016-10-27 13:20:17.968114Z] Finished create-virtualenv step (success) 06:20:17 INFO - [mozharness: 2016-10-27 13:20:17.968600Z] Running install step. 06:20:17 INFO - Running pre-action listener: _resource_record_pre_action 06:20:17 INFO - Running main action method: install 06:20:17 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 06:20:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 06:20:18 INFO - Reading from file tmpfile_stdout 06:20:18 INFO - Detecting whether we're running mozinstall >=1.0... 06:20:18 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 06:20:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 06:20:18 INFO - Reading from file tmpfile_stdout 06:20:18 INFO - Output received: 06:20:18 INFO - Usage: mozinstall [options] installer 06:20:18 INFO - Options: 06:20:18 INFO - -h, --help show this help message and exit 06:20:18 INFO - -d DEST, --destination=DEST 06:20:18 INFO - Directory to install application into. [default: 06:20:18 INFO - "/builds/slave/test"] 06:20:18 INFO - --app=APP Application being installed. [default: firefox] 06:20:18 INFO - mkdir: /builds/slave/test/build/application 06:20:18 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'] 06:20:18 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 06:20:32 INFO - Reading from file tmpfile_stdout 06:20:32 INFO - Output received: 06:20:32 INFO - /builds/slave/test/build/application/firefox/firefox 06:20:32 INFO - Running post-action listener: _resource_record_post_action 06:20:32 INFO - [mozharness: 2016-10-27 13:20:32.288262Z] Finished install step (success) 06:20:32 INFO - [mozharness: 2016-10-27 13:20:32.288536Z] Running run-tests step. 06:20:32 INFO - Running pre-action listener: _resource_record_pre_action 06:20:32 INFO - Running main action method: run_tests 06:20:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 06:20:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 06:20:32 INFO - Python 2.7.3 06:20:32 INFO - Return code: 0 06:20:32 INFO - grabbing minidump binary from tooltool 06:20:32 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')]} 06:20:32 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 0x206c3b0>, '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 0x206d2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x206d740>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 06:20:32 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 06:20:32 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 06:20:32 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 06:20:32 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 06:20:32 INFO - Return code: 0 06:20:32 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 06:20:32 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 06:20:32 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 06:20:32 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 06:20:32 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 06:20:32 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/NUMy7VlYR3WKCZ7HISXUUw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-023', '--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 06:20:32 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/NUMy7VlYR3WKCZ7HISXUUw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-023 --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 06:20:32 INFO - Using env: {'DISPLAY': ':0', 06:20:32 INFO - 'HOME': '/home/cltbld', 06:20:32 INFO - 'LANG': 'en_US.UTF-8', 06:20:32 INFO - 'LANGUAGE': 'en_US:en', 06:20:32 INFO - 'LOGNAME': 'cltbld', 06:20:32 INFO - 'MAIL': '/var/mail/cltbld', 06:20:32 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 06:20:32 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 06:20:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:20:32 INFO - 'MOZ_NO_REMOTE': '1', 06:20:32 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 06:20:32 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 06:20:32 INFO - 'NO_EM_RESTART': '1', 06:20:32 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 06:20:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:20:32 INFO - 'PWD': '/builds/slave/test', 06:20:32 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 06:20:32 INFO - 'SHELL': '/bin/bash', 06:20:32 INFO - 'SHLVL': '1', 06:20:32 INFO - 'TERM': 'linux', 06:20:32 INFO - 'TMOUT': '86400', 06:20:32 INFO - 'USER': 'cltbld', 06:20:32 INFO - 'XDG_SESSION_COOKIE': '6d15e502d8e29d5d2676d664000001c3-1477571525.235682-683999593', 06:20:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:20:32 INFO - '_': '/tools/buildbot/bin/python'} 06:20:32 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/NUMy7VlYR3WKCZ7HISXUUw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-023', '--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 06:20:32 INFO - mozversion application_buildid: 20161027030534 06:20:32 INFO - mozversion application_changeset: 30092cd6645ff14c7714da97e177f82fee34caf1 06:20:32 INFO - mozversion application_display_name: Firefox 06:20:32 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 06:20:32 INFO - mozversion application_name: Firefox 06:20:32 INFO - mozversion application_remotingname: firefox 06:20:32 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 06:20:32 INFO - mozversion application_vendor: Mozilla 06:20:32 INFO - mozversion application_version: 50.0 06:20:32 INFO - mozversion platform_buildid: 20161027030534 06:20:32 INFO - mozversion platform_changeset: 30092cd6645ff14c7714da97e177f82fee34caf1 06:20:32 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 06:20:32 INFO - mozversion platform_version: 50.0 06:20:32 INFO - using testdate: 1477574432 06:20:32 INFO - actual date: 1477574432 06:20:32 INFO - starting webserver on 'localhost:46809' 06:20:32 INFO - SUITE-START | Running 2 tests 06:20:32 INFO - TEST-START | dromaeo_css 06:20:32 INFO - operating with platform_type : linux_ 06:20:32 INFO - Initialising browser for dromaeo_css test... 06:20:32 INFO - TEST-INFO | started process 4162 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpWVbxOy/profile http://localhost:46809/getInfo.html) 06:20:36 INFO - PROCESS | 4162 | __metrics Screen width/height:1600/1200 06:20:36 INFO - PROCESS | 4162 | colorDepth:24 06:20:36 INFO - PROCESS | 4162 | Browser inner width/height: 1024/697 06:20:36 INFO - PROCESS | 4162 | __metrics 06:20:37 INFO - PROCESS | 4162 | 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" 06:20:37 INFO - PROCESS | 4162 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 06:20:37 INFO - PROCESS | 4162 | console.error: PushService: 06:20:37 INFO - PROCESS | 4162 | stateChangeProcessEnqueue: Error transitioning state 06:20:37 INFO - PROCESS | 4162 | UnknownError 06:20:37 INFO - TEST-INFO | 4162: exit 0 06:20:37 INFO - Browser initialized. 06:20:37 INFO - Running cycle 1/1 for dromaeo_css test... 06:20:37 INFO - TEST-INFO | started process 4250 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpWVbxOy/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 06:20:49 INFO - PROCESS | 4250 | RSS: Main: 144646144 06:20:49 INFO - PROCESS | 4250 | 06:21:40 INFO - PROCESS | 4250 | 1477574500902 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] 06:23:48 INFO - PROCESS | 4250 | Cycle 1(1): loaded http://localhost:46809/tests/dromaeo/cssquery-dojo.html (next: http://localhost:46809/tests/dromaeo/cssquery-ext.html) 06:23:50 INFO - PROCESS | 4250 | RSS: Main: 258895872 06:23:50 INFO - PROCESS | 4250 | 06:26:52 INFO - PROCESS | 4250 | Cycle 1(1): loaded http://localhost:46809/tests/dromaeo/cssquery-ext.html (next: http://localhost:46809/tests/dromaeo/cssquery-jquery.html) 06:26:52 INFO - PROCESS | 4250 | RSS: Main: 237400064 06:26:52 INFO - PROCESS | 4250 | 06:29:54 INFO - PROCESS | 4250 | Cycle 1(1): loaded http://localhost:46809/tests/dromaeo/cssquery-jquery.html (next: http://localhost:46809/tests/dromaeo/cssquery-mootools.html) 06:29:55 INFO - PROCESS | 4250 | RSS: Main: 180822016 06:29:55 INFO - PROCESS | 4250 | 06:32:57 INFO - PROCESS | 4250 | Cycle 1(1): loaded http://localhost:46809/tests/dromaeo/cssquery-mootools.html (next: http://localhost:46809/tests/dromaeo/cssquery-prototype.html) 06:32:57 INFO - PROCESS | 4250 | RSS: Main: 175775744 06:32:57 INFO - PROCESS | 4250 | 06:36:00 INFO - PROCESS | 4250 | Cycle 1(1): loaded http://localhost:46809/tests/dromaeo/cssquery-prototype.html (next: http://localhost:46809/tests/dromaeo/cssquery-yui.html) 06:36:00 INFO - PROCESS | 4250 | RSS: Main: 177590272 06:36:00 INFO - PROCESS | 4250 | 06:39:03 INFO - PROCESS | 4250 | Cycle 1(1): loaded http://localhost:46809/tests/dromaeo/cssquery-yui.html (next: http://localhost:46809/tests/dromaeo/cssquery-dojo.html) 06:39:03 INFO - PROCESS | 4250 | RSS: Main: 249122816 06:39:03 INFO - PROCESS | 4250 | 06:39:03 INFO - PROCESS | 4250 | __start_tp_report 06:39:03 INFO - PROCESS | 4250 | _x_x_mozilla_page_load 06:39:03 INFO - PROCESS | 4250 | _x_x_mozilla_page_load_details 06:39:03 INFO - PROCESS | 4250 | |i|pagename|runs| 06:39:03 INFO - PROCESS | 4250 | |0;dojo.html;3234.7897386669865;3245.38337715834;3275.737940964723;3298.3897978873924;3310.35448087623;5667.57493188011;5753.827385178441;5761;5762.654240745558;5770.394108618587;5656.547476201904;5694.032014557525;5710.714464276791;5717.085266357383;5730.570207234453;5115.335006449166;5144.511271429208;5155.175171972485;5203.765830537616;5224.582033437316;5060.240963855433;5095.210242412413;5226.137687281594;5228.111221092405;5258.737063146847;5115.053715062725;5121.3342265505435;5153.123968925292;5207.453217412174;5226.529612334889;5214.66104703195;5245.501677340666;5247.580193584504;5248;5270.183121616155;5070.847874563769;5083.79664813407;5167.044096842096;5211.42674305826;5230.476952304777;5548.667079975174;5556.749946252428;5593.105103183471;5691.174779656926;5695.231143795618;6040.96979515104;6089.573729838913;6089.703599822355;6090.086487026937;6098.481629061491;6096.237970253718;6115.296740874767;6125.785597504066;6126.540509461764;6131.570790044699;5556.16657501374;5590.888182236373;5621.831345059655;5621.887562248732;5696.11710184922;6186.133941248228;6192.071189321638;6200.875982480416;6204.13142160098;6223.875522489525;5915.497182739429;5952.9107063394085;5956.225690660186;5958.314793798769;5974.1934838796205;5943.970280148572;6052.213212282375;6060.090986352083;6088.269407671108;6115.388461153938;6062.818115456544;6094.451499365078;6106.908396374058;6113.877722445615;6136.478399336017;6126.172966649535;6132.63204207749;6145.9692701536205;6152.569320147547;6152.8769424611255;4568.954310456919;4668.089722504079;4706.058788242365;4713.457952335457;4724.078804633064;4163.417121602978;4167.041580436456;4180.958190418118;4181.205570941512;4186.958130418657;5875.148103524965;5876.1479585459865;5877.676727779927;5880.206172166788;5890;5944.881102377928;6106.389361063947;6115.755369785248;6135.509159267248;6192.318844927055;5920.2303700519515;5920.407959204045;5920.437558431942;5920.526357891358;5951.9702401488585;5167.379914410295;5180.378354597397;5187.0922588546855;5189.273501709764;5196.194589838581;6138.419052470195;6283.622982621057;6319.589226700342;6346.2384513857705;6440.25937017249;6204.069389591597;6215.658138802229;6222.844428889314;6228.875422491616;6238.376162383725;6439.291677915519;6454.225492940689;6460.160179176677;6463.709133089116;6465.482761379172;5876.794312199224;5935.139404786368;5947.851303717442;5970.820875373745;6013.579049466671;6108.236470441195;6170.845728856814;6171.321154672893;6178.135061091541;6181.04193849936;6004.489618382312;6005;6006;6008.699565021819;6051.788187413419;5801.535877129904;5940.762369505172;5952.553558483044;5982.46157845796;6009.789657361971;5996.040633498794;6005.129256257906;6008.8497787553915;6019.699015049143;6034.60775049638;6047.183630209865;6051.909221361595;6085.361037091042;6106.603070800384;6108.297545782292;5789;5872.412758724094;5919.6152250103605;5945.494632956247;6002.609830361012 06:39:03 INFO - PROCESS | 4250 | |1;ext.html;594.3076316091754;636.3986033198628;663.3929954092005;666.7233098264221;683.5488577538825;30418.847905760467;46029;47134.528654713446;47166;47281.763591181996;48137.759311203394;48591.75704121475;48596.2710559341;48629;48658.27012594805;8475.321974242046;9309;9477.43135411874;9480.715578532621;11071.225014249007;10918.399488028155;11355.091592672567;11364.977152056314;11372.37451940143;11379.374134422604;11357.204995650307;11398.430078496083;11415.0297224736;11431.742508324078;11463.426828658576;11242.550297988071;11351.886481135165;11369.602063927805;11372.829407558893;11379.317240965485;11142.721431964266;11236.887631123665;11241.100711943025;11241.606543771011;11242.325460472397;46014;46350.76824615889;46425;46471.07057858858;46513.30230046552;3768.09565704229;3792.0709426190483;3948.190620922704;3982.38273067675;4154.729942553724;33888.49167262493;34339.14152146191;34339.4849077264;34344.82827585846;34589.481157782655;4668.649851261169;4727.57451829337;4737.692050016738;4760.809567617292;4767.928481072787;244116;244395;244404;244773;246428;62990.37009629937;63427;63520.36479635237;63566.36433635605;63608;40118;41011.38482922759;41230;43140.35289470661;65381.346186538474;18911.14899829511;19164.137613807412;19188.232470701296;19194.232230710895;19209.711854322195;12601.495940162291;12840;14185;14441;14483.78274325865;20709;21016.474588135417;21030.789692102884;21091.578168436547;21134.154633814476;7445.8883116753295;7476.401887848959;7671.966928763882;7753.689852405953;7811.617456675768;1061.9681409557725;1064.494365176535;1086.4947799273218;1110.1063643766684;1131.8302254661703;34576.82711586426;34973;35352.82323588417;35494.822525887204;35722;1164.941752912351;1182.0779791762438;1183.526589364244;1197.461142485885;1210.3524614331466;17750.37873674415;17809.10954452298;17885.552861178574;17944.730829037573;18033.639327213383;601082;602750;605455;605835;605950;60535;60822;60945;60986;61122.694386528674;1361;1438.8057612222426;1457.3951809998944;1480.8148981377328;1490.5379332407206;33202.667973320924;34598;35424;35560;35578;53348.1997770026;53871.46128538664;54007.72996134994;54072.72963635156;54163.18755218596;455553;461494;461749;462571;464427;27272.454550909268;29630.85184574063;30531.320777357458;30604.540931886484;32301.838490807397;46893.53106469028;49972;50336.748316258185;50614.49385506098;51329.743351283;42418.151636967705;42423.3636495459;43380.13239735251;43552.78223608862;44821.10357792891;41307.1738565221;41638.37542436805;42169.78915105405;43002.78498607487;43906.34140487959;42197.367039495046;42761;43556.34665480084;44150.55849441465;44167.33748993703;35667.28665426625;36676.63323366732;37659.246815062994;38312.616873830906;38501.6149838498;29490.410191796473;29639.25901852471;29756;29952;29958.850205748833 06:39:03 INFO - PROCESS | 4250 | |2;jquery.html;422.1500109587475;425.1390933359944;430.63396113303696;436.1473319660064;439.1590104949025;18796.154173062227;19602.509937251558;20334.796652033474;20424.38726838197;20705.06827192776;17268.22292996815;18934.62130757384;19521.609567808635;19579.5105122372;19640.901795491038;5248.422673505921;5616.0171969905105;5620.775168993236;5623.9437605623825;5634.887302253953;5459.508644222019;5473.261109750172;5518.944810551883;5556.972215138939;5558.166275058753;5335.67985920846;5462.262594549745;5482.205080263359;5492.945070549284;5503.3395992480955;5397.8380648580405;5418.214358917974;5459.563234941195;5475.561955043607;5486.890262194774;5344.866378340534;5347.385050719178;5349.384820745586;5351.464853514617;5357.062514060024;13982.510612128526;14011.299435028208;14145.717085658229;14148.151110933273;14379.281035948161;2438.4635380216323;2597.0520759922683;2642.2337522118564;2799.0623141247706;2825.3925406037597;10565.41890196039;10660.62687805931;10939.507722152443;11030.338179709242;11120.777584448346;1905.3331334033116;1955.882647041182;2008.789077146893;2015.647261729206;2061.2064355223347;79178.60410697968;83315.16684833134;84608.15391846064;84980;86871;11646.592369267204;11808.46861891217;11912.52349905994;11985.22096063753;12012.819807702892;16692.666146677242;16698.749518757228;16734.4142954996;16738;16751.6649667006;8865.512396818236;8970.327225458117;8982.101789821096;9027.14242146995;9168.72493825173;8531.829363412697;8601.483910965362;8636.524991125549;8721.781955451037;8894.777630559158;5135.897282054338;5149.562287205555;5160.92258616121;5208.140656791587;5233.822389962228;2037.9796202038087;2066.265749431688;2088.2273558783045;2122.543653114588;2171.83711221659;954.6610953111684;1023.6621914768109;1026.9486525673688;1033.1682995188944;1083.6640641401034;20036;21176.470588235417;21293;21295.574088518144;21556.784432155793;968.2060710217554;1001.2840818814437;1015.8171529124828;1041.5937784264142;1048.6644273832453;10048.196144308438;10435.947820261003;11260.99180474961;11319;11578.76842463146;571163;572162;576277;606600;612441;100334.49832750935;101269;102780.48609757052;103718.481407594;104376.47811761044;1062.3838173859144;1103.5254840418536;1111.9166062545503;1123.5112725964232;1125.2404626876826;17408.651826963644;17751.733723993893;18283.634327313644;18469.168887399832;18547.44357669272;42204.78897605492;43298.78350608227;43378.56621433745;43491.347629786214;44034;59364.40635593589;60327.396726033934;62958.685206573675;64218;65091.349086510425;9258.814823703622;9302.069793020644;9319.114684105;9376.109269619375;9446.574904129193;10144.137748291478;13068.150570212905;13694.178349299074;13756.17462952226;13834.654133646336;11760.882391176321;11914.940425297818;11979.622343430618;12058.75882482363;12125.69685757863;11400.144989125683;11414.201005929837;11750.412479376168;11930.821037684615;12131.393430328624;11845.230060046415;12059.638210853689;12203.267803931438;12224.938875305923;12613.74772504563;9058;9101.453912765255;9185.402948808307;9215;9516.85724714116;11135.443227838414;11726.237794543327;11745.1191160665;11981.161318707607;12326.383680816103 06:39:03 INFO - PROCESS | 4250 | |3;mootools.html;1317.7035167087402;1553.4951140879214;1555.4866893925007;1721.561001944504;1838.6598479281326;7956.283934445899;9355.859662105075;9563.665271715483;9577.760555986104;9827.312088153833;9409.858852117224;9477.47873866937;9479.620815167385;9494.857577136348;9496.810063798719;2485.080520207521;2485.5774518331837;2505.436276837715;2513.434477242624;2514.735952724965;2457.447074408262;2540.3522101864;2541.8474891506476;2543.860087695176;2545.0328875027462;2421.1767998880377;2450.0812195426715;2496.87515624219;2501.3496490912403;2501.5997440409537;2380.7262164850918;2381.499885024147;2394.892229849661;2440.3777036855577;2446.498467814094;2430.91491797788;2449.8407603505716;2484.962725559118;2489.514544663791;2492.9376765580823;9539.666111686058;9744.220462362993;9758.268129890286;9775.21798256145;9854.310198286123;5565.526930210937;5691.7723291068305;5700.714964251771;5713.200151978726;5718.59969802114;3562.340966921128;3585.1395665040463;3603.279344131158;3610.4584312353095;3632.5640923089136;1187.554666999875;1188.3464094747853;1200.9459574319176;1247.7878760610627;1248.450681700049;12728.80906786399;12745.617631470977;12755.298458584773;12800.743985120338;12817.295048772314;4731.33761273422;4740.336352910595;4750.918234453414;4816.494268101869;4869.342638743795;10070.295079344376;10093.343932644355;10126.797464050678;10140.391576505433;10164.136048435817;5446.373667028263;5453.3455985281225;5457.072297709399;5486;5489;4687.414073240845;4720.197566413718;4787.970586323957;5130.230465430141;5174.818881339211;4137.77242251679;4168.583141685868;4210.726302790309;4225.345071513921;4245.299525578245;1172.2497601535024;1175.9529618815152;1194.116353898109;1194.569954816283;1205.4033253539553;868.3270465389247;933.8972713001565;995.8796354101636;996.9501524923725;1017.3641474078701;6650.634215118118;6669.7665581704405;6816.863662726718;6846.486513511507;6920.792376228721;497.2292945933788;515.1172464641741;516.2437029751433;516.3416643779211;525.3485677759551;4367.45406824147;4472.798724057425;4563.498015218323;4566.49768525462;4575.153596584642;11495.42522873853;11841.644750657493;11958.282503049844;11979.76040479203;11985.22096063753;11544.249623774427;11810.40947952599;11898.464569094238;11939.940300298616;12083.637490875288;9062.229710474683;9214.078592140731;9261.768455788524;9266.07339266068;9272.907270927339;6371.713272902638;6470.255920569007;6514.902276465951;6537.607743535403;6573.96713016451;10982.780344393228;11117.16621253393;11308.717282067684;11403.315801051964;11443.313401195623;10354.637587684394;11141.498632561716;11364.147688923198;11525.711857203636;11694.47374868116;8989.730308090768;9415.340926135379;9422.811543769223;9473.763155921157;9491.098345657152;9335.439873607605;9502.23982081418;9505.76235594088;9517.143457088896;9518.476483793458;8313.16868313188;8492.532910689972;8508.659653613786;8511.702090426805;8686.78283042929;8005.39959503028;8298.460600060977;8302.252797248277;8492.108328625405;8554.443961142506;7717.536947783151;7736.574488403192;7760.534367937942;7822;7832.725854595062;5400.7569659364635;5669.85825354353;5773.595848290579;5979.461848433488;6144.047672610753;5777.075667893117;5807.709614519342;5809.331926828299;5942.049272116441;5949.524038076857 06:39:03 INFO - PROCESS | 4250 | |4;prototype.html;358.88695061055756;360.58532687409485;362.23206801580653;363.1937099638801;365.92864391443663;5503.779848806048;5521.641093328941;5539.556835453164;5553.139263414172;5555.444455554452;5482.533984611313;5489.862753431166;5498.560115190785;5503.3395992480855;5515.972420137894;4160.980559762862;4237.152569486099;4313.396124542551;4331.501877284121;4336.850734555345;4273.273543497613;4283.742975421469;4289.678274129437;4309.741415515064;4311.1593239318345;4276.166147601219;4278.700490965618;4282.443282373287;4296.63478604319;4311.590398912114;4192.350185721203;4247.193033323659;4250;4280.229558679436;4297.548757380461;3767.472553842437;3779.395296752534;3782.9621703782886;3790.7915064671433;3792.9569368423904;5313.867153321159;5323.627346085776;5330.706811125387;5334.493223143834;5339.5194432500875;4753.643476739224;4921.261810728383;4928.679635823696;4941.751503066096;4961.181405068195;5733.362653179543;5744.195812586241;5748.712564371806;5749.367569567345;5757.971210143923;4158.812853421603;4184.142250838601;4192.4907033467925;4192.895177620553;4197.454330936958;5727.513161381287;5952.226210592596;6007.729652165619;6019.999999999956;6040.788572399989;4293.82824687009;4296.978515107405;4312.698111132253;4314.101795210097;4320.827166913351;4361.825526978949;4362.825486980549;4364.5635436456105;4377.693561450732;4385.386045953569;4842.322074909515;4933.358663373739;4969.2546118081855;4977.900441991213;4983.526564976321;5795;5809.746541936529;5840.707964601753;5849.356570777211;5851.297844258716;1484.3320505772447;1497.5133081748475;1502.4140585171792;1504.473434298009;1506.1113942773598;948.4451595816524;958.4393130019017;963.3545524498462;964.8263312603799;978.3249557805234;4942.5057494251005;4962.6774259673;5011.323471331323;5032.037505372908;5035.420926593489;5214.2960700304975;5229.738513074331;5230.483159883591;5242.1088414969545;5249.530131563169;4752.7880390500895;4975.925361119586;4983.451820299764;4997.425296090923;5007.446202436783;2777.8055536112684;2781.930451738682;2789.391918556116;2837.8864845406365;2844.0472441731836;5381.762194695164;5382.946170538245;5394.757235924393;5410.648307859977;5438.04230477028;5010.874728131826;5181.4300426953005;5310.6542877987395;5324.866878328073;5727.656340619493;6026.186464827279;6127.816165515042;6131.540134489931;6136.815895523141;6146.262448506208;5917.6745279010065;5940.346561878277;5949.494292985145;5963.165156878127;5969.806038792172;5659.943400566106;5881.441263080001;5890.204822348928;5913.881722365442;5933.872564212786;6183.165272688309;6237.75048998035;6242.188515492957;6252.187215661935;6271.3415091414745;4115.279826030493;4331.436913201263;4331.783410829509;4334.133173365402;4342.761148136757;5924.703764811656;5930.169776231245;5946.513371657085;5947.1079338099635;5978.790742323998;5813.82558523245;5817;5834.27071616048;5840.182374467493;5841.4450627190345;5688.744006519633;5742.5405967521665;5774.797882074107;5816.41835816415;5831.7375718093635;5773.16289138081;5781.364049954417;5791.7683292667825;5797.854793726348;5802.82591522255;5624.409437009055;5835.299764028344;5835.591508594358;5839.795607153729;5862.179294898632;5679.801206957736;5717.685527296038;5718.485336319751;5719.3136823581435;5741.3971532988435 06:39:03 INFO - PROCESS | 4250 | |5;yui.html;845.4927043773737;873.6243415331408;876.9956150219248;886.5700135434315;917.8714979902811;16060.839391606081;16500;16615.584610384747;16712.2479488423;16730.916345418256;15277.541673749805;15408.768868466954;15475.071495710237;16371.91814040931;16979.32082716693;881.2685471059037;900.4372267332917;902.0573500691793;903.8237543678986;905.1898550797066;673.7237732529663;849.8682704180831;875.9912400876005;882.1002577371078;899.244634507014;946;946.9621215151386;947.3321308477507;948.83869742144;949.3212353167484;946.0964778636454;946.3895787217275;946.5030858799151;946.6024269806697;946.8343039968013;693.5318659904594;694.2259380790433;694.6630884021246;694.7672529702555;695.4644923408947;15744.212789360487;15749.527514174593;15773.132477713722;15776.132312722795;15780.763288550566;14397.424103035866;14428.278586070655;14457.710845783131;14908.850911490963;14963.775543366859;12892.419841107172;13007.284599347033;13036;13053.347332633426;13430.462781488728;4084.36692312692;4126.339785634284;4127.422160897477;4214.662826973835;4226.197022565702;76883.61558192229;77370.61314693447;77922;78680;78855;11987.160898737178;12118.212316199419;12246.81629775554;12282.631521054383;12491.562795302303;22714.659280110813;22723.659145112837;22730;22763;22776.20283290077;8638.654453821902;8792.736217913473;8803.075677053945;8830.91169088301;9081.863772043425;4660.836870709509;4790.928136077961;5118.283440318359;5176.534111929945;5235.397929238186;3891.7664940103687;4030.6775457963295;4040.8585699500377;4054.2296963576828;4054.2499637567144;618.6906546726636;652.6019128331714;657.5890068707058;667.0860920538893;682.1064405628642;704.2148004974416;802.6628815897337;808.6199486241458;815.6003558256411;844.3329769482041;8542.505061614193;9728.416295022322;9938.45338506389;10782.299150555189;11507;705.562551218248;792.3938187286731;827.6772058897035;840.7435732101768;846.2468403121227;4853.635977301646;5029.471905449948;5534.4742249487035;5775.8556036099435;5885.970570147122;126473.36763316308;128426;133271;133924;136699.31650341683;104457;110658.44670776755;112465.43767281275;115342;117037;97137.51431242799;100555.15857747433;100990.4950475243;102581.48709256406;105617;10864.674059778219;11052.557897684324;11075.224734268846;11237.662870113909;11268.3802390866;50239.49760502348;50491;51011.48988510067;51509.197178098584;52306.47693523168;47599.52400475951;52029.47970520398;52415;77887;83633;13465.259410732504;13498;13874.552007107955;14143.787843182155;15348.693026139192;15501.767473488133;15911.363545458053;16009.599760005627;17198.31206751716;17209.483715488554;15291.311890965157;15414.306356214222;16294.837051629333;16571.08859012766;16726.916365418096;15279.61800954985;15679;15938.442154524537;15951.122688252257;16856.66286674284;9756.804863902824;13854.930725346308;13892.583222503341;15102.697946041237;15182.240887955779;12207.69480762988;12674.302913339854;12680.492780288685;13008.739825203254;13422.530211442552;12414.131010829155;12426.502939882665;12454.24164396378;12672.493100275886;12854.935725321313 06:39:03 INFO - PROCESS | 4250 | __end_tp_report 06:39:03 INFO - PROCESS | 4250 | __start_cc_report 06:39:03 INFO - PROCESS | 4250 | _x_x_mozilla_cycle_collect,955 06:39:03 INFO - PROCESS | 4250 | __end_cc_report 06:39:03 INFO - PROCESS | 4250 | __startTimestamp1477575543833__endTimestamp 06:39:03 INFO - PROCESS | 4250 | 06:39:03 INFO - PROCESS | 4250 | ------- Summary: start ------- 06:39:03 INFO - PROCESS | 4250 | Number of tests: 6 06:39:03 INFO - PROCESS | 4250 | 06:39:03 INFO - PROCESS | 4250 | [#0] dojo.html Cycles:165 Average:5681.68 Median:5943.97 stddev:658.81 (11.1%) stddev-sans-first:632.25 06:39:03 INFO - PROCESS | 4250 | Values: 3234.8 3245.4 3275.7 3298.4 3310.4 5667.6 5753.8 5761.0 5762.7 5770.4 5656.5 5694.0 5710.7 5717.1 5730.6 5115.3 5144.5 5155.2 5203.8 5224.6 5060.2 5095.2 5226.1 5228.1 5258.7 5115.1 5121.3 5153.1 5207.5 5226.5 5214.7 5245.5 5247.6 5248.0 5270.2 5070.8 5083.8 5167.0 5211.4 5230.5 5548.7 5556.7 5593.1 5691.2 5695.2 6041.0 6089.6 6089.7 6090.1 6098.5 6096.2 6115.3 6125.8 6126.5 6131.6 5556.2 5590.9 5621.8 5621.9 5696.1 6186.1 6192.1 6200.9 6204.1 6223.9 5915.5 5952.9 5956.2 5958.3 5974.2 5944.0 6052.2 6060.1 6088.3 6115.4 6062.8 6094.5 6106.9 6113.9 6136.5 6126.2 6132.6 6146.0 6152.6 6152.9 4569.0 4668.1 4706.1 4713.5 4724.1 4163.4 4167.0 4181.0 4181.2 4187.0 5875.1 5876.1 5877.7 5880.2 5890.0 5944.9 6106.4 6115.8 6135.5 6192.3 5920.2 5920.4 5920.4 5920.5 5952.0 5167.4 5180.4 5187.1 5189.3 5196.2 6138.4 6283.6 6319.6 6346.2 6440.3 6204.1 6215.7 6222.8 6228.9 6238.4 6439.3 6454.2 6460.2 6463.7 6465.5 5876.8 5935.1 5947.9 5970.8 6013.6 6108.2 6170.8 6171.3 6178.1 6181.0 6004.5 6005.0 6006.0 6008.7 6051.8 5801.5 5940.8 5952.6 5982.5 6009.8 5996.0 6005.1 6008.8 6019.7 6034.6 6047.2 6051.9 6085.4 6106.6 6108.3 5789.0 5872.4 5919.6 5945.5 6002.6 06:39:03 INFO - PROCESS | 4250 | 06:39:03 INFO - PROCESS | 4250 | [#1] ext.html Cycles:180 Average:61132.52 Median:3780.08 stddev:122742.30 (3247.1%) stddev-sans-first:123002.47 06:39:03 INFO - PROCESS | 4250 | Values: 594.3 636.4 663.4 666.7 683.5 30418.8 46029.0 47134.5 47166.0 47281.8 48137.8 48591.8 48596.3 48629.0 48658.3 8475.3 9309.0 9477.4 9480.7 11071.2 10918.4 11355.1 11365.0 11372.4 11379.4 11357.2 11398.4 11415.0 11431.7 11463.4 11242.6 11351.9 11369.6 11372.8 11379.3 11142.7 11236.9 11241.1 11241.6 11242.3 46014.0 46350.8 46425.0 46471.1 46513.3 3768.1 3792.1 3948.2 3982.4 4154.7 33888.5 34339.1 34339.5 34344.8 34589.5 4668.6 4727.6 4737.7 4760.8 4767.9 244116.0 244395.0 244404.0 244773.0 246428.0 62990.4 63427.0 63520.4 63566.4 63608.0 40118.0 41011.4 41230.0 43140.4 65381.3 18911.1 19164.1 19188.2 19194.2 19209.7 12601.5 12840.0 14185.0 14441.0 14483.8 20709.0 21016.5 21030.8 21091.6 21134.2 7445.9 7476.4 7672.0 7753.7 7811.6 1062.0 1064.5 1086.5 1110.1 1131.8 34576.8 34973.0 35352.8 35494.8 35722.0 1164.9 1182.1 1183.5 1197.5 1210.4 17750.4 17809.1 17885.6 17944.7 18033.6 601082.0 602750.0 605455.0 605835.0 605950.0 60535.0 60822.0 60945.0 60986.0 61122.7 1361.0 1438.8 1457.4 1480.8 1490.5 33202.7 34598.0 35424.0 35560.0 35578.0 53348.2 53871.5 54007.7 54072.7 54163.2 455553.0 461494.0 461749.0 462571.0 464427.0 27272.5 29630.9 30531.3 30604.5 32301.8 46893.5 49972.0 50336.7 50614.5 51329.7 42418.2 42423.4 43380.1 43552.8 44821.1 41307.2 41638.4 42169.8 43002.8 43906.3 42197.4 42761.0 43556.3 44150.6 44167.3 35667.3 36676.6 37659.2 38312.6 38501.6 29490.4 29639.3 29756.0 29952.0 29958.9 06:39:03 INFO - PROCESS | 4250 | 06:39:03 INFO - PROCESS | 4250 | [#2] jquery.html Cycles:180 Average:32276.44 Median:11396.65 stddev:96763.53 (849.1%) stddev-sans-first:97005.41 06:39:03 INFO - PROCESS | 4250 | Values: 422.2 425.1 430.6 436.1 439.2 18796.2 19602.5 20334.8 20424.4 20705.1 17268.2 18934.6 19521.6 19579.5 19640.9 5248.4 5616.0 5620.8 5623.9 5634.9 5459.5 5473.3 5518.9 5557.0 5558.2 5335.7 5462.3 5482.2 5492.9 5503.3 5397.8 5418.2 5459.6 5475.6 5486.9 5344.9 5347.4 5349.4 5351.5 5357.1 13982.5 14011.3 14145.7 14148.2 14379.3 2438.5 2597.1 2642.2 2799.1 2825.4 10565.4 10660.6 10939.5 11030.3 11120.8 1905.3 1955.9 2008.8 2015.6 2061.2 79178.6 83315.2 84608.2 84980.0 86871.0 11646.6 11808.5 11912.5 11985.2 12012.8 16692.7 16698.7 16734.4 16738.0 16751.7 8865.5 8970.3 8982.1 9027.1 9168.7 8531.8 8601.5 8636.5 8721.8 8894.8 5135.9 5149.6 5160.9 5208.1 5233.8 2038.0 2066.3 2088.2 2122.5 2171.8 954.7 1023.7 1026.9 1033.2 1083.7 20036.0 21176.5 21293.0 21295.6 21556.8 968.2 1001.3 1015.8 1041.6 1048.7 10048.2 10435.9 11261.0 11319.0 11578.8 571163.0 572162.0 576277.0 606600.0 612441.0 100334.5 101269.0 102780.5 103718.5 104376.5 1062.4 1103.5 1111.9 1123.5 1125.2 17408.7 17751.7 18283.6 18469.2 18547.4 42204.8 43298.8 43378.6 43491.3 44034.0 59364.4 60327.4 62958.7 64218.0 65091.3 9258.8 9302.1 9319.1 9376.1 9446.6 10144.1 13068.2 13694.2 13756.2 13834.7 11760.9 11914.9 11979.6 12058.8 12125.7 11400.1 11414.2 11750.4 11930.8 12131.4 11845.2 12059.6 12203.3 12224.9 12613.7 9058.0 9101.5 9185.4 9215.0 9516.9 11135.4 11726.2 11745.1 11981.2 12326.4 06:39:03 INFO - PROCESS | 4250 | 06:39:03 INFO - PROCESS | 4250 | [#3] mootools.html Cycles:180 Average:6280.42 Median:4769.44 stddev:3614.54 (75.8%) stddev-sans-first:3605.44 06:39:03 INFO - PROCESS | 4250 | Values: 1317.7 1553.5 1555.5 1721.6 1838.7 7956.3 9355.9 9563.7 9577.8 9827.3 9409.9 9477.5 9479.6 9494.9 9496.8 2485.1 2485.6 2505.4 2513.4 2514.7 2457.4 2540.4 2541.8 2543.9 2545.0 2421.2 2450.1 2496.9 2501.3 2501.6 2380.7 2381.5 2394.9 2440.4 2446.5 2430.9 2449.8 2485.0 2489.5 2492.9 9539.7 9744.2 9758.3 9775.2 9854.3 5565.5 5691.8 5700.7 5713.2 5718.6 3562.3 3585.1 3603.3 3610.5 3632.6 1187.6 1188.3 1200.9 1247.8 1248.5 12728.8 12745.6 12755.3 12800.7 12817.3 4731.3 4740.3 4750.9 4816.5 4869.3 10070.3 10093.3 10126.8 10140.4 10164.1 5446.4 5453.3 5457.1 5486.0 5489.0 4687.4 4720.2 4788.0 5130.2 5174.8 4137.8 4168.6 4210.7 4225.3 4245.3 1172.2 1176.0 1194.1 1194.6 1205.4 868.3 933.9 995.9 997.0 1017.4 6650.6 6669.8 6816.9 6846.5 6920.8 497.2 515.1 516.2 516.3 525.3 4367.5 4472.8 4563.5 4566.5 4575.2 11495.4 11841.6 11958.3 11979.8 11985.2 11544.2 11810.4 11898.5 11939.9 12083.6 9062.2 9214.1 9261.8 9266.1 9272.9 6371.7 6470.3 6514.9 6537.6 6574.0 10982.8 11117.2 11308.7 11403.3 11443.3 10354.6 11141.5 11364.1 11525.7 11694.5 8989.7 9415.3 9422.8 9473.8 9491.1 9335.4 9502.2 9505.8 9517.1 9518.5 8313.2 8492.5 8508.7 8511.7 8686.8 8005.4 8298.5 8302.3 8492.1 8554.4 7717.5 7736.6 7760.5 7822.0 7832.7 5400.8 5669.9 5773.6 5979.5 6144.0 5777.1 5807.7 5809.3 5942.0 5949.5 06:39:03 INFO - PROCESS | 4250 | 06:39:03 INFO - PROCESS | 4250 | [#4] prototype.html Cycles:180 Average:4786.10 Median:5327.79 stddev:1402.74 (26.3%) stddev-sans-first:1366.75 06:39:03 INFO - PROCESS | 4250 | Values: 358.9 360.6 362.2 363.2 365.9 5503.8 5521.6 5539.6 5553.1 5555.4 5482.5 5489.9 5498.6 5503.3 5516.0 4161.0 4237.2 4313.4 4331.5 4336.9 4273.3 4283.7 4289.7 4309.7 4311.2 4276.2 4278.7 4282.4 4296.6 4311.6 4192.4 4247.2 4250.0 4280.2 4297.5 3767.5 3779.4 3783.0 3790.8 3793.0 5313.9 5323.6 5330.7 5334.5 5339.5 4753.6 4921.3 4928.7 4941.8 4961.2 5733.4 5744.2 5748.7 5749.4 5758.0 4158.8 4184.1 4192.5 4192.9 4197.5 5727.5 5952.2 6007.7 6020.0 6040.8 4293.8 4297.0 4312.7 4314.1 4320.8 4361.8 4362.8 4364.6 4377.7 4385.4 4842.3 4933.4 4969.3 4977.9 4983.5 5795.0 5809.7 5840.7 5849.4 5851.3 1484.3 1497.5 1502.4 1504.5 1506.1 948.4 958.4 963.4 964.8 978.3 4942.5 4962.7 5011.3 5032.0 5035.4 5214.3 5229.7 5230.5 5242.1 5249.5 4752.8 4975.9 4983.5 4997.4 5007.4 2777.8 2781.9 2789.4 2837.9 2844.0 5381.8 5382.9 5394.8 5410.6 5438.0 5010.9 5181.4 5310.7 5324.9 5727.7 6026.2 6127.8 6131.5 6136.8 6146.3 5917.7 5940.3 5949.5 5963.2 5969.8 5659.9 5881.4 5890.2 5913.9 5933.9 6183.2 6237.8 6242.2 6252.2 6271.3 4115.3 4331.4 4331.8 4334.1 4342.8 5924.7 5930.2 5946.5 5947.1 5978.8 5813.8 5817.0 5834.3 5840.2 5841.4 5688.7 5742.5 5774.8 5816.4 5831.7 5773.2 5781.4 5791.8 5797.9 5802.8 5624.4 5835.3 5835.6 5839.8 5862.2 5679.8 5717.7 5718.5 5719.3 5741.4 06:39:03 INFO - PROCESS | 4250 | 06:39:03 INFO - PROCESS | 4250 | [#5] yui.html Cycles:180 Average:22366.35 Median:3961.22 stddev:32908.55 (830.8%) stddev-sans-first:32961.19 06:39:03 INFO - PROCESS | 4250 | Values: 845.5 873.6 877.0 886.6 917.9 16060.8 16500.0 16615.6 16712.2 16730.9 15277.5 15408.8 15475.1 16371.9 16979.3 881.3 900.4 902.1 903.8 905.2 673.7 849.9 876.0 882.1 899.2 946.0 947.0 947.3 948.8 949.3 946.1 946.4 946.5 946.6 946.8 693.5 694.2 694.7 694.8 695.5 15744.2 15749.5 15773.1 15776.1 15780.8 14397.4 14428.3 14457.7 14908.9 14963.8 12892.4 13007.3 13036.0 13053.3 13430.5 4084.4 4126.3 4127.4 4214.7 4226.2 76883.6 77370.6 77922.0 78680.0 78855.0 11987.2 12118.2 12246.8 12282.6 12491.6 22714.7 22723.7 22730.0 22763.0 22776.2 8638.7 8792.7 8803.1 8830.9 9081.9 4660.8 4790.9 5118.3 5176.5 5235.4 3891.8 4030.7 4040.9 4054.2 4054.2 618.7 652.6 657.6 667.1 682.1 704.2 802.7 808.6 815.6 844.3 8542.5 9728.4 9938.5 10782.3 11507.0 705.6 792.4 827.7 840.7 846.2 4853.6 5029.5 5534.5 5775.9 5886.0 126473.4 128426.0 133271.0 133924.0 136699.3 104457.0 110658.4 112465.4 115342.0 117037.0 97137.5 100555.2 100990.5 102581.5 105617.0 10864.7 11052.6 11075.2 11237.7 11268.4 50239.5 50491.0 51011.5 51509.2 52306.5 47599.5 52029.5 52415.0 77887.0 83633.0 13465.3 13498.0 13874.6 14143.8 15348.7 15501.8 15911.4 16009.6 17198.3 17209.5 15291.3 15414.3 16294.8 16571.1 16726.9 15279.6 15679.0 15938.4 15951.1 16856.7 9756.8 13854.9 13892.6 15102.7 15182.2 12207.7 12674.3 12680.5 13008.7 13422.5 12414.1 12426.5 12454.2 12672.5 12854.9 06:39:03 INFO - PROCESS | 4250 | -------- Summary: end -------- 06:39:03 INFO - PROCESS | 4250 | 06:39:04 INFO - TEST-INFO | 4250: exit 0 06:39:04 INFO - TEST-OK | dromaeo_css | took 1112032ms 06:39:04 INFO - TEST-START | kraken 06:39:04 INFO - operating with platform_type : linux_ 06:39:04 INFO - Initialising browser for kraken test... 06:39:04 INFO - TEST-INFO | started process 4443 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpYkl4ai/profile http://localhost:46809/getInfo.html) 06:39:08 INFO - PROCESS | 4443 | __metrics Screen width/height:1600/1200 06:39:08 INFO - PROCESS | 4443 | colorDepth:24 06:39:08 INFO - PROCESS | 4443 | Browser inner width/height: 1024/697 06:39:08 INFO - PROCESS | 4443 | __metrics 06:39:08 INFO - PROCESS | 4443 | 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" 06:39:08 INFO - PROCESS | 4443 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 06:39:09 INFO - TEST-INFO | 4443: exit 0 06:39:09 INFO - Browser initialized. 06:39:09 INFO - Running cycle 1/1 for kraken test... 06:39:09 INFO - TEST-INFO | started process 4531 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpYkl4ai/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 06:39:21 INFO - PROCESS | 4531 | RSS: Main: 144359424 06:39:21 INFO - PROCESS | 4531 | 06:39:24 INFO - PROCESS | 4531 | Cycle 1(1): loaded http://localhost:46809/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:46809/tests/kraken/driver.html?testName=audio-beat-detection) 06:39:24 INFO - PROCESS | 4531 | RSS: Main: 199585792 06:39:24 INFO - PROCESS | 4531 | 06:39:27 INFO - PROCESS | 4531 | Cycle 1(1): loaded http://localhost:46809/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:46809/tests/kraken/driver.html?testName=audio-dft) 06:39:28 INFO - PROCESS | 4531 | RSS: Main: 184049664 06:39:28 INFO - PROCESS | 4531 | 06:39:30 INFO - PROCESS | 4531 | Cycle 1(1): loaded http://localhost:46809/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:46809/tests/kraken/driver.html?testName=audio-fft) 06:39:31 INFO - PROCESS | 4531 | RSS: Main: 275632128 06:39:31 INFO - PROCESS | 4531 | 06:39:33 INFO - PROCESS | 4531 | Cycle 1(1): loaded http://localhost:46809/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:46809/tests/kraken/driver.html?testName=audio-oscillator) 06:39:34 INFO - PROCESS | 4531 | RSS: Main: 180252672 06:39:34 INFO - PROCESS | 4531 | 06:39:36 INFO - PROCESS | 4531 | Cycle 1(1): loaded http://localhost:46809/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:46809/tests/kraken/driver.html?testName=imaging-gaussian-blur) 06:39:36 INFO - PROCESS | 4531 | RSS: Main: 179085312 06:39:36 INFO - PROCESS | 4531 | 06:39:39 INFO - PROCESS | 4531 | Cycle 1(1): loaded http://localhost:46809/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:46809/tests/kraken/driver.html?testName=imaging-darkroom) 06:39:40 INFO - PROCESS | 4531 | RSS: Main: 215715840 06:39:40 INFO - PROCESS | 4531 | 06:39:43 INFO - PROCESS | 4531 | Cycle 1(1): loaded http://localhost:46809/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:46809/tests/kraken/driver.html?testName=imaging-desaturate) 06:39:44 INFO - PROCESS | 4531 | RSS: Main: 213950464 06:39:44 INFO - PROCESS | 4531 | 06:39:47 INFO - PROCESS | 4531 | Cycle 1(1): loaded http://localhost:46809/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:46809/tests/kraken/driver.html?testName=json-parse-financial) 06:39:47 INFO - PROCESS | 4531 | RSS: Main: 213999616 06:39:47 INFO - PROCESS | 4531 | 06:39:49 INFO - PROCESS | 4531 | Cycle 1(1): loaded http://localhost:46809/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:46809/tests/kraken/driver.html?testName=json-stringify-tinderbox) 06:39:49 INFO - PROCESS | 4531 | RSS: Main: 176267264 06:39:49 INFO - PROCESS | 4531 | 06:39:51 INFO - PROCESS | 4531 | Cycle 1(1): loaded http://localhost:46809/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:46809/tests/kraken/driver.html?testName=stanford-crypto-aes) 06:39:52 INFO - PROCESS | 4531 | RSS: Main: 183873536 06:39:52 INFO - PROCESS | 4531 | 06:39:54 INFO - PROCESS | 4531 | Cycle 1(1): loaded http://localhost:46809/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:46809/tests/kraken/driver.html?testName=stanford-crypto-ccm) 06:39:54 INFO - PROCESS | 4531 | RSS: Main: 190001152 06:39:54 INFO - PROCESS | 4531 | 06:39:57 INFO - PROCESS | 4531 | Cycle 1(1): loaded http://localhost:46809/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:46809/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 06:39:57 INFO - PROCESS | 4531 | RSS: Main: 197177344 06:39:57 INFO - PROCESS | 4531 | 06:40:00 INFO - PROCESS | 4531 | Cycle 1(1): loaded http://localhost:46809/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:46809/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 06:40:01 INFO - PROCESS | 4531 | RSS: Main: 194629632 06:40:01 INFO - PROCESS | 4531 | 06:40:02 INFO - PROCESS | 4531 | Cycle 1(1): loaded http://localhost:46809/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:46809/tests/kraken/driver.html?testName=ai-astar) 06:40:03 INFO - PROCESS | 4531 | RSS: Main: 243388416 06:40:03 INFO - PROCESS | 4531 | 06:40:03 INFO - PROCESS | 4531 | __start_tp_report 06:40:03 INFO - PROCESS | 4531 | _x_x_mozilla_page_load 06:40:03 INFO - PROCESS | 4531 | _x_x_mozilla_page_load_details 06:40:03 INFO - PROCESS | 4531 | |i|pagename|runs| 06:40:03 INFO - PROCESS | 4531 | |0;ai-astar;112;112;121;108;109;111;109;109;111;111 06:40:03 INFO - PROCESS | 4531 | |1;audio-beat-detection;148;114;154;163;126;145;146;118;175;116 06:40:03 INFO - PROCESS | 4531 | |2;audio-dft;161;161;160;160;160;161;161;159;162;162 06:40:03 INFO - PROCESS | 4531 | |3;audio-fft;74;75;75;177;77;76;77;75;163;75 06:40:03 INFO - PROCESS | 4531 | |4;audio-oscillator;83;84;149;84;83;84;145;84;83;155 06:40:03 INFO - PROCESS | 4531 | |5;imaging-gaussian-blur;116;118;117;116;117;117;116;116;116;116 06:40:03 INFO - PROCESS | 4531 | |6;imaging-darkroom;146;147;146;145;146;146;146;146;147;146 06:40:03 INFO - PROCESS | 4531 | |7;imaging-desaturate;95;95;94;95;95;95;95;94;94;94 06:40:03 INFO - PROCESS | 4531 | |8;json-parse-financial;53;50;50;50;49;53;51;51;49;50 06:40:03 INFO - PROCESS | 4531 | |9;json-stringify-tinderbox;60;61;60;60;62;60;60;60;60;60 06:40:03 INFO - PROCESS | 4531 | |10;stanford-crypto-aes;73;72;73;74;76;73;78;72;73;71 06:40:03 INFO - PROCESS | 4531 | |11;stanford-crypto-ccm;120;131;121;120;126;118;127;117;153;122 06:40:03 INFO - PROCESS | 4531 | |12;stanford-crypto-pbkdf2;153;151;153;154;149;151;155;157;153;151 06:40:03 INFO - PROCESS | 4531 | |13;stanford-crypto-sha256-iterative;61;61;66;65;62;61;61;62;60;61 06:40:03 INFO - PROCESS | 4531 | __end_tp_report 06:40:03 INFO - PROCESS | 4531 | __start_cc_report 06:40:03 INFO - PROCESS | 4531 | _x_x_mozilla_cycle_collect,1698 06:40:03 INFO - PROCESS | 4531 | __end_cc_report 06:40:03 INFO - PROCESS | 4531 | __startTimestamp1477575603027__endTimestamp 06:40:03 INFO - PROCESS | 4531 | 06:40:03 INFO - PROCESS | 4531 | ------- Summary: start ------- 06:40:03 INFO - PROCESS | 4531 | Number of tests: 14 06:40:03 INFO - PROCESS | 4531 | 06:40:03 INFO - PROCESS | 4531 | [#0] ai-astar Cycles:10 Average:111.30 Median:111.00 stddev:3.68 (3.3%) stddev-sans-first:3.90 06:40:03 INFO - PROCESS | 4531 | Values: 112.0 112.0 121.0 108.0 109.0 111.0 109.0 109.0 111.0 111.0 06:40:03 INFO - PROCESS | 4531 | 06:40:03 INFO - PROCESS | 4531 | [#1] audio-beat-detection Cycles:10 Average:140.50 Median:147.00 stddev:21.09 (14.3%) stddev-sans-first:22.20 06:40:03 INFO - PROCESS | 4531 | Values: 148.0 114.0 154.0 163.0 126.0 145.0 146.0 118.0 175.0 116.0 06:40:03 INFO - PROCESS | 4531 | 06:40:03 INFO - PROCESS | 4531 | [#2] audio-dft Cycles:10 Average:160.70 Median:161.00 stddev:0.95 (0.6%) stddev-sans-first:1.00 06:40:03 INFO - PROCESS | 4531 | Values: 161.0 161.0 160.0 160.0 160.0 161.0 161.0 159.0 162.0 162.0 06:40:03 INFO - PROCESS | 4531 | 06:40:03 INFO - PROCESS | 4531 | [#3] audio-fft Cycles:10 Average:94.40 Median:75.00 stddev:39.99 (53.3%) stddev-sans-first:41.73 06:40:03 INFO - PROCESS | 4531 | Values: 74.0 75.0 75.0 177.0 77.0 76.0 77.0 75.0 163.0 75.0 06:40:03 INFO - PROCESS | 4531 | 06:40:03 INFO - PROCESS | 4531 | [#4] audio-oscillator Cycles:10 Average:103.40 Median:83.50 stddev:32.02 (38.3%) stddev-sans-first:33.10 06:40:03 INFO - PROCESS | 4531 | Values: 83.0 84.0 149.0 84.0 83.0 84.0 145.0 84.0 83.0 155.0 06:40:03 INFO - PROCESS | 4531 | 06:40:03 INFO - PROCESS | 4531 | [#5] imaging-gaussian-blur Cycles:10 Average:116.50 Median:116.50 stddev:0.71 (0.6%) stddev-sans-first:0.73 06:40:03 INFO - PROCESS | 4531 | Values: 116.0 118.0 117.0 116.0 117.0 117.0 116.0 116.0 116.0 116.0 06:40:03 INFO - PROCESS | 4531 | 06:40:03 INFO - PROCESS | 4531 | [#6] imaging-darkroom Cycles:10 Average:146.10 Median:146.00 stddev:0.57 (0.4%) stddev-sans-first:0.60 06:40:03 INFO - PROCESS | 4531 | Values: 146.0 147.0 146.0 145.0 146.0 146.0 146.0 146.0 147.0 146.0 06:40:03 INFO - PROCESS | 4531 | 06:40:03 INFO - PROCESS | 4531 | [#7] imaging-desaturate Cycles:10 Average:94.60 Median:95.00 stddev:0.52 (0.5%) stddev-sans-first:0.53 06:40:03 INFO - PROCESS | 4531 | Values: 95.0 95.0 94.0 95.0 95.0 95.0 95.0 94.0 94.0 94.0 06:40:03 INFO - PROCESS | 4531 | 06:40:03 INFO - PROCESS | 4531 | [#8] json-parse-financial Cycles:10 Average:50.60 Median:50.50 stddev:1.43 (2.8%) stddev-sans-first:1.22 06:40:03 INFO - PROCESS | 4531 | Values: 53.0 50.0 50.0 50.0 49.0 53.0 51.0 51.0 49.0 50.0 06:40:03 INFO - PROCESS | 4531 | 06:40:03 INFO - PROCESS | 4531 | [#9] json-stringify-tinderbox Cycles:10 Average:60.30 Median:60.00 stddev:0.67 (1.1%) stddev-sans-first:0.71 06:40:03 INFO - PROCESS | 4531 | Values: 60.0 61.0 60.0 60.0 62.0 60.0 60.0 60.0 60.0 60.0 06:40:03 INFO - PROCESS | 4531 | 06:40:03 INFO - PROCESS | 4531 | [#10] stanford-crypto-aes Cycles:10 Average:73.50 Median:73.00 stddev:2.07 (2.8%) stddev-sans-first:2.19 06:40:03 INFO - PROCESS | 4531 | Values: 73.0 72.0 73.0 74.0 76.0 73.0 78.0 72.0 73.0 71.0 06:40:03 INFO - PROCESS | 4531 | 06:40:03 INFO - PROCESS | 4531 | [#11] stanford-crypto-ccm Cycles:10 Average:125.50 Median:124.00 stddev:10.60 (8.5%) stddev-sans-first:11.05 06:40:03 INFO - PROCESS | 4531 | Values: 120.0 131.0 121.0 120.0 126.0 118.0 127.0 117.0 153.0 122.0 06:40:03 INFO - PROCESS | 4531 | 06:40:03 INFO - PROCESS | 4531 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:152.70 Median:153.00 stddev:2.31 (1.5%) stddev-sans-first:2.45 06:40:03 INFO - PROCESS | 4531 | Values: 153.0 151.0 153.0 154.0 149.0 151.0 155.0 157.0 153.0 151.0 06:40:03 INFO - PROCESS | 4531 | 06:40:03 INFO - PROCESS | 4531 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:62.00 Median:61.50 stddev:1.94 (3.2%) stddev-sans-first:2.03 06:40:03 INFO - PROCESS | 4531 | Values: 61.0 61.0 66.0 65.0 62.0 61.0 61.0 62.0 60.0 61.0 06:40:03 INFO - PROCESS | 4531 | -------- Summary: end -------- 06:40:03 INFO - PROCESS | 4531 | 06:40:03 INFO - TEST-INFO | 4531: exit 0 06:40:03 INFO - TEST-OK | kraken | took 59362ms 06:40:03 INFO - SUITE-END | took 1171s 06:40:04 INFO - Completed test suite (00:19:31) 06:40:04 INFO - javascript benchmark 06:40:04 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3234.7897386669865, 3245.38337715834, 3275.737940964723, 3298.3897978873924, 3310.35448087623, 5667.57493188011, 5753.827385178441, 5761.0, 5762.654240745558, 5770.394108618587, 5656.547476201904, 5694.032014557525, 5710.714464276791, 5717.085266357383, 5730.570207234453, 5115.335006449166, 5144.511271429208, 5155.175171972485, 5203.765830537616, 5224.582033437316, 5060.240963855433, 5095.210242412413, 5226.137687281594, 5228.111221092405, 5258.737063146847, 5115.053715062725, 5121.3342265505435, 5153.123968925292, 5207.453217412174, 5226.529612334889, 5214.66104703195, 5245.501677340666, 5247.580193584504, 5248.0, 5270.183121616155, 5070.847874563769, 5083.79664813407, 5167.044096842096, 5211.42674305826, 5230.476952304777, 5548.667079975174, 5556.749946252428, 5593.105103183471, 5691.174779656926, 5695.231143795618, 6040.96979515104, 6089.573729838913, 6089.703599822355, 6090.086487026937, 6098.481629061491, 6096.237970253718, 6115.296740874767, 6125.785597504066, 6126.540509461764, 6131.570790044699, 5556.16657501374, 5590.888182236373, 5621.831345059655, 5621.887562248732, 5696.11710184922, 6186.133941248228, 6192.071189321638, 6200.875982480416, 6204.13142160098, 6223.875522489525, 5915.497182739429, 5952.9107063394085, 5956.225690660186, 5958.314793798769, 5974.1934838796205, 5943.970280148572, 6052.213212282375, 6060.090986352083, 6088.269407671108, 6115.388461153938, 6062.818115456544, 6094.451499365078, 6106.908396374058, 6113.877722445615, 6136.478399336017, 6126.172966649535, 6132.63204207749, 6145.9692701536205, 6152.569320147547, 6152.8769424611255, 4568.954310456919, 4668.089722504079, 4706.058788242365, 4713.457952335457, 4724.078804633064, 4163.417121602978, 4167.041580436456, 4180.958190418118, 4181.205570941512, 4186.958130418657, 5875.148103524965, 5876.1479585459865, 5877.676727779927, 5880.206172166788, 5890.0, 5944.881102377928, 6106.389361063947, 6115.755369785248, 6135.509159267248, 6192.318844927055, 5920.2303700519515, 5920.407959204045, 5920.437558431942, 5920.526357891358, 5951.9702401488585, 5167.379914410295, 5180.378354597397, 5187.0922588546855, 5189.273501709764, 5196.194589838581, 6138.419052470195, 6283.622982621057, 6319.589226700342, 6346.2384513857705, 6440.25937017249, 6204.069389591597, 6215.658138802229, 6222.844428889314, 6228.875422491616, 6238.376162383725, 6439.291677915519, 6454.225492940689, 6460.160179176677, 6463.709133089116, 6465.482761379172, 5876.794312199224, 5935.139404786368, 5947.851303717442, 5970.820875373745, 6013.579049466671, 6108.236470441195, 6170.845728856814, 6171.321154672893, 6178.135061091541, 6181.04193849936, 6004.489618382312, 6005.0, 6006.0, 6008.699565021819, 6051.788187413419, 5801.535877129904, 5940.762369505172, 5952.553558483044, 5982.46157845796, 6009.789657361971, 5996.040633498794, 6005.129256257906, 6008.8497787553915, 6019.699015049143, 6034.60775049638, 6047.183630209865, 6051.909221361595, 6085.361037091042, 6106.603070800384, 6108.297545782292, 5789.0, 5872.412758724094, 5919.6152250103605, 5945.494632956247, 6002.609830361012], "value": 5636.1435622951985, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [594.3076316091754, 636.3986033198628, 663.3929954092005, 666.7233098264221, 683.5488577538825, 30418.847905760467, 46029.0, 47134.528654713446, 47166.0, 47281.763591181996, 48137.759311203394, 48591.75704121475, 48596.2710559341, 48629.0, 48658.27012594805, 8475.321974242046, 9309.0, 9477.43135411874, 9480.715578532621, 11071.225014249007, 10918.399488028155, 11355.091592672567, 11364.977152056314, 11372.37451940143, 11379.374134422604, 11357.204995650307, 11398.430078496083, 11415.0297224736, 11431.742508324078, 11463.426828658576, 11242.550297988071, 11351.886481135165, 11369.602063927805, 11372.829407558893, 11379.317240965485, 11142.721431964266, 11236.887631123665, 11241.100711943025, 11241.606543771011, 11242.325460472397, 46014.0, 46350.76824615889, 46425.0, 46471.07057858858, 46513.30230046552, 3768.09565704229, 3792.0709426190483, 3948.190620922704, 3982.38273067675, 4154.729942553724, 33888.49167262493, 34339.14152146191, 34339.4849077264, 34344.82827585846, 34589.481157782655, 4668.649851261169, 4727.57451829337, 4737.692050016738, 4760.809567617292, 4767.928481072787, 244116.0, 244395.0, 244404.0, 244773.0, 246428.0, 62990.37009629937, 63427.0, 63520.36479635237, 63566.36433635605, 63608.0, 40118.0, 41011.38482922759, 41230.0, 43140.35289470661, 65381.346186538474, 18911.14899829511, 19164.137613807412, 19188.232470701296, 19194.232230710895, 19209.711854322195, 12601.495940162291, 12840.0, 14185.0, 14441.0, 14483.78274325865, 20709.0, 21016.474588135417, 21030.789692102884, 21091.578168436547, 21134.154633814476, 7445.8883116753295, 7476.401887848959, 7671.966928763882, 7753.689852405953, 7811.617456675768, 1061.9681409557725, 1064.494365176535, 1086.4947799273218, 1110.1063643766684, 1131.8302254661703, 34576.82711586426, 34973.0, 35352.82323588417, 35494.822525887204, 35722.0, 1164.941752912351, 1182.0779791762438, 1183.526589364244, 1197.461142485885, 1210.3524614331466, 17750.37873674415, 17809.10954452298, 17885.552861178574, 17944.730829037573, 18033.639327213383, 601082.0, 602750.0, 605455.0, 605835.0, 605950.0, 60535.0, 60822.0, 60945.0, 60986.0, 61122.694386528674, 1361.0, 1438.8057612222426, 1457.3951809998944, 1480.8148981377328, 1490.5379332407206, 33202.667973320924, 34598.0, 35424.0, 35560.0, 35578.0, 53348.1997770026, 53871.46128538664, 54007.72996134994, 54072.72963635156, 54163.18755218596, 455553.0, 461494.0, 461749.0, 462571.0, 464427.0, 27272.454550909268, 29630.85184574063, 30531.320777357458, 30604.540931886484, 32301.838490807397, 46893.53106469028, 49972.0, 50336.748316258185, 50614.49385506098, 51329.743351283, 42418.151636967705, 42423.3636495459, 43380.13239735251, 43552.78223608862, 44821.10357792891, 41307.1738565221, 41638.37542436805, 42169.78915105405, 43002.78498607487, 43906.34140487959, 42197.367039495046, 42761.0, 43556.34665480084, 44150.55849441465, 44167.33748993703, 35667.28665426625, 36676.63323366732, 37659.246815062994, 38312.616873830906, 38501.6149838498, 29490.410191796473, 29639.25901852471, 29756.0, 29952.0, 29958.850205748833], "value": 21520.470197978106, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [422.1500109587475, 425.1390933359944, 430.63396113303696, 436.1473319660064, 439.1590104949025, 18796.154173062227, 19602.509937251558, 20334.796652033474, 20424.38726838197, 20705.06827192776, 17268.22292996815, 18934.62130757384, 19521.609567808635, 19579.5105122372, 19640.901795491038, 5248.422673505921, 5616.0171969905105, 5620.775168993236, 5623.9437605623825, 5634.887302253953, 5459.508644222019, 5473.261109750172, 5518.944810551883, 5556.972215138939, 5558.166275058753, 5335.67985920846, 5462.262594549745, 5482.205080263359, 5492.945070549284, 5503.3395992480955, 5397.8380648580405, 5418.214358917974, 5459.563234941195, 5475.561955043607, 5486.890262194774, 5344.866378340534, 5347.385050719178, 5349.384820745586, 5351.464853514617, 5357.062514060024, 13982.510612128526, 14011.299435028208, 14145.717085658229, 14148.151110933273, 14379.281035948161, 2438.4635380216323, 2597.0520759922683, 2642.2337522118564, 2799.0623141247706, 2825.3925406037597, 10565.41890196039, 10660.62687805931, 10939.507722152443, 11030.338179709242, 11120.777584448346, 1905.3331334033116, 1955.882647041182, 2008.789077146893, 2015.647261729206, 2061.2064355223347, 79178.60410697968, 83315.16684833134, 84608.15391846064, 84980.0, 86871.0, 11646.592369267204, 11808.46861891217, 11912.52349905994, 11985.22096063753, 12012.819807702892, 16692.666146677242, 16698.749518757228, 16734.4142954996, 16738.0, 16751.6649667006, 8865.512396818236, 8970.327225458117, 8982.101789821096, 9027.14242146995, 9168.72493825173, 8531.829363412697, 8601.483910965362, 8636.524991125549, 8721.781955451037, 8894.777630559158, 5135.897282054338, 5149.562287205555, 5160.92258616121, 5208.140656791587, 5233.822389962228, 2037.9796202038087, 2066.265749431688, 2088.2273558783045, 2122.543653114588, 2171.83711221659, 954.6610953111684, 1023.6621914768109, 1026.9486525673688, 1033.1682995188944, 1083.6640641401034, 20036.0, 21176.470588235417, 21293.0, 21295.574088518144, 21556.784432155793, 968.2060710217554, 1001.2840818814437, 1015.8171529124828, 1041.5937784264142, 1048.6644273832453, 10048.196144308438, 10435.947820261003, 11260.99180474961, 11319.0, 11578.76842463146, 571163.0, 572162.0, 576277.0, 606600.0, 612441.0, 100334.49832750935, 101269.0, 102780.48609757052, 103718.481407594, 104376.47811761044, 1062.3838173859144, 1103.5254840418536, 1111.9166062545503, 1123.5112725964232, 1125.2404626876826, 17408.651826963644, 17751.733723993893, 18283.634327313644, 18469.168887399832, 18547.44357669272, 42204.78897605492, 43298.78350608227, 43378.56621433745, 43491.347629786214, 44034.0, 59364.40635593589, 60327.396726033934, 62958.685206573675, 64218.0, 65091.349086510425, 9258.814823703622, 9302.069793020644, 9319.114684105, 9376.109269619375, 9446.574904129193, 10144.137748291478, 13068.150570212905, 13694.178349299074, 13756.17462952226, 13834.654133646336, 11760.882391176321, 11914.940425297818, 11979.622343430618, 12058.75882482363, 12125.69685757863, 11400.144989125683, 11414.201005929837, 11750.412479376168, 11930.821037684615, 12131.393430328624, 11845.230060046415, 12059.638210853689, 12203.267803931438, 12224.938875305923, 12613.74772504563, 9058.0, 9101.453912765255, 9185.402948808307, 9215.0, 9516.85724714116, 11135.443227838414, 11726.237794543327, 11745.1191160665, 11981.161318707607, 12326.383680816103], "value": 9503.18674740851, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1317.7035167087402, 1553.4951140879214, 1555.4866893925007, 1721.561001944504, 1838.6598479281326, 7956.283934445899, 9355.859662105075, 9563.665271715483, 9577.760555986104, 9827.312088153833, 9409.858852117224, 9477.47873866937, 9479.620815167385, 9494.857577136348, 9496.810063798719, 2485.080520207521, 2485.5774518331837, 2505.436276837715, 2513.434477242624, 2514.735952724965, 2457.447074408262, 2540.3522101864, 2541.8474891506476, 2543.860087695176, 2545.0328875027462, 2421.1767998880377, 2450.0812195426715, 2496.87515624219, 2501.3496490912403, 2501.5997440409537, 2380.7262164850918, 2381.499885024147, 2394.892229849661, 2440.3777036855577, 2446.498467814094, 2430.91491797788, 2449.8407603505716, 2484.962725559118, 2489.514544663791, 2492.9376765580823, 9539.666111686058, 9744.220462362993, 9758.268129890286, 9775.21798256145, 9854.310198286123, 5565.526930210937, 5691.7723291068305, 5700.714964251771, 5713.200151978726, 5718.59969802114, 3562.340966921128, 3585.1395665040463, 3603.279344131158, 3610.4584312353095, 3632.5640923089136, 1187.554666999875, 1188.3464094747853, 1200.9459574319176, 1247.7878760610627, 1248.450681700049, 12728.80906786399, 12745.617631470977, 12755.298458584773, 12800.743985120338, 12817.295048772314, 4731.33761273422, 4740.336352910595, 4750.918234453414, 4816.494268101869, 4869.342638743795, 10070.295079344376, 10093.343932644355, 10126.797464050678, 10140.391576505433, 10164.136048435817, 5446.373667028263, 5453.3455985281225, 5457.072297709399, 5486.0, 5489.0, 4687.414073240845, 4720.197566413718, 4787.970586323957, 5130.230465430141, 5174.818881339211, 4137.77242251679, 4168.583141685868, 4210.726302790309, 4225.345071513921, 4245.299525578245, 1172.2497601535024, 1175.9529618815152, 1194.116353898109, 1194.569954816283, 1205.4033253539553, 868.3270465389247, 933.8972713001565, 995.8796354101636, 996.9501524923725, 1017.3641474078701, 6650.634215118118, 6669.7665581704405, 6816.863662726718, 6846.486513511507, 6920.792376228721, 497.2292945933788, 515.1172464641741, 516.2437029751433, 516.3416643779211, 525.3485677759551, 4367.45406824147, 4472.798724057425, 4563.498015218323, 4566.49768525462, 4575.153596584642, 11495.42522873853, 11841.644750657493, 11958.282503049844, 11979.76040479203, 11985.22096063753, 11544.249623774427, 11810.40947952599, 11898.464569094238, 11939.940300298616, 12083.637490875288, 9062.229710474683, 9214.078592140731, 9261.768455788524, 9266.07339266068, 9272.907270927339, 6371.713272902638, 6470.255920569007, 6514.902276465951, 6537.607743535403, 6573.96713016451, 10982.780344393228, 11117.16621253393, 11308.717282067684, 11403.315801051964, 11443.313401195623, 10354.637587684394, 11141.498632561716, 11364.147688923198, 11525.711857203636, 11694.47374868116, 8989.730308090768, 9415.340926135379, 9422.811543769223, 9473.763155921157, 9491.098345657152, 9335.439873607605, 9502.23982081418, 9505.76235594088, 9517.143457088896, 9518.476483793458, 8313.16868313188, 8492.532910689972, 8508.659653613786, 8511.702090426805, 8686.78283042929, 8005.39959503028, 8298.460600060977, 8302.252797248277, 8492.108328625405, 8554.443961142506, 7717.536947783151, 7736.574488403192, 7760.534367937942, 7822.0, 7832.725854595062, 5400.7569659364635, 5669.85825354353, 5773.595848290579, 5979.461848433488, 6144.047672610753, 5777.075667893117, 5807.709614519342, 5809.331926828299, 5942.049272116441, 5949.524038076857], "value": 4898.069943699171, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [358.88695061055756, 360.58532687409485, 362.23206801580653, 363.1937099638801, 365.92864391443663, 5503.779848806048, 5521.641093328941, 5539.556835453164, 5553.139263414172, 5555.444455554452, 5482.533984611313, 5489.862753431166, 5498.560115190785, 5503.3395992480855, 5515.972420137894, 4160.980559762862, 4237.152569486099, 4313.396124542551, 4331.501877284121, 4336.850734555345, 4273.273543497613, 4283.742975421469, 4289.678274129437, 4309.741415515064, 4311.1593239318345, 4276.166147601219, 4278.700490965618, 4282.443282373287, 4296.63478604319, 4311.590398912114, 4192.350185721203, 4247.193033323659, 4250.0, 4280.229558679436, 4297.548757380461, 3767.472553842437, 3779.395296752534, 3782.9621703782886, 3790.7915064671433, 3792.9569368423904, 5313.867153321159, 5323.627346085776, 5330.706811125387, 5334.493223143834, 5339.5194432500875, 4753.643476739224, 4921.261810728383, 4928.679635823696, 4941.751503066096, 4961.181405068195, 5733.362653179543, 5744.195812586241, 5748.712564371806, 5749.367569567345, 5757.971210143923, 4158.812853421603, 4184.142250838601, 4192.4907033467925, 4192.895177620553, 4197.454330936958, 5727.513161381287, 5952.226210592596, 6007.729652165619, 6019.999999999956, 6040.788572399989, 4293.82824687009, 4296.978515107405, 4312.698111132253, 4314.101795210097, 4320.827166913351, 4361.825526978949, 4362.825486980549, 4364.5635436456105, 4377.693561450732, 4385.386045953569, 4842.322074909515, 4933.358663373739, 4969.2546118081855, 4977.900441991213, 4983.526564976321, 5795.0, 5809.746541936529, 5840.707964601753, 5849.356570777211, 5851.297844258716, 1484.3320505772447, 1497.5133081748475, 1502.4140585171792, 1504.473434298009, 1506.1113942773598, 948.4451595816524, 958.4393130019017, 963.3545524498462, 964.8263312603799, 978.3249557805234, 4942.5057494251005, 4962.6774259673, 5011.323471331323, 5032.037505372908, 5035.420926593489, 5214.2960700304975, 5229.738513074331, 5230.483159883591, 5242.1088414969545, 5249.530131563169, 4752.7880390500895, 4975.925361119586, 4983.451820299764, 4997.425296090923, 5007.446202436783, 2777.8055536112684, 2781.930451738682, 2789.391918556116, 2837.8864845406365, 2844.0472441731836, 5381.762194695164, 5382.946170538245, 5394.757235924393, 5410.648307859977, 5438.04230477028, 5010.874728131826, 5181.4300426953005, 5310.6542877987395, 5324.866878328073, 5727.656340619493, 6026.186464827279, 6127.816165515042, 6131.540134489931, 6136.815895523141, 6146.262448506208, 5917.6745279010065, 5940.346561878277, 5949.494292985145, 5963.165156878127, 5969.806038792172, 5659.943400566106, 5881.441263080001, 5890.204822348928, 5913.881722365442, 5933.872564212786, 6183.165272688309, 6237.75048998035, 6242.188515492957, 6252.187215661935, 6271.3415091414745, 4115.279826030493, 4331.436913201263, 4331.783410829509, 4334.133173365402, 4342.761148136757, 5924.703764811656, 5930.169776231245, 5946.513371657085, 5947.1079338099635, 5978.790742323998, 5813.82558523245, 5817.0, 5834.27071616048, 5840.182374467493, 5841.4450627190345, 5688.744006519633, 5742.5405967521665, 5774.797882074107, 5816.41835816415, 5831.7375718093635, 5773.16289138081, 5781.364049954417, 5791.7683292667825, 5797.854793726348, 5802.82591522255, 5624.409437009055, 5835.299764028344, 5835.591508594358, 5839.795607153729, 5862.179294898632, 5679.801206957736, 5717.685527296038, 5718.485336319751, 5719.3136823581435, 5741.3971532988435], "value": 4345.51448430901, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [845.4927043773737, 873.6243415331408, 876.9956150219248, 886.5700135434315, 917.8714979902811, 16060.839391606081, 16500.0, 16615.584610384747, 16712.2479488423, 16730.916345418256, 15277.541673749805, 15408.768868466954, 15475.071495710237, 16371.91814040931, 16979.32082716693, 881.2685471059037, 900.4372267332917, 902.0573500691793, 903.8237543678986, 905.1898550797066, 673.7237732529663, 849.8682704180831, 875.9912400876005, 882.1002577371078, 899.244634507014, 946.0, 946.9621215151386, 947.3321308477507, 948.83869742144, 949.3212353167484, 946.0964778636454, 946.3895787217275, 946.5030858799151, 946.6024269806697, 946.8343039968013, 693.5318659904594, 694.2259380790433, 694.6630884021246, 694.7672529702555, 695.4644923408947, 15744.212789360487, 15749.527514174593, 15773.132477713722, 15776.132312722795, 15780.763288550566, 14397.424103035866, 14428.278586070655, 14457.710845783131, 14908.850911490963, 14963.775543366859, 12892.419841107172, 13007.284599347033, 13036.0, 13053.347332633426, 13430.462781488728, 4084.36692312692, 4126.339785634284, 4127.422160897477, 4214.662826973835, 4226.197022565702, 76883.61558192229, 77370.61314693447, 77922.0, 78680.0, 78855.0, 11987.160898737178, 12118.212316199419, 12246.81629775554, 12282.631521054383, 12491.562795302303, 22714.659280110813, 22723.659145112837, 22730.0, 22763.0, 22776.20283290077, 8638.654453821902, 8792.736217913473, 8803.075677053945, 8830.91169088301, 9081.863772043425, 4660.836870709509, 4790.928136077961, 5118.283440318359, 5176.534111929945, 5235.397929238186, 3891.7664940103687, 4030.6775457963295, 4040.8585699500377, 4054.2296963576828, 4054.2499637567144, 618.6906546726636, 652.6019128331714, 657.5890068707058, 667.0860920538893, 682.1064405628642, 704.2148004974416, 802.6628815897337, 808.6199486241458, 815.6003558256411, 844.3329769482041, 8542.505061614193, 9728.416295022322, 9938.45338506389, 10782.299150555189, 11507.0, 705.562551218248, 792.3938187286731, 827.6772058897035, 840.7435732101768, 846.2468403121227, 4853.635977301646, 5029.471905449948, 5534.4742249487035, 5775.8556036099435, 5885.970570147122, 126473.36763316308, 128426.0, 133271.0, 133924.0, 136699.31650341683, 104457.0, 110658.44670776755, 112465.43767281275, 115342.0, 117037.0, 97137.51431242799, 100555.15857747433, 100990.4950475243, 102581.48709256406, 105617.0, 10864.674059778219, 11052.557897684324, 11075.224734268846, 11237.662870113909, 11268.3802390866, 50239.49760502348, 50491.0, 51011.48988510067, 51509.197178098584, 52306.47693523168, 47599.52400475951, 52029.47970520398, 52415.0, 77887.0, 83633.0, 13465.259410732504, 13498.0, 13874.552007107955, 14143.787843182155, 15348.693026139192, 15501.767473488133, 15911.363545458053, 16009.599760005627, 17198.31206751716, 17209.483715488554, 15291.311890965157, 15414.306356214222, 16294.837051629333, 16571.08859012766, 16726.916365418096, 15279.61800954985, 15679.0, 15938.442154524537, 15951.122688252257, 16856.66286674284, 9756.804863902824, 13854.930725346308, 13892.583222503341, 15102.697946041237, 15182.240887955779, 12207.69480762988, 12674.302913339854, 12680.492780288685, 13008.739825203254, 13422.530211442552, 12414.131010829155, 12426.502939882665, 12454.24164396378, 12672.493100275886, 12854.935725321313], "value": 8209.367625723196, "unit": "score"}], "name": "dromaeo_css", "value": 7656.327601825409, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 112.0, 121.0, 108.0, 109.0, 111.0, 109.0, 109.0, 111.0, 111.0], "value": 111.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 114.0, 154.0, 163.0, 126.0, 145.0, 146.0, 118.0, 175.0, 116.0], "value": 140.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 161.0, 160.0, 160.0, 160.0, 161.0, 161.0, 159.0, 162.0, 162.0], "value": 160.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 75.0, 75.0, 177.0, 77.0, 76.0, 77.0, 75.0, 163.0, 75.0], "value": 94.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 84.0, 149.0, 84.0, 83.0, 84.0, 145.0, 84.0, 83.0, 155.0], "value": 103.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 118.0, 117.0, 116.0, 117.0, 117.0, 116.0, 116.0, 116.0, 116.0], "value": 116.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 147.0, 146.0, 145.0, 146.0, 146.0, 146.0, 146.0, 147.0, 146.0], "value": 146.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 94.0, 95.0, 95.0, 95.0, 95.0, 94.0, 94.0, 94.0], "value": 94.6, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [53.0, 50.0, 50.0, 50.0, 49.0, 53.0, 51.0, 51.0, 49.0, 50.0], "value": 50.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 61.0, 60.0, 60.0, 62.0, 60.0, 60.0, 60.0, 60.0, 60.0], "value": 60.3, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 72.0, 73.0, 74.0, 76.0, 73.0, 78.0, 72.0, 73.0, 71.0], "value": 73.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [120.0, 131.0, 121.0, 120.0, 126.0, 118.0, 127.0, 117.0, 153.0, 122.0], "value": 125.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [153.0, 151.0, 153.0, 154.0, 149.0, 151.0, 155.0, 157.0, 153.0, 151.0], "value": 152.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 61.0, 66.0, 65.0, 62.0, 61.0, 61.0, 62.0, 60.0, 61.0], "value": 62.0, "unit": "score"}], "name": "kraken", "value": 1492.1000000000001, "alertThreshold": 2.0}]} 06:40:04 INFO - Return code: 0 06:40:04 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 06:40:04 INFO - # TBPL SUCCESS # 06:40:04 INFO - Running post-action listener: _resource_record_post_action 06:40:04 INFO - [mozharness: 2016-10-27 13:40:04.334530Z] Finished run-tests step (success) 06:40:04 INFO - Running post-run listener: _resource_record_post_run 06:40:04 INFO - Total resource usage - Wall time: 1186s; CPU: 13.0%; Read bytes: 0; Write bytes: 291827712; Read time: 0; Write time: 205268 06:40:04 INFO - TinderboxPrint: CPU usage
12.9% 06:40:04 INFO - TinderboxPrint: I/O read bytes / time
0 / 0 06:40:04 INFO - TinderboxPrint: I/O write bytes / time
291,827,712 / 205,268 06:40:04 INFO - TinderboxPrint: CPU idle
8,242.7 (87.1%) 06:40:04 INFO - TinderboxPrint: CPU user
1,182.1 (12.5%) 06:40:04 INFO - TinderboxPrint: Swap in / out
0 / 0 06:40:04 INFO - install - Wall time: 14s; CPU: 13.0%; Read bytes: 0; Write bytes: 71340032; Read time: 0; Write time: 36552 06:40:04 INFO - run-tests - Wall time: 1172s; CPU: 13.0%; Read bytes: 0; Write bytes: 220487680; Read time: 0; Write time: 168716 06:40:04 INFO - Running post-run listener: _upload_blobber_files 06:40:04 INFO - Blob upload gear active. 06:40:04 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 06:40:04 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 06:40:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Beta', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 06:40:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Beta -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 06:40:05 INFO - (blobuploader) - INFO - Open directory for files ... 06:40:05 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 06:40:05 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:40:05 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:40:06 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 06:40:06 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:40:06 INFO - (blobuploader) - INFO - Done attempting. 06:40:06 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 06:40:06 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:40:06 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:40:06 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 06:40:06 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:40:06 INFO - (blobuploader) - INFO - Done attempting. 06:40:06 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 06:40:06 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:40:06 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:40:10 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 06:40:10 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:40:10 INFO - (blobuploader) - INFO - Done attempting. 06:40:10 INFO - (blobuploader) - INFO - Iteration through files over. 06:40:10 INFO - Return code: 0 06:40:10 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 06:40:10 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 06:40:10 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/312ccb856d2a1b01efd679a99e10c41f4c33bf728c00739bb2735eaea4a4d9637cfd58c7e4c1246240d9c757950e62b01a75475f95bd3d8bbc4c8939d7ffa840", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/ef7ae0f7f55eef91f52f140ced02feca0015177b0a19e544f40ab4a94d940e2017fc5d0c773af536f779a65db3a46e7778bd666bf85002d93d6fa152bedce5ee", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/076d27da32aa5ab1e7d89384d4a6f283be758518300d3e43ce22b864a5d3fd070e7c3ce696c4720b3c111bf6cf394f61a076de2b3a0e477e6e8281004ca0bd88"} 06:40:10 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 06:40:10 INFO - Writing to file /builds/slave/test/properties/blobber_files 06:40:10 INFO - Contents: 06:40:10 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/312ccb856d2a1b01efd679a99e10c41f4c33bf728c00739bb2735eaea4a4d9637cfd58c7e4c1246240d9c757950e62b01a75475f95bd3d8bbc4c8939d7ffa840", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/ef7ae0f7f55eef91f52f140ced02feca0015177b0a19e544f40ab4a94d940e2017fc5d0c773af536f779a65db3a46e7778bd666bf85002d93d6fa152bedce5ee", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/076d27da32aa5ab1e7d89384d4a6f283be758518300d3e43ce22b864a5d3fd070e7c3ce696c4720b3c111bf6cf394f61a076de2b3a0e477e6e8281004ca0bd88"} 06:40:10 INFO - Running post-run listener: copy_logs_to_upload_dir 06:40:10 INFO - Copying logs to upload dir... 06:40:10 INFO - mkdir: /builds/slave/test/build/upload/logs 06:40:10 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1673.394340 ========= master_lag: 0.00 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 27 mins, 53 secs) (at 2016-10-27 06:40:09.577442) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-27 06:40:09.580989) ========= 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=6d15e502d8e29d5d2676d664000001c3-1477571525.235682-683999593 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/312ccb856d2a1b01efd679a99e10c41f4c33bf728c00739bb2735eaea4a4d9637cfd58c7e4c1246240d9c757950e62b01a75475f95bd3d8bbc4c8939d7ffa840", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/ef7ae0f7f55eef91f52f140ced02feca0015177b0a19e544f40ab4a94d940e2017fc5d0c773af536f779a65db3a46e7778bd666bf85002d93d6fa152bedce5ee", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/076d27da32aa5ab1e7d89384d4a6f283be758518300d3e43ce22b864a5d3fd070e7c3ce696c4720b3c111bf6cf394f61a076de2b3a0e477e6e8281004ca0bd88"} build_url:https://queue.taskcluster.net/v1/task/NUMy7VlYR3WKCZ7HISXUUw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011231 build_url: 'https://queue.taskcluster.net/v1/task/NUMy7VlYR3WKCZ7HISXUUw/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/312ccb856d2a1b01efd679a99e10c41f4c33bf728c00739bb2735eaea4a4d9637cfd58c7e4c1246240d9c757950e62b01a75475f95bd3d8bbc4c8939d7ffa840", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/ef7ae0f7f55eef91f52f140ced02feca0015177b0a19e544f40ab4a94d940e2017fc5d0c773af536f779a65db3a46e7778bd666bf85002d93d6fa152bedce5ee", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/076d27da32aa5ab1e7d89384d4a6f283be758518300d3e43ce22b864a5d3fd070e7c3ce696c4720b3c111bf6cf394f61a076de2b3a0e477e6e8281004ca0bd88"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-27 06:40:09.608637) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-27 06:40:09.609047) ========= 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=6d15e502d8e29d5d2676d664000001c3-1477571525.235682-683999593 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004409 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-27 06:40:09.694572) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-27 06:40:09.695074) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-27 06:40:09.695496) ========= ========= Total master_lag: 0.16 =========