builder: mozilla-central_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0159 starttime: 1458129136.88 results: success (0) buildid: 20160316025546 builduid: e0d577403d934075896813836e9b0302 revision: d49937a2e5fe27e469781ea09c42aa2a142cf77d ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-03-16 04:52:16.875423) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-03-16 04:52:16.875850) ========= ========= Started set props: basedir (results: 0, elapsed: 0 secs) (at 2016-03-16 04:52:16.876138) ========= 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.daDqGGMSBK/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.yTZW1ht56M/Listeners TMPDIR=/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/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.005863 basedir: '/builds/slave/test' ========= master_lag: 0.02 ========= ========= Finished set props: basedir (results: 0, elapsed: 0 secs) (at 2016-03-16 04:52:16.899717) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-03-16 04:52:16.900125) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-03-16 04:52:16.916177) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-03-16 04:52:16.916603) ========= 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.daDqGGMSBK/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.yTZW1ht56M/Listeners TMPDIR=/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/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.005553 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-03-16 04:52:16.966976) ========= ========= Started set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2016-03-16 04:52:16.967262) ========= script_repo_url: https://hg.mozilla.org/build/mozharness ========= Finished set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2016-03-16 04:52:16.967602) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-03-16 04:52:16.967888) ========= 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.daDqGGMSBK/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.yTZW1ht56M/Listeners TMPDIR=/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/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-16 04:52:16-- 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% 19.2M=0.001s 2016-03-16 04:52:17 (19.2 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.156665 ========= master_lag: 0.04 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-03-16 04:52:17.168617) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-03-16 04:52:17.168945) ========= 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.daDqGGMSBK/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.yTZW1ht56M/Listeners TMPDIR=/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/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.019445 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-03-16 04:52:17.217606) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-03-16 04:52:17.217953) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev d49937a2e5fe27e469781ea09c42aa2a142cf77d --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev d49937a2e5fe27e469781ea09c42aa2a142cf77d --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.daDqGGMSBK/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.yTZW1ht56M/Listeners TMPDIR=/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/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-16 04:52:17,286 truncating revision to first 12 chars 2016-03-16 04:52:17,286 Setting DEBUG logging. 2016-03-16 04:52:17,287 attempt 1/10 2016-03-16 04:52:17,287 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/d49937a2e5fe?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-03-16 04:52:19,360 unpacking tar archive at: mozilla-central-d49937a2e5fe/testing/mozharness/ program finished with exit code 0 elapsedTime=2.320685 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-03-16 04:52:19.557819) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-03-16 04:52:19.558202) ========= echo d49937a2e5fe27e469781ea09c42aa2a142cf77d in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['echo', u'd49937a2e5fe27e469781ea09c42aa2a142cf77d'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.daDqGGMSBK/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.yTZW1ht56M/Listeners TMPDIR=/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/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 d49937a2e5fe27e469781ea09c42aa2a142cf77d program finished with exit code 0 elapsedTime=0.005267 script_repo_revision: 'd49937a2e5fe27e469781ea09c42aa2a142cf77d' ========= master_lag: 0.05 ========= ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-03-16 04:52:19.609158) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-03-16 04:52:19.609465) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-03-16 04:52:19.623326) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 3 secs) (at 2016-03-16 04:52:19.623639) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 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', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.daDqGGMSBK/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.yTZW1ht56M/Listeners TMPDIR=/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/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 04:52:19 INFO - MultiFileLogger online at 20160316 04:52:19 in /builds/slave/test 04:52:19 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 04:52:19 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 04:52:19 INFO - {'append_to_log': False, 04:52:19 INFO - 'base_work_dir': '/builds/slave/test', 04:52:19 INFO - 'blob_upload_branch': 'Firefox', 04:52:19 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 04:52:19 INFO - 'branch': 'Firefox', 04:52:19 INFO - 'buildbot_json_path': 'buildprops.json', 04:52:19 INFO - 'config_files': ('talos/mac_config.py',), 04:52:19 INFO - 'default_actions': ('clobber', 04:52:19 INFO - 'read-buildbot-config', 04:52:19 INFO - 'download-and-extract', 04:52:19 INFO - 'populate-webroot', 04:52:19 INFO - 'create-virtualenv', 04:52:19 INFO - 'install', 04:52:19 INFO - 'run-tests'), 04:52:19 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 04:52:19 INFO - 'download_minidump_stackwalk': True, 04:52:19 INFO - 'download_symbols': 'ondemand', 04:52:19 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 04:52:19 INFO - 'tooltool.py': '/tools/tooltool.py', 04:52:19 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 04:52:19 INFO - '/tools/misc-python/virtualenv.py')}, 04:52:19 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 04:52:19 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 04:52:19 INFO - 'installer_path': 'installer.exe', 04:52:19 INFO - 'log_level': 'info', 04:52:19 INFO - 'log_name': 'talos', 04:52:19 INFO - 'log_to_console': True, 04:52:19 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 04:52:19 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 04:52:19 INFO - 'opt_config_files': (), 04:52:19 INFO - 'pip_index': False, 04:52:19 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 04:52:19 INFO - 'cmd': ('bash', 04:52:19 INFO - '-c', 04:52:19 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 04:52:19 INFO - 'enabled': True, 04:52:19 INFO - 'halt_on_failure': False, 04:52:19 INFO - 'name': 'check_screen_resolution'},), 04:52:19 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 04:52:19 INFO - 'cmd': ('bash', 04:52:19 INFO - '-c', 04:52:19 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 04:52:19 INFO - 'enabled': True, 04:52:19 INFO - 'halt_on_failure': False, 04:52:19 INFO - 'name': 'check_screen_resolution'},), 04:52:19 INFO - 'run_cmd_checks_enabled': True, 04:52:19 INFO - 'sps_profile': False, 04:52:19 INFO - 'sps_profile_interval': 0, 04:52:19 INFO - 'suite': 'dromaeojs', 04:52:19 INFO - 'system_bits': '32', 04:52:19 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 04:52:19 INFO - 'title': 't-yosemite-r7-0159', 04:52:19 INFO - 'tooltool_cache': '/builds/tooltool_cache', 04:52:19 INFO - 'use_talos_json': True, 04:52:19 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 04:52:19 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 04:52:19 INFO - 'work_dir': 'build'} 04:52:19 INFO - ##### 04:52:19 INFO - ##### Running clobber step. 04:52:19 INFO - ##### 04:52:19 INFO - Running pre-action listener: _resource_record_pre_action 04:52:19 INFO - Running main action method: clobber 04:52:19 INFO - rmtree: /builds/slave/test/build 04:52:19 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 04:52:20 INFO - Running post-action listener: _resource_record_post_action 04:52:20 INFO - ##### 04:52:20 INFO - ##### Running read-buildbot-config step. 04:52:20 INFO - ##### 04:52:20 INFO - Running pre-action listener: _resource_record_pre_action 04:52:20 INFO - Running main action method: read_buildbot_config 04:52:20 INFO - Using buildbot properties: 04:52:20 INFO - { 04:52:20 INFO - "project": "", 04:52:20 INFO - "product": "firefox", 04:52:20 INFO - "script_repo_revision": "production", 04:52:20 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs", 04:52:20 INFO - "repository": "", 04:52:20 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs", 04:52:20 INFO - "buildid": "20160316025546", 04:52:20 INFO - "pgo_build": "False", 04:52:20 INFO - "basedir": "/builds/slave/test", 04:52:20 INFO - "buildnumber": 169, 04:52:20 INFO - "slavename": "t-yosemite-r7-0159", 04:52:20 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 04:52:20 INFO - "platform": "yosemite_r7", 04:52:20 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 04:52:20 INFO - "branch": "mozilla-central", 04:52:20 INFO - "repo_path": "mozilla-central", 04:52:20 INFO - "revision": "d49937a2e5fe27e469781ea09c42aa2a142cf77d", 04:52:20 INFO - "stage_platform": "macosx64", 04:52:20 INFO - "builduid": "e0d577403d934075896813836e9b0302", 04:52:20 INFO - "slavebuilddir": "test" 04:52:20 INFO - } 04:52:20 INFO - Found installer url https://queue.taskcluster.net/v1/task/L34geki1RnGm5jqLmpnxPg/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg. 04:52:20 INFO - Running post-action listener: _resource_record_post_action 04:52:20 INFO - ##### 04:52:20 INFO - ##### Running download-and-extract step. 04:52:20 INFO - ##### 04:52:20 INFO - Running pre-action listener: _resource_record_pre_action 04:52:20 INFO - Running main action method: download_and_extract 04:52:20 INFO - mkdir: /builds/slave/test/build/tests 04:52:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:52:20 INFO - https://queue.taskcluster.net/v1/task/L34geki1RnGm5jqLmpnxPg/artifacts/public/build/firefox-48.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 04:52:20 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/L34geki1RnGm5jqLmpnxPg/artifacts/public/build/firefox-48.0a1.en-US.mac.test_packages.json 04:52:20 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/L34geki1RnGm5jqLmpnxPg/artifacts/public/build/firefox-48.0a1.en-US.mac.test_packages.json 04:52:20 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/L34geki1RnGm5jqLmpnxPg/artifacts/public/build/firefox-48.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-48.0a1.en-US.mac.test_packages.json 04:52:20 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/L34geki1RnGm5jqLmpnxPg/artifacts/public/build/firefox-48.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-48.0a1.en-US.mac.test_packages.json'}, attempt #1 04:52:22 INFO - Downloaded 1199 bytes. 04:52:22 INFO - Reading from file /builds/slave/test/build/firefox-48.0a1.en-US.mac.test_packages.json 04:52:22 INFO - Using the following test package requirements: 04:52:22 INFO - {u'common': [u'firefox-48.0a1.en-US.mac.common.tests.zip'], 04:52:22 INFO - u'cppunittest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 04:52:22 INFO - u'firefox-48.0a1.en-US.mac.cppunittest.tests.zip'], 04:52:22 INFO - u'gtest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 04:52:22 INFO - u'firefox-48.0a1.en-US.mac.gtest.tests.zip'], 04:52:22 INFO - u'jittest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 04:52:22 INFO - u'jsshell-mac.zip'], 04:52:22 INFO - u'mochitest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 04:52:22 INFO - u'firefox-48.0a1.en-US.mac.mochitest.tests.zip'], 04:52:22 INFO - u'mozbase': [u'firefox-48.0a1.en-US.mac.common.tests.zip'], 04:52:22 INFO - u'reftest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 04:52:22 INFO - u'firefox-48.0a1.en-US.mac.reftest.tests.zip'], 04:52:22 INFO - u'talos': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 04:52:22 INFO - u'firefox-48.0a1.en-US.mac.talos.tests.zip'], 04:52:22 INFO - u'web-platform': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 04:52:22 INFO - u'firefox-48.0a1.en-US.mac.web-platform.tests.zip'], 04:52:22 INFO - u'xpcshell': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 04:52:22 INFO - u'firefox-48.0a1.en-US.mac.xpcshell.tests.zip']} 04:52:22 INFO - Downloading packages: [u'firefox-48.0a1.en-US.mac.common.tests.zip'] for test suite category: common 04:52:22 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')]} 04:52:22 INFO - https://queue.taskcluster.net/v1/task/L34geki1RnGm5jqLmpnxPg/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 04:52:22 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/L34geki1RnGm5jqLmpnxPg/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip 04:52:22 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/L34geki1RnGm5jqLmpnxPg/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip 04:52:22 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/L34geki1RnGm5jqLmpnxPg/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip 04:52:22 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/L34geki1RnGm5jqLmpnxPg/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip'}, attempt #1 04:52:23 INFO - Downloaded 17854430 bytes. 04:52:23 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 04:52:23 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 04:52:26 INFO - Return code: 0 04:52:26 INFO - Downloading packages: [u'firefox-48.0a1.en-US.mac.common.tests.zip', u'firefox-48.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 04:52:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:52:26 INFO - https://queue.taskcluster.net/v1/task/L34geki1RnGm5jqLmpnxPg/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 04:52:26 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/L34geki1RnGm5jqLmpnxPg/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip 04:52:26 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/L34geki1RnGm5jqLmpnxPg/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip 04:52:26 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/L34geki1RnGm5jqLmpnxPg/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip 04:52:26 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/L34geki1RnGm5jqLmpnxPg/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip'}, attempt #1 04:52:27 INFO - Downloaded 17854430 bytes. 04:52:27 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 04:52:27 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 04:52:31 INFO - Return code: 0 04:52:31 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')]} 04:52:31 INFO - https://queue.taskcluster.net/v1/task/L34geki1RnGm5jqLmpnxPg/artifacts/public/build/firefox-48.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 04:52:31 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/L34geki1RnGm5jqLmpnxPg/artifacts/public/build/firefox-48.0a1.en-US.mac.talos.tests.zip 04:52:31 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/L34geki1RnGm5jqLmpnxPg/artifacts/public/build/firefox-48.0a1.en-US.mac.talos.tests.zip 04:52:31 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/L34geki1RnGm5jqLmpnxPg/artifacts/public/build/firefox-48.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-48.0a1.en-US.mac.talos.tests.zip 04:52:31 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/L34geki1RnGm5jqLmpnxPg/artifacts/public/build/firefox-48.0a1.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.talos.tests.zip'}, attempt #1 04:52:32 INFO - Downloaded 10846462 bytes. 04:52:32 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 04:52:32 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a1.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 04:52:32 INFO - Return code: 0 04:52:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:52:32 INFO - https://queue.taskcluster.net/v1/task/L34geki1RnGm5jqLmpnxPg/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 04:52:32 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/L34geki1RnGm5jqLmpnxPg/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg 04:52:32 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/L34geki1RnGm5jqLmpnxPg/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg 04:52:32 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/L34geki1RnGm5jqLmpnxPg/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-48.0a1.en-US.mac.dmg 04:52:32 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/L34geki1RnGm5jqLmpnxPg/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-48.0a1.en-US.mac.dmg'}, attempt #1 04:52:35 INFO - Downloaded 111117636 bytes. 04:52:35 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/L34geki1RnGm5jqLmpnxPg/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg 04:52:35 INFO - mkdir: /builds/slave/test/properties 04:52:35 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 04:52:35 INFO - Writing to file /builds/slave/test/properties/build_url 04:52:35 INFO - Contents: 04:52:35 INFO - build_url:https://queue.taskcluster.net/v1/task/L34geki1RnGm5jqLmpnxPg/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg 04:52:36 INFO - Running post-action listener: _resource_record_post_action 04:52:36 INFO - Running post-action listener: set_extra_try_arguments 04:52:36 INFO - ##### 04:52:36 INFO - ##### Running populate-webroot step. 04:52:36 INFO - ##### 04:52:36 INFO - Running pre-action listener: _resource_record_pre_action 04:52:36 INFO - Running main action method: populate_webroot 04:52:36 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 04:52:36 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 04:52:36 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 04:52:36 INFO - '--tppagecycles', 04:52:36 INFO - '7'], 04:52:36 INFO - 'tests': ['tsvgm']}}, 04:52:36 INFO - 'suites': {'chromez': {'tests': ['tresize', 'tcanvasmark']}, 04:52:36 INFO - 'chromez-e10s': {'talos_options': ['--e10s'], 04:52:36 INFO - 'tests': ['tresize', 'tcanvasmark']}, 04:52:36 INFO - 'dromaeojs': {'tests': ['dromaeo_css', 'kraken']}, 04:52:36 INFO - 'dromaeojs-e10s': {'talos_options': ['--e10s'], 04:52:36 INFO - 'tests': ['dromaeo_css', 'kraken']}, 04:52:36 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:52:36 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:52:36 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:52:36 INFO - 'talos_options': ['--e10s'], 04:52:36 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:52:36 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:52:36 INFO - 'tests': ['damp', 'tps']}, 04:52:36 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:52:36 INFO - 'talos_options': ['--e10s'], 04:52:36 INFO - 'tests': ['damp', 'tps']}, 04:52:36 INFO - 'g3': {'tests': ['dromaeo_dom']}, 04:52:36 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 04:52:36 INFO - 'other': {'tests': ['a11yr', 04:52:36 INFO - 'ts_paint', 04:52:36 INFO - 'tpaint', 04:52:36 INFO - 'sessionrestore', 04:52:36 INFO - 'sessionrestore_no_auto_restore', 04:52:36 INFO - 'tabpaint']}, 04:52:36 INFO - 'other-e10s': {'talos_options': ['--e10s'], 04:52:36 INFO - 'tests': ['a11yr', 04:52:36 INFO - 'ts_paint', 04:52:36 INFO - 'tpaint', 04:52:36 INFO - 'sessionrestore', 04:52:36 INFO - 'sessionrestore_no_auto_restore', 04:52:36 INFO - 'tabpaint']}, 04:52:36 INFO - 'other-e10s_l64': {'talos_options': ['--e10s'], 04:52:36 INFO - 'tests': ['a11yr', 04:52:36 INFO - 'ts_paint', 04:52:36 INFO - 'tpaint', 04:52:36 INFO - 'sessionrestore', 04:52:36 INFO - 'sessionrestore_no_auto_restore', 04:52:36 INFO - 'tabpaint']}, 04:52:36 INFO - 'other-e10s_nol64': {'talos_options': ['--e10s'], 04:52:36 INFO - 'tests': ['a11yr', 04:52:36 INFO - 'ts_paint', 04:52:36 INFO - 'tpaint', 04:52:36 INFO - 'sessionrestore', 04:52:36 INFO - 'sessionrestore_no_auto_restore', 04:52:36 INFO - 'tabpaint']}, 04:52:36 INFO - 'other_l64': {'tests': ['a11yr', 04:52:36 INFO - 'ts_paint', 04:52:36 INFO - 'tpaint', 04:52:36 INFO - 'sessionrestore', 04:52:36 INFO - 'sessionrestore_no_auto_restore', 04:52:36 INFO - 'tabpaint']}, 04:52:36 INFO - 'other_nol64': {'tests': ['a11yr', 04:52:36 INFO - 'ts_paint', 04:52:36 INFO - 'tpaint', 04:52:36 INFO - 'sessionrestore', 04:52:36 INFO - 'sessionrestore_no_auto_restore', 04:52:36 INFO - 'tabpaint']}, 04:52:36 INFO - 'svgr': {'tests': ['tsvgx', 04:52:36 INFO - 'tsvgr_opacity', 04:52:36 INFO - 'tart', 04:52:36 INFO - 'tscrollx', 04:52:36 INFO - 'cart']}, 04:52:36 INFO - 'svgr-e10s': {'talos_options': ['--e10s'], 04:52:36 INFO - 'tests': ['tsvgx', 04:52:36 INFO - 'tsvgr_opacity', 04:52:36 INFO - 'tart', 04:52:36 INFO - 'tscrollx', 04:52:36 INFO - 'cart']}, 04:52:36 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:52:36 INFO - 'tests': ['tp5o']}, 04:52:36 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:52:36 INFO - 'talos_options': ['--e10s'], 04:52:36 INFO - 'tests': ['tp5o']}, 04:52:36 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:52:36 INFO - 'talos_options': ['--xperf_path', 04:52:36 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:52:36 INFO - 'tests': ['tp5n']}, 04:52:36 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:52:36 INFO - 'talos_options': ['--e10s', 04:52:36 INFO - '--xperf_path', 04:52:36 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:52:36 INFO - 'tests': ['tp5n']}}, 04:52:36 INFO - 'talos.zip': {'path': '', 04:52:36 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 04:52:36 INFO - Running post-action listener: _resource_record_post_action 04:52:36 INFO - ##### 04:52:36 INFO - ##### Running create-virtualenv step. 04:52:36 INFO - ##### 04:52:36 INFO - Running pre-action listener: _resource_record_pre_action 04:52:36 INFO - Running main action method: create_virtualenv 04:52:36 INFO - Creating virtualenv /builds/slave/test/build/venv 04:52:36 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 04:52:36 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 04:52:36 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 04:52:36 INFO - Using real prefix '/tools/python27' 04:52:36 INFO - New python executable in /builds/slave/test/build/venv/bin/python 04:52:37 INFO - Installing distribute.............................................................................................................................................................................................done. 04:52:40 INFO - Installing pip.................done. 04:52:40 INFO - Return code: 0 04:52:40 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 04:52:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:52:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:52:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:52:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:52:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:52:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:52:40 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10319ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1031a64c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbf23597160>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103042d30>, '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 0x102e66db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103039b20>, '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.daDqGGMSBK/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.yTZW1ht56M/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/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 04:52:40 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 04:52:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 04:52:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.daDqGGMSBK/Render', 04:52:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:52:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:52:40 INFO - 'HOME': '/Users/cltbld', 04:52:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:52:40 INFO - 'LOGNAME': 'cltbld', 04:52:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:52:40 INFO - 'MOZ_NO_REMOTE': '1', 04:52:40 INFO - 'NO_EM_RESTART': '1', 04:52:40 INFO - 'PAGER': '/bin/cat', 04:52:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:52:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:52:40 INFO - 'PWD': '/builds/slave/test', 04:52:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:52:40 INFO - 'SHELL': '/bin/bash', 04:52:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.yTZW1ht56M/Listeners', 04:52:40 INFO - 'TMPDIR': '/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/', 04:52:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:52:40 INFO - 'USER': 'cltbld', 04:52:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:52:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:52:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:52:40 INFO - 'XPC_FLAGS': '0x0', 04:52:40 INFO - 'XPC_SERVICE_NAME': '0', 04:52:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:52:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:52:40 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 04:52:40 INFO - Cleaning up... 04:52:40 INFO - Return code: 0 04:52:40 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 04:52:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:52:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:52:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:52:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:52:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:52:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:52:40 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10319ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1031a64c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbf23597160>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103042d30>, '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 0x102e66db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103039b20>, '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.daDqGGMSBK/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.yTZW1ht56M/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/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 04:52:40 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1'] in /builds/slave/test/build 04:52:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=0.7.1 04:52:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.daDqGGMSBK/Render', 04:52:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:52:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:52:40 INFO - 'HOME': '/Users/cltbld', 04:52:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:52:40 INFO - 'LOGNAME': 'cltbld', 04:52:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:52:40 INFO - 'MOZ_NO_REMOTE': '1', 04:52:40 INFO - 'NO_EM_RESTART': '1', 04:52:40 INFO - 'PAGER': '/bin/cat', 04:52:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:52:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:52:40 INFO - 'PWD': '/builds/slave/test', 04:52:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:52:40 INFO - 'SHELL': '/bin/bash', 04:52:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.yTZW1ht56M/Listeners', 04:52:40 INFO - 'TMPDIR': '/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/', 04:52:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:52:40 INFO - 'USER': 'cltbld', 04:52:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:52:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:52:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:52:40 INFO - 'XPC_FLAGS': '0x0', 04:52:40 INFO - 'XPC_SERVICE_NAME': '0', 04:52:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:52:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:52:40 INFO - Downloading/unpacking psutil>=0.7.1 04:52:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:52:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:52:40 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:52:40 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:52:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:52:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:52:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 04:52:43 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:52:43 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:52:43 INFO - Installing collected packages: psutil 04:52:43 INFO - Running setup.py install for psutil 04:52:43 INFO - building 'psutil._psutil_osx' extension 04:52:43 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 04:52:43 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 04:52:44 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 04:52:44 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 04:52:44 INFO - building 'psutil._psutil_posix' extension 04:52:44 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 04:52:44 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 04:52:44 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 04:52:44 INFO - ^ 04:52:44 INFO - 1 warning generated. 04:52:44 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 04:52:44 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:52:44 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:52:44 INFO - Successfully installed psutil 04:52:44 INFO - Cleaning up... 04:52:44 INFO - Return code: 0 04:52:44 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 04:52:44 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')]} 04:52:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:52:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:52:44 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')]} 04:52:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:52:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:52:44 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 0x10319ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1031a64c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbf23597160>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103042d30>, '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 0x102e66db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103039b20>, '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.daDqGGMSBK/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.yTZW1ht56M/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/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 04:52:44 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 04:52:44 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 04:52:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.daDqGGMSBK/Render', 04:52:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:52:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:52:44 INFO - 'HOME': '/Users/cltbld', 04:52:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:52:44 INFO - 'LOGNAME': 'cltbld', 04:52:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:52:44 INFO - 'MOZ_NO_REMOTE': '1', 04:52:44 INFO - 'NO_EM_RESTART': '1', 04:52:44 INFO - 'PAGER': '/bin/cat', 04:52:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:52:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:52:44 INFO - 'PWD': '/builds/slave/test', 04:52:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:52:44 INFO - 'SHELL': '/bin/bash', 04:52:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.yTZW1ht56M/Listeners', 04:52:44 INFO - 'TMPDIR': '/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/', 04:52:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:52:44 INFO - 'USER': 'cltbld', 04:52:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:52:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:52:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:52:44 INFO - 'XPC_FLAGS': '0x0', 04:52:44 INFO - 'XPC_SERVICE_NAME': '0', 04:52:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:52:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:52:44 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 04:52: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 04:52: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 04:52: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 04:52: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 04:52: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 04:52: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 04:52:47 INFO - Downloading mozsystemmonitor-0.0.tar.gz 04:52:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 04:52:47 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 04:52:47 INFO - Installing collected packages: mozsystemmonitor 04:52:47 INFO - Running setup.py install for mozsystemmonitor 04:52:47 INFO - Successfully installed mozsystemmonitor 04:52:47 INFO - Cleaning up... 04:52:47 INFO - Return code: 0 04:52:47 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 04:52:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:52:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:52:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:52:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:52:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:52:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:52:47 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10319ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1031a64c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbf23597160>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103042d30>, '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 0x102e66db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103039b20>, '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.daDqGGMSBK/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.yTZW1ht56M/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/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 04:52:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 04:52:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 04:52:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.daDqGGMSBK/Render', 04:52:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:52:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:52:47 INFO - 'HOME': '/Users/cltbld', 04:52:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:52:47 INFO - 'LOGNAME': 'cltbld', 04:52:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:52:47 INFO - 'MOZ_NO_REMOTE': '1', 04:52:47 INFO - 'NO_EM_RESTART': '1', 04:52:47 INFO - 'PAGER': '/bin/cat', 04:52:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:52:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:52:47 INFO - 'PWD': '/builds/slave/test', 04:52:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:52:47 INFO - 'SHELL': '/bin/bash', 04:52:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.yTZW1ht56M/Listeners', 04:52:47 INFO - 'TMPDIR': '/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/', 04:52:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:52:47 INFO - 'USER': 'cltbld', 04:52:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:52:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:52:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:52:47 INFO - 'XPC_FLAGS': '0x0', 04:52:47 INFO - 'XPC_SERVICE_NAME': '0', 04:52:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:52:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:52:47 INFO - Downloading/unpacking blobuploader==1.2.4 04:52:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:52:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:52:47 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:52:47 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:52:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:52:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:52:49 INFO - Downloading blobuploader-1.2.4.tar.gz 04:52:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 04:52:50 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 04:52:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:52:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:52:50 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:52:50 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:52:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:52:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:52:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 04:52:50 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 04:52:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:52:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:52:50 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:52:50 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:52:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:52:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:52:51 INFO - Downloading docopt-0.6.1.tar.gz 04:52:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 04:52:51 INFO - Installing collected packages: blobuploader, requests, docopt 04:52:51 INFO - Running setup.py install for blobuploader 04:52:51 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 04:52:51 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 04:52:51 INFO - Running setup.py install for requests 04:52:51 INFO - Running setup.py install for docopt 04:52:52 INFO - Successfully installed blobuploader requests docopt 04:52:52 INFO - Cleaning up... 04:52:52 INFO - Return code: 0 04:52:52 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:52:52 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')]} 04:52:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:52:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:52:52 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')]} 04:52:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:52:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:52:52 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 0x10319ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1031a64c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbf23597160>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103042d30>, '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 0x102e66db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103039b20>, '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.daDqGGMSBK/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.yTZW1ht56M/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/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 04:52:52 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 04:52:52 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 04:52:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.daDqGGMSBK/Render', 04:52:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:52:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:52:52 INFO - 'HOME': '/Users/cltbld', 04:52:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:52:52 INFO - 'LOGNAME': 'cltbld', 04:52:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:52:52 INFO - 'MOZ_NO_REMOTE': '1', 04:52:52 INFO - 'NO_EM_RESTART': '1', 04:52:52 INFO - 'PAGER': '/bin/cat', 04:52:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:52:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:52:52 INFO - 'PWD': '/builds/slave/test', 04:52:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:52:52 INFO - 'SHELL': '/bin/bash', 04:52:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.yTZW1ht56M/Listeners', 04:52:52 INFO - 'TMPDIR': '/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/', 04:52:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:52:52 INFO - 'USER': 'cltbld', 04:52:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:52:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:52:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:52:52 INFO - 'XPC_FLAGS': '0x0', 04:52:52 INFO - 'XPC_SERVICE_NAME': '0', 04:52:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:52:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:52:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:52:52 INFO - Running setup.py (path:/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/pip-c6kfxj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:52:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:52:52 INFO - Running setup.py (path:/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/pip-A64Rzq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:52:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:52:52 INFO - Running setup.py (path:/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/pip-gBzegF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:52:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:52:52 INFO - Running setup.py (path:/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/pip-j1rqRi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:52:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:52:52 INFO - Running setup.py (path:/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/pip-3Hr44l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:52:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:52:52 INFO - Running setup.py (path:/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/pip-Q436Y3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:52:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:52:53 INFO - Running setup.py (path:/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/pip-9jjZ1F-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:52:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:52:53 INFO - Running setup.py (path:/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/pip-iztXtr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:52:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:52:53 INFO - Running setup.py (path:/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/pip-fiIxWf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:52:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:52:53 INFO - Running setup.py (path:/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/pip-ynJTb7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:52:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:52:53 INFO - Running setup.py (path:/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/pip-Y7bLr0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:52:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:52:53 INFO - Running setup.py (path:/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/pip-J2Y3j6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:52:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:52:53 INFO - Running setup.py (path:/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/pip-V_c7Nl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:52:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:52:53 INFO - Running setup.py (path:/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/pip-Nm2vHl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:52:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:52:53 INFO - Running setup.py (path:/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/pip-P__RDG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:52:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:52:53 INFO - Running setup.py (path:/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/pip-Jw8sft-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:52:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:52:54 INFO - Running setup.py (path:/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/pip-Bj5mxL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:52:54 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 04:52:54 INFO - Running setup.py install for manifestparser 04:52:54 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 04:52:54 INFO - Running setup.py install for mozcrash 04:52:54 INFO - Running setup.py install for mozdebug 04:52:54 INFO - Running setup.py install for mozdevice 04:52:54 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 04:52:54 INFO - Installing dm script to /builds/slave/test/build/venv/bin 04:52:54 INFO - Running setup.py install for mozfile 04:52:54 INFO - Running setup.py install for mozhttpd 04:52:55 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 04:52:55 INFO - Running setup.py install for mozinfo 04:52:55 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 04:52:55 INFO - Running setup.py install for mozInstall 04:52:55 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 04:52:55 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 04:52:55 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 04:52:55 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 04:52:55 INFO - Running setup.py install for mozleak 04:52:55 INFO - Running setup.py install for mozlog 04:52:55 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 04:52:55 INFO - Running setup.py install for moznetwork 04:52:55 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 04:52:55 INFO - Running setup.py install for mozprocess 04:52:55 INFO - Running setup.py install for mozprofile 04:52:56 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 04:52:56 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 04:52:56 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 04:52:56 INFO - Running setup.py install for mozrunner 04:52:56 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 04:52:56 INFO - Running setup.py install for mozscreenshot 04:52:56 INFO - Running setup.py install for moztest 04:52:56 INFO - Running setup.py install for mozversion 04:52:56 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 04:52:56 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 04:52:56 INFO - Cleaning up... 04:52:56 INFO - Return code: 0 04:52:56 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:52: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')]} 04:52:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:52:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:52: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')]} 04:52:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:52:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:52:56 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 0x10319ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1031a64c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbf23597160>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103042d30>, '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 0x102e66db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103039b20>, '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.daDqGGMSBK/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.yTZW1ht56M/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/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 04:52:56 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 04:52:56 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 04:52:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.daDqGGMSBK/Render', 04:52:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:52:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:52:56 INFO - 'HOME': '/Users/cltbld', 04:52:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:52:56 INFO - 'LOGNAME': 'cltbld', 04:52:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:52:56 INFO - 'MOZ_NO_REMOTE': '1', 04:52:56 INFO - 'NO_EM_RESTART': '1', 04:52:56 INFO - 'PAGER': '/bin/cat', 04:52:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:52:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:52:56 INFO - 'PWD': '/builds/slave/test', 04:52:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:52:56 INFO - 'SHELL': '/bin/bash', 04:52:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.yTZW1ht56M/Listeners', 04:52:56 INFO - 'TMPDIR': '/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/', 04:52:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:52:56 INFO - 'USER': 'cltbld', 04:52:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:52:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:52:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:52:56 INFO - 'XPC_FLAGS': '0x0', 04:52:56 INFO - 'XPC_SERVICE_NAME': '0', 04:52:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:52:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:52:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:52:56 INFO - Running setup.py (path:/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/pip-C1E7ig-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:52:57 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)) 04:52:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:52:57 INFO - Running setup.py (path:/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/pip-BVACaZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:52:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 04:52:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:52:57 INFO - Running setup.py (path:/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/pip-3tyzTO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:52:57 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)) 04:52:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:52:57 INFO - Running setup.py (path:/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/pip-oe4Enz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:52:57 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)) 04:52:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:52:57 INFO - Running setup.py (path:/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/pip-XRhLQ_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:52:57 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)) 04:52:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:52:57 INFO - Running setup.py (path:/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/pip-76IlCL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:52:57 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)) 04:52:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:52:57 INFO - Running setup.py (path:/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/pip-fodr9P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:52:57 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)) 04:52:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:52:57 INFO - Running setup.py (path:/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/pip-YN_YnB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:52:57 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)) 04:52:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:52:57 INFO - Running setup.py (path:/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/pip-mIEn4I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:52:57 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)) 04:52:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:52:57 INFO - Running setup.py (path:/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/pip-hcpN1a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:52:58 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)) 04:52:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:52:58 INFO - Running setup.py (path:/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/pip-VWqWkW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:52:58 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)) 04:52:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:52:58 INFO - Running setup.py (path:/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/pip-GVL0Db-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:52:58 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)) 04:52:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:52:58 INFO - Running setup.py (path:/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/pip-OkAglN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:52:58 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)) 04:52:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:52:58 INFO - Running setup.py (path:/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/pip-w0m0c9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:52:58 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)) 04:52:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:52:58 INFO - Running setup.py (path:/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/pip-duH6xQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:52:58 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)) 04:52:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:52:58 INFO - Running setup.py (path:/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/pip-EGUwaX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:52:58 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)) 04:52:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:52:58 INFO - Running setup.py (path:/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/pip-4NU2V3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:52:58 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)) 04:52:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 04:52:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 04:52:58 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)) 04:52:58 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)) 04:52:58 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)) 04:52:58 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 04:52:58 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 04:52:58 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 04:52:58 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 04:52:58 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 04:52:58 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 04:52:58 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 04:53:01 INFO - Downloading blessings-1.6.tar.gz 04:53:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 04:53:01 INFO - Installing collected packages: blessings 04:53:01 INFO - Running setup.py install for blessings 04:53:01 INFO - Successfully installed blessings 04:53:01 INFO - Cleaning up... 04:53:01 INFO - Return code: 0 04:53:01 INFO - Done creating virtualenv /builds/slave/test/build/venv. 04:53:01 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:53:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:53:01 INFO - Reading from file tmpfile_stdout 04:53:01 INFO - Current package versions: 04:53:01 INFO - blessings == 1.6 04:53:01 INFO - blobuploader == 1.2.4 04:53:01 INFO - docopt == 0.6.1 04:53:01 INFO - manifestparser == 1.1 04:53:01 INFO - mozInstall == 1.12 04:53:01 INFO - mozcrash == 0.17 04:53:01 INFO - mozdebug == 0.1 04:53:01 INFO - mozdevice == 0.48 04:53:01 INFO - mozfile == 1.2 04:53:01 INFO - mozhttpd == 0.7 04:53:01 INFO - mozinfo == 0.9 04:53:01 INFO - mozleak == 0.1 04:53:01 INFO - mozlog == 3.1 04:53:01 INFO - moznetwork == 0.27 04:53:01 INFO - mozprocess == 0.22 04:53:01 INFO - mozprofile == 0.28 04:53:01 INFO - mozrunner == 6.11 04:53:01 INFO - mozscreenshot == 0.1 04:53:01 INFO - mozsystemmonitor == 0.0 04:53:01 INFO - moztest == 0.7 04:53:01 INFO - mozversion == 1.4 04:53:01 INFO - psutil == 3.1.1 04:53:01 INFO - requests == 1.2.3 04:53:01 INFO - wsgiref == 0.1.2 04:53:01 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:53:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:53:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:53:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:53:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:53:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:53:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:53:01 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 0x10319ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1031a64c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbf23597160>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103042d30>, '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 0x102e66db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103039b20>, '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.daDqGGMSBK/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.yTZW1ht56M/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/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 04:53:01 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 04:53:01 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 04:53:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.daDqGGMSBK/Render', 04:53:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:53:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:53:01 INFO - 'HOME': '/Users/cltbld', 04:53:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:53:01 INFO - 'LOGNAME': 'cltbld', 04:53:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:53:01 INFO - 'MOZ_NO_REMOTE': '1', 04:53:01 INFO - 'NO_EM_RESTART': '1', 04:53:01 INFO - 'PAGER': '/bin/cat', 04:53:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:53:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:53:01 INFO - 'PWD': '/builds/slave/test', 04:53:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:53:01 INFO - 'SHELL': '/bin/bash', 04:53:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.yTZW1ht56M/Listeners', 04:53:01 INFO - 'TMPDIR': '/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/', 04:53:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:53:01 INFO - 'USER': 'cltbld', 04:53:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:53:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:53:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:53:01 INFO - 'XPC_FLAGS': '0x0', 04:53:01 INFO - 'XPC_SERVICE_NAME': '0', 04:53:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:53:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:53:02 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)) 04:53:02 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)) 04:53:02 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)) 04:53:02 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)) 04:53:02 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)) 04:53:02 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)) 04:53:02 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)) 04:53:02 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)) 04:53:02 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)) 04:53:02 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)) 04:53:02 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)) 04:53:02 INFO - Cleaning up... 04:53:02 INFO - Return code: 0 04:53:02 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 04:53:02 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')]} 04:53:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:53:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:53:02 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')]} 04:53:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:53:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:53:02 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 0x10319ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1031a64c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbf23597160>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103042d30>, '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 0x102e66db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103039b20>, '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.daDqGGMSBK/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.yTZW1ht56M/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/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 04:53:02 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 04:53:02 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 04:53:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.daDqGGMSBK/Render', 04:53:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:53:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:53:02 INFO - 'HOME': '/Users/cltbld', 04:53:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:53:02 INFO - 'LOGNAME': 'cltbld', 04:53:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:53:02 INFO - 'MOZ_NO_REMOTE': '1', 04:53:02 INFO - 'NO_EM_RESTART': '1', 04:53:02 INFO - 'PAGER': '/bin/cat', 04:53:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:53:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:53:02 INFO - 'PWD': '/builds/slave/test', 04:53:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:53:02 INFO - 'SHELL': '/bin/bash', 04:53:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.yTZW1ht56M/Listeners', 04:53:02 INFO - 'TMPDIR': '/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/', 04:53:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:53:02 INFO - 'USER': 'cltbld', 04:53:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:53:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:53:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:53:02 INFO - 'XPC_FLAGS': '0x0', 04:53:02 INFO - 'XPC_SERVICE_NAME': '0', 04:53:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:53:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:53:02 INFO - Downloading/unpacking jsonschema 04:53: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 04:53: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 04:53: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 04:53: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 04:53: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 04:53: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 04:53:04 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 04:53:04 INFO - Downloading/unpacking functools32 (from jsonschema) 04:53:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:53:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:53:04 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:53:04 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:53:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:53:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:53:05 INFO - Downloading functools32-3.2.3-2.tar.gz 04:53:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 04:53:05 INFO - warning: no files found matching '*.txt' 04:53:05 INFO - no previously-included directories found matching 'build' 04:53:05 INFO - no previously-included directories found matching 'dist' 04:53:05 INFO - no previously-included directories found matching '.git*' 04:53:05 INFO - Installing collected packages: jsonschema, functools32 04:53:05 INFO - Running setup.py install for functools32 04:53:05 INFO - warning: no files found matching '*.txt' 04:53:05 INFO - no previously-included directories found matching 'build' 04:53:05 INFO - no previously-included directories found matching 'dist' 04:53:05 INFO - no previously-included directories found matching '.git*' 04:53:05 INFO - Successfully installed jsonschema functools32 04:53:05 INFO - Cleaning up... 04:53:05 INFO - Return code: 0 04:53:05 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 04:53:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:53:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:53:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:53:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:53:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:53:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:53:05 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 0x10319ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1031a64c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbf23597160>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103042d30>, '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 0x102e66db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103039b20>, '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.daDqGGMSBK/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.yTZW1ht56M/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/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 04:53:05 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 04:53:05 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 04:53:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.daDqGGMSBK/Render', 04:53:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:53:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:53:05 INFO - 'HOME': '/Users/cltbld', 04:53:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:53:05 INFO - 'LOGNAME': 'cltbld', 04:53:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:53:05 INFO - 'MOZ_NO_REMOTE': '1', 04:53:05 INFO - 'NO_EM_RESTART': '1', 04:53:05 INFO - 'PAGER': '/bin/cat', 04:53:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:53:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:53:05 INFO - 'PWD': '/builds/slave/test', 04:53:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:53:05 INFO - 'SHELL': '/bin/bash', 04:53:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.yTZW1ht56M/Listeners', 04:53:05 INFO - 'TMPDIR': '/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/', 04:53:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:53:05 INFO - 'USER': 'cltbld', 04:53:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:53:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:53:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:53:05 INFO - 'XPC_FLAGS': '0x0', 04:53:05 INFO - 'XPC_SERVICE_NAME': '0', 04:53:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:53:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:53:05 INFO - Downloading/unpacking flake8 04:53:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:53:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:53:05 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:53:05 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:53:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:53:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:53:08 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 04:53:08 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 04:53:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:53:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:53:08 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:53:08 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:53:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:53:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:53:08 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 04:53:08 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 04:53:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:53:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:53:08 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:53:08 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:53:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:53:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:53:08 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 04:53:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:53:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:53:08 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:53:08 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:53:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:53:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:53:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 04:53:09 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 04:53:09 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 04:53:09 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 04:53:09 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 04:53:09 INFO - no previously-included directories found matching 'docs/_build' 04:53:09 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 04:53:09 INFO - Running setup.py install for pep8 04:53:09 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 04:53:09 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 04:53:09 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 04:53:09 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 04:53:09 INFO - no previously-included directories found matching 'docs/_build' 04:53:09 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 04:53:09 INFO - Successfully installed flake8 mccabe pyflakes pep8 04:53:09 INFO - Cleaning up... 04:53:09 INFO - Return code: 0 04:53:09 INFO - Running post-action listener: _resource_record_post_action 04:53:09 INFO - Running post-action listener: _start_resource_monitoring 04:53:09 INFO - Starting resource monitoring. 04:53:09 INFO - ##### 04:53:09 INFO - ##### Running install step. 04:53:09 INFO - ##### 04:53:09 INFO - Running pre-action listener: _resource_record_pre_action 04:53:09 INFO - Running main action method: install 04:53:09 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:53:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:53:09 INFO - Reading from file tmpfile_stdout 04:53:09 INFO - Detecting whether we're running mozinstall >=1.0... 04:53:09 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 04:53:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 04:53:10 INFO - Reading from file tmpfile_stdout 04:53:10 INFO - Output received: 04:53:10 INFO - Usage: mozinstall [options] installer 04:53:10 INFO - Options: 04:53:10 INFO - -h, --help show this help message and exit 04:53:10 INFO - -d DEST, --destination=DEST 04:53:10 INFO - Directory to install application into. [default: 04:53:10 INFO - "/builds/slave/test"] 04:53:10 INFO - --app=APP Application being installed. [default: firefox] 04:53:10 INFO - mkdir: /builds/slave/test/build/application 04:53:10 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-48.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 04:53:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-48.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 04:53:44 INFO - Reading from file tmpfile_stdout 04:53:44 INFO - Output received: 04:53:44 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 04:53:44 INFO - Running post-action listener: _resource_record_post_action 04:53:44 INFO - ##### 04:53:44 INFO - ##### Running run-tests step. 04:53:44 INFO - ##### 04:53:44 INFO - Running pre-action listener: _resource_record_pre_action 04:53:44 INFO - Running main action method: run_tests 04:53:44 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 04:53:44 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 04:53:44 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 04:53:44 INFO - 2016-03-16 04:53:44.056 screenresolution[1928:10908] starting screenresolution argv=screenresolution get 04:53:44 INFO - 2016-03-16 04:53:44.059 screenresolution[1928:10908] Display 0: 1600x1200x32@60 04:53:44 INFO - 2016-03-16 04:53:44.065 screenresolution[1929:10910] starting screenresolution argv=screenresolution list 04:53:44 INFO - Available Modes on Display 0 04:53:44 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 04:53:44 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 04:53:44 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 04:53:44 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 04:53:44 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 04:53:44 INFO - Intel Iris: 04:53:44 INFO - Chipset Model: Intel Iris 04:53:44 INFO - Type: GPU 04:53:44 INFO - Bus: Built-In 04:53:44 INFO - VRAM (Dynamic, Max): 1536 MB 04:53:44 INFO - Vendor: Intel (0x8086) 04:53:44 INFO - Device ID: 0x0a2e 04:53:44 INFO - Revision ID: 0x0009 04:53:44 INFO - Displays: 04:53:44 INFO - Display: 04:53:44 INFO - Resolution: 1600 x 1200 @ 60 Hz 04:53:44 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 04:53:44 INFO - Display Serial Number: 1600x1200 60 04:53:44 INFO - Main Display: Yes 04:53:44 INFO - Mirror: Off 04:53:44 INFO - Online: Yes 04:53:44 INFO - Rotation: Supported 04:53:44 INFO - Return code: 0 04:53:44 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 04:53:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 04:53:44 INFO - Python 2.7.3 04:53:44 INFO - Return code: 0 04:53:44 INFO - grabbing minidump binary from tooltool 04:53:44 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')]} 04:53:44 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 0x103042d30>, '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 0x102e66db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103039b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 04:53:44 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 04:53:44 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 04:53:44 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 04:53:44 INFO - Return code: 0 04:53:44 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 04:53:44 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 04:53:44 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 04:53:44 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 04:53:44 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 04:53:44 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 04:53:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 04:53:45 INFO - Return code: 0 04:53:45 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/L34geki1RnGm5jqLmpnxPg/artifacts/public/build/firefox-48.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0159', '--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 04:53:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/L34geki1RnGm5jqLmpnxPg/artifacts/public/build/firefox-48.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0159 --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 04:53:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.daDqGGMSBK/Render', 04:53:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:53:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:53:45 INFO - 'HOME': '/Users/cltbld', 04:53:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:53:45 INFO - 'LOGNAME': 'cltbld', 04:53:45 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 04:53:45 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 04:53:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:53:45 INFO - 'MOZ_NO_REMOTE': '1', 04:53:45 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 04:53:45 INFO - 'NO_EM_RESTART': '1', 04:53:45 INFO - 'PAGER': '/bin/cat', 04:53:45 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:53:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:53:45 INFO - 'PWD': '/builds/slave/test', 04:53:45 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 04:53:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:53:45 INFO - 'SHELL': '/bin/bash', 04:53:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.yTZW1ht56M/Listeners', 04:53:45 INFO - 'TMPDIR': '/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/', 04:53:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:53:45 INFO - 'USER': 'cltbld', 04:53:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:53:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:53:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:53:45 INFO - 'XPC_FLAGS': '0x0', 04:53:45 INFO - 'XPC_SERVICE_NAME': '0', 04:53:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:53:45 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/L34geki1RnGm5jqLmpnxPg/artifacts/public/build/firefox-48.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0159', '--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 04:53:45 INFO - mozversion application_buildid: 20160316025546 04:53:45 INFO - mozversion application_changeset: d49937a2e5fe27e469781ea09c42aa2a142cf77d 04:53:45 INFO - mozversion application_display_name: Nightly 04:53:45 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 04:53:45 INFO - mozversion application_name: Firefox 04:53:45 INFO - mozversion application_remotingname: firefox 04:53:45 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 04:53:45 INFO - mozversion application_vendor: Mozilla 04:53:45 INFO - mozversion application_version: 48.0a1 04:53:45 INFO - mozversion platform_buildid: 20160316025546 04:53:45 INFO - mozversion platform_changeset: d49937a2e5fe27e469781ea09c42aa2a142cf77d 04:53:45 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 04:53:45 INFO - mozversion platform_version: 48.0a1 04:53:45 INFO - using testdate: 1458129225 04:53:45 INFO - actual date: 1458129225 04:53:45 INFO - starting webserver on 'localhost:51253' 04:53:45 INFO - SUITE-START | Running 2 tests 04:53:45 INFO - TEST-START | dromaeo_css 04:53:45 INFO - operating with platform_type : mac_ 04:53:45 INFO - Initialising browser for dromaeo_css test... 04:53:45 INFO - TEST-INFO | started process 1947 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/tmp2SdBYg/profile http://localhost:51253/getInfo.html) 04:53:48 INFO - PROCESS | 1947 | __metrics Screen width/height:1600/1200 04:53:48 INFO - PROCESS | 1947 | colorDepth:24 04:53:48 INFO - PROCESS | 1947 | Browser inner width/height: 1024/689 04:53:48 INFO - PROCESS | 1947 | __metrics 04:53:48 INFO - PROCESS | 1947 | 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" 04:53:48 ERROR - PROCESS | 1947 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 04:53:48 INFO - TEST-INFO | 1947: exit 0 04:53:48 INFO - Browser initialized. 04:53:48 INFO - Running cycle 1/1 for dromaeo_css test... 04:53:48 INFO - TEST-INFO | started process 1950 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/tmp2SdBYg/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:54:00 INFO - PROCESS | 1950 | RSS: Main: 242139136 04:54:00 INFO - PROCESS | 1950 | 04:54:52 INFO - PROCESS | 1950 | 1458129292348 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] 04:57:02 INFO - PROCESS | 1950 | Cycle 1(1): loaded http://localhost:51253/tests/dromaeo/cssquery-dojo.html (next: http://localhost:51253/tests/dromaeo/cssquery-ext.html) 04:57:04 INFO - PROCESS | 1950 | RSS: Main: 369487872 04:57:04 INFO - PROCESS | 1950 | 05:00:06 INFO - PROCESS | 1950 | Cycle 1(1): loaded http://localhost:51253/tests/dromaeo/cssquery-ext.html (next: http://localhost:51253/tests/dromaeo/cssquery-jquery.html) 05:00:07 INFO - PROCESS | 1950 | RSS: Main: 379314176 05:00:07 INFO - PROCESS | 1950 | 05:03:09 INFO - PROCESS | 1950 | Cycle 1(1): loaded http://localhost:51253/tests/dromaeo/cssquery-jquery.html (next: http://localhost:51253/tests/dromaeo/cssquery-mootools.html) 05:03:09 INFO - PROCESS | 1950 | RSS: Main: 376123392 05:03:09 INFO - PROCESS | 1950 | 05:06:11 INFO - PROCESS | 1950 | Cycle 1(1): loaded http://localhost:51253/tests/dromaeo/cssquery-mootools.html (next: http://localhost:51253/tests/dromaeo/cssquery-prototype.html) 05:06:12 INFO - PROCESS | 1950 | RSS: Main: 332181504 05:06:12 INFO - PROCESS | 1950 | 05:09:15 INFO - PROCESS | 1950 | Cycle 1(1): loaded http://localhost:51253/tests/dromaeo/cssquery-prototype.html (next: http://localhost:51253/tests/dromaeo/cssquery-yui.html) 05:09:16 INFO - PROCESS | 1950 | RSS: Main: 334594048 05:09:16 INFO - PROCESS | 1950 | 05:12:18 INFO - PROCESS | 1950 | Cycle 1(1): loaded http://localhost:51253/tests/dromaeo/cssquery-yui.html (next: http://localhost:51253/tests/dromaeo/cssquery-dojo.html) 05:12:19 INFO - PROCESS | 1950 | RSS: Main: 397856768 05:12:19 INFO - PROCESS | 1950 | 05:12:19 INFO - PROCESS | 1950 | __start_tp_report 05:12:19 INFO - PROCESS | 1950 | _x_x_mozilla_page_load 05:12:19 INFO - PROCESS | 1950 | _x_x_mozilla_page_load_details 05:12:19 INFO - PROCESS | 1950 | |i|pagename|runs| 05:12:19 INFO - PROCESS | 1950 | |0;dojo.html;3427.1603457153;3456.0323109529327;3498.5451891254133;3501.2822371413863;3546.7162626989843;5591.384947655754;5648.519875810551;5673.035583950728;5716.657000579963;5717.142428635707;5594.300712410949;5659.773609055643;5664.0937450008005;5669.574781891354;5677.034904066309;5064.594832413407;5078.31442755227;5108.948910510903;5114.897702045957;5162.6128040397125;5183;5185.118529849935;5187.636865419422;5189.351331083614;5193.766280517367;5156.303898973647;5168.767405466763;5168.9224661630105;5176.715280659562;5207.192885102815;5154.6134039946965;5190.506901844319;5222.73886305687;5222.92165617516;5261.394939581959;5057.873553161164;5078.365204349457;5084.3644544431945;5147.845564633029;5158.53573178412;5555.777768889239;5557.221988921554;5593.888122237532;5623.887522249532;5696.145578163268;6179.351168127327;6197.969010154966;6203.565750397474;6207.565470417073;6212;6099.664518451484;6125.876495711611;6150.84622884427;6188.319284878659;6224.253089629228;5806.825795226066;5841.3282472515775;5853;5858.326292476419;5864.941350586482;6260.092286618415;6283.7800676977;6290.591111577824;6293.024581189923;6306.085617585424;6024.510023435259;6025.849353766191;6072.008042922125;6123.6486823095165;6171.722272497658;6121.785737499166;6199.039148931923;6200.410960958792;6201.534884883652;6213.627182369072;6133.570650054543;6186.257649082139;6190.226221722285;6194.59735117225;6196.1635179250225;5159.380874295033;6096.420840020191;6176.567640265138;6191.318954914956;6196;4179.561146079679;4887.36464259644;4897.9020419592125;4899.069176856386;4911.656184067081;4374.343848422762;4375.387445757597;4446.399736035659;4456.997175635456;4473.4408198975125;5614.9438505615235;5641.435856414326;5657.519110875622;5673.659580425187;5682.204491371212;6136.7852125175405;6171.9691401542705;6201.348858369806;6205.565610407228;6220.720067596969;5609.887802244014;5613.803516876889;5668.914966275509;5685.914711279334;5720.998825205573;5306.734663266821;5310.707911064928;5318.282031925641;5318.308619879391;5322.281491998608;6208.192934918432;6293.842653933688;6325.936740632535;6352.6823658816875;6357.11000459936;6124.319907527303;6200.81397558074;6225.8132256032395;6228.688565571794;6241.375862413723;5349.839504814861;6290.352241832537;6311.431971122621;6334.113224148715;6350.396712312323;6016.157737916783;6105.84735381619;6150.446459818638;6168.537359697951;6183.5671502994355;6077.2099627049965;6194.256689197145;6248.906266406098;6251.4061164189325;6273;6049.455549000435;6083.513318934563;6099.817005489665;6111.572189946661;6112.057722768878;5789.131630255495;6031.517478601613;6095.329513753395;6106.969465152646;6126.846828829315;5938.584299099021;5939.792107276225;5945.19739835134;5950.7917222896995;5967.164596956516;6093.146959425772;6101.196390953443;6103.386738381593;6136.417040381157;6142.109394137914;5808.273965754281;5899.203607513103;5903.173555702119;5910.876933382644;5973.55198360116 05:12:19 INFO - PROCESS | 1950 | |1;ext.html;493.50156342094516;575.3785911215887;579.4958386204;625.2340882419036;640.481210219722;30254.54618180726;30608;30621.928232511866;30664.846675766647;30693.693063069364;30924.381512369797;40437.797811010976;40571.79714101433;40680;40907.386389204185;9491.95254023729;9761.07269809367;9768.462734549597;9774.609015639366;9806.362586431895;8635.654573817072;9552.761180970461;9674.758131046745;9707.320487565874;9733.756656083619;9657.034296570322;9721.708348749513;9729.70810875675;9776.022397760238;9884.802303953917;9757.170640495566;9832.508374581314;9833.852492212587;9840.26198035143;9846.556904939223;9624.759381015461;9742;9756.804863902753;9768.951155244178;9784;40012.799616011565;40136;40236;40299.7985010076;40303.59696403028;3184.41088398647;3242.5298331742;3281.753868459851;3513.4729790531624;3916.8041597920273;25076.874615626988;27198.32004199891;28513;28631.713682863112;29197.562036569467;2719.279390961377;2740.1505533284744;2747.8626068696485;2792.921951969683;2829.589709492133;459814;462439;462717;463080;463320;61864.69067654633;62996.05505917415;63147.052794208124;63172;63329;72059.27940720631;72919.63540182372;73028.63485682537;73117;73190.902136468;16328.75506867398;16429.753553696704;16482.093484858447;16493.422730204384;16516.256768445448;12672.112952093265;12687.11190216676;12699.238045717286;12704.237745735101;12739;21137.365879023655;21450.141994320053;21480;21482;21657;7573.772786816405;7654.846903061907;8860.158284962918;9089.863652045224;9104.271658267322;956.0917128727737;958.803445293727;1003.5885287032281;1020.8315627921459;1126.9436528173558;29196.7080329194;29341.85329073341;29634.55548166779;29722.85138574293;30086;1064.2550214849637;1096.1285777806663;1103.22774058158;1119.1158984402232;1129.4804389980536;14879.404823807143;14911.776323355158;14950.551483455296;15266.236688165547;15273.541793746206;515026;517433;520651;520797;522061;58734;59100.113498297556;59200.407995919486;59238.81522369529;59288.814223715286;1321.0950498908278;1435.612384656158;1439.6688761584894;1458.941642334316;1474.7566651502596;28421;28711.851525938728;29041.854790725913;29108.27229319284;29284.121476355744;44417.77791111153;44821.775891120335;45275.77362113168;45293.77353113213;45301.773491132335;301523;301671;301895.490522546;302434;302552;28580.42839143164;32627.836860816493;33231;33232.66767332392;33648;42820.357694635226;45919;47084.52915470939;47234.29148562843;47379.519265038034;36846.44730328993;37174;37201;38071.61928380681;38169;36293.81853090718;36365.909022729364;36553.45169822402;37333.62666373301;37774.43338349872;36830.263394732494;36975.44536831896;37220.25559488741;37671.81164094162;37912;25357;29078;29242.561361580018;32089.839550802095;32732.836335818167;24389.878050609634;24514.264572062868;24939.376515587257;25080.49839003173;25306.746932530437 05:12:19 INFO - PROCESS | 1950 | |2;jquery.html;356.6006073198018;359.7517712778183;359.9658032486914;369.51593412629455;370.42953851069353;17520;19861.801381986177;20446.182152713893;20899.895500522496;21345.893270533626;16710.498685039438;18711.812881871178;19774.604507909833;20454.284100056502;20469.795302046976;5186;5320.414754377015;5371.838844834641;5374.731263436832;5381.5425688816495;3919.5884432134644;4063.6139566741113;4107.774072426016;5363.597730170253;5390.676559406428;5926.525877929756;5964.761409543613;5966.552508561876;5973.970130149343;5985.281766188064;5628.690422026787;5834;5837.50381217597;5841.386654401269;5933.673647949361;3769.1519408133227;4770.403699537558;5425.647332923348;5564.1931919871795;5581.134924086773;12824.487020519167;12939.935300323532;12942.61172164827;12964.416601252966;16967.91516042412;2191.813695835856;2220.6335954567476;2257.3792207143;2640.405908670553;2894.2474956511246;9563.469844824795;9585.281103917234;9605.903940960641;9974.700758977242;10014.098731114234;1725.8964462132187;1803.6302557975714;1810.4840120565575;1847.9815201847944;2015.7681866585383;94638;102211.48894255556;103552;104002;107705.92294077037;10837.349759014327;11101.722456938642;11205.103591712643;11246.718832029264;11252.49363778632;13669.179849209078;13800.309984500735;13812.861871381157;13817.170969741848;13820.516281930084;6579.967100164468;8348.540830254273;8508.53252556764;8549.914500855037;8622.82754344922;6930;8665.570180920204;8673.956630216935;8751.956240218886;8757.343199260082;4474.888127796831;4538.432695913011;4562.475315338712;4598.011427543094;4598.540145985375;1776.67131580658;1868.2246867549813;1922.2118931238126;3398.966010339914;3565.2512972276113;841.9242268195892;872.3936863879543;916.5737931861717;961.2118063188119;966.2607306847647;18411.355602553846;18965.810341896406;20076.498087547927;20272.594548108955;20292.492687682927;925.9907400925905;976.6581696406201;1006.0291818395284;1014.6803756816579;1034.177828626244;10245.553769185413;10936.835947460795;10958.123350131971;10970.506327215295;11025.779484410266;597881;628266;629291;650569;653844;114701;115223.42388288173;115642;116573.41713291549;119077.61844763056;906.4561263242002;985.975350616226;995.6664517386612;1010.6664800615779;1015.9796804064025;17777.55556111056;17802.732959005367;17868.910655446638;18450.169742361893;18461.630767384846;39875.80372588827;50763.746181268856;50945.49054509407;51758.223626644874;53512.46487535231;69556.60886782134;70127.59744805178;74382;74592.62703686664;74654;7438.628068596657;7635.847283054419;7693.038370203724;7818.100918394457;7988.560629165451;10481;11563.190576659554;12081;12255.448504817483;12603.180793248775;10697.893021069689;10798.514066866852;10835.349879007284;10901.509432075418;11043.613473528389;10539.209559283237;10631.8936810634;10644.84032739494;10884.45577721095;10922.235443518877;10506.369617822955;10934.781304374028;11287.717807054889;11298.039666628429;11346.489407976496;7622.771316860503;7661.391107867187;7681.231876812274;7728.26581474759;7874.960625196837;10288;10723.142148627934;10926.071283940637;11031.393273370042;11047.889521104686 05:12:19 INFO - PROCESS | 1950 | |3;mootools.html;1257.6164269897688;1560.9375624975003;1587.2143289071907;1847.298026749835;1968.645643784119;7698.846023079542;8509.489430634167;8543.743687689379;8547.401681882271;8570.657173713043;7793.298603125718;7986.361091112712;8014.3187829034605;8017.839643207132;8028.839423211532;2174.7825217478203;2425.915092971746;2434.2697190842764;2460.458699086198;2485.7762801347876;2089.8328133749264;2510.585753350695;2512.434702192001;2513.522430738154;2516.1570873757314;2425.9757402425926;2469.9753002469924;2477.170148000422;2478.6405971134263;2480.082369523279;2454.200649495529;2464.199135281041;2477.492114116611;2479.3181874984343;2486.1422809130822;2453.3498622864954;2459.225344016647;2465.90136394544;2469.061756532524;2477.1082410332265;7195.280471952815;8828.602712877935;8835.602397892046;8836.955815220947;8852.734417967407;5264.078786212436;5427.565794736412;5437;5441.993231252231;5444.50999410051;3493.4410494320787;3569.1969306906;3628.256207477461;3670.889873303805;3686.299603075407;1013.1185868294557;1024.1550720655414;1034.9379037257788;1044.1542350695884;1050.595520724519;11773.941130294379;11783.7054073648;11816.172867899251;11844.822327665092;11891.167618266725;4777.140114779373;4834.975825120852;4892.559669629751;4900.4854490278;4913.066517361762;10077.798444031077;10079.24405669563;10088.69733907984;10104.141148002354;10106.090451859369;5415.404305526389;5449.427810079885;5451.373092094381;5458.072127738294;5480.808171713971;4335.067960388468;4435.489918659331;4734.502877197913;4836.153673107192;4850.611951043908;3775.622437756257;3852.152526444178;3865.4781604483596;3887.494625698641;3898.941515877264;1299.155548893227;1299.3243513372993;1304.1327517201146;1311.54096066376;1314.2508770001198;1344.099453366247;1370.1778932640336;1372.5607805502343;1388.5140200929593;1404.8805851502532;7991.760247192594;8181.345492360597;8191.221833925768;8196.795080122925;8207.835843283101;468.1362885476291;485.7110019538369;485.8493866901272;488.2091012559676;509.617786660005;6140.078988151813;6196.535259855439;6206;6215.409536094064;6233.625982441068;10010.526943015595;10132.949335253277;10233;10252.692419227435;10301.021402966706;9808.607655693835;10114.645987390406;10124.949375253076;10146.543405546769;10147.441890696082;8017;8068.233517815681;8143;8213.34293256538;8264.421490495608;7806.843863122819;7955.84088318242;8032.718854840053;8072.556009419538;8236.258736713724;9104.590293436944;9151.588178532114;9473.621055157717;9559;9745.561449734922;9220.585073671835;9433.764155896157;9459.432434053979;9574.664886728931;9642.325037247607;6774.762883299061;6849.965750171217;7231.3853322466075;7260.2739726026975;7362.190159082604;8015.278624923785;8037.879431808645;8052.396070294634;8083.7170699025255;8085.070216925129;7074.893876591752;7176.282371762782;7202.099737532809;7295.489315747846;7364.3003914628025;7036.226015138228;7063.223045464892;7156.821079472847;7159.64201789898;7207.531510451804;6280.5289603279025;7525.3227209551405;7540.208278130936;7573;7579.393648508217;6887;6895.069165662772;6949.30506949301;6955.92183211603;6976.860462790817;6896.965515172392;6907.34380233877;7007.194172670208;7011.614361210182;7018.368346848808 05:12:19 INFO - PROCESS | 1950 | |4;prototype.html;292.9194471520331;320.548027281533;326.5901293876187;327.04013719732586;329.70491410187867;6579.8355041124;6651.767188148409;6681.43207827334;6698.162729658787;6715.8656826863435;6581.572197807139;6609.636469994149;6610.272869984298;6611.735530578783;6619.371159739817;4601.631869450444;4725.740084295362;4745.76271186441;4761.142994261032;4762;4740.6918550294295;4777.593904518103;4827.155247831633;4832.275158726184;4834.9516504834855;4692.014676917851;4753.477117517087;4754.738489383082;4754.952450475485;4773.880652983686;4563.467275662007;4733.5976442002475;4734.124187025377;4739.218029025224;4743.976280118594;4045.2111838191563;4098.508179018507;4103.876883693494;4110.219058378928;4111.198316328288;6267.749290028393;6281.905771413432;6330.715117819663;6345.302016778151;6350.04749287606;5672.602917795756;5675.233843431125;5685.800996965087;5688.516476099537;5695.433755717169;5571.4428557144365;6989.720411183546;6995;6997.1253593300835;6997.160340759091;4331.090471001094;4636.814527418899;4637.188492013884;4643.3963584733765;4649.232876575361;7141.678624461912;7257.927420725778;7319.487635865492;7324.1577218620005;7340.339369456721;4586.449626044896;4586.701864378805;4596.655250856199;4605.263157894721;4606.216943119678;4658.464276608233;4662.696924699883;4674.322223277606;4675;4675.4155730533685;4010.3984402339884;5279.604029697788;5523.475269849358;5526.447355264522;5539.639923404966;5559.27729395176;6712.630805305755;6969.23338432772;6969.47728920333;6972.581645101209;1588.396409364434;1590.3241122523114;1612.048891154201;1614.4268784581539;1614.7981502312211;787.3661702329565;897.3314880414133;1002.9147522460527;1023.6826583758909;1029.459533744775;6231.3145553989025;6295.874082518324;6381;6425.58233714807;6446.387593178734;5683.232763576864;7176.497645164893;7181.174164971095;7257.52826066304;7328.523645962995;5468.972655136698;6430.742770289229;6513.902291465632;6515.087887695727;6521.771737989147;3120.672329405425;3818.5417749870194;3860.942085868714;3862.5944275851193;3863.150106976461;7272.781816545512;7506.774796756106;7512.361449276764;7553.206913274137;7559.508631939017;7510.399168066541;7528.360089392353;7549.396048316122;7550;7560.659770310349;7569;7576.393888489018;7577.886331705029;7591.051118610284;7593.088829340514;7322.707091716485;7327.926720732725;7329.9267007329245;7333;7350.411967042731;6911.447084233349;7130.358267755927;7187.604681742554;7195.532290401107;7223.783286501413;6712.093867327848;6963.79108626742;7514.887276690744;7567.848643026999;7579.621018949141;3813.8093095345675;4545.54544545556;4608.032313214262;4609.792559334771;4624.49130595641;6091.1472393864005;7222.349988501061;7237.927620723725;7249.601271930094;7258.745943891939;6812.21071518347;6816.250212476524;6970.616616086164;7034.5427547209265;7057.36483716468;6938.410235129869;6998.580085194904;7001.089858318386;7003.089598352181;7006.684699188447;6071.453569178795;6423.357664233727;6861.142357205349;6868.347506986828;6869.416099631388;7002.2297547268745;7009.719611215494;7011.368976792113;7012.50912436145;7020.964895175492;6670.132882725215;6704.597724136567;6852.8286792828585;6866.4506839453725;6934 05:12:19 INFO - PROCESS | 1950 | |5;yui.html;621.8973869311563;666.7699643622948;671.06386590706;680.3978479046044;717.2970488920857;14687.926560367197;14759.92620036901;14793.630159246024;14985.87605929557;15017.474388396407;13417.329133543333;13509.121907076033;13583.388747506362;13641.386137623806;13656.795148072786;735.1435577552145;739.9852002959938;901.5492253873047;905.3300557587404;924.8797656304671;931.9720408387759;933.7525555727738;935.2144198872979;936.5223735894675;937.3063932689829;977.7848873247909;983.3755565216068;985.0346660272937;986.1765425869407;990.0000998991035;774.9341305988997;971.8930917599058;983.6557204978272;989.4904124375952;991.2961797124051;726.1395246632757;727.2690945599638;729.1978823294387;729.4201110117469;729.6461216310086;14734.926325368411;14761.704765904622;14787.630309242248;14819.925900370428;14836.925815370962;12438.067144964163;12598.433070511848;12619.684507887285;12719.046071544672;12845.100842940998;8616.913830861673;9805.803883922354;9918.702438926844;10148.340357876714;10721.785564288748;2925.824450532975;3425.1950791563963;3458.688718015391;3532.116970757311;3544.4506101554302;62142.75714485685;79958;84300;84874;85316.57341713314;9348;9414.95292523533;9461.952690236643;9498.667546635834;9951.502424878727;21768.564628707336;21813.345599632037;21867.453313666967;21923.67114493284;22009.33971980843;6871.278515755874;7043.8239044023285;7089.751858684921;7131.572105673572;7192.640367981685;3907.492026036654;3941.4087886816633;3965.8413663453716;4017.6384125428854;4022.2960981828064;3520.4015317396106;3548.3435564420665;3559;3570.1788588624763;3571.910702232413;571.223136534313;648.4552975500602;658.6706646676662;661.1009027722293;671.5232185148501;447.8029666946533;484.6026258468093;487.381026096855;500.65955150497285;566.3034467604787;7662.080550333995;7752.108507521594;7815.140334563193;7931.801704957422;7963.960180199062;498.2028753993581;540.6918056707639;543.7063985447813;566.1027271774202;568.8464114689011;3688.2992231475932;3710.350688629479;3787.867424640124;3795.4686343911876;3874.612538746103;82097;82775.586122069;83406;83439.3312133754;95082.52458737801;69960.6501967497;72154;72289;74136;75047;71222.64388678127;71573.6319776019;72176.27823721801;72271.63864180645;73395.1651208724;8345.54099524532;8388.790280243044;8503.394588567833;8569.10024447424;8631.43895646781;48648.35279658822;51307.65218687073;51763.223551646;51941.7402912983;52118.21822672739;51732;62408.68795655993;64030.67984660047;70406.64796675983;71387;10638.14894808429;10806.891931080589;10909.34543927367;11358;11756.11829112837;11889.405529723306;11915.285082895054;12203.69490762738;12249.938750306192;12279.386030698608;11590.246633968765;11610.88389116098;11713.414329283672;11768.470418831002;11835.89182195891;8991.595378208127;11334.60328888485;11360.772784544097;11441.370724610226;12232.571859984857;11006.449677516252;11186.32882027081;11197.216194866282;11206.103511719206;11350.772984540428;9553.180234937163;9658.516670898647;9687.166637723809;9921.503924803586;10087.344322619007;6998.545094568837;8001.719939802079;9039.96040455356;9044.14080662336;9713.320067595201 05:12:19 INFO - PROCESS | 1950 | __end_tp_report 05:12:19 INFO - PROCESS | 1950 | __start_cc_report 05:12:19 INFO - PROCESS | 1950 | _x_x_mozilla_cycle_collect,1118 05:12:19 INFO - PROCESS | 1950 | __end_cc_report 05:12:19 INFO - PROCESS | 1950 | __startTimestamp1458130339181__endTimestamp 05:12:19 INFO - PROCESS | 1950 | 05:12:19 INFO - PROCESS | 1950 | ------- Summary: start ------- 05:12:19 INFO - PROCESS | 1950 | Number of tests: 6 05:12:19 INFO - PROCESS | 1950 | 05:12:19 INFO - PROCESS | 1950 | [#0] dojo.html Cycles:165 Average:5701.71 Median:5938.58 stddev:630.77 (10.6%) stddev-sans-first:606.94 05:12:19 INFO - PROCESS | 1950 | Values: 3427.2 3456.0 3498.5 3501.3 3546.7 5591.4 5648.5 5673.0 5716.7 5717.1 5594.3 5659.8 5664.1 5669.6 5677.0 5064.6 5078.3 5108.9 5114.9 5162.6 5183.0 5185.1 5187.6 5189.4 5193.8 5156.3 5168.8 5168.9 5176.7 5207.2 5154.6 5190.5 5222.7 5222.9 5261.4 5057.9 5078.4 5084.4 5147.8 5158.5 5555.8 5557.2 5593.9 5623.9 5696.1 6179.4 6198.0 6203.6 6207.6 6212.0 6099.7 6125.9 6150.8 6188.3 6224.3 5806.8 5841.3 5853.0 5858.3 5864.9 6260.1 6283.8 6290.6 6293.0 6306.1 6024.5 6025.8 6072.0 6123.6 6171.7 6121.8 6199.0 6200.4 6201.5 6213.6 6133.6 6186.3 6190.2 6194.6 6196.2 5159.4 6096.4 6176.6 6191.3 6196.0 4179.6 4887.4 4897.9 4899.1 4911.7 4374.3 4375.4 4446.4 4457.0 4473.4 5614.9 5641.4 5657.5 5673.7 5682.2 6136.8 6172.0 6201.3 6205.6 6220.7 5609.9 5613.8 5668.9 5685.9 5721.0 5306.7 5310.7 5318.3 5318.3 5322.3 6208.2 6293.8 6325.9 6352.7 6357.1 6124.3 6200.8 6225.8 6228.7 6241.4 5349.8 6290.4 6311.4 6334.1 6350.4 6016.2 6105.8 6150.4 6168.5 6183.6 6077.2 6194.3 6248.9 6251.4 6273.0 6049.5 6083.5 6099.8 6111.6 6112.1 5789.1 6031.5 6095.3 6107.0 6126.8 5938.6 5939.8 5945.2 5950.8 5967.2 6093.1 6101.2 6103.4 6136.4 6142.1 5808.3 5899.2 5903.2 5910.9 5973.6 05:12:19 INFO - PROCESS | 1950 | 05:12:19 INFO - PROCESS | 1950 | [#1] ext.html Cycles:180 Average:58238.27 Median:37210.63 stddev:116301.84 (312.6%) stddev-sans-first:116547.28 05:12:19 INFO - PROCESS | 1950 | Values: 493.5 575.4 579.5 625.2 640.5 30254.5 30608.0 30621.9 30664.8 30693.7 30924.4 40437.8 40571.8 40680.0 40907.4 9492.0 9761.1 9768.5 9774.6 9806.4 8635.7 9552.8 9674.8 9707.3 9733.8 9657.0 9721.7 9729.7 9776.0 9884.8 9757.2 9832.5 9833.9 9840.3 9846.6 9624.8 9742.0 9756.8 9769.0 9784.0 40012.8 40136.0 40236.0 40299.8 40303.6 3184.4 3242.5 3281.8 3513.5 3916.8 25076.9 27198.3 28513.0 28631.7 29197.6 2719.3 2740.2 2747.9 2792.9 2829.6 459814.0 462439.0 462717.0 463080.0 463320.0 61864.7 62996.1 63147.1 63172.0 63329.0 72059.3 72919.6 73028.6 73117.0 73190.9 16328.8 16429.8 16482.1 16493.4 16516.3 12672.1 12687.1 12699.2 12704.2 12739.0 21137.4 21450.1 21480.0 21482.0 21657.0 7573.8 7654.8 8860.2 9089.9 9104.3 956.1 958.8 1003.6 1020.8 1126.9 29196.7 29341.9 29634.6 29722.9 30086.0 1064.3 1096.1 1103.2 1119.1 1129.5 14879.4 14911.8 14950.6 15266.2 15273.5 515026.0 517433.0 520651.0 520797.0 522061.0 58734.0 59100.1 59200.4 59238.8 59288.8 1321.1 1435.6 1439.7 1458.9 1474.8 28421.0 28711.9 29041.9 29108.3 29284.1 44417.8 44821.8 45275.8 45293.8 45301.8 301523.0 301671.0 301895.5 302434.0 302552.0 28580.4 32627.8 33231.0 33232.7 33648.0 42820.4 45919.0 47084.5 47234.3 47379.5 36846.4 37174.0 37201.0 38071.6 38169.0 36293.8 36365.9 36553.5 37333.6 37774.4 36830.3 36975.4 37220.3 37671.8 37912.0 25357.0 29078.0 29242.6 32089.8 32732.8 24389.9 24514.3 24939.4 25080.5 25306.7 05:12:19 INFO - PROCESS | 1950 | 05:12:19 INFO - PROCESS | 1950 | [#2] jquery.html Cycles:180 Average:34368.06 Median:2448.89 stddev:104679.82 (4274.6%) stddev-sans-first:104942.32 05:12:19 INFO - PROCESS | 1950 | Values: 356.6 359.8 360.0 369.5 370.4 17520.0 19861.8 20446.2 20899.9 21345.9 16710.5 18711.8 19774.6 20454.3 20469.8 5186.0 5320.4 5371.8 5374.7 5381.5 3919.6 4063.6 4107.8 5363.6 5390.7 5926.5 5964.8 5966.6 5974.0 5985.3 5628.7 5834.0 5837.5 5841.4 5933.7 3769.2 4770.4 5425.6 5564.2 5581.1 12824.5 12939.9 12942.6 12964.4 16967.9 2191.8 2220.6 2257.4 2640.4 2894.2 9563.5 9585.3 9605.9 9974.7 10014.1 1725.9 1803.6 1810.5 1848.0 2015.8 94638.0 102211.5 103552.0 104002.0 107705.9 10837.3 11101.7 11205.1 11246.7 11252.5 13669.2 13800.3 13812.9 13817.2 13820.5 6580.0 8348.5 8508.5 8549.9 8622.8 6930.0 8665.6 8674.0 8752.0 8757.3 4474.9 4538.4 4562.5 4598.0 4598.5 1776.7 1868.2 1922.2 3399.0 3565.3 841.9 872.4 916.6 961.2 966.3 18411.4 18965.8 20076.5 20272.6 20292.5 926.0 976.7 1006.0 1014.7 1034.2 10245.6 10936.8 10958.1 10970.5 11025.8 597881.0 628266.0 629291.0 650569.0 653844.0 114701.0 115223.4 115642.0 116573.4 119077.6 906.5 986.0 995.7 1010.7 1016.0 17777.6 17802.7 17868.9 18450.2 18461.6 39875.8 50763.7 50945.5 51758.2 53512.5 69556.6 70127.6 74382.0 74592.6 74654.0 7438.6 7635.8 7693.0 7818.1 7988.6 10481.0 11563.2 12081.0 12255.4 12603.2 10697.9 10798.5 10835.3 10901.5 11043.6 10539.2 10631.9 10644.8 10884.5 10922.2 10506.4 10934.8 11287.7 11298.0 11346.5 7622.8 7661.4 7681.2 7728.3 7875.0 10288.0 10723.1 10926.1 11031.4 11047.9 05:12:19 INFO - PROCESS | 1950 | 05:12:19 INFO - PROCESS | 1950 | [#3] mootools.html Cycles:180 Average:5916.73 Median:5421.49 stddev:3103.63 (57.2%) stddev-sans-first:3092.57 05:12:19 INFO - PROCESS | 1950 | Values: 1257.6 1560.9 1587.2 1847.3 1968.6 7698.8 8509.5 8543.7 8547.4 8570.7 7793.3 7986.4 8014.3 8017.8 8028.8 2174.8 2425.9 2434.3 2460.5 2485.8 2089.8 2510.6 2512.4 2513.5 2516.2 2426.0 2470.0 2477.2 2478.6 2480.1 2454.2 2464.2 2477.5 2479.3 2486.1 2453.3 2459.2 2465.9 2469.1 2477.1 7195.3 8828.6 8835.6 8837.0 8852.7 5264.1 5427.6 5437.0 5442.0 5444.5 3493.4 3569.2 3628.3 3670.9 3686.3 1013.1 1024.2 1034.9 1044.2 1050.6 11773.9 11783.7 11816.2 11844.8 11891.2 4777.1 4835.0 4892.6 4900.5 4913.1 10077.8 10079.2 10088.7 10104.1 10106.1 5415.4 5449.4 5451.4 5458.1 5480.8 4335.1 4435.5 4734.5 4836.2 4850.6 3775.6 3852.2 3865.5 3887.5 3898.9 1299.2 1299.3 1304.1 1311.5 1314.3 1344.1 1370.2 1372.6 1388.5 1404.9 7991.8 8181.3 8191.2 8196.8 8207.8 468.1 485.7 485.8 488.2 509.6 6140.1 6196.5 6206.0 6215.4 6233.6 10010.5 10132.9 10233.0 10252.7 10301.0 9808.6 10114.6 10124.9 10146.5 10147.4 8017.0 8068.2 8143.0 8213.3 8264.4 7806.8 7955.8 8032.7 8072.6 8236.3 9104.6 9151.6 9473.6 9559.0 9745.6 9220.6 9433.8 9459.4 9574.7 9642.3 6774.8 6850.0 7231.4 7260.3 7362.2 8015.3 8037.9 8052.4 8083.7 8085.1 7074.9 7176.3 7202.1 7295.5 7364.3 7036.2 7063.2 7156.8 7159.6 7207.5 6280.5 7525.3 7540.2 7573.0 7579.4 6887.0 6895.1 6949.3 6955.9 6976.9 6897.0 6907.3 7007.2 7011.6 7018.4 05:12:19 INFO - PROCESS | 1950 | 05:12:19 INFO - PROCESS | 1950 | [#4] prototype.html Cycles:180 Average:5674.32 Median:6480.14 stddev:1837.98 (28.4%) stddev-sans-first:1798.21 05:12:19 INFO - PROCESS | 1950 | Values: 292.9 320.5 326.6 327.0 329.7 6579.8 6651.8 6681.4 6698.2 6715.9 6581.6 6609.6 6610.3 6611.7 6619.4 4601.6 4725.7 4745.8 4761.1 4762.0 4740.7 4777.6 4827.2 4832.3 4835.0 4692.0 4753.5 4754.7 4755.0 4773.9 4563.5 4733.6 4734.1 4739.2 4744.0 4045.2 4098.5 4103.9 4110.2 4111.2 6267.7 6281.9 6330.7 6345.3 6350.0 5672.6 5675.2 5685.8 5688.5 5695.4 5571.4 6989.7 6995.0 6997.1 6997.2 4331.1 4636.8 4637.2 4643.4 4649.2 7141.7 7257.9 7319.5 7324.2 7340.3 4586.4 4586.7 4596.7 4605.3 4606.2 4658.5 4662.7 4674.3 4675.0 4675.4 4010.4 5279.6 5523.5 5526.4 5539.6 5559.3 6712.6 6969.2 6969.5 6972.6 1588.4 1590.3 1612.0 1614.4 1614.8 787.4 897.3 1002.9 1023.7 1029.5 6231.3 6295.9 6381.0 6425.6 6446.4 5683.2 7176.5 7181.2 7257.5 7328.5 5469.0 6430.7 6513.9 6515.1 6521.8 3120.7 3818.5 3860.9 3862.6 3863.2 7272.8 7506.8 7512.4 7553.2 7559.5 7510.4 7528.4 7549.4 7550.0 7560.7 7569.0 7576.4 7577.9 7591.1 7593.1 7322.7 7327.9 7329.9 7333.0 7350.4 6911.4 7130.4 7187.6 7195.5 7223.8 6712.1 6963.8 7514.9 7567.8 7579.6 3813.8 4545.5 4608.0 4609.8 4624.5 6091.1 7222.3 7237.9 7249.6 7258.7 6812.2 6816.3 6970.6 7034.5 7057.4 6938.4 6998.6 7001.1 7003.1 7006.7 6071.5 6423.4 6861.1 6868.3 6869.4 7002.2 7009.7 7011.4 7012.5 7021.0 6670.1 6704.6 6852.8 6866.5 6934.0 05:12:19 INFO - PROCESS | 1950 | 05:12:19 INFO - PROCESS | 1950 | [#5] yui.html Cycles:180 Average:17836.52 Median:62275.72 stddev:24790.51 (39.8%) stddev-sans-first:24826.36 05:12:19 INFO - PROCESS | 1950 | Values: 621.9 666.8 671.1 680.4 717.3 14687.9 14759.9 14793.6 14985.9 15017.5 13417.3 13509.1 13583.4 13641.4 13656.8 735.1 740.0 901.5 905.3 924.9 932.0 933.8 935.2 936.5 937.3 977.8 983.4 985.0 986.2 990.0 774.9 971.9 983.7 989.5 991.3 726.1 727.3 729.2 729.4 729.6 14734.9 14761.7 14787.6 14819.9 14836.9 12438.1 12598.4 12619.7 12719.0 12845.1 8616.9 9805.8 9918.7 10148.3 10721.8 2925.8 3425.2 3458.7 3532.1 3544.5 62142.8 79958.0 84300.0 84874.0 85316.6 9348.0 9415.0 9462.0 9498.7 9951.5 21768.6 21813.3 21867.5 21923.7 22009.3 6871.3 7043.8 7089.8 7131.6 7192.6 3907.5 3941.4 3965.8 4017.6 4022.3 3520.4 3548.3 3559.0 3570.2 3571.9 571.2 648.5 658.7 661.1 671.5 447.8 484.6 487.4 500.7 566.3 7662.1 7752.1 7815.1 7931.8 7964.0 498.2 540.7 543.7 566.1 568.8 3688.3 3710.4 3787.9 3795.5 3874.6 82097.0 82775.6 83406.0 83439.3 95082.5 69960.7 72154.0 72289.0 74136.0 75047.0 71222.6 71573.6 72176.3 72271.6 73395.2 8345.5 8388.8 8503.4 8569.1 8631.4 48648.4 51307.7 51763.2 51941.7 52118.2 51732.0 62408.7 64030.7 70406.6 71387.0 10638.1 10806.9 10909.3 11358.0 11756.1 11889.4 11915.3 12203.7 12249.9 12279.4 11590.2 11610.9 11713.4 11768.5 11835.9 8991.6 11334.6 11360.8 11441.4 12232.6 11006.4 11186.3 11197.2 11206.1 11350.8 9553.2 9658.5 9687.2 9921.5 10087.3 6998.5 8001.7 9040.0 9044.1 9713.3 05:12:19 INFO - PROCESS | 1950 | -------- Summary: end -------- 05:12:19 INFO - PROCESS | 1950 | 05:12:19 INFO - TEST-INFO | 1950: exit 0 05:12:19 INFO - TEST-OK | dromaeo_css | took 1113900ms 05:12:19 INFO - TEST-START | kraken 05:12:19 INFO - operating with platform_type : mac_ 05:12:19 INFO - Initialising browser for kraken test... 05:12:19 INFO - TEST-INFO | started process 1953 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/tmp6yTO2P/profile http://localhost:51253/getInfo.html) 05:12:21 INFO - PROCESS | 1953 | __metrics Screen width/height:1600/1200 05:12:21 INFO - PROCESS | 1953 | colorDepth:24 05:12:21 INFO - PROCESS | 1953 | Browser inner width/height: 1024/689 05:12:21 INFO - PROCESS | 1953 | __metrics 05:12:22 INFO - PROCESS | 1953 | 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" 05:12:22 ERROR - PROCESS | 1953 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 05:12:22 INFO - TEST-INFO | 1953: exit 0 05:12:22 INFO - Browser initialized. 05:12:22 INFO - Running cycle 1/1 for kraken test... 05:12:22 INFO - TEST-INFO | started process 1955 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/tmp6yTO2P/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:12:34 INFO - PROCESS | 1955 | RSS: Main: 242253824 05:12:34 INFO - PROCESS | 1955 | 05:12:37 INFO - PROCESS | 1955 | Cycle 1(1): loaded http://localhost:51253/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:51253/tests/kraken/driver.html?testName=audio-beat-detection) 05:12:37 INFO - PROCESS | 1955 | RSS: Main: 289423360 05:12:37 INFO - PROCESS | 1955 | 05:12:40 INFO - PROCESS | 1955 | Cycle 1(1): loaded http://localhost:51253/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:51253/tests/kraken/driver.html?testName=audio-dft) 05:12:40 INFO - PROCESS | 1955 | RSS: Main: 300449792 05:12:40 INFO - PROCESS | 1955 | 05:12:44 INFO - PROCESS | 1955 | Cycle 1(1): loaded http://localhost:51253/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:51253/tests/kraken/driver.html?testName=audio-fft) 05:12:44 INFO - PROCESS | 1955 | RSS: Main: 287670272 05:12:44 INFO - PROCESS | 1955 | 05:12:46 INFO - PROCESS | 1955 | Cycle 1(1): loaded http://localhost:51253/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:51253/tests/kraken/driver.html?testName=audio-oscillator) 05:12:47 INFO - PROCESS | 1955 | RSS: Main: 291287040 05:12:47 INFO - PROCESS | 1955 | 05:12:49 INFO - PROCESS | 1955 | Cycle 1(1): loaded http://localhost:51253/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:51253/tests/kraken/driver.html?testName=imaging-gaussian-blur) 05:12:50 INFO - PROCESS | 1955 | RSS: Main: 291250176 05:12:50 INFO - PROCESS | 1955 | 05:12:52 INFO - PROCESS | 1955 | Cycle 1(1): loaded http://localhost:51253/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:51253/tests/kraken/driver.html?testName=imaging-darkroom) 05:12:53 INFO - PROCESS | 1955 | RSS: Main: 291901440 05:12:53 INFO - PROCESS | 1955 | 05:12:56 INFO - PROCESS | 1955 | Cycle 1(1): loaded http://localhost:51253/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:51253/tests/kraken/driver.html?testName=imaging-desaturate) 05:12:56 INFO - PROCESS | 1955 | RSS: Main: 289906688 05:12:56 INFO - PROCESS | 1955 | 05:12:59 INFO - PROCESS | 1955 | Cycle 1(1): loaded http://localhost:51253/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:51253/tests/kraken/driver.html?testName=json-parse-financial) 05:13:00 INFO - PROCESS | 1955 | RSS: Main: 290926592 05:13:00 INFO - PROCESS | 1955 | 05:13:01 INFO - PROCESS | 1955 | Cycle 1(1): loaded http://localhost:51253/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:51253/tests/kraken/driver.html?testName=json-stringify-tinderbox) 05:13:02 INFO - PROCESS | 1955 | RSS: Main: 325820416 05:13:02 INFO - PROCESS | 1955 | 05:13:04 INFO - PROCESS | 1955 | Cycle 1(1): loaded http://localhost:51253/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:51253/tests/kraken/driver.html?testName=stanford-crypto-aes) 05:13:05 INFO - PROCESS | 1955 | RSS: Main: 324026368 05:13:05 INFO - PROCESS | 1955 | 05:13:07 INFO - PROCESS | 1955 | Cycle 1(1): loaded http://localhost:51253/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:51253/tests/kraken/driver.html?testName=stanford-crypto-ccm) 05:13:07 INFO - PROCESS | 1955 | RSS: Main: 331792384 05:13:07 INFO - PROCESS | 1955 | 05:13:10 INFO - PROCESS | 1955 | Cycle 1(1): loaded http://localhost:51253/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:51253/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 05:13:11 INFO - PROCESS | 1955 | RSS: Main: 331878400 05:13:11 INFO - PROCESS | 1955 | 05:13:13 INFO - PROCESS | 1955 | Cycle 1(1): loaded http://localhost:51253/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:51253/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 05:13:14 INFO - PROCESS | 1955 | RSS: Main: 332955648 05:13:14 INFO - PROCESS | 1955 | 05:13:15 INFO - PROCESS | 1955 | Cycle 1(1): loaded http://localhost:51253/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:51253/tests/kraken/driver.html?testName=ai-astar) 05:13:16 INFO - PROCESS | 1955 | RSS: Main: 392953856 05:13:16 INFO - PROCESS | 1955 | 05:13:16 INFO - PROCESS | 1955 | __start_tp_report 05:13:16 INFO - PROCESS | 1955 | _x_x_mozilla_page_load 05:13:16 INFO - PROCESS | 1955 | _x_x_mozilla_page_load_details 05:13:16 INFO - PROCESS | 1955 | |i|pagename|runs| 05:13:16 INFO - PROCESS | 1955 | |0;ai-astar;111;107;112;111;110;112;116;111;111;111 05:13:16 INFO - PROCESS | 1955 | |1;audio-beat-detection;165;157;169;157;118;143;141;120;162;119 05:13:16 INFO - PROCESS | 1955 | |2;audio-dft;183;182;185;183;188;188;185;185;182;186 05:13:16 INFO - PROCESS | 1955 | |3;audio-fft;78;74;84;83;81;83;83;99;76;55 05:13:16 INFO - PROCESS | 1955 | |4;audio-oscillator;91;103;135;77;80;78;114;76;82;115 05:13:16 INFO - PROCESS | 1955 | |5;imaging-gaussian-blur;89;87;90;87;90;86;90;87;90;86 05:13:16 INFO - PROCESS | 1955 | |6;imaging-darkroom;116;147;94;96;94;94;94;96;95;94 05:13:16 INFO - PROCESS | 1955 | |7;imaging-desaturate;85;85;101;115;86;97;142;134;86;84 05:13:16 INFO - PROCESS | 1955 | |8;json-parse-financial;54;55;56;53;54;54;52;62;54;53 05:13:16 INFO - PROCESS | 1955 | |9;json-stringify-tinderbox;65;71;77;77;71;69;68;68;69;68 05:13:16 INFO - PROCESS | 1955 | |10;stanford-crypto-aes;84;97;107;106;111;81;81;82;82;84 05:13:16 INFO - PROCESS | 1955 | |11;stanford-crypto-ccm;119;122;132;124;119;117;122;135;125;136 05:13:16 INFO - PROCESS | 1955 | |12;stanford-crypto-pbkdf2;139;129;140;135;139;126;131;138;141;136 05:13:16 INFO - PROCESS | 1955 | |13;stanford-crypto-sha256-iterative;67;67;68;68;66;67;67;67;67;67 05:13:16 INFO - PROCESS | 1955 | __end_tp_report 05:13:16 INFO - PROCESS | 1955 | __start_cc_report 05:13:16 INFO - PROCESS | 1955 | _x_x_mozilla_cycle_collect,1213 05:13:16 INFO - PROCESS | 1955 | __end_cc_report 05:13:16 INFO - PROCESS | 1955 | __startTimestamp1458130396083__endTimestamp 05:13:16 INFO - PROCESS | 1955 | 05:13:16 INFO - PROCESS | 1955 | ------- Summary: start ------- 05:13:16 INFO - PROCESS | 1955 | Number of tests: 14 05:13:16 INFO - PROCESS | 1955 | 05:13:16 INFO - PROCESS | 1955 | [#0] ai-astar Cycles:10 Average:111.20 Median:111.00 stddev:2.20 (2.0%) stddev-sans-first:2.33 05:13:16 INFO - PROCESS | 1955 | Values: 111.0 107.0 112.0 111.0 110.0 112.0 116.0 111.0 111.0 111.0 05:13:16 INFO - PROCESS | 1955 | 05:13:16 INFO - PROCESS | 1955 | [#1] audio-beat-detection Cycles:10 Average:145.10 Median:157.00 stddev:20.01 (12.7%) stddev-sans-first:19.88 05:13:16 INFO - PROCESS | 1955 | Values: 165.0 157.0 169.0 157.0 118.0 143.0 141.0 120.0 162.0 119.0 05:13:16 INFO - PROCESS | 1955 | 05:13:16 INFO - PROCESS | 1955 | [#2] audio-dft Cycles:10 Average:184.70 Median:185.00 stddev:2.21 (1.2%) stddev-sans-first:2.26 05:13:16 INFO - PROCESS | 1955 | Values: 183.0 182.0 185.0 183.0 188.0 188.0 185.0 185.0 182.0 186.0 05:13:16 INFO - PROCESS | 1955 | 05:13:16 INFO - PROCESS | 1955 | [#3] audio-fft Cycles:10 Average:79.60 Median:83.00 stddev:10.98 (13.2%) stddev-sans-first:11.63 05:13:16 INFO - PROCESS | 1955 | Values: 78.0 74.0 84.0 83.0 81.0 83.0 83.0 99.0 76.0 55.0 05:13:16 INFO - PROCESS | 1955 | 05:13:16 INFO - PROCESS | 1955 | [#4] audio-oscillator Cycles:10 Average:95.10 Median:77.50 stddev:20.57 (26.5%) stddev-sans-first:21.77 05:13:16 INFO - PROCESS | 1955 | Values: 91.0 103.0 135.0 77.0 80.0 78.0 114.0 76.0 82.0 115.0 05:13:16 INFO - PROCESS | 1955 | 05:13:16 INFO - PROCESS | 1955 | [#5] imaging-gaussian-blur Cycles:10 Average:88.20 Median:89.50 stddev:1.75 (2.0%) stddev-sans-first:1.83 05:13:16 INFO - PROCESS | 1955 | Values: 89.0 87.0 90.0 87.0 90.0 86.0 90.0 87.0 90.0 86.0 05:13:16 INFO - PROCESS | 1955 | 05:13:16 INFO - PROCESS | 1955 | [#6] imaging-darkroom Cycles:10 Average:102.00 Median:94.00 stddev:17.20 (18.3%) stddev-sans-first:17.48 05:13:16 INFO - PROCESS | 1955 | Values: 116.0 147.0 94.0 96.0 94.0 94.0 94.0 96.0 95.0 94.0 05:13:16 INFO - PROCESS | 1955 | 05:13:16 INFO - PROCESS | 1955 | [#7] imaging-desaturate Cycles:10 Average:101.50 Median:85.00 stddev:21.68 (25.5%) stddev-sans-first:22.16 05:13:16 INFO - PROCESS | 1955 | Values: 85.0 85.0 101.0 115.0 86.0 97.0 142.0 134.0 86.0 84.0 05:13:16 INFO - PROCESS | 1955 | 05:13:16 INFO - PROCESS | 1955 | [#8] json-parse-financial Cycles:10 Average:54.70 Median:54.00 stddev:2.79 (5.2%) stddev-sans-first:2.95 05:13:16 INFO - PROCESS | 1955 | Values: 54.0 55.0 56.0 53.0 54.0 54.0 52.0 62.0 54.0 53.0 05:13:16 INFO - PROCESS | 1955 | 05:13:16 INFO - PROCESS | 1955 | [#9] json-stringify-tinderbox Cycles:10 Average:70.30 Median:70.00 stddev:3.92 (5.6%) stddev-sans-first:3.66 05:13:16 INFO - PROCESS | 1955 | Values: 65.0 71.0 77.0 77.0 71.0 69.0 68.0 68.0 69.0 68.0 05:13:16 INFO - PROCESS | 1955 | 05:13:16 INFO - PROCESS | 1955 | [#10] stanford-crypto-aes Cycles:10 Average:91.50 Median:82.00 stddev:12.36 (15.1%) stddev-sans-first:12.81 05:13:16 INFO - PROCESS | 1955 | Values: 84.0 97.0 107.0 106.0 111.0 81.0 81.0 82.0 82.0 84.0 05:13:16 INFO - PROCESS | 1955 | 05:13:16 INFO - PROCESS | 1955 | [#11] stanford-crypto-ccm Cycles:10 Average:125.10 Median:124.50 stddev:6.87 (5.5%) stddev-sans-first:6.92 05:13:16 INFO - PROCESS | 1955 | Values: 119.0 122.0 132.0 124.0 119.0 117.0 122.0 135.0 125.0 136.0 05:13:16 INFO - PROCESS | 1955 | 05:13:16 INFO - PROCESS | 1955 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:135.40 Median:138.50 stddev:5.10 (3.7%) stddev-sans-first:5.24 05:13:16 INFO - PROCESS | 1955 | Values: 139.0 129.0 140.0 135.0 139.0 126.0 131.0 138.0 141.0 136.0 05:13:16 INFO - PROCESS | 1955 | 05:13:16 INFO - PROCESS | 1955 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.10 Median:67.00 stddev:0.57 (0.8%) stddev-sans-first:0.60 05:13:16 INFO - PROCESS | 1955 | Values: 67.0 67.0 68.0 68.0 66.0 67.0 67.0 67.0 67.0 67.0 05:13:16 INFO - PROCESS | 1955 | -------- Summary: end -------- 05:13:16 INFO - PROCESS | 1955 | 05:13:16 INFO - TEST-INFO | 1955: exit 0 05:13:16 INFO - TEST-OK | kraken | took 57120ms 05:13:16 INFO - SUITE-END | took 1171s 05:13:17 INFO - Completed test suite (00:19:31) 05:13:17 INFO - javascript benchmark 05:13:17 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [3427.1603457153, 3456.0323109529327, 3498.5451891254133, 3501.2822371413863, 3546.7162626989843, 5591.384947655754, 5648.519875810551, 5673.035583950728, 5716.657000579963, 5717.142428635707, 5594.300712410949, 5659.773609055643, 5664.0937450008005, 5669.574781891354, 5677.034904066309, 5064.594832413407, 5078.31442755227, 5108.948910510903, 5114.897702045957, 5162.6128040397125, 5183.0, 5185.118529849935, 5187.636865419422, 5189.351331083614, 5193.766280517367, 5156.303898973647, 5168.767405466763, 5168.9224661630105, 5176.715280659562, 5207.192885102815, 5154.6134039946965, 5190.506901844319, 5222.73886305687, 5222.92165617516, 5261.394939581959, 5057.873553161164, 5078.365204349457, 5084.3644544431945, 5147.845564633029, 5158.53573178412, 5555.777768889239, 5557.221988921554, 5593.888122237532, 5623.887522249532, 5696.145578163268, 6179.351168127327, 6197.969010154966, 6203.565750397474, 6207.565470417073, 6212.0, 6099.664518451484, 6125.876495711611, 6150.84622884427, 6188.319284878659, 6224.253089629228, 5806.825795226066, 5841.3282472515775, 5853.0, 5858.326292476419, 5864.941350586482, 6260.092286618415, 6283.7800676977, 6290.591111577824, 6293.024581189923, 6306.085617585424, 6024.510023435259, 6025.849353766191, 6072.008042922125, 6123.6486823095165, 6171.722272497658, 6121.785737499166, 6199.039148931923, 6200.410960958792, 6201.534884883652, 6213.627182369072, 6133.570650054543, 6186.257649082139, 6190.226221722285, 6194.59735117225, 6196.1635179250225, 5159.380874295033, 6096.420840020191, 6176.567640265138, 6191.318954914956, 6196.0, 4179.561146079679, 4887.36464259644, 4897.9020419592125, 4899.069176856386, 4911.656184067081, 4374.343848422762, 4375.387445757597, 4446.399736035659, 4456.997175635456, 4473.4408198975125, 5614.9438505615235, 5641.435856414326, 5657.519110875622, 5673.659580425187, 5682.204491371212, 6136.7852125175405, 6171.9691401542705, 6201.348858369806, 6205.565610407228, 6220.720067596969, 5609.887802244014, 5613.803516876889, 5668.914966275509, 5685.914711279334, 5720.998825205573, 5306.734663266821, 5310.707911064928, 5318.282031925641, 5318.308619879391, 5322.281491998608, 6208.192934918432, 6293.842653933688, 6325.936740632535, 6352.6823658816875, 6357.11000459936, 6124.319907527303, 6200.81397558074, 6225.8132256032395, 6228.688565571794, 6241.375862413723, 5349.839504814861, 6290.352241832537, 6311.431971122621, 6334.113224148715, 6350.396712312323, 6016.157737916783, 6105.84735381619, 6150.446459818638, 6168.537359697951, 6183.5671502994355, 6077.2099627049965, 6194.256689197145, 6248.906266406098, 6251.4061164189325, 6273.0, 6049.455549000435, 6083.513318934563, 6099.817005489665, 6111.572189946661, 6112.057722768878, 5789.131630255495, 6031.517478601613, 6095.329513753395, 6106.969465152646, 6126.846828829315, 5938.584299099021, 5939.792107276225, 5945.19739835134, 5950.7917222896995, 5967.164596956516, 6093.146959425772, 6101.196390953443, 6103.386738381593, 6136.417040381157, 6142.109394137914, 5808.273965754281, 5899.203607513103, 5903.173555702119, 5910.876933382644, 5973.55198360116], "unit": "score", "name": "dojo.html", "value": 5662.674037728015}, {"lowerIsBetter": false, "replicates": [493.50156342094516, 575.3785911215887, 579.4958386204, 625.2340882419036, 640.481210219722, 30254.54618180726, 30608.0, 30621.928232511866, 30664.846675766647, 30693.693063069364, 30924.381512369797, 40437.797811010976, 40571.79714101433, 40680.0, 40907.386389204185, 9491.95254023729, 9761.07269809367, 9768.462734549597, 9774.609015639366, 9806.362586431895, 8635.654573817072, 9552.761180970461, 9674.758131046745, 9707.320487565874, 9733.756656083619, 9657.034296570322, 9721.708348749513, 9729.70810875675, 9776.022397760238, 9884.802303953917, 9757.170640495566, 9832.508374581314, 9833.852492212587, 9840.26198035143, 9846.556904939223, 9624.759381015461, 9742.0, 9756.804863902753, 9768.951155244178, 9784.0, 40012.799616011565, 40136.0, 40236.0, 40299.7985010076, 40303.59696403028, 3184.41088398647, 3242.5298331742, 3281.753868459851, 3513.4729790531624, 3916.8041597920273, 25076.874615626988, 27198.32004199891, 28513.0, 28631.713682863112, 29197.562036569467, 2719.279390961377, 2740.1505533284744, 2747.8626068696485, 2792.921951969683, 2829.589709492133, 459814.0, 462439.0, 462717.0, 463080.0, 463320.0, 61864.69067654633, 62996.05505917415, 63147.052794208124, 63172.0, 63329.0, 72059.27940720631, 72919.63540182372, 73028.63485682537, 73117.0, 73190.902136468, 16328.75506867398, 16429.753553696704, 16482.093484858447, 16493.422730204384, 16516.256768445448, 12672.112952093265, 12687.11190216676, 12699.238045717286, 12704.237745735101, 12739.0, 21137.365879023655, 21450.141994320053, 21480.0, 21482.0, 21657.0, 7573.772786816405, 7654.846903061907, 8860.158284962918, 9089.863652045224, 9104.271658267322, 956.0917128727737, 958.803445293727, 1003.5885287032281, 1020.8315627921459, 1126.9436528173558, 29196.7080329194, 29341.85329073341, 29634.55548166779, 29722.85138574293, 30086.0, 1064.2550214849637, 1096.1285777806663, 1103.22774058158, 1119.1158984402232, 1129.4804389980536, 14879.404823807143, 14911.776323355158, 14950.551483455296, 15266.236688165547, 15273.541793746206, 515026.0, 517433.0, 520651.0, 520797.0, 522061.0, 58734.0, 59100.113498297556, 59200.407995919486, 59238.81522369529, 59288.814223715286, 1321.0950498908278, 1435.612384656158, 1439.6688761584894, 1458.941642334316, 1474.7566651502596, 28421.0, 28711.851525938728, 29041.854790725913, 29108.27229319284, 29284.121476355744, 44417.77791111153, 44821.775891120335, 45275.77362113168, 45293.77353113213, 45301.773491132335, 301523.0, 301671.0, 301895.490522546, 302434.0, 302552.0, 28580.42839143164, 32627.836860816493, 33231.0, 33232.66767332392, 33648.0, 42820.357694635226, 45919.0, 47084.52915470939, 47234.29148562843, 47379.519265038034, 36846.44730328993, 37174.0, 37201.0, 38071.61928380681, 38169.0, 36293.81853090718, 36365.909022729364, 36553.45169822402, 37333.62666373301, 37774.43338349872, 36830.263394732494, 36975.44536831896, 37220.25559488741, 37671.81164094162, 37912.0, 25357.0, 29078.0, 29242.561361580018, 32089.839550802095, 32732.836335818167, 24389.878050609634, 24514.264572062868, 24939.376515587257, 25080.49839003173, 25306.746932530437], "unit": "score", "name": "ext.html", "value": 19433.467301319135}, {"lowerIsBetter": false, "replicates": [356.6006073198018, 359.7517712778183, 359.9658032486914, 369.51593412629455, 370.42953851069353, 17520.0, 19861.801381986177, 20446.182152713893, 20899.895500522496, 21345.893270533626, 16710.498685039438, 18711.812881871178, 19774.604507909833, 20454.284100056502, 20469.795302046976, 5186.0, 5320.414754377015, 5371.838844834641, 5374.731263436832, 5381.5425688816495, 3919.5884432134644, 4063.6139566741113, 4107.774072426016, 5363.597730170253, 5390.676559406428, 5926.525877929756, 5964.761409543613, 5966.552508561876, 5973.970130149343, 5985.281766188064, 5628.690422026787, 5834.0, 5837.50381217597, 5841.386654401269, 5933.673647949361, 3769.1519408133227, 4770.403699537558, 5425.647332923348, 5564.1931919871795, 5581.134924086773, 12824.487020519167, 12939.935300323532, 12942.61172164827, 12964.416601252966, 16967.91516042412, 2191.813695835856, 2220.6335954567476, 2257.3792207143, 2640.405908670553, 2894.2474956511246, 9563.469844824795, 9585.281103917234, 9605.903940960641, 9974.700758977242, 10014.098731114234, 1725.8964462132187, 1803.6302557975714, 1810.4840120565575, 1847.9815201847944, 2015.7681866585383, 94638.0, 102211.48894255556, 103552.0, 104002.0, 107705.92294077037, 10837.349759014327, 11101.722456938642, 11205.103591712643, 11246.718832029264, 11252.49363778632, 13669.179849209078, 13800.309984500735, 13812.861871381157, 13817.170969741848, 13820.516281930084, 6579.967100164468, 8348.540830254273, 8508.53252556764, 8549.914500855037, 8622.82754344922, 6930.0, 8665.570180920204, 8673.956630216935, 8751.956240218886, 8757.343199260082, 4474.888127796831, 4538.432695913011, 4562.475315338712, 4598.011427543094, 4598.540145985375, 1776.67131580658, 1868.2246867549813, 1922.2118931238126, 3398.966010339914, 3565.2512972276113, 841.9242268195892, 872.3936863879543, 916.5737931861717, 961.2118063188119, 966.2607306847647, 18411.355602553846, 18965.810341896406, 20076.498087547927, 20272.594548108955, 20292.492687682927, 925.9907400925905, 976.6581696406201, 1006.0291818395284, 1014.6803756816579, 1034.177828626244, 10245.553769185413, 10936.835947460795, 10958.123350131971, 10970.506327215295, 11025.779484410266, 597881.0, 628266.0, 629291.0, 650569.0, 653844.0, 114701.0, 115223.42388288173, 115642.0, 116573.41713291549, 119077.61844763056, 906.4561263242002, 985.975350616226, 995.6664517386612, 1010.6664800615779, 1015.9796804064025, 17777.55556111056, 17802.732959005367, 17868.910655446638, 18450.169742361893, 18461.630767384846, 39875.80372588827, 50763.746181268856, 50945.49054509407, 51758.223626644874, 53512.46487535231, 69556.60886782134, 70127.59744805178, 74382.0, 74592.62703686664, 74654.0, 7438.628068596657, 7635.847283054419, 7693.038370203724, 7818.100918394457, 7988.560629165451, 10481.0, 11563.190576659554, 12081.0, 12255.448504817483, 12603.180793248775, 10697.893021069689, 10798.514066866852, 10835.349879007284, 10901.509432075418, 11043.613473528389, 10539.209559283237, 10631.8936810634, 10644.84032739494, 10884.45577721095, 10922.235443518877, 10506.369617822955, 10934.781304374028, 11287.717807054889, 11298.039666628429, 11346.489407976496, 7622.771316860503, 7661.391107867187, 7681.231876812274, 7728.26581474759, 7874.960625196837, 10288.0, 10723.142148627934, 10926.071283940637, 11031.393273370042, 11047.889521104686], "unit": "score", "name": "jquery.html", "value": 9133.744433704267}, {"lowerIsBetter": false, "replicates": [1257.6164269897688, 1560.9375624975003, 1587.2143289071907, 1847.298026749835, 1968.645643784119, 7698.846023079542, 8509.489430634167, 8543.743687689379, 8547.401681882271, 8570.657173713043, 7793.298603125718, 7986.361091112712, 8014.3187829034605, 8017.839643207132, 8028.839423211532, 2174.7825217478203, 2425.915092971746, 2434.2697190842764, 2460.458699086198, 2485.7762801347876, 2089.8328133749264, 2510.585753350695, 2512.434702192001, 2513.522430738154, 2516.1570873757314, 2425.9757402425926, 2469.9753002469924, 2477.170148000422, 2478.6405971134263, 2480.082369523279, 2454.200649495529, 2464.199135281041, 2477.492114116611, 2479.3181874984343, 2486.1422809130822, 2453.3498622864954, 2459.225344016647, 2465.90136394544, 2469.061756532524, 2477.1082410332265, 7195.280471952815, 8828.602712877935, 8835.602397892046, 8836.955815220947, 8852.734417967407, 5264.078786212436, 5427.565794736412, 5437.0, 5441.993231252231, 5444.50999410051, 3493.4410494320787, 3569.1969306906, 3628.256207477461, 3670.889873303805, 3686.299603075407, 1013.1185868294557, 1024.1550720655414, 1034.9379037257788, 1044.1542350695884, 1050.595520724519, 11773.941130294379, 11783.7054073648, 11816.172867899251, 11844.822327665092, 11891.167618266725, 4777.140114779373, 4834.975825120852, 4892.559669629751, 4900.4854490278, 4913.066517361762, 10077.798444031077, 10079.24405669563, 10088.69733907984, 10104.141148002354, 10106.090451859369, 5415.404305526389, 5449.427810079885, 5451.373092094381, 5458.072127738294, 5480.808171713971, 4335.067960388468, 4435.489918659331, 4734.502877197913, 4836.153673107192, 4850.611951043908, 3775.622437756257, 3852.152526444178, 3865.4781604483596, 3887.494625698641, 3898.941515877264, 1299.155548893227, 1299.3243513372993, 1304.1327517201146, 1311.54096066376, 1314.2508770001198, 1344.099453366247, 1370.1778932640336, 1372.5607805502343, 1388.5140200929593, 1404.8805851502532, 7991.760247192594, 8181.345492360597, 8191.221833925768, 8196.795080122925, 8207.835843283101, 468.1362885476291, 485.7110019538369, 485.8493866901272, 488.2091012559676, 509.617786660005, 6140.078988151813, 6196.535259855439, 6206.0, 6215.409536094064, 6233.625982441068, 10010.526943015595, 10132.949335253277, 10233.0, 10252.692419227435, 10301.021402966706, 9808.607655693835, 10114.645987390406, 10124.949375253076, 10146.543405546769, 10147.441890696082, 8017.0, 8068.233517815681, 8143.0, 8213.34293256538, 8264.421490495608, 7806.843863122819, 7955.84088318242, 8032.718854840053, 8072.556009419538, 8236.258736713724, 9104.590293436944, 9151.588178532114, 9473.621055157717, 9559.0, 9745.561449734922, 9220.585073671835, 9433.764155896157, 9459.432434053979, 9574.664886728931, 9642.325037247607, 6774.762883299061, 6849.965750171217, 7231.3853322466075, 7260.2739726026975, 7362.190159082604, 8015.278624923785, 8037.879431808645, 8052.396070294634, 8083.7170699025255, 8085.070216925129, 7074.893876591752, 7176.282371762782, 7202.099737532809, 7295.489315747846, 7364.3003914628025, 7036.226015138228, 7063.223045464892, 7156.821079472847, 7159.64201789898, 7207.531510451804, 6280.5289603279025, 7525.3227209551405, 7540.208278130936, 7573.0, 7579.393648508217, 6887.0, 6895.069165662772, 6949.30506949301, 6955.92183211603, 6976.860462790817, 6896.965515172392, 6907.34380233877, 7007.194172670208, 7011.614361210182, 7018.368346848808], "unit": "score", "name": "mootools.html", "value": 4764.269665234057}, {"lowerIsBetter": false, "replicates": [292.9194471520331, 320.548027281533, 326.5901293876187, 327.04013719732586, 329.70491410187867, 6579.8355041124, 6651.767188148409, 6681.43207827334, 6698.162729658787, 6715.8656826863435, 6581.572197807139, 6609.636469994149, 6610.272869984298, 6611.735530578783, 6619.371159739817, 4601.631869450444, 4725.740084295362, 4745.76271186441, 4761.142994261032, 4762.0, 4740.6918550294295, 4777.593904518103, 4827.155247831633, 4832.275158726184, 4834.9516504834855, 4692.014676917851, 4753.477117517087, 4754.738489383082, 4754.952450475485, 4773.880652983686, 4563.467275662007, 4733.5976442002475, 4734.124187025377, 4739.218029025224, 4743.976280118594, 4045.2111838191563, 4098.508179018507, 4103.876883693494, 4110.219058378928, 4111.198316328288, 6267.749290028393, 6281.905771413432, 6330.715117819663, 6345.302016778151, 6350.04749287606, 5672.602917795756, 5675.233843431125, 5685.800996965087, 5688.516476099537, 5695.433755717169, 5571.4428557144365, 6989.720411183546, 6995.0, 6997.1253593300835, 6997.160340759091, 4331.090471001094, 4636.814527418899, 4637.188492013884, 4643.3963584733765, 4649.232876575361, 7141.678624461912, 7257.927420725778, 7319.487635865492, 7324.1577218620005, 7340.339369456721, 4586.449626044896, 4586.701864378805, 4596.655250856199, 4605.263157894721, 4606.216943119678, 4658.464276608233, 4662.696924699883, 4674.322223277606, 4675.0, 4675.4155730533685, 4010.3984402339884, 5279.604029697788, 5523.475269849358, 5526.447355264522, 5539.639923404966, 5559.27729395176, 6712.630805305755, 6969.23338432772, 6969.47728920333, 6972.581645101209, 1588.396409364434, 1590.3241122523114, 1612.048891154201, 1614.4268784581539, 1614.7981502312211, 787.3661702329565, 897.3314880414133, 1002.9147522460527, 1023.6826583758909, 1029.459533744775, 6231.3145553989025, 6295.874082518324, 6381.0, 6425.58233714807, 6446.387593178734, 5683.232763576864, 7176.497645164893, 7181.174164971095, 7257.52826066304, 7328.523645962995, 5468.972655136698, 6430.742770289229, 6513.902291465632, 6515.087887695727, 6521.771737989147, 3120.672329405425, 3818.5417749870194, 3860.942085868714, 3862.5944275851193, 3863.150106976461, 7272.781816545512, 7506.774796756106, 7512.361449276764, 7553.206913274137, 7559.508631939017, 7510.399168066541, 7528.360089392353, 7549.396048316122, 7550.0, 7560.659770310349, 7569.0, 7576.393888489018, 7577.886331705029, 7591.051118610284, 7593.088829340514, 7322.707091716485, 7327.926720732725, 7329.9267007329245, 7333.0, 7350.411967042731, 6911.447084233349, 7130.358267755927, 7187.604681742554, 7195.532290401107, 7223.783286501413, 6712.093867327848, 6963.79108626742, 7514.887276690744, 7567.848643026999, 7579.621018949141, 3813.8093095345675, 4545.54544545556, 4608.032313214262, 4609.792559334771, 4624.49130595641, 6091.1472393864005, 7222.349988501061, 7237.927620723725, 7249.601271930094, 7258.745943891939, 6812.21071518347, 6816.250212476524, 6970.616616086164, 7034.5427547209265, 7057.36483716468, 6938.410235129869, 6998.580085194904, 7001.089858318386, 7003.089598352181, 7006.684699188447, 6071.453569178795, 6423.357664233727, 6861.142357205349, 6868.347506986828, 6869.416099631388, 7002.2297547268745, 7009.719611215494, 7011.368976792113, 7012.50912436145, 7020.964895175492, 6670.132882725215, 6704.597724136567, 6852.8286792828585, 6866.4506839453725, 6934.0], "unit": "score", "name": "prototype.html", "value": 5050.5244730104705}, {"lowerIsBetter": false, "replicates": [621.8973869311563, 666.7699643622948, 671.06386590706, 680.3978479046044, 717.2970488920857, 14687.926560367197, 14759.92620036901, 14793.630159246024, 14985.87605929557, 15017.474388396407, 13417.329133543333, 13509.121907076033, 13583.388747506362, 13641.386137623806, 13656.795148072786, 735.1435577552145, 739.9852002959938, 901.5492253873047, 905.3300557587404, 924.8797656304671, 931.9720408387759, 933.7525555727738, 935.2144198872979, 936.5223735894675, 937.3063932689829, 977.7848873247909, 983.3755565216068, 985.0346660272937, 986.1765425869407, 990.0000998991035, 774.9341305988997, 971.8930917599058, 983.6557204978272, 989.4904124375952, 991.2961797124051, 726.1395246632757, 727.2690945599638, 729.1978823294387, 729.4201110117469, 729.6461216310086, 14734.926325368411, 14761.704765904622, 14787.630309242248, 14819.925900370428, 14836.925815370962, 12438.067144964163, 12598.433070511848, 12619.684507887285, 12719.046071544672, 12845.100842940998, 8616.913830861673, 9805.803883922354, 9918.702438926844, 10148.340357876714, 10721.785564288748, 2925.824450532975, 3425.1950791563963, 3458.688718015391, 3532.116970757311, 3544.4506101554302, 62142.75714485685, 79958.0, 84300.0, 84874.0, 85316.57341713314, 9348.0, 9414.95292523533, 9461.952690236643, 9498.667546635834, 9951.502424878727, 21768.564628707336, 21813.345599632037, 21867.453313666967, 21923.67114493284, 22009.33971980843, 6871.278515755874, 7043.8239044023285, 7089.751858684921, 7131.572105673572, 7192.640367981685, 3907.492026036654, 3941.4087886816633, 3965.8413663453716, 4017.6384125428854, 4022.2960981828064, 3520.4015317396106, 3548.3435564420665, 3559.0, 3570.1788588624763, 3571.910702232413, 571.223136534313, 648.4552975500602, 658.6706646676662, 661.1009027722293, 671.5232185148501, 447.8029666946533, 484.6026258468093, 487.381026096855, 500.65955150497285, 566.3034467604787, 7662.080550333995, 7752.108507521594, 7815.140334563193, 7931.801704957422, 7963.960180199062, 498.2028753993581, 540.6918056707639, 543.7063985447813, 566.1027271774202, 568.8464114689011, 3688.2992231475932, 3710.350688629479, 3787.867424640124, 3795.4686343911876, 3874.612538746103, 82097.0, 82775.586122069, 83406.0, 83439.3312133754, 95082.52458737801, 69960.6501967497, 72154.0, 72289.0, 74136.0, 75047.0, 71222.64388678127, 71573.6319776019, 72176.27823721801, 72271.63864180645, 73395.1651208724, 8345.54099524532, 8388.790280243044, 8503.394588567833, 8569.10024447424, 8631.43895646781, 48648.35279658822, 51307.65218687073, 51763.223551646, 51941.7402912983, 52118.21822672739, 51732.0, 62408.68795655993, 64030.67984660047, 70406.64796675983, 71387.0, 10638.14894808429, 10806.891931080589, 10909.34543927367, 11358.0, 11756.11829112837, 11889.405529723306, 11915.285082895054, 12203.69490762738, 12249.938750306192, 12279.386030698608, 11590.246633968765, 11610.88389116098, 11713.414329283672, 11768.470418831002, 11835.89182195891, 8991.595378208127, 11334.60328888485, 11360.772784544097, 11441.370724610226, 12232.571859984857, 11006.449677516252, 11186.32882027081, 11197.216194866282, 11206.103511719206, 11350.772984540428, 9553.180234937163, 9658.516670898647, 9687.166637723809, 9921.503924803586, 10087.344322619007, 6998.545094568837, 8001.719939802079, 9039.96040455356, 9044.14080662336, 9713.320067595201], "unit": "score", "name": "yui.html", "value": 6764.4949688607}], "name": "dromaeo_css", "value": 7395.554620128522}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [111.0, 107.0, 112.0, 111.0, 110.0, 112.0, 116.0, 111.0, 111.0, 111.0], "unit": "score", "name": "ai-astar", "value": 111.2}, {"lowerIsBetter": true, "replicates": [165.0, 157.0, 169.0, 157.0, 118.0, 143.0, 141.0, 120.0, 162.0, 119.0], "unit": "score", "name": "audio-beat-detection", "value": 145.1}, {"lowerIsBetter": true, "replicates": [183.0, 182.0, 185.0, 183.0, 188.0, 188.0, 185.0, 185.0, 182.0, 186.0], "unit": "score", "name": "audio-dft", "value": 184.7}, {"lowerIsBetter": true, "replicates": [78.0, 74.0, 84.0, 83.0, 81.0, 83.0, 83.0, 99.0, 76.0, 55.0], "unit": "score", "name": "audio-fft", "value": 79.6}, {"lowerIsBetter": true, "replicates": [91.0, 103.0, 135.0, 77.0, 80.0, 78.0, 114.0, 76.0, 82.0, 115.0], "unit": "score", "name": "audio-oscillator", "value": 95.1}, {"lowerIsBetter": true, "replicates": [89.0, 87.0, 90.0, 87.0, 90.0, 86.0, 90.0, 87.0, 90.0, 86.0], "unit": "score", "name": "imaging-gaussian-blur", "value": 88.2}, {"lowerIsBetter": true, "replicates": [116.0, 147.0, 94.0, 96.0, 94.0, 94.0, 94.0, 96.0, 95.0, 94.0], "unit": "score", "name": "imaging-darkroom", "value": 102.0}, {"lowerIsBetter": true, "replicates": [85.0, 85.0, 101.0, 115.0, 86.0, 97.0, 142.0, 134.0, 86.0, 84.0], "unit": "score", "name": "imaging-desaturate", "value": 101.5}, {"lowerIsBetter": true, "replicates": [54.0, 55.0, 56.0, 53.0, 54.0, 54.0, 52.0, 62.0, 54.0, 53.0], "unit": "score", "name": "json-parse-financial", "value": 54.7}, {"lowerIsBetter": true, "replicates": [65.0, 71.0, 77.0, 77.0, 71.0, 69.0, 68.0, 68.0, 69.0, 68.0], "unit": "score", "name": "json-stringify-tinderbox", "value": 70.3}, {"lowerIsBetter": true, "replicates": [84.0, 97.0, 107.0, 106.0, 111.0, 81.0, 81.0, 82.0, 82.0, 84.0], "unit": "score", "name": "stanford-crypto-aes", "value": 91.5}, {"lowerIsBetter": true, "replicates": [119.0, 122.0, 132.0, 124.0, 119.0, 117.0, 122.0, 135.0, 125.0, 136.0], "unit": "score", "name": "stanford-crypto-ccm", "value": 125.1}, {"lowerIsBetter": true, "replicates": [139.0, 129.0, 140.0, 135.0, 139.0, 126.0, 131.0, 138.0, 141.0, 136.0], "unit": "score", "name": "stanford-crypto-pbkdf2", "value": 135.4}, {"lowerIsBetter": true, "replicates": [67.0, 67.0, 68.0, 68.0, 66.0, 67.0, 67.0, 67.0, 67.0, 67.0], "unit": "score", "name": "stanford-crypto-sha256-iterative", "value": 67.1}], "name": "kraken", "value": 1451.5}]} 05:13:17 INFO - Return code: 0 05:13:17 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 05:13:17 ERROR - # TBPL SUCCESS # 05:13:17 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:13:17 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:13:17 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:13:17 INFO - 2016-03-16 05:13:17.423 screenresolution[1959:12861] starting screenresolution argv=screenresolution get 05:13:17 INFO - 2016-03-16 05:13:17.426 screenresolution[1959:12861] Display 0: 1600x1200x32@60 05:13:17 INFO - 2016-03-16 05:13:17.432 screenresolution[1960:12864] starting screenresolution argv=screenresolution list 05:13:17 INFO - Available Modes on Display 0 05:13:17 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:13:17 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:13:17 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:13:17 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:13:17 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:13:17 INFO - Intel Iris: 05:13:17 INFO - Chipset Model: Intel Iris 05:13:17 INFO - Type: GPU 05:13:17 INFO - Bus: Built-In 05:13:17 INFO - VRAM (Dynamic, Max): 1536 MB 05:13:17 INFO - Vendor: Intel (0x8086) 05:13:17 INFO - Device ID: 0x0a2e 05:13:17 INFO - Revision ID: 0x0009 05:13:17 INFO - Displays: 05:13:17 INFO - Display: 05:13:17 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:13:17 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:13:17 INFO - Display Serial Number: 1600x1200 60 05:13:17 INFO - Main Display: Yes 05:13:17 INFO - Mirror: Off 05:13:17 INFO - Online: Yes 05:13:17 INFO - Rotation: Supported 05:13:17 INFO - Return code: 0 05:13:17 INFO - Running post-action listener: _resource_record_post_action 05:13:17 INFO - Running post-run listener: _resource_record_post_run 05:13:18 INFO - Total resource usage - Wall time: 1207s; CPU: 53.0%; Read bytes: 401408; Write bytes: 704719872; Read time: 4; Write time: 2108 05:13:18 INFO - install - Wall time: 34s; CPU: 55.0%; Read bytes: 397367808; Write bytes: 390395904; Read time: 29978; Write time: 752 05:13:18 INFO - run-tests - Wall time: 1174s; CPU: 52.0%; Read bytes: 376832; Write bytes: 299036160; Read time: 3; Write time: 1312 05:13:18 INFO - Running post-run listener: _upload_blobber_files 05:13:18 INFO - Blob upload gear active. 05:13:18 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 05:13:18 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 05:13:18 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', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 05:13:18 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 Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 05:13:19 INFO - (blobuploader) - INFO - Open directory for files ... 05:13:19 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 05:13:19 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:13:19 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:13:20 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 05:13:20 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:13:20 INFO - (blobuploader) - INFO - Done attempting. 05:13:20 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 05:13:20 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:13:20 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:13:21 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 05:13:21 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:13:21 INFO - (blobuploader) - INFO - Done attempting. 05:13:21 INFO - (blobuploader) - INFO - Iteration through files over. 05:13:21 INFO - Return code: 0 05:13:21 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 05:13:21 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 05:13:21 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/586d7a9383f9ff8b3de740de03366e9e8c54c0375ea2f6abc51395186f2e64b3fcf0d91e6dc73450f934ed8555278e5dc64bd38e47791ca21550c93ec7d00c41", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a3e486bbc6e667cd997ba17eeff90f512e7b9e89c1cad0d926a683d123887a344802eebb73351de5a1c786e726c225d9cd54be8dd3b8e0237837752b23f73ef6"} 05:13:21 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 05:13:21 INFO - Writing to file /builds/slave/test/properties/blobber_files 05:13:21 INFO - Contents: 05:13:21 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/586d7a9383f9ff8b3de740de03366e9e8c54c0375ea2f6abc51395186f2e64b3fcf0d91e6dc73450f934ed8555278e5dc64bd38e47791ca21550c93ec7d00c41", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a3e486bbc6e667cd997ba17eeff90f512e7b9e89c1cad0d926a683d123887a344802eebb73351de5a1c786e726c225d9cd54be8dd3b8e0237837752b23f73ef6"} 05:13:21 INFO - Running post-run listener: copy_logs_to_upload_dir 05:13:21 INFO - Copying logs to upload dir... 05:13:21 INFO - mkdir: /builds/slave/test/build/upload/logs 05:13:21 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1261.819131 ========= master_lag: 1.77 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 3 secs) (at 2016-03-16 05:13:23.213025) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-03-16 05:13:23.216265) ========= 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.daDqGGMSBK/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.yTZW1ht56M/Listeners TMPDIR=/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/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/Firefox/sha512/586d7a9383f9ff8b3de740de03366e9e8c54c0375ea2f6abc51395186f2e64b3fcf0d91e6dc73450f934ed8555278e5dc64bd38e47791ca21550c93ec7d00c41", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a3e486bbc6e667cd997ba17eeff90f512e7b9e89c1cad0d926a683d123887a344802eebb73351de5a1c786e726c225d9cd54be8dd3b8e0237837752b23f73ef6"} build_url:https://queue.taskcluster.net/v1/task/L34geki1RnGm5jqLmpnxPg/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011843 build_url: 'https://queue.taskcluster.net/v1/task/L34geki1RnGm5jqLmpnxPg/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/586d7a9383f9ff8b3de740de03366e9e8c54c0375ea2f6abc51395186f2e64b3fcf0d91e6dc73450f934ed8555278e5dc64bd38e47791ca21550c93ec7d00c41", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a3e486bbc6e667cd997ba17eeff90f512e7b9e89c1cad0d926a683d123887a344802eebb73351de5a1c786e726c225d9cd54be8dd3b8e0237837752b23f73ef6"}' ========= master_lag: 0.88 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-03-16 05:13:24.109316) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-03-16 05:13:24.109640) ========= 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.daDqGGMSBK/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.yTZW1ht56M/Listeners TMPDIR=/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/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.005625 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-03-16 05:13:24.153986) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-03-16 05:13:24.154296) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-03-16 05:13:24.162754) ========= ========= Total master_lag: 2.89 =========