builder: mozilla-beta_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-069 starttime: 1476387131.31 results: success (0) buildid: 20161013104124 builduid: ee62ee9dbfe8468395e1f8db01ae1b01 revision: 2a05cc73a28ad6b6ce4e4f87b83a9cc3c0a94396 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-10-13 12:32:11.313152) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-10-13 12:32:11.313596) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-13 12:32:11.313884) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-13 12:32:11.328159) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-13 12:32:11.328415) ========= 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=05b19d94dfa9f3ce7b6e62cc000001f7-1476387132.106355-1991483113 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-10-13 12:32:12-- 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% 455K=0.03s 2016-10-13 12:32:13 (455 KB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.136693 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-13 12:32:11.478442) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-13 12:32:11.478756) ========= 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=05b19d94dfa9f3ce7b6e62cc000001f7-1476387132.106355-1991483113 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.016888 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-13 12:32:11.527024) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-10-13 12:32:11.527326) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 2a05cc73a28ad6b6ce4e4f87b83a9cc3c0a94396 --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 2a05cc73a28ad6b6ce4e4f87b83a9cc3c0a94396 --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=05b19d94dfa9f3ce7b6e62cc000001f7-1476387132.106355-1991483113 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-10-13 12:32:13,221 truncating revision to first 12 chars 2016-10-13 12:32:13,221 Setting DEBUG logging. 2016-10-13 12:32:13,221 attempt 1/10 2016-10-13 12:32:13,221 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/2a05cc73a28a?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-10-13 12:32:14,401 unpacking tar archive at: mozilla-beta-2a05cc73a28a/testing/mozharness/ program finished with exit code 0 elapsedTime=1.519096 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-10-13 12:32:13.059420) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-13 12:32:13.059753) ========= script_repo_revision: 2a05cc73a28ad6b6ce4e4f87b83a9cc3c0a94396 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-13 12:32:13.060161) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-13 12:32:13.060425) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-13 12:32:13.069943) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 27 mins, 58 secs) (at 2016-10-13 12:32:13.070207) ========= /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=05b19d94dfa9f3ce7b6e62cc000001f7-1476387132.106355-1991483113 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 12:32:14 INFO - MultiFileLogger online at 20161013 12:32:14 in /builds/slave/test 12:32:14 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 12:32:14 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 12:32:14 INFO - {'append_to_log': False, 12:32:14 INFO - 'base_work_dir': '/builds/slave/test', 12:32:14 INFO - 'blob_upload_branch': 'Mozilla-Beta', 12:32:14 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 12:32:14 INFO - 'branch': 'Mozilla-Beta', 12:32:14 INFO - 'buildbot_json_path': 'buildprops.json', 12:32:14 INFO - 'config_files': ('talos/linux_config.py',), 12:32:14 INFO - 'default_actions': ('clobber', 12:32:14 INFO - 'read-buildbot-config', 12:32:14 INFO - 'download-and-extract', 12:32:14 INFO - 'populate-webroot', 12:32:14 INFO - 'create-virtualenv', 12:32:14 INFO - 'install', 12:32:14 INFO - 'run-tests'), 12:32:14 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 12:32:14 INFO - 'download_minidump_stackwalk': True, 12:32:14 INFO - 'download_symbols': 'ondemand', 12:32:14 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 12:32:14 INFO - 'tooltool.py': '/tools/tooltool.py', 12:32:14 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 12:32:14 INFO - '/tools/misc-python/virtualenv.py')}, 12:32:14 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 12:32:14 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 12:32:14 INFO - 'installer_path': 'installer.exe', 12:32:14 INFO - 'log_level': 'info', 12:32:14 INFO - 'log_name': 'talos', 12:32:14 INFO - 'log_to_console': True, 12:32:14 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 12:32:14 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 12:32:14 INFO - 'opt_config_files': (), 12:32:14 INFO - 'pip_index': False, 12:32:14 INFO - 'sps_profile': False, 12:32:14 INFO - 'sps_profile_interval': 0, 12:32:14 INFO - 'suite': 'dromaeojs', 12:32:14 INFO - 'system_bits': '32', 12:32:14 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 12:32:14 INFO - 'title': 'talos-linux64-ix-069', 12:32:14 INFO - 'tooltool_cache': '/builds/tooltool_cache', 12:32:14 INFO - 'use_talos_json': True, 12:32:14 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 12:32:14 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 12:32:14 INFO - 'work_dir': 'build'} 12:32:14 INFO - [mozharness: 2016-10-13 19:32:14.857450Z] Running clobber step. 12:32:14 INFO - Running pre-action listener: _resource_record_pre_action 12:32:14 INFO - Running main action method: clobber 12:32:14 INFO - rmtree: /builds/slave/test/build 12:32:14 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 12:32:15 INFO - Running post-action listener: _resource_record_post_action 12:32:15 INFO - [mozharness: 2016-10-13 19:32:15.318483Z] Finished clobber step (success) 12:32:15 INFO - [mozharness: 2016-10-13 19:32:15.318585Z] Running read-buildbot-config step. 12:32:15 INFO - Running pre-action listener: _resource_record_pre_action 12:32:15 INFO - Running main action method: read_buildbot_config 12:32:15 INFO - Using buildbot properties: 12:32:15 INFO - { 12:32:15 INFO - "project": "", 12:32:15 INFO - "product": "firefox", 12:32:15 INFO - "script_repo_revision": "production", 12:32:15 INFO - "builddir": "mozilla-beta_ubuntu64_hw_test-dromaeojs", 12:32:15 INFO - "repository": "", 12:32:15 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-beta pgo talos dromaeojs", 12:32:15 INFO - "buildid": "20161013104124", 12:32:15 INFO - "pgo_build": "True", 12:32:15 INFO - "basedir": "/builds/slave/test", 12:32:15 INFO - "buildnumber": 275, 12:32:15 INFO - "slavename": "talos-linux64-ix-069", 12:32:15 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 12:32:15 INFO - "platform": "ubuntu64_hw", 12:32:15 INFO - "scheduler": "tests-mozilla-beta-linux64-pgo-talos", 12:32:15 INFO - "branch": "mozilla-beta", 12:32:15 INFO - "repo_path": "releases/mozilla-beta", 12:32:15 INFO - "revision": "2a05cc73a28ad6b6ce4e4f87b83a9cc3c0a94396", 12:32:15 INFO - "stage_platform": "linux64-pgo", 12:32:15 INFO - "builduid": "ee62ee9dbfe8468395e1f8db01ae1b01", 12:32:15 INFO - "slavebuilddir": "test" 12:32:15 INFO - } 12:32:15 INFO - Found installer url https://queue.taskcluster.net/v1/task/EH9J9CEdQBa9afEInMwAwg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2. 12:32:15 INFO - Running post-action listener: _resource_record_post_action 12:32:15 INFO - [mozharness: 2016-10-13 19:32:15.321037Z] Finished read-buildbot-config step (success) 12:32:15 INFO - [mozharness: 2016-10-13 19:32:15.321176Z] Running download-and-extract step. 12:32:15 INFO - Running pre-action listener: _resource_record_pre_action 12:32:15 INFO - Running main action method: download_and_extract 12:32:15 INFO - mkdir: /builds/slave/test/build/tests 12:32:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:32:15 INFO - https://queue.taskcluster.net/v1/task/EH9J9CEdQBa9afEInMwAwg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 12:32:15 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EH9J9CEdQBa9afEInMwAwg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 12:32:15 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EH9J9CEdQBa9afEInMwAwg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 12:32:15 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EH9J9CEdQBa9afEInMwAwg/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 12:32:15 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EH9J9CEdQBa9afEInMwAwg/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 12:32:15 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EH9J9CEdQBa9afEInMwAwg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 12:32:15 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 12:32:15 INFO - retry: Failed, sleeping 30 seconds before retrying 12:32:45 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EH9J9CEdQBa9afEInMwAwg/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 12:32:45 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EH9J9CEdQBa9afEInMwAwg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 12:32:45 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 12:32:45 INFO - retry: Failed, sleeping 60 seconds before retrying 12:33:45 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EH9J9CEdQBa9afEInMwAwg/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 12:33:45 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EH9J9CEdQBa9afEInMwAwg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 12:33:45 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 12:33:45 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EH9J9CEdQBa9afEInMwAwg/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! 12:33:45 INFO - Caught exception: HTTP Error 404: Not Found 12:33:45 INFO - Caught exception: HTTP Error 404: Not Found 12:33:45 INFO - Caught exception: HTTP Error 404: Not Found 12:33:45 INFO - trying https://queue.taskcluster.net/v1/task/EH9J9CEdQBa9afEInMwAwg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 12:33:45 INFO - Downloading https://queue.taskcluster.net/v1/task/EH9J9CEdQBa9afEInMwAwg/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 12:33:45 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/EH9J9CEdQBa9afEInMwAwg/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 12:33:45 INFO - Downloaded 1327 bytes. 12:33:45 INFO - Reading from file /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 12:33:45 INFO - Using the following test package requirements: 12:33:45 INFO - {u'common': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip'], 12:33:45 INFO - u'cppunittest': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 12:33:45 INFO - u'firefox-50.0.en-US.linux-x86_64.cppunittest.tests.zip'], 12:33:45 INFO - u'gtest': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 12:33:45 INFO - u'firefox-50.0.en-US.linux-x86_64.gtest.tests.zip'], 12:33:45 INFO - u'jittest': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 12:33:45 INFO - u'jsshell-linux-x86_64.zip'], 12:33:45 INFO - u'mochitest': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 12:33:45 INFO - u'firefox-50.0.en-US.linux-x86_64.mochitest.tests.zip'], 12:33:45 INFO - u'mozbase': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip'], 12:33:45 INFO - u'reftest': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 12:33:45 INFO - u'firefox-50.0.en-US.linux-x86_64.reftest.tests.zip'], 12:33:45 INFO - u'talos': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 12:33:45 INFO - u'firefox-50.0.en-US.linux-x86_64.talos.tests.zip'], 12:33:45 INFO - u'web-platform': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 12:33:45 INFO - u'firefox-50.0.en-US.linux-x86_64.web-platform.tests.zip'], 12:33:45 INFO - u'xpcshell': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 12:33:45 INFO - u'firefox-50.0.en-US.linux-x86_64.xpcshell.tests.zip']} 12:33:45 INFO - Downloading packages: [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 12:33:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:33:45 INFO - https://queue.taskcluster.net/v1/task/EH9J9CEdQBa9afEInMwAwg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 12:33:45 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EH9J9CEdQBa9afEInMwAwg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 12:33:45 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EH9J9CEdQBa9afEInMwAwg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 12:33:45 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EH9J9CEdQBa9afEInMwAwg/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 12:33:45 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EH9J9CEdQBa9afEInMwAwg/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 12:33:46 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EH9J9CEdQBa9afEInMwAwg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 12:33:46 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 12:33:46 INFO - retry: Failed, sleeping 30 seconds before retrying 12:34:16 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EH9J9CEdQBa9afEInMwAwg/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 12:34:16 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EH9J9CEdQBa9afEInMwAwg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 12:34:16 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 12:34:16 INFO - retry: Failed, sleeping 60 seconds before retrying 12:35:16 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EH9J9CEdQBa9afEInMwAwg/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 12:35:16 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EH9J9CEdQBa9afEInMwAwg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 12:35:16 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 12:35:16 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EH9J9CEdQBa9afEInMwAwg/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! 12:35:16 INFO - Caught exception: HTTP Error 404: Not Found 12:35:16 INFO - Caught exception: HTTP Error 404: Not Found 12:35:16 INFO - Caught exception: HTTP Error 404: Not Found 12:35:16 INFO - trying https://queue.taskcluster.net/v1/task/EH9J9CEdQBa9afEInMwAwg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 12:35:16 INFO - Downloading https://queue.taskcluster.net/v1/task/EH9J9CEdQBa9afEInMwAwg/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 12:35:16 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/EH9J9CEdQBa9afEInMwAwg/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 12:35:16 INFO - Downloaded 21774039 bytes. 12:35:16 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'] 12:35:16 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 12:35:18 INFO - Return code: 0 12:35:18 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 12:35:18 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')]} 12:35:18 INFO - https://queue.taskcluster.net/v1/task/EH9J9CEdQBa9afEInMwAwg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 12:35:18 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EH9J9CEdQBa9afEInMwAwg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 12:35:18 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EH9J9CEdQBa9afEInMwAwg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 12:35:18 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EH9J9CEdQBa9afEInMwAwg/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 12:35:18 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EH9J9CEdQBa9afEInMwAwg/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 12:35:18 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EH9J9CEdQBa9afEInMwAwg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 12:35:18 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 12:35:18 INFO - retry: Failed, sleeping 30 seconds before retrying 12:35:48 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EH9J9CEdQBa9afEInMwAwg/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 12:35:48 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EH9J9CEdQBa9afEInMwAwg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 12:35:48 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 12:35:48 INFO - retry: Failed, sleeping 60 seconds before retrying 12:36:48 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EH9J9CEdQBa9afEInMwAwg/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 12:36:48 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EH9J9CEdQBa9afEInMwAwg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 12:36:48 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 12:36:48 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EH9J9CEdQBa9afEInMwAwg/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! 12:36:48 INFO - Caught exception: HTTP Error 404: Not Found 12:36:48 INFO - Caught exception: HTTP Error 404: Not Found 12:36:48 INFO - Caught exception: HTTP Error 404: Not Found 12:36:48 INFO - trying https://queue.taskcluster.net/v1/task/EH9J9CEdQBa9afEInMwAwg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 12:36:48 INFO - Downloading https://queue.taskcluster.net/v1/task/EH9J9CEdQBa9afEInMwAwg/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 12:36:48 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/EH9J9CEdQBa9afEInMwAwg/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 12:36:49 INFO - Downloaded 21774039 bytes. 12:36:49 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'] 12:36:49 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 12:36:50 INFO - Return code: 0 12:36:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:36:50 INFO - https://queue.taskcluster.net/v1/task/EH9J9CEdQBa9afEInMwAwg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 12:36:50 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EH9J9CEdQBa9afEInMwAwg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 12:36:50 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EH9J9CEdQBa9afEInMwAwg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 12:36:50 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EH9J9CEdQBa9afEInMwAwg/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 12:36:50 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EH9J9CEdQBa9afEInMwAwg/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 12:36:50 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EH9J9CEdQBa9afEInMwAwg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 12:36:50 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 12:36:50 INFO - retry: Failed, sleeping 30 seconds before retrying 12:37:20 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EH9J9CEdQBa9afEInMwAwg/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 12:37:20 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EH9J9CEdQBa9afEInMwAwg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 12:37:20 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 12:37:20 INFO - retry: Failed, sleeping 60 seconds before retrying 12:38:20 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EH9J9CEdQBa9afEInMwAwg/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 12:38:20 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EH9J9CEdQBa9afEInMwAwg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 12:38:20 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 12:38:20 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EH9J9CEdQBa9afEInMwAwg/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! 12:38:20 INFO - Caught exception: HTTP Error 404: Not Found 12:38:20 INFO - Caught exception: HTTP Error 404: Not Found 12:38:20 INFO - Caught exception: HTTP Error 404: Not Found 12:38:20 INFO - trying https://queue.taskcluster.net/v1/task/EH9J9CEdQBa9afEInMwAwg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 12:38:20 INFO - Downloading https://queue.taskcluster.net/v1/task/EH9J9CEdQBa9afEInMwAwg/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 12:38:20 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/EH9J9CEdQBa9afEInMwAwg/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 12:38:21 INFO - Downloaded 14413740 bytes. 12:38:21 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'] 12:38:21 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 12:38:21 INFO - Return code: 0 12:38:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:38:21 INFO - https://queue.taskcluster.net/v1/task/EH9J9CEdQBa9afEInMwAwg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 12:38:21 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EH9J9CEdQBa9afEInMwAwg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 12:38:21 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EH9J9CEdQBa9afEInMwAwg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 12:38:21 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EH9J9CEdQBa9afEInMwAwg/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 12:38:21 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EH9J9CEdQBa9afEInMwAwg/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 12:38:21 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EH9J9CEdQBa9afEInMwAwg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 12:38:21 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 12:38:21 INFO - retry: Failed, sleeping 30 seconds before retrying 12:38:51 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EH9J9CEdQBa9afEInMwAwg/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 12:38:51 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EH9J9CEdQBa9afEInMwAwg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 12:38:51 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 12:38:51 INFO - retry: Failed, sleeping 60 seconds before retrying 12:39:51 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EH9J9CEdQBa9afEInMwAwg/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 12:39:51 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EH9J9CEdQBa9afEInMwAwg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 12:39:51 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 12:39:51 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EH9J9CEdQBa9afEInMwAwg/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! 12:39:51 INFO - Caught exception: HTTP Error 404: Not Found 12:39:51 INFO - Caught exception: HTTP Error 404: Not Found 12:39:51 INFO - Caught exception: HTTP Error 404: Not Found 12:39:51 INFO - trying https://queue.taskcluster.net/v1/task/EH9J9CEdQBa9afEInMwAwg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 12:39:51 INFO - Downloading https://queue.taskcluster.net/v1/task/EH9J9CEdQBa9afEInMwAwg/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 12:39:51 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/EH9J9CEdQBa9afEInMwAwg/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 12:39:52 INFO - Downloaded 56811084 bytes. 12:39:52 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/EH9J9CEdQBa9afEInMwAwg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 12:39:52 INFO - mkdir: /builds/slave/test/properties 12:39:52 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 12:39:52 INFO - Writing to file /builds/slave/test/properties/build_url 12:39:52 INFO - Contents: 12:39:52 INFO - build_url:https://queue.taskcluster.net/v1/task/EH9J9CEdQBa9afEInMwAwg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 12:39:53 INFO - Running post-action listener: _resource_record_post_action 12:39:53 INFO - Running post-action listener: set_extra_try_arguments 12:39:53 INFO - [mozharness: 2016-10-13 19:39:53.624550Z] Finished download-and-extract step (success) 12:39:53 INFO - [mozharness: 2016-10-13 19:39:53.624784Z] Running populate-webroot step. 12:39:53 INFO - Running pre-action listener: _resource_record_pre_action 12:39:53 INFO - Running main action method: populate_webroot 12:39:53 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 12:39:53 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 12:39:53 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 12:39:53 INFO - '--tppagecycles', 12:39:53 INFO - '7'], 12:39:53 INFO - 'tests': ['tsvgm']}}, 12:39:53 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 12:39:53 INFO - 'tests': ['tresize', 'tcanvasmark']}, 12:39:53 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 12:39:53 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 12:39:53 INFO - 'tests': ['dromaeo_css', 'kraken']}, 12:39:53 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 12:39:53 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:39:53 INFO - 'talos_options': ['--disable-e10s'], 12:39:53 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:39:53 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:39:53 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:39:53 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:39:53 INFO - 'talos_options': ['--disable-e10s'], 12:39:53 INFO - 'tests': ['damp', 'tps']}, 12:39:53 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:39:53 INFO - 'tests': ['damp', 'tps']}, 12:39:53 INFO - 'g3': {'talos_options': ['--disable-e10s'], 12:39:53 INFO - 'tests': ['dromaeo_dom']}, 12:39:53 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 12:39:53 INFO - 'g4': {'talos_options': ['--disable-e10s'], 12:39:53 INFO - 'tests': ['basic_compositor_video']}, 12:39:53 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 12:39:53 INFO - 'other': {'talos_options': ['--disable-e10s'], 12:39:53 INFO - 'tests': ['a11yr', 12:39:53 INFO - 'ts_paint', 12:39:53 INFO - 'tpaint', 12:39:53 INFO - 'sessionrestore', 12:39:53 INFO - 'sessionrestore_no_auto_restore', 12:39:53 INFO - 'tabpaint']}, 12:39:53 INFO - 'other-e10s': {'tests': ['a11yr', 12:39:53 INFO - 'ts_paint', 12:39:53 INFO - 'tpaint', 12:39:53 INFO - 'sessionrestore', 12:39:53 INFO - 'sessionrestore_no_auto_restore', 12:39:53 INFO - 'tabpaint']}, 12:39:53 INFO - 'other-e10s_l64': {'tests': ['a11yr', 12:39:53 INFO - 'ts_paint', 12:39:53 INFO - 'tpaint', 12:39:53 INFO - 'sessionrestore', 12:39:53 INFO - 'sessionrestore_no_auto_restore', 12:39:53 INFO - 'tabpaint']}, 12:39:53 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 12:39:53 INFO - 'ts_paint', 12:39:53 INFO - 'tpaint', 12:39:53 INFO - 'sessionrestore', 12:39:53 INFO - 'sessionrestore_no_auto_restore', 12:39:53 INFO - 'tabpaint']}, 12:39:53 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 12:39:53 INFO - 'tests': ['a11yr', 12:39:53 INFO - 'ts_paint', 12:39:53 INFO - 'tpaint', 12:39:53 INFO - 'sessionrestore', 12:39:53 INFO - 'sessionrestore_no_auto_restore', 12:39:53 INFO - 'tabpaint']}, 12:39:53 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 12:39:53 INFO - 'tests': ['a11yr', 12:39:53 INFO - 'ts_paint', 12:39:53 INFO - 'tpaint', 12:39:53 INFO - 'sessionrestore', 12:39:53 INFO - 'sessionrestore_no_auto_restore', 12:39:53 INFO - 'tabpaint']}, 12:39:53 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 12:39:53 INFO - 'tests': ['tsvgx', 12:39:53 INFO - 'tsvgr_opacity', 12:39:53 INFO - 'tart', 12:39:53 INFO - 'tscrollx', 12:39:53 INFO - 'cart']}, 12:39:53 INFO - 'svgr-e10s': {'tests': ['tsvgx', 12:39:53 INFO - 'tsvgr_opacity', 12:39:53 INFO - 'tart', 12:39:53 INFO - 'tscrollx', 12:39:53 INFO - 'cart']}, 12:39:53 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:39:53 INFO - 'talos_options': ['--disable-e10s'], 12:39:53 INFO - 'tests': ['tp5o']}, 12:39:53 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:39:53 INFO - 'tests': ['tp5o']}, 12:39:53 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:39:53 INFO - 'talos_options': ['--disable-e10s', 12:39:53 INFO - '--xperf_path', 12:39:53 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:39:53 INFO - 'tests': ['tp5n']}, 12:39:53 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:39:53 INFO - 'talos_options': ['--xperf_path', 12:39:53 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:39:53 INFO - 'tests': ['tp5n']}}, 12:39:53 INFO - 'talos.zip': {'path': '', 12:39:53 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 12:39:53 INFO - Running post-action listener: _resource_record_post_action 12:39:53 INFO - [mozharness: 2016-10-13 19:39:53.648502Z] Finished populate-webroot step (success) 12:39:53 INFO - [mozharness: 2016-10-13 19:39:53.648591Z] Running create-virtualenv step. 12:39:53 INFO - Running pre-action listener: _resource_record_pre_action 12:39:53 INFO - Running main action method: create_virtualenv 12:39:53 INFO - Creating virtualenv /builds/slave/test/build/venv 12:39:53 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 12:39:53 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 12:39:53 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 12:39:53 INFO - Using real prefix '/usr' 12:39:53 INFO - New python executable in /builds/slave/test/build/venv/bin/python 12:39:54 INFO - Installing distribute.............................................................................................................................................................................................done. 12:39:56 INFO - Installing pip.................done. 12:39:56 INFO - Return code: 0 12:39:56 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 12:39:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:39:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:39:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:39:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:39:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:39:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:39:56 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x124d030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13b08a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x150e180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x150c3b0>, '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 0x150d2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x150d740>, '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': '05b19d94dfa9f3ce7b6e62cc000001f7-1476387132.106355-1991483113', '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 12:39:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 12:39:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 12:39:56 INFO - Using env: {'DISPLAY': ':0', 12:39:56 INFO - 'HOME': '/home/cltbld', 12:39:56 INFO - 'LANG': 'en_US.UTF-8', 12:39:56 INFO - 'LANGUAGE': 'en_US:en', 12:39:56 INFO - 'LOGNAME': 'cltbld', 12:39:56 INFO - 'MAIL': '/var/mail/cltbld', 12:39:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:39:56 INFO - 'MOZ_NO_REMOTE': '1', 12:39:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:39:56 INFO - 'NO_EM_RESTART': '1', 12:39:56 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:39:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:39:56 INFO - 'PWD': '/builds/slave/test', 12:39:56 INFO - 'SHELL': '/bin/bash', 12:39:56 INFO - 'SHLVL': '1', 12:39:56 INFO - 'TERM': 'linux', 12:39:56 INFO - 'TMOUT': '86400', 12:39:56 INFO - 'USER': 'cltbld', 12:39:56 INFO - 'XDG_SESSION_COOKIE': '05b19d94dfa9f3ce7b6e62cc000001f7-1476387132.106355-1991483113', 12:39:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:39:56 INFO - '_': '/tools/buildbot/bin/python'} 12:39:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:39:56 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 12:39:56 INFO - Cleaning up... 12:39:56 INFO - Return code: 0 12:39:56 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 12:39:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:39:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:39:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:39:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:39:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:39:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:39:56 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x124d030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13b08a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x150e180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x150c3b0>, '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 0x150d2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x150d740>, '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': '05b19d94dfa9f3ce7b6e62cc000001f7-1476387132.106355-1991483113', '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 12:39:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 12:39:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 12:39:56 INFO - Using env: {'DISPLAY': ':0', 12:39:56 INFO - 'HOME': '/home/cltbld', 12:39:56 INFO - 'LANG': 'en_US.UTF-8', 12:39:56 INFO - 'LANGUAGE': 'en_US:en', 12:39:56 INFO - 'LOGNAME': 'cltbld', 12:39:56 INFO - 'MAIL': '/var/mail/cltbld', 12:39:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:39:56 INFO - 'MOZ_NO_REMOTE': '1', 12:39:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:39:56 INFO - 'NO_EM_RESTART': '1', 12:39:56 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:39:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:39:56 INFO - 'PWD': '/builds/slave/test', 12:39:56 INFO - 'SHELL': '/bin/bash', 12:39:56 INFO - 'SHLVL': '1', 12:39:56 INFO - 'TERM': 'linux', 12:39:56 INFO - 'TMOUT': '86400', 12:39:56 INFO - 'USER': 'cltbld', 12:39:56 INFO - 'XDG_SESSION_COOKIE': '05b19d94dfa9f3ce7b6e62cc000001f7-1476387132.106355-1991483113', 12:39:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:39:56 INFO - '_': '/tools/buildbot/bin/python'} 12:39:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:39:56 INFO - Downloading/unpacking psutil>=3.1.1 12:39:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:39:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:39:56 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:39:56 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:39:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:39:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:39:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 12:39:58 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:39:58 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:39:58 INFO - Installing collected packages: psutil 12:39:58 INFO - Running setup.py install for psutil 12:39:58 INFO - building 'psutil._psutil_linux' extension 12:39:58 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 12:39:58 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 12:39:58 INFO - building 'psutil._psutil_posix' extension 12:39:58 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 12:39:59 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 12:39:59 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:39:59 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:39:59 INFO - Successfully installed psutil 12:39:59 INFO - Cleaning up... 12:39:59 INFO - Return code: 0 12:39:59 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 12:39:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:39:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:39:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:39:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:39:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:39:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:39:59 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 0x124d030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13b08a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x150e180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x150c3b0>, '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 0x150d2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x150d740>, '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': '05b19d94dfa9f3ce7b6e62cc000001f7-1476387132.106355-1991483113', '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 12:39:59 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 12:39:59 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 12:39:59 INFO - Using env: {'DISPLAY': ':0', 12:39:59 INFO - 'HOME': '/home/cltbld', 12:39:59 INFO - 'LANG': 'en_US.UTF-8', 12:39:59 INFO - 'LANGUAGE': 'en_US:en', 12:39:59 INFO - 'LOGNAME': 'cltbld', 12:39:59 INFO - 'MAIL': '/var/mail/cltbld', 12:39:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:39:59 INFO - 'MOZ_NO_REMOTE': '1', 12:39:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:39:59 INFO - 'NO_EM_RESTART': '1', 12:39:59 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:39:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:39:59 INFO - 'PWD': '/builds/slave/test', 12:39:59 INFO - 'SHELL': '/bin/bash', 12:39:59 INFO - 'SHLVL': '1', 12:39:59 INFO - 'TERM': 'linux', 12:39:59 INFO - 'TMOUT': '86400', 12:39:59 INFO - 'USER': 'cltbld', 12:39:59 INFO - 'XDG_SESSION_COOKIE': '05b19d94dfa9f3ce7b6e62cc000001f7-1476387132.106355-1991483113', 12:39:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:39:59 INFO - '_': '/tools/buildbot/bin/python'} 12:39:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:39:59 INFO - Downloading/unpacking mozsystemmonitor==0.3 12:39: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 12:39: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 12:39: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 12:39: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 12:39: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 12:39: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 12:40:01 INFO - Downloading mozsystemmonitor-0.3.tar.gz 12:40:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 12:40:01 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 12:40:01 INFO - Installing collected packages: mozsystemmonitor 12:40:01 INFO - Running setup.py install for mozsystemmonitor 12:40:01 INFO - Successfully installed mozsystemmonitor 12:40:01 INFO - Cleaning up... 12:40:01 INFO - Return code: 0 12:40:01 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 12:40: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')]} 12:40:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:40:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:40: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')]} 12:40:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:40:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:40: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', '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 0x124d030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13b08a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x150e180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x150c3b0>, '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 0x150d2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x150d740>, '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': '05b19d94dfa9f3ce7b6e62cc000001f7-1476387132.106355-1991483113', '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 12:40: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', 'jsonschema==2.5.1'] in /builds/slave/test/build 12:40: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 jsonschema==2.5.1 12:40:01 INFO - Using env: {'DISPLAY': ':0', 12:40:01 INFO - 'HOME': '/home/cltbld', 12:40:01 INFO - 'LANG': 'en_US.UTF-8', 12:40:01 INFO - 'LANGUAGE': 'en_US:en', 12:40:01 INFO - 'LOGNAME': 'cltbld', 12:40:01 INFO - 'MAIL': '/var/mail/cltbld', 12:40:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:40:01 INFO - 'MOZ_NO_REMOTE': '1', 12:40:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:40:01 INFO - 'NO_EM_RESTART': '1', 12:40:01 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:40:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:40:01 INFO - 'PWD': '/builds/slave/test', 12:40:01 INFO - 'SHELL': '/bin/bash', 12:40:01 INFO - 'SHLVL': '1', 12:40:01 INFO - 'TERM': 'linux', 12:40:01 INFO - 'TMOUT': '86400', 12:40:01 INFO - 'USER': 'cltbld', 12:40:01 INFO - 'XDG_SESSION_COOKIE': '05b19d94dfa9f3ce7b6e62cc000001f7-1476387132.106355-1991483113', 12:40:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:40:01 INFO - '_': '/tools/buildbot/bin/python'} 12:40:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:40:01 INFO - Downloading/unpacking jsonschema==2.5.1 12:40: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 12:40: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 12:40: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 12:40: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 12:40: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 12:40: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 12:40:03 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 12:40:03 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 12:40:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:40:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:40:03 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:40:03 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:40:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:40:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:40:03 INFO - Downloading functools32-3.2.3-2.tar.gz 12:40:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 12:40:03 INFO - warning: no files found matching '*.txt' 12:40:03 INFO - no previously-included directories found matching 'build' 12:40:03 INFO - no previously-included directories found matching 'dist' 12:40:03 INFO - no previously-included directories found matching '.git*' 12:40:03 INFO - Installing collected packages: jsonschema, functools32 12:40:03 INFO - Running setup.py install for functools32 12:40:04 INFO - warning: no files found matching '*.txt' 12:40:04 INFO - no previously-included directories found matching 'build' 12:40:04 INFO - no previously-included directories found matching 'dist' 12:40:04 INFO - no previously-included directories found matching '.git*' 12:40:04 INFO - Successfully installed jsonschema functools32 12:40:04 INFO - Cleaning up... 12:40:04 INFO - Return code: 0 12:40:04 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 12:40: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')]} 12:40:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:40:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:40: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')]} 12:40:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:40:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:40: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', '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 0x124d030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13b08a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x150e180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x150c3b0>, '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 0x150d2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x150d740>, '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': '05b19d94dfa9f3ce7b6e62cc000001f7-1476387132.106355-1991483113', '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 12:40: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', 'functools32==3.2.3-2'] in /builds/slave/test/build 12:40: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 functools32==3.2.3-2 12:40:04 INFO - Using env: {'DISPLAY': ':0', 12:40:04 INFO - 'HOME': '/home/cltbld', 12:40:04 INFO - 'LANG': 'en_US.UTF-8', 12:40:04 INFO - 'LANGUAGE': 'en_US:en', 12:40:04 INFO - 'LOGNAME': 'cltbld', 12:40:04 INFO - 'MAIL': '/var/mail/cltbld', 12:40:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:40:04 INFO - 'MOZ_NO_REMOTE': '1', 12:40:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:40:04 INFO - 'NO_EM_RESTART': '1', 12:40:04 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:40:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:40:04 INFO - 'PWD': '/builds/slave/test', 12:40:04 INFO - 'SHELL': '/bin/bash', 12:40:04 INFO - 'SHLVL': '1', 12:40:04 INFO - 'TERM': 'linux', 12:40:04 INFO - 'TMOUT': '86400', 12:40:04 INFO - 'USER': 'cltbld', 12:40:04 INFO - 'XDG_SESSION_COOKIE': '05b19d94dfa9f3ce7b6e62cc000001f7-1476387132.106355-1991483113', 12:40:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:40:04 INFO - '_': '/tools/buildbot/bin/python'} 12:40:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:40:04 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 12:40:04 INFO - Cleaning up... 12:40:04 INFO - Return code: 0 12:40:04 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 12:40: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')]} 12:40:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:40:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:40: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')]} 12:40:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:40:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:40: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', '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 0x124d030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13b08a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x150e180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x150c3b0>, '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 0x150d2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x150d740>, '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': '05b19d94dfa9f3ce7b6e62cc000001f7-1476387132.106355-1991483113', '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 12:40: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', 'blobuploader==1.2.4'] in /builds/slave/test/build 12:40: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 blobuploader==1.2.4 12:40:04 INFO - Using env: {'DISPLAY': ':0', 12:40:04 INFO - 'HOME': '/home/cltbld', 12:40:04 INFO - 'LANG': 'en_US.UTF-8', 12:40:04 INFO - 'LANGUAGE': 'en_US:en', 12:40:04 INFO - 'LOGNAME': 'cltbld', 12:40:04 INFO - 'MAIL': '/var/mail/cltbld', 12:40:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:40:04 INFO - 'MOZ_NO_REMOTE': '1', 12:40:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:40:04 INFO - 'NO_EM_RESTART': '1', 12:40:04 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:40:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:40:04 INFO - 'PWD': '/builds/slave/test', 12:40:04 INFO - 'SHELL': '/bin/bash', 12:40:04 INFO - 'SHLVL': '1', 12:40:04 INFO - 'TERM': 'linux', 12:40:04 INFO - 'TMOUT': '86400', 12:40:04 INFO - 'USER': 'cltbld', 12:40:04 INFO - 'XDG_SESSION_COOKIE': '05b19d94dfa9f3ce7b6e62cc000001f7-1476387132.106355-1991483113', 12:40:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:40:04 INFO - '_': '/tools/buildbot/bin/python'} 12:40:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:40:04 INFO - Downloading/unpacking blobuploader==1.2.4 12:40: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 12:40: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 12:40: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 12:40: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 12:40: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 12:40: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 12:40:06 INFO - Downloading blobuploader-1.2.4.tar.gz 12:40:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 12:40:06 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 12:40: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 12:40: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 12:40: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 12:40: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 12:40: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 12:40: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 12:40:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 12:40:06 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 12:40: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 12:40: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 12:40: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 12:40: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 12:40: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 12:40: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 12:40:07 INFO - Downloading docopt-0.6.1.tar.gz 12:40:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 12:40:07 INFO - Installing collected packages: blobuploader, requests, docopt 12:40:07 INFO - Running setup.py install for blobuploader 12:40:07 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 12:40:07 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 12:40:07 INFO - Running setup.py install for requests 12:40:07 INFO - Running setup.py install for docopt 12:40:07 INFO - Successfully installed blobuploader requests docopt 12:40:07 INFO - Cleaning up... 12:40:07 INFO - Return code: 0 12:40:07 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:40:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:40:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:40:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:40:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:40:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:40:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:40:07 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 0x124d030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13b08a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x150e180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x150c3b0>, '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 0x150d2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x150d740>, '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': '05b19d94dfa9f3ce7b6e62cc000001f7-1476387132.106355-1991483113', '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 12:40:07 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 12:40:07 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 12:40:07 INFO - Using env: {'DISPLAY': ':0', 12:40:07 INFO - 'HOME': '/home/cltbld', 12:40:07 INFO - 'LANG': 'en_US.UTF-8', 12:40:07 INFO - 'LANGUAGE': 'en_US:en', 12:40:07 INFO - 'LOGNAME': 'cltbld', 12:40:07 INFO - 'MAIL': '/var/mail/cltbld', 12:40:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:40:07 INFO - 'MOZ_NO_REMOTE': '1', 12:40:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:40:07 INFO - 'NO_EM_RESTART': '1', 12:40:07 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:40:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:40:07 INFO - 'PWD': '/builds/slave/test', 12:40:07 INFO - 'SHELL': '/bin/bash', 12:40:07 INFO - 'SHLVL': '1', 12:40:07 INFO - 'TERM': 'linux', 12:40:07 INFO - 'TMOUT': '86400', 12:40:07 INFO - 'USER': 'cltbld', 12:40:07 INFO - 'XDG_SESSION_COOKIE': '05b19d94dfa9f3ce7b6e62cc000001f7-1476387132.106355-1991483113', 12:40:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:40:07 INFO - '_': '/tools/buildbot/bin/python'} 12:40:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:40:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:40:08 INFO - Running setup.py (path:/tmp/pip-yK4VHC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:40:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:40:08 INFO - Running setup.py (path:/tmp/pip-CSJa8_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:40:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:40:08 INFO - Running setup.py (path:/tmp/pip-xKGdVH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:40:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:40:08 INFO - Running setup.py (path:/tmp/pip-gc42Vi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:40:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:40:08 INFO - Running setup.py (path:/tmp/pip-ZRSvXw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:40:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:40:08 INFO - Running setup.py (path:/tmp/pip-2ZtJCk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:40:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:40:08 INFO - Running setup.py (path:/tmp/pip-1LUpwN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:40:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:40:08 INFO - Running setup.py (path:/tmp/pip-ZUDaqe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:40:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:40:08 INFO - Running setup.py (path:/tmp/pip-cPZkaP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:40:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:40:08 INFO - Running setup.py (path:/tmp/pip-E0jbPh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:40:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:40:08 INFO - Running setup.py (path:/tmp/pip-R0n58H-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:40:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:40:08 INFO - Running setup.py (path:/tmp/pip-xJhWbe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:40:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:40:08 INFO - Running setup.py (path:/tmp/pip-MiWTZb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:40:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:40:09 INFO - Running setup.py (path:/tmp/pip-GOXG8P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:40:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:40:09 INFO - Running setup.py (path:/tmp/pip-TmeXqq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:40:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:40:09 INFO - Running setup.py (path:/tmp/pip-bS8k4u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:40:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:40:09 INFO - Running setup.py (path:/tmp/pip-J9jJsd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:40:09 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 12:40:09 INFO - Running setup.py install for manifestparser 12:40:09 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 12:40:09 INFO - Running setup.py install for mozcrash 12:40:09 INFO - Running setup.py install for mozdebug 12:40:09 INFO - Running setup.py install for mozdevice 12:40:09 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 12:40:09 INFO - Installing dm script to /builds/slave/test/build/venv/bin 12:40:09 INFO - Running setup.py install for mozfile 12:40:09 INFO - Running setup.py install for mozhttpd 12:40:09 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 12:40:09 INFO - Running setup.py install for mozinfo 12:40:10 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 12:40:10 INFO - Running setup.py install for mozInstall 12:40:10 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 12:40:10 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 12:40:10 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 12:40:10 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 12:40:10 INFO - Running setup.py install for mozleak 12:40:10 INFO - Running setup.py install for mozlog 12:40:10 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 12:40:10 INFO - Running setup.py install for moznetwork 12:40:10 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 12:40:10 INFO - Running setup.py install for mozprocess 12:40:10 INFO - Running setup.py install for mozprofile 12:40:10 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 12:40:10 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 12:40:10 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 12:40:10 INFO - Running setup.py install for mozrunner 12:40:10 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 12:40:10 INFO - Running setup.py install for mozscreenshot 12:40:10 INFO - Running setup.py install for moztest 12:40:10 INFO - Running setup.py install for mozversion 12:40:11 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 12:40:11 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 12:40:11 INFO - Cleaning up... 12:40:11 INFO - Return code: 0 12:40:11 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:40:11 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')]} 12:40:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:40:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:40:11 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')]} 12:40:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:40:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:40:11 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 0x124d030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13b08a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x150e180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x150c3b0>, '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 0x150d2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x150d740>, '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': '05b19d94dfa9f3ce7b6e62cc000001f7-1476387132.106355-1991483113', '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 12:40:11 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 12:40:11 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 12:40:11 INFO - Using env: {'DISPLAY': ':0', 12:40:11 INFO - 'HOME': '/home/cltbld', 12:40:11 INFO - 'LANG': 'en_US.UTF-8', 12:40:11 INFO - 'LANGUAGE': 'en_US:en', 12:40:11 INFO - 'LOGNAME': 'cltbld', 12:40:11 INFO - 'MAIL': '/var/mail/cltbld', 12:40:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:40:11 INFO - 'MOZ_NO_REMOTE': '1', 12:40:11 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:40:11 INFO - 'NO_EM_RESTART': '1', 12:40:11 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:40:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:40:11 INFO - 'PWD': '/builds/slave/test', 12:40:11 INFO - 'SHELL': '/bin/bash', 12:40:11 INFO - 'SHLVL': '1', 12:40:11 INFO - 'TERM': 'linux', 12:40:11 INFO - 'TMOUT': '86400', 12:40:11 INFO - 'USER': 'cltbld', 12:40:11 INFO - 'XDG_SESSION_COOKIE': '05b19d94dfa9f3ce7b6e62cc000001f7-1476387132.106355-1991483113', 12:40:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:40:11 INFO - '_': '/tools/buildbot/bin/python'} 12:40:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:40:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:40:11 INFO - Running setup.py (path:/tmp/pip-eIJ9xK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:40:11 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)) 12:40:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:40:11 INFO - Running setup.py (path:/tmp/pip-kbFqXe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:40:11 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)) 12:40:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:40:11 INFO - Running setup.py (path:/tmp/pip-EWoK4o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:40:11 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)) 12:40:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:40:11 INFO - Running setup.py (path:/tmp/pip-yxgRtG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:40:11 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)) 12:40:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:40:11 INFO - Running setup.py (path:/tmp/pip-7TvNRa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:40:11 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)) 12:40:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:40:11 INFO - Running setup.py (path:/tmp/pip-AkgtnB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:40:11 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)) 12:40:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:40:11 INFO - Running setup.py (path:/tmp/pip-p8M6Bh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:40:11 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)) 12:40:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:40:11 INFO - Running setup.py (path:/tmp/pip-cEj1k0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:40:11 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)) 12:40:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:40:11 INFO - Running setup.py (path:/tmp/pip-bWjDE4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:40:11 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)) 12:40:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:40:11 INFO - Running setup.py (path:/tmp/pip-Ox4CS_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:40:12 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)) 12:40:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:40:12 INFO - Running setup.py (path:/tmp/pip-VhU_4Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:40:12 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)) 12:40:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:40:12 INFO - Running setup.py (path:/tmp/pip-jl_E3l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:40:12 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)) 12:40:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:40:12 INFO - Running setup.py (path:/tmp/pip-ohEiTI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:40:12 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)) 12:40:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:40:12 INFO - Running setup.py (path:/tmp/pip-uIEu4J-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:40:12 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)) 12:40:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:40:12 INFO - Running setup.py (path:/tmp/pip-nQ_ax7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:40:12 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)) 12:40:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:40:12 INFO - Running setup.py (path:/tmp/pip-7rpDAy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:40:12 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)) 12:40:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:40:12 INFO - Running setup.py (path:/tmp/pip-F6BKOM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:40:12 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)) 12:40:12 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)) 12:40:12 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)) 12:40:12 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)) 12:40:12 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)) 12:40:12 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)) 12:40:12 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 12:40:12 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 12:40:12 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 12:40:12 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 12:40:12 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 12:40:12 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 12:40:12 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 12:40:14 INFO - Downloading blessings-1.6.tar.gz 12:40:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 12:40:14 INFO - Installing collected packages: blessings 12:40:14 INFO - Running setup.py install for blessings 12:40:14 INFO - Successfully installed blessings 12:40:14 INFO - Cleaning up... 12:40:14 INFO - Return code: 0 12:40:14 INFO - Done creating virtualenv /builds/slave/test/build/venv. 12:40:14 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:40:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:40:14 INFO - Reading from file tmpfile_stdout 12:40:14 INFO - Current package versions: 12:40:14 INFO - argparse == 1.2.1 12:40:14 INFO - blessings == 1.6 12:40:14 INFO - blobuploader == 1.2.4 12:40:14 INFO - docopt == 0.6.1 12:40:14 INFO - functools32 == 3.2.3-2 12:40:14 INFO - jsonschema == 2.5.1 12:40:14 INFO - manifestparser == 1.1 12:40:14 INFO - mozInstall == 1.12 12:40:14 INFO - mozcrash == 0.17 12:40:14 INFO - mozdebug == 0.1 12:40:14 INFO - mozdevice == 0.48 12:40:14 INFO - mozfile == 1.2 12:40:14 INFO - mozhttpd == 0.7 12:40:14 INFO - mozinfo == 0.9 12:40:14 INFO - mozleak == 0.1 12:40:14 INFO - mozlog == 3.3 12:40:14 INFO - moznetwork == 0.27 12:40:14 INFO - mozprocess == 0.23 12:40:14 INFO - mozprofile == 0.28 12:40:14 INFO - mozrunner == 6.12 12:40:14 INFO - mozscreenshot == 0.1 12:40:14 INFO - mozsystemmonitor == 0.3 12:40:14 INFO - moztest == 0.7 12:40:14 INFO - mozversion == 1.4 12:40:14 INFO - psutil == 3.1.1 12:40:14 INFO - requests == 1.2.3 12:40:14 INFO - wsgiref == 0.1.2 12:40:14 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:40:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:40:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:40:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:40:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:40:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:40:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:40:14 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 0x124d030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13b08a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x150e180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x150c3b0>, '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 0x150d2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x150d740>, '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': '05b19d94dfa9f3ce7b6e62cc000001f7-1476387132.106355-1991483113', '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 12:40:14 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 12:40:14 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 12:40:14 INFO - Using env: {'DISPLAY': ':0', 12:40:14 INFO - 'HOME': '/home/cltbld', 12:40:14 INFO - 'LANG': 'en_US.UTF-8', 12:40:14 INFO - 'LANGUAGE': 'en_US:en', 12:40:14 INFO - 'LOGNAME': 'cltbld', 12:40:14 INFO - 'MAIL': '/var/mail/cltbld', 12:40:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:40:14 INFO - 'MOZ_NO_REMOTE': '1', 12:40:14 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:40:14 INFO - 'NO_EM_RESTART': '1', 12:40:14 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:40:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:40:14 INFO - 'PWD': '/builds/slave/test', 12:40:14 INFO - 'SHELL': '/bin/bash', 12:40:14 INFO - 'SHLVL': '1', 12:40:14 INFO - 'TERM': 'linux', 12:40:14 INFO - 'TMOUT': '86400', 12:40:14 INFO - 'USER': 'cltbld', 12:40:15 INFO - 'XDG_SESSION_COOKIE': '05b19d94dfa9f3ce7b6e62cc000001f7-1476387132.106355-1991483113', 12:40:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:40:15 INFO - '_': '/tools/buildbot/bin/python'} 12:40:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:40:15 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)) 12:40:15 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)) 12:40:15 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)) 12:40:15 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)) 12:40:15 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)) 12:40:15 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)) 12:40:15 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)) 12:40:15 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)) 12:40:15 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)) 12:40:15 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)) 12:40:15 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)) 12:40:15 INFO - Cleaning up... 12:40:15 INFO - Return code: 0 12:40:15 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 12:40:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:40:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:40:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:40:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:40:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:40:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:40:15 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 0x124d030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13b08a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x150e180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x150c3b0>, '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 0x150d2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x150d740>, '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': '05b19d94dfa9f3ce7b6e62cc000001f7-1476387132.106355-1991483113', '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 12:40:15 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 12:40:15 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 12:40:15 INFO - Using env: {'DISPLAY': ':0', 12:40:15 INFO - 'HOME': '/home/cltbld', 12:40:15 INFO - 'LANG': 'en_US.UTF-8', 12:40:15 INFO - 'LANGUAGE': 'en_US:en', 12:40:15 INFO - 'LOGNAME': 'cltbld', 12:40:15 INFO - 'MAIL': '/var/mail/cltbld', 12:40:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:40:15 INFO - 'MOZ_NO_REMOTE': '1', 12:40:15 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:40:15 INFO - 'NO_EM_RESTART': '1', 12:40:15 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:40:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:40:15 INFO - 'PWD': '/builds/slave/test', 12:40:15 INFO - 'SHELL': '/bin/bash', 12:40:15 INFO - 'SHLVL': '1', 12:40:15 INFO - 'TERM': 'linux', 12:40:15 INFO - 'TMOUT': '86400', 12:40:15 INFO - 'USER': 'cltbld', 12:40:15 INFO - 'XDG_SESSION_COOKIE': '05b19d94dfa9f3ce7b6e62cc000001f7-1476387132.106355-1991483113', 12:40:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:40:15 INFO - '_': '/tools/buildbot/bin/python'} 12:40:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:40:15 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 12:40:15 INFO - Cleaning up... 12:40:15 INFO - Return code: 0 12:40:15 INFO - Running post-action listener: _resource_record_post_action 12:40:15 INFO - Running post-action listener: _start_resource_monitoring 12:40:15 INFO - Starting resource monitoring. 12:40:15 INFO - [mozharness: 2016-10-13 19:40:15.474193Z] Finished create-virtualenv step (success) 12:40:15 INFO - [mozharness: 2016-10-13 19:40:15.474624Z] Running install step. 12:40:15 INFO - Running pre-action listener: _resource_record_pre_action 12:40:15 INFO - Running main action method: install 12:40:15 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:40:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:40:15 INFO - Reading from file tmpfile_stdout 12:40:15 INFO - Detecting whether we're running mozinstall >=1.0... 12:40:15 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 12:40:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 12:40:15 INFO - Reading from file tmpfile_stdout 12:40:15 INFO - Output received: 12:40:15 INFO - Usage: mozinstall [options] installer 12:40:15 INFO - Options: 12:40:15 INFO - -h, --help show this help message and exit 12:40:15 INFO - -d DEST, --destination=DEST 12:40:15 INFO - Directory to install application into. [default: 12:40:15 INFO - "/builds/slave/test"] 12:40:15 INFO - --app=APP Application being installed. [default: firefox] 12:40:15 INFO - mkdir: /builds/slave/test/build/application 12:40:15 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'] 12:40:15 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 12:40:29 INFO - Reading from file tmpfile_stdout 12:40:29 INFO - Output received: 12:40:29 INFO - /builds/slave/test/build/application/firefox/firefox 12:40:29 INFO - Running post-action listener: _resource_record_post_action 12:40:29 INFO - [mozharness: 2016-10-13 19:40:29.266550Z] Finished install step (success) 12:40:29 INFO - [mozharness: 2016-10-13 19:40:29.266826Z] Running run-tests step. 12:40:29 INFO - Running pre-action listener: _resource_record_pre_action 12:40:29 INFO - Running main action method: run_tests 12:40:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 12:40:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 12:40:29 INFO - Python 2.7.3 12:40:29 INFO - Return code: 0 12:40:29 INFO - grabbing minidump binary from tooltool 12:40:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:40:29 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 0x150c3b0>, '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 0x150d2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x150d740>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 12:40:29 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 12:40:29 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 12:40:29 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 12:40:29 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 12:40:29 INFO - Return code: 0 12:40:29 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 12:40:29 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 12:40:29 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 12:40:29 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 12:40:29 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 12:40:29 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/EH9J9CEdQBa9afEInMwAwg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-069', '--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 12:40:29 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/EH9J9CEdQBa9afEInMwAwg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-069 --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 12:40:29 INFO - Using env: {'DISPLAY': ':0', 12:40:29 INFO - 'HOME': '/home/cltbld', 12:40:29 INFO - 'LANG': 'en_US.UTF-8', 12:40:29 INFO - 'LANGUAGE': 'en_US:en', 12:40:29 INFO - 'LOGNAME': 'cltbld', 12:40:29 INFO - 'MAIL': '/var/mail/cltbld', 12:40:29 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 12:40:29 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 12:40:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:40:29 INFO - 'MOZ_NO_REMOTE': '1', 12:40:29 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 12:40:29 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:40:29 INFO - 'NO_EM_RESTART': '1', 12:40:29 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:40:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:40:29 INFO - 'PWD': '/builds/slave/test', 12:40:29 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 12:40:29 INFO - 'SHELL': '/bin/bash', 12:40:29 INFO - 'SHLVL': '1', 12:40:29 INFO - 'TERM': 'linux', 12:40:29 INFO - 'TMOUT': '86400', 12:40:29 INFO - 'USER': 'cltbld', 12:40:29 INFO - 'XDG_SESSION_COOKIE': '05b19d94dfa9f3ce7b6e62cc000001f7-1476387132.106355-1991483113', 12:40:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:40:29 INFO - '_': '/tools/buildbot/bin/python'} 12:40:29 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/EH9J9CEdQBa9afEInMwAwg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-069', '--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 12:40:29 INFO - mozversion application_buildid: 20161013104124 12:40:29 INFO - mozversion application_changeset: 2a05cc73a28ad6b6ce4e4f87b83a9cc3c0a94396 12:40:29 INFO - mozversion application_display_name: Firefox 12:40:29 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 12:40:29 INFO - mozversion application_name: Firefox 12:40:29 INFO - mozversion application_remotingname: firefox 12:40:29 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 12:40:29 INFO - mozversion application_vendor: Mozilla 12:40:29 INFO - mozversion application_version: 50.0 12:40:29 INFO - mozversion platform_buildid: 20161013104124 12:40:29 INFO - mozversion platform_changeset: 2a05cc73a28ad6b6ce4e4f87b83a9cc3c0a94396 12:40:29 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 12:40:29 INFO - mozversion platform_version: 50.0 12:40:29 INFO - using testdate: 1476387629 12:40:29 INFO - actual date: 1476387629 12:40:29 INFO - starting webserver on 'localhost:37358' 12:40:29 INFO - SUITE-START | Running 2 tests 12:40:29 INFO - TEST-START | dromaeo_css 12:40:29 INFO - operating with platform_type : linux_ 12:40:29 INFO - Initialising browser for dromaeo_css test... 12:40:29 INFO - TEST-INFO | started process 8549 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpASs5zZ/profile http://localhost:37358/getInfo.html) 12:40:33 INFO - PROCESS | 8549 | __metrics Screen width/height:1600/1200 12:40:33 INFO - PROCESS | 8549 | colorDepth:24 12:40:33 INFO - PROCESS | 8549 | Browser inner width/height: 1024/697 12:40:33 INFO - PROCESS | 8549 | __metrics 12:40:34 INFO - PROCESS | 8549 | 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" 12:40:34 INFO - PROCESS | 8549 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 12:40:35 INFO - PROCESS | 8549 | console.error: PushService: 12:40:35 INFO - PROCESS | 8549 | stateChangeProcessEnqueue: Error transitioning state 12:40:35 INFO - PROCESS | 8549 | UnknownError 12:40:35 INFO - TEST-INFO | 8549: exit 0 12:40:35 INFO - Browser initialized. 12:40:35 INFO - Running cycle 1/1 for dromaeo_css test... 12:40:35 INFO - TEST-INFO | started process 8637 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpASs5zZ/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 12:40:47 INFO - PROCESS | 8637 | RSS: Main: 143687680 12:40:47 INFO - PROCESS | 8637 | 12:41:38 INFO - PROCESS | 8637 | 1476387698574 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] 12:43:46 INFO - PROCESS | 8637 | Cycle 1(1): loaded http://localhost:37358/tests/dromaeo/cssquery-dojo.html (next: http://localhost:37358/tests/dromaeo/cssquery-ext.html) 12:43:47 INFO - PROCESS | 8637 | RSS: Main: 233017344 12:43:47 INFO - PROCESS | 8637 | 12:46:49 INFO - PROCESS | 8637 | Cycle 1(1): loaded http://localhost:37358/tests/dromaeo/cssquery-ext.html (next: http://localhost:37358/tests/dromaeo/cssquery-jquery.html) 12:46:50 INFO - PROCESS | 8637 | RSS: Main: 236015616 12:46:50 INFO - PROCESS | 8637 | 12:49:52 INFO - PROCESS | 8637 | Cycle 1(1): loaded http://localhost:37358/tests/dromaeo/cssquery-jquery.html (next: http://localhost:37358/tests/dromaeo/cssquery-mootools.html) 12:49:52 INFO - PROCESS | 8637 | RSS: Main: 184602624 12:49:52 INFO - PROCESS | 8637 | 12:52:54 INFO - PROCESS | 8637 | Cycle 1(1): loaded http://localhost:37358/tests/dromaeo/cssquery-mootools.html (next: http://localhost:37358/tests/dromaeo/cssquery-prototype.html) 12:52:55 INFO - PROCESS | 8637 | RSS: Main: 195948544 12:52:55 INFO - PROCESS | 8637 | 12:55:57 INFO - PROCESS | 8637 | Cycle 1(1): loaded http://localhost:37358/tests/dromaeo/cssquery-prototype.html (next: http://localhost:37358/tests/dromaeo/cssquery-yui.html) 12:55:58 INFO - PROCESS | 8637 | RSS: Main: 184221696 12:55:58 INFO - PROCESS | 8637 | 12:59:00 INFO - PROCESS | 8637 | Cycle 1(1): loaded http://localhost:37358/tests/dromaeo/cssquery-yui.html (next: http://localhost:37358/tests/dromaeo/cssquery-dojo.html) 12:59:01 INFO - PROCESS | 8637 | RSS: Main: 265064448 12:59:01 INFO - PROCESS | 8637 | 12:59:01 INFO - PROCESS | 8637 | __start_tp_report 12:59:01 INFO - PROCESS | 8637 | _x_x_mozilla_page_load 12:59:01 INFO - PROCESS | 8637 | _x_x_mozilla_page_load_details 12:59:01 INFO - PROCESS | 8637 | |i|pagename|runs| 12:59:01 INFO - PROCESS | 8637 | |0;dojo.html;3209.8876539321122;3238.740900727941;3246.967530324696;3269.820159891205;3296.7197788188005;5697.715114244287;5770.653760774351;5783.826485205451;5789.04480760674;5795.797147099847;5647.915281270784;5696.601237913348;5708.800191993281;5714.057180565202;5721.485066344028;5167.328247327842;5168.5606723428555;5179;5243.528082472577;5258.2638430619745;5065.569426598743;5076.898462030757;5254.789808407659;5260.579153667697;5291.417944026154;5106.923396149061;5181.792728290883;5190.4031036430915;5217.713025783581;5232.895342093156;5208;5217.973910130444;5237.240600113;5246.8688282792855;5265.736713164365;4937.35814344133;5013.598912087061;5124.385073791132;5143.254228136937;5220.112580861225;5533.557315414798;5594.860128496779;5602.635828671123;5642.492175704166;5660.29246344207;5968.70156492178;5997.460228579449;6023.186869772569;6023.939760602426;6028.487578555828;5872.148538461899;6043.697815109227;6054.96972515139;6060.696965151725;6060.757569697207;5665.291838520185;5753.798617048383;5783.450572195635;5785.942140578582;5790.71046447676;6288.622682639057;6294.339094395023;6295.118683384329;6295.716692748837;6302.558820882494;5769.278840144982;5917.201177841022;6045.546584006217;6047.15339852421;6053;5975;6115.605440958429;6120.17287048654;6143.170671959318;6143.201383820075;6039.607425517327;6067.2112625359305;6082.330943596201;6087.239095113111;6097.298810636834;5876.1479585459865;5962.85092872673;6096.634201947809;6101.938980610226;6102.7558897644485;4574.176648203289;4637.536246375335;4637.837675681402;4638;4638.304254361805;4082.081531655413;4092.3656833190903;4115.42384066231;4134.255833949918;4148.8340466381005;5729.5702822288495;5732.914006289909;5739.684317362502;5756.884862302731;5775.624584402;6076.513878889678;6106.6336019838955;6129.754809807647;6134.938650613526;6135.417135372132;5515.862103447365;5630.774769009194;5663.546916246691;5720.427957204246;5747.109198074305;5263.105272103751;5266.868328291746;5270.868228294324;5275.498827611371;5301.204819277139;6216.813495595139;6254.8123556293385;6284.591501552384;6289.245290565162;6289.7484100635465;5837.445442682938;6006.579539432198;6064.848378790565;6122.051082082285;6139.29398119222;6272.059191121369;6291.96854015727;6296.150019747459;6298.685065746786;6301.96849015752;5977.252843394575;5988.131720900358;5994.996679433006;5997.370276121119;6036.275646922398;5819.447152520504;6010.879782404415;6121.8469538260115;6140.846478837886;6141.692915354304;5840.853978650568;5889.5582831286965;5953.28560572734;5984.1323008164445;5984.730687119177;5891.793787217427;5943;5943.58394912352;5944.851378715567;5948.8810223796145;5716.371199168171;5803.419658034163;5928.733207005781;5947.078202878561;5947.940520594739;5845.883082338414;5952.5237980960555;5980;5985;5987.910181347196;5780.682062486603;5792.82621521355;5794.304683438015;5797.304323481209;5827.067669172912 12:59:01 INFO - PROCESS | 8637 | |1;ext.html;638.3201889987163;667.0061608203777;670.2894931372742;678.043958019193;691.7371398868429;44997.550024499746;46796.53203467969;47212.763936180316;51344;51473.485265147334;47480;47563.28655070177;47899;48105;48111.27833082498;8706.738797836075;9432.764180895481;9583.856242156373;9590.472524011177;9590.520473976308;8713.912860871405;11205.439728013607;11457.140714446408;11498.482568284406;11901.166918315721;11661.883381166206;11768.588099416522;11783.646490605295;11788.646340609796;11812.527498900034;11500.42497875107;11553.364564948924;11567.132465065153;11567.884321156766;11569.421528923562;11326.093912486982;11396.60111896088;11413.144014198968;11416.372099534605;11421.31472111676;45578.77210613926;45901;45911;45939.310910336375;45941.77029114866;3762.322781899257;3896;4151.190517849021;4359.12817436514;4716.198246298138;35125.648743512495;35577;35900.28199436023;35964.460533092024;36975;4755.239161734141;4798.664093513456;4861.73260470674;4876.561109500162;4973.602111831081;383225;384138;384159;384567;385789.07105464576;39997.40003899944;66521.33478665151;66759.66620166868;66839;66886.66556667186;40199.79900100481;40200.79899600542;40296.597034029284;40421;40622.18755624871;16048.27782749779;16165;16174.27215775293;16175.514734557983;16192.676146477004;12098.637040888787;12791.936040319739;13070.934645326903;13254.337283135805;13313;9187.03536128697;9219.446833189895;9236.122568355995;9291.72124836256;9318.068193180628;3494.598121216042;3526.982365088158;3585.71314294861;3595.802230877327;3624.130208749881;1037.2998226197287;1089.096050278267;1089.3518356577824;1213.2295992044938;1229.8155276708565;34958.65041349554;35555.22521183332;36015.264430280964;36017.683642630465;36063.819680901426;1152.608113241502;1164.5807509296646;1199.7420554580808;1208.456194712383;1260.180882426412;18102.637947240983;18489.260429582668;18526.629467410577;18734.25062997465;18741.7188742169;605411;617097;618507;618638;620725;65333;65828.67085664636;65838.67080664662;65858.34141658522;65910.67044664746;1338.919664820114;1423.181670539444;1447.7321695486369;1458.2125652147722;1463.5609317204796;32182.517262240617;36407;36540.413589953336;36908.26183476369;36964.63035369612;54341.18488222601;54942.450575493735;54952.72523637516;54958;54998;422838;425543;425892;426058;426824;27501.724982749918;27735.30661733392;30154.547681784352;31565;31935.201619959687;51700.74149629228;51986.740066299426;53015.734921326686;53227.467725322254;53300.46699532955;45020.32469512895;45510.54489455063;45543.54456455525;45891;46956;43649.56350436582;45615.31577026413;45811.31283030824;46288.30567541557;46751.766241168574;44224;44636.776816115715;44640.55359446364;44977.55022449864;45187.54812451965;38350;40224.7988760066;40423.59576404198;40529;40903.59096409117;29974.400511990076;30019.69980300169;30086.24784380408;30478.238044049078;30660.080197594107 12:59:01 INFO - PROCESS | 8637 | |2;jquery.html;406.5365483348981;408.977506237157;419.2872117400419;421.33429181892615;431.10760725298627;19688.803111968875;20626.89686551567;21388.144474221033;21650.133994640197;21799.45501362467;20227.696584551206;20250;20402.081906314215;20879.060442280097;21052.157913683473;5879.294484661836;6125.693715314239;6125.785597504088;6131.6627585483;6132.8466788330425;6097.054956481752;6100.1459795628425;6117.051856962155;6137.079438084278;6147.231596050494;5923.318818335882;6047.969760151193;6055.303640081403;6056.091586262052;6058.727357268912;5782;5988.730507127189;6035.124906888521;6044.3955604439425;6130.662813545253;5088.898222035538;5300.655457395295;5315.229291752713;5953.464188223037;5962.314333851619;15499.767503487457;15856.682866342724;17672.734908976374;24781.87609061961;24950;2419.2258477287273;2428.4171798768343;2444.486657801846;2455.8035357171384;2477.454959908835;10840.837387439195;10902.291351062155;11009.2843965142;11079.50142243601;11525.020373268282;1856.1090676475233;1933.1590758020166;1962.2837664252588;2002.3592450415872;2009.357005758158;88228;88355.55822220848;89641.55179224061;91008.54495727479;96392.036079639;11604.94197529007;11624.244424112574;11641.253811928278;11736.823947640793;12487.438065287084;17123.74314385285;17228.397006104726;17232.827671723124;17236.396726114526;17268.740968885475;8465.449745766506;8623.655053797778;8801.603927823264;8902.955485222532;8962.82074358509;8332.958335208285;8362.45644033136;8376.83246335082;8526.275266602284;8599.269062129664;5089.083964886356;5113.335266415343;5157.7679004444135;5184.740762961912;5242.633015688865;2057.1565658080117;2149.21553632926;2154.7737487563895;2168.7722789107233;2177.444751588335;948.4309414351334;984.8128855517429;1020.5917632946881;1106.5573770491867;1151.792677318074;19342.613147736967;21343.8932805335;21391.8930405347;21513.78486215149;21594.428398860575;1003.7741508160751;1021.382063851374;1029.2949329709172;1034.5654824973528;1036.4403222260046;9405.717828465156;11257.211995160424;11777.17559770825;11962;11965.282083075042;539143;541633;555107;562146;572154;97376.51311743396;97601.51199244101;99339.50330248302;99771.50114249527;100072.49963750134;1024.6311327921942;1055.3562326980689;1062.4050531702271;1072.5066469423996;1084.7179733269406;17910.5522361942;18610.627787443904;18844.717329239797;18992.47249565697;19178.616427671648;42821.8589070539;43311.56688433075;43845.78077109594;44569.331460027475;44915.775421122686;59434.40565594289;59563.40436595752;59626.10560841678;59709.40290597038;60684;8202.138775428493;8208.46644968075;8332.833343332979;8342.874856877273;8510.74467765968;13846.861531384557;14542.85457145415;14659.267036648338;14815.703685926006;15070.397184112951;12441.377931103227;12737.235765854079;12765.170263932814;12941.870581294066;13080.411381488046;12529.310887901252;12673.683157921127;12865.742685146432;12875.484980601046;13035.087543871838;12865.099443038898;13100.148490348478;13103.14829536077;13323.13399629021;13365.26491043002;8256.463329883538;8429.957850210709;8468.57657117154;8624.870626940476;8998.100189980949;11244;12293.56972505958;12844.807327889903;12862.678433038875;12878.162919410208 12:59:01 INFO - PROCESS | 8637 | |3;mootools.html;1429.306786208688;1450.347343695337;1516.9544913652592;1654.2803880312065;1866.4773863318267;8737.475751454916;9117.589708463118;9165.12931271529;9463;9520.523973801299;9248.260139188866;9335.346525743184;9335.719928402159;9345.392549484279;9381.81236375272;2463.285647162321;2494.713107992577;2494.9875250623813;2501.7123030851412;2508.3603681061395;2393.868337241451;2493.763092506209;2495.413577809213;2497.3881399057163;2501.2871331670476;2410.2648692148887;2436.5248776488593;2446.437319416535;2452.2520631207476;2454.275988583372;2393.377721792338;2420.286015625386;2429.878506074698;2430.647556104355;2431.6230984197477;2366.7278262999803;2378.345954862427;2379.7263314718857;2381.7380088190284;2382.487765130488;9095.772605684911;9189.448633082036;9418.717438476857;9431.575579098888;9443.386179898353;5436.728163591844;5650.067738823089;5666.971665141689;5669.177969194444;5671.489565939044;3659.4693769403552;3663.3222853772136;3712.7029837612927;3725.9627403725885;3728.328900797855;1178.8938995490362;1189.9405029748477;1204.7168915304896;1220.45079714129;1232.7904256276454;12996.805047924194;13025.348732563334;13031.47874085026;13068.607941761762;13123.60629181126;4394.560543945579;4668.673192876534;4740.360051393087;4876.634252431082;4888;9754.804903901882;9760.951195243979;9776.31565790402;9777.46223957675;9811.558479868423;5476.26070480488;5478.315210598676;5483.177523371526;5485.862853428616;5490.917636235459;4383.07955329378;4850.296706977469;4975.203967365204;4977.850664480072;5072.188449848007;4035.8991025224605;4080.8979775505254;4171.311733563989;4173.770442625685;4194.2450358935075;1184.6268425445958;1186.5787645385765;1196.0431654676224;1204.7349583091707;1205.1142410328403;936.2697096264924;1067.9679609611724;1069.272894431794;1077.6982444915436;1091.879893211762;6308.274548426898;6430.006697923659;6576.224419406733;6598.208215014133;6614.371634694697;477.8470889315455;481.6917173009277;486.1832122035015;489.1000558971509;501.04050742827076;4338.7830608470085;4403.53762854902;4432.091421258699;4476.216662084187;4567.725936443824;11308.434578271053;11769.352685602375;11769.411529423494;11781.882181178249;11803.350815705218;11383.317000979967;11495.195336326544;11508.309501429774;11569.305841649528;11638.359890205951;8458.28104611115;8461.661533538712;8499.36254780894;8507.361947853935;8521.233089022018;6321.30465648769;6340.841478963062;6359.841003974938;6456.644884531396;6575.210974683068;10732.73168170802;10749.301295415773;10834.241603087708;10909.836352454879;10912.781744365227;10411.687649370531;10549.683509494727;10652.786944261226;10925.399103049409;11007;7335.559866408033;8009.078955919911;8104.351651867954;8181.100078991425;8259.297959673255;9043.457392556467;9096.317776166681;9115.316351273548;9120.224780893699;9154.542272886461;7879.975603171551;7896.802579935547;7979.880301795594;8036.401750542828;8159.8776018358585;7717.24967923531;7862.292393684367;7942.722004729807;7980.321672657657;8188.222118898695;7108;7227.891581626174;7235.963820180866;7239.239879812544;7246.130464344102;5488.17677348401;5606.24315717373;5643.068893632505;5672.177534257592;5673.3759286477625;5556.110622652041;5618.943810562006;5621.072523033654;5651.237082993744;5707.086866101404 12:59:01 INFO - PROCESS | 8637 | |4;prototype.html;343.9380911435942;350.90700964244496;351.6044449993757;353.0995960301232;359.9622039685833;5254.264402983586;5389.191621256813;5536.224928510007;5571.693556854371;5578.386377498477;5369.5167434930945;5395.757190926408;5415.864603384907;5431.728413579315;5462.968052730743;4299.473686841173;4333.306670932651;4347.174036932988;4387.319965405367;4390.363397307388;4252.80862361193;4347.826086956534;4365.148795984785;4405.88985275369;4430.446194225722;4254.680898932577;4309.159713855799;4328.437303150586;4330.588594083557;4337.761423121727;4306.181825453185;4313.503947046099;4319.287317592593;4325.178216138955;4327.437433133672;3576.123849656825;3866.3040652682507;3881.1655494068627;3888.377859542488;3907.5897030811925;5247.553957913582;5273.630845840793;5287.180487024518;5309.469053094698;5323.600729945231;4910.705357678551;4918.385201849769;4932.087563800708;4933.9638675878105;4963.950360496421;5490.3411590608985;5664.971675141639;5670.206171136044;5688.60179787415;5704.461514625993;4153.501579810412;4153.68847336451;4155.667546596265;4164.66682665386;4167.937480937789;5841.649501029951;5851.853703657443;5871.14868344092;5896.852578685524;5913.526917846519;4137.46212992309;4270.508891477458;4281.422008028876;4292.377605247221;4303.289957157034;4311.2239796836875;4323.178596066738;4323.373110898902;4325.805338759764;4344.45694288214;4856.805727770849;4874.95125048745;4891.388576427947;4900.411950565955;4904.8773780655765;5556.583256255797;5670.461306175906;5681.431856814285;5691.8292451226525;5722.656640601577;1472.1314424489387;1494;1497.6147063965789;1500.579837645461;1501.909885406879;927.536231884058;958.9089036541657;961.3606951377395;978.882534095913;979.9069088436587;4890.608751299887;4893.779779909913;4903.681260718042;4905.3132561441425;4909.661430227408;5123.718195499283;5127.436281859071;5127.846164615067;5145.382554093458;5177.223416487482;4640.32715256286;4871.171900776876;4907.2088687629475;4908.239222920453;4914.311996320518;2738.945221095567;2766.2807670005946;2769.4737999779977;2794.636697229347;2799.496090703693;5213.843584692465;5241.606879484054;5305.893882122413;5313.920291195635;5319.388270348882;4839.249916262985;5162.328897243409;5193.559506621859;5213.661112027706;5232.947670523322;5255.159174532057;5262.60530460217;5265.473452654781;5277.815276465305;5283.996040752245;5148.948510514997;5153.2270159476375;5169.96600679858;5188.573142385874;5204.687718736888;5098.668586541863;5350;5791.594588378773;5831.7667293307795;5847.970760146342;6011.158437818796;6021.608595441282;6029.78895738647;6031.487323577545;6057.666828324484;4028.5165780105403;4075.938860917148;4161.687873409567;4187.9320773202635;4278.786060696891;5801.810628821166;5810.1575271586225;5814.563907707024;5831.446012628793;5832.737526811219;5623.718814059199;5665.801696940587;5719.799807006735;5722.828315150552;5727;5646.689432081275;5655;5663.716814159358;5675.545956323402;5686.971565142313;5637.633553818989;5672.177534257427;5675.517581005661;5676.34722006958;5693.202951586864;5430.348358197041;5666.376698563237;5673.517750991212;5687.943120568907;5742.425757424224;5520.861978450571;5563.499285064364;5565.220869078414;5582.246396736388;5599.020171470058 12:59:01 INFO - PROCESS | 8637 | |5;yui.html;838.7903024243939;849.8937632795901;874.2612492443883;910.6448485090815;915.4049867586073;16585.58536036598;17805.198766055528;18011.09944502773;18017.99999999998;18027.098645067745;15800.288986995583;15972.440964566242;16015.958962667419;16134.919325403385;16716.66566668666;882.4087861133056;910.9979023074632;913.9223166030862;914.5518695839057;914.7392992996997;749.6926260233305;852.7100785732849;888.094143973147;908.2733812949647;909.8817153770001;943.0145497954654;946.6166202687888;947.1996163242084;948.1514044929784;948.5968463403061;942.0155937045735;943;943.5706753427174;943.7168849345203;943.782929926114;689.1316940654812;689.1902015132199;690.5131882023162;690.775497963159;692.1106378303855;16486.587835304093;16620.33518659252;16624.168791560496;16631.417900373428;16676;14330.49843255481;14343.856561434357;14478.92760536201;14601.123932563974;16136.838631613651;12235.32705701186;12423.813642795365;12467.251964882136;12525.624231273076;12678.619641410773;4020.43713880057;4133.966508372907;4148.585141485857;4204.138151678899;4225.218334608107;60087.399126009055;60666.39333606652;71494.28505714929;80886.5955670218;82950.17049829545;12072.3360215189;12218.389080545938;12547.121701480808;12576.37118144089;12634.178778379375;22653.546929061657;22821.429464263194;22907.88546057259;22919.656205156934;22932.541349173258;8438.113998030118;8632.266257368068;8779.736607901774;8995.865062024075;9271.351005429555;5020.221865610836;5075.162598171334;5076.82231121909;5078.13671675816;5174.767135478913;3936.645701886844;4072.0227145485464;4080.449139366207;4082.8979275517754;4091.3044782387065;694.4930200953332;696.6133795743411;721.6896734404152;722.3029776265823;728.7595093619093;779.574552388504;827.2554700769235;837.5979529825718;850.8511010573125;857.1942374168262;10156.949215254024;10261.846072308921;10360;10462.738431539272;10628.309159904582;693.7398475571661;761.6229966166783;783.9098503672151;805.255138996426;808.1231863428129;4647.209974304376;5079.492050794965;5091.1090559152;5161.483851614884;5221.347331583552;63911.68044159749;95769;97585;99777.50111249542;113249.43375283235;72887.63556182185;99675;100004.49997749965;103295.48352258191;105862;84636;85872;87646;89030;92255;9662.3236373456;9693.321467497208;9779.951100244452;9788;9796.706098817047;44900.55099448964;52350.47649523456;52416.21375679292;52993.470065298854;55483.445165547826;75862;76550;78627;79929.2007079945;82352.17647823445;13141.936894844972;13235.27206003679;13267.668308291984;13483;13517.86482135166;14364.066335688147;14383.424663013362;14460.710785784437;14619.19594422317;14864.108153510824;14273.357698903781;14460.566183014527;14552.49066282675;15136.848631513543;15409.460668876536;13609.183448993092;13750.312484375541;14031.859681403464;14066.78899816524;14080;9301.953490232505;12480.126391152533;12543.686407839878;13753.174809511462;14478.203698796668;11504.597339093092;11621.418929053345;11813.527458901548;12001.579944701894;12189.390530473263;11203.770125936631;12024.699382515508;12103.515859365527;12175.39123043862;12405.937970310091 12:59:01 INFO - PROCESS | 8637 | __end_tp_report 12:59:01 INFO - PROCESS | 8637 | __start_cc_report 12:59:01 INFO - PROCESS | 8637 | _x_x_mozilla_cycle_collect,949 12:59:01 INFO - PROCESS | 8637 | __end_cc_report 12:59:01 INFO - PROCESS | 8637 | __startTimestamp1476388741241__endTimestamp 12:59:01 INFO - PROCESS | 8637 | 12:59:01 INFO - PROCESS | 8637 | ------- Summary: start ------- 12:59:01 INFO - PROCESS | 8637 | Number of tests: 6 12:59:01 INFO - PROCESS | 8637 | 12:59:01 INFO - PROCESS | 8637 | [#0] dojo.html Cycles:165 Average:5640.27 Median:5819.45 stddev:644.49 (11.1%) stddev-sans-first:617.63 12:59:01 INFO - PROCESS | 8637 | Values: 3209.9 3238.7 3247.0 3269.8 3296.7 5697.7 5770.7 5783.8 5789.0 5795.8 5647.9 5696.6 5708.8 5714.1 5721.5 5167.3 5168.6 5179.0 5243.5 5258.3 5065.6 5076.9 5254.8 5260.6 5291.4 5106.9 5181.8 5190.4 5217.7 5232.9 5208.0 5218.0 5237.2 5246.9 5265.7 4937.4 5013.6 5124.4 5143.3 5220.1 5533.6 5594.9 5602.6 5642.5 5660.3 5968.7 5997.5 6023.2 6023.9 6028.5 5872.1 6043.7 6055.0 6060.7 6060.8 5665.3 5753.8 5783.5 5785.9 5790.7 6288.6 6294.3 6295.1 6295.7 6302.6 5769.3 5917.2 6045.5 6047.2 6053.0 5975.0 6115.6 6120.2 6143.2 6143.2 6039.6 6067.2 6082.3 6087.2 6097.3 5876.1 5962.9 6096.6 6101.9 6102.8 4574.2 4637.5 4637.8 4638.0 4638.3 4082.1 4092.4 4115.4 4134.3 4148.8 5729.6 5732.9 5739.7 5756.9 5775.6 6076.5 6106.6 6129.8 6134.9 6135.4 5515.9 5630.8 5663.5 5720.4 5747.1 5263.1 5266.9 5270.9 5275.5 5301.2 6216.8 6254.8 6284.6 6289.2 6289.7 5837.4 6006.6 6064.8 6122.1 6139.3 6272.1 6292.0 6296.2 6298.7 6302.0 5977.3 5988.1 5995.0 5997.4 6036.3 5819.4 6010.9 6121.8 6140.8 6141.7 5840.9 5889.6 5953.3 5984.1 5984.7 5891.8 5943.0 5943.6 5944.9 5948.9 5716.4 5803.4 5928.7 5947.1 5947.9 5845.9 5952.5 5980.0 5985.0 5987.9 5780.7 5792.8 5794.3 5797.3 5827.1 12:59:01 INFO - PROCESS | 8637 | 12:59:01 INFO - PROCESS | 8637 | [#1] ext.html Cycles:180 Average:64319.24 Median:40260.70 stddev:129104.07 (320.7%) stddev-sans-first:129377.71 12:59:01 INFO - PROCESS | 8637 | Values: 638.3 667.0 670.3 678.0 691.7 44997.6 46796.5 47212.8 51344.0 51473.5 47480.0 47563.3 47899.0 48105.0 48111.3 8706.7 9432.8 9583.9 9590.5 9590.5 8713.9 11205.4 11457.1 11498.5 11901.2 11661.9 11768.6 11783.6 11788.6 11812.5 11500.4 11553.4 11567.1 11567.9 11569.4 11326.1 11396.6 11413.1 11416.4 11421.3 45578.8 45901.0 45911.0 45939.3 45941.8 3762.3 3896.0 4151.2 4359.1 4716.2 35125.6 35577.0 35900.3 35964.5 36975.0 4755.2 4798.7 4861.7 4876.6 4973.6 383225.0 384138.0 384159.0 384567.0 385789.1 39997.4 66521.3 66759.7 66839.0 66886.7 40199.8 40200.8 40296.6 40421.0 40622.2 16048.3 16165.0 16174.3 16175.5 16192.7 12098.6 12791.9 13070.9 13254.3 13313.0 9187.0 9219.4 9236.1 9291.7 9318.1 3494.6 3527.0 3585.7 3595.8 3624.1 1037.3 1089.1 1089.4 1213.2 1229.8 34958.7 35555.2 36015.3 36017.7 36063.8 1152.6 1164.6 1199.7 1208.5 1260.2 18102.6 18489.3 18526.6 18734.3 18741.7 605411.0 617097.0 618507.0 618638.0 620725.0 65333.0 65828.7 65838.7 65858.3 65910.7 1338.9 1423.2 1447.7 1458.2 1463.6 32182.5 36407.0 36540.4 36908.3 36964.6 54341.2 54942.5 54952.7 54958.0 54998.0 422838.0 425543.0 425892.0 426058.0 426824.0 27501.7 27735.3 30154.5 31565.0 31935.2 51700.7 51986.7 53015.7 53227.5 53300.5 45020.3 45510.5 45543.5 45891.0 46956.0 43649.6 45615.3 45811.3 46288.3 46751.8 44224.0 44636.8 44640.6 44977.6 45187.5 38350.0 40224.8 40423.6 40529.0 40903.6 29974.4 30019.7 30086.2 30478.2 30660.1 12:59:01 INFO - PROCESS | 8637 | 12:59:01 INFO - PROCESS | 8637 | [#2] jquery.html Cycles:180 Average:31793.58 Median:12109.34 stddev:91292.47 (753.9%) stddev-sans-first:91518.15 12:59:01 INFO - PROCESS | 8637 | Values: 406.5 409.0 419.3 421.3 431.1 19688.8 20626.9 21388.1 21650.1 21799.5 20227.7 20250.0 20402.1 20879.1 21052.2 5879.3 6125.7 6125.8 6131.7 6132.8 6097.1 6100.1 6117.1 6137.1 6147.2 5923.3 6048.0 6055.3 6056.1 6058.7 5782.0 5988.7 6035.1 6044.4 6130.7 5088.9 5300.7 5315.2 5953.5 5962.3 15499.8 15856.7 17672.7 24781.9 24950.0 2419.2 2428.4 2444.5 2455.8 2477.5 10840.8 10902.3 11009.3 11079.5 11525.0 1856.1 1933.2 1962.3 2002.4 2009.4 88228.0 88355.6 89641.6 91008.5 96392.0 11604.9 11624.2 11641.3 11736.8 12487.4 17123.7 17228.4 17232.8 17236.4 17268.7 8465.4 8623.7 8801.6 8903.0 8962.8 8333.0 8362.5 8376.8 8526.3 8599.3 5089.1 5113.3 5157.8 5184.7 5242.6 2057.2 2149.2 2154.8 2168.8 2177.4 948.4 984.8 1020.6 1106.6 1151.8 19342.6 21343.9 21391.9 21513.8 21594.4 1003.8 1021.4 1029.3 1034.6 1036.4 9405.7 11257.2 11777.2 11962.0 11965.3 539143.0 541633.0 555107.0 562146.0 572154.0 97376.5 97601.5 99339.5 99771.5 100072.5 1024.6 1055.4 1062.4 1072.5 1084.7 17910.6 18610.6 18844.7 18992.5 19178.6 42821.9 43311.6 43845.8 44569.3 44915.8 59434.4 59563.4 59626.1 59709.4 60684.0 8202.1 8208.5 8332.8 8342.9 8510.7 13846.9 14542.9 14659.3 14815.7 15070.4 12441.4 12737.2 12765.2 12941.9 13080.4 12529.3 12673.7 12865.7 12875.5 13035.1 12865.1 13100.1 13103.1 13323.1 13365.3 8256.5 8430.0 8468.6 8624.9 8998.1 11244.0 12293.6 12844.8 12862.7 12878.2 12:59:01 INFO - PROCESS | 8637 | 12:59:01 INFO - PROCESS | 8637 | [#3] mootools.html Cycles:180 Average:6082.19 Median:2668.24 stddev:3477.35 (130.3%) stddev-sans-first:3469.53 12:59:01 INFO - PROCESS | 8637 | Values: 1429.3 1450.3 1517.0 1654.3 1866.5 8737.5 9117.6 9165.1 9463.0 9520.5 9248.3 9335.3 9335.7 9345.4 9381.8 2463.3 2494.7 2495.0 2501.7 2508.4 2393.9 2493.8 2495.4 2497.4 2501.3 2410.3 2436.5 2446.4 2452.3 2454.3 2393.4 2420.3 2429.9 2430.6 2431.6 2366.7 2378.3 2379.7 2381.7 2382.5 9095.8 9189.4 9418.7 9431.6 9443.4 5436.7 5650.1 5667.0 5669.2 5671.5 3659.5 3663.3 3712.7 3726.0 3728.3 1178.9 1189.9 1204.7 1220.5 1232.8 12996.8 13025.3 13031.5 13068.6 13123.6 4394.6 4668.7 4740.4 4876.6 4888.0 9754.8 9761.0 9776.3 9777.5 9811.6 5476.3 5478.3 5483.2 5485.9 5490.9 4383.1 4850.3 4975.2 4977.9 5072.2 4035.9 4080.9 4171.3 4173.8 4194.2 1184.6 1186.6 1196.0 1204.7 1205.1 936.3 1068.0 1069.3 1077.7 1091.9 6308.3 6430.0 6576.2 6598.2 6614.4 477.8 481.7 486.2 489.1 501.0 4338.8 4403.5 4432.1 4476.2 4567.7 11308.4 11769.4 11769.4 11781.9 11803.4 11383.3 11495.2 11508.3 11569.3 11638.4 8458.3 8461.7 8499.4 8507.4 8521.2 6321.3 6340.8 6359.8 6456.6 6575.2 10732.7 10749.3 10834.2 10909.8 10912.8 10411.7 10549.7 10652.8 10925.4 11007.0 7335.6 8009.1 8104.4 8181.1 8259.3 9043.5 9096.3 9115.3 9120.2 9154.5 7880.0 7896.8 7979.9 8036.4 8159.9 7717.2 7862.3 7942.7 7980.3 8188.2 7108.0 7227.9 7236.0 7239.2 7246.1 5488.2 5606.2 5643.1 5672.2 5673.4 5556.1 5618.9 5621.1 5651.2 5707.1 12:59:01 INFO - PROCESS | 8637 | 12:59:01 INFO - PROCESS | 8637 | [#4] prototype.html Cycles:180 Average:4672.52 Median:5182.90 stddev:1333.31 (25.7%) stddev-sans-first:1296.86 12:59:01 INFO - PROCESS | 8637 | Values: 343.9 350.9 351.6 353.1 360.0 5254.3 5389.2 5536.2 5571.7 5578.4 5369.5 5395.8 5415.9 5431.7 5463.0 4299.5 4333.3 4347.2 4387.3 4390.4 4252.8 4347.8 4365.1 4405.9 4430.4 4254.7 4309.2 4328.4 4330.6 4337.8 4306.2 4313.5 4319.3 4325.2 4327.4 3576.1 3866.3 3881.2 3888.4 3907.6 5247.6 5273.6 5287.2 5309.5 5323.6 4910.7 4918.4 4932.1 4934.0 4964.0 5490.3 5665.0 5670.2 5688.6 5704.5 4153.5 4153.7 4155.7 4164.7 4167.9 5841.6 5851.9 5871.1 5896.9 5913.5 4137.5 4270.5 4281.4 4292.4 4303.3 4311.2 4323.2 4323.4 4325.8 4344.5 4856.8 4875.0 4891.4 4900.4 4904.9 5556.6 5670.5 5681.4 5691.8 5722.7 1472.1 1494.0 1497.6 1500.6 1501.9 927.5 958.9 961.4 978.9 979.9 4890.6 4893.8 4903.7 4905.3 4909.7 5123.7 5127.4 5127.8 5145.4 5177.2 4640.3 4871.2 4907.2 4908.2 4914.3 2738.9 2766.3 2769.5 2794.6 2799.5 5213.8 5241.6 5305.9 5313.9 5319.4 4839.2 5162.3 5193.6 5213.7 5232.9 5255.2 5262.6 5265.5 5277.8 5284.0 5148.9 5153.2 5170.0 5188.6 5204.7 5098.7 5350.0 5791.6 5831.8 5848.0 6011.2 6021.6 6029.8 6031.5 6057.7 4028.5 4075.9 4161.7 4187.9 4278.8 5801.8 5810.2 5814.6 5831.4 5832.7 5623.7 5665.8 5719.8 5722.8 5727.0 5646.7 5655.0 5663.7 5675.5 5687.0 5637.6 5672.2 5675.5 5676.3 5693.2 5430.3 5666.4 5673.5 5687.9 5742.4 5520.9 5563.5 5565.2 5582.2 5599.0 12:59:01 INFO - PROCESS | 8637 | 12:59:01 INFO - PROCESS | 8637 | [#5] yui.html Cycles:180 Average:20566.55 Median:5047.69 stddev:28261.27 (559.9%) stddev-sans-first:28301.73 12:59:01 INFO - PROCESS | 8637 | Values: 838.8 849.9 874.3 910.6 915.4 16585.6 17805.2 18011.1 18018.0 18027.1 15800.3 15972.4 16016.0 16134.9 16716.7 882.4 911.0 913.9 914.6 914.7 749.7 852.7 888.1 908.3 909.9 943.0 946.6 947.2 948.2 948.6 942.0 943.0 943.6 943.7 943.8 689.1 689.2 690.5 690.8 692.1 16486.6 16620.3 16624.2 16631.4 16676.0 14330.5 14343.9 14478.9 14601.1 16136.8 12235.3 12423.8 12467.3 12525.6 12678.6 4020.4 4134.0 4148.6 4204.1 4225.2 60087.4 60666.4 71494.3 80886.6 82950.2 12072.3 12218.4 12547.1 12576.4 12634.2 22653.5 22821.4 22907.9 22919.7 22932.5 8438.1 8632.3 8779.7 8995.9 9271.4 5020.2 5075.2 5076.8 5078.1 5174.8 3936.6 4072.0 4080.4 4082.9 4091.3 694.5 696.6 721.7 722.3 728.8 779.6 827.3 837.6 850.9 857.2 10156.9 10261.8 10360.0 10462.7 10628.3 693.7 761.6 783.9 805.3 808.1 4647.2 5079.5 5091.1 5161.5 5221.3 63911.7 95769.0 97585.0 99777.5 113249.4 72887.6 99675.0 100004.5 103295.5 105862.0 84636.0 85872.0 87646.0 89030.0 92255.0 9662.3 9693.3 9780.0 9788.0 9796.7 44900.6 52350.5 52416.2 52993.5 55483.4 75862.0 76550.0 78627.0 79929.2 82352.2 13141.9 13235.3 13267.7 13483.0 13517.9 14364.1 14383.4 14460.7 14619.2 14864.1 14273.4 14460.6 14552.5 15136.8 15409.5 13609.2 13750.3 14031.9 14066.8 14080.0 9302.0 12480.1 12543.7 13753.2 14478.2 11504.6 11621.4 11813.5 12001.6 12189.4 11203.8 12024.7 12103.5 12175.4 12405.9 12:59:01 INFO - PROCESS | 8637 | -------- Summary: end -------- 12:59:01 INFO - PROCESS | 8637 | 12:59:01 INFO - TEST-INFO | 8637: exit 0 12:59:02 INFO - TEST-OK | dromaeo_css | took 1112469ms 12:59:02 INFO - TEST-START | kraken 12:59:02 INFO - operating with platform_type : linux_ 12:59:02 INFO - Initialising browser for kraken test... 12:59:02 INFO - TEST-INFO | started process 8829 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp_2Bu6w/profile http://localhost:37358/getInfo.html) 12:59:05 INFO - PROCESS | 8829 | __metrics Screen width/height:1600/1200 12:59:05 INFO - PROCESS | 8829 | colorDepth:24 12:59:05 INFO - PROCESS | 8829 | Browser inner width/height: 1024/697 12:59:05 INFO - PROCESS | 8829 | __metrics 12:59:06 INFO - PROCESS | 8829 | 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" 12:59:06 INFO - PROCESS | 8829 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 12:59:06 INFO - PROCESS | 8829 | console.error: PushService: 12:59:06 INFO - PROCESS | 8829 | stateChangeProcessEnqueue: Error transitioning state 12:59:06 INFO - PROCESS | 8829 | UnknownError 12:59:07 INFO - TEST-INFO | 8829: exit 0 12:59:07 INFO - Browser initialized. 12:59:07 INFO - Running cycle 1/1 for kraken test... 12:59:07 INFO - TEST-INFO | started process 8922 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp_2Bu6w/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 12:59:19 INFO - PROCESS | 8922 | RSS: Main: 144089088 12:59:19 INFO - PROCESS | 8922 | 12:59:21 INFO - PROCESS | 8922 | Cycle 1(1): loaded http://localhost:37358/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:37358/tests/kraken/driver.html?testName=audio-beat-detection) 12:59:22 INFO - PROCESS | 8922 | RSS: Main: 199028736 12:59:22 INFO - PROCESS | 8922 | 12:59:25 INFO - PROCESS | 8922 | Cycle 1(1): loaded http://localhost:37358/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:37358/tests/kraken/driver.html?testName=audio-dft) 12:59:25 INFO - PROCESS | 8922 | RSS: Main: 184066048 12:59:25 INFO - PROCESS | 8922 | 12:59:28 INFO - PROCESS | 8922 | Cycle 1(1): loaded http://localhost:37358/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:37358/tests/kraken/driver.html?testName=audio-fft) 12:59:29 INFO - PROCESS | 8922 | RSS: Main: 274874368 12:59:29 INFO - PROCESS | 8922 | 12:59:31 INFO - PROCESS | 8922 | Cycle 1(1): loaded http://localhost:37358/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:37358/tests/kraken/driver.html?testName=audio-oscillator) 12:59:31 INFO - PROCESS | 8922 | RSS: Main: 180293632 12:59:31 INFO - PROCESS | 8922 | 12:59:33 INFO - PROCESS | 8922 | Cycle 1(1): loaded http://localhost:37358/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:37358/tests/kraken/driver.html?testName=imaging-gaussian-blur) 12:59:34 INFO - PROCESS | 8922 | RSS: Main: 179372032 12:59:34 INFO - PROCESS | 8922 | 12:59:37 INFO - PROCESS | 8922 | Cycle 1(1): loaded http://localhost:37358/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:37358/tests/kraken/driver.html?testName=imaging-darkroom) 12:59:38 INFO - PROCESS | 8922 | RSS: Main: 215388160 12:59:38 INFO - PROCESS | 8922 | 12:59:41 INFO - PROCESS | 8922 | Cycle 1(1): loaded http://localhost:37358/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:37358/tests/kraken/driver.html?testName=imaging-desaturate) 12:59:42 INFO - PROCESS | 8922 | RSS: Main: 213835776 12:59:42 INFO - PROCESS | 8922 | 12:59:44 INFO - PROCESS | 8922 | Cycle 1(1): loaded http://localhost:37358/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:37358/tests/kraken/driver.html?testName=json-parse-financial) 12:59:45 INFO - PROCESS | 8922 | RSS: Main: 212729856 12:59:45 INFO - PROCESS | 8922 | 12:59:46 INFO - PROCESS | 8922 | Cycle 1(1): loaded http://localhost:37358/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:37358/tests/kraken/driver.html?testName=json-stringify-tinderbox) 12:59:47 INFO - PROCESS | 8922 | RSS: Main: 175968256 12:59:47 INFO - PROCESS | 8922 | 12:59:49 INFO - PROCESS | 8922 | Cycle 1(1): loaded http://localhost:37358/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:37358/tests/kraken/driver.html?testName=stanford-crypto-aes) 12:59:49 INFO - PROCESS | 8922 | RSS: Main: 183050240 12:59:49 INFO - PROCESS | 8922 | 12:59:51 INFO - PROCESS | 8922 | Cycle 1(1): loaded http://localhost:37358/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:37358/tests/kraken/driver.html?testName=stanford-crypto-ccm) 12:59:52 INFO - PROCESS | 8922 | RSS: Main: 189239296 12:59:52 INFO - PROCESS | 8922 | 12:59:54 INFO - PROCESS | 8922 | Cycle 1(1): loaded http://localhost:37358/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:37358/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 12:59:55 INFO - PROCESS | 8922 | RSS: Main: 192651264 12:59:55 INFO - PROCESS | 8922 | 12:59:58 INFO - PROCESS | 8922 | Cycle 1(1): loaded http://localhost:37358/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:37358/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 12:59:58 INFO - PROCESS | 8922 | RSS: Main: 193880064 12:59:58 INFO - PROCESS | 8922 | 13:00:00 INFO - PROCESS | 8922 | Cycle 1(1): loaded http://localhost:37358/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:37358/tests/kraken/driver.html?testName=ai-astar) 13:00:00 INFO - PROCESS | 8922 | RSS: Main: 243847168 13:00:00 INFO - PROCESS | 8922 | 13:00:00 INFO - PROCESS | 8922 | __start_tp_report 13:00:00 INFO - PROCESS | 8922 | _x_x_mozilla_page_load 13:00:00 INFO - PROCESS | 8922 | _x_x_mozilla_page_load_details 13:00:00 INFO - PROCESS | 8922 | |i|pagename|runs| 13:00:00 INFO - PROCESS | 8922 | |0;ai-astar;110;110;120;114;110;110;110;110;111;109 13:00:00 INFO - PROCESS | 8922 | |1;audio-beat-detection;141;117;144;182;116;142;142;118;178;116 13:00:00 INFO - PROCESS | 8922 | |2;audio-dft;161;162;162;163;161;161;161;160;161;161 13:00:00 INFO - PROCESS | 8922 | |3;audio-fft;75;76;75;173;76;74;75;75;169;74 13:00:00 INFO - PROCESS | 8922 | |4;audio-oscillator;83;83;144;84;83;83;145;83;83;150 13:00:00 INFO - PROCESS | 8922 | |5;imaging-gaussian-blur;116;117;118;116;116;116;116;117;117;116 13:00:00 INFO - PROCESS | 8922 | |6;imaging-darkroom;145;147;146;146;146;145;146;147;145;146 13:00:00 INFO - PROCESS | 8922 | |7;imaging-desaturate;95;95;96;95;94;95;95;95;95;95 13:00:00 INFO - PROCESS | 8922 | |8;json-parse-financial;51;49;49;49;49;52;50;49;49;50 13:00:00 INFO - PROCESS | 8922 | |9;json-stringify-tinderbox;59;60;60;59;60;60;59;60;60;61 13:00:00 INFO - PROCESS | 8922 | |10;stanford-crypto-aes;81;73;81;74;77;74;77;72;76;81 13:00:00 INFO - PROCESS | 8922 | |11;stanford-crypto-ccm;124;122;142;122;119;123;120;125;173;118 13:00:00 INFO - PROCESS | 8922 | |12;stanford-crypto-pbkdf2;153;154;155;156;153;152;154;154;153;153 13:00:00 INFO - PROCESS | 8922 | |13;stanford-crypto-sha256-iterative;63;64;60;67;61;62;62;60;61;61 13:00:00 INFO - PROCESS | 8922 | __end_tp_report 13:00:00 INFO - PROCESS | 8922 | __start_cc_report 13:00:00 INFO - PROCESS | 8922 | _x_x_mozilla_cycle_collect,1679 13:00:00 INFO - PROCESS | 8922 | __end_cc_report 13:00:00 INFO - PROCESS | 8922 | __startTimestamp1476388800717__endTimestamp 13:00:00 INFO - PROCESS | 8922 | 13:00:00 INFO - PROCESS | 8922 | ------- Summary: start ------- 13:00:00 INFO - PROCESS | 8922 | Number of tests: 14 13:00:00 INFO - PROCESS | 8922 | 13:00:00 INFO - PROCESS | 8922 | [#0] ai-astar Cycles:10 Average:111.40 Median:110.00 stddev:3.31 (3.0%) stddev-sans-first:3.47 13:00:00 INFO - PROCESS | 8922 | Values: 110.0 110.0 120.0 114.0 110.0 110.0 110.0 110.0 111.0 109.0 13:00:00 INFO - PROCESS | 8922 | 13:00:00 INFO - PROCESS | 8922 | [#1] audio-beat-detection Cycles:10 Average:139.60 Median:142.00 stddev:24.49 (17.2%) stddev-sans-first:25.97 13:00:00 INFO - PROCESS | 8922 | Values: 141.0 117.0 144.0 182.0 116.0 142.0 142.0 118.0 178.0 116.0 13:00:00 INFO - PROCESS | 8922 | 13:00:00 INFO - PROCESS | 8922 | [#2] audio-dft Cycles:10 Average:161.30 Median:161.00 stddev:0.82 (0.5%) stddev-sans-first:0.87 13:00:00 INFO - PROCESS | 8922 | Values: 161.0 162.0 162.0 163.0 161.0 161.0 161.0 160.0 161.0 161.0 13:00:00 INFO - PROCESS | 8922 | 13:00:00 INFO - PROCESS | 8922 | [#3] audio-fft Cycles:10 Average:94.20 Median:75.00 stddev:40.49 (54.0%) stddev-sans-first:42.35 13:00:00 INFO - PROCESS | 8922 | Values: 75.0 76.0 75.0 173.0 76.0 74.0 75.0 75.0 169.0 74.0 13:00:00 INFO - PROCESS | 8922 | 13:00:00 INFO - PROCESS | 8922 | [#4] audio-oscillator Cycles:10 Average:102.10 Median:83.00 stddev:30.56 (36.8%) stddev-sans-first:31.63 13:00:00 INFO - PROCESS | 8922 | Values: 83.0 83.0 144.0 84.0 83.0 83.0 145.0 83.0 83.0 150.0 13:00:00 INFO - PROCESS | 8922 | 13:00:00 INFO - PROCESS | 8922 | [#5] imaging-gaussian-blur Cycles:10 Average:116.50 Median:116.50 stddev:0.71 (0.6%) stddev-sans-first:0.73 13:00:00 INFO - PROCESS | 8922 | Values: 116.0 117.0 118.0 116.0 116.0 116.0 116.0 117.0 117.0 116.0 13:00:00 INFO - PROCESS | 8922 | 13:00:00 INFO - PROCESS | 8922 | [#6] imaging-darkroom Cycles:10 Average:145.90 Median:146.00 stddev:0.74 (0.5%) stddev-sans-first:0.71 13:00:00 INFO - PROCESS | 8922 | Values: 145.0 147.0 146.0 146.0 146.0 145.0 146.0 147.0 145.0 146.0 13:00:00 INFO - PROCESS | 8922 | 13:00:00 INFO - PROCESS | 8922 | [#7] imaging-desaturate Cycles:10 Average:95.00 Median:95.00 stddev:0.47 (0.5%) stddev-sans-first:0.50 13:00:00 INFO - PROCESS | 8922 | Values: 95.0 95.0 96.0 95.0 94.0 95.0 95.0 95.0 95.0 95.0 13:00:00 INFO - PROCESS | 8922 | 13:00:00 INFO - PROCESS | 8922 | [#8] json-parse-financial Cycles:10 Average:49.70 Median:49.50 stddev:1.06 (2.1%) stddev-sans-first:1.01 13:00:00 INFO - PROCESS | 8922 | Values: 51.0 49.0 49.0 49.0 49.0 52.0 50.0 49.0 49.0 50.0 13:00:00 INFO - PROCESS | 8922 | 13:00:00 INFO - PROCESS | 8922 | [#9] json-stringify-tinderbox Cycles:10 Average:59.80 Median:60.00 stddev:0.63 (1.1%) stddev-sans-first:0.60 13:00:00 INFO - PROCESS | 8922 | Values: 59.0 60.0 60.0 59.0 60.0 60.0 59.0 60.0 60.0 61.0 13:00:00 INFO - PROCESS | 8922 | 13:00:00 INFO - PROCESS | 8922 | [#10] stanford-crypto-aes Cycles:10 Average:76.60 Median:77.00 stddev:3.44 (4.5%) stddev-sans-first:3.26 13:00:00 INFO - PROCESS | 8922 | Values: 81.0 73.0 81.0 74.0 77.0 74.0 77.0 72.0 76.0 81.0 13:00:00 INFO - PROCESS | 8922 | 13:00:00 INFO - PROCESS | 8922 | [#11] stanford-crypto-ccm Cycles:10 Average:128.80 Median:123.50 stddev:16.94 (13.7%) stddev-sans-first:17.87 13:00:00 INFO - PROCESS | 8922 | Values: 124.0 122.0 142.0 122.0 119.0 123.0 120.0 125.0 173.0 118.0 13:00:00 INFO - PROCESS | 8922 | 13:00:00 INFO - PROCESS | 8922 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:153.70 Median:154.00 stddev:1.16 (0.8%) stddev-sans-first:1.20 13:00:00 INFO - PROCESS | 8922 | Values: 153.0 154.0 155.0 156.0 153.0 152.0 154.0 154.0 153.0 153.0 13:00:00 INFO - PROCESS | 8922 | 13:00:00 INFO - PROCESS | 8922 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:62.10 Median:62.00 stddev:2.13 (3.4%) stddev-sans-first:2.24 13:00:00 INFO - PROCESS | 8922 | Values: 63.0 64.0 60.0 67.0 61.0 62.0 62.0 60.0 61.0 61.0 13:00:00 INFO - PROCESS | 8922 | -------- Summary: end -------- 13:00:00 INFO - PROCESS | 8922 | 13:00:01 INFO - TEST-INFO | 8922: exit 0 13:00:01 INFO - TEST-OK | kraken | took 59848ms 13:00:01 INFO - SUITE-END | took 1172s 13:00:01 INFO - Completed test suite (00:19:32) 13:00:01 INFO - javascript benchmark 13:00:01 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3209.8876539321122, 3238.740900727941, 3246.967530324696, 3269.820159891205, 3296.7197788188005, 5697.715114244287, 5770.653760774351, 5783.826485205451, 5789.04480760674, 5795.797147099847, 5647.915281270784, 5696.601237913348, 5708.800191993281, 5714.057180565202, 5721.485066344028, 5167.328247327842, 5168.5606723428555, 5179.0, 5243.528082472577, 5258.2638430619745, 5065.569426598743, 5076.898462030757, 5254.789808407659, 5260.579153667697, 5291.417944026154, 5106.923396149061, 5181.792728290883, 5190.4031036430915, 5217.713025783581, 5232.895342093156, 5208.0, 5217.973910130444, 5237.240600113, 5246.8688282792855, 5265.736713164365, 4937.35814344133, 5013.598912087061, 5124.385073791132, 5143.254228136937, 5220.112580861225, 5533.557315414798, 5594.860128496779, 5602.635828671123, 5642.492175704166, 5660.29246344207, 5968.70156492178, 5997.460228579449, 6023.186869772569, 6023.939760602426, 6028.487578555828, 5872.148538461899, 6043.697815109227, 6054.96972515139, 6060.696965151725, 6060.757569697207, 5665.291838520185, 5753.798617048383, 5783.450572195635, 5785.942140578582, 5790.71046447676, 6288.622682639057, 6294.339094395023, 6295.118683384329, 6295.716692748837, 6302.558820882494, 5769.278840144982, 5917.201177841022, 6045.546584006217, 6047.15339852421, 6053.0, 5975.0, 6115.605440958429, 6120.17287048654, 6143.170671959318, 6143.201383820075, 6039.607425517327, 6067.2112625359305, 6082.330943596201, 6087.239095113111, 6097.298810636834, 5876.1479585459865, 5962.85092872673, 6096.634201947809, 6101.938980610226, 6102.7558897644485, 4574.176648203289, 4637.536246375335, 4637.837675681402, 4638.0, 4638.304254361805, 4082.081531655413, 4092.3656833190903, 4115.42384066231, 4134.255833949918, 4148.8340466381005, 5729.5702822288495, 5732.914006289909, 5739.684317362502, 5756.884862302731, 5775.624584402, 6076.513878889678, 6106.6336019838955, 6129.754809807647, 6134.938650613526, 6135.417135372132, 5515.862103447365, 5630.774769009194, 5663.546916246691, 5720.427957204246, 5747.109198074305, 5263.105272103751, 5266.868328291746, 5270.868228294324, 5275.498827611371, 5301.204819277139, 6216.813495595139, 6254.8123556293385, 6284.591501552384, 6289.245290565162, 6289.7484100635465, 5837.445442682938, 6006.579539432198, 6064.848378790565, 6122.051082082285, 6139.29398119222, 6272.059191121369, 6291.96854015727, 6296.150019747459, 6298.685065746786, 6301.96849015752, 5977.252843394575, 5988.131720900358, 5994.996679433006, 5997.370276121119, 6036.275646922398, 5819.447152520504, 6010.879782404415, 6121.8469538260115, 6140.846478837886, 6141.692915354304, 5840.853978650568, 5889.5582831286965, 5953.28560572734, 5984.1323008164445, 5984.730687119177, 5891.793787217427, 5943.0, 5943.58394912352, 5944.851378715567, 5948.8810223796145, 5716.371199168171, 5803.419658034163, 5928.733207005781, 5947.078202878561, 5947.940520594739, 5845.883082338414, 5952.5237980960555, 5980.0, 5985.0, 5987.910181347196, 5780.682062486603, 5792.82621521355, 5794.304683438015, 5797.304323481209, 5827.067669172912], "value": 5596.209167554889, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [638.3201889987163, 667.0061608203777, 670.2894931372742, 678.043958019193, 691.7371398868429, 44997.550024499746, 46796.53203467969, 47212.763936180316, 51344.0, 51473.485265147334, 47480.0, 47563.28655070177, 47899.0, 48105.0, 48111.27833082498, 8706.738797836075, 9432.764180895481, 9583.856242156373, 9590.472524011177, 9590.520473976308, 8713.912860871405, 11205.439728013607, 11457.140714446408, 11498.482568284406, 11901.166918315721, 11661.883381166206, 11768.588099416522, 11783.646490605295, 11788.646340609796, 11812.527498900034, 11500.42497875107, 11553.364564948924, 11567.132465065153, 11567.884321156766, 11569.421528923562, 11326.093912486982, 11396.60111896088, 11413.144014198968, 11416.372099534605, 11421.31472111676, 45578.77210613926, 45901.0, 45911.0, 45939.310910336375, 45941.77029114866, 3762.322781899257, 3896.0, 4151.190517849021, 4359.12817436514, 4716.198246298138, 35125.648743512495, 35577.0, 35900.28199436023, 35964.460533092024, 36975.0, 4755.239161734141, 4798.664093513456, 4861.73260470674, 4876.561109500162, 4973.602111831081, 383225.0, 384138.0, 384159.0, 384567.0, 385789.07105464576, 39997.40003899944, 66521.33478665151, 66759.66620166868, 66839.0, 66886.66556667186, 40199.79900100481, 40200.79899600542, 40296.597034029284, 40421.0, 40622.18755624871, 16048.27782749779, 16165.0, 16174.27215775293, 16175.514734557983, 16192.676146477004, 12098.637040888787, 12791.936040319739, 13070.934645326903, 13254.337283135805, 13313.0, 9187.03536128697, 9219.446833189895, 9236.122568355995, 9291.72124836256, 9318.068193180628, 3494.598121216042, 3526.982365088158, 3585.71314294861, 3595.802230877327, 3624.130208749881, 1037.2998226197287, 1089.096050278267, 1089.3518356577824, 1213.2295992044938, 1229.8155276708565, 34958.65041349554, 35555.22521183332, 36015.264430280964, 36017.683642630465, 36063.819680901426, 1152.608113241502, 1164.5807509296646, 1199.7420554580808, 1208.456194712383, 1260.180882426412, 18102.637947240983, 18489.260429582668, 18526.629467410577, 18734.25062997465, 18741.7188742169, 605411.0, 617097.0, 618507.0, 618638.0, 620725.0, 65333.0, 65828.67085664636, 65838.67080664662, 65858.34141658522, 65910.67044664746, 1338.919664820114, 1423.181670539444, 1447.7321695486369, 1458.2125652147722, 1463.5609317204796, 32182.517262240617, 36407.0, 36540.413589953336, 36908.26183476369, 36964.63035369612, 54341.18488222601, 54942.450575493735, 54952.72523637516, 54958.0, 54998.0, 422838.0, 425543.0, 425892.0, 426058.0, 426824.0, 27501.724982749918, 27735.30661733392, 30154.547681784352, 31565.0, 31935.201619959687, 51700.74149629228, 51986.740066299426, 53015.734921326686, 53227.467725322254, 53300.46699532955, 45020.32469512895, 45510.54489455063, 45543.54456455525, 45891.0, 46956.0, 43649.56350436582, 45615.31577026413, 45811.31283030824, 46288.30567541557, 46751.766241168574, 44224.0, 44636.776816115715, 44640.55359446364, 44977.55022449864, 45187.54812451965, 38350.0, 40224.7988760066, 40423.59576404198, 40529.0, 40903.59096409117, 29974.400511990076, 30019.69980300169, 30086.24784380408, 30478.238044049078, 30660.080197594107], "value": 21024.552662392412, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [406.5365483348981, 408.977506237157, 419.2872117400419, 421.33429181892615, 431.10760725298627, 19688.803111968875, 20626.89686551567, 21388.144474221033, 21650.133994640197, 21799.45501362467, 20227.696584551206, 20250.0, 20402.081906314215, 20879.060442280097, 21052.157913683473, 5879.294484661836, 6125.693715314239, 6125.785597504088, 6131.6627585483, 6132.8466788330425, 6097.054956481752, 6100.1459795628425, 6117.051856962155, 6137.079438084278, 6147.231596050494, 5923.318818335882, 6047.969760151193, 6055.303640081403, 6056.091586262052, 6058.727357268912, 5782.0, 5988.730507127189, 6035.124906888521, 6044.3955604439425, 6130.662813545253, 5088.898222035538, 5300.655457395295, 5315.229291752713, 5953.464188223037, 5962.314333851619, 15499.767503487457, 15856.682866342724, 17672.734908976374, 24781.87609061961, 24950.0, 2419.2258477287273, 2428.4171798768343, 2444.486657801846, 2455.8035357171384, 2477.454959908835, 10840.837387439195, 10902.291351062155, 11009.2843965142, 11079.50142243601, 11525.020373268282, 1856.1090676475233, 1933.1590758020166, 1962.2837664252588, 2002.3592450415872, 2009.357005758158, 88228.0, 88355.55822220848, 89641.55179224061, 91008.54495727479, 96392.036079639, 11604.94197529007, 11624.244424112574, 11641.253811928278, 11736.823947640793, 12487.438065287084, 17123.74314385285, 17228.397006104726, 17232.827671723124, 17236.396726114526, 17268.740968885475, 8465.449745766506, 8623.655053797778, 8801.603927823264, 8902.955485222532, 8962.82074358509, 8332.958335208285, 8362.45644033136, 8376.83246335082, 8526.275266602284, 8599.269062129664, 5089.083964886356, 5113.335266415343, 5157.7679004444135, 5184.740762961912, 5242.633015688865, 2057.1565658080117, 2149.21553632926, 2154.7737487563895, 2168.7722789107233, 2177.444751588335, 948.4309414351334, 984.8128855517429, 1020.5917632946881, 1106.5573770491867, 1151.792677318074, 19342.613147736967, 21343.8932805335, 21391.8930405347, 21513.78486215149, 21594.428398860575, 1003.7741508160751, 1021.382063851374, 1029.2949329709172, 1034.5654824973528, 1036.4403222260046, 9405.717828465156, 11257.211995160424, 11777.17559770825, 11962.0, 11965.282083075042, 539143.0, 541633.0, 555107.0, 562146.0, 572154.0, 97376.51311743396, 97601.51199244101, 99339.50330248302, 99771.50114249527, 100072.49963750134, 1024.6311327921942, 1055.3562326980689, 1062.4050531702271, 1072.5066469423996, 1084.7179733269406, 17910.5522361942, 18610.627787443904, 18844.717329239797, 18992.47249565697, 19178.616427671648, 42821.8589070539, 43311.56688433075, 43845.78077109594, 44569.331460027475, 44915.775421122686, 59434.40565594289, 59563.40436595752, 59626.10560841678, 59709.40290597038, 60684.0, 8202.138775428493, 8208.46644968075, 8332.833343332979, 8342.874856877273, 8510.74467765968, 13846.861531384557, 14542.85457145415, 14659.267036648338, 14815.703685926006, 15070.397184112951, 12441.377931103227, 12737.235765854079, 12765.170263932814, 12941.870581294066, 13080.411381488046, 12529.310887901252, 12673.683157921127, 12865.742685146432, 12875.484980601046, 13035.087543871838, 12865.099443038898, 13100.148490348478, 13103.14829536077, 13323.13399629021, 13365.26491043002, 8256.463329883538, 8429.957850210709, 8468.57657117154, 8624.870626940476, 8998.100189980949, 11244.0, 12293.56972505958, 12844.807327889903, 12862.678433038875, 12878.162919410208], "value": 9765.310206891478, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1429.306786208688, 1450.347343695337, 1516.9544913652592, 1654.2803880312065, 1866.4773863318267, 8737.475751454916, 9117.589708463118, 9165.12931271529, 9463.0, 9520.523973801299, 9248.260139188866, 9335.346525743184, 9335.719928402159, 9345.392549484279, 9381.81236375272, 2463.285647162321, 2494.713107992577, 2494.9875250623813, 2501.7123030851412, 2508.3603681061395, 2393.868337241451, 2493.763092506209, 2495.413577809213, 2497.3881399057163, 2501.2871331670476, 2410.2648692148887, 2436.5248776488593, 2446.437319416535, 2452.2520631207476, 2454.275988583372, 2393.377721792338, 2420.286015625386, 2429.878506074698, 2430.647556104355, 2431.6230984197477, 2366.7278262999803, 2378.345954862427, 2379.7263314718857, 2381.7380088190284, 2382.487765130488, 9095.772605684911, 9189.448633082036, 9418.717438476857, 9431.575579098888, 9443.386179898353, 5436.728163591844, 5650.067738823089, 5666.971665141689, 5669.177969194444, 5671.489565939044, 3659.4693769403552, 3663.3222853772136, 3712.7029837612927, 3725.9627403725885, 3728.328900797855, 1178.8938995490362, 1189.9405029748477, 1204.7168915304896, 1220.45079714129, 1232.7904256276454, 12996.805047924194, 13025.348732563334, 13031.47874085026, 13068.607941761762, 13123.60629181126, 4394.560543945579, 4668.673192876534, 4740.360051393087, 4876.634252431082, 4888.0, 9754.804903901882, 9760.951195243979, 9776.31565790402, 9777.46223957675, 9811.558479868423, 5476.26070480488, 5478.315210598676, 5483.177523371526, 5485.862853428616, 5490.917636235459, 4383.07955329378, 4850.296706977469, 4975.203967365204, 4977.850664480072, 5072.188449848007, 4035.8991025224605, 4080.8979775505254, 4171.311733563989, 4173.770442625685, 4194.2450358935075, 1184.6268425445958, 1186.5787645385765, 1196.0431654676224, 1204.7349583091707, 1205.1142410328403, 936.2697096264924, 1067.9679609611724, 1069.272894431794, 1077.6982444915436, 1091.879893211762, 6308.274548426898, 6430.006697923659, 6576.224419406733, 6598.208215014133, 6614.371634694697, 477.8470889315455, 481.6917173009277, 486.1832122035015, 489.1000558971509, 501.04050742827076, 4338.7830608470085, 4403.53762854902, 4432.091421258699, 4476.216662084187, 4567.725936443824, 11308.434578271053, 11769.352685602375, 11769.411529423494, 11781.882181178249, 11803.350815705218, 11383.317000979967, 11495.195336326544, 11508.309501429774, 11569.305841649528, 11638.359890205951, 8458.28104611115, 8461.661533538712, 8499.36254780894, 8507.361947853935, 8521.233089022018, 6321.30465648769, 6340.841478963062, 6359.841003974938, 6456.644884531396, 6575.210974683068, 10732.73168170802, 10749.301295415773, 10834.241603087708, 10909.836352454879, 10912.781744365227, 10411.687649370531, 10549.683509494727, 10652.786944261226, 10925.399103049409, 11007.0, 7335.559866408033, 8009.078955919911, 8104.351651867954, 8181.100078991425, 8259.297959673255, 9043.457392556467, 9096.317776166681, 9115.316351273548, 9120.224780893699, 9154.542272886461, 7879.975603171551, 7896.802579935547, 7979.880301795594, 8036.401750542828, 8159.8776018358585, 7717.24967923531, 7862.292393684367, 7942.722004729807, 7980.321672657657, 8188.222118898695, 7108.0, 7227.891581626174, 7235.963820180866, 7239.239879812544, 7246.130464344102, 5488.17677348401, 5606.24315717373, 5643.068893632505, 5672.177534257592, 5673.3759286477625, 5556.110622652041, 5618.943810562006, 5621.072523033654, 5651.237082993744, 5707.086866101404], "value": 4779.739932322912, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [343.9380911435942, 350.90700964244496, 351.6044449993757, 353.0995960301232, 359.9622039685833, 5254.264402983586, 5389.191621256813, 5536.224928510007, 5571.693556854371, 5578.386377498477, 5369.5167434930945, 5395.757190926408, 5415.864603384907, 5431.728413579315, 5462.968052730743, 4299.473686841173, 4333.306670932651, 4347.174036932988, 4387.319965405367, 4390.363397307388, 4252.80862361193, 4347.826086956534, 4365.148795984785, 4405.88985275369, 4430.446194225722, 4254.680898932577, 4309.159713855799, 4328.437303150586, 4330.588594083557, 4337.761423121727, 4306.181825453185, 4313.503947046099, 4319.287317592593, 4325.178216138955, 4327.437433133672, 3576.123849656825, 3866.3040652682507, 3881.1655494068627, 3888.377859542488, 3907.5897030811925, 5247.553957913582, 5273.630845840793, 5287.180487024518, 5309.469053094698, 5323.600729945231, 4910.705357678551, 4918.385201849769, 4932.087563800708, 4933.9638675878105, 4963.950360496421, 5490.3411590608985, 5664.971675141639, 5670.206171136044, 5688.60179787415, 5704.461514625993, 4153.501579810412, 4153.68847336451, 4155.667546596265, 4164.66682665386, 4167.937480937789, 5841.649501029951, 5851.853703657443, 5871.14868344092, 5896.852578685524, 5913.526917846519, 4137.46212992309, 4270.508891477458, 4281.422008028876, 4292.377605247221, 4303.289957157034, 4311.2239796836875, 4323.178596066738, 4323.373110898902, 4325.805338759764, 4344.45694288214, 4856.805727770849, 4874.95125048745, 4891.388576427947, 4900.411950565955, 4904.8773780655765, 5556.583256255797, 5670.461306175906, 5681.431856814285, 5691.8292451226525, 5722.656640601577, 1472.1314424489387, 1494.0, 1497.6147063965789, 1500.579837645461, 1501.909885406879, 927.536231884058, 958.9089036541657, 961.3606951377395, 978.882534095913, 979.9069088436587, 4890.608751299887, 4893.779779909913, 4903.681260718042, 4905.3132561441425, 4909.661430227408, 5123.718195499283, 5127.436281859071, 5127.846164615067, 5145.382554093458, 5177.223416487482, 4640.32715256286, 4871.171900776876, 4907.2088687629475, 4908.239222920453, 4914.311996320518, 2738.945221095567, 2766.2807670005946, 2769.4737999779977, 2794.636697229347, 2799.496090703693, 5213.843584692465, 5241.606879484054, 5305.893882122413, 5313.920291195635, 5319.388270348882, 4839.249916262985, 5162.328897243409, 5193.559506621859, 5213.661112027706, 5232.947670523322, 5255.159174532057, 5262.60530460217, 5265.473452654781, 5277.815276465305, 5283.996040752245, 5148.948510514997, 5153.2270159476375, 5169.96600679858, 5188.573142385874, 5204.687718736888, 5098.668586541863, 5350.0, 5791.594588378773, 5831.7667293307795, 5847.970760146342, 6011.158437818796, 6021.608595441282, 6029.78895738647, 6031.487323577545, 6057.666828324484, 4028.5165780105403, 4075.938860917148, 4161.687873409567, 4187.9320773202635, 4278.786060696891, 5801.810628821166, 5810.1575271586225, 5814.563907707024, 5831.446012628793, 5832.737526811219, 5623.718814059199, 5665.801696940587, 5719.799807006735, 5722.828315150552, 5727.0, 5646.689432081275, 5655.0, 5663.716814159358, 5675.545956323402, 5686.971565142313, 5637.633553818989, 5672.177534257427, 5675.517581005661, 5676.34722006958, 5693.202951586864, 5430.348358197041, 5666.376698563237, 5673.517750991212, 5687.943120568907, 5742.425757424224, 5520.861978450571, 5563.499285064364, 5565.220869078414, 5582.246396736388, 5599.020171470058], "value": 4253.9197667941535, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [838.7903024243939, 849.8937632795901, 874.2612492443883, 910.6448485090815, 915.4049867586073, 16585.58536036598, 17805.198766055528, 18011.09944502773, 18017.99999999998, 18027.098645067745, 15800.288986995583, 15972.440964566242, 16015.958962667419, 16134.919325403385, 16716.66566668666, 882.4087861133056, 910.9979023074632, 913.9223166030862, 914.5518695839057, 914.7392992996997, 749.6926260233305, 852.7100785732849, 888.094143973147, 908.2733812949647, 909.8817153770001, 943.0145497954654, 946.6166202687888, 947.1996163242084, 948.1514044929784, 948.5968463403061, 942.0155937045735, 943.0, 943.5706753427174, 943.7168849345203, 943.782929926114, 689.1316940654812, 689.1902015132199, 690.5131882023162, 690.775497963159, 692.1106378303855, 16486.587835304093, 16620.33518659252, 16624.168791560496, 16631.417900373428, 16676.0, 14330.49843255481, 14343.856561434357, 14478.92760536201, 14601.123932563974, 16136.838631613651, 12235.32705701186, 12423.813642795365, 12467.251964882136, 12525.624231273076, 12678.619641410773, 4020.43713880057, 4133.966508372907, 4148.585141485857, 4204.138151678899, 4225.218334608107, 60087.399126009055, 60666.39333606652, 71494.28505714929, 80886.5955670218, 82950.17049829545, 12072.3360215189, 12218.389080545938, 12547.121701480808, 12576.37118144089, 12634.178778379375, 22653.546929061657, 22821.429464263194, 22907.88546057259, 22919.656205156934, 22932.541349173258, 8438.113998030118, 8632.266257368068, 8779.736607901774, 8995.865062024075, 9271.351005429555, 5020.221865610836, 5075.162598171334, 5076.82231121909, 5078.13671675816, 5174.767135478913, 3936.645701886844, 4072.0227145485464, 4080.449139366207, 4082.8979275517754, 4091.3044782387065, 694.4930200953332, 696.6133795743411, 721.6896734404152, 722.3029776265823, 728.7595093619093, 779.574552388504, 827.2554700769235, 837.5979529825718, 850.8511010573125, 857.1942374168262, 10156.949215254024, 10261.846072308921, 10360.0, 10462.738431539272, 10628.309159904582, 693.7398475571661, 761.6229966166783, 783.9098503672151, 805.255138996426, 808.1231863428129, 4647.209974304376, 5079.492050794965, 5091.1090559152, 5161.483851614884, 5221.347331583552, 63911.68044159749, 95769.0, 97585.0, 99777.50111249542, 113249.43375283235, 72887.63556182185, 99675.0, 100004.49997749965, 103295.48352258191, 105862.0, 84636.0, 85872.0, 87646.0, 89030.0, 92255.0, 9662.3236373456, 9693.321467497208, 9779.951100244452, 9788.0, 9796.706098817047, 44900.55099448964, 52350.47649523456, 52416.21375679292, 52993.470065298854, 55483.445165547826, 75862.0, 76550.0, 78627.0, 79929.2007079945, 82352.17647823445, 13141.936894844972, 13235.27206003679, 13267.668308291984, 13483.0, 13517.86482135166, 14364.066335688147, 14383.424663013362, 14460.710785784437, 14619.19594422317, 14864.108153510824, 14273.357698903781, 14460.566183014527, 14552.49066282675, 15136.848631513543, 15409.460668876536, 13609.183448993092, 13750.312484375541, 14031.859681403464, 14066.78899816524, 14080.0, 9301.953490232505, 12480.126391152533, 12543.686407839878, 13753.174809511462, 14478.203698796668, 11504.597339093092, 11621.418929053345, 11813.527458901548, 12001.579944701894, 12189.390530473263, 11203.770125936631, 12024.699382515508, 12103.515859365527, 12175.39123043862, 12405.937970310091], "value": 7983.126034059093, "unit": "score"}], "name": "dromaeo_css", "value": 7558.804390597932, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [110.0, 110.0, 120.0, 114.0, 110.0, 110.0, 110.0, 110.0, 111.0, 109.0], "value": 111.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [141.0, 117.0, 144.0, 182.0, 116.0, 142.0, 142.0, 118.0, 178.0, 116.0], "value": 139.6, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 162.0, 162.0, 163.0, 161.0, 161.0, 161.0, 160.0, 161.0, 161.0], "value": 161.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 76.0, 75.0, 173.0, 76.0, 74.0, 75.0, 75.0, 169.0, 74.0], "value": 94.2, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 83.0, 144.0, 84.0, 83.0, 83.0, 145.0, 83.0, 83.0, 150.0], "value": 102.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 117.0, 118.0, 116.0, 116.0, 116.0, 116.0, 117.0, 117.0, 116.0], "value": 116.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 147.0, 146.0, 146.0, 146.0, 145.0, 146.0, 147.0, 145.0, 146.0], "value": 145.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 96.0, 95.0, 94.0, 95.0, 95.0, 95.0, 95.0, 95.0], "value": 95.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [51.0, 49.0, 49.0, 49.0, 49.0, 52.0, 50.0, 49.0, 49.0, 50.0], "value": 49.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 60.0, 60.0, 59.0, 60.0, 60.0, 59.0, 60.0, 60.0, 61.0], "value": 59.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 73.0, 81.0, 74.0, 77.0, 74.0, 77.0, 72.0, 76.0, 81.0], "value": 76.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [124.0, 122.0, 142.0, 122.0, 119.0, 123.0, 120.0, 125.0, 173.0, 118.0], "value": 128.8, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [153.0, 154.0, 155.0, 156.0, 153.0, 152.0, 154.0, 154.0, 153.0, 153.0], "value": 153.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 64.0, 60.0, 67.0, 61.0, 62.0, 62.0, 60.0, 61.0, 61.0], "value": 62.1, "unit": "score"}], "name": "kraken", "value": 1496.6999999999998, "alertThreshold": 2.0}]} 13:00:01 INFO - Return code: 0 13:00:02 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 13:00:02 INFO - # TBPL SUCCESS # 13:00:02 INFO - Running post-action listener: _resource_record_post_action 13:00:02 INFO - [mozharness: 2016-10-13 20:00:02.010261Z] Finished run-tests step (success) 13:00:02 INFO - Running post-run listener: _resource_record_post_run 13:00:02 INFO - Total resource usage - Wall time: 1186s; CPU: 13.0%; Read bytes: 4096; Write bytes: 290324480; Read time: 12; Write time: 270736 13:00:02 INFO - TinderboxPrint: CPU usage
13.0% 13:00:02 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 12 13:00:02 INFO - TinderboxPrint: I/O write bytes / time
290,324,480 / 270,736 13:00:02 INFO - TinderboxPrint: CPU idle
8,240.3 (87.0%) 13:00:02 INFO - TinderboxPrint: CPU user
1,183.9 (12.5%) 13:00:02 INFO - TinderboxPrint: Swap in / out
0 / 0 13:00:02 INFO - install - Wall time: 14s; CPU: 13.0%; Read bytes: 0; Write bytes: 70098944; Read time: 0; Write time: 101148 13:00:02 INFO - run-tests - Wall time: 1173s; CPU: 13.0%; Read bytes: 4096; Write bytes: 220225536; Read time: 12; Write time: 169588 13:00:02 INFO - Running post-run listener: _upload_blobber_files 13:00:02 INFO - Blob upload gear active. 13:00:02 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 13:00:02 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 13:00:02 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'] 13:00:02 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 13:00:03 INFO - (blobuploader) - INFO - Open directory for files ... 13:00:03 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 13:00:03 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 13:00:03 INFO - (blobuploader) - INFO - Uploading, attempt #1. 13:00:08 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 13:00:08 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 13:00:08 INFO - (blobuploader) - INFO - Done attempting. 13:00:08 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 13:00:08 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 13:00:08 INFO - (blobuploader) - INFO - Uploading, attempt #1. 13:00:12 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 13:00:12 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 13:00:12 INFO - (blobuploader) - INFO - Done attempting. 13:00:12 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 13:00:12 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 13:00:12 INFO - (blobuploader) - INFO - Uploading, attempt #1. 13:00:12 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 13:00:12 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 13:00:12 INFO - (blobuploader) - INFO - Done attempting. 13:00:12 INFO - (blobuploader) - INFO - Iteration through files over. 13:00:12 INFO - Return code: 0 13:00:12 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 13:00:12 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 13:00:12 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/fb20445968fa6038d74a9cfb6fd3d5fe9761c305e2e1b50bc3afda76a71596001bec5edffcc96622856aa576fc3cbbe5befb188aaa4dfe83c9e56fe5a0bc0973", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/f551c6a17f9e074e8c870a1eec10a548e0265543e57cad45b24630e52575684c93df74b0699fc90ba927aed34080032dcf5761277894d4aa9d377d3009e60f8d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/309e83ab997bbc9b2a17b97794d659e5baad27d719a22feffb9b7d58b7301f6a69e49f16164db1b375d19695224a9ecfa4158a0fde33b17f1757f57855fbe56c"} 13:00:12 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 13:00:12 INFO - Writing to file /builds/slave/test/properties/blobber_files 13:00:12 INFO - Contents: 13:00:12 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/fb20445968fa6038d74a9cfb6fd3d5fe9761c305e2e1b50bc3afda76a71596001bec5edffcc96622856aa576fc3cbbe5befb188aaa4dfe83c9e56fe5a0bc0973", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/f551c6a17f9e074e8c870a1eec10a548e0265543e57cad45b24630e52575684c93df74b0699fc90ba927aed34080032dcf5761277894d4aa9d377d3009e60f8d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/309e83ab997bbc9b2a17b97794d659e5baad27d719a22feffb9b7d58b7301f6a69e49f16164db1b375d19695224a9ecfa4158a0fde33b17f1757f57855fbe56c"} 13:00:12 INFO - Running post-run listener: copy_logs_to_upload_dir 13:00:12 INFO - Copying logs to upload dir... 13:00:12 INFO - mkdir: /builds/slave/test/build/upload/logs 13:00:12 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1678.265837 ========= master_lag: -0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 27 mins, 58 secs) (at 2016-10-13 13:00:11.317736) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-13 13:00:11.321050) ========= 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=05b19d94dfa9f3ce7b6e62cc000001f7-1476387132.106355-1991483113 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/fb20445968fa6038d74a9cfb6fd3d5fe9761c305e2e1b50bc3afda76a71596001bec5edffcc96622856aa576fc3cbbe5befb188aaa4dfe83c9e56fe5a0bc0973", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/f551c6a17f9e074e8c870a1eec10a548e0265543e57cad45b24630e52575684c93df74b0699fc90ba927aed34080032dcf5761277894d4aa9d377d3009e60f8d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/309e83ab997bbc9b2a17b97794d659e5baad27d719a22feffb9b7d58b7301f6a69e49f16164db1b375d19695224a9ecfa4158a0fde33b17f1757f57855fbe56c"} build_url:https://queue.taskcluster.net/v1/task/EH9J9CEdQBa9afEInMwAwg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011304 build_url: 'https://queue.taskcluster.net/v1/task/EH9J9CEdQBa9afEInMwAwg/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/fb20445968fa6038d74a9cfb6fd3d5fe9761c305e2e1b50bc3afda76a71596001bec5edffcc96622856aa576fc3cbbe5befb188aaa4dfe83c9e56fe5a0bc0973", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/f551c6a17f9e074e8c870a1eec10a548e0265543e57cad45b24630e52575684c93df74b0699fc90ba927aed34080032dcf5761277894d4aa9d377d3009e60f8d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/309e83ab997bbc9b2a17b97794d659e5baad27d719a22feffb9b7d58b7301f6a69e49f16164db1b375d19695224a9ecfa4158a0fde33b17f1757f57855fbe56c"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-13 13:00:11.345554) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-13 13:00:11.345845) ========= 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=05b19d94dfa9f3ce7b6e62cc000001f7-1476387132.106355-1991483113 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004622 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-13 13:00:11.427368) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-13 13:00:11.427654) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-13 13:00:11.427929) ========= ========= Total master_lag: 0.13 =========