builder: fx-team_yosemite_r7_test-dromaeojs
slave: t-yosemite-r7-0246
starttime: 1464139869.62
results: success (0)
buildid: 20160524164650
builduid: 14755ce953e344db8806d97804776a25
revision: 788365ddaf61746bc66479335ae72d3e566c38ec
========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-24 18:31:09.624609) =========
master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/
========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-24 18:31:09.625018) =========
========= Started downloading to buildprops.json (results: 0, elapsed: 2 secs) (at 2016-05-24 18:31:09.625293) =========
========= Finished downloading to buildprops.json (results: 0, elapsed: 2 secs) (at 2016-05-24 18:31:12.163744) =========
========= Started 'bash -c ...' (results: 0, elapsed: 5 secs) (at 2016-05-24 18:31:12.164059) =========
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.xpal0DPj4f/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.92gVrI0cof/Listeners
TMPDIR=/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/
TWISTD_LOG_PATH=/builds/slave/twistd.log
USER=cltbld
VERSIONER_PYTHON_PREFER_32_BIT=no
VERSIONER_PYTHON_VERSION=2.7
XPCOM_DEBUG_BREAK=warn
XPC_FLAGS=0x0
XPC_SERVICE_NAME=0
__CF_USER_TEXT_ENCODING=0x1C:0:0
using PTY: False
--2016-05-24 18:31:11-- 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% 170M=0s
2016-05-24 18:31:11 (170 MB/s) - 'archiver_client.py' saved [12141/12141]
program finished with exit code 0
elapsedTime=0.140168
========= master_lag: 5.19 =========
========= Finished 'bash -c ...' (results: 0, elapsed: 5 secs) (at 2016-05-24 18:31:17.489733) =========
========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-24 18:31:17.490071) =========
rm -rf scripts properties
in dir /builds/slave/test/. (timeout 1200 secs)
watching logfiles {}
argv: ['rm', '-rf', 'scripts', 'properties']
environment:
Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.xpal0DPj4f/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.92gVrI0cof/Listeners
TMPDIR=/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/
TWISTD_LOG_PATH=/builds/slave/twistd.log
USER=cltbld
VERSIONER_PYTHON_PREFER_32_BIT=no
VERSIONER_PYTHON_VERSION=2.7
XPCOM_DEBUG_BREAK=warn
XPC_FLAGS=0x0
XPC_SERVICE_NAME=0
__CF_USER_TEXT_ENCODING=0x1C:0:0
using PTY: False
program finished with exit code 0
elapsedTime=0.025191
========= master_lag: 0.09 =========
========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-24 18:31:17.606768) =========
========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-24 18:31:17.607123) =========
bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 788365ddaf61746bc66479335ae72d3e566c38ec --destination scripts --debug'
in dir /builds/slave/test/. (timeout 1200 secs)
watching logfiles {}
argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/fx-team --rev 788365ddaf61746bc66479335ae72d3e566c38ec --destination scripts --debug']
environment:
Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.xpal0DPj4f/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.92gVrI0cof/Listeners
TMPDIR=/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/
TWISTD_LOG_PATH=/builds/slave/twistd.log
USER=cltbld
VERSIONER_PYTHON_PREFER_32_BIT=no
VERSIONER_PYTHON_VERSION=2.7
XPCOM_DEBUG_BREAK=warn
XPC_FLAGS=0x0
XPC_SERVICE_NAME=0
__CF_USER_TEXT_ENCODING=0x1C:0:0
using PTY: False
2016-05-24 18:31:17,096 truncating revision to first 12 chars
2016-05-24 18:31:17,096 Setting DEBUG logging.
2016-05-24 18:31:17,096 attempt 1/10
2016-05-24 18:31:17,096 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/788365ddaf61?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness
2016-05-24 18:31:17,757 unpacking tar archive at: fx-team-788365ddaf61/testing/mozharness/
program finished with exit code 0
elapsedTime=0.913327
========= master_lag: 0.15 =========
========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-24 18:31:18.666458) =========
========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-24 18:31:18.666751) =========
script_repo_revision: 788365ddaf61746bc66479335ae72d3e566c38ec
========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-24 18:31:18.667144) =========
========= Started downloading to oauth.txt (results: 0, elapsed: 5 secs) (at 2016-05-24 18:31:18.667413) =========
========= Finished downloading to oauth.txt (results: 0, elapsed: 5 secs) (at 2016-05-24 18:31:24.123480) =========
========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 18 secs) (at 2016-05-24 18:31:24.123945) =========
/tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team
in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs)
watching logfiles {}
argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Fx-Team', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team']
environment:
Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.xpal0DPj4f/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.92gVrI0cof/Listeners
TMPDIR=/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/
TWISTD_LOG_PATH=/builds/slave/twistd.log
USER=cltbld
VERSIONER_PYTHON_PREFER_32_BIT=no
VERSIONER_PYTHON_VERSION=2.7
XPCOM_DEBUG_BREAK=warn
XPC_FLAGS=0x0
XPC_SERVICE_NAME=0
__CF_USER_TEXT_ENCODING=0x1C:0:0
using PTY: False
18:31:23 INFO - MultiFileLogger online at 20160524 18:31:23 in /builds/slave/test
18:31:23 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team
18:31:23 INFO - Dumping config to /builds/slave/test/logs/localconfig.json.
18:31:23 INFO - {'append_to_log': False,
18:31:23 INFO - 'base_work_dir': '/builds/slave/test',
18:31:23 INFO - 'blob_upload_branch': 'Fx-Team',
18:31:23 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt',
18:31:23 INFO - 'branch': 'Fx-Team',
18:31:23 INFO - 'buildbot_json_path': 'buildprops.json',
18:31:23 INFO - 'config_files': ('talos/mac_config.py',),
18:31:23 INFO - 'default_actions': ('clobber',
18:31:23 INFO - 'read-buildbot-config',
18:31:23 INFO - 'download-and-extract',
18:31:23 INFO - 'populate-webroot',
18:31:23 INFO - 'create-virtualenv',
18:31:23 INFO - 'install',
18:31:23 INFO - 'run-tests'),
18:31:23 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',),
18:31:23 INFO - 'download_minidump_stackwalk': True,
18:31:23 INFO - 'download_symbols': 'ondemand',
18:31:23 INFO - 'exes': {'python': '/tools/buildbot/bin/python',
18:31:23 INFO - 'tooltool.py': '/tools/tooltool.py',
18:31:23 INFO - 'virtualenv': ('/tools/buildbot/bin/python',
18:31:23 INFO - '/tools/misc-python/virtualenv.py')},
18:31:23 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub',
18:31:23 INFO - 'http://pypi.pub.build.mozilla.org/pub'),
18:31:23 INFO - 'installer_path': 'installer.exe',
18:31:23 INFO - 'log_level': 'info',
18:31:23 INFO - 'log_name': 'talos',
18:31:23 INFO - 'log_to_console': True,
18:31:23 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk',
18:31:23 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest',
18:31:23 INFO - 'opt_config_files': (),
18:31:23 INFO - 'pip_index': False,
18:31:23 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'),
18:31:23 INFO - 'cmd': ('bash',
18:31:23 INFO - '-c',
18:31:23 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'),
18:31:23 INFO - 'enabled': True,
18:31:23 INFO - 'halt_on_failure': False,
18:31:23 INFO - 'name': 'check_screen_resolution'},),
18:31:23 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'),
18:31:23 INFO - 'cmd': ('bash',
18:31:23 INFO - '-c',
18:31:23 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'),
18:31:23 INFO - 'enabled': True,
18:31:23 INFO - 'halt_on_failure': False,
18:31:23 INFO - 'name': 'check_screen_resolution'},),
18:31:23 INFO - 'run_cmd_checks_enabled': True,
18:31:23 INFO - 'sps_profile': False,
18:31:23 INFO - 'sps_profile_interval': 0,
18:31:23 INFO - 'suite': 'dromaeojs',
18:31:23 INFO - 'system_bits': '32',
18:31:23 INFO - 'talos_extra_options': ('--webServer', 'localhost'),
18:31:23 INFO - 'title': 't-yosemite-r7-0246',
18:31:23 INFO - 'tooltool_cache': '/builds/tooltool_cache',
18:31:23 INFO - 'use_talos_json': True,
18:31:23 INFO - 'virtualenv_path': '/builds/slave/test/build/venv',
18:31:23 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None},
18:31:23 INFO - 'work_dir': 'build'}
18:31:23 INFO - #####
18:31:23 INFO - ##### Running clobber step.
18:31:23 INFO - #####
18:31:23 INFO - Running pre-action listener: _resource_record_pre_action
18:31:23 INFO - Running main action method: clobber
18:31:23 INFO - rmtree: /builds/slave/test/build
18:31:23 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1
18:31:25 INFO - Running post-action listener: _resource_record_post_action
18:31:25 INFO - #####
18:31:25 INFO - ##### Finished clobber step (success)
18:31:25 INFO - #####
18:31:25 INFO - #####
18:31:25 INFO - ##### Running read-buildbot-config step.
18:31:25 INFO - #####
18:31:25 INFO - Running pre-action listener: _resource_record_pre_action
18:31:25 INFO - Running main action method: read_buildbot_config
18:31:25 INFO - Using buildbot properties:
18:31:25 INFO - {
18:31:25 INFO - "project": "",
18:31:25 INFO - "product": "firefox",
18:31:25 INFO - "script_repo_revision": "production",
18:31:25 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs",
18:31:25 INFO - "repository": "",
18:31:25 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs",
18:31:25 INFO - "buildid": "20160524164650",
18:31:25 INFO - "pgo_build": "False",
18:31:25 INFO - "basedir": "/builds/slave/test",
18:31:25 INFO - "buildnumber": 722,
18:31:25 INFO - "slavename": "t-yosemite-r7-0246",
18:31:25 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/",
18:31:25 INFO - "platform": "yosemite_r7",
18:31:25 INFO - "scheduler": "tests-fx-team-macosx64-talos",
18:31:25 INFO - "branch": "fx-team",
18:31:25 INFO - "repo_path": "integration/fx-team",
18:31:25 INFO - "revision": "788365ddaf61746bc66479335ae72d3e566c38ec",
18:31:25 INFO - "stage_platform": "macosx64",
18:31:25 INFO - "builduid": "14755ce953e344db8806d97804776a25",
18:31:25 INFO - "slavebuilddir": "test"
18:31:25 INFO - }
18:31:25 INFO - Found installer url https://queue.taskcluster.net/v1/task/HkqLcYedTf6XAzOv4myc3Q/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg.
18:31:25 INFO - Running post-action listener: _resource_record_post_action
18:31:25 INFO - #####
18:31:25 INFO - ##### Finished read-buildbot-config step (success)
18:31:25 INFO - #####
18:31:25 INFO - #####
18:31:25 INFO - ##### Running download-and-extract step.
18:31:25 INFO - #####
18:31:25 INFO - Running pre-action listener: _resource_record_pre_action
18:31:25 INFO - Running main action method: download_and_extract
18:31:25 INFO - mkdir: /builds/slave/test/build/tests
18:31:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]}
18:31:25 INFO - https://queue.taskcluster.net/v1/task/HkqLcYedTf6XAzOv4myc3Q/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net
18:31:25 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HkqLcYedTf6XAzOv4myc3Q/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json
18:31:25 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HkqLcYedTf6XAzOv4myc3Q/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json
18:31:25 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HkqLcYedTf6XAzOv4myc3Q/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json
18:31:25 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HkqLcYedTf6XAzOv4myc3Q/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json'}, attempt #1
18:31:27 INFO - Downloaded 1199 bytes.
18:31:27 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json
18:31:27 INFO - Using the following test package requirements:
18:31:27 INFO - {u'common': [u'firefox-49.0a1.en-US.mac.common.tests.zip'],
18:31:27 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip',
18:31:27 INFO - u'firefox-49.0a1.en-US.mac.cppunittest.tests.zip'],
18:31:27 INFO - u'gtest': [u'firefox-49.0a1.en-US.mac.common.tests.zip',
18:31:27 INFO - u'firefox-49.0a1.en-US.mac.gtest.tests.zip'],
18:31:27 INFO - u'jittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip',
18:31:27 INFO - u'jsshell-mac.zip'],
18:31:27 INFO - u'mochitest': [u'firefox-49.0a1.en-US.mac.common.tests.zip',
18:31:27 INFO - u'firefox-49.0a1.en-US.mac.mochitest.tests.zip'],
18:31:27 INFO - u'mozbase': [u'firefox-49.0a1.en-US.mac.common.tests.zip'],
18:31:27 INFO - u'reftest': [u'firefox-49.0a1.en-US.mac.common.tests.zip',
18:31:27 INFO - u'firefox-49.0a1.en-US.mac.reftest.tests.zip'],
18:31:27 INFO - u'talos': [u'firefox-49.0a1.en-US.mac.common.tests.zip',
18:31:27 INFO - u'firefox-49.0a1.en-US.mac.talos.tests.zip'],
18:31:27 INFO - u'web-platform': [u'firefox-49.0a1.en-US.mac.common.tests.zip',
18:31:27 INFO - u'firefox-49.0a1.en-US.mac.web-platform.tests.zip'],
18:31:27 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.mac.common.tests.zip',
18:31:27 INFO - u'firefox-49.0a1.en-US.mac.xpcshell.tests.zip']}
18:31:27 INFO - Downloading packages: [u'firefox-49.0a1.en-US.mac.common.tests.zip'] for test suite category: common
18:31:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]}
18:31:27 INFO - https://queue.taskcluster.net/v1/task/HkqLcYedTf6XAzOv4myc3Q/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net
18:31:27 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HkqLcYedTf6XAzOv4myc3Q/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip
18:31:27 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HkqLcYedTf6XAzOv4myc3Q/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip
18:31:27 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HkqLcYedTf6XAzOv4myc3Q/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip
18:31:27 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HkqLcYedTf6XAzOv4myc3Q/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip'}, attempt #1
18:31:34 INFO - Downloaded 18632966 bytes.
18:31:34 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests']
18:31:34 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests
18:31:37 INFO - Return code: 0
18:31:37 INFO - Downloading packages: [u'firefox-49.0a1.en-US.mac.common.tests.zip', u'firefox-49.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos
18:31:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]}
18:31:37 INFO - https://queue.taskcluster.net/v1/task/HkqLcYedTf6XAzOv4myc3Q/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net
18:31:37 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HkqLcYedTf6XAzOv4myc3Q/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip
18:31:37 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HkqLcYedTf6XAzOv4myc3Q/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip
18:31:37 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HkqLcYedTf6XAzOv4myc3Q/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip
18:31:37 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HkqLcYedTf6XAzOv4myc3Q/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip'}, attempt #1
18:31:39 INFO - Downloaded 18632966 bytes.
18:31:39 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests']
18:31:39 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests
18:31:43 INFO - Return code: 0
18:31: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')]}
18:31:43 INFO - https://queue.taskcluster.net/v1/task/HkqLcYedTf6XAzOv4myc3Q/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net
18:31:43 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HkqLcYedTf6XAzOv4myc3Q/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip
18:31:43 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HkqLcYedTf6XAzOv4myc3Q/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip
18:31:43 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HkqLcYedTf6XAzOv4myc3Q/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.mac.talos.tests.zip
18:31:43 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HkqLcYedTf6XAzOv4myc3Q/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.talos.tests.zip'}, attempt #1
18:31:44 INFO - Downloaded 10840359 bytes.
18:31:44 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests']
18:31:44 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests
18:31:44 INFO - Return code: 0
18:31:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]}
18:31:44 INFO - https://queue.taskcluster.net/v1/task/HkqLcYedTf6XAzOv4myc3Q/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg matches https://queue.taskcluster.net
18:31:44 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HkqLcYedTf6XAzOv4myc3Q/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg
18:31:44 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HkqLcYedTf6XAzOv4myc3Q/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg
18:31:44 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HkqLcYedTf6XAzOv4myc3Q/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg
18:31:44 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HkqLcYedTf6XAzOv4myc3Q/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg'}, attempt #1
18:31:47 INFO - Downloaded 110242124 bytes.
18:31:47 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/HkqLcYedTf6XAzOv4myc3Q/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg
18:31:47 INFO - mkdir: /builds/slave/test/properties
18:31:47 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url
18:31:47 INFO - Writing to file /builds/slave/test/properties/build_url
18:31:47 INFO - Contents:
18:31:47 INFO - build_url:https://queue.taskcluster.net/v1/task/HkqLcYedTf6XAzOv4myc3Q/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg
18:31:48 INFO - Running post-action listener: _resource_record_post_action
18:31:48 INFO - Running post-action listener: set_extra_try_arguments
18:31:48 INFO - #####
18:31:48 INFO - ##### Finished download-and-extract step (success)
18:31:48 INFO - #####
18:31:48 INFO - #####
18:31:48 INFO - ##### Running populate-webroot step.
18:31:48 INFO - #####
18:31:48 INFO - Running pre-action listener: _resource_record_pre_action
18:31:48 INFO - Running main action method: populate_webroot
18:31:49 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']},
18:31:49 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']},
18:31:49 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome',
18:31:49 INFO - '--tppagecycles',
18:31:49 INFO - '7'],
18:31:49 INFO - 'tests': ['tsvgm']}},
18:31:49 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'],
18:31:49 INFO - 'tests': ['tresize', 'tcanvasmark']},
18:31:49 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']},
18:31:49 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'],
18:31:49 INFO - 'tests': ['dromaeo_css', 'kraken']},
18:31:49 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']},
18:31:49 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip',
18:31:49 INFO - 'talos_options': ['--disable-e10s'],
18:31:49 INFO - 'tests': ['tp5o_scroll', 'glterrain']},
18:31:49 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip',
18:31:49 INFO - 'tests': ['tp5o_scroll', 'glterrain']},
18:31:49 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip',
18:31:49 INFO - 'talos_options': ['--disable-e10s'],
18:31:49 INFO - 'tests': ['damp', 'tps']},
18:31:49 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip',
18:31:49 INFO - 'tests': ['damp', 'tps']},
18:31:49 INFO - 'g3': {'talos_options': ['--disable-e10s'],
18:31:49 INFO - 'tests': ['dromaeo_dom']},
18:31:49 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']},
18:31:49 INFO - 'other': {'talos_options': ['--disable-e10s'],
18:31:49 INFO - 'tests': ['a11yr',
18:31:49 INFO - 'ts_paint',
18:31:49 INFO - 'tpaint',
18:31:49 INFO - 'sessionrestore',
18:31:49 INFO - 'sessionrestore_no_auto_restore',
18:31:49 INFO - 'tabpaint']},
18:31:49 INFO - 'other-e10s': {'tests': ['a11yr',
18:31:49 INFO - 'ts_paint',
18:31:49 INFO - 'tpaint',
18:31:49 INFO - 'sessionrestore',
18:31:49 INFO - 'sessionrestore_no_auto_restore',
18:31:49 INFO - 'tabpaint']},
18:31:49 INFO - 'other-e10s_l64': {'tests': ['a11yr',
18:31:49 INFO - 'ts_paint',
18:31:49 INFO - 'tpaint',
18:31:49 INFO - 'sessionrestore',
18:31:49 INFO - 'sessionrestore_no_auto_restore',
18:31:49 INFO - 'tabpaint']},
18:31:49 INFO - 'other-e10s_nol64': {'tests': ['a11yr',
18:31:49 INFO - 'ts_paint',
18:31:49 INFO - 'tpaint',
18:31:49 INFO - 'sessionrestore',
18:31:49 INFO - 'sessionrestore_no_auto_restore',
18:31:49 INFO - 'tabpaint']},
18:31:49 INFO - 'other_l64': {'talos_options': ['--disable-e10s'],
18:31:49 INFO - 'tests': ['a11yr',
18:31:49 INFO - 'ts_paint',
18:31:49 INFO - 'tpaint',
18:31:49 INFO - 'sessionrestore',
18:31:49 INFO - 'sessionrestore_no_auto_restore',
18:31:49 INFO - 'tabpaint']},
18:31:49 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'],
18:31:49 INFO - 'tests': ['a11yr',
18:31:49 INFO - 'ts_paint',
18:31:49 INFO - 'tpaint',
18:31:49 INFO - 'sessionrestore',
18:31:49 INFO - 'sessionrestore_no_auto_restore',
18:31:49 INFO - 'tabpaint']},
18:31:49 INFO - 'svgr': {'talos_options': ['--disable-e10s'],
18:31:49 INFO - 'tests': ['tsvgx',
18:31:49 INFO - 'tsvgr_opacity',
18:31:49 INFO - 'tart',
18:31:49 INFO - 'tscrollx',
18:31:49 INFO - 'cart']},
18:31:49 INFO - 'svgr-e10s': {'tests': ['tsvgx',
18:31:49 INFO - 'tsvgr_opacity',
18:31:49 INFO - 'tart',
18:31:49 INFO - 'tscrollx',
18:31:49 INFO - 'cart']},
18:31:49 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip',
18:31:49 INFO - 'talos_options': ['--disable-e10s'],
18:31:49 INFO - 'tests': ['tp5o']},
18:31:49 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip',
18:31:49 INFO - 'tests': ['tp5o']},
18:31:49 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip',
18:31:49 INFO - 'talos_options': ['--disable-e10s',
18:31:49 INFO - '--xperf_path',
18:31:49 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'],
18:31:49 INFO - 'tests': ['tp5n']},
18:31:49 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip',
18:31:49 INFO - 'talos_options': ['--xperf_path',
18:31:49 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'],
18:31:49 INFO - 'tests': ['tp5n']}},
18:31:49 INFO - 'talos.zip': {'path': '',
18:31:49 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}}
18:31:49 INFO - Running post-action listener: _resource_record_post_action
18:31:49 INFO - #####
18:31:49 INFO - ##### Finished populate-webroot step (success)
18:31:49 INFO - #####
18:31:49 INFO - #####
18:31:49 INFO - ##### Running create-virtualenv step.
18:31:49 INFO - #####
18:31:49 INFO - Running pre-action listener: _resource_record_pre_action
18:31:49 INFO - Running main action method: create_virtualenv
18:31:49 INFO - Creating virtualenv /builds/slave/test/build/venv
18:31:49 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build
18:31:49 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv
18:31:49 INFO - The --no-site-packages flag is deprecated; it is now the default behavior.
18:31:49 INFO - Using real prefix '/tools/python27'
18:31:49 INFO - New python executable in /builds/slave/test/build/venv/bin/python
18:31:49 INFO - Installing distribute.............................................................................................................................................................................................done.
18:31:52 INFO - Installing pip.................done.
18:31:52 INFO - Return code: 0
18:31:52 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv
18:31:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]}
18:31:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
18:31:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub
18:31:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]}
18:31:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
18:31:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub
18:31:52 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10deada30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10deb3570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff1d9cd1990>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dd51d30>, '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 0x10db75db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10dd48b20>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xpal0DPj4f/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.92gVrI0cof/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1
18:31:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build
18:31:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5
18:31:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xpal0DPj4f/Render',
18:31:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared',
18:31:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared',
18:31:52 INFO - 'HOME': '/Users/cltbld',
18:31:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true',
18:31:52 INFO - 'LOGNAME': 'cltbld',
18:31:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
18:31:52 INFO - 'MOZ_NO_REMOTE': '1',
18:31:52 INFO - 'NO_EM_RESTART': '1',
18:31:52 INFO - 'PAGER': '/bin/cat',
18:31:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11',
18:31:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
18:31:52 INFO - 'PWD': '/builds/slave/test',
18:31:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg',
18:31:52 INFO - 'SHELL': '/bin/bash',
18:31:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.92gVrI0cof/Listeners',
18:31:52 INFO - 'TMPDIR': '/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/',
18:31:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log',
18:31:52 INFO - 'USER': 'cltbld',
18:31:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no',
18:31:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7',
18:31:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
18:31:52 INFO - 'XPC_FLAGS': '0x0',
18:31:52 INFO - 'XPC_SERVICE_NAME': '0',
18:31:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'}
18:31:53 INFO - Ignoring indexes: https://pypi.python.org/simple/
18:31:53 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg
18:31:53 INFO - Cleaning up...
18:31:53 INFO - Return code: 0
18:31:53 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv
18:31: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')]}
18:31:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
18:31:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub
18:31: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')]}
18:31:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
18:31:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub
18:31:53 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10deada30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10deb3570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff1d9cd1990>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dd51d30>, '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 0x10db75db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10dd48b20>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xpal0DPj4f/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.92gVrI0cof/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1
18:31:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build
18:31:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1
18:31:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xpal0DPj4f/Render',
18:31:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared',
18:31:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared',
18:31:53 INFO - 'HOME': '/Users/cltbld',
18:31:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true',
18:31:53 INFO - 'LOGNAME': 'cltbld',
18:31:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
18:31:53 INFO - 'MOZ_NO_REMOTE': '1',
18:31:53 INFO - 'NO_EM_RESTART': '1',
18:31:53 INFO - 'PAGER': '/bin/cat',
18:31:53 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11',
18:31:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
18:31:53 INFO - 'PWD': '/builds/slave/test',
18:31:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg',
18:31:53 INFO - 'SHELL': '/bin/bash',
18:31:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.92gVrI0cof/Listeners',
18:31:53 INFO - 'TMPDIR': '/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/',
18:31:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log',
18:31:53 INFO - 'USER': 'cltbld',
18:31:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no',
18:31:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7',
18:31:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
18:31:53 INFO - 'XPC_FLAGS': '0x0',
18:31:53 INFO - 'XPC_SERVICE_NAME': '0',
18:31:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'}
18:31:53 INFO - Ignoring indexes: https://pypi.python.org/simple/
18:31:53 INFO - Downloading/unpacking psutil>=3.1.1
18:31:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available
18:31:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available
18:31:53 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available
18:31:53 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available
18:31:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available
18:31:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available
18:31:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil
18:31:56 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build'
18:31:56 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects
...
18:31:56 INFO - Installing collected packages: psutil
18:31:56 INFO - Running setup.py install for psutil
18:31:56 INFO - building 'psutil._psutil_osx' extension
18:31:56 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o
18:31:57 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o
18:31:57 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o
18:31:57 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit
18:31:57 INFO - building 'psutil._psutil_posix' extension
18:31:57 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o
18:31:57 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration]
18:31:57 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr);
18:31:57 INFO - ^
18:31:57 INFO - 1 warning generated.
18:31:57 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so
18:31:57 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build'
18:31:57 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ...
18:31:57 INFO - Successfully installed psutil
18:31:57 INFO - Cleaning up...
18:31:57 INFO - Return code: 0
18:31:57 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv
18:31: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')]}
18:31:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
18:31:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub
18:31: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')]}
18:31:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
18:31:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub
18:31:57 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10deada30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10deb3570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff1d9cd1990>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dd51d30>, '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 0x10db75db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10dd48b20>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xpal0DPj4f/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.92gVrI0cof/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1
18:31:57 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build
18:31:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3
18:31:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xpal0DPj4f/Render',
18:31:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared',
18:31:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared',
18:31:57 INFO - 'HOME': '/Users/cltbld',
18:31:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true',
18:31:57 INFO - 'LOGNAME': 'cltbld',
18:31:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
18:31:57 INFO - 'MOZ_NO_REMOTE': '1',
18:31:57 INFO - 'NO_EM_RESTART': '1',
18:31:57 INFO - 'PAGER': '/bin/cat',
18:31:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11',
18:31:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
18:31:57 INFO - 'PWD': '/builds/slave/test',
18:31:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg',
18:31:57 INFO - 'SHELL': '/bin/bash',
18:31:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.92gVrI0cof/Listeners',
18:31:57 INFO - 'TMPDIR': '/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/',
18:31:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log',
18:31:57 INFO - 'USER': 'cltbld',
18:31:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no',
18:31:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7',
18:31:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
18:31:57 INFO - 'XPC_FLAGS': '0x0',
18:31:57 INFO - 'XPC_SERVICE_NAME': '0',
18:31:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'}
18:31:58 INFO - Ignoring indexes: https://pypi.python.org/simple/
18:31:58 INFO - Downloading/unpacking mozsystemmonitor==0.3
18:31:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available
18:31:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available
18:31:58 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available
18:31:58 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available
18:31:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available
18:31:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available
18:32:00 INFO - Downloading mozsystemmonitor-0.3.tar.gz
18:32:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor
18:32:00 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3)
18:32:00 INFO - Installing collected packages: mozsystemmonitor
18:32:00 INFO - Running setup.py install for mozsystemmonitor
18:32:00 INFO - Successfully installed mozsystemmonitor
18:32:00 INFO - Cleaning up...
18:32:00 INFO - Return code: 0
18:32:00 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv
18:32:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]}
18:32:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
18:32:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub
18:32:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]}
18:32:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
18:32:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub
18:32:00 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10deada30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10deb3570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff1d9cd1990>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dd51d30>, '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 0x10db75db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10dd48b20>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xpal0DPj4f/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.92gVrI0cof/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1
18:32:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build
18:32:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4
18:32:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xpal0DPj4f/Render',
18:32:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared',
18:32:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared',
18:32:00 INFO - 'HOME': '/Users/cltbld',
18:32:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true',
18:32:00 INFO - 'LOGNAME': 'cltbld',
18:32:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
18:32:00 INFO - 'MOZ_NO_REMOTE': '1',
18:32:00 INFO - 'NO_EM_RESTART': '1',
18:32:00 INFO - 'PAGER': '/bin/cat',
18:32:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11',
18:32:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
18:32:00 INFO - 'PWD': '/builds/slave/test',
18:32:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg',
18:32:00 INFO - 'SHELL': '/bin/bash',
18:32:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.92gVrI0cof/Listeners',
18:32:00 INFO - 'TMPDIR': '/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/',
18:32:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log',
18:32:00 INFO - 'USER': 'cltbld',
18:32:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no',
18:32:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7',
18:32:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
18:32:00 INFO - 'XPC_FLAGS': '0x0',
18:32:00 INFO - 'XPC_SERVICE_NAME': '0',
18:32:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'}
18:32:01 INFO - Ignoring indexes: https://pypi.python.org/simple/
18:32:01 INFO - Downloading/unpacking blobuploader==1.2.4
18:32:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available
18:32:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available
18:32:01 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available
18:32:01 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available
18:32:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available
18:32:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available
18:32:03 INFO - Downloading blobuploader-1.2.4.tar.gz
18:32:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader
18:32:03 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4)
18:32:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available
18:32:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available
18:32:03 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available
18:32:03 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available
18:32:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available
18:32:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available
18:32:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests
18:32:04 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4)
18:32:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available
18:32:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available
18:32:04 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available
18:32:04 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available
18:32:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available
18:32:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available
18:32:04 INFO - Downloading docopt-0.6.1.tar.gz
18:32:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt
18:32:04 INFO - Installing collected packages: blobuploader, requests, docopt
18:32:04 INFO - Running setup.py install for blobuploader
18:32:04 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775
18:32:04 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775
18:32:04 INFO - Running setup.py install for requests
18:32:05 INFO - Running setup.py install for docopt
18:32:05 INFO - Successfully installed blobuploader requests docopt
18:32:05 INFO - Cleaning up...
18:32:05 INFO - Return code: 0
18:32:05 INFO - Installing None into virtualenv /builds/slave/test/build/venv
18:32:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]}
18:32:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
18:32:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub
18:32:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]}
18:32:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
18:32:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub
18:32:05 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10deada30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10deb3570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff1d9cd1990>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dd51d30>, '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 0x10db75db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10dd48b20>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xpal0DPj4f/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.92gVrI0cof/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1
18:32:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config
18:32:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub
18:32:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xpal0DPj4f/Render',
18:32:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared',
18:32:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared',
18:32:05 INFO - 'HOME': '/Users/cltbld',
18:32:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true',
18:32:05 INFO - 'LOGNAME': 'cltbld',
18:32:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
18:32:05 INFO - 'MOZ_NO_REMOTE': '1',
18:32:05 INFO - 'NO_EM_RESTART': '1',
18:32:05 INFO - 'PAGER': '/bin/cat',
18:32:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11',
18:32:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
18:32:05 INFO - 'PWD': '/builds/slave/test',
18:32:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg',
18:32:05 INFO - 'SHELL': '/bin/bash',
18:32:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.92gVrI0cof/Listeners',
18:32:05 INFO - 'TMPDIR': '/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/',
18:32:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log',
18:32:05 INFO - 'USER': 'cltbld',
18:32:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no',
18:32:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7',
18:32:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
18:32:05 INFO - 'XPC_FLAGS': '0x0',
18:32:05 INFO - 'XPC_SERVICE_NAME': '0',
18:32:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'}
18:32:05 INFO - Ignoring indexes: https://pypi.python.org/simple/
18:32:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser
18:32:05 INFO - Running setup.py (path:/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/pip-vGBWgw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser
18:32:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash
18:32:06 INFO - Running setup.py (path:/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/pip-X0ngWy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash
18:32:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug
18:32:06 INFO - Running setup.py (path:/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/pip-YzIBHQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug
18:32:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice
18:32:06 INFO - Running setup.py (path:/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/pip-oRLohB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice
18:32:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile
18:32:06 INFO - Running setup.py (path:/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/pip-jf4TWK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile
18:32:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd
18:32:06 INFO - Running setup.py (path:/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/pip-GkMhto-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd
18:32:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo
18:32:06 INFO - Running setup.py (path:/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/pip-c4H82D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo
18:32:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall
18:32:06 INFO - Running setup.py (path:/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/pip-aYEEqw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall
18:32:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak
18:32:06 INFO - Running setup.py (path:/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/pip-nrZjac-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak
18:32:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog
18:32:06 INFO - Running setup.py (path:/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/pip-gi2IjS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog
18:32:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork
18:32:06 INFO - Running setup.py (path:/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/pip-aalPTz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork
18:32:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess
18:32:07 INFO - Running setup.py (path:/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/pip-09BZbF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess
18:32:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile
18:32:07 INFO - Running setup.py (path:/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/pip-biGTvq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile
18:32:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner
18:32:07 INFO - Running setup.py (path:/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/pip-f9sTWO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner
18:32:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot
18:32:07 INFO - Running setup.py (path:/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/pip-mQ1JkO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot
18:32:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest
18:32:07 INFO - Running setup.py (path:/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/pip-iBFQVr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest
18:32:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion
18:32:07 INFO - Running setup.py (path:/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/pip-356N7x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion
18:32:07 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion
18:32:07 INFO - Running setup.py install for manifestparser
18:32:07 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin
18:32:07 INFO - Running setup.py install for mozcrash
18:32:07 INFO - Running setup.py install for mozdebug
18:32:08 INFO - Running setup.py install for mozdevice
18:32:08 INFO - Installing sutini script to /builds/slave/test/build/venv/bin
18:32:08 INFO - Installing dm script to /builds/slave/test/build/venv/bin
18:32:08 INFO - Running setup.py install for mozfile
18:32:08 INFO - Running setup.py install for mozhttpd
18:32:08 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin
18:32:08 INFO - Running setup.py install for mozinfo
18:32:08 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin
18:32:08 INFO - Running setup.py install for mozInstall
18:32:08 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin
18:32:08 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin
18:32:08 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin
18:32:08 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin
18:32:08 INFO - Running setup.py install for mozleak
18:32:09 INFO - Running setup.py install for mozlog
18:32:09 INFO - Installing structlog script to /builds/slave/test/build/venv/bin
18:32:09 INFO - Running setup.py install for moznetwork
18:32:09 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin
18:32:09 INFO - Running setup.py install for mozprocess
18:32:09 INFO - Running setup.py install for mozprofile
18:32:09 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin
18:32:09 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin
18:32:09 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin
18:32:09 INFO - Running setup.py install for mozrunner
18:32:09 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin
18:32:09 INFO - Running setup.py install for mozscreenshot
18:32:09 INFO - Running setup.py install for moztest
18:32:10 INFO - Running setup.py install for mozversion
18:32:10 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin
18:32:10 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion
18:32:10 INFO - Cleaning up...
18:32:10 INFO - Return code: 0
18:32:10 INFO - Installing None into virtualenv /builds/slave/test/build/venv
18:32: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')]}
18:32:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
18:32:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub
18:32: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')]}
18:32:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
18:32:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub
18:32:10 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10deada30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10deb3570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff1d9cd1990>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dd51d30>, '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 0x10db75db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10dd48b20>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xpal0DPj4f/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.92gVrI0cof/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1
18:32:10 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config
18:32:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub
18:32:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xpal0DPj4f/Render',
18:32:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared',
18:32:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared',
18:32:10 INFO - 'HOME': '/Users/cltbld',
18:32:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true',
18:32:10 INFO - 'LOGNAME': 'cltbld',
18:32:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
18:32:10 INFO - 'MOZ_NO_REMOTE': '1',
18:32:10 INFO - 'NO_EM_RESTART': '1',
18:32:10 INFO - 'PAGER': '/bin/cat',
18:32:10 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11',
18:32:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
18:32:10 INFO - 'PWD': '/builds/slave/test',
18:32:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg',
18:32:10 INFO - 'SHELL': '/bin/bash',
18:32:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.92gVrI0cof/Listeners',
18:32:10 INFO - 'TMPDIR': '/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/',
18:32:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log',
18:32:10 INFO - 'USER': 'cltbld',
18:32:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no',
18:32:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7',
18:32:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
18:32:10 INFO - 'XPC_FLAGS': '0x0',
18:32:10 INFO - 'XPC_SERVICE_NAME': '0',
18:32:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'}
18:32:10 INFO - Ignoring indexes: https://pypi.python.org/simple/
18:32:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser
18:32:10 INFO - Running setup.py (path:/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/pip-q5zcLB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser
18:32:10 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1))
18:32:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash
18:32:10 INFO - Running setup.py (path:/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/pip-4lCTPG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash
18:32:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2))
18:32:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug
18:32:10 INFO - Running setup.py (path:/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/pip-qAeU8u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug
18:32:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3))
18:32:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice
18:32:10 INFO - Running setup.py (path:/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/pip-l3hEN9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice
18:32:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4))
18:32:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile
18:32:11 INFO - Running setup.py (path:/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/pip-3S8fZp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile
18:32:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5))
18:32:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd
18:32:11 INFO - Running setup.py (path:/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/pip-66uf4u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd
18:32:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6))
18:32:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo
18:32:11 INFO - Running setup.py (path:/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/pip-CVHgUP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo
18:32:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7))
18:32:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall
18:32:11 INFO - Running setup.py (path:/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/pip-ZTDxsH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall
18:32:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8))
18:32:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak
18:32:11 INFO - Running setup.py (path:/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/pip-G5e0Cy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak
18:32:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9))
18:32:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog
18:32:11 INFO - Running setup.py (path:/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/pip-N46Snt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog
18:32:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.2 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10))
18:32:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork
18:32:11 INFO - Running setup.py (path:/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/pip-Ku9GSn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork
18:32:11 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11))
18:32:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess
18:32:11 INFO - Running setup.py (path:/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/pip-itiR8n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess
18:32:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12))
18:32:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile
18:32:11 INFO - Running setup.py (path:/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/pip-YvhXD1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile
18:32:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13))
18:32:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner
18:32:11 INFO - Running setup.py (path:/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/pip-uuUp8y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner
18:32:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.11 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14))
18:32:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot
18:32:12 INFO - Running setup.py (path:/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/pip-LoN2QS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot
18:32:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15))
18:32:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest
18:32:12 INFO - Running setup.py (path:/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/pip-3NPCFy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest
18:32:12 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16))
18:32:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion
18:32:12 INFO - Running setup.py (path:/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/pip-BYM7Ql-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion
18:32:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17))
18:32:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2))
18:32:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2))
18:32:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3))
18:32:12 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4))
18:32:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4))
18:32:12 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10))
18:32:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available
18:32:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available
18:32:12 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available
18:32:12 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available
18:32:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available
18:32:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available
18:32:14 INFO - Downloading blessings-1.6.tar.gz
18:32:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings
18:32:14 INFO - Installing collected packages: blessings
18:32:14 INFO - Running setup.py install for blessings
18:32:15 INFO - Successfully installed blessings
18:32:15 INFO - Cleaning up...
18:32:15 INFO - Return code: 0
18:32:15 INFO - Done creating virtualenv /builds/slave/test/build/venv.
18:32:15 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze']
18:32:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze
18:32:15 INFO - Reading from file tmpfile_stdout
18:32:15 INFO - Current package versions:
18:32:15 INFO - blessings == 1.6
18:32:15 INFO - blobuploader == 1.2.4
18:32:15 INFO - docopt == 0.6.1
18:32:15 INFO - manifestparser == 1.1
18:32:15 INFO - mozInstall == 1.12
18:32:15 INFO - mozcrash == 0.17
18:32:15 INFO - mozdebug == 0.1
18:32:15 INFO - mozdevice == 0.48
18:32:15 INFO - mozfile == 1.2
18:32:15 INFO - mozhttpd == 0.7
18:32:15 INFO - mozinfo == 0.9
18:32:15 INFO - mozleak == 0.1
18:32:15 INFO - mozlog == 3.2
18:32:15 INFO - moznetwork == 0.27
18:32:15 INFO - mozprocess == 0.22
18:32:15 INFO - mozprofile == 0.28
18:32:15 INFO - mozrunner == 6.11
18:32:15 INFO - mozscreenshot == 0.1
18:32:15 INFO - mozsystemmonitor == 0.3
18:32:15 INFO - moztest == 0.7
18:32:15 INFO - mozversion == 1.4
18:32:15 INFO - psutil == 3.1.1
18:32:15 INFO - requests == 1.2.3
18:32:15 INFO - wsgiref == 0.1.2
18:32:15 INFO - Installing None into virtualenv /builds/slave/test/build/venv
18:32:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]}
18:32:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
18:32:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub
18:32:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]}
18:32:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
18:32:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub
18:32:15 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10deada30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10deb3570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff1d9cd1990>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dd51d30>, '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 0x10db75db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10dd48b20>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/talos', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xpal0DPj4f/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.92gVrI0cof/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1
18:32:15 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos
18:32:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub
18:32:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xpal0DPj4f/Render',
18:32:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared',
18:32:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared',
18:32:15 INFO - 'HOME': '/Users/cltbld',
18:32:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true',
18:32:15 INFO - 'LOGNAME': 'cltbld',
18:32:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
18:32:15 INFO - 'MOZ_NO_REMOTE': '1',
18:32:15 INFO - 'NO_EM_RESTART': '1',
18:32:15 INFO - 'PAGER': '/bin/cat',
18:32:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11',
18:32:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
18:32:15 INFO - 'PWD': '/builds/slave/test',
18:32:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg',
18:32:15 INFO - 'SHELL': '/bin/bash',
18:32:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.92gVrI0cof/Listeners',
18:32:15 INFO - 'TMPDIR': '/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/',
18:32:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log',
18:32:15 INFO - 'USER': 'cltbld',
18:32:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no',
18:32:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7',
18:32:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
18:32:15 INFO - 'XPC_FLAGS': '0x0',
18:32:15 INFO - 'XPC_SERVICE_NAME': '0',
18:32:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'}
18:32:15 INFO - Ignoring indexes: https://pypi.python.org/simple/
18:32:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1))
18:32:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2))
18:32:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3))
18:32:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4))
18:32:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5))
18:32:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6))
18:32:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7))
18:32:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8))
18:32:15 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9))
18:32:15 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1))
18:32:15 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4))
18:32:15 INFO - Cleaning up...
18:32:15 INFO - Return code: 0
18:32:15 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv
18:32:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]}
18:32:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
18:32:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub
18:32:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]}
18:32:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
18:32:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub
18:32:15 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10deada30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10deb3570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff1d9cd1990>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dd51d30>, '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 0x10db75db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10dd48b20>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xpal0DPj4f/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.92gVrI0cof/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1
18:32:15 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build
18:32:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema
18:32:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xpal0DPj4f/Render',
18:32:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared',
18:32:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared',
18:32:15 INFO - 'HOME': '/Users/cltbld',
18:32:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true',
18:32:15 INFO - 'LOGNAME': 'cltbld',
18:32:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
18:32:15 INFO - 'MOZ_NO_REMOTE': '1',
18:32:15 INFO - 'NO_EM_RESTART': '1',
18:32:15 INFO - 'PAGER': '/bin/cat',
18:32:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11',
18:32:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
18:32:15 INFO - 'PWD': '/builds/slave/test',
18:32:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg',
18:32:15 INFO - 'SHELL': '/bin/bash',
18:32:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.92gVrI0cof/Listeners',
18:32:15 INFO - 'TMPDIR': '/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/',
18:32:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log',
18:32:15 INFO - 'USER': 'cltbld',
18:32:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no',
18:32:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7',
18:32:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
18:32:15 INFO - 'XPC_FLAGS': '0x0',
18:32:15 INFO - 'XPC_SERVICE_NAME': '0',
18:32:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'}
18:32:15 INFO - Ignoring indexes: https://pypi.python.org/simple/
18:32:15 INFO - Downloading/unpacking jsonschema
18:32:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available
18:32:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available
18:32:15 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available
18:32:15 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available
18:32:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available
18:32:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available
18:32:18 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl
18:32:18 INFO - Downloading/unpacking functools32 (from jsonschema)
18:32:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available
18:32:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available
18:32:18 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available
18:32:18 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available
18:32:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available
18:32:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available
18:32:18 INFO - Downloading functools32-3.2.3-2.tar.gz
18:32:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32
18:32:18 INFO - warning: no files found matching '*.txt'
18:32:19 INFO - no previously-included directories found matching 'build'
18:32:19 INFO - no previously-included directories found matching 'dist'
18:32:19 INFO - no previously-included directories found matching '.git*'
18:32:19 INFO - Installing collected packages: jsonschema, functools32
18:32:19 INFO - Running setup.py install for functools32
18:32:19 INFO - warning: no files found matching '*.txt'
18:32:19 INFO - no previously-included directories found matching 'build'
18:32:19 INFO - no previously-included directories found matching 'dist'
18:32:19 INFO - no previously-included directories found matching '.git*'
18:32:19 INFO - Successfully installed jsonschema functools32
18:32:19 INFO - Cleaning up...
18:32:19 INFO - Return code: 0
18:32:19 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv
18:32:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]}
18:32:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
18:32:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub
18:32:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]}
18:32:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
18:32:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub
18:32:19 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10deada30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10deb3570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff1d9cd1990>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dd51d30>, '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 0x10db75db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10dd48b20>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xpal0DPj4f/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.92gVrI0cof/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1
18:32:19 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test/build
18:32:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8
18:32:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xpal0DPj4f/Render',
18:32:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared',
18:32:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared',
18:32:19 INFO - 'HOME': '/Users/cltbld',
18:32:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true',
18:32:19 INFO - 'LOGNAME': 'cltbld',
18:32:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
18:32:19 INFO - 'MOZ_NO_REMOTE': '1',
18:32:19 INFO - 'NO_EM_RESTART': '1',
18:32:19 INFO - 'PAGER': '/bin/cat',
18:32:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11',
18:32:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
18:32:19 INFO - 'PWD': '/builds/slave/test',
18:32:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg',
18:32:19 INFO - 'SHELL': '/bin/bash',
18:32:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.92gVrI0cof/Listeners',
18:32:19 INFO - 'TMPDIR': '/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/',
18:32:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log',
18:32:19 INFO - 'USER': 'cltbld',
18:32:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no',
18:32:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7',
18:32:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
18:32:19 INFO - 'XPC_FLAGS': '0x0',
18:32:19 INFO - 'XPC_SERVICE_NAME': '0',
18:32:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'}
18:32:19 INFO - Ignoring indexes: https://pypi.python.org/simple/
18:32:19 INFO - Downloading/unpacking flake8
18:32:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available
18:32:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available
18:32:19 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available
18:32:19 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available
18:32:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available
18:32:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available
18:32:22 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl
18:32:22 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8)
18:32:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available
18:32:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available
18:32:22 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available
18:32:22 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available
18:32:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available
18:32:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available
18:32:22 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl
18:32:22 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8)
18:32:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available
18:32:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available
18:32:22 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available
18:32:22 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available
18:32:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available
18:32:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available
18:32:23 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8)
18:32:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available
18:32:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available
18:32:23 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available
18:32:23 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available
18:32:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available
18:32:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available
18:32:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8
18:32:23 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs'
18:32:23 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs'
18:32:23 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite'
18:32:23 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite'
18:32:23 INFO - no previously-included directories found matching 'docs/_build'
18:32:23 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8
18:32:23 INFO - Running setup.py install for pep8
18:32:23 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs'
18:32:23 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs'
18:32:23 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite'
18:32:23 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite'
18:32:23 INFO - no previously-included directories found matching 'docs/_build'
18:32:23 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin
18:32:23 INFO - Successfully installed flake8 mccabe pyflakes pep8
18:32:23 INFO - Cleaning up...
18:32:23 INFO - Return code: 0
18:32:23 INFO - Running post-action listener: _resource_record_post_action
18:32:23 INFO - Running post-action listener: _start_resource_monitoring
18:32:23 INFO - Starting resource monitoring.
18:32:23 INFO - #####
18:32:23 INFO - ##### Finished create-virtualenv step (success)
18:32:23 INFO - #####
18:32:23 INFO - #####
18:32:23 INFO - ##### Running install step.
18:32:23 INFO - #####
18:32:23 INFO - Running pre-action listener: _resource_record_pre_action
18:32:23 INFO - Running main action method: install
18:32:23 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze']
18:32:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze
18:32:24 INFO - Reading from file tmpfile_stdout
18:32:24 INFO - Detecting whether we're running mozinstall >=1.0...
18:32:24 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h']
18:32:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h
18:32:24 INFO - Reading from file tmpfile_stdout
18:32:24 INFO - Output received:
18:32:24 INFO - Usage: mozinstall [options] installer
18:32:24 INFO - Options:
18:32:24 INFO - -h, --help show this help message and exit
18:32:24 INFO - -d DEST, --destination=DEST
18:32:24 INFO - Directory to install application into. [default:
18:32:24 INFO - "/builds/slave/test"]
18:32:24 INFO - --app=APP Application being installed. [default: firefox]
18:32:24 INFO - mkdir: /builds/slave/test/build/application
18:32:24 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application']
18:32:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application
18:32:58 INFO - Reading from file tmpfile_stdout
18:32:58 INFO - Output received:
18:32:58 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox
18:32:58 INFO - Running post-action listener: _resource_record_post_action
18:32:58 INFO - #####
18:32:58 INFO - ##### Finished install step (success)
18:32:58 INFO - #####
18:32:58 INFO - #####
18:32:58 INFO - ##### Running run-tests step.
18:32:58 INFO - #####
18:32:58 INFO - Running pre-action listener: _resource_record_pre_action
18:32:58 INFO - Running main action method: run_tests
18:32:58 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'
18:32:58 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build
18:32:58 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType"
18:32:58 INFO - 2016-05-24 18:32:58.289 screenresolution[1645:7600] starting screenresolution argv=screenresolution get
18:32:58 INFO - 2016-05-24 18:32:58.292 screenresolution[1645:7600] Display 0: 1600x1200x32@60
18:32:58 INFO - 2016-05-24 18:32:58.298 screenresolution[1646:7602] starting screenresolution argv=screenresolution list
18:32:58 INFO - Available Modes on Display 0
18:32:58 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75
18:32:58 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60
18:32:58 INFO - 800x600x32@75 800x600x32@60 640x480x32@75
18:32:58 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60
18:32:58 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays:
18:32:58 INFO - Intel Iris:
18:32:58 INFO - Chipset Model: Intel Iris
18:32:58 INFO - Type: GPU
18:32:58 INFO - Bus: Built-In
18:32:58 INFO - VRAM (Dynamic, Max): 1536 MB
18:32:58 INFO - Vendor: Intel (0x8086)
18:32:58 INFO - Device ID: 0x0a2e
18:32:58 INFO - Revision ID: 0x0009
18:32:58 INFO - Displays:
18:32:58 INFO - Display:
18:32:58 INFO - Resolution: 1600 x 1200 @ 60 Hz
18:32:58 INFO - Pixel Depth: 32-Bit Color (ARGB8888)
18:32:58 INFO - Display Serial Number: 1600x1200 60
18:32:58 INFO - Main Display: Yes
18:32:58 INFO - Mirror: Off
18:32:58 INFO - Online: Yes
18:32:58 INFO - Rotation: Supported
18:32:58 INFO - Return code: 0
18:32:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version']
18:32:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version
18:32:58 INFO - Python 2.7.3
18:32:58 INFO - Return code: 0
18:32:58 INFO - grabbing minidump binary from tooltool
18:32:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]}
18:32:58 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: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dd51d30>, '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 0x10db75db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10dd48b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1
18:32:58 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build
18:32:58 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache
18:32:58 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600
18:32:58 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache
18:32:58 INFO - Return code: 0
18:32:58 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755
18:32:58 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir
18:32:58 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir
18:32:58 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk
18:32:58 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir
18:32:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos']
18:32:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos
18:32:59 INFO - Return code: 0
18:32:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/HkqLcYedTf6XAzOv4myc3Q/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0246', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build
18:32:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Fx-Team --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/HkqLcYedTf6XAzOv4myc3Q/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0246 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log
18:32:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xpal0DPj4f/Render',
18:32:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared',
18:32:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared',
18:32:59 INFO - 'HOME': '/Users/cltbld',
18:32:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true',
18:32:59 INFO - 'LOGNAME': 'cltbld',
18:32:59 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir',
18:32:59 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk',
18:32:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
18:32:59 INFO - 'MOZ_NO_REMOTE': '1',
18:32:59 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir',
18:32:59 INFO - 'NO_EM_RESTART': '1',
18:32:59 INFO - 'PAGER': '/bin/cat',
18:32:59 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11',
18:32:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
18:32:59 INFO - 'PWD': '/builds/slave/test',
18:32:59 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos',
18:32:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg',
18:32:59 INFO - 'SHELL': '/bin/bash',
18:32:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.92gVrI0cof/Listeners',
18:32:59 INFO - 'TMPDIR': '/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/',
18:32:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log',
18:32:59 INFO - 'USER': 'cltbld',
18:32:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no',
18:32:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7',
18:32:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
18:32:59 INFO - 'XPC_FLAGS': '0x0',
18:32:59 INFO - 'XPC_SERVICE_NAME': '0',
18:32:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'}
18:32:59 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/HkqLcYedTf6XAzOv4myc3Q/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0246', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600
18:32:59 INFO - mozversion application_buildid: 20160524164650
18:32:59 INFO - mozversion application_changeset: 788365ddaf61746bc66479335ae72d3e566c38ec
18:32:59 INFO - mozversion application_display_name: Nightly
18:32:59 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384}
18:32:59 INFO - mozversion application_name: Firefox
18:32:59 INFO - mozversion application_remotingname: firefox
18:32:59 INFO - mozversion application_repository: https://hg.mozilla.org/integration/mozilla-inbound
18:32:59 INFO - mozversion application_vendor: Mozilla
18:32:59 INFO - mozversion application_version: 49.0a1
18:32:59 INFO - mozversion platform_buildid: 20160524164650
18:32:59 INFO - mozversion platform_changeset: 788365ddaf61746bc66479335ae72d3e566c38ec
18:32:59 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/mozilla-inbound
18:32:59 INFO - mozversion platform_version: 49.0a1
18:32:59 INFO - using testdate: 1464139979
18:32:59 INFO - actual date: 1464139979
18:32:59 INFO - starting webserver on 'localhost:49270'
18:32:59 INFO - SUITE-START | Running 2 tests
18:32:59 INFO - TEST-START | dromaeo_css
18:32:59 INFO - operating with platform_type : mac_
18:32:59 INFO - Initialising browser for dromaeo_css test...
18:33:00 INFO - TEST-INFO | started process 1664 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/tmp4KsxGJ/profile http://localhost:49270/getInfo.html)
18:33:02 INFO - PROCESS | 1664 | __metrics Screen width/height:1600/1200
18:33:02 INFO - PROCESS | 1664 | colorDepth:24
18:33:02 INFO - PROCESS | 1664 | Browser inner width/height: 1024/689
18:33:02 INFO - PROCESS | 1664 | __metrics
18:33:02 INFO - PROCESS | 1664 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows"
18:33:02 ERROR - PROCESS | 1664 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null
18:33:03 INFO - TEST-INFO | 1664: exit 0
18:33:03 INFO - Browser initialized.
18:33:03 INFO - Running cycle 1/1 for dromaeo_css test...
18:33:03 INFO - TEST-INFO | started process 1666 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/tmp4KsxGJ/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1)
18:33:15 INFO - PROCESS | 1666 | RSS: Main: 239005696
18:33:15 INFO - PROCESS | 1666 |
18:34:06 INFO - PROCESS | 1666 | 1464140046071 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no]
18:36:17 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49270/tests/dromaeo/cssquery-ext.html)
18:36:18 INFO - PROCESS | 1666 | RSS: Main: 323837952
18:36:18 INFO - PROCESS | 1666 |
18:39:21 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-ext.html (next: http://localhost:49270/tests/dromaeo/cssquery-jquery.html)
18:39:22 INFO - PROCESS | 1666 | RSS: Main: 375353344
18:39:22 INFO - PROCESS | 1666 |
18:42:24 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49270/tests/dromaeo/cssquery-mootools.html)
18:42:25 INFO - PROCESS | 1666 | RSS: Main: 370434048
18:42:25 INFO - PROCESS | 1666 |
18:45:27 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49270/tests/dromaeo/cssquery-prototype.html)
18:45:28 INFO - PROCESS | 1666 | RSS: Main: 369668096
18:45:28 INFO - PROCESS | 1666 |
18:48:31 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49270/tests/dromaeo/cssquery-yui.html)
18:48:31 INFO - PROCESS | 1666 | RSS: Main: 338694144
18:48:31 INFO - PROCESS | 1666 |
18:51:34 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-yui.html (next: http://localhost:49270/tests/dromaeo/cssquery-dojo.html)
18:51:34 INFO - PROCESS | 1666 | RSS: Main: 379039744
18:51:34 INFO - PROCESS | 1666 |
18:51:34 INFO - PROCESS | 1666 | __start_tp_report
18:51:34 INFO - PROCESS | 1666 | _x_x_mozilla_page_load
18:51:34 INFO - PROCESS | 1666 | _x_x_mozilla_page_load_details
18:51:34 INFO - PROCESS | 1666 | |i|pagename|runs|
18:51:34 INFO - PROCESS | 1666 | |0;dojo.html;3425.81158036308;3458.135466133468;3470.646447885325;3491.3540994915957;3522.5244591980077;5621;5643.858903527409;5665.660060396369;5792.884142317156;5798.420157984199;5670.121131224657;5749.396313387097;5857.150713146601;5900;5950.285965684113;5020.924686129711;5052.9242061369105;5116.769745361449;5148.665336753118;5190.403103643072;5203.1414816555225;5208.479152084799;5218.947810521884;5249.396319423258;5252.737363131847;5053.620978426633;5080.847574572768;5096.464871188546;5159.458256883029;5170.095233334151;5138.332016837825;5146.691198528063;5148.9702059588235;5159.871003224912;5163.586913046947;5046.19260918255;5102.540771330561;5104.055749686283;5107.770150343244;5113.156329205676;5529.889402211933;5539.50144486994;5601.523870471015;5689.687067211302;5696.8006119785605;6196.411340922605;6241.469475094577;6273.404026617464;6275.09011193379;6297.527685423612;6150.523874270188;6205;6282.308946015934;6331.968340158316;6333.524985626051;5826.679532625704;5884.205632239678;5908.675022873697;5920.289565252111;5930.584859059867;6250.406211409909;6266.874662506724;6276.780312689125;6281.214848143982;6286.339934306923;5021.949780502148;5984.2818861736;6134.539909506805;6174.938250617436;6194.81415557525;6242.781502647385;6294.276158241861;6297.874042519124;6302.621842689453;6307.211598550181;6246.969250073777;6247.656378899113;6251.78118765841;6271.27880293763;6292.685365731695;5501.669899806025;5977.611455255482;6108.04631976549;6147.846303842351;6258.3741625837965;4925.13810083234;4948.802047918043;4949.381327334083;4955.8513244602;5001.799928002839;4428.4685837699035;4433.113377324548;4454.7549884756;4462.620677242406;4505.36924830524;5530.861728456821;5576.079946808731;5607.943920560742;5678.659280443187;5701.686407247641;6232.283287421914;6259.906101408483;6293.087502312231;6313.747450101944;6323.683815809191;5650.26546548946;5665.065264231438;5676.063449530864;5676.290463692038;5721.942780572224;5233.528982391603;5334.333208348957;5344;5407.675539467645;5444.6188766785945;6314.778982735582;6315.842103947346;6331.9366806331345;6338.873222535616;6347.206599175103;6251.093591429309;6256.0303153011355;6296.748130074746;6301.558890877594;6303.653299068412;6180.907286390618;6229.345918678474;6263.248410190807;6271.153394291712;6306.684665766785;6201.96899015502;6255.90521658717;6265.624062556261;6268.592541484789;6271;5524.281843360337;6158.661273629993;6275.717592708431;6299.748010079546;6319.115323854756;5910.290765108214;5986.16193732869;6063.242094738158;6094.51243900478;6106;6085.117657939485;6090.086487026982;6097.085437184458;6105.877882442415;6109.236345456818;5888.4700376966275;5901.262342207224;5915.349311575809;5936.851578710567;6017.759289628366;5926.407359264039;5935.792247271324;5941.465268125889;5963.254593175853;6005.129256257906;5804.738786754522;5833.649981001154;5845.1232315153065;5848.239728835394;5856.824295271149
18:51:34 INFO - PROCESS | 1666 | |1;ext.html;505.82900585145387;512.1038183179434;520.336570872138;524.0802391802389;548.478945002248;28700.712992870063;41604.37593436101;42684;42704;42768.57231427685;44151.558484415145;44452.110957780824;44604.55395446053;44678.553214467844;44693.55306446943;8453.57732113395;8653.913460865406;9484.478353690543;9672.613095476172;9688.273379496532;11370.260933039368;11634.360110193937;11670.35813030283;11800.291982481078;11923.224990375598;11651.300921944669;11655.883441165606;11671.708207294801;11677.357745324003;11711.7072073198;8539.957300213522;11466.94266528662;11644.30134191947;11742.765144697058;11749.001334886545;11658;11752.941235293854;11752.941235293854;11758.353290569015;11771.411429428494;40714.18571628551;40866.795666021775;41239;41287;41300.58699413027;3355.74831887607;3449;3522.7710198836994;3654.7807131571926;3908.6873050156205;28998.13005609835;29262.122136335944;30314.84842575795;30330.69669303301;30378.240543986358;2628;2635.5783074707956;2765.9308517287027;2769.1138835572624;2775.791815613837;524549;525460;525787;527312.3634381841;527694;62403.6879815598;62612.68693656503;62955.68522157452;63092.05361919575;63174.68412657907;62784.37215627785;63755.36244637587;65075;65130;65133.34866651273;16177.676446471005;16291;16507.422240221535;16519.174041297887;16549;11935.985441237419;12194.085443591588;12866.356682165855;13167.012474064484;13176;16259.756103658454;16309.227431336463;16742.58143546421;16758.91067080636;16902.985820850787;5757.366689664133;5961.344252132262;6726.226483954408;6883.690233939485;6976.476764242702;734.5225603357753;882.6513527156737;908.6592527802075;994.2195376629311;1011.079917275276;26849;28923.855380722962;29609.703902961126;29671.258218544277;29675.554866677016;1025.553884060436;1089.090609341209;1097.1113398147527;1115.7712668902855;1117.7876203521469;12305.507779688713;12313.753724925451;12950.740985180244;14907.403703851724;15234;676433;681135;681802;682561;682584;59895.700521497114;60016.69991650101;60088;60168.39831601628;60203.698981504815;1136.9772604547863;1432.4485073246874;1475.7417451945867;1487.6355292953297;1488.24843454055;25445.982160713364;26246;29322;30278.09165725032;30344.54483182798;48619;51139.488605113474;51163.488365115874;51279.743601281756;51336.74331628318;538960;558174;558288;558666;560407;33773.15567110842;39138.41292380673;39247.607523924395;39670;39899.60100398959;46297.6110716679;46475;46793.76603116962;48000;48440;37810.865674029825;37983.430248545745;38134.809325953196;38632.6136738629;38672.613273866904;31649.8417507911;37588.436173456874;37626;38346.0413489665;38548.229035418575;33127.83436082804;36849;37304.813475932446;37757;38518.614813851505;32157.678423215468;33051.504227436126;33393;33553.32893342071;33643.99068027963;19736.40790776279;23898.163564275168;24451.877740611184;24635.63046554339;24865.378365541008
18:51:34 INFO - PROCESS | 1666 | |2;jquery.html;362.5051804287148;387.79253099591716;389.62401282762147;400.19560683027106;407.17953324052024;19633.705494417576;21548.461288467795;21978.89010554947;21996.89001554992;22383.992720327584;20612.89693551534;20653;21450;21860.8906955465;21901.452463688416;5128.48715128486;5384.40771515133;5391.838244852641;5401.837944861661;5403.162509810985;3050.3136794221346;4028.858989935353;5225.712585807779;5649.943500565003;5959.70201489926;5982.79060232892;5993.4006599340155;6003.30961939376;6010.8497287567725;6014.729337179838;5868.970655146718;5872;5950.55370847186;5952.70236488176;6047.274327080734;3738.9439158412647;5393.541548968343;5710.486056254957;5710.71446427677;5811.941880581182;10048.44733539655;11973.40132993347;12481.126321157522;12514.74970500594;13740.65648358789;1902.4958386027713;2034.9084291206932;2253.9887300563555;2341.9297421077395;2444.4377793107683;8968.013518512958;9647.469389183592;9716.562754675986;9730.854037189449;10312.329698569569;1458.9197594132318;1667.608112093657;1691.8139004709471;1711.3069206971197;1733.7226043832927;110241;117142;119332;128066;129296.3535182318;11229.27009744364;11261;11309.49107290174;11532.481038353126;11641.243319184226;12107.39463026845;14555.490557830424;14656.706865862836;14688.632784180267;14698.338574763948;8619.870701939475;8621.267192288726;8661.263792577702;8716.43343182691;8814.779630509289;8415.116412776693;8598.613062412207;8670.523121228392;8758.56207189638;8768.517731524827;3929.410588411704;4574.519675434098;4584.151931892544;4610.400647915817;4640.559146881041;1604.7994000749907;1774.3434929076325;1793.22483869242;1823.3076187503427;1913.2926908424702;877.7761670774039;956.416585882611;969.2827307792325;988.4761076629397;1000.5847573257017;19976.700349494768;20608.454365922615;20652.96735163236;20672.48318792042;20862.687059694115;907.8865141857268;921.2959962509921;951.5147274889717;997.804829375377;1002.3484734922358;10092.949535252423;10148.543315550818;10696.94651526753;10834.266517357186;10899.782004360028;592743;595192;600171;608137;613124;104639;115751.2637310441;127307;134508.6549134496;146820;838.7693384319239;981.6061193105807;986.063239922077;1021.5096753558186;1023.3961962442195;18040;18256.178471968527;18316.72524912154;18330.725039124158;18374.448766537025;52296;52852;53255;53389.73305133605;53590.46409535855;74244.62877685577;74796.62601687174;75308.24691753232;75882;76967.61516192384;5355.3305836770405;7404.851902962018;7598.506097103671;7823.100343460575;7846.882296765667;10457.84313235287;12025.27848329068;12187.729381276096;12193.451294691937;12408.193467424588;8546.871796922927;10338.534765935701;10466.947665261625;10529.210309226992;10670.253082284165;9034.683786067457;10181.592736290466;10341.4312212826;10396.48017599132;10638.41488718128;10569.260151789302;10666;10743.677689669323;10911.399873007062;10961;7699.422543309162;7946.32456241226;7968.960155199419;7973.401994850526;8190.180981901762;9110.773748786529;9446.858297125686;9862.013798620368;10678.092362149304;10694.465276736288
18:51:34 INFO - PROCESS | 1666 | |3;mootools.html;1389.5414513210642;1546.1959780913921;1576.4009676322996;1815.4825874625726;1980.1188471130345;7787.64955576999;8682.956585217082;8824.249938755198;8884.511351875644;9018.774530636738;8624.310055195585;8708.738737837875;8708.782280442992;8850.645974161042;8853.026167121627;2069.286096296775;2270.8069814065825;2318.791308782205;2349.400902769791;2352.5177338645535;2383.630537266718;2398.3764221302413;2403.471236328005;2406.254061241012;2412.8431651942656;1993.372087792348;2326.7673232676766;2365.7042869641296;2366.5148644527917;2371.430856594422;1954.6188493243824;2207.6357401028813;2267.365137761413;2335.1943579465055;2338.298510446859;2348.4833336665906;2365.042157926043;2366.928992130239;2368.5973384524673;2370.4192472844093;8793.516356600385;8854.114588541095;8859.06979767128;8859.689910853154;8889.333300002461;5220.138677118271;5277.6305658603915;5371.946280537184;5375.354957405098;5400.513953744143;3338.6327503974544;3373.443381841993;3571.1786289153642;3571.8392672329805;3579.3736096183065;877.2148926710558;996.5814357969598;997.9900200998043;1016.588281745894;1045.5869931377063;11825.586104486301;11876.643700688906;11884.821727674092;11896.583619573274;11907.583234586747;4643.187442197602;4764.523547645277;4820.180569303227;4832.855014349575;4843.733594652328;9959.352642078242;9971.650992215238;10015.899841001643;10024;10041.548130334153;5328.92006619901;5409.459054094559;5412.377576578665;5421.864453388697;5426.077566813651;4366.825326986885;4531.025829446685;4741.2176990796825;4890.388701412324;4934.358533390637;3883.747556408881;3948.1314110895555;3990.0423898264207;4029.979850100731;4052.615001574846;1167.7197472606683;1266.316189257809;1293.7994610835146;1313.3170751209318;1327.0644195841908;822.2229992656881;858.8797568340437;905.3391024551983;920.4201353147608;943.7726964364095;5605.271314729059;5779.855503612359;5887.234659494324;5986.281646202484;5996.687354073949;438.36218302369974;438.48478038304864;444.56876829475357;450.9368688383629;461.10545541649094;3832.789196594214;3883.689304855605;3930;3983.780892050907;3997.6801855851463;10506.212034097472;10838.67483975482;10868.402237876993;10923.836142457869;10960.013598776148;10161.237907156992;10764.730881728019;10838.67483975482;10852.077573406192;10886.29239099456;8091.109977902426;8215.548144852091;8315.95842020786;8331.500109993298;8334.874976875351;5843.649381037151;5869.297903607906;5933.525317974465;6072.937235983597;6187.1647327612;9551.235901127755;10063.748406289902;10137.884832668547;10230.539625716712;10246.026627470379;10052.64815731446;10111.848322275024;10242.897571024492;10290;10344.844827327746;6393.487474620461;6887.793366199022;7129.607871567114;7418.295261950106;7611.543307401574;7893.605319734105;8366.33069354438;8474.152584741476;8479.279261262862;8510.361722870686;7390.852182956418;7453.776386708408;7473.028506294147;7585.658645361083;7660.463767536219;7279;7343.853122937405;7362.815929601803;7410.333070023508;7508;6842.55523390978;6996.440284777103;7006.124234470692;7012.509124361246;7024.473164512784;4780.713157210578;4834.250691142878;4913.06651736169;4930.901381972412;5104.2343648453025;4886.364772579542;4943.134951383566;4954.083494553519;4981.70109793399;5176.948230517647
18:51:34 INFO - PROCESS | 1666 | |4;prototype.html;304.30162776428097;307.8799268285368;313.08266778339464;315.55034076441063;318.41411802283795;4922;5118.564921981631;5432.311006052;5514.91727624086;5552.972235138819;4469.888252793682;4546.635749410305;5389.137737961927;5389.676619402839;5412.83761487156;3398.779079359846;3549.988253347796;3854.383298672213;3989.780562069085;4063.4108054332105;3115.115355210099;3465.220325426784;3651.561812582494;3928.998105483098;4035.6367926886574;4012.5184977802705;4025.859094931678;4038.2327357801923;4041.737287076345;4066.8983275418054;3862.806859657006;3975.4434379186937;4024.9195016099807;4048.392741088831;4075.8777236682945;2982.492976194052;3510.33303672304;3513.1744040150543;3519.63043880394;3537.2218112015316;5091;5107;5117.411497677777;5126.820561280375;5167.612429067797;3864.8260828262514;4076.857309994136;4147.921540399488;4387.166438376698;4604.204360299486;4782.880427989293;5300.840974770763;5372.54333381663;5610.326760788691;5616.691081990489;3483.477478378238;3798.841353387216;3927;3952.940705889414;3956.881293561169;5551.750171242304;5862.443067908584;5894.789453954767;5912.34964153947;5951.553633477506;3334.6498617645284;3596.3526565218513;3751.531058617673;3859.807009649506;3904.3557812960544;3934.403293826061;3944.783036932939;3967.504061992251;3974.5627980922077;3983.3029219886985;3943.369060950234;3980.7014473914574;4323.832565207376;4661.720296782204;4666.300054991711;4964.641795147276;5287.550558202596;5448.828501872037;5509.779608815683;5580.381689310055;1296.397175313465;1348.2315080404271;1353.8781509664177;1356.796480527909;1365.7336819320137;730.9195988441268;734.7391675954959;808.7048227396958;820.9425340226246;825.5005721538503;4375.5405682403525;4674.994876101655;4780.617550596014;4849.599448273128;5301.284326615934;4655.348251244828;4729.763511824395;5785.739641716133;5793.333766616809;5861.443162899518;4945.950540494621;5264.92102618461;5317.202419637086;5339.01228272763;5368.436314187033;2533.5946248600135;3008.518637018067;3039.9240018999703;3051.725344718986;3060.6786287439945;5187.406296851575;5778.16216648592;5984.611000284967;6017.338092809788;6047.365026672224;4683.297505374153;5123.641345105882;5375.3280839895015;5914.112883067574;6033.849153771103;5099.388073431212;5217.973910130425;6065.2418447694035;6080.9391906081255;6083.878322433615;5671.262735844314;5851.707414629166;5871.796281762314;5890.587658863838;5899.793507227227;5718.342390625131;5758.387651457599;5767.769289228551;5768.480836724715;5788.594798364074;4839.395075615548;5346.65246758975;5551.8889622206525;6053.909191362045;6059.6364218147055;3507.5440192774777;3681.8645185791643;3684.4289135183017;3850;3888.902777430587;5687.573431992534;5797.739101740516;5815.302163740209;5822.155787410887;5830.329512106162;5024.84925452237;5034.539983404771;5638.126090455986;5660.320761508646;5680.40355762639;4974.353334066548;5306.975024236148;5413.269208656781;5663.915041274467;5681;5505.504504594445;5517.503424691797;5531.668099914018;5534.557235421237;5540.722963851711;4794.352762377174;5383.838484845304;5451.555337835397;5524.889502210013;5621.016322143691;4932.7286999215385;5543.667379957215;5557.9709058563985;5566.524870909293;5579.856129493363
18:51:34 INFO - PROCESS | 1666 | |5;yui.html;618.227215980025;630.9905351419727;652.7225928980185;668.1547841979893;747.19676347926;14920.850791492081;15033.398664053439;15231.238438078079;15238.542843714678;15260.542183734491;13514.391852366642;14268.143911365325;14349.210793406384;14367.35346909389;14369.281535923214;719.6509692798988;890.1143362354467;900.954952252388;909.9499527525984;910.7495438754329;732.0227496292456;888.1518150166607;914.7301546043933;917.4082716647758;920.102899672817;931.5156118818211;954.4511905654242;954.7279025477741;955.6416343871049;958.1233171647934;754.811297175706;922.9676961306322;947.0576776107782;949.924006079512;950.7100334398006;709.0923617769264;709.1313141401793;709.6238993333542;711.498393632493;712.291270186168;14862.256887155707;14995.925020374938;15002.24988750558;15003.549893503212;15028.849711502964;12231.449584768618;12280.631581052583;12347.320897350643;12357.135000549964;12573.11988160829;10711.571537138505;10954.068904143078;10965.177611679077;11310.151738619636;11311.547538098466;3965.1078507335906;3983.810493382646;4003.459532963042;4043.9460012516465;4164.292070348048;85615;88084;88376;88406;89228.55385723095;9407.388519746195;9418.105279998526;9447.905520944703;9458.243340532741;9875.456849873326;21920.89039554792;22094.668579971312;22116;22147.231596050493;22152;7110.253423390573;7223.494355395177;7315.853682926312;7370.078740157481;7495.8500829983095;3818.427235914635;3847.615238476186;3997.2804895119157;4004.839806407769;4008.5390180129075;3410.0963244740165;3422.7261819054415;3426.811525366079;3508.6315936826777;3513.2622149348413;563.4168635462277;595.8410890817405;614.4377894226733;634.194572892424;647.8445173158408;467.41806450968085;495.2892599120202;545.9153831156177;551.2640624765902;564.3989151553583;6460.515461340418;7639.93040974264;7718.53688778675;7780.3386712130105;7998.880016799752;535.7589084911727;537.492069993852;554.0995881692251;577.6707276852154;578.6961845031392;3585;3659.688926441229;3750.6061863504483;3759.586445490994;3775.7923314217983;65747.67126164338;93170;95824;96329;99342.5032874831;78084.60957695176;83348;84877.57561212154;85562.57218713866;85814.14185858061;71974;72311;72348.63825680838;72561;73331;6909.754329842922;6936.994135850173;7222.6388680566815;7315.122185337581;7451.4038876888635;45398.77300613476;46161.076778464914;46186.538134618226;46801.063978720915;47155;58539.70730146322;59639.105413419704;59710.40289597048;64483.677581611795;65742.67128664517;9741.220702343653;9982.101610854768;10273.9486302568;10283.588656453958;10343.63797267092;10359.896401035892;10414.531346089596;10442.425666588108;10461.320014199357;10710.518026688662;9668.70993870185;9851.556679949275;9900.257480688833;9994.850077248993;10286.948565257126;9977.401355918668;10003.899961000297;10027.649032283834;10107.69676909694;10433.478326083818;9707.6116955321;9731.562079706571;9794.412335259907;9848.803023939625;10191.796164076824;8385.748427547183;8493.872591911002;8561.82876342482;8598.570071496526;8920.776980575538;8492.872606911025;8609.182127697859;8668.78328041804;8799.296056315607;8811.427257228259
18:51:34 INFO - PROCESS | 1666 | __end_tp_report
18:51:34 INFO - PROCESS | 1666 | __start_cc_report
18:51:34 INFO - PROCESS | 1666 | _x_x_mozilla_cycle_collect,1307
18:51:34 INFO - PROCESS | 1666 | __end_cc_report
18:51:34 INFO - PROCESS | 1666 | __startTimestamp1464141094861__endTimestamp
18:51:34 INFO - PROCESS | 1666 |
18:51:34 INFO - PROCESS | 1666 | ------- Summary: start -------
18:51:34 INFO - PROCESS | 1666 | Number of tests: 6
18:51:34 INFO - PROCESS | 1666 |
18:51:34 INFO - PROCESS | 1666 | [#0] dojo.html Cycles:165 Average:5723.40 Median:5910.29 stddev:635.48 (10.8%) stddev-sans-first:611.33
18:51:34 INFO - PROCESS | 1666 | Values: 3425.8 3458.1 3470.6 3491.4 3522.5 5621.0 5643.9 5665.7 5792.9 5798.4 5670.1 5749.4 5857.2 5900.0 5950.3 5020.9 5052.9 5116.8 5148.7 5190.4 5203.1 5208.5 5218.9 5249.4 5252.7 5053.6 5080.8 5096.5 5159.5 5170.1 5138.3 5146.7 5149.0 5159.9 5163.6 5046.2 5102.5 5104.1 5107.8 5113.2 5529.9 5539.5 5601.5 5689.7 5696.8 6196.4 6241.5 6273.4 6275.1 6297.5 6150.5 6205.0 6282.3 6332.0 6333.5 5826.7 5884.2 5908.7 5920.3 5930.6 6250.4 6266.9 6276.8 6281.2 6286.3 5021.9 5984.3 6134.5 6174.9 6194.8 6242.8 6294.3 6297.9 6302.6 6307.2 6247.0 6247.7 6251.8 6271.3 6292.7 5501.7 5977.6 6108.0 6147.8 6258.4 4925.1 4948.8 4949.4 4955.9 5001.8 4428.5 4433.1 4454.8 4462.6 4505.4 5530.9 5576.1 5607.9 5678.7 5701.7 6232.3 6259.9 6293.1 6313.7 6323.7 5650.3 5665.1 5676.1 5676.3 5721.9 5233.5 5334.3 5344.0 5407.7 5444.6 6314.8 6315.8 6331.9 6338.9 6347.2 6251.1 6256.0 6296.7 6301.6 6303.7 6180.9 6229.3 6263.2 6271.2 6306.7 6202.0 6255.9 6265.6 6268.6 6271.0 5524.3 6158.7 6275.7 6299.7 6319.1 5910.3 5986.2 6063.2 6094.5 6106.0 6085.1 6090.1 6097.1 6105.9 6109.2 5888.5 5901.3 5915.3 5936.9 6017.8 5926.4 5935.8 5941.5 5963.3 6005.1 5804.7 5833.6 5845.1 5848.2 5856.8
18:51:34 INFO - PROCESS | 1666 |
18:51:34 INFO - PROCESS | 1666 | [#1] ext.html Cycles:180 Average:72153.42 Median:37607.22 stddev:158046.26 (420.3%) stddev-sans-first:158398.07
18:51:34 INFO - PROCESS | 1666 | Values: 505.8 512.1 520.3 524.1 548.5 28700.7 41604.4 42684.0 42704.0 42768.6 44151.6 44452.1 44604.6 44678.6 44693.6 8453.6 8653.9 9484.5 9672.6 9688.3 11370.3 11634.4 11670.4 11800.3 11923.2 11651.3 11655.9 11671.7 11677.4 11711.7 8540.0 11466.9 11644.3 11742.8 11749.0 11658.0 11752.9 11752.9 11758.4 11771.4 40714.2 40866.8 41239.0 41287.0 41300.6 3355.7 3449.0 3522.8 3654.8 3908.7 28998.1 29262.1 30314.8 30330.7 30378.2 2628.0 2635.6 2765.9 2769.1 2775.8 524549.0 525460.0 525787.0 527312.4 527694.0 62403.7 62612.7 62955.7 63092.1 63174.7 62784.4 63755.4 65075.0 65130.0 65133.3 16177.7 16291.0 16507.4 16519.2 16549.0 11936.0 12194.1 12866.4 13167.0 13176.0 16259.8 16309.2 16742.6 16758.9 16903.0 5757.4 5961.3 6726.2 6883.7 6976.5 734.5 882.7 908.7 994.2 1011.1 26849.0 28923.9 29609.7 29671.3 29675.6 1025.6 1089.1 1097.1 1115.8 1117.8 12305.5 12313.8 12950.7 14907.4 15234.0 676433.0 681135.0 681802.0 682561.0 682584.0 59895.7 60016.7 60088.0 60168.4 60203.7 1137.0 1432.4 1475.7 1487.6 1488.2 25446.0 26246.0 29322.0 30278.1 30344.5 48619.0 51139.5 51163.5 51279.7 51336.7 538960.0 558174.0 558288.0 558666.0 560407.0 33773.2 39138.4 39247.6 39670.0 39899.6 46297.6 46475.0 46793.8 48000.0 48440.0 37810.9 37983.4 38134.8 38632.6 38672.6 31649.8 37588.4 37626.0 38346.0 38548.2 33127.8 36849.0 37304.8 37757.0 38518.6 32157.7 33051.5 33393.0 33553.3 33644.0 19736.4 23898.2 24451.9 24635.6 24865.4
18:51:34 INFO - PROCESS | 1666 |
18:51:34 INFO - PROCESS | 1666 | [#2] jquery.html Cycles:180 Average:34515.06 Median:1706.41 stddev:100556.32 (5892.9%) stddev-sans-first:100805.70
18:51:34 INFO - PROCESS | 1666 | Values: 362.5 387.8 389.6 400.2 407.2 19633.7 21548.5 21978.9 21996.9 22384.0 20612.9 20653.0 21450.0 21860.9 21901.5 5128.5 5384.4 5391.8 5401.8 5403.2 3050.3 4028.9 5225.7 5649.9 5959.7 5982.8 5993.4 6003.3 6010.8 6014.7 5869.0 5872.0 5950.6 5952.7 6047.3 3738.9 5393.5 5710.5 5710.7 5811.9 10048.4 11973.4 12481.1 12514.7 13740.7 1902.5 2034.9 2254.0 2341.9 2444.4 8968.0 9647.5 9716.6 9730.9 10312.3 1458.9 1667.6 1691.8 1711.3 1733.7 110241.0 117142.0 119332.0 128066.0 129296.4 11229.3 11261.0 11309.5 11532.5 11641.2 12107.4 14555.5 14656.7 14688.6 14698.3 8619.9 8621.3 8661.3 8716.4 8814.8 8415.1 8598.6 8670.5 8758.6 8768.5 3929.4 4574.5 4584.2 4610.4 4640.6 1604.8 1774.3 1793.2 1823.3 1913.3 877.8 956.4 969.3 988.5 1000.6 19976.7 20608.5 20653.0 20672.5 20862.7 907.9 921.3 951.5 997.8 1002.3 10092.9 10148.5 10696.9 10834.3 10899.8 592743.0 595192.0 600171.0 608137.0 613124.0 104639.0 115751.3 127307.0 134508.7 146820.0 838.8 981.6 986.1 1021.5 1023.4 18040.0 18256.2 18316.7 18330.7 18374.4 52296.0 52852.0 53255.0 53389.7 53590.5 74244.6 74796.6 75308.2 75882.0 76967.6 5355.3 7404.9 7598.5 7823.1 7846.9 10457.8 12025.3 12187.7 12193.5 12408.2 8546.9 10338.5 10466.9 10529.2 10670.3 9034.7 10181.6 10341.4 10396.5 10638.4 10569.3 10666.0 10743.7 10911.4 10961.0 7699.4 7946.3 7969.0 7973.4 8190.2 9110.8 9446.9 9862.0 10678.1 10694.5
18:51:34 INFO - PROCESS | 1666 |
18:51:34 INFO - PROCESS | 1666 | [#3] mootools.html Cycles:180 Average:5710.73 Median:4826.52 stddev:3256.98 (67.5%) stddev-sans-first:3249.93
18:51:34 INFO - PROCESS | 1666 | Values: 1389.5 1546.2 1576.4 1815.5 1980.1 7787.6 8683.0 8824.2 8884.5 9018.8 8624.3 8708.7 8708.8 8850.6 8853.0 2069.3 2270.8 2318.8 2349.4 2352.5 2383.6 2398.4 2403.5 2406.3 2412.8 1993.4 2326.8 2365.7 2366.5 2371.4 1954.6 2207.6 2267.4 2335.2 2338.3 2348.5 2365.0 2366.9 2368.6 2370.4 8793.5 8854.1 8859.1 8859.7 8889.3 5220.1 5277.6 5371.9 5375.4 5400.5 3338.6 3373.4 3571.2 3571.8 3579.4 877.2 996.6 998.0 1016.6 1045.6 11825.6 11876.6 11884.8 11896.6 11907.6 4643.2 4764.5 4820.2 4832.9 4843.7 9959.4 9971.7 10015.9 10024.0 10041.5 5328.9 5409.5 5412.4 5421.9 5426.1 4366.8 4531.0 4741.2 4890.4 4934.4 3883.7 3948.1 3990.0 4030.0 4052.6 1167.7 1266.3 1293.8 1313.3 1327.1 822.2 858.9 905.3 920.4 943.8 5605.3 5779.9 5887.2 5986.3 5996.7 438.4 438.5 444.6 450.9 461.1 3832.8 3883.7 3930.0 3983.8 3997.7 10506.2 10838.7 10868.4 10923.8 10960.0 10161.2 10764.7 10838.7 10852.1 10886.3 8091.1 8215.5 8316.0 8331.5 8334.9 5843.6 5869.3 5933.5 6072.9 6187.2 9551.2 10063.7 10137.9 10230.5 10246.0 10052.6 10111.8 10242.9 10290.0 10344.8 6393.5 6887.8 7129.6 7418.3 7611.5 7893.6 8366.3 8474.2 8479.3 8510.4 7390.9 7453.8 7473.0 7585.7 7660.5 7279.0 7343.9 7362.8 7410.3 7508.0 6842.6 6996.4 7006.1 7012.5 7024.5 4780.7 4834.3 4913.1 4930.9 5104.2 4886.4 4943.1 4954.1 4981.7 5176.9
18:51:34 INFO - PROCESS | 1666 |
18:51:34 INFO - PROCESS | 1666 | [#4] prototype.html Cycles:180 Average:4511.23 Median:5103.19 stddev:1415.15 (27.7%) stddev-sans-first:1383.44
18:51:34 INFO - PROCESS | 1666 | Values: 304.3 307.9 313.1 315.6 318.4 4922.0 5118.6 5432.3 5514.9 5553.0 4469.9 4546.6 5389.1 5389.7 5412.8 3398.8 3550.0 3854.4 3989.8 4063.4 3115.1 3465.2 3651.6 3929.0 4035.6 4012.5 4025.9 4038.2 4041.7 4066.9 3862.8 3975.4 4024.9 4048.4 4075.9 2982.5 3510.3 3513.2 3519.6 3537.2 5091.0 5107.0 5117.4 5126.8 5167.6 3864.8 4076.9 4147.9 4387.2 4604.2 4782.9 5300.8 5372.5 5610.3 5616.7 3483.5 3798.8 3927.0 3952.9 3956.9 5551.8 5862.4 5894.8 5912.3 5951.6 3334.6 3596.4 3751.5 3859.8 3904.4 3934.4 3944.8 3967.5 3974.6 3983.3 3943.4 3980.7 4323.8 4661.7 4666.3 4964.6 5287.6 5448.8 5509.8 5580.4 1296.4 1348.2 1353.9 1356.8 1365.7 730.9 734.7 808.7 820.9 825.5 4375.5 4675.0 4780.6 4849.6 5301.3 4655.3 4729.8 5785.7 5793.3 5861.4 4946.0 5264.9 5317.2 5339.0 5368.4 2533.6 3008.5 3039.9 3051.7 3060.7 5187.4 5778.2 5984.6 6017.3 6047.4 4683.3 5123.6 5375.3 5914.1 6033.8 5099.4 5218.0 6065.2 6080.9 6083.9 5671.3 5851.7 5871.8 5890.6 5899.8 5718.3 5758.4 5767.8 5768.5 5788.6 4839.4 5346.7 5551.9 6053.9 6059.6 3507.5 3681.9 3684.4 3850.0 3888.9 5687.6 5797.7 5815.3 5822.2 5830.3 5024.8 5034.5 5638.1 5660.3 5680.4 4974.4 5307.0 5413.3 5663.9 5681.0 5505.5 5517.5 5531.7 5534.6 5540.7 4794.4 5383.8 5451.6 5524.9 5621.0 4932.7 5543.7 5558.0 5566.5 5579.9
18:51:34 INFO - PROCESS | 1666 |
18:51:34 INFO - PROCESS | 1666 | [#5] yui.html Cycles:180 Average:18135.98 Median:650.28 stddev:26293.65 (4043.4%) stddev-sans-first:26334.51
18:51:34 INFO - PROCESS | 1666 | Values: 618.2 631.0 652.7 668.2 747.2 14920.9 15033.4 15231.2 15238.5 15260.5 13514.4 14268.1 14349.2 14367.4 14369.3 719.7 890.1 901.0 909.9 910.7 732.0 888.2 914.7 917.4 920.1 931.5 954.5 954.7 955.6 958.1 754.8 923.0 947.1 949.9 950.7 709.1 709.1 709.6 711.5 712.3 14862.3 14995.9 15002.2 15003.5 15028.8 12231.4 12280.6 12347.3 12357.1 12573.1 10711.6 10954.1 10965.2 11310.2 11311.5 3965.1 3983.8 4003.5 4043.9 4164.3 85615.0 88084.0 88376.0 88406.0 89228.6 9407.4 9418.1 9447.9 9458.2 9875.5 21920.9 22094.7 22116.0 22147.2 22152.0 7110.3 7223.5 7315.9 7370.1 7495.9 3818.4 3847.6 3997.3 4004.8 4008.5 3410.1 3422.7 3426.8 3508.6 3513.3 563.4 595.8 614.4 634.2 647.8 467.4 495.3 545.9 551.3 564.4 6460.5 7639.9 7718.5 7780.3 7998.9 535.8 537.5 554.1 577.7 578.7 3585.0 3659.7 3750.6 3759.6 3775.8 65747.7 93170.0 95824.0 96329.0 99342.5 78084.6 83348.0 84877.6 85562.6 85814.1 71974.0 72311.0 72348.6 72561.0 73331.0 6909.8 6937.0 7222.6 7315.1 7451.4 45398.8 46161.1 46186.5 46801.1 47155.0 58539.7 59639.1 59710.4 64483.7 65742.7 9741.2 9982.1 10273.9 10283.6 10343.6 10359.9 10414.5 10442.4 10461.3 10710.5 9668.7 9851.6 9900.3 9994.9 10286.9 9977.4 10003.9 10027.6 10107.7 10433.5 9707.6 9731.6 9794.4 9848.8 10191.8 8385.7 8493.9 8561.8 8598.6 8920.8 8492.9 8609.2 8668.8 8799.3 8811.4
18:51:34 INFO - PROCESS | 1666 | -------- Summary: end --------
18:51:34 INFO - PROCESS | 1666 |
18:51:35 INFO - TEST-INFO | 1666: exit 0
18:51:35 INFO - TEST-OK | dromaeo_css | took 1115588ms
18:51:35 INFO - TEST-START | kraken
18:51:35 INFO - operating with platform_type : mac_
18:51:35 INFO - Initialising browser for kraken test...
18:51:35 INFO - TEST-INFO | started process 1672 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/tmpSZXEAA/profile http://localhost:49270/getInfo.html)
18:51:37 INFO - PROCESS | 1672 | __metrics Screen width/height:1600/1200
18:51:37 INFO - PROCESS | 1672 | colorDepth:24
18:51:37 INFO - PROCESS | 1672 | Browser inner width/height: 1024/689
18:51:37 INFO - PROCESS | 1672 | __metrics
18:51:38 INFO - PROCESS | 1672 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows"
18:51:38 ERROR - PROCESS | 1672 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null
18:51:38 INFO - TEST-INFO | 1672: exit 0
18:51:38 INFO - Browser initialized.
18:51:38 INFO - Running cycle 1/1 for kraken test...
18:51:38 INFO - TEST-INFO | started process 1674 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/tmpSZXEAA/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1)
18:51:50 INFO - PROCESS | 1674 | RSS: Main: 238948352
18:51:50 INFO - PROCESS | 1674 |
18:51:53 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49270/tests/kraken/driver.html?testName=audio-beat-detection)
18:51:54 INFO - PROCESS | 1674 | RSS: Main: 286765056
18:51:54 INFO - PROCESS | 1674 |
18:51:56 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49270/tests/kraken/driver.html?testName=audio-dft)
18:51:57 INFO - PROCESS | 1674 | RSS: Main: 295157760
18:51:57 INFO - PROCESS | 1674 |
18:52:01 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49270/tests/kraken/driver.html?testName=audio-fft)
18:52:01 INFO - PROCESS | 1674 | RSS: Main: 382746624
18:52:01 INFO - PROCESS | 1674 |
18:52:03 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49270/tests/kraken/driver.html?testName=audio-oscillator)
18:52:04 INFO - PROCESS | 1674 | RSS: Main: 283062272
18:52:04 INFO - PROCESS | 1674 |
18:52:06 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49270/tests/kraken/driver.html?testName=imaging-gaussian-blur)
18:52:06 INFO - PROCESS | 1674 | RSS: Main: 287219712
18:52:06 INFO - PROCESS | 1674 |
18:52:09 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49270/tests/kraken/driver.html?testName=imaging-darkroom)
18:52:10 INFO - PROCESS | 1674 | RSS: Main: 325533696
18:52:10 INFO - PROCESS | 1674 |
18:52:13 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49270/tests/kraken/driver.html?testName=imaging-desaturate)
18:52:13 INFO - PROCESS | 1674 | RSS: Main: 326369280
18:52:13 INFO - PROCESS | 1674 |
18:52:16 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49270/tests/kraken/driver.html?testName=json-parse-financial)
18:52:17 INFO - PROCESS | 1674 | RSS: Main: 326008832
18:52:17 INFO - PROCESS | 1674 |
18:52:18 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49270/tests/kraken/driver.html?testName=json-stringify-tinderbox)
18:52:19 INFO - PROCESS | 1674 | RSS: Main: 312573952
18:52:19 INFO - PROCESS | 1674 |
18:52:21 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-aes)
18:52:22 INFO - PROCESS | 1674 | RSS: Main: 319840256
18:52:22 INFO - PROCESS | 1674 |
18:52:24 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-ccm)
18:52:25 INFO - PROCESS | 1674 | RSS: Main: 321003520
18:52:25 INFO - PROCESS | 1674 |
18:52:27 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2)
18:52:28 INFO - PROCESS | 1674 | RSS: Main: 325861376
18:52:28 INFO - PROCESS | 1674 |
18:52:31 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative)
18:52:32 INFO - PROCESS | 1674 | RSS: Main: 323510272
18:52:32 INFO - PROCESS | 1674 |
18:52:33 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49270/tests/kraken/driver.html?testName=ai-astar)
18:52:34 INFO - PROCESS | 1674 | RSS: Main: 370688000
18:52:34 INFO - PROCESS | 1674 |
18:52:34 INFO - PROCESS | 1674 | __start_tp_report
18:52:34 INFO - PROCESS | 1674 | _x_x_mozilla_page_load
18:52:34 INFO - PROCESS | 1674 | _x_x_mozilla_page_load_details
18:52:34 INFO - PROCESS | 1674 | |i|pagename|runs|
18:52:34 INFO - PROCESS | 1674 | |0;ai-astar;113;105;113;112;112;111;112;113;111;112
18:52:34 INFO - PROCESS | 1674 | |1;audio-beat-detection;153;117;118;168;116;118;139;115;149;114
18:52:34 INFO - PROCESS | 1674 | |2;audio-dft;183;185;185;183;188;186;183;182;182;185
18:52:34 INFO - PROCESS | 1674 | |3;audio-fft;78;57;60;87;59;57;64;68;83;57
18:52:34 INFO - PROCESS | 1674 | |4;audio-oscillator;89;69;104;70;76;69;96;70;69;102
18:52:34 INFO - PROCESS | 1674 | |5;imaging-gaussian-blur;93;129;90;88;91;87;91;87;89;87
18:52:34 INFO - PROCESS | 1674 | |6;imaging-darkroom;93;95;95;95;94;95;95;96;95;95
18:52:34 INFO - PROCESS | 1674 | |7;imaging-desaturate;84;84;85;84;85;84;84;84;84;90
18:52:34 INFO - PROCESS | 1674 | |8;json-parse-financial;57;56;58;55;56;56;55;76;58;54
18:52:34 INFO - PROCESS | 1674 | |9;json-stringify-tinderbox;66;68;74;76;71;72;69;71;69;69
18:52:34 INFO - PROCESS | 1674 | |10;stanford-crypto-aes;86;86;89;102;88;85;85;86;84;87
18:52:34 INFO - PROCESS | 1674 | |11;stanford-crypto-ccm;140;141;138;140;138;139;146;146;179;157
18:52:34 INFO - PROCESS | 1674 | |12;stanford-crypto-pbkdf2;161;167;165;164;167;163;166;163;163;216
18:52:34 INFO - PROCESS | 1674 | |13;stanford-crypto-sha256-iterative;65;66;66;67;66;65;67;67;67;65
18:52:34 INFO - PROCESS | 1674 | __end_tp_report
18:52:34 INFO - PROCESS | 1674 | __start_cc_report
18:52:34 INFO - PROCESS | 1674 | _x_x_mozilla_cycle_collect,2059
18:52:34 INFO - PROCESS | 1674 | __end_cc_report
18:52:34 INFO - PROCESS | 1674 | __startTimestamp1464141154067__endTimestamp
18:52:34 INFO - PROCESS | 1674 |
18:52:34 INFO - PROCESS | 1674 | ------- Summary: start -------
18:52:34 INFO - PROCESS | 1674 | Number of tests: 14
18:52:34 INFO - PROCESS | 1674 |
18:52:34 INFO - PROCESS | 1674 | [#0] ai-astar Cycles:10 Average:111.40 Median:112.00 stddev:2.37 (2.1%) stddev-sans-first:2.44
18:52:34 INFO - PROCESS | 1674 | Values: 113.0 105.0 113.0 112.0 112.0 111.0 112.0 113.0 111.0 112.0
18:52:34 INFO - PROCESS | 1674 |
18:52:34 INFO - PROCESS | 1674 | [#1] audio-beat-detection Cycles:10 Average:130.70 Median:128.50 stddev:19.84 (15.4%) stddev-sans-first:19.34
18:52:34 INFO - PROCESS | 1674 | Values: 153.0 117.0 118.0 168.0 116.0 118.0 139.0 115.0 149.0 114.0
18:52:34 INFO - PROCESS | 1674 |
18:52:34 INFO - PROCESS | 1674 | [#2] audio-dft Cycles:10 Average:184.20 Median:185.00 stddev:1.93 (1.0%) stddev-sans-first:2.00
18:52:34 INFO - PROCESS | 1674 | Values: 183.0 185.0 185.0 183.0 188.0 186.0 183.0 182.0 182.0 185.0
18:52:34 INFO - PROCESS | 1674 |
18:52:34 INFO - PROCESS | 1674 | [#3] audio-fft Cycles:10 Average:67.00 Median:66.00 stddev:11.55 (17.5%) stddev-sans-first:11.54
18:52:34 INFO - PROCESS | 1674 | Values: 78.0 57.0 60.0 87.0 59.0 57.0 64.0 68.0 83.0 57.0
18:52:34 INFO - PROCESS | 1674 |
18:52:34 INFO - PROCESS | 1674 | [#4] audio-oscillator Cycles:10 Average:81.40 Median:70.00 stddev:14.74 (21.1%) stddev-sans-first:15.38
18:52:34 INFO - PROCESS | 1674 | Values: 89.0 69.0 104.0 70.0 76.0 69.0 96.0 70.0 69.0 102.0
18:52:34 INFO - PROCESS | 1674 |
18:52:34 INFO - PROCESS | 1674 | [#5] imaging-gaussian-blur Cycles:10 Average:93.20 Median:89.50 stddev:12.74 (14.2%) stddev-sans-first:13.52
18:52:34 INFO - PROCESS | 1674 | Values: 93.0 129.0 90.0 88.0 91.0 87.0 91.0 87.0 89.0 87.0
18:52:34 INFO - PROCESS | 1674 |
18:52:34 INFO - PROCESS | 1674 | [#6] imaging-darkroom Cycles:10 Average:94.80 Median:95.00 stddev:0.79 (0.8%) stddev-sans-first:0.50
18:52:34 INFO - PROCESS | 1674 | Values: 93.0 95.0 95.0 95.0 94.0 95.0 95.0 96.0 95.0 95.0
18:52:34 INFO - PROCESS | 1674 |
18:52:34 INFO - PROCESS | 1674 | [#7] imaging-desaturate Cycles:10 Average:84.80 Median:84.00 stddev:1.87 (2.2%) stddev-sans-first:1.96
18:52:34 INFO - PROCESS | 1674 | Values: 84.0 84.0 85.0 84.0 85.0 84.0 84.0 84.0 84.0 90.0
18:52:34 INFO - PROCESS | 1674 |
18:52:34 INFO - PROCESS | 1674 | [#8] json-parse-financial Cycles:10 Average:58.10 Median:56.50 stddev:6.42 (11.4%) stddev-sans-first:6.80
18:52:34 INFO - PROCESS | 1674 | Values: 57.0 56.0 58.0 55.0 56.0 56.0 55.0 76.0 58.0 54.0
18:52:34 INFO - PROCESS | 1674 |
18:52:34 INFO - PROCESS | 1674 | [#9] json-stringify-tinderbox Cycles:10 Average:70.50 Median:71.00 stddev:2.95 (4.2%) stddev-sans-first:2.65
18:52:34 INFO - PROCESS | 1674 | Values: 66.0 68.0 74.0 76.0 71.0 72.0 69.0 71.0 69.0 69.0
18:52:34 INFO - PROCESS | 1674 |
18:52:34 INFO - PROCESS | 1674 | [#10] stanford-crypto-aes Cycles:10 Average:87.80 Median:86.00 stddev:5.20 (6.0%) stddev-sans-first:5.48
18:52:34 INFO - PROCESS | 1674 | Values: 86.0 86.0 89.0 102.0 88.0 85.0 85.0 86.0 84.0 87.0
18:52:34 INFO - PROCESS | 1674 |
18:52:34 INFO - PROCESS | 1674 | [#11] stanford-crypto-ccm Cycles:10 Average:146.40 Median:143.50 stddev:12.83 (8.9%) stddev-sans-first:13.40
18:52:34 INFO - PROCESS | 1674 | Values: 140.0 141.0 138.0 140.0 138.0 139.0 146.0 146.0 179.0 157.0
18:52:34 INFO - PROCESS | 1674 |
18:52:34 INFO - PROCESS | 1674 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:169.50 Median:165.50 stddev:16.45 (9.9%) stddev-sans-first:17.16
18:52:34 INFO - PROCESS | 1674 | Values: 161.0 167.0 165.0 164.0 167.0 163.0 166.0 163.0 163.0 216.0
18:52:34 INFO - PROCESS | 1674 |
18:52:34 INFO - PROCESS | 1674 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.10 Median:66.50 stddev:0.88 (1.3%) stddev-sans-first:0.83
18:52:34 INFO - PROCESS | 1674 | Values: 65.0 66.0 66.0 67.0 66.0 65.0 67.0 67.0 67.0 65.0
18:52:34 INFO - PROCESS | 1674 | -------- Summary: end --------
18:52:34 INFO - PROCESS | 1674 |
18:52:34 INFO - TEST-INFO | 1674: exit 0
18:52:34 INFO - TEST-OK | kraken | took 59338ms
18:52:34 INFO - SUITE-END | took 1174s
18:52:35 INFO - Completed test suite (00:19:35)
18:52:35 INFO - javascript benchmark
18:52:35 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3425.81158036308, 3458.135466133468, 3470.646447885325, 3491.3540994915957, 3522.5244591980077, 5621.0, 5643.858903527409, 5665.660060396369, 5792.884142317156, 5798.420157984199, 5670.121131224657, 5749.396313387097, 5857.150713146601, 5900.0, 5950.285965684113, 5020.924686129711, 5052.9242061369105, 5116.769745361449, 5148.665336753118, 5190.403103643072, 5203.1414816555225, 5208.479152084799, 5218.947810521884, 5249.396319423258, 5252.737363131847, 5053.620978426633, 5080.847574572768, 5096.464871188546, 5159.458256883029, 5170.095233334151, 5138.332016837825, 5146.691198528063, 5148.9702059588235, 5159.871003224912, 5163.586913046947, 5046.19260918255, 5102.540771330561, 5104.055749686283, 5107.770150343244, 5113.156329205676, 5529.889402211933, 5539.50144486994, 5601.523870471015, 5689.687067211302, 5696.8006119785605, 6196.411340922605, 6241.469475094577, 6273.404026617464, 6275.09011193379, 6297.527685423612, 6150.523874270188, 6205.0, 6282.308946015934, 6331.968340158316, 6333.524985626051, 5826.679532625704, 5884.205632239678, 5908.675022873697, 5920.289565252111, 5930.584859059867, 6250.406211409909, 6266.874662506724, 6276.780312689125, 6281.214848143982, 6286.339934306923, 5021.949780502148, 5984.2818861736, 6134.539909506805, 6174.938250617436, 6194.81415557525, 6242.781502647385, 6294.276158241861, 6297.874042519124, 6302.621842689453, 6307.211598550181, 6246.969250073777, 6247.656378899113, 6251.78118765841, 6271.27880293763, 6292.685365731695, 5501.669899806025, 5977.611455255482, 6108.04631976549, 6147.846303842351, 6258.3741625837965, 4925.13810083234, 4948.802047918043, 4949.381327334083, 4955.8513244602, 5001.799928002839, 4428.4685837699035, 4433.113377324548, 4454.7549884756, 4462.620677242406, 4505.36924830524, 5530.861728456821, 5576.079946808731, 5607.943920560742, 5678.659280443187, 5701.686407247641, 6232.283287421914, 6259.906101408483, 6293.087502312231, 6313.747450101944, 6323.683815809191, 5650.26546548946, 5665.065264231438, 5676.063449530864, 5676.290463692038, 5721.942780572224, 5233.528982391603, 5334.333208348957, 5344.0, 5407.675539467645, 5444.6188766785945, 6314.778982735582, 6315.842103947346, 6331.9366806331345, 6338.873222535616, 6347.206599175103, 6251.093591429309, 6256.0303153011355, 6296.748130074746, 6301.558890877594, 6303.653299068412, 6180.907286390618, 6229.345918678474, 6263.248410190807, 6271.153394291712, 6306.684665766785, 6201.96899015502, 6255.90521658717, 6265.624062556261, 6268.592541484789, 6271.0, 5524.281843360337, 6158.661273629993, 6275.717592708431, 6299.748010079546, 6319.115323854756, 5910.290765108214, 5986.16193732869, 6063.242094738158, 6094.51243900478, 6106.0, 6085.117657939485, 6090.086487026982, 6097.085437184458, 6105.877882442415, 6109.236345456818, 5888.4700376966275, 5901.262342207224, 5915.349311575809, 5936.851578710567, 6017.759289628366, 5926.407359264039, 5935.792247271324, 5941.465268125889, 5963.254593175853, 6005.129256257906, 5804.738786754522, 5833.649981001154, 5845.1232315153065, 5848.239728835394, 5856.824295271149], "value": 5683.84267179452, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [505.82900585145387, 512.1038183179434, 520.336570872138, 524.0802391802389, 548.478945002248, 28700.712992870063, 41604.37593436101, 42684.0, 42704.0, 42768.57231427685, 44151.558484415145, 44452.110957780824, 44604.55395446053, 44678.553214467844, 44693.55306446943, 8453.57732113395, 8653.913460865406, 9484.478353690543, 9672.613095476172, 9688.273379496532, 11370.260933039368, 11634.360110193937, 11670.35813030283, 11800.291982481078, 11923.224990375598, 11651.300921944669, 11655.883441165606, 11671.708207294801, 11677.357745324003, 11711.7072073198, 8539.957300213522, 11466.94266528662, 11644.30134191947, 11742.765144697058, 11749.001334886545, 11658.0, 11752.941235293854, 11752.941235293854, 11758.353290569015, 11771.411429428494, 40714.18571628551, 40866.795666021775, 41239.0, 41287.0, 41300.58699413027, 3355.74831887607, 3449.0, 3522.7710198836994, 3654.7807131571926, 3908.6873050156205, 28998.13005609835, 29262.122136335944, 30314.84842575795, 30330.69669303301, 30378.240543986358, 2628.0, 2635.5783074707956, 2765.9308517287027, 2769.1138835572624, 2775.791815613837, 524549.0, 525460.0, 525787.0, 527312.3634381841, 527694.0, 62403.6879815598, 62612.68693656503, 62955.68522157452, 63092.05361919575, 63174.68412657907, 62784.37215627785, 63755.36244637587, 65075.0, 65130.0, 65133.34866651273, 16177.676446471005, 16291.0, 16507.422240221535, 16519.174041297887, 16549.0, 11935.985441237419, 12194.085443591588, 12866.356682165855, 13167.012474064484, 13176.0, 16259.756103658454, 16309.227431336463, 16742.58143546421, 16758.91067080636, 16902.985820850787, 5757.366689664133, 5961.344252132262, 6726.226483954408, 6883.690233939485, 6976.476764242702, 734.5225603357753, 882.6513527156737, 908.6592527802075, 994.2195376629311, 1011.079917275276, 26849.0, 28923.855380722962, 29609.703902961126, 29671.258218544277, 29675.554866677016, 1025.553884060436, 1089.090609341209, 1097.1113398147527, 1115.7712668902855, 1117.7876203521469, 12305.507779688713, 12313.753724925451, 12950.740985180244, 14907.403703851724, 15234.0, 676433.0, 681135.0, 681802.0, 682561.0, 682584.0, 59895.700521497114, 60016.69991650101, 60088.0, 60168.39831601628, 60203.698981504815, 1136.9772604547863, 1432.4485073246874, 1475.7417451945867, 1487.6355292953297, 1488.24843454055, 25445.982160713364, 26246.0, 29322.0, 30278.09165725032, 30344.54483182798, 48619.0, 51139.488605113474, 51163.488365115874, 51279.743601281756, 51336.74331628318, 538960.0, 558174.0, 558288.0, 558666.0, 560407.0, 33773.15567110842, 39138.41292380673, 39247.607523924395, 39670.0, 39899.60100398959, 46297.6110716679, 46475.0, 46793.76603116962, 48000.0, 48440.0, 37810.865674029825, 37983.430248545745, 38134.809325953196, 38632.6136738629, 38672.613273866904, 31649.8417507911, 37588.436173456874, 37626.0, 38346.0413489665, 38548.229035418575, 33127.83436082804, 36849.0, 37304.813475932446, 37757.0, 38518.614813851505, 32157.678423215468, 33051.504227436126, 33393.0, 33553.32893342071, 33643.99068027963, 19736.40790776279, 23898.163564275168, 24451.877740611184, 24635.63046554339, 24865.378365541008], "value": 20261.48388637678, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [362.5051804287148, 387.79253099591716, 389.62401282762147, 400.19560683027106, 407.17953324052024, 19633.705494417576, 21548.461288467795, 21978.89010554947, 21996.89001554992, 22383.992720327584, 20612.89693551534, 20653.0, 21450.0, 21860.8906955465, 21901.452463688416, 5128.48715128486, 5384.40771515133, 5391.838244852641, 5401.837944861661, 5403.162509810985, 3050.3136794221346, 4028.858989935353, 5225.712585807779, 5649.943500565003, 5959.70201489926, 5982.79060232892, 5993.4006599340155, 6003.30961939376, 6010.8497287567725, 6014.729337179838, 5868.970655146718, 5872.0, 5950.55370847186, 5952.70236488176, 6047.274327080734, 3738.9439158412647, 5393.541548968343, 5710.486056254957, 5710.71446427677, 5811.941880581182, 10048.44733539655, 11973.40132993347, 12481.126321157522, 12514.74970500594, 13740.65648358789, 1902.4958386027713, 2034.9084291206932, 2253.9887300563555, 2341.9297421077395, 2444.4377793107683, 8968.013518512958, 9647.469389183592, 9716.562754675986, 9730.854037189449, 10312.329698569569, 1458.9197594132318, 1667.608112093657, 1691.8139004709471, 1711.3069206971197, 1733.7226043832927, 110241.0, 117142.0, 119332.0, 128066.0, 129296.3535182318, 11229.27009744364, 11261.0, 11309.49107290174, 11532.481038353126, 11641.243319184226, 12107.39463026845, 14555.490557830424, 14656.706865862836, 14688.632784180267, 14698.338574763948, 8619.870701939475, 8621.267192288726, 8661.263792577702, 8716.43343182691, 8814.779630509289, 8415.116412776693, 8598.613062412207, 8670.523121228392, 8758.56207189638, 8768.517731524827, 3929.410588411704, 4574.519675434098, 4584.151931892544, 4610.400647915817, 4640.559146881041, 1604.7994000749907, 1774.3434929076325, 1793.22483869242, 1823.3076187503427, 1913.2926908424702, 877.7761670774039, 956.416585882611, 969.2827307792325, 988.4761076629397, 1000.5847573257017, 19976.700349494768, 20608.454365922615, 20652.96735163236, 20672.48318792042, 20862.687059694115, 907.8865141857268, 921.2959962509921, 951.5147274889717, 997.804829375377, 1002.3484734922358, 10092.949535252423, 10148.543315550818, 10696.94651526753, 10834.266517357186, 10899.782004360028, 592743.0, 595192.0, 600171.0, 608137.0, 613124.0, 104639.0, 115751.2637310441, 127307.0, 134508.6549134496, 146820.0, 838.7693384319239, 981.6061193105807, 986.063239922077, 1021.5096753558186, 1023.3961962442195, 18040.0, 18256.178471968527, 18316.72524912154, 18330.725039124158, 18374.448766537025, 52296.0, 52852.0, 53255.0, 53389.73305133605, 53590.46409535855, 74244.62877685577, 74796.62601687174, 75308.24691753232, 75882.0, 76967.61516192384, 5355.3305836770405, 7404.851902962018, 7598.506097103671, 7823.100343460575, 7846.882296765667, 10457.84313235287, 12025.27848329068, 12187.729381276096, 12193.451294691937, 12408.193467424588, 8546.871796922927, 10338.534765935701, 10466.947665261625, 10529.210309226992, 10670.253082284165, 9034.683786067457, 10181.592736290466, 10341.4312212826, 10396.48017599132, 10638.41488718128, 10569.260151789302, 10666.0, 10743.677689669323, 10911.399873007062, 10961.0, 7699.422543309162, 7946.32456241226, 7968.960155199419, 7973.401994850526, 8190.180981901762, 9110.773748786529, 9446.858297125686, 9862.013798620368, 10678.092362149304, 10694.465276736288], "value": 9103.516082775619, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1389.5414513210642, 1546.1959780913921, 1576.4009676322996, 1815.4825874625726, 1980.1188471130345, 7787.64955576999, 8682.956585217082, 8824.249938755198, 8884.511351875644, 9018.774530636738, 8624.310055195585, 8708.738737837875, 8708.782280442992, 8850.645974161042, 8853.026167121627, 2069.286096296775, 2270.8069814065825, 2318.791308782205, 2349.400902769791, 2352.5177338645535, 2383.630537266718, 2398.3764221302413, 2403.471236328005, 2406.254061241012, 2412.8431651942656, 1993.372087792348, 2326.7673232676766, 2365.7042869641296, 2366.5148644527917, 2371.430856594422, 1954.6188493243824, 2207.6357401028813, 2267.365137761413, 2335.1943579465055, 2338.298510446859, 2348.4833336665906, 2365.042157926043, 2366.928992130239, 2368.5973384524673, 2370.4192472844093, 8793.516356600385, 8854.114588541095, 8859.06979767128, 8859.689910853154, 8889.333300002461, 5220.138677118271, 5277.6305658603915, 5371.946280537184, 5375.354957405098, 5400.513953744143, 3338.6327503974544, 3373.443381841993, 3571.1786289153642, 3571.8392672329805, 3579.3736096183065, 877.2148926710558, 996.5814357969598, 997.9900200998043, 1016.588281745894, 1045.5869931377063, 11825.586104486301, 11876.643700688906, 11884.821727674092, 11896.583619573274, 11907.583234586747, 4643.187442197602, 4764.523547645277, 4820.180569303227, 4832.855014349575, 4843.733594652328, 9959.352642078242, 9971.650992215238, 10015.899841001643, 10024.0, 10041.548130334153, 5328.92006619901, 5409.459054094559, 5412.377576578665, 5421.864453388697, 5426.077566813651, 4366.825326986885, 4531.025829446685, 4741.2176990796825, 4890.388701412324, 4934.358533390637, 3883.747556408881, 3948.1314110895555, 3990.0423898264207, 4029.979850100731, 4052.615001574846, 1167.7197472606683, 1266.316189257809, 1293.7994610835146, 1313.3170751209318, 1327.0644195841908, 822.2229992656881, 858.8797568340437, 905.3391024551983, 920.4201353147608, 943.7726964364095, 5605.271314729059, 5779.855503612359, 5887.234659494324, 5986.281646202484, 5996.687354073949, 438.36218302369974, 438.48478038304864, 444.56876829475357, 450.9368688383629, 461.10545541649094, 3832.789196594214, 3883.689304855605, 3930.0, 3983.780892050907, 3997.6801855851463, 10506.212034097472, 10838.67483975482, 10868.402237876993, 10923.836142457869, 10960.013598776148, 10161.237907156992, 10764.730881728019, 10838.67483975482, 10852.077573406192, 10886.29239099456, 8091.109977902426, 8215.548144852091, 8315.95842020786, 8331.500109993298, 8334.874976875351, 5843.649381037151, 5869.297903607906, 5933.525317974465, 6072.937235983597, 6187.1647327612, 9551.235901127755, 10063.748406289902, 10137.884832668547, 10230.539625716712, 10246.026627470379, 10052.64815731446, 10111.848322275024, 10242.897571024492, 10290.0, 10344.844827327746, 6393.487474620461, 6887.793366199022, 7129.607871567114, 7418.295261950106, 7611.543307401574, 7893.605319734105, 8366.33069354438, 8474.152584741476, 8479.279261262862, 8510.361722870686, 7390.852182956418, 7453.776386708408, 7473.028506294147, 7585.658645361083, 7660.463767536219, 7279.0, 7343.853122937405, 7362.815929601803, 7410.333070023508, 7508.0, 6842.55523390978, 6996.440284777103, 7006.124234470692, 7012.509124361246, 7024.473164512784, 4780.713157210578, 4834.250691142878, 4913.06651736169, 4930.901381972412, 5104.2343648453025, 4886.364772579542, 4943.134951383566, 4954.083494553519, 4981.70109793399, 5176.948230517647], "value": 4486.17423952756, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [304.30162776428097, 307.8799268285368, 313.08266778339464, 315.55034076441063, 318.41411802283795, 4922.0, 5118.564921981631, 5432.311006052, 5514.91727624086, 5552.972235138819, 4469.888252793682, 4546.635749410305, 5389.137737961927, 5389.676619402839, 5412.83761487156, 3398.779079359846, 3549.988253347796, 3854.383298672213, 3989.780562069085, 4063.4108054332105, 3115.115355210099, 3465.220325426784, 3651.561812582494, 3928.998105483098, 4035.6367926886574, 4012.5184977802705, 4025.859094931678, 4038.2327357801923, 4041.737287076345, 4066.8983275418054, 3862.806859657006, 3975.4434379186937, 4024.9195016099807, 4048.392741088831, 4075.8777236682945, 2982.492976194052, 3510.33303672304, 3513.1744040150543, 3519.63043880394, 3537.2218112015316, 5091.0, 5107.0, 5117.411497677777, 5126.820561280375, 5167.612429067797, 3864.8260828262514, 4076.857309994136, 4147.921540399488, 4387.166438376698, 4604.204360299486, 4782.880427989293, 5300.840974770763, 5372.54333381663, 5610.326760788691, 5616.691081990489, 3483.477478378238, 3798.841353387216, 3927.0, 3952.940705889414, 3956.881293561169, 5551.750171242304, 5862.443067908584, 5894.789453954767, 5912.34964153947, 5951.553633477506, 3334.6498617645284, 3596.3526565218513, 3751.531058617673, 3859.807009649506, 3904.3557812960544, 3934.403293826061, 3944.783036932939, 3967.504061992251, 3974.5627980922077, 3983.3029219886985, 3943.369060950234, 3980.7014473914574, 4323.832565207376, 4661.720296782204, 4666.300054991711, 4964.641795147276, 5287.550558202596, 5448.828501872037, 5509.779608815683, 5580.381689310055, 1296.397175313465, 1348.2315080404271, 1353.8781509664177, 1356.796480527909, 1365.7336819320137, 730.9195988441268, 734.7391675954959, 808.7048227396958, 820.9425340226246, 825.5005721538503, 4375.5405682403525, 4674.994876101655, 4780.617550596014, 4849.599448273128, 5301.284326615934, 4655.348251244828, 4729.763511824395, 5785.739641716133, 5793.333766616809, 5861.443162899518, 4945.950540494621, 5264.92102618461, 5317.202419637086, 5339.01228272763, 5368.436314187033, 2533.5946248600135, 3008.518637018067, 3039.9240018999703, 3051.725344718986, 3060.6786287439945, 5187.406296851575, 5778.16216648592, 5984.611000284967, 6017.338092809788, 6047.365026672224, 4683.297505374153, 5123.641345105882, 5375.3280839895015, 5914.112883067574, 6033.849153771103, 5099.388073431212, 5217.973910130425, 6065.2418447694035, 6080.9391906081255, 6083.878322433615, 5671.262735844314, 5851.707414629166, 5871.796281762314, 5890.587658863838, 5899.793507227227, 5718.342390625131, 5758.387651457599, 5767.769289228551, 5768.480836724715, 5788.594798364074, 4839.395075615548, 5346.65246758975, 5551.8889622206525, 6053.909191362045, 6059.6364218147055, 3507.5440192774777, 3681.8645185791643, 3684.4289135183017, 3850.0, 3888.902777430587, 5687.573431992534, 5797.739101740516, 5815.302163740209, 5822.155787410887, 5830.329512106162, 5024.84925452237, 5034.539983404771, 5638.126090455986, 5660.320761508646, 5680.40355762639, 4974.353334066548, 5306.975024236148, 5413.269208656781, 5663.915041274467, 5681.0, 5505.504504594445, 5517.503424691797, 5531.668099914018, 5534.557235421237, 5540.722963851711, 4794.352762377174, 5383.838484845304, 5451.555337835397, 5524.889502210013, 5621.016322143691, 4932.7286999215385, 5543.667379957215, 5557.9709058563985, 5566.524870909293, 5579.856129493363], "value": 4056.917803709889, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [618.227215980025, 630.9905351419727, 652.7225928980185, 668.1547841979893, 747.19676347926, 14920.850791492081, 15033.398664053439, 15231.238438078079, 15238.542843714678, 15260.542183734491, 13514.391852366642, 14268.143911365325, 14349.210793406384, 14367.35346909389, 14369.281535923214, 719.6509692798988, 890.1143362354467, 900.954952252388, 909.9499527525984, 910.7495438754329, 732.0227496292456, 888.1518150166607, 914.7301546043933, 917.4082716647758, 920.102899672817, 931.5156118818211, 954.4511905654242, 954.7279025477741, 955.6416343871049, 958.1233171647934, 754.811297175706, 922.9676961306322, 947.0576776107782, 949.924006079512, 950.7100334398006, 709.0923617769264, 709.1313141401793, 709.6238993333542, 711.498393632493, 712.291270186168, 14862.256887155707, 14995.925020374938, 15002.24988750558, 15003.549893503212, 15028.849711502964, 12231.449584768618, 12280.631581052583, 12347.320897350643, 12357.135000549964, 12573.11988160829, 10711.571537138505, 10954.068904143078, 10965.177611679077, 11310.151738619636, 11311.547538098466, 3965.1078507335906, 3983.810493382646, 4003.459532963042, 4043.9460012516465, 4164.292070348048, 85615.0, 88084.0, 88376.0, 88406.0, 89228.55385723095, 9407.388519746195, 9418.105279998526, 9447.905520944703, 9458.243340532741, 9875.456849873326, 21920.89039554792, 22094.668579971312, 22116.0, 22147.231596050493, 22152.0, 7110.253423390573, 7223.494355395177, 7315.853682926312, 7370.078740157481, 7495.8500829983095, 3818.427235914635, 3847.615238476186, 3997.2804895119157, 4004.839806407769, 4008.5390180129075, 3410.0963244740165, 3422.7261819054415, 3426.811525366079, 3508.6315936826777, 3513.2622149348413, 563.4168635462277, 595.8410890817405, 614.4377894226733, 634.194572892424, 647.8445173158408, 467.41806450968085, 495.2892599120202, 545.9153831156177, 551.2640624765902, 564.3989151553583, 6460.515461340418, 7639.93040974264, 7718.53688778675, 7780.3386712130105, 7998.880016799752, 535.7589084911727, 537.492069993852, 554.0995881692251, 577.6707276852154, 578.6961845031392, 3585.0, 3659.688926441229, 3750.6061863504483, 3759.586445490994, 3775.7923314217983, 65747.67126164338, 93170.0, 95824.0, 96329.0, 99342.5032874831, 78084.60957695176, 83348.0, 84877.57561212154, 85562.57218713866, 85814.14185858061, 71974.0, 72311.0, 72348.63825680838, 72561.0, 73331.0, 6909.754329842922, 6936.994135850173, 7222.6388680566815, 7315.122185337581, 7451.4038876888635, 45398.77300613476, 46161.076778464914, 46186.538134618226, 46801.063978720915, 47155.0, 58539.70730146322, 59639.105413419704, 59710.40289597048, 64483.677581611795, 65742.67128664517, 9741.220702343653, 9982.101610854768, 10273.9486302568, 10283.588656453958, 10343.63797267092, 10359.896401035892, 10414.531346089596, 10442.425666588108, 10461.320014199357, 10710.518026688662, 9668.70993870185, 9851.556679949275, 9900.257480688833, 9994.850077248993, 10286.948565257126, 9977.401355918668, 10003.899961000297, 10027.649032283834, 10107.69676909694, 10433.478326083818, 9707.6116955321, 9731.562079706571, 9794.412335259907, 9848.803023939625, 10191.796164076824, 8385.748427547183, 8493.872591911002, 8561.82876342482, 8598.570071496526, 8920.776980575538, 8492.872606911025, 8609.182127697859, 8668.78328041804, 8799.296056315607, 8811.427257228259], "value": 6658.978801094576, "unit": "score"}], "name": "dromaeo_css", "value": 7090.468384723972, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 105.0, 113.0, 112.0, 112.0, 111.0, 112.0, 113.0, 111.0, 112.0], "value": 111.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [153.0, 117.0, 118.0, 168.0, 116.0, 118.0, 139.0, 115.0, 149.0, 114.0], "value": 130.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [183.0, 185.0, 185.0, 183.0, 188.0, 186.0, 183.0, 182.0, 182.0, 185.0], "value": 184.2, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 57.0, 60.0, 87.0, 59.0, 57.0, 64.0, 68.0, 83.0, 57.0], "value": 67.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 69.0, 104.0, 70.0, 76.0, 69.0, 96.0, 70.0, 69.0, 102.0], "value": 81.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 129.0, 90.0, 88.0, 91.0, 87.0, 91.0, 87.0, 89.0, 87.0], "value": 93.2, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 95.0, 95.0, 95.0, 94.0, 95.0, 95.0, 96.0, 95.0, 95.0], "value": 94.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 84.0, 85.0, 84.0, 85.0, 84.0, 84.0, 84.0, 84.0, 90.0], "value": 84.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 56.0, 58.0, 55.0, 56.0, 56.0, 55.0, 76.0, 58.0, 54.0], "value": 58.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 68.0, 74.0, 76.0, 71.0, 72.0, 69.0, 71.0, 69.0, 69.0], "value": 70.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 86.0, 89.0, 102.0, 88.0, 85.0, 85.0, 86.0, 84.0, 87.0], "value": 87.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [140.0, 141.0, 138.0, 140.0, 138.0, 139.0, 146.0, 146.0, 179.0, 157.0], "value": 146.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 167.0, 165.0, 164.0, 167.0, 163.0, 166.0, 163.0, 163.0, 216.0], "value": 169.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 66.0, 66.0, 67.0, 66.0, 65.0, 67.0, 67.0, 67.0, 65.0], "value": 66.1, "unit": "score"}], "name": "kraken", "value": 1445.8999999999999, "alertThreshold": 2.0}]}
18:52:35 INFO - Return code: 0
18:52:35 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json
18:52:35 ERROR - # TBPL SUCCESS #
18:52:35 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'
18:52:35 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build
18:52:35 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType"
18:52:35 INFO - 2016-05-24 18:52:35.385 screenresolution[1678:9881] starting screenresolution argv=screenresolution get
18:52:35 INFO - 2016-05-24 18:52:35.388 screenresolution[1678:9881] Display 0: 1600x1200x32@60
18:52:35 INFO - 2016-05-24 18:52:35.394 screenresolution[1679:9884] starting screenresolution argv=screenresolution list
18:52:35 INFO - Available Modes on Display 0
18:52:35 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75
18:52:35 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60
18:52:35 INFO - 800x600x32@75 800x600x32@60 640x480x32@75
18:52:35 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60
18:52:35 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays:
18:52:35 INFO - Intel Iris:
18:52:35 INFO - Chipset Model: Intel Iris
18:52:35 INFO - Type: GPU
18:52:35 INFO - Bus: Built-In
18:52:35 INFO - VRAM (Dynamic, Max): 1536 MB
18:52:35 INFO - Vendor: Intel (0x8086)
18:52:35 INFO - Device ID: 0x0a2e
18:52:35 INFO - Revision ID: 0x0009
18:52:35 INFO - Displays:
18:52:35 INFO - Display:
18:52:35 INFO - Resolution: 1600 x 1200 @ 60 Hz
18:52:35 INFO - Pixel Depth: 32-Bit Color (ARGB8888)
18:52:35 INFO - Display Serial Number: 1600x1200 60
18:52:35 INFO - Main Display: Yes
18:52:35 INFO - Mirror: Off
18:52:35 INFO - Online: Yes
18:52:35 INFO - Rotation: Supported
18:52:35 INFO - Return code: 0
18:52:35 INFO - Running post-action listener: _resource_record_post_action
18:52:35 INFO - #####
18:52:35 INFO - ##### Finished run-tests step (success)
18:52:35 INFO - #####
18:52:35 INFO - Running post-run listener: _resource_record_post_run
18:52:35 INFO - Total resource usage - Wall time: 1211s; CPU: 54.0%; Read bytes: 31538688; Write bytes: 694974976; Read time: 200; Write time: 1910
18:52:35 INFO - TinderboxPrint: CPU usage
53.8%
18:52:35 INFO - TinderboxPrint: I/O read bytes / time
31,538,688 / 200
18:52:35 INFO - TinderboxPrint: I/O write bytes / time
694,974,976 / 1,910
18:52:35 INFO - TinderboxPrint: CPU idle
2,240.9 (46.2%)
18:52:35 INFO - TinderboxPrint: CPU system
973.1 (20.1%)
18:52:35 INFO - TinderboxPrint: CPU user
1,631.7 (33.7%)
18:52:35 INFO - TinderboxPrint: Swap in / out
424,189,952 / 0
18:52:35 INFO - install - Wall time: 34s; CPU: 54.0%; Read bytes: 390492672; Write bytes: 385075200; Read time: 29372; Write time: 781
18:52:35 INFO - run-tests - Wall time: 1177s; CPU: 54.0%; Read bytes: 29756928; Write bytes: 294564352; Read time: 184; Write time: 1085
18:52:36 INFO - Running post-run listener: _upload_blobber_files
18:52:36 INFO - Blob upload gear active.
18:52:36 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir.
18:52:36 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com
18:52:36 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Fx-Team', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json']
18:52:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Fx-Team -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json
18:52:36 INFO - (blobuploader) - INFO - Open directory for files ...
18:52:36 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ...
18:52:36 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com
18:52:36 INFO - (blobuploader) - INFO - Uploading, attempt #1.
18:52:37 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded
18:52:37 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded!
18:52:37 INFO - (blobuploader) - INFO - Done attempting.
18:52:37 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ...
18:52:37 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com
18:52:37 INFO - (blobuploader) - INFO - Uploading, attempt #1.
18:52:38 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded
18:52:38 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded!
18:52:38 INFO - (blobuploader) - INFO - Done attempting.
18:52:38 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ...
18:52:38 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com
18:52:38 INFO - (blobuploader) - INFO - Uploading, attempt #1.
18:52:41 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded
18:52:41 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded!
18:52:41 INFO - (blobuploader) - INFO - Done attempting.
18:52:41 INFO - (blobuploader) - INFO - Iteration through files over.
18:52:41 INFO - Return code: 0
18:52:41 INFO - rmtree: /builds/slave/test/build/uploaded_files.json
18:52:41 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1
18:52:41 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/879ead7aee2c951e02f666d0003229b99a53d27fa73d53d1ea23a4579261bd181cc405534af03370494c243026bc6c89c0e30c06624dc99d123877bcf0527499", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/492c077dad2eb4cbee75d47740936218b34b4b44f9147a711b128e6c07a8495981ea9e40642f0f9351788ce9a6677c1966981b07a5acf9b5ce2137ee54289607", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/77c9fb0ca83c71b53cd5cadff19fc0516fd053424e5a94d647d8313401c576eca03525bc8883ea0b9fefc59f6cceeccab21f8cc74207a624fe1e3b135c4c5a7f"}
18:52:41 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files
18:52:41 INFO - Writing to file /builds/slave/test/properties/blobber_files
18:52:41 INFO - Contents:
18:52:41 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/879ead7aee2c951e02f666d0003229b99a53d27fa73d53d1ea23a4579261bd181cc405534af03370494c243026bc6c89c0e30c06624dc99d123877bcf0527499", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/492c077dad2eb4cbee75d47740936218b34b4b44f9147a711b128e6c07a8495981ea9e40642f0f9351788ce9a6677c1966981b07a5acf9b5ce2137ee54289607", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/77c9fb0ca83c71b53cd5cadff19fc0516fd053424e5a94d647d8313401c576eca03525bc8883ea0b9fefc59f6cceeccab21f8cc74207a624fe1e3b135c4c5a7f"}
18:52:41 INFO - Running post-run listener: copy_logs_to_upload_dir
18:52:41 INFO - Copying logs to upload dir...
18:52:41 INFO - mkdir: /builds/slave/test/build/upload/logs
18:52:41 INFO - Copying logs to upload dir...
program finished with exit code 0
elapsedTime=1277.859653
========= master_lag: 0.51 =========
========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 18 secs) (at 2016-05-24 18:52:42.489213) =========
========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-24 18:52:42.493164) =========
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.xpal0DPj4f/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.92gVrI0cof/Listeners
TMPDIR=/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/
TWISTD_LOG_PATH=/builds/slave/twistd.log
USER=cltbld
VERSIONER_PYTHON_PREFER_32_BIT=no
VERSIONER_PYTHON_VERSION=2.7
XPCOM_DEBUG_BREAK=warn
XPC_FLAGS=0x0
XPC_SERVICE_NAME=0
__CF_USER_TEXT_ENCODING=0x1C:0:0
using PTY: False
blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/879ead7aee2c951e02f666d0003229b99a53d27fa73d53d1ea23a4579261bd181cc405534af03370494c243026bc6c89c0e30c06624dc99d123877bcf0527499", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/492c077dad2eb4cbee75d47740936218b34b4b44f9147a711b128e6c07a8495981ea9e40642f0f9351788ce9a6677c1966981b07a5acf9b5ce2137ee54289607", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/77c9fb0ca83c71b53cd5cadff19fc0516fd053424e5a94d647d8313401c576eca03525bc8883ea0b9fefc59f6cceeccab21f8cc74207a624fe1e3b135c4c5a7f"}
build_url:https://queue.taskcluster.net/v1/task/HkqLcYedTf6XAzOv4myc3Q/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg
program finished with exit code 0
elapsedTime=0.011113
build_url: 'https://queue.taskcluster.net/v1/task/HkqLcYedTf6XAzOv4myc3Q/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg'
blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/879ead7aee2c951e02f666d0003229b99a53d27fa73d53d1ea23a4579261bd181cc405534af03370494c243026bc6c89c0e30c06624dc99d123877bcf0527499", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/492c077dad2eb4cbee75d47740936218b34b4b44f9147a711b128e6c07a8495981ea9e40642f0f9351788ce9a6677c1966981b07a5acf9b5ce2137ee54289607", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/77c9fb0ca83c71b53cd5cadff19fc0516fd053424e5a94d647d8313401c576eca03525bc8883ea0b9fefc59f6cceeccab21f8cc74207a624fe1e3b135c4c5a7f"}'
========= master_lag: 0.59 =========
========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-24 18:52:43.093965) =========
========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-24 18:52:43.094280) =========
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.xpal0DPj4f/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.92gVrI0cof/Listeners
TMPDIR=/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/
TWISTD_LOG_PATH=/builds/slave/twistd.log
USER=cltbld
VERSIONER_PYTHON_PREFER_32_BIT=no
VERSIONER_PYTHON_VERSION=2.7
XPCOM_DEBUG_BREAK=warn
XPC_FLAGS=0x0
XPC_SERVICE_NAME=0
__CF_USER_TEXT_ENCODING=0x1C:0:0
using PTY: False
program finished with exit code 0
elapsedTime=0.005212
========= master_lag: 0.08 =========
========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-24 18:52:43.184159) =========
========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-24 18:52:43.184462) =========
========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-24 18:52:43.184893) =========
========= Total master_lag: 6.60 =========