builder: mozilla-inbound_yosemite_test-chromez-e10s
slave: t-yosemite-r5-0067
starttime: 1447109367.62
results: success (0)
buildid: 20151109132133
builduid: 60bca578163d4d938e68697233565017
revision: 6c6d04b021ddfdfe1ce35f5b6939ef80deda3f3f
========= Started set props: master (results: 0, elapsed: 0 secs) (at 2015-11-09 14:49:27.622591) =========
master: http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/
========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2015-11-09 14:49:27.623098) =========
========= Started set props: basedir (results: 0, elapsed: 0 secs) (at 2015-11-09 14:49:27.623393) =========
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.IE2EwGFq5Q/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.wSbHOtz3pt/Listeners
TMPDIR=/var/folders/0d/wgdyxjyj2r96yt9mnwtg9t0m00000w/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:0x0:0x0
using PTY: False
/builds/slave/test
program finished with exit code 0
elapsedTime=0.006546
basedir: '/builds/slave/test'
========= master_lag: 0.02 =========
========= Finished set props: basedir (results: 0, elapsed: 0 secs) (at 2015-11-09 14:49:27.651657) =========
========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2015-11-09 14:49:27.651985) =========
========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2015-11-09 14:49:27.714158) =========
========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-11-09 14:49:27.715752) =========
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.IE2EwGFq5Q/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.wSbHOtz3pt/Listeners
TMPDIR=/var/folders/0d/wgdyxjyj2r96yt9mnwtg9t0m00000w/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:0x0:0x0
using PTY: False
program finished with exit code 0
elapsedTime=0.005552
========= master_lag: 0.05 =========
========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-11-09 14:49:27.767090) =========
========= Started set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2015-11-09 14:49:27.767393) =========
script_repo_url: https://hg.mozilla.org/build/mozharness
========= Finished set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2015-11-09 14:49:27.767771) =========
========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-11-09 14:49:27.768046) =========
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.IE2EwGFq5Q/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.wSbHOtz3pt/Listeners
TMPDIR=/var/folders/0d/wgdyxjyj2r96yt9mnwtg9t0m00000w/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:0x0:0x0
using PTY: False
--2015-11-09 14:49:27-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py
Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25, ::
Connecting to hg.mozilla.org|63.245.215.102|:443... connected.
HTTP request sent, awaiting response... 200 Script output follows
Length: 12141 (12K) [text/x-python]
Saving to: `archiver_client.py'
0K .......... . 100% 37.2M=0s
2015-11-09 14:49:28 (37.2 MB/s) - `archiver_client.py' saved [12141/12141]
program finished with exit code 0
elapsedTime=0.353998
========= master_lag: 0.02 =========
========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-11-09 14:49:28.140112) =========
========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-11-09 14:49:28.140423) =========
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.IE2EwGFq5Q/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.wSbHOtz3pt/Listeners
TMPDIR=/var/folders/0d/wgdyxjyj2r96yt9mnwtg9t0m00000w/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:0x0:0x0
using PTY: False
program finished with exit code 0
elapsedTime=0.021639
========= master_lag: 0.03 =========
========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-11-09 14:49:28.189398) =========
========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-11-09 14:49:28.189764) =========
bash -c 'python archiver_client.py mozharness --repo integration/mozilla-inbound --rev 6c6d04b021ddfdfe1ce35f5b6939ef80deda3f3f --destination scripts --debug'
in dir /builds/slave/test/. (timeout 1200 secs)
watching logfiles {}
argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/mozilla-inbound --rev 6c6d04b021ddfdfe1ce35f5b6939ef80deda3f3f --destination scripts --debug']
environment:
Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.IE2EwGFq5Q/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.wSbHOtz3pt/Listeners
TMPDIR=/var/folders/0d/wgdyxjyj2r96yt9mnwtg9t0m00000w/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:0x0:0x0
using PTY: False
2015-11-09 14:49:28,271 truncating revision to first 12 chars
2015-11-09 14:49:28,271 Setting DEBUG logging.
2015-11-09 14:49:28,271 attempt 1/10
2015-11-09 14:49:28,271 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/mozilla-inbound/6c6d04b021dd?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness
2015-11-09 14:49:28,631 unpacking tar archive at: mozilla-inbound-6c6d04b021dd/testing/mozharness/
program finished with exit code 0
elapsedTime=0.629580
========= master_lag: 0.02 =========
========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-11-09 14:49:28.839010) =========
========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2015-11-09 14:49:28.839301) =========
========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2015-11-09 14:49:28.856765) =========
========= Started tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2015-11-09 14:49:28.857026) =========
TinderboxPrint: script_revlink: https://hg.mozilla.org/build/mozharness/rev/production
========= Finished tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2015-11-09 14:49:28.857405) =========
========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 17 mins, 27 secs) (at 2015-11-09 14:49:28.857785) =========
/tools/buildbot/bin/python scripts/scripts/talos_script.py --suite chromez-e10s --add-option --webServer,localhost --branch-name Mozilla-Inbound --system-bits 64 --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Inbound
in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs)
watching logfiles {}
argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'chromez-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Inbound', '--system-bits', '64', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Inbound']
environment:
Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.IE2EwGFq5Q/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.wSbHOtz3pt/Listeners
TMPDIR=/var/folders/0d/wgdyxjyj2r96yt9mnwtg9t0m00000w/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:0x0:0x0
using PTY: False
14:49:29 INFO - MultiFileLogger online at 20151109 14:49:29 in /builds/slave/test
14:49:29 INFO - Run as scripts/scripts/talos_script.py --suite chromez-e10s --add-option --webServer,localhost --branch-name Mozilla-Inbound --system-bits 64 --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Inbound
14:49:29 INFO - Dumping config to /builds/slave/test/logs/localconfig.json.
14:49:29 INFO - {'append_to_log': False,
14:49:29 INFO - 'base_work_dir': '/builds/slave/test',
14:49:29 INFO - 'blob_upload_branch': 'Mozilla-Inbound',
14:49:29 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt',
14:49:29 INFO - 'branch': 'Mozilla-Inbound',
14:49:29 INFO - 'buildbot_json_path': 'buildprops.json',
14:49:29 INFO - 'config_files': ('talos/mac_config.py',),
14:49:29 INFO - 'default_actions': ('clobber',
14:49:29 INFO - 'read-buildbot-config',
14:49:29 INFO - 'download-and-extract',
14:49:29 INFO - 'populate-webroot',
14:49:29 INFO - 'create-virtualenv',
14:49:29 INFO - 'install',
14:49:29 INFO - 'run-tests'),
14:49:29 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',),
14:49:29 INFO - 'download_minidump_stackwalk': True,
14:49:29 INFO - 'download_symbols': 'ondemand',
14:49:29 INFO - 'exes': {'python': '/tools/buildbot/bin/python',
14:49:29 INFO - 'tooltool.py': '/tools/tooltool.py',
14:49:29 INFO - 'virtualenv': ('/tools/buildbot/bin/python',
14:49:29 INFO - '/tools/misc-python/virtualenv.py')},
14:49:29 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub',
14:49:29 INFO - 'http://pypi.pub.build.mozilla.org/pub'),
14:49:29 INFO - 'installer_path': 'installer.exe',
14:49:29 INFO - 'log_level': 'info',
14:49:29 INFO - 'log_name': 'talos',
14:49:29 INFO - 'log_to_console': True,
14:49:29 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk',
14:49:29 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest',
14:49:29 INFO - 'opt_config_files': (),
14:49:29 INFO - 'pip_index': False,
14:49:29 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'),
14:49:29 INFO - 'cmd': ('bash',
14:49:29 INFO - '-c',
14:49:29 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'),
14:49:29 INFO - 'enabled': True,
14:49:29 INFO - 'halt_on_failure': False,
14:49:29 INFO - 'name': 'check_screen_resolution'},),
14:49:29 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'),
14:49:29 INFO - 'cmd': ('bash',
14:49:29 INFO - '-c',
14:49:29 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'),
14:49:29 INFO - 'enabled': True,
14:49:29 INFO - 'halt_on_failure': False,
14:49:29 INFO - 'name': 'check_screen_resolution'},),
14:49:29 INFO - 'run_cmd_checks_enabled': True,
14:49:29 INFO - 'sps_profile': False,
14:49:29 INFO - 'sps_profile_interval': 0,
14:49:29 INFO - 'suite': 'chromez-e10s',
14:49:29 INFO - 'system_bits': '64',
14:49:29 INFO - 'talos_extra_options': ('--webServer', 'localhost'),
14:49:29 INFO - 'title': 't-yosemite-r5-0067',
14:49:29 INFO - 'tooltool_cache': '/builds/tooltool_cache',
14:49:29 INFO - 'use_talos_json': True,
14:49:29 INFO - 'virtualenv_path': '/builds/slave/test/build/venv',
14:49:29 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None},
14:49:29 INFO - 'work_dir': 'build'}
14:49:29 INFO - #####
14:49:29 INFO - ##### Running clobber step.
14:49:29 INFO - #####
14:49:29 INFO - Running pre-action listener: _resource_record_pre_action
14:49:29 INFO - Running main action method: clobber
14:49:29 INFO - rmtree: /builds/slave/test/build
14:49:29 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1
14:49:31 INFO - Running post-action listener: _resource_record_post_action
14:49:31 INFO - #####
14:49:31 INFO - ##### Running read-buildbot-config step.
14:49:31 INFO - #####
14:49:31 INFO - Running pre-action listener: _resource_record_pre_action
14:49:31 INFO - Running main action method: read_buildbot_config
14:49:31 INFO - Using buildbot properties:
14:49:31 INFO - {
14:49:31 INFO - "properties": {
14:49:31 INFO - "buildnumber": 775,
14:49:31 INFO - "product": "firefox",
14:49:31 INFO - "script_repo_revision": "production",
14:49:31 INFO - "builddir": "mozilla-inbound_yosemite_test-chromez-e10s",
14:49:31 INFO - "repository": "",
14:49:31 INFO - "buildername": "Rev5 MacOSX Yosemite 10.10 mozilla-inbound talos chromez-e10s",
14:49:31 INFO - "buildid": "20151109132133",
14:49:31 INFO - "slavename": "t-yosemite-r5-0067",
14:49:31 INFO - "pgo_build": "False",
14:49:31 INFO - "basedir": "/builds/slave/test",
14:49:31 INFO - "project": "",
14:49:31 INFO - "platform": "yosemite",
14:49:31 INFO - "master": "http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/",
14:49:31 INFO - "slavebuilddir": "test",
14:49:31 INFO - "scheduler": "tests-mozilla-inbound-macosx64-talos",
14:49:31 INFO - "branch": "mozilla-inbound",
14:49:31 INFO - "repo_path": "integration/mozilla-inbound",
14:49:31 INFO - "stage_platform": "macosx64",
14:49:31 INFO - "builduid": "60bca578163d4d938e68697233565017",
14:49:31 INFO - "revision": "6c6d04b021ddfdfe1ce35f5b6939ef80deda3f3f"
14:49:31 INFO - },
14:49:31 INFO - "sourcestamp": {
14:49:31 INFO - "repository": "",
14:49:31 INFO - "hasPatch": false,
14:49:31 INFO - "project": "",
14:49:31 INFO - "branch": "mozilla-inbound-macosx64-talos",
14:49:31 INFO - "changes": [
14:49:31 INFO - {
14:49:31 INFO - "category": null,
14:49:31 INFO - "files": [
14:49:31 INFO - {
14:49:31 INFO - "url": null,
14:49:31 INFO - "name": "https://queue.taskcluster.net/v1/task/r8Tnl8SQSDG-UdPkRjQqQQ/artifacts/public/build/firefox-45.0a1.en-US.mac.dmg"
14:49:31 INFO - }
14:49:31 INFO - ],
14:49:31 INFO - "repository": "",
14:49:31 INFO - "rev": "6c6d04b021ddfdfe1ce35f5b6939ef80deda3f3f",
14:49:31 INFO - "who": "rgiles@mozilla.com",
14:49:31 INFO - "when": 1447109353,
14:49:31 INFO - "number": 6639384,
14:49:31 INFO - "comments": "Bug 1222145 - Bump maximum video size to 8k. r=jya\n\nYouTube and WebVR have been experimenting with 8k video for\nimmersive applications, where you need more than 4k resolution\neven on a mid-resolution display because you're not looking\nat the whole scene simultaneously.\n\nWe were rejecting video frames larger than 4000x3000,\nor 16k in any one dimension, to limit resource exhaustion\nattacks. Bump this to accept 8k video now that there's\na demand for it.",
14:49:31 INFO - "project": "",
14:49:31 INFO - "at": "Mon 09 Nov 2015 14:49:13",
14:49:31 INFO - "branch": "mozilla-inbound-macosx64-talos",
14:49:31 INFO - "revlink": "",
14:49:31 INFO - "properties": [
14:49:31 INFO - [
14:49:31 INFO - "buildid",
14:49:31 INFO - "20151109132133",
14:49:31 INFO - "Change"
14:49:31 INFO - ],
14:49:31 INFO - [
14:49:31 INFO - "builduid",
14:49:31 INFO - "60bca578163d4d938e68697233565017",
14:49:31 INFO - "Change"
14:49:31 INFO - ],
14:49:31 INFO - [
14:49:31 INFO - "pgo_build",
14:49:31 INFO - "False",
14:49:31 INFO - "Change"
14:49:31 INFO - ]
14:49:31 INFO - ],
14:49:31 INFO - "revision": "6c6d04b021ddfdfe1ce35f5b6939ef80deda3f3f"
14:49:31 INFO - }
14:49:31 INFO - ],
14:49:31 INFO - "revision": "6c6d04b021ddfdfe1ce35f5b6939ef80deda3f3f"
14:49:31 INFO - }
14:49:31 INFO - }
14:49:31 INFO - Found installer url https://queue.taskcluster.net/v1/task/r8Tnl8SQSDG-UdPkRjQqQQ/artifacts/public/build/firefox-45.0a1.en-US.mac.dmg.
14:49:31 INFO - Running post-action listener: _resource_record_post_action
14:49:31 INFO - #####
14:49:31 INFO - ##### Running download-and-extract step.
14:49:31 INFO - #####
14:49:31 INFO - Running pre-action listener: _resource_record_pre_action
14:49:31 INFO - Running main action method: download_and_extract
14:49:31 INFO - mkdir: /builds/slave/test/build/tests
14:49: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')]}
14:49:31 INFO - https://queue.taskcluster.net/v1/task/r8Tnl8SQSDG-UdPkRjQqQQ/artifacts/public/build/test_packages.json matches https://queue.taskcluster.net
14:49:31 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/r8Tnl8SQSDG-UdPkRjQqQQ/artifacts/public/build/test_packages.json
14:49:31 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/r8Tnl8SQSDG-UdPkRjQqQQ/artifacts/public/build/test_packages.json
14:49:31 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/r8Tnl8SQSDG-UdPkRjQqQQ/artifacts/public/build/test_packages.json to /builds/slave/test/build/test_packages.json
14:49:31 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/r8Tnl8SQSDG-UdPkRjQqQQ/artifacts/public/build/test_packages.json', 'file_name': '/builds/slave/test/build/test_packages.json'}, attempt #1
14:49:33 INFO - Downloaded 1149 bytes.
14:49:33 INFO - Reading from file /builds/slave/test/build/test_packages.json
14:49:33 INFO - Using the following test package requirements:
14:49:33 INFO - {u'common': [u'firefox-45.0a1.en-US.mac.common.tests.zip'],
14:49:33 INFO - u'cppunittest': [u'firefox-45.0a1.en-US.mac.common.tests.zip',
14:49:33 INFO - u'firefox-45.0a1.en-US.mac.cppunittest.tests.zip'],
14:49:33 INFO - u'jittest': [u'firefox-45.0a1.en-US.mac.common.tests.zip',
14:49:33 INFO - u'jsshell-mac.zip'],
14:49:33 INFO - u'mochitest': [u'firefox-45.0a1.en-US.mac.common.tests.zip',
14:49:33 INFO - u'firefox-45.0a1.en-US.mac.mochitest.tests.zip'],
14:49:33 INFO - u'mozbase': [u'firefox-45.0a1.en-US.mac.common.tests.zip'],
14:49:33 INFO - u'reftest': [u'firefox-45.0a1.en-US.mac.common.tests.zip',
14:49:33 INFO - u'firefox-45.0a1.en-US.mac.reftest.tests.zip'],
14:49:33 INFO - u'talos': [u'firefox-45.0a1.en-US.mac.common.tests.zip',
14:49:33 INFO - u'firefox-45.0a1.en-US.mac.talos.tests.zip'],
14:49:33 INFO - u'web-platform': [u'firefox-45.0a1.en-US.mac.common.tests.zip',
14:49:33 INFO - u'firefox-45.0a1.en-US.mac.web-platform.tests.zip'],
14:49:33 INFO - u'webapprt': [u'firefox-45.0a1.en-US.mac.common.tests.zip'],
14:49:33 INFO - u'xpcshell': [u'firefox-45.0a1.en-US.mac.common.tests.zip',
14:49:33 INFO - u'firefox-45.0a1.en-US.mac.xpcshell.tests.zip']}
14:49:33 INFO - Downloading packages: [u'firefox-45.0a1.en-US.mac.common.tests.zip'] for test suite category: common
14:49:33 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')]}
14:49:33 INFO - https://queue.taskcluster.net/v1/task/r8Tnl8SQSDG-UdPkRjQqQQ/artifacts/public/build/firefox-45.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net
14:49:33 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/r8Tnl8SQSDG-UdPkRjQqQQ/artifacts/public/build/firefox-45.0a1.en-US.mac.common.tests.zip
14:49:33 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/r8Tnl8SQSDG-UdPkRjQqQQ/artifacts/public/build/firefox-45.0a1.en-US.mac.common.tests.zip
14:49:33 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/r8Tnl8SQSDG-UdPkRjQqQQ/artifacts/public/build/firefox-45.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-45.0a1.en-US.mac.common.tests.zip
14:49:33 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/r8Tnl8SQSDG-UdPkRjQqQQ/artifacts/public/build/firefox-45.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-45.0a1.en-US.mac.common.tests.zip'}, attempt #1
14:49:55 INFO - Downloaded 17436215 bytes.
14:49:55 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-45.0a1.en-US.mac.common.tests.zip'] in /builds/slave/test/build/tests
14:49:55 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-45.0a1.en-US.mac.common.tests.zip
14:49:55 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-45.0a1.en-US.mac.common.tests.zip'] with output_timeout 1760
14:50:00 INFO - Return code: 0
14:50:00 INFO - Downloading packages: [u'firefox-45.0a1.en-US.mac.common.tests.zip', u'firefox-45.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos
14:50:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]}
14:50:00 INFO - https://queue.taskcluster.net/v1/task/r8Tnl8SQSDG-UdPkRjQqQQ/artifacts/public/build/firefox-45.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net
14:50:00 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/r8Tnl8SQSDG-UdPkRjQqQQ/artifacts/public/build/firefox-45.0a1.en-US.mac.common.tests.zip
14:50:00 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/r8Tnl8SQSDG-UdPkRjQqQQ/artifacts/public/build/firefox-45.0a1.en-US.mac.common.tests.zip
14:50:00 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/r8Tnl8SQSDG-UdPkRjQqQQ/artifacts/public/build/firefox-45.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-45.0a1.en-US.mac.common.tests.zip
14:50:00 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/r8Tnl8SQSDG-UdPkRjQqQQ/artifacts/public/build/firefox-45.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-45.0a1.en-US.mac.common.tests.zip'}, attempt #1
14:50:04 INFO - Downloaded 17436215 bytes.
14:50:04 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-45.0a1.en-US.mac.common.tests.zip'] in /builds/slave/test/build/tests
14:50:04 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-45.0a1.en-US.mac.common.tests.zip
14:50:04 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-45.0a1.en-US.mac.common.tests.zip'] with output_timeout 1760
14:50:10 INFO - Return code: 0
14:50:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]}
14:50:10 INFO - https://queue.taskcluster.net/v1/task/r8Tnl8SQSDG-UdPkRjQqQQ/artifacts/public/build/firefox-45.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net
14:50:10 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/r8Tnl8SQSDG-UdPkRjQqQQ/artifacts/public/build/firefox-45.0a1.en-US.mac.talos.tests.zip
14:50:10 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/r8Tnl8SQSDG-UdPkRjQqQQ/artifacts/public/build/firefox-45.0a1.en-US.mac.talos.tests.zip
14:50:10 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/r8Tnl8SQSDG-UdPkRjQqQQ/artifacts/public/build/firefox-45.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-45.0a1.en-US.mac.talos.tests.zip
14:50:10 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/r8Tnl8SQSDG-UdPkRjQqQQ/artifacts/public/build/firefox-45.0a1.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-45.0a1.en-US.mac.talos.tests.zip'}, attempt #1
14:50:23 INFO - Downloaded 11131618 bytes.
14:50:23 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-45.0a1.en-US.mac.talos.tests.zip'] in /builds/slave/test/build/tests
14:50:23 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-45.0a1.en-US.mac.talos.tests.zip
14:50:23 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-45.0a1.en-US.mac.talos.tests.zip'] with output_timeout 1760
14:50:23 INFO - Return code: 0
14:50:23 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')]}
14:50:23 INFO - https://queue.taskcluster.net/v1/task/r8Tnl8SQSDG-UdPkRjQqQQ/artifacts/public/build/firefox-45.0a1.en-US.mac.dmg matches https://queue.taskcluster.net
14:50:23 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/r8Tnl8SQSDG-UdPkRjQqQQ/artifacts/public/build/firefox-45.0a1.en-US.mac.dmg
14:50:23 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/r8Tnl8SQSDG-UdPkRjQqQQ/artifacts/public/build/firefox-45.0a1.en-US.mac.dmg
14:50:23 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/r8Tnl8SQSDG-UdPkRjQqQQ/artifacts/public/build/firefox-45.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-45.0a1.en-US.mac.dmg
14:50:23 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/r8Tnl8SQSDG-UdPkRjQqQQ/artifacts/public/build/firefox-45.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-45.0a1.en-US.mac.dmg'}, attempt #1
14:52:42 INFO - Downloaded 108653199 bytes.
14:52:42 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/r8Tnl8SQSDG-UdPkRjQqQQ/artifacts/public/build/firefox-45.0a1.en-US.mac.dmg
14:52:42 INFO - mkdir: /builds/slave/test/properties
14:52:42 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url
14:52:42 INFO - Writing to file /builds/slave/test/properties/build_url
14:52:42 INFO - Contents:
14:52:42 INFO - build_url:https://queue.taskcluster.net/v1/task/r8Tnl8SQSDG-UdPkRjQqQQ/artifacts/public/build/firefox-45.0a1.en-US.mac.dmg
14:52:42 INFO - Running post-action listener: _resource_record_post_action
14:52:42 INFO - Running post-action listener: set_extra_try_arguments
14:52:42 INFO - #####
14:52:42 INFO - ##### Running populate-webroot step.
14:52:42 INFO - #####
14:52:42 INFO - Running pre-action listener: _resource_record_pre_action
14:52:42 INFO - Running main action method: populate_webroot
14:52:42 INFO - Guessing talos json url...
14:52:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]}
14:52:42 INFO - https://queue.taskcluster.net/v1/task/r8Tnl8SQSDG-UdPkRjQqQQ/artifacts/public/build/firefox-45.0a1.en-US.mac.txt matches https://queue.taskcluster.net
14:52:42 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/r8Tnl8SQSDG-UdPkRjQqQQ/artifacts/public/build/firefox-45.0a1.en-US.mac.txt
14:52:42 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/r8Tnl8SQSDG-UdPkRjQqQQ/artifacts/public/build/firefox-45.0a1.en-US.mac.txt
14:52:42 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/r8Tnl8SQSDG-UdPkRjQqQQ/artifacts/public/build/firefox-45.0a1.en-US.mac.txt to /builds/slave/test/build/firefox-45.0a1.en-US.mac.txt
14:52:42 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/r8Tnl8SQSDG-UdPkRjQqQQ/artifacts/public/build/firefox-45.0a1.en-US.mac.txt', 'file_name': '/builds/slave/test/build/firefox-45.0a1.en-US.mac.txt'}, attempt #1
14:52:43 INFO - Downloaded 111 bytes.
14:52:43 INFO - Reading from file /builds/slave/test/build/firefox-45.0a1.en-US.mac.txt
14:52:43 INFO - Contents:
14:52:43 INFO - 20151109132133
14:52:43 INFO - https://hg.mozilla.org/integration/mozilla-inbound/rev/6c6d04b021ddfdfe1ce35f5b6939ef80deda3f3f
14:52:43 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')]}
14:52:43 INFO - trying https://hg.mozilla.org/integration/mozilla-inbound/raw-file/6c6d04b021/testing/talos/talos.json
14:52:43 INFO - Downloading https://hg.mozilla.org/integration/mozilla-inbound/raw-file/6c6d04b021/testing/talos/talos.json to /builds/slave/test/build/talos.json
14:52:43 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://hg.mozilla.org/integration/mozilla-inbound/raw-file/6c6d04b021/testing/talos/talos.json', 'file_name': '/builds/slave/test/build/talos.json'}, attempt #1
14:52:43 INFO - Downloaded 4206 bytes.
14:52:43 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']},
14:52:43 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']},
14:52:43 INFO - 'remote-trobocheck2': {'talos_options': ['--fennecIDs',
14:52:43 INFO - '../fennec_ids.txt'],
14:52:43 INFO - 'tests': ['tcheck2']},
14:52:43 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome',
14:52:43 INFO - '--tppagecycles',
14:52:43 INFO - '7'],
14:52:43 INFO - 'tests': ['tsvgm']}},
14:52:43 INFO - 'suites': {'chromez': {'tests': ['tresize', 'tcanvasmark']},
14:52:43 INFO - 'chromez-e10s': {'talos_options': ['--e10s'],
14:52:43 INFO - 'tests': ['tresize', 'tcanvasmark']},
14:52:43 INFO - 'dromaeojs': {'tests': ['dromaeo_css', 'kraken', 'v8_7']},
14:52:43 INFO - 'dromaeojs-e10s': {'talos_options': ['--e10s'],
14:52:43 INFO - 'tests': ['dromaeo_css', 'kraken', 'v8_7']},
14:52:43 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip',
14:52:43 INFO - 'tests': ['tp5o_scroll', 'glterrain']},
14:52:43 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip',
14:52:43 INFO - 'talos_options': ['--e10s'],
14:52:43 INFO - 'tests': ['tp5o_scroll', 'glterrain']},
14:52:43 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip',
14:52:43 INFO - 'tests': ['damp', 'tps']},
14:52:43 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip',
14:52:43 INFO - 'talos_options': ['--e10s'],
14:52:43 INFO - 'tests': ['damp', 'tps']},
14:52:43 INFO - 'g3': {'tests': ['dromaeo_dom']},
14:52:43 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']},
14:52:43 INFO - 'other': {'tests': ['a11yr',
14:52:43 INFO - 'ts_paint',
14:52:43 INFO - 'tpaint',
14:52:43 INFO - 'sessionrestore',
14:52:43 INFO - 'sessionrestore_no_auto_restore']},
14:52:43 INFO - 'other-e10s': {'talos_options': ['--e10s'],
14:52:43 INFO - 'tests': ['a11yr',
14:52:43 INFO - 'ts_paint',
14:52:43 INFO - 'tpaint',
14:52:43 INFO - 'sessionrestore',
14:52:43 INFO - 'sessionrestore_no_auto_restore']},
14:52:43 INFO - 'other-e10s_l64': {'talos_options': ['--e10s'],
14:52:43 INFO - 'tests': ['a11yr',
14:52:43 INFO - 'ts_paint',
14:52:43 INFO - 'tpaint',
14:52:43 INFO - 'sessionrestore',
14:52:43 INFO - 'sessionrestore_no_auto_restore']},
14:52:43 INFO - 'other-e10s_nol64': {'talos_options': ['--e10s'],
14:52:43 INFO - 'tests': ['a11yr',
14:52:43 INFO - 'ts_paint',
14:52:43 INFO - 'tpaint',
14:52:43 INFO - 'sessionrestore',
14:52:43 INFO - 'sessionrestore_no_auto_restore']},
14:52:43 INFO - 'other_l64': {'tests': ['a11yr',
14:52:43 INFO - 'ts_paint',
14:52:43 INFO - 'tpaint',
14:52:43 INFO - 'sessionrestore',
14:52:43 INFO - 'sessionrestore_no_auto_restore']},
14:52:43 INFO - 'other_nol64': {'tests': ['a11yr',
14:52:43 INFO - 'ts_paint',
14:52:43 INFO - 'tpaint',
14:52:43 INFO - 'sessionrestore',
14:52:43 INFO - 'sessionrestore_no_auto_restore']},
14:52:43 INFO - 'svgr': {'tests': ['tsvgx',
14:52:43 INFO - 'tsvgr_opacity',
14:52:43 INFO - 'tart',
14:52:43 INFO - 'tscrollx',
14:52:43 INFO - 'cart']},
14:52:43 INFO - 'svgr-e10s': {'talos_options': ['--e10s'],
14:52:43 INFO - 'tests': ['tsvgx',
14:52:43 INFO - 'tsvgr_opacity',
14:52:43 INFO - 'tart',
14:52:43 INFO - 'tscrollx',
14:52:43 INFO - 'cart']},
14:52:43 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip',
14:52:43 INFO - 'tests': ['tp5o']},
14:52:43 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip',
14:52:43 INFO - 'talos_options': ['--e10s'],
14:52:43 INFO - 'tests': ['tp5o']},
14:52:43 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip',
14:52:43 INFO - 'talos_options': ['--xperf_path',
14:52:43 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'],
14:52:43 INFO - 'tests': ['tp5n']},
14:52:43 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip',
14:52:43 INFO - 'talos_options': ['--e10s',
14:52:43 INFO - '--xperf_path',
14:52:43 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'],
14:52:43 INFO - 'tests': ['tp5n']}},
14:52:43 INFO - 'talos.zip': {'path': '',
14:52:43 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}}
14:52:43 INFO - Running post-action listener: _resource_record_post_action
14:52:43 INFO - #####
14:52:43 INFO - ##### Running create-virtualenv step.
14:52:43 INFO - #####
14:52:43 INFO - Running pre-action listener: _resource_record_pre_action
14:52:43 INFO - Running main action method: create_virtualenv
14:52:43 INFO - Creating virtualenv /builds/slave/test/build/venv
14:52:43 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
14:52:43 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv
14:52:44 INFO - The --no-site-packages flag is deprecated; it is now the default behavior.
14:52:44 INFO - Using real prefix '/tools/python27'
14:52:44 INFO - New python executable in /builds/slave/test/build/venv/bin/python
14:52:44 INFO - Installing distribute.............................................................................................................................................................................................done.
14:52:48 INFO - Installing pip.................done.
14:52:48 INFO - Return code: 0
14:52:48 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv
14:52:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]}
14:52:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
14:52:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub
14:52:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]}
14:52:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
14:52:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub
14:52:48 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test/build/venv/cache', '--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 0x10e965a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e9d2228>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc0e34d6af0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e91a9f0>, '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 0x10e679cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e8e4990>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'HOME': '/Users/cltbld', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.IE2EwGFq5Q/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.wSbHOtz3pt/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0d/wgdyxjyj2r96yt9mnwtg9t0m00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0x0:0x0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1
14:52:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test/build/venv/cache', '--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
14:52:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --download-cache /builds/slave/test/build/venv/cache --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
14:52:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.IE2EwGFq5Q/Render',
14:52:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared',
14:52:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared',
14:52:48 INFO - 'HOME': '/Users/cltbld',
14:52:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true',
14:52:48 INFO - 'LOGNAME': 'cltbld',
14:52:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
14:52:48 INFO - 'MOZ_NO_REMOTE': '1',
14:52:48 INFO - 'NO_EM_RESTART': '1',
14:52:48 INFO - 'PAGER': '/bin/cat',
14:52:48 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11',
14:52:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
14:52:48 INFO - 'PWD': '/builds/slave/test',
14:52:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg',
14:52:48 INFO - 'SHELL': '/bin/bash',
14:52:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.wSbHOtz3pt/Listeners',
14:52:48 INFO - 'TMPDIR': '/var/folders/0d/wgdyxjyj2r96yt9mnwtg9t0m00000w/T/',
14:52:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log',
14:52:48 INFO - 'USER': 'cltbld',
14:52:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no',
14:52:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7',
14:52:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
14:52:48 INFO - 'XPC_FLAGS': '0x0',
14:52:48 INFO - 'XPC_SERVICE_NAME': '0',
14:52:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0x0:0x0'}
14:52:49 INFO - Ignoring indexes: https://pypi.python.org/simple/
14:52:49 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
14:52:49 INFO - Cleaning up...
14:52:49 INFO - Return code: 0
14:52:49 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv
14:52:49 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')]}
14:52:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
14:52:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub
14:52:49 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')]}
14:52:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
14:52:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub
14:52:49 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test/build/venv/cache', '--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 0x10e965a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e9d2228>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc0e34d6af0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e91a9f0>, '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 0x10e679cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e8e4990>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'HOME': '/Users/cltbld', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.IE2EwGFq5Q/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.wSbHOtz3pt/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0d/wgdyxjyj2r96yt9mnwtg9t0m00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0x0:0x0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1
14:52:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test/build/venv/cache', '--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
14:52:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --download-cache /builds/slave/test/build/venv/cache --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
14:52:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.IE2EwGFq5Q/Render',
14:52:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared',
14:52:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared',
14:52:49 INFO - 'HOME': '/Users/cltbld',
14:52:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true',
14:52:49 INFO - 'LOGNAME': 'cltbld',
14:52:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
14:52:49 INFO - 'MOZ_NO_REMOTE': '1',
14:52:49 INFO - 'NO_EM_RESTART': '1',
14:52:49 INFO - 'PAGER': '/bin/cat',
14:52:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11',
14:52:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
14:52:49 INFO - 'PWD': '/builds/slave/test',
14:52:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg',
14:52:49 INFO - 'SHELL': '/bin/bash',
14:52:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.wSbHOtz3pt/Listeners',
14:52:49 INFO - 'TMPDIR': '/var/folders/0d/wgdyxjyj2r96yt9mnwtg9t0m00000w/T/',
14:52:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log',
14:52:49 INFO - 'USER': 'cltbld',
14:52:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no',
14:52:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7',
14:52:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
14:52:49 INFO - 'XPC_FLAGS': '0x0',
14:52:49 INFO - 'XPC_SERVICE_NAME': '0',
14:52:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0x0:0x0'}
14:52:49 INFO - Ignoring indexes: https://pypi.python.org/simple/
14:52:49 INFO - Downloading/unpacking psutil>=0.7.1
14:52:49 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
14:52:49 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
14:52:49 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
14:52:49 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
14:52:49 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
14:52:49 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
14:52:52 INFO - Creating supposed download cache at /builds/slave/test/build/venv/cache
14:52:52 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fpsutil-3.1.1.tar.gz
14:52:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil
14:52:52 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build'
14:52:52 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects
...
14:52:52 INFO - Installing collected packages: psutil
14:52:52 INFO - Running setup.py install for psutil
14:52:52 INFO - building 'psutil._psutil_osx' extension
14:52:52 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
14:52:52 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
14:52:52 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
14:52:52 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
14:52:52 INFO - building 'psutil._psutil_posix' extension
14:52:52 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
14:52:53 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration]
14:52:53 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr);
14:52:53 INFO - ^
14:52:53 INFO - 1 warning generated.
14:52:53 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
14:52:53 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build'
14:52:53 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ...
14:52:53 INFO - Successfully installed psutil
14:52:53 INFO - Cleaning up...
14:52:53 INFO - Return code: 0
14:52:53 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv
14:52:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]}
14:52:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
14:52:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub
14:52:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]}
14:52:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
14:52:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub
14:52:53 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test/build/venv/cache', '--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 0x10e965a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e9d2228>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc0e34d6af0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e91a9f0>, '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 0x10e679cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e8e4990>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'HOME': '/Users/cltbld', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.IE2EwGFq5Q/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.wSbHOtz3pt/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0d/wgdyxjyj2r96yt9mnwtg9t0m00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0x0:0x0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1
14:52:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test/build/venv/cache', '--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
14:52:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --download-cache /builds/slave/test/build/venv/cache --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
14:52:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.IE2EwGFq5Q/Render',
14:52:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared',
14:52:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared',
14:52:53 INFO - 'HOME': '/Users/cltbld',
14:52:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true',
14:52:53 INFO - 'LOGNAME': 'cltbld',
14:52:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
14:52:53 INFO - 'MOZ_NO_REMOTE': '1',
14:52:53 INFO - 'NO_EM_RESTART': '1',
14:52:53 INFO - 'PAGER': '/bin/cat',
14:52:53 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11',
14:52:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
14:52:53 INFO - 'PWD': '/builds/slave/test',
14:52:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg',
14:52:53 INFO - 'SHELL': '/bin/bash',
14:52:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.wSbHOtz3pt/Listeners',
14:52:53 INFO - 'TMPDIR': '/var/folders/0d/wgdyxjyj2r96yt9mnwtg9t0m00000w/T/',
14:52:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log',
14:52:53 INFO - 'USER': 'cltbld',
14:52:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no',
14:52:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7',
14:52:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
14:52:53 INFO - 'XPC_FLAGS': '0x0',
14:52:53 INFO - 'XPC_SERVICE_NAME': '0',
14:52:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0x0:0x0'}
14:52:53 INFO - Ignoring indexes: https://pypi.python.org/simple/
14:52:53 INFO - Downloading/unpacking mozsystemmonitor==0.0.0
14:52:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available
14:52:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available
14:52:53 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available
14:52:53 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available
14:52:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available
14:52:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available
14:52:56 INFO - Downloading mozsystemmonitor-0.0.tar.gz
14:52:56 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fmozsystemmonitor-0.0.tar.gz
14:52:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor
14:52:56 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0)
14:52:56 INFO - Installing collected packages: mozsystemmonitor
14:52:56 INFO - Running setup.py install for mozsystemmonitor
14:52:56 INFO - Successfully installed mozsystemmonitor
14:52:56 INFO - Cleaning up...
14:52:56 INFO - Return code: 0
14:52:56 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv
14: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')]}
14:52:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
14:52:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub
14: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')]}
14:52:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
14:52:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub
14:52:56 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test/build/venv/cache', '--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 0x10e965a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e9d2228>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc0e34d6af0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e91a9f0>, '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 0x10e679cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e8e4990>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'HOME': '/Users/cltbld', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.IE2EwGFq5Q/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.wSbHOtz3pt/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0d/wgdyxjyj2r96yt9mnwtg9t0m00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0x0:0x0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1
14:52:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test/build/venv/cache', '--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
14:52:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --download-cache /builds/slave/test/build/venv/cache --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
14:52:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.IE2EwGFq5Q/Render',
14:52:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared',
14:52:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared',
14:52:56 INFO - 'HOME': '/Users/cltbld',
14:52:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true',
14:52:56 INFO - 'LOGNAME': 'cltbld',
14:52:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
14:52:56 INFO - 'MOZ_NO_REMOTE': '1',
14:52:56 INFO - 'NO_EM_RESTART': '1',
14:52:56 INFO - 'PAGER': '/bin/cat',
14:52:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11',
14:52:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
14:52:56 INFO - 'PWD': '/builds/slave/test',
14:52:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg',
14:52:56 INFO - 'SHELL': '/bin/bash',
14:52:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.wSbHOtz3pt/Listeners',
14:52:56 INFO - 'TMPDIR': '/var/folders/0d/wgdyxjyj2r96yt9mnwtg9t0m00000w/T/',
14:52:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log',
14:52:56 INFO - 'USER': 'cltbld',
14:52:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no',
14:52:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7',
14:52:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
14:52:56 INFO - 'XPC_FLAGS': '0x0',
14:52:56 INFO - 'XPC_SERVICE_NAME': '0',
14:52:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0x0:0x0'}
14:52:57 INFO - Ignoring indexes: https://pypi.python.org/simple/
14:52:57 INFO - Downloading/unpacking blobuploader==1.2.4
14:52:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available
14:52:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available
14:52:57 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available
14:52:57 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available
14:52:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available
14:52:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available
14:52:59 INFO - Downloading blobuploader-1.2.4.tar.gz
14:52:59 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fblobuploader-1.2.4.tar.gz
14:52:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader
14:52:59 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4)
14:52:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available
14:52:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available
14:52:59 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available
14:52:59 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available
14:52:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available
14:52:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available
14:53:00 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Frequests-1.2.3.tar.gz
14:53:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests
14:53:00 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4)
14:53:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available
14:53:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available
14:53:00 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available
14:53:00 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available
14:53:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available
14:53:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available
14:53:01 INFO - Downloading docopt-0.6.1.tar.gz
14:53:01 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fdocopt-0.6.1.tar.gz
14:53:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt
14:53:01 INFO - Installing collected packages: blobuploader, requests, docopt
14:53:01 INFO - Running setup.py install for blobuploader
14:53:01 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775
14:53:01 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775
14:53:01 INFO - Running setup.py install for requests
14:53:02 INFO - Running setup.py install for docopt
14:53:02 INFO - Successfully installed blobuploader requests docopt
14:53:02 INFO - Cleaning up...
14:53:02 INFO - Return code: 0
14:53:02 INFO - Installing None into virtualenv /builds/slave/test/build/venv
14: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')]}
14:53:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
14:53:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub
14: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')]}
14:53:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
14:53:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub
14:53:02 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--download-cache', '/builds/slave/test/build/venv/cache', '--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 0x10e965a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e9d2228>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc0e34d6af0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e91a9f0>, '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 0x10e679cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e8e4990>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'HOME': '/Users/cltbld', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.IE2EwGFq5Q/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.wSbHOtz3pt/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0d/wgdyxjyj2r96yt9mnwtg9t0m00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0x0:0x0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1
14:53:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--download-cache', '/builds/slave/test/build/venv/cache', '--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
14:53:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --download-cache /builds/slave/test/build/venv/cache --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
14:53:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.IE2EwGFq5Q/Render',
14:53:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared',
14:53:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared',
14:53:02 INFO - 'HOME': '/Users/cltbld',
14:53:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true',
14:53:02 INFO - 'LOGNAME': 'cltbld',
14:53:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
14:53:02 INFO - 'MOZ_NO_REMOTE': '1',
14:53:02 INFO - 'NO_EM_RESTART': '1',
14:53:02 INFO - 'PAGER': '/bin/cat',
14:53:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11',
14:53:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
14:53:02 INFO - 'PWD': '/builds/slave/test',
14:53:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg',
14:53:02 INFO - 'SHELL': '/bin/bash',
14:53:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.wSbHOtz3pt/Listeners',
14:53:02 INFO - 'TMPDIR': '/var/folders/0d/wgdyxjyj2r96yt9mnwtg9t0m00000w/T/',
14:53:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log',
14:53:02 INFO - 'USER': 'cltbld',
14:53:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no',
14:53:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7',
14:53:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
14:53:02 INFO - 'XPC_FLAGS': '0x0',
14:53:02 INFO - 'XPC_SERVICE_NAME': '0',
14:53:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0x0:0x0'}
14:53:02 INFO - Ignoring indexes: https://pypi.python.org/simple/
14:53:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser
14:53:02 INFO - Running setup.py (path:/var/folders/0d/wgdyxjyj2r96yt9mnwtg9t0m00000w/T/pip-XjNT2_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser
14:53:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash
14:53:02 INFO - Running setup.py (path:/var/folders/0d/wgdyxjyj2r96yt9mnwtg9t0m00000w/T/pip-NdGibF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash
14:53:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug
14:53:03 INFO - Running setup.py (path:/var/folders/0d/wgdyxjyj2r96yt9mnwtg9t0m00000w/T/pip-RrxXjZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug
14:53:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice
14:53:03 INFO - Running setup.py (path:/var/folders/0d/wgdyxjyj2r96yt9mnwtg9t0m00000w/T/pip-KbcNGC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice
14:53:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile
14:53:03 INFO - Running setup.py (path:/var/folders/0d/wgdyxjyj2r96yt9mnwtg9t0m00000w/T/pip-H9NzSl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile
14:53:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd
14:53:03 INFO - Running setup.py (path:/var/folders/0d/wgdyxjyj2r96yt9mnwtg9t0m00000w/T/pip-PQYYrt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd
14:53:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo
14:53:03 INFO - Running setup.py (path:/var/folders/0d/wgdyxjyj2r96yt9mnwtg9t0m00000w/T/pip-rmA8Gi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo
14:53:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall
14:53:03 INFO - Running setup.py (path:/var/folders/0d/wgdyxjyj2r96yt9mnwtg9t0m00000w/T/pip-uAh67o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall
14:53:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak
14:53:03 INFO - Running setup.py (path:/var/folders/0d/wgdyxjyj2r96yt9mnwtg9t0m00000w/T/pip-k_0vJL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak
14:53:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog
14:53:03 INFO - Running setup.py (path:/var/folders/0d/wgdyxjyj2r96yt9mnwtg9t0m00000w/T/pip-_x7ofZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog
14:53:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork
14:53:04 INFO - Running setup.py (path:/var/folders/0d/wgdyxjyj2r96yt9mnwtg9t0m00000w/T/pip-vmb72S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork
14:53:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess
14:53:04 INFO - Running setup.py (path:/var/folders/0d/wgdyxjyj2r96yt9mnwtg9t0m00000w/T/pip-6ZmaE_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess
14:53:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile
14:53:04 INFO - Running setup.py (path:/var/folders/0d/wgdyxjyj2r96yt9mnwtg9t0m00000w/T/pip-E98v28-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile
14:53:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner
14:53:04 INFO - Running setup.py (path:/var/folders/0d/wgdyxjyj2r96yt9mnwtg9t0m00000w/T/pip-XPprvw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner
14:53:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot
14:53:04 INFO - Running setup.py (path:/var/folders/0d/wgdyxjyj2r96yt9mnwtg9t0m00000w/T/pip-WSn9Wb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot
14:53:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest
14:53:04 INFO - Running setup.py (path:/var/folders/0d/wgdyxjyj2r96yt9mnwtg9t0m00000w/T/pip-9LadSX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest
14:53:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion
14:53:04 INFO - Running setup.py (path:/var/folders/0d/wgdyxjyj2r96yt9mnwtg9t0m00000w/T/pip-Csd2OA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion
14:53:05 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion
14:53:05 INFO - Running setup.py install for manifestparser
14:53:05 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin
14:53:05 INFO - Running setup.py install for mozcrash
14:53:05 INFO - Running setup.py install for mozdebug
14:53:05 INFO - Running setup.py install for mozdevice
14:53:05 INFO - Installing sutini script to /builds/slave/test/build/venv/bin
14:53:05 INFO - Installing dm script to /builds/slave/test/build/venv/bin
14:53:05 INFO - Running setup.py install for mozfile
14:53:06 INFO - Running setup.py install for mozhttpd
14:53:06 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin
14:53:06 INFO - Running setup.py install for mozinfo
14:53:06 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin
14:53:06 INFO - Running setup.py install for mozInstall
14:53:06 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin
14:53:06 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin
14:53:06 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin
14:53:06 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin
14:53:06 INFO - Running setup.py install for mozleak
14:53:06 INFO - Running setup.py install for mozlog
14:53:06 INFO - Installing structlog script to /builds/slave/test/build/venv/bin
14:53:06 INFO - Running setup.py install for moznetwork
14:53:07 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin
14:53:07 INFO - Running setup.py install for mozprocess
14:53:07 INFO - Running setup.py install for mozprofile
14:53:07 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin
14:53:07 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin
14:53:07 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin
14:53:07 INFO - Running setup.py install for mozrunner
14:53:07 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin
14:53:07 INFO - Running setup.py install for mozscreenshot
14:53:07 INFO - Running setup.py install for moztest
14:53:08 INFO - Running setup.py install for mozversion
14:53:08 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin
14:53:08 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion
14:53:08 INFO - Cleaning up...
14:53:08 INFO - Return code: 0
14:53:08 INFO - Installing None into virtualenv /builds/slave/test/build/venv
14:53:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]}
14:53:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
14:53:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub
14:53:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]}
14:53:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
14:53:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub
14:53:08 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test/build/venv/cache', '--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 0x10e965a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e9d2228>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc0e34d6af0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e91a9f0>, '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 0x10e679cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e8e4990>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'HOME': '/Users/cltbld', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.IE2EwGFq5Q/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.wSbHOtz3pt/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0d/wgdyxjyj2r96yt9mnwtg9t0m00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0x0:0x0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1
14:53:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test/build/venv/cache', '--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
14:53:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --download-cache /builds/slave/test/build/venv/cache --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
14:53:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.IE2EwGFq5Q/Render',
14:53:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared',
14:53:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared',
14:53:08 INFO - 'HOME': '/Users/cltbld',
14:53:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true',
14:53:08 INFO - 'LOGNAME': 'cltbld',
14:53:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
14:53:08 INFO - 'MOZ_NO_REMOTE': '1',
14:53:08 INFO - 'NO_EM_RESTART': '1',
14:53:08 INFO - 'PAGER': '/bin/cat',
14:53:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11',
14:53:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
14:53:08 INFO - 'PWD': '/builds/slave/test',
14:53:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg',
14:53:08 INFO - 'SHELL': '/bin/bash',
14:53:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.wSbHOtz3pt/Listeners',
14:53:08 INFO - 'TMPDIR': '/var/folders/0d/wgdyxjyj2r96yt9mnwtg9t0m00000w/T/',
14:53:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log',
14:53:08 INFO - 'USER': 'cltbld',
14:53:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no',
14:53:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7',
14:53:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
14:53:08 INFO - 'XPC_FLAGS': '0x0',
14:53:08 INFO - 'XPC_SERVICE_NAME': '0',
14:53:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0x0:0x0'}
14:53:08 INFO - Ignoring indexes: https://pypi.python.org/simple/
14:53:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser
14:53:08 INFO - Running setup.py (path:/var/folders/0d/wgdyxjyj2r96yt9mnwtg9t0m00000w/T/pip-mV0sPH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser
14:53:08 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))
14:53:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash
14:53:08 INFO - Running setup.py (path:/var/folders/0d/wgdyxjyj2r96yt9mnwtg9t0m00000w/T/pip-eX605N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash
14:53:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.16 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2))
14:53:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug
14:53:09 INFO - Running setup.py (path:/var/folders/0d/wgdyxjyj2r96yt9mnwtg9t0m00000w/T/pip-kIORFV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug
14:53:09 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))
14:53:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice
14:53:09 INFO - Running setup.py (path:/var/folders/0d/wgdyxjyj2r96yt9mnwtg9t0m00000w/T/pip-iAPnW6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice
14:53:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.47 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))
14:53:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile
14:53:09 INFO - Running setup.py (path:/var/folders/0d/wgdyxjyj2r96yt9mnwtg9t0m00000w/T/pip-KXFXch-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile
14:53:09 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))
14:53:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd
14:53:09 INFO - Running setup.py (path:/var/folders/0d/wgdyxjyj2r96yt9mnwtg9t0m00000w/T/pip-pOGevj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd
14:53:09 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))
14:53:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo
14:53:09 INFO - Running setup.py (path:/var/folders/0d/wgdyxjyj2r96yt9mnwtg9t0m00000w/T/pip-JECHdf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo
14:53:09 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))
14:53:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall
14:53:09 INFO - Running setup.py (path:/var/folders/0d/wgdyxjyj2r96yt9mnwtg9t0m00000w/T/pip-DP2la4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall
14:53:09 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))
14:53:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak
14:53:09 INFO - Running setup.py (path:/var/folders/0d/wgdyxjyj2r96yt9mnwtg9t0m00000w/T/pip-VJJqhq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak
14:53:09 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))
14:53:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog
14:53:10 INFO - Running setup.py (path:/var/folders/0d/wgdyxjyj2r96yt9mnwtg9t0m00000w/T/pip-S01OK9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog
14:53:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.0 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))
14:53:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork
14:53:10 INFO - Running setup.py (path:/var/folders/0d/wgdyxjyj2r96yt9mnwtg9t0m00000w/T/pip-5iYuCH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork
14:53:10 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))
14:53:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess
14:53:10 INFO - Running setup.py (path:/var/folders/0d/wgdyxjyj2r96yt9mnwtg9t0m00000w/T/pip-7b_0lH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess
14:53:10 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))
14:53:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile
14:53:10 INFO - Running setup.py (path:/var/folders/0d/wgdyxjyj2r96yt9mnwtg9t0m00000w/T/pip-enWeM7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile
14:53:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.27 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))
14:53:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner
14:53:10 INFO - Running setup.py (path:/var/folders/0d/wgdyxjyj2r96yt9mnwtg9t0m00000w/T/pip-nmSpZH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner
14:53:10 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))
14:53:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot
14:53:10 INFO - Running setup.py (path:/var/folders/0d/wgdyxjyj2r96yt9mnwtg9t0m00000w/T/pip-57YqWM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot
14:53:10 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))
14:53:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest
14:53:10 INFO - Running setup.py (path:/var/folders/0d/wgdyxjyj2r96yt9mnwtg9t0m00000w/T/pip-hV6mlb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest
14:53:10 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))
14:53:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion
14:53:10 INFO - Running setup.py (path:/var/folders/0d/wgdyxjyj2r96yt9mnwtg9t0m00000w/T/pip-QjCSEI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion
14:53:11 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))
14:53:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.16->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2))
14:53:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.16->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2))
14:53:11 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))
14:53:11 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.47->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4))
14:53:11 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.47->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4))
14:53:11 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10))
14:53:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available
14:53:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available
14:53:11 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available
14:53:11 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available
14:53:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available
14:53:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available
14:53:13 INFO - Downloading blessings-1.5.1.tar.gz
14:53:13 INFO - Storing download in cache at /builds/slave/test/build/venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fblessings-1.5.1.tar.gz
14:53:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings
14:53:13 INFO - Installing collected packages: blessings
14:53:13 INFO - Running setup.py install for blessings
14:53:14 INFO - Successfully installed blessings
14:53:14 INFO - Cleaning up...
14:53:14 INFO - Return code: 0
14:53:14 INFO - Done creating virtualenv /builds/slave/test/build/venv.
14:53:14 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze']
14:53:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze
14:53:14 INFO - Reading from file tmpfile_stdout
14:53:14 INFO - Current package versions:
14:53:14 INFO - blessings == 1.5.1
14:53:14 INFO - blobuploader == 1.2.4
14:53:14 INFO - docopt == 0.6.1
14:53:14 INFO - manifestparser == 1.1
14:53:14 INFO - mozInstall == 1.12
14:53:14 INFO - mozcrash == 0.16
14:53:14 INFO - mozdebug == 0.1
14:53:14 INFO - mozdevice == 0.47
14:53:14 INFO - mozfile == 1.2
14:53:14 INFO - mozhttpd == 0.7
14:53:14 INFO - mozinfo == 0.9
14:53:14 INFO - mozleak == 0.1
14:53:14 INFO - mozlog == 3.0
14:53:14 INFO - moznetwork == 0.27
14:53:14 INFO - mozprocess == 0.22
14:53:14 INFO - mozprofile == 0.27
14:53:14 INFO - mozrunner == 6.11
14:53:14 INFO - mozscreenshot == 0.1
14:53:14 INFO - mozsystemmonitor == 0.0
14:53:14 INFO - moztest == 0.7
14:53:14 INFO - mozversion == 1.4
14:53:14 INFO - psutil == 3.1.1
14:53:14 INFO - requests == 1.2.3
14:53:14 INFO - wsgiref == 0.1.2
14:53:14 INFO - Installing None into virtualenv /builds/slave/test/build/venv
14:53:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]}
14:53:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
14:53:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub
14:53:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]}
14:53:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
14:53:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub
14:53:14 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test/build/venv/cache', '--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 0x10e965a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e9d2228>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc0e34d6af0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e91a9f0>, '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 0x10e679cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e8e4990>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/talos', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'HOME': '/Users/cltbld', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.IE2EwGFq5Q/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.wSbHOtz3pt/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0d/wgdyxjyj2r96yt9mnwtg9t0m00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0x0:0x0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1
14:53:14 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test/build/venv/cache', '--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
14:53:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --download-cache /builds/slave/test/build/venv/cache --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
14:53:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.IE2EwGFq5Q/Render',
14:53:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared',
14:53:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared',
14:53:14 INFO - 'HOME': '/Users/cltbld',
14:53:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true',
14:53:14 INFO - 'LOGNAME': 'cltbld',
14:53:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
14:53:14 INFO - 'MOZ_NO_REMOTE': '1',
14:53:14 INFO - 'NO_EM_RESTART': '1',
14:53:14 INFO - 'PAGER': '/bin/cat',
14:53:14 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11',
14:53:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
14:53:14 INFO - 'PWD': '/builds/slave/test',
14:53:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg',
14:53:14 INFO - 'SHELL': '/bin/bash',
14:53:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.wSbHOtz3pt/Listeners',
14:53:14 INFO - 'TMPDIR': '/var/folders/0d/wgdyxjyj2r96yt9mnwtg9t0m00000w/T/',
14:53:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log',
14:53:14 INFO - 'USER': 'cltbld',
14:53:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no',
14:53:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7',
14:53:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
14:53:14 INFO - 'XPC_FLAGS': '0x0',
14:53:14 INFO - 'XPC_SERVICE_NAME': '0',
14:53:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0x0:0x0'}
14:53:14 INFO - Ignoring indexes: https://pypi.python.org/simple/
14:53:14 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 1))
14:53:14 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 2))
14:53:14 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 3))
14:53:14 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 4))
14:53:14 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 5))
14:53:14 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 6))
14:53:14 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 7))
14:53:14 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 8))
14:53:14 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.15->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1))
14:53:14 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 3))
14:53:14 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.0->mozcrash>=0.15->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1))
14:53:14 INFO - Cleaning up...
14:53:14 INFO - Return code: 0
14:53:14 INFO - Running post-action listener: _resource_record_post_action
14:53:14 INFO - Running post-action listener: _start_resource_monitoring
14:53:14 INFO - Starting resource monitoring.
14:53:14 INFO - #####
14:53:14 INFO - ##### Running install step.
14:53:14 INFO - #####
14:53:14 INFO - Running pre-action listener: _resource_record_pre_action
14:53:14 INFO - Running main action method: install
14:53:14 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze']
14:53:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze
14:53:15 INFO - Reading from file tmpfile_stdout
14:53:15 INFO - Detecting whether we're running mozinstall >=1.0...
14:53:15 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h']
14:53:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h
14:53:15 INFO - Reading from file tmpfile_stdout
14:53:15 INFO - Output received:
14:53:15 INFO - Usage: mozinstall [options] installer
14:53:15 INFO - Options:
14:53:15 INFO - -h, --help show this help message and exit
14:53:15 INFO - -d DEST, --destination=DEST
14:53:15 INFO - Directory to install application into. [default:
14:53:15 INFO - "/builds/slave/test"]
14:53:15 INFO - --app=APP Application being installed. [default: firefox]
14:53:15 INFO - mkdir: /builds/slave/test/build/application
14:53:15 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-45.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application']
14:53:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-45.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application
14:53:57 INFO - Reading from file tmpfile_stdout
14:53:57 INFO - Output received:
14:53:57 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox
14:53:57 INFO - Running post-action listener: _resource_record_post_action
14:53:57 INFO - #####
14:53:57 INFO - ##### Running run-tests step.
14:53:57 INFO - #####
14:53:57 INFO - Running pre-action listener: _resource_record_pre_action
14:53:57 INFO - Running main action method: run_tests
14:53:57 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'
14:53:57 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build
14:53:57 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType"
14:53:57 INFO - 2015-11-09 14:53:57.161 screenresolution[2264:75766] starting screenresolution argv=screenresolution get
14:53:57 INFO - 2015-11-09 14:53:57.165 screenresolution[2264:75766] Display 0: 1600x1200x32@60
14:53:57 INFO - 2015-11-09 14:53:57.173 screenresolution[2265:75768] starting screenresolution argv=screenresolution list
14:53:57 INFO - Available Modes on Display 0
14:53:57 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75
14:53:57 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60
14:53:57 INFO - 800x600x32@75 800x600x32@60 640x480x32@75
14:53:57 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60
14:53:57 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays:
14:53:57 INFO - Intel HD Graphics 3000:
14:53:57 INFO - Chipset Model: Intel HD Graphics 3000
14:53:57 INFO - Type: GPU
14:53:57 INFO - Bus: Built-In
14:53:57 INFO - VRAM (Total): 512 MB
14:53:57 INFO - VRAM (Dynamic, Max): 10
14:53:57 INFO - Vendor: Intel (0x8086)
14:53:57 INFO - Device ID: 0x0116
14:53:57 INFO - Revision ID: 0x0009
14:53:57 INFO - Displays:
14:53:57 INFO - Display:
14:53:57 INFO - Resolution: 1600 x 1200 @ 60 Hz
14:53:57 INFO - Pixel Depth: 32-Bit Color (ARGB8888)
14:53:57 INFO - Display Serial Number: 1600x1200 60
14:53:57 INFO - Main Display: Yes
14:53:57 INFO - Mirror: Off
14:53:57 INFO - Online: Yes
14:53:57 INFO - Rotation: Supported
14:53:57 INFO - Return code: 0
14:53:57 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version']
14:53:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version
14:53:57 INFO - Python 2.7.3
14:53:57 INFO - Return code: 0
14:53:57 INFO - grabbing minidump binary from tooltool
14:53:57 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')]}
14:53:57 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 0x10e91a9f0>, '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 0x10e679cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e8e4990>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1
14:53:57 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
14:53:57 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
14:53:57 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache
14:53:57 INFO - Return code: 0
14:53:57 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755
14:53:57 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir
14:53:57 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir
14:53:57 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk
14:53:57 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir
14:53:57 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--debug', '--branchName', 'Mozilla-Inbound', '--suite', 'chromez-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/r8Tnl8SQSDG-UdPkRjQqQQ/artifacts/public/build/firefox-45.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r5-0067', '--webServer', 'localhost'] in /builds/slave/test/build
14:53:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --debug --branchName Mozilla-Inbound --suite chromez-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/r8Tnl8SQSDG-UdPkRjQqQQ/artifacts/public/build/firefox-45.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r5-0067 --webServer localhost
14:53:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.IE2EwGFq5Q/Render',
14:53:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared',
14:53:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared',
14:53:57 INFO - 'HOME': '/Users/cltbld',
14:53:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true',
14:53:57 INFO - 'LOGNAME': 'cltbld',
14:53:57 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir',
14:53:57 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk',
14:53:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
14:53:57 INFO - 'MOZ_NO_REMOTE': '1',
14:53:57 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir',
14:53:57 INFO - 'NO_EM_RESTART': '1',
14:53:57 INFO - 'PAGER': '/bin/cat',
14:53:57 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11',
14:53:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
14:53:57 INFO - 'PWD': '/builds/slave/test',
14:53:57 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos',
14:53:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg',
14:53:57 INFO - 'SHELL': '/bin/bash',
14:53:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.wSbHOtz3pt/Listeners',
14:53:57 INFO - 'TMPDIR': '/var/folders/0d/wgdyxjyj2r96yt9mnwtg9t0m00000w/T/',
14:53:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log',
14:53:57 INFO - 'USER': 'cltbld',
14:53:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no',
14:53:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7',
14:53:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
14:53:57 INFO - 'XPC_FLAGS': '0x0',
14:53:57 INFO - 'XPC_SERVICE_NAME': '0',
14:53:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0x0:0x0'}
14:53:57 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--debug', '--branchName', 'Mozilla-Inbound', '--suite', 'chromez-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/r8Tnl8SQSDG-UdPkRjQqQQ/artifacts/public/build/firefox-45.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r5-0067', '--webServer', 'localhost'] with output_timeout 3600
14:53:57 INFO - mozversion INFO | application_buildid: 20151109132133
14:53:57 INFO - mozversion INFO | application_changeset: 6c6d04b021ddfdfe1ce35f5b6939ef80deda3f3f
14:53:57 INFO - mozversion INFO | application_display_name: Nightly
14:53:57 INFO - mozversion INFO | application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384}
14:53:57 INFO - mozversion INFO | application_name: Firefox
14:53:57 INFO - mozversion INFO | application_remotingname: firefox
14:53:57 INFO - mozversion INFO | application_repository: https://hg.mozilla.org/integration/mozilla-inbound
14:53:57 INFO - mozversion INFO | application_vendor: Mozilla
14:53:57 INFO - mozversion INFO | application_version: 45.0a1
14:53:57 INFO - mozversion INFO | platform_buildid: 20151109132133
14:53:57 INFO - mozversion INFO | platform_changeset: 6c6d04b021ddfdfe1ce35f5b6939ef80deda3f3f
14:53:57 INFO - mozversion INFO | platform_repository: https://hg.mozilla.org/integration/mozilla-inbound
14:53:57 INFO - mozversion INFO | platform_version: 45.0a1
14:53:57 INFO - 2015-11-09 14:53:57,829 DEBUG : using testdate: 1447109637
14:53:57 INFO - 2015-11-09 14:53:57,829 DEBUG : actual date: 1447109637
14:53:57 INFO - 2015-11-09 14:53:57,845 INFO : starting webserver on 'localhost:55251'
14:53:57 INFO - 2015-11-09 14:53:57,846 INFO : Starting test suite t-yosemite-r5-0067.e
14:53:57 INFO - 2015-11-09 14:53:57,846 INFO : Starting test tresize
14:53:57 INFO - 2015-11-09 14:53:57,846 DEBUG : operating with platform_type : mac_
14:53:57 INFO - 2015-11-09 14:53:57,847 INFO : Initialising browser for tresize test...
14:53:57 INFO - 2015-11-09 14:53:57,868 DEBUG : command line: /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/0d/wgdyxjyj2r96yt9mnwtg9t0m00000w/T/tmpC1tGr3/profile http://localhost:55251/getInfo.html
14:54:01 INFO - 2015-11-09 14:54:01,555 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200
14:54:01 INFO - 2015-11-09 14:54:01,555 DEBUG : BROWSER_OUTPUT: colorDepth:24
14:54:01 INFO - 2015-11-09 14:54:01,555 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/689
14:54:01 INFO - 2015-11-09 14:54:01,555 DEBUG : BROWSER_OUTPUT: __metrics
14:54:02 INFO - 2015-11-09 14:54:02,254 INFO : Browser initialized.
14:54:02 INFO - 2015-11-09 14:54:02,255 INFO : Running cycle 1/20 for tresize test...
14:54:02 INFO - 2015-11-09 14:54:02,255 DEBUG : command line: /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/0d/wgdyxjyj2r96yt9mnwtg9t0m00000w/T/tmpC1tGr3/profile http://localhost:55251/startup_test/tresize/addon/content/tresize-test.html
14:54:24 INFO - 2015-11-09 14:54:24,303 DEBUG : BROWSER_OUTPUT: __start_report38.14598333333334__end_report
14:54:24 INFO - 2015-11-09 14:54:24,304 DEBUG : BROWSER_OUTPUT: __startTimestamp1447109664299__endTimestamp
14:54:25 INFO - 2015-11-09 14:54:25,263 INFO : Browser exited with error code: 0
14:54:25 INFO - 2015-11-09 14:54:25,266 INFO : Running cycle 2/20 for tresize test...
14:54:25 INFO - 2015-11-09 14:54:25,266 DEBUG : command line: /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/0d/wgdyxjyj2r96yt9mnwtg9t0m00000w/T/tmpC1tGr3/profile http://localhost:55251/startup_test/tresize/addon/content/tresize-test.html
14:54:46 INFO - 2015-11-09 14:54:46,564 DEBUG : BROWSER_OUTPUT: __start_report37.62169999999998__end_report
14:54:46 INFO - 2015-11-09 14:54:46,564 DEBUG : BROWSER_OUTPUT: __startTimestamp1447109686557__endTimestamp
14:54:47 INFO - 2015-11-09 14:54:47,201 INFO : Browser exited with error code: 0
14:54:47 INFO - 2015-11-09 14:54:47,202 INFO : Running cycle 3/20 for tresize test...
14:54:47 INFO - 2015-11-09 14:54:47,203 DEBUG : command line: /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/0d/wgdyxjyj2r96yt9mnwtg9t0m00000w/T/tmpC1tGr3/profile http://localhost:55251/startup_test/tresize/addon/content/tresize-test.html
14:55:08 INFO - 2015-11-09 14:55:08,607 DEBUG : BROWSER_OUTPUT: __start_report37.52496666666669__end_report
14:55:08 INFO - 2015-11-09 14:55:08,607 DEBUG : BROWSER_OUTPUT: __startTimestamp1447109708604__endTimestamp
14:55:09 INFO - 2015-11-09 14:55:09,259 INFO : Browser exited with error code: 0
14:55:09 INFO - 2015-11-09 14:55:09,260 INFO : Running cycle 4/20 for tresize test...
14:55:09 INFO - 2015-11-09 14:55:09,260 DEBUG : command line: /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/0d/wgdyxjyj2r96yt9mnwtg9t0m00000w/T/tmpC1tGr3/profile http://localhost:55251/startup_test/tresize/addon/content/tresize-test.html
14:55:30 INFO - 2015-11-09 14:55:30,738 DEBUG : BROWSER_OUTPUT: __start_report38.172083333333404__end_report
14:55:30 INFO - 2015-11-09 14:55:30,738 DEBUG : BROWSER_OUTPUT: __startTimestamp1447109730734__endTimestamp
14:55:31 INFO - 2015-11-09 14:55:31,471 INFO : Browser exited with error code: 0
14:55:31 INFO - 2015-11-09 14:55:31,472 INFO : Running cycle 5/20 for tresize test...
14:55:31 INFO - 2015-11-09 14:55:31,473 DEBUG : command line: /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/0d/wgdyxjyj2r96yt9mnwtg9t0m00000w/T/tmpC1tGr3/profile http://localhost:55251/startup_test/tresize/addon/content/tresize-test.html
14:55:53 INFO - 2015-11-09 14:55:53,090 DEBUG : BROWSER_OUTPUT: __start_report38.35506666666676__end_report
14:55:53 INFO - 2015-11-09 14:55:53,091 DEBUG : BROWSER_OUTPUT: __startTimestamp1447109753087__endTimestamp
14:55:53 INFO - 2015-11-09 14:55:53,853 INFO : Browser exited with error code: 0
14:55:53 INFO - 2015-11-09 14:55:53,854 INFO : Running cycle 6/20 for tresize test...
14:55:53 INFO - 2015-11-09 14:55:53,854 DEBUG : command line: /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/0d/wgdyxjyj2r96yt9mnwtg9t0m00000w/T/tmpC1tGr3/profile http://localhost:55251/startup_test/tresize/addon/content/tresize-test.html
14:56:15 INFO - 2015-11-09 14:56:15,608 DEBUG : BROWSER_OUTPUT: __start_report38.660316666666674__end_report
14:56:15 INFO - 2015-11-09 14:56:15,608 DEBUG : BROWSER_OUTPUT: __startTimestamp1447109775602__endTimestamp
14:56:16 INFO - 2015-11-09 14:56:16,395 INFO : Browser exited with error code: 0
14:56:16 INFO - 2015-11-09 14:56:16,396 INFO : Running cycle 7/20 for tresize test...
14:56:16 INFO - 2015-11-09 14:56:16,396 DEBUG : command line: /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/0d/wgdyxjyj2r96yt9mnwtg9t0m00000w/T/tmpC1tGr3/profile http://localhost:55251/startup_test/tresize/addon/content/tresize-test.html
14:56:38 INFO - 2015-11-09 14:56:38,075 DEBUG : BROWSER_OUTPUT: __start_report38.77739999999994__end_report
14:56:38 INFO - 2015-11-09 14:56:38,076 DEBUG : BROWSER_OUTPUT: __startTimestamp1447109798074__endTimestamp
14:56:38 INFO - 2015-11-09 14:56:38,842 INFO : Browser exited with error code: 0
14:56:38 INFO - 2015-11-09 14:56:38,844 INFO : Running cycle 8/20 for tresize test...
14:56:38 INFO - 2015-11-09 14:56:38,844 DEBUG : command line: /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/0d/wgdyxjyj2r96yt9mnwtg9t0m00000w/T/tmpC1tGr3/profile http://localhost:55251/startup_test/tresize/addon/content/tresize-test.html
14:57:00 INFO - 2015-11-09 14:57:00,346 DEBUG : BROWSER_OUTPUT: __start_report38.08653333333335__end_report
14:57:00 INFO - 2015-11-09 14:57:00,346 DEBUG : BROWSER_OUTPUT: __startTimestamp1447109820345__endTimestamp
14:57:01 INFO - 2015-11-09 14:57:01,118 INFO : Browser exited with error code: 0
14:57:01 INFO - 2015-11-09 14:57:01,120 INFO : Running cycle 9/20 for tresize test...
14:57:01 INFO - 2015-11-09 14:57:01,120 DEBUG : command line: /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/0d/wgdyxjyj2r96yt9mnwtg9t0m00000w/T/tmpC1tGr3/profile http://localhost:55251/startup_test/tresize/addon/content/tresize-test.html
14:57:22 INFO - 2015-11-09 14:57:22,838 DEBUG : BROWSER_OUTPUT: __start_report38.907483333333275__end_report
14:57:22 INFO - 2015-11-09 14:57:22,838 DEBUG : BROWSER_OUTPUT: __startTimestamp1447109842837__endTimestamp
14:57:23 INFO - 2015-11-09 14:57:23,596 INFO : Browser exited with error code: 0
14:57:23 INFO - 2015-11-09 14:57:23,598 INFO : Running cycle 10/20 for tresize test...
14:57:23 INFO - 2015-11-09 14:57:23,598 DEBUG : command line: /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/0d/wgdyxjyj2r96yt9mnwtg9t0m00000w/T/tmpC1tGr3/profile http://localhost:55251/startup_test/tresize/addon/content/tresize-test.html
14:57:45 INFO - 2015-11-09 14:57:45,008 DEBUG : BROWSER_OUTPUT: __start_report37.855583333333335__end_report
14:57:45 INFO - 2015-11-09 14:57:45,009 DEBUG : BROWSER_OUTPUT: __startTimestamp1447109865007__endTimestamp
14:57:45 INFO - 2015-11-09 14:57:45,799 INFO : Browser exited with error code: 0
14:57:45 INFO - 2015-11-09 14:57:45,800 INFO : Running cycle 11/20 for tresize test...
14:57:45 INFO - 2015-11-09 14:57:45,800 DEBUG : command line: /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/0d/wgdyxjyj2r96yt9mnwtg9t0m00000w/T/tmpC1tGr3/profile http://localhost:55251/startup_test/tresize/addon/content/tresize-test.html
14:58:07 INFO - 2015-11-09 14:58:07,398 DEBUG : BROWSER_OUTPUT: __start_report38.47721666666657__end_report
14:58:07 INFO - 2015-11-09 14:58:07,398 DEBUG : BROWSER_OUTPUT: __startTimestamp1447109887392__endTimestamp
14:58:08 INFO - 2015-11-09 14:58:08,201 INFO : Browser exited with error code: 0
14:58:08 INFO - 2015-11-09 14:58:08,203 INFO : Running cycle 12/20 for tresize test...
14:58:08 INFO - 2015-11-09 14:58:08,203 DEBUG : command line: /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/0d/wgdyxjyj2r96yt9mnwtg9t0m00000w/T/tmpC1tGr3/profile http://localhost:55251/startup_test/tresize/addon/content/tresize-test.html
14:58:29 INFO - 2015-11-09 14:58:29,676 DEBUG : BROWSER_OUTPUT: __start_report37.87746666666675__end_report
14:58:29 INFO - 2015-11-09 14:58:29,676 DEBUG : BROWSER_OUTPUT: __startTimestamp1447109909675__endTimestamp
14:58:30 INFO - 2015-11-09 14:58:30,448 INFO : Browser exited with error code: 0
14:58:30 INFO - 2015-11-09 14:58:30,450 INFO : Running cycle 13/20 for tresize test...
14:58:30 INFO - 2015-11-09 14:58:30,450 DEBUG : command line: /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/0d/wgdyxjyj2r96yt9mnwtg9t0m00000w/T/tmpC1tGr3/profile http://localhost:55251/startup_test/tresize/addon/content/tresize-test.html
14:58:51 INFO - 2015-11-09 14:58:51,961 DEBUG : BROWSER_OUTPUT: __start_report38.12110000000009__end_report
14:58:51 INFO - 2015-11-09 14:58:51,961 DEBUG : BROWSER_OUTPUT: __startTimestamp1447109931958__endTimestamp
14:58:52 INFO - 2015-11-09 14:58:52,753 INFO : Browser exited with error code: 0
14:58:52 INFO - 2015-11-09 14:58:52,755 INFO : Running cycle 14/20 for tresize test...
14:58:52 INFO - 2015-11-09 14:58:52,755 DEBUG : command line: /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/0d/wgdyxjyj2r96yt9mnwtg9t0m00000w/T/tmpC1tGr3/profile http://localhost:55251/startup_test/tresize/addon/content/tresize-test.html
14:59:14 INFO - 2015-11-09 14:59:14,363 DEBUG : BROWSER_OUTPUT: __start_report38.345266666666674__end_report
14:59:14 INFO - 2015-11-09 14:59:14,364 DEBUG : BROWSER_OUTPUT: __startTimestamp1447109954359__endTimestamp
14:59:15 INFO - 2015-11-09 14:59:15,181 INFO : Browser exited with error code: 0
14:59:15 INFO - 2015-11-09 14:59:15,182 INFO : Running cycle 15/20 for tresize test...
14:59:15 INFO - 2015-11-09 14:59:15,182 DEBUG : command line: /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/0d/wgdyxjyj2r96yt9mnwtg9t0m00000w/T/tmpC1tGr3/profile http://localhost:55251/startup_test/tresize/addon/content/tresize-test.html
14:59:36 INFO - 2015-11-09 14:59:36,729 DEBUG : BROWSER_OUTPUT: __start_report38.21796666666667__end_report
14:59:36 INFO - 2015-11-09 14:59:36,729 DEBUG : BROWSER_OUTPUT: __startTimestamp1447109976728__endTimestamp
14:59:37 INFO - 2015-11-09 14:59:37,564 INFO : Browser exited with error code: 0
14:59:37 INFO - 2015-11-09 14:59:37,566 INFO : Running cycle 16/20 for tresize test...
14:59:37 INFO - 2015-11-09 14:59:37,566 DEBUG : command line: /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/0d/wgdyxjyj2r96yt9mnwtg9t0m00000w/T/tmpC1tGr3/profile http://localhost:55251/startup_test/tresize/addon/content/tresize-test.html
14:59:59 INFO - 2015-11-09 14:59:59,314 DEBUG : BROWSER_OUTPUT: __start_report38.65924999999994__end_report
14:59:59 INFO - 2015-11-09 14:59:59,314 DEBUG : BROWSER_OUTPUT: __startTimestamp1447109999311__endTimestamp
15:00:00 INFO - 2015-11-09 15:00:00,144 INFO : Browser exited with error code: 0
15:00:00 INFO - 2015-11-09 15:00:00,145 INFO : Running cycle 17/20 for tresize test...
15:00:00 INFO - 2015-11-09 15:00:00,145 DEBUG : command line: /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/0d/wgdyxjyj2r96yt9mnwtg9t0m00000w/T/tmpC1tGr3/profile http://localhost:55251/startup_test/tresize/addon/content/tresize-test.html
15:00:21 INFO - 2015-11-09 15:00:21,663 DEBUG : BROWSER_OUTPUT: __start_report38.3106833333334__end_report
15:00:21 INFO - 2015-11-09 15:00:21,663 DEBUG : BROWSER_OUTPUT: __startTimestamp1447110021659__endTimestamp
15:00:22 INFO - 2015-11-09 15:00:22,492 INFO : Browser exited with error code: 0
15:00:22 INFO - 2015-11-09 15:00:22,493 INFO : Running cycle 18/20 for tresize test...
15:00:22 INFO - 2015-11-09 15:00:22,494 DEBUG : command line: /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/0d/wgdyxjyj2r96yt9mnwtg9t0m00000w/T/tmpC1tGr3/profile http://localhost:55251/startup_test/tresize/addon/content/tresize-test.html
15:00:44 INFO - 2015-11-09 15:00:44,003 DEBUG : BROWSER_OUTPUT: __start_report37.943916666666674__end_report
15:00:44 INFO - 2015-11-09 15:00:44,003 DEBUG : BROWSER_OUTPUT: __startTimestamp1447110043997__endTimestamp
15:00:44 INFO - 2015-11-09 15:00:44,825 INFO : Browser exited with error code: 0
15:00:44 INFO - 2015-11-09 15:00:44,827 INFO : Running cycle 19/20 for tresize test...
15:00:44 INFO - 2015-11-09 15:00:44,827 DEBUG : command line: /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/0d/wgdyxjyj2r96yt9mnwtg9t0m00000w/T/tmpC1tGr3/profile http://localhost:55251/startup_test/tresize/addon/content/tresize-test.html
15:01:06 INFO - 2015-11-09 15:01:06,443 DEBUG : BROWSER_OUTPUT: __start_report38.42996666666661__end_report
15:01:06 INFO - 2015-11-09 15:01:06,444 DEBUG : BROWSER_OUTPUT: __startTimestamp1447110066443__endTimestamp
15:01:07 INFO - 2015-11-09 15:01:07,255 INFO : Browser exited with error code: 0
15:01:07 INFO - 2015-11-09 15:01:07,256 INFO : Running cycle 20/20 for tresize test...
15:01:07 INFO - 2015-11-09 15:01:07,257 DEBUG : command line: /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/0d/wgdyxjyj2r96yt9mnwtg9t0m00000w/T/tmpC1tGr3/profile http://localhost:55251/startup_test/tresize/addon/content/tresize-test.html
15:01:28 INFO - 2015-11-09 15:01:28,729 DEBUG : BROWSER_OUTPUT: __start_report37.99441666666667__end_report
15:01:28 INFO - 2015-11-09 15:01:28,730 DEBUG : BROWSER_OUTPUT: __startTimestamp1447110088727__endTimestamp
15:01:29 INFO - 2015-11-09 15:01:29,545 INFO : Browser exited with error code: 0
15:01:29 INFO - 2015-11-09 15:01:29,578 INFO : Completed test tresize (00:07:31)
15:01:29 INFO - 2015-11-09 15:01:29,578 INFO : Starting test tcanvasmark
15:01:29 INFO - 2015-11-09 15:01:29,578 DEBUG : operating with platform_type : mac_
15:01:29 INFO - 2015-11-09 15:01:29,579 INFO : Initialising browser for tcanvasmark test...
15:01:29 INFO - 2015-11-09 15:01:29,596 DEBUG : command line: /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/0d/wgdyxjyj2r96yt9mnwtg9t0m00000w/T/tmpY7Ch8w/profile http://localhost:55251/getInfo.html
15:01:32 INFO - 2015-11-09 15:01:32,992 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200
15:01:32 INFO - 2015-11-09 15:01:32,993 DEBUG : BROWSER_OUTPUT: colorDepth:24
15:01:32 INFO - 2015-11-09 15:01:32,993 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/689
15:01:32 INFO - 2015-11-09 15:01:32,993 DEBUG : BROWSER_OUTPUT: __metrics
15:01:33 INFO - 2015-11-09 15:01:33,685 INFO : Browser initialized.
15:01:33 INFO - 2015-11-09 15:01:33,685 INFO : Running cycle 1/1 for tcanvasmark test...
15:01:33 INFO - 2015-11-09 15:01:33,685 DEBUG : command line: /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/0d/wgdyxjyj2r96yt9mnwtg9t0m00000w/T/tmpY7Ch8w/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/canvasmark/canvasmark.manifest.develop -tpchrome -tpnoisy -tpcycles 5 -tppagecycles 1
15:01:46 INFO - 2015-11-09 15:01:46,026 DEBUG : BROWSER_OUTPUT: RSS: Main: 239816704
15:01:46 INFO - 2015-11-09 15:01:46,026 DEBUG : BROWSER_OUTPUT:
15:02:35 INFO - 2015-11-09 15:02:35,036 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:55251/tests/canvasmark/index.html?auto=true (next: http://localhost:55251/tests/canvasmark/index.html?auto=true)
15:02:35 INFO - 2015-11-09 15:02:35,297 DEBUG : BROWSER_OUTPUT: RSS: Main: 265633792
15:02:35 INFO - 2015-11-09 15:02:35,298 DEBUG : BROWSER_OUTPUT:
15:02:35 INFO - 2015-11-09 15:02:35,547 DEBUG : BROWSER_OUTPUT: RSS: Main: 265637888
15:02:35 INFO - 2015-11-09 15:02:35,547 DEBUG : BROWSER_OUTPUT:
15:02:35 INFO - 2015-11-09 15:02:35,773 DEBUG : BROWSER_OUTPUT: 1447110155771 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]
15:03:24 INFO - 2015-11-09 15:03:24,808 DEBUG : BROWSER_OUTPUT: Cycle 2(1): loaded http://localhost:55251/tests/canvasmark/index.html?auto=true (next: http://localhost:55251/tests/canvasmark/index.html?auto=true)
15:03:25 INFO - 2015-11-09 15:03:25,063 DEBUG : BROWSER_OUTPUT: RSS: Main: 273154048
15:03:25 INFO - 2015-11-09 15:03:25,063 DEBUG : BROWSER_OUTPUT:
15:03:25 INFO - 2015-11-09 15:03:25,310 DEBUG : BROWSER_OUTPUT: RSS: Main: 273174528
15:03:25 INFO - 2015-11-09 15:03:25,311 DEBUG : BROWSER_OUTPUT:
15:04:14 INFO - 2015-11-09 15:04:14,290 DEBUG : BROWSER_OUTPUT: Cycle 3(1): loaded http://localhost:55251/tests/canvasmark/index.html?auto=true (next: http://localhost:55251/tests/canvasmark/index.html?auto=true)
15:04:14 INFO - 2015-11-09 15:04:14,547 DEBUG : BROWSER_OUTPUT: RSS: Main: 271900672
15:04:14 INFO - 2015-11-09 15:04:14,547 DEBUG : BROWSER_OUTPUT:
15:04:14 INFO - 2015-11-09 15:04:14,802 DEBUG : BROWSER_OUTPUT: RSS: Main: 271904768
15:04:14 INFO - 2015-11-09 15:04:14,803 DEBUG : BROWSER_OUTPUT:
15:05:03 INFO - 2015-11-09 15:05:03,515 DEBUG : BROWSER_OUTPUT: Cycle 4(1): loaded http://localhost:55251/tests/canvasmark/index.html?auto=true (next: http://localhost:55251/tests/canvasmark/index.html?auto=true)
15:05:03 INFO - 2015-11-09 15:05:03,778 DEBUG : BROWSER_OUTPUT: RSS: Main: 287281152
15:05:03 INFO - 2015-11-09 15:05:03,778 DEBUG : BROWSER_OUTPUT:
15:05:04 INFO - 2015-11-09 15:05:04,030 DEBUG : BROWSER_OUTPUT: RSS: Main: 287285248
15:05:04 INFO - 2015-11-09 15:05:04,031 DEBUG : BROWSER_OUTPUT:
15:05:52 INFO - 2015-11-09 15:05:52,676 DEBUG : BROWSER_OUTPUT: Cycle 5(1): loaded http://localhost:55251/tests/canvasmark/index.html?auto=true (next: http://localhost:55251/tests/canvasmark/index.html?auto=true)
15:05:52 INFO - 2015-11-09 15:05:52,939 DEBUG : BROWSER_OUTPUT: RSS: Main: 286015488
15:05:52 INFO - 2015-11-09 15:05:52,939 DEBUG : BROWSER_OUTPUT:
15:05:52 INFO - 2015-11-09 15:05:52,939 DEBUG : BROWSER_OUTPUT: __start_tp_report
15:05:52 INFO - 2015-11-09 15:05:52,939 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load
15:05:52 INFO - 2015-11-09 15:05:52,940 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details
15:05:52 INFO - 2015-11-09 15:05:52,940 DEBUG : BROWSER_OUTPUT: |i|pagename|runs|
15:05:52 INFO - 2015-11-09 15:05:52,940 DEBUG : BROWSER_OUTPUT: |0;Asteroids - Bitmaps;328;332;326;328;320
15:05:52 INFO - 2015-11-09 15:05:52,940 DEBUG : BROWSER_OUTPUT: |1;Asteroids - Vectors;582;578;570;575;590
15:05:52 INFO - 2015-11-09 15:05:52,940 DEBUG : BROWSER_OUTPUT: |2;Asteroids - Bitmaps- shapes- text;431;456;458;424;432
15:05:52 INFO - 2015-11-09 15:05:52,941 DEBUG : BROWSER_OUTPUT: |3;Asteroids - Shapes- shadows- blending;621;602;598;603;612
15:05:52 INFO - 2015-11-09 15:05:52,941 DEBUG : BROWSER_OUTPUT: |4;Arena5 - Vectors- shadows- bitmaps- text;233;239;237;237;238
15:05:52 INFO - 2015-11-09 15:05:52,941 DEBUG : BROWSER_OUTPUT: |5;Plasma - Maths- canvas shapes;849;878;880;850;863
15:05:52 INFO - 2015-11-09 15:05:52,941 DEBUG : BROWSER_OUTPUT: |6;3D Rendering - Maths- polygons- image transforms;341;353;334;331;355
15:05:52 INFO - 2015-11-09 15:05:52,941 DEBUG : BROWSER_OUTPUT: |7;Pixel blur - Math- getImageData- putImageData;1315;1253;1257;1316;1186
15:05:52 INFO - 2015-11-09 15:05:52,942 DEBUG : BROWSER_OUTPUT: __end_tp_report
15:05:52 INFO - 2015-11-09 15:05:52,942 DEBUG : BROWSER_OUTPUT: __startTimestamp1447110352936__endTimestamp
15:05:52 INFO - 2015-11-09 15:05:52,942 DEBUG : BROWSER_OUTPUT:
15:05:52 INFO - 2015-11-09 15:05:52,942 DEBUG : BROWSER_OUTPUT: ------- Summary: start -------
15:05:52 INFO - 2015-11-09 15:05:52,943 DEBUG : BROWSER_OUTPUT: Number of tests: 8
15:05:52 INFO - 2015-11-09 15:05:52,943 DEBUG : BROWSER_OUTPUT:
15:05:52 INFO - 2015-11-09 15:05:52,943 DEBUG : BROWSER_OUTPUT: [#0] Asteroids - Bitmaps Cycles:5 Average:326.80 Median:328.00 stddev:4.38 (1.3%) stddev-sans-first:5.00
15:05:52 INFO - 2015-11-09 15:05:52,943 DEBUG : BROWSER_OUTPUT: Values: 328.0 332.0 326.0 328.0 320.0
15:05:52 INFO - 2015-11-09 15:05:52,943 DEBUG : BROWSER_OUTPUT:
15:05:52 INFO - 2015-11-09 15:05:52,945 DEBUG : BROWSER_OUTPUT: [#1] Asteroids - Vectors Cycles:5 Average:579.00 Median:578.00 stddev:7.55 (1.3%) stddev-sans-first:8.50
15:05:52 INFO - 2015-11-09 15:05:52,946 DEBUG : BROWSER_OUTPUT: Values: 582.0 578.0 570.0 575.0 590.0
15:05:52 INFO - 2015-11-09 15:05:52,946 DEBUG : BROWSER_OUTPUT:
15:05:52 INFO - 2015-11-09 15:05:52,946 DEBUG : BROWSER_OUTPUT: [#2] Asteroids - Bitmaps- shapes- text Cycles:5 Average:440.20 Median:432.00 stddev:15.66 (3.6%) stddev-sans-first:17.08
15:05:52 INFO - 2015-11-09 15:05:52,946 DEBUG : BROWSER_OUTPUT: Values: 431.0 456.0 458.0 424.0 432.0
15:05:52 INFO - 2015-11-09 15:05:52,946 DEBUG : BROWSER_OUTPUT:
15:05:52 INFO - 2015-11-09 15:05:52,946 DEBUG : BROWSER_OUTPUT: [#3] Asteroids - Shapes- shadows- blending Cycles:5 Average:607.20 Median:603.00 stddev:9.26 (1.5%) stddev-sans-first:5.91
15:05:52 INFO - 2015-11-09 15:05:52,946 DEBUG : BROWSER_OUTPUT: Values: 621.0 602.0 598.0 603.0 612.0
15:05:52 INFO - 2015-11-09 15:05:52,946 DEBUG : BROWSER_OUTPUT:
15:05:52 INFO - 2015-11-09 15:05:52,947 DEBUG : BROWSER_OUTPUT: [#4] Arena5 - Vectors- shadows- bitmaps- text Cycles:5 Average:236.80 Median:237.00 stddev:2.28 (1.0%) stddev-sans-first:0.96
15:05:52 INFO - 2015-11-09 15:05:52,947 DEBUG : BROWSER_OUTPUT: Values: 233.0 239.0 237.0 237.0 238.0
15:05:52 INFO - 2015-11-09 15:05:52,947 DEBUG : BROWSER_OUTPUT:
15:05:52 INFO - 2015-11-09 15:05:52,947 DEBUG : BROWSER_OUTPUT: [#5] Plasma - Maths- canvas shapes Cycles:5 Average:864.00 Median:863.00 stddev:14.78 (1.7%) stddev-sans-first:14.06
15:05:52 INFO - 2015-11-09 15:05:52,947 DEBUG : BROWSER_OUTPUT: Values: 849.0 878.0 880.0 850.0 863.0
15:05:52 INFO - 2015-11-09 15:05:52,947 DEBUG : BROWSER_OUTPUT:
15:05:52 INFO - 2015-11-09 15:05:52,947 DEBUG : BROWSER_OUTPUT: [#6] 3D Rendering - Maths- polygons- image transforms Cycles:5 Average:342.80 Median:341.00 stddev:10.87 (3.2%) stddev-sans-first:12.50
15:05:52 INFO - 2015-11-09 15:05:52,947 DEBUG : BROWSER_OUTPUT: Values: 341.0 353.0 334.0 331.0 355.0
15:05:52 INFO - 2015-11-09 15:05:52,947 DEBUG : BROWSER_OUTPUT:
15:05:52 INFO - 2015-11-09 15:05:52,947 DEBUG : BROWSER_OUTPUT: [#7] Pixel blur - Math- getImageData- putImageData Cycles:5 Average:1265.40 Median:1257.00 stddev:53.73 (4.3%) stddev-sans-first:53.15
15:05:52 INFO - 2015-11-09 15:05:52,948 DEBUG : BROWSER_OUTPUT: Values: 1315.0 1253.0 1257.0 1316.0 1186.0
15:05:52 INFO - 2015-11-09 15:05:52,948 DEBUG : BROWSER_OUTPUT: -------- Summary: end --------
15:05:52 INFO - 2015-11-09 15:05:52,948 DEBUG : BROWSER_OUTPUT:
15:05:53 INFO - 2015-11-09 15:05:53,654 INFO : Browser exited with error code: 0
15:05:53 INFO - 2015-11-09 15:05:53,680 INFO : Completed test tcanvasmark (00:04:24)
15:05:53 INFO - 2015-11-09 15:05:53,884 INFO : Completed test suite (00:11:56)
15:05:53 INFO - 2015-11-09 15:05:53,884 DEBUG : Working with test: tresize
15:05:53 INFO - 2015-11-09 15:05:53,884 DEBUG : Generating results file: tresize
15:05:53 INFO - 2015-11-09 15:05:53,885 DEBUG : Working with test: tcanvasmark
15:05:53 INFO - 2015-11-09 15:05:53,885 DEBUG : Generating results file: tcanvasmark
15:05:53 INFO - 2015-11-09 15:05:53,885 INFO : CanvasMark benchmark
15:05:53 INFO - 2015-11-09 15:05:53,885 INFO : Posting result 0 of 2 to http://graphs.mozilla.org/server/collect.cgi, attempt 0
15:05:53 INFO - 2015-11-09 15:05:53,939 DEBUG : process_Request line: tresize graph.html#tests=[[254,63,57]]
15:05:53 INFO - 2015-11-09 15:05:53,939 DEBUG : process_Request line: tresize 38.22 graph.html#tests=[[254,63,57]]
15:05:53 INFO - 2015-11-09 15:05:53,939 INFO : Posting result 1 of 2 to http://graphs.mozilla.org/server/collect.cgi, attempt 0
15:05:54 INFO - 2015-11-09 15:05:54,051 DEBUG : process_Request line: tcanvasmark 4656.50 graph.html#tests=[[289,63,57]]
15:05:54 INFO - 2015-11-09 15:05:54,051 INFO : CanvasMark benchmark
15:05:54 INFO - 2015-11-09 15:05:54,052 INFO : TALOSDATA: [{"talos_counters": {}, "results": {"tresize": [38.14598333333334, 37.62169999999998, 37.52496666666669, 38.172083333333404, 38.35506666666676, 38.660316666666674, 38.77739999999994, 38.08653333333335, 38.907483333333275, 37.855583333333335, 38.47721666666657, 37.87746666666675, 38.12110000000009, 38.345266666666674, 38.21796666666667, 38.65924999999994, 38.3106833333334, 37.943916666666674, 38.42996666666661, 37.99441666666667]}, "summary": {"suite": 38.3106833333334, "subtests": {"tresize": {"filtered": 38.3106833333334, "value": 38.3106833333334}}}, "test_machine": {"platform": "x86_64", "osversion": "OS X 10.10.2.e", "os": "mac", "name": "t-yosemite-r5-0067.e"}, "testrun": {"date": 1447109637, "suite": "tresize", "options": {"responsiveness": false, "cycles": 20, "tpmozafterpaint": true, "shutdown": false, "rss": false}}, "test_build": {"name": "Firefox", "version": "45.0a1", "id": "20151109132133", "branch": "Mozilla-Inbound", "revision": "6c6d04b021ddfdfe1ce35f5b6939ef80deda3f3f"}}, {"talos_counters": {}, "results": {"Plasma - Maths- canvas shapes": [849.0, 878.0, 880.0, 850.0, 863.0], "Asteroids - Shapes- shadows- blending": [621.0, 602.0, 598.0, 603.0, 612.0], "Asteroids - Bitmaps- shapes- text": [431.0, 456.0, 458.0, 424.0, 432.0], "Arena5 - Vectors- shadows- bitmaps- text": [233.0, 239.0, 237.0, 237.0, 238.0], "Asteroids - Vectors": [582.0, 578.0, 570.0, 575.0, 590.0], "3D Rendering - Maths- polygons- image transforms": [341.0, 353.0, 334.0, 331.0, 355.0], "Pixel blur - Math- getImageData- putImageData": [1315.0, 1253.0, 1257.0, 1316.0, 1186.0], "Asteroids - Bitmaps": [328.0, 332.0, 326.0, 328.0, 320.0]}, "summary": {"lowerIsBetter": false, "suite": 4656.5, "subtests": {"Plasma - Maths- canvas shapes": {"lowerIsBetter": false, "filtered": 870.5, "unit": "score", "value": 870.5}, "Asteroids - Shapes- shadows- blending": {"lowerIsBetter": false, "filtered": 602.5, "unit": "score", "value": 602.5}, "Asteroids - Bitmaps- shapes- text": {"lowerIsBetter": false, "filtered": 444.0, "unit": "score", "value": 444.0}, "Arena5 - Vectors- shadows- bitmaps- text": {"lowerIsBetter": false, "filtered": 237.5, "unit": "score", "value": 237.5}, "Asteroids - Vectors": {"lowerIsBetter": false, "filtered": 576.5, "unit": "score", "value": 576.5}, "3D Rendering - Maths- polygons- image transforms": {"lowerIsBetter": false, "filtered": 343.5, "unit": "score", "value": 343.5}, "Pixel blur - Math- getImageData- putImageData": {"lowerIsBetter": false, "filtered": 1255.0, "unit": "score", "value": 1255.0}, "Asteroids - Bitmaps": {"lowerIsBetter": false, "filtered": 327.0, "unit": "score", "value": 327.0}}}, "test_machine": {"platform": "x86_64", "osversion": "OS X 10.10.2.e", "os": "mac", "name": "t-yosemite-r5-0067.e"}, "testrun": {"date": 1447109637, "suite": "tcanvasmark", "options": {"responsiveness": false, "tpmozafterpaint": false, "tpchrome": true, "tppagecycles": 1, "tpcycles": 5, "tprender": false, "shutdown": false, "cycles": 1, "rss": false}}, "test_build": {"name": "Firefox", "version": "45.0a1", "id": "20151109132133", "branch": "Mozilla-Inbound", "revision": "6c6d04b021ddfdfe1ce35f5b6939ef80deda3f3f"}}]
15:05:54 INFO - RETURN: tresize: 38.22
15:05:54 INFO - RETURN: tcanvasmark: 4656.50
15:05:54 INFO - TinderboxPrint: TalosResult: {"graphserver": {"tcanvasmark": {"url": "http://graphs.mozilla.org/graph.html#tests=[[289,63,57]]", "result": "4656.50"}, "tresize": {"url": "http://graphs.mozilla.org/graph.html#tests=[[254,63,57]]", "result": "38.22"}}}
15:05:54 INFO - Return code: 0
15:05:54 INFO - # TBPL SUCCESS #
15:05:54 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'
15:05:54 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build
15:05:54 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType"
15:05:54 INFO - 2015-11-09 15:05:54.108 screenresolution[2330:81479] starting screenresolution argv=screenresolution get
15:05:54 INFO - 2015-11-09 15:05:54.111 screenresolution[2330:81479] Display 0: 1600x1200x32@60
15:05:54 INFO - 2015-11-09 15:05:54.117 screenresolution[2331:81482] starting screenresolution argv=screenresolution list
15:05:54 INFO - Available Modes on Display 0
15:05:54 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75
15:05:54 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60
15:05:54 INFO - 800x600x32@75 800x600x32@60 640x480x32@75
15:05:54 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60
15:05:54 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays:
15:05:54 INFO - Intel HD Graphics 3000:
15:05:54 INFO - Chipset Model: Intel HD Graphics 3000
15:05:54 INFO - Type: GPU
15:05:54 INFO - Bus: Built-In
15:05:54 INFO - VRAM (Total): 512 MB
15:05:54 INFO - VRAM (Dynamic, Max): 10
15:05:54 INFO - Vendor: Intel (0x8086)
15:05:54 INFO - Device ID: 0x0116
15:05:54 INFO - Revision ID: 0x0009
15:05:54 INFO - Displays:
15:05:54 INFO - Display:
15:05:54 INFO - Resolution: 1600 x 1200 @ 60 Hz
15:05:54 INFO - Pixel Depth: 32-Bit Color (ARGB8888)
15:05:54 INFO - Display Serial Number: 1600x1200 60
15:05:54 INFO - Main Display: Yes
15:05:54 INFO - Mirror: Off
15:05:54 INFO - Online: Yes
15:05:54 INFO - Rotation: Supported
15:05:54 INFO - Return code: 0
15:05:54 INFO - Running post-action listener: _resource_record_post_action
15:05:54 INFO - Running post-run listener: _resource_record_post_run
15:05:55 INFO - Total resource usage - Wall time: 759s; CPU: 27.0%; Read bytes: 6734336; Write bytes: 482207744; Read time: 663; Write time: 30721
15:05:55 INFO - install - Wall time: 42s; CPU: 30.0%; Read bytes: 344785408; Write bytes: 346970112; Read time: 37244; Write time: 5881
15:05:55 INFO - run-tests - Wall time: 717s; CPU: 27.0%; Read bytes: 3822080; Write bytes: 133414912; Read time: 591; Write time: 24823
15:05:55 INFO - Running post-run listener: _upload_blobber_files
15:05:55 INFO - Blob upload gear active.
15:05:55 INFO - There are no files to upload in the directory. Skipping the blob upload mechanism ...
15:05:55 INFO - Copying logs to upload dir...
15:05:55 INFO - mkdir: /builds/slave/test/build/upload/logs
program finished with exit code 0
elapsedTime=986.619867
========= master_lag: 60.43 =========
========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 17 mins, 27 secs) (at 2015-11-09 15:06:55.911827) =========
========= Started set props: build_url (results: 0, elapsed: 1 secs) (at 2015-11-09 15:06:55.915167) =========
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.IE2EwGFq5Q/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.wSbHOtz3pt/Listeners
TMPDIR=/var/folders/0d/wgdyxjyj2r96yt9mnwtg9t0m00000w/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:0x0:0x0
using PTY: False
build_url:https://queue.taskcluster.net/v1/task/r8Tnl8SQSDG-UdPkRjQqQQ/artifacts/public/build/firefox-45.0a1.en-US.mac.dmg
program finished with exit code 0
elapsedTime=0.011012
build_url: 'https://queue.taskcluster.net/v1/task/r8Tnl8SQSDG-UdPkRjQqQQ/artifacts/public/build/firefox-45.0a1.en-US.mac.dmg'
========= master_lag: 1.34 =========
========= Finished set props: build_url (results: 0, elapsed: 1 secs) (at 2015-11-09 15:06:57.270584) =========
========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2015-11-09 15:06:57.270932) =========
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.IE2EwGFq5Q/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.wSbHOtz3pt/Listeners
TMPDIR=/var/folders/0d/wgdyxjyj2r96yt9mnwtg9t0m00000w/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:0x0:0x0
using PTY: False
program finished with exit code 0
elapsedTime=0.005899
========= master_lag: 0.11 =========
========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2015-11-09 15:06:57.390298) =========
========= Started reboot skipped (results: 3, elapsed: 2 secs) (at 2015-11-09 15:06:57.390608) =========
========= Finished reboot skipped (results: 3, elapsed: 2 secs) (at 2015-11-09 15:07:00.196820) =========
========= Total master_lag: 62.02 =========