builder: mozilla-central_yosemite_r7_test-dromaeojs
slave: t-yosemite-r7-0321
starttime: 1494028760.67
results: success (0)
buildid: 20170505161344
builduid: c6384675c533465fb250ab23704247da
revision: 37a5b7f6f101df2eb292b1b6baaf1540c9920e20
========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-05-05 16:59:20.673898) =========
master: http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/
========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-05-05 16:59:20.674334) =========
========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-05 16:59:20.674606) =========
========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-05 16:59:20.701343) =========
========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-05 16:59:20.701699) =========
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.9bysca6FWz/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.Yspqbs65xC/Listeners
TMPDIR=/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/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
--2017-05-05 16:59:20-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py
Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102
Connecting to hg.mozilla.org|63.245.215.25|:443... connected.
HTTP request sent, awaiting response... 200 Script output follows
Length: 12179 (12K) [text/x-python]
Saving to: 'archiver_client.py'
0K .......... . 100% 39.6M=0s
2017-05-05 16:59:20 (39.6 MB/s) - 'archiver_client.py' saved [12179/12179]
program finished with exit code 0
elapsedTime=0.148886
========= master_lag: 0.03 =========
========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-05 16:59:20.875930) =========
========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-05 16:59:20.876244) =========
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.9bysca6FWz/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.Yspqbs65xC/Listeners
TMPDIR=/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/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.031322
========= master_lag: 0.02 =========
========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-05 16:59:20.931186) =========
========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-05-05 16:59:20.931698) =========
bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 37a5b7f6f101df2eb292b1b6baaf1540c9920e20 --destination scripts --debug'
in dir /builds/slave/test/. (timeout 1200 secs)
watching logfiles {}
argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev 37a5b7f6f101df2eb292b1b6baaf1540c9920e20 --destination scripts --debug']
environment:
Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.9bysca6FWz/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.Yspqbs65xC/Listeners
TMPDIR=/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/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
2017-05-05 16:59:21,029 truncating revision to first 12 chars
2017-05-05 16:59:21,029 Setting DEBUG logging.
2017-05-05 16:59:21,029 attempt 1/10
2017-05-05 16:59:21,029 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/37a5b7f6f101?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness
2017-05-05 16:59:22,121 unpacking tar archive at: mozilla-central-37a5b7f6f101/testing/mozharness/
program finished with exit code 0
elapsedTime=1.375571
========= master_lag: 0.03 =========
========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-05-05 16:59:22.332892) =========
========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-05 16:59:22.333245) =========
script_repo_revision: 37a5b7f6f101df2eb292b1b6baaf1540c9920e20
========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-05 16:59:22.333698) =========
========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-05 16:59:22.334043) =========
========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-05 16:59:22.354737) =========
========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 16 secs) (at 2017-05-05 16:59:22.355093) =========
/tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox
in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs)
watching logfiles {}
argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox']
environment:
Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.9bysca6FWz/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.Yspqbs65xC/Listeners
TMPDIR=/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/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
16:59:22 INFO - MultiFileLogger online at 20170505 16:59:22 in /builds/slave/test
16:59:22 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox
16:59:22 INFO - Dumping config to /builds/slave/test/logs/localconfig.json.
16:59:22 INFO - {'append_to_log': False,
16:59:22 INFO - 'base_work_dir': '/builds/slave/test',
16:59:22 INFO - 'blob_upload_branch': 'Firefox',
16:59:22 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt',
16:59:22 INFO - 'branch': 'Firefox',
16:59:22 INFO - 'buildbot_json_path': 'buildprops.json',
16:59:22 INFO - 'config_files': ('talos/mac_config.py',),
16:59:22 INFO - 'default_actions': ('clobber',
16:59:22 INFO - 'read-buildbot-config',
16:59:22 INFO - 'download-and-extract',
16:59:22 INFO - 'populate-webroot',
16:59:22 INFO - 'create-virtualenv',
16:59:22 INFO - 'install',
16:59:22 INFO - 'run-tests'),
16:59:22 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',),
16:59:22 INFO - 'download_minidump_stackwalk': True,
16:59:22 INFO - 'download_symbols': 'ondemand',
16:59:22 INFO - 'exes': {'python': '/tools/buildbot/bin/python',
16:59:22 INFO - 'tooltool.py': '/tools/tooltool.py',
16:59:22 INFO - 'virtualenv': ('/tools/buildbot/bin/python',
16:59:22 INFO - '/tools/misc-python/virtualenv.py')},
16:59:22 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub',
16:59:22 INFO - 'http://pypi.pub.build.mozilla.org/pub'),
16:59:22 INFO - 'gecko_profile': False,
16:59:22 INFO - 'gecko_profile_interval': 0,
16:59:22 INFO - 'installer_path': 'installer.exe',
16:59:22 INFO - 'log_level': 'info',
16:59:22 INFO - 'log_name': 'talos',
16:59:22 INFO - 'log_to_console': True,
16:59:22 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk',
16:59:22 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest',
16:59:22 INFO - 'opt_config_files': (),
16:59:22 INFO - 'pip_index': False,
16:59:22 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'),
16:59:22 INFO - 'cmd': ('bash',
16:59:22 INFO - '-c',
16:59:22 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'),
16:59:22 INFO - 'enabled': True,
16:59:22 INFO - 'halt_on_failure': False,
16:59:22 INFO - 'name': 'check_screen_resolution'},),
16:59:22 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'),
16:59:22 INFO - 'cmd': ('bash',
16:59:22 INFO - '-c',
16:59:22 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'),
16:59:22 INFO - 'enabled': True,
16:59:22 INFO - 'halt_on_failure': False,
16:59:22 INFO - 'name': 'check_screen_resolution'},),
16:59:22 INFO - 'run_cmd_checks_enabled': True,
16:59:22 INFO - 'suite': 'dromaeojs',
16:59:22 INFO - 'system_bits': '32',
16:59:22 INFO - 'talos_extra_options': ('--webServer', 'localhost'),
16:59:22 INFO - 'title': 't-yosemite-r7-0321',
16:59:22 INFO - 'tooltool_cache': '/builds/tooltool_cache',
16:59:22 INFO - 'use_talos_json': True,
16:59:22 INFO - 'virtualenv_path': '/builds/slave/test/build/venv',
16:59:22 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None},
16:59:22 INFO - 'work_dir': 'build'}
16:59:22 INFO - [mozharness: 2017-05-05 23:59:22.559967Z] Running clobber step.
16:59:22 INFO - Running pre-action listener: _resource_record_pre_action
16:59:22 INFO - Running main action method: clobber
16:59:22 INFO - rmtree: /builds/slave/test/build
16:59:22 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1
16:59:25 INFO - Running post-action listener: _resource_record_post_action
16:59:25 INFO - [mozharness: 2017-05-05 23:59:25.059656Z] Finished clobber step (success)
16:59:25 INFO - [mozharness: 2017-05-05 23:59:25.059775Z] Running read-buildbot-config step.
16:59:25 INFO - Running pre-action listener: _resource_record_pre_action
16:59:25 INFO - Running main action method: read_buildbot_config
16:59:25 INFO - Using buildbot properties:
16:59:25 INFO - {
16:59:25 INFO - "project": "",
16:59:25 INFO - "product": "firefox",
16:59:25 INFO - "script_repo_revision": "production",
16:59:25 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs",
16:59:25 INFO - "repository": "",
16:59:25 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs",
16:59:25 INFO - "buildid": "20170505161344",
16:59:25 INFO - "pgo_build": "False",
16:59:25 INFO - "basedir": "/builds/slave/test",
16:59:25 INFO - "buildnumber": 25,
16:59:25 INFO - "slavename": "t-yosemite-r7-0321",
16:59:25 INFO - "master": "http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/",
16:59:25 INFO - "platform": "yosemite_r7",
16:59:25 INFO - "scheduler": "tests-mozilla-central-macosx64-talos",
16:59:25 INFO - "branch": "mozilla-central",
16:59:25 INFO - "repo_path": "mozilla-central",
16:59:25 INFO - "revision": "37a5b7f6f101df2eb292b1b6baaf1540c9920e20",
16:59:25 INFO - "stage_platform": "macosx64",
16:59:25 INFO - "builduid": "c6384675c533465fb250ab23704247da",
16:59:25 INFO - "slavebuilddir": "test"
16:59:25 INFO - }
16:59:25 INFO - Found installer url https://queue.taskcluster.net/v1/task/Tet-IZzERMCWsG0xEhzITw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg.
16:59:25 INFO - Running post-action listener: _resource_record_post_action
16:59:25 INFO - [mozharness: 2017-05-05 23:59:25.063039Z] Finished read-buildbot-config step (success)
16:59:25 INFO - [mozharness: 2017-05-05 23:59:25.063169Z] Running download-and-extract step.
16:59:25 INFO - Running pre-action listener: _resource_record_pre_action
16:59:25 INFO - Running main action method: download_and_extract
16:59:25 INFO - mkdir: /builds/slave/test/build/tests
16:59: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
16:59:25 INFO - trying https://queue.taskcluster.net/v1/task/Tet-IZzERMCWsG0xEhzITw/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json
16:59:25 INFO - Downloading https://queue.taskcluster.net/v1/task/Tet-IZzERMCWsG0xEhzITw/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json
16:59:25 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Tet-IZzERMCWsG0xEhzITw/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json'}, attempt #1
16:59:25 INFO - Downloaded 1325 bytes.
16:59:25 INFO - Reading from file /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json
16:59:25 INFO - Using the following test package requirements:
16:59:25 INFO - {u'awsy': [u'firefox-55.0a1.en-US.mac.common.tests.zip',
16:59:25 INFO - u'firefox-55.0a1.en-US.mac.awsy.tests.zip'],
16:59:25 INFO - u'common': [u'firefox-55.0a1.en-US.mac.common.tests.zip'],
16:59:25 INFO - u'cppunittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip',
16:59:25 INFO - u'firefox-55.0a1.en-US.mac.cppunittest.tests.zip'],
16:59:25 INFO - u'gtest': [u'firefox-55.0a1.en-US.mac.common.tests.zip',
16:59:25 INFO - u'firefox-55.0a1.en-US.mac.gtest.tests.zip'],
16:59:25 INFO - u'jittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip',
16:59:25 INFO - u'jsshell-mac.zip'],
16:59:25 INFO - u'mochitest': [u'firefox-55.0a1.en-US.mac.common.tests.zip',
16:59:25 INFO - u'firefox-55.0a1.en-US.mac.mochitest.tests.zip'],
16:59:25 INFO - u'mozbase': [u'firefox-55.0a1.en-US.mac.common.tests.zip'],
16:59:25 INFO - u'reftest': [u'firefox-55.0a1.en-US.mac.common.tests.zip',
16:59:25 INFO - u'firefox-55.0a1.en-US.mac.reftest.tests.zip'],
16:59:25 INFO - u'talos': [u'firefox-55.0a1.en-US.mac.common.tests.zip',
16:59:25 INFO - u'firefox-55.0a1.en-US.mac.talos.tests.zip'],
16:59:25 INFO - u'web-platform': [u'firefox-55.0a1.en-US.mac.common.tests.zip',
16:59:25 INFO - u'firefox-55.0a1.en-US.mac.web-platform.tests.zip'],
16:59:25 INFO - u'xpcshell': [u'firefox-55.0a1.en-US.mac.common.tests.zip',
16:59:25 INFO - u'firefox-55.0a1.en-US.mac.xpcshell.tests.zip']}
16:59:25 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip'] for test suite category: common
16:59:25 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Tet-IZzERMCWsG0xEhzITw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip
16:59:25 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Tet-IZzERMCWsG0xEhzITw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1
16:59:25 INFO - Fetch https://queue.taskcluster.net/v1/task/Tet-IZzERMCWsG0xEhzITw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory
16:59:27 INFO - Content-Length response header: 52703722
16:59:27 INFO - Bytes received: 52703722
16:59:43 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip', u'firefox-55.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos
16:59:43 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Tet-IZzERMCWsG0xEhzITw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip
16:59:43 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Tet-IZzERMCWsG0xEhzITw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1
16:59:43 INFO - Fetch https://queue.taskcluster.net/v1/task/Tet-IZzERMCWsG0xEhzITw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory
16:59:45 INFO - Content-Length response header: 52703722
16:59:45 INFO - Bytes received: 52703722
17:00:00 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Tet-IZzERMCWsG0xEhzITw/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip
17:00:00 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Tet-IZzERMCWsG0xEhzITw/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip'}, attempt #1
17:00:00 INFO - Fetch https://queue.taskcluster.net/v1/task/Tet-IZzERMCWsG0xEhzITw/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip into memory
17:00:01 INFO - Content-Length response header: 14408697
17:00:01 INFO - Bytes received: 14408697
17:00:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
17:00:01 INFO - trying https://queue.taskcluster.net/v1/task/Tet-IZzERMCWsG0xEhzITw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg
17:00:01 INFO - Downloading https://queue.taskcluster.net/v1/task/Tet-IZzERMCWsG0xEhzITw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg
17:00:01 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Tet-IZzERMCWsG0xEhzITw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg'}, attempt #1
17:00:03 INFO - Downloaded 70883507 bytes.
17:00:03 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Tet-IZzERMCWsG0xEhzITw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg
17:00:03 INFO - mkdir: /builds/slave/test/properties
17:00:03 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url
17:00:03 INFO - Writing to file /builds/slave/test/properties/build_url
17:00:03 INFO - Contents:
17:00:03 INFO - build_url:https://queue.taskcluster.net/v1/task/Tet-IZzERMCWsG0xEhzITw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg
17:00:04 INFO - Running post-action listener: _resource_record_post_action
17:00:04 INFO - Running post-action listener: set_extra_try_arguments
17:00:04 INFO - [mozharness: 2017-05-06 00:00:04.601398Z] Finished download-and-extract step (success)
17:00:04 INFO - [mozharness: 2017-05-06 00:00:04.601520Z] Running populate-webroot step.
17:00:04 INFO - Running pre-action listener: _resource_record_pre_action
17:00:04 INFO - Running main action method: populate_webroot
17:00:04 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']},
17:00:04 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']},
17:00:04 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome',
17:00:04 INFO - '--tppagecycles',
17:00:04 INFO - '7'],
17:00:04 INFO - 'tests': ['tsvgm']}},
17:00:04 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'],
17:00:04 INFO - 'tests': ['tresize', 'tcanvasmark']},
17:00:04 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']},
17:00:04 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'],
17:00:04 INFO - 'tests': ['dromaeo_css', 'kraken']},
17:00:04 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']},
17:00:04 INFO - 'g1': {'pagesets_name': 'tp5n.zip',
17:00:04 INFO - 'talos_options': ['--disable-e10s'],
17:00:04 INFO - 'tests': ['tp5o_scroll', 'glterrain']},
17:00:04 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip',
17:00:04 INFO - 'tests': ['tp5o_scroll', 'glterrain']},
17:00:04 INFO - 'g2': {'pagesets_name': 'tp5n.zip',
17:00:04 INFO - 'talos_options': ['--disable-e10s'],
17:00:04 INFO - 'tests': ['damp', 'tps']},
17:00:04 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip',
17:00:04 INFO - 'tests': ['damp', 'tps']},
17:00:04 INFO - 'g3': {'talos_options': ['--disable-e10s'],
17:00:04 INFO - 'tests': ['dromaeo_dom']},
17:00:04 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']},
17:00:04 INFO - 'g4': {'talos_options': ['--disable-e10s'],
17:00:04 INFO - 'tests': ['basic_compositor_video', 'glvideo']},
17:00:04 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']},
17:00:04 INFO - 'other': {'talos_options': ['--disable-e10s'],
17:00:04 INFO - 'tests': ['a11yr',
17:00:04 INFO - 'ts_paint',
17:00:04 INFO - 'tpaint',
17:00:04 INFO - 'sessionrestore',
17:00:04 INFO - 'sessionrestore_no_auto_restore',
17:00:04 INFO - 'tabpaint']},
17:00:04 INFO - 'other-e10s': {'tests': ['a11yr',
17:00:04 INFO - 'ts_paint',
17:00:04 INFO - 'tpaint',
17:00:04 INFO - 'sessionrestore',
17:00:04 INFO - 'sessionrestore_no_auto_restore',
17:00:04 INFO - 'tabpaint']},
17:00:04 INFO - 'other-e10s_l64': {'tests': ['a11yr',
17:00:04 INFO - 'ts_paint',
17:00:04 INFO - 'tpaint',
17:00:04 INFO - 'sessionrestore',
17:00:04 INFO - 'sessionrestore_no_auto_restore',
17:00:04 INFO - 'tabpaint']},
17:00:04 INFO - 'other-e10s_nol64': {'tests': ['a11yr',
17:00:04 INFO - 'ts_paint',
17:00:04 INFO - 'tpaint',
17:00:04 INFO - 'sessionrestore',
17:00:04 INFO - 'sessionrestore_no_auto_restore',
17:00:04 INFO - 'tabpaint']},
17:00:04 INFO - 'other_l64': {'talos_options': ['--disable-e10s'],
17:00:04 INFO - 'tests': ['a11yr',
17:00:04 INFO - 'ts_paint',
17:00:04 INFO - 'tpaint',
17:00:04 INFO - 'sessionrestore',
17:00:04 INFO - 'sessionrestore_no_auto_restore',
17:00:04 INFO - 'tabpaint']},
17:00:04 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'],
17:00:04 INFO - 'tests': ['a11yr',
17:00:04 INFO - 'ts_paint',
17:00:04 INFO - 'tpaint',
17:00:04 INFO - 'sessionrestore',
17:00:04 INFO - 'sessionrestore_no_auto_restore',
17:00:04 INFO - 'tabpaint']},
17:00:04 INFO - 'perf-reftest': {'tests': ['bloom_basic', 'bloom_basic_ref']},
17:00:04 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic',
17:00:04 INFO - 'bloom_basic_ref']},
17:00:04 INFO - 'svgr': {'talos_options': ['--disable-e10s'],
17:00:04 INFO - 'tests': ['tsvgx',
17:00:04 INFO - 'tsvgr_opacity',
17:00:04 INFO - 'tart',
17:00:04 INFO - 'tscrollx',
17:00:04 INFO - 'cart',
17:00:04 INFO - 'tsvg_static']},
17:00:04 INFO - 'svgr-e10s': {'tests': ['tsvgx',
17:00:04 INFO - 'tsvgr_opacity',
17:00:04 INFO - 'tart',
17:00:04 INFO - 'tscrollx',
17:00:04 INFO - 'cart',
17:00:04 INFO - 'tsvg_static']},
17:00:04 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip',
17:00:04 INFO - 'talos_options': ['--disable-e10s'],
17:00:04 INFO - 'tests': ['tp5o']},
17:00:04 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']},
17:00:04 INFO - 'xperf': {'pagesets_name': 'tp5n.zip',
17:00:04 INFO - 'talos_options': ['--disable-e10s',
17:00:04 INFO - '--xperf_path',
17:00:04 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'],
17:00:04 INFO - 'tests': ['tp5n']},
17:00:04 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip',
17:00:04 INFO - 'talos_options': ['--xperf_path',
17:00:04 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'],
17:00:04 INFO - 'tests': ['tp5n']}},
17:00:04 INFO - 'talos.zip': {'path': '',
17:00:04 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}}
17:00:04 INFO - Running post-action listener: _resource_record_post_action
17:00:04 INFO - [mozharness: 2017-05-06 00:00:04.620229Z] Finished populate-webroot step (success)
17:00:04 INFO - [mozharness: 2017-05-06 00:00:04.620377Z] Running create-virtualenv step.
17:00:04 INFO - Running pre-action listener: _resource_record_pre_action
17:00:04 INFO - Running main action method: create_virtualenv
17:00:04 INFO - Creating virtualenv /builds/slave/test/build/venv
17:00:04 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
17:00:04 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv
17:00:04 INFO - The --no-site-packages flag is deprecated; it is now the default behavior.
17:00:04 INFO - Using real prefix '/tools/python27'
17:00:04 INFO - New python executable in /builds/slave/test/build/venv/bin/python
17:00:05 INFO - Installing distribute.............................................................................................................................................................................................done.
17:00:08 INFO - Installing pip.................done.
17:00:08 INFO - Return code: 0
17:00:08 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version']
17:00:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version
17:00:08 INFO - Reading from file tmpfile_stdout
17:00:08 INFO - Output received:
17:00:08 INFO - pip 1.5.5 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7)
17:00:08 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv
17:00:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
17:00:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
17:00:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub
17:00:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
17:00:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
17:00:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub
17:00:08 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10c56eb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c571768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb4a95d4e30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c34cd30>, '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 0x10c272db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c341be8>, '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.9bysca6FWz/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.Yspqbs65xC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/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
17:00:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build
17:00:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5
17:00:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.9bysca6FWz/Render',
17:00:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared',
17:00:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared',
17:00:08 INFO - 'HOME': '/Users/cltbld',
17:00:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true',
17:00:08 INFO - 'LOGNAME': 'cltbld',
17:00:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
17:00:08 INFO - 'MOZ_NO_REMOTE': '1',
17:00:08 INFO - 'NO_EM_RESTART': '1',
17:00:08 INFO - 'PAGER': '/bin/cat',
17:00:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11',
17:00:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
17:00:08 INFO - 'PWD': '/builds/slave/test',
17:00:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg',
17:00:08 INFO - 'SHELL': '/bin/bash',
17:00:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Yspqbs65xC/Listeners',
17:00:08 INFO - 'TMPDIR': '/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/T/',
17:00:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log',
17:00:08 INFO - 'USER': 'cltbld',
17:00:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no',
17:00:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7',
17:00:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
17:00:08 INFO - 'XPC_FLAGS': '0x0',
17:00:08 INFO - 'XPC_SERVICE_NAME': '0',
17:00:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'}
17:00:08 INFO - Ignoring indexes: https://pypi.python.org/simple/
17:00:08 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
17:00:08 INFO - Cleaning up...
17:00:08 INFO - Return code: 0
17:00:08 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv
17:00:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
17:00:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
17:00:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub
17:00:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
17:00:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
17:00:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub
17:00:08 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10c56eb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c571768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb4a95d4e30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c34cd30>, '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 0x10c272db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c341be8>, '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.9bysca6FWz/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.Yspqbs65xC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/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
17:00:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build
17:00:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1
17:00:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.9bysca6FWz/Render',
17:00:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared',
17:00:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared',
17:00:08 INFO - 'HOME': '/Users/cltbld',
17:00:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true',
17:00:08 INFO - 'LOGNAME': 'cltbld',
17:00:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
17:00:08 INFO - 'MOZ_NO_REMOTE': '1',
17:00:08 INFO - 'NO_EM_RESTART': '1',
17:00:08 INFO - 'PAGER': '/bin/cat',
17:00:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11',
17:00:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
17:00:08 INFO - 'PWD': '/builds/slave/test',
17:00:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg',
17:00:08 INFO - 'SHELL': '/bin/bash',
17:00:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Yspqbs65xC/Listeners',
17:00:08 INFO - 'TMPDIR': '/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/T/',
17:00:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log',
17:00:08 INFO - 'USER': 'cltbld',
17:00:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no',
17:00:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7',
17:00:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
17:00:08 INFO - 'XPC_FLAGS': '0x0',
17:00:08 INFO - 'XPC_SERVICE_NAME': '0',
17:00:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'}
17:00:09 INFO - Ignoring indexes: https://pypi.python.org/simple/
17:00:09 INFO - Downloading/unpacking psutil>=3.1.1
17:00:09 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
17:00:09 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
17:00:09 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
17:00:09 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
17:00:09 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
17:00:09 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
17:00:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil
17:00:11 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build'
17:00:11 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects
...
17:00:11 INFO - Installing collected packages: psutil
17:00:11 INFO - Running setup.py install for psutil
17:00:11 INFO - building 'psutil._psutil_osx' extension
17:00:11 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
17:00:11 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
17:00:12 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
17:00:12 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
17:00:12 INFO - building 'psutil._psutil_posix' extension
17:00:12 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
17:00:12 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration]
17:00:12 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr);
17:00:12 INFO - ^
17:00:12 INFO - 1 warning generated.
17:00:12 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
17:00:12 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build'
17:00:12 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ...
17:00:12 INFO - Successfully installed psutil
17:00:12 INFO - Cleaning up...
17:00:12 INFO - Return code: 0
17:00:12 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv
17:00:12 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
17:00:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
17:00:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub
17:00:12 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
17:00:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
17:00:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub
17:00:12 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 0x10c56eb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c571768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb4a95d4e30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c34cd30>, '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 0x10c272db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c341be8>, '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.9bysca6FWz/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.Yspqbs65xC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/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
17:00:12 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
17:00:12 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
17:00:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.9bysca6FWz/Render',
17:00:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared',
17:00:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared',
17:00:12 INFO - 'HOME': '/Users/cltbld',
17:00:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true',
17:00:12 INFO - 'LOGNAME': 'cltbld',
17:00:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
17:00:12 INFO - 'MOZ_NO_REMOTE': '1',
17:00:12 INFO - 'NO_EM_RESTART': '1',
17:00:12 INFO - 'PAGER': '/bin/cat',
17:00:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11',
17:00:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
17:00:12 INFO - 'PWD': '/builds/slave/test',
17:00:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg',
17:00:12 INFO - 'SHELL': '/bin/bash',
17:00:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Yspqbs65xC/Listeners',
17:00:12 INFO - 'TMPDIR': '/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/T/',
17:00:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log',
17:00:12 INFO - 'USER': 'cltbld',
17:00:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no',
17:00:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7',
17:00:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
17:00:12 INFO - 'XPC_FLAGS': '0x0',
17:00:12 INFO - 'XPC_SERVICE_NAME': '0',
17:00:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'}
17:00:12 INFO - Ignoring indexes: https://pypi.python.org/simple/
17:00:12 INFO - Downloading/unpacking mozsystemmonitor==0.3
17:00: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
17:00: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
17:00: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
17:00: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
17:00: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
17:00: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
17:00:14 INFO - Downloading mozsystemmonitor-0.3.tar.gz
17:00:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor
17:00:14 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3)
17:00:14 INFO - Installing collected packages: mozsystemmonitor
17:00:14 INFO - Running setup.py install for mozsystemmonitor
17:00:14 INFO - Successfully installed mozsystemmonitor
17:00:14 INFO - Cleaning up...
17:00:14 INFO - Return code: 0
17:00:14 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv
17:00:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
17:00:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
17:00:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub
17:00:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
17:00:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
17:00:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub
17:00:14 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==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10c56eb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c571768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb4a95d4e30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c34cd30>, '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 0x10c272db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c341be8>, '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.9bysca6FWz/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.Yspqbs65xC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/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
17:00:14 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==2.5.1'] in /builds/slave/test/build
17:00:14 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==2.5.1
17:00:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.9bysca6FWz/Render',
17:00:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared',
17:00:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared',
17:00:14 INFO - 'HOME': '/Users/cltbld',
17:00:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true',
17:00:14 INFO - 'LOGNAME': 'cltbld',
17:00:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
17:00:14 INFO - 'MOZ_NO_REMOTE': '1',
17:00:14 INFO - 'NO_EM_RESTART': '1',
17:00:14 INFO - 'PAGER': '/bin/cat',
17:00:14 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11',
17:00:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
17:00:14 INFO - 'PWD': '/builds/slave/test',
17:00:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg',
17:00:14 INFO - 'SHELL': '/bin/bash',
17:00:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Yspqbs65xC/Listeners',
17:00:14 INFO - 'TMPDIR': '/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/T/',
17:00:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log',
17:00:14 INFO - 'USER': 'cltbld',
17:00:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no',
17:00:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7',
17:00:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
17:00:14 INFO - 'XPC_FLAGS': '0x0',
17:00:14 INFO - 'XPC_SERVICE_NAME': '0',
17:00:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'}
17:00:14 INFO - Ignoring indexes: https://pypi.python.org/simple/
17:00:14 INFO - Downloading/unpacking jsonschema==2.5.1
17:00:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available
17:00:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available
17:00:14 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available
17:00:14 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available
17:00:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available
17:00:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available
17:00:15 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl
17:00:16 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1)
17:00:16 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
17:00:16 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
17:00:16 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
17:00:16 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
17:00:16 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
17:00:16 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
17:00:16 INFO - Downloading functools32-3.2.3-2.tar.gz
17:00:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32
17:00:16 INFO - warning: no files found matching '*.txt'
17:00:16 INFO - no previously-included directories found matching 'build'
17:00:16 INFO - no previously-included directories found matching 'dist'
17:00:16 INFO - no previously-included directories found matching '.git*'
17:00:16 INFO - Installing collected packages: jsonschema, functools32
17:00:16 INFO - Running setup.py install for functools32
17:00:16 INFO - warning: no files found matching '*.txt'
17:00:16 INFO - no previously-included directories found matching 'build'
17:00:16 INFO - no previously-included directories found matching 'dist'
17:00:16 INFO - no previously-included directories found matching '.git*'
17:00:16 INFO - Successfully installed jsonschema functools32
17:00:16 INFO - Cleaning up...
17:00:16 INFO - Return code: 0
17:00:16 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv
17:00:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
17:00:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
17:00:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub
17:00:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
17:00:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
17:00:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub
17:00:17 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', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10c56eb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c571768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb4a95d4e30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c34cd30>, '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 0x10c272db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c341be8>, '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.9bysca6FWz/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.Yspqbs65xC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/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
17:00:17 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', 'functools32==3.2.3-2'] in /builds/slave/test/build
17:00:17 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 functools32==3.2.3-2
17:00:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.9bysca6FWz/Render',
17:00:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared',
17:00:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared',
17:00:17 INFO - 'HOME': '/Users/cltbld',
17:00:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true',
17:00:17 INFO - 'LOGNAME': 'cltbld',
17:00:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
17:00:17 INFO - 'MOZ_NO_REMOTE': '1',
17:00:17 INFO - 'NO_EM_RESTART': '1',
17:00:17 INFO - 'PAGER': '/bin/cat',
17:00:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11',
17:00:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
17:00:17 INFO - 'PWD': '/builds/slave/test',
17:00:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg',
17:00:17 INFO - 'SHELL': '/bin/bash',
17:00:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Yspqbs65xC/Listeners',
17:00:17 INFO - 'TMPDIR': '/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/T/',
17:00:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log',
17:00:17 INFO - 'USER': 'cltbld',
17:00:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no',
17:00:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7',
17:00:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
17:00:17 INFO - 'XPC_FLAGS': '0x0',
17:00:17 INFO - 'XPC_SERVICE_NAME': '0',
17:00:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'}
17:00:17 INFO - Ignoring indexes: https://pypi.python.org/simple/
17:00:17 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages
17:00:17 INFO - Cleaning up...
17:00:17 INFO - Return code: 0
17:00:17 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv
17:00:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
17:00:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
17:00:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub
17:00:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
17:00:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
17:00:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub
17:00:18 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 0x10c56eb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c571768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb4a95d4e30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c34cd30>, '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 0x10c272db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c341be8>, '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.9bysca6FWz/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.Yspqbs65xC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/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
17:00:18 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
17:00:18 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
17:00:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.9bysca6FWz/Render',
17:00:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared',
17:00:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared',
17:00:18 INFO - 'HOME': '/Users/cltbld',
17:00:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true',
17:00:18 INFO - 'LOGNAME': 'cltbld',
17:00:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
17:00:18 INFO - 'MOZ_NO_REMOTE': '1',
17:00:18 INFO - 'NO_EM_RESTART': '1',
17:00:18 INFO - 'PAGER': '/bin/cat',
17:00:18 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11',
17:00:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
17:00:18 INFO - 'PWD': '/builds/slave/test',
17:00:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg',
17:00:18 INFO - 'SHELL': '/bin/bash',
17:00:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Yspqbs65xC/Listeners',
17:00:18 INFO - 'TMPDIR': '/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/T/',
17:00:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log',
17:00:18 INFO - 'USER': 'cltbld',
17:00:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no',
17:00:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7',
17:00:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
17:00:18 INFO - 'XPC_FLAGS': '0x0',
17:00:18 INFO - 'XPC_SERVICE_NAME': '0',
17:00:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'}
17:00:18 INFO - Ignoring indexes: https://pypi.python.org/simple/
17:00:18 INFO - Downloading/unpacking blobuploader==1.2.4
17:00: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
17:00: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
17:00: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
17:00: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
17:00: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
17:00: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
17:00:20 INFO - Downloading blobuploader-1.2.4.tar.gz
17:00:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader
17:00:20 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4)
17:00:20 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
17:00:20 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
17:00:20 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
17:00:20 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
17:00:20 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
17:00:20 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
17:00:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests
17:00:20 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4)
17:00:20 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
17:00:20 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
17:00:20 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
17:00:20 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
17:00:20 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
17:00:20 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
17:00:21 INFO - Downloading docopt-0.6.1.tar.gz
17:00:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt
17:00:21 INFO - Installing collected packages: blobuploader, requests, docopt
17:00:21 INFO - Running setup.py install for blobuploader
17:00:21 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775
17:00:21 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775
17:00:21 INFO - Running setup.py install for requests
17:00:21 INFO - Running setup.py install for docopt
17:00:22 INFO - Successfully installed blobuploader requests docopt
17:00:22 INFO - Cleaning up...
17:00:22 INFO - Return code: 0
17:00:22 INFO - Installing None into virtualenv /builds/slave/test/build/venv
17:00:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
17:00:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
17:00:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub
17:00:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
17:00:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
17:00:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub
17:00:22 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 0x10c56eb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c571768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb4a95d4e30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c34cd30>, '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 0x10c272db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c341be8>, '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.9bysca6FWz/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.Yspqbs65xC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/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
17:00:22 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
17:00:22 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
17:00:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.9bysca6FWz/Render',
17:00:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared',
17:00:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared',
17:00:22 INFO - 'HOME': '/Users/cltbld',
17:00:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true',
17:00:22 INFO - 'LOGNAME': 'cltbld',
17:00:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
17:00:22 INFO - 'MOZ_NO_REMOTE': '1',
17:00:22 INFO - 'NO_EM_RESTART': '1',
17:00:22 INFO - 'PAGER': '/bin/cat',
17:00:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11',
17:00:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
17:00:22 INFO - 'PWD': '/builds/slave/test',
17:00:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg',
17:00:22 INFO - 'SHELL': '/bin/bash',
17:00:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Yspqbs65xC/Listeners',
17:00:22 INFO - 'TMPDIR': '/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/T/',
17:00:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log',
17:00:22 INFO - 'USER': 'cltbld',
17:00:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no',
17:00:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7',
17:00:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
17:00:22 INFO - 'XPC_FLAGS': '0x0',
17:00:22 INFO - 'XPC_SERVICE_NAME': '0',
17:00:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'}
17:00:22 INFO - Ignoring indexes: https://pypi.python.org/simple/
17:00:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser
17:00:22 INFO - Running setup.py (path:/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/T/pip-fB11xH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser
17:00:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash
17:00:22 INFO - Running setup.py (path:/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/T/pip-9EJ9ot-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash
17:00:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug
17:00:22 INFO - Running setup.py (path:/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/T/pip-4wgMv9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug
17:00:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice
17:00:22 INFO - Running setup.py (path:/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/T/pip-fnL7sn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice
17:00:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile
17:00:22 INFO - Running setup.py (path:/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/T/pip-2sxgXE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile
17:00:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd
17:00:22 INFO - Running setup.py (path:/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/T/pip-67_dYJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd
17:00:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo
17:00:22 INFO - Running setup.py (path:/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/T/pip-Jv2jUQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo
17:00:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall
17:00:23 INFO - Running setup.py (path:/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/T/pip-J8qniX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall
17:00:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak
17:00:23 INFO - Running setup.py (path:/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/T/pip-_H82T8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak
17:00:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog
17:00:23 INFO - Running setup.py (path:/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/T/pip-fLW4wJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog
17:00:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork
17:00:23 INFO - Running setup.py (path:/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/T/pip-gSXdwq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork
17:00:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess
17:00:23 INFO - Running setup.py (path:/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/T/pip-9MgsZA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess
17:00:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile
17:00:23 INFO - Running setup.py (path:/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/T/pip-TpVQm_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile
17:00:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner
17:00:23 INFO - Running setup.py (path:/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/T/pip-y1qgRw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner
17:00:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot
17:00:23 INFO - Running setup.py (path:/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/T/pip-mSWRIk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot
17:00:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest
17:00:23 INFO - Running setup.py (path:/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/T/pip-ddE7zp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest
17:00:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion
17:00:24 INFO - Running setup.py (path:/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/T/pip-A5WIUN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion
17:00:24 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion
17:00:24 INFO - Running setup.py install for manifestparser
17:00:24 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin
17:00:24 INFO - Running setup.py install for mozcrash
17:00:24 INFO - Running setup.py install for mozdebug
17:00:24 INFO - Running setup.py install for mozdevice
17:00:24 INFO - Installing dm script to /builds/slave/test/build/venv/bin
17:00:24 INFO - Running setup.py install for mozfile
17:00:24 INFO - Running setup.py install for mozhttpd
17:00:24 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin
17:00:24 INFO - Running setup.py install for mozinfo
17:00:25 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin
17:00:25 INFO - Running setup.py install for mozInstall
17:00:25 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin
17:00:25 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin
17:00:25 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin
17:00:25 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin
17:00:25 INFO - Running setup.py install for mozleak
17:00:25 INFO - Running setup.py install for mozlog
17:00:25 INFO - Installing structlog script to /builds/slave/test/build/venv/bin
17:00:25 INFO - Running setup.py install for moznetwork
17:00:25 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin
17:00:25 INFO - Running setup.py install for mozprocess
17:00:25 INFO - Running setup.py install for mozprofile
17:00:26 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin
17:00:26 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin
17:00:26 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin
17:00:26 INFO - Running setup.py install for mozrunner
17:00:26 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin
17:00:26 INFO - Running setup.py install for mozscreenshot
17:00:26 INFO - Running setup.py install for moztest
17:00:26 INFO - Running setup.py install for mozversion
17:00:26 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin
17:00:26 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion
17:00:26 INFO - Cleaning up...
17:00:26 INFO - Return code: 0
17:00:26 INFO - Installing None into virtualenv /builds/slave/test/build/venv
17:00:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
17:00:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
17:00:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub
17:00:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
17:00:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
17:00:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub
17:00:26 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 0x10c56eb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c571768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb4a95d4e30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c34cd30>, '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 0x10c272db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c341be8>, '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.9bysca6FWz/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.Yspqbs65xC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/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
17:00:26 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
17:00:26 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
17:00:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.9bysca6FWz/Render',
17:00:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared',
17:00:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared',
17:00:26 INFO - 'HOME': '/Users/cltbld',
17:00:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true',
17:00:26 INFO - 'LOGNAME': 'cltbld',
17:00:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
17:00:26 INFO - 'MOZ_NO_REMOTE': '1',
17:00:26 INFO - 'NO_EM_RESTART': '1',
17:00:26 INFO - 'PAGER': '/bin/cat',
17:00:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11',
17:00:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
17:00:26 INFO - 'PWD': '/builds/slave/test',
17:00:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg',
17:00:26 INFO - 'SHELL': '/bin/bash',
17:00:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Yspqbs65xC/Listeners',
17:00:26 INFO - 'TMPDIR': '/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/T/',
17:00:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log',
17:00:26 INFO - 'USER': 'cltbld',
17:00:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no',
17:00:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7',
17:00:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
17:00:26 INFO - 'XPC_FLAGS': '0x0',
17:00:26 INFO - 'XPC_SERVICE_NAME': '0',
17:00:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'}
17:00:26 INFO - Ignoring indexes: https://pypi.python.org/simple/
17:00:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser
17:00:26 INFO - Running setup.py (path:/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/T/pip-Cj81g9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser
17:00:27 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))
17:00:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash
17:00:27 INFO - Running setup.py (path:/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/T/pip-O_JnMX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash
17:00:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 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))
17:00:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug
17:00:27 INFO - Running setup.py (path:/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/T/pip-F0J6BO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug
17:00:27 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))
17:00:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice
17:00:27 INFO - Running setup.py (path:/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/T/pip-sBaBfK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice
17:00:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.50 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))
17:00:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile
17:00:27 INFO - Running setup.py (path:/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/T/pip-ygOQP4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile
17:00:27 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))
17:00:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd
17:00:27 INFO - Running setup.py (path:/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/T/pip-0a8aLg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd
17:00:27 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))
17:00:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo
17:00:27 INFO - Running setup.py (path:/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/T/pip-Zba_7m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo
17:00:27 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))
17:00:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall
17:00:27 INFO - Running setup.py (path:/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/T/pip-LY6qeW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall
17:00:27 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))
17:00:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak
17:00:27 INFO - Running setup.py (path:/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/T/pip-Ew0mTA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak
17:00:27 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))
17:00:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog
17:00:27 INFO - Running setup.py (path:/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/T/pip-k_m2hj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog
17:00:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 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))
17:00:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork
17:00:28 INFO - Running setup.py (path:/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/T/pip-k_oUQG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork
17:00:28 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))
17:00:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess
17:00:28 INFO - Running setup.py (path:/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/T/pip-VGhe6S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess
17:00:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 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))
17:00:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile
17:00:28 INFO - Running setup.py (path:/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/T/pip-8rgFgh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile
17:00:28 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))
17:00:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner
17:00:28 INFO - Running setup.py (path:/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/T/pip-wT33Tw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner
17:00:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 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))
17:00:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot
17:00:28 INFO - Running setup.py (path:/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/T/pip-ZsV5U8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot
17:00:28 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))
17:00:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest
17:00:28 INFO - Running setup.py (path:/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/T/pip-VRoXSs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest
17:00:28 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 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))
17:00:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion
17:00:28 INFO - Running setup.py (path:/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/T/pip-oqd3VG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion
17:00:28 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))
17:00:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2))
17:00:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2))
17:00:28 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))
17:00:28 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.50->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4))
17:00:28 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.50->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4))
17:00:28 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10))
17:00:28 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
17:00:28 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
17:00:28 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
17:00:28 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
17:00:28 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
17:00:28 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
17:00:30 INFO - Downloading blessings-1.6.tar.gz
17:00:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings
17:00:30 INFO - Installing collected packages: blessings
17:00:30 INFO - Running setup.py install for blessings
17:00:30 INFO - Successfully installed blessings
17:00:30 INFO - Cleaning up...
17:00:30 INFO - Return code: 0
17:00:30 INFO - Done creating virtualenv /builds/slave/test/build/venv.
17:00:30 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze']
17:00:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze
17:00:30 INFO - Reading from file tmpfile_stdout
17:00:30 INFO - Current package versions:
17:00:30 INFO - blessings == 1.6
17:00:30 INFO - blobuploader == 1.2.4
17:00:30 INFO - docopt == 0.6.1
17:00:30 INFO - functools32 == 3.2.3-2
17:00:30 INFO - jsonschema == 2.5.1
17:00:30 INFO - manifestparser == 1.1
17:00:30 INFO - mozInstall == 1.12
17:00:30 INFO - mozcrash == 1.0
17:00:30 INFO - mozdebug == 0.1
17:00:30 INFO - mozdevice == 0.50
17:00:30 INFO - mozfile == 1.2
17:00:30 INFO - mozhttpd == 0.7
17:00:30 INFO - mozinfo == 0.9
17:00:30 INFO - mozleak == 0.1
17:00:30 INFO - mozlog == 3.4
17:00:30 INFO - moznetwork == 0.27
17:00:30 INFO - mozprocess == 0.25
17:00:30 INFO - mozprofile == 0.28
17:00:30 INFO - mozrunner == 6.13
17:00:30 INFO - mozscreenshot == 0.1
17:00:30 INFO - mozsystemmonitor == 0.3
17:00:30 INFO - moztest == 0.8
17:00:30 INFO - mozversion == 1.4
17:00:30 INFO - psutil == 3.1.1
17:00:30 INFO - requests == 1.2.3
17:00:30 INFO - wsgiref == 0.1.2
17:00:30 INFO - Installing None into virtualenv /builds/slave/test/build/venv
17:00:30 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
17:00:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
17:00:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub
17:00:30 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
17:00:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
17:00:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub
17:00:30 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 0x10c56eb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c571768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb4a95d4e30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c34cd30>, '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 0x10c272db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c341be8>, '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.9bysca6FWz/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.Yspqbs65xC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/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
17:00:30 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
17:00:30 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
17:00:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.9bysca6FWz/Render',
17:00:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared',
17:00:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared',
17:00:30 INFO - 'HOME': '/Users/cltbld',
17:00:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true',
17:00:30 INFO - 'LOGNAME': 'cltbld',
17:00:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
17:00:30 INFO - 'MOZ_NO_REMOTE': '1',
17:00:30 INFO - 'NO_EM_RESTART': '1',
17:00:30 INFO - 'PAGER': '/bin/cat',
17:00:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11',
17:00:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
17:00:30 INFO - 'PWD': '/builds/slave/test',
17:00:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg',
17:00:30 INFO - 'SHELL': '/bin/bash',
17:00:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Yspqbs65xC/Listeners',
17:00:30 INFO - 'TMPDIR': '/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/T/',
17:00:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log',
17:00:30 INFO - 'USER': 'cltbld',
17:00:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no',
17:00:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7',
17:00:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
17:00:30 INFO - 'XPC_FLAGS': '0x0',
17:00:30 INFO - 'XPC_SERVICE_NAME': '0',
17:00:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'}
17:00:30 INFO - Ignoring indexes: https://pypi.python.org/simple/
17:00:30 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))
17:00:30 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))
17:00:30 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))
17:00:30 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))
17:00:30 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))
17:00:30 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))
17:00:30 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))
17:00:30 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))
17:00:30 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))
17:00:30 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))
17:00:30 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))
17:00:30 INFO - Cleaning up...
17:00:30 INFO - Return code: 0
17:00:30 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv
17:00:30 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
17:00:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
17:00:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub
17:00:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
17:00:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
17:00:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub
17:00:31 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 0x10c56eb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c571768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb4a95d4e30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c34cd30>, '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 0x10c272db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c341be8>, '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.9bysca6FWz/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.Yspqbs65xC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/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
17:00:31 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
17:00:31 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
17:00:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.9bysca6FWz/Render',
17:00:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared',
17:00:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared',
17:00:31 INFO - 'HOME': '/Users/cltbld',
17:00:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true',
17:00:31 INFO - 'LOGNAME': 'cltbld',
17:00:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
17:00:31 INFO - 'MOZ_NO_REMOTE': '1',
17:00:31 INFO - 'NO_EM_RESTART': '1',
17:00:31 INFO - 'PAGER': '/bin/cat',
17:00:31 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11',
17:00:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
17:00:31 INFO - 'PWD': '/builds/slave/test',
17:00:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg',
17:00:31 INFO - 'SHELL': '/bin/bash',
17:00:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Yspqbs65xC/Listeners',
17:00:31 INFO - 'TMPDIR': '/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/T/',
17:00:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log',
17:00:31 INFO - 'USER': 'cltbld',
17:00:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no',
17:00:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7',
17:00:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
17:00:31 INFO - 'XPC_FLAGS': '0x0',
17:00:31 INFO - 'XPC_SERVICE_NAME': '0',
17:00:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'}
17:00:31 INFO - Ignoring indexes: https://pypi.python.org/simple/
17:00:31 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages
17:00:31 INFO - Cleaning up...
17:00:31 INFO - Return code: 0
17:00:31 INFO - Running post-action listener: _resource_record_post_action
17:00:31 INFO - Running post-action listener: _start_resource_monitoring
17:00:31 INFO - Starting resource monitoring.
17:00:31 INFO - [mozharness: 2017-05-06 00:00:31.292654Z] Finished create-virtualenv step (success)
17:00:31 INFO - [mozharness: 2017-05-06 00:00:31.293612Z] Running install step.
17:00:31 INFO - Running pre-action listener: _resource_record_pre_action
17:00:31 INFO - Running main action method: install
17:00:31 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze']
17:00:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze
17:00:31 INFO - Reading from file tmpfile_stdout
17:00:31 INFO - Detecting whether we're running mozinstall >=1.0...
17:00:31 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h']
17:00:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h
17:00:31 INFO - Reading from file tmpfile_stdout
17:00:31 INFO - Output received:
17:00:31 INFO - Usage: mozinstall [options] installer
17:00:31 INFO - Options:
17:00:31 INFO - -h, --help show this help message and exit
17:00:31 INFO - -d DEST, --destination=DEST
17:00:31 INFO - Directory to install application into. [default:
17:00:31 INFO - "/builds/slave/test"]
17:00:31 INFO - --app=APP Application being installed. [default: firefox]
17:00:31 INFO - mkdir: /builds/slave/test/build/application
17:00:31 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application']
17:00:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application
17:00:51 INFO - Reading from file tmpfile_stdout
17:00:51 INFO - Output received:
17:00:51 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox
17:00:51 INFO - Running post-action listener: _resource_record_post_action
17:00:51 INFO - [mozharness: 2017-05-06 00:00:51.623276Z] Finished install step (success)
17:00:51 INFO - [mozharness: 2017-05-06 00:00:51.623413Z] Running run-tests step.
17:00:51 INFO - Running pre-action listener: _resource_record_pre_action
17:00:51 INFO - Running main action method: run_tests
17:00:51 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'
17:00:51 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build
17:00:51 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType"
17:00:51 INFO - 2017-05-05 17:00:51.638 screenresolution[1620:7310] starting screenresolution argv=screenresolution get
17:00:51 INFO - 2017-05-05 17:00:51.640 screenresolution[1620:7310] Display 0: 1600x1200x32@60
17:00:51 INFO - 2017-05-05 17:00:51.646 screenresolution[1621:7313] starting screenresolution argv=screenresolution list
17:00:51 INFO - Available Modes on Display 0
17:00:51 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75
17:00:51 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60
17:00:51 INFO - 800x600x32@75 800x600x32@60 640x480x32@75
17:00:51 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60
17:00:51 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays:
17:00:51 INFO - Intel Iris:
17:00:51 INFO - Chipset Model: Intel Iris
17:00:51 INFO - Type: GPU
17:00:51 INFO - Bus: Built-In
17:00:51 INFO - VRAM (Dynamic, Max): 1536 MB
17:00:51 INFO - Vendor: Intel (0x8086)
17:00:51 INFO - Device ID: 0x0a2e
17:00:51 INFO - Revision ID: 0x0009
17:00:51 INFO - Displays:
17:00:51 INFO - Display:
17:00:51 INFO - Resolution: 1600 x 1200 @ 60 Hz
17:00:51 INFO - Pixel Depth: 32-Bit Color (ARGB8888)
17:00:51 INFO - Display Serial Number: 1600x1200 60
17:00:51 INFO - Main Display: Yes
17:00:51 INFO - Mirror: Off
17:00:51 INFO - Online: Yes
17:00:51 INFO - Rotation: Supported
17:00:51 INFO - Return code: 0
17:00:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version']
17:00:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version
17:00:51 INFO - Python 2.7.3
17:00:51 INFO - Return code: 0
17:00:51 INFO - grabbing minidump binary from tooltool
17:00:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
17:00:51 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 0x10c34cd30>, '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 0x10c272db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c341be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1
17:00:51 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
17:00:51 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
17:00:51 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
17:00:51 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache
17:00:52 INFO - Return code: 0
17:00:52 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755
17:00:52 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir
17:00:52 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir
17:00:52 INFO - ENV: RUST_BACKTRACE is now 1
17:00:52 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk
17:00:52 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir
17:00:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Tet-IZzERMCWsG0xEhzITw/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0321', '--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
17:00:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/Tet-IZzERMCWsG0xEhzITw/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0321 --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
17:00:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.9bysca6FWz/Render',
17:00:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared',
17:00:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared',
17:00:52 INFO - 'HOME': '/Users/cltbld',
17:00:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true',
17:00:52 INFO - 'LOGNAME': 'cltbld',
17:00:52 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir',
17:00:52 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk',
17:00:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
17:00:52 INFO - 'MOZ_NO_REMOTE': '1',
17:00:52 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir',
17:00:52 INFO - 'NO_EM_RESTART': '1',
17:00:52 INFO - 'PAGER': '/bin/cat',
17:00:52 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11',
17:00:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
17:00:52 INFO - 'PWD': '/builds/slave/test',
17:00:52 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos',
17:00:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg',
17:00:52 INFO - 'RUST_BACKTRACE': '1',
17:00:52 INFO - 'SHELL': '/bin/bash',
17:00:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Yspqbs65xC/Listeners',
17:00:52 INFO - 'TMPDIR': '/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/T/',
17:00:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log',
17:00:52 INFO - 'USER': 'cltbld',
17:00:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no',
17:00:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7',
17:00:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
17:00:52 INFO - 'XPC_FLAGS': '0x0',
17:00:52 INFO - 'XPC_SERVICE_NAME': '0',
17:00:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'}
17:00:52 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Tet-IZzERMCWsG0xEhzITw/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0321', '--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
17:00:52 INFO - mozversion application_buildid: 20170505161344
17:00:52 INFO - mozversion application_changeset: 37a5b7f6f101df2eb292b1b6baaf1540c9920e20
17:00:52 INFO - mozversion application_display_name: Nightly
17:00:52 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384}
17:00:52 INFO - mozversion application_name: Firefox
17:00:52 INFO - mozversion application_remotingname: firefox
17:00:52 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central
17:00:52 INFO - mozversion application_vendor: Mozilla
17:00:52 INFO - mozversion application_version: 55.0a1
17:00:52 INFO - mozversion platform_buildid: 20170505161344
17:00:52 INFO - mozversion platform_changeset: 37a5b7f6f101df2eb292b1b6baaf1540c9920e20
17:00:52 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central
17:00:52 INFO - mozversion platform_version: 55.0a1
17:00:52 INFO - using testdate: 1494028852
17:00:52 INFO - actual date: 1494028852
17:00:52 INFO - starting webserver on 'localhost:49234'
17:00:52 INFO - SUITE-START | Running 2 tests
17:00:52 INFO - TEST-START | dromaeo_css
17:00:52 INFO - operating with platform_type : mac_
17:00:52 INFO - Initialising browser for dromaeo_css test...
17:00:52 INFO - TEST-INFO | started process 1631 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/f_/784x9wv55zl7jxv09s004psc00000w/T/tmp5RHsmO/profile http://localhost:49234/getInfo.html)
17:00:54 INFO - PID 1631 | __metrics Screen width/height:1600/1200
17:00:54 INFO - PID 1631 | colorDepth:24
17:00:54 INFO - PID 1631 | Browser inner width/height: 1024/691
17:00:54 INFO - PID 1631 | __metrics
17:00:55 INFO - PID 1631 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 673: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows"
17:00:55 INFO - PID 1631 | JavaScript error: resource:///modules/BrowserUsageTelemetry.jsm, line 334: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver]
17:00:55 INFO - TEST-INFO | 1631: exit 0
17:00:55 INFO - Browser initialized.
17:00:55 INFO - Running cycle 1/1 for dromaeo_css test...
17:00:55 INFO - TEST-INFO | started process 1633 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/f_/784x9wv55zl7jxv09s004psc00000w/T/tmp5RHsmO/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1)
17:01:08 INFO - PID 1633 | RSS: Main: 239194112
17:01:08 INFO - PID 1633 |
17:04:13 INFO - PID 1633 | Cycle 1(1): loaded http://localhost:49234/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49234/tests/dromaeo/cssquery-ext.html)
17:04:15 INFO - PID 1633 | RSS: Main: 381886464
17:04:15 INFO - PID 1633 |
17:07:17 INFO - PID 1633 | Cycle 1(1): loaded http://localhost:49234/tests/dromaeo/cssquery-ext.html (next: http://localhost:49234/tests/dromaeo/cssquery-jquery.html)
17:07:18 INFO - PID 1633 | RSS: Main: 364462080
17:07:18 INFO - PID 1633 |
17:10:20 INFO - PID 1633 | Cycle 1(1): loaded http://localhost:49234/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49234/tests/dromaeo/cssquery-mootools.html)
17:10:21 INFO - PID 1633 | RSS: Main: 327933952
17:10:21 INFO - PID 1633 |
17:13:23 INFO - PID 1633 | Cycle 1(1): loaded http://localhost:49234/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49234/tests/dromaeo/cssquery-prototype.html)
17:13:24 INFO - PID 1633 | RSS: Main: 328589312
17:13:24 INFO - PID 1633 |
17:16:27 INFO - PID 1633 | Cycle 1(1): loaded http://localhost:49234/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49234/tests/dromaeo/cssquery-yui.html)
17:16:27 INFO - PID 1633 | RSS: Main: 325541888
17:16:27 INFO - PID 1633 |
17:19:30 INFO - PID 1633 | Cycle 1(1): loaded http://localhost:49234/tests/dromaeo/cssquery-yui.html (next: http://localhost:49234/tests/dromaeo/cssquery-dojo.html)
17:19:30 INFO - PID 1633 | RSS: Main: 368103424
17:19:30 INFO - PID 1633 |
17:19:30 INFO - PID 1633 | __start_tp_report
17:19:30 INFO - PID 1633 | _x_x_mozilla_page_load
17:19:30 INFO - PID 1633 | _x_x_mozilla_page_load_details
17:19:30 INFO - PID 1633 | |i|pagename|runs|
17:19:30 INFO - PID 1633 | |0;dojo.html;3381.628020917697;3513.6837684608395;3569.6251893551166;3577.3739595570787;3594.5506811648543;5745.109508026252;6094.969525152379;6267.749290028399;6278.654673992928;6312.274088479815;5775.682337471438;6224.719887605057;6294.874102517958;6298.9921612542;6306.337834527366;5263.394709608406;5323.707196104213;5413.485718856702;5439.755211015514;5474.206240095204;4620.798592365975;5541.168824676291;5543.168524721284;5549.861253468675;5561.416051314614;5349.277847490617;5415.6479828811;5426.891462170774;5472.863178420532;5508.1462373332;5348.812791552277;5364.651297665679;5385.703786291752;5390.407055223922;5413.756380962866;5281.339832520935;5326.307580014612;5347.438518955492;5358.544523715489;5360.142377219665;6151.354107818659;6248.343923888017;6318.74725010999;6319.399657032574;6319.8736025279695;6628.536002479828;6632.568883022588;6632.6683665817;6636.800895973129;6660.2340730816095;6256.374362563753;6643.103181070542;6749.7300107995625;6763.797086087426;6764.729410823561;6223.564350495421;6265.530085243625;6295.96852015737;6299.590526615755;6343.524235682296;6722.260551339349;6743.123393958754;6759.966200168968;6859.108315918898;6932.272111428329;6531.804045878631;6542.21493420783;6545.836354091186;6545.869082618321;6558.344165583499;6694.263631000586;6694.564853284521;6696.229933557605;6697.196336439659;6706.932930670729;6399.648019358887;6457.2574153971955;6601.339866013456;6621.9337806621315;6627.37039981201;6602.669866506655;6673.733050678015;6705.161854768154;6705.530612857151;6716.1604799400075;5299.4435584263865;5324.041672498987;5326.92009619856;5343.171808369709;5377.892442151135;4592.265237561974;4649.325847752057;4714.3871296731895;4728.59806916416;4775.283707443842;6217.813465596039;6280.591761535486;6302.527310451735;6352.968235158794;6357.682115894187;6734.6632668367365;6760.932390676128;6776.525643204928;6785.762498312634;6798.660066996729;5342.492463215988;6289.685515724195;6334.778282760173;6364.331745166691;6364.42720155179;5761.942380576141;5818.8254352369495;5888.4700376966275;5929;5935;5866.472017518444;6780.186377634715;6923.238443771081;6998.16022077354;7026.086608740831;6517.608943463408;6594.637294948824;6611.206655201407;6736.225334086643;6828.487863410165;6823.863522729617;6829.141664753067;6905.274946130584;6907.13660792401;6936.445084393337;6534.477241820738;6544.770933017321;6553.246376666744;6560.606363618198;6564.80305590833;6543.247526534319;6614.140161778938;6681.064650948774;6689.364510371507;6740.12378390806;6486.4486518646445;6503.349665033459;6519.250286217146;6521;6541.672916354068;4984.501549844987;6441.259255185526;6453.838654033687;6457.192850893638;6520.836979075561;5517.813670060957;6241.282252541016;6294.402031806971;6358.395952384516;6371.267304260069;6500.609963402211;6527.738890444519;6528.053432252202;6536.901946470712;6553.836154096186;5270.578353731769;6341.55609107358;6343.873122537616;6351.618902865843;6352.047192920914
17:19:30 INFO - PID 1633 | |1;ext.html;737.3842841227574;769.3768047881217;802.7792357101798;1106.363840791545;1126.003486914081;25715.871420642892;35492.75775347863;35714.285714285696;35819.64180358199;35831;37346.25307493848;37742.43386349207;37755.62244377555;37770.8111459443;37804.43293350595;5887.0580707086665;6008.879822403549;8124.228198321165;8262.628181731836;8281;8043.356531477468;8179.631916563769;8194.713185038525;8206.425550211488;8210.630521626541;8226.136255693156;8283.917160828405;8293.377996650244;8335.166483351646;8341.332693384546;8213.466124701905;8218.753437396888;8267.503949763033;8290.41967062306;8296.58517074147;8073.6366863491285;8173.386995975326;8207.95896020522;8227.465214761021;8228.382871284617;35301.646983530096;35440.936771896886;35467.4679879802;35470.11324716903;35627.4655880162;3573.3925232710503;3593.5508061492314;3596.334678084537;3597.4244120940784;3598.5501812273465;29247.56128658072;29401.823927042893;29435;29452.410951781058;29504.26239344012;3072.5698402223707;3141.5444760509827;3158.6367567729776;3169.2869104451543;3179.82510961897;235840;293071;293645.0635493639;296008;296502;47113.5288647116;47172.292415613796;47173.5282647176;47205.29192062122;47266.5273347269;50123.24815127776;50201.99596008132;50336.49663503318;50386.24420633693;50445.74777126091;13992.230427326595;14080.577582672537;14093.436262549383;14104.576862694135;14119.576412707635;9853.950730246446;9871.851922221173;9885.25860560461;11488.94255528717;11531.827022594667;11549.88450115505;11569.363684997406;11635.825462618068;11661.35862527569;11685.298882067103;4365.628921541641;5137.17805151174;5171.922421163686;5197.480251974772;5199.700074981255;1008.7276435362411;1010.3988127064385;1012.4026824173626;1044.3368461027303;1045.7281106912258;17752.91123544374;21267.893660531598;22550.32349029532;23690.28929132129;23715.40711482227;1105.789899919013;1106.5407855739777;1137.4256000719583;1138.6014894786758;1156.1329003247565;11533.480993355319;12027.759444811054;12198.14612977083;12235.632931012084;12258.08064395174;302880;303742;305174;305478;306124;49937.750311248936;50054.74972625114;50126.997460050596;50137.74931125394;50210.748946255764;1463.7072585482733;1514.3109885002298;1553.2854886751995;1555.4400415850284;1569.3016607609504;20758.06588703542;22957.311280661608;22960.885195573916;23082.307530774102;23230.767692323538;36677.63322366849;36703.89888303355;36738.8163059183;36804.89585312445;36979.26041479102;266612;267597;268435;268442;269147;24076.51846963086;27895;31374;32276.83861580677;32397.676023239466;34351.4847277286;34407;39195.41206881842;39960;42037.78981105075;28494.287642809097;29133.417331652825;29645.25886852846;29725;30437.543436848024;25784.484310314063;28231.43537129287;28716.42567148687;29219.85390073036;29344.11967640974;33864.322713546084;34210.65789342174;35074.82462587671;35199.64800351964;35253.942381728586;28587.28531786722;28927.69825357906;29308.267293316985;29558.078724882813;30448.695513045473;22638.773612263667;22921.541569168858;23224.76775232226;23380.532389351778;23407.41481462977
17:19:30 INFO - PID 1633 | |2;jquery.html;372.9533808273966;426.9709998902645;440.709131972898;440.76419115773064;454.12354156478006;16069.51791446257;20853.165873365066;21494.785052149495;21977.890110549466;22238.99924503397;21082;21676.89161554194;21963.450913727123;22186.556268874654;22617.54764904701;4784.899473121167;6041.909371359433;6235.688215589225;6250.749970001195;6272.090546870701;5999.370066143058;6255.061740738903;6281.842953926142;6282.7172777224905;6285.245770507523;6032.245969253843;6108.205933228674;6194.00895856663;6196.1944947157035;6250.374962503759;5342.893142137155;5546.69493177875;6002.439365764889;6077.088436734503;6162.983107787232;5422.349318081816;5439.700816455093;5513.283273174502;5513.338399392059;5514.420985796473;20741.481462963395;22127.668084978737;26610;26720.866395667897;26807;2181.596404665142;2254.1434254983164;2254.5490901819703;2256.4697296135396;2260.2089268755967;17576;17634.91182544079;17779.73330400032;18094.63810723778;18297.359592414203;2080.5318803269297;2101.8423618228694;2154.1598776477185;2155.805977462036;2156.590247852903;125009;138301.925471118;142925;142989;143492;11373;12065;12486.50053997848;12546.749065018648;12591.748165036648;13782.517611883535;15258.694826103416;15261.160636165117;15596;16317.673646527002;8787.956060219658;8891;9008.144226298491;9104.544772761468;9111.498867562215;8509.959523857018;8530.914690853137;8536.829263414696;8717.259032982141;8717.912820871838;5863.29640443141;7409.222031686703;7486.737964171228;7596.962015189999;7633.679349482993;1871.4666320098706;2176.738791345049;2192.6820611011312;2197.7582465928735;2209.304069218191;942.4863449420083;974.2692980264801;975.5609975510906;1014.7970405918845;1033.6382266206917;20980.685289720666;21607.02768375427;21619.243326483498;21639;21673.916304184728;1005.7938122684832;1020.5764607687875;1027.3476342522404;1038.2557303729657;1040.8126537223372;10584.364938103738;10640.989106034915;10649.627263045757;10946;10948.34309941406;530170;538125;538902;552705;554745;112271.43864280626;118513.11151663659;130303;133342;134018;1189.6728399689982;1204.28345134643;1214.860291066521;1231.815227715832;1234.3889774561649;18309.633807324044;18364.265429382674;18425.907870460564;18443.631127377645;18503.907480462512;54156.458435415145;56486.43513564812;58494.707526462094;58831.70584147052;59033.40966590279;75116;76051.61974190094;78129.60935195515;78618.6069069651;79154.81267780873;7196.352328290479;8620.568971551524;8869.246114080117;8908.10918908104;8947.552622368725;13631.182129072287;13669.384877680726;13672.316384180951;14050.367733452224;14148.575542733735;11663.300201987908;11722.118076446826;11795.52817887275;11939.940300298444;12119.151659383759;10291.691249262534;11059.28114672576;11140.220184587;11396.088312935111;11415.543378264776;10614.522346494581;11297.548098075986;11823.172377933462;12094.153409261267;12295.446704898122;8597.914020859711;8786.516741579275;8789.868151977598;8865.468071915706;9117.77205569866;10025.649102281384;11834.940825295818;11924.821127682919;11997.220180688228;12044.698882528008
17:19:30 INFO - PID 1633 | |3;mootools.html;1422.4523558430005;1452.2520901735602;1482.4737218287507;1500.579837645459;1503.0906301687478;11194.776104477916;11702.707432314186;11715.707107322321;11729.003034742058;11732.413379331032;11361;11584.13119016073;11608.767824643503;11610.883891161086;11617.941910290438;2494.4761600064007;2543.707473640527;2544.427503811646;2544.9618255726177;2550.885210165547;2485.4159272570932;2529.0895277700015;2532.189699296226;2533.5946248600226;2536.5687833068423;2461.433870209853;2501.9749802502015;2526.734692857251;2530.266222795387;2537.1880998080624;2229;2426.7694569015916;2497.8501289922574;2506;2514.0823599386276;2439.4755127647463;2457.741937096615;2459.938501537458;2483.8261321707487;2487.8756062197;11388.202825802196;11421.086313094933;11539.134564907581;11546.076313894868;11562.826557601642;6285.968570157165;6530.6408147551865;6536.836579085513;6561.212654481445;6573.375529324707;3825.6365645263654;4111.835526578963;4150.481189851269;4161.687873409476;4188.727732697395;1225.6261840138754;1238.851337839456;1300.478440224939;1303.758804621148;1311.757324894888;15238.923805381013;15620.984635998737;15643.843561564467;16138.354465821352;16241.512754617263;5292.153255479105;5430.8099216527235;5558.86102847424;5575.135853942646;5593.74828132735;11658.475368608264;11659.941700291443;11664.708382290512;11667.299962002307;11686.94156529212;5738.08190689496;5933.258342707161;6530.379613936669;6535.281119076898;6553.737850485927;6008.789692360747;6059.8485037873525;6229.688515574203;6236.407541283571;6236.438720515175;4290.420793192941;4374.343848422762;4392.692511524163;4440.089781594766;4456.7548784817145;1083.3554526597193;1253.3106791264768;1261.9495220191272;1273.2933222061845;1277.0294576122214;742.3726950726627;767.1944458318743;797.908240552344;812.3947658993969;816.5019338203691;6548.377904099103;6793.184817821893;6956.95645653156;6964.338387853146;7023.051887995158;942.9009953954874;954.9856752148723;964.392432767352;966.8114717629994;988.2193067476757;3608.6030536640947;3830.482884810571;3872.3029854625875;3954.387070004154;3970.3250447424007;14664.780028299583;14908.47820326305;15089.849101509064;15097.471588494569;15119.924400377928;14459.27703614815;14979.775303370458;15021.098734075991;15023.02350347224;15051.849481505264;7536.962315188389;8480.194381533744;8689.782755431164;8701.17338852808;8752.387332886765;6108.328083910677;6438.291787903233;7029.26192749754;7066.57600543969;7076.824673442694;12449.751004980031;12581.245125292511;12655.430505627084;13673.794893076412;13678.179309241477;13145;13463.932680336535;13729.382177802223;13761.862381376059;13931.30343482842;9078.45529268246;9218.01812610292;9243.676471323472;9385.812283754423;9418.293627977791;10611.09805666527;10682.679519614114;10687.25189236746;10688.89311106879;10704.411257380918;8153.143919888326;8154.9806274215725;8899.510526921082;8995.55022248898;9001.234895033995;8777.473351598925;8857.911420885966;8880.822383552422;8943.821123577362;9072.546372681207;7860.646270917708;7926.643301051351;7975.601219939096;8091.716789912325;8113.878291825746;5924.437178468039;6012.398760123952;6047.213862197887;6053.303870055;6064.696765161636;6043.12374705674;6102.725377358119;6119.816405507842;6137.60105593135;6214.627122372672
17:19:30 INFO - PID 1633 | |4;prototype.html;397.61828644501276;398.11220977220796;401.05951543630187;402.38033428520083;405.40000798817766;5037.848864534065;5443.401225865152;6065.514758819295;6155.907661385083;6187.412195841393;5899.380565040673;5962.463378295953;5990.311114221866;6051.788187413442;6094.878102437949;3814.5231846019246;4596.655250856183;4597.954020459786;4602.585767280944;4619.683390233792;4623.052274283764;4624;4631.212693842038;4631.745254011028;4633.953660463403;4416.955830441702;4417.249067658489;4548.567886050836;4556.2254416749065;4580.175568397687;3874.786886721229;4479.3281007848755;4544.909101817978;4560.680752347321;4565.589096981271;3698.685611723007;3928.8213535939103;4048.352263637804;4058.939115913264;4069.6337329640473;5560.999020176367;5646.2377579027125;5860.6850294134;5904.645721256738;5945.197398351211;5211.791528338862;5215.765290561933;5237.79048838046;5261.053010458116;5268.631195816294;5291.3385826771655;5887.646741195542;6067.514598832127;6397.296297407282;6423.454006409461;3596.496490491333;4238.682098842568;4287.142571485715;4291.334843099294;4305.677074219446;6545.9018114728315;6682.331766823376;6707.228668703064;6769.763058292985;6878.27778083299;3518.3842827505086;4255.468066491689;4296.419983302277;4326.394304797331;4336.978315108404;4388.253996820485;4409.5149533551285;4412.84555040572;4414.580614841585;4464.17412778637;5030.245463180552;5107.284980102789;5182.948170518322;5191.792328306901;5217.660852044605;5201.557867581222;6341.850721822436;6419.807405777834;6426.228852537725;6449.516286278548;1481.7629179331254;1499.130504307499;1500.3398504657916;1504.5561559340022;1505.4956589542408;809.7489778168787;836.3685417509741;911.5715613661702;986.4180133721139;1004.1924535835612;5033.7734801934;5201.635885487979;6128.601640893327;6155.384461553898;6168.352323006109;5579.888402231933;6438.130852334969;6648.368405001517;6666;6671.366220209073;6023.789167379121;6129.387061293835;6143.508519318444;6151.784687536005;6182.412670796267;2988.4770165221435;3173.841307934594;3286.274705779437;3415.8121303328558;3461.44616861301;6227.532935029891;6234.501239900797;6520.18497687789;6723.226828914738;6885.414739747077;5599.116455025665;5608.663480191201;5689.800856969986;6835.418989385858;6873.381395674413;5705.0635061807225;5824.6704265597355;6824.488163387766;6889.965550172217;6908.481863860231;6352.936470635419;6609.735610575523;6629.834254143685;6650.434713049445;6655;6381.106645069601;6494.935050649433;6504.674766261763;6538.738450461929;6588.8023359299295;6795.422389096982;6799;6811.523193376416;6822.658867056528;6874.175098988154;4318.784060796885;4341.305391137403;4341.565843415633;4349.151915376598;4350.21696094706;5655.586103474131;6075.27096748393;6380.055457020846;6407;6729.495287853529;6448.387403196689;6461.547691661539;6484.546081774419;6488.448481879093;6498.415142637185;5042.4705405931845;5557.972210139085;6486.2216534016225;6502.60984340941;6504.252011018793;5866.266716660418;6348.650824204713;6359.332270111757;6359.8092057238355;6365.331640177715;6313.715882785377;6468.094466774562;6511.511636627367;6532.41208291256;6560.573562718408;6032.276126864629;6294.716737746904;6325.304216536269;6325.564657606763;6361.332060133619
17:19:30 INFO - PID 1633 | |5;yui.html;818.930390916772;839.6431516605443;845.4800297816845;847.6736456464263;863.0420233540768;13514.391852366642;14146.858531414682;16074.276657550408;16532.66934661306;16575.834241657594;11805.586804461846;12882.098253122285;17283.74074388885;17335.82664173358;17367.47897563072;980.0640388429068;986.2553771902204;992.1566668331932;992.8212921674095;994.8955359687237;1008.0574662690408;1009.878814542256;1010.9090181883629;1011.1152741351318;1011.8988101189896;686.2588404523103;704.8907419350008;1000.2797985450501;1022.2486472442748;1031.7730099378125;1021.0555236406369;1021.1065317846884;1024.2318261304022;1026.2303272545591;1036.5387402605843;762.4662736084763;763.7823220382164;766.1151370167465;767.1178145133085;773.234497847133;15912.283947222402;16288.755668664979;16430.260638271186;16685.749713754303;16988.405405810736;16346.509604711877;16399.016059036498;16629.584260393465;16818.24317905697;16846.915765421218;13644.727105457934;13814.723705525934;13935.512257070954;14024.018318717694;14147.29263536819;3409.829508524564;3417.419038763416;3421.7091547218515;3425.0067480430644;3428.6914177724125;118406;119620.40189798996;122012;122676;122731.77268227293;10461.581536738446;11907.34509601963;11940.940295298642;12110.515579376726;12125.818112728317;20259.696104558443;20324.69512957307;20928.47678808042;20946.371608851758;20988.685169722467;7229.855402892018;7367.81580460482;7370.631468426557;7392.075990501187;7395.778126656209;3583.5699716033723;3617.0957260684827;3618.5476815398074;3624.1664417184197;3665.9633403666157;4065.735727177724;4130.173965206971;4187;4195.119025004722;4195.286801243796;843.9831203375898;867.7526904832218;878.1481962496368;880.9075047120089;883.4566741454088;629.2040568680623;661.3518751623436;676.7631329034798;681.1655721740926;685.9657017149223;10874.619388321371;11221.60724374643;11505.769884602261;11932.343721095423;12058.517659293706;637.0953246390045;650.9121268628769;654.6268626882631;663.5587334422652;681.9897701534481;5333.413324534298;5434.592405569598;5512.3660779010925;5521.116621340647;5580.91628625571;106278.46860765801;108075;108342;108504.4574777121;108836;98181;99211.5433651156;99428.00571994332;100222.49888750656;101405.47891781482;55057.724711376984;71445.6427717858;74762.62618686871;76165.61917190379;76611;10949.678433401297;11591.88408115908;12027.699307517032;12146.635600931986;12402.75194496123;42031;42249.78875105605;42317.78841105775;43046.56953430425;43062.78468607762;53450.73274633602;54266.728666357994;54770.17844732253;55180.89638207293;55656.443435565125;15847.52457426279;15961.920190399438;16083.35666573324;16428.425005125242;16443.58891027734;17406.73889891678;17895.10524473797;18141.818581814012;18244;18423.723644145615;15972.28124734413;16125.596860078593;16609.252583633523;16720.498385048468;16721.331146753993;16021.839781602035;16050.67898642044;16400.507984760476;16532.917335413247;16646.084465354037;16430.17849107516;16716.331346745996;16774;17150.485485435456;17155.399561015303;11683.415829208336;12021.939890300493;12086.15396922207;12202.81695774582;12209.267443953391;11447.370394628375;12780.552680656132;13390.330483475593;13790.069830098373;14029.228392438514
17:19:30 INFO - PID 1633 | __end_tp_report
17:19:30 INFO - PID 1633 | __start_cc_report
17:19:30 INFO - PID 1633 | _x_x_mozilla_cycle_collect,1472
17:19:30 INFO - PID 1633 | __end_cc_report
17:19:30 INFO - PID 1633 | __startTimestamp1494029970477__endTimestamp
17:19:30 INFO - PID 1633 |
17:19:30 INFO - PID 1633 | ------- Summary: start -------
17:19:30 INFO - PID 1633 | Number of tests: 6
17:19:30 INFO - PID 1633 |
17:19:30 INFO - PID 1633 | [#0] dojo.html Cycles:165 Average:6118.61 Median:6352.97 stddev:742.04 (11.7%) stddev-sans-first:712.57
17:19:30 INFO - PID 1633 | Values: 3381.6 3513.7 3569.6 3577.4 3594.6 5745.1 6095.0 6267.7 6278.7 6312.3 5775.7 6224.7 6294.9 6299.0 6306.3 5263.4 5323.7 5413.5 5439.8 5474.2 4620.8 5541.2 5543.2 5549.9 5561.4 5349.3 5415.6 5426.9 5472.9 5508.1 5348.8 5364.7 5385.7 5390.4 5413.8 5281.3 5326.3 5347.4 5358.5 5360.1 6151.4 6248.3 6318.7 6319.4 6319.9 6628.5 6632.6 6632.7 6636.8 6660.2 6256.4 6643.1 6749.7 6763.8 6764.7 6223.6 6265.5 6296.0 6299.6 6343.5 6722.3 6743.1 6760.0 6859.1 6932.3 6531.8 6542.2 6545.8 6545.9 6558.3 6694.3 6694.6 6696.2 6697.2 6706.9 6399.6 6457.3 6601.3 6621.9 6627.4 6602.7 6673.7 6705.2 6705.5 6716.2 5299.4 5324.0 5326.9 5343.2 5377.9 4592.3 4649.3 4714.4 4728.6 4775.3 6217.8 6280.6 6302.5 6353.0 6357.7 6734.7 6760.9 6776.5 6785.8 6798.7 5342.5 6289.7 6334.8 6364.3 6364.4 5761.9 5818.8 5888.5 5929.0 5935.0 5866.5 6780.2 6923.2 6998.2 7026.1 6517.6 6594.6 6611.2 6736.2 6828.5 6823.9 6829.1 6905.3 6907.1 6936.4 6534.5 6544.8 6553.2 6560.6 6564.8 6543.2 6614.1 6681.1 6689.4 6740.1 6486.4 6503.3 6519.3 6521.0 6541.7 4984.5 6441.3 6453.8 6457.2 6520.8 5517.8 6241.3 6294.4 6358.4 6371.3 6500.6 6527.7 6528.1 6536.9 6553.8 5270.6 6341.6 6343.9 6351.6 6352.0
17:19:30 INFO - PID 1633 |
17:19:30 INFO - PID 1633 | [#1] ext.html Cycles:180 Average:42726.01 Median:3163.96 stddev:75024.00 (2371.2%) stddev-sans-first:75168.23
17:19:30 INFO - PID 1633 | Values: 737.4 769.4 802.8 1106.4 1126.0 25715.9 35492.8 35714.3 35819.6 35831.0 37346.3 37742.4 37755.6 37770.8 37804.4 5887.1 6008.9 8124.2 8262.6 8281.0 8043.4 8179.6 8194.7 8206.4 8210.6 8226.1 8283.9 8293.4 8335.2 8341.3 8213.5 8218.8 8267.5 8290.4 8296.6 8073.6 8173.4 8208.0 8227.5 8228.4 35301.6 35440.9 35467.5 35470.1 35627.5 3573.4 3593.6 3596.3 3597.4 3598.6 29247.6 29401.8 29435.0 29452.4 29504.3 3072.6 3141.5 3158.6 3169.3 3179.8 235840.0 293071.0 293645.1 296008.0 296502.0 47113.5 47172.3 47173.5 47205.3 47266.5 50123.2 50202.0 50336.5 50386.2 50445.7 13992.2 14080.6 14093.4 14104.6 14119.6 9854.0 9871.9 9885.3 11488.9 11531.8 11549.9 11569.4 11635.8 11661.4 11685.3 4365.6 5137.2 5171.9 5197.5 5199.7 1008.7 1010.4 1012.4 1044.3 1045.7 17752.9 21267.9 22550.3 23690.3 23715.4 1105.8 1106.5 1137.4 1138.6 1156.1 11533.5 12027.8 12198.1 12235.6 12258.1 302880.0 303742.0 305174.0 305478.0 306124.0 49937.8 50054.7 50127.0 50137.7 50210.7 1463.7 1514.3 1553.3 1555.4 1569.3 20758.1 22957.3 22960.9 23082.3 23230.8 36677.6 36703.9 36738.8 36804.9 36979.3 266612.0 267597.0 268435.0 268442.0 269147.0 24076.5 27895.0 31374.0 32276.8 32397.7 34351.5 34407.0 39195.4 39960.0 42037.8 28494.3 29133.4 29645.3 29725.0 30437.5 25784.5 28231.4 28716.4 29219.9 29344.1 33864.3 34210.7 35074.8 35199.6 35253.9 28587.3 28927.7 29308.3 29558.1 30448.7 22638.8 22921.5 23224.8 23380.5 23407.4
17:19:30 INFO - PID 1633 |
17:19:30 INFO - PID 1633 | [#2] jquery.html Cycles:180 Average:34633.98 Median:2187.14 stddev:91618.34 (4189.0%) stddev-sans-first:91839.24
17:19:30 INFO - PID 1633 | Values: 373.0 427.0 440.7 440.8 454.1 16069.5 20853.2 21494.8 21977.9 22239.0 21082.0 21676.9 21963.5 22186.6 22617.5 4784.9 6041.9 6235.7 6250.7 6272.1 5999.4 6255.1 6281.8 6282.7 6285.2 6032.2 6108.2 6194.0 6196.2 6250.4 5342.9 5546.7 6002.4 6077.1 6163.0 5422.3 5439.7 5513.3 5513.3 5514.4 20741.5 22127.7 26610.0 26720.9 26807.0 2181.6 2254.1 2254.5 2256.5 2260.2 17576.0 17634.9 17779.7 18094.6 18297.4 2080.5 2101.8 2154.2 2155.8 2156.6 125009.0 138301.9 142925.0 142989.0 143492.0 11373.0 12065.0 12486.5 12546.7 12591.7 13782.5 15258.7 15261.2 15596.0 16317.7 8788.0 8891.0 9008.1 9104.5 9111.5 8510.0 8530.9 8536.8 8717.3 8717.9 5863.3 7409.2 7486.7 7597.0 7633.7 1871.5 2176.7 2192.7 2197.8 2209.3 942.5 974.3 975.6 1014.8 1033.6 20980.7 21607.0 21619.2 21639.0 21673.9 1005.8 1020.6 1027.3 1038.3 1040.8 10584.4 10641.0 10649.6 10946.0 10948.3 530170.0 538125.0 538902.0 552705.0 554745.0 112271.4 118513.1 130303.0 133342.0 134018.0 1189.7 1204.3 1214.9 1231.8 1234.4 18309.6 18364.3 18425.9 18443.6 18503.9 54156.5 56486.4 58494.7 58831.7 59033.4 75116.0 76051.6 78129.6 78618.6 79154.8 7196.4 8620.6 8869.2 8908.1 8947.6 13631.2 13669.4 13672.3 14050.4 14148.6 11663.3 11722.1 11795.5 11939.9 12119.2 10291.7 11059.3 11140.2 11396.1 11415.5 10614.5 11297.5 11823.2 12094.2 12295.4 8597.9 8786.5 8789.9 8865.5 9117.8 10025.6 11834.9 11924.8 11997.2 12044.7
17:19:30 INFO - PID 1633 |
17:19:30 INFO - PID 1633 | [#3] mootools.html Cycles:180 Average:7033.11 Median:3717.12 stddev:4423.09 (119.0%) stddev-sans-first:4415.40
17:19:30 INFO - PID 1633 | Values: 1422.5 1452.3 1482.5 1500.6 1503.1 11194.8 11702.7 11715.7 11729.0 11732.4 11361.0 11584.1 11608.8 11610.9 11617.9 2494.5 2543.7 2544.4 2545.0 2550.9 2485.4 2529.1 2532.2 2533.6 2536.6 2461.4 2502.0 2526.7 2530.3 2537.2 2229.0 2426.8 2497.9 2506.0 2514.1 2439.5 2457.7 2459.9 2483.8 2487.9 11388.2 11421.1 11539.1 11546.1 11562.8 6286.0 6530.6 6536.8 6561.2 6573.4 3825.6 4111.8 4150.5 4161.7 4188.7 1225.6 1238.9 1300.5 1303.8 1311.8 15238.9 15621.0 15643.8 16138.4 16241.5 5292.2 5430.8 5558.9 5575.1 5593.7 11658.5 11659.9 11664.7 11667.3 11686.9 5738.1 5933.3 6530.4 6535.3 6553.7 6008.8 6059.8 6229.7 6236.4 6236.4 4290.4 4374.3 4392.7 4440.1 4456.8 1083.4 1253.3 1261.9 1273.3 1277.0 742.4 767.2 797.9 812.4 816.5 6548.4 6793.2 6957.0 6964.3 7023.1 942.9 955.0 964.4 966.8 988.2 3608.6 3830.5 3872.3 3954.4 3970.3 14664.8 14908.5 15089.8 15097.5 15119.9 14459.3 14979.8 15021.1 15023.0 15051.8 7537.0 8480.2 8689.8 8701.2 8752.4 6108.3 6438.3 7029.3 7066.6 7076.8 12449.8 12581.2 12655.4 13673.8 13678.2 13145.0 13463.9 13729.4 13761.9 13931.3 9078.5 9218.0 9243.7 9385.8 9418.3 10611.1 10682.7 10687.3 10688.9 10704.4 8153.1 8155.0 8899.5 8995.6 9001.2 8777.5 8857.9 8880.8 8943.8 9072.5 7860.6 7926.6 7975.6 8091.7 8113.9 5924.4 6012.4 6047.2 6053.3 6064.7 6043.1 6102.7 6119.8 6137.6 6214.6
17:19:30 INFO - PID 1633 |
17:19:30 INFO - PID 1633 | [#4] prototype.html Cycles:180 Average:5191.60 Median:5893.51 stddev:1622.50 (27.5%) stddev-sans-first:1586.65
17:19:30 INFO - PID 1633 | Values: 397.6 398.1 401.1 402.4 405.4 5037.8 5443.4 6065.5 6155.9 6187.4 5899.4 5962.5 5990.3 6051.8 6094.9 3814.5 4596.7 4598.0 4602.6 4619.7 4623.1 4624.0 4631.2 4631.7 4634.0 4417.0 4417.2 4548.6 4556.2 4580.2 3874.8 4479.3 4544.9 4560.7 4565.6 3698.7 3928.8 4048.4 4058.9 4069.6 5561.0 5646.2 5860.7 5904.6 5945.2 5211.8 5215.8 5237.8 5261.1 5268.6 5291.3 5887.6 6067.5 6397.3 6423.5 3596.5 4238.7 4287.1 4291.3 4305.7 6545.9 6682.3 6707.2 6769.8 6878.3 3518.4 4255.5 4296.4 4326.4 4337.0 4388.3 4409.5 4412.8 4414.6 4464.2 5030.2 5107.3 5182.9 5191.8 5217.7 5201.6 6341.9 6419.8 6426.2 6449.5 1481.8 1499.1 1500.3 1504.6 1505.5 809.7 836.4 911.6 986.4 1004.2 5033.8 5201.6 6128.6 6155.4 6168.4 5579.9 6438.1 6648.4 6666.0 6671.4 6023.8 6129.4 6143.5 6151.8 6182.4 2988.5 3173.8 3286.3 3415.8 3461.4 6227.5 6234.5 6520.2 6723.2 6885.4 5599.1 5608.7 5689.8 6835.4 6873.4 5705.1 5824.7 6824.5 6890.0 6908.5 6352.9 6609.7 6629.8 6650.4 6655.0 6381.1 6494.9 6504.7 6538.7 6588.8 6795.4 6799.0 6811.5 6822.7 6874.2 4318.8 4341.3 4341.6 4349.2 4350.2 5655.6 6075.3 6380.1 6407.0 6729.5 6448.4 6461.5 6484.5 6488.4 6498.4 5042.5 5558.0 6486.2 6502.6 6504.3 5866.3 6348.7 6359.3 6359.8 6365.3 6313.7 6468.1 6511.5 6532.4 6560.6 6032.3 6294.7 6325.3 6325.6 6361.3
17:19:30 INFO - PID 1633 |
17:19:30 INFO - PID 1633 | [#5] yui.html Cycles:180 Average:21413.38 Median:17152.94 stddev:30616.07 (178.5%) stddev-sans-first:30662.90
17:19:30 INFO - PID 1633 | Values: 818.9 839.6 845.5 847.7 863.0 13514.4 14146.9 16074.3 16532.7 16575.8 11805.6 12882.1 17283.7 17335.8 17367.5 980.1 986.3 992.2 992.8 994.9 1008.1 1009.9 1010.9 1011.1 1011.9 686.3 704.9 1000.3 1022.2 1031.8 1021.1 1021.1 1024.2 1026.2 1036.5 762.5 763.8 766.1 767.1 773.2 15912.3 16288.8 16430.3 16685.7 16988.4 16346.5 16399.0 16629.6 16818.2 16846.9 13644.7 13814.7 13935.5 14024.0 14147.3 3409.8 3417.4 3421.7 3425.0 3428.7 118406.0 119620.4 122012.0 122676.0 122731.8 10461.6 11907.3 11940.9 12110.5 12125.8 20259.7 20324.7 20928.5 20946.4 20988.7 7229.9 7367.8 7370.6 7392.1 7395.8 3583.6 3617.1 3618.5 3624.2 3666.0 4065.7 4130.2 4187.0 4195.1 4195.3 844.0 867.8 878.1 880.9 883.5 629.2 661.4 676.8 681.2 686.0 10874.6 11221.6 11505.8 11932.3 12058.5 637.1 650.9 654.6 663.6 682.0 5333.4 5434.6 5512.4 5521.1 5580.9 106278.5 108075.0 108342.0 108504.5 108836.0 98181.0 99211.5 99428.0 100222.5 101405.5 55057.7 71445.6 74762.6 76165.6 76611.0 10949.7 11591.9 12027.7 12146.6 12402.8 42031.0 42249.8 42317.8 43046.6 43062.8 53450.7 54266.7 54770.2 55180.9 55656.4 15847.5 15961.9 16083.4 16428.4 16443.6 17406.7 17895.1 18141.8 18244.0 18423.7 15972.3 16125.6 16609.3 16720.5 16721.3 16021.8 16050.7 16400.5 16532.9 16646.1 16430.2 16716.3 16774.0 17150.5 17155.4 11683.4 12021.9 12086.2 12202.8 12209.3 11447.4 12780.6 13390.3 13790.1 14029.2
17:19:30 INFO - PID 1633 | -------- Summary: end --------
17:19:30 INFO - PID 1633 |
17:19:31 INFO - TEST-INFO | 1633: exit 0
17:19:31 INFO - TEST-OK | dromaeo_css | took 1118856ms
17:19:31 INFO - TEST-START | kraken
17:19:31 INFO - operating with platform_type : mac_
17:19:31 INFO - Initialising browser for kraken test...
17:19:31 INFO - TEST-INFO | started process 1644 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/f_/784x9wv55zl7jxv09s004psc00000w/T/tmpKyTP4h/profile http://localhost:49234/getInfo.html)
17:19:33 INFO - PID 1644 | __metrics Screen width/height:1600/1200
17:19:33 INFO - PID 1644 | colorDepth:24
17:19:33 INFO - PID 1644 | Browser inner width/height: 1024/691
17:19:33 INFO - PID 1644 | __metrics
17:19:33 INFO - PID 1644 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 673: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows"
17:19:33 INFO - PID 1644 | JavaScript error: resource:///modules/BrowserUsageTelemetry.jsm, line 334: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver]
17:19:34 INFO - TEST-INFO | 1644: exit 0
17:19:34 INFO - Browser initialized.
17:19:34 INFO - Running cycle 1/1 for kraken test...
17:19:34 INFO - TEST-INFO | started process 1645 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/f_/784x9wv55zl7jxv09s004psc00000w/T/tmpKyTP4h/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1)
17:19:47 INFO - PID 1645 | RSS: Main: 240234496
17:19:47 INFO - PID 1645 |
17:19:49 INFO - PID 1645 | Cycle 1(1): loaded http://localhost:49234/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49234/tests/kraken/driver.html?testName=audio-beat-detection)
17:19:50 INFO - PID 1645 | RSS: Main: 308248576
17:19:50 INFO - PID 1645 |
17:19:53 INFO - PID 1645 | Cycle 1(1): loaded http://localhost:49234/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49234/tests/kraken/driver.html?testName=audio-dft)
17:19:54 INFO - PID 1645 | RSS: Main: 290197504
17:19:54 INFO - PID 1645 |
17:19:57 INFO - PID 1645 | Cycle 1(1): loaded http://localhost:49234/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49234/tests/kraken/driver.html?testName=audio-fft)
17:19:58 INFO - PID 1645 | RSS: Main: 381939712
17:19:58 INFO - PID 1645 |
17:20:00 INFO - PID 1645 | Cycle 1(1): loaded http://localhost:49234/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49234/tests/kraken/driver.html?testName=audio-oscillator)
17:20:00 INFO - PID 1645 | RSS: Main: 285028352
17:20:00 INFO - PID 1645 |
17:20:02 INFO - PID 1645 | Cycle 1(1): loaded http://localhost:49234/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49234/tests/kraken/driver.html?testName=imaging-gaussian-blur)
17:20:03 INFO - PID 1645 | RSS: Main: 288608256
17:20:03 INFO - PID 1645 |
17:20:06 INFO - PID 1645 | Cycle 1(1): loaded http://localhost:49234/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49234/tests/kraken/driver.html?testName=imaging-darkroom)
17:20:06 INFO - PID 1645 | RSS: Main: 351752192
17:20:06 INFO - PID 1645 |
17:20:09 INFO - PID 1645 | Cycle 1(1): loaded http://localhost:49234/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49234/tests/kraken/driver.html?testName=imaging-desaturate)
17:20:10 INFO - PID 1645 | RSS: Main: 367099904
17:20:10 INFO - PID 1645 |
17:20:13 INFO - PID 1645 | Cycle 1(1): loaded http://localhost:49234/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49234/tests/kraken/driver.html?testName=json-parse-financial)
17:20:13 INFO - PID 1645 | RSS: Main: 371908608
17:20:13 INFO - PID 1645 |
17:20:15 INFO - PID 1645 | Cycle 1(1): loaded http://localhost:49234/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49234/tests/kraken/driver.html?testName=json-stringify-tinderbox)
17:20:16 INFO - PID 1645 | RSS: Main: 310738944
17:20:16 INFO - PID 1645 |
17:20:17 INFO - PID 1645 | Cycle 1(1): loaded http://localhost:49234/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49234/tests/kraken/driver.html?testName=stanford-crypto-aes)
17:20:18 INFO - PID 1645 | RSS: Main: 331259904
17:20:18 INFO - PID 1645 |
17:20:20 INFO - PID 1645 | Cycle 1(1): loaded http://localhost:49234/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49234/tests/kraken/driver.html?testName=stanford-crypto-ccm)
17:20:21 INFO - PID 1645 | RSS: Main: 314503168
17:20:21 INFO - PID 1645 |
17:20:24 INFO - PID 1645 | Cycle 1(1): loaded http://localhost:49234/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49234/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2)
17:20:24 INFO - PID 1645 | RSS: Main: 333996032
17:20:24 INFO - PID 1645 |
17:20:28 INFO - PID 1645 | Cycle 1(1): loaded http://localhost:49234/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49234/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative)
17:20:28 INFO - PID 1645 | RSS: Main: 344621056
17:20:28 INFO - PID 1645 |
17:20:30 INFO - PID 1645 | Cycle 1(1): loaded http://localhost:49234/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49234/tests/kraken/driver.html?testName=ai-astar)
17:20:30 INFO - PID 1645 | RSS: Main: 387248128
17:20:30 INFO - PID 1645 |
17:20:30 INFO - PID 1645 | __start_tp_report
17:20:30 INFO - PID 1645 | _x_x_mozilla_page_load
17:20:30 INFO - PID 1645 | _x_x_mozilla_page_load_details
17:20:30 INFO - PID 1645 | |i|pagename|runs|
17:20:30 INFO - PID 1645 | |0;ai-astar;111;112;110;112;112;98;139;106;149;163
17:20:30 INFO - PID 1645 | |1;audio-beat-detection;140;103;107;148;108;134;131;108;132;107
17:20:30 INFO - PID 1645 | |2;audio-dft;185;199;257;228;186;184;181;184;176;185
17:20:30 INFO - PID 1645 | |3;audio-fft;81;59;59;86;59;58;60;60;80;72
17:20:30 INFO - PID 1645 | |4;audio-oscillator;86;71;84;69;68;68;88;69;67;88
17:20:30 INFO - PID 1645 | |5;imaging-gaussian-blur;90;90;89;90;90;133;91;92;91;89
17:20:30 INFO - PID 1645 | |6;imaging-darkroom;97;96;96;96;97;96;98;96;98;142
17:20:30 INFO - PID 1645 | |7;imaging-desaturate;97;128;87;85;86;85;86;85;83;85
17:20:30 INFO - PID 1645 | |8;json-parse-financial;47;48;47;70;68;70;69;60;48;48
17:20:30 INFO - PID 1645 | |9;json-stringify-tinderbox;52;61;54;59;62;62;57;57;56;57
17:20:30 INFO - PID 1645 | |10;stanford-crypto-aes;83;83;82;80;83;80;83;81;82;81
17:20:30 INFO - PID 1645 | |11;stanford-crypto-ccm;137;135;133;145;150;137;137;133;181;149
17:20:30 INFO - PID 1645 | |12;stanford-crypto-pbkdf2;179;181;183;210;225;180;182;184;183;178
17:20:30 INFO - PID 1645 | |13;stanford-crypto-sha256-iterative;68;67;68;64;67;67;67;66;67;66
17:20:30 INFO - PID 1645 | __end_tp_report
17:20:30 INFO - PID 1645 | __start_cc_report
17:20:30 INFO - PID 1645 | _x_x_mozilla_cycle_collect,2102
17:20:30 INFO - PID 1645 | __end_cc_report
17:20:30 INFO - PID 1645 | __startTimestamp1494030030640__endTimestamp
17:20:30 INFO - PID 1645 |
17:20:30 INFO - PID 1645 | ------- Summary: start -------
17:20:30 INFO - PID 1645 | Number of tests: 14
17:20:30 INFO - PID 1645 |
17:20:30 INFO - PID 1645 | [#0] ai-astar Cycles:10 Average:121.20 Median:125.50 stddev:21.32 (17.0%) stddev-sans-first:22.29
17:20:30 INFO - PID 1645 | Values: 111.0 112.0 110.0 112.0 112.0 98.0 139.0 106.0 149.0 163.0
17:20:30 INFO - PID 1645 |
17:20:30 INFO - PID 1645 | [#1] audio-beat-detection Cycles:10 Average:121.80 Median:131.50 stddev:16.76 (12.7%) stddev-sans-first:16.43
17:20:30 INFO - PID 1645 | Values: 140.0 103.0 107.0 148.0 108.0 134.0 131.0 108.0 132.0 107.0
17:20:30 INFO - PID 1645 |
17:20:30 INFO - PID 1645 | [#2] audio-dft Cycles:10 Average:196.50 Median:185.50 stddev:25.83 (13.9%) stddev-sans-first:27.06
17:20:30 INFO - PID 1645 | Values: 185.0 199.0 257.0 228.0 186.0 184.0 181.0 184.0 176.0 185.0
17:20:30 INFO - PID 1645 |
17:20:30 INFO - PID 1645 | [#3] audio-fft Cycles:10 Average:67.40 Median:66.00 stddev:11.16 (16.9%) stddev-sans-first:10.69
17:20:30 INFO - PID 1645 | Values: 81.0 59.0 59.0 86.0 59.0 58.0 60.0 60.0 80.0 72.0
17:20:30 INFO - PID 1645 |
17:20:30 INFO - PID 1645 | [#4] audio-oscillator Cycles:10 Average:75.80 Median:77.50 stddev:9.33 (12.0%) stddev-sans-first:9.14
17:20:30 INFO - PID 1645 | Values: 86.0 71.0 84.0 69.0 68.0 68.0 88.0 69.0 67.0 88.0
17:20:30 INFO - PID 1645 |
17:20:30 INFO - PID 1645 | [#5] imaging-gaussian-blur Cycles:10 Average:94.50 Median:90.00 stddev:13.56 (15.1%) stddev-sans-first:14.28
17:20:30 INFO - PID 1645 | Values: 90.0 90.0 89.0 90.0 90.0 133.0 91.0 92.0 91.0 89.0
17:20:30 INFO - PID 1645 |
17:20:30 INFO - PID 1645 | [#6] imaging-darkroom Cycles:10 Average:101.20 Median:96.50 stddev:14.36 (14.9%) stddev-sans-first:15.15
17:20:30 INFO - PID 1645 | Values: 97.0 96.0 96.0 96.0 97.0 96.0 98.0 96.0 98.0 142.0
17:20:30 INFO - PID 1645 |
17:20:30 INFO - PID 1645 | [#7] imaging-desaturate Cycles:10 Average:90.70 Median:85.50 stddev:13.65 (16.0%) stddev-sans-first:14.29
17:20:30 INFO - PID 1645 | Values: 97.0 128.0 87.0 85.0 86.0 85.0 86.0 85.0 83.0 85.0
17:20:30 INFO - PID 1645 |
17:20:30 INFO - PID 1645 | [#8] json-parse-financial Cycles:10 Average:57.50 Median:64.00 stddev:10.81 (16.9%) stddev-sans-first:10.78
17:20:30 INFO - PID 1645 | Values: 47.0 48.0 47.0 70.0 68.0 70.0 69.0 60.0 48.0 48.0
17:20:30 INFO - PID 1645 |
17:20:30 INFO - PID 1645 | [#9] json-stringify-tinderbox Cycles:10 Average:57.70 Median:58.00 stddev:3.33 (5.7%) stddev-sans-first:2.83
17:20:30 INFO - PID 1645 | Values: 52.0 61.0 54.0 59.0 62.0 62.0 57.0 57.0 56.0 57.0
17:20:30 INFO - PID 1645 |
17:20:30 INFO - PID 1645 | [#10] stanford-crypto-aes Cycles:10 Average:81.80 Median:82.50 stddev:1.23 (1.5%) stddev-sans-first:1.22
17:20:30 INFO - PID 1645 | Values: 83.0 83.0 82.0 80.0 83.0 80.0 83.0 81.0 82.0 81.0
17:20:30 INFO - PID 1645 |
17:20:30 INFO - PID 1645 | [#11] stanford-crypto-ccm Cycles:10 Average:143.70 Median:141.00 stddev:14.53 (10.3%) stddev-sans-first:15.21
17:20:30 INFO - PID 1645 | Values: 137.0 135.0 133.0 145.0 150.0 137.0 137.0 133.0 181.0 149.0
17:20:30 INFO - PID 1645 |
17:20:30 INFO - PID 1645 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:188.50 Median:183.00 stddev:15.80 (8.6%) stddev-sans-first:16.38
17:20:30 INFO - PID 1645 | Values: 179.0 181.0 183.0 210.0 225.0 180.0 182.0 184.0 183.0 178.0
17:20:30 INFO - PID 1645 |
17:20:30 INFO - PID 1645 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.70 Median:67.00 stddev:1.16 (1.7%) stddev-sans-first:1.13
17:20:30 INFO - PID 1645 | Values: 68.0 67.0 68.0 64.0 67.0 67.0 67.0 66.0 67.0 66.0
17:20:30 INFO - PID 1645 | -------- Summary: end --------
17:20:30 INFO - PID 1645 |
17:20:31 INFO - TEST-INFO | 1645: exit 0
17:20:31 INFO - TEST-OK | kraken | took 60619ms
17:20:31 INFO - SUITE-END | took 1179s
17:20:31 INFO - Completed test suite (00:19:39)
17:20:31 INFO - javascript benchmark
17:20:31 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": [], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7717.435112748657, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3381.628020917697, 3513.6837684608395, 3569.6251893551166, 3577.3739595570787, 3594.5506811648543, 5745.109508026252, 6094.969525152379, 6267.749290028399, 6278.654673992928, 6312.274088479815, 5775.682337471438, 6224.719887605057, 6294.874102517958, 6298.9921612542, 6306.337834527366, 5263.394709608406, 5323.707196104213, 5413.485718856702, 5439.755211015514, 5474.206240095204, 4620.798592365975, 5541.168824676291, 5543.168524721284, 5549.861253468675, 5561.416051314614, 5349.277847490617, 5415.6479828811, 5426.891462170774, 5472.863178420532, 5508.1462373332, 5348.812791552277, 5364.651297665679, 5385.703786291752, 5390.407055223922, 5413.756380962866, 5281.339832520935, 5326.307580014612, 5347.438518955492, 5358.544523715489, 5360.142377219665, 6151.354107818659, 6248.343923888017, 6318.74725010999, 6319.399657032574, 6319.8736025279695, 6628.536002479828, 6632.568883022588, 6632.6683665817, 6636.800895973129, 6660.2340730816095, 6256.374362563753, 6643.103181070542, 6749.7300107995625, 6763.797086087426, 6764.729410823561, 6223.564350495421, 6265.530085243625, 6295.96852015737, 6299.590526615755, 6343.524235682296, 6722.260551339349, 6743.123393958754, 6759.966200168968, 6859.108315918898, 6932.272111428329, 6531.804045878631, 6542.21493420783, 6545.836354091186, 6545.869082618321, 6558.344165583499, 6694.263631000586, 6694.564853284521, 6696.229933557605, 6697.196336439659, 6706.932930670729, 6399.648019358887, 6457.2574153971955, 6601.339866013456, 6621.9337806621315, 6627.37039981201, 6602.669866506655, 6673.733050678015, 6705.161854768154, 6705.530612857151, 6716.1604799400075, 5299.4435584263865, 5324.041672498987, 5326.92009619856, 5343.171808369709, 5377.892442151135, 4592.265237561974, 4649.325847752057, 4714.3871296731895, 4728.59806916416, 4775.283707443842, 6217.813465596039, 6280.591761535486, 6302.527310451735, 6352.968235158794, 6357.682115894187, 6734.6632668367365, 6760.932390676128, 6776.525643204928, 6785.762498312634, 6798.660066996729, 5342.492463215988, 6289.685515724195, 6334.778282760173, 6364.331745166691, 6364.42720155179, 5761.942380576141, 5818.8254352369495, 5888.4700376966275, 5929.0, 5935.0, 5866.472017518444, 6780.186377634715, 6923.238443771081, 6998.16022077354, 7026.086608740831, 6517.608943463408, 6594.637294948824, 6611.206655201407, 6736.225334086643, 6828.487863410165, 6823.863522729617, 6829.141664753067, 6905.274946130584, 6907.13660792401, 6936.445084393337, 6534.477241820738, 6544.770933017321, 6553.246376666744, 6560.606363618198, 6564.80305590833, 6543.247526534319, 6614.140161778938, 6681.064650948774, 6689.364510371507, 6740.12378390806, 6486.4486518646445, 6503.349665033459, 6519.250286217146, 6521.0, 6541.672916354068, 4984.501549844987, 6441.259255185526, 6453.838654033687, 6457.192850893638, 6520.836979075561, 5517.813670060957, 6241.282252541016, 6294.402031806971, 6358.395952384516, 6371.267304260069, 6500.609963402211, 6527.738890444519, 6528.053432252202, 6536.901946470712, 6553.836154096186, 5270.578353731769, 6341.55609107358, 6343.873122537616, 6351.618902865843, 6352.047192920914], "value": 6069.351053420486, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [737.3842841227574, 769.3768047881217, 802.7792357101798, 1106.363840791545, 1126.003486914081, 25715.871420642892, 35492.75775347863, 35714.285714285696, 35819.64180358199, 35831.0, 37346.25307493848, 37742.43386349207, 37755.62244377555, 37770.8111459443, 37804.43293350595, 5887.0580707086665, 6008.879822403549, 8124.228198321165, 8262.628181731836, 8281.0, 8043.356531477468, 8179.631916563769, 8194.713185038525, 8206.425550211488, 8210.630521626541, 8226.136255693156, 8283.917160828405, 8293.377996650244, 8335.166483351646, 8341.332693384546, 8213.466124701905, 8218.753437396888, 8267.503949763033, 8290.41967062306, 8296.58517074147, 8073.6366863491285, 8173.386995975326, 8207.95896020522, 8227.465214761021, 8228.382871284617, 35301.646983530096, 35440.936771896886, 35467.4679879802, 35470.11324716903, 35627.4655880162, 3573.3925232710503, 3593.5508061492314, 3596.334678084537, 3597.4244120940784, 3598.5501812273465, 29247.56128658072, 29401.823927042893, 29435.0, 29452.410951781058, 29504.26239344012, 3072.5698402223707, 3141.5444760509827, 3158.6367567729776, 3169.2869104451543, 3179.82510961897, 235840.0, 293071.0, 293645.0635493639, 296008.0, 296502.0, 47113.5288647116, 47172.292415613796, 47173.5282647176, 47205.29192062122, 47266.5273347269, 50123.24815127776, 50201.99596008132, 50336.49663503318, 50386.24420633693, 50445.74777126091, 13992.230427326595, 14080.577582672537, 14093.436262549383, 14104.576862694135, 14119.576412707635, 9853.950730246446, 9871.851922221173, 9885.25860560461, 11488.94255528717, 11531.827022594667, 11549.88450115505, 11569.363684997406, 11635.825462618068, 11661.35862527569, 11685.298882067103, 4365.628921541641, 5137.17805151174, 5171.922421163686, 5197.480251974772, 5199.700074981255, 1008.7276435362411, 1010.3988127064385, 1012.4026824173626, 1044.3368461027303, 1045.7281106912258, 17752.91123544374, 21267.893660531598, 22550.32349029532, 23690.28929132129, 23715.40711482227, 1105.789899919013, 1106.5407855739777, 1137.4256000719583, 1138.6014894786758, 1156.1329003247565, 11533.480993355319, 12027.759444811054, 12198.14612977083, 12235.632931012084, 12258.08064395174, 302880.0, 303742.0, 305174.0, 305478.0, 306124.0, 49937.750311248936, 50054.74972625114, 50126.997460050596, 50137.74931125394, 50210.748946255764, 1463.7072585482733, 1514.3109885002298, 1553.2854886751995, 1555.4400415850284, 1569.3016607609504, 20758.06588703542, 22957.311280661608, 22960.885195573916, 23082.307530774102, 23230.767692323538, 36677.63322366849, 36703.89888303355, 36738.8163059183, 36804.89585312445, 36979.26041479102, 266612.0, 267597.0, 268435.0, 268442.0, 269147.0, 24076.51846963086, 27895.0, 31374.0, 32276.83861580677, 32397.676023239466, 34351.4847277286, 34407.0, 39195.41206881842, 39960.0, 42037.78981105075, 28494.287642809097, 29133.417331652825, 29645.25886852846, 29725.0, 30437.543436848024, 25784.484310314063, 28231.43537129287, 28716.42567148687, 29219.85390073036, 29344.11967640974, 33864.322713546084, 34210.65789342174, 35074.82462587671, 35199.64800351964, 35253.942381728586, 28587.28531786722, 28927.69825357906, 29308.267293316985, 29558.078724882813, 30448.695513045473, 22638.773612263667, 22921.541569168858, 23224.76775232226, 23380.532389351778, 23407.41481462977], "value": 16645.05157757496, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [372.9533808273966, 426.9709998902645, 440.709131972898, 440.76419115773064, 454.12354156478006, 16069.51791446257, 20853.165873365066, 21494.785052149495, 21977.890110549466, 22238.99924503397, 21082.0, 21676.89161554194, 21963.450913727123, 22186.556268874654, 22617.54764904701, 4784.899473121167, 6041.909371359433, 6235.688215589225, 6250.749970001195, 6272.090546870701, 5999.370066143058, 6255.061740738903, 6281.842953926142, 6282.7172777224905, 6285.245770507523, 6032.245969253843, 6108.205933228674, 6194.00895856663, 6196.1944947157035, 6250.374962503759, 5342.893142137155, 5546.69493177875, 6002.439365764889, 6077.088436734503, 6162.983107787232, 5422.349318081816, 5439.700816455093, 5513.283273174502, 5513.338399392059, 5514.420985796473, 20741.481462963395, 22127.668084978737, 26610.0, 26720.866395667897, 26807.0, 2181.596404665142, 2254.1434254983164, 2254.5490901819703, 2256.4697296135396, 2260.2089268755967, 17576.0, 17634.91182544079, 17779.73330400032, 18094.63810723778, 18297.359592414203, 2080.5318803269297, 2101.8423618228694, 2154.1598776477185, 2155.805977462036, 2156.590247852903, 125009.0, 138301.925471118, 142925.0, 142989.0, 143492.0, 11373.0, 12065.0, 12486.50053997848, 12546.749065018648, 12591.748165036648, 13782.517611883535, 15258.694826103416, 15261.160636165117, 15596.0, 16317.673646527002, 8787.956060219658, 8891.0, 9008.144226298491, 9104.544772761468, 9111.498867562215, 8509.959523857018, 8530.914690853137, 8536.829263414696, 8717.259032982141, 8717.912820871838, 5863.29640443141, 7409.222031686703, 7486.737964171228, 7596.962015189999, 7633.679349482993, 1871.4666320098706, 2176.738791345049, 2192.6820611011312, 2197.7582465928735, 2209.304069218191, 942.4863449420083, 974.2692980264801, 975.5609975510906, 1014.7970405918845, 1033.6382266206917, 20980.685289720666, 21607.02768375427, 21619.243326483498, 21639.0, 21673.916304184728, 1005.7938122684832, 1020.5764607687875, 1027.3476342522404, 1038.2557303729657, 1040.8126537223372, 10584.364938103738, 10640.989106034915, 10649.627263045757, 10946.0, 10948.34309941406, 530170.0, 538125.0, 538902.0, 552705.0, 554745.0, 112271.43864280626, 118513.11151663659, 130303.0, 133342.0, 134018.0, 1189.6728399689982, 1204.28345134643, 1214.860291066521, 1231.815227715832, 1234.3889774561649, 18309.633807324044, 18364.265429382674, 18425.907870460564, 18443.631127377645, 18503.907480462512, 54156.458435415145, 56486.43513564812, 58494.707526462094, 58831.70584147052, 59033.40966590279, 75116.0, 76051.61974190094, 78129.60935195515, 78618.6069069651, 79154.81267780873, 7196.352328290479, 8620.568971551524, 8869.246114080117, 8908.10918908104, 8947.552622368725, 13631.182129072287, 13669.384877680726, 13672.316384180951, 14050.367733452224, 14148.575542733735, 11663.300201987908, 11722.118076446826, 11795.52817887275, 11939.940300298444, 12119.151659383759, 10291.691249262534, 11059.28114672576, 11140.220184587, 11396.088312935111, 11415.543378264776, 10614.522346494581, 11297.548098075986, 11823.172377933462, 12094.153409261267, 12295.446704898122, 8597.914020859711, 8786.516741579275, 8789.868151977598, 8865.468071915706, 9117.77205569866, 10025.649102281384, 11834.940825295818, 11924.821127682919, 11997.220180688228, 12044.698882528008], "value": 10270.170575331169, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1422.4523558430005, 1452.2520901735602, 1482.4737218287507, 1500.579837645459, 1503.0906301687478, 11194.776104477916, 11702.707432314186, 11715.707107322321, 11729.003034742058, 11732.413379331032, 11361.0, 11584.13119016073, 11608.767824643503, 11610.883891161086, 11617.941910290438, 2494.4761600064007, 2543.707473640527, 2544.427503811646, 2544.9618255726177, 2550.885210165547, 2485.4159272570932, 2529.0895277700015, 2532.189699296226, 2533.5946248600226, 2536.5687833068423, 2461.433870209853, 2501.9749802502015, 2526.734692857251, 2530.266222795387, 2537.1880998080624, 2229.0, 2426.7694569015916, 2497.8501289922574, 2506.0, 2514.0823599386276, 2439.4755127647463, 2457.741937096615, 2459.938501537458, 2483.8261321707487, 2487.8756062197, 11388.202825802196, 11421.086313094933, 11539.134564907581, 11546.076313894868, 11562.826557601642, 6285.968570157165, 6530.6408147551865, 6536.836579085513, 6561.212654481445, 6573.375529324707, 3825.6365645263654, 4111.835526578963, 4150.481189851269, 4161.687873409476, 4188.727732697395, 1225.6261840138754, 1238.851337839456, 1300.478440224939, 1303.758804621148, 1311.757324894888, 15238.923805381013, 15620.984635998737, 15643.843561564467, 16138.354465821352, 16241.512754617263, 5292.153255479105, 5430.8099216527235, 5558.86102847424, 5575.135853942646, 5593.74828132735, 11658.475368608264, 11659.941700291443, 11664.708382290512, 11667.299962002307, 11686.94156529212, 5738.08190689496, 5933.258342707161, 6530.379613936669, 6535.281119076898, 6553.737850485927, 6008.789692360747, 6059.8485037873525, 6229.688515574203, 6236.407541283571, 6236.438720515175, 4290.420793192941, 4374.343848422762, 4392.692511524163, 4440.089781594766, 4456.7548784817145, 1083.3554526597193, 1253.3106791264768, 1261.9495220191272, 1273.2933222061845, 1277.0294576122214, 742.3726950726627, 767.1944458318743, 797.908240552344, 812.3947658993969, 816.5019338203691, 6548.377904099103, 6793.184817821893, 6956.95645653156, 6964.338387853146, 7023.051887995158, 942.9009953954874, 954.9856752148723, 964.392432767352, 966.8114717629994, 988.2193067476757, 3608.6030536640947, 3830.482884810571, 3872.3029854625875, 3954.387070004154, 3970.3250447424007, 14664.780028299583, 14908.47820326305, 15089.849101509064, 15097.471588494569, 15119.924400377928, 14459.27703614815, 14979.775303370458, 15021.098734075991, 15023.02350347224, 15051.849481505264, 7536.962315188389, 8480.194381533744, 8689.782755431164, 8701.17338852808, 8752.387332886765, 6108.328083910677, 6438.291787903233, 7029.26192749754, 7066.57600543969, 7076.824673442694, 12449.751004980031, 12581.245125292511, 12655.430505627084, 13673.794893076412, 13678.179309241477, 13145.0, 13463.932680336535, 13729.382177802223, 13761.862381376059, 13931.30343482842, 9078.45529268246, 9218.01812610292, 9243.676471323472, 9385.812283754423, 9418.293627977791, 10611.09805666527, 10682.679519614114, 10687.25189236746, 10688.89311106879, 10704.411257380918, 8153.143919888326, 8154.9806274215725, 8899.510526921082, 8995.55022248898, 9001.234895033995, 8777.473351598925, 8857.911420885966, 8880.822383552422, 8943.821123577362, 9072.546372681207, 7860.646270917708, 7926.643301051351, 7975.601219939096, 8091.716789912325, 8113.878291825746, 5924.437178468039, 6012.398760123952, 6047.213862197887, 6053.303870055, 6064.696765161636, 6043.12374705674, 6102.725377358119, 6119.816405507842, 6137.60105593135, 6214.627122372672], "value": 5342.4130796299705, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [397.61828644501276, 398.11220977220796, 401.05951543630187, 402.38033428520083, 405.40000798817766, 5037.848864534065, 5443.401225865152, 6065.514758819295, 6155.907661385083, 6187.412195841393, 5899.380565040673, 5962.463378295953, 5990.311114221866, 6051.788187413442, 6094.878102437949, 3814.5231846019246, 4596.655250856183, 4597.954020459786, 4602.585767280944, 4619.683390233792, 4623.052274283764, 4624.0, 4631.212693842038, 4631.745254011028, 4633.953660463403, 4416.955830441702, 4417.249067658489, 4548.567886050836, 4556.2254416749065, 4580.175568397687, 3874.786886721229, 4479.3281007848755, 4544.909101817978, 4560.680752347321, 4565.589096981271, 3698.685611723007, 3928.8213535939103, 4048.352263637804, 4058.939115913264, 4069.6337329640473, 5560.999020176367, 5646.2377579027125, 5860.6850294134, 5904.645721256738, 5945.197398351211, 5211.791528338862, 5215.765290561933, 5237.79048838046, 5261.053010458116, 5268.631195816294, 5291.3385826771655, 5887.646741195542, 6067.514598832127, 6397.296297407282, 6423.454006409461, 3596.496490491333, 4238.682098842568, 4287.142571485715, 4291.334843099294, 4305.677074219446, 6545.9018114728315, 6682.331766823376, 6707.228668703064, 6769.763058292985, 6878.27778083299, 3518.3842827505086, 4255.468066491689, 4296.419983302277, 4326.394304797331, 4336.978315108404, 4388.253996820485, 4409.5149533551285, 4412.84555040572, 4414.580614841585, 4464.17412778637, 5030.245463180552, 5107.284980102789, 5182.948170518322, 5191.792328306901, 5217.660852044605, 5201.557867581222, 6341.850721822436, 6419.807405777834, 6426.228852537725, 6449.516286278548, 1481.7629179331254, 1499.130504307499, 1500.3398504657916, 1504.5561559340022, 1505.4956589542408, 809.7489778168787, 836.3685417509741, 911.5715613661702, 986.4180133721139, 1004.1924535835612, 5033.7734801934, 5201.635885487979, 6128.601640893327, 6155.384461553898, 6168.352323006109, 5579.888402231933, 6438.130852334969, 6648.368405001517, 6666.0, 6671.366220209073, 6023.789167379121, 6129.387061293835, 6143.508519318444, 6151.784687536005, 6182.412670796267, 2988.4770165221435, 3173.841307934594, 3286.274705779437, 3415.8121303328558, 3461.44616861301, 6227.532935029891, 6234.501239900797, 6520.18497687789, 6723.226828914738, 6885.414739747077, 5599.116455025665, 5608.663480191201, 5689.800856969986, 6835.418989385858, 6873.381395674413, 5705.0635061807225, 5824.6704265597355, 6824.488163387766, 6889.965550172217, 6908.481863860231, 6352.936470635419, 6609.735610575523, 6629.834254143685, 6650.434713049445, 6655.0, 6381.106645069601, 6494.935050649433, 6504.674766261763, 6538.738450461929, 6588.8023359299295, 6795.422389096982, 6799.0, 6811.523193376416, 6822.658867056528, 6874.175098988154, 4318.784060796885, 4341.305391137403, 4341.565843415633, 4349.151915376598, 4350.21696094706, 5655.586103474131, 6075.27096748393, 6380.055457020846, 6407.0, 6729.495287853529, 6448.387403196689, 6461.547691661539, 6484.546081774419, 6488.448481879093, 6498.415142637185, 5042.4705405931845, 5557.972210139085, 6486.2216534016225, 6502.60984340941, 6504.252011018793, 5866.266716660418, 6348.650824204713, 6359.332270111757, 6359.8092057238355, 6365.331640177715, 6313.715882785377, 6468.094466774562, 6511.511636627367, 6532.41208291256, 6560.573562718408, 6032.276126864629, 6294.716737746904, 6325.304216536269, 6325.564657606763, 6361.332060133619], "value": 4679.32834179229, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [818.930390916772, 839.6431516605443, 845.4800297816845, 847.6736456464263, 863.0420233540768, 13514.391852366642, 14146.858531414682, 16074.276657550408, 16532.66934661306, 16575.834241657594, 11805.586804461846, 12882.098253122285, 17283.74074388885, 17335.82664173358, 17367.47897563072, 980.0640388429068, 986.2553771902204, 992.1566668331932, 992.8212921674095, 994.8955359687237, 1008.0574662690408, 1009.878814542256, 1010.9090181883629, 1011.1152741351318, 1011.8988101189896, 686.2588404523103, 704.8907419350008, 1000.2797985450501, 1022.2486472442748, 1031.7730099378125, 1021.0555236406369, 1021.1065317846884, 1024.2318261304022, 1026.2303272545591, 1036.5387402605843, 762.4662736084763, 763.7823220382164, 766.1151370167465, 767.1178145133085, 773.234497847133, 15912.283947222402, 16288.755668664979, 16430.260638271186, 16685.749713754303, 16988.405405810736, 16346.509604711877, 16399.016059036498, 16629.584260393465, 16818.24317905697, 16846.915765421218, 13644.727105457934, 13814.723705525934, 13935.512257070954, 14024.018318717694, 14147.29263536819, 3409.829508524564, 3417.419038763416, 3421.7091547218515, 3425.0067480430644, 3428.6914177724125, 118406.0, 119620.40189798996, 122012.0, 122676.0, 122731.77268227293, 10461.581536738446, 11907.34509601963, 11940.940295298642, 12110.515579376726, 12125.818112728317, 20259.696104558443, 20324.69512957307, 20928.47678808042, 20946.371608851758, 20988.685169722467, 7229.855402892018, 7367.81580460482, 7370.631468426557, 7392.075990501187, 7395.778126656209, 3583.5699716033723, 3617.0957260684827, 3618.5476815398074, 3624.1664417184197, 3665.9633403666157, 4065.735727177724, 4130.173965206971, 4187.0, 4195.119025004722, 4195.286801243796, 843.9831203375898, 867.7526904832218, 878.1481962496368, 880.9075047120089, 883.4566741454088, 629.2040568680623, 661.3518751623436, 676.7631329034798, 681.1655721740926, 685.9657017149223, 10874.619388321371, 11221.60724374643, 11505.769884602261, 11932.343721095423, 12058.517659293706, 637.0953246390045, 650.9121268628769, 654.6268626882631, 663.5587334422652, 681.9897701534481, 5333.413324534298, 5434.592405569598, 5512.3660779010925, 5521.116621340647, 5580.91628625571, 106278.46860765801, 108075.0, 108342.0, 108504.4574777121, 108836.0, 98181.0, 99211.5433651156, 99428.00571994332, 100222.49888750656, 101405.47891781482, 55057.724711376984, 71445.6427717858, 74762.62618686871, 76165.61917190379, 76611.0, 10949.678433401297, 11591.88408115908, 12027.699307517032, 12146.635600931986, 12402.75194496123, 42031.0, 42249.78875105605, 42317.78841105775, 43046.56953430425, 43062.78468607762, 53450.73274633602, 54266.728666357994, 54770.17844732253, 55180.89638207293, 55656.443435565125, 15847.52457426279, 15961.920190399438, 16083.35666573324, 16428.425005125242, 16443.58891027734, 17406.73889891678, 17895.10524473797, 18141.818581814012, 18244.0, 18423.723644145615, 15972.28124734413, 16125.596860078593, 16609.252583633523, 16720.498385048468, 16721.331146753993, 16021.839781602035, 16050.67898642044, 16400.507984760476, 16532.917335413247, 16646.084465354037, 16430.17849107516, 16716.331346745996, 16774.0, 17150.485485435456, 17155.399561015303, 11683.415829208336, 12021.939890300493, 12086.15396922207, 12202.81695774582, 12209.267443953391, 11447.370394628375, 12780.552680656132, 13390.330483475593, 13790.069830098373, 14029.228392438514], "value": 8144.832755623912, "unit": "score"}]}, {"extraOptions": [], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1465.0000000000002, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [111.0, 112.0, 110.0, 112.0, 112.0, 98.0, 139.0, 106.0, 149.0, 163.0], "value": 121.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [140.0, 103.0, 107.0, 148.0, 108.0, 134.0, 131.0, 108.0, 132.0, 107.0], "value": 121.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [185.0, 199.0, 257.0, 228.0, 186.0, 184.0, 181.0, 184.0, 176.0, 185.0], "value": 196.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 59.0, 59.0, 86.0, 59.0, 58.0, 60.0, 60.0, 80.0, 72.0], "value": 67.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 71.0, 84.0, 69.0, 68.0, 68.0, 88.0, 69.0, 67.0, 88.0], "value": 75.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 90.0, 89.0, 90.0, 90.0, 133.0, 91.0, 92.0, 91.0, 89.0], "value": 94.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [97.0, 96.0, 96.0, 96.0, 97.0, 96.0, 98.0, 96.0, 98.0, 142.0], "value": 101.2, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [97.0, 128.0, 87.0, 85.0, 86.0, 85.0, 86.0, 85.0, 83.0, 85.0], "value": 90.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [47.0, 48.0, 47.0, 70.0, 68.0, 70.0, 69.0, 60.0, 48.0, 48.0], "value": 57.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [52.0, 61.0, 54.0, 59.0, 62.0, 62.0, 57.0, 57.0, 56.0, 57.0], "value": 57.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 83.0, 82.0, 80.0, 83.0, 80.0, 83.0, 81.0, 82.0, 81.0], "value": 81.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [137.0, 135.0, 133.0, 145.0, 150.0, 137.0, 137.0, 133.0, 181.0, 149.0], "value": 143.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [179.0, 181.0, 183.0, 210.0, 225.0, 180.0, 182.0, 184.0, 183.0, 178.0], "value": 188.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 67.0, 68.0, 64.0, 67.0, 67.0, 67.0, 66.0, 67.0, 66.0], "value": 66.7, "unit": "score"}]}]}
17:20:31 INFO - Return code: 0
17:20:31 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json
17:20:31 INFO - # TBPL SUCCESS #
17:20:31 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'
17:20:31 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build
17:20:31 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType"
17:20:31 INFO - 2017-05-05 17:20:31.964 screenresolution[1650:9768] starting screenresolution argv=screenresolution get
17:20:31 INFO - 2017-05-05 17:20:31.967 screenresolution[1650:9768] Display 0: 1600x1200x32@60
17:20:31 INFO - 2017-05-05 17:20:31.974 screenresolution[1651:9771] starting screenresolution argv=screenresolution list
17:20:31 INFO - Available Modes on Display 0
17:20:31 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75
17:20:31 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60
17:20:31 INFO - 800x600x32@75 800x600x32@60 640x480x32@75
17:20:31 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60
17:20:32 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays:
17:20:32 INFO - Intel Iris:
17:20:32 INFO - Chipset Model: Intel Iris
17:20:32 INFO - Type: GPU
17:20:32 INFO - Bus: Built-In
17:20:32 INFO - VRAM (Dynamic, Max): 1536 MB
17:20:32 INFO - Vendor: Intel (0x8086)
17:20:32 INFO - Device ID: 0x0a2e
17:20:32 INFO - Revision ID: 0x0009
17:20:32 INFO - Displays:
17:20:32 INFO - Display:
17:20:32 INFO - Resolution: 1600 x 1200 @ 60 Hz
17:20:32 INFO - Pixel Depth: 32-Bit Color (ARGB8888)
17:20:32 INFO - Display Serial Number: 1600x1200 60
17:20:32 INFO - Main Display: Yes
17:20:32 INFO - Mirror: Off
17:20:32 INFO - Online: Yes
17:20:32 INFO - Rotation: Supported
17:20:32 INFO - Return code: 0
17:20:32 INFO - Running post-action listener: _resource_record_post_action
17:20:32 INFO - [mozharness: 2017-05-06 00:20:32.221039Z] Finished run-tests step (success)
17:20:32 INFO - Running post-run listener: _resource_record_post_run
17:20:32 INFO - Total resource usage - Wall time: 1200s; CPU: 54.0%; Read bytes: 38483456; Write bytes: 510152704; Read time: 248; Write time: 1561
17:20:32 INFO - TinderboxPrint: CPU usage
53.6%
17:20:32 INFO - TinderboxPrint: I/O read bytes / time
38,483,456 / 248
17:20:32 INFO - TinderboxPrint: I/O write bytes / time
510,152,704 / 1,561
17:20:32 INFO - TinderboxPrint: CPU idle
2,225.8 (46.4%)
17:20:32 INFO - TinderboxPrint: CPU system
957.6 (19.9%)
17:20:32 INFO - TinderboxPrint: CPU user
1,618.2 (33.7%)
17:20:32 INFO - TinderboxPrint: Swap in / out
247,095,296 / 0
17:20:32 INFO - install - Wall time: 20s; CPU: 54.0%; Read bytes: 205773312; Write bytes: 206280192; Read time: 16080; Write time: 432
17:20:32 INFO - run-tests - Wall time: 1181s; CPU: 54.0%; Read bytes: 36378112; Write bytes: 296798720; Read time: 227; Write time: 1101
17:20:32 INFO - Running post-run listener: _upload_blobber_files
17:20:32 INFO - Blob upload gear active.
17:20:32 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir.
17:20:32 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
17:20:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json']
17:20:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json
17:20:33 INFO - (blobuploader) - INFO - Open directory for files ...
17:20:33 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ...
17:20:33 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com
17:20:33 INFO - (blobuploader) - INFO - Uploading, attempt #1.
17:20:33 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded
17:20:33 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded!
17:20:33 INFO - (blobuploader) - INFO - Done attempting.
17:20:33 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ...
17:20:33 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com
17:20:33 INFO - (blobuploader) - INFO - Uploading, attempt #1.
17:20:34 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded
17:20:34 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded!
17:20:34 INFO - (blobuploader) - INFO - Done attempting.
17:20:34 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ...
17:20:34 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com
17:20:34 INFO - (blobuploader) - INFO - Uploading, attempt #1.
17:20:36 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded
17:20:36 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded!
17:20:36 INFO - (blobuploader) - INFO - Done attempting.
17:20:36 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ...
17:20:36 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com
17:20:36 INFO - (blobuploader) - INFO - Uploading, attempt #1.
17:20:38 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded
17:20:38 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded!
17:20:38 INFO - (blobuploader) - INFO - Done attempting.
17:20:38 INFO - (blobuploader) - INFO - Iteration through files over.
17:20:38 INFO - Return code: 0
17:20:38 INFO - rmtree: /builds/slave/test/build/uploaded_files.json
17:20:38 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1
17:20:38 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/944f4ef4c6fbdf9449078eedb3db4da1e8b3f2bc41689a3edd85201e28763272c57be8f8ff2e479db730c53d10500530931947f2706f922d57232cad1a5d8b02", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2970bf52a41933a37ddda0ba28204b4bfc72d5d59d58c10d4c42fb34965763962b27c9f1264c09b5a26b1a175f83d13ed4f233e4b6a94bb14a5d3226c46dfa4a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1aa2a4e8a8c545489f71d3f7a8af1920a375831480b5b3459eea6a7d90b3f2c530153b36bbe6de8110ecb7391a66eba0c93005ddcd624947f27c91d823896b08", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9d75aa97263ab6e9fbfe0add7aa5d8f6739439e7e07bead2645b533255f9abaa16f1c202a58436892e5bddc833b46cb5ba19fe45df65f7c706d5d8ecff0ca131"}
17:20:38 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files
17:20:38 INFO - Writing to file /builds/slave/test/properties/blobber_files
17:20:38 INFO - Contents:
17:20:38 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/944f4ef4c6fbdf9449078eedb3db4da1e8b3f2bc41689a3edd85201e28763272c57be8f8ff2e479db730c53d10500530931947f2706f922d57232cad1a5d8b02", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2970bf52a41933a37ddda0ba28204b4bfc72d5d59d58c10d4c42fb34965763962b27c9f1264c09b5a26b1a175f83d13ed4f233e4b6a94bb14a5d3226c46dfa4a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1aa2a4e8a8c545489f71d3f7a8af1920a375831480b5b3459eea6a7d90b3f2c530153b36bbe6de8110ecb7391a66eba0c93005ddcd624947f27c91d823896b08", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9d75aa97263ab6e9fbfe0add7aa5d8f6739439e7e07bead2645b533255f9abaa16f1c202a58436892e5bddc833b46cb5ba19fe45df65f7c706d5d8ecff0ca131"}
17:20:38 INFO - Running post-run listener: copy_logs_to_upload_dir
17:20:38 INFO - Copying logs to upload dir...
17:20:38 INFO - mkdir: /builds/slave/test/build/upload/logs
17:20:38 INFO - Copying logs to upload dir...
program finished with exit code 0
elapsedTime=1275.991193
========= master_lag: 0.05 =========
========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 16 secs) (at 2017-05-05 17:20:38.395794) =========
========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-05 17:20:38.399242) =========
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.9bysca6FWz/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.Yspqbs65xC/Listeners
TMPDIR=/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/T/
TWISTD_LOG_PATH=/builds/slave/twistd.log
USER=cltbld
VERSIONER_PYTHON_PREFER_32_BIT=no
VERSIONER_PYTHON_VERSION=2.7
XPCOM_DEBUG_BREAK=warn
XPC_FLAGS=0x0
XPC_SERVICE_NAME=0
__CF_USER_TEXT_ENCODING=0x1C:0:0
using PTY: False
blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/944f4ef4c6fbdf9449078eedb3db4da1e8b3f2bc41689a3edd85201e28763272c57be8f8ff2e479db730c53d10500530931947f2706f922d57232cad1a5d8b02", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2970bf52a41933a37ddda0ba28204b4bfc72d5d59d58c10d4c42fb34965763962b27c9f1264c09b5a26b1a175f83d13ed4f233e4b6a94bb14a5d3226c46dfa4a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1aa2a4e8a8c545489f71d3f7a8af1920a375831480b5b3459eea6a7d90b3f2c530153b36bbe6de8110ecb7391a66eba0c93005ddcd624947f27c91d823896b08", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9d75aa97263ab6e9fbfe0add7aa5d8f6739439e7e07bead2645b533255f9abaa16f1c202a58436892e5bddc833b46cb5ba19fe45df65f7c706d5d8ecff0ca131"}
build_url:https://queue.taskcluster.net/v1/task/Tet-IZzERMCWsG0xEhzITw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg
program finished with exit code 0
elapsedTime=0.011029
build_url: 'https://queue.taskcluster.net/v1/task/Tet-IZzERMCWsG0xEhzITw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg'
blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/944f4ef4c6fbdf9449078eedb3db4da1e8b3f2bc41689a3edd85201e28763272c57be8f8ff2e479db730c53d10500530931947f2706f922d57232cad1a5d8b02", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2970bf52a41933a37ddda0ba28204b4bfc72d5d59d58c10d4c42fb34965763962b27c9f1264c09b5a26b1a175f83d13ed4f233e4b6a94bb14a5d3226c46dfa4a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1aa2a4e8a8c545489f71d3f7a8af1920a375831480b5b3459eea6a7d90b3f2c530153b36bbe6de8110ecb7391a66eba0c93005ddcd624947f27c91d823896b08", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9d75aa97263ab6e9fbfe0add7aa5d8f6739439e7e07bead2645b533255f9abaa16f1c202a58436892e5bddc833b46cb5ba19fe45df65f7c706d5d8ecff0ca131"}'
========= master_lag: 0.03 =========
========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-05 17:20:38.436242) =========
========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-05 17:20:38.436603) =========
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.9bysca6FWz/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.Yspqbs65xC/Listeners
TMPDIR=/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/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.005190
========= master_lag: 0.04 =========
========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-05 17:20:38.484960) =========
========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-05 17:20:38.485310) =========
========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-05 17:20:38.485631) =========
========= Total master_lag: 0.19 =========