builder: mozilla-aurora_yosemite_r7_test-dromaeojs-e10s
slave: t-yosemite-r7-0060
starttime: 1490691629.55
results: success (0)
buildid: 20170328004003
builduid: a93bd0709e6f4eaa8ac19c1f076cdd14
revision: db2babffcafbd6b5b47a8c5813005838eb171a90
========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-03-28 02:00:29.553187) =========
master: http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/
========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-03-28 02:00:29.553642) =========
========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-28 02:00:29.553983) =========
========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-28 02:00:29.578738) =========
========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-28 02:00:29.579096) =========
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.0VMVrWf0XM/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.YjIGNKBMXi/Listeners
TMPDIR=/var/folders/9c/2dh985pd15l19fywb0pq404000000w/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-03-28 02:00:29-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py
Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25
Connecting to hg.mozilla.org|63.245.215.102|:443... connected.
HTTP request sent, awaiting response... 200 Script output follows
Length: 12179 (12K) [text/x-python]
Saving to: 'archiver_client.py'
0K .......... . 100% 151M=0s
2017-03-28 02:00:29 (151 MB/s) - 'archiver_client.py' saved [12179/12179]
program finished with exit code 0
elapsedTime=0.117098
========= master_lag: 0.02 =========
========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-28 02:00:29.719830) =========
========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-28 02:00:29.720167) =========
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.0VMVrWf0XM/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.YjIGNKBMXi/Listeners
TMPDIR=/var/folders/9c/2dh985pd15l19fywb0pq404000000w/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.021243
========= master_lag: 0.03 =========
========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-28 02:00:29.768736) =========
========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-28 02:00:29.769080) =========
bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev db2babffcafbd6b5b47a8c5813005838eb171a90 --destination scripts --debug'
in dir /builds/slave/test/. (timeout 1200 secs)
watching logfiles {}
argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev db2babffcafbd6b5b47a8c5813005838eb171a90 --destination scripts --debug']
environment:
Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.0VMVrWf0XM/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.YjIGNKBMXi/Listeners
TMPDIR=/var/folders/9c/2dh985pd15l19fywb0pq404000000w/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-03-28 02:00:29,860 truncating revision to first 12 chars
2017-03-28 02:00:29,860 Setting DEBUG logging.
2017-03-28 02:00:29,860 attempt 1/10
2017-03-28 02:00:29,860 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/db2babffcafb?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness
2017-03-28 02:00:30,532 unpacking tar archive at: mozilla-aurora-db2babffcafb/testing/mozharness/
program finished with exit code 0
elapsedTime=0.929079
========= master_lag: 0.04 =========
========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-28 02:00:30.742222) =========
========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-28 02:00:30.742549) =========
script_repo_revision: db2babffcafbd6b5b47a8c5813005838eb171a90
========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-28 02:00:30.742957) =========
========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-28 02:00:30.743219) =========
========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-28 02:00:30.770668) =========
========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 21 secs) (at 2017-03-28 02:00:30.771054) =========
/tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora
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-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Aurora', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora']
environment:
Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.0VMVrWf0XM/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.YjIGNKBMXi/Listeners
TMPDIR=/var/folders/9c/2dh985pd15l19fywb0pq404000000w/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
02:00:30 INFO - MultiFileLogger online at 20170328 02:00:30 in /builds/slave/test
02:00:30 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora
02:00:30 INFO - Dumping config to /builds/slave/test/logs/localconfig.json.
02:00:30 INFO - {'append_to_log': False,
02:00:30 INFO - 'base_work_dir': '/builds/slave/test',
02:00:30 INFO - 'blob_upload_branch': 'Mozilla-Aurora',
02:00:30 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt',
02:00:30 INFO - 'branch': 'Mozilla-Aurora',
02:00:30 INFO - 'buildbot_json_path': 'buildprops.json',
02:00:30 INFO - 'config_files': ('talos/mac_config.py',),
02:00:30 INFO - 'default_actions': ('clobber',
02:00:30 INFO - 'read-buildbot-config',
02:00:30 INFO - 'download-and-extract',
02:00:30 INFO - 'populate-webroot',
02:00:30 INFO - 'create-virtualenv',
02:00:30 INFO - 'install',
02:00:30 INFO - 'run-tests'),
02:00:30 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',),
02:00:30 INFO - 'download_minidump_stackwalk': True,
02:00:30 INFO - 'download_symbols': 'ondemand',
02:00:30 INFO - 'exes': {'python': '/tools/buildbot/bin/python',
02:00:30 INFO - 'tooltool.py': '/tools/tooltool.py',
02:00:30 INFO - 'virtualenv': ('/tools/buildbot/bin/python',
02:00:30 INFO - '/tools/misc-python/virtualenv.py')},
02:00:30 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub',
02:00:30 INFO - 'http://pypi.pub.build.mozilla.org/pub'),
02:00:30 INFO - 'gecko_profile': False,
02:00:30 INFO - 'gecko_profile_interval': 0,
02:00:30 INFO - 'installer_path': 'installer.exe',
02:00:30 INFO - 'log_level': 'info',
02:00:30 INFO - 'log_name': 'talos',
02:00:30 INFO - 'log_to_console': True,
02:00:30 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk',
02:00:30 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest',
02:00:30 INFO - 'opt_config_files': (),
02:00:30 INFO - 'pip_index': False,
02:00:30 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'),
02:00:30 INFO - 'cmd': ('bash',
02:00:30 INFO - '-c',
02:00:30 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'),
02:00:30 INFO - 'enabled': True,
02:00:30 INFO - 'halt_on_failure': False,
02:00:30 INFO - 'name': 'check_screen_resolution'},),
02:00:30 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'),
02:00:30 INFO - 'cmd': ('bash',
02:00:30 INFO - '-c',
02:00:30 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'),
02:00:30 INFO - 'enabled': True,
02:00:30 INFO - 'halt_on_failure': False,
02:00:30 INFO - 'name': 'check_screen_resolution'},),
02:00:30 INFO - 'run_cmd_checks_enabled': True,
02:00:30 INFO - 'suite': 'dromaeojs-e10s',
02:00:30 INFO - 'system_bits': '32',
02:00:30 INFO - 'talos_extra_options': ('--webServer', 'localhost'),
02:00:30 INFO - 'title': 't-yosemite-r7-0060',
02:00:30 INFO - 'tooltool_cache': '/builds/tooltool_cache',
02:00:30 INFO - 'use_talos_json': True,
02:00:30 INFO - 'virtualenv_path': '/builds/slave/test/build/venv',
02:00:30 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None},
02:00:30 INFO - 'work_dir': 'build'}
02:00:30 INFO - [mozharness: 2017-03-28 09:00:30.983681Z] Running clobber step.
02:00:30 INFO - Running pre-action listener: _resource_record_pre_action
02:00:30 INFO - Running main action method: clobber
02:00:30 INFO - rmtree: /builds/slave/test/build
02:00:30 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1
02:00:32 INFO - Running post-action listener: _resource_record_post_action
02:00:32 INFO - [mozharness: 2017-03-28 09:00:32.716326Z] Finished clobber step (success)
02:00:32 INFO - [mozharness: 2017-03-28 09:00:32.716451Z] Running read-buildbot-config step.
02:00:32 INFO - Running pre-action listener: _resource_record_pre_action
02:00:32 INFO - Running main action method: read_buildbot_config
02:00:32 INFO - Using buildbot properties:
02:00:32 INFO - {
02:00:32 INFO - "project": "",
02:00:32 INFO - "product": "firefox",
02:00:32 INFO - "script_repo_revision": "production",
02:00:32 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs-e10s",
02:00:32 INFO - "repository": "",
02:00:32 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs-e10s",
02:00:32 INFO - "buildid": "20170328004003",
02:00:32 INFO - "stage_platform": "macosx64",
02:00:32 INFO - "basedir": "/builds/slave/test",
02:00:32 INFO - "nightly_build": "True",
02:00:32 INFO - "buildnumber": 91,
02:00:32 INFO - "slavename": "t-yosemite-r7-0060",
02:00:32 INFO - "master": "http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/",
02:00:32 INFO - "platform": "yosemite_r7",
02:00:32 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos",
02:00:32 INFO - "branch": "mozilla-aurora",
02:00:32 INFO - "repo_path": "releases/mozilla-aurora",
02:00:32 INFO - "revision": "db2babffcafbd6b5b47a8c5813005838eb171a90",
02:00:32 INFO - "pgo_build": "False",
02:00:32 INFO - "builduid": "a93bd0709e6f4eaa8ac19c1f076cdd14",
02:00:32 INFO - "slavebuilddir": "test"
02:00:32 INFO - }
02:00:32 INFO - Found installer url https://queue.taskcluster.net/v1/task/RLih5AzXS-mN1Qrsh7HNNQ/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg.
02:00:32 INFO - Running post-action listener: _resource_record_post_action
02:00:32 INFO - [mozharness: 2017-03-28 09:00:32.719836Z] Finished read-buildbot-config step (success)
02:00:32 INFO - [mozharness: 2017-03-28 09:00:32.719948Z] Running download-and-extract step.
02:00:32 INFO - Running pre-action listener: _resource_record_pre_action
02:00:32 INFO - Running main action method: download_and_extract
02:00:32 INFO - mkdir: /builds/slave/test/build/tests
02:00:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
02:00:32 INFO - trying https://queue.taskcluster.net/v1/task/RLih5AzXS-mN1Qrsh7HNNQ/artifacts/public/build/firefox-54.0a2.en-US.mac.test_packages.json
02:00:32 INFO - Downloading https://queue.taskcluster.net/v1/task/RLih5AzXS-mN1Qrsh7HNNQ/artifacts/public/build/firefox-54.0a2.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-54.0a2.en-US.mac.test_packages.json
02:00:32 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/RLih5AzXS-mN1Qrsh7HNNQ/artifacts/public/build/firefox-54.0a2.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-54.0a2.en-US.mac.test_packages.json'}, attempt #1
02:00:33 INFO - Downloaded 1199 bytes.
02:00:33 INFO - Reading from file /builds/slave/test/build/firefox-54.0a2.en-US.mac.test_packages.json
02:00:33 INFO - Using the following test package requirements:
02:00:33 INFO - {u'common': [u'firefox-54.0a2.en-US.mac.common.tests.zip'],
02:00:33 INFO - u'cppunittest': [u'firefox-54.0a2.en-US.mac.common.tests.zip',
02:00:33 INFO - u'firefox-54.0a2.en-US.mac.cppunittest.tests.zip'],
02:00:33 INFO - u'gtest': [u'firefox-54.0a2.en-US.mac.common.tests.zip',
02:00:33 INFO - u'firefox-54.0a2.en-US.mac.gtest.tests.zip'],
02:00:33 INFO - u'jittest': [u'firefox-54.0a2.en-US.mac.common.tests.zip',
02:00:33 INFO - u'jsshell-mac.zip'],
02:00:33 INFO - u'mochitest': [u'firefox-54.0a2.en-US.mac.common.tests.zip',
02:00:33 INFO - u'firefox-54.0a2.en-US.mac.mochitest.tests.zip'],
02:00:33 INFO - u'mozbase': [u'firefox-54.0a2.en-US.mac.common.tests.zip'],
02:00:33 INFO - u'reftest': [u'firefox-54.0a2.en-US.mac.common.tests.zip',
02:00:33 INFO - u'firefox-54.0a2.en-US.mac.reftest.tests.zip'],
02:00:33 INFO - u'talos': [u'firefox-54.0a2.en-US.mac.common.tests.zip',
02:00:33 INFO - u'firefox-54.0a2.en-US.mac.talos.tests.zip'],
02:00:33 INFO - u'web-platform': [u'firefox-54.0a2.en-US.mac.common.tests.zip',
02:00:33 INFO - u'firefox-54.0a2.en-US.mac.web-platform.tests.zip'],
02:00:33 INFO - u'xpcshell': [u'firefox-54.0a2.en-US.mac.common.tests.zip',
02:00:33 INFO - u'firefox-54.0a2.en-US.mac.xpcshell.tests.zip']}
02:00:33 INFO - Downloading packages: [u'firefox-54.0a2.en-US.mac.common.tests.zip'] for test suite category: common
02:00:33 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/RLih5AzXS-mN1Qrsh7HNNQ/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip
02:00:33 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/RLih5AzXS-mN1Qrsh7HNNQ/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip'}, attempt #1
02:00:33 INFO - Fetch https://queue.taskcluster.net/v1/task/RLih5AzXS-mN1Qrsh7HNNQ/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip into memory
02:00:35 INFO - Expected file size: 51789064
02:00:35 INFO - Obtained file size: 51789064
02:00:51 INFO - Downloading packages: [u'firefox-54.0a2.en-US.mac.common.tests.zip', u'firefox-54.0a2.en-US.mac.talos.tests.zip'] for test suite category: talos
02:00:51 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/RLih5AzXS-mN1Qrsh7HNNQ/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip
02:00:51 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/RLih5AzXS-mN1Qrsh7HNNQ/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip'}, attempt #1
02:00:51 INFO - Fetch https://queue.taskcluster.net/v1/task/RLih5AzXS-mN1Qrsh7HNNQ/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip into memory
02:00:53 INFO - Expected file size: 51789064
02:00:53 INFO - Obtained file size: 51789064
02:01:08 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/RLih5AzXS-mN1Qrsh7HNNQ/artifacts/public/build/firefox-54.0a2.en-US.mac.talos.tests.zip
02:01:08 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/RLih5AzXS-mN1Qrsh7HNNQ/artifacts/public/build/firefox-54.0a2.en-US.mac.talos.tests.zip'}, attempt #1
02:01:08 INFO - Fetch https://queue.taskcluster.net/v1/task/RLih5AzXS-mN1Qrsh7HNNQ/artifacts/public/build/firefox-54.0a2.en-US.mac.talos.tests.zip into memory
02:01:09 INFO - Expected file size: 14404438
02:01:09 INFO - Obtained file size: 14404438
02:01:09 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')]}
02:01:09 INFO - trying https://queue.taskcluster.net/v1/task/RLih5AzXS-mN1Qrsh7HNNQ/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg
02:01:09 INFO - Downloading https://queue.taskcluster.net/v1/task/RLih5AzXS-mN1Qrsh7HNNQ/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-54.0a2.en-US.mac.dmg
02:01:09 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/RLih5AzXS-mN1Qrsh7HNNQ/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-54.0a2.en-US.mac.dmg'}, attempt #1
02:01:11 INFO - Downloaded 74737487 bytes.
02:01:11 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/RLih5AzXS-mN1Qrsh7HNNQ/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg
02:01:11 INFO - mkdir: /builds/slave/test/properties
02:01:11 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url
02:01:11 INFO - Writing to file /builds/slave/test/properties/build_url
02:01:11 INFO - Contents:
02:01:11 INFO - build_url:https://queue.taskcluster.net/v1/task/RLih5AzXS-mN1Qrsh7HNNQ/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg
02:01:12 INFO - Running post-action listener: _resource_record_post_action
02:01:12 INFO - Running post-action listener: set_extra_try_arguments
02:01:12 INFO - [mozharness: 2017-03-28 09:01:12.298535Z] Finished download-and-extract step (success)
02:01:12 INFO - [mozharness: 2017-03-28 09:01:12.298660Z] Running populate-webroot step.
02:01:12 INFO - Running pre-action listener: _resource_record_pre_action
02:01:12 INFO - Running main action method: populate_webroot
02:01:12 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']},
02:01:12 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']},
02:01:12 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome',
02:01:12 INFO - '--tppagecycles',
02:01:12 INFO - '7'],
02:01:12 INFO - 'tests': ['tsvgm']}},
02:01:12 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'],
02:01:12 INFO - 'tests': ['tresize', 'tcanvasmark']},
02:01:12 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']},
02:01:12 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'],
02:01:12 INFO - 'tests': ['dromaeo_css', 'kraken']},
02:01:12 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']},
02:01:12 INFO - 'g1': {'pagesets_name': 'tp5n.zip',
02:01:12 INFO - 'talos_options': ['--disable-e10s'],
02:01:12 INFO - 'tests': ['tp5o_scroll', 'glterrain']},
02:01:12 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip',
02:01:12 INFO - 'tests': ['tp5o_scroll', 'glterrain']},
02:01:12 INFO - 'g2': {'pagesets_name': 'tp5n.zip',
02:01:12 INFO - 'talos_options': ['--disable-e10s'],
02:01:12 INFO - 'tests': ['damp', 'tps']},
02:01:12 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip',
02:01:12 INFO - 'tests': ['damp', 'tps']},
02:01:12 INFO - 'g3': {'talos_options': ['--disable-e10s'],
02:01:12 INFO - 'tests': ['dromaeo_dom']},
02:01:12 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']},
02:01:12 INFO - 'g4': {'talos_options': ['--disable-e10s'],
02:01:12 INFO - 'tests': ['basic_compositor_video']},
02:01:12 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']},
02:01:12 INFO - 'other': {'talos_options': ['--disable-e10s'],
02:01:12 INFO - 'tests': ['a11yr',
02:01:12 INFO - 'ts_paint',
02:01:12 INFO - 'tpaint',
02:01:12 INFO - 'sessionrestore',
02:01:12 INFO - 'sessionrestore_no_auto_restore',
02:01:12 INFO - 'tabpaint']},
02:01:12 INFO - 'other-e10s': {'tests': ['a11yr',
02:01:12 INFO - 'ts_paint',
02:01:12 INFO - 'tpaint',
02:01:12 INFO - 'sessionrestore',
02:01:12 INFO - 'sessionrestore_no_auto_restore',
02:01:12 INFO - 'tabpaint']},
02:01:12 INFO - 'other-e10s_l64': {'tests': ['a11yr',
02:01:12 INFO - 'ts_paint',
02:01:12 INFO - 'tpaint',
02:01:12 INFO - 'sessionrestore',
02:01:12 INFO - 'sessionrestore_no_auto_restore',
02:01:12 INFO - 'tabpaint']},
02:01:12 INFO - 'other-e10s_nol64': {'tests': ['a11yr',
02:01:12 INFO - 'ts_paint',
02:01:12 INFO - 'tpaint',
02:01:12 INFO - 'sessionrestore',
02:01:12 INFO - 'sessionrestore_no_auto_restore',
02:01:12 INFO - 'tabpaint']},
02:01:12 INFO - 'other_l64': {'talos_options': ['--disable-e10s'],
02:01:12 INFO - 'tests': ['a11yr',
02:01:12 INFO - 'ts_paint',
02:01:12 INFO - 'tpaint',
02:01:12 INFO - 'sessionrestore',
02:01:12 INFO - 'sessionrestore_no_auto_restore',
02:01:12 INFO - 'tabpaint']},
02:01:12 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'],
02:01:12 INFO - 'tests': ['a11yr',
02:01:12 INFO - 'ts_paint',
02:01:12 INFO - 'tpaint',
02:01:12 INFO - 'sessionrestore',
02:01:12 INFO - 'sessionrestore_no_auto_restore',
02:01:12 INFO - 'tabpaint']},
02:01:12 INFO - 'svgr': {'talos_options': ['--disable-e10s'],
02:01:12 INFO - 'tests': ['tsvgx',
02:01:12 INFO - 'tsvgr_opacity',
02:01:12 INFO - 'tart',
02:01:12 INFO - 'tscrollx',
02:01:12 INFO - 'cart',
02:01:12 INFO - 'tsvg_static']},
02:01:12 INFO - 'svgr-e10s': {'tests': ['tsvgx',
02:01:12 INFO - 'tsvgr_opacity',
02:01:12 INFO - 'tart',
02:01:12 INFO - 'tscrollx',
02:01:12 INFO - 'cart',
02:01:12 INFO - 'tsvg_static']},
02:01:12 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip',
02:01:12 INFO - 'talos_options': ['--disable-e10s'],
02:01:12 INFO - 'tests': ['tp5o']},
02:01:12 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']},
02:01:12 INFO - 'xperf': {'pagesets_name': 'tp5n.zip',
02:01:12 INFO - 'talos_options': ['--disable-e10s',
02:01:12 INFO - '--xperf_path',
02:01:12 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'],
02:01:12 INFO - 'tests': ['tp5n']},
02:01:12 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip',
02:01:12 INFO - 'talos_options': ['--xperf_path',
02:01:12 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'],
02:01:12 INFO - 'tests': ['tp5n']}},
02:01:12 INFO - 'talos.zip': {'path': '',
02:01:12 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}}
02:01:12 INFO - Running post-action listener: _resource_record_post_action
02:01:12 INFO - [mozharness: 2017-03-28 09:01:12.317636Z] Finished populate-webroot step (success)
02:01:12 INFO - [mozharness: 2017-03-28 09:01:12.317776Z] Running create-virtualenv step.
02:01:12 INFO - Running pre-action listener: _resource_record_pre_action
02:01:12 INFO - Running main action method: create_virtualenv
02:01:12 INFO - Creating virtualenv /builds/slave/test/build/venv
02:01:12 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
02:01:12 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv
02:01:12 INFO - The --no-site-packages flag is deprecated; it is now the default behavior.
02:01:12 INFO - Using real prefix '/tools/python27'
02:01:12 INFO - New python executable in /builds/slave/test/build/venv/bin/python
02:01:13 INFO - Installing distribute.............................................................................................................................................................................................done.
02:01:16 INFO - Installing pip.................done.
02:01:16 INFO - Return code: 0
02:01:16 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version']
02:01:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version
02:01:16 INFO - Reading from file tmpfile_stdout
02:01:16 INFO - Output received:
02:01:16 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)
02:01:16 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv
02:01: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')]}
02:01:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
02:01:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub
02:01: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')]}
02:01:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
02:01:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub
02:01:16 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 0x1006ffb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100702768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa512d76a10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1004ddd30>, '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 0x100404db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1004d2be8>, '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.0VMVrWf0XM/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.YjIGNKBMXi/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9c/2dh985pd15l19fywb0pq404000000w/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
02:01:16 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
02:01:16 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
02:01:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0VMVrWf0XM/Render',
02:01:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared',
02:01:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared',
02:01:16 INFO - 'HOME': '/Users/cltbld',
02:01:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true',
02:01:16 INFO - 'LOGNAME': 'cltbld',
02:01:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
02:01:16 INFO - 'MOZ_NO_REMOTE': '1',
02:01:16 INFO - 'NO_EM_RESTART': '1',
02:01:16 INFO - 'PAGER': '/bin/cat',
02:01:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11',
02:01:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
02:01:16 INFO - 'PWD': '/builds/slave/test',
02:01:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg',
02:01:16 INFO - 'SHELL': '/bin/bash',
02:01:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YjIGNKBMXi/Listeners',
02:01:16 INFO - 'TMPDIR': '/var/folders/9c/2dh985pd15l19fywb0pq404000000w/T/',
02:01:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log',
02:01:16 INFO - 'USER': 'cltbld',
02:01:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no',
02:01:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7',
02:01:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
02:01:16 INFO - 'XPC_FLAGS': '0x0',
02:01:16 INFO - 'XPC_SERVICE_NAME': '0',
02:01:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'}
02:01:16 INFO - Ignoring indexes: https://pypi.python.org/simple/
02:01:16 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
02:01:16 INFO - Cleaning up...
02:01:16 INFO - Return code: 0
02:01:16 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv
02:01: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')]}
02:01:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
02:01:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub
02:01: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')]}
02:01:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
02:01:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub
02:01:16 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 0x1006ffb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100702768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa512d76a10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1004ddd30>, '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 0x100404db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1004d2be8>, '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.0VMVrWf0XM/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.YjIGNKBMXi/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9c/2dh985pd15l19fywb0pq404000000w/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
02:01:16 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
02:01:16 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
02:01:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0VMVrWf0XM/Render',
02:01:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared',
02:01:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared',
02:01:16 INFO - 'HOME': '/Users/cltbld',
02:01:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true',
02:01:16 INFO - 'LOGNAME': 'cltbld',
02:01:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
02:01:16 INFO - 'MOZ_NO_REMOTE': '1',
02:01:16 INFO - 'NO_EM_RESTART': '1',
02:01:16 INFO - 'PAGER': '/bin/cat',
02:01:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11',
02:01:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
02:01:16 INFO - 'PWD': '/builds/slave/test',
02:01:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg',
02:01:16 INFO - 'SHELL': '/bin/bash',
02:01:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YjIGNKBMXi/Listeners',
02:01:16 INFO - 'TMPDIR': '/var/folders/9c/2dh985pd15l19fywb0pq404000000w/T/',
02:01:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log',
02:01:16 INFO - 'USER': 'cltbld',
02:01:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no',
02:01:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7',
02:01:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
02:01:16 INFO - 'XPC_FLAGS': '0x0',
02:01:16 INFO - 'XPC_SERVICE_NAME': '0',
02:01:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'}
02:01:16 INFO - Ignoring indexes: https://pypi.python.org/simple/
02:01:16 INFO - Downloading/unpacking psutil>=3.1.1
02:01: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
02:01: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
02:01: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
02:01: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
02:01: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
02:01: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
02:01:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil
02:01:20 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build'
02:01:20 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects
...
02:01:20 INFO - Installing collected packages: psutil
02:01:20 INFO - Running setup.py install for psutil
02:01:20 INFO - building 'psutil._psutil_osx' extension
02:01:20 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
02:01:20 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
02:01:20 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
02:01:20 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
02:01:21 INFO - building 'psutil._psutil_posix' extension
02:01:21 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
02:01:21 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration]
02:01:21 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr);
02:01:21 INFO - ^
02:01:21 INFO - 1 warning generated.
02:01:21 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
02:01:21 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build'
02:01:21 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ...
02:01:21 INFO - Successfully installed psutil
02:01:21 INFO - Cleaning up...
02:01:21 INFO - Return code: 0
02:01:21 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv
02:01:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
02:01:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
02:01:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub
02:01:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
02:01:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
02:01:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub
02:01:21 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 0x1006ffb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100702768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa512d76a10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1004ddd30>, '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 0x100404db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1004d2be8>, '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.0VMVrWf0XM/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.YjIGNKBMXi/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9c/2dh985pd15l19fywb0pq404000000w/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
02:01:21 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
02:01:21 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
02:01:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0VMVrWf0XM/Render',
02:01:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared',
02:01:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared',
02:01:21 INFO - 'HOME': '/Users/cltbld',
02:01:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true',
02:01:21 INFO - 'LOGNAME': 'cltbld',
02:01:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
02:01:21 INFO - 'MOZ_NO_REMOTE': '1',
02:01:21 INFO - 'NO_EM_RESTART': '1',
02:01:21 INFO - 'PAGER': '/bin/cat',
02:01:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11',
02:01:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
02:01:21 INFO - 'PWD': '/builds/slave/test',
02:01:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg',
02:01:21 INFO - 'SHELL': '/bin/bash',
02:01:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YjIGNKBMXi/Listeners',
02:01:21 INFO - 'TMPDIR': '/var/folders/9c/2dh985pd15l19fywb0pq404000000w/T/',
02:01:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log',
02:01:21 INFO - 'USER': 'cltbld',
02:01:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no',
02:01:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7',
02:01:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
02:01:21 INFO - 'XPC_FLAGS': '0x0',
02:01:21 INFO - 'XPC_SERVICE_NAME': '0',
02:01:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'}
02:01:21 INFO - Ignoring indexes: https://pypi.python.org/simple/
02:01:21 INFO - Downloading/unpacking mozsystemmonitor==0.3
02:01:21 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
02:01:21 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
02:01:21 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
02:01:21 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
02:01:21 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
02:01:21 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
02:01:24 INFO - Downloading mozsystemmonitor-0.3.tar.gz
02:01:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor
02:01:24 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3)
02:01:24 INFO - Installing collected packages: mozsystemmonitor
02:01:24 INFO - Running setup.py install for mozsystemmonitor
02:01:24 INFO - Successfully installed mozsystemmonitor
02:01:24 INFO - Cleaning up...
02:01:24 INFO - Return code: 0
02:01:24 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv
02:01:24 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')]}
02:01:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
02:01:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub
02:01:24 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')]}
02:01:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
02:01:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub
02:01:24 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 0x1006ffb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100702768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa512d76a10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1004ddd30>, '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 0x100404db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1004d2be8>, '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.0VMVrWf0XM/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.YjIGNKBMXi/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9c/2dh985pd15l19fywb0pq404000000w/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
02:01:24 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
02:01:24 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
02:01:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0VMVrWf0XM/Render',
02:01:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared',
02:01:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared',
02:01:24 INFO - 'HOME': '/Users/cltbld',
02:01:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true',
02:01:24 INFO - 'LOGNAME': 'cltbld',
02:01:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
02:01:24 INFO - 'MOZ_NO_REMOTE': '1',
02:01:24 INFO - 'NO_EM_RESTART': '1',
02:01:24 INFO - 'PAGER': '/bin/cat',
02:01:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11',
02:01:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
02:01:24 INFO - 'PWD': '/builds/slave/test',
02:01:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg',
02:01:24 INFO - 'SHELL': '/bin/bash',
02:01:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YjIGNKBMXi/Listeners',
02:01:24 INFO - 'TMPDIR': '/var/folders/9c/2dh985pd15l19fywb0pq404000000w/T/',
02:01:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log',
02:01:24 INFO - 'USER': 'cltbld',
02:01:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no',
02:01:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7',
02:01:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
02:01:24 INFO - 'XPC_FLAGS': '0x0',
02:01:24 INFO - 'XPC_SERVICE_NAME': '0',
02:01:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'}
02:01:24 INFO - Ignoring indexes: https://pypi.python.org/simple/
02:01:24 INFO - Downloading/unpacking jsonschema==2.5.1
02:01:24 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
02:01:24 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
02:01:24 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
02:01:24 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
02:01:24 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
02:01:24 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
02:01:27 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl
02:01:27 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1)
02:01:27 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
02:01:27 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
02:01:27 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
02:01:27 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
02:01:27 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
02:01:27 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
02:01:27 INFO - Downloading functools32-3.2.3-2.tar.gz
02:01:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32
02:01:27 INFO - warning: no files found matching '*.txt'
02:01:27 INFO - no previously-included directories found matching 'build'
02:01:27 INFO - no previously-included directories found matching 'dist'
02:01:27 INFO - no previously-included directories found matching '.git*'
02:01:27 INFO - Installing collected packages: jsonschema, functools32
02:01:27 INFO - Running setup.py install for functools32
02:01:28 INFO - warning: no files found matching '*.txt'
02:01:28 INFO - no previously-included directories found matching 'build'
02:01:28 INFO - no previously-included directories found matching 'dist'
02:01:28 INFO - no previously-included directories found matching '.git*'
02:01:28 INFO - Successfully installed jsonschema functools32
02:01:28 INFO - Cleaning up...
02:01:28 INFO - Return code: 0
02:01:28 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv
02:01:28 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')]}
02:01:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
02:01:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub
02:01:28 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')]}
02:01:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
02:01:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub
02:01:29 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 0x1006ffb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100702768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa512d76a10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1004ddd30>, '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 0x100404db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1004d2be8>, '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.0VMVrWf0XM/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.YjIGNKBMXi/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9c/2dh985pd15l19fywb0pq404000000w/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
02:01:29 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
02:01:29 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
02:01:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0VMVrWf0XM/Render',
02:01:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared',
02:01:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared',
02:01:29 INFO - 'HOME': '/Users/cltbld',
02:01:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true',
02:01:29 INFO - 'LOGNAME': 'cltbld',
02:01:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
02:01:29 INFO - 'MOZ_NO_REMOTE': '1',
02:01:29 INFO - 'NO_EM_RESTART': '1',
02:01:29 INFO - 'PAGER': '/bin/cat',
02:01:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11',
02:01:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
02:01:29 INFO - 'PWD': '/builds/slave/test',
02:01:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg',
02:01:29 INFO - 'SHELL': '/bin/bash',
02:01:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YjIGNKBMXi/Listeners',
02:01:29 INFO - 'TMPDIR': '/var/folders/9c/2dh985pd15l19fywb0pq404000000w/T/',
02:01:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log',
02:01:29 INFO - 'USER': 'cltbld',
02:01:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no',
02:01:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7',
02:01:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
02:01:29 INFO - 'XPC_FLAGS': '0x0',
02:01:29 INFO - 'XPC_SERVICE_NAME': '0',
02:01:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'}
02:01:29 INFO - Ignoring indexes: https://pypi.python.org/simple/
02:01:29 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages
02:01:29 INFO - Cleaning up...
02:01:29 INFO - Return code: 0
02:01:29 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv
02:01:29 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')]}
02:01:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
02:01:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub
02:01:29 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')]}
02:01:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
02:01:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub
02:01:29 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 0x1006ffb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100702768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa512d76a10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1004ddd30>, '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 0x100404db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1004d2be8>, '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.0VMVrWf0XM/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.YjIGNKBMXi/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9c/2dh985pd15l19fywb0pq404000000w/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
02:01:29 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
02:01:29 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
02:01:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0VMVrWf0XM/Render',
02:01:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared',
02:01:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared',
02:01:29 INFO - 'HOME': '/Users/cltbld',
02:01:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true',
02:01:29 INFO - 'LOGNAME': 'cltbld',
02:01:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
02:01:29 INFO - 'MOZ_NO_REMOTE': '1',
02:01:29 INFO - 'NO_EM_RESTART': '1',
02:01:29 INFO - 'PAGER': '/bin/cat',
02:01:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11',
02:01:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
02:01:29 INFO - 'PWD': '/builds/slave/test',
02:01:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg',
02:01:29 INFO - 'SHELL': '/bin/bash',
02:01:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YjIGNKBMXi/Listeners',
02:01:29 INFO - 'TMPDIR': '/var/folders/9c/2dh985pd15l19fywb0pq404000000w/T/',
02:01:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log',
02:01:29 INFO - 'USER': 'cltbld',
02:01:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no',
02:01:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7',
02:01:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
02:01:29 INFO - 'XPC_FLAGS': '0x0',
02:01:29 INFO - 'XPC_SERVICE_NAME': '0',
02:01:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'}
02:01:30 INFO - Ignoring indexes: https://pypi.python.org/simple/
02:01:30 INFO - Downloading/unpacking blobuploader==1.2.4
02:01:30 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
02:01:30 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
02:01:30 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
02:01:30 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
02:01:30 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
02:01:30 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
02:01:33 INFO - Downloading blobuploader-1.2.4.tar.gz
02:01:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader
02:01:33 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4)
02:01:33 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
02:01:33 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
02:01:33 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
02:01:33 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
02:01:33 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
02:01:33 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
02:01:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests
02:01:33 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4)
02:01:33 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
02:01:33 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
02:01:33 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
02:01:33 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
02:01:33 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
02:01:33 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
02:01:34 INFO - Downloading docopt-0.6.1.tar.gz
02:01:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt
02:01:34 INFO - Installing collected packages: blobuploader, requests, docopt
02:01:34 INFO - Running setup.py install for blobuploader
02:01:34 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775
02:01:34 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775
02:01:34 INFO - Running setup.py install for requests
02:01:35 INFO - Running setup.py install for docopt
02:01:35 INFO - Successfully installed blobuploader requests docopt
02:01:35 INFO - Cleaning up...
02:01:35 INFO - Return code: 0
02:01:35 INFO - Installing None into virtualenv /builds/slave/test/build/venv
02:01:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
02:01:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
02:01:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub
02:01:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
02:01:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
02:01:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub
02:01:35 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 0x1006ffb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100702768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa512d76a10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1004ddd30>, '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 0x100404db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1004d2be8>, '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.0VMVrWf0XM/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.YjIGNKBMXi/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9c/2dh985pd15l19fywb0pq404000000w/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
02:01:35 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
02:01:35 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
02:01:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0VMVrWf0XM/Render',
02:01:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared',
02:01:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared',
02:01:35 INFO - 'HOME': '/Users/cltbld',
02:01:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true',
02:01:35 INFO - 'LOGNAME': 'cltbld',
02:01:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
02:01:35 INFO - 'MOZ_NO_REMOTE': '1',
02:01:35 INFO - 'NO_EM_RESTART': '1',
02:01:35 INFO - 'PAGER': '/bin/cat',
02:01:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11',
02:01:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
02:01:35 INFO - 'PWD': '/builds/slave/test',
02:01:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg',
02:01:35 INFO - 'SHELL': '/bin/bash',
02:01:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YjIGNKBMXi/Listeners',
02:01:35 INFO - 'TMPDIR': '/var/folders/9c/2dh985pd15l19fywb0pq404000000w/T/',
02:01:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log',
02:01:35 INFO - 'USER': 'cltbld',
02:01:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no',
02:01:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7',
02:01:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
02:01:35 INFO - 'XPC_FLAGS': '0x0',
02:01:35 INFO - 'XPC_SERVICE_NAME': '0',
02:01:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'}
02:01:35 INFO - Ignoring indexes: https://pypi.python.org/simple/
02:01:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser
02:01:35 INFO - Running setup.py (path:/var/folders/9c/2dh985pd15l19fywb0pq404000000w/T/pip-Dlst2a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser
02:01:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash
02:01:35 INFO - Running setup.py (path:/var/folders/9c/2dh985pd15l19fywb0pq404000000w/T/pip-9_pwwG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash
02:01:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug
02:01:35 INFO - Running setup.py (path:/var/folders/9c/2dh985pd15l19fywb0pq404000000w/T/pip-PHnqhU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug
02:01:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice
02:01:35 INFO - Running setup.py (path:/var/folders/9c/2dh985pd15l19fywb0pq404000000w/T/pip-jItK42-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice
02:01:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile
02:01:35 INFO - Running setup.py (path:/var/folders/9c/2dh985pd15l19fywb0pq404000000w/T/pip-f3ks7t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile
02:01:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd
02:01:36 INFO - Running setup.py (path:/var/folders/9c/2dh985pd15l19fywb0pq404000000w/T/pip-CWdi2g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd
02:01:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo
02:01:36 INFO - Running setup.py (path:/var/folders/9c/2dh985pd15l19fywb0pq404000000w/T/pip-52ow1M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo
02:01:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall
02:01:36 INFO - Running setup.py (path:/var/folders/9c/2dh985pd15l19fywb0pq404000000w/T/pip-1qWnLG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall
02:01:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak
02:01:36 INFO - Running setup.py (path:/var/folders/9c/2dh985pd15l19fywb0pq404000000w/T/pip-QijTIi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak
02:01:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog
02:01:36 INFO - Running setup.py (path:/var/folders/9c/2dh985pd15l19fywb0pq404000000w/T/pip-kT6mYn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog
02:01:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork
02:01:36 INFO - Running setup.py (path:/var/folders/9c/2dh985pd15l19fywb0pq404000000w/T/pip-RxrRMR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork
02:01:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess
02:01:36 INFO - Running setup.py (path:/var/folders/9c/2dh985pd15l19fywb0pq404000000w/T/pip-faQppk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess
02:01:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile
02:01:36 INFO - Running setup.py (path:/var/folders/9c/2dh985pd15l19fywb0pq404000000w/T/pip-yd7GUt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile
02:01:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner
02:01:36 INFO - Running setup.py (path:/var/folders/9c/2dh985pd15l19fywb0pq404000000w/T/pip-RwIEAD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner
02:01:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot
02:01:37 INFO - Running setup.py (path:/var/folders/9c/2dh985pd15l19fywb0pq404000000w/T/pip-SubKGl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot
02:01:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest
02:01:37 INFO - Running setup.py (path:/var/folders/9c/2dh985pd15l19fywb0pq404000000w/T/pip-PcwPso-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest
02:01:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion
02:01:37 INFO - Running setup.py (path:/var/folders/9c/2dh985pd15l19fywb0pq404000000w/T/pip-KuyuR2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion
02:01:37 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion
02:01:37 INFO - Running setup.py install for manifestparser
02:01:37 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin
02:01:37 INFO - Running setup.py install for mozcrash
02:01:37 INFO - Running setup.py install for mozdebug
02:01:37 INFO - Running setup.py install for mozdevice
02:01:37 INFO - Installing sutini script to /builds/slave/test/build/venv/bin
02:01:37 INFO - Installing dm script to /builds/slave/test/build/venv/bin
02:01:37 INFO - Running setup.py install for mozfile
02:01:38 INFO - Running setup.py install for mozhttpd
02:01:38 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin
02:01:38 INFO - Running setup.py install for mozinfo
02:01:38 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin
02:01:38 INFO - Running setup.py install for mozInstall
02:01:38 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin
02:01:38 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin
02:01:38 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin
02:01:38 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin
02:01:38 INFO - Running setup.py install for mozleak
02:01:38 INFO - Running setup.py install for mozlog
02:01:38 INFO - Installing structlog script to /builds/slave/test/build/venv/bin
02:01:38 INFO - Running setup.py install for moznetwork
02:01:38 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin
02:01:38 INFO - Running setup.py install for mozprocess
02:01:39 INFO - Running setup.py install for mozprofile
02:01:39 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin
02:01:39 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin
02:01:39 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin
02:01:39 INFO - Running setup.py install for mozrunner
02:01:39 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin
02:01:39 INFO - Running setup.py install for mozscreenshot
02:01:39 INFO - Running setup.py install for moztest
02:01:39 INFO - Running setup.py install for mozversion
02:01:39 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin
02:01:39 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion
02:01:39 INFO - Cleaning up...
02:01:39 INFO - Return code: 0
02:01:39 INFO - Installing None into virtualenv /builds/slave/test/build/venv
02:01:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
02:01:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
02:01:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub
02:01:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
02:01:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
02:01:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub
02:01:39 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 0x1006ffb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100702768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa512d76a10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1004ddd30>, '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 0x100404db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1004d2be8>, '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.0VMVrWf0XM/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.YjIGNKBMXi/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9c/2dh985pd15l19fywb0pq404000000w/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
02:01:39 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
02:01:39 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
02:01:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0VMVrWf0XM/Render',
02:01:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared',
02:01:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared',
02:01:39 INFO - 'HOME': '/Users/cltbld',
02:01:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true',
02:01:39 INFO - 'LOGNAME': 'cltbld',
02:01:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
02:01:39 INFO - 'MOZ_NO_REMOTE': '1',
02:01:39 INFO - 'NO_EM_RESTART': '1',
02:01:39 INFO - 'PAGER': '/bin/cat',
02:01:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11',
02:01:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
02:01:39 INFO - 'PWD': '/builds/slave/test',
02:01:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg',
02:01:39 INFO - 'SHELL': '/bin/bash',
02:01:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YjIGNKBMXi/Listeners',
02:01:39 INFO - 'TMPDIR': '/var/folders/9c/2dh985pd15l19fywb0pq404000000w/T/',
02:01:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log',
02:01:39 INFO - 'USER': 'cltbld',
02:01:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no',
02:01:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7',
02:01:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
02:01:39 INFO - 'XPC_FLAGS': '0x0',
02:01:39 INFO - 'XPC_SERVICE_NAME': '0',
02:01:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'}
02:01:40 INFO - Ignoring indexes: https://pypi.python.org/simple/
02:01:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser
02:01:40 INFO - Running setup.py (path:/var/folders/9c/2dh985pd15l19fywb0pq404000000w/T/pip-c_9lhZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser
02:01:40 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))
02:01:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash
02:01:40 INFO - Running setup.py (path:/var/folders/9c/2dh985pd15l19fywb0pq404000000w/T/pip-0xf5WU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash
02:01:40 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))
02:01:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug
02:01:40 INFO - Running setup.py (path:/var/folders/9c/2dh985pd15l19fywb0pq404000000w/T/pip-uvILVc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug
02:01:40 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))
02:01:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice
02:01:40 INFO - Running setup.py (path:/var/folders/9c/2dh985pd15l19fywb0pq404000000w/T/pip-c97XxP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice
02:01:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 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))
02:01:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile
02:01:40 INFO - Running setup.py (path:/var/folders/9c/2dh985pd15l19fywb0pq404000000w/T/pip-22GN9E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile
02:01:40 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))
02:01:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd
02:01:40 INFO - Running setup.py (path:/var/folders/9c/2dh985pd15l19fywb0pq404000000w/T/pip-L4WHmq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd
02:01:40 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))
02:01:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo
02:01:40 INFO - Running setup.py (path:/var/folders/9c/2dh985pd15l19fywb0pq404000000w/T/pip-48q2TX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo
02:01:40 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))
02:01:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall
02:01:40 INFO - Running setup.py (path:/var/folders/9c/2dh985pd15l19fywb0pq404000000w/T/pip-rI57yf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall
02:01:41 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))
02:01:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak
02:01:41 INFO - Running setup.py (path:/var/folders/9c/2dh985pd15l19fywb0pq404000000w/T/pip-CqG2mT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak
02:01:41 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))
02:01:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog
02:01:41 INFO - Running setup.py (path:/var/folders/9c/2dh985pd15l19fywb0pq404000000w/T/pip-FiicHC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog
02:01:41 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))
02:01:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork
02:01:41 INFO - Running setup.py (path:/var/folders/9c/2dh985pd15l19fywb0pq404000000w/T/pip-rVyvvA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork
02:01:41 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))
02:01:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess
02:01:41 INFO - Running setup.py (path:/var/folders/9c/2dh985pd15l19fywb0pq404000000w/T/pip-JAdDVE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess
02:01:41 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))
02:01:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile
02:01:41 INFO - Running setup.py (path:/var/folders/9c/2dh985pd15l19fywb0pq404000000w/T/pip-2rlxBH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile
02:01:41 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))
02:01:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner
02:01:41 INFO - Running setup.py (path:/var/folders/9c/2dh985pd15l19fywb0pq404000000w/T/pip-zfNUnT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner
02:01:41 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))
02:01:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot
02:01:41 INFO - Running setup.py (path:/var/folders/9c/2dh985pd15l19fywb0pq404000000w/T/pip-mZLEAg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot
02:01:41 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))
02:01:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest
02:01:41 INFO - Running setup.py (path:/var/folders/9c/2dh985pd15l19fywb0pq404000000w/T/pip-lGHxCa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest
02:01:41 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))
02:01:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion
02:01:41 INFO - Running setup.py (path:/var/folders/9c/2dh985pd15l19fywb0pq404000000w/T/pip-3m5O0B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion
02:01:42 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))
02:01:42 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))
02:01:42 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))
02:01:42 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))
02:01:42 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.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4))
02:01:42 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.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4))
02:01:42 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10))
02:01:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available
02:01:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available
02:01:42 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available
02:01:42 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available
02:01:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available
02:01:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available
02:01:44 INFO - Downloading blessings-1.6.tar.gz
02:01:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings
02:01:44 INFO - Installing collected packages: blessings
02:01:44 INFO - Running setup.py install for blessings
02:01:44 INFO - Successfully installed blessings
02:01:44 INFO - Cleaning up...
02:01:45 INFO - Return code: 0
02:01:45 INFO - Done creating virtualenv /builds/slave/test/build/venv.
02:01:45 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze']
02:01:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze
02:01:45 INFO - Reading from file tmpfile_stdout
02:01:45 INFO - Current package versions:
02:01:45 INFO - blessings == 1.6
02:01:45 INFO - blobuploader == 1.2.4
02:01:45 INFO - docopt == 0.6.1
02:01:45 INFO - functools32 == 3.2.3-2
02:01:45 INFO - jsonschema == 2.5.1
02:01:45 INFO - manifestparser == 1.1
02:01:45 INFO - mozInstall == 1.12
02:01:45 INFO - mozcrash == 1.0
02:01:45 INFO - mozdebug == 0.1
02:01:45 INFO - mozdevice == 0.49
02:01:45 INFO - mozfile == 1.2
02:01:45 INFO - mozhttpd == 0.7
02:01:45 INFO - mozinfo == 0.9
02:01:45 INFO - mozleak == 0.1
02:01:45 INFO - mozlog == 3.4
02:01:45 INFO - moznetwork == 0.27
02:01:45 INFO - mozprocess == 0.25
02:01:45 INFO - mozprofile == 0.28
02:01:45 INFO - mozrunner == 6.13
02:01:45 INFO - mozscreenshot == 0.1
02:01:45 INFO - mozsystemmonitor == 0.3
02:01:45 INFO - moztest == 0.8
02:01:45 INFO - mozversion == 1.4
02:01:45 INFO - psutil == 3.1.1
02:01:45 INFO - requests == 1.2.3
02:01:45 INFO - wsgiref == 0.1.2
02:01:45 INFO - Installing None into virtualenv /builds/slave/test/build/venv
02:01:45 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')]}
02:01:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
02:01:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub
02:01:45 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')]}
02:01:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
02:01:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub
02:01:45 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 0x1006ffb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100702768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa512d76a10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1004ddd30>, '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 0x100404db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1004d2be8>, '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.0VMVrWf0XM/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.YjIGNKBMXi/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9c/2dh985pd15l19fywb0pq404000000w/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
02:01:45 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
02:01:45 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
02:01:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0VMVrWf0XM/Render',
02:01:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared',
02:01:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared',
02:01:45 INFO - 'HOME': '/Users/cltbld',
02:01:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true',
02:01:45 INFO - 'LOGNAME': 'cltbld',
02:01:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
02:01:45 INFO - 'MOZ_NO_REMOTE': '1',
02:01:45 INFO - 'NO_EM_RESTART': '1',
02:01:45 INFO - 'PAGER': '/bin/cat',
02:01:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11',
02:01:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
02:01:45 INFO - 'PWD': '/builds/slave/test',
02:01:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg',
02:01:45 INFO - 'SHELL': '/bin/bash',
02:01:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YjIGNKBMXi/Listeners',
02:01:45 INFO - 'TMPDIR': '/var/folders/9c/2dh985pd15l19fywb0pq404000000w/T/',
02:01:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log',
02:01:45 INFO - 'USER': 'cltbld',
02:01:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no',
02:01:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7',
02:01:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
02:01:45 INFO - 'XPC_FLAGS': '0x0',
02:01:45 INFO - 'XPC_SERVICE_NAME': '0',
02:01:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'}
02:01:45 INFO - Ignoring indexes: https://pypi.python.org/simple/
02:01:45 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))
02:01:45 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))
02:01:45 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))
02:01:45 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))
02:01:45 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))
02:01:45 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))
02:01:45 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))
02:01:45 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))
02:01:45 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))
02:01:45 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))
02:01:45 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))
02:01:45 INFO - Cleaning up...
02:01:45 INFO - Return code: 0
02:01:45 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv
02:01:45 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')]}
02:01:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
02:01:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub
02:01:45 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')]}
02:01:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
02:01:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub
02:01:45 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 0x1006ffb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100702768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa512d76a10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1004ddd30>, '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 0x100404db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1004d2be8>, '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.0VMVrWf0XM/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.YjIGNKBMXi/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9c/2dh985pd15l19fywb0pq404000000w/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
02:01:45 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
02:01:45 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
02:01:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0VMVrWf0XM/Render',
02:01:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared',
02:01:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared',
02:01:45 INFO - 'HOME': '/Users/cltbld',
02:01:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true',
02:01:45 INFO - 'LOGNAME': 'cltbld',
02:01:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
02:01:45 INFO - 'MOZ_NO_REMOTE': '1',
02:01:45 INFO - 'NO_EM_RESTART': '1',
02:01:45 INFO - 'PAGER': '/bin/cat',
02:01:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11',
02:01:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
02:01:45 INFO - 'PWD': '/builds/slave/test',
02:01:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg',
02:01:45 INFO - 'SHELL': '/bin/bash',
02:01:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YjIGNKBMXi/Listeners',
02:01:45 INFO - 'TMPDIR': '/var/folders/9c/2dh985pd15l19fywb0pq404000000w/T/',
02:01:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log',
02:01:45 INFO - 'USER': 'cltbld',
02:01:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no',
02:01:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7',
02:01:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
02:01:45 INFO - 'XPC_FLAGS': '0x0',
02:01:45 INFO - 'XPC_SERVICE_NAME': '0',
02:01:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'}
02:01:45 INFO - Ignoring indexes: https://pypi.python.org/simple/
02:01:45 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages
02:01:45 INFO - Cleaning up...
02:01:45 INFO - Return code: 0
02:01:45 INFO - Running post-action listener: _resource_record_post_action
02:01:45 INFO - Running post-action listener: _start_resource_monitoring
02:01:45 INFO - Starting resource monitoring.
02:01:45 INFO - [mozharness: 2017-03-28 09:01:45.890560Z] Finished create-virtualenv step (success)
02:01:45 INFO - [mozharness: 2017-03-28 09:01:45.891562Z] Running install step.
02:01:45 INFO - Running pre-action listener: _resource_record_pre_action
02:01:45 INFO - Running main action method: install
02:01:45 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze']
02:01:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze
02:01:46 INFO - Reading from file tmpfile_stdout
02:01:46 INFO - Detecting whether we're running mozinstall >=1.0...
02:01:46 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h']
02:01:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h
02:01:46 INFO - Reading from file tmpfile_stdout
02:01:46 INFO - Output received:
02:01:46 INFO - Usage: mozinstall [options] installer
02:01:46 INFO - Options:
02:01:46 INFO - -h, --help show this help message and exit
02:01:46 INFO - -d DEST, --destination=DEST
02:01:46 INFO - Directory to install application into. [default:
02:01:46 INFO - "/builds/slave/test"]
02:01:46 INFO - --app=APP Application being installed. [default: firefox]
02:01:46 INFO - mkdir: /builds/slave/test/build/application
02:01:46 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-54.0a2.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application']
02:01:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-54.0a2.en-US.mac.dmg --destination /builds/slave/test/build/application
02:02:06 INFO - Reading from file tmpfile_stdout
02:02:06 INFO - Output received:
02:02:06 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox
02:02:06 INFO - Running post-action listener: _resource_record_post_action
02:02:06 INFO - [mozharness: 2017-03-28 09:02:06.394681Z] Finished install step (success)
02:02:06 INFO - [mozharness: 2017-03-28 09:02:06.394824Z] Running run-tests step.
02:02:06 INFO - Running pre-action listener: _resource_record_pre_action
02:02:06 INFO - Running main action method: run_tests
02:02:06 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'
02:02:06 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build
02:02:06 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType"
02:02:06 INFO - 2017-03-28 02:02:06.409 screenresolution[1903:17683] starting screenresolution argv=screenresolution get
02:02:06 INFO - 2017-03-28 02:02:06.412 screenresolution[1903:17683] Display 0: 1600x1200x32@60
02:02:06 INFO - 2017-03-28 02:02:06.417 screenresolution[1904:17686] starting screenresolution argv=screenresolution list
02:02:06 INFO - Available Modes on Display 0
02:02:06 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75
02:02:06 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60
02:02:06 INFO - 800x600x32@75 800x600x32@60 640x480x32@75
02:02:06 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60
02:02:06 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays:
02:02:06 INFO - Intel Iris:
02:02:06 INFO - Chipset Model: Intel Iris
02:02:06 INFO - Type: GPU
02:02:06 INFO - Bus: Built-In
02:02:06 INFO - VRAM (Dynamic, Max): 1536 MB
02:02:06 INFO - Vendor: Intel (0x8086)
02:02:06 INFO - Device ID: 0x0a2e
02:02:06 INFO - Revision ID: 0x0009
02:02:06 INFO - Displays:
02:02:06 INFO - Display:
02:02:06 INFO - Resolution: 1600 x 1200 @ 60 Hz
02:02:06 INFO - Pixel Depth: 32-Bit Color (ARGB8888)
02:02:06 INFO - Display Serial Number: 1600x1200 60
02:02:06 INFO - Main Display: Yes
02:02:06 INFO - Mirror: Off
02:02:06 INFO - Online: Yes
02:02:06 INFO - Rotation: Supported
02:02:06 INFO - Return code: 0
02:02:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version']
02:02:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version
02:02:06 INFO - Python 2.7.3
02:02:06 INFO - Return code: 0
02:02:06 INFO - grabbing minidump binary from tooltool
02:02:06 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')]}
02:02:06 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 0x1004ddd30>, '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 0x100404db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1004d2be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1
02:02:06 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
02:02:06 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
02:02:06 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
02:02:06 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache
02:02:06 INFO - Return code: 0
02:02:06 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755
02:02:06 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir
02:02:06 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir
02:02:06 INFO - ENV: RUST_BACKTRACE is now 1
02:02:06 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk
02:02:06 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir
02:02:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/RLih5AzXS-mN1Qrsh7HNNQ/artifacts/public/build/firefox-54.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0060', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build
02:02:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/RLih5AzXS-mN1Qrsh7HNNQ/artifacts/public/build/firefox-54.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0060 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log
02:02:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0VMVrWf0XM/Render',
02:02:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared',
02:02:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared',
02:02:06 INFO - 'HOME': '/Users/cltbld',
02:02:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true',
02:02:06 INFO - 'LOGNAME': 'cltbld',
02:02:06 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir',
02:02:06 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk',
02:02:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
02:02:06 INFO - 'MOZ_NO_REMOTE': '1',
02:02:06 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir',
02:02:06 INFO - 'NO_EM_RESTART': '1',
02:02:06 INFO - 'PAGER': '/bin/cat',
02:02:06 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11',
02:02:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
02:02:06 INFO - 'PWD': '/builds/slave/test',
02:02:06 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos',
02:02:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg',
02:02:06 INFO - 'RUST_BACKTRACE': '1',
02:02:06 INFO - 'SHELL': '/bin/bash',
02:02:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YjIGNKBMXi/Listeners',
02:02:06 INFO - 'TMPDIR': '/var/folders/9c/2dh985pd15l19fywb0pq404000000w/T/',
02:02:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log',
02:02:06 INFO - 'USER': 'cltbld',
02:02:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no',
02:02:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7',
02:02:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
02:02:06 INFO - 'XPC_FLAGS': '0x0',
02:02:06 INFO - 'XPC_SERVICE_NAME': '0',
02:02:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'}
02:02:06 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/RLih5AzXS-mN1Qrsh7HNNQ/artifacts/public/build/firefox-54.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0060', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600
02:02:07 INFO - mozversion application_buildid: 20170328004003
02:02:07 INFO - mozversion application_changeset: db2babffcafbd6b5b47a8c5813005838eb171a90
02:02:07 INFO - mozversion application_display_name: FirefoxDeveloperEdition
02:02:07 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384}
02:02:07 INFO - mozversion application_name: Firefox
02:02:07 INFO - mozversion application_remotingname: firefox-dev
02:02:07 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora
02:02:07 INFO - mozversion application_vendor: Mozilla
02:02:07 INFO - mozversion application_version: 54.0a2
02:02:07 INFO - mozversion platform_buildid: 20170328004003
02:02:07 INFO - mozversion platform_changeset: db2babffcafbd6b5b47a8c5813005838eb171a90
02:02:07 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora
02:02:07 INFO - mozversion platform_version: 54.0a2
02:02:07 INFO - using testdate: 1490691727
02:02:07 INFO - actual date: 1490691727
02:02:07 INFO - starting webserver on 'localhost:54249'
02:02:07 INFO - SUITE-START | Running 2 tests
02:02:07 INFO - TEST-START | dromaeo_css
02:02:07 INFO - operating with platform_type : mac_
02:02:07 INFO - Initialising browser for dromaeo_css test...
02:02:07 INFO - TEST-INFO | started process 1914 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/9c/2dh985pd15l19fywb0pq404000000w/T/tmplfXIwl/profile http://localhost:54249/getInfo.html)
02:02:09 INFO - PROCESS | 1914 | 2017-03-28 02:02:09.881 plugin-container[1917:17853] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x903f, name = 'com.apple.tsm.portname'
02:02:09 INFO - PROCESS | 1914 | See /usr/include/servers/bootstrap_defs.h for the error codes.
02:02:09 INFO - PROCESS | 1914 | 2017-03-28 02:02:09.881 plugin-container[1917:17853] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x970b, name = 'com.apple.CFPasteboardClient'
02:02:09 INFO - PROCESS | 1914 | See /usr/include/servers/bootstrap_defs.h for the error codes.
02:02:09 INFO - PROCESS | 1914 | 2017-03-28 02:02:09.881 plugin-container[1917:17853] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions
02:02:09 INFO - PROCESS | 1914 | __metrics Screen width/height:1600/1200
02:02:09 INFO - PROCESS | 1914 | colorDepth:24
02:02:09 INFO - PROCESS | 1914 | Browser inner width/height: 1024/689
02:02:09 INFO - PROCESS | 1914 | __metrics
02:02:10 ERROR - PROCESS | 1914 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3353: TypeError: this.tabs is undefined
02:02:10 INFO - TEST-INFO | 1914: exit 0
02:02:10 INFO - Browser initialized.
02:02:10 INFO - Running cycle 1/1 for dromaeo_css test...
02:02:10 INFO - TEST-INFO | started process 1918 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/9c/2dh985pd15l19fywb0pq404000000w/T/tmplfXIwl/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1)
02:02:13 INFO - PROCESS | 1918 | 2017-03-28 02:02:13.407 plugin-container[1919:17986] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4547, name = 'com.apple.tsm.portname'
02:02:13 INFO - PROCESS | 1918 | See /usr/include/servers/bootstrap_defs.h for the error codes.
02:02:13 INFO - PROCESS | 1918 | 2017-03-28 02:02:13.408 plugin-container[1919:17986] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x900b, name = 'com.apple.CFPasteboardClient'
02:02:13 INFO - PROCESS | 1918 | See /usr/include/servers/bootstrap_defs.h for the error codes.
02:02:13 INFO - PROCESS | 1918 | 2017-03-28 02:02:13.408 plugin-container[1919:17986] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions
02:02:22 INFO - PROCESS | 1918 | RSS: Main: 220696576
02:02:22 INFO - PROCESS | 1918 |
02:05:25 INFO - PROCESS | 1918 | Cycle 1(1): loaded http://localhost:54249/tests/dromaeo/cssquery-dojo.html (next: http://localhost:54249/tests/dromaeo/cssquery-ext.html)
02:05:28 INFO - PROCESS | 1918 | RSS: Main: 238751744
02:05:28 INFO - PROCESS | 1918 |
02:07:11 INFO - PROCESS | 1918 | *************************
02:07:11 INFO - PROCESS | 1918 | A coding exception was thrown and uncaught in a Task.
02:07:11 INFO - PROCESS | 1918 |
02:07:11 ERROR - PROCESS | 1918 | Full message: TypeError: NetworkError when attempting to fetch resource.
02:07:11 INFO - PROCESS | 1918 | Full stack:
02:07:11 INFO - PROCESS | 1918 | *************************
02:08:30 INFO - PROCESS | 1918 | Cycle 1(1): loaded http://localhost:54249/tests/dromaeo/cssquery-ext.html (next: http://localhost:54249/tests/dromaeo/cssquery-jquery.html)
02:08:31 INFO - PROCESS | 1918 | RSS: Main: 249671680
02:08:31 INFO - PROCESS | 1918 |
02:11:33 INFO - PROCESS | 1918 | Cycle 1(1): loaded http://localhost:54249/tests/dromaeo/cssquery-jquery.html (next: http://localhost:54249/tests/dromaeo/cssquery-mootools.html)
02:11:34 INFO - PROCESS | 1918 | RSS: Main: 259129344
02:11:34 INFO - PROCESS | 1918 |
02:14:36 INFO - PROCESS | 1918 | Cycle 1(1): loaded http://localhost:54249/tests/dromaeo/cssquery-mootools.html (next: http://localhost:54249/tests/dromaeo/cssquery-prototype.html)
02:14:36 INFO - PROCESS | 1918 | RSS: Main: 255238144
02:14:36 INFO - PROCESS | 1918 |
02:17:39 INFO - PROCESS | 1918 | Cycle 1(1): loaded http://localhost:54249/tests/dromaeo/cssquery-prototype.html (next: http://localhost:54249/tests/dromaeo/cssquery-yui.html)
02:17:40 INFO - PROCESS | 1918 | RSS: Main: 260411392
02:17:40 INFO - PROCESS | 1918 |
02:20:43 INFO - PROCESS | 1918 | Cycle 1(1): loaded http://localhost:54249/tests/dromaeo/cssquery-yui.html (next: http://localhost:54249/tests/dromaeo/cssquery-dojo.html)
02:20:43 INFO - PROCESS | 1918 | RSS: Main: 256708608
02:20:43 INFO - PROCESS | 1918 |
02:20:43 INFO - PROCESS | 1918 | __start_tp_report
02:20:43 INFO - PROCESS | 1918 | _x_x_mozilla_page_load
02:20:43 INFO - PROCESS | 1918 | _x_x_mozilla_page_load_details
02:20:43 INFO - PROCESS | 1918 | |i|pagename|runs|
02:20:43 INFO - PROCESS | 1918 | |0;dojo.html;2886.89895853645;3000.309928716395;3468.1329667583104;3498.3702933471964;3537.5931767846696;4934.3585333906585;5156.561692256163;6056.333803281641;6125.908111378321;6136.723847426865;5905.704714764266;6100.176476175715;6140.232470941132;6163.167972323735;6179.938200617992;5435.537979271757;5494.560435165177;5518.255035570187;5571.220029195895;5590.273264475612;4535.274356103024;5553.028220061493;5554.86112847178;5563.499285064343;5579.776808927638;5357.651752636086;5382.865428364303;5591.30108736408;5594.832155035356;5621.269234999444;5457.372402173761;5464.043792336345;5467.5625949923915;5481.287432633772;5496.285482887199;5409.594280428983;5423.511883930465;5470.261514695505;5521.641093328921;5580.469855363734;6160.229971253593;6200.658963756991;6203;6222.968885155546;6238.750449981995;6196.070589411579;6265.530085243579;6454.515911306624;6489.740410383579;6500.804975850732;6342.365763423621;6415.1981002374705;6445.709943052574;6569.704363303663;6600.372964568358;6237.345078766755;6270.874582508415;6274.623522588659;6283.277423096311;6283.3088360280335;6641.269460359356;6658.167729033871;6683.331666833375;6684.598924064571;6706.798796036127;6208.161898143783;6248.718807653667;6255.74977000924;6283.3402492737605;6286.245650521967;5416.972915135478;5486.231927530149;6456.709448074848;6529.053287273318;6533.803985880431;6352.333005034405;6391.265004524446;6403.96798016007;6422.967885160544;6446.613203207822;6201.317855035943;6243.843903902439;6256.906146407808;6450.612963222222;6470.2559205691;4339.305711086211;5057.165567681292;5158.509941555546;5212.270282160501;5241.266222728821;4507.098580283956;4569.725816451024;4571.017231295287;4590.724556526552;4593.954060459419;4740.28895665646;6120.296165940974;6127.969360153171;6178.258608966953;6195.039768835838;6259.217597800275;6561.179852518435;6563.540552161398;6574.868502629921;6589.209294884548;5981.431763982415;6088.330283668793;6127.234095738033;6170.444659980623;6206.162168107338;5789.131630255495;5790.623609465371;5791.160281759121;5797.304323481209;5809;6734.865302693918;6757.256701762803;6758.8648227036165;6780.593164410151;6788.830279243059;5888.646681199141;6240.7503699851495;6355.110284560258;6448.967755161382;6471.094046833541;6624.6025238485845;6659.866802664016;6679.866402672016;6724.260331363644;6741.089952856301;6411.3909178627955;6438.774642887476;6442.194725659293;6451.2903580607035;6463.289038205888;6240.968795155995;6304.936950630618;6419.197600299963;6435.356464353715;6490.8052758417325;6219.564630475823;6342.460890824332;6376.202974628171;6382.968085159545;6388.936110638834;6339.397757213057;6370.980643097082;6386.074019267273;6399.136116624196;6400.423961843457;5333.8265581571995;5379.735762095983;6053.152558641706;6185;6216.53375996793;6268.843278918063;6286.27707813589;6315.210598675166;6344.841378965562;6347.5239357047485;5226.477352264743;6249.218847644044;6260.248770147611;6277.466415354746;6293.465055470153
02:20:43 INFO - PROCESS | 1918 | |1;ext.html;793.3494534481728;811.2414892075909;813.4265342933231;867.7873920889383;940.2571968145165;38061.61938380615;38908.02729931753;39199.648021118715;40477;40635.59364406355;38855.80572097136;39005.41491877617;39081.02297442565;39134.825955221386;39147.21705565887;7500.662470188828;7588.241175882423;7841.294283514469;7955.960220198891;11117.499712512956;11317.773644527104;11413.942930285379;11437.142214333917;11441.199116061878;11448.542058317656;9678.419294842297;11447.02700270478;11463;11466.312021278709;11508.71228219297;11352;11400.315981041082;11401.828972565418;11402.31586104828;11404;11055.170862185289;11109.111271098292;11120.165987550967;11122.944385278102;11133.72165695856;39450.80274598609;39581.812545623674;39643.00892477683;39663.60336396628;39717;3802.7718336899875;3813.8474461021524;3838.9040273993096;3892.338302488584;3911.178652482982;28119.156425307272;29652.8517357414;30143.09570712882;30262.84868575643;30297.091087267196;3005.7144571265694;3136.905892823219;3151.495760678303;3288.3423315337027;3290.1610089427045;357334;359537;363090;363094;364567;44307.11385772267;44853.10293794106;53366;54319.45680543223;54377.72811135919;55388;56392.15411768827;56529.15206271827;56754.716226418604;56914.86170276571;14868.330925108396;14949.925250373679;14999.17504537239;15041.924790375979;15062.246887655574;10998.395088270061;11205;12851.614451566467;12861;12923;13501.324933753272;13507.459701612022;13541;13604.04771665974;13632.727345453035;6095.323785958897;6097.939020609826;6123.84690382744;6127.938720612826;6147.170132032208;948.7343543807744;1071.8959471744115;1089.8147314956318;1111.1166622535104;1126.695792136135;26962.115512061155;27177.45645087127;27310.5903411449;27400.177994660193;27597;941.9105185007413;998.9500524973722;1006.7181189267017;1040.422565476168;1222.5904322052033;13593.660158496117;13676.726465470634;13781.966352523601;13801.723965520634;13948.930255348861;496411;500730;502347;502860;502923;47563.28655070177;48008.27987580189;48087.519124809005;48122;48221.758891206024;1293.8318018657515;1328.156620545941;1328.4686125549665;1338.0767270583267;1345.9259740714158;20070.09684564169;25056.62415063739;25898.870505648105;26302.736972630795;26406.867965660047;41993;42236.78881605572;42317.78841105775;42412.575874242095;42415.5758442424;485927;486041;486111;486124;487521;24104.75895241025;30230.54654180145;31005.534916976714;32039;32053.67946320507;39099.631512896915;39345.80327098346;40067.59932400638;40151;40188.1962360757;28191;28244.576331355456;28754.568681470213;28922.42155156843;29458.705412945597;24985.625215621418;26177.560234187302;28414.715852841207;28561.000364987125;29026.564601531416;32307.51538726874;33272.00183994484;33349.499757503174;33522.32955340928;33545;22972.77027229773;26545.60181597316;27117;28827.71172288334;29403.705962940952;22441.551168976857;23133.421664458525;23216.767832322137;23622.88188559046;23695.881520592287
02:20:43 INFO - PROCESS | 1918 | |2;jquery.html;402.11334008511216;405.8396933211383;410.2390066426779;418.20332267031307;439.93096774837153;19443.319483818068;19647.60704785905;20038.59922801543;20425.080871360788;20653.586928261444;21160.365189044314;21222.57554848902;21514.784852151475;21974;22255.888720556413;5779.479846813786;7044.753433629824;7052.188998265188;7055.259197784236;7061.046758687588;6529.510286728514;6825.044493770876;6856.074429951967;6880.518363714517;6880.759173428931;5463.836084917439;6098.658295175054;6545.214574251097;6876.724931002754;6883.827904302382;5942.673152976585;6904.965475172592;6923.653817309114;6927.618980956071;6934.965325173393;6393.520485963525;6645.900311495331;6673.699683514254;6684.665766711693;6754.2907994660345;20588.86761228132;26062;26101.73898261012;26321;26597.335066623295;2704.837709737422;2755.9586606200924;2796.4826507096254;2806.649168853893;2812.6343575335077;7541.944127822109;7642.694292228304;16620.750688739678;16629.91685041567;16689;1783.2777725021388;1810.63787242552;2035.5827055453597;2110.0504772852123;2112.207922029239;117066;118652.40673796662;120294.39852800767;122251;122321.16518252234;16332;21706.240281590068;21805.345839624835;21920;21986;34911.07989060575;34982.30035399328;35514.644853551654;35743.28513429717;35801.8209908954;10656;10818.29681070728;10920.071793897609;11009.064229540418;11018.063464605599;7320.524165929304;8850.644132430229;8908.06465321145;8964.462132272085;9030.683926062557;9495.38280011797;10188.9811018899;10499.527521261562;10923.508442120125;10961;3586.497890295361;3915.4909861717792;4262.744235345889;4318.740875547477;4345.327049086125;764.3846703403702;885.4421714319938;885.6191837509932;888.853339199025;905.3798148268457;20902.477438063866;21762;21802.672959905613;21937.890310548344;21971.121155153614;842.5113434208145;931.1970967388211;932.6875496708678;935.6818681648273;947.280067148958;7913.061299981541;11382.317060976367;11792.764144717057;11799.941000294944;11814.88185118155;623788;730522;753104;764026;765312;94658;114781;159931;165864.17067914826;167749;1274.49020391844;1274.8916342111024;1325.0327261099258;1327.8605746396684;1336.1181620130667;19036.714449282994;19500.414987550397;19803.306884258982;20249.69625455649;20275;56855;65955.01067484087;65990;66226.66886665729;66821.32946676372;86193.7070943949;92164.53917730368;93533;93695;95247;8657.047724750399;10152.64465743725;11094.223404361617;11225.49485273181;11816.46825892854;12689.302088385228;12846.357682116044;12947.741045179233;13139.54011609589;13243.20540767557;10893.237473376788;10904.672859814218;10911.2362134653;11118.833217501582;11283.266587671775;8088;9085.727998080141;9873.210143188384;10371.222158338003;10682.198835087554;9885.357451765612;10247.7438064049;10965.725856853642;11534;11643.359615221245;9669.854952175583;11336.886631133582;11406.201565890307;11454.854514548479;11567.595134170264;7779.611019448892;11017.779644407226;11224.831627525431;11442.713932151762;11468.31190128595
02:20:43 INFO - PROCESS | 1918 | |3;mootools.html;1454.803601513796;1516.9469068582603;1581.6757564699235;1586.9526112765577;1588.6902054099448;10686.305390149635;11116.444177791098;11240.606578769744;11315.886841131587;11324.150688698339;9051.502167380808;9211.539423028855;11274.323540587571;11280.871912808736;11307.208495405324;2306.2043594959714;2360.4216966843146;2363.948043120812;2364.988175059122;2371.9644205336936;2263.151318255654;2343.297010896733;2345.9882700586472;2360.8819559022068;2363.161077817376;2257.6613507973852;2306.8961896714686;2342.625179971205;2355.0815182079004;2358.9056437742556;2286.9771302287095;2299;2316.976830231693;2326.813854891613;2335.2177020698086;2298.988505057481;2308.4344335637707;2315.3169814904477;2315.3979965209;2321.5472982768365;11053.778924421547;11054.281471704395;11091.55633774648;11136.498857551349;11175;4628.6991345562765;5504.91742623861;5683;5684.488396044376;5705.515031222351;3199.856006479714;3247.7564182686156;3344.966550334514;3372.426687463137;3383.6954674079207;1158.2645020412015;1170.3153655111769;1602.767598698194;1748.6360638701833;1757.0072908806483;12165.270083795001;14062.578122656336;14232.928835355757;15373.538793836204;15928.681426371408;4629.981404091094;4630.814767409333;4730.2431610942085;4740.620750340034;4762.618990480753;9841;12939.741205175844;12950.481980720853;12984.935075324753;13001.349932503337;5664.49019588239;5680.318361796528;5701.971490142606;5703.714814259353;5713.628614140151;4907.754612269372;5099.6685215460875;5106.259592359087;5160.148575485003;5192.402873669501;3836.3861782114727;3887.814216663888;3935.193285376491;4010.298197815429;4064.5732198119363;1017.9871028326714;1048.8688913885765;1065.6909496246;1072.8658917635296;1081.6268387406333;917.4632839788665;931.0921851195166;946.4794363100267;950.6007476859736;1011.110223003752;6468.8059358219325;6488.448481879093;6634;6750.7299708012115;6754.493412994045;967.2020583019038;970.5438443931341;992.9851052234222;1003.7691330993912;1023.5701005577675;3582.283543291352;3741.401375779862;3956.1296514766705;4163.625273725379;4203.432536607519;11353.60262390829;13253.536126235536;13875.58373248784;13902.860971390359;13907.374168162458;11609.767804644029;13280;13540.052196346162;13665.111767735065;13847.376868040963;10017.899821001842;10274.383536987805;10310.639127630648;10344.844827327595;10485.108765754843;6504.707288171949;6748.628825414649;6903.309669033057;6913.274106218976;7037.894431583428;11870;12138.817917731418;12153.270803751802;12174.208676436003;12361.320127393079;11097.611583594535;12217.755644887231;12449.564265251034;12564.434600442819;12755.48978040868;8885.777855553662;9159.725208243763;9166.862497062417;9301.488418136796;9313.534323283673;10218.489075546044;10330.225233107396;10347.017033381817;10434.791304173723;10495.265331426726;7378.704851805867;8794.824103518022;8795.604197810986;8822.955885220534;8901.376903616685;8533.615987280711;8614.3108551317;8662.177093176138;8663;8729.869051964099;7742.806429839074;7752.341051010727;7787.338076263581;7863.449558530848;7873.606319684108;5675.205471233948;5808.593398462067;5858.355580886185;5877.23595932526;5985.88563876216;5862.12068189775;5880.206172166702;5919.437653422918;5963.2844058713235;6046.30467496226
02:20:43 INFO - PROCESS | 1918 | |4;prototype.html;347.1529468097843;349.18465383329925;369.7686703278086;381.71943621438237;389.581921804631;5179.81870634527;5203.4796520347945;5229.450907654698;5267.420583735786;5278.392984806748;4425.77871106445;4484.461864576256;4792.880177995552;5203.933193695293;5213.817516386927;3365.4951757236486;4000.4796977904025;4014.808227459177;4188.602082802144;4328.653707703376;3556.590992035923;3725.962740372602;4069.006186968379;4366.463164836477;4384.298512238042;4148.274052040896;4224.704270701052;4378.737275763448;4400.889977750549;4413.470383553978;3583.3191693578137;4004.9599504004877;4259.595338442843;4410.360497727827;4415.624671902892;3977.3835055566433;3994.7603143811466;4005.517958355398;4050.6354428101317;4067.51189857219;4405.22908491016;4892.584130348924;5182.248573956793;5195.19474481456;5210.921836172422;4443.777811109431;4921.704697718113;5065.189569668835;5091.0581542414775;5110.616703747234;5603.159526071081;5670;5699.549193846644;5751.447109280471;5780.913286300709;4316.395704601358;4516.696484792747;4528.128450175086;4558.521355257683;4569.109023740372;4840.273958906157;4918.778654960535;5334.119870221409;5469.699166545838;5805.53555715546;3993.2845664947536;4301.494476933049;4403.603675669206;4617.538246175355;4623.398958135488;4019.789115986196;4521.321801729701;4569.634429245652;4663.370444989883;4765.285207218945;4350.912981740347;4649.325847752057;4744.695208817603;4839.564439200489;4888.682235654671;5224.268602395668;5515.896820635889;5599.244102046253;5627.296587926509;5707.828765137053;1339.6115126613367;1592;1593.8406159384201;1594.5136733296224;1602.1748799368336;955.1308309438377;1085.3270971997413;1104.812181929074;1197.963761346429;1214.9514019439123;4793.664443488922;5070.518300761421;5270.393904701008;5322.760475778599;5345.893082138336;5794.652320860761;5875.29496460419;5881.00068964831;5926.362768567946;5969.343372229051;4080.2346448488443;4732.6687131900435;4760.547747963938;5231.3199284092825;5273.630845840754;2715.932101697473;3035.1653295343517;3065.601471808651;3067.708567686066;3102.1314032071054;5682.77268909239;6069.998450255746;6074.514038876968;6093.786717464867;6106.358832322631;5345.1447768356875;5472.005436031719;5539.972300138474;5684.54523638108;5966.181643748005;5506.394296627368;5801.82594522165;5932.644041357532;6026;6050.848728781815;4993.551869957817;5032.986805277918;5425.755840987086;5783.710814459176;5875.914050512178;4640.429177260463;4716.806684891542;5037.140088937413;5299.887023724907;5769.336526299529;5046.873828154178;5096.598435430286;5248.24183898383;5995.970020149872;6027.46846324536;3916.686665066845;4027.0536423940935;4526.117407105715;4559.202139625486;4586.945002649342;4710.845842768544;4892.602624713936;5444.686008446473;5537.311120108245;5777.8555536111935;5223.895522089613;5603.691796951207;5658.840752047325;5678.176664383724;5684.971575142098;5187.714675692722;5240.34495688039;5450.150756273237;5555.638883472561;5664.801731939362;4393.62654174386;4816.686915350631;5208.947910520847;5463.207834864002;5583.832485025456;4938.629602779878;4978.726170060681;5419.187121931742;5656.830295091154;5663.263775709132;4667.989147000234;4681.976590117028;5183.663061900965;5485.698286594116;5547.750351234124
02:20:43 INFO - PROCESS | 1918 | |5;yui.html;683.8085336105889;689.3657834791991;744.2111361956327;805.5368163306099;829.4981536170617;15349;15432.459863904765;16021.59946001349;16071.919640401811;16159.030458172503;12947.48210071596;15348.61628459289;15533.301001454962;15605.297761600727;16226.756598651029;913.7213149989267;918.2332752151962;928.7770934975625;929.9349045566813;931.7903471718843;888.6667499687617;923.5520772425368;925.0656836595053;925.5742358515091;926.0045451140018;1001.7695929936083;1002.413588050991;1004.1063453526367;1005.8038682456923;1006.4112494190907;992.7865508915581;993.88073431188;997.306871724152;998.4907697074472;999.6601155607092;624.2010226909563;734.4711807498646;740.0896896816945;740.5371642723298;741.4624397311906;16257.024578525326;16677.833221667748;16805.495835124966;16881.40915067979;16898.662026759517;12570.120091593591;12861.16402433848;13521.188728676212;13860.168389896638;14106.435742570286;11109;12320.199187052813;12404.441800119017;12840.165389249669;12924;4308.116836048603;4320.179165958458;4390.692651514395;4411.6250118739945;4489.573490518395;70408.6479567604;96875;102493.4875325626;102728.48635756773;103837.96162038359;8752.431091979;10369.222308326905;10441.791164176673;10451.02919245674;10723.981221783917;19552.413427597196;20669.89665051665;20793.792062079487;20887.68668469974;20941.790582094287;7463.8507229855095;7560.621968901533;7576.659050342748;7665.808354791175;7702.845943081107;4251.638610718123;4278.871633850989;4312.37470566766;4331.7834108294455;4431.8670439886855;3178.3166619176986;3259.071164718044;3260.1849537615594;3261.2662151015834;3271.0677456925137;596.4214711729616;613.2365205319317;625.4964753373487;635.86964672243;637.5186734015789;540.4325458268802;556.8751122732059;572.1275055540334;580.7676929228343;586.7447660267798;9360.531973401303;9829.655962041294;9835.704928852147;10058.396496210251;10142.695719128438;616.636184651058;640.3692363022419;653.6862306093102;657.4083325007532;663.575311800448;4249.936250956238;4290.485141783006;4741.336212930192;4752.738599377068;4818.204996175662;79761.60119199484;97115.54326685105;99235;100034;100330;75505.24494754981;78534;78573.60713196397;87471.56264218639;88564.6715299271;72124.91812622815;76122.4775504498;76569.85145222826;78345;79522.20477795148;10572.947135264274;11146.119151934488;11182.496787644739;11401.771964560829;11404.201705880509;45899;51155;51712.74143629258;52034.21948670697;52721.20918186154;62818;64877.02684459831;66401;68297;69886.65056674684;12676.04929630263;13201.933990329986;13216.140950838164;13285.66785830362;13464.730705386033;12341.50633974631;14475.565733028026;14697.632559186106;14748.33632486562;14880.466588371259;13528.86471135276;13734.244616546186;13747.587572372844;14365.712685746435;14647.26763661834;11106.833397498882;12517.624471265877;12528.874711253135;13696;13839.723205536035;10024.147947424259;12434.813477798021;13570.253636050113;13713.382897769823;13990.930045349709;9008.819823603622;9108;9402.95298523503;9529;9776.120149186607;9609.327347085637;10958.28771129874;11106.555737770723;11169.553217871518;11284
02:20:43 INFO - PROCESS | 1918 | __end_tp_report
02:20:43 INFO - PROCESS | 1918 | __start_cc_report
02:20:43 INFO - PROCESS | 1918 | _x_x_mozilla_cycle_collect,416
02:20:43 INFO - PROCESS | 1918 | __end_cc_report
02:20:43 INFO - PROCESS | 1918 | __startTimestamp1490692843529__endTimestamp
02:20:43 INFO - PROCESS | 1918 |
02:20:43 INFO - PROCESS | 1918 | ------- Summary: start -------
02:20:43 INFO - PROCESS | 1918 | Number of tests: 6
02:20:43 INFO - PROCESS | 1918 |
02:20:43 INFO - PROCESS | 1918 | [#0] dojo.html Cycles:165 Average:5964.12 Median:6237.35 stddev:722.88 (11.6%) stddev-sans-first:683.60
02:20:43 INFO - PROCESS | 1918 | Values: 2886.9 3000.3 3468.1 3498.4 3537.6 4934.4 5156.6 6056.3 6125.9 6136.7 5905.7 6100.2 6140.2 6163.2 6179.9 5435.5 5494.6 5518.3 5571.2 5590.3 4535.3 5553.0 5554.9 5563.5 5579.8 5357.7 5382.9 5591.3 5594.8 5621.3 5457.4 5464.0 5467.6 5481.3 5496.3 5409.6 5423.5 5470.3 5521.6 5580.5 6160.2 6200.7 6203.0 6223.0 6238.8 6196.1 6265.5 6454.5 6489.7 6500.8 6342.4 6415.2 6445.7 6569.7 6600.4 6237.3 6270.9 6274.6 6283.3 6283.3 6641.3 6658.2 6683.3 6684.6 6706.8 6208.2 6248.7 6255.7 6283.3 6286.2 5417.0 5486.2 6456.7 6529.1 6533.8 6352.3 6391.3 6404.0 6423.0 6446.6 6201.3 6243.8 6256.9 6450.6 6470.3 4339.3 5057.2 5158.5 5212.3 5241.3 4507.1 4569.7 4571.0 4590.7 4594.0 4740.3 6120.3 6128.0 6178.3 6195.0 6259.2 6561.2 6563.5 6574.9 6589.2 5981.4 6088.3 6127.2 6170.4 6206.2 5789.1 5790.6 5791.2 5797.3 5809.0 6734.9 6757.3 6758.9 6780.6 6788.8 5888.6 6240.8 6355.1 6449.0 6471.1 6624.6 6659.9 6679.9 6724.3 6741.1 6411.4 6438.8 6442.2 6451.3 6463.3 6241.0 6304.9 6419.2 6435.4 6490.8 6219.6 6342.5 6376.2 6383.0 6388.9 6339.4 6371.0 6386.1 6399.1 6400.4 5333.8 5379.7 6053.2 6185.0 6216.5 6268.8 6286.3 6315.2 6344.8 6347.5 5226.5 6249.2 6260.2 6277.5 6293.5
02:20:43 INFO - PROCESS | 1918 |
02:20:43 INFO - PROCESS | 1918 | [#1] ext.html Cycles:180 Average:57783.53 Median:17071.22 stddev:120826.39 (707.8%) stddev-sans-first:121089.58
02:20:43 INFO - PROCESS | 1918 | Values: 793.3 811.2 813.4 867.8 940.3 38061.6 38908.0 39199.6 40477.0 40635.6 38855.8 39005.4 39081.0 39134.8 39147.2 7500.7 7588.2 7841.3 7956.0 11117.5 11317.8 11413.9 11437.1 11441.2 11448.5 9678.4 11447.0 11463.0 11466.3 11508.7 11352.0 11400.3 11401.8 11402.3 11404.0 11055.2 11109.1 11120.2 11122.9 11133.7 39450.8 39581.8 39643.0 39663.6 39717.0 3802.8 3813.8 3838.9 3892.3 3911.2 28119.2 29652.9 30143.1 30262.8 30297.1 3005.7 3136.9 3151.5 3288.3 3290.2 357334.0 359537.0 363090.0 363094.0 364567.0 44307.1 44853.1 53366.0 54319.5 54377.7 55388.0 56392.2 56529.2 56754.7 56914.9 14868.3 14949.9 14999.2 15041.9 15062.2 10998.4 11205.0 12851.6 12861.0 12923.0 13501.3 13507.5 13541.0 13604.0 13632.7 6095.3 6097.9 6123.8 6127.9 6147.2 948.7 1071.9 1089.8 1111.1 1126.7 26962.1 27177.5 27310.6 27400.2 27597.0 941.9 999.0 1006.7 1040.4 1222.6 13593.7 13676.7 13782.0 13801.7 13948.9 496411.0 500730.0 502347.0 502860.0 502923.0 47563.3 48008.3 48087.5 48122.0 48221.8 1293.8 1328.2 1328.5 1338.1 1345.9 20070.1 25056.6 25898.9 26302.7 26406.9 41993.0 42236.8 42317.8 42412.6 42415.6 485927.0 486041.0 486111.0 486124.0 487521.0 24104.8 30230.5 31005.5 32039.0 32053.7 39099.6 39345.8 40067.6 40151.0 40188.2 28191.0 28244.6 28754.6 28922.4 29458.7 24985.6 26177.6 28414.7 28561.0 29026.6 32307.5 33272.0 33349.5 33522.3 33545.0 22972.8 26545.6 27117.0 28827.7 29403.7 22441.6 23133.4 23216.8 23622.9 23695.9
02:20:43 INFO - PROCESS | 1918 |
02:20:43 INFO - PROCESS | 1918 | [#2] jquery.html Cycles:180 Average:41257.34 Median:26211.37 stddev:120999.22 (461.6%) stddev-sans-first:121299.76
02:20:43 INFO - PROCESS | 1918 | Values: 402.1 405.8 410.2 418.2 439.9 19443.3 19647.6 20038.6 20425.1 20653.6 21160.4 21222.6 21514.8 21974.0 22255.9 5779.5 7044.8 7052.2 7055.3 7061.0 6529.5 6825.0 6856.1 6880.5 6880.8 5463.8 6098.7 6545.2 6876.7 6883.8 5942.7 6905.0 6923.7 6927.6 6935.0 6393.5 6645.9 6673.7 6684.7 6754.3 20588.9 26062.0 26101.7 26321.0 26597.3 2704.8 2756.0 2796.5 2806.6 2812.6 7541.9 7642.7 16620.8 16629.9 16689.0 1783.3 1810.6 2035.6 2110.1 2112.2 117066.0 118652.4 120294.4 122251.0 122321.2 16332.0 21706.2 21805.3 21920.0 21986.0 34911.1 34982.3 35514.6 35743.3 35801.8 10656.0 10818.3 10920.1 11009.1 11018.1 7320.5 8850.6 8908.1 8964.5 9030.7 9495.4 10189.0 10499.5 10923.5 10961.0 3586.5 3915.5 4262.7 4318.7 4345.3 764.4 885.4 885.6 888.9 905.4 20902.5 21762.0 21802.7 21937.9 21971.1 842.5 931.2 932.7 935.7 947.3 7913.1 11382.3 11792.8 11799.9 11814.9 623788.0 730522.0 753104.0 764026.0 765312.0 94658.0 114781.0 159931.0 165864.2 167749.0 1274.5 1274.9 1325.0 1327.9 1336.1 19036.7 19500.4 19803.3 20249.7 20275.0 56855.0 65955.0 65990.0 66226.7 66821.3 86193.7 92164.5 93533.0 93695.0 95247.0 8657.0 10152.6 11094.2 11225.5 11816.5 12689.3 12846.4 12947.7 13139.5 13243.2 10893.2 10904.7 10911.2 11118.8 11283.3 8088.0 9085.7 9873.2 10371.2 10682.2 9885.4 10247.7 10965.7 11534.0 11643.4 9669.9 11336.9 11406.2 11454.9 11567.6 7779.6 11017.8 11224.8 11442.7 11468.3
02:20:43 INFO - PROCESS | 1918 |
02:20:43 INFO - PROCESS | 1918 | [#3] mootools.html Cycles:180 Average:6665.67 Median:2362.02 stddev:4163.18 (176.3%) stddev-sans-first:4156.44
02:20:43 INFO - PROCESS | 1918 | Values: 1454.8 1516.9 1581.7 1587.0 1588.7 10686.3 11116.4 11240.6 11315.9 11324.2 9051.5 9211.5 11274.3 11280.9 11307.2 2306.2 2360.4 2363.9 2365.0 2372.0 2263.2 2343.3 2346.0 2360.9 2363.2 2257.7 2306.9 2342.6 2355.1 2358.9 2287.0 2299.0 2317.0 2326.8 2335.2 2299.0 2308.4 2315.3 2315.4 2321.5 11053.8 11054.3 11091.6 11136.5 11175.0 4628.7 5504.9 5683.0 5684.5 5705.5 3199.9 3247.8 3345.0 3372.4 3383.7 1158.3 1170.3 1602.8 1748.6 1757.0 12165.3 14062.6 14232.9 15373.5 15928.7 4630.0 4630.8 4730.2 4740.6 4762.6 9841.0 12939.7 12950.5 12984.9 13001.3 5664.5 5680.3 5702.0 5703.7 5713.6 4907.8 5099.7 5106.3 5160.1 5192.4 3836.4 3887.8 3935.2 4010.3 4064.6 1018.0 1048.9 1065.7 1072.9 1081.6 917.5 931.1 946.5 950.6 1011.1 6468.8 6488.4 6634.0 6750.7 6754.5 967.2 970.5 993.0 1003.8 1023.6 3582.3 3741.4 3956.1 4163.6 4203.4 11353.6 13253.5 13875.6 13902.9 13907.4 11609.8 13280.0 13540.1 13665.1 13847.4 10017.9 10274.4 10310.6 10344.8 10485.1 6504.7 6748.6 6903.3 6913.3 7037.9 11870.0 12138.8 12153.3 12174.2 12361.3 11097.6 12217.8 12449.6 12564.4 12755.5 8885.8 9159.7 9166.9 9301.5 9313.5 10218.5 10330.2 10347.0 10434.8 10495.3 7378.7 8794.8 8795.6 8823.0 8901.4 8533.6 8614.3 8662.2 8663.0 8729.9 7742.8 7752.3 7787.3 7863.4 7873.6 5675.2 5808.6 5858.4 5877.2 5985.9 5862.1 5880.2 5919.4 5963.3 6046.3
02:20:43 INFO - PROCESS | 1918 |
02:20:43 INFO - PROCESS | 1918 | [#4] prototype.html Cycles:180 Average:4625.09 Median:4958.68 stddev:1301.79 (26.3%) stddev-sans-first:1265.23
02:20:43 INFO - PROCESS | 1918 | Values: 347.2 349.2 369.8 381.7 389.6 5179.8 5203.5 5229.5 5267.4 5278.4 4425.8 4484.5 4792.9 5203.9 5213.8 3365.5 4000.5 4014.8 4188.6 4328.7 3556.6 3726.0 4069.0 4366.5 4384.3 4148.3 4224.7 4378.7 4400.9 4413.5 3583.3 4005.0 4259.6 4410.4 4415.6 3977.4 3994.8 4005.5 4050.6 4067.5 4405.2 4892.6 5182.2 5195.2 5210.9 4443.8 4921.7 5065.2 5091.1 5110.6 5603.2 5670.0 5699.5 5751.4 5780.9 4316.4 4516.7 4528.1 4558.5 4569.1 4840.3 4918.8 5334.1 5469.7 5805.5 3993.3 4301.5 4403.6 4617.5 4623.4 4019.8 4521.3 4569.6 4663.4 4765.3 4350.9 4649.3 4744.7 4839.6 4888.7 5224.3 5515.9 5599.2 5627.3 5707.8 1339.6 1592.0 1593.8 1594.5 1602.2 955.1 1085.3 1104.8 1198.0 1215.0 4793.7 5070.5 5270.4 5322.8 5345.9 5794.7 5875.3 5881.0 5926.4 5969.3 4080.2 4732.7 4760.5 5231.3 5273.6 2715.9 3035.2 3065.6 3067.7 3102.1 5682.8 6070.0 6074.5 6093.8 6106.4 5345.1 5472.0 5540.0 5684.5 5966.2 5506.4 5801.8 5932.6 6026.0 6050.8 4993.6 5033.0 5425.8 5783.7 5875.9 4640.4 4716.8 5037.1 5299.9 5769.3 5046.9 5096.6 5248.2 5996.0 6027.5 3916.7 4027.1 4526.1 4559.2 4586.9 4710.8 4892.6 5444.7 5537.3 5777.9 5223.9 5603.7 5658.8 5678.2 5685.0 5187.7 5240.3 5450.2 5555.6 5664.8 4393.6 4816.7 5208.9 5463.2 5583.8 4938.6 4978.7 5419.2 5656.8 5663.3 4668.0 4682.0 5183.7 5485.7 5547.8
02:20:43 INFO - PROCESS | 1918 |
02:20:43 INFO - PROCESS | 1918 | [#5] yui.html Cycles:180 Average:19704.98 Median:4316.28 stddev:27488.25 (636.9%) stddev-sans-first:27528.26
02:20:43 INFO - PROCESS | 1918 | Values: 683.8 689.4 744.2 805.5 829.5 15349.0 15432.5 16021.6 16071.9 16159.0 12947.5 15348.6 15533.3 15605.3 16226.8 913.7 918.2 928.8 929.9 931.8 888.7 923.6 925.1 925.6 926.0 1001.8 1002.4 1004.1 1005.8 1006.4 992.8 993.9 997.3 998.5 999.7 624.2 734.5 740.1 740.5 741.5 16257.0 16677.8 16805.5 16881.4 16898.7 12570.1 12861.2 13521.2 13860.2 14106.4 11109.0 12320.2 12404.4 12840.2 12924.0 4308.1 4320.2 4390.7 4411.6 4489.6 70408.6 96875.0 102493.5 102728.5 103838.0 8752.4 10369.2 10441.8 10451.0 10724.0 19552.4 20669.9 20793.8 20887.7 20941.8 7463.9 7560.6 7576.7 7665.8 7702.8 4251.6 4278.9 4312.4 4331.8 4431.9 3178.3 3259.1 3260.2 3261.3 3271.1 596.4 613.2 625.5 635.9 637.5 540.4 556.9 572.1 580.8 586.7 9360.5 9829.7 9835.7 10058.4 10142.7 616.6 640.4 653.7 657.4 663.6 4249.9 4290.5 4741.3 4752.7 4818.2 79761.6 97115.5 99235.0 100034.0 100330.0 75505.2 78534.0 78573.6 87471.6 88564.7 72124.9 76122.5 76569.9 78345.0 79522.2 10572.9 11146.1 11182.5 11401.8 11404.2 45899.0 51155.0 51712.7 52034.2 52721.2 62818.0 64877.0 66401.0 68297.0 69886.7 12676.0 13201.9 13216.1 13285.7 13464.7 12341.5 14475.6 14697.6 14748.3 14880.5 13528.9 13734.2 13747.6 14365.7 14647.3 11106.8 12517.6 12528.9 13696.0 13839.7 10024.1 12434.8 13570.3 13713.4 13990.9 9008.8 9108.0 9403.0 9529.0 9776.1 9609.3 10958.3 11106.6 11169.6 11284.0
02:20:43 INFO - PROCESS | 1918 | -------- Summary: end --------
02:20:43 INFO - PROCESS | 1918 |
02:20:44 INFO - TEST-INFO | 1918: exit 0
02:20:44 INFO - TEST-OK | dromaeo_css | took 1117181ms
02:20:44 INFO - TEST-START | kraken
02:20:44 INFO - operating with platform_type : mac_
02:20:44 INFO - Initialising browser for kraken test...
02:20:44 INFO - TEST-INFO | started process 1924 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/9c/2dh985pd15l19fywb0pq404000000w/T/tmp7mcWIp/profile http://localhost:54249/getInfo.html)
02:20:47 INFO - PROCESS | 1924 | 2017-03-28 02:20:47.122 plugin-container[1926:20170] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8a37, name = 'com.apple.tsm.portname'
02:20:47 INFO - PROCESS | 1924 | See /usr/include/servers/bootstrap_defs.h for the error codes.
02:20:47 INFO - PROCESS | 1924 | 2017-03-28 02:20:47.123 plugin-container[1926:20170] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4607, name = 'com.apple.CFPasteboardClient'
02:20:47 INFO - PROCESS | 1924 | See /usr/include/servers/bootstrap_defs.h for the error codes.
02:20:47 INFO - PROCESS | 1924 | 2017-03-28 02:20:47.123 plugin-container[1926:20170] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions
02:20:47 INFO - PROCESS | 1924 | __metrics Screen width/height:1600/1200
02:20:47 INFO - PROCESS | 1924 | colorDepth:24
02:20:47 INFO - PROCESS | 1924 | Browser inner width/height: 1024/689
02:20:47 INFO - PROCESS | 1924 | __metrics
02:20:47 ERROR - PROCESS | 1924 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3353: TypeError: this.tabs is undefined
02:20:47 INFO - TEST-INFO | 1924: exit 0
02:20:47 INFO - Browser initialized.
02:20:47 INFO - Running cycle 1/1 for kraken test...
02:20:47 INFO - TEST-INFO | started process 1927 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/9c/2dh985pd15l19fywb0pq404000000w/T/tmp7mcWIp/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1)
02:20:50 INFO - PROCESS | 1927 | 2017-03-28 02:20:50.597 plugin-container[1928:20294] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x444b, name = 'com.apple.tsm.portname'
02:20:50 INFO - PROCESS | 1927 | See /usr/include/servers/bootstrap_defs.h for the error codes.
02:20:50 INFO - PROCESS | 1927 | 2017-03-28 02:20:50.598 plugin-container[1928:20294] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8c0f, name = 'com.apple.CFPasteboardClient'
02:20:50 INFO - PROCESS | 1927 | See /usr/include/servers/bootstrap_defs.h for the error codes.
02:20:50 INFO - PROCESS | 1927 | 2017-03-28 02:20:50.598 plugin-container[1928:20294] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions
02:21:00 INFO - PROCESS | 1927 | RSS: Main: 220594176
02:21:00 INFO - PROCESS | 1927 |
02:21:03 INFO - PROCESS | 1927 | Cycle 1(1): loaded http://localhost:54249/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:54249/tests/kraken/driver.html?testName=audio-beat-detection)
02:21:04 INFO - PROCESS | 1927 | RSS: Main: 235360256
02:21:04 INFO - PROCESS | 1927 |
02:21:07 INFO - PROCESS | 1927 | Cycle 1(1): loaded http://localhost:54249/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:54249/tests/kraken/driver.html?testName=audio-dft)
02:21:07 INFO - PROCESS | 1927 | RSS: Main: 243265536
02:21:07 INFO - PROCESS | 1927 |
02:21:11 INFO - PROCESS | 1927 | Cycle 1(1): loaded http://localhost:54249/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:54249/tests/kraken/driver.html?testName=audio-fft)
02:21:11 INFO - PROCESS | 1927 | RSS: Main: 244871168
02:21:11 INFO - PROCESS | 1927 |
02:21:13 INFO - PROCESS | 1927 | Cycle 1(1): loaded http://localhost:54249/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:54249/tests/kraken/driver.html?testName=audio-oscillator)
02:21:14 INFO - PROCESS | 1927 | RSS: Main: 247623680
02:21:14 INFO - PROCESS | 1927 |
02:21:16 INFO - PROCESS | 1927 | Cycle 1(1): loaded http://localhost:54249/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:54249/tests/kraken/driver.html?testName=imaging-gaussian-blur)
02:21:16 INFO - PROCESS | 1927 | RSS: Main: 248008704
02:21:16 INFO - PROCESS | 1927 |
02:21:20 INFO - PROCESS | 1927 | Cycle 1(1): loaded http://localhost:54249/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:54249/tests/kraken/driver.html?testName=imaging-darkroom)
02:21:20 INFO - PROCESS | 1927 | RSS: Main: 252039168
02:21:20 INFO - PROCESS | 1927 |
02:21:23 INFO - PROCESS | 1927 | Cycle 1(1): loaded http://localhost:54249/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:54249/tests/kraken/driver.html?testName=imaging-desaturate)
02:21:24 INFO - PROCESS | 1927 | RSS: Main: 251801600
02:21:24 INFO - PROCESS | 1927 |
02:21:27 INFO - PROCESS | 1927 | Cycle 1(1): loaded http://localhost:54249/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:54249/tests/kraken/driver.html?testName=json-parse-financial)
02:21:27 INFO - PROCESS | 1927 | RSS: Main: 251514880
02:21:27 INFO - PROCESS | 1927 |
02:21:29 INFO - PROCESS | 1927 | Cycle 1(1): loaded http://localhost:54249/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:54249/tests/kraken/driver.html?testName=json-stringify-tinderbox)
02:21:30 INFO - PROCESS | 1927 | RSS: Main: 251510784
02:21:30 INFO - PROCESS | 1927 |
02:21:32 INFO - PROCESS | 1927 | Cycle 1(1): loaded http://localhost:54249/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:54249/tests/kraken/driver.html?testName=stanford-crypto-aes)
02:21:32 INFO - PROCESS | 1927 | RSS: Main: 251457536
02:21:32 INFO - PROCESS | 1927 |
02:21:34 INFO - PROCESS | 1927 | Cycle 1(1): loaded http://localhost:54249/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:54249/tests/kraken/driver.html?testName=stanford-crypto-ccm)
02:21:35 INFO - PROCESS | 1927 | RSS: Main: 251547648
02:21:35 INFO - PROCESS | 1927 |
02:21:38 INFO - PROCESS | 1927 | Cycle 1(1): loaded http://localhost:54249/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:54249/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2)
02:21:39 INFO - PROCESS | 1927 | RSS: Main: 251809792
02:21:39 INFO - PROCESS | 1927 |
02:21:42 INFO - PROCESS | 1927 | Cycle 1(1): loaded http://localhost:54249/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:54249/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative)
02:21:42 INFO - PROCESS | 1927 | RSS: Main: 251527168
02:21:42 INFO - PROCESS | 1927 |
02:21:44 INFO - PROCESS | 1927 | Cycle 1(1): loaded http://localhost:54249/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:54249/tests/kraken/driver.html?testName=ai-astar)
02:21:44 INFO - PROCESS | 1927 | RSS: Main: 253575168
02:21:44 INFO - PROCESS | 1927 |
02:21:44 INFO - PROCESS | 1927 | __start_tp_report
02:21:44 INFO - PROCESS | 1927 | _x_x_mozilla_page_load
02:21:44 INFO - PROCESS | 1927 | _x_x_mozilla_page_load_details
02:21:44 INFO - PROCESS | 1927 | |i|pagename|runs|
02:21:44 INFO - PROCESS | 1927 | |0;ai-astar;107;118;135;177;159;119;119;119;119;118
02:21:44 INFO - PROCESS | 1927 | |1;audio-beat-detection;131;111;113;145;111;114;112;119;155;126
02:21:44 INFO - PROCESS | 1927 | |2;audio-dft;196;199;199;203;198;207;263;282;196;236
02:21:44 INFO - PROCESS | 1927 | |3;audio-fft;82;59;62;87;63;63;65;62;100;87
02:21:44 INFO - PROCESS | 1927 | |4;audio-oscillator;89;72;86;73;73;70;82;71;73;108
02:21:44 INFO - PROCESS | 1927 | |5;imaging-gaussian-blur;87;90;128;121;138;136;88;88;88;88
02:21:44 INFO - PROCESS | 1927 | |6;imaging-darkroom;96;97;98;130;96;95;97;97;97;102
02:21:44 INFO - PROCESS | 1927 | |7;imaging-desaturate;118;90;91;91;92;85;91;91;91;92
02:21:44 INFO - PROCESS | 1927 | |8;json-parse-financial;66;67;64;68;70;71;69;68;68;70
02:21:44 INFO - PROCESS | 1927 | |9;json-stringify-tinderbox;63;55;58;64;58;58;56;56;56;59
02:21:44 INFO - PROCESS | 1927 | |10;stanford-crypto-aes;87;86;89;87;86;84;86;89;90;102
02:21:44 INFO - PROCESS | 1927 | |11;stanford-crypto-ccm;136;142;138;137;139;138;138;147;178;158
02:21:44 INFO - PROCESS | 1927 | |12;stanford-crypto-pbkdf2;173;170;163;164;178;222;227;193;174;168
02:21:44 INFO - PROCESS | 1927 | |13;stanford-crypto-sha256-iterative;60;74;70;65;91;87;84;86;65;65
02:21:44 INFO - PROCESS | 1927 | __end_tp_report
02:21:44 INFO - PROCESS | 1927 | __start_cc_report
02:21:44 INFO - PROCESS | 1927 | _x_x_mozilla_cycle_collect,1015
02:21:44 INFO - PROCESS | 1927 | __end_cc_report
02:21:44 INFO - PROCESS | 1927 | __startTimestamp1490692904781__endTimestamp
02:21:44 INFO - PROCESS | 1927 |
02:21:44 INFO - PROCESS | 1927 | ------- Summary: start -------
02:21:44 INFO - PROCESS | 1927 | Number of tests: 14
02:21:44 INFO - PROCESS | 1927 |
02:21:44 INFO - PROCESS | 1927 | [#0] ai-astar Cycles:10 Average:129.00 Median:119.00 stddev:22.03 (18.5%) stddev-sans-first:21.88
02:21:44 INFO - PROCESS | 1927 | Values: 107.0 118.0 135.0 177.0 159.0 119.0 119.0 119.0 119.0 118.0
02:21:44 INFO - PROCESS | 1927 |
02:21:44 INFO - PROCESS | 1927 | [#1] audio-beat-detection Cycles:10 Average:123.70 Median:122.50 stddev:15.57 (12.7%) stddev-sans-first:16.29
02:21:44 INFO - PROCESS | 1927 | Values: 131.0 111.0 113.0 145.0 111.0 114.0 112.0 119.0 155.0 126.0
02:21:44 INFO - PROCESS | 1927 |
02:21:44 INFO - PROCESS | 1927 | [#2] audio-dft Cycles:10 Average:217.90 Median:205.00 stddev:31.41 (15.3%) stddev-sans-first:32.30
02:21:44 INFO - PROCESS | 1927 | Values: 196.0 199.0 199.0 203.0 198.0 207.0 263.0 282.0 196.0 236.0
02:21:44 INFO - PROCESS | 1927 |
02:21:44 INFO - PROCESS | 1927 | [#3] audio-fft Cycles:10 Average:73.00 Median:64.00 stddev:14.54 (22.7%) stddev-sans-first:15.06
02:21:44 INFO - PROCESS | 1927 | Values: 82.0 59.0 62.0 87.0 63.0 63.0 65.0 62.0 100.0 87.0
02:21:44 INFO - PROCESS | 1927 |
02:21:44 INFO - PROCESS | 1927 | [#4] audio-oscillator Cycles:10 Average:79.70 Median:73.00 stddev:12.00 (16.4%) stddev-sans-first:12.25
02:21:44 INFO - PROCESS | 1927 | Values: 89.0 72.0 86.0 73.0 73.0 70.0 82.0 71.0 73.0 108.0
02:21:44 INFO - PROCESS | 1927 |
02:21:44 INFO - PROCESS | 1927 | [#5] imaging-gaussian-blur Cycles:10 Average:105.20 Median:88.00 stddev:22.46 (25.5%) stddev-sans-first:22.84
02:21:44 INFO - PROCESS | 1927 | Values: 87.0 90.0 128.0 121.0 138.0 136.0 88.0 88.0 88.0 88.0
02:21:44 INFO - PROCESS | 1927 |
02:21:44 INFO - PROCESS | 1927 | [#6] imaging-darkroom Cycles:10 Average:100.50 Median:97.00 stddev:10.53 (10.9%) stddev-sans-first:11.05
02:21:44 INFO - PROCESS | 1927 | Values: 96.0 97.0 98.0 130.0 96.0 95.0 97.0 97.0 97.0 102.0
02:21:44 INFO - PROCESS | 1927 |
02:21:44 INFO - PROCESS | 1927 | [#7] imaging-desaturate Cycles:10 Average:93.20 Median:91.00 stddev:8.94 (9.8%) stddev-sans-first:2.13
02:21:44 INFO - PROCESS | 1927 | Values: 118.0 90.0 91.0 91.0 92.0 85.0 91.0 91.0 91.0 92.0
02:21:44 INFO - PROCESS | 1927 |
02:21:44 INFO - PROCESS | 1927 | [#8] json-parse-financial Cycles:10 Average:68.10 Median:68.50 stddev:2.08 (3.0%) stddev-sans-first:2.06
02:21:44 INFO - PROCESS | 1927 | Values: 66.0 67.0 64.0 68.0 70.0 71.0 69.0 68.0 68.0 70.0
02:21:44 INFO - PROCESS | 1927 |
02:21:44 INFO - PROCESS | 1927 | [#9] json-stringify-tinderbox Cycles:10 Average:58.30 Median:58.00 stddev:3.02 (5.2%) stddev-sans-first:2.68
02:21:44 INFO - PROCESS | 1927 | Values: 63.0 55.0 58.0 64.0 58.0 58.0 56.0 56.0 56.0 59.0
02:21:44 INFO - PROCESS | 1927 |
02:21:44 INFO - PROCESS | 1927 | [#10] stanford-crypto-aes Cycles:10 Average:88.60 Median:87.00 stddev:5.04 (5.8%) stddev-sans-first:5.31
02:21:44 INFO - PROCESS | 1927 | Values: 87.0 86.0 89.0 87.0 86.0 84.0 86.0 89.0 90.0 102.0
02:21:44 INFO - PROCESS | 1927 |
02:21:44 INFO - PROCESS | 1927 | [#11] stanford-crypto-ccm Cycles:10 Average:145.10 Median:140.50 stddev:13.33 (9.5%) stddev-sans-first:13.72
02:21:44 INFO - PROCESS | 1927 | Values: 136.0 142.0 138.0 137.0 139.0 138.0 138.0 147.0 178.0 158.0
02:21:44 INFO - PROCESS | 1927 |
02:21:44 INFO - PROCESS | 1927 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:183.20 Median:176.00 stddev:23.38 (13.3%) stddev-sans-first:24.50
02:21:44 INFO - PROCESS | 1927 | Values: 173.0 170.0 163.0 164.0 178.0 222.0 227.0 193.0 174.0 168.0
02:21:44 INFO - PROCESS | 1927 |
02:21:44 INFO - PROCESS | 1927 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:74.70 Median:79.00 stddev:11.31 (14.3%) stddev-sans-first:10.68
02:21:44 INFO - PROCESS | 1927 | Values: 60.0 74.0 70.0 65.0 91.0 87.0 84.0 86.0 65.0 65.0
02:21:44 INFO - PROCESS | 1927 | -------- Summary: end --------
02:21:44 INFO - PROCESS | 1927 |
02:21:45 INFO - TEST-INFO | 1927: exit 0
02:21:45 INFO - TEST-OK | kraken | took 61586ms
02:21:45 INFO - SUITE-END | took 1178s
02:21:45 INFO - Completed test suite (00:19:38)
02:21:45 INFO - javascript benchmark
02:21:45 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7656.982265086147, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2886.89895853645, 3000.309928716395, 3468.1329667583104, 3498.3702933471964, 3537.5931767846696, 4934.3585333906585, 5156.561692256163, 6056.333803281641, 6125.908111378321, 6136.723847426865, 5905.704714764266, 6100.176476175715, 6140.232470941132, 6163.167972323735, 6179.938200617992, 5435.537979271757, 5494.560435165177, 5518.255035570187, 5571.220029195895, 5590.273264475612, 4535.274356103024, 5553.028220061493, 5554.86112847178, 5563.499285064343, 5579.776808927638, 5357.651752636086, 5382.865428364303, 5591.30108736408, 5594.832155035356, 5621.269234999444, 5457.372402173761, 5464.043792336345, 5467.5625949923915, 5481.287432633772, 5496.285482887199, 5409.594280428983, 5423.511883930465, 5470.261514695505, 5521.641093328921, 5580.469855363734, 6160.229971253593, 6200.658963756991, 6203.0, 6222.968885155546, 6238.750449981995, 6196.070589411579, 6265.530085243579, 6454.515911306624, 6489.740410383579, 6500.804975850732, 6342.365763423621, 6415.1981002374705, 6445.709943052574, 6569.704363303663, 6600.372964568358, 6237.345078766755, 6270.874582508415, 6274.623522588659, 6283.277423096311, 6283.3088360280335, 6641.269460359356, 6658.167729033871, 6683.331666833375, 6684.598924064571, 6706.798796036127, 6208.161898143783, 6248.718807653667, 6255.74977000924, 6283.3402492737605, 6286.245650521967, 5416.972915135478, 5486.231927530149, 6456.709448074848, 6529.053287273318, 6533.803985880431, 6352.333005034405, 6391.265004524446, 6403.96798016007, 6422.967885160544, 6446.613203207822, 6201.317855035943, 6243.843903902439, 6256.906146407808, 6450.612963222222, 6470.2559205691, 4339.305711086211, 5057.165567681292, 5158.509941555546, 5212.270282160501, 5241.266222728821, 4507.098580283956, 4569.725816451024, 4571.017231295287, 4590.724556526552, 4593.954060459419, 4740.28895665646, 6120.296165940974, 6127.969360153171, 6178.258608966953, 6195.039768835838, 6259.217597800275, 6561.179852518435, 6563.540552161398, 6574.868502629921, 6589.209294884548, 5981.431763982415, 6088.330283668793, 6127.234095738033, 6170.444659980623, 6206.162168107338, 5789.131630255495, 5790.623609465371, 5791.160281759121, 5797.304323481209, 5809.0, 6734.865302693918, 6757.256701762803, 6758.8648227036165, 6780.593164410151, 6788.830279243059, 5888.646681199141, 6240.7503699851495, 6355.110284560258, 6448.967755161382, 6471.094046833541, 6624.6025238485845, 6659.866802664016, 6679.866402672016, 6724.260331363644, 6741.089952856301, 6411.3909178627955, 6438.774642887476, 6442.194725659293, 6451.2903580607035, 6463.289038205888, 6240.968795155995, 6304.936950630618, 6419.197600299963, 6435.356464353715, 6490.8052758417325, 6219.564630475823, 6342.460890824332, 6376.202974628171, 6382.968085159545, 6388.936110638834, 6339.397757213057, 6370.980643097082, 6386.074019267273, 6399.136116624196, 6400.423961843457, 5333.8265581571995, 5379.735762095983, 6053.152558641706, 6185.0, 6216.53375996793, 6268.843278918063, 6286.27707813589, 6315.210598675166, 6344.841378965562, 6347.5239357047485, 5226.477352264743, 6249.218847644044, 6260.248770147611, 6277.466415354746, 6293.465055470153], "value": 5915.830414387801, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [793.3494534481728, 811.2414892075909, 813.4265342933231, 867.7873920889383, 940.2571968145165, 38061.61938380615, 38908.02729931753, 39199.648021118715, 40477.0, 40635.59364406355, 38855.80572097136, 39005.41491877617, 39081.02297442565, 39134.825955221386, 39147.21705565887, 7500.662470188828, 7588.241175882423, 7841.294283514469, 7955.960220198891, 11117.499712512956, 11317.773644527104, 11413.942930285379, 11437.142214333917, 11441.199116061878, 11448.542058317656, 9678.419294842297, 11447.02700270478, 11463.0, 11466.312021278709, 11508.71228219297, 11352.0, 11400.315981041082, 11401.828972565418, 11402.31586104828, 11404.0, 11055.170862185289, 11109.111271098292, 11120.165987550967, 11122.944385278102, 11133.72165695856, 39450.80274598609, 39581.812545623674, 39643.00892477683, 39663.60336396628, 39717.0, 3802.7718336899875, 3813.8474461021524, 3838.9040273993096, 3892.338302488584, 3911.178652482982, 28119.156425307272, 29652.8517357414, 30143.09570712882, 30262.84868575643, 30297.091087267196, 3005.7144571265694, 3136.905892823219, 3151.495760678303, 3288.3423315337027, 3290.1610089427045, 357334.0, 359537.0, 363090.0, 363094.0, 364567.0, 44307.11385772267, 44853.10293794106, 53366.0, 54319.45680543223, 54377.72811135919, 55388.0, 56392.15411768827, 56529.15206271827, 56754.716226418604, 56914.86170276571, 14868.330925108396, 14949.925250373679, 14999.17504537239, 15041.924790375979, 15062.246887655574, 10998.395088270061, 11205.0, 12851.614451566467, 12861.0, 12923.0, 13501.324933753272, 13507.459701612022, 13541.0, 13604.04771665974, 13632.727345453035, 6095.323785958897, 6097.939020609826, 6123.84690382744, 6127.938720612826, 6147.170132032208, 948.7343543807744, 1071.8959471744115, 1089.8147314956318, 1111.1166622535104, 1126.695792136135, 26962.115512061155, 27177.45645087127, 27310.5903411449, 27400.177994660193, 27597.0, 941.9105185007413, 998.9500524973722, 1006.7181189267017, 1040.422565476168, 1222.5904322052033, 13593.660158496117, 13676.726465470634, 13781.966352523601, 13801.723965520634, 13948.930255348861, 496411.0, 500730.0, 502347.0, 502860.0, 502923.0, 47563.28655070177, 48008.27987580189, 48087.519124809005, 48122.0, 48221.758891206024, 1293.8318018657515, 1328.156620545941, 1328.4686125549665, 1338.0767270583267, 1345.9259740714158, 20070.09684564169, 25056.62415063739, 25898.870505648105, 26302.736972630795, 26406.867965660047, 41993.0, 42236.78881605572, 42317.78841105775, 42412.575874242095, 42415.5758442424, 485927.0, 486041.0, 486111.0, 486124.0, 487521.0, 24104.75895241025, 30230.54654180145, 31005.534916976714, 32039.0, 32053.67946320507, 39099.631512896915, 39345.80327098346, 40067.59932400638, 40151.0, 40188.1962360757, 28191.0, 28244.576331355456, 28754.568681470213, 28922.42155156843, 29458.705412945597, 24985.625215621418, 26177.560234187302, 28414.715852841207, 28561.000364987125, 29026.564601531416, 32307.51538726874, 33272.00183994484, 33349.499757503174, 33522.32955340928, 33545.0, 22972.77027229773, 26545.60181597316, 27117.0, 28827.71172288334, 29403.705962940952, 22441.551168976857, 23133.421664458525, 23216.767832322137, 23622.88188559046, 23695.881520592287], "value": 18615.816122336888, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [402.11334008511216, 405.8396933211383, 410.2390066426779, 418.20332267031307, 439.93096774837153, 19443.319483818068, 19647.60704785905, 20038.59922801543, 20425.080871360788, 20653.586928261444, 21160.365189044314, 21222.57554848902, 21514.784852151475, 21974.0, 22255.888720556413, 5779.479846813786, 7044.753433629824, 7052.188998265188, 7055.259197784236, 7061.046758687588, 6529.510286728514, 6825.044493770876, 6856.074429951967, 6880.518363714517, 6880.759173428931, 5463.836084917439, 6098.658295175054, 6545.214574251097, 6876.724931002754, 6883.827904302382, 5942.673152976585, 6904.965475172592, 6923.653817309114, 6927.618980956071, 6934.965325173393, 6393.520485963525, 6645.900311495331, 6673.699683514254, 6684.665766711693, 6754.2907994660345, 20588.86761228132, 26062.0, 26101.73898261012, 26321.0, 26597.335066623295, 2704.837709737422, 2755.9586606200924, 2796.4826507096254, 2806.649168853893, 2812.6343575335077, 7541.944127822109, 7642.694292228304, 16620.750688739678, 16629.91685041567, 16689.0, 1783.2777725021388, 1810.63787242552, 2035.5827055453597, 2110.0504772852123, 2112.207922029239, 117066.0, 118652.40673796662, 120294.39852800767, 122251.0, 122321.16518252234, 16332.0, 21706.240281590068, 21805.345839624835, 21920.0, 21986.0, 34911.07989060575, 34982.30035399328, 35514.644853551654, 35743.28513429717, 35801.8209908954, 10656.0, 10818.29681070728, 10920.071793897609, 11009.064229540418, 11018.063464605599, 7320.524165929304, 8850.644132430229, 8908.06465321145, 8964.462132272085, 9030.683926062557, 9495.38280011797, 10188.9811018899, 10499.527521261562, 10923.508442120125, 10961.0, 3586.497890295361, 3915.4909861717792, 4262.744235345889, 4318.740875547477, 4345.327049086125, 764.3846703403702, 885.4421714319938, 885.6191837509932, 888.853339199025, 905.3798148268457, 20902.477438063866, 21762.0, 21802.672959905613, 21937.890310548344, 21971.121155153614, 842.5113434208145, 931.1970967388211, 932.6875496708678, 935.6818681648273, 947.280067148958, 7913.061299981541, 11382.317060976367, 11792.764144717057, 11799.941000294944, 11814.88185118155, 623788.0, 730522.0, 753104.0, 764026.0, 765312.0, 94658.0, 114781.0, 159931.0, 165864.17067914826, 167749.0, 1274.49020391844, 1274.8916342111024, 1325.0327261099258, 1327.8605746396684, 1336.1181620130667, 19036.714449282994, 19500.414987550397, 19803.306884258982, 20249.69625455649, 20275.0, 56855.0, 65955.01067484087, 65990.0, 66226.66886665729, 66821.32946676372, 86193.7070943949, 92164.53917730368, 93533.0, 93695.0, 95247.0, 8657.047724750399, 10152.64465743725, 11094.223404361617, 11225.49485273181, 11816.46825892854, 12689.302088385228, 12846.357682116044, 12947.741045179233, 13139.54011609589, 13243.20540767557, 10893.237473376788, 10904.672859814218, 10911.2362134653, 11118.833217501582, 11283.266587671775, 8088.0, 9085.727998080141, 9873.210143188384, 10371.222158338003, 10682.198835087554, 9885.357451765612, 10247.7438064049, 10965.725856853642, 11534.0, 11643.359615221245, 9669.854952175583, 11336.886631133582, 11406.201565890307, 11454.854514548479, 11567.595134170264, 7779.611019448892, 11017.779644407226, 11224.831627525431, 11442.713932151762, 11468.31190128595], "value": 11302.775693199119, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1454.803601513796, 1516.9469068582603, 1581.6757564699235, 1586.9526112765577, 1588.6902054099448, 10686.305390149635, 11116.444177791098, 11240.606578769744, 11315.886841131587, 11324.150688698339, 9051.502167380808, 9211.539423028855, 11274.323540587571, 11280.871912808736, 11307.208495405324, 2306.2043594959714, 2360.4216966843146, 2363.948043120812, 2364.988175059122, 2371.9644205336936, 2263.151318255654, 2343.297010896733, 2345.9882700586472, 2360.8819559022068, 2363.161077817376, 2257.6613507973852, 2306.8961896714686, 2342.625179971205, 2355.0815182079004, 2358.9056437742556, 2286.9771302287095, 2299.0, 2316.976830231693, 2326.813854891613, 2335.2177020698086, 2298.988505057481, 2308.4344335637707, 2315.3169814904477, 2315.3979965209, 2321.5472982768365, 11053.778924421547, 11054.281471704395, 11091.55633774648, 11136.498857551349, 11175.0, 4628.6991345562765, 5504.91742623861, 5683.0, 5684.488396044376, 5705.515031222351, 3199.856006479714, 3247.7564182686156, 3344.966550334514, 3372.426687463137, 3383.6954674079207, 1158.2645020412015, 1170.3153655111769, 1602.767598698194, 1748.6360638701833, 1757.0072908806483, 12165.270083795001, 14062.578122656336, 14232.928835355757, 15373.538793836204, 15928.681426371408, 4629.981404091094, 4630.814767409333, 4730.2431610942085, 4740.620750340034, 4762.618990480753, 9841.0, 12939.741205175844, 12950.481980720853, 12984.935075324753, 13001.349932503337, 5664.49019588239, 5680.318361796528, 5701.971490142606, 5703.714814259353, 5713.628614140151, 4907.754612269372, 5099.6685215460875, 5106.259592359087, 5160.148575485003, 5192.402873669501, 3836.3861782114727, 3887.814216663888, 3935.193285376491, 4010.298197815429, 4064.5732198119363, 1017.9871028326714, 1048.8688913885765, 1065.6909496246, 1072.8658917635296, 1081.6268387406333, 917.4632839788665, 931.0921851195166, 946.4794363100267, 950.6007476859736, 1011.110223003752, 6468.8059358219325, 6488.448481879093, 6634.0, 6750.7299708012115, 6754.493412994045, 967.2020583019038, 970.5438443931341, 992.9851052234222, 1003.7691330993912, 1023.5701005577675, 3582.283543291352, 3741.401375779862, 3956.1296514766705, 4163.625273725379, 4203.432536607519, 11353.60262390829, 13253.536126235536, 13875.58373248784, 13902.860971390359, 13907.374168162458, 11609.767804644029, 13280.0, 13540.052196346162, 13665.111767735065, 13847.376868040963, 10017.899821001842, 10274.383536987805, 10310.639127630648, 10344.844827327595, 10485.108765754843, 6504.707288171949, 6748.628825414649, 6903.309669033057, 6913.274106218976, 7037.894431583428, 11870.0, 12138.817917731418, 12153.270803751802, 12174.208676436003, 12361.320127393079, 11097.611583594535, 12217.755644887231, 12449.564265251034, 12564.434600442819, 12755.48978040868, 8885.777855553662, 9159.725208243763, 9166.862497062417, 9301.488418136796, 9313.534323283673, 10218.489075546044, 10330.225233107396, 10347.017033381817, 10434.791304173723, 10495.265331426726, 7378.704851805867, 8794.824103518022, 8795.604197810986, 8822.955885220534, 8901.376903616685, 8533.615987280711, 8614.3108551317, 8662.177093176138, 8663.0, 8729.869051964099, 7742.806429839074, 7752.341051010727, 7787.338076263581, 7863.449558530848, 7873.606319684108, 5675.205471233948, 5808.593398462067, 5858.355580886185, 5877.23595932526, 5985.88563876216, 5862.12068189775, 5880.206172166702, 5919.437653422918, 5963.2844058713235, 6046.30467496226], "value": 5107.003401334761, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [347.1529468097843, 349.18465383329925, 369.7686703278086, 381.71943621438237, 389.581921804631, 5179.81870634527, 5203.4796520347945, 5229.450907654698, 5267.420583735786, 5278.392984806748, 4425.77871106445, 4484.461864576256, 4792.880177995552, 5203.933193695293, 5213.817516386927, 3365.4951757236486, 4000.4796977904025, 4014.808227459177, 4188.602082802144, 4328.653707703376, 3556.590992035923, 3725.962740372602, 4069.006186968379, 4366.463164836477, 4384.298512238042, 4148.274052040896, 4224.704270701052, 4378.737275763448, 4400.889977750549, 4413.470383553978, 3583.3191693578137, 4004.9599504004877, 4259.595338442843, 4410.360497727827, 4415.624671902892, 3977.3835055566433, 3994.7603143811466, 4005.517958355398, 4050.6354428101317, 4067.51189857219, 4405.22908491016, 4892.584130348924, 5182.248573956793, 5195.19474481456, 5210.921836172422, 4443.777811109431, 4921.704697718113, 5065.189569668835, 5091.0581542414775, 5110.616703747234, 5603.159526071081, 5670.0, 5699.549193846644, 5751.447109280471, 5780.913286300709, 4316.395704601358, 4516.696484792747, 4528.128450175086, 4558.521355257683, 4569.109023740372, 4840.273958906157, 4918.778654960535, 5334.119870221409, 5469.699166545838, 5805.53555715546, 3993.2845664947536, 4301.494476933049, 4403.603675669206, 4617.538246175355, 4623.398958135488, 4019.789115986196, 4521.321801729701, 4569.634429245652, 4663.370444989883, 4765.285207218945, 4350.912981740347, 4649.325847752057, 4744.695208817603, 4839.564439200489, 4888.682235654671, 5224.268602395668, 5515.896820635889, 5599.244102046253, 5627.296587926509, 5707.828765137053, 1339.6115126613367, 1592.0, 1593.8406159384201, 1594.5136733296224, 1602.1748799368336, 955.1308309438377, 1085.3270971997413, 1104.812181929074, 1197.963761346429, 1214.9514019439123, 4793.664443488922, 5070.518300761421, 5270.393904701008, 5322.760475778599, 5345.893082138336, 5794.652320860761, 5875.29496460419, 5881.00068964831, 5926.362768567946, 5969.343372229051, 4080.2346448488443, 4732.6687131900435, 4760.547747963938, 5231.3199284092825, 5273.630845840754, 2715.932101697473, 3035.1653295343517, 3065.601471808651, 3067.708567686066, 3102.1314032071054, 5682.77268909239, 6069.998450255746, 6074.514038876968, 6093.786717464867, 6106.358832322631, 5345.1447768356875, 5472.005436031719, 5539.972300138474, 5684.54523638108, 5966.181643748005, 5506.394296627368, 5801.82594522165, 5932.644041357532, 6026.0, 6050.848728781815, 4993.551869957817, 5032.986805277918, 5425.755840987086, 5783.710814459176, 5875.914050512178, 4640.429177260463, 4716.806684891542, 5037.140088937413, 5299.887023724907, 5769.336526299529, 5046.873828154178, 5096.598435430286, 5248.24183898383, 5995.970020149872, 6027.46846324536, 3916.686665066845, 4027.0536423940935, 4526.117407105715, 4559.202139625486, 4586.945002649342, 4710.845842768544, 4892.602624713936, 5444.686008446473, 5537.311120108245, 5777.8555536111935, 5223.895522089613, 5603.691796951207, 5658.840752047325, 5678.176664383724, 5684.971575142098, 5187.714675692722, 5240.34495688039, 5450.150756273237, 5555.638883472561, 5664.801731939362, 4393.62654174386, 4816.686915350631, 5208.947910520847, 5463.207834864002, 5583.832485025456, 4938.629602779878, 4978.726170060681, 5419.187121931742, 5656.830295091154, 5663.263775709132, 4667.989147000234, 4681.976590117028, 5183.663061900965, 5485.698286594116, 5547.750351234124], "value": 4241.7778741672555, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [683.8085336105889, 689.3657834791991, 744.2111361956327, 805.5368163306099, 829.4981536170617, 15349.0, 15432.459863904765, 16021.59946001349, 16071.919640401811, 16159.030458172503, 12947.48210071596, 15348.61628459289, 15533.301001454962, 15605.297761600727, 16226.756598651029, 913.7213149989267, 918.2332752151962, 928.7770934975625, 929.9349045566813, 931.7903471718843, 888.6667499687617, 923.5520772425368, 925.0656836595053, 925.5742358515091, 926.0045451140018, 1001.7695929936083, 1002.413588050991, 1004.1063453526367, 1005.8038682456923, 1006.4112494190907, 992.7865508915581, 993.88073431188, 997.306871724152, 998.4907697074472, 999.6601155607092, 624.2010226909563, 734.4711807498646, 740.0896896816945, 740.5371642723298, 741.4624397311906, 16257.024578525326, 16677.833221667748, 16805.495835124966, 16881.40915067979, 16898.662026759517, 12570.120091593591, 12861.16402433848, 13521.188728676212, 13860.168389896638, 14106.435742570286, 11109.0, 12320.199187052813, 12404.441800119017, 12840.165389249669, 12924.0, 4308.116836048603, 4320.179165958458, 4390.692651514395, 4411.6250118739945, 4489.573490518395, 70408.6479567604, 96875.0, 102493.4875325626, 102728.48635756773, 103837.96162038359, 8752.431091979, 10369.222308326905, 10441.791164176673, 10451.02919245674, 10723.981221783917, 19552.413427597196, 20669.89665051665, 20793.792062079487, 20887.68668469974, 20941.790582094287, 7463.8507229855095, 7560.621968901533, 7576.659050342748, 7665.808354791175, 7702.845943081107, 4251.638610718123, 4278.871633850989, 4312.37470566766, 4331.7834108294455, 4431.8670439886855, 3178.3166619176986, 3259.071164718044, 3260.1849537615594, 3261.2662151015834, 3271.0677456925137, 596.4214711729616, 613.2365205319317, 625.4964753373487, 635.86964672243, 637.5186734015789, 540.4325458268802, 556.8751122732059, 572.1275055540334, 580.7676929228343, 586.7447660267798, 9360.531973401303, 9829.655962041294, 9835.704928852147, 10058.396496210251, 10142.695719128438, 616.636184651058, 640.3692363022419, 653.6862306093102, 657.4083325007532, 663.575311800448, 4249.936250956238, 4290.485141783006, 4741.336212930192, 4752.738599377068, 4818.204996175662, 79761.60119199484, 97115.54326685105, 99235.0, 100034.0, 100330.0, 75505.24494754981, 78534.0, 78573.60713196397, 87471.56264218639, 88564.6715299271, 72124.91812622815, 76122.4775504498, 76569.85145222826, 78345.0, 79522.20477795148, 10572.947135264274, 11146.119151934488, 11182.496787644739, 11401.771964560829, 11404.201705880509, 45899.0, 51155.0, 51712.74143629258, 52034.21948670697, 52721.20918186154, 62818.0, 64877.02684459831, 66401.0, 68297.0, 69886.65056674684, 12676.04929630263, 13201.933990329986, 13216.140950838164, 13285.66785830362, 13464.730705386033, 12341.50633974631, 14475.565733028026, 14697.632559186106, 14748.33632486562, 14880.466588371259, 13528.86471135276, 13734.244616546186, 13747.587572372844, 14365.712685746435, 14647.26763661834, 11106.833397498882, 12517.624471265877, 12528.874711253135, 13696.0, 13839.723205536035, 10024.147947424259, 12434.813477798021, 13570.253636050113, 13713.382897769823, 13990.930045349709, 9008.819823603622, 9108.0, 9402.95298523503, 9529.0, 9776.120149186607, 9609.327347085637, 10958.28771129874, 11106.555737770723, 11169.553217871518, 11284.0], "value": 7473.232480930305, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1540.2, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [107.0, 118.0, 135.0, 177.0, 159.0, 119.0, 119.0, 119.0, 119.0, 118.0], "value": 129.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [131.0, 111.0, 113.0, 145.0, 111.0, 114.0, 112.0, 119.0, 155.0, 126.0], "value": 123.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [196.0, 199.0, 199.0, 203.0, 198.0, 207.0, 263.0, 282.0, 196.0, 236.0], "value": 217.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 59.0, 62.0, 87.0, 63.0, 63.0, 65.0, 62.0, 100.0, 87.0], "value": 73.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 72.0, 86.0, 73.0, 73.0, 70.0, 82.0, 71.0, 73.0, 108.0], "value": 79.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 90.0, 128.0, 121.0, 138.0, 136.0, 88.0, 88.0, 88.0, 88.0], "value": 105.2, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 97.0, 98.0, 130.0, 96.0, 95.0, 97.0, 97.0, 97.0, 102.0], "value": 100.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [118.0, 90.0, 91.0, 91.0, 92.0, 85.0, 91.0, 91.0, 91.0, 92.0], "value": 93.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 67.0, 64.0, 68.0, 70.0, 71.0, 69.0, 68.0, 68.0, 70.0], "value": 68.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 55.0, 58.0, 64.0, 58.0, 58.0, 56.0, 56.0, 56.0, 59.0], "value": 58.3, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 86.0, 89.0, 87.0, 86.0, 84.0, 86.0, 89.0, 90.0, 102.0], "value": 88.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [136.0, 142.0, 138.0, 137.0, 139.0, 138.0, 138.0, 147.0, 178.0, 158.0], "value": 145.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [173.0, 170.0, 163.0, 164.0, 178.0, 222.0, 227.0, 193.0, 174.0, 168.0], "value": 183.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 74.0, 70.0, 65.0, 91.0, 87.0, 84.0, 86.0, 65.0, 65.0], "value": 74.7, "unit": "score"}]}]}
02:21:46 INFO - Return code: 0
02:21:46 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json
02:21:46 ERROR - # TBPL SUCCESS #
02:21:46 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'
02:21:46 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build
02:21:46 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType"
02:21:46 INFO - 2017-03-28 02:21:46.119 screenresolution[1932:20681] starting screenresolution argv=screenresolution get
02:21:46 INFO - 2017-03-28 02:21:46.122 screenresolution[1932:20681] Display 0: 1600x1200x32@60
02:21:46 INFO - 2017-03-28 02:21:46.129 screenresolution[1933:20684] starting screenresolution argv=screenresolution list
02:21:46 INFO - Available Modes on Display 0
02:21:46 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75
02:21:46 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60
02:21:46 INFO - 800x600x32@75 800x600x32@60 640x480x32@75
02:21:46 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60
02:21:46 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays:
02:21:46 INFO - Intel Iris:
02:21:46 INFO - Chipset Model: Intel Iris
02:21:46 INFO - Type: GPU
02:21:46 INFO - Bus: Built-In
02:21:46 INFO - VRAM (Dynamic, Max): 1536 MB
02:21:46 INFO - Vendor: Intel (0x8086)
02:21:46 INFO - Device ID: 0x0a2e
02:21:46 INFO - Revision ID: 0x0009
02:21:46 INFO - Displays:
02:21:46 INFO - Display:
02:21:46 INFO - Resolution: 1600 x 1200 @ 60 Hz
02:21:46 INFO - Pixel Depth: 32-Bit Color (ARGB8888)
02:21:46 INFO - Display Serial Number: 1600x1200 60
02:21:46 INFO - Main Display: Yes
02:21:46 INFO - Mirror: Off
02:21:46 INFO - Online: Yes
02:21:46 INFO - Rotation: Supported
02:21:46 INFO - Return code: 0
02:21:46 INFO - Running post-action listener: _resource_record_post_action
02:21:46 INFO - [mozharness: 2017-03-28 09:21:46.368829Z] Finished run-tests step (success)
02:21:46 INFO - Running post-run listener: _resource_record_post_run
02:21:46 INFO - Total resource usage - Wall time: 1200s; CPU: 55.0%; Read bytes: 1249280; Write bytes: 511007744; Read time: 15; Write time: 1982
02:21:46 INFO - TinderboxPrint: CPU usage
54.6%
02:21:46 INFO - TinderboxPrint: I/O read bytes / time
1,249,280 / 15
02:21:46 INFO - TinderboxPrint: I/O write bytes / time
511,007,744 / 1,982
02:21:46 INFO - TinderboxPrint: CPU idle
2,181.2 (45.4%)
02:21:46 INFO - TinderboxPrint: CPU system
966.3 (20.1%)
02:21:46 INFO - TinderboxPrint: CPU user
1,654.1 (34.4%)
02:21:46 INFO - TinderboxPrint: Swap in / out
209,506,304 / 0
02:21:46 INFO - install - Wall time: 21s; CPU: 54.0%; Read bytes: 203414016; Write bytes: 199106560; Read time: 15826; Write time: 362
02:21:46 INFO - run-tests - Wall time: 1180s; CPU: 55.0%; Read bytes: 1249280; Write bytes: 301124608; Read time: 15; Write time: 1589
02:21:46 INFO - Running post-run listener: _upload_blobber_files
02:21:46 INFO - Blob upload gear active.
02:21:46 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir.
02:21:46 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
02:21:46 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', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json']
02:21:46 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 Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json
02:21:47 INFO - (blobuploader) - INFO - Open directory for files ...
02:21:47 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ...
02:21:47 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com
02:21:47 INFO - (blobuploader) - INFO - Uploading, attempt #1.
02:21:47 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded
02:21:47 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded!
02:21:47 INFO - (blobuploader) - INFO - Done attempting.
02:21:47 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ...
02:21:48 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com
02:21:48 INFO - (blobuploader) - INFO - Uploading, attempt #1.
02:21:48 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded
02:21:48 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded!
02:21:48 INFO - (blobuploader) - INFO - Done attempting.
02:21:48 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ...
02:21:48 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com
02:21:48 INFO - (blobuploader) - INFO - Uploading, attempt #1.
02:21:50 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded
02:21:50 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded!
02:21:50 INFO - (blobuploader) - INFO - Done attempting.
02:21:50 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ...
02:21:50 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com
02:21:50 INFO - (blobuploader) - INFO - Uploading, attempt #1.
02:21:52 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded
02:21:52 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded!
02:21:52 INFO - (blobuploader) - INFO - Done attempting.
02:21:52 INFO - (blobuploader) - INFO - Iteration through files over.
02:21:52 INFO - Return code: 0
02:21:52 INFO - rmtree: /builds/slave/test/build/uploaded_files.json
02:21:52 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1
02:21:52 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/fa391803651c3db379b10c839090517e068ae0d27ffa967312fd7f4372fd270593d2423c440b94f74ff5525582fa1926a03d9c06fe96231ac491fe45c24d1a92", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/fb242479a3a8bedcb1fa370793f6b9dcdd90b978c0b4dfe47f7f0be03cdd93925aa64f4dfb733936a7057cf92d9d21a5343df2b8f3355a90a87467ee67d6fe01", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c626417a57ec0b7c613e88ae374614ac6aca22705fd5e47141ed7524f2b0366c57a7f3f2471a4cc32ba2924f9c043991e346a9c1bccd6c384a334e54a6ce7965", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/88b207f31cd7a3b5f6ccb7d61e2b428afa970c691e9f3dc0cf0ad153c1f53d9f792d38c0213b21fbdb8f6144df1fe39cff3d0bd15348ca121dcf085524e6eacf"}
02:21:52 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files
02:21:52 INFO - Writing to file /builds/slave/test/properties/blobber_files
02:21:52 INFO - Contents:
02:21:52 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/fa391803651c3db379b10c839090517e068ae0d27ffa967312fd7f4372fd270593d2423c440b94f74ff5525582fa1926a03d9c06fe96231ac491fe45c24d1a92", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/fb242479a3a8bedcb1fa370793f6b9dcdd90b978c0b4dfe47f7f0be03cdd93925aa64f4dfb733936a7057cf92d9d21a5343df2b8f3355a90a87467ee67d6fe01", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c626417a57ec0b7c613e88ae374614ac6aca22705fd5e47141ed7524f2b0366c57a7f3f2471a4cc32ba2924f9c043991e346a9c1bccd6c384a334e54a6ce7965", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/88b207f31cd7a3b5f6ccb7d61e2b428afa970c691e9f3dc0cf0ad153c1f53d9f792d38c0213b21fbdb8f6144df1fe39cff3d0bd15348ca121dcf085524e6eacf"}
02:21:52 INFO - Running post-run listener: copy_logs_to_upload_dir
02:21:52 INFO - Copying logs to upload dir...
02:21:52 INFO - mkdir: /builds/slave/test/build/upload/logs
02:21:52 INFO - Copying logs to upload dir...
program finished with exit code 0
elapsedTime=1281.417525
========= master_lag: 0.04 =========
========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 21 secs) (at 2017-03-28 02:21:52.233339) =========
========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-28 02:21:52.237127) =========
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.0VMVrWf0XM/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.YjIGNKBMXi/Listeners
TMPDIR=/var/folders/9c/2dh985pd15l19fywb0pq404000000w/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-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/fa391803651c3db379b10c839090517e068ae0d27ffa967312fd7f4372fd270593d2423c440b94f74ff5525582fa1926a03d9c06fe96231ac491fe45c24d1a92", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/fb242479a3a8bedcb1fa370793f6b9dcdd90b978c0b4dfe47f7f0be03cdd93925aa64f4dfb733936a7057cf92d9d21a5343df2b8f3355a90a87467ee67d6fe01", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c626417a57ec0b7c613e88ae374614ac6aca22705fd5e47141ed7524f2b0366c57a7f3f2471a4cc32ba2924f9c043991e346a9c1bccd6c384a334e54a6ce7965", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/88b207f31cd7a3b5f6ccb7d61e2b428afa970c691e9f3dc0cf0ad153c1f53d9f792d38c0213b21fbdb8f6144df1fe39cff3d0bd15348ca121dcf085524e6eacf"}
build_url:https://queue.taskcluster.net/v1/task/RLih5AzXS-mN1Qrsh7HNNQ/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg
program finished with exit code 0
elapsedTime=0.011033
build_url: 'https://queue.taskcluster.net/v1/task/RLih5AzXS-mN1Qrsh7HNNQ/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg'
blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/fa391803651c3db379b10c839090517e068ae0d27ffa967312fd7f4372fd270593d2423c440b94f74ff5525582fa1926a03d9c06fe96231ac491fe45c24d1a92", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/fb242479a3a8bedcb1fa370793f6b9dcdd90b978c0b4dfe47f7f0be03cdd93925aa64f4dfb733936a7057cf92d9d21a5343df2b8f3355a90a87467ee67d6fe01", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c626417a57ec0b7c613e88ae374614ac6aca22705fd5e47141ed7524f2b0366c57a7f3f2471a4cc32ba2924f9c043991e346a9c1bccd6c384a334e54a6ce7965", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/88b207f31cd7a3b5f6ccb7d61e2b428afa970c691e9f3dc0cf0ad153c1f53d9f792d38c0213b21fbdb8f6144df1fe39cff3d0bd15348ca121dcf085524e6eacf"}'
========= master_lag: 0.02 =========
========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-28 02:21:52.271488) =========
========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-28 02:21:52.271865) =========
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.0VMVrWf0XM/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.YjIGNKBMXi/Listeners
TMPDIR=/var/folders/9c/2dh985pd15l19fywb0pq404000000w/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.005375
========= master_lag: 0.04 =========
========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-28 02:21:52.321339) =========
========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-28 02:21:52.322110) =========
========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-28 02:21:52.322847) =========
========= Total master_lag: 0.21 =========