builder: fx-team_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0016 starttime: 1457145042.71 results: success (0) buildid: 20160304155027 builduid: 9a416a62a1e54cb69a34497648f791f6 revision: 69d357fb6a0b38239b855a9aeea863d035f9fca5 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-03-04 18:30:42.712684) ========= master: http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-03-04 18:30:42.713237) ========= ========= Started set props: basedir (results: 0, elapsed: 14 secs) (at 2016-03-04 18:30:42.713527) ========= bash -c pwd in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'pwd'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.h6VlQgQM7n/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.bimA75Iz0Q/Listeners TMPDIR=/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/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 /builds/slave/test program finished with exit code 0 elapsedTime=0.005961 basedir: '/builds/slave/test' ========= master_lag: 14.04 ========= ========= Finished set props: basedir (results: 0, elapsed: 14 secs) (at 2016-03-04 18:30:56.754662) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 8 secs) (at 2016-03-04 18:30:56.758666) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 8 secs) (at 2016-03-04 18:31:05.728955) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 1 secs) (at 2016-03-04 18:31:05.729618) ========= rm -rf properties in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-rf', 'properties'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.h6VlQgQM7n/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.bimA75Iz0Q/Listeners TMPDIR=/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/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.005110 ========= master_lag: 1.25 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 1 secs) (at 2016-03-04 18:31:06.985088) ========= ========= Started set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2016-03-04 18:31:06.985424) ========= script_repo_url: https://hg.mozilla.org/build/mozharness ========= Finished set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2016-03-04 18:31:06.985775) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-03-04 18:31:06.986046) ========= 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.h6VlQgQM7n/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.bimA75Iz0Q/Listeners TMPDIR=/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/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-03-04 18:31:07-- 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% 8.37M=0.001s 2016-03-04 18:31:08 (8.37 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.578771 ========= master_lag: 2.14 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-03-04 18:31:09.701976) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 3 secs) (at 2016-03-04 18:31:09.702304) ========= rm -rf scripts in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-rf', 'scripts'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.h6VlQgQM7n/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.bimA75Iz0Q/Listeners TMPDIR=/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/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.019684 ========= master_lag: 3.26 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 3 secs) (at 2016-03-04 18:31:12.979953) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-03-04 18:31:12.980309) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 69d357fb6a0b38239b855a9aeea863d035f9fca5 --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 69d357fb6a0b38239b855a9aeea863d035f9fca5 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.h6VlQgQM7n/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.bimA75Iz0Q/Listeners TMPDIR=/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/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-03-04 18:31:13,233 truncating revision to first 12 chars 2016-03-04 18:31:13,233 Setting DEBUG logging. 2016-03-04 18:31:13,233 attempt 1/10 2016-03-04 18:31:13,233 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/69d357fb6a0b?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-03-04 18:31:13,983 unpacking tar archive at: fx-team-69d357fb6a0b/testing/mozharness/ program finished with exit code 0 elapsedTime=0.996505 ========= master_lag: 0.91 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-03-04 18:31:14.888892) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-03-04 18:31:14.889313) ========= echo 69d357fb6a0b38239b855a9aeea863d035f9fca5 in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['echo', u'69d357fb6a0b38239b855a9aeea863d035f9fca5'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.h6VlQgQM7n/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.bimA75Iz0Q/Listeners TMPDIR=/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/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 69d357fb6a0b38239b855a9aeea863d035f9fca5 program finished with exit code 0 elapsedTime=0.005040 script_repo_revision: '69d357fb6a0b38239b855a9aeea863d035f9fca5' ========= master_lag: 0.24 ========= ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-03-04 18:31:15.129920) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-03-04 18:31:15.130245) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-03-04 18:31:15.298552) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 1 secs) (at 2016-03-04 18:31:15.298868) ========= /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.h6VlQgQM7n/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.bimA75Iz0Q/Listeners TMPDIR=/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/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 18:31:15 INFO - MultiFileLogger online at 20160304 18:31:15 in /builds/slave/test 18:31:15 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 18:31:15 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 18:31:15 INFO - {'append_to_log': False, 18:31:15 INFO - 'base_work_dir': '/builds/slave/test', 18:31:15 INFO - 'blob_upload_branch': 'Fx-Team', 18:31:15 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 18:31:15 INFO - 'branch': 'Fx-Team', 18:31:15 INFO - 'buildbot_json_path': 'buildprops.json', 18:31:15 INFO - 'config_files': ('talos/mac_config.py',), 18:31:15 INFO - 'default_actions': ('clobber', 18:31:15 INFO - 'read-buildbot-config', 18:31:15 INFO - 'download-and-extract', 18:31:15 INFO - 'populate-webroot', 18:31:15 INFO - 'create-virtualenv', 18:31:15 INFO - 'install', 18:31:15 INFO - 'run-tests'), 18:31:15 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 18:31:15 INFO - 'download_minidump_stackwalk': True, 18:31:15 INFO - 'download_symbols': 'ondemand', 18:31:15 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 18:31:15 INFO - 'tooltool.py': '/tools/tooltool.py', 18:31:15 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 18:31:15 INFO - '/tools/misc-python/virtualenv.py')}, 18:31:15 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 18:31:15 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 18:31:15 INFO - 'installer_path': 'installer.exe', 18:31:15 INFO - 'log_level': 'info', 18:31:15 INFO - 'log_name': 'talos', 18:31:15 INFO - 'log_to_console': True, 18:31:15 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 18:31:15 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 18:31:15 INFO - 'opt_config_files': (), 18:31:15 INFO - 'pip_index': False, 18:31:15 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 18:31:15 INFO - 'cmd': ('bash', 18:31:15 INFO - '-c', 18:31:15 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 18:31:15 INFO - 'enabled': True, 18:31:15 INFO - 'halt_on_failure': False, 18:31:15 INFO - 'name': 'check_screen_resolution'},), 18:31:15 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 18:31:15 INFO - 'cmd': ('bash', 18:31:15 INFO - '-c', 18:31:15 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 18:31:15 INFO - 'enabled': True, 18:31:15 INFO - 'halt_on_failure': False, 18:31:15 INFO - 'name': 'check_screen_resolution'},), 18:31:15 INFO - 'run_cmd_checks_enabled': True, 18:31:15 INFO - 'sps_profile': False, 18:31:15 INFO - 'sps_profile_interval': 0, 18:31:15 INFO - 'suite': 'dromaeojs', 18:31:15 INFO - 'system_bits': '32', 18:31:15 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 18:31:15 INFO - 'title': 't-yosemite-r7-0016', 18:31:15 INFO - 'tooltool_cache': '/builds/tooltool_cache', 18:31:15 INFO - 'use_talos_json': True, 18:31:15 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 18:31:15 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 18:31:15 INFO - 'work_dir': 'build'} 18:31:15 INFO - ##### 18:31:15 INFO - ##### Running clobber step. 18:31:15 INFO - ##### 18:31:15 INFO - Running pre-action listener: _resource_record_pre_action 18:31:15 INFO - Running main action method: clobber 18:31:15 INFO - rmtree: /builds/slave/test/build 18:31:15 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 18:31:16 INFO - Running post-action listener: _resource_record_post_action 18:31:16 INFO - ##### 18:31:16 INFO - ##### Running read-buildbot-config step. 18:31:16 INFO - ##### 18:31:16 INFO - Running pre-action listener: _resource_record_pre_action 18:31:16 INFO - Running main action method: read_buildbot_config 18:31:16 INFO - Using buildbot properties: 18:31:16 INFO - { 18:31:16 INFO - "project": "", 18:31:16 INFO - "product": "firefox", 18:31:16 INFO - "script_repo_revision": "production", 18:31:16 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs", 18:31:16 INFO - "repository": "", 18:31:16 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs", 18:31:16 INFO - "buildid": "20160304155027", 18:31:16 INFO - "pgo_build": "False", 18:31:16 INFO - "basedir": "/builds/slave/test", 18:31:16 INFO - "buildnumber": 545, 18:31:16 INFO - "slavename": "t-yosemite-r7-0016", 18:31:16 INFO - "master": "http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/", 18:31:16 INFO - "platform": "yosemite_r7", 18:31:16 INFO - "scheduler": "tests-fx-team-macosx64-talos", 18:31:16 INFO - "branch": "fx-team", 18:31:16 INFO - "repo_path": "integration/fx-team", 18:31:16 INFO - "revision": "69d357fb6a0b38239b855a9aeea863d035f9fca5", 18:31:16 INFO - "stage_platform": "macosx64", 18:31:16 INFO - "builduid": "9a416a62a1e54cb69a34497648f791f6", 18:31:16 INFO - "slavebuilddir": "test" 18:31:16 INFO - } 18:31:16 INFO - Found installer url https://queue.taskcluster.net/v1/task/QWDN3FC0Spa76pCf-e2VFA/artifacts/public/build/firefox-47.0a1.en-US.mac.dmg. 18:31:16 INFO - Running post-action listener: _resource_record_post_action 18:31:16 INFO - ##### 18:31:16 INFO - ##### Running download-and-extract step. 18:31:16 INFO - ##### 18:31:16 INFO - Running pre-action listener: _resource_record_pre_action 18:31:16 INFO - Running main action method: download_and_extract 18:31:16 INFO - mkdir: /builds/slave/test/build/tests 18:31:16 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')]} 18:31:16 INFO - https://queue.taskcluster.net/v1/task/QWDN3FC0Spa76pCf-e2VFA/artifacts/public/build/firefox-47.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 18:31:16 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QWDN3FC0Spa76pCf-e2VFA/artifacts/public/build/firefox-47.0a1.en-US.mac.test_packages.json 18:31:16 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QWDN3FC0Spa76pCf-e2VFA/artifacts/public/build/firefox-47.0a1.en-US.mac.test_packages.json 18:31:16 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QWDN3FC0Spa76pCf-e2VFA/artifacts/public/build/firefox-47.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-47.0a1.en-US.mac.test_packages.json 18:31:16 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QWDN3FC0Spa76pCf-e2VFA/artifacts/public/build/firefox-47.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-47.0a1.en-US.mac.test_packages.json'}, attempt #1 18:31:17 INFO - Downloaded 1277 bytes. 18:31:17 INFO - Reading from file /builds/slave/test/build/firefox-47.0a1.en-US.mac.test_packages.json 18:31:17 INFO - Using the following test package requirements: 18:31:17 INFO - {u'common': [u'firefox-47.0a1.en-US.mac.common.tests.zip'], 18:31:17 INFO - u'cppunittest': [u'firefox-47.0a1.en-US.mac.common.tests.zip', 18:31:17 INFO - u'firefox-47.0a1.en-US.mac.cppunittest.tests.zip'], 18:31:17 INFO - u'gtest': [u'firefox-47.0a1.en-US.mac.common.tests.zip', 18:31:17 INFO - u'firefox-47.0a1.en-US.mac.gtest.tests.zip'], 18:31:17 INFO - u'jittest': [u'firefox-47.0a1.en-US.mac.common.tests.zip', 18:31:17 INFO - u'jsshell-mac.zip'], 18:31:17 INFO - u'mochitest': [u'firefox-47.0a1.en-US.mac.common.tests.zip', 18:31:17 INFO - u'firefox-47.0a1.en-US.mac.mochitest.tests.zip'], 18:31:17 INFO - u'mozbase': [u'firefox-47.0a1.en-US.mac.common.tests.zip'], 18:31:17 INFO - u'reftest': [u'firefox-47.0a1.en-US.mac.common.tests.zip', 18:31:17 INFO - u'firefox-47.0a1.en-US.mac.reftest.tests.zip'], 18:31:17 INFO - u'talos': [u'firefox-47.0a1.en-US.mac.common.tests.zip', 18:31:17 INFO - u'firefox-47.0a1.en-US.mac.talos.tests.zip'], 18:31:17 INFO - u'web-platform': [u'firefox-47.0a1.en-US.mac.common.tests.zip', 18:31:17 INFO - u'firefox-47.0a1.en-US.mac.web-platform.tests.zip'], 18:31:17 INFO - u'webapprt': [u'firefox-47.0a1.en-US.mac.common.tests.zip'], 18:31:17 INFO - u'xpcshell': [u'firefox-47.0a1.en-US.mac.common.tests.zip', 18:31:17 INFO - u'firefox-47.0a1.en-US.mac.xpcshell.tests.zip']} 18:31:17 INFO - Downloading packages: [u'firefox-47.0a1.en-US.mac.common.tests.zip'] for test suite category: common 18:31:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:31:17 INFO - https://queue.taskcluster.net/v1/task/QWDN3FC0Spa76pCf-e2VFA/artifacts/public/build/firefox-47.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 18:31:17 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QWDN3FC0Spa76pCf-e2VFA/artifacts/public/build/firefox-47.0a1.en-US.mac.common.tests.zip 18:31:17 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QWDN3FC0Spa76pCf-e2VFA/artifacts/public/build/firefox-47.0a1.en-US.mac.common.tests.zip 18:31:17 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QWDN3FC0Spa76pCf-e2VFA/artifacts/public/build/firefox-47.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-47.0a1.en-US.mac.common.tests.zip 18:31:17 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QWDN3FC0Spa76pCf-e2VFA/artifacts/public/build/firefox-47.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-47.0a1.en-US.mac.common.tests.zip'}, attempt #1 18:31:18 INFO - Downloaded 17860931 bytes. 18:31:18 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-47.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 18:31:18 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-47.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 18:31:21 INFO - Return code: 0 18:31:21 INFO - Downloading packages: [u'firefox-47.0a1.en-US.mac.common.tests.zip', u'firefox-47.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 18:31:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:31:21 INFO - https://queue.taskcluster.net/v1/task/QWDN3FC0Spa76pCf-e2VFA/artifacts/public/build/firefox-47.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 18:31:21 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QWDN3FC0Spa76pCf-e2VFA/artifacts/public/build/firefox-47.0a1.en-US.mac.common.tests.zip 18:31:21 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QWDN3FC0Spa76pCf-e2VFA/artifacts/public/build/firefox-47.0a1.en-US.mac.common.tests.zip 18:31:21 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QWDN3FC0Spa76pCf-e2VFA/artifacts/public/build/firefox-47.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-47.0a1.en-US.mac.common.tests.zip 18:31:21 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QWDN3FC0Spa76pCf-e2VFA/artifacts/public/build/firefox-47.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-47.0a1.en-US.mac.common.tests.zip'}, attempt #1 18:31:22 INFO - Downloaded 17860931 bytes. 18:31:22 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-47.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 18:31:22 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-47.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 18:31:26 INFO - Return code: 0 18:31: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')]} 18:31:26 INFO - https://queue.taskcluster.net/v1/task/QWDN3FC0Spa76pCf-e2VFA/artifacts/public/build/firefox-47.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 18:31:26 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QWDN3FC0Spa76pCf-e2VFA/artifacts/public/build/firefox-47.0a1.en-US.mac.talos.tests.zip 18:31:26 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QWDN3FC0Spa76pCf-e2VFA/artifacts/public/build/firefox-47.0a1.en-US.mac.talos.tests.zip 18:31:26 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QWDN3FC0Spa76pCf-e2VFA/artifacts/public/build/firefox-47.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-47.0a1.en-US.mac.talos.tests.zip 18:31:26 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QWDN3FC0Spa76pCf-e2VFA/artifacts/public/build/firefox-47.0a1.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-47.0a1.en-US.mac.talos.tests.zip'}, attempt #1 18:31:27 INFO - Downloaded 10803659 bytes. 18:31:27 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-47.0a1.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 18:31:27 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-47.0a1.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 18:31:27 INFO - Return code: 0 18:31: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')]} 18:31:27 INFO - https://queue.taskcluster.net/v1/task/QWDN3FC0Spa76pCf-e2VFA/artifacts/public/build/firefox-47.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 18:31:27 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QWDN3FC0Spa76pCf-e2VFA/artifacts/public/build/firefox-47.0a1.en-US.mac.dmg 18:31:27 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QWDN3FC0Spa76pCf-e2VFA/artifacts/public/build/firefox-47.0a1.en-US.mac.dmg 18:31:27 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QWDN3FC0Spa76pCf-e2VFA/artifacts/public/build/firefox-47.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-47.0a1.en-US.mac.dmg 18:31:27 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QWDN3FC0Spa76pCf-e2VFA/artifacts/public/build/firefox-47.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-47.0a1.en-US.mac.dmg'}, attempt #1 18:31:30 INFO - Downloaded 110938526 bytes. 18:31:30 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/QWDN3FC0Spa76pCf-e2VFA/artifacts/public/build/firefox-47.0a1.en-US.mac.dmg 18:31:30 INFO - mkdir: /builds/slave/test/properties 18:31:30 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 18:31:30 INFO - Writing to file /builds/slave/test/properties/build_url 18:31:30 INFO - Contents: 18:31:30 INFO - build_url:https://queue.taskcluster.net/v1/task/QWDN3FC0Spa76pCf-e2VFA/artifacts/public/build/firefox-47.0a1.en-US.mac.dmg 18:31:31 INFO - Running post-action listener: _resource_record_post_action 18:31:31 INFO - Running post-action listener: set_extra_try_arguments 18:31:31 INFO - ##### 18:31:31 INFO - ##### Running populate-webroot step. 18:31:31 INFO - ##### 18:31:31 INFO - Running pre-action listener: _resource_record_pre_action 18:31:31 INFO - Running main action method: populate_webroot 18:31:31 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 18:31:31 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 18:31:31 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 18:31:31 INFO - '--tppagecycles', 18:31:31 INFO - '7'], 18:31:31 INFO - 'tests': ['tsvgm']}}, 18:31:31 INFO - 'suites': {'chromez': {'tests': ['tresize', 'tcanvasmark']}, 18:31:31 INFO - 'chromez-e10s': {'talos_options': ['--e10s'], 18:31:31 INFO - 'tests': ['tresize', 'tcanvasmark']}, 18:31:31 INFO - 'dromaeojs': {'tests': ['dromaeo_css', 'kraken']}, 18:31:31 INFO - 'dromaeojs-e10s': {'talos_options': ['--e10s'], 18:31:31 INFO - 'tests': ['dromaeo_css', 'kraken']}, 18:31:31 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:31:31 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:31:31 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:31:31 INFO - 'talos_options': ['--e10s'], 18:31:31 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:31:31 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:31:31 INFO - 'tests': ['damp', 'tps']}, 18:31:31 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:31:31 INFO - 'talos_options': ['--e10s'], 18:31:31 INFO - 'tests': ['damp', 'tps']}, 18:31:31 INFO - 'g3': {'tests': ['dromaeo_dom']}, 18:31:31 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 18:31:31 INFO - 'other': {'tests': ['a11yr', 18:31:31 INFO - 'ts_paint', 18:31:31 INFO - 'tpaint', 18:31:31 INFO - 'sessionrestore', 18:31:31 INFO - 'sessionrestore_no_auto_restore']}, 18:31:31 INFO - 'other-e10s': {'talos_options': ['--e10s'], 18:31:31 INFO - 'tests': ['a11yr', 18:31:31 INFO - 'ts_paint', 18:31:31 INFO - 'tpaint', 18:31:31 INFO - 'sessionrestore', 18:31:31 INFO - 'sessionrestore_no_auto_restore']}, 18:31:31 INFO - 'other-e10s_l64': {'talos_options': ['--e10s'], 18:31:31 INFO - 'tests': ['a11yr', 18:31:31 INFO - 'ts_paint', 18:31:31 INFO - 'tpaint', 18:31:31 INFO - 'sessionrestore', 18:31:31 INFO - 'sessionrestore_no_auto_restore']}, 18:31:31 INFO - 'other-e10s_nol64': {'talos_options': ['--e10s'], 18:31:31 INFO - 'tests': ['a11yr', 18:31:31 INFO - 'ts_paint', 18:31:31 INFO - 'tpaint', 18:31:31 INFO - 'sessionrestore', 18:31:31 INFO - 'sessionrestore_no_auto_restore']}, 18:31:31 INFO - 'other_l64': {'tests': ['a11yr', 18:31:31 INFO - 'ts_paint', 18:31:31 INFO - 'tpaint', 18:31:31 INFO - 'sessionrestore', 18:31:31 INFO - 'sessionrestore_no_auto_restore']}, 18:31:31 INFO - 'other_nol64': {'tests': ['a11yr', 18:31:31 INFO - 'ts_paint', 18:31:31 INFO - 'tpaint', 18:31:31 INFO - 'sessionrestore', 18:31:31 INFO - 'sessionrestore_no_auto_restore']}, 18:31:31 INFO - 'svgr': {'tests': ['tsvgx', 18:31:31 INFO - 'tsvgr_opacity', 18:31:31 INFO - 'tart', 18:31:31 INFO - 'tscrollx', 18:31:31 INFO - 'cart']}, 18:31:31 INFO - 'svgr-e10s': {'talos_options': ['--e10s'], 18:31:31 INFO - 'tests': ['tsvgx', 18:31:31 INFO - 'tsvgr_opacity', 18:31:31 INFO - 'tart', 18:31:31 INFO - 'tscrollx', 18:31:31 INFO - 'cart']}, 18:31:31 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:31:31 INFO - 'tests': ['tp5o']}, 18:31:31 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:31:31 INFO - 'talos_options': ['--e10s'], 18:31:31 INFO - 'tests': ['tp5o']}, 18:31:31 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:31:31 INFO - 'talos_options': ['--xperf_path', 18:31:31 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:31:31 INFO - 'tests': ['tp5n']}, 18:31:31 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:31:31 INFO - 'talos_options': ['--e10s', 18:31:31 INFO - '--xperf_path', 18:31:31 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:31:31 INFO - 'tests': ['tp5n']}}, 18:31:31 INFO - 'talos.zip': {'path': '', 18:31:31 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 18:31:31 INFO - Running post-action listener: _resource_record_post_action 18:31:31 INFO - ##### 18:31:31 INFO - ##### Running create-virtualenv step. 18:31:31 INFO - ##### 18:31:31 INFO - Running pre-action listener: _resource_record_pre_action 18:31:31 INFO - Running main action method: create_virtualenv 18:31:31 INFO - Creating virtualenv /builds/slave/test/build/venv 18:31:31 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 18:31:31 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 18:31:31 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 18:31:31 INFO - Using real prefix '/tools/python27' 18:31:31 INFO - New python executable in /builds/slave/test/build/venv/bin/python 18:31:32 INFO - Installing distribute.............................................................................................................................................................................................done. 18:31:34 INFO - Installing pip.................done. 18:31:34 INFO - Return code: 0 18:31:34 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 18:31:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:31:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:31:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:31:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:31:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:31:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:31:34 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 0x110038a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1100404c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9b79c56600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fedcd30>, '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 0x10fd00db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fed3b20>, '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.h6VlQgQM7n/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.bimA75Iz0Q/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/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 18:31:34 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 18:31:34 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 18:31:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.h6VlQgQM7n/Render', 18:31:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:31:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:31:34 INFO - 'HOME': '/Users/cltbld', 18:31:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:31:34 INFO - 'LOGNAME': 'cltbld', 18:31:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:31:34 INFO - 'MOZ_NO_REMOTE': '1', 18:31:34 INFO - 'NO_EM_RESTART': '1', 18:31:34 INFO - 'PAGER': '/bin/cat', 18:31:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:31:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:31:34 INFO - 'PWD': '/builds/slave/test', 18:31:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:31:34 INFO - 'SHELL': '/bin/bash', 18:31:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.bimA75Iz0Q/Listeners', 18:31:34 INFO - 'TMPDIR': '/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/', 18:31:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:31:34 INFO - 'USER': 'cltbld', 18:31:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:31:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:31:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:31:34 INFO - 'XPC_FLAGS': '0x0', 18:31:34 INFO - 'XPC_SERVICE_NAME': '0', 18:31:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:31:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:31:35 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 18:31:35 INFO - Cleaning up... 18:31:35 INFO - Return code: 0 18:31:35 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 18:31:35 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')]} 18:31:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:31:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:31:35 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')]} 18:31:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:31:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:31:35 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 0x110038a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1100404c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9b79c56600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fedcd30>, '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 0x10fd00db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fed3b20>, '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.h6VlQgQM7n/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.bimA75Iz0Q/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/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 18:31:35 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 18:31:35 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 18:31:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.h6VlQgQM7n/Render', 18:31:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:31:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:31:35 INFO - 'HOME': '/Users/cltbld', 18:31:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:31:35 INFO - 'LOGNAME': 'cltbld', 18:31:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:31:35 INFO - 'MOZ_NO_REMOTE': '1', 18:31:35 INFO - 'NO_EM_RESTART': '1', 18:31:35 INFO - 'PAGER': '/bin/cat', 18:31:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:31:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:31:35 INFO - 'PWD': '/builds/slave/test', 18:31:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:31:35 INFO - 'SHELL': '/bin/bash', 18:31:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.bimA75Iz0Q/Listeners', 18:31:35 INFO - 'TMPDIR': '/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/', 18:31:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:31:35 INFO - 'USER': 'cltbld', 18:31:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:31:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:31:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:31:35 INFO - 'XPC_FLAGS': '0x0', 18:31:35 INFO - 'XPC_SERVICE_NAME': '0', 18:31:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:31:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:31:35 INFO - Downloading/unpacking psutil>=0.7.1 18:31:35 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 18:31:35 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 18:31:35 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 18:31:35 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 18:31:35 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 18:31:35 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 18:31:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 18:31:38 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:31:38 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:31:38 INFO - Installing collected packages: psutil 18:31:38 INFO - Running setup.py install for psutil 18:31:38 INFO - building 'psutil._psutil_osx' extension 18:31:38 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 18:31:38 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 18:31:38 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 18:31:38 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 18:31:38 INFO - building 'psutil._psutil_posix' extension 18:31:38 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 18:31:38 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 18:31:38 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 18:31:38 INFO - ^ 18:31:38 INFO - 1 warning generated. 18:31:39 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 18:31:39 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:31:39 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:31:39 INFO - Successfully installed psutil 18:31:39 INFO - Cleaning up... 18:31:39 INFO - Return code: 0 18:31:39 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 18:31:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:31:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:31:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:31:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:31:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:31:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:31:39 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x110038a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1100404c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9b79c56600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fedcd30>, '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 0x10fd00db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fed3b20>, '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.h6VlQgQM7n/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.bimA75Iz0Q/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/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 18:31:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0'] in /builds/slave/test/build 18:31:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.0.0 18:31:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.h6VlQgQM7n/Render', 18:31:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:31:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:31:39 INFO - 'HOME': '/Users/cltbld', 18:31:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:31:39 INFO - 'LOGNAME': 'cltbld', 18:31:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:31:39 INFO - 'MOZ_NO_REMOTE': '1', 18:31:39 INFO - 'NO_EM_RESTART': '1', 18:31:39 INFO - 'PAGER': '/bin/cat', 18:31:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:31:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:31:39 INFO - 'PWD': '/builds/slave/test', 18:31:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:31:39 INFO - 'SHELL': '/bin/bash', 18:31:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.bimA75Iz0Q/Listeners', 18:31:39 INFO - 'TMPDIR': '/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/', 18:31:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:31:39 INFO - 'USER': 'cltbld', 18:31:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:31:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:31:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:31:39 INFO - 'XPC_FLAGS': '0x0', 18:31:39 INFO - 'XPC_SERVICE_NAME': '0', 18:31:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:31:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:31:39 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 18:31:39 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 18:31:39 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 18:31:39 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 18:31:39 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 18:31:39 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 18:31:39 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 18:31:41 INFO - Downloading mozsystemmonitor-0.0.tar.gz 18:31:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 18:31:41 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 18:31:41 INFO - Installing collected packages: mozsystemmonitor 18:31:41 INFO - Running setup.py install for mozsystemmonitor 18:31:42 INFO - Successfully installed mozsystemmonitor 18:31:42 INFO - Cleaning up... 18:31:42 INFO - Return code: 0 18:31:42 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 18:31:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:31:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:31:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:31:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:31:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:31:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:31:42 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x110038a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1100404c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9b79c56600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fedcd30>, '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 0x10fd00db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fed3b20>, '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.h6VlQgQM7n/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.bimA75Iz0Q/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/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 18:31:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 18:31:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 18:31:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.h6VlQgQM7n/Render', 18:31:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:31:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:31:42 INFO - 'HOME': '/Users/cltbld', 18:31:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:31:42 INFO - 'LOGNAME': 'cltbld', 18:31:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:31:42 INFO - 'MOZ_NO_REMOTE': '1', 18:31:42 INFO - 'NO_EM_RESTART': '1', 18:31:42 INFO - 'PAGER': '/bin/cat', 18:31:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:31:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:31:42 INFO - 'PWD': '/builds/slave/test', 18:31:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:31:42 INFO - 'SHELL': '/bin/bash', 18:31:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.bimA75Iz0Q/Listeners', 18:31:42 INFO - 'TMPDIR': '/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/', 18:31:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:31:42 INFO - 'USER': 'cltbld', 18:31:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:31:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:31:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:31:42 INFO - 'XPC_FLAGS': '0x0', 18:31:42 INFO - 'XPC_SERVICE_NAME': '0', 18:31:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:31:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:31:42 INFO - Downloading/unpacking blobuploader==1.2.4 18:31:42 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 18:31:42 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 18:31:42 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 18:31:42 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 18:31:42 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 18:31:42 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 18:31:44 INFO - Downloading blobuploader-1.2.4.tar.gz 18:31:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 18:31:44 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 18:31:44 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 18:31:44 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 18:31:44 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 18:31:44 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 18:31:44 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 18:31:44 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 18:31:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 18:31:45 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 18:31:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:31:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:31:45 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:31:45 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:31:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:31:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:31:45 INFO - Downloading docopt-0.6.1.tar.gz 18:31:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 18:31:45 INFO - Installing collected packages: blobuploader, requests, docopt 18:31:45 INFO - Running setup.py install for blobuploader 18:31:46 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 18:31:46 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 18:31:46 INFO - Running setup.py install for requests 18:31:46 INFO - Running setup.py install for docopt 18:31:46 INFO - Successfully installed blobuploader requests docopt 18:31:46 INFO - Cleaning up... 18:31:46 INFO - Return code: 0 18:31:46 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:31: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')]} 18:31:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:31:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:31: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')]} 18:31:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:31:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:31:46 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 0x110038a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1100404c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9b79c56600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fedcd30>, '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 0x10fd00db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fed3b20>, '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.h6VlQgQM7n/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.bimA75Iz0Q/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/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 18:31:46 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 18:31:46 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 18:31:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.h6VlQgQM7n/Render', 18:31:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:31:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:31:46 INFO - 'HOME': '/Users/cltbld', 18:31:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:31:46 INFO - 'LOGNAME': 'cltbld', 18:31:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:31:46 INFO - 'MOZ_NO_REMOTE': '1', 18:31:46 INFO - 'NO_EM_RESTART': '1', 18:31:46 INFO - 'PAGER': '/bin/cat', 18:31:46 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:31:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:31:46 INFO - 'PWD': '/builds/slave/test', 18:31:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:31:46 INFO - 'SHELL': '/bin/bash', 18:31:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.bimA75Iz0Q/Listeners', 18:31:46 INFO - 'TMPDIR': '/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/', 18:31:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:31:46 INFO - 'USER': 'cltbld', 18:31:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:31:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:31:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:31:46 INFO - 'XPC_FLAGS': '0x0', 18:31:46 INFO - 'XPC_SERVICE_NAME': '0', 18:31:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:31:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:31:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:31:47 INFO - Running setup.py (path:/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/pip-7lmsen-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:31:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:31:47 INFO - Running setup.py (path:/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/pip-KynEcc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:31:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:31:47 INFO - Running setup.py (path:/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/pip-JyXrus-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:31:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:31:47 INFO - Running setup.py (path:/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/pip-RNN4Mn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:31:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:31:47 INFO - Running setup.py (path:/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/pip-gS7T_K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:31:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:31:47 INFO - Running setup.py (path:/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/pip-tVbyqZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:31:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:31:47 INFO - Running setup.py (path:/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/pip-ShubM9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:31:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:31:47 INFO - Running setup.py (path:/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/pip-uGnFDj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:31:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:31:47 INFO - Running setup.py (path:/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/pip-gDRPfz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:31:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:31:48 INFO - Running setup.py (path:/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/pip-LrEkZv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:31:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:31:48 INFO - Running setup.py (path:/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/pip-usZWkX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:31:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:31:48 INFO - Running setup.py (path:/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/pip-B4dsJ5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:31:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:31:48 INFO - Running setup.py (path:/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/pip-gAXjgb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:31:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:31:48 INFO - Running setup.py (path:/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/pip-k2gepf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:31:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:31:48 INFO - Running setup.py (path:/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/pip-YavX6T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:31:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:31:48 INFO - Running setup.py (path:/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/pip-3zK7LG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:31:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:31:48 INFO - Running setup.py (path:/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/pip-DaPiQg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:31:48 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 18:31:48 INFO - Running setup.py install for manifestparser 18:31:49 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 18:31:49 INFO - Running setup.py install for mozcrash 18:31:49 INFO - Running setup.py install for mozdebug 18:31:49 INFO - Running setup.py install for mozdevice 18:31:49 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 18:31:49 INFO - Installing dm script to /builds/slave/test/build/venv/bin 18:31:49 INFO - Running setup.py install for mozfile 18:31:49 INFO - Running setup.py install for mozhttpd 18:31:49 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 18:31:49 INFO - Running setup.py install for mozinfo 18:31:49 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 18:31:49 INFO - Running setup.py install for mozInstall 18:31:50 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 18:31:50 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 18:31:50 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 18:31:50 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 18:31:50 INFO - Running setup.py install for mozleak 18:31:50 INFO - Running setup.py install for mozlog 18:31:50 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 18:31:50 INFO - Running setup.py install for moznetwork 18:31:50 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 18:31:50 INFO - Running setup.py install for mozprocess 18:31:50 INFO - Running setup.py install for mozprofile 18:31:50 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 18:31:50 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 18:31:50 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 18:31:50 INFO - Running setup.py install for mozrunner 18:31:51 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 18:31:51 INFO - Running setup.py install for mozscreenshot 18:31:51 INFO - Running setup.py install for moztest 18:31:51 INFO - Running setup.py install for mozversion 18:31:51 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 18:31:51 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 18:31:51 INFO - Cleaning up... 18:31:51 INFO - Return code: 0 18:31:51 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:31:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:31:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:31:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:31:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:31:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:31:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:31:51 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 0x110038a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1100404c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9b79c56600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fedcd30>, '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 0x10fd00db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fed3b20>, '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.h6VlQgQM7n/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.bimA75Iz0Q/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/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 18:31:51 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 18:31:51 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 18:31:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.h6VlQgQM7n/Render', 18:31:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:31:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:31:51 INFO - 'HOME': '/Users/cltbld', 18:31:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:31:51 INFO - 'LOGNAME': 'cltbld', 18:31:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:31:51 INFO - 'MOZ_NO_REMOTE': '1', 18:31:51 INFO - 'NO_EM_RESTART': '1', 18:31:51 INFO - 'PAGER': '/bin/cat', 18:31:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:31:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:31:51 INFO - 'PWD': '/builds/slave/test', 18:31:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:31:51 INFO - 'SHELL': '/bin/bash', 18:31:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.bimA75Iz0Q/Listeners', 18:31:51 INFO - 'TMPDIR': '/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/', 18:31:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:31:51 INFO - 'USER': 'cltbld', 18:31:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:31:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:31:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:31:51 INFO - 'XPC_FLAGS': '0x0', 18:31:51 INFO - 'XPC_SERVICE_NAME': '0', 18:31:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:31:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:31:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:31:51 INFO - Running setup.py (path:/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/pip-du08aS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:31:51 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)) 18:31:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:31:51 INFO - Running setup.py (path:/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/pip-CVUvK2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:31:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.16 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)) 18:31:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:31:52 INFO - Running setup.py (path:/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/pip-YWbQpQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:31:52 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)) 18:31:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:31:52 INFO - Running setup.py (path:/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/pip-dI02Be-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:31:52 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)) 18:31:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:31:52 INFO - Running setup.py (path:/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/pip-LqGt2r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:31:52 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)) 18:31:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:31:52 INFO - Running setup.py (path:/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/pip-9_rffu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:31:52 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)) 18:31:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:31:52 INFO - Running setup.py (path:/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/pip-ipzb9r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:31:52 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)) 18:31:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:31:52 INFO - Running setup.py (path:/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/pip-u244dO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:31:52 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)) 18:31:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:31:52 INFO - Running setup.py (path:/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/pip-vpgQSl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:31:52 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)) 18:31:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:31:52 INFO - Running setup.py (path:/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/pip-S9TXaP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:31:52 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)) 18:31:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:31:52 INFO - Running setup.py (path:/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/pip-2lnlsy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:31:52 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)) 18:31:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:31:52 INFO - Running setup.py (path:/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/pip-bwOU3O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:31:53 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)) 18:31:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:31:53 INFO - Running setup.py (path:/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/pip-G7B2R9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:31:53 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)) 18:31:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:31:53 INFO - Running setup.py (path:/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/pip-sMuhpI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:31:53 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)) 18:31:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:31:53 INFO - Running setup.py (path:/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/pip-s76GTu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:31:53 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)) 18:31:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:31:53 INFO - Running setup.py (path:/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/pip-kcS7on-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:31:53 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)) 18:31:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:31:53 INFO - Running setup.py (path:/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/pip-NBaSZd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:31:53 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)) 18:31:53 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.16->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 18:31:53 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.16->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 18:31:53 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)) 18:31:53 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)) 18:31:53 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)) 18:31:53 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 18:31: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 18:31: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 18:31: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 18:31: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 18:31: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 18:31: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 18:31:55 INFO - Downloading blessings-1.6.tar.gz 18:31:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 18:31:56 INFO - Installing collected packages: blessings 18:31:56 INFO - Running setup.py install for blessings 18:31:56 INFO - Successfully installed blessings 18:31:56 INFO - Cleaning up... 18:31:56 INFO - Return code: 0 18:31:56 INFO - Done creating virtualenv /builds/slave/test/build/venv. 18:31:56 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:31:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:31:56 INFO - Reading from file tmpfile_stdout 18:31:56 INFO - Current package versions: 18:31:56 INFO - blessings == 1.6 18:31:56 INFO - blobuploader == 1.2.4 18:31:56 INFO - docopt == 0.6.1 18:31:56 INFO - manifestparser == 1.1 18:31:56 INFO - mozInstall == 1.12 18:31:56 INFO - mozcrash == 0.16 18:31:56 INFO - mozdebug == 0.1 18:31:56 INFO - mozdevice == 0.48 18:31:56 INFO - mozfile == 1.2 18:31:56 INFO - mozhttpd == 0.7 18:31:56 INFO - mozinfo == 0.9 18:31:56 INFO - mozleak == 0.1 18:31:56 INFO - mozlog == 3.1 18:31:56 INFO - moznetwork == 0.27 18:31:56 INFO - mozprocess == 0.22 18:31:56 INFO - mozprofile == 0.28 18:31:56 INFO - mozrunner == 6.11 18:31:56 INFO - mozscreenshot == 0.1 18:31:56 INFO - mozsystemmonitor == 0.0 18:31:56 INFO - moztest == 0.7 18:31:56 INFO - mozversion == 1.4 18:31:56 INFO - psutil == 3.1.1 18:31:56 INFO - requests == 1.2.3 18:31:56 INFO - wsgiref == 0.1.2 18:31:56 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:31:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:31:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:31:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:31:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:31:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:31:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:31:56 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x110038a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1100404c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9b79c56600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fedcd30>, '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 0x10fd00db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fed3b20>, '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.h6VlQgQM7n/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.bimA75Iz0Q/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/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 18:31:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 18:31:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 18:31:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.h6VlQgQM7n/Render', 18:31:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:31:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:31:56 INFO - 'HOME': '/Users/cltbld', 18:31:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:31:56 INFO - 'LOGNAME': 'cltbld', 18:31:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:31:56 INFO - 'MOZ_NO_REMOTE': '1', 18:31:56 INFO - 'NO_EM_RESTART': '1', 18:31:56 INFO - 'PAGER': '/bin/cat', 18:31:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:31:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:31:56 INFO - 'PWD': '/builds/slave/test', 18:31:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:31:56 INFO - 'SHELL': '/bin/bash', 18:31:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.bimA75Iz0Q/Listeners', 18:31:56 INFO - 'TMPDIR': '/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/', 18:31:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:31:56 INFO - 'USER': 'cltbld', 18:31:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:31:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:31:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:31:56 INFO - 'XPC_FLAGS': '0x0', 18:31:56 INFO - 'XPC_SERVICE_NAME': '0', 18:31:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:31:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:31:56 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)) 18:31:56 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)) 18:31:56 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)) 18:31:56 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)) 18:31:56 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)) 18:31:56 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)) 18:31:56 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)) 18:31:56 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)) 18:31:56 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)) 18:31:56 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)) 18:31:56 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)) 18:31:56 INFO - Cleaning up... 18:31:56 INFO - Return code: 0 18:31:56 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 18:31:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:31:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:31:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:31:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:31:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:31:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:31:56 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x110038a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1100404c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9b79c56600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fedcd30>, '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 0x10fd00db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fed3b20>, '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.h6VlQgQM7n/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.bimA75Iz0Q/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/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 18:31:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 18:31:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 18:31:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.h6VlQgQM7n/Render', 18:31:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:31:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:31:56 INFO - 'HOME': '/Users/cltbld', 18:31:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:31:56 INFO - 'LOGNAME': 'cltbld', 18:31:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:31:56 INFO - 'MOZ_NO_REMOTE': '1', 18:31:56 INFO - 'NO_EM_RESTART': '1', 18:31:56 INFO - 'PAGER': '/bin/cat', 18:31:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:31:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:31:56 INFO - 'PWD': '/builds/slave/test', 18:31:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:31:56 INFO - 'SHELL': '/bin/bash', 18:31:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.bimA75Iz0Q/Listeners', 18:31:56 INFO - 'TMPDIR': '/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/', 18:31:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:31:56 INFO - 'USER': 'cltbld', 18:31:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:31:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:31:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:31:56 INFO - 'XPC_FLAGS': '0x0', 18:31:56 INFO - 'XPC_SERVICE_NAME': '0', 18:31:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:31:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:31:57 INFO - Downloading/unpacking jsonschema 18:31: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 18:31: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 18:31: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 18:31: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 18:31: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 18:31: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 18:31:59 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 18:31:59 INFO - Downloading/unpacking functools32 (from jsonschema) 18:31:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:31:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:31:59 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:31:59 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:31:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:31:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:31:59 INFO - Downloading functools32-3.2.3-2.tar.gz 18:31:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 18:31:59 INFO - warning: no files found matching '*.txt' 18:31:59 INFO - no previously-included directories found matching 'build' 18:31:59 INFO - no previously-included directories found matching 'dist' 18:31:59 INFO - no previously-included directories found matching '.git*' 18:31:59 INFO - Installing collected packages: jsonschema, functools32 18:31:59 INFO - Running setup.py install for functools32 18:32:00 INFO - warning: no files found matching '*.txt' 18:32:00 INFO - no previously-included directories found matching 'build' 18:32:00 INFO - no previously-included directories found matching 'dist' 18:32:00 INFO - no previously-included directories found matching '.git*' 18:32:00 INFO - Successfully installed jsonschema functools32 18:32:00 INFO - Cleaning up... 18:32:00 INFO - Return code: 0 18:32:00 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 18:32:00 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')]} 18:32:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:32:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:32:00 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')]} 18:32:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:32:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:32:00 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 0x110038a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1100404c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9b79c56600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fedcd30>, '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 0x10fd00db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fed3b20>, '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.h6VlQgQM7n/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.bimA75Iz0Q/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/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 18:32:00 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 18:32:00 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 18:32:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.h6VlQgQM7n/Render', 18:32:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:32:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:32:00 INFO - 'HOME': '/Users/cltbld', 18:32:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:32:00 INFO - 'LOGNAME': 'cltbld', 18:32:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:32:00 INFO - 'MOZ_NO_REMOTE': '1', 18:32:00 INFO - 'NO_EM_RESTART': '1', 18:32:00 INFO - 'PAGER': '/bin/cat', 18:32:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:32:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:32:00 INFO - 'PWD': '/builds/slave/test', 18:32:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:32:00 INFO - 'SHELL': '/bin/bash', 18:32:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.bimA75Iz0Q/Listeners', 18:32:00 INFO - 'TMPDIR': '/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/', 18:32:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:32:00 INFO - 'USER': 'cltbld', 18:32:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:32:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:32:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:32:00 INFO - 'XPC_FLAGS': '0x0', 18:32:00 INFO - 'XPC_SERVICE_NAME': '0', 18:32:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:32:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:32:00 INFO - Downloading/unpacking flake8 18:32:00 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 18:32:00 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 18:32:00 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 18:32:00 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 18:32:00 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 18:32:00 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 18:32:02 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 18:32:02 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 18:32:02 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 18:32:02 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 18:32:02 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 18:32:02 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 18:32:02 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 18:32:02 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 18:32:03 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 18:32:03 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 18:32:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:32:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:32:03 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:32:03 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:32:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:32:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:32:03 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 18:32:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:32:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:32:03 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:32:03 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:32:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:32:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:32:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 18:32:03 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 18:32:03 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 18:32:03 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 18:32:03 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 18:32:03 INFO - no previously-included directories found matching 'docs/_build' 18:32:04 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 18:32:04 INFO - Running setup.py install for pep8 18:32:04 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 18:32:04 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 18:32:04 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 18:32:04 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 18:32:04 INFO - no previously-included directories found matching 'docs/_build' 18:32:04 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 18:32:04 INFO - Successfully installed flake8 mccabe pyflakes pep8 18:32:04 INFO - Cleaning up... 18:32:04 INFO - Return code: 0 18:32:04 INFO - Running post-action listener: _resource_record_post_action 18:32:04 INFO - Running post-action listener: _start_resource_monitoring 18:32:04 INFO - Starting resource monitoring. 18:32:04 INFO - ##### 18:32:04 INFO - ##### Running install step. 18:32:04 INFO - ##### 18:32:04 INFO - Running pre-action listener: _resource_record_pre_action 18:32:04 INFO - Running main action method: install 18:32:04 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:32:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:32:04 INFO - Reading from file tmpfile_stdout 18:32:04 INFO - Detecting whether we're running mozinstall >=1.0... 18:32:04 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 18:32:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 18:32:04 INFO - Reading from file tmpfile_stdout 18:32:04 INFO - Output received: 18:32:04 INFO - Usage: mozinstall [options] installer 18:32:04 INFO - Options: 18:32:04 INFO - -h, --help show this help message and exit 18:32:04 INFO - -d DEST, --destination=DEST 18:32:04 INFO - Directory to install application into. [default: 18:32:04 INFO - "/builds/slave/test"] 18:32:04 INFO - --app=APP Application being installed. [default: firefox] 18:32:04 INFO - mkdir: /builds/slave/test/build/application 18:32:04 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-47.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 18:32:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-47.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 18:32:39 INFO - Reading from file tmpfile_stdout 18:32:39 INFO - Output received: 18:32:39 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 18:32:39 INFO - Running post-action listener: _resource_record_post_action 18:32:39 INFO - ##### 18:32:39 INFO - ##### Running run-tests step. 18:32:39 INFO - ##### 18:32:39 INFO - Running pre-action listener: _resource_record_pre_action 18:32:39 INFO - Running main action method: run_tests 18:32:39 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 18:32:39 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 18:32:39 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 18:32:39 INFO - 2016-03-04 18:32:39.074 screenresolution[6079:100954] starting screenresolution argv=screenresolution get 18:32:39 INFO - 2016-03-04 18:32:39.077 screenresolution[6079:100954] Display 0: 1600x1200x32@60 18:32:39 INFO - 2016-03-04 18:32:39.083 screenresolution[6080:100956] starting screenresolution argv=screenresolution list 18:32:39 INFO - Available Modes on Display 0 18:32:39 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 18:32:39 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 18:32:39 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 18:32:39 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 18:32:39 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 18:32:39 INFO - Intel Iris: 18:32:39 INFO - Chipset Model: Intel Iris 18:32:39 INFO - Type: GPU 18:32:39 INFO - Bus: Built-In 18:32:39 INFO - VRAM (Dynamic, Max): 1536 MB 18:32:39 INFO - Vendor: Intel (0x8086) 18:32:39 INFO - Device ID: 0x0a2e 18:32:39 INFO - Revision ID: 0x0009 18:32:39 INFO - Displays: 18:32:39 INFO - Display: 18:32:39 INFO - Resolution: 1600 x 1200 @ 60 Hz 18:32:39 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 18:32:39 INFO - Display Serial Number: 1600x1200 60 18:32:39 INFO - Main Display: Yes 18:32:39 INFO - Mirror: Off 18:32:39 INFO - Online: Yes 18:32:39 INFO - Rotation: Supported 18:32:39 INFO - Return code: 0 18:32:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 18:32:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 18:32:39 INFO - Python 2.7.3 18:32:39 INFO - Return code: 0 18:32:39 INFO - grabbing minidump binary from tooltool 18:32:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:32:39 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 0x10fedcd30>, '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 0x10fd00db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fed3b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 18:32:39 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 18:32:39 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 18:32:39 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 18:32:39 INFO - Return code: 0 18:32:39 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 18:32:39 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 18:32:39 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 18:32:39 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 18:32:39 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 18:32:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 18:32:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 18:32:40 INFO - Return code: 0 18:32:40 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/QWDN3FC0Spa76pCf-e2VFA/artifacts/public/build/firefox-47.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0016', '--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 18:32:40 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/QWDN3FC0Spa76pCf-e2VFA/artifacts/public/build/firefox-47.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0016 --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 18:32:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.h6VlQgQM7n/Render', 18:32:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:32:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:32:40 INFO - 'HOME': '/Users/cltbld', 18:32:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:32:40 INFO - 'LOGNAME': 'cltbld', 18:32:40 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 18:32:40 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 18:32:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:32:40 INFO - 'MOZ_NO_REMOTE': '1', 18:32:40 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 18:32:40 INFO - 'NO_EM_RESTART': '1', 18:32:40 INFO - 'PAGER': '/bin/cat', 18:32:40 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:32:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:32:40 INFO - 'PWD': '/builds/slave/test', 18:32:40 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 18:32:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:32:40 INFO - 'SHELL': '/bin/bash', 18:32:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.bimA75Iz0Q/Listeners', 18:32:40 INFO - 'TMPDIR': '/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/', 18:32:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:32:40 INFO - 'USER': 'cltbld', 18:32:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:32:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:32:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:32:40 INFO - 'XPC_FLAGS': '0x0', 18:32:40 INFO - 'XPC_SERVICE_NAME': '0', 18:32:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:32:40 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/QWDN3FC0Spa76pCf-e2VFA/artifacts/public/build/firefox-47.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0016', '--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 18:32:40 INFO - mozversion application_buildid: 20160304155027 18:32:40 INFO - mozversion application_changeset: 69d357fb6a0b38239b855a9aeea863d035f9fca5 18:32:40 INFO - mozversion application_display_name: Nightly 18:32:40 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 18:32:40 INFO - mozversion application_name: Firefox 18:32:40 INFO - mozversion application_remotingname: firefox 18:32:40 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 18:32:40 INFO - mozversion application_vendor: Mozilla 18:32:40 INFO - mozversion application_version: 47.0a1 18:32:40 INFO - mozversion platform_buildid: 20160304155027 18:32:40 INFO - mozversion platform_changeset: 69d357fb6a0b38239b855a9aeea863d035f9fca5 18:32:40 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 18:32:40 INFO - mozversion platform_version: 47.0a1 18:32:40 INFO - using testdate: 1457145160 18:32:40 INFO - actual date: 1457145160 18:32:40 INFO - starting webserver on 'localhost:56228' 18:32:40 INFO - SUITE-START | Running 2 tests 18:32:40 INFO - TEST-START | dromaeo_css 18:32:40 INFO - operating with platform_type : mac_ 18:32:40 INFO - Initialising browser for dromaeo_css test... 18:32:40 INFO - TEST-INFO | started process 6098 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/tmpbxrF_2/profile http://localhost:56228/getInfo.html) 18:32:43 INFO - PROCESS | 6098 | __metrics Screen width/height:1600/1200 18:32:43 INFO - PROCESS | 6098 | colorDepth:24 18:32:43 INFO - PROCESS | 6098 | Browser inner width/height: 1024/689 18:32:43 INFO - PROCESS | 6098 | __metrics 18:32:43 INFO - PROCESS | 6098 | 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" 18:32:43 INFO - PROCESS | 6098 | JavaScript error: resource://app/modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 18:32:43 INFO - TEST-INFO | 6098: exit 0 18:32:43 INFO - Browser initialized. 18:32:43 INFO - Running cycle 1/1 for dromaeo_css test... 18:32:43 INFO - TEST-INFO | started process 6102 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/tmpbxrF_2/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 18:32:55 INFO - PROCESS | 6102 | RSS: Main: 245903360 18:32:55 INFO - PROCESS | 6102 | 18:33:47 INFO - PROCESS | 6102 | 1457145227115 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] 18:35:57 INFO - PROCESS | 6102 | Cycle 1(1): loaded http://localhost:56228/tests/dromaeo/cssquery-dojo.html (next: http://localhost:56228/tests/dromaeo/cssquery-ext.html) 18:35:59 INFO - PROCESS | 6102 | RSS: Main: 462950400 18:35:59 INFO - PROCESS | 6102 | 18:39:02 INFO - PROCESS | 6102 | Cycle 1(1): loaded http://localhost:56228/tests/dromaeo/cssquery-ext.html (next: http://localhost:56228/tests/dromaeo/cssquery-jquery.html) 18:39:02 INFO - PROCESS | 6102 | RSS: Main: 374734848 18:39:02 INFO - PROCESS | 6102 | 18:42:04 INFO - PROCESS | 6102 | Cycle 1(1): loaded http://localhost:56228/tests/dromaeo/cssquery-jquery.html (next: http://localhost:56228/tests/dromaeo/cssquery-mootools.html) 18:42:05 INFO - PROCESS | 6102 | RSS: Main: 374857728 18:42:05 INFO - PROCESS | 6102 | 18:45:07 INFO - PROCESS | 6102 | Cycle 1(1): loaded http://localhost:56228/tests/dromaeo/cssquery-mootools.html (next: http://localhost:56228/tests/dromaeo/cssquery-prototype.html) 18:45:08 INFO - PROCESS | 6102 | RSS: Main: 333500416 18:45:08 INFO - PROCESS | 6102 | 18:48:11 INFO - PROCESS | 6102 | Cycle 1(1): loaded http://localhost:56228/tests/dromaeo/cssquery-prototype.html (next: http://localhost:56228/tests/dromaeo/cssquery-yui.html) 18:48:11 INFO - PROCESS | 6102 | RSS: Main: 337981440 18:48:11 INFO - PROCESS | 6102 | 18:51:14 INFO - PROCESS | 6102 | Cycle 1(1): loaded http://localhost:56228/tests/dromaeo/cssquery-yui.html (next: http://localhost:56228/tests/dromaeo/cssquery-dojo.html) 18:51:15 INFO - PROCESS | 6102 | RSS: Main: 417116160 18:51:15 INFO - PROCESS | 6102 | 18:51:15 INFO - PROCESS | 6102 | __start_tp_report 18:51:15 INFO - PROCESS | 6102 | _x_x_mozilla_page_load 18:51:15 INFO - PROCESS | 6102 | _x_x_mozilla_page_load_details 18:51:15 INFO - PROCESS | 6102 | |i|pagename|runs| 18:51:15 INFO - PROCESS | 6102 | |0;dojo.html;3336.4995250712395;3395.9151021224484;3405.778624389416;3426.3318652862686;3459.394605943961;4558.635309175258;5599.720013999299;5611.186377975197;5643.153526970957;5649.011423000974;5684.459976302244;5689.943100568993;5747.453991870766;5828.154917536955;5850.707464626773;5066.417362003362;5127.589792816575;5144.845654630349;5147.1764517677175;5218.843434696965;5251.501107394811;5252.107141785887;5293.100172970604;5300.363956325227;5355.250264962909;4283.293256612656;5144.331236939192;5189.962007598477;5191.766370513336;5199.012187684366;5180.430152683201;5196.92204616931;5201.167813149916;5202.843914682566;5222.399424066205;5137.229415587654;5144.099782538041;5166.43169251382;5166.844994650167;5218.712970786605;4880.658353915227;5442.428545002796;5533.08704063829;5581;5595.468430499096;6171.938280617226;6208.81373558794;6215.56491045627;6218.129461875341;6223.190985171944;6147.385261473862;6182.876342473125;6207.068939659042;6220.129181914535;6230.657313847737;5634.4365563443735;5735.340435849847;5739.454751798572;5850.0932355484965;5865.032269675456;6187.102870083812;6206.441420272197;6208.348123446973;6222.3466536013975;6256.562040657111;5986.2217911671205;6021.3677563856045;6094.481969032682;6094.6647934363145;6097.237845269341;5764;5985.6408615483215;6140.074827791203;6257.092721555349;6278.874422511615;6161.476274516716;6195.690215489208;6200;6206.441420272197;6208.068789681584;6175.62946223228;6179.3820617937845;6193.6903154842075;6203.2245969253845;6216.378362163748;4787.61699064074;4821.566059054632;4866.977934633766;4867.561919427269;4868.342773725572;4394.36281739146;4431.7562534060935;4444.555544445594;4456.309272062836;4456.710313829655;5522.779088836402;5568.49883510486;5601.10382338832;5666;5690.630109042899;5780.248567686174;5782.681952492653;6270.561060725706;6292.433680968735;6294;5593.804216852391;5605.495505404451;5613.961417137843;5627.943720562742;5628.380878103405;5227.032998895216;5298.814541491107;5325.09473389517;5340.172273297645;5351.705656188947;6234.875302493925;6245.0632405139595;6252.062190671436;6264.498840092872;6271.122042913996;6168.845778855385;6276.811695649138;6281.96859015702;6290.402411770874;6302.905456418249;6210.844728881814;6232.065190221503;6232.096346029891;6252.812415627538;6266.68666566679;6137.478314343331;6142.692865356804;6161.753529858755;6166.044263139221;6171.876562468815;6220.782272620437;6234.314225435289;6247.96876015617;6272.027835685476;6278.309385967448;5901.734421951109;5999.130126131599;6061.060535616986;6062;6065.7876974307455;5981.880362392815;6005.849853753691;6022.8795424090395;6040.516758659384;6043.758249669964;5745.166950792195;5823.70881455934;5842.649441033552;5866.5893387464175;5884.117382392504;5967.283925928917;5972.58191926561;6016.2479690038745;6119.357467465852;6123.326434092157;5838.12428135783;5933.970330148494;5967.552433567413;5970.791022314198;5973.820785376446 18:51:15 INFO - PROCESS | 6102 | |1;ext.html;472.88414338487075;506.99614762769716;530.6921985248556;586.694918642306;636.866258085802;27385.315367115832;31158;38748;39315.41026884592;39810.60189398105;42033.73898783034;42108;42325.36511952323;42416.36375454371;42458;9758.85361719573;10011.799764004716;10018.649347272847;10032.398056116619;10037.949810250975;9778.951105244463;9954.253430992705;9961.501924903725;9964.95017524915;9970.202383809314;10041.648542301022;10122.54448549817;10134;10136.391816491034;10140.23948203884;10014.799704005916;10055.748606284827;10083.394996300172;10093.041161089684;10094.798104037915;9733.464659443727;9875.358101723437;9876.308658393915;9880.654177103766;9881.950590247076;41267.7936610315;41494;41608.79195604033;41612;41727.58272417267;3434.1242983039415;3528.311979164064;3815.1606646537716;3842.2507611015862;3974.6621537169376;29466.558001629994;30023.69976300253;30258;30449.08652740421;30518.847405763052;2715.6876959149554;2852.0588205892004;2860.1705505403406;2878.0214726992713;2962.4371369439955;478700;481247;481375;482024;483488;64579.67710161513;64680;65129.674351628884;65194;65245.021324680165;67756;68274.65862670654;68413.65793171003;68503.97244041343;68552.6572367145;16972.575685607713;17189.484315470556;17224.913875430542;17227.052512111954;17253;13101.213927164401;13184.208947463183;13203.40584673692;13217.669558260928;13221.537246196336;19013.42959711211;19948.90025549892;20284.79715202829;20286.492837679176;20311.086001129985;7514.6618402172035;7902.091259505116;8760.12398760119;8914.55427228636;8928;771.714465647714;1002.1431675917132;1025;1036.9585216591402;1109.7447587054862;28474.572881406795;28511.857440712665;29014.7098529012;29219.707802921697;29242.41515169685;1072.4744875011345;1111.777644471109;1123.393367581497;1127.5813380299212;1156.7512984708328;15361.155136467602;15511.333553111439;15827.366905323657;15921;16376.754348684779;536063;542712;543401;543866;544741;51202.23196652053;58911.41088589145;60937.39062609317;61432;61477;1366.3441548056987;1395.7069015506654;1500.189897455362;1521.0493441599;1639.0575419134045;27912.58131127994;27923.022694205607;28228.435431290847;28459.857700711364;28822.135335939118;46550.53449465462;47254.29118563293;47296.05407891754;47349.52650473451;47361.52638473709;312320.43839780655;323520;323657;324114;325181;28468.572971405858;32304.030879073667;32375.838120809247;33485.33029339448;33630;40209;40530.79734601308;41715.582844172386;41899.371509426775;42013.78993105015;34480.82759586186;34629.653703462645;34846.303073938885;35382.93851184469;35385;33173.336533269685;33836.661633384334;34153;35175.64824351724;35347.82326088353;36003.27993440169;36280.63719362773;37095.44356834704;37454.81272593619;38247.61752382552;29150.7084929148;31022.844885775427;31546.36907261888;32347.191320216243;32660.183495412806;24215.63676544818;24391.756082438947;24654.876725616974;24689.753102468745;24820.88306026269 18:51:15 INFO - PROCESS | 6102 | |2;jquery.html;356.711064038129;359.84706499737615;366.08843978493553;367.4690072844739;373.04500478774355;20428;20817.791822081774;20935.05792239349;21094.472638184034;21217;20965.580688386224;20987.370378888656;21824.563508729814;21862;22010.119595216172;5762.250907382045;5929.140274660172;5978.22283103196;5981.700914954256;5992.041273396258;5693.658380497162;5798;5876.9118463223085;5878.294604647448;5888.646681199141;4922.68002579833;5030.849074527751;5711.742971566268;5844.824655260349;5896.469317761401;5664.518515926151;5679.318481782192;5839.620424672383;5839.620424672383;5841.561882858781;5080.491950804927;5116.181410974264;5120.948790512121;5124.590032797368;5125.692458452468;24977.500449990897;26732.866335668197;27064.59403108955;27065.864670676718;27304.590431143548;2191.9342419727272;2388.4864754077958;2450.2894160693377;2567.4094958159726;2901.303687115077;14309.713805723828;14840.554783356516;14928.776068358984;15005.099694018394;15462.693978895097;1698.388580111159;1735.3232239426634;1797.5170484350333;1893.5644801695548;2023.2412845183057;109054.90945090527;113456.43271783671;113707;113725;118561;11387.202895797214;11464.828027579593;11475.942620286845;11482.425878706032;11576.76846463066;12489.813467720553;13437.992150588745;13688.52090176859;13832.100913440594;13839.584812455643;8520.74437766868;8718.91281087181;8730.86903696445;8807.867881981776;8810.20708136258;8049.557274349967;8105.635246413807;8865.68970086044;8873.201411872962;9009.459432433943;3731.2350968051483;4438.201123797748;4528.50186479487;4535.9773201133785;4608.723476591415;1637.3614290426742;1893.2332405375844;1933.6326098041327;1975.024975024975;1979.910904009323;735.6689489729536;756.7275780718937;936.5270538378076;980.6959842448864;1004.161525126513;19903.203871845253;20940.685889711665;20974.16103355879;21218.57562848765;21255.0435230415;912.9863052054092;923.4044041593135;963.7927845513249;1000.2498126405196;1042.598599539183;11011.61459348919;11066.557337706401;11202.271852329572;11334;11337;608451;627921;635326;643670;647059;94961;122419.36763597428;123053;123980;126360;893.5621545442687;1021.7905329407454;1091.3233795047122;1109.9944500277447;1113.2429947635524;18418.63162736711;18695.813041869405;18751.249950001846;18849.717254240924;19093.90453047726;51496;55968.72015639896;58033.129503058335;58587.70706146442;61170.082448764195;74015;74669.87995180185;75395;76477.67200067415;77275.61362193378;8000.59997000136;8173.56608478803;8187.877181842158;8340.833183336421;8497.757066113156;9538.713838584854;12901.354932253538;13387.263700496565;13886.583402497941;13893.235872027135;9116.863247051167;11731.178817482694;11971.521139154336;11973.700657483632;11994.76010479803;11135.665930022113;11392.25950313227;11555.537778488766;11931.284122952651;11999.64001079969;11571.6528504145;11838.881611184122;11879.762404751684;12006.279623222634;12122.696932576757;8035.799105022421;8087.069986951576;8243.299319557666;8314.875276870973;8355.665773368997;8218.383621228311;8410.453320534145;10833.783324333626;11927.582534611247;11979.101567382655 18:51:15 INFO - PROCESS | 6102 | |3;mootools.html;1453.7455945209585;1598.7282162032457;1630.1034431062913;1913.6842421000526;2031.9695204571933;7948;8108.91891081089;8160.959195204023;8161.10227874934;8578.957105214466;8540.57297135144;8602.225799678043;8705.434146780455;8713.259372953291;8726.432781869175;2468.506298740255;2492.264781889338;2492.9252122436355;2494.6133349330794;2498.1756020513276;2167.5881582499355;2219.6226641470907;2400.3759022654062;2415.7463466336044;2471.406862353031;2447.486027934136;2449.2774631483753;2451.705795304566;2483.776460118589;2489.116363690891;2429.817763667732;2457.852528848266;2459.3605662527784;2464.6549483072204;2465.3466831289725;2457.103157347573;2468.6667299914643;2473.6784218051716;2488.9875550622314;2490.2404766546197;8936.955315223448;8980.55097245135;8998.505082220476;9018.458892466473;9024.323175761843;5405.5135037846385;5554.805581804658;5564.27664403629;5567.805126820583;5571.777128914839;3418;3501.492283618861;3505.5618047744033;3510.666486683761;3526.1360966563357;1047.3663433622626;1065.1638463805953;1069.534752382716;1077.3159044007034;1081.5615231741842;11910.702232444171;11916.642500724993;11939.641810745692;11962.760744785055;11963.880361196363;4774.737389443544;4898.730569818694;4916.311716359713;4920.507949205123;4978.0292842896015;10022.649207277711;10109.696709098738;10113.494325283706;10114.393136411838;10140.949295253477;5445.918311225335;5450.018996580574;5451.1005684062475;5454.181872719124;5460.235567020541;4869.975650121727;4940.925886111711;4943.728094954812;4947.529984651453;4949.406071271471;3697.445383192489;4021.416894550274;4049.5545489996075;4086.6117718816663;4088.8977775555254;1183.6863231243726;1207.0826172109084;1211.975760484803;1305.693162106904;1317.5652034828483;1372.9931350343184;1381.6338670252392;1395.1001603965587;1410.8800751935996;1418.7162567486544;8077.394195435366;8120.634571444181;8142.18578142181;8143.429959902868;8143.552104634302;1124.820028795389;1144.2676686920377;1150.6893138852463;1151.228676786555;1205.7588482303574;6262.405071518289;6312.558120931582;6333.841653958688;6337.0887562139715;6364.045393191059;10534.841977370346;10547.208959328082;10748.193885458622;10930.508127134299;10930.890691093146;8945.418547794372;10678.359298442118;10785.62250321235;10794.946025269823;10816.91830816912;8095.757127286191;8419.536925469036;8458.661653533913;8514.233718965199;8515.233628973423;7884.881726773988;7924.48490848093;7984.281414672707;8028.197180281925;8036.236557527026;9299.163075323253;9585.281103917094;9638.566264517973;9678.612855485984;9827.606895724091;9689.031096890254;9964.953679863509;10077.344972576759;10140.74648133773;10145.391276523433;7513;7538.020057392504;7551.584662843597;7590.203028681909;7624.618769061635;6703.195616526048;7091.893621595577;8195.795105122419;8324.29243514284;8350.83298334042;7338.77983660491;7343.192248852515;7370.5577665338105;7499.66251518672;7522.3605993491165;7201.531900426456;7303.963480182565;7382.550335570606;7430.591317477374;7531.623418829146;7777.338926191111;7783.1827658097345;7853.646585903533;7862.1351651319455;7866.252705992922;7056.047433596604;7141.750038748619;7146.892796608159;7149.177844547944;7154.606496642735;7175;7285.562866228043;7289.088863892013;7292.781216563511;7295.781126566211 18:51:15 INFO - PROCESS | 6102 | |4;prototype.html;319.09695561560784;324.92851572654024;328.884890288399;332.0834496788862;336.59272280540546;6495.090687303781;6497.935020649792;6502.7398904043785;6507.772227972022;6564.967175164124;6512.055751915969;6664;6672.9666351668175;6676.26561078281;6694.397504224618;3687.7418580699364;4741.573258406743;4788.832390866321;4897.090134847394;4903.632227582945;4825.517448255164;4868.586170175522;4869.805207791684;4874.97562512187;4881.096997055538;4285.778553112364;4405.779711014453;4580.931286030712;4822.348982887318;4835.395575553056;4704.082703872747;4707.646926480527;4727.243641017455;4744.478107408182;4866;3609.9097522561883;3926.960730392688;4163.417121602978;4170.207660544487;4177.143685544486;6197.504199664016;6275.309715931244;6287.779927702554;6295.779647712354;6301.243850737895;5270.341207349082;5760.107183386582;5782.10377391505;5815.418458154193;5819.127130930352;6361.300256971729;6737.528373013891;7031.613261270628;7035;7048.894266585954;4182.53992060873;4483.843065492725;4766.833160839354;4820.156472617278;4852.369192005052;7367.668454919488;7446.329830315243;7446.478746487748;7450.85098298031;7474.327310542023;4799.712017278975;4801.423829140455;4809.350737650442;4814.1815891297865;4816.735079570657;4868.196747536687;4890.902181956341;4894.755262236874;4894.828680996148;4899.608031357483;5562.137868630288;5831.3877042910735;5834.824955251349;5853.180554722342;5855.121731740273;7014.018037474758;7037.261087585832;7109.786706398816;7112.964435177791;7137.82155446118;1700;1718.5789481577094;1724.1034661975702;1724.2068648421866;1725.767021452113;1020.3673722292226;1105.5798796457295;1115.4161091250444;1130.2314426190105;1142.1091548592112;6191.79260044296;6388.584741991755;6541.640209788412;6552.115464412339;6554.868902621921;7349.669264883093;7374.852502949911;7386.409087273005;7391.741289054858;7392.4825262231125;6458.80512105262;6473.902891456632;6476.31998640136;6479.902801457982;6480.773172938924;3841.558220804643;3892.4355968384425;3900.90247743803;3906.824192911326;3906.902327441837;7292.197348836694;7663.386929045663;7686.269804368576;7695.230476952372;7699.499532530368;7547.886781698279;7598.354139898171;7608.581528015901;7614.6954121835615;7625.733099341496;7632.045994250719;7639.777635578281;7640.121386040676;7657.808554786064;7687.846243075219;6421.6514531948815;6901.861962760817;7084.539504932163;7381.224971377928;7391.334779869838;7262.692715311295;7264.055672762507;7275.236100209402;7280.781576552712;7282.563046217244;6079.361667025076;7658.4639075264195;7660.885086723815;7670.386369090348;7678.347340475899;3954.2289253596423;4839.733814640229;4839.975800120977;4846.975765121152;4851.223804191312;6582.80251592453;6680.99785032249;7060.576365418091;7323.048003759477;7323.63381830917;7061.258567850302;7087.14954205478;7091.1490621125795;7093.467989900882;7104.751333703295;6497.25281592604;6680.03139544773;7020.192677842114;7066.752663656747;7072.328128827753;5939.524838013035;6732.360425759545;6876.140482439695;6885;6917.550359226835;6811.182658081062;7071.186813516511;7083.858322833617;7086.078809754699;7088.362047415551;5412.512873841373;6572.868542629025;6704.731810727516;6909.89635155463;6917.792466226021 18:51:15 INFO - PROCESS | 6102 | |5;yui.html;599.3437186281022;734.5480104341456;742.4320394897908;750.6134340814482;810.8337790752896;15051.548453546397;15271.08373497591;15308;15309.387624495006;15314.387424503006;13888.37502312396;13998.230097344642;14035.157890526576;14191.716165676706;14196.645083872909;753.8190834199808;767.4781148818801;919.3794188922484;938.0431959401407;939.7462685075026;762.3824701991377;768.4313607930147;770.2105342024441;922.5571725571696;945.2815859946456;989.5052473763119;991.4794732765283;992.3648864726545;994.6866736977829;996.4170959988415;977.7018009507098;983.1446641421937;983.2920297385871;983.3853841349156;984.5421878826344;579.2498714165166;710.2187593646998;732.2348146187247;732.3482100930177;735.3749313083889;14976.47582334613;15006;15039.548813535503;15046.172460514666;15067.698646027127;10386.272960892666;12440.75118497634;12589.74820503594;12707.428165732472;12758.979281657537;9875.950620246924;9954.153896918771;10024.699259022242;10296.845547316796;10798.028177464066;3471.6528347165336;3556.5376501054957;3559.2169722660974;3652.6164752701116;3673.302072606223;57294.14058789121;76605.85091223691;84900;85029.72455413174;85288.86777830604;9358.4384936904;9361;9675.806483870283;9704;9776.52518573108;20796.1681532737;21060.684089738665;21074.78925210759;21093;21104.577908441745;7159.675460039909;7434.702611895572;7475.252474752481;7714.267144621364;7817.452778305464;4048.149888523384;4114.136031433432;4123.546409894909;4132.070284186094;4132.669386449077;3684.428913518409;3732.5894151643297;3738.9065273368383;3739.719521035933;3741.139537906297;660.7654282729658;672.2571558427867;675.4967549175896;695.1137299942512;695.1796879681923;585.5666806563113;588.1941739816479;606.3057798820327;626.417431788432;629.2700467457819;7201.243869393638;7882;8277.95861020691;8363.247307742211;8408.621612027473;496.473737837893;575.8330084275514;586.9530437564985;597.5667640960356;607.4654304212353;3822.2546603412625;3825.387937929918;3871;3912.2762288976633;4087.9560219890054;79690;86173.56913215393;86355.56822215849;86508.56745716358;90384;78699.42601147945;81403.77894331589;81951.5902420484;81984.59007704923;83921;70806;70981.64509177524;71666.92499612614;71700;72458.27541724726;8376.664933402595;8676.089010654043;8718.128187181232;8822.867656985021;8923;49837;51917;52385;53104.67238319073;53320.73339633277;68160;68897.65551172211;70514.64742676253;70704.64647676729;71739.64130179316;10903.127749780157;10967.945160274148;11037.227394082338;11226.438678066228;11881.465334060162;9566.330356874952;12029.338386388483;12215.083868709704;12390.690232743893;12702.110852240254;11349;11419.200655954004;11645.825312620149;11732.589359372381;11768.76462470763;11345.20583559176;11418.942905285421;11529.020033297264;11919.642410727693;12517.874821251671;11068.114550836075;11219.382933938712;11229.943850280697;11479.25384849982;11489.092727987498;9346.439213647203;9359.812803743751;9489.1934185595;9884.555195016097;9992.250581206294;9454.385464944757;9579.712608621752;9603.327767056518;9636.46999415039;9668.613255469703 18:51:15 INFO - PROCESS | 6102 | __end_tp_report 18:51:15 INFO - PROCESS | 6102 | __start_cc_report 18:51:15 INFO - PROCESS | 6102 | _x_x_mozilla_cycle_collect,2171 18:51:15 INFO - PROCESS | 6102 | __end_cc_report 18:51:15 INFO - PROCESS | 6102 | __startTimestamp1457146275070__endTimestamp 18:51:15 INFO - PROCESS | 6102 | 18:51:15 INFO - PROCESS | 6102 | ------- Summary: start ------- 18:51:15 INFO - PROCESS | 6102 | Number of tests: 6 18:51:15 INFO - PROCESS | 6102 | 18:51:15 INFO - PROCESS | 6102 | [#0] dojo.html Cycles:165 Average:5685.06 Median:5866.59 stddev:640.86 (10.9%) stddev-sans-first:615.78 18:51:15 INFO - PROCESS | 6102 | Values: 3336.5 3395.9 3405.8 3426.3 3459.4 4558.6 5599.7 5611.2 5643.2 5649.0 5684.5 5689.9 5747.5 5828.2 5850.7 5066.4 5127.6 5144.8 5147.2 5218.8 5251.5 5252.1 5293.1 5300.4 5355.3 4283.3 5144.3 5190.0 5191.8 5199.0 5180.4 5196.9 5201.2 5202.8 5222.4 5137.2 5144.1 5166.4 5166.8 5218.7 4880.7 5442.4 5533.1 5581.0 5595.5 6171.9 6208.8 6215.6 6218.1 6223.2 6147.4 6182.9 6207.1 6220.1 6230.7 5634.4 5735.3 5739.5 5850.1 5865.0 6187.1 6206.4 6208.3 6222.3 6256.6 5986.2 6021.4 6094.5 6094.7 6097.2 5764.0 5985.6 6140.1 6257.1 6278.9 6161.5 6195.7 6200.0 6206.4 6208.1 6175.6 6179.4 6193.7 6203.2 6216.4 4787.6 4821.6 4867.0 4867.6 4868.3 4394.4 4431.8 4444.6 4456.3 4456.7 5522.8 5568.5 5601.1 5666.0 5690.6 5780.2 5782.7 6270.6 6292.4 6294.0 5593.8 5605.5 5614.0 5627.9 5628.4 5227.0 5298.8 5325.1 5340.2 5351.7 6234.9 6245.1 6252.1 6264.5 6271.1 6168.8 6276.8 6282.0 6290.4 6302.9 6210.8 6232.1 6232.1 6252.8 6266.7 6137.5 6142.7 6161.8 6166.0 6171.9 6220.8 6234.3 6248.0 6272.0 6278.3 5901.7 5999.1 6061.1 6062.0 6065.8 5981.9 6005.8 6022.9 6040.5 6043.8 5745.2 5823.7 5842.6 5866.6 5884.1 5967.3 5972.6 6016.2 6119.4 6123.3 5838.1 5934.0 5967.6 5970.8 5973.8 18:51:15 INFO - PROCESS | 6102 | 18:51:15 INFO - PROCESS | 6102 | [#1] ext.html Cycles:180 Average:60044.43 Median:33733.33 stddev:121803.78 (361.1%) stddev-sans-first:122063.35 18:51:15 INFO - PROCESS | 6102 | Values: 472.9 507.0 530.7 586.7 636.9 27385.3 31158.0 38748.0 39315.4 39810.6 42033.7 42108.0 42325.4 42416.4 42458.0 9758.9 10011.8 10018.6 10032.4 10037.9 9779.0 9954.3 9961.5 9965.0 9970.2 10041.6 10122.5 10134.0 10136.4 10140.2 10014.8 10055.7 10083.4 10093.0 10094.8 9733.5 9875.4 9876.3 9880.7 9882.0 41267.8 41494.0 41608.8 41612.0 41727.6 3434.1 3528.3 3815.2 3842.3 3974.7 29466.6 30023.7 30258.0 30449.1 30518.8 2715.7 2852.1 2860.2 2878.0 2962.4 478700.0 481247.0 481375.0 482024.0 483488.0 64579.7 64680.0 65129.7 65194.0 65245.0 67756.0 68274.7 68413.7 68504.0 68552.7 16972.6 17189.5 17224.9 17227.1 17253.0 13101.2 13184.2 13203.4 13217.7 13221.5 19013.4 19948.9 20284.8 20286.5 20311.1 7514.7 7902.1 8760.1 8914.6 8928.0 771.7 1002.1 1025.0 1037.0 1109.7 28474.6 28511.9 29014.7 29219.7 29242.4 1072.5 1111.8 1123.4 1127.6 1156.8 15361.2 15511.3 15827.4 15921.0 16376.8 536063.0 542712.0 543401.0 543866.0 544741.0 51202.2 58911.4 60937.4 61432.0 61477.0 1366.3 1395.7 1500.2 1521.0 1639.1 27912.6 27923.0 28228.4 28459.9 28822.1 46550.5 47254.3 47296.1 47349.5 47361.5 312320.4 323520.0 323657.0 324114.0 325181.0 28468.6 32304.0 32375.8 33485.3 33630.0 40209.0 40530.8 41715.6 41899.4 42013.8 34480.8 34629.7 34846.3 35382.9 35385.0 33173.3 33836.7 34153.0 35175.6 35347.8 36003.3 36280.6 37095.4 37454.8 38247.6 29150.7 31022.8 31546.4 32347.2 32660.2 24215.6 24391.8 24654.9 24689.8 24820.9 18:51:15 INFO - PROCESS | 6102 | 18:51:15 INFO - PROCESS | 6102 | [#2] jquery.html Cycles:180 Average:35839.56 Median:13772.45 stddev:104935.89 (761.9%) stddev-sans-first:105196.44 18:51:15 INFO - PROCESS | 6102 | Values: 356.7 359.8 366.1 367.5 373.0 20428.0 20817.8 20935.1 21094.5 21217.0 20965.6 20987.4 21824.6 21862.0 22010.1 5762.3 5929.1 5978.2 5981.7 5992.0 5693.7 5798.0 5876.9 5878.3 5888.6 4922.7 5030.8 5711.7 5844.8 5896.5 5664.5 5679.3 5839.6 5839.6 5841.6 5080.5 5116.2 5120.9 5124.6 5125.7 24977.5 26732.9 27064.6 27065.9 27304.6 2191.9 2388.5 2450.3 2567.4 2901.3 14309.7 14840.6 14928.8 15005.1 15462.7 1698.4 1735.3 1797.5 1893.6 2023.2 109054.9 113456.4 113707.0 113725.0 118561.0 11387.2 11464.8 11475.9 11482.4 11576.8 12489.8 13438.0 13688.5 13832.1 13839.6 8520.7 8718.9 8730.9 8807.9 8810.2 8049.6 8105.6 8865.7 8873.2 9009.5 3731.2 4438.2 4528.5 4536.0 4608.7 1637.4 1893.2 1933.6 1975.0 1979.9 735.7 756.7 936.5 980.7 1004.2 19903.2 20940.7 20974.2 21218.6 21255.0 913.0 923.4 963.8 1000.2 1042.6 11011.6 11066.6 11202.3 11334.0 11337.0 608451.0 627921.0 635326.0 643670.0 647059.0 94961.0 122419.4 123053.0 123980.0 126360.0 893.6 1021.8 1091.3 1110.0 1113.2 18418.6 18695.8 18751.2 18849.7 19093.9 51496.0 55968.7 58033.1 58587.7 61170.1 74015.0 74669.9 75395.0 76477.7 77275.6 8000.6 8173.6 8187.9 8340.8 8497.8 9538.7 12901.4 13387.3 13886.6 13893.2 9116.9 11731.2 11971.5 11973.7 11994.8 11135.7 11392.3 11555.5 11931.3 11999.6 11571.7 11838.9 11879.8 12006.3 12122.7 8035.8 8087.1 8243.3 8314.9 8355.7 8218.4 8410.5 10833.8 11927.6 11979.1 18:51:15 INFO - PROCESS | 6102 | 18:51:15 INFO - PROCESS | 6102 | [#3] mootools.html Cycles:180 Average:6079.53 Median:4963.72 stddev:3175.55 (64.0%) stddev-sans-first:3165.42 18:51:15 INFO - PROCESS | 6102 | Values: 1453.7 1598.7 1630.1 1913.7 2032.0 7948.0 8108.9 8161.0 8161.1 8579.0 8540.6 8602.2 8705.4 8713.3 8726.4 2468.5 2492.3 2492.9 2494.6 2498.2 2167.6 2219.6 2400.4 2415.7 2471.4 2447.5 2449.3 2451.7 2483.8 2489.1 2429.8 2457.9 2459.4 2464.7 2465.3 2457.1 2468.7 2473.7 2489.0 2490.2 8937.0 8980.6 8998.5 9018.5 9024.3 5405.5 5554.8 5564.3 5567.8 5571.8 3418.0 3501.5 3505.6 3510.7 3526.1 1047.4 1065.2 1069.5 1077.3 1081.6 11910.7 11916.6 11939.6 11962.8 11963.9 4774.7 4898.7 4916.3 4920.5 4978.0 10022.6 10109.7 10113.5 10114.4 10140.9 5445.9 5450.0 5451.1 5454.2 5460.2 4870.0 4940.9 4943.7 4947.5 4949.4 3697.4 4021.4 4049.6 4086.6 4088.9 1183.7 1207.1 1212.0 1305.7 1317.6 1373.0 1381.6 1395.1 1410.9 1418.7 8077.4 8120.6 8142.2 8143.4 8143.6 1124.8 1144.3 1150.7 1151.2 1205.8 6262.4 6312.6 6333.8 6337.1 6364.0 10534.8 10547.2 10748.2 10930.5 10930.9 8945.4 10678.4 10785.6 10794.9 10816.9 8095.8 8419.5 8458.7 8514.2 8515.2 7884.9 7924.5 7984.3 8028.2 8036.2 9299.2 9585.3 9638.6 9678.6 9827.6 9689.0 9965.0 10077.3 10140.7 10145.4 7513.0 7538.0 7551.6 7590.2 7624.6 6703.2 7091.9 8195.8 8324.3 8350.8 7338.8 7343.2 7370.6 7499.7 7522.4 7201.5 7304.0 7382.6 7430.6 7531.6 7777.3 7783.2 7853.6 7862.1 7866.3 7056.0 7141.8 7146.9 7149.2 7154.6 7175.0 7285.6 7289.1 7292.8 7295.8 18:51:15 INFO - PROCESS | 6102 | 18:51:15 INFO - PROCESS | 6102 | [#4] prototype.html Cycles:180 Average:5752.02 Median:6480.34 stddev:1830.03 (28.2%) stddev-sans-first:1789.16 18:51:15 INFO - PROCESS | 6102 | Values: 319.1 324.9 328.9 332.1 336.6 6495.1 6497.9 6502.7 6507.8 6565.0 6512.1 6664.0 6673.0 6676.3 6694.4 3687.7 4741.6 4788.8 4897.1 4903.6 4825.5 4868.6 4869.8 4875.0 4881.1 4285.8 4405.8 4580.9 4822.3 4835.4 4704.1 4707.6 4727.2 4744.5 4866.0 3609.9 3927.0 4163.4 4170.2 4177.1 6197.5 6275.3 6287.8 6295.8 6301.2 5270.3 5760.1 5782.1 5815.4 5819.1 6361.3 6737.5 7031.6 7035.0 7048.9 4182.5 4483.8 4766.8 4820.2 4852.4 7367.7 7446.3 7446.5 7450.9 7474.3 4799.7 4801.4 4809.4 4814.2 4816.7 4868.2 4890.9 4894.8 4894.8 4899.6 5562.1 5831.4 5834.8 5853.2 5855.1 7014.0 7037.3 7109.8 7113.0 7137.8 1700.0 1718.6 1724.1 1724.2 1725.8 1020.4 1105.6 1115.4 1130.2 1142.1 6191.8 6388.6 6541.6 6552.1 6554.9 7349.7 7374.9 7386.4 7391.7 7392.5 6458.8 6473.9 6476.3 6479.9 6480.8 3841.6 3892.4 3900.9 3906.8 3906.9 7292.2 7663.4 7686.3 7695.2 7699.5 7547.9 7598.4 7608.6 7614.7 7625.7 7632.0 7639.8 7640.1 7657.8 7687.8 6421.7 6901.9 7084.5 7381.2 7391.3 7262.7 7264.1 7275.2 7280.8 7282.6 6079.4 7658.5 7660.9 7670.4 7678.3 3954.2 4839.7 4840.0 4847.0 4851.2 6582.8 6681.0 7060.6 7323.0 7323.6 7061.3 7087.1 7091.1 7093.5 7104.8 6497.3 6680.0 7020.2 7066.8 7072.3 5939.5 6732.4 6876.1 6885.0 6917.6 6811.2 7071.2 7083.9 7086.1 7088.4 5412.5 6572.9 6704.7 6909.9 6917.8 18:51:15 INFO - PROCESS | 6102 | 18:51:15 INFO - PROCESS | 6102 | [#5] yui.html Cycles:180 Average:18320.28 Median:666.51 stddev:25575.64 (3837.2%) stddev-sans-first:25612.77 18:51:15 INFO - PROCESS | 6102 | Values: 599.3 734.5 742.4 750.6 810.8 15051.5 15271.1 15308.0 15309.4 15314.4 13888.4 13998.2 14035.2 14191.7 14196.6 753.8 767.5 919.4 938.0 939.7 762.4 768.4 770.2 922.6 945.3 989.5 991.5 992.4 994.7 996.4 977.7 983.1 983.3 983.4 984.5 579.2 710.2 732.2 732.3 735.4 14976.5 15006.0 15039.5 15046.2 15067.7 10386.3 12440.8 12589.7 12707.4 12759.0 9876.0 9954.2 10024.7 10296.8 10798.0 3471.7 3556.5 3559.2 3652.6 3673.3 57294.1 76605.9 84900.0 85029.7 85288.9 9358.4 9361.0 9675.8 9704.0 9776.5 20796.2 21060.7 21074.8 21093.0 21104.6 7159.7 7434.7 7475.3 7714.3 7817.5 4048.1 4114.1 4123.5 4132.1 4132.7 3684.4 3732.6 3738.9 3739.7 3741.1 660.8 672.3 675.5 695.1 695.2 585.6 588.2 606.3 626.4 629.3 7201.2 7882.0 8278.0 8363.2 8408.6 496.5 575.8 587.0 597.6 607.5 3822.3 3825.4 3871.0 3912.3 4088.0 79690.0 86173.6 86355.6 86508.6 90384.0 78699.4 81403.8 81951.6 81984.6 83921.0 70806.0 70981.6 71666.9 71700.0 72458.3 8376.7 8676.1 8718.1 8822.9 8923.0 49837.0 51917.0 52385.0 53104.7 53320.7 68160.0 68897.7 70514.6 70704.6 71739.6 10903.1 10967.9 11037.2 11226.4 11881.5 9566.3 12029.3 12215.1 12390.7 12702.1 11349.0 11419.2 11645.8 11732.6 11768.8 11345.2 11418.9 11529.0 11919.6 12517.9 11068.1 11219.4 11229.9 11479.3 11489.1 9346.4 9359.8 9489.2 9884.6 9992.3 9454.4 9579.7 9603.3 9636.5 9668.6 18:51:15 INFO - PROCESS | 6102 | -------- Summary: end -------- 18:51:15 INFO - PROCESS | 6102 | 18:51:15 INFO - TEST-INFO | 6102: exit 0 18:51:15 INFO - TEST-OK | dromaeo_css | took 1114829ms 18:51:15 INFO - TEST-START | kraken 18:51:15 INFO - operating with platform_type : mac_ 18:51:15 INFO - Initialising browser for kraken test... 18:51:15 INFO - TEST-INFO | started process 6106 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/tmpo0ycDF/profile http://localhost:56228/getInfo.html) 18:51:17 INFO - PROCESS | 6106 | __metrics Screen width/height:1600/1200 18:51:17 INFO - PROCESS | 6106 | colorDepth:24 18:51:17 INFO - PROCESS | 6106 | Browser inner width/height: 1024/689 18:51:17 INFO - PROCESS | 6106 | __metrics 18:51:17 INFO - PROCESS | 6106 | 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" 18:51:17 INFO - PROCESS | 6106 | JavaScript error: resource://app/modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 18:51:18 INFO - TEST-INFO | 6106: exit 0 18:51:18 INFO - Browser initialized. 18:51:18 INFO - Running cycle 1/1 for kraken test... 18:51:18 INFO - TEST-INFO | started process 6108 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/tmpo0ycDF/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 18:51:30 INFO - PROCESS | 6108 | RSS: Main: 246050816 18:51:30 INFO - PROCESS | 6108 | 18:51:32 INFO - PROCESS | 6108 | Cycle 1(1): loaded http://localhost:56228/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:56228/tests/kraken/driver.html?testName=audio-beat-detection) 18:51:33 INFO - PROCESS | 6108 | RSS: Main: 291336192 18:51:33 INFO - PROCESS | 6108 | 18:51:36 INFO - PROCESS | 6108 | Cycle 1(1): loaded http://localhost:56228/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:56228/tests/kraken/driver.html?testName=audio-dft) 18:51:36 INFO - PROCESS | 6108 | RSS: Main: 301277184 18:51:36 INFO - PROCESS | 6108 | 18:51:40 INFO - PROCESS | 6108 | Cycle 1(1): loaded http://localhost:56228/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:56228/tests/kraken/driver.html?testName=audio-fft) 18:51:40 INFO - PROCESS | 6108 | RSS: Main: 287416320 18:51:40 INFO - PROCESS | 6108 | 18:51:42 INFO - PROCESS | 6108 | Cycle 1(1): loaded http://localhost:56228/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:56228/tests/kraken/driver.html?testName=audio-oscillator) 18:51:43 INFO - PROCESS | 6108 | RSS: Main: 288993280 18:51:43 INFO - PROCESS | 6108 | 18:51:45 INFO - PROCESS | 6108 | Cycle 1(1): loaded http://localhost:56228/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:56228/tests/kraken/driver.html?testName=imaging-gaussian-blur) 18:51:46 INFO - PROCESS | 6108 | RSS: Main: 287629312 18:51:46 INFO - PROCESS | 6108 | 18:51:49 INFO - PROCESS | 6108 | Cycle 1(1): loaded http://localhost:56228/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:56228/tests/kraken/driver.html?testName=imaging-darkroom) 18:51:49 INFO - PROCESS | 6108 | RSS: Main: 290254848 18:51:49 INFO - PROCESS | 6108 | 18:51:52 INFO - PROCESS | 6108 | Cycle 1(1): loaded http://localhost:56228/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:56228/tests/kraken/driver.html?testName=imaging-desaturate) 18:51:53 INFO - PROCESS | 6108 | RSS: Main: 290840576 18:51:53 INFO - PROCESS | 6108 | 18:51:55 INFO - PROCESS | 6108 | Cycle 1(1): loaded http://localhost:56228/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:56228/tests/kraken/driver.html?testName=json-parse-financial) 18:51:56 INFO - PROCESS | 6108 | RSS: Main: 289374208 18:51:56 INFO - PROCESS | 6108 | 18:51:58 INFO - PROCESS | 6108 | Cycle 1(1): loaded http://localhost:56228/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:56228/tests/kraken/driver.html?testName=json-stringify-tinderbox) 18:51:58 INFO - PROCESS | 6108 | RSS: Main: 322985984 18:51:58 INFO - PROCESS | 6108 | 18:52:00 INFO - PROCESS | 6108 | Cycle 1(1): loaded http://localhost:56228/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:56228/tests/kraken/driver.html?testName=stanford-crypto-aes) 18:52:01 INFO - PROCESS | 6108 | RSS: Main: 323440640 18:52:01 INFO - PROCESS | 6108 | 18:52:03 INFO - PROCESS | 6108 | Cycle 1(1): loaded http://localhost:56228/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:56228/tests/kraken/driver.html?testName=stanford-crypto-ccm) 18:52:03 INFO - PROCESS | 6108 | RSS: Main: 332832768 18:52:03 INFO - PROCESS | 6108 | 18:52:06 INFO - PROCESS | 6108 | Cycle 1(1): loaded http://localhost:56228/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:56228/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 18:52:07 INFO - PROCESS | 6108 | RSS: Main: 333381632 18:52:07 INFO - PROCESS | 6108 | 18:52:09 INFO - PROCESS | 6108 | Cycle 1(1): loaded http://localhost:56228/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:56228/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 18:52:10 INFO - PROCESS | 6108 | RSS: Main: 332677120 18:52:10 INFO - PROCESS | 6108 | 18:52:11 INFO - PROCESS | 6108 | Cycle 1(1): loaded http://localhost:56228/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:56228/tests/kraken/driver.html?testName=ai-astar) 18:52:12 INFO - PROCESS | 6108 | RSS: Main: 394461184 18:52:12 INFO - PROCESS | 6108 | 18:52:12 INFO - PROCESS | 6108 | __start_tp_report 18:52:12 INFO - PROCESS | 6108 | _x_x_mozilla_page_load 18:52:12 INFO - PROCESS | 6108 | _x_x_mozilla_page_load_details 18:52:12 INFO - PROCESS | 6108 | |i|pagename|runs| 18:52:12 INFO - PROCESS | 6108 | |0;ai-astar;110;108;110;110;109;109;114;110;110;110 18:52:12 INFO - PROCESS | 6108 | |1;audio-beat-detection;158;122;148;159;119;145;142;118;166;113 18:52:12 INFO - PROCESS | 6108 | |2;audio-dft;186;184;185;185;185;185;190;187;179;185 18:52:12 INFO - PROCESS | 6108 | |3;audio-fft;75;77;83;80;76;82;83;104;78;55 18:52:12 INFO - PROCESS | 6108 | |4;audio-oscillator;91;99;136;80;113;126;152;76;79;112 18:52:12 INFO - PROCESS | 6108 | |5;imaging-gaussian-blur;90;86;135;130;90;87;135;130;91;86 18:52:12 INFO - PROCESS | 6108 | |6;imaging-darkroom;94;94;94;95;94;94;95;95;94;95 18:52:12 INFO - PROCESS | 6108 | |7;imaging-desaturate;88;85;84;86;85;87;87;87;83;83 18:52:12 INFO - PROCESS | 6108 | |8;json-parse-financial;43;43;72;72;79;80;64;62;55;53 18:52:12 INFO - PROCESS | 6108 | |9;json-stringify-tinderbox;63;69;73;80;72;71;68;68;74;92 18:52:12 INFO - PROCESS | 6108 | |10;stanford-crypto-aes;82;82;83;82;84;83;81;80;84;84 18:52:12 INFO - PROCESS | 6108 | |11;stanford-crypto-ccm;105;126;130;129;129;119;129;125;122;128 18:52:12 INFO - PROCESS | 6108 | |12;stanford-crypto-pbkdf2;139;125;140;137;138;125;137;139;136;135 18:52:12 INFO - PROCESS | 6108 | |13;stanford-crypto-sha256-iterative;57;79;98;67;68;67;66;66;66;67 18:52:12 INFO - PROCESS | 6108 | __end_tp_report 18:52:12 INFO - PROCESS | 6108 | __start_cc_report 18:52:12 INFO - PROCESS | 6108 | _x_x_mozilla_cycle_collect,1258 18:52:12 INFO - PROCESS | 6108 | __end_cc_report 18:52:12 INFO - PROCESS | 6108 | __startTimestamp1457146332040__endTimestamp 18:52:12 INFO - PROCESS | 6108 | 18:52:12 INFO - PROCESS | 6108 | ------- Summary: start ------- 18:52:12 INFO - PROCESS | 6108 | Number of tests: 14 18:52:12 INFO - PROCESS | 6108 | 18:52:12 INFO - PROCESS | 6108 | [#0] ai-astar Cycles:10 Average:110.00 Median:110.00 stddev:1.56 (1.4%) stddev-sans-first:1.66 18:52:12 INFO - PROCESS | 6108 | Values: 110.0 108.0 110.0 110.0 109.0 109.0 114.0 110.0 110.0 110.0 18:52:12 INFO - PROCESS | 6108 | 18:52:12 INFO - PROCESS | 6108 | [#1] audio-beat-detection Cycles:10 Average:139.00 Median:146.50 stddev:19.50 (13.3%) stddev-sans-first:19.43 18:52:12 INFO - PROCESS | 6108 | Values: 158.0 122.0 148.0 159.0 119.0 145.0 142.0 118.0 166.0 113.0 18:52:12 INFO - PROCESS | 6108 | 18:52:12 INFO - PROCESS | 6108 | [#2] audio-dft Cycles:10 Average:185.10 Median:185.00 stddev:2.73 (1.5%) stddev-sans-first:2.87 18:52:12 INFO - PROCESS | 6108 | Values: 186.0 184.0 185.0 185.0 185.0 185.0 190.0 187.0 179.0 185.0 18:52:12 INFO - PROCESS | 6108 | 18:52:12 INFO - PROCESS | 6108 | [#3] audio-fft Cycles:10 Average:79.30 Median:79.00 stddev:11.89 (15.0%) stddev-sans-first:12.51 18:52:12 INFO - PROCESS | 6108 | Values: 75.0 77.0 83.0 80.0 76.0 82.0 83.0 104.0 78.0 55.0 18:52:12 INFO - PROCESS | 6108 | 18:52:12 INFO - PROCESS | 6108 | [#4] audio-oscillator Cycles:10 Average:106.40 Median:77.50 stddev:25.99 (33.5%) stddev-sans-first:26.96 18:52:12 INFO - PROCESS | 6108 | Values: 91.0 99.0 136.0 80.0 113.0 126.0 152.0 76.0 79.0 112.0 18:52:12 INFO - PROCESS | 6108 | 18:52:12 INFO - PROCESS | 6108 | [#5] imaging-gaussian-blur Cycles:10 Average:106.00 Median:86.50 stddev:22.93 (26.5%) stddev-sans-first:23.58 18:52:12 INFO - PROCESS | 6108 | Values: 90.0 86.0 135.0 130.0 90.0 87.0 135.0 130.0 91.0 86.0 18:52:12 INFO - PROCESS | 6108 | 18:52:12 INFO - PROCESS | 6108 | [#6] imaging-darkroom Cycles:10 Average:94.40 Median:94.50 stddev:0.52 (0.5%) stddev-sans-first:0.53 18:52:12 INFO - PROCESS | 6108 | Values: 94.0 94.0 94.0 95.0 94.0 94.0 95.0 95.0 94.0 95.0 18:52:12 INFO - PROCESS | 6108 | 18:52:12 INFO - PROCESS | 6108 | [#7] imaging-desaturate Cycles:10 Average:85.50 Median:86.50 stddev:1.78 (2.1%) stddev-sans-first:1.64 18:52:12 INFO - PROCESS | 6108 | Values: 88.0 85.0 84.0 86.0 85.0 87.0 87.0 87.0 83.0 83.0 18:52:12 INFO - PROCESS | 6108 | 18:52:12 INFO - PROCESS | 6108 | [#8] json-parse-financial Cycles:10 Average:62.30 Median:68.00 stddev:13.61 (20.0%) stddev-sans-first:12.52 18:52:12 INFO - PROCESS | 6108 | Values: 43.0 43.0 72.0 72.0 79.0 80.0 64.0 62.0 55.0 53.0 18:52:12 INFO - PROCESS | 6108 | 18:52:12 INFO - PROCESS | 6108 | [#9] json-stringify-tinderbox Cycles:10 Average:73.00 Median:72.50 stddev:8.04 (11.1%) stddev-sans-first:7.67 18:52:12 INFO - PROCESS | 6108 | Values: 63.0 69.0 73.0 80.0 72.0 71.0 68.0 68.0 74.0 92.0 18:52:12 INFO - PROCESS | 6108 | 18:52:12 INFO - PROCESS | 6108 | [#10] stanford-crypto-aes Cycles:10 Average:82.50 Median:83.00 stddev:1.35 (1.6%) stddev-sans-first:1.42 18:52:12 INFO - PROCESS | 6108 | Values: 82.0 82.0 83.0 82.0 84.0 83.0 81.0 80.0 84.0 84.0 18:52:12 INFO - PROCESS | 6108 | 18:52:12 INFO - PROCESS | 6108 | [#11] stanford-crypto-ccm Cycles:10 Average:124.20 Median:128.50 stddev:7.61 (5.9%) stddev-sans-first:3.74 18:52:12 INFO - PROCESS | 6108 | Values: 105.0 126.0 130.0 129.0 129.0 119.0 129.0 125.0 122.0 128.0 18:52:12 INFO - PROCESS | 6108 | 18:52:12 INFO - PROCESS | 6108 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:135.10 Median:137.50 stddev:5.53 (4.0%) stddev-sans-first:5.68 18:52:12 INFO - PROCESS | 6108 | Values: 139.0 125.0 140.0 137.0 138.0 125.0 137.0 139.0 136.0 135.0 18:52:12 INFO - PROCESS | 6108 | 18:52:12 INFO - PROCESS | 6108 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:70.10 Median:67.00 stddev:11.12 (16.6%) stddev-sans-first:10.74 18:52:12 INFO - PROCESS | 6108 | Values: 57.0 79.0 98.0 67.0 68.0 67.0 66.0 66.0 66.0 67.0 18:52:12 INFO - PROCESS | 6108 | -------- Summary: end -------- 18:52:12 INFO - PROCESS | 6108 | 18:52:12 INFO - TEST-INFO | 6108: exit 0 18:52:12 INFO - TEST-OK | kraken | took 57156ms 18:52:12 INFO - SUITE-END | took 1171s 18:52:13 INFO - Completed test suite (00:19:32) 18:52:13 INFO - javascript benchmark 18:52:13 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [3336.4995250712395, 3395.9151021224484, 3405.778624389416, 3426.3318652862686, 3459.394605943961, 4558.635309175258, 5599.720013999299, 5611.186377975197, 5643.153526970957, 5649.011423000974, 5684.459976302244, 5689.943100568993, 5747.453991870766, 5828.154917536955, 5850.707464626773, 5066.417362003362, 5127.589792816575, 5144.845654630349, 5147.1764517677175, 5218.843434696965, 5251.501107394811, 5252.107141785887, 5293.100172970604, 5300.363956325227, 5355.250264962909, 4283.293256612656, 5144.331236939192, 5189.962007598477, 5191.766370513336, 5199.012187684366, 5180.430152683201, 5196.92204616931, 5201.167813149916, 5202.843914682566, 5222.399424066205, 5137.229415587654, 5144.099782538041, 5166.43169251382, 5166.844994650167, 5218.712970786605, 4880.658353915227, 5442.428545002796, 5533.08704063829, 5581.0, 5595.468430499096, 6171.938280617226, 6208.81373558794, 6215.56491045627, 6218.129461875341, 6223.190985171944, 6147.385261473862, 6182.876342473125, 6207.068939659042, 6220.129181914535, 6230.657313847737, 5634.4365563443735, 5735.340435849847, 5739.454751798572, 5850.0932355484965, 5865.032269675456, 6187.102870083812, 6206.441420272197, 6208.348123446973, 6222.3466536013975, 6256.562040657111, 5986.2217911671205, 6021.3677563856045, 6094.481969032682, 6094.6647934363145, 6097.237845269341, 5764.0, 5985.6408615483215, 6140.074827791203, 6257.092721555349, 6278.874422511615, 6161.476274516716, 6195.690215489208, 6200.0, 6206.441420272197, 6208.068789681584, 6175.62946223228, 6179.3820617937845, 6193.6903154842075, 6203.2245969253845, 6216.378362163748, 4787.61699064074, 4821.566059054632, 4866.977934633766, 4867.561919427269, 4868.342773725572, 4394.36281739146, 4431.7562534060935, 4444.555544445594, 4456.309272062836, 4456.710313829655, 5522.779088836402, 5568.49883510486, 5601.10382338832, 5666.0, 5690.630109042899, 5780.248567686174, 5782.681952492653, 6270.561060725706, 6292.433680968735, 6294.0, 5593.804216852391, 5605.495505404451, 5613.961417137843, 5627.943720562742, 5628.380878103405, 5227.032998895216, 5298.814541491107, 5325.09473389517, 5340.172273297645, 5351.705656188947, 6234.875302493925, 6245.0632405139595, 6252.062190671436, 6264.498840092872, 6271.122042913996, 6168.845778855385, 6276.811695649138, 6281.96859015702, 6290.402411770874, 6302.905456418249, 6210.844728881814, 6232.065190221503, 6232.096346029891, 6252.812415627538, 6266.68666566679, 6137.478314343331, 6142.692865356804, 6161.753529858755, 6166.044263139221, 6171.876562468815, 6220.782272620437, 6234.314225435289, 6247.96876015617, 6272.027835685476, 6278.309385967448, 5901.734421951109, 5999.130126131599, 6061.060535616986, 6062.0, 6065.7876974307455, 5981.880362392815, 6005.849853753691, 6022.8795424090395, 6040.516758659384, 6043.758249669964, 5745.166950792195, 5823.70881455934, 5842.649441033552, 5866.5893387464175, 5884.117382392504, 5967.283925928917, 5972.58191926561, 6016.2479690038745, 6119.357467465852, 6123.326434092157, 5838.12428135783, 5933.970330148494, 5967.552433567413, 5970.791022314198, 5973.820785376446], "unit": "score", "name": "dojo.html", "value": 5644.663887367029}, {"lowerIsBetter": false, "replicates": [472.88414338487075, 506.99614762769716, 530.6921985248556, 586.694918642306, 636.866258085802, 27385.315367115832, 31158.0, 38748.0, 39315.41026884592, 39810.60189398105, 42033.73898783034, 42108.0, 42325.36511952323, 42416.36375454371, 42458.0, 9758.85361719573, 10011.799764004716, 10018.649347272847, 10032.398056116619, 10037.949810250975, 9778.951105244463, 9954.253430992705, 9961.501924903725, 9964.95017524915, 9970.202383809314, 10041.648542301022, 10122.54448549817, 10134.0, 10136.391816491034, 10140.23948203884, 10014.799704005916, 10055.748606284827, 10083.394996300172, 10093.041161089684, 10094.798104037915, 9733.464659443727, 9875.358101723437, 9876.308658393915, 9880.654177103766, 9881.950590247076, 41267.7936610315, 41494.0, 41608.79195604033, 41612.0, 41727.58272417267, 3434.1242983039415, 3528.311979164064, 3815.1606646537716, 3842.2507611015862, 3974.6621537169376, 29466.558001629994, 30023.69976300253, 30258.0, 30449.08652740421, 30518.847405763052, 2715.6876959149554, 2852.0588205892004, 2860.1705505403406, 2878.0214726992713, 2962.4371369439955, 478700.0, 481247.0, 481375.0, 482024.0, 483488.0, 64579.67710161513, 64680.0, 65129.674351628884, 65194.0, 65245.021324680165, 67756.0, 68274.65862670654, 68413.65793171003, 68503.97244041343, 68552.6572367145, 16972.575685607713, 17189.484315470556, 17224.913875430542, 17227.052512111954, 17253.0, 13101.213927164401, 13184.208947463183, 13203.40584673692, 13217.669558260928, 13221.537246196336, 19013.42959711211, 19948.90025549892, 20284.79715202829, 20286.492837679176, 20311.086001129985, 7514.6618402172035, 7902.091259505116, 8760.12398760119, 8914.55427228636, 8928.0, 771.714465647714, 1002.1431675917132, 1025.0, 1036.9585216591402, 1109.7447587054862, 28474.572881406795, 28511.857440712665, 29014.7098529012, 29219.707802921697, 29242.41515169685, 1072.4744875011345, 1111.777644471109, 1123.393367581497, 1127.5813380299212, 1156.7512984708328, 15361.155136467602, 15511.333553111439, 15827.366905323657, 15921.0, 16376.754348684779, 536063.0, 542712.0, 543401.0, 543866.0, 544741.0, 51202.23196652053, 58911.41088589145, 60937.39062609317, 61432.0, 61477.0, 1366.3441548056987, 1395.7069015506654, 1500.189897455362, 1521.0493441599, 1639.0575419134045, 27912.58131127994, 27923.022694205607, 28228.435431290847, 28459.857700711364, 28822.135335939118, 46550.53449465462, 47254.29118563293, 47296.05407891754, 47349.52650473451, 47361.52638473709, 312320.43839780655, 323520.0, 323657.0, 324114.0, 325181.0, 28468.572971405858, 32304.030879073667, 32375.838120809247, 33485.33029339448, 33630.0, 40209.0, 40530.79734601308, 41715.582844172386, 41899.371509426775, 42013.78993105015, 34480.82759586186, 34629.653703462645, 34846.303073938885, 35382.93851184469, 35385.0, 33173.336533269685, 33836.661633384334, 34153.0, 35175.64824351724, 35347.82326088353, 36003.27993440169, 36280.63719362773, 37095.44356834704, 37454.81272593619, 38247.61752382552, 29150.7084929148, 31022.844885775427, 31546.36907261888, 32347.191320216243, 32660.183495412806, 24215.63676544818, 24391.756082438947, 24654.876725616974, 24689.753102468745, 24820.88306026269], "unit": "score", "name": "ext.html", "value": 19710.71083111171}, {"lowerIsBetter": false, "replicates": [356.711064038129, 359.84706499737615, 366.08843978493553, 367.4690072844739, 373.04500478774355, 20428.0, 20817.791822081774, 20935.05792239349, 21094.472638184034, 21217.0, 20965.580688386224, 20987.370378888656, 21824.563508729814, 21862.0, 22010.119595216172, 5762.250907382045, 5929.140274660172, 5978.22283103196, 5981.700914954256, 5992.041273396258, 5693.658380497162, 5798.0, 5876.9118463223085, 5878.294604647448, 5888.646681199141, 4922.68002579833, 5030.849074527751, 5711.742971566268, 5844.824655260349, 5896.469317761401, 5664.518515926151, 5679.318481782192, 5839.620424672383, 5839.620424672383, 5841.561882858781, 5080.491950804927, 5116.181410974264, 5120.948790512121, 5124.590032797368, 5125.692458452468, 24977.500449990897, 26732.866335668197, 27064.59403108955, 27065.864670676718, 27304.590431143548, 2191.9342419727272, 2388.4864754077958, 2450.2894160693377, 2567.4094958159726, 2901.303687115077, 14309.713805723828, 14840.554783356516, 14928.776068358984, 15005.099694018394, 15462.693978895097, 1698.388580111159, 1735.3232239426634, 1797.5170484350333, 1893.5644801695548, 2023.2412845183057, 109054.90945090527, 113456.43271783671, 113707.0, 113725.0, 118561.0, 11387.202895797214, 11464.828027579593, 11475.942620286845, 11482.425878706032, 11576.76846463066, 12489.813467720553, 13437.992150588745, 13688.52090176859, 13832.100913440594, 13839.584812455643, 8520.74437766868, 8718.91281087181, 8730.86903696445, 8807.867881981776, 8810.20708136258, 8049.557274349967, 8105.635246413807, 8865.68970086044, 8873.201411872962, 9009.459432433943, 3731.2350968051483, 4438.201123797748, 4528.50186479487, 4535.9773201133785, 4608.723476591415, 1637.3614290426742, 1893.2332405375844, 1933.6326098041327, 1975.024975024975, 1979.910904009323, 735.6689489729536, 756.7275780718937, 936.5270538378076, 980.6959842448864, 1004.161525126513, 19903.203871845253, 20940.685889711665, 20974.16103355879, 21218.57562848765, 21255.0435230415, 912.9863052054092, 923.4044041593135, 963.7927845513249, 1000.2498126405196, 1042.598599539183, 11011.61459348919, 11066.557337706401, 11202.271852329572, 11334.0, 11337.0, 608451.0, 627921.0, 635326.0, 643670.0, 647059.0, 94961.0, 122419.36763597428, 123053.0, 123980.0, 126360.0, 893.5621545442687, 1021.7905329407454, 1091.3233795047122, 1109.9944500277447, 1113.2429947635524, 18418.63162736711, 18695.813041869405, 18751.249950001846, 18849.717254240924, 19093.90453047726, 51496.0, 55968.72015639896, 58033.129503058335, 58587.70706146442, 61170.082448764195, 74015.0, 74669.87995180185, 75395.0, 76477.67200067415, 77275.61362193378, 8000.59997000136, 8173.56608478803, 8187.877181842158, 8340.833183336421, 8497.757066113156, 9538.713838584854, 12901.354932253538, 13387.263700496565, 13886.583402497941, 13893.235872027135, 9116.863247051167, 11731.178817482694, 11971.521139154336, 11973.700657483632, 11994.76010479803, 11135.665930022113, 11392.25950313227, 11555.537778488766, 11931.284122952651, 11999.64001079969, 11571.6528504145, 11838.881611184122, 11879.762404751684, 12006.279623222634, 12122.696932576757, 8035.799105022421, 8087.069986951576, 8243.299319557666, 8314.875276870973, 8355.665773368997, 8218.383621228311, 8410.453320534145, 10833.783324333626, 11927.582534611247, 11979.101567382655], "unit": "score", "name": "jquery.html", "value": 9685.463891000612}, {"lowerIsBetter": false, "replicates": [1453.7455945209585, 1598.7282162032457, 1630.1034431062913, 1913.6842421000526, 2031.9695204571933, 7948.0, 8108.91891081089, 8160.959195204023, 8161.10227874934, 8578.957105214466, 8540.57297135144, 8602.225799678043, 8705.434146780455, 8713.259372953291, 8726.432781869175, 2468.506298740255, 2492.264781889338, 2492.9252122436355, 2494.6133349330794, 2498.1756020513276, 2167.5881582499355, 2219.6226641470907, 2400.3759022654062, 2415.7463466336044, 2471.406862353031, 2447.486027934136, 2449.2774631483753, 2451.705795304566, 2483.776460118589, 2489.116363690891, 2429.817763667732, 2457.852528848266, 2459.3605662527784, 2464.6549483072204, 2465.3466831289725, 2457.103157347573, 2468.6667299914643, 2473.6784218051716, 2488.9875550622314, 2490.2404766546197, 8936.955315223448, 8980.55097245135, 8998.505082220476, 9018.458892466473, 9024.323175761843, 5405.5135037846385, 5554.805581804658, 5564.27664403629, 5567.805126820583, 5571.777128914839, 3418.0, 3501.492283618861, 3505.5618047744033, 3510.666486683761, 3526.1360966563357, 1047.3663433622626, 1065.1638463805953, 1069.534752382716, 1077.3159044007034, 1081.5615231741842, 11910.702232444171, 11916.642500724993, 11939.641810745692, 11962.760744785055, 11963.880361196363, 4774.737389443544, 4898.730569818694, 4916.311716359713, 4920.507949205123, 4978.0292842896015, 10022.649207277711, 10109.696709098738, 10113.494325283706, 10114.393136411838, 10140.949295253477, 5445.918311225335, 5450.018996580574, 5451.1005684062475, 5454.181872719124, 5460.235567020541, 4869.975650121727, 4940.925886111711, 4943.728094954812, 4947.529984651453, 4949.406071271471, 3697.445383192489, 4021.416894550274, 4049.5545489996075, 4086.6117718816663, 4088.8977775555254, 1183.6863231243726, 1207.0826172109084, 1211.975760484803, 1305.693162106904, 1317.5652034828483, 1372.9931350343184, 1381.6338670252392, 1395.1001603965587, 1410.8800751935996, 1418.7162567486544, 8077.394195435366, 8120.634571444181, 8142.18578142181, 8143.429959902868, 8143.552104634302, 1124.820028795389, 1144.2676686920377, 1150.6893138852463, 1151.228676786555, 1205.7588482303574, 6262.405071518289, 6312.558120931582, 6333.841653958688, 6337.0887562139715, 6364.045393191059, 10534.841977370346, 10547.208959328082, 10748.193885458622, 10930.508127134299, 10930.890691093146, 8945.418547794372, 10678.359298442118, 10785.62250321235, 10794.946025269823, 10816.91830816912, 8095.757127286191, 8419.536925469036, 8458.661653533913, 8514.233718965199, 8515.233628973423, 7884.881726773988, 7924.48490848093, 7984.281414672707, 8028.197180281925, 8036.236557527026, 9299.163075323253, 9585.281103917094, 9638.566264517973, 9678.612855485984, 9827.606895724091, 9689.031096890254, 9964.953679863509, 10077.344972576759, 10140.74648133773, 10145.391276523433, 7513.0, 7538.020057392504, 7551.584662843597, 7590.203028681909, 7624.618769061635, 6703.195616526048, 7091.893621595577, 8195.795105122419, 8324.29243514284, 8350.83298334042, 7338.77983660491, 7343.192248852515, 7370.5577665338105, 7499.66251518672, 7522.3605993491165, 7201.531900426456, 7303.963480182565, 7382.550335570606, 7430.591317477374, 7531.623418829146, 7777.338926191111, 7783.1827658097345, 7853.646585903533, 7862.1351651319455, 7866.252705992922, 7056.047433596604, 7141.750038748619, 7146.892796608159, 7149.177844547944, 7154.606496642735, 7175.0, 7285.562866228043, 7289.088863892013, 7292.781216563511, 7295.781126566211], "unit": "score", "name": "mootools.html", "value": 4974.111958246662}, {"lowerIsBetter": false, "replicates": [319.09695561560784, 324.92851572654024, 328.884890288399, 332.0834496788862, 336.59272280540546, 6495.090687303781, 6497.935020649792, 6502.7398904043785, 6507.772227972022, 6564.967175164124, 6512.055751915969, 6664.0, 6672.9666351668175, 6676.26561078281, 6694.397504224618, 3687.7418580699364, 4741.573258406743, 4788.832390866321, 4897.090134847394, 4903.632227582945, 4825.517448255164, 4868.586170175522, 4869.805207791684, 4874.97562512187, 4881.096997055538, 4285.778553112364, 4405.779711014453, 4580.931286030712, 4822.348982887318, 4835.395575553056, 4704.082703872747, 4707.646926480527, 4727.243641017455, 4744.478107408182, 4866.0, 3609.9097522561883, 3926.960730392688, 4163.417121602978, 4170.207660544487, 4177.143685544486, 6197.504199664016, 6275.309715931244, 6287.779927702554, 6295.779647712354, 6301.243850737895, 5270.341207349082, 5760.107183386582, 5782.10377391505, 5815.418458154193, 5819.127130930352, 6361.300256971729, 6737.528373013891, 7031.613261270628, 7035.0, 7048.894266585954, 4182.53992060873, 4483.843065492725, 4766.833160839354, 4820.156472617278, 4852.369192005052, 7367.668454919488, 7446.329830315243, 7446.478746487748, 7450.85098298031, 7474.327310542023, 4799.712017278975, 4801.423829140455, 4809.350737650442, 4814.1815891297865, 4816.735079570657, 4868.196747536687, 4890.902181956341, 4894.755262236874, 4894.828680996148, 4899.608031357483, 5562.137868630288, 5831.3877042910735, 5834.824955251349, 5853.180554722342, 5855.121731740273, 7014.018037474758, 7037.261087585832, 7109.786706398816, 7112.964435177791, 7137.82155446118, 1700.0, 1718.5789481577094, 1724.1034661975702, 1724.2068648421866, 1725.767021452113, 1020.3673722292226, 1105.5798796457295, 1115.4161091250444, 1130.2314426190105, 1142.1091548592112, 6191.79260044296, 6388.584741991755, 6541.640209788412, 6552.115464412339, 6554.868902621921, 7349.669264883093, 7374.852502949911, 7386.409087273005, 7391.741289054858, 7392.4825262231125, 6458.80512105262, 6473.902891456632, 6476.31998640136, 6479.902801457982, 6480.773172938924, 3841.558220804643, 3892.4355968384425, 3900.90247743803, 3906.824192911326, 3906.902327441837, 7292.197348836694, 7663.386929045663, 7686.269804368576, 7695.230476952372, 7699.499532530368, 7547.886781698279, 7598.354139898171, 7608.581528015901, 7614.6954121835615, 7625.733099341496, 7632.045994250719, 7639.777635578281, 7640.121386040676, 7657.808554786064, 7687.846243075219, 6421.6514531948815, 6901.861962760817, 7084.539504932163, 7381.224971377928, 7391.334779869838, 7262.692715311295, 7264.055672762507, 7275.236100209402, 7280.781576552712, 7282.563046217244, 6079.361667025076, 7658.4639075264195, 7660.885086723815, 7670.386369090348, 7678.347340475899, 3954.2289253596423, 4839.733814640229, 4839.975800120977, 4846.975765121152, 4851.223804191312, 6582.80251592453, 6680.99785032249, 7060.576365418091, 7323.048003759477, 7323.63381830917, 7061.258567850302, 7087.14954205478, 7091.1490621125795, 7093.467989900882, 7104.751333703295, 6497.25281592604, 6680.03139544773, 7020.192677842114, 7066.752663656747, 7072.328128827753, 5939.524838013035, 6732.360425759545, 6876.140482439695, 6885.0, 6917.550359226835, 6811.182658081062, 7071.186813516511, 7083.858322833617, 7086.078809754699, 7088.362047415551, 5412.512873841373, 6572.868542629025, 6704.731810727516, 6909.89635155463, 6917.792466226021], "unit": "score", "name": "prototype.html", "value": 5146.7359219967175}, {"lowerIsBetter": false, "replicates": [599.3437186281022, 734.5480104341456, 742.4320394897908, 750.6134340814482, 810.8337790752896, 15051.548453546397, 15271.08373497591, 15308.0, 15309.387624495006, 15314.387424503006, 13888.37502312396, 13998.230097344642, 14035.157890526576, 14191.716165676706, 14196.645083872909, 753.8190834199808, 767.4781148818801, 919.3794188922484, 938.0431959401407, 939.7462685075026, 762.3824701991377, 768.4313607930147, 770.2105342024441, 922.5571725571696, 945.2815859946456, 989.5052473763119, 991.4794732765283, 992.3648864726545, 994.6866736977829, 996.4170959988415, 977.7018009507098, 983.1446641421937, 983.2920297385871, 983.3853841349156, 984.5421878826344, 579.2498714165166, 710.2187593646998, 732.2348146187247, 732.3482100930177, 735.3749313083889, 14976.47582334613, 15006.0, 15039.548813535503, 15046.172460514666, 15067.698646027127, 10386.272960892666, 12440.75118497634, 12589.74820503594, 12707.428165732472, 12758.979281657537, 9875.950620246924, 9954.153896918771, 10024.699259022242, 10296.845547316796, 10798.028177464066, 3471.6528347165336, 3556.5376501054957, 3559.2169722660974, 3652.6164752701116, 3673.302072606223, 57294.14058789121, 76605.85091223691, 84900.0, 85029.72455413174, 85288.86777830604, 9358.4384936904, 9361.0, 9675.806483870283, 9704.0, 9776.52518573108, 20796.1681532737, 21060.684089738665, 21074.78925210759, 21093.0, 21104.577908441745, 7159.675460039909, 7434.702611895572, 7475.252474752481, 7714.267144621364, 7817.452778305464, 4048.149888523384, 4114.136031433432, 4123.546409894909, 4132.070284186094, 4132.669386449077, 3684.428913518409, 3732.5894151643297, 3738.9065273368383, 3739.719521035933, 3741.139537906297, 660.7654282729658, 672.2571558427867, 675.4967549175896, 695.1137299942512, 695.1796879681923, 585.5666806563113, 588.1941739816479, 606.3057798820327, 626.417431788432, 629.2700467457819, 7201.243869393638, 7882.0, 8277.95861020691, 8363.247307742211, 8408.621612027473, 496.473737837893, 575.8330084275514, 586.9530437564985, 597.5667640960356, 607.4654304212353, 3822.2546603412625, 3825.387937929918, 3871.0, 3912.2762288976633, 4087.9560219890054, 79690.0, 86173.56913215393, 86355.56822215849, 86508.56745716358, 90384.0, 78699.42601147945, 81403.77894331589, 81951.5902420484, 81984.59007704923, 83921.0, 70806.0, 70981.64509177524, 71666.92499612614, 71700.0, 72458.27541724726, 8376.664933402595, 8676.089010654043, 8718.128187181232, 8822.867656985021, 8923.0, 49837.0, 51917.0, 52385.0, 53104.67238319073, 53320.73339633277, 68160.0, 68897.65551172211, 70514.64742676253, 70704.64647676729, 71739.64130179316, 10903.127749780157, 10967.945160274148, 11037.227394082338, 11226.438678066228, 11881.465334060162, 9566.330356874952, 12029.338386388483, 12215.083868709704, 12390.690232743893, 12702.110852240254, 11349.0, 11419.200655954004, 11645.825312620149, 11732.589359372381, 11768.76462470763, 11345.20583559176, 11418.942905285421, 11529.020033297264, 11919.642410727693, 12517.874821251671, 11068.114550836075, 11219.382933938712, 11229.943850280697, 11479.25384849982, 11489.092727987498, 9346.439213647203, 9359.812803743751, 9489.1934185595, 9884.555195016097, 9992.250581206294, 9454.385464944757, 9579.712608621752, 9603.327767056518, 9636.46999415039, 9668.613255469703], "unit": "score", "name": "yui.html", "value": 6950.270540763402}], "name": "dromaeo_css", "value": 7593.770449942155}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [110.0, 108.0, 110.0, 110.0, 109.0, 109.0, 114.0, 110.0, 110.0, 110.0], "unit": "score", "name": "ai-astar", "value": 110.0}, {"lowerIsBetter": true, "replicates": [158.0, 122.0, 148.0, 159.0, 119.0, 145.0, 142.0, 118.0, 166.0, 113.0], "unit": "score", "name": "audio-beat-detection", "value": 139.0}, {"lowerIsBetter": true, "replicates": [186.0, 184.0, 185.0, 185.0, 185.0, 185.0, 190.0, 187.0, 179.0, 185.0], "unit": "score", "name": "audio-dft", "value": 185.1}, {"lowerIsBetter": true, "replicates": [75.0, 77.0, 83.0, 80.0, 76.0, 82.0, 83.0, 104.0, 78.0, 55.0], "unit": "score", "name": "audio-fft", "value": 79.3}, {"lowerIsBetter": true, "replicates": [91.0, 99.0, 136.0, 80.0, 113.0, 126.0, 152.0, 76.0, 79.0, 112.0], "unit": "score", "name": "audio-oscillator", "value": 106.4}, {"lowerIsBetter": true, "replicates": [90.0, 86.0, 135.0, 130.0, 90.0, 87.0, 135.0, 130.0, 91.0, 86.0], "unit": "score", "name": "imaging-gaussian-blur", "value": 106.0}, {"lowerIsBetter": true, "replicates": [94.0, 94.0, 94.0, 95.0, 94.0, 94.0, 95.0, 95.0, 94.0, 95.0], "unit": "score", "name": "imaging-darkroom", "value": 94.4}, {"lowerIsBetter": true, "replicates": [88.0, 85.0, 84.0, 86.0, 85.0, 87.0, 87.0, 87.0, 83.0, 83.0], "unit": "score", "name": "imaging-desaturate", "value": 85.5}, {"lowerIsBetter": true, "replicates": [43.0, 43.0, 72.0, 72.0, 79.0, 80.0, 64.0, 62.0, 55.0, 53.0], "unit": "score", "name": "json-parse-financial", "value": 62.3}, {"lowerIsBetter": true, "replicates": [63.0, 69.0, 73.0, 80.0, 72.0, 71.0, 68.0, 68.0, 74.0, 92.0], "unit": "score", "name": "json-stringify-tinderbox", "value": 73.0}, {"lowerIsBetter": true, "replicates": [82.0, 82.0, 83.0, 82.0, 84.0, 83.0, 81.0, 80.0, 84.0, 84.0], "unit": "score", "name": "stanford-crypto-aes", "value": 82.5}, {"lowerIsBetter": true, "replicates": [105.0, 126.0, 130.0, 129.0, 129.0, 119.0, 129.0, 125.0, 122.0, 128.0], "unit": "score", "name": "stanford-crypto-ccm", "value": 124.2}, {"lowerIsBetter": true, "replicates": [139.0, 125.0, 140.0, 137.0, 138.0, 125.0, 137.0, 139.0, 136.0, 135.0], "unit": "score", "name": "stanford-crypto-pbkdf2", "value": 135.1}, {"lowerIsBetter": true, "replicates": [57.0, 79.0, 98.0, 67.0, 68.0, 67.0, 66.0, 66.0, 66.0, 67.0], "unit": "score", "name": "stanford-crypto-sha256-iterative", "value": 70.1}], "name": "kraken", "value": 1452.8999999999999}]} 18:52:13 INFO - Return code: 0 18:52:13 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 18:52:13 INFO - # TBPL SUCCESS # 18:52:13 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 18:52:13 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 18:52:13 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 18:52:13 INFO - 2016-03-04 18:52:13.349 screenresolution[6112:103113] starting screenresolution argv=screenresolution get 18:52:13 INFO - 2016-03-04 18:52:13.352 screenresolution[6112:103113] Display 0: 1600x1200x32@60 18:52:13 INFO - 2016-03-04 18:52:13.357 screenresolution[6113:103115] starting screenresolution argv=screenresolution list 18:52:13 INFO - Available Modes on Display 0 18:52:13 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 18:52:13 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 18:52:13 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 18:52:13 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 18:52:13 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 18:52:13 INFO - Intel Iris: 18:52:13 INFO - Chipset Model: Intel Iris 18:52:13 INFO - Type: GPU 18:52:13 INFO - Bus: Built-In 18:52:13 INFO - VRAM (Dynamic, Max): 1536 MB 18:52:13 INFO - Vendor: Intel (0x8086) 18:52:13 INFO - Device ID: 0x0a2e 18:52:13 INFO - Revision ID: 0x0009 18:52:13 INFO - Displays: 18:52:13 INFO - Display: 18:52:13 INFO - Resolution: 1600 x 1200 @ 60 Hz 18:52:13 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 18:52:13 INFO - Display Serial Number: 1600x1200 60 18:52:13 INFO - Main Display: Yes 18:52:13 INFO - Mirror: Off 18:52:13 INFO - Online: Yes 18:52:13 INFO - Rotation: Supported 18:52:13 INFO - Return code: 0 18:52:13 INFO - Running post-action listener: _resource_record_post_action 18:52:13 INFO - Running post-run listener: _resource_record_post_run 18:52:14 INFO - Total resource usage - Wall time: 1208s; CPU: 53.0%; Read bytes: 21466624; Write bytes: 704944128; Read time: 235; Write time: 1971 18:52:14 INFO - install - Wall time: 35s; CPU: 55.0%; Read bytes: 390007296; Write bytes: 385266176; Read time: 29508; Write time: 768 18:52:14 INFO - run-tests - Wall time: 1175s; CPU: 53.0%; Read bytes: 21466624; Write bytes: 300512768; Read time: 235; Write time: 1161 18:52:14 INFO - Running post-run listener: _upload_blobber_files 18:52:14 INFO - Blob upload gear active. 18:52:14 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 18:52:14 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 18:52:14 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'] 18:52:14 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 18:52:15 INFO - (blobuploader) - INFO - Open directory for files ... 18:52:15 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 18:52:15 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:52:15 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:52:15 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 18:52:15 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:52:15 INFO - (blobuploader) - INFO - Done attempting. 18:52:15 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 18:52:15 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:52:15 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:52:16 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 18:52:16 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:52:16 INFO - (blobuploader) - INFO - Done attempting. 18:52:16 INFO - (blobuploader) - INFO - Iteration through files over. 18:52:16 INFO - Return code: 0 18:52:16 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 18:52:16 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 18:52:16 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/c1ecd7d8a75b3006fb55d2aeb4b886e9ac48f01609276a1472013950211cab872fb8256ab312a99821e5fe06692900209d4bc9d762f04f1336fedbb0120eda3b", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/4e7a0bdccdd1f54dcd9dcde2bf40b1a5e787248b2f972605f05327641d4407bb980a066a8b891800939e403241d8acf4c797e079a342433748ceb2a82fc53731"} 18:52:16 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 18:52:16 INFO - Writing to file /builds/slave/test/properties/blobber_files 18:52:16 INFO - Contents: 18:52:16 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/c1ecd7d8a75b3006fb55d2aeb4b886e9ac48f01609276a1472013950211cab872fb8256ab312a99821e5fe06692900209d4bc9d762f04f1336fedbb0120eda3b", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/4e7a0bdccdd1f54dcd9dcde2bf40b1a5e787248b2f972605f05327641d4407bb980a066a8b891800939e403241d8acf4c797e079a342433748ceb2a82fc53731"} 18:52:16 INFO - Running post-run listener: copy_logs_to_upload_dir 18:52:16 INFO - Copying logs to upload dir... 18:52:16 INFO - mkdir: /builds/slave/test/build/upload/logs 18:52:16 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1261.703341 ========= master_lag: 0.08 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 1 secs) (at 2016-03-04 18:52:17.086247) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-03-04 18:52:17.094832) ========= 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.h6VlQgQM7n/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.bimA75Iz0Q/Listeners TMPDIR=/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/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/c1ecd7d8a75b3006fb55d2aeb4b886e9ac48f01609276a1472013950211cab872fb8256ab312a99821e5fe06692900209d4bc9d762f04f1336fedbb0120eda3b", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/4e7a0bdccdd1f54dcd9dcde2bf40b1a5e787248b2f972605f05327641d4407bb980a066a8b891800939e403241d8acf4c797e079a342433748ceb2a82fc53731"} build_url:https://queue.taskcluster.net/v1/task/QWDN3FC0Spa76pCf-e2VFA/artifacts/public/build/firefox-47.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011084 build_url: 'https://queue.taskcluster.net/v1/task/QWDN3FC0Spa76pCf-e2VFA/artifacts/public/build/firefox-47.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/c1ecd7d8a75b3006fb55d2aeb4b886e9ac48f01609276a1472013950211cab872fb8256ab312a99821e5fe06692900209d4bc9d762f04f1336fedbb0120eda3b", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/4e7a0bdccdd1f54dcd9dcde2bf40b1a5e787248b2f972605f05327641d4407bb980a066a8b891800939e403241d8acf4c797e079a342433748ceb2a82fc53731"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-03-04 18:52:17.132267) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-03-04 18:52:17.132657) ========= 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.h6VlQgQM7n/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.bimA75Iz0Q/Listeners TMPDIR=/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/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.005294 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-03-04 18:52:17.184316) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-03-04 18:52:17.186656) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-03-04 18:52:17.187080) ========= ========= Total master_lag: 21.99 =========