builder: fx-team_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0168 starttime: 1462553544.28 results: success (0) buildid: 20160506065217 builduid: ce1d5bc5eb2746038152a5365675c762 revision: dff37a8c089293d90c938404e5803c784bff7d70 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-06 09:52:24.278589) ========= master: http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-06 09:52:24.279161) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-06 09:52:24.279443) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-06 09:52:24.299278) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-06 09:52:24.299648) ========= 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: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.TGvpKmRPhW/Render GIT_SHARE_BASE_DIR=/builds/git-shared HG_SHARE_BASE_DIR=/builds/hg-shared HOME=/Users/cltbld IDLEIZER_DISABLE_SHUTDOWN=true LOGNAME=cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NO_EM_RESTART=1 PAGER=/bin/cat PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11 PWD=/builds/slave/test RUNNER_CONFIG_CMD=/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg SHELL=/bin/bash SSH_AUTH_SOCK=/private/tmp/com.apple.launchd.ekx6weuAyS/Listeners TMPDIR=/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-05-06 09:52:24-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 6.29M=0.002s 2016-05-06 09:52:24 (6.29 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.088847 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-06 09:52:24.405042) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-06 09:52:24.405380) ========= rm -rf scripts properties in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-rf', 'scripts', 'properties'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.TGvpKmRPhW/Render GIT_SHARE_BASE_DIR=/builds/git-shared HG_SHARE_BASE_DIR=/builds/hg-shared HOME=/Users/cltbld IDLEIZER_DISABLE_SHUTDOWN=true LOGNAME=cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NO_EM_RESTART=1 PAGER=/bin/cat PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11 PWD=/builds/slave/test RUNNER_CONFIG_CMD=/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg SHELL=/bin/bash SSH_AUTH_SOCK=/private/tmp/com.apple.launchd.ekx6weuAyS/Listeners TMPDIR=/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False program finished with exit code 0 elapsedTime=0.020907 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-06 09:52:24.454083) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-06 09:52:24.454439) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev dff37a8c089293d90c938404e5803c784bff7d70 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/fx-team --rev dff37a8c089293d90c938404e5803c784bff7d70 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.TGvpKmRPhW/Render GIT_SHARE_BASE_DIR=/builds/git-shared HG_SHARE_BASE_DIR=/builds/hg-shared HOME=/Users/cltbld IDLEIZER_DISABLE_SHUTDOWN=true LOGNAME=cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NO_EM_RESTART=1 PAGER=/bin/cat PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11 PWD=/builds/slave/test RUNNER_CONFIG_CMD=/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg SHELL=/bin/bash SSH_AUTH_SOCK=/private/tmp/com.apple.launchd.ekx6weuAyS/Listeners TMPDIR=/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-05-06 09:52:24,522 truncating revision to first 12 chars 2016-05-06 09:52:24,522 Setting DEBUG logging. 2016-05-06 09:52:24,522 attempt 1/10 2016-05-06 09:52:24,522 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/dff37a8c0892?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-06 09:52:25,516 unpacking tar archive at: fx-team-dff37a8c0892/testing/mozharness/ program finished with exit code 0 elapsedTime=1.239694 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-06 09:52:25.711243) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-06 09:52:25.711657) ========= script_repo_revision: dff37a8c089293d90c938404e5803c784bff7d70 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-06 09:52:25.712090) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-06 09:52:25.712395) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-06 09:52:25.726626) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 4 secs) (at 2016-05-06 09:52:25.727073) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 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', 'Fx-Team', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.TGvpKmRPhW/Render GIT_SHARE_BASE_DIR=/builds/git-shared HG_SHARE_BASE_DIR=/builds/hg-shared HOME=/Users/cltbld IDLEIZER_DISABLE_SHUTDOWN=true LOGNAME=cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NO_EM_RESTART=1 PAGER=/bin/cat PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11 PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test RUNNER_CONFIG_CMD=/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg SHELL=/bin/bash SSH_AUTH_SOCK=/private/tmp/com.apple.launchd.ekx6weuAyS/Listeners TMPDIR=/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 09:52:25 INFO - MultiFileLogger online at 20160506 09:52:25 in /builds/slave/test 09:52:25 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 09:52:25 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 09:52:25 INFO - {'append_to_log': False, 09:52:25 INFO - 'base_work_dir': '/builds/slave/test', 09:52:25 INFO - 'blob_upload_branch': 'Fx-Team', 09:52:25 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 09:52:25 INFO - 'branch': 'Fx-Team', 09:52:25 INFO - 'buildbot_json_path': 'buildprops.json', 09:52:25 INFO - 'config_files': ('talos/mac_config.py',), 09:52:25 INFO - 'default_actions': ('clobber', 09:52:25 INFO - 'read-buildbot-config', 09:52:25 INFO - 'download-and-extract', 09:52:25 INFO - 'populate-webroot', 09:52:25 INFO - 'create-virtualenv', 09:52:25 INFO - 'install', 09:52:25 INFO - 'run-tests'), 09:52:25 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:52:25 INFO - 'download_minidump_stackwalk': True, 09:52:25 INFO - 'download_symbols': 'ondemand', 09:52:25 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 09:52:25 INFO - 'tooltool.py': '/tools/tooltool.py', 09:52:25 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 09:52:25 INFO - '/tools/misc-python/virtualenv.py')}, 09:52:25 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:52:25 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:52:25 INFO - 'installer_path': 'installer.exe', 09:52:25 INFO - 'log_level': 'info', 09:52:25 INFO - 'log_name': 'talos', 09:52:25 INFO - 'log_to_console': True, 09:52:25 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 09:52:25 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 09:52:25 INFO - 'opt_config_files': (), 09:52:25 INFO - 'pip_index': False, 09:52:25 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:52:25 INFO - 'cmd': ('bash', 09:52:25 INFO - '-c', 09:52:25 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:52:25 INFO - 'enabled': True, 09:52:25 INFO - 'halt_on_failure': False, 09:52:25 INFO - 'name': 'check_screen_resolution'},), 09:52:25 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:52:25 INFO - 'cmd': ('bash', 09:52:25 INFO - '-c', 09:52:25 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:52:25 INFO - 'enabled': True, 09:52:25 INFO - 'halt_on_failure': False, 09:52:25 INFO - 'name': 'check_screen_resolution'},), 09:52:25 INFO - 'run_cmd_checks_enabled': True, 09:52:25 INFO - 'sps_profile': False, 09:52:25 INFO - 'sps_profile_interval': 0, 09:52:25 INFO - 'suite': 'dromaeojs', 09:52:25 INFO - 'system_bits': '32', 09:52:25 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:52:25 INFO - 'title': 't-yosemite-r7-0168', 09:52:25 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:52:25 INFO - 'use_talos_json': True, 09:52:25 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 09:52:25 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:52:25 INFO - 'work_dir': 'build'} 09:52:25 INFO - ##### 09:52:25 INFO - ##### Running clobber step. 09:52:25 INFO - ##### 09:52:25 INFO - Running pre-action listener: _resource_record_pre_action 09:52:25 INFO - Running main action method: clobber 09:52:25 INFO - rmtree: /builds/slave/test/build 09:52:25 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 09:52:26 INFO - Running post-action listener: _resource_record_post_action 09:52:26 INFO - ##### 09:52:26 INFO - ##### Finished clobber step (success) 09:52:26 INFO - ##### 09:52:26 INFO - ##### 09:52:26 INFO - ##### Running read-buildbot-config step. 09:52:26 INFO - ##### 09:52:26 INFO - Running pre-action listener: _resource_record_pre_action 09:52:26 INFO - Running main action method: read_buildbot_config 09:52:26 INFO - Using buildbot properties: 09:52:26 INFO - { 09:52:26 INFO - "project": "", 09:52:26 INFO - "product": "firefox", 09:52:26 INFO - "script_repo_revision": "production", 09:52:26 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs", 09:52:26 INFO - "repository": "", 09:52:26 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs", 09:52:26 INFO - "buildid": "20160506065217", 09:52:26 INFO - "pgo_build": "False", 09:52:26 INFO - "basedir": "/builds/slave/test", 09:52:26 INFO - "buildnumber": 3, 09:52:26 INFO - "slavename": "t-yosemite-r7-0168", 09:52:26 INFO - "master": "http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/", 09:52:26 INFO - "platform": "yosemite_r7", 09:52:26 INFO - "scheduler": "tests-fx-team-macosx64-talos", 09:52:26 INFO - "branch": "fx-team", 09:52:26 INFO - "repo_path": "integration/fx-team", 09:52:26 INFO - "revision": "dff37a8c089293d90c938404e5803c784bff7d70", 09:52:26 INFO - "stage_platform": "macosx64", 09:52:26 INFO - "builduid": "ce1d5bc5eb2746038152a5365675c762", 09:52:26 INFO - "slavebuilddir": "test" 09:52:26 INFO - } 09:52:26 INFO - Found installer url https://queue.taskcluster.net/v1/task/PireGrcHSxyTuLLRjDgxdA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg. 09:52:26 INFO - Running post-action listener: _resource_record_post_action 09:52:26 INFO - ##### 09:52:26 INFO - ##### Finished read-buildbot-config step (success) 09:52:26 INFO - ##### 09:52:26 INFO - ##### 09:52:26 INFO - ##### Running download-and-extract step. 09:52:26 INFO - ##### 09:52:26 INFO - Running pre-action listener: _resource_record_pre_action 09:52:26 INFO - Running main action method: download_and_extract 09:52:26 INFO - mkdir: /builds/slave/test/build/tests 09:52:26 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')]} 09:52:26 INFO - https://queue.taskcluster.net/v1/task/PireGrcHSxyTuLLRjDgxdA/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 09:52:26 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PireGrcHSxyTuLLRjDgxdA/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 09:52:26 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PireGrcHSxyTuLLRjDgxdA/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 09:52:26 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PireGrcHSxyTuLLRjDgxdA/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json 09:52:26 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PireGrcHSxyTuLLRjDgxdA/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json'}, attempt #1 09:52:27 INFO - Downloaded 1199 bytes. 09:52:27 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json 09:52:27 INFO - Using the following test package requirements: 09:52:27 INFO - {u'common': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 09:52:27 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 09:52:27 INFO - u'firefox-49.0a1.en-US.mac.cppunittest.tests.zip'], 09:52:27 INFO - u'gtest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 09:52:27 INFO - u'firefox-49.0a1.en-US.mac.gtest.tests.zip'], 09:52:27 INFO - u'jittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 09:52:27 INFO - u'jsshell-mac.zip'], 09:52:27 INFO - u'mochitest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 09:52:27 INFO - u'firefox-49.0a1.en-US.mac.mochitest.tests.zip'], 09:52:27 INFO - u'mozbase': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 09:52:27 INFO - u'reftest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 09:52:27 INFO - u'firefox-49.0a1.en-US.mac.reftest.tests.zip'], 09:52:27 INFO - u'talos': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 09:52:27 INFO - u'firefox-49.0a1.en-US.mac.talos.tests.zip'], 09:52:27 INFO - u'web-platform': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 09:52:27 INFO - u'firefox-49.0a1.en-US.mac.web-platform.tests.zip'], 09:52:27 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 09:52:27 INFO - u'firefox-49.0a1.en-US.mac.xpcshell.tests.zip']} 09:52:27 INFO - Downloading packages: [u'firefox-49.0a1.en-US.mac.common.tests.zip'] for test suite category: common 09:52:27 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')]} 09:52:27 INFO - https://queue.taskcluster.net/v1/task/PireGrcHSxyTuLLRjDgxdA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 09:52:27 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PireGrcHSxyTuLLRjDgxdA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 09:52:27 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PireGrcHSxyTuLLRjDgxdA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 09:52:27 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PireGrcHSxyTuLLRjDgxdA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip 09:52:27 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PireGrcHSxyTuLLRjDgxdA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip'}, attempt #1 09:52:29 INFO - Downloaded 18145159 bytes. 09:52:29 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 09:52:29 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 09:52:32 INFO - Return code: 0 09:52:32 INFO - Downloading packages: [u'firefox-49.0a1.en-US.mac.common.tests.zip', u'firefox-49.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 09:52:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:52:32 INFO - https://queue.taskcluster.net/v1/task/PireGrcHSxyTuLLRjDgxdA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 09:52:32 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PireGrcHSxyTuLLRjDgxdA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 09:52:32 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PireGrcHSxyTuLLRjDgxdA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 09:52:32 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PireGrcHSxyTuLLRjDgxdA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip 09:52:32 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PireGrcHSxyTuLLRjDgxdA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip'}, attempt #1 09:52:33 INFO - Downloaded 18145159 bytes. 09:52:33 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 09:52:33 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 09:52:37 INFO - Return code: 0 09:52:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:52:37 INFO - https://queue.taskcluster.net/v1/task/PireGrcHSxyTuLLRjDgxdA/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 09:52:37 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PireGrcHSxyTuLLRjDgxdA/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 09:52:37 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PireGrcHSxyTuLLRjDgxdA/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 09:52:37 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PireGrcHSxyTuLLRjDgxdA/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.mac.talos.tests.zip 09:52:37 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PireGrcHSxyTuLLRjDgxdA/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.talos.tests.zip'}, attempt #1 09:52:38 INFO - Downloaded 10840152 bytes. 09:52:38 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 09:52:38 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 09:52:38 INFO - Return code: 0 09:52:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:52:38 INFO - https://queue.taskcluster.net/v1/task/PireGrcHSxyTuLLRjDgxdA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 09:52:38 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PireGrcHSxyTuLLRjDgxdA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 09:52:38 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PireGrcHSxyTuLLRjDgxdA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 09:52:38 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PireGrcHSxyTuLLRjDgxdA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg 09:52:38 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PireGrcHSxyTuLLRjDgxdA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg'}, attempt #1 09:52:41 INFO - Downloaded 110453643 bytes. 09:52:41 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/PireGrcHSxyTuLLRjDgxdA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 09:52:41 INFO - mkdir: /builds/slave/test/properties 09:52:41 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 09:52:41 INFO - Writing to file /builds/slave/test/properties/build_url 09:52:41 INFO - Contents: 09:52:41 INFO - build_url:https://queue.taskcluster.net/v1/task/PireGrcHSxyTuLLRjDgxdA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 09:52:42 INFO - Running post-action listener: _resource_record_post_action 09:52:42 INFO - Running post-action listener: set_extra_try_arguments 09:52:42 INFO - ##### 09:52:42 INFO - ##### Finished download-and-extract step (success) 09:52:42 INFO - ##### 09:52:42 INFO - ##### 09:52:42 INFO - ##### Running populate-webroot step. 09:52:42 INFO - ##### 09:52:42 INFO - Running pre-action listener: _resource_record_pre_action 09:52:42 INFO - Running main action method: populate_webroot 09:52:42 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:52:42 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:52:42 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:52:42 INFO - '--tppagecycles', 09:52:42 INFO - '7'], 09:52:42 INFO - 'tests': ['tsvgm']}}, 09:52:42 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 09:52:42 INFO - 'tests': ['tresize', 'tcanvasmark']}, 09:52:42 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 09:52:42 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 09:52:42 INFO - 'tests': ['dromaeo_css', 'kraken']}, 09:52:42 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 09:52:42 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:52:42 INFO - 'talos_options': ['--disable-e10s'], 09:52:42 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:52:42 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:52:42 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:52:42 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:52:42 INFO - 'talos_options': ['--disable-e10s'], 09:52:42 INFO - 'tests': ['damp', 'tps']}, 09:52:42 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:52:42 INFO - 'tests': ['damp', 'tps']}, 09:52:42 INFO - 'g3': {'talos_options': ['--disable-e10s'], 09:52:42 INFO - 'tests': ['dromaeo_dom']}, 09:52:42 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:52:42 INFO - 'other': {'talos_options': ['--disable-e10s'], 09:52:42 INFO - 'tests': ['a11yr', 09:52:42 INFO - 'ts_paint', 09:52:42 INFO - 'tpaint', 09:52:42 INFO - 'sessionrestore', 09:52:42 INFO - 'sessionrestore_no_auto_restore', 09:52:42 INFO - 'tabpaint']}, 09:52:42 INFO - 'other-e10s': {'tests': ['a11yr', 09:52:42 INFO - 'ts_paint', 09:52:42 INFO - 'tpaint', 09:52:42 INFO - 'sessionrestore', 09:52:42 INFO - 'sessionrestore_no_auto_restore', 09:52:42 INFO - 'tabpaint']}, 09:52:42 INFO - 'other-e10s_l64': {'tests': ['a11yr', 09:52:42 INFO - 'ts_paint', 09:52:42 INFO - 'tpaint', 09:52:42 INFO - 'sessionrestore', 09:52:42 INFO - 'sessionrestore_no_auto_restore', 09:52:42 INFO - 'tabpaint']}, 09:52:42 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 09:52:42 INFO - 'ts_paint', 09:52:42 INFO - 'tpaint', 09:52:42 INFO - 'sessionrestore', 09:52:42 INFO - 'sessionrestore_no_auto_restore', 09:52:42 INFO - 'tabpaint']}, 09:52:42 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 09:52:42 INFO - 'tests': ['a11yr', 09:52:42 INFO - 'ts_paint', 09:52:42 INFO - 'tpaint', 09:52:42 INFO - 'sessionrestore', 09:52:42 INFO - 'sessionrestore_no_auto_restore', 09:52:42 INFO - 'tabpaint']}, 09:52:42 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 09:52:42 INFO - 'tests': ['a11yr', 09:52:42 INFO - 'ts_paint', 09:52:42 INFO - 'tpaint', 09:52:42 INFO - 'sessionrestore', 09:52:42 INFO - 'sessionrestore_no_auto_restore', 09:52:42 INFO - 'tabpaint']}, 09:52:42 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 09:52:42 INFO - 'tests': ['tsvgx', 09:52:42 INFO - 'tsvgr_opacity', 09:52:42 INFO - 'tart', 09:52:42 INFO - 'tscrollx', 09:52:42 INFO - 'cart']}, 09:52:42 INFO - 'svgr-e10s': {'tests': ['tsvgx', 09:52:42 INFO - 'tsvgr_opacity', 09:52:42 INFO - 'tart', 09:52:42 INFO - 'tscrollx', 09:52:42 INFO - 'cart']}, 09:52:42 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:52:42 INFO - 'talos_options': ['--disable-e10s'], 09:52:42 INFO - 'tests': ['tp5o']}, 09:52:42 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:52:42 INFO - 'tests': ['tp5o']}, 09:52:42 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:52:42 INFO - 'talos_options': ['--disable-e10s', 09:52:42 INFO - '--xperf_path', 09:52:42 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:52:42 INFO - 'tests': ['tp5n']}, 09:52:42 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:52:42 INFO - 'talos_options': ['--xperf_path', 09:52:42 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:52:42 INFO - 'tests': ['tp5n']}}, 09:52:42 INFO - 'talos.zip': {'path': '', 09:52:42 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:52:42 INFO - Running post-action listener: _resource_record_post_action 09:52:42 INFO - ##### 09:52:42 INFO - ##### Finished populate-webroot step (success) 09:52:42 INFO - ##### 09:52:42 INFO - ##### 09:52:42 INFO - ##### Running create-virtualenv step. 09:52:42 INFO - ##### 09:52:42 INFO - Running pre-action listener: _resource_record_pre_action 09:52:42 INFO - Running main action method: create_virtualenv 09:52:42 INFO - Creating virtualenv /builds/slave/test/build/venv 09:52:42 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 09:52:42 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 09:52:43 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:52:43 INFO - Using real prefix '/tools/python27' 09:52:43 INFO - New python executable in /builds/slave/test/build/venv/bin/python 09:52:43 INFO - Installing distribute.............................................................................................................................................................................................done. 09:52:46 INFO - Installing pip.................done. 09:52:46 INFO - Return code: 0 09:52:46 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 09:52:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:52:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:52:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:52:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:52:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:52:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:52:46 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10a31ba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a32b420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fce894abb10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a1bae00>, '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 0x109fe3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a1b5b20>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.TGvpKmRPhW/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ekx6weuAyS/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:52:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 09:52:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 09:52:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.TGvpKmRPhW/Render', 09:52:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:52:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:52:46 INFO - 'HOME': '/Users/cltbld', 09:52:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:52:46 INFO - 'LOGNAME': 'cltbld', 09:52:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:52:46 INFO - 'MOZ_NO_REMOTE': '1', 09:52:46 INFO - 'NO_EM_RESTART': '1', 09:52:46 INFO - 'PAGER': '/bin/cat', 09:52:46 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:52:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:52:46 INFO - 'PWD': '/builds/slave/test', 09:52:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:52:46 INFO - 'SHELL': '/bin/bash', 09:52:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ekx6weuAyS/Listeners', 09:52:46 INFO - 'TMPDIR': '/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/T/', 09:52:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:52:46 INFO - 'USER': 'cltbld', 09:52:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:52:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:52:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:52:46 INFO - 'XPC_FLAGS': '0x0', 09:52:46 INFO - 'XPC_SERVICE_NAME': '0', 09:52:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:52:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:52:46 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 09:52:46 INFO - Cleaning up... 09:52:46 INFO - Return code: 0 09:52:46 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 09:52:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:52:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:52:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:52:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:52:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:52:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:52:46 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10a31ba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a32b420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fce894abb10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a1bae00>, '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 0x109fe3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a1b5b20>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.TGvpKmRPhW/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ekx6weuAyS/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:52:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1'] in /builds/slave/test/build 09:52:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=0.7.1 09:52:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.TGvpKmRPhW/Render', 09:52:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:52:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:52:46 INFO - 'HOME': '/Users/cltbld', 09:52:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:52:46 INFO - 'LOGNAME': 'cltbld', 09:52:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:52:46 INFO - 'MOZ_NO_REMOTE': '1', 09:52:46 INFO - 'NO_EM_RESTART': '1', 09:52:46 INFO - 'PAGER': '/bin/cat', 09:52:46 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:52:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:52:46 INFO - 'PWD': '/builds/slave/test', 09:52:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:52:46 INFO - 'SHELL': '/bin/bash', 09:52:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ekx6weuAyS/Listeners', 09:52:46 INFO - 'TMPDIR': '/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/T/', 09:52:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:52:46 INFO - 'USER': 'cltbld', 09:52:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:52:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:52:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:52:46 INFO - 'XPC_FLAGS': '0x0', 09:52:46 INFO - 'XPC_SERVICE_NAME': '0', 09:52:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:52:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:52:47 INFO - Downloading/unpacking psutil>=0.7.1 09:52:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:52:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:52:47 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:52:47 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:52:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:52:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:52:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 09:52:49 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:52:49 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:52:49 INFO - Installing collected packages: psutil 09:52:49 INFO - Running setup.py install for psutil 09:52:49 INFO - building 'psutil._psutil_osx' extension 09:52:49 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 09:52:50 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 09:52:50 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 09:52:50 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 09:52:50 INFO - building 'psutil._psutil_posix' extension 09:52:50 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 09:52:50 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 09:52:50 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 09:52:50 INFO - ^ 09:52:50 INFO - 1 warning generated. 09:52:50 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 09:52:50 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:52:50 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:52:50 INFO - Successfully installed psutil 09:52:50 INFO - Cleaning up... 09:52:50 INFO - Return code: 0 09:52:50 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 09:52: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')]} 09:52:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:52:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:52: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')]} 09:52:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:52:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:52:50 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.0.0']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10a31ba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a32b420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fce894abb10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a1bae00>, '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 0x109fe3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a1b5b20>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.TGvpKmRPhW/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ekx6weuAyS/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:52:50 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.0.0'] in /builds/slave/test/build 09:52:50 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.0.0 09:52:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.TGvpKmRPhW/Render', 09:52:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:52:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:52:50 INFO - 'HOME': '/Users/cltbld', 09:52:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:52:50 INFO - 'LOGNAME': 'cltbld', 09:52:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:52:50 INFO - 'MOZ_NO_REMOTE': '1', 09:52:50 INFO - 'NO_EM_RESTART': '1', 09:52:50 INFO - 'PAGER': '/bin/cat', 09:52:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:52:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:52:50 INFO - 'PWD': '/builds/slave/test', 09:52:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:52:50 INFO - 'SHELL': '/bin/bash', 09:52:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ekx6weuAyS/Listeners', 09:52:50 INFO - 'TMPDIR': '/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/T/', 09:52:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:52:50 INFO - 'USER': 'cltbld', 09:52:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:52:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:52:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:52:50 INFO - 'XPC_FLAGS': '0x0', 09:52:50 INFO - 'XPC_SERVICE_NAME': '0', 09:52:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:52:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:52:50 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 09:52:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:52:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:52:50 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:52:50 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:52:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:52:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:52:53 INFO - Downloading mozsystemmonitor-0.0.tar.gz 09:52:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:52:53 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 09:52:53 INFO - Installing collected packages: mozsystemmonitor 09:52:53 INFO - Running setup.py install for mozsystemmonitor 09:52:53 INFO - Successfully installed mozsystemmonitor 09:52:53 INFO - Cleaning up... 09:52:53 INFO - Return code: 0 09:52:53 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 09:52:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:52:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:52:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:52:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:52:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:52:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:52:53 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10a31ba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a32b420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fce894abb10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a1bae00>, '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 0x109fe3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a1b5b20>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.TGvpKmRPhW/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ekx6weuAyS/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:52:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 09:52:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 09:52:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.TGvpKmRPhW/Render', 09:52:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:52:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:52:53 INFO - 'HOME': '/Users/cltbld', 09:52:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:52:53 INFO - 'LOGNAME': 'cltbld', 09:52:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:52:53 INFO - 'MOZ_NO_REMOTE': '1', 09:52:53 INFO - 'NO_EM_RESTART': '1', 09:52:53 INFO - 'PAGER': '/bin/cat', 09:52:53 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:52:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:52:53 INFO - 'PWD': '/builds/slave/test', 09:52:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:52:53 INFO - 'SHELL': '/bin/bash', 09:52:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ekx6weuAyS/Listeners', 09:52:53 INFO - 'TMPDIR': '/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/T/', 09:52:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:52:53 INFO - 'USER': 'cltbld', 09:52:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:52:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:52:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:52:53 INFO - 'XPC_FLAGS': '0x0', 09:52:53 INFO - 'XPC_SERVICE_NAME': '0', 09:52:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:52:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:52:53 INFO - Downloading/unpacking blobuploader==1.2.4 09:52:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:52:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:52:53 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:52:53 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:52:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:52:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:52:56 INFO - Downloading blobuploader-1.2.4.tar.gz 09:52:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:52:56 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:52: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 09:52: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 09:52: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 09:52: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 09:52: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 09:52: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 09:52:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 09:52:57 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:52:57 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 09:52:57 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 09:52:57 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 09:52:57 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 09:52:57 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 09:52:57 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 09:52:57 INFO - Downloading docopt-0.6.1.tar.gz 09:52:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 09:52:57 INFO - Installing collected packages: blobuploader, requests, docopt 09:52:57 INFO - Running setup.py install for blobuploader 09:52:57 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:52:57 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 09:52:57 INFO - Running setup.py install for requests 09:52:58 INFO - Running setup.py install for docopt 09:52:58 INFO - Successfully installed blobuploader requests docopt 09:52:58 INFO - Cleaning up... 09:52:58 INFO - Return code: 0 09:52:58 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:52:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:52:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:52:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:52:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:52:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:52:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:52:58 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 0x10a31ba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a32b420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fce894abb10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a1bae00>, '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 0x109fe3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a1b5b20>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.TGvpKmRPhW/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ekx6weuAyS/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:52:58 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 09:52:58 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 09:52:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.TGvpKmRPhW/Render', 09:52:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:52:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:52:58 INFO - 'HOME': '/Users/cltbld', 09:52:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:52:58 INFO - 'LOGNAME': 'cltbld', 09:52:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:52:58 INFO - 'MOZ_NO_REMOTE': '1', 09:52:58 INFO - 'NO_EM_RESTART': '1', 09:52:58 INFO - 'PAGER': '/bin/cat', 09:52:58 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:52:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:52:58 INFO - 'PWD': '/builds/slave/test', 09:52:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:52:58 INFO - 'SHELL': '/bin/bash', 09:52:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ekx6weuAyS/Listeners', 09:52:58 INFO - 'TMPDIR': '/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/T/', 09:52:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:52:58 INFO - 'USER': 'cltbld', 09:52:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:52:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:52:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:52:58 INFO - 'XPC_FLAGS': '0x0', 09:52:58 INFO - 'XPC_SERVICE_NAME': '0', 09:52:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:52:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:52:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:52:58 INFO - Running setup.py (path:/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/T/pip-pzQxsQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:52:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:52:58 INFO - Running setup.py (path:/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/T/pip-1KrSNM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:52:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:52:58 INFO - Running setup.py (path:/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/T/pip-aqlScv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:52:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:52:59 INFO - Running setup.py (path:/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/T/pip-P0fBLM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:52:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:52:59 INFO - Running setup.py (path:/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/T/pip-xCEeC8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:52:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:52:59 INFO - Running setup.py (path:/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/T/pip-sN7jBQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:52:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:52:59 INFO - Running setup.py (path:/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/T/pip-FezYzi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:52:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:52:59 INFO - Running setup.py (path:/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/T/pip-MB1MYA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:52:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:52:59 INFO - Running setup.py (path:/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/T/pip-blr_Fd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:52:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:52:59 INFO - Running setup.py (path:/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/T/pip-fkKySY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:52:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:52:59 INFO - Running setup.py (path:/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/T/pip-BvybAj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:52:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:52:59 INFO - Running setup.py (path:/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/T/pip-rauhsR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:52:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:53:00 INFO - Running setup.py (path:/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/T/pip-idJt5O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:53:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:53:00 INFO - Running setup.py (path:/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/T/pip-F6qnQR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:53:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:53:00 INFO - Running setup.py (path:/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/T/pip-IkRS0r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:53:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:53:00 INFO - Running setup.py (path:/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/T/pip-IfLH0g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:53:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:53:00 INFO - Running setup.py (path:/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/T/pip-h_sH8P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:53:00 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:53:00 INFO - Running setup.py install for manifestparser 09:53:00 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 09:53:00 INFO - Running setup.py install for mozcrash 09:53:00 INFO - Running setup.py install for mozdebug 09:53:00 INFO - Running setup.py install for mozdevice 09:53:01 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 09:53:01 INFO - Installing dm script to /builds/slave/test/build/venv/bin 09:53:01 INFO - Running setup.py install for mozfile 09:53:01 INFO - Running setup.py install for mozhttpd 09:53:01 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 09:53:01 INFO - Running setup.py install for mozinfo 09:53:01 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 09:53:01 INFO - Running setup.py install for mozInstall 09:53:01 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 09:53:01 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 09:53:01 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 09:53:01 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 09:53:01 INFO - Running setup.py install for mozleak 09:53:01 INFO - Running setup.py install for mozlog 09:53:01 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 09:53:02 INFO - Running setup.py install for moznetwork 09:53:02 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 09:53:02 INFO - Running setup.py install for mozprocess 09:53:02 INFO - Running setup.py install for mozprofile 09:53:02 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 09:53:02 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 09:53:02 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 09:53:02 INFO - Running setup.py install for mozrunner 09:53:02 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 09:53:02 INFO - Running setup.py install for mozscreenshot 09:53:02 INFO - Running setup.py install for moztest 09:53:02 INFO - Running setup.py install for mozversion 09:53:03 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 09:53:03 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:53:03 INFO - Cleaning up... 09:53:03 INFO - Return code: 0 09:53:03 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:53:03 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')]} 09:53:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:53:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:53:03 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')]} 09:53:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:53:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:53:03 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 0x10a31ba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a32b420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fce894abb10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a1bae00>, '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 0x109fe3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a1b5b20>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.TGvpKmRPhW/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ekx6weuAyS/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:53:03 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 09:53:03 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 09:53:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.TGvpKmRPhW/Render', 09:53:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:53:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:53:03 INFO - 'HOME': '/Users/cltbld', 09:53:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:53:03 INFO - 'LOGNAME': 'cltbld', 09:53:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:53:03 INFO - 'MOZ_NO_REMOTE': '1', 09:53:03 INFO - 'NO_EM_RESTART': '1', 09:53:03 INFO - 'PAGER': '/bin/cat', 09:53:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:53:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:53:03 INFO - 'PWD': '/builds/slave/test', 09:53:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:53:03 INFO - 'SHELL': '/bin/bash', 09:53:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ekx6weuAyS/Listeners', 09:53:03 INFO - 'TMPDIR': '/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/T/', 09:53:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:53:03 INFO - 'USER': 'cltbld', 09:53:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:53:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:53:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:53:03 INFO - 'XPC_FLAGS': '0x0', 09:53:03 INFO - 'XPC_SERVICE_NAME': '0', 09:53:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:53:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:53:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:53:03 INFO - Running setup.py (path:/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/T/pip-UldTpb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:53:03 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 09:53:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:53:03 INFO - Running setup.py (path:/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/T/pip-vI8LQa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:53:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:53:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:53:03 INFO - Running setup.py (path:/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/T/pip-GHmg7k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:53:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 09:53:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:53:03 INFO - Running setup.py (path:/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/T/pip-vBdxlC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:53:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:53:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:53:03 INFO - Running setup.py (path:/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/T/pip-iT905K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:53:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 09:53:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:53:03 INFO - Running setup.py (path:/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/T/pip-_jpin6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:53:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 09:53:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:53:03 INFO - Running setup.py (path:/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/T/pip-uXvJUy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:53:04 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)) 09:53:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:53:04 INFO - Running setup.py (path:/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/T/pip-CNfo6R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:53:04 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)) 09:53:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:53:04 INFO - Running setup.py (path:/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/T/pip-k86yhf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:53:04 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)) 09:53:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:53:04 INFO - Running setup.py (path:/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/T/pip-0YktET-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:53:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.1 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)) 09:53:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:53:04 INFO - Running setup.py (path:/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/T/pip-iUmjqM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:53:04 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)) 09:53:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:53:04 INFO - Running setup.py (path:/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/T/pip-ZXFBtk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:53:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 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)) 09:53:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:53:04 INFO - Running setup.py (path:/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/T/pip-HCsL5z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:53:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 09:53:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:53:04 INFO - Running setup.py (path:/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/T/pip-JIUo7c-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:53:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.11 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)) 09:53:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:53:04 INFO - Running setup.py (path:/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/T/pip-NnfBta-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:53:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 09:53:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:53:04 INFO - Running setup.py (path:/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/T/pip-iYc34m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:53:05 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)) 09:53:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:53:05 INFO - Running setup.py (path:/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/T/pip-RVjkQ9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:53:05 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)) 09:53:05 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)) 09:53:05 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)) 09:53:05 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)) 09:53:05 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)) 09:53:05 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)) 09:53:05 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:53:05 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 09:53:05 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 09:53:05 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 09:53:05 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 09:53:05 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 09:53:05 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 09:53:07 INFO - Downloading blessings-1.6.tar.gz 09:53:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 09:53:07 INFO - Installing collected packages: blessings 09:53:07 INFO - Running setup.py install for blessings 09:53:07 INFO - Successfully installed blessings 09:53:07 INFO - Cleaning up... 09:53:07 INFO - Return code: 0 09:53:07 INFO - Done creating virtualenv /builds/slave/test/build/venv. 09:53:07 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:53:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:53:08 INFO - Reading from file tmpfile_stdout 09:53:08 INFO - Current package versions: 09:53:08 INFO - blessings == 1.6 09:53:08 INFO - blobuploader == 1.2.4 09:53:08 INFO - docopt == 0.6.1 09:53:08 INFO - manifestparser == 1.1 09:53:08 INFO - mozInstall == 1.12 09:53:08 INFO - mozcrash == 0.17 09:53:08 INFO - mozdebug == 0.1 09:53:08 INFO - mozdevice == 0.48 09:53:08 INFO - mozfile == 1.2 09:53:08 INFO - mozhttpd == 0.7 09:53:08 INFO - mozinfo == 0.9 09:53:08 INFO - mozleak == 0.1 09:53:08 INFO - mozlog == 3.1 09:53:08 INFO - moznetwork == 0.27 09:53:08 INFO - mozprocess == 0.22 09:53:08 INFO - mozprofile == 0.28 09:53:08 INFO - mozrunner == 6.11 09:53:08 INFO - mozscreenshot == 0.1 09:53:08 INFO - mozsystemmonitor == 0.0 09:53:08 INFO - moztest == 0.7 09:53:08 INFO - mozversion == 1.4 09:53:08 INFO - psutil == 3.1.1 09:53:08 INFO - requests == 1.2.3 09:53:08 INFO - wsgiref == 0.1.2 09:53:08 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:53:08 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')]} 09:53:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:53:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:53:08 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')]} 09:53:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:53:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:53:08 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 0x10a31ba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a32b420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fce894abb10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a1bae00>, '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 0x109fe3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a1b5b20>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/talos', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.TGvpKmRPhW/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ekx6weuAyS/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:53:08 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 09:53:08 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 09:53:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.TGvpKmRPhW/Render', 09:53:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:53:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:53:08 INFO - 'HOME': '/Users/cltbld', 09:53:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:53:08 INFO - 'LOGNAME': 'cltbld', 09:53:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:53:08 INFO - 'MOZ_NO_REMOTE': '1', 09:53:08 INFO - 'NO_EM_RESTART': '1', 09:53:08 INFO - 'PAGER': '/bin/cat', 09:53:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:53:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:53:08 INFO - 'PWD': '/builds/slave/test', 09:53:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:53:08 INFO - 'SHELL': '/bin/bash', 09:53:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ekx6weuAyS/Listeners', 09:53:08 INFO - 'TMPDIR': '/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/T/', 09:53:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:53:08 INFO - 'USER': 'cltbld', 09:53:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:53:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:53:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:53:08 INFO - 'XPC_FLAGS': '0x0', 09:53:08 INFO - 'XPC_SERVICE_NAME': '0', 09:53:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:53:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:53:08 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)) 09:53:08 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)) 09:53:08 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)) 09:53:08 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)) 09:53:08 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)) 09:53:08 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)) 09:53:08 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)) 09:53:08 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)) 09:53:08 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)) 09:53:08 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)) 09:53:08 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)) 09:53:08 INFO - Cleaning up... 09:53:08 INFO - Return code: 0 09:53:08 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 09:53:08 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')]} 09:53:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:53:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:53:08 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')]} 09:53:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:53:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:53:08 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 0x10a31ba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a32b420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fce894abb10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a1bae00>, '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 0x109fe3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a1b5b20>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.TGvpKmRPhW/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ekx6weuAyS/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:53:08 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 09:53:08 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 09:53:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.TGvpKmRPhW/Render', 09:53:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:53:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:53:08 INFO - 'HOME': '/Users/cltbld', 09:53:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:53:08 INFO - 'LOGNAME': 'cltbld', 09:53:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:53:08 INFO - 'MOZ_NO_REMOTE': '1', 09:53:08 INFO - 'NO_EM_RESTART': '1', 09:53:08 INFO - 'PAGER': '/bin/cat', 09:53:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:53:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:53:08 INFO - 'PWD': '/builds/slave/test', 09:53:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:53:08 INFO - 'SHELL': '/bin/bash', 09:53:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ekx6weuAyS/Listeners', 09:53:08 INFO - 'TMPDIR': '/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/T/', 09:53:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:53:08 INFO - 'USER': 'cltbld', 09:53:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:53:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:53:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:53:08 INFO - 'XPC_FLAGS': '0x0', 09:53:08 INFO - 'XPC_SERVICE_NAME': '0', 09:53:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:53:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:53:08 INFO - Downloading/unpacking jsonschema 09:53:08 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 09:53:08 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 09:53:08 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 09:53:08 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 09:53:08 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 09:53:08 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 09:53:11 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:53:11 INFO - Downloading/unpacking functools32 (from jsonschema) 09:53:11 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 09:53:11 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 09:53:11 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 09:53:11 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 09:53:11 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 09:53:11 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 09:53:11 INFO - Downloading functools32-3.2.3-2.tar.gz 09:53:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 09:53:11 INFO - warning: no files found matching '*.txt' 09:53:11 INFO - no previously-included directories found matching 'build' 09:53:11 INFO - no previously-included directories found matching 'dist' 09:53:11 INFO - no previously-included directories found matching '.git*' 09:53:11 INFO - Installing collected packages: jsonschema, functools32 09:53:11 INFO - Running setup.py install for functools32 09:53:11 INFO - warning: no files found matching '*.txt' 09:53:11 INFO - no previously-included directories found matching 'build' 09:53:11 INFO - no previously-included directories found matching 'dist' 09:53:11 INFO - no previously-included directories found matching '.git*' 09:53:11 INFO - Successfully installed jsonschema functools32 09:53:11 INFO - Cleaning up... 09:53:11 INFO - Return code: 0 09:53:11 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 09:53: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')]} 09:53:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:53:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:53: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')]} 09:53:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:53:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:53:11 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', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10a31ba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a32b420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fce894abb10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a1bae00>, '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 0x109fe3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a1b5b20>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.TGvpKmRPhW/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ekx6weuAyS/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:53:11 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', 'flake8'] in /builds/slave/test/build 09:53:11 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 flake8 09:53:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.TGvpKmRPhW/Render', 09:53:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:53:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:53:11 INFO - 'HOME': '/Users/cltbld', 09:53:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:53:11 INFO - 'LOGNAME': 'cltbld', 09:53:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:53:11 INFO - 'MOZ_NO_REMOTE': '1', 09:53:11 INFO - 'NO_EM_RESTART': '1', 09:53:11 INFO - 'PAGER': '/bin/cat', 09:53:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:53:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:53:11 INFO - 'PWD': '/builds/slave/test', 09:53:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:53:11 INFO - 'SHELL': '/bin/bash', 09:53:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ekx6weuAyS/Listeners', 09:53:11 INFO - 'TMPDIR': '/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/T/', 09:53:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:53:11 INFO - 'USER': 'cltbld', 09:53:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:53:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:53:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:53:11 INFO - 'XPC_FLAGS': '0x0', 09:53:11 INFO - 'XPC_SERVICE_NAME': '0', 09:53:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:53:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:53:12 INFO - Downloading/unpacking flake8 09:53: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 09:53: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 09:53: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 09:53: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 09:53: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 09:53: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 09:53:14 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 09:53:14 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 09:53:14 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 09:53:14 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 09:53:14 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 09:53:14 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 09:53:14 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 09:53:14 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 09:53:14 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 09:53:14 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 09:53:14 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 09:53:14 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 09:53:14 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 09:53:14 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 09:53:14 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 09:53:14 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 09:53:15 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 09:53:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:53:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:53:15 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:53:15 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:53:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:53:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:53:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 09:53:15 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 09:53:15 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 09:53:15 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 09:53:15 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 09:53:15 INFO - no previously-included directories found matching 'docs/_build' 09:53:15 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 09:53:15 INFO - Running setup.py install for pep8 09:53:16 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 09:53:16 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 09:53:16 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 09:53:16 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 09:53:16 INFO - no previously-included directories found matching 'docs/_build' 09:53:16 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 09:53:16 INFO - Successfully installed flake8 mccabe pyflakes pep8 09:53:16 INFO - Cleaning up... 09:53:16 INFO - Return code: 0 09:53:16 INFO - Running post-action listener: _resource_record_post_action 09:53:16 INFO - Running post-action listener: _start_resource_monitoring 09:53:16 INFO - Starting resource monitoring. 09:53:16 INFO - ##### 09:53:16 INFO - ##### Finished create-virtualenv step (success) 09:53:16 INFO - ##### 09:53:16 INFO - ##### 09:53:16 INFO - ##### Running install step. 09:53:16 INFO - ##### 09:53:16 INFO - Running pre-action listener: _resource_record_pre_action 09:53:16 INFO - Running main action method: install 09:53:16 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:53:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:53:16 INFO - Reading from file tmpfile_stdout 09:53:16 INFO - Detecting whether we're running mozinstall >=1.0... 09:53:16 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 09:53:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 09:53:16 INFO - Reading from file tmpfile_stdout 09:53:16 INFO - Output received: 09:53:16 INFO - Usage: mozinstall [options] installer 09:53:16 INFO - Options: 09:53:16 INFO - -h, --help show this help message and exit 09:53:16 INFO - -d DEST, --destination=DEST 09:53:16 INFO - Directory to install application into. [default: 09:53:16 INFO - "/builds/slave/test"] 09:53:16 INFO - --app=APP Application being installed. [default: firefox] 09:53:16 INFO - mkdir: /builds/slave/test/build/application 09:53:16 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 09:53:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 09:53:50 INFO - Reading from file tmpfile_stdout 09:53:50 INFO - Output received: 09:53:50 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 09:53:50 INFO - Running post-action listener: _resource_record_post_action 09:53:50 INFO - ##### 09:53:50 INFO - ##### Finished install step (success) 09:53:50 INFO - ##### 09:53:50 INFO - ##### 09:53:50 INFO - ##### Running run-tests step. 09:53:50 INFO - ##### 09:53:50 INFO - Running pre-action listener: _resource_record_pre_action 09:53:50 INFO - Running main action method: run_tests 09:53:50 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:53:50 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:53:50 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:53:50 INFO - 2016-05-06 09:53:50.537 screenresolution[2025:15037] starting screenresolution argv=screenresolution get 09:53:50 INFO - 2016-05-06 09:53:50.539 screenresolution[2025:15037] Display 0: 1600x1200x32@60 09:53:50 INFO - 2016-05-06 09:53:50.545 screenresolution[2026:15039] starting screenresolution argv=screenresolution list 09:53:50 INFO - Available Modes on Display 0 09:53:50 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:53:50 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:53:50 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:53:50 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:53:50 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:53:50 INFO - Intel Iris: 09:53:50 INFO - Chipset Model: Intel Iris 09:53:50 INFO - Type: GPU 09:53:50 INFO - Bus: Built-In 09:53:50 INFO - VRAM (Dynamic, Max): 1536 MB 09:53:50 INFO - Vendor: Intel (0x8086) 09:53:50 INFO - Device ID: 0x0a2e 09:53:50 INFO - Revision ID: 0x0009 09:53:50 INFO - Displays: 09:53:50 INFO - Display: 09:53:50 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:53:50 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:53:50 INFO - Display Serial Number: 1600x1200 60 09:53:50 INFO - Main Display: Yes 09:53:50 INFO - Mirror: Off 09:53:50 INFO - Online: Yes 09:53:50 INFO - Rotation: Supported 09:53:50 INFO - Return code: 0 09:53:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 09:53:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 09:53:50 INFO - Python 2.7.3 09:53:50 INFO - Return code: 0 09:53:50 INFO - grabbing minidump binary from tooltool 09:53: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')]} 09:53:50 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/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a1bae00>, '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 0x109fe3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a1b5b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 09:53:50 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/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 09:53:50 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/macosx64/releng.manifest -o -c /builds/tooltool_cache 09:53:50 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:53:50 INFO - Return code: 0 09:53:50 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 09:53:50 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 09:53:50 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 09:53:50 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 09:53:50 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 09:53:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 09:53:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 09:53:51 INFO - Return code: 0 09:53:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/PireGrcHSxyTuLLRjDgxdA/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0168', '--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 09:53:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Fx-Team --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/PireGrcHSxyTuLLRjDgxdA/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0168 --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 09:53:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.TGvpKmRPhW/Render', 09:53:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:53:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:53:51 INFO - 'HOME': '/Users/cltbld', 09:53:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:53:51 INFO - 'LOGNAME': 'cltbld', 09:53:51 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:53:51 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 09:53:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:53:51 INFO - 'MOZ_NO_REMOTE': '1', 09:53:51 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:53:51 INFO - 'NO_EM_RESTART': '1', 09:53:51 INFO - 'PAGER': '/bin/cat', 09:53:51 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:53:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:53:51 INFO - 'PWD': '/builds/slave/test', 09:53:51 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:53:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:53:51 INFO - 'SHELL': '/bin/bash', 09:53:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ekx6weuAyS/Listeners', 09:53:51 INFO - 'TMPDIR': '/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/T/', 09:53:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:53:51 INFO - 'USER': 'cltbld', 09:53:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:53:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:53:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:53:51 INFO - 'XPC_FLAGS': '0x0', 09:53:51 INFO - 'XPC_SERVICE_NAME': '0', 09:53:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:53:51 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/PireGrcHSxyTuLLRjDgxdA/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0168', '--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 09:53:52 INFO - mozversion application_buildid: 20160506065217 09:53:52 INFO - mozversion application_changeset: dff37a8c089293d90c938404e5803c784bff7d70 09:53:52 INFO - mozversion application_display_name: Nightly 09:53:52 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:53:52 INFO - mozversion application_name: Firefox 09:53:52 INFO - mozversion application_remotingname: firefox 09:53:52 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 09:53:52 INFO - mozversion application_vendor: Mozilla 09:53:52 INFO - mozversion application_version: 49.0a1 09:53:52 INFO - mozversion platform_buildid: 20160506065217 09:53:52 INFO - mozversion platform_changeset: dff37a8c089293d90c938404e5803c784bff7d70 09:53:52 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 09:53:52 INFO - mozversion platform_version: 49.0a1 09:53:52 INFO - using testdate: 1462553632 09:53:52 INFO - actual date: 1462553632 09:53:52 INFO - starting webserver on 'localhost:51424' 09:53:52 INFO - SUITE-START | Running 2 tests 09:53:52 INFO - TEST-START | dromaeo_css 09:53:52 INFO - operating with platform_type : mac_ 09:53:52 INFO - Initialising browser for dromaeo_css test... 09:53:52 INFO - TEST-INFO | started process 2044 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/T/tmpnwPFHR/profile http://localhost:51424/getInfo.html) 09:53:54 INFO - PROCESS | 2044 | __metrics Screen width/height:1600/1200 09:53:54 INFO - PROCESS | 2044 | colorDepth:24 09:53:54 INFO - PROCESS | 2044 | Browser inner width/height: 1024/689 09:53:54 INFO - PROCESS | 2044 | __metrics 09:53:54 INFO - PROCESS | 2044 | 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" 09:53:54 ERROR - PROCESS | 2044 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 09:53:54 INFO - TEST-INFO | 2044: exit 0 09:53:54 INFO - Browser initialized. 09:53:54 INFO - Running cycle 1/1 for dromaeo_css test... 09:53:54 INFO - TEST-INFO | started process 2046 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/T/tmpnwPFHR/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:54:07 INFO - PROCESS | 2046 | RSS: Main: 238907392 09:54:07 INFO - PROCESS | 2046 | 09:54:58 INFO - PROCESS | 2046 | 1462553698948 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] 09:57:08 INFO - PROCESS | 2046 | Cycle 1(1): loaded http://localhost:51424/tests/dromaeo/cssquery-dojo.html (next: http://localhost:51424/tests/dromaeo/cssquery-ext.html) 09:57:10 INFO - PROCESS | 2046 | RSS: Main: 374546432 09:57:10 INFO - PROCESS | 2046 | 10:00:13 INFO - PROCESS | 2046 | Cycle 1(1): loaded http://localhost:51424/tests/dromaeo/cssquery-ext.html (next: http://localhost:51424/tests/dromaeo/cssquery-jquery.html) 10:00:14 INFO - PROCESS | 2046 | RSS: Main: 376918016 10:00:14 INFO - PROCESS | 2046 | 10:03:16 INFO - PROCESS | 2046 | Cycle 1(1): loaded http://localhost:51424/tests/dromaeo/cssquery-jquery.html (next: http://localhost:51424/tests/dromaeo/cssquery-mootools.html) 10:03:16 INFO - PROCESS | 2046 | RSS: Main: 370188288 10:03:16 INFO - PROCESS | 2046 | 10:06:18 INFO - PROCESS | 2046 | Cycle 1(1): loaded http://localhost:51424/tests/dromaeo/cssquery-mootools.html (next: http://localhost:51424/tests/dromaeo/cssquery-prototype.html) 10:06:19 INFO - PROCESS | 2046 | RSS: Main: 368488448 10:06:19 INFO - PROCESS | 2046 | 10:09:22 INFO - PROCESS | 2046 | Cycle 1(1): loaded http://localhost:51424/tests/dromaeo/cssquery-prototype.html (next: http://localhost:51424/tests/dromaeo/cssquery-yui.html) 10:09:23 INFO - PROCESS | 2046 | RSS: Main: 340881408 10:09:23 INFO - PROCESS | 2046 | 10:12:26 INFO - PROCESS | 2046 | Cycle 1(1): loaded http://localhost:51424/tests/dromaeo/cssquery-yui.html (next: http://localhost:51424/tests/dromaeo/cssquery-dojo.html) 10:12:26 INFO - PROCESS | 2046 | RSS: Main: 412897280 10:12:26 INFO - PROCESS | 2046 | 10:12:26 INFO - PROCESS | 2046 | __start_tp_report 10:12:26 INFO - PROCESS | 2046 | _x_x_mozilla_page_load 10:12:26 INFO - PROCESS | 2046 | _x_x_mozilla_page_load_details 10:12:26 INFO - PROCESS | 2046 | |i|pagename|runs| 10:12:26 INFO - PROCESS | 2046 | |0;dojo.html;3113.9065828025155;3373.881914133005;3406.1484628842795;3412.761106722529;3421.8289085545716;5566;5585.190147428621;5627;5667.23492328536;5676.14857771334;4614.723116612997;5584.385717571074;5718.313802343714;5747.712614369275;5751.4536119068625;5007.273945277951;5039.344885164924;5090.567301779353;5102.566281866046;5307.946920530803;4366.432363792703;5220.817271395502;5254.080535906221;5260.316158899338;5330.440303768107;5095.031943930641;5105.795768169269;5140.40885298188;5149.536541711245;5261.605379596526;5155.994581056695;5175.689458632494;5185.274061631337;5190.403103643054;5200.869978250536;5001.349824522788;5080.034793389244;5084.694918304914;5086.745662716849;5135.12702840518;5512.531434828044;5642.605017648766;5649.971750141223;5671.149327600851;5674.205611214434;6044.909326360108;6146.078088286748;6169;6181.96909015452;6186.133941248228;6069.9848721573035;6112.755489780404;6130.080487926802;6159.599626024339;6244.500439964792;5791.768329266824;5802.970985145132;5807.332156801979;5813.680247586339;5816.040353341736;6147.739713358751;6203.286622038523;6222.377762223741;6232.158658581034;6249.562530622904;6014.729337179838;6081.75672973085;6102.603330783485;6115.327313995457;6125.387461253839;6135.538906164978;6198.690065496708;6201.348858369896;6228.096925945712;6242.032484964838;6105.32841387447;6127.5404344674325;6162.5378096642935;6184.845378865474;6197.628142311386;6100.938990610126;6115.666295175888;6228.937710622927;6236.282827474808;6250.499960003189;4815.470298267188;4907.435644900882;4907.509249075135;4910.607151427877;4931.827386041471;4373.75944323059;4384.408104905797;4393.0994146199955;4396.912061758747;4431.556844315543;5495.120780675073;5676.460736230051;5736.741846616912;5739.454751798656;5812.9709351452975;6174.66039367839;6183.134361189437;6221.595596286227;6226.844328891814;6234.812955611339;5727.627704199213;5755;5767.336756273083;5773.653580785166;5784.132380143013;5239.423663397023;5374.328208973879;5375.032494151091;5382.757775900016;5391.433899440581;6156.661383623944;6196.380361963767;6213.099100630383;6219.626822390671;6273.121762953099;6232.283287421823;6252.843678908063;6257.7184026718;6259.499240060875;6267.059941008885;6152.969235153795;6188.504919606331;6238.563300568917;6245.28179259391;6252.218472690914;6110.51115910735;6136.079588061826;6152.138700581832;6208.099825525183;6212.968935155295;6258;6258.874822503433;6259.436650701458;6268.1851359323;6280;5921.61509501881;5980.551458640706;6090.725917333642;6101.145839582373;6124.081387791867;5973.790917317873;6054.33402325735;6064.727087280995;6071.453569178795;6088.6042407243385;5640.097584386478;5875.853103672443;5879.7060146993335;5900.144479050427;5912.320083190488;5836.182934389104;5854.297484301911;5880.8235752927485;6030.246219222598;6087.939120608914;4963.801447942042;5839.620424672383;5849.268841394825;5870.647761134345;5874.177615133799 10:12:26 INFO - PROCESS | 2046 | |1;ext.html;500.14225603090705;508.0879820721804;522.7516929458508;540.0090833320857;555.0093083845337;30836.53745193821;33746.49380259295;44104.3384349235;44329.33505997409;47251;44578;44951.4267000655;44960.77519612397;45017.324740128926;45042.32436513447;8645.740627781177;9580.999999999982;9814.067663571985;9818.263630227726;9831.016898310147;8648.135186481364;8726.127387261287;8754.343424243176;8757.51833649149;11745.23655962364;11485.25345852521;11742.706432339217;11745;11752;11761.588344407948;11633.883661163365;11748.882511174865;11754.823677644885;11758.706032349173;11764.411779410995;8119.350451963829;8373.581320933929;11442.313461192354;11577.884221157765;11686.240394374423;41850.581494184975;42318.36522452166;42342.78828605868;42419;42439.78780106111;3049.8017628854277;3228.386606544749;3236.0291912426414;3250.0606354596102;3305.86776528941;29201.853990730124;30167.547486787716;31248;31248.906288280028;31280.061598152093;2710.9322266943286;2745.2450576091533;2756.2282560882986;2767.2943399433216;2830.2641313258496;471371;473043;475203;475379;476791;63150.368496315365;64765.67617161884;64858;64901.67549162319;64919.02621460682;67086.66456667783;67176.99234511486;67211;67224.66387668128;67529.32470675328;17178.141092945552;17436.738448923275;17468.737968930476;17472.38846640387;17529.649407011788;13291.601251962456;13353.198808071547;13371.997100217523;13388.19670819754;13433.19400835953;15570.84429155694;15891.761623575656;15934.60163495922;16217.108059056629;16218.75671864923;5434.673919564839;6298.244210694655;7076.8449812628005;7107.857842843114;7187.676554555058;920.7652048727532;927.9072092790667;967.7387105481481;1003.087190656499;1021.1830535571662;23978.640320395207;29540.113796586138;29683.851580741957;29844.253893652833;30733.84633076865;1039.880413752413;1129.4917287220783;1156.306216270231;1176.8764279750674;1229.489761748882;15081.698366032837;15513.922430387776;15543.222838858012;15564.066156030674;15867.285972131282;580210;635569;639382;645181;651621;48384;49446.50553494491;53670.73164634152;53775.46224537783;59341.70329148327;1351.8783309502192;1378.06980288305;1453;1492.2389581313826;1496.1172907984123;30250.09249722512;30568.541471877495;30611.387772243987;31091.84454077715;31402.528962066044;51534.22698659598;52030.479695202564;52682.47317526929;52693.31146819686;52750.47249527455;507578;509333;510078;511126;839332;26307.342316442242;41016.974575635846;42005.36991945062;42715.35926961036;44352;45595.77202114101;47218.81952951204;47426.52573474221;48851.755741221066;49070;37221;38575.61424385833;38669.6133038666;39355.6064439352;39491.80254098711;37748.62251377451;38076.23847523089;38433.615663844124;38845.41731874081;38895.611043890334;37792.811035944644;38296.808515957244;38879.6112038876;38893;38947.6105238944;29841.657125428974;30008.699913001463;34085.82957085199;34227.315453691284;34228.315433691685;22048.33854984353;22763;24290.271291861274;24731;25586.360340991625 10:12:26 INFO - PROCESS | 2046 | |2;jquery.html;362.46898293999294;387.8681248375553;393.6339204539777;396.396575850684;423.49180982820604;17988;19868.50328741782;20347.593048139046;20477;21649.134034638595;19787.604247915035;20230;20280.98595070248;20345.98270086497;20738.48153796152;5716.39977802331;5776.249087618625;6000.489958353545;6009.519238460913;6024.8493787655225;5909.970450147744;5912.379200183983;5957.791477298296;5992.760289588411;6016.518678505731;5745.683987380692;5881.441263080001;5925.970370148143;5926.585139040269;5931.199288096116;5674.574406919498;5866.413358664142;5925;5937.673427961482;6044.546659000549;5841.795537156222;5847;5861.501772349356;5865.237519122486;5868.735906884201;13360.265185414799;23382;24063.759362406327;24209.152679656305;24631;2044.9591008179902;2116.449723072012;2215.656573231152;2270.71616047994;2492.7258001619807;9705.223582113415;9722.125008749246;9926.255530835217;10068.546915388824;10210.18318534523;1666.5916850371616;1721.5351854999205;1764.6382491589193;1773.654137443199;1825.3520000399933;108339.45830270876;112120;112827;113711.86288137095;113808.43095784551;11462;11475.254108483088;11602.419879006016;11664.533418663328;11690.181687281809;14885.851141488663;14920.328585213474;14929.029613075116;14947.626309342353;14966.17686027279;7399.630018499053;8709.651613935499;8764.75703989528;8767.561621919007;8798.692045778367;8665.523396213268;8696.086910874266;8753.343499237582;8808.427452215714;9076.8638470423;4538.909221815546;4553.726776393427;4562.634989200856;4568.565986231302;4602.148602508547;1663.4676903391041;1904.5429097016893;1930.5289229785897;1940.194819150065;1967.6950072738748;830.2071521696765;896.7758060484879;922.9261659067258;983.9655612053687;1060.8302671572512;18820.15309311057;20375.4906127348;20540.897295513427;20697.896510517352;20698.482537936372;868.9304855611535;965.1362030982372;976.3995142987012;1000.2498126405196;1020.6631811502186;10730.980556847087;10889;10899.400532970603;10942.4528773561;10946.671599852018;529689.7031029718;570402;573251;591054;592126;120727.39636301763;137923;140004;142139;142303;979.1236843025453;990.6136606723384;998.2164001259122;1003.7239759066315;1021.3361315145215;17189;18120.18459169314;18293.634127317644;18326.900385976885;18746.906265468584;43399.132017360105;45065.77467112643;53692.89917457835;53763;54020;75433;75652.73041808755;75785.62107189429;78617;78853;7847.293743563107;7876.054873415227;7912.169222231582;7959.681612735426;8063.596820159086;10846.67459976202;11938.22401543897;12013.75972480563;12216.26702397859;12306.07704422154;7046.4010559101;9922.206223502248;10373.54770332139;10686.305390149615;10732.839007415052;10163.288569800043;10182.032706892833;10320.27758056959;10486.318389304672;10596.682099537025;10573.62992295266;10595.364278143335;10620.415877126832;10640.201984851012;10826.025657690845;7813.3749300057;7850.3701260078515;7892.921070789219;7992.200779922194;8008.6396112173925;7538.057742782153;7577.507462014952;7598.354139898171;7938.642761075881;8037.156098609562 10:12:26 INFO - PROCESS | 2046 | |3;mootools.html;1418.7162567486491;1457.5117335692541;1564.9843501564985;1790.3017823049013;1936.9225230990762;6914.481413893959;8565;8816.911830881705;8844.823103537925;8948.463092214472;8511.78720531987;8782.824343513126;8782.868256976151;8795;8796.296296296297;2428.150147448392;2477.2568229531157;2478.677771889652;2479.293401380598;2479.3429741118616;2402.2793162051403;2466.4450498637843;2468.9135880244194;2469.333280014395;2472.3819045238693;2433.634954756792;2438.207582535683;2439.3413778279855;2454.6686197363306;2463.6797216361847;2385.7614238576175;2386.033656369173;2416.2026531244646;2416.984866356126;2419.27421773469;2317.663938728892;2373.940651483727;2383.7020372453444;2386.009805930537;2387.3792813868345;7806.921930780733;8033.517988920684;8757.693480728143;8843;8869;4930.753462326869;5096.159133742928;5102.540771330598;5138.152204886189;5225.738713064332;2796.832190068582;3549.1482044309437;3681.9263614727824;3693.2059607184315;3700.5929347771726;1015.9949200253951;1051.3901936876593;1053.7629033467408;1056.4823236614081;1063.415121683079;11481.253718508271;11618.593349232737;11622.302661840316;11634.883651163465;11652.242604230783;4545.795439205243;4824.396950381202;4844.370231869834;5005.6996580205305;5028.296038554606;10001.699949001542;10007.849882251627;10019.298649094493;10023.047810457994;10027.097561219525;5514.6691198527415;5521.00621888064;5522.199281104297;5531.032069387841;5539.947409992089;4210;4941;5029.321041659402;5048.040872234264;5064.088464076428;4059.65492933104;4106;4128.215900946556;4141.109661422808;4224.197402493516;1339.519830587187;1367.9794803077962;1368.4868174434587;1379.0415661115428;1384.0795870746042;854.7179430787825;911.8541033434618;923.8891333039944;968.1818863060843;979.2215188924822;5961.374055724086;6042.758289668452;6068.089786532056;6100.72546735398;6159.84600384985;471.34953763806413;471.75940270461626;475.62900937268984;476;490.7423602608588;3351.4302568563403;4033.2941735196223;4094.570070142592;4168.228877657643;4248.85129020489;10445.007724266183;10703.571857125628;10807.135429165648;10828.971247731453;10848.294860834021;10560.155187584975;10635.042846143884;10657.786844263226;10767.461626918623;10834.02493775564;8024.197580242046;8222.753317400488;8245;8277.255047045674;8294;5790.1893734877995;5994.490468310242;6034.426729460694;6148.1085242640465;6246.781362652468;9483.95258023733;10000.949995249977;10173.796524069625;10268.64059757905;10291.794164116825;10050.296479246676;10153.340032897839;10219.182465402899;10343.27597068198;10345.793084138124;7466;7506.512076714996;7536.7362142324755;7675.270849269311;7675.961620191863;8210.63052162642;8229.753107406787;8321.58533049371;8330.833383332421;8357.038940521674;6832.180138383425;7391.667374968247;7480.102387713509;7643;7669.846603068019;7210.675519601526;7366.63166841645;7372.115346158495;7406.962965185355;7461.4030877528585;7013.333733295329;7060.3292687194635;7102.715891364288;7111.146662400338;7128.85742285141;4831.637627177905;4872.780724867319;4975.378077740283;5039.244113383022;5162.9483705162465;4942.159832828356;5040.193569028937;5077.593792496666;5144;5312.734363281743 10:12:26 INFO - PROCESS | 2046 | |4;prototype.html;305.17602473322034;309.71970366818033;325.26165604078756;325.43374528320714;326.97874638148517;4552.45370555533;4861.756912154396;4933.741895816566;5140.784556663528;5288.444713305105;5012.954208495367;5053.848384548461;5207.687538747668;5217.843464696065;5290.603204759649;3581.910452238689;3785.5835858055593;4089.6280157508572;4094.6724262058965;4125.525564560076;3636.799976001332;3767.077066118791;3941.724079314449;4086.346184610463;4116.526599441073;3297.093299342676;3646.7994260315672;4037.919241615166;4070.75575465473;4073.714839961204;3842.9615703842883;4046.393041043867;4064.8170832312617;4080.2247572961337;4156.625903668654;2815.9155225343275;3512.841922113511;3554.4668299755244;3561.7506774525796;3582.46263060543;4448.243798554254;4492.820287188509;4762.523747625244;4828.5412885775795;5002.89936214036;3761.54861416629;4120.299549076664;4532.3201519771965;4581.358609794632;4587.885302867422;5553.250311207976;5587;5598.720063996784;5621.01632214365;5624.3813180550105;3373.1567108222944;3682.392405253157;3688.963110368889;3853.344931361675;3854.383298672227;5731.140328950649;5763.538916886639;5801.941980580183;5803.53571714266;5870.207521984563;3221.5328777327154;3643.5952271500682;3895.4028848172115;3921.8627348042683;3943.6056394360335;3548.716102711777;3667.394879844796;3920.4511368408444;3977.6817854571564;3994.6205110514456;3857.884263472044;4118.423420721101;4434.955650443519;4527.3435351873795;4596.379488768974;5200.7139607321205;5606.523445507096;5610.298712660918;5634.239377684042;5644.774209031675;1174.8472698549133;1227.643983244849;1228.7112528555783;1246.7506498700116;1265.949262112463;812.5652775764937;852.377764232114;867.7244450657523;873.5326600268826;878.8989266234337;4373.753480258175;4545.227311357077;4933.851984440473;4971.353724015855;5034.137369173356;4789.928151077736;5130.461301563357;5398.379186393537;5472.196823207912;5477.972610137003;4354.105963905711;4361.672874534423;4603.516630753789;4713.727293630701;4923.655344125948;2661.4144888124583;2677.6611694152925;2679.7356233982255;2864.3268831824503;2865.7850661200077;4890.755462226874;5322.787088516416;5370.395358251245;5655.907314293738;5715.514181294553;5139.922901156486;5353.518183363438;5509.669419834822;5632.648164440505;5929.407059294036;5605.990921634064;5884.762970661117;6028.889888538976;6035;6042.123892035543;4894.4616092230535;5595.300587426572;5793;5846.766129354778;5896.764129434946;5451.3458384994055;5731.96206010815;5733.254676891977;5735.598508104393;5744.396838332083;4663.20725476663;5141.38303403594;5801.955647983404;5960.4635582797755;5997.280326360865;3611.6207798180812;4052.311107111857;4068;4068.7558746474124;4082.265192265421;5308.4160742317545;5388.811391601432;5489;5613.438656134354;5740.531571424161;5223.347081614798;5286.444923283;5488.4785945335125;5495.230667706444;5628.8311350657905;4919;5391.676499410048;5568.832935011957;5593.916091258716;5648.858778530405;4690.765461726968;5068.695878247317;5433.293671822637;5485.424030476902;5504.311961004874;4949.876253093701;5543.944560554504;5570;5622.437756224344;5626.324841019104;5477.370102438112;5483.122700367922;5504;5543.334799824047;5551.335359027608 10:12:26 INFO - PROCESS | 2046 | |5;yui.html;645.7352485480953;648.6205569741701;656.4584218020132;715.3669002932406;717.8959050937611;14469.493567725118;15095.849041509582;15098.849011509881;15111.395544178233;15125.848741512582;13412;14314.35585398657;14346.56960291193;14350.138991660482;14374.20941848198;716.4698123388681;741.2550386861197;903.1104362203224;909.236241557092;919.6873063158524;907.541691445819;931.5063016601213;933.7432206143297;933.9912894074383;935.6584846531001;973.4013581647272;983.9262055345841;984.8178087053882;985.4087547471531;987.323683276954;749.407967705512;944.390867890207;973.474323865112;975.6438899801557;977.8337682593976;713.5754226235382;714.4498735973324;714.907062081926;715.2204097533713;715.5241764226782;14468.98717089804;14531.12813231199;14535.345909433996;14565.708685826225;14565.927170364186;12129.514819407214;12138.878611213951;12375.690607734789;12434.378281086;12443.502259909592;10624.840627390595;10685.305455145392;10734.946325268402;11093.889061109447;11257.099432045417;3980.900477488057;3983.820728067244;3986.5814089520472;4108.342665173588;4138.048248902769;74218.25781742168;74572;75226.49547009029;76855.23144768593;77299.84050239195;9309.487978161265;9398.624055037722;9502.477363744922;9740.074692904163;9841.753956151153;20964.685529717066;20997.475063123544;21007.159713611283;21047.78952210489;21120.68318975186;6976.372126508537;7036.964815175994;7066.293370662996;7099.538529995534;7220.725471476468;4089.6114869087387;4126.938095928564;4131.772752498641;4141.461609990682;4149.6265336119895;2993;3434.261633748756;3515.0860776198374;3520.9647903520636;3524.964750352515;616.1189499016449;625.9530535209877;631.4411745605172;642.3191417097892;651.90547370631;528.6959998001165;546.8960897429555;559.4377650461261;605.9272887253558;637.6620391192675;6871.347222013901;7837.882431763528;8016.639251233709;8160.959195204105;8259.256666899888;496.2927827845306;536.5272327461145;544.335910189568;556.1574215064181;595.9987221467975;3180.634227063871;3768.8304026318883;3905.9804700976315;3929.31237033518;3958.960410395859;70458.23854403579;77140;78627;79132;79417;70861;71139;71505;72184.63907680532;73495;62183.6890815543;62517.68741156265;63156;63224.103276902686;63583;5956.612820166675;7684.8463030740195;7693.269139431745;7750.031246094239;7854.842903142019;41125;41530.37704434375;41643.16713665771;41890.58109418867;42035.949101272716;54446.45553544414;56056.71971640279;56283.71858140683;56318.43681563132;57106.14340784975;10338.689839304832;10416.166706663296;10612.366674125518;10875.51060202277;11337.829932550854;10274;10870.347779133277;11003.17476189305;11175.050120739828;11404.885951140383;9891.851622225817;10179.236557258087;10242.795144097225;10899.618513351994;10962.561497540328;10238.948805256225;10466.267876242906;10516.273291925465;10613.257072004886;10899.618513351994;9988.400695958266;10192.694219173436;10340.327878687862;10531.157507399543;10687.839682404614;8723.91276087231;8785.341099417697;8868.689595864114;8921.464712117293;9063.13900179482;8294.83410331802;8813.91186088131;8922.286217102486;9032.277417806692;9077.09229077087 10:12:26 INFO - PROCESS | 2046 | __end_tp_report 10:12:26 INFO - PROCESS | 2046 | __start_cc_report 10:12:26 INFO - PROCESS | 2046 | _x_x_mozilla_cycle_collect,1388 10:12:26 INFO - PROCESS | 2046 | __end_cc_report 10:12:26 INFO - PROCESS | 2046 | __startTimestamp1462554746720__endTimestamp 10:12:26 INFO - PROCESS | 2046 | 10:12:26 INFO - PROCESS | 2046 | ------- Summary: start ------- 10:12:26 INFO - PROCESS | 2046 | Number of tests: 6 10:12:26 INFO - PROCESS | 2046 | 10:12:26 INFO - PROCESS | 2046 | [#0] dojo.html Cycles:165 Average:5684.69 Median:5874.18 stddev:647.75 (11.0%) stddev-sans-first:617.54 10:12:26 INFO - PROCESS | 2046 | Values: 3113.9 3373.9 3406.1 3412.8 3421.8 5566.0 5585.2 5627.0 5667.2 5676.1 4614.7 5584.4 5718.3 5747.7 5751.5 5007.3 5039.3 5090.6 5102.6 5307.9 4366.4 5220.8 5254.1 5260.3 5330.4 5095.0 5105.8 5140.4 5149.5 5261.6 5156.0 5175.7 5185.3 5190.4 5200.9 5001.3 5080.0 5084.7 5086.7 5135.1 5512.5 5642.6 5650.0 5671.1 5674.2 6044.9 6146.1 6169.0 6182.0 6186.1 6070.0 6112.8 6130.1 6159.6 6244.5 5791.8 5803.0 5807.3 5813.7 5816.0 6147.7 6203.3 6222.4 6232.2 6249.6 6014.7 6081.8 6102.6 6115.3 6125.4 6135.5 6198.7 6201.3 6228.1 6242.0 6105.3 6127.5 6162.5 6184.8 6197.6 6100.9 6115.7 6228.9 6236.3 6250.5 4815.5 4907.4 4907.5 4910.6 4931.8 4373.8 4384.4 4393.1 4396.9 4431.6 5495.1 5676.5 5736.7 5739.5 5813.0 6174.7 6183.1 6221.6 6226.8 6234.8 5727.6 5755.0 5767.3 5773.7 5784.1 5239.4 5374.3 5375.0 5382.8 5391.4 6156.7 6196.4 6213.1 6219.6 6273.1 6232.3 6252.8 6257.7 6259.5 6267.1 6153.0 6188.5 6238.6 6245.3 6252.2 6110.5 6136.1 6152.1 6208.1 6213.0 6258.0 6258.9 6259.4 6268.2 6280.0 5921.6 5980.6 6090.7 6101.1 6124.1 5973.8 6054.3 6064.7 6071.5 6088.6 5640.1 5875.9 5879.7 5900.1 5912.3 5836.2 5854.3 5880.8 6030.2 6087.9 4963.8 5839.6 5849.3 5870.6 5874.2 10:12:26 INFO - PROCESS | 2046 | 10:12:26 INFO - PROCESS | 2046 | [#1] ext.html Cycles:180 Average:70140.08 Median:38622.61 stddev:152128.36 (393.9%) stddev-sans-first:152465.27 10:12:26 INFO - PROCESS | 2046 | Values: 500.1 508.1 522.8 540.0 555.0 30836.5 33746.5 44104.3 44329.3 47251.0 44578.0 44951.4 44960.8 45017.3 45042.3 8645.7 9581.0 9814.1 9818.3 9831.0 8648.1 8726.1 8754.3 8757.5 11745.2 11485.3 11742.7 11745.0 11752.0 11761.6 11633.9 11748.9 11754.8 11758.7 11764.4 8119.4 8373.6 11442.3 11577.9 11686.2 41850.6 42318.4 42342.8 42419.0 42439.8 3049.8 3228.4 3236.0 3250.1 3305.9 29201.9 30167.5 31248.0 31248.9 31280.1 2710.9 2745.2 2756.2 2767.3 2830.3 471371.0 473043.0 475203.0 475379.0 476791.0 63150.4 64765.7 64858.0 64901.7 64919.0 67086.7 67177.0 67211.0 67224.7 67529.3 17178.1 17436.7 17468.7 17472.4 17529.6 13291.6 13353.2 13372.0 13388.2 13433.2 15570.8 15891.8 15934.6 16217.1 16218.8 5434.7 6298.2 7076.8 7107.9 7187.7 920.8 927.9 967.7 1003.1 1021.2 23978.6 29540.1 29683.9 29844.3 30733.8 1039.9 1129.5 1156.3 1176.9 1229.5 15081.7 15513.9 15543.2 15564.1 15867.3 580210.0 635569.0 639382.0 645181.0 651621.0 48384.0 49446.5 53670.7 53775.5 59341.7 1351.9 1378.1 1453.0 1492.2 1496.1 30250.1 30568.5 30611.4 31091.8 31402.5 51534.2 52030.5 52682.5 52693.3 52750.5 507578.0 509333.0 510078.0 511126.0 839332.0 26307.3 41017.0 42005.4 42715.4 44352.0 45595.8 47218.8 47426.5 48851.8 49070.0 37221.0 38575.6 38669.6 39355.6 39491.8 37748.6 38076.2 38433.6 38845.4 38895.6 37792.8 38296.8 38879.6 38893.0 38947.6 29841.7 30008.7 34085.8 34227.3 34228.3 22048.3 22763.0 24290.3 24731.0 25586.4 10:12:26 INFO - PROCESS | 2046 | 10:12:26 INFO - PROCESS | 2046 | [#2] jquery.html Cycles:180 Average:33933.12 Median:375.17 stddev:95838.87 (25545.6%) stddev-sans-first:96074.58 10:12:26 INFO - PROCESS | 2046 | Values: 362.5 387.9 393.6 396.4 423.5 17988.0 19868.5 20347.6 20477.0 21649.1 19787.6 20230.0 20281.0 20346.0 20738.5 5716.4 5776.2 6000.5 6009.5 6024.8 5910.0 5912.4 5957.8 5992.8 6016.5 5745.7 5881.4 5926.0 5926.6 5931.2 5674.6 5866.4 5925.0 5937.7 6044.5 5841.8 5847.0 5861.5 5865.2 5868.7 13360.3 23382.0 24063.8 24209.2 24631.0 2045.0 2116.4 2215.7 2270.7 2492.7 9705.2 9722.1 9926.3 10068.5 10210.2 1666.6 1721.5 1764.6 1773.7 1825.4 108339.5 112120.0 112827.0 113711.9 113808.4 11462.0 11475.3 11602.4 11664.5 11690.2 14885.9 14920.3 14929.0 14947.6 14966.2 7399.6 8709.7 8764.8 8767.6 8798.7 8665.5 8696.1 8753.3 8808.4 9076.9 4538.9 4553.7 4562.6 4568.6 4602.1 1663.5 1904.5 1930.5 1940.2 1967.7 830.2 896.8 922.9 984.0 1060.8 18820.2 20375.5 20540.9 20697.9 20698.5 868.9 965.1 976.4 1000.2 1020.7 10731.0 10889.0 10899.4 10942.5 10946.7 529689.7 570402.0 573251.0 591054.0 592126.0 120727.4 137923.0 140004.0 142139.0 142303.0 979.1 990.6 998.2 1003.7 1021.3 17189.0 18120.2 18293.6 18326.9 18746.9 43399.1 45065.8 53692.9 53763.0 54020.0 75433.0 75652.7 75785.6 78617.0 78853.0 7847.3 7876.1 7912.2 7959.7 8063.6 10846.7 11938.2 12013.8 12216.3 12306.1 7046.4 9922.2 10373.5 10686.3 10732.8 10163.3 10182.0 10320.3 10486.3 10596.7 10573.6 10595.4 10620.4 10640.2 10826.0 7813.4 7850.4 7892.9 7992.2 8008.6 7538.1 7577.5 7598.4 7938.6 8037.2 10:12:26 INFO - PROCESS | 2046 | 10:12:26 INFO - PROCESS | 2046 | [#3] mootools.html Cycles:180 Average:5737.68 Median:4828.02 stddev:3199.80 (66.3%) stddev-sans-first:3192.31 10:12:26 INFO - PROCESS | 2046 | Values: 1418.7 1457.5 1565.0 1790.3 1936.9 6914.5 8565.0 8816.9 8844.8 8948.5 8511.8 8782.8 8782.9 8795.0 8796.3 2428.2 2477.3 2478.7 2479.3 2479.3 2402.3 2466.4 2468.9 2469.3 2472.4 2433.6 2438.2 2439.3 2454.7 2463.7 2385.8 2386.0 2416.2 2417.0 2419.3 2317.7 2373.9 2383.7 2386.0 2387.4 7806.9 8033.5 8757.7 8843.0 8869.0 4930.8 5096.2 5102.5 5138.2 5225.7 2796.8 3549.1 3681.9 3693.2 3700.6 1016.0 1051.4 1053.8 1056.5 1063.4 11481.3 11618.6 11622.3 11634.9 11652.2 4545.8 4824.4 4844.4 5005.7 5028.3 10001.7 10007.8 10019.3 10023.0 10027.1 5514.7 5521.0 5522.2 5531.0 5539.9 4210.0 4941.0 5029.3 5048.0 5064.1 4059.7 4106.0 4128.2 4141.1 4224.2 1339.5 1368.0 1368.5 1379.0 1384.1 854.7 911.9 923.9 968.2 979.2 5961.4 6042.8 6068.1 6100.7 6159.8 471.3 471.8 475.6 476.0 490.7 3351.4 4033.3 4094.6 4168.2 4248.9 10445.0 10703.6 10807.1 10829.0 10848.3 10560.2 10635.0 10657.8 10767.5 10834.0 8024.2 8222.8 8245.0 8277.3 8294.0 5790.2 5994.5 6034.4 6148.1 6246.8 9484.0 10000.9 10173.8 10268.6 10291.8 10050.3 10153.3 10219.2 10343.3 10345.8 7466.0 7506.5 7536.7 7675.3 7676.0 8210.6 8229.8 8321.6 8330.8 8357.0 6832.2 7391.7 7480.1 7643.0 7669.8 7210.7 7366.6 7372.1 7407.0 7461.4 7013.3 7060.3 7102.7 7111.1 7128.9 4831.6 4872.8 4975.4 5039.2 5162.9 4942.2 5040.2 5077.6 5144.0 5312.7 10:12:26 INFO - PROCESS | 2046 | 10:12:26 INFO - PROCESS | 2046 | [#4] prototype.html Cycles:180 Average:4462.80 Median:4941.86 stddev:1387.46 (28.1%) stddev-sans-first:1355.81 10:12:26 INFO - PROCESS | 2046 | Values: 305.2 309.7 325.3 325.4 327.0 4552.5 4861.8 4933.7 5140.8 5288.4 5013.0 5053.8 5207.7 5217.8 5290.6 3581.9 3785.6 4089.6 4094.7 4125.5 3636.8 3767.1 3941.7 4086.3 4116.5 3297.1 3646.8 4037.9 4070.8 4073.7 3843.0 4046.4 4064.8 4080.2 4156.6 2815.9 3512.8 3554.5 3561.8 3582.5 4448.2 4492.8 4762.5 4828.5 5002.9 3761.5 4120.3 4532.3 4581.4 4587.9 5553.3 5587.0 5598.7 5621.0 5624.4 3373.2 3682.4 3689.0 3853.3 3854.4 5731.1 5763.5 5801.9 5803.5 5870.2 3221.5 3643.6 3895.4 3921.9 3943.6 3548.7 3667.4 3920.5 3977.7 3994.6 3857.9 4118.4 4435.0 4527.3 4596.4 5200.7 5606.5 5610.3 5634.2 5644.8 1174.8 1227.6 1228.7 1246.8 1265.9 812.6 852.4 867.7 873.5 878.9 4373.8 4545.2 4933.9 4971.4 5034.1 4789.9 5130.5 5398.4 5472.2 5478.0 4354.1 4361.7 4603.5 4713.7 4923.7 2661.4 2677.7 2679.7 2864.3 2865.8 4890.8 5322.8 5370.4 5655.9 5715.5 5139.9 5353.5 5509.7 5632.6 5929.4 5606.0 5884.8 6028.9 6035.0 6042.1 4894.5 5595.3 5793.0 5846.8 5896.8 5451.3 5732.0 5733.3 5735.6 5744.4 4663.2 5141.4 5802.0 5960.5 5997.3 3611.6 4052.3 4068.0 4068.8 4082.3 5308.4 5388.8 5489.0 5613.4 5740.5 5223.3 5286.4 5488.5 5495.2 5628.8 4919.0 5391.7 5568.8 5593.9 5648.9 4690.8 5068.7 5433.3 5485.4 5504.3 4949.9 5543.9 5570.0 5622.4 5626.3 5477.4 5483.1 5504.0 5543.3 5551.3 10:12:26 INFO - PROCESS | 2046 | 10:12:26 INFO - PROCESS | 2046 | [#5] yui.html Cycles:180 Average:16600.35 Median:3276.31 stddev:22499.54 (686.7%) stddev-sans-first:22530.76 10:12:26 INFO - PROCESS | 2046 | Values: 645.7 648.6 656.5 715.4 717.9 14469.5 15095.8 15098.8 15111.4 15125.8 13412.0 14314.4 14346.6 14350.1 14374.2 716.5 741.3 903.1 909.2 919.7 907.5 931.5 933.7 934.0 935.7 973.4 983.9 984.8 985.4 987.3 749.4 944.4 973.5 975.6 977.8 713.6 714.4 714.9 715.2 715.5 14469.0 14531.1 14535.3 14565.7 14565.9 12129.5 12138.9 12375.7 12434.4 12443.5 10624.8 10685.3 10734.9 11093.9 11257.1 3980.9 3983.8 3986.6 4108.3 4138.0 74218.3 74572.0 75226.5 76855.2 77299.8 9309.5 9398.6 9502.5 9740.1 9841.8 20964.7 20997.5 21007.2 21047.8 21120.7 6976.4 7037.0 7066.3 7099.5 7220.7 4089.6 4126.9 4131.8 4141.5 4149.6 2993.0 3434.3 3515.1 3521.0 3525.0 616.1 626.0 631.4 642.3 651.9 528.7 546.9 559.4 605.9 637.7 6871.3 7837.9 8016.6 8161.0 8259.3 496.3 536.5 544.3 556.2 596.0 3180.6 3768.8 3906.0 3929.3 3959.0 70458.2 77140.0 78627.0 79132.0 79417.0 70861.0 71139.0 71505.0 72184.6 73495.0 62183.7 62517.7 63156.0 63224.1 63583.0 5956.6 7684.8 7693.3 7750.0 7854.8 41125.0 41530.4 41643.2 41890.6 42035.9 54446.5 56056.7 56283.7 56318.4 57106.1 10338.7 10416.2 10612.4 10875.5 11337.8 10274.0 10870.3 11003.2 11175.1 11404.9 9891.9 10179.2 10242.8 10899.6 10962.6 10238.9 10466.3 10516.3 10613.3 10899.6 9988.4 10192.7 10340.3 10531.2 10687.8 8723.9 8785.3 8868.7 8921.5 9063.1 8294.8 8813.9 8922.3 9032.3 9077.1 10:12:26 INFO - PROCESS | 2046 | -------- Summary: end -------- 10:12:26 INFO - PROCESS | 2046 | 10:12:27 INFO - TEST-INFO | 2046: exit 0 10:12:27 INFO - TEST-OK | dromaeo_css | took 1115134ms 10:12:27 INFO - TEST-START | kraken 10:12:27 INFO - operating with platform_type : mac_ 10:12:27 INFO - Initialising browser for kraken test... 10:12:27 INFO - TEST-INFO | started process 2048 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/T/tmpFKJRHP/profile http://localhost:51424/getInfo.html) 10:12:29 INFO - PROCESS | 2048 | __metrics Screen width/height:1600/1200 10:12:29 INFO - PROCESS | 2048 | colorDepth:24 10:12:29 INFO - PROCESS | 2048 | Browser inner width/height: 1024/689 10:12:29 INFO - PROCESS | 2048 | __metrics 10:12:29 INFO - PROCESS | 2048 | 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" 10:12:29 ERROR - PROCESS | 2048 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 10:12:30 INFO - TEST-INFO | 2048: exit 0 10:12:30 INFO - Browser initialized. 10:12:30 INFO - Running cycle 1/1 for kraken test... 10:12:30 INFO - TEST-INFO | started process 2050 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/T/tmpFKJRHP/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 10:12:42 INFO - PROCESS | 2050 | RSS: Main: 239104000 10:12:42 INFO - PROCESS | 2050 | 10:12:44 INFO - PROCESS | 2050 | Cycle 1(1): loaded http://localhost:51424/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:51424/tests/kraken/driver.html?testName=audio-beat-detection) 10:12:45 INFO - PROCESS | 2050 | RSS: Main: 286576640 10:12:45 INFO - PROCESS | 2050 | 10:12:48 INFO - PROCESS | 2050 | Cycle 1(1): loaded http://localhost:51424/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:51424/tests/kraken/driver.html?testName=audio-dft) 10:12:48 INFO - PROCESS | 2050 | RSS: Main: 295546880 10:12:48 INFO - PROCESS | 2050 | 10:12:52 INFO - PROCESS | 2050 | Cycle 1(1): loaded http://localhost:51424/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:51424/tests/kraken/driver.html?testName=audio-fft) 10:12:53 INFO - PROCESS | 2050 | RSS: Main: 383287296 10:12:53 INFO - PROCESS | 2050 | 10:12:54 INFO - PROCESS | 2050 | Cycle 1(1): loaded http://localhost:51424/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:51424/tests/kraken/driver.html?testName=audio-oscillator) 10:12:55 INFO - PROCESS | 2050 | RSS: Main: 284082176 10:12:55 INFO - PROCESS | 2050 | 10:12:57 INFO - PROCESS | 2050 | Cycle 1(1): loaded http://localhost:51424/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:51424/tests/kraken/driver.html?testName=imaging-gaussian-blur) 10:12:58 INFO - PROCESS | 2050 | RSS: Main: 287993856 10:12:58 INFO - PROCESS | 2050 | 10:13:01 INFO - PROCESS | 2050 | Cycle 1(1): loaded http://localhost:51424/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:51424/tests/kraken/driver.html?testName=imaging-darkroom) 10:13:01 INFO - PROCESS | 2050 | RSS: Main: 326336512 10:13:01 INFO - PROCESS | 2050 | 10:13:04 INFO - PROCESS | 2050 | Cycle 1(1): loaded http://localhost:51424/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:51424/tests/kraken/driver.html?testName=imaging-desaturate) 10:13:05 INFO - PROCESS | 2050 | RSS: Main: 329400320 10:13:05 INFO - PROCESS | 2050 | 10:13:08 INFO - PROCESS | 2050 | Cycle 1(1): loaded http://localhost:51424/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:51424/tests/kraken/driver.html?testName=json-parse-financial) 10:13:08 INFO - PROCESS | 2050 | RSS: Main: 328704000 10:13:08 INFO - PROCESS | 2050 | 10:13:10 INFO - PROCESS | 2050 | Cycle 1(1): loaded http://localhost:51424/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:51424/tests/kraken/driver.html?testName=json-stringify-tinderbox) 10:13:11 INFO - PROCESS | 2050 | RSS: Main: 315383808 10:13:11 INFO - PROCESS | 2050 | 10:13:13 INFO - PROCESS | 2050 | Cycle 1(1): loaded http://localhost:51424/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:51424/tests/kraken/driver.html?testName=stanford-crypto-aes) 10:13:13 INFO - PROCESS | 2050 | RSS: Main: 322084864 10:13:13 INFO - PROCESS | 2050 | 10:13:15 INFO - PROCESS | 2050 | Cycle 1(1): loaded http://localhost:51424/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:51424/tests/kraken/driver.html?testName=stanford-crypto-ccm) 10:13:16 INFO - PROCESS | 2050 | RSS: Main: 324268032 10:13:16 INFO - PROCESS | 2050 | 10:13:19 INFO - PROCESS | 2050 | Cycle 1(1): loaded http://localhost:51424/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:51424/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 10:13:20 INFO - PROCESS | 2050 | RSS: Main: 328552448 10:13:20 INFO - PROCESS | 2050 | 10:13:23 INFO - PROCESS | 2050 | Cycle 1(1): loaded http://localhost:51424/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:51424/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 10:13:23 INFO - PROCESS | 2050 | RSS: Main: 326336512 10:13:23 INFO - PROCESS | 2050 | 10:13:25 INFO - PROCESS | 2050 | Cycle 1(1): loaded http://localhost:51424/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:51424/tests/kraken/driver.html?testName=ai-astar) 10:13:25 INFO - PROCESS | 2050 | RSS: Main: 372629504 10:13:25 INFO - PROCESS | 2050 | 10:13:25 INFO - PROCESS | 2050 | __start_tp_report 10:13:25 INFO - PROCESS | 2050 | _x_x_mozilla_page_load 10:13:25 INFO - PROCESS | 2050 | _x_x_mozilla_page_load_details 10:13:25 INFO - PROCESS | 2050 | |i|pagename|runs| 10:13:25 INFO - PROCESS | 2050 | |0;ai-astar;112;108;112;112;110;112;108;112;108;154 10:13:25 INFO - PROCESS | 2050 | |1;audio-beat-detection;157;119;141;165;116;127;167;135;194;114 10:13:25 INFO - PROCESS | 2050 | |2;audio-dft;185;188;188;186;187;183;183;183;187;184 10:13:25 INFO - PROCESS | 2050 | |3;audio-fft;78;74;60;104;54;62;56;58;113;54 10:13:25 INFO - PROCESS | 2050 | |4;audio-oscillator;89;101;100;78;82;76;101;80;77;100 10:13:25 INFO - PROCESS | 2050 | |5;imaging-gaussian-blur;88;86;89;87;89;86;90;87;88;82 10:13:25 INFO - PROCESS | 2050 | |6;imaging-darkroom;94;94;95;104;145;145;95;95;95;93 10:13:25 INFO - PROCESS | 2050 | |7;imaging-desaturate;85;85;85;84;85;85;85;83;88;84 10:13:25 INFO - PROCESS | 2050 | |8;json-parse-financial;46;47;80;79;80;79;79;77;57;57 10:13:25 INFO - PROCESS | 2050 | |9;json-stringify-tinderbox;61;81;93;95;104;98;75;69;69;70 10:13:25 INFO - PROCESS | 2050 | |10;stanford-crypto-aes;85;83;86;84;83;81;85;84;84;86 10:13:25 INFO - PROCESS | 2050 | |11;stanford-crypto-ccm;141;138;139;137;133;134;140;143;196;138 10:13:25 INFO - PROCESS | 2050 | |12;stanford-crypto-pbkdf2;168;169;165;214;205;167;168;167;218;165 10:13:25 INFO - PROCESS | 2050 | |13;stanford-crypto-sha256-iterative;65;66;68;66;66;66;66;65;64;66 10:13:25 INFO - PROCESS | 2050 | __end_tp_report 10:13:25 INFO - PROCESS | 2050 | __start_cc_report 10:13:25 INFO - PROCESS | 2050 | _x_x_mozilla_cycle_collect,1983 10:13:25 INFO - PROCESS | 2050 | __end_cc_report 10:13:25 INFO - PROCESS | 2050 | __startTimestamp1462554805624__endTimestamp 10:13:25 INFO - PROCESS | 2050 | 10:13:25 INFO - PROCESS | 2050 | ------- Summary: start ------- 10:13:25 INFO - PROCESS | 2050 | Number of tests: 14 10:13:25 INFO - PROCESS | 2050 | 10:13:25 INFO - PROCESS | 2050 | [#0] ai-astar Cycles:10 Average:114.80 Median:112.00 stddev:13.89 (12.4%) stddev-sans-first:14.70 10:13:25 INFO - PROCESS | 2050 | Values: 112.0 108.0 112.0 112.0 110.0 112.0 108.0 112.0 108.0 154.0 10:13:25 INFO - PROCESS | 2050 | 10:13:25 INFO - PROCESS | 2050 | [#1] audio-beat-detection Cycles:10 Average:143.50 Median:149.00 stddev:26.51 (17.8%) stddev-sans-first:27.66 10:13:25 INFO - PROCESS | 2050 | Values: 157.0 119.0 141.0 165.0 116.0 127.0 167.0 135.0 194.0 114.0 10:13:25 INFO - PROCESS | 2050 | 10:13:25 INFO - PROCESS | 2050 | [#2] audio-dft Cycles:10 Average:185.40 Median:186.50 stddev:2.07 (1.1%) stddev-sans-first:2.19 10:13:25 INFO - PROCESS | 2050 | Values: 185.0 188.0 188.0 186.0 187.0 183.0 183.0 183.0 187.0 184.0 10:13:25 INFO - PROCESS | 2050 | 10:13:25 INFO - PROCESS | 2050 | [#3] audio-fft Cycles:10 Average:71.30 Median:59.00 stddev:21.30 (36.1%) stddev-sans-first:22.46 10:13:25 INFO - PROCESS | 2050 | Values: 78.0 74.0 60.0 104.0 54.0 62.0 56.0 58.0 113.0 54.0 10:13:25 INFO - PROCESS | 2050 | 10:13:25 INFO - PROCESS | 2050 | [#4] audio-oscillator Cycles:10 Average:88.40 Median:77.50 stddev:11.01 (14.2%) stddev-sans-first:11.67 10:13:25 INFO - PROCESS | 2050 | Values: 89.0 101.0 100.0 78.0 82.0 76.0 101.0 80.0 77.0 100.0 10:13:25 INFO - PROCESS | 2050 | 10:13:25 INFO - PROCESS | 2050 | [#5] imaging-gaussian-blur Cycles:10 Average:87.20 Median:88.00 stddev:2.25 (2.6%) stddev-sans-first:2.37 10:13:25 INFO - PROCESS | 2050 | Values: 88.0 86.0 89.0 87.0 89.0 86.0 90.0 87.0 88.0 82.0 10:13:25 INFO - PROCESS | 2050 | 10:13:25 INFO - PROCESS | 2050 | [#6] imaging-darkroom Cycles:10 Average:105.50 Median:94.50 stddev:21.04 (22.3%) stddev-sans-first:21.90 10:13:25 INFO - PROCESS | 2050 | Values: 94.0 94.0 95.0 104.0 145.0 145.0 95.0 95.0 95.0 93.0 10:13:25 INFO - PROCESS | 2050 | 10:13:25 INFO - PROCESS | 2050 | [#7] imaging-desaturate Cycles:10 Average:84.90 Median:85.00 stddev:1.29 (1.5%) stddev-sans-first:1.36 10:13:25 INFO - PROCESS | 2050 | Values: 85.0 85.0 85.0 84.0 85.0 85.0 85.0 83.0 88.0 84.0 10:13:25 INFO - PROCESS | 2050 | 10:13:25 INFO - PROCESS | 2050 | [#8] json-parse-financial Cycles:10 Average:68.10 Median:79.00 stddev:14.53 (18.4%) stddev-sans-first:13.02 10:13:25 INFO - PROCESS | 2050 | Values: 46.0 47.0 80.0 79.0 80.0 79.0 79.0 77.0 57.0 57.0 10:13:25 INFO - PROCESS | 2050 | 10:13:25 INFO - PROCESS | 2050 | [#9] json-stringify-tinderbox Cycles:10 Average:81.50 Median:78.00 stddev:14.91 (19.1%) stddev-sans-first:13.85 10:13:25 INFO - PROCESS | 2050 | Values: 61.0 81.0 93.0 95.0 104.0 98.0 75.0 69.0 69.0 70.0 10:13:25 INFO - PROCESS | 2050 | 10:13:25 INFO - PROCESS | 2050 | [#10] stanford-crypto-aes Cycles:10 Average:84.10 Median:84.50 stddev:1.52 (1.8%) stddev-sans-first:1.58 10:13:25 INFO - PROCESS | 2050 | Values: 85.0 83.0 86.0 84.0 83.0 81.0 85.0 84.0 84.0 86.0 10:13:25 INFO - PROCESS | 2050 | 10:13:25 INFO - PROCESS | 2050 | [#11] stanford-crypto-ccm Cycles:10 Average:143.90 Median:139.50 stddev:18.55 (13.3%) stddev-sans-first:19.65 10:13:25 INFO - PROCESS | 2050 | Values: 141.0 138.0 139.0 137.0 133.0 134.0 140.0 143.0 196.0 138.0 10:13:25 INFO - PROCESS | 2050 | 10:13:25 INFO - PROCESS | 2050 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:180.60 Median:168.50 stddev:22.16 (13.1%) stddev-sans-first:23.03 10:13:25 INFO - PROCESS | 2050 | Values: 168.0 169.0 165.0 214.0 205.0 167.0 168.0 167.0 218.0 165.0 10:13:25 INFO - PROCESS | 2050 | 10:13:25 INFO - PROCESS | 2050 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:65.80 Median:66.00 stddev:1.03 (1.6%) stddev-sans-first:1.05 10:13:25 INFO - PROCESS | 2050 | Values: 65.0 66.0 68.0 66.0 66.0 66.0 66.0 65.0 64.0 66.0 10:13:25 INFO - PROCESS | 2050 | -------- Summary: end -------- 10:13:25 INFO - PROCESS | 2050 | 10:13:26 INFO - TEST-INFO | 2050: exit 0 10:13:26 INFO - TEST-OK | kraken | took 59129ms 10:13:26 INFO - SUITE-END | took 1174s 10:13:26 INFO - Completed test suite (00:19:34) 10:13:26 INFO - javascript benchmark 10:13:26 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3113.9065828025155, 3373.881914133005, 3406.1484628842795, 3412.761106722529, 3421.8289085545716, 5566.0, 5585.190147428621, 5627.0, 5667.23492328536, 5676.14857771334, 4614.723116612997, 5584.385717571074, 5718.313802343714, 5747.712614369275, 5751.4536119068625, 5007.273945277951, 5039.344885164924, 5090.567301779353, 5102.566281866046, 5307.946920530803, 4366.432363792703, 5220.817271395502, 5254.080535906221, 5260.316158899338, 5330.440303768107, 5095.031943930641, 5105.795768169269, 5140.40885298188, 5149.536541711245, 5261.605379596526, 5155.994581056695, 5175.689458632494, 5185.274061631337, 5190.403103643054, 5200.869978250536, 5001.349824522788, 5080.034793389244, 5084.694918304914, 5086.745662716849, 5135.12702840518, 5512.531434828044, 5642.605017648766, 5649.971750141223, 5671.149327600851, 5674.205611214434, 6044.909326360108, 6146.078088286748, 6169.0, 6181.96909015452, 6186.133941248228, 6069.9848721573035, 6112.755489780404, 6130.080487926802, 6159.599626024339, 6244.500439964792, 5791.768329266824, 5802.970985145132, 5807.332156801979, 5813.680247586339, 5816.040353341736, 6147.739713358751, 6203.286622038523, 6222.377762223741, 6232.158658581034, 6249.562530622904, 6014.729337179838, 6081.75672973085, 6102.603330783485, 6115.327313995457, 6125.387461253839, 6135.538906164978, 6198.690065496708, 6201.348858369896, 6228.096925945712, 6242.032484964838, 6105.32841387447, 6127.5404344674325, 6162.5378096642935, 6184.845378865474, 6197.628142311386, 6100.938990610126, 6115.666295175888, 6228.937710622927, 6236.282827474808, 6250.499960003189, 4815.470298267188, 4907.435644900882, 4907.509249075135, 4910.607151427877, 4931.827386041471, 4373.75944323059, 4384.408104905797, 4393.0994146199955, 4396.912061758747, 4431.556844315543, 5495.120780675073, 5676.460736230051, 5736.741846616912, 5739.454751798656, 5812.9709351452975, 6174.66039367839, 6183.134361189437, 6221.595596286227, 6226.844328891814, 6234.812955611339, 5727.627704199213, 5755.0, 5767.336756273083, 5773.653580785166, 5784.132380143013, 5239.423663397023, 5374.328208973879, 5375.032494151091, 5382.757775900016, 5391.433899440581, 6156.661383623944, 6196.380361963767, 6213.099100630383, 6219.626822390671, 6273.121762953099, 6232.283287421823, 6252.843678908063, 6257.7184026718, 6259.499240060875, 6267.059941008885, 6152.969235153795, 6188.504919606331, 6238.563300568917, 6245.28179259391, 6252.218472690914, 6110.51115910735, 6136.079588061826, 6152.138700581832, 6208.099825525183, 6212.968935155295, 6258.0, 6258.874822503433, 6259.436650701458, 6268.1851359323, 6280.0, 5921.61509501881, 5980.551458640706, 6090.725917333642, 6101.145839582373, 6124.081387791867, 5973.790917317873, 6054.33402325735, 6064.727087280995, 6071.453569178795, 6088.6042407243385, 5640.097584386478, 5875.853103672443, 5879.7060146993335, 5900.144479050427, 5912.320083190488, 5836.182934389104, 5854.297484301911, 5880.8235752927485, 6030.246219222598, 6087.939120608914, 4963.801447942042, 5839.620424672383, 5849.268841394825, 5870.647761134345, 5874.177615133799], "value": 5643.027472924667, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [500.14225603090705, 508.0879820721804, 522.7516929458508, 540.0090833320857, 555.0093083845337, 30836.53745193821, 33746.49380259295, 44104.3384349235, 44329.33505997409, 47251.0, 44578.0, 44951.4267000655, 44960.77519612397, 45017.324740128926, 45042.32436513447, 8645.740627781177, 9580.999999999982, 9814.067663571985, 9818.263630227726, 9831.016898310147, 8648.135186481364, 8726.127387261287, 8754.343424243176, 8757.51833649149, 11745.23655962364, 11485.25345852521, 11742.706432339217, 11745.0, 11752.0, 11761.588344407948, 11633.883661163365, 11748.882511174865, 11754.823677644885, 11758.706032349173, 11764.411779410995, 8119.350451963829, 8373.581320933929, 11442.313461192354, 11577.884221157765, 11686.240394374423, 41850.581494184975, 42318.36522452166, 42342.78828605868, 42419.0, 42439.78780106111, 3049.8017628854277, 3228.386606544749, 3236.0291912426414, 3250.0606354596102, 3305.86776528941, 29201.853990730124, 30167.547486787716, 31248.0, 31248.906288280028, 31280.061598152093, 2710.9322266943286, 2745.2450576091533, 2756.2282560882986, 2767.2943399433216, 2830.2641313258496, 471371.0, 473043.0, 475203.0, 475379.0, 476791.0, 63150.368496315365, 64765.67617161884, 64858.0, 64901.67549162319, 64919.02621460682, 67086.66456667783, 67176.99234511486, 67211.0, 67224.66387668128, 67529.32470675328, 17178.141092945552, 17436.738448923275, 17468.737968930476, 17472.38846640387, 17529.649407011788, 13291.601251962456, 13353.198808071547, 13371.997100217523, 13388.19670819754, 13433.19400835953, 15570.84429155694, 15891.761623575656, 15934.60163495922, 16217.108059056629, 16218.75671864923, 5434.673919564839, 6298.244210694655, 7076.8449812628005, 7107.857842843114, 7187.676554555058, 920.7652048727532, 927.9072092790667, 967.7387105481481, 1003.087190656499, 1021.1830535571662, 23978.640320395207, 29540.113796586138, 29683.851580741957, 29844.253893652833, 30733.84633076865, 1039.880413752413, 1129.4917287220783, 1156.306216270231, 1176.8764279750674, 1229.489761748882, 15081.698366032837, 15513.922430387776, 15543.222838858012, 15564.066156030674, 15867.285972131282, 580210.0, 635569.0, 639382.0, 645181.0, 651621.0, 48384.0, 49446.50553494491, 53670.73164634152, 53775.46224537783, 59341.70329148327, 1351.8783309502192, 1378.06980288305, 1453.0, 1492.2389581313826, 1496.1172907984123, 30250.09249722512, 30568.541471877495, 30611.387772243987, 31091.84454077715, 31402.528962066044, 51534.22698659598, 52030.479695202564, 52682.47317526929, 52693.31146819686, 52750.47249527455, 507578.0, 509333.0, 510078.0, 511126.0, 839332.0, 26307.342316442242, 41016.974575635846, 42005.36991945062, 42715.35926961036, 44352.0, 45595.77202114101, 47218.81952951204, 47426.52573474221, 48851.755741221066, 49070.0, 37221.0, 38575.61424385833, 38669.6133038666, 39355.6064439352, 39491.80254098711, 37748.62251377451, 38076.23847523089, 38433.615663844124, 38845.41731874081, 38895.611043890334, 37792.811035944644, 38296.808515957244, 38879.6112038876, 38893.0, 38947.6105238944, 29841.657125428974, 30008.699913001463, 34085.82957085199, 34227.315453691284, 34228.315433691685, 22048.33854984353, 22763.0, 24290.271291861274, 24731.0, 25586.360340991625], "value": 20340.17157042692, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [362.46898293999294, 387.8681248375553, 393.6339204539777, 396.396575850684, 423.49180982820604, 17988.0, 19868.50328741782, 20347.593048139046, 20477.0, 21649.134034638595, 19787.604247915035, 20230.0, 20280.98595070248, 20345.98270086497, 20738.48153796152, 5716.39977802331, 5776.249087618625, 6000.489958353545, 6009.519238460913, 6024.8493787655225, 5909.970450147744, 5912.379200183983, 5957.791477298296, 5992.760289588411, 6016.518678505731, 5745.683987380692, 5881.441263080001, 5925.970370148143, 5926.585139040269, 5931.199288096116, 5674.574406919498, 5866.413358664142, 5925.0, 5937.673427961482, 6044.546659000549, 5841.795537156222, 5847.0, 5861.501772349356, 5865.237519122486, 5868.735906884201, 13360.265185414799, 23382.0, 24063.759362406327, 24209.152679656305, 24631.0, 2044.9591008179902, 2116.449723072012, 2215.656573231152, 2270.71616047994, 2492.7258001619807, 9705.223582113415, 9722.125008749246, 9926.255530835217, 10068.546915388824, 10210.18318534523, 1666.5916850371616, 1721.5351854999205, 1764.6382491589193, 1773.654137443199, 1825.3520000399933, 108339.45830270876, 112120.0, 112827.0, 113711.86288137095, 113808.43095784551, 11462.0, 11475.254108483088, 11602.419879006016, 11664.533418663328, 11690.181687281809, 14885.851141488663, 14920.328585213474, 14929.029613075116, 14947.626309342353, 14966.17686027279, 7399.630018499053, 8709.651613935499, 8764.75703989528, 8767.561621919007, 8798.692045778367, 8665.523396213268, 8696.086910874266, 8753.343499237582, 8808.427452215714, 9076.8638470423, 4538.909221815546, 4553.726776393427, 4562.634989200856, 4568.565986231302, 4602.148602508547, 1663.4676903391041, 1904.5429097016893, 1930.5289229785897, 1940.194819150065, 1967.6950072738748, 830.2071521696765, 896.7758060484879, 922.9261659067258, 983.9655612053687, 1060.8302671572512, 18820.15309311057, 20375.4906127348, 20540.897295513427, 20697.896510517352, 20698.482537936372, 868.9304855611535, 965.1362030982372, 976.3995142987012, 1000.2498126405196, 1020.6631811502186, 10730.980556847087, 10889.0, 10899.400532970603, 10942.4528773561, 10946.671599852018, 529689.7031029718, 570402.0, 573251.0, 591054.0, 592126.0, 120727.39636301763, 137923.0, 140004.0, 142139.0, 142303.0, 979.1236843025453, 990.6136606723384, 998.2164001259122, 1003.7239759066315, 1021.3361315145215, 17189.0, 18120.18459169314, 18293.634127317644, 18326.900385976885, 18746.906265468584, 43399.132017360105, 45065.77467112643, 53692.89917457835, 53763.0, 54020.0, 75433.0, 75652.73041808755, 75785.62107189429, 78617.0, 78853.0, 7847.293743563107, 7876.054873415227, 7912.169222231582, 7959.681612735426, 8063.596820159086, 10846.67459976202, 11938.22401543897, 12013.75972480563, 12216.26702397859, 12306.07704422154, 7046.4010559101, 9922.206223502248, 10373.54770332139, 10686.305390149615, 10732.839007415052, 10163.288569800043, 10182.032706892833, 10320.27758056959, 10486.318389304672, 10596.682099537025, 10573.62992295266, 10595.364278143335, 10620.415877126832, 10640.201984851012, 10826.025657690845, 7813.3749300057, 7850.3701260078515, 7892.921070789219, 7992.200779922194, 8008.6396112173925, 7538.057742782153, 7577.507462014952, 7598.354139898171, 7938.642761075881, 8037.156098609562], "value": 9326.995218250975, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1418.7162567486491, 1457.5117335692541, 1564.9843501564985, 1790.3017823049013, 1936.9225230990762, 6914.481413893959, 8565.0, 8816.911830881705, 8844.823103537925, 8948.463092214472, 8511.78720531987, 8782.824343513126, 8782.868256976151, 8795.0, 8796.296296296297, 2428.150147448392, 2477.2568229531157, 2478.677771889652, 2479.293401380598, 2479.3429741118616, 2402.2793162051403, 2466.4450498637843, 2468.9135880244194, 2469.333280014395, 2472.3819045238693, 2433.634954756792, 2438.207582535683, 2439.3413778279855, 2454.6686197363306, 2463.6797216361847, 2385.7614238576175, 2386.033656369173, 2416.2026531244646, 2416.984866356126, 2419.27421773469, 2317.663938728892, 2373.940651483727, 2383.7020372453444, 2386.009805930537, 2387.3792813868345, 7806.921930780733, 8033.517988920684, 8757.693480728143, 8843.0, 8869.0, 4930.753462326869, 5096.159133742928, 5102.540771330598, 5138.152204886189, 5225.738713064332, 2796.832190068582, 3549.1482044309437, 3681.9263614727824, 3693.2059607184315, 3700.5929347771726, 1015.9949200253951, 1051.3901936876593, 1053.7629033467408, 1056.4823236614081, 1063.415121683079, 11481.253718508271, 11618.593349232737, 11622.302661840316, 11634.883651163465, 11652.242604230783, 4545.795439205243, 4824.396950381202, 4844.370231869834, 5005.6996580205305, 5028.296038554606, 10001.699949001542, 10007.849882251627, 10019.298649094493, 10023.047810457994, 10027.097561219525, 5514.6691198527415, 5521.00621888064, 5522.199281104297, 5531.032069387841, 5539.947409992089, 4210.0, 4941.0, 5029.321041659402, 5048.040872234264, 5064.088464076428, 4059.65492933104, 4106.0, 4128.215900946556, 4141.109661422808, 4224.197402493516, 1339.519830587187, 1367.9794803077962, 1368.4868174434587, 1379.0415661115428, 1384.0795870746042, 854.7179430787825, 911.8541033434618, 923.8891333039944, 968.1818863060843, 979.2215188924822, 5961.374055724086, 6042.758289668452, 6068.089786532056, 6100.72546735398, 6159.84600384985, 471.34953763806413, 471.75940270461626, 475.62900937268984, 476.0, 490.7423602608588, 3351.4302568563403, 4033.2941735196223, 4094.570070142592, 4168.228877657643, 4248.85129020489, 10445.007724266183, 10703.571857125628, 10807.135429165648, 10828.971247731453, 10848.294860834021, 10560.155187584975, 10635.042846143884, 10657.786844263226, 10767.461626918623, 10834.02493775564, 8024.197580242046, 8222.753317400488, 8245.0, 8277.255047045674, 8294.0, 5790.1893734877995, 5994.490468310242, 6034.426729460694, 6148.1085242640465, 6246.781362652468, 9483.95258023733, 10000.949995249977, 10173.796524069625, 10268.64059757905, 10291.794164116825, 10050.296479246676, 10153.340032897839, 10219.182465402899, 10343.27597068198, 10345.793084138124, 7466.0, 7506.512076714996, 7536.7362142324755, 7675.270849269311, 7675.961620191863, 8210.63052162642, 8229.753107406787, 8321.58533049371, 8330.833383332421, 8357.038940521674, 6832.180138383425, 7391.667374968247, 7480.102387713509, 7643.0, 7669.846603068019, 7210.675519601526, 7366.63166841645, 7372.115346158495, 7406.962965185355, 7461.4030877528585, 7013.333733295329, 7060.3292687194635, 7102.715891364288, 7111.146662400338, 7128.85742285141, 4831.637627177905, 4872.780724867319, 4975.378077740283, 5039.244113383022, 5162.9483705162465, 4942.159832828356, 5040.193569028937, 5077.593792496666, 5144.0, 5312.734363281743], "value": 4559.833253361178, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [305.17602473322034, 309.71970366818033, 325.26165604078756, 325.43374528320714, 326.97874638148517, 4552.45370555533, 4861.756912154396, 4933.741895816566, 5140.784556663528, 5288.444713305105, 5012.954208495367, 5053.848384548461, 5207.687538747668, 5217.843464696065, 5290.603204759649, 3581.910452238689, 3785.5835858055593, 4089.6280157508572, 4094.6724262058965, 4125.525564560076, 3636.799976001332, 3767.077066118791, 3941.724079314449, 4086.346184610463, 4116.526599441073, 3297.093299342676, 3646.7994260315672, 4037.919241615166, 4070.75575465473, 4073.714839961204, 3842.9615703842883, 4046.393041043867, 4064.8170832312617, 4080.2247572961337, 4156.625903668654, 2815.9155225343275, 3512.841922113511, 3554.4668299755244, 3561.7506774525796, 3582.46263060543, 4448.243798554254, 4492.820287188509, 4762.523747625244, 4828.5412885775795, 5002.89936214036, 3761.54861416629, 4120.299549076664, 4532.3201519771965, 4581.358609794632, 4587.885302867422, 5553.250311207976, 5587.0, 5598.720063996784, 5621.01632214365, 5624.3813180550105, 3373.1567108222944, 3682.392405253157, 3688.963110368889, 3853.344931361675, 3854.383298672227, 5731.140328950649, 5763.538916886639, 5801.941980580183, 5803.53571714266, 5870.207521984563, 3221.5328777327154, 3643.5952271500682, 3895.4028848172115, 3921.8627348042683, 3943.6056394360335, 3548.716102711777, 3667.394879844796, 3920.4511368408444, 3977.6817854571564, 3994.6205110514456, 3857.884263472044, 4118.423420721101, 4434.955650443519, 4527.3435351873795, 4596.379488768974, 5200.7139607321205, 5606.523445507096, 5610.298712660918, 5634.239377684042, 5644.774209031675, 1174.8472698549133, 1227.643983244849, 1228.7112528555783, 1246.7506498700116, 1265.949262112463, 812.5652775764937, 852.377764232114, 867.7244450657523, 873.5326600268826, 878.8989266234337, 4373.753480258175, 4545.227311357077, 4933.851984440473, 4971.353724015855, 5034.137369173356, 4789.928151077736, 5130.461301563357, 5398.379186393537, 5472.196823207912, 5477.972610137003, 4354.105963905711, 4361.672874534423, 4603.516630753789, 4713.727293630701, 4923.655344125948, 2661.4144888124583, 2677.6611694152925, 2679.7356233982255, 2864.3268831824503, 2865.7850661200077, 4890.755462226874, 5322.787088516416, 5370.395358251245, 5655.907314293738, 5715.514181294553, 5139.922901156486, 5353.518183363438, 5509.669419834822, 5632.648164440505, 5929.407059294036, 5605.990921634064, 5884.762970661117, 6028.889888538976, 6035.0, 6042.123892035543, 4894.4616092230535, 5595.300587426572, 5793.0, 5846.766129354778, 5896.764129434946, 5451.3458384994055, 5731.96206010815, 5733.254676891977, 5735.598508104393, 5744.396838332083, 4663.20725476663, 5141.38303403594, 5801.955647983404, 5960.4635582797755, 5997.280326360865, 3611.6207798180812, 4052.311107111857, 4068.0, 4068.7558746474124, 4082.265192265421, 5308.4160742317545, 5388.811391601432, 5489.0, 5613.438656134354, 5740.531571424161, 5223.347081614798, 5286.444923283, 5488.4785945335125, 5495.230667706444, 5628.8311350657905, 4919.0, 5391.676499410048, 5568.832935011957, 5593.916091258716, 5648.858778530405, 4690.765461726968, 5068.695878247317, 5433.293671822637, 5485.424030476902, 5504.311961004874, 4949.876253093701, 5543.944560554504, 5570.0, 5622.437756224344, 5626.324841019104, 5477.370102438112, 5483.122700367922, 5504.0, 5543.334799824047, 5551.335359027608], "value": 4021.127130640585, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [645.7352485480953, 648.6205569741701, 656.4584218020132, 715.3669002932406, 717.8959050937611, 14469.493567725118, 15095.849041509582, 15098.849011509881, 15111.395544178233, 15125.848741512582, 13412.0, 14314.35585398657, 14346.56960291193, 14350.138991660482, 14374.20941848198, 716.4698123388681, 741.2550386861197, 903.1104362203224, 909.236241557092, 919.6873063158524, 907.541691445819, 931.5063016601213, 933.7432206143297, 933.9912894074383, 935.6584846531001, 973.4013581647272, 983.9262055345841, 984.8178087053882, 985.4087547471531, 987.323683276954, 749.407967705512, 944.390867890207, 973.474323865112, 975.6438899801557, 977.8337682593976, 713.5754226235382, 714.4498735973324, 714.907062081926, 715.2204097533713, 715.5241764226782, 14468.98717089804, 14531.12813231199, 14535.345909433996, 14565.708685826225, 14565.927170364186, 12129.514819407214, 12138.878611213951, 12375.690607734789, 12434.378281086, 12443.502259909592, 10624.840627390595, 10685.305455145392, 10734.946325268402, 11093.889061109447, 11257.099432045417, 3980.900477488057, 3983.820728067244, 3986.5814089520472, 4108.342665173588, 4138.048248902769, 74218.25781742168, 74572.0, 75226.49547009029, 76855.23144768593, 77299.84050239195, 9309.487978161265, 9398.624055037722, 9502.477363744922, 9740.074692904163, 9841.753956151153, 20964.685529717066, 20997.475063123544, 21007.159713611283, 21047.78952210489, 21120.68318975186, 6976.372126508537, 7036.964815175994, 7066.293370662996, 7099.538529995534, 7220.725471476468, 4089.6114869087387, 4126.938095928564, 4131.772752498641, 4141.461609990682, 4149.6265336119895, 2993.0, 3434.261633748756, 3515.0860776198374, 3520.9647903520636, 3524.964750352515, 616.1189499016449, 625.9530535209877, 631.4411745605172, 642.3191417097892, 651.90547370631, 528.6959998001165, 546.8960897429555, 559.4377650461261, 605.9272887253558, 637.6620391192675, 6871.347222013901, 7837.882431763528, 8016.639251233709, 8160.959195204105, 8259.256666899888, 496.2927827845306, 536.5272327461145, 544.335910189568, 556.1574215064181, 595.9987221467975, 3180.634227063871, 3768.8304026318883, 3905.9804700976315, 3929.31237033518, 3958.960410395859, 70458.23854403579, 77140.0, 78627.0, 79132.0, 79417.0, 70861.0, 71139.0, 71505.0, 72184.63907680532, 73495.0, 62183.6890815543, 62517.68741156265, 63156.0, 63224.103276902686, 63583.0, 5956.612820166675, 7684.8463030740195, 7693.269139431745, 7750.031246094239, 7854.842903142019, 41125.0, 41530.37704434375, 41643.16713665771, 41890.58109418867, 42035.949101272716, 54446.45553544414, 56056.71971640279, 56283.71858140683, 56318.43681563132, 57106.14340784975, 10338.689839304832, 10416.166706663296, 10612.366674125518, 10875.51060202277, 11337.829932550854, 10274.0, 10870.347779133277, 11003.17476189305, 11175.050120739828, 11404.885951140383, 9891.851622225817, 10179.236557258087, 10242.795144097225, 10899.618513351994, 10962.561497540328, 10238.948805256225, 10466.267876242906, 10516.273291925465, 10613.257072004886, 10899.618513351994, 9988.400695958266, 10192.694219173436, 10340.327878687862, 10531.157507399543, 10687.839682404614, 8723.91276087231, 8785.341099417697, 8868.689595864114, 8921.464712117293, 9063.13900179482, 8294.83410331802, 8813.91186088131, 8922.286217102486, 9032.277417806692, 9077.09229077087], "value": 6599.875897100535, "unit": "score"}], "name": "dromaeo_css", "value": 7113.469012002666, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 108.0, 112.0, 112.0, 110.0, 112.0, 108.0, 112.0, 108.0, 154.0], "value": 114.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [157.0, 119.0, 141.0, 165.0, 116.0, 127.0, 167.0, 135.0, 194.0, 114.0], "value": 143.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [185.0, 188.0, 188.0, 186.0, 187.0, 183.0, 183.0, 183.0, 187.0, 184.0], "value": 185.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 74.0, 60.0, 104.0, 54.0, 62.0, 56.0, 58.0, 113.0, 54.0], "value": 71.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 101.0, 100.0, 78.0, 82.0, 76.0, 101.0, 80.0, 77.0, 100.0], "value": 88.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 86.0, 89.0, 87.0, 89.0, 86.0, 90.0, 87.0, 88.0, 82.0], "value": 87.2, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 95.0, 104.0, 145.0, 145.0, 95.0, 95.0, 95.0, 93.0], "value": 105.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 85.0, 85.0, 84.0, 85.0, 85.0, 85.0, 83.0, 88.0, 84.0], "value": 84.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [46.0, 47.0, 80.0, 79.0, 80.0, 79.0, 79.0, 77.0, 57.0, 57.0], "value": 68.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 81.0, 93.0, 95.0, 104.0, 98.0, 75.0, 69.0, 69.0, 70.0], "value": 81.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 83.0, 86.0, 84.0, 83.0, 81.0, 85.0, 84.0, 84.0, 86.0], "value": 84.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [141.0, 138.0, 139.0, 137.0, 133.0, 134.0, 140.0, 143.0, 196.0, 138.0], "value": 143.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [168.0, 169.0, 165.0, 214.0, 205.0, 167.0, 168.0, 167.0, 218.0, 165.0], "value": 180.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 66.0, 68.0, 66.0, 66.0, 66.0, 66.0, 65.0, 64.0, 66.0], "value": 65.8, "unit": "score"}], "name": "kraken", "value": 1504.9999999999998, "alertThreshold": 2.0}]} 10:13:26 INFO - Return code: 0 10:13:26 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 10:13:26 ERROR - # TBPL SUCCESS # 10:13:26 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 10:13:26 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 10:13:26 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 10:13:26 INFO - 2016-05-06 10:13:26.912 screenresolution[2055:16864] starting screenresolution argv=screenresolution get 10:13:26 INFO - 2016-05-06 10:13:26.914 screenresolution[2055:16864] Display 0: 1600x1200x32@60 10:13:26 INFO - 2016-05-06 10:13:26.920 screenresolution[2056:16867] starting screenresolution argv=screenresolution list 10:13:26 INFO - Available Modes on Display 0 10:13:26 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 10:13:26 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 10:13:26 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 10:13:26 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 10:13:27 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 10:13:27 INFO - Intel Iris: 10:13:27 INFO - Chipset Model: Intel Iris 10:13:27 INFO - Type: GPU 10:13:27 INFO - Bus: Built-In 10:13:27 INFO - VRAM (Dynamic, Max): 1536 MB 10:13:27 INFO - Vendor: Intel (0x8086) 10:13:27 INFO - Device ID: 0x0a2e 10:13:27 INFO - Revision ID: 0x0009 10:13:27 INFO - Displays: 10:13:27 INFO - Display: 10:13:27 INFO - Resolution: 1600 x 1200 @ 60 Hz 10:13:27 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 10:13:27 INFO - Display Serial Number: 1600x1200 60 10:13:27 INFO - Main Display: Yes 10:13:27 INFO - Mirror: Off 10:13:27 INFO - Online: Yes 10:13:27 INFO - Rotation: Supported 10:13:27 INFO - Return code: 0 10:13:27 INFO - Running post-action listener: _resource_record_post_action 10:13:27 INFO - ##### 10:13:27 INFO - ##### Finished run-tests step (success) 10:13:27 INFO - ##### 10:13:27 INFO - Running post-run listener: _resource_record_post_run 10:13:28 INFO - Total resource usage - Wall time: 1210s; CPU: 54.0%; Read bytes: 926208; Write bytes: 694742528; Read time: 12; Write time: 2023 10:13:28 INFO - install - Wall time: 34s; CPU: 55.0%; Read bytes: 392507904; Write bytes: 387469824; Read time: 29392; Write time: 739 10:13:28 INFO - run-tests - Wall time: 1177s; CPU: 54.0%; Read bytes: 922112; Write bytes: 294087680; Read time: 11; Write time: 1250 10:13:28 INFO - Running post-run listener: _upload_blobber_files 10:13:28 INFO - Blob upload gear active. 10:13:28 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 10:13:28 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 10:13:28 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', 'Fx-Team', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 10:13:28 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 Fx-Team -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 10:13:28 INFO - (blobuploader) - INFO - Open directory for files ... 10:13:28 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 10:13:28 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:13:28 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:13:29 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 10:13:29 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:13:29 INFO - (blobuploader) - INFO - Done attempting. 10:13:29 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 10:13:29 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:13:29 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:13:30 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 10:13:30 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:13:30 INFO - (blobuploader) - INFO - Done attempting. 10:13:30 INFO - (blobuploader) - INFO - Iteration through files over. 10:13:30 INFO - Return code: 0 10:13:30 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 10:13:30 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 10:13:30 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/454f707ae90235e2b4794a33b8fbd155b86297cb5491573c636ba49a5d8bb21df02de1ba0dca8fc2901657c46f85605c58ba242dd8bf4ff43f9927d94df41f32", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/84f1508900840085c53c85e63208ba4c71b4e04e3e7576ba8912af4e2e083a49622b8204bffb996c63d13c6a6351440f0532fbb33548415b78195b492f078455"} 10:13:30 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 10:13:30 INFO - Writing to file /builds/slave/test/properties/blobber_files 10:13:30 INFO - Contents: 10:13:30 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/454f707ae90235e2b4794a33b8fbd155b86297cb5491573c636ba49a5d8bb21df02de1ba0dca8fc2901657c46f85605c58ba242dd8bf4ff43f9927d94df41f32", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/84f1508900840085c53c85e63208ba4c71b4e04e3e7576ba8912af4e2e083a49622b8204bffb996c63d13c6a6351440f0532fbb33548415b78195b492f078455"} 10:13:30 INFO - Running post-run listener: copy_logs_to_upload_dir 10:13:30 INFO - Copying logs to upload dir... 10:13:30 INFO - mkdir: /builds/slave/test/build/upload/logs 10:13:30 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1264.653910 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 4 secs) (at 2016-05-06 10:13:30.418948) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-06 10:13:30.422711) ========= 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: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.TGvpKmRPhW/Render GIT_SHARE_BASE_DIR=/builds/git-shared HG_SHARE_BASE_DIR=/builds/hg-shared HOME=/Users/cltbld IDLEIZER_DISABLE_SHUTDOWN=true LOGNAME=cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NO_EM_RESTART=1 PAGER=/bin/cat PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11 PWD=/builds/slave/test/properties RUNNER_CONFIG_CMD=/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg SHELL=/bin/bash SSH_AUTH_SOCK=/private/tmp/com.apple.launchd.ekx6weuAyS/Listeners TMPDIR=/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/454f707ae90235e2b4794a33b8fbd155b86297cb5491573c636ba49a5d8bb21df02de1ba0dca8fc2901657c46f85605c58ba242dd8bf4ff43f9927d94df41f32", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/84f1508900840085c53c85e63208ba4c71b4e04e3e7576ba8912af4e2e083a49622b8204bffb996c63d13c6a6351440f0532fbb33548415b78195b492f078455"} build_url:https://queue.taskcluster.net/v1/task/PireGrcHSxyTuLLRjDgxdA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011070 build_url: 'https://queue.taskcluster.net/v1/task/PireGrcHSxyTuLLRjDgxdA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/454f707ae90235e2b4794a33b8fbd155b86297cb5491573c636ba49a5d8bb21df02de1ba0dca8fc2901657c46f85605c58ba242dd8bf4ff43f9927d94df41f32", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/84f1508900840085c53c85e63208ba4c71b4e04e3e7576ba8912af4e2e083a49622b8204bffb996c63d13c6a6351440f0532fbb33548415b78195b492f078455"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-06 10:13:30.454222) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-06 10:13:30.455036) ========= rm -f oauth.txt in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-f', 'oauth.txt'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.TGvpKmRPhW/Render GIT_SHARE_BASE_DIR=/builds/git-shared HG_SHARE_BASE_DIR=/builds/hg-shared HOME=/Users/cltbld IDLEIZER_DISABLE_SHUTDOWN=true LOGNAME=cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NO_EM_RESTART=1 PAGER=/bin/cat PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11 PWD=/builds/slave/test RUNNER_CONFIG_CMD=/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg SHELL=/bin/bash SSH_AUTH_SOCK=/private/tmp/com.apple.launchd.ekx6weuAyS/Listeners TMPDIR=/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False program finished with exit code 0 elapsedTime=0.005236 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-06 10:13:30.507546) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-06 10:13:30.508302) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-06 10:13:30.509008) ========= ========= Total master_lag: 0.17 =========