builder: mozilla-beta_yosemite_r7_test-dromaeojs
slave: t-yosemite-r7-0008
starttime: 1467027893.51
results: success (0)
buildid: 20160627033728
builduid: bbebe8b318bb492daf3521276efec601
revision: e2f8b2e421f954c7f3b74f9f7bd7a982aed94d05
========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-06-27 04:44:53.514265) =========
master: http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/
========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-06-27 04:44:53.514767) =========
========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-27 04:44:53.515065) =========
========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-27 04:44:53.543037) =========
========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-27 04:44:53.543303) =========
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.ILS8nAVdl1/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.Xa2o4KSJuj/Listeners
TMPDIR=/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/
TWISTD_LOG_PATH=/builds/slave/twistd.log
USER=cltbld
VERSIONER_PYTHON_PREFER_32_BIT=no
VERSIONER_PYTHON_VERSION=2.7
XPCOM_DEBUG_BREAK=warn
XPC_FLAGS=0x0
XPC_SERVICE_NAME=0
__CF_USER_TEXT_ENCODING=0x1C:0:0
using PTY: False
--2016-06-27 04:44:53-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py
Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25
Connecting to hg.mozilla.org|63.245.215.102|:443... connected.
HTTP request sent, awaiting response... 200 Script output follows
Length: 12141 (12K) [text/x-python]
Saving to: 'archiver_client.py'
0K .......... . 100% 161M=0s
2016-06-27 04:44:54 (161 MB/s) - 'archiver_client.py' saved [12141/12141]
program finished with exit code 0
elapsedTime=0.592522
========= master_lag: 0.02 =========
========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-27 04:44:54.158325) =========
========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-27 04:44:54.158618) =========
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.ILS8nAVdl1/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.Xa2o4KSJuj/Listeners
TMPDIR=/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/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.039199
========= master_lag: 0.02 =========
========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-27 04:44:54.217625) =========
========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-06-27 04:44:54.217939) =========
bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev e2f8b2e421f954c7f3b74f9f7bd7a982aed94d05 --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-beta --rev e2f8b2e421f954c7f3b74f9f7bd7a982aed94d05 --destination scripts --debug']
environment:
Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.ILS8nAVdl1/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.Xa2o4KSJuj/Listeners
TMPDIR=/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/
TWISTD_LOG_PATH=/builds/slave/twistd.log
USER=cltbld
VERSIONER_PYTHON_PREFER_32_BIT=no
VERSIONER_PYTHON_VERSION=2.7
XPCOM_DEBUG_BREAK=warn
XPC_FLAGS=0x0
XPC_SERVICE_NAME=0
__CF_USER_TEXT_ENCODING=0x1C:0:0
using PTY: False
2016-06-27 04:44:54,295 truncating revision to first 12 chars
2016-06-27 04:44:54,295 Setting DEBUG logging.
2016-06-27 04:44:54,295 attempt 1/10
2016-06-27 04:44:54,295 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/e2f8b2e421f9?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness
2016-06-27 04:44:55,560 unpacking tar archive at: mozilla-beta-e2f8b2e421f9/testing/mozharness/
program finished with exit code 0
elapsedTime=1.537730
========= master_lag: 0.03 =========
========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-06-27 04:44:55.780889) =========
========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-27 04:44:55.781212) =========
script_repo_revision: e2f8b2e421f954c7f3b74f9f7bd7a982aed94d05
========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-27 04:44:55.781593) =========
========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-27 04:44:55.782286) =========
========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-27 04:44:55.805177) =========
========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 21 secs) (at 2016-06-27 04:44:55.805458) =========
/tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta
in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs)
watching logfiles {}
argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Beta', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Beta']
environment:
Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.ILS8nAVdl1/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.Xa2o4KSJuj/Listeners
TMPDIR=/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/
TWISTD_LOG_PATH=/builds/slave/twistd.log
USER=cltbld
VERSIONER_PYTHON_PREFER_32_BIT=no
VERSIONER_PYTHON_VERSION=2.7
XPCOM_DEBUG_BREAK=warn
XPC_FLAGS=0x0
XPC_SERVICE_NAME=0
__CF_USER_TEXT_ENCODING=0x1C:0:0
using PTY: False
04:44:55 INFO - MultiFileLogger online at 20160627 04:44:55 in /builds/slave/test
04:44:55 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta
04:44:55 INFO - Dumping config to /builds/slave/test/logs/localconfig.json.
04:44:55 INFO - {'append_to_log': False,
04:44:55 INFO - 'base_work_dir': '/builds/slave/test',
04:44:55 INFO - 'blob_upload_branch': 'Mozilla-Beta',
04:44:55 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt',
04:44:55 INFO - 'branch': 'Mozilla-Beta',
04:44:55 INFO - 'buildbot_json_path': 'buildprops.json',
04:44:55 INFO - 'config_files': ('talos/mac_config.py',),
04:44:55 INFO - 'default_actions': ('clobber',
04:44:55 INFO - 'read-buildbot-config',
04:44:55 INFO - 'download-and-extract',
04:44:55 INFO - 'populate-webroot',
04:44:55 INFO - 'create-virtualenv',
04:44:55 INFO - 'install',
04:44:55 INFO - 'run-tests'),
04:44:55 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',),
04:44:55 INFO - 'download_minidump_stackwalk': True,
04:44:55 INFO - 'download_symbols': 'ondemand',
04:44:55 INFO - 'exes': {'python': '/tools/buildbot/bin/python',
04:44:55 INFO - 'tooltool.py': '/tools/tooltool.py',
04:44:55 INFO - 'virtualenv': ('/tools/buildbot/bin/python',
04:44:55 INFO - '/tools/misc-python/virtualenv.py')},
04:44:55 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub',
04:44:55 INFO - 'http://pypi.pub.build.mozilla.org/pub'),
04:44:55 INFO - 'installer_path': 'installer.exe',
04:44:55 INFO - 'log_level': 'info',
04:44:55 INFO - 'log_name': 'talos',
04:44:55 INFO - 'log_to_console': True,
04:44:55 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk',
04:44:55 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest',
04:44:55 INFO - 'opt_config_files': (),
04:44:55 INFO - 'pip_index': False,
04:44:55 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'),
04:44:55 INFO - 'cmd': ('bash',
04:44:55 INFO - '-c',
04:44:55 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'),
04:44:55 INFO - 'enabled': True,
04:44:55 INFO - 'halt_on_failure': False,
04:44:55 INFO - 'name': 'check_screen_resolution'},),
04:44:55 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'),
04:44:55 INFO - 'cmd': ('bash',
04:44:55 INFO - '-c',
04:44:55 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'),
04:44:55 INFO - 'enabled': True,
04:44:55 INFO - 'halt_on_failure': False,
04:44:55 INFO - 'name': 'check_screen_resolution'},),
04:44:55 INFO - 'run_cmd_checks_enabled': True,
04:44:55 INFO - 'sps_profile': False,
04:44:55 INFO - 'sps_profile_interval': 0,
04:44:55 INFO - 'suite': 'dromaeojs',
04:44:55 INFO - 'system_bits': '32',
04:44:55 INFO - 'talos_extra_options': ('--webServer', 'localhost'),
04:44:55 INFO - 'title': 't-yosemite-r7-0008',
04:44:55 INFO - 'tooltool_cache': '/builds/tooltool_cache',
04:44:55 INFO - 'use_talos_json': True,
04:44:55 INFO - 'virtualenv_path': '/builds/slave/test/build/venv',
04:44:55 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None},
04:44:55 INFO - 'work_dir': 'build'}
04:44:55 INFO - #####
04:44:55 INFO - ##### Running clobber step.
04:44:55 INFO - #####
04:44:55 INFO - Running pre-action listener: _resource_record_pre_action
04:44:55 INFO - Running main action method: clobber
04:44:55 INFO - rmtree: /builds/slave/test/build
04:44:56 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1
04:44:58 INFO - Running post-action listener: _resource_record_post_action
04:44:58 INFO - #####
04:44:58 INFO - ##### Running read-buildbot-config step.
04:44:58 INFO - #####
04:44:58 INFO - Running pre-action listener: _resource_record_pre_action
04:44:58 INFO - Running main action method: read_buildbot_config
04:44:58 INFO - Using buildbot properties:
04:44:58 INFO - {
04:44:58 INFO - "project": "",
04:44:58 INFO - "product": "firefox",
04:44:58 INFO - "script_repo_revision": "production",
04:44:58 INFO - "builddir": "mozilla-beta_yosemite_r7_test-dromaeojs",
04:44:58 INFO - "repository": "",
04:44:58 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-beta talos dromaeojs",
04:44:58 INFO - "buildid": "20160627033728",
04:44:58 INFO - "pgo_build": "False",
04:44:58 INFO - "basedir": "/builds/slave/test",
04:44:58 INFO - "buildnumber": 7,
04:44:58 INFO - "slavename": "t-yosemite-r7-0008",
04:44:58 INFO - "master": "http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/",
04:44:58 INFO - "platform": "yosemite_r7",
04:44:58 INFO - "scheduler": "tests-mozilla-beta-macosx64-talos",
04:44:58 INFO - "branch": "mozilla-beta",
04:44:58 INFO - "repo_path": "releases/mozilla-beta",
04:44:58 INFO - "revision": "e2f8b2e421f954c7f3b74f9f7bd7a982aed94d05",
04:44:58 INFO - "stage_platform": "macosx64",
04:44:58 INFO - "builduid": "bbebe8b318bb492daf3521276efec601",
04:44:58 INFO - "slavebuilddir": "test"
04:44:58 INFO - }
04:44:58 INFO - Found installer url https://queue.taskcluster.net/v1/task/Ml2LMrcDQ9iVEnpQTiMd3g/artifacts/public/build/firefox-48.0.en-US.mac.dmg.
04:44:58 INFO - Running post-action listener: _resource_record_post_action
04:44:58 INFO - #####
04:44:58 INFO - ##### Running download-and-extract step.
04:44:58 INFO - #####
04:44:58 INFO - Running pre-action listener: _resource_record_pre_action
04:44:58 INFO - Running main action method: download_and_extract
04:44:58 INFO - mkdir: /builds/slave/test/build/tests
04:44:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]}
04:44:58 INFO - https://queue.taskcluster.net/v1/task/Ml2LMrcDQ9iVEnpQTiMd3g/artifacts/public/build/firefox-48.0.en-US.mac.test_packages.json matches https://queue.taskcluster.net
04:44:58 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ml2LMrcDQ9iVEnpQTiMd3g/artifacts/public/build/firefox-48.0.en-US.mac.test_packages.json
04:44:58 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ml2LMrcDQ9iVEnpQTiMd3g/artifacts/public/build/firefox-48.0.en-US.mac.test_packages.json
04:44:58 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ml2LMrcDQ9iVEnpQTiMd3g/artifacts/public/build/firefox-48.0.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-48.0.en-US.mac.test_packages.json
04:44:58 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ml2LMrcDQ9iVEnpQTiMd3g/artifacts/public/build/firefox-48.0.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-48.0.en-US.mac.test_packages.json'}, attempt #1
04:45:23 INFO - Downloaded 1165 bytes.
04:45:23 INFO - Reading from file /builds/slave/test/build/firefox-48.0.en-US.mac.test_packages.json
04:45:23 INFO - Using the following test package requirements:
04:45:23 INFO - {u'common': [u'firefox-48.0.en-US.mac.common.tests.zip'],
04:45:23 INFO - u'cppunittest': [u'firefox-48.0.en-US.mac.common.tests.zip',
04:45:23 INFO - u'firefox-48.0.en-US.mac.cppunittest.tests.zip'],
04:45:23 INFO - u'gtest': [u'firefox-48.0.en-US.mac.common.tests.zip',
04:45:23 INFO - u'firefox-48.0.en-US.mac.gtest.tests.zip'],
04:45:23 INFO - u'jittest': [u'firefox-48.0.en-US.mac.common.tests.zip', u'jsshell-mac.zip'],
04:45:23 INFO - u'mochitest': [u'firefox-48.0.en-US.mac.common.tests.zip',
04:45:23 INFO - u'firefox-48.0.en-US.mac.mochitest.tests.zip'],
04:45:23 INFO - u'mozbase': [u'firefox-48.0.en-US.mac.common.tests.zip'],
04:45:23 INFO - u'reftest': [u'firefox-48.0.en-US.mac.common.tests.zip',
04:45:23 INFO - u'firefox-48.0.en-US.mac.reftest.tests.zip'],
04:45:23 INFO - u'talos': [u'firefox-48.0.en-US.mac.common.tests.zip',
04:45:23 INFO - u'firefox-48.0.en-US.mac.talos.tests.zip'],
04:45:23 INFO - u'web-platform': [u'firefox-48.0.en-US.mac.common.tests.zip',
04:45:23 INFO - u'firefox-48.0.en-US.mac.web-platform.tests.zip'],
04:45:23 INFO - u'xpcshell': [u'firefox-48.0.en-US.mac.common.tests.zip',
04:45:23 INFO - u'firefox-48.0.en-US.mac.xpcshell.tests.zip']}
04:45:23 INFO - Downloading packages: [u'firefox-48.0.en-US.mac.common.tests.zip'] for test suite category: common
04:45:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]}
04:45:23 INFO - https://queue.taskcluster.net/v1/task/Ml2LMrcDQ9iVEnpQTiMd3g/artifacts/public/build/firefox-48.0.en-US.mac.common.tests.zip matches https://queue.taskcluster.net
04:45:23 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ml2LMrcDQ9iVEnpQTiMd3g/artifacts/public/build/firefox-48.0.en-US.mac.common.tests.zip
04:45:23 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ml2LMrcDQ9iVEnpQTiMd3g/artifacts/public/build/firefox-48.0.en-US.mac.common.tests.zip
04:45:23 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ml2LMrcDQ9iVEnpQTiMd3g/artifacts/public/build/firefox-48.0.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0.en-US.mac.common.tests.zip
04:45:23 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ml2LMrcDQ9iVEnpQTiMd3g/artifacts/public/build/firefox-48.0.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0.en-US.mac.common.tests.zip'}, attempt #1
04:45:24 INFO - Downloaded 18344067 bytes.
04:45:24 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests']
04:45:24 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests
04:45:27 INFO - Return code: 0
04:45:27 INFO - Downloading packages: [u'firefox-48.0.en-US.mac.common.tests.zip', u'firefox-48.0.en-US.mac.talos.tests.zip'] for test suite category: talos
04:45:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]}
04:45:27 INFO - https://queue.taskcluster.net/v1/task/Ml2LMrcDQ9iVEnpQTiMd3g/artifacts/public/build/firefox-48.0.en-US.mac.common.tests.zip matches https://queue.taskcluster.net
04:45:27 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ml2LMrcDQ9iVEnpQTiMd3g/artifacts/public/build/firefox-48.0.en-US.mac.common.tests.zip
04:45:27 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ml2LMrcDQ9iVEnpQTiMd3g/artifacts/public/build/firefox-48.0.en-US.mac.common.tests.zip
04:45:27 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ml2LMrcDQ9iVEnpQTiMd3g/artifacts/public/build/firefox-48.0.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0.en-US.mac.common.tests.zip
04:45:27 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ml2LMrcDQ9iVEnpQTiMd3g/artifacts/public/build/firefox-48.0.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0.en-US.mac.common.tests.zip'}, attempt #1
04:45:33 INFO - Downloaded 18344067 bytes.
04:45:33 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests']
04:45:33 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests
04:45:37 INFO - Return code: 0
04:45:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]}
04:45:37 INFO - https://queue.taskcluster.net/v1/task/Ml2LMrcDQ9iVEnpQTiMd3g/artifacts/public/build/firefox-48.0.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net
04:45:37 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ml2LMrcDQ9iVEnpQTiMd3g/artifacts/public/build/firefox-48.0.en-US.mac.talos.tests.zip
04:45:37 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ml2LMrcDQ9iVEnpQTiMd3g/artifacts/public/build/firefox-48.0.en-US.mac.talos.tests.zip
04:45:37 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ml2LMrcDQ9iVEnpQTiMd3g/artifacts/public/build/firefox-48.0.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-48.0.en-US.mac.talos.tests.zip
04:45:37 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ml2LMrcDQ9iVEnpQTiMd3g/artifacts/public/build/firefox-48.0.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0.en-US.mac.talos.tests.zip'}, attempt #1
04:45:38 INFO - Downloaded 10840145 bytes.
04:45:38 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests']
04:45:38 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests
04:45:38 INFO - Return code: 0
04:45:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]}
04:45:38 INFO - https://queue.taskcluster.net/v1/task/Ml2LMrcDQ9iVEnpQTiMd3g/artifacts/public/build/firefox-48.0.en-US.mac.dmg matches https://queue.taskcluster.net
04:45:38 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ml2LMrcDQ9iVEnpQTiMd3g/artifacts/public/build/firefox-48.0.en-US.mac.dmg
04:45:38 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ml2LMrcDQ9iVEnpQTiMd3g/artifacts/public/build/firefox-48.0.en-US.mac.dmg
04:45:38 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ml2LMrcDQ9iVEnpQTiMd3g/artifacts/public/build/firefox-48.0.en-US.mac.dmg to /builds/slave/test/build/firefox-48.0.en-US.mac.dmg
04:45:38 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ml2LMrcDQ9iVEnpQTiMd3g/artifacts/public/build/firefox-48.0.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-48.0.en-US.mac.dmg'}, attempt #1
04:45:41 INFO - Downloaded 86225931 bytes.
04:45:41 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Ml2LMrcDQ9iVEnpQTiMd3g/artifacts/public/build/firefox-48.0.en-US.mac.dmg
04:45:41 INFO - mkdir: /builds/slave/test/properties
04:45:41 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url
04:45:41 INFO - Writing to file /builds/slave/test/properties/build_url
04:45:41 INFO - Contents:
04:45:41 INFO - build_url:https://queue.taskcluster.net/v1/task/Ml2LMrcDQ9iVEnpQTiMd3g/artifacts/public/build/firefox-48.0.en-US.mac.dmg
04:45:42 INFO - Running post-action listener: _resource_record_post_action
04:45:42 INFO - Running post-action listener: set_extra_try_arguments
04:45:42 INFO - #####
04:45:42 INFO - ##### Running populate-webroot step.
04:45:42 INFO - #####
04:45:42 INFO - Running pre-action listener: _resource_record_pre_action
04:45:42 INFO - Running main action method: populate_webroot
04:45:42 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']},
04:45:42 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']},
04:45:42 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome',
04:45:42 INFO - '--tppagecycles',
04:45:42 INFO - '7'],
04:45:42 INFO - 'tests': ['tsvgm']}},
04:45:42 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'],
04:45:42 INFO - 'tests': ['tresize', 'tcanvasmark']},
04:45:42 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']},
04:45:42 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'],
04:45:42 INFO - 'tests': ['dromaeo_css', 'kraken']},
04:45:42 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']},
04:45:42 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip',
04:45:42 INFO - 'talos_options': ['--disable-e10s'],
04:45:42 INFO - 'tests': ['tp5o_scroll', 'glterrain']},
04:45:42 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip',
04:45:42 INFO - 'tests': ['tp5o_scroll', 'glterrain']},
04:45:42 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip',
04:45:42 INFO - 'talos_options': ['--disable-e10s'],
04:45:42 INFO - 'tests': ['damp', 'tps']},
04:45:42 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip',
04:45:42 INFO - 'tests': ['damp', 'tps']},
04:45:42 INFO - 'g3': {'talos_options': ['--disable-e10s'],
04:45:42 INFO - 'tests': ['dromaeo_dom']},
04:45:42 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']},
04:45:42 INFO - 'other': {'talos_options': ['--disable-e10s'],
04:45:42 INFO - 'tests': ['a11yr',
04:45:42 INFO - 'ts_paint',
04:45:42 INFO - 'tpaint',
04:45:42 INFO - 'sessionrestore',
04:45:42 INFO - 'sessionrestore_no_auto_restore',
04:45:42 INFO - 'tabpaint']},
04:45:42 INFO - 'other-e10s': {'tests': ['a11yr',
04:45:42 INFO - 'ts_paint',
04:45:42 INFO - 'tpaint',
04:45:42 INFO - 'sessionrestore',
04:45:42 INFO - 'sessionrestore_no_auto_restore',
04:45:42 INFO - 'tabpaint']},
04:45:42 INFO - 'other-e10s_l64': {'tests': ['a11yr',
04:45:42 INFO - 'ts_paint',
04:45:42 INFO - 'tpaint',
04:45:42 INFO - 'sessionrestore',
04:45:42 INFO - 'sessionrestore_no_auto_restore',
04:45:42 INFO - 'tabpaint']},
04:45:42 INFO - 'other-e10s_nol64': {'tests': ['a11yr',
04:45:42 INFO - 'ts_paint',
04:45:42 INFO - 'tpaint',
04:45:42 INFO - 'sessionrestore',
04:45:42 INFO - 'sessionrestore_no_auto_restore',
04:45:42 INFO - 'tabpaint']},
04:45:42 INFO - 'other_l64': {'talos_options': ['--disable-e10s'],
04:45:42 INFO - 'tests': ['a11yr',
04:45:42 INFO - 'ts_paint',
04:45:42 INFO - 'tpaint',
04:45:42 INFO - 'sessionrestore',
04:45:42 INFO - 'sessionrestore_no_auto_restore',
04:45:42 INFO - 'tabpaint']},
04:45:42 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'],
04:45:42 INFO - 'tests': ['a11yr',
04:45:42 INFO - 'ts_paint',
04:45:42 INFO - 'tpaint',
04:45:42 INFO - 'sessionrestore',
04:45:42 INFO - 'sessionrestore_no_auto_restore',
04:45:42 INFO - 'tabpaint']},
04:45:42 INFO - 'svgr': {'talos_options': ['--disable-e10s'],
04:45:42 INFO - 'tests': ['tsvgx',
04:45:42 INFO - 'tsvgr_opacity',
04:45:42 INFO - 'tart',
04:45:42 INFO - 'tscrollx',
04:45:42 INFO - 'cart']},
04:45:42 INFO - 'svgr-e10s': {'tests': ['tsvgx',
04:45:42 INFO - 'tsvgr_opacity',
04:45:42 INFO - 'tart',
04:45:42 INFO - 'tscrollx',
04:45:42 INFO - 'cart']},
04:45:42 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip',
04:45:42 INFO - 'talos_options': ['--disable-e10s'],
04:45:42 INFO - 'tests': ['tp5o']},
04:45:42 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip',
04:45:42 INFO - 'tests': ['tp5o']},
04:45:42 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip',
04:45:42 INFO - 'talos_options': ['--disable-e10s',
04:45:42 INFO - '--xperf_path',
04:45:42 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'],
04:45:42 INFO - 'tests': ['tp5n']},
04:45:42 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip',
04:45:42 INFO - 'talos_options': ['--xperf_path',
04:45:42 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'],
04:45:42 INFO - 'tests': ['tp5n']}},
04:45:42 INFO - 'talos.zip': {'path': '',
04:45:42 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}}
04:45:42 INFO - Running post-action listener: _resource_record_post_action
04:45:42 INFO - #####
04:45:42 INFO - ##### Running create-virtualenv step.
04:45:42 INFO - #####
04:45:42 INFO - Running pre-action listener: _resource_record_pre_action
04:45:42 INFO - Running main action method: create_virtualenv
04:45:42 INFO - Creating virtualenv /builds/slave/test/build/venv
04:45:42 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build
04:45:42 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv
04:45:43 INFO - The --no-site-packages flag is deprecated; it is now the default behavior.
04:45:43 INFO - Using real prefix '/tools/python27'
04:45:43 INFO - New python executable in /builds/slave/test/build/venv/bin/python
04:45:43 INFO - Installing distribute.............................................................................................................................................................................................done.
04:45:46 INFO - Installing pip.................done.
04:45:46 INFO - Return code: 0
04:45:46 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv
04:45:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]}
04:45:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
04:45:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub
04:45:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]}
04:45:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
04:45:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub
04:45:46 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 0x10fb64a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fb74420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb9c84652c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fa03e00>, '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 0x10f86cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f9feb20>, '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.ILS8nAVdl1/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.Xa2o4KSJuj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1
04:45:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build
04:45:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5
04:45:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ILS8nAVdl1/Render',
04:45:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared',
04:45:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared',
04:45:46 INFO - 'HOME': '/Users/cltbld',
04:45:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true',
04:45:46 INFO - 'LOGNAME': 'cltbld',
04:45:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
04:45:46 INFO - 'MOZ_NO_REMOTE': '1',
04:45:46 INFO - 'NO_EM_RESTART': '1',
04:45:46 INFO - 'PAGER': '/bin/cat',
04:45:46 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11',
04:45:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
04:45:46 INFO - 'PWD': '/builds/slave/test',
04:45:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg',
04:45:46 INFO - 'SHELL': '/bin/bash',
04:45:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Xa2o4KSJuj/Listeners',
04:45:46 INFO - 'TMPDIR': '/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/',
04:45:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log',
04:45:46 INFO - 'USER': 'cltbld',
04:45:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no',
04:45:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7',
04:45:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
04:45:46 INFO - 'XPC_FLAGS': '0x0',
04:45:46 INFO - 'XPC_SERVICE_NAME': '0',
04:45:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'}
04:45:46 INFO - Ignoring indexes: https://pypi.python.org/simple/
04:45:46 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg
04:45:46 INFO - Cleaning up...
04:45:46 INFO - Return code: 0
04:45:46 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv
04:45:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]}
04:45:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
04:45:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub
04:45:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]}
04:45:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
04:45:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub
04:45:46 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10fb64a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fb74420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb9c84652c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fa03e00>, '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 0x10f86cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f9feb20>, '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.ILS8nAVdl1/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.Xa2o4KSJuj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1
04:45:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1'] in /builds/slave/test/build
04:45:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=0.7.1
04:45:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ILS8nAVdl1/Render',
04:45:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared',
04:45:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared',
04:45:46 INFO - 'HOME': '/Users/cltbld',
04:45:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true',
04:45:46 INFO - 'LOGNAME': 'cltbld',
04:45:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
04:45:46 INFO - 'MOZ_NO_REMOTE': '1',
04:45:46 INFO - 'NO_EM_RESTART': '1',
04:45:46 INFO - 'PAGER': '/bin/cat',
04:45:46 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11',
04:45:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
04:45:46 INFO - 'PWD': '/builds/slave/test',
04:45:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg',
04:45:46 INFO - 'SHELL': '/bin/bash',
04:45:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Xa2o4KSJuj/Listeners',
04:45:46 INFO - 'TMPDIR': '/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/',
04:45:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log',
04:45:46 INFO - 'USER': 'cltbld',
04:45:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no',
04:45:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7',
04:45:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
04:45:46 INFO - 'XPC_FLAGS': '0x0',
04:45:46 INFO - 'XPC_SERVICE_NAME': '0',
04:45:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'}
04:45:47 INFO - Ignoring indexes: https://pypi.python.org/simple/
04:45:47 INFO - Downloading/unpacking psutil>=0.7.1
04:45:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available
04:45:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available
04:45:47 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available
04:45:47 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available
04:45:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available
04:45:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available
04:45:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil
04:45:50 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build'
04:45:50 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects
...
04:45:50 INFO - Installing collected packages: psutil
04:45:50 INFO - Running setup.py install for psutil
04:45:50 INFO - building 'psutil._psutil_osx' extension
04:45:50 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o
04:45:50 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o
04:45:50 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o
04:45:50 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit
04:45:51 INFO - building 'psutil._psutil_posix' extension
04:45:51 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o
04:45:51 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration]
04:45:51 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr);
04:45:51 INFO - ^
04:45:51 INFO - 1 warning generated.
04:45:51 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so
04:45:51 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build'
04:45:51 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ...
04:45:51 INFO - Successfully installed psutil
04:45:51 INFO - Cleaning up...
04:45:51 INFO - Return code: 0
04:45:51 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv
04:45:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]}
04:45:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
04:45:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub
04:45:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]}
04:45:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
04:45:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub
04:45:51 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10fb64a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fb74420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb9c84652c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fa03e00>, '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 0x10f86cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f9feb20>, '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.ILS8nAVdl1/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.Xa2o4KSJuj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1
04:45:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0'] in /builds/slave/test/build
04:45:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.0.0
04:45:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ILS8nAVdl1/Render',
04:45:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared',
04:45:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared',
04:45:51 INFO - 'HOME': '/Users/cltbld',
04:45:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true',
04:45:51 INFO - 'LOGNAME': 'cltbld',
04:45:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
04:45:51 INFO - 'MOZ_NO_REMOTE': '1',
04:45:51 INFO - 'NO_EM_RESTART': '1',
04:45:51 INFO - 'PAGER': '/bin/cat',
04:45:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11',
04:45:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
04:45:51 INFO - 'PWD': '/builds/slave/test',
04:45:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg',
04:45:51 INFO - 'SHELL': '/bin/bash',
04:45:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Xa2o4KSJuj/Listeners',
04:45:51 INFO - 'TMPDIR': '/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/',
04:45:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log',
04:45:51 INFO - 'USER': 'cltbld',
04:45:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no',
04:45:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7',
04:45:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
04:45:51 INFO - 'XPC_FLAGS': '0x0',
04:45:51 INFO - 'XPC_SERVICE_NAME': '0',
04:45:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'}
04:45:51 INFO - Ignoring indexes: https://pypi.python.org/simple/
04:45:51 INFO - Downloading/unpacking mozsystemmonitor==0.0.0
04:45:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available
04:45:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available
04:45:51 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available
04:45:51 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available
04:45:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available
04:45:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available
04:45:54 INFO - Downloading mozsystemmonitor-0.0.tar.gz
04:45:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor
04:45:54 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0)
04:45:54 INFO - Installing collected packages: mozsystemmonitor
04:45:54 INFO - Running setup.py install for mozsystemmonitor
04:45:54 INFO - Successfully installed mozsystemmonitor
04:45:54 INFO - Cleaning up...
04:45:54 INFO - Return code: 0
04:45:54 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv
04:45:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]}
04:45:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
04:45:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub
04:45:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]}
04:45:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
04:45:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub
04:45:54 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 0x10fb64a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fb74420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb9c84652c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fa03e00>, '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 0x10f86cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f9feb20>, '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.ILS8nAVdl1/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.Xa2o4KSJuj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1
04:45:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build
04:45:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4
04:45:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ILS8nAVdl1/Render',
04:45:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared',
04:45:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared',
04:45:54 INFO - 'HOME': '/Users/cltbld',
04:45:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true',
04:45:54 INFO - 'LOGNAME': 'cltbld',
04:45:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
04:45:54 INFO - 'MOZ_NO_REMOTE': '1',
04:45:54 INFO - 'NO_EM_RESTART': '1',
04:45:54 INFO - 'PAGER': '/bin/cat',
04:45:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11',
04:45:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
04:45:54 INFO - 'PWD': '/builds/slave/test',
04:45:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg',
04:45:54 INFO - 'SHELL': '/bin/bash',
04:45:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Xa2o4KSJuj/Listeners',
04:45:54 INFO - 'TMPDIR': '/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/',
04:45:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log',
04:45:54 INFO - 'USER': 'cltbld',
04:45:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no',
04:45:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7',
04:45:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
04:45:54 INFO - 'XPC_FLAGS': '0x0',
04:45:54 INFO - 'XPC_SERVICE_NAME': '0',
04:45:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'}
04:45:54 INFO - Ignoring indexes: https://pypi.python.org/simple/
04:45:54 INFO - Downloading/unpacking blobuploader==1.2.4
04:45:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available
04:45:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available
04:45:54 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available
04:45:54 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available
04:45:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available
04:45:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available
04:45:57 INFO - Downloading blobuploader-1.2.4.tar.gz
04:45:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader
04:45:57 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4)
04:45:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available
04:45:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available
04:45:57 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available
04:45:57 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available
04:45:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available
04:45:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available
04:45:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests
04:45:57 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4)
04:45:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available
04:45:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available
04:45:57 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available
04:45:57 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available
04:45:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available
04:45:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available
04:45:58 INFO - Downloading docopt-0.6.1.tar.gz
04:45:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt
04:45:58 INFO - Installing collected packages: blobuploader, requests, docopt
04:45:58 INFO - Running setup.py install for blobuploader
04:45:58 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775
04:45:58 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775
04:45:58 INFO - Running setup.py install for requests
04:45:59 INFO - Running setup.py install for docopt
04:45:59 INFO - Successfully installed blobuploader requests docopt
04:45:59 INFO - Cleaning up...
04:45:59 INFO - Return code: 0
04:45:59 INFO - Installing None into virtualenv /builds/slave/test/build/venv
04:45:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]}
04:45:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
04:45:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub
04:45:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]}
04:45:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
04:45:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub
04:45:59 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 0x10fb64a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fb74420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb9c84652c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fa03e00>, '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 0x10f86cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f9feb20>, '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.ILS8nAVdl1/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.Xa2o4KSJuj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1
04:45:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config
04:45:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub
04:45:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ILS8nAVdl1/Render',
04:45:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared',
04:45:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared',
04:45:59 INFO - 'HOME': '/Users/cltbld',
04:45:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true',
04:45:59 INFO - 'LOGNAME': 'cltbld',
04:45:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
04:45:59 INFO - 'MOZ_NO_REMOTE': '1',
04:45:59 INFO - 'NO_EM_RESTART': '1',
04:45:59 INFO - 'PAGER': '/bin/cat',
04:45:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11',
04:45:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
04:45:59 INFO - 'PWD': '/builds/slave/test',
04:45:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg',
04:45:59 INFO - 'SHELL': '/bin/bash',
04:45:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Xa2o4KSJuj/Listeners',
04:45:59 INFO - 'TMPDIR': '/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/',
04:45:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log',
04:45:59 INFO - 'USER': 'cltbld',
04:45:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no',
04:45:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7',
04:45:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
04:45:59 INFO - 'XPC_FLAGS': '0x0',
04:45:59 INFO - 'XPC_SERVICE_NAME': '0',
04:45:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'}
04:46:00 INFO - Ignoring indexes: https://pypi.python.org/simple/
04:46:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser
04:46:00 INFO - Running setup.py (path:/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/pip-vfTUWR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser
04:46:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash
04:46:00 INFO - Running setup.py (path:/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/pip-mV7dol-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash
04:46:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug
04:46:00 INFO - Running setup.py (path:/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/pip-zTF7D9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug
04:46:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice
04:46:00 INFO - Running setup.py (path:/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/pip-V7fSAz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice
04:46:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile
04:46:00 INFO - Running setup.py (path:/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/pip-iFee7L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile
04:46:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd
04:46:00 INFO - Running setup.py (path:/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/pip-_MMZA9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd
04:46:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo
04:46:00 INFO - Running setup.py (path:/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/pip-Y6J3_W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo
04:46:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall
04:46:00 INFO - Running setup.py (path:/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/pip-rh3LxQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall
04:46:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak
04:46:00 INFO - Running setup.py (path:/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/pip-yXKutI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak
04:46:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog
04:46:01 INFO - Running setup.py (path:/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/pip-fMnmJp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog
04:46:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork
04:46:01 INFO - Running setup.py (path:/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/pip-24HVlZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork
04:46:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess
04:46:01 INFO - Running setup.py (path:/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/pip-AVuGQa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess
04:46:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile
04:46:01 INFO - Running setup.py (path:/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/pip-eHSpjg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile
04:46:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner
04:46:01 INFO - Running setup.py (path:/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/pip-g_eQaZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner
04:46:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot
04:46:01 INFO - Running setup.py (path:/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/pip-OqWjht-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot
04:46:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest
04:46:01 INFO - Running setup.py (path:/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/pip-8RiY_E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest
04:46:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion
04:46:01 INFO - Running setup.py (path:/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/pip-yL6NvC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion
04:46:01 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion
04:46:01 INFO - Running setup.py install for manifestparser
04:46:01 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin
04:46:01 INFO - Running setup.py install for mozcrash
04:46:02 INFO - Running setup.py install for mozdebug
04:46:02 INFO - Running setup.py install for mozdevice
04:46:02 INFO - Installing sutini script to /builds/slave/test/build/venv/bin
04:46:02 INFO - Installing dm script to /builds/slave/test/build/venv/bin
04:46:02 INFO - Running setup.py install for mozfile
04:46:02 INFO - Running setup.py install for mozhttpd
04:46:02 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin
04:46:02 INFO - Running setup.py install for mozinfo
04:46:02 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin
04:46:02 INFO - Running setup.py install for mozInstall
04:46:02 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin
04:46:02 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin
04:46:02 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin
04:46:02 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin
04:46:03 INFO - Running setup.py install for mozleak
04:46:03 INFO - Running setup.py install for mozlog
04:46:03 INFO - Installing structlog script to /builds/slave/test/build/venv/bin
04:46:03 INFO - Running setup.py install for moznetwork
04:46:03 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin
04:46:03 INFO - Running setup.py install for mozprocess
04:46:03 INFO - Running setup.py install for mozprofile
04:46:03 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin
04:46:03 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin
04:46:03 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin
04:46:03 INFO - Running setup.py install for mozrunner
04:46:03 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin
04:46:03 INFO - Running setup.py install for mozscreenshot
04:46:04 INFO - Running setup.py install for moztest
04:46:04 INFO - Running setup.py install for mozversion
04:46:04 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin
04:46:04 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion
04:46:04 INFO - Cleaning up...
04:46:04 INFO - Return code: 0
04:46:04 INFO - Installing None into virtualenv /builds/slave/test/build/venv
04:46:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]}
04:46:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
04:46:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub
04:46:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]}
04:46:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
04:46:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub
04:46:04 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 0x10fb64a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fb74420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb9c84652c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fa03e00>, '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 0x10f86cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f9feb20>, '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.ILS8nAVdl1/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.Xa2o4KSJuj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1
04:46:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config
04:46:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub
04:46:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ILS8nAVdl1/Render',
04:46:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared',
04:46:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared',
04:46:04 INFO - 'HOME': '/Users/cltbld',
04:46:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true',
04:46:04 INFO - 'LOGNAME': 'cltbld',
04:46:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
04:46:04 INFO - 'MOZ_NO_REMOTE': '1',
04:46:04 INFO - 'NO_EM_RESTART': '1',
04:46:04 INFO - 'PAGER': '/bin/cat',
04:46:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11',
04:46:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
04:46:04 INFO - 'PWD': '/builds/slave/test',
04:46:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg',
04:46:04 INFO - 'SHELL': '/bin/bash',
04:46:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Xa2o4KSJuj/Listeners',
04:46:04 INFO - 'TMPDIR': '/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/',
04:46:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log',
04:46:04 INFO - 'USER': 'cltbld',
04:46:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no',
04:46:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7',
04:46:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
04:46:04 INFO - 'XPC_FLAGS': '0x0',
04:46:04 INFO - 'XPC_SERVICE_NAME': '0',
04:46:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'}
04:46:04 INFO - Ignoring indexes: https://pypi.python.org/simple/
04:46:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser
04:46:04 INFO - Running setup.py (path:/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/pip-y69qnK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser
04:46:04 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1))
04:46:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash
04:46:04 INFO - Running setup.py (path:/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/pip-N9QHB9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash
04:46:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2))
04:46:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug
04:46:04 INFO - Running setup.py (path:/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/pip-NGt1jk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug
04:46:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3))
04:46:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice
04:46:05 INFO - Running setup.py (path:/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/pip-TZjEOK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice
04:46:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4))
04:46:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile
04:46:05 INFO - Running setup.py (path:/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/pip-lygHrV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile
04:46:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5))
04:46:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd
04:46:05 INFO - Running setup.py (path:/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/pip-oX6KF_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd
04:46:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6))
04:46:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo
04:46:05 INFO - Running setup.py (path:/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/pip-OhmmxT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo
04:46:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7))
04:46:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall
04:46:05 INFO - Running setup.py (path:/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/pip-6ylf4V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall
04:46:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8))
04:46:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak
04:46:05 INFO - Running setup.py (path:/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/pip-FqUg0s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak
04:46:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9))
04:46:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog
04:46:05 INFO - Running setup.py (path:/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/pip-4bv_Rm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog
04:46:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.1 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10))
04:46:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork
04:46:05 INFO - Running setup.py (path:/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/pip-_jwYY4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork
04:46:05 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11))
04:46:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess
04:46:05 INFO - Running setup.py (path:/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/pip-4Jcykb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess
04:46:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12))
04:46:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile
04:46:05 INFO - Running setup.py (path:/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/pip-zFdnnH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile
04:46:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13))
04:46:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner
04:46:06 INFO - Running setup.py (path:/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/pip-yQzgd2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner
04:46:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.11 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14))
04:46:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot
04:46:06 INFO - Running setup.py (path:/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/pip-zKmkEY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot
04:46:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15))
04:46:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest
04:46:06 INFO - Running setup.py (path:/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/pip-LidQIy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest
04:46:06 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16))
04:46:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion
04:46:06 INFO - Running setup.py (path:/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/pip-YPjU5L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion
04:46:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17))
04:46:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2))
04:46:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2))
04:46:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3))
04:46:06 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4))
04:46:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4))
04:46:06 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10))
04:46:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available
04:46:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available
04:46:06 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available
04:46:06 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available
04:46:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available
04:46:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available
04:46:08 INFO - Downloading blessings-1.6.tar.gz
04:46:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings
04:46:08 INFO - Installing collected packages: blessings
04:46:08 INFO - Running setup.py install for blessings
04:46:09 INFO - Successfully installed blessings
04:46:09 INFO - Cleaning up...
04:46:09 INFO - Return code: 0
04:46:09 INFO - Done creating virtualenv /builds/slave/test/build/venv.
04:46:09 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze']
04:46:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze
04:46:09 INFO - Reading from file tmpfile_stdout
04:46:09 INFO - Current package versions:
04:46:09 INFO - blessings == 1.6
04:46:09 INFO - blobuploader == 1.2.4
04:46:09 INFO - docopt == 0.6.1
04:46:09 INFO - manifestparser == 1.1
04:46:09 INFO - mozInstall == 1.12
04:46:09 INFO - mozcrash == 0.17
04:46:09 INFO - mozdebug == 0.1
04:46:09 INFO - mozdevice == 0.48
04:46:09 INFO - mozfile == 1.2
04:46:09 INFO - mozhttpd == 0.7
04:46:09 INFO - mozinfo == 0.9
04:46:09 INFO - mozleak == 0.1
04:46:09 INFO - mozlog == 3.1
04:46:09 INFO - moznetwork == 0.27
04:46:09 INFO - mozprocess == 0.22
04:46:09 INFO - mozprofile == 0.28
04:46:09 INFO - mozrunner == 6.11
04:46:09 INFO - mozscreenshot == 0.1
04:46:09 INFO - mozsystemmonitor == 0.0
04:46:09 INFO - moztest == 0.7
04:46:09 INFO - mozversion == 1.4
04:46:09 INFO - psutil == 3.1.1
04:46:09 INFO - requests == 1.2.3
04:46:09 INFO - wsgiref == 0.1.2
04:46:09 INFO - Installing None into virtualenv /builds/slave/test/build/venv
04:46: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]}
04:46:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
04:46:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub
04:46: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]}
04:46:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
04:46:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub
04:46:09 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 0x10fb64a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fb74420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb9c84652c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fa03e00>, '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 0x10f86cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f9feb20>, '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.ILS8nAVdl1/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.Xa2o4KSJuj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1
04:46:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos
04:46:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub
04:46:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ILS8nAVdl1/Render',
04:46:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared',
04:46:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared',
04:46:09 INFO - 'HOME': '/Users/cltbld',
04:46:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true',
04:46:09 INFO - 'LOGNAME': 'cltbld',
04:46:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
04:46:09 INFO - 'MOZ_NO_REMOTE': '1',
04:46:09 INFO - 'NO_EM_RESTART': '1',
04:46:09 INFO - 'PAGER': '/bin/cat',
04:46:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11',
04:46:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
04:46:09 INFO - 'PWD': '/builds/slave/test',
04:46:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg',
04:46:09 INFO - 'SHELL': '/bin/bash',
04:46:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Xa2o4KSJuj/Listeners',
04:46:09 INFO - 'TMPDIR': '/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/',
04:46:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log',
04:46:09 INFO - 'USER': 'cltbld',
04:46:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no',
04:46:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7',
04:46:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
04:46:09 INFO - 'XPC_FLAGS': '0x0',
04:46:09 INFO - 'XPC_SERVICE_NAME': '0',
04:46:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'}
04:46:09 INFO - Ignoring indexes: https://pypi.python.org/simple/
04:46:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1))
04:46:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2))
04:46:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3))
04:46:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4))
04:46:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5))
04:46:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6))
04:46:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7))
04:46:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8))
04:46:09 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9))
04:46:09 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1))
04:46:09 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4))
04:46:09 INFO - Cleaning up...
04:46:09 INFO - Return code: 0
04:46:09 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv
04:46: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]}
04:46:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
04:46:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub
04:46: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]}
04:46:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
04:46:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub
04:46:09 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 0x10fb64a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fb74420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb9c84652c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fa03e00>, '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 0x10f86cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f9feb20>, '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.ILS8nAVdl1/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.Xa2o4KSJuj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1
04:46:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build
04:46:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema
04:46:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ILS8nAVdl1/Render',
04:46:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared',
04:46:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared',
04:46:09 INFO - 'HOME': '/Users/cltbld',
04:46:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true',
04:46:09 INFO - 'LOGNAME': 'cltbld',
04:46:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
04:46:09 INFO - 'MOZ_NO_REMOTE': '1',
04:46:09 INFO - 'NO_EM_RESTART': '1',
04:46:09 INFO - 'PAGER': '/bin/cat',
04:46:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11',
04:46:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
04:46:09 INFO - 'PWD': '/builds/slave/test',
04:46:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg',
04:46:09 INFO - 'SHELL': '/bin/bash',
04:46:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Xa2o4KSJuj/Listeners',
04:46:09 INFO - 'TMPDIR': '/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/',
04:46:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log',
04:46:09 INFO - 'USER': 'cltbld',
04:46:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no',
04:46:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7',
04:46:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
04:46:09 INFO - 'XPC_FLAGS': '0x0',
04:46:09 INFO - 'XPC_SERVICE_NAME': '0',
04:46:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'}
04:46:10 INFO - Ignoring indexes: https://pypi.python.org/simple/
04:46:10 INFO - Downloading/unpacking jsonschema
04:46:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available
04:46:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available
04:46:10 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available
04:46:10 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available
04:46:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available
04:46:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available
04:46:12 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl
04:46:12 INFO - Downloading/unpacking functools32 (from jsonschema)
04:46:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available
04:46:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available
04:46:12 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available
04:46:12 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available
04:46:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available
04:46:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available
04:46:12 INFO - Downloading functools32-3.2.3-2.tar.gz
04:46:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32
04:46:12 INFO - warning: no files found matching '*.txt'
04:46:12 INFO - no previously-included directories found matching 'build'
04:46:12 INFO - no previously-included directories found matching 'dist'
04:46:12 INFO - no previously-included directories found matching '.git*'
04:46:12 INFO - Installing collected packages: jsonschema, functools32
04:46:12 INFO - Running setup.py install for functools32
04:46:13 INFO - warning: no files found matching '*.txt'
04:46:13 INFO - no previously-included directories found matching 'build'
04:46:13 INFO - no previously-included directories found matching 'dist'
04:46:13 INFO - no previously-included directories found matching '.git*'
04:46:13 INFO - Successfully installed jsonschema functools32
04:46:13 INFO - Cleaning up...
04:46:13 INFO - Return code: 0
04:46:13 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv
04:46:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]}
04:46:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
04:46:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub
04:46:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]}
04:46:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
04:46:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub
04:46:13 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10fb64a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fb74420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb9c84652c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fa03e00>, '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 0x10f86cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f9feb20>, '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.ILS8nAVdl1/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.Xa2o4KSJuj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1
04:46:13 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test/build
04:46:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8
04:46:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ILS8nAVdl1/Render',
04:46:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared',
04:46:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared',
04:46:13 INFO - 'HOME': '/Users/cltbld',
04:46:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true',
04:46:13 INFO - 'LOGNAME': 'cltbld',
04:46:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
04:46:13 INFO - 'MOZ_NO_REMOTE': '1',
04:46:13 INFO - 'NO_EM_RESTART': '1',
04:46:13 INFO - 'PAGER': '/bin/cat',
04:46:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11',
04:46:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
04:46:13 INFO - 'PWD': '/builds/slave/test',
04:46:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg',
04:46:13 INFO - 'SHELL': '/bin/bash',
04:46:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Xa2o4KSJuj/Listeners',
04:46:13 INFO - 'TMPDIR': '/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/',
04:46:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log',
04:46:13 INFO - 'USER': 'cltbld',
04:46:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no',
04:46:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7',
04:46:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
04:46:13 INFO - 'XPC_FLAGS': '0x0',
04:46:13 INFO - 'XPC_SERVICE_NAME': '0',
04:46:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'}
04:46:13 INFO - Ignoring indexes: https://pypi.python.org/simple/
04:46:13 INFO - Downloading/unpacking flake8
04:46:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available
04:46:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available
04:46:13 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available
04:46:13 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available
04:46:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available
04:46:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available
04:46:15 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl
04:46:15 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8)
04:46:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available
04:46:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available
04:46:15 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available
04:46:15 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available
04:46:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available
04:46:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available
04:46:16 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl
04:46:16 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8)
04:46: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
04:46: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
04:46: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
04:46: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
04:46: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
04:46: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
04:46:16 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8)
04:46: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
04:46: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
04:46: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
04:46: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
04:46: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
04:46: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
04:46:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8
04:46:17 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs'
04:46:17 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs'
04:46:17 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite'
04:46:17 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite'
04:46:17 INFO - no previously-included directories found matching 'docs/_build'
04:46:17 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8
04:46:17 INFO - Running setup.py install for pep8
04:46:17 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs'
04:46:17 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs'
04:46:17 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite'
04:46:17 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite'
04:46:17 INFO - no previously-included directories found matching 'docs/_build'
04:46:17 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin
04:46:17 INFO - Successfully installed flake8 mccabe pyflakes pep8
04:46:17 INFO - Cleaning up...
04:46:17 INFO - Return code: 0
04:46:17 INFO - Running post-action listener: _resource_record_post_action
04:46:17 INFO - Running post-action listener: _start_resource_monitoring
04:46:17 INFO - Starting resource monitoring.
04:46:17 INFO - #####
04:46:17 INFO - ##### Running install step.
04:46:17 INFO - #####
04:46:17 INFO - Running pre-action listener: _resource_record_pre_action
04:46:17 INFO - Running main action method: install
04:46:17 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze']
04:46:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze
04:46:17 INFO - Reading from file tmpfile_stdout
04:46:17 INFO - Detecting whether we're running mozinstall >=1.0...
04:46:17 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h']
04:46:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h
04:46:17 INFO - Reading from file tmpfile_stdout
04:46:17 INFO - Output received:
04:46:17 INFO - Usage: mozinstall [options] installer
04:46:17 INFO - Options:
04:46:17 INFO - -h, --help show this help message and exit
04:46:17 INFO - -d DEST, --destination=DEST
04:46:17 INFO - Directory to install application into. [default:
04:46:17 INFO - "/builds/slave/test"]
04:46:17 INFO - --app=APP Application being installed. [default: firefox]
04:46:17 INFO - mkdir: /builds/slave/test/build/application
04:46:17 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-48.0.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application']
04:46:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-48.0.en-US.mac.dmg --destination /builds/slave/test/build/application
04:46:39 INFO - Reading from file tmpfile_stdout
04:46:39 INFO - Output received:
04:46:39 INFO - /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox
04:46:39 INFO - Running post-action listener: _resource_record_post_action
04:46:39 INFO - #####
04:46:39 INFO - ##### Running run-tests step.
04:46:39 INFO - #####
04:46:39 INFO - Running pre-action listener: _resource_record_pre_action
04:46:39 INFO - Running main action method: run_tests
04:46:39 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'
04:46:39 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build
04:46:39 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType"
04:46:39 INFO - 2016-06-27 04:46:39.578 screenresolution[1648:8232] starting screenresolution argv=screenresolution get
04:46:39 INFO - 2016-06-27 04:46:39.580 screenresolution[1648:8232] Display 0: 1600x1200x32@60
04:46:39 INFO - 2016-06-27 04:46:39.586 screenresolution[1649:8234] starting screenresolution argv=screenresolution list
04:46:39 INFO - Available Modes on Display 0
04:46:39 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75
04:46:39 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60
04:46:39 INFO - 800x600x32@75 800x600x32@60 640x480x32@75
04:46:39 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60
04:46:39 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays:
04:46:39 INFO - Intel Iris:
04:46:39 INFO - Chipset Model: Intel Iris
04:46:39 INFO - Type: GPU
04:46:39 INFO - Bus: Built-In
04:46:39 INFO - VRAM (Dynamic, Max): 1536 MB
04:46:39 INFO - Vendor: Intel (0x8086)
04:46:39 INFO - Device ID: 0x0a2e
04:46:39 INFO - Revision ID: 0x0009
04:46:39 INFO - Displays:
04:46:39 INFO - Display:
04:46:39 INFO - Resolution: 1600 x 1200 @ 60 Hz
04:46:39 INFO - Pixel Depth: 32-Bit Color (ARGB8888)
04:46:39 INFO - Display Serial Number: 1600x1200 60
04:46:39 INFO - Main Display: Yes
04:46:39 INFO - Mirror: Off
04:46:39 INFO - Online: Yes
04:46:39 INFO - Rotation: Supported
04:46:39 INFO - Return code: 0
04:46:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version']
04:46:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version
04:46:39 INFO - Python 2.7.3
04:46:39 INFO - Return code: 0
04:46:39 INFO - grabbing minidump binary from tooltool
04:46: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]}
04:46:39 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fa03e00>, '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 0x10f86cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f9feb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1
04:46:39 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build
04:46:39 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache
04:46:39 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache
04:46:39 INFO - Return code: 0
04:46:39 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755
04:46:39 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir
04:46:39 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir
04:46:39 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk
04:46:39 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir
04:46:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos']
04:46:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos
04:46:41 INFO - Return code: 0
04:46:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Ml2LMrcDQ9iVEnpQTiMd3g/artifacts/public/build/firefox-48.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0008', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build
04:46:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Beta --suite dromaeojs --executablePath /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/Ml2LMrcDQ9iVEnpQTiMd3g/artifacts/public/build/firefox-48.0.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0008 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log
04:46:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ILS8nAVdl1/Render',
04:46:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared',
04:46:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared',
04:46:41 INFO - 'HOME': '/Users/cltbld',
04:46:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true',
04:46:41 INFO - 'LOGNAME': 'cltbld',
04:46:41 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir',
04:46:41 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk',
04:46:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
04:46:41 INFO - 'MOZ_NO_REMOTE': '1',
04:46:41 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir',
04:46:41 INFO - 'NO_EM_RESTART': '1',
04:46:41 INFO - 'PAGER': '/bin/cat',
04:46:41 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11',
04:46:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
04:46:41 INFO - 'PWD': '/builds/slave/test',
04:46:41 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos',
04:46:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg',
04:46:41 INFO - 'SHELL': '/bin/bash',
04:46:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Xa2o4KSJuj/Listeners',
04:46:41 INFO - 'TMPDIR': '/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/',
04:46:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log',
04:46:41 INFO - 'USER': 'cltbld',
04:46:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no',
04:46:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7',
04:46:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
04:46:41 INFO - 'XPC_FLAGS': '0x0',
04:46:41 INFO - 'XPC_SERVICE_NAME': '0',
04:46:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'}
04:46:41 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Ml2LMrcDQ9iVEnpQTiMd3g/artifacts/public/build/firefox-48.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0008', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600
04:46:41 INFO - mozversion application_buildid: 20160627033728
04:46:41 INFO - mozversion application_changeset: e2f8b2e421f954c7f3b74f9f7bd7a982aed94d05
04:46:41 INFO - mozversion application_display_name: Firefox
04:46:41 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384}
04:46:41 INFO - mozversion application_name: Firefox
04:46:41 INFO - mozversion application_remotingname: firefox
04:46:41 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta
04:46:41 INFO - mozversion application_vendor: Mozilla
04:46:41 INFO - mozversion application_version: 48.0
04:46:41 INFO - mozversion platform_buildid: 20160627033728
04:46:41 INFO - mozversion platform_changeset: e2f8b2e421f954c7f3b74f9f7bd7a982aed94d05
04:46:41 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta
04:46:41 INFO - mozversion platform_version: 48.0
04:46:41 INFO - using testdate: 1467028001
04:46:41 INFO - actual date: 1467028001
04:46:41 INFO - starting webserver on 'localhost:49277'
04:46:41 INFO - SUITE-START | Running 2 tests
04:46:41 INFO - TEST-START | dromaeo_css
04:46:41 INFO - operating with platform_type : mac_
04:46:41 INFO - Initialising browser for dromaeo_css test...
04:46:41 INFO - TEST-INFO | started process 1667 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/tmpBhE3xd/profile http://localhost:49277/getInfo.html)
04:46:43 INFO - PROCESS | 1667 | __metrics Screen width/height:1600/1200
04:46:43 INFO - PROCESS | 1667 | colorDepth:24
04:46:43 INFO - PROCESS | 1667 | Browser inner width/height: 1024/689
04:46:43 INFO - PROCESS | 1667 | __metrics
04:46:43 INFO - PROCESS | 1667 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows"
04:46:43 ERROR - PROCESS | 1667 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null
04:46:44 INFO - TEST-INFO | 1667: exit 0
04:46:44 INFO - Browser initialized.
04:46:44 INFO - Running cycle 1/1 for dromaeo_css test...
04:46:44 INFO - TEST-INFO | started process 1671 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/tmpBhE3xd/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1)
04:46:55 INFO - PROCESS | 1671 | RSS: Main: 161427456
04:46:55 INFO - PROCESS | 1671 |
04:47:47 INFO - PROCESS | 1671 | 1467028067286 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no]
04:49:57 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49277/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49277/tests/dromaeo/cssquery-ext.html)
04:49:58 INFO - PROCESS | 1671 | RSS: Main: 254578688
04:49:58 INFO - PROCESS | 1671 |
04:53:01 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49277/tests/dromaeo/cssquery-ext.html (next: http://localhost:49277/tests/dromaeo/cssquery-jquery.html)
04:53:01 INFO - PROCESS | 1671 | RSS: Main: 306917376
04:53:01 INFO - PROCESS | 1671 |
04:56:03 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49277/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49277/tests/dromaeo/cssquery-mootools.html)
04:56:04 INFO - PROCESS | 1671 | RSS: Main: 310345728
04:56:04 INFO - PROCESS | 1671 |
04:59:06 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49277/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49277/tests/dromaeo/cssquery-prototype.html)
04:59:07 INFO - PROCESS | 1671 | RSS: Main: 304783360
04:59:07 INFO - PROCESS | 1671 |
05:02:10 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49277/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49277/tests/dromaeo/cssquery-yui.html)
05:02:10 INFO - PROCESS | 1671 | RSS: Main: 270704640
05:02:10 INFO - PROCESS | 1671 |
05:05:13 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49277/tests/dromaeo/cssquery-yui.html (next: http://localhost:49277/tests/dromaeo/cssquery-dojo.html)
05:05:13 INFO - PROCESS | 1671 | RSS: Main: 312348672
05:05:13 INFO - PROCESS | 1671 |
05:05:13 INFO - PROCESS | 1671 | __start_tp_report
05:05:13 INFO - PROCESS | 1671 | _x_x_mozilla_page_load
05:05:13 INFO - PROCESS | 1671 | _x_x_mozilla_page_load_details
05:05:13 INFO - PROCESS | 1671 | |i|pagename|runs|
05:05:13 INFO - PROCESS | 1671 | |0;dojo.html;3345.330933813237;3628.546431696038;3633.5821380541247;3642.4900513928055;3712.4988126602902;5930.792422265222;5944.851378715534;5954.880902381949;5965.88068238635;5996.610220335675;5946.791862284821;5987.221661184051;6000.33996260411;6012.2184116064855;6019.097135429688;5286.471352864722;5351.357837059559;5383.515483606475;5438.401775804658;5473.890522189554;5422.728863556826;5460.645058071213;5465.234867118606;5480.150576660604;5481.205225242337;5403.189521571776;5440.9934162180125;5448.210009548613;5477.726113694319;5492.752826122814;4674.994876101655;5447.645903016291;5460.180972854104;5463.535599474009;5487.176923461473;5300.681959082467;5318.494742999409;5322.361316641989;5400.270963419927;5410.621256512046;5834.008218602848;5834.2998840139035;5861.736221870026;5879.235699359098;5915.526757859361;6289.559730818844;6300.275468321156;6337.651429171394;6340.112384266206;6341;5555.500004999529;6201.720922558496;6224.439800417938;6412.198475190601;6421.614703117782;6077.270727512682;6083.756649734005;6114.9694251529345;6126.111713801474;6171.25944886612;6502.804915852532;6514.739410423625;6518.934810651833;6525.738970441129;6526.804195874131;6194.752209911643;6221.5333849961435;6272.278687950853;6296.275928268216;6318.8420289492215;6381.3937675920715;6382.20222472191;6394.712237949303;6395.232572091379;6400.871982560323;6233.407826256498;6278.623282603058;6295.087212354183;6298.968505157445;6323.588966717149;6213.751449942042;6250.562460627803;6265.937340626627;6320.652364120017;6336.841578960563;5040.974795126001;5067.442581316052;5077.568406685473;5092.28707980883;5112.258722485219;4611.262198048296;4612.238980568236;4615.215413379734;4623.9075218495445;4636.188666983265;5876.236089308362;5974.731137098841;6014.609050411709;6111.113888486234;6115.082737589397;6345.524085693592;6386.936130638727;6402.071699603532;6404.679766011681;6420.839479013062;6031.125486804476;6051.485623722033;6074.210352654127;6076.118962750376;6107.969460152671;5416.7020813855615;5540.335159780852;5556.16657501378;5590.636608620427;5633.859153521113;6581.144451221407;6606.174228221473;6631.20425548918;6648.767293144717;6656.334366563305;6216.937830621636;6369.363063693593;6385.425311721968;6390.616563006234;6413.8717225656155;6518.706658200487;6616.172978377703;6618.205815302194;6632.336766323329;6657.567258128206;6305.054241863573;6319.715612797527;6339.651319177489;6352;6379.011253255753;6242.438180563771;6280.371962803683;6342.143810585512;6350.872982540416;6426.646534440651;6229.657368844757;6254.749810007731;6274.686265686789;6279.497640188682;6293.811185664437;5306.548943339705;5401.432849550741;5606.523445507178;6072.271327440735;6260.186175797299;6083.969580152071;6111.663858487648;6165.475934545614;6177.227846519185;6186.505079593531;6127.758165267708;6157.353477884758;6218.782342618168;6219.813405597839;6245.18812554365;6060.454559089703;6128.325884152829;6128.66292353907;6128.693565321805;6130.9577371846
05:05:13 INFO - PROCESS | 1671 | |1;ext.html;516.5428595692812;523.8585581892889;608.8812681527104;611.6788685939878;641.0640464921215;32957.670423295756;33603.327933441375;33604.327913441746;33616.32767344652;33630.32739345211;39299.21401571967;43141.35287970683;43145.78427107868;43163.115319621225;43189.35215971763;9697.903020969807;9897.50512474377;9909.950450247774;9923.851142232872;9950.552225149886;9164.8625270621;9367.531623418836;9679.225661947028;9807.558659860324;11994.040476761882;11885.227460215101;11973.880261197408;11980.98161656256;11987.40062996851;11994.220375675553;11773.352465614387;11853.703657408547;11873.990710789592;11875.82186267211;11889.821652675217;11693.883061169365;11790.056795456429;11791.056715462742;11799.292042477393;11804.173707840455;41305.38041929373;41668.37497437541;41758.37362439566;43783;44054;3487.668671476206;3537.6970579000103;3558.857645694169;3724.981375093107;3998.8000599969887;27803;28455.857720711472;28648.570271445944;28706.282342941384;28877.278068048257;2695.649565556465;2716.5925111233278;2724.5368287391193;2740.2190375742816;2742.012875364867;407885;413800.9309953461;415267;415409;416173;67236.66381668061;67574;67701;67715;67869;71916.92124618134;75977.86033209506;75992.62003689946;76008;76012.61993689997;18034.917904925747;18258.726119108225;18322.175502102436;18324.90837545804;18326.992015439013;12433.191842530206;13278.601641950758;14702.852971470362;14805.777913331309;14819;17924.193411296525;18106;18119.547011324823;18139.09304534768;20805.791942080687;7886.960565197137;7898.921010789819;7919.802004949807;7925.286724194735;7969.800754981172;1004.2468148888333;1056.3186744549876;1056.5562463765236;1077.9029887310023;1103.624767579027;31740.206494837814;31920;32069.67930320667;32130.83934580312;32270.83864580662;1080.438172150493;1105;1154.3708678769922;1167.795635763738;1175.535663412947;16299.674006519803;16325.59186020359;16383.918080409521;16556.172191390382;16648.833511664972;649366;736437;738718;739265;741428;51167.744161278955;63895.68052159803;64519.67740161363;64637;64644;1529.755239161751;1530.3266562712372;1543.398074750848;1547.257316488092;1549.527394144774;29213.85393073021;31317.23976561631;32862;33025.50461743124;33386;58124.709376454535;58512.707436462544;58614.70692646509;58647.4135258659;58702.412975869694;491715;507085;507142;507403;573481;26264.4747105053;26863.86568067147;29034.709652903202;29912;30194.24514387158;41192;41202.79398602988;45424.77287613541;45571.316430254235;45577.860553486425;36316.91049268526;37291.06772330713;37419.69031083899;37432.625673742914;38124.04689882775;38202.617973819906;38725.80637096796;38963.415548767356;39448.605513944494;39605.00987475336;39235.822925312284;39432.6056739429;39489.802550987064;39738.801305993285;40151.59848401479;33092.17269568184;33360.583044734194;34527.65472345244;35041.12397190091;35166.82416587901;25780.742192578586;26045.739542604333;26148.477030459664;26278.94884204687;26458.86770566135
05:05:13 INFO - PROCESS | 1671 | |2;jquery.html;400.9478767760191;418.82409388057033;422.7379025004497;423.86648205815163;432.5307041859582;16382.344706211752;20853.663413853785;21269.787302126955;21634.675479867816;21653.67519487209;21181.470463238387;21433.785662143375;22279.77720222797;22570.887145564247;22575.887120564374;4700.882477938053;6104.08438734189;6151.969240153793;6158.56890017699;6169.753209871622;4432.578905004036;5772.682502462363;5975.671338076404;6682.198136223636;6847.520673552854;6767.796966091025;6784.321567843201;6786.253512113663;6792.117024786796;6799.286074962132;6416.550841441078;6669.032990216391;6671.966640166816;6676.098726671886;6684.398404143651;5537.473939975695;5837.474627283523;5857.619254748428;5864.06175011996;5866.178734977107;13481.730365392637;13674.93162534191;13715.794263086062;13741.24423156726;14604.70790584193;2196.8242540596716;2308.3998160478227;2369.6563998220163;2384.225126833786;2510.1590967025886;9854.753631159278;10193.23550733698;11236.606718764886;11298.209125361229;14677.77983330251;1807.7921039080543;1828.9725654115198;1850.0009994603033;1857.1735577667944;1903.381401044666;107816.99999999921;109592;119872;120281;121280.57438851261;11257.099432045417;11554.94222528882;11719.238249513925;12021;12144.453499592539;36036.819815901275;36558.90323290305;36972.26055478875;37006.29937006278;37010.629893701254;11318.434078296052;11437.885621143681;11583.826242606368;11601.419929003516;11914.880851191378;8550.957245213734;9607.471589062528;9745.220382369393;10000.999900009941;10167.135793457639;10105.595776169019;10182.796344073226;10213.642522511676;10258.640947566948;10268.127209187154;3332.8833490827706;3968.5634580196156;3975.6819454443576;4009.4363198352667;4125.443065186221;913.6299798581585;969.5152423788106;978.1196922769423;987.1411871671692;995.7162208770466;20040.298589549588;21324.253651122137;21328.573428531654;21397.46506337354;21706.78293217079;900.9009009009004;991.4844280973854;1004.5780772075746;1018.6689325969089;1116.2911551164907;11097.667069987914;11325.490352934137;11364.715882103013;11431;11446;439520;639102.0224494425;650452;654766;663968;92289.53855230815;111371;127103;127690;128872.35563822309;465.6717014504766;488.8899997500534;489.40781654198105;489.6009752052066;490.95581397673664;8313.875291870747;17343.132843358035;18818.059097045367;19392;19561.119749611586;40082.799586001885;47501.287480687126;51503.74248128735;51529.742351288005;53217.20174197313;75695;77190.6140469294;80113;82447.93880153044;83147.16852831394;10252;11205.887941120485;12092;12142.300077989265;12501.749965000467;13155.802662960255;13341.599752007456;13545.051846371054;13701;13862.514811981533;10300.587976480856;11420.714482138013;11481.942590286995;11641.76716465649;11740.236884602473;11344.319340839576;11444.084473241954;11449.484773185393;11828.17202795796;11873.643790686294;6034.939650603438;8276.503409795432;11656.426382438261;11859.110566707704;12075.637730868088;12221.877781222074;12277.631671049883;12486.438110284877;12667.239965602093;12738;11144.387058711525;11731.530738770354;11761.647150585497;11819.586314479297;12108.273503589813
05:05:13 INFO - PROCESS | 1671 | |3;mootools.html;1488.4641529049534;1617.5632579203614;1631.9020858748472;1887.311131437025;2006.5284658105352;8348.499090054602;9014.143656352644;9168.174864262215;9172.357934944557;9183.908160918381;8815.162559556848;9052.366334356597;9064.682736104238;9075.273978081754;9087.091290870909;2564.358910272432;2591.753783390575;2592.8833202505934;2593.9351516212055;2594.675665541807;2327.278543651465;2459.004103338142;2503.912363067293;2574.034736973635;2588.3011586871535;2548.808839337057;2575.626534152556;2597.571400718879;2600.9349766255805;2604.5572252717084;2281.6577513372963;2455.754424557539;2573.45957348956;2584;2588.8576128312934;2535.0620270500035;2574.1505303249883;2581.0450133450554;2583.3799888026924;2587.45663410684;8928;9189.081091890825;9252.306077044248;9266.934302555157;9281.860772088425;5564.415736347666;5641.464060914206;5661.46216109469;5682.943170568283;5692.658440493584;3601.927961440783;3692.9815350923072;3728.3475391806323;3730.570984336809;3766.2090960898245;1028.7325295423243;1060.6075751971744;1065.6802959112235;1065.8241390170613;1067.871855377352;12384.009279257638;12691.809622855664;12696.1747486414;12715.173513721578;12725.745485090247;5027.874303142377;5034.647574669738;5133.512316329943;5176.767045482962;5196.4803519648485;10930.070943969851;10931.289466184831;10934.015938565408;10938;10955.17836162291;5899.675517846474;5906.084556893688;5908.970455147697;5917.289925208916;5930.199423077829;4289.249381358312;4658.464276608166;4732.5977291929885;5262.657927234794;5306.602004849575;4222.303319952235;4249.702520823513;4335.046289816235;4354.586314300136;4370.847020354272;1378.9724205515834;1418.581518452059;1419.6592817723879;1430.799688043654;1435.4904009076627;896.1038961038961;932.2355668352022;933.2906990687127;950;984.5815528400401;6001.669908155006;6053.636781793107;6074.878502429927;6175.320714721377;6220;491.55268705477846;492.65514140101357;493.7629937629958;501.51353187408154;501.9548240658285;3328.5340052392685;3940.231654827281;4079.63283304504;4094.9869128254477;4116.773577453209;10797.08224800885;11135.220534562503;11262.04272636835;11275.887241127484;11282.210245282751;11028.117750579935;11046.33721976684;11133.33200007986;11139.944300278446;11163.939425754541;7100.5029647925185;8648.827023459495;8649.740507784776;8665.090165532529;8666.739997800076;5570.749316280858;5833.766649333979;5851.268591426072;5884.146798714248;5956.8808623828145;9895.455749933823;9945.602175912882;10070.949645251727;10091;10104.646337378157;9927.1375464684;10067.798644027225;10155.949220253851;10389.532471038972;10454.790904181722;6879.071325371211;7057.823554411181;7432.665530051053;7764.767056988299;7840.490368126054;7728.497647653109;7991.4006449515355;8501.19238672325;8511.74464766058;8572.314214862672;7455;7599.278068583476;7646.9617651911385;7657.846843063219;7721.845563088819;7421.8144546386775;7477.551346919202;7482.551046937201;7598.772036838904;7672.769816905502;6789.524733268624;6871.3128687132885;6881.071055407656;6922.79231623032;6941.062956500808;4840.152973229741;4842.128416884994;4913.70517768935;5027.698338099725;5039.319691841554;5006.974965125151;5034.874128146826;5065.746712664426;5131.10205714006;5299.602529810202
05:05:13 INFO - PROCESS | 1671 | |4;prototype.html;336.15624781797686;343.54651859545396;349.26305495404694;352.96999755020806;356.5917025006369;6661.367170118831;6661.833454163642;6674.532782705212;6690.966545167267;6717.932820671792;6620.668966551665;6638.900416493744;6645.966770166143;6655.935050391939;6693.866122677544;4722.622190224791;4805.303231031498;4858.149823780842;4912.0175964807;4925.891674373255;4850.660453768238;4900.607951363901;4918.950810491885;4930.211166213407;4937.65436419449;4849.224124140138;4868.975655121737;4869.245266983623;4925.138100832358;4925.310456536088;4701.365315682373;4790.449098353699;4909.509049095098;4935.753212339387;4979.751012449363;4230.978845105755;4241.1093670329265;4258.382534532507;4258.893527661802;4268.9786551067045;6403.775867844603;6433.517486188554;6447.226332840042;6451.774187903401;6459.35406459355;5828.504577110951;5834.095715164156;5836.270466191726;5842.73707683151;5852.531797456193;5728.68492232927;7089.971954066683;7106;7116.395106415961;7133.144022717255;4441.555844415565;4580.564846339593;4586.816527338902;4587.587117159472;4599.103174880899;7165.140183178052;7488.288612581796;7491.438142139361;7500.099988001475;7511.774646760551;4691.358024691331;4693.155232058262;4699;4707.31743897133;4707.505711900201;4750.097481478508;4755.476897541268;4761.119192949315;4768.403949506312;4772.260299653559;5170.1986192140275;5221.973890130525;5693.658380497101;5699.116636921284;5701.144828275709;6838.589684618939;7067.22260551339;7091.6454177290925;7186.209516953131;7207.747728829466;1637.8853480256269;1647.1928754910248;1660.65126323471;1662.3683000459748;1668.7079761041769;996.5366104761321;1043.0716662170673;1043.9530221140067;1061.994690026545;1070.352436775755;6622.106015687917;6745.460363170934;6777;6777.864442711217;6789.660516974231;7445.813854653568;7448.180700122982;7451.701931922771;7454.515456495311;7471.626418679044;6738.079647300496;6740.427063699641;6741.191057073084;6747.662616869137;6777.932220677829;3870.0905287257474;3903.4535165076345;3913.2369188008634;3922.764634121962;3925.725199236083;7489.737859174903;7719;7733.729319473791;7748.651310691032;7748.883766743504;7645.923540764632;7729.729459468892;7731.729389471341;7735.845283094307;7737.92262077372;7810.29707326332;7815.29662330393;7815.960920195363;7819.843603127905;7825.7652270430835;6937.549059311129;7167.462440316893;7399.8150046249275;7444.032275804111;7451.8882216765705;7265.418766498773;7333.6333183341685;7335.523190992569;7336.449766267445;7340.926590734024;7785.688572457038;7789.298963093349;7792;7799.220077992155;7813.609319533887;4247.808848601882;4499.302608095751;4676.92984605238;4749.976250118727;4755.500672429345;7119;7367.300106489874;7431.40548756109;7433.293837085468;7437.181909990005;7151.53515021522;7164.104486939133;7175.677094530654;7190.245024272585;7198.820029499305;7135.179454362607;7167.8566428672175;7181.35367816899;7187.137543494814;7195.13658361;6981.685824137824;7002.75490357835;7004.334588214111;7020.087388639444;7022.367986881205;6261.436470717657;6936;7066.152061752417;7157.069580954442;7170.892436613351;6719.529632925648;6719.865602687821;6899.724011039502;6955.826104347432;7013.509054366145
05:05:13 INFO - PROCESS | 1671 | |5;yui.html;770.6416516319914;779.244133190805;785.7328508307174;791.8930944322515;793.5714714054411;15108.622284442894;15489.14809685467;15498.845011549882;15526.767098493503;15565;13129.277889716062;14247.145171289703;14388.424463021493;14401.567952961428;14429.062110962781;949.9667511637094;952.4237836109158;966.5457235099492;967.9516024198762;968.239931653652;801.53110430398;961.6105477281712;962.8988956159608;963.8120566489538;965.584798536629;1020.34187948773;1020.6886899495651;1020.9943205942178;1024.2011231239644;1026.7073883943078;1014.9951547967532;1015.0864222200005;1015.9796804063877;1016.2784423059636;1016.7712264740449;754.8678981178272;754.9547027178332;755.6335177438938;762.8474305138939;765.7128576783706;13083.41124649393;13133.212007279593;13144.60566183016;13187.208767473887;15288.847111528854;12319;12348.320842353667;12393.256404615662;12587.24476531411;13533.255670938095;9974.152197063258;10033.397996120184;10185.185185185168;10398.168146548258;10581.941805819433;3484.61669216386;3526.3828829954655;3542.574891013069;3574.410222313315;3663.9633603663888;73068.63465682691;78031;80207;82884.17115828824;83307.5834620823;9609.231261499202;9676.371035882787;9787;9952.552135153936;10305.175585953106;21604.56790864174;21703.34889953304;21757.67363489549;21763.67354489684;21807.67288490674;7555.206703296075;7567.545947243183;7708.653110609924;7712.614369281513;7728.343090837229;4125.814338354781;4165.981963678348;4194.454720886265;4202.789860506962;4253.234417804825;3838.9424158637644;3880.5537363203593;3894.065424298148;3894.6689531389584;3905.3946638271113;687.3916583823352;695.5478938689891;697.0554898113085;711.7259854955879;724.72460465024;540.1627477409999;575.323994306688;587.0020964360604;603.6076550242377;613.515322894917;8352.206540378655;8356.973468589582;8367.456115352483;8377.45546539473;8463.576821158917;566.4279078131117;582.205289779448;599.3736545310161;617.6973283091343;635.5455849067938;3814.6948244140403;3905;3956.208758248304;3962.782046987386;3990.461287726178;89185;89705.55147224222;90239;91144;91331.54334228286;63692.726145477754;64130.717385652955;66799.33200668028;74443;75425.6228718853;67160.6641966787;69059;69188.6540567304;69696.6515167431;69906.9513957291;8315.667373305;8557.400981931454;8603.52680602573;8606.956965215133;8850.690225842065;45334.63996080123;46168.61494155046;47706.522934771594;49613.50386496089;50326.99346013132;60763;60930.08604871019;62831.37168628438;65840.6707966457;66257.00614490882;11217.55129794799;11334.60328888485;11377;11727.179097463095;11773.352465614473;12091.939540302243;12255.080868935043;12265.26408415498;12448;12544.059195560187;11570.363630000431;11803.527858885549;11977.760444791229;12020.0383969284;12121.27272363661;11533.423328833693;11752.588659397223;11779.293242405483;12059.21615095016;12344.938275308567;11333.320000799979;11455.369954652575;11540.134489913458;11629.53481860716;12472.937635311766;8905.154010369004;9136.862947055666;9159.084091590788;9369;9612.086851749073;9860;9916.900830991597;9941;9948.303618746619;9950.502474876372
05:05:13 INFO - PROCESS | 1671 | __end_tp_report
05:05:13 INFO - PROCESS | 1671 | __start_cc_report
05:05:13 INFO - PROCESS | 1671 | _x_x_mozilla_cycle_collect,1100
05:05:13 INFO - PROCESS | 1671 | __end_cc_report
05:05:13 INFO - PROCESS | 1671 | __startTimestamp1467029113571__endTimestamp
05:05:13 INFO - PROCESS | 1671 |
05:05:13 INFO - PROCESS | 1671 | ------- Summary: start -------
05:05:13 INFO - PROCESS | 1671 | Number of tests: 6
05:05:13 INFO - PROCESS | 1671 |
05:05:13 INFO - PROCESS | 1671 | [#0] dojo.html Cycles:165 Average:5913.04 Median:6127.76 stddev:631.39 (10.3%) stddev-sans-first:600.34
05:05:13 INFO - PROCESS | 1671 | Values: 3345.3 3628.5 3633.6 3642.5 3712.5 5930.8 5944.9 5954.9 5965.9 5996.6 5946.8 5987.2 6000.3 6012.2 6019.1 5286.5 5351.4 5383.5 5438.4 5473.9 5422.7 5460.6 5465.2 5480.2 5481.2 5403.2 5441.0 5448.2 5477.7 5492.8 4675.0 5447.6 5460.2 5463.5 5487.2 5300.7 5318.5 5322.4 5400.3 5410.6 5834.0 5834.3 5861.7 5879.2 5915.5 6289.6 6300.3 6337.7 6340.1 6341.0 5555.5 6201.7 6224.4 6412.2 6421.6 6077.3 6083.8 6115.0 6126.1 6171.3 6502.8 6514.7 6518.9 6525.7 6526.8 6194.8 6221.5 6272.3 6296.3 6318.8 6381.4 6382.2 6394.7 6395.2 6400.9 6233.4 6278.6 6295.1 6299.0 6323.6 6213.8 6250.6 6265.9 6320.7 6336.8 5041.0 5067.4 5077.6 5092.3 5112.3 4611.3 4612.2 4615.2 4623.9 4636.2 5876.2 5974.7 6014.6 6111.1 6115.1 6345.5 6386.9 6402.1 6404.7 6420.8 6031.1 6051.5 6074.2 6076.1 6108.0 5416.7 5540.3 5556.2 5590.6 5633.9 6581.1 6606.2 6631.2 6648.8 6656.3 6216.9 6369.4 6385.4 6390.6 6413.9 6518.7 6616.2 6618.2 6632.3 6657.6 6305.1 6319.7 6339.7 6352.0 6379.0 6242.4 6280.4 6342.1 6350.9 6426.6 6229.7 6254.7 6274.7 6279.5 6293.8 5306.5 5401.4 5606.5 6072.3 6260.2 6084.0 6111.7 6165.5 6177.2 6186.5 6127.8 6157.4 6218.8 6219.8 6245.2 6060.5 6128.3 6128.7 6128.7 6131.0
05:05:13 INFO - PROCESS | 1671 |
05:05:13 INFO - PROCESS | 1671 | [#1] ext.html Cycles:180 Average:69889.98 Median:35103.97 stddev:151437.70 (431.4%) stddev-sans-first:151772.95
05:05:13 INFO - PROCESS | 1671 | Values: 516.5 523.9 608.9 611.7 641.1 32957.7 33603.3 33604.3 33616.3 33630.3 39299.2 43141.4 43145.8 43163.1 43189.4 9697.9 9897.5 9910.0 9923.9 9950.6 9164.9 9367.5 9679.2 9807.6 11994.0 11885.2 11973.9 11981.0 11987.4 11994.2 11773.4 11853.7 11874.0 11875.8 11889.8 11693.9 11790.1 11791.1 11799.3 11804.2 41305.4 41668.4 41758.4 43783.0 44054.0 3487.7 3537.7 3558.9 3725.0 3998.8 27803.0 28455.9 28648.6 28706.3 28877.3 2695.6 2716.6 2724.5 2740.2 2742.0 407885.0 413800.9 415267.0 415409.0 416173.0 67236.7 67574.0 67701.0 67715.0 67869.0 71916.9 75977.9 75992.6 76008.0 76012.6 18034.9 18258.7 18322.2 18324.9 18327.0 12433.2 13278.6 14702.9 14805.8 14819.0 17924.2 18106.0 18119.5 18139.1 20805.8 7887.0 7898.9 7919.8 7925.3 7969.8 1004.2 1056.3 1056.6 1077.9 1103.6 31740.2 31920.0 32069.7 32130.8 32270.8 1080.4 1105.0 1154.4 1167.8 1175.5 16299.7 16325.6 16383.9 16556.2 16648.8 649366.0 736437.0 738718.0 739265.0 741428.0 51167.7 63895.7 64519.7 64637.0 64644.0 1529.8 1530.3 1543.4 1547.3 1549.5 29213.9 31317.2 32862.0 33025.5 33386.0 58124.7 58512.7 58614.7 58647.4 58702.4 491715.0 507085.0 507142.0 507403.0 573481.0 26264.5 26863.9 29034.7 29912.0 30194.2 41192.0 41202.8 45424.8 45571.3 45577.9 36316.9 37291.1 37419.7 37432.6 38124.0 38202.6 38725.8 38963.4 39448.6 39605.0 39235.8 39432.6 39489.8 39738.8 40151.6 33092.2 33360.6 34527.7 35041.1 35166.8 25780.7 26045.7 26148.5 26278.9 26458.9
05:05:13 INFO - PROCESS | 1671 |
05:05:13 INFO - PROCESS | 1671 | [#2] jquery.html Cycles:180 Average:35893.71 Median:21326.41 stddev:102182.19 (479.1%) stddev-sans-first:102434.08
05:05:13 INFO - PROCESS | 1671 | Values: 400.9 418.8 422.7 423.9 432.5 16382.3 20853.7 21269.8 21634.7 21653.7 21181.5 21433.8 22279.8 22570.9 22575.9 4700.9 6104.1 6152.0 6158.6 6169.8 4432.6 5772.7 5975.7 6682.2 6847.5 6767.8 6784.3 6786.3 6792.1 6799.3 6416.6 6669.0 6672.0 6676.1 6684.4 5537.5 5837.5 5857.6 5864.1 5866.2 13481.7 13674.9 13715.8 13741.2 14604.7 2196.8 2308.4 2369.7 2384.2 2510.2 9854.8 10193.2 11236.6 11298.2 14677.8 1807.8 1829.0 1850.0 1857.2 1903.4 107817.0 109592.0 119872.0 120281.0 121280.6 11257.1 11554.9 11719.2 12021.0 12144.5 36036.8 36558.9 36972.3 37006.3 37010.6 11318.4 11437.9 11583.8 11601.4 11914.9 8551.0 9607.5 9745.2 10001.0 10167.1 10105.6 10182.8 10213.6 10258.6 10268.1 3332.9 3968.6 3975.7 4009.4 4125.4 913.6 969.5 978.1 987.1 995.7 20040.3 21324.3 21328.6 21397.5 21706.8 900.9 991.5 1004.6 1018.7 1116.3 11097.7 11325.5 11364.7 11431.0 11446.0 439520.0 639102.0 650452.0 654766.0 663968.0 92289.5 111371.0 127103.0 127690.0 128872.4 465.7 488.9 489.4 489.6 491.0 8313.9 17343.1 18818.1 19392.0 19561.1 40082.8 47501.3 51503.7 51529.7 53217.2 75695.0 77190.6 80113.0 82447.9 83147.2 10252.0 11205.9 12092.0 12142.3 12501.7 13155.8 13341.6 13545.1 13701.0 13862.5 10300.6 11420.7 11481.9 11641.8 11740.2 11344.3 11444.1 11449.5 11828.2 11873.6 6034.9 8276.5 11656.4 11859.1 12075.6 12221.9 12277.6 12486.4 12667.2 12738.0 11144.4 11731.5 11761.6 11819.6 12108.3
05:05:13 INFO - PROCESS | 1671 |
05:05:13 INFO - PROCESS | 1671 | [#3] mootools.html Cycles:180 Average:5917.91 Median:2750.37 stddev:3335.30 (121.3%) stddev-sans-first:3328.04
05:05:13 INFO - PROCESS | 1671 | Values: 1488.5 1617.6 1631.9 1887.3 2006.5 8348.5 9014.1 9168.2 9172.4 9183.9 8815.2 9052.4 9064.7 9075.3 9087.1 2564.4 2591.8 2592.9 2593.9 2594.7 2327.3 2459.0 2503.9 2574.0 2588.3 2548.8 2575.6 2597.6 2600.9 2604.6 2281.7 2455.8 2573.5 2584.0 2588.9 2535.1 2574.2 2581.0 2583.4 2587.5 8928.0 9189.1 9252.3 9266.9 9281.9 5564.4 5641.5 5661.5 5682.9 5692.7 3601.9 3693.0 3728.3 3730.6 3766.2 1028.7 1060.6 1065.7 1065.8 1067.9 12384.0 12691.8 12696.2 12715.2 12725.7 5027.9 5034.6 5133.5 5176.8 5196.5 10930.1 10931.3 10934.0 10938.0 10955.2 5899.7 5906.1 5909.0 5917.3 5930.2 4289.2 4658.5 4732.6 5262.7 5306.6 4222.3 4249.7 4335.0 4354.6 4370.8 1379.0 1418.6 1419.7 1430.8 1435.5 896.1 932.2 933.3 950.0 984.6 6001.7 6053.6 6074.9 6175.3 6220.0 491.6 492.7 493.8 501.5 502.0 3328.5 3940.2 4079.6 4095.0 4116.8 10797.1 11135.2 11262.0 11275.9 11282.2 11028.1 11046.3 11133.3 11139.9 11163.9 7100.5 8648.8 8649.7 8665.1 8666.7 5570.7 5833.8 5851.3 5884.1 5956.9 9895.5 9945.6 10070.9 10091.0 10104.6 9927.1 10067.8 10155.9 10389.5 10454.8 6879.1 7057.8 7432.7 7764.8 7840.5 7728.5 7991.4 8501.2 8511.7 8572.3 7455.0 7599.3 7647.0 7657.8 7721.8 7421.8 7477.6 7482.6 7598.8 7672.8 6789.5 6871.3 6881.1 6922.8 6941.1 4840.2 4842.1 4913.7 5027.7 5039.3 5007.0 5034.9 5065.7 5131.1 5299.6
05:05:13 INFO - PROCESS | 1671 |
05:05:13 INFO - PROCESS | 1671 | [#4] prototype.html Cycles:180 Average:5856.27 Median:6705.90 stddev:1879.91 (28.0%) stddev-sans-first:1838.95
05:05:13 INFO - PROCESS | 1671 | Values: 336.2 343.5 349.3 353.0 356.6 6661.4 6661.8 6674.5 6691.0 6717.9 6620.7 6638.9 6646.0 6655.9 6693.9 4722.6 4805.3 4858.1 4912.0 4925.9 4850.7 4900.6 4919.0 4930.2 4937.7 4849.2 4869.0 4869.2 4925.1 4925.3 4701.4 4790.4 4909.5 4935.8 4979.8 4231.0 4241.1 4258.4 4258.9 4269.0 6403.8 6433.5 6447.2 6451.8 6459.4 5828.5 5834.1 5836.3 5842.7 5852.5 5728.7 7090.0 7106.0 7116.4 7133.1 4441.6 4580.6 4586.8 4587.6 4599.1 7165.1 7488.3 7491.4 7500.1 7511.8 4691.4 4693.2 4699.0 4707.3 4707.5 4750.1 4755.5 4761.1 4768.4 4772.3 5170.2 5222.0 5693.7 5699.1 5701.1 6838.6 7067.2 7091.6 7186.2 7207.7 1637.9 1647.2 1660.7 1662.4 1668.7 996.5 1043.1 1044.0 1062.0 1070.4 6622.1 6745.5 6777.0 6777.9 6789.7 7445.8 7448.2 7451.7 7454.5 7471.6 6738.1 6740.4 6741.2 6747.7 6777.9 3870.1 3903.5 3913.2 3922.8 3925.7 7489.7 7719.0 7733.7 7748.7 7748.9 7645.9 7729.7 7731.7 7735.8 7737.9 7810.3 7815.3 7816.0 7819.8 7825.8 6937.5 7167.5 7399.8 7444.0 7451.9 7265.4 7333.6 7335.5 7336.4 7340.9 7785.7 7789.3 7792.0 7799.2 7813.6 4247.8 4499.3 4676.9 4750.0 4755.5 7119.0 7367.3 7431.4 7433.3 7437.2 7151.5 7164.1 7175.7 7190.2 7198.8 7135.2 7167.9 7181.4 7187.1 7195.1 6981.7 7002.8 7004.3 7020.1 7022.4 6261.4 6936.0 7066.2 7157.1 7170.9 6719.5 6719.9 6899.7 6955.8 7013.5
05:05:13 INFO - PROCESS | 1671 |
05:05:13 INFO - PROCESS | 1671 | [#5] yui.html Cycles:180 Average:17845.86 Median:553.30 stddev:24496.83 (4427.4%) stddev-sans-first:24531.99
05:05:13 INFO - PROCESS | 1671 | Values: 770.6 779.2 785.7 791.9 793.6 15108.6 15489.1 15498.8 15526.8 15565.0 13129.3 14247.1 14388.4 14401.6 14429.1 950.0 952.4 966.5 968.0 968.2 801.5 961.6 962.9 963.8 965.6 1020.3 1020.7 1021.0 1024.2 1026.7 1015.0 1015.1 1016.0 1016.3 1016.8 754.9 755.0 755.6 762.8 765.7 13083.4 13133.2 13144.6 13187.2 15288.8 12319.0 12348.3 12393.3 12587.2 13533.3 9974.2 10033.4 10185.2 10398.2 10581.9 3484.6 3526.4 3542.6 3574.4 3664.0 73068.6 78031.0 80207.0 82884.2 83307.6 9609.2 9676.4 9787.0 9952.6 10305.2 21604.6 21703.3 21757.7 21763.7 21807.7 7555.2 7567.5 7708.7 7712.6 7728.3 4125.8 4166.0 4194.5 4202.8 4253.2 3838.9 3880.6 3894.1 3894.7 3905.4 687.4 695.5 697.1 711.7 724.7 540.2 575.3 587.0 603.6 613.5 8352.2 8357.0 8367.5 8377.5 8463.6 566.4 582.2 599.4 617.7 635.5 3814.7 3905.0 3956.2 3962.8 3990.5 89185.0 89705.6 90239.0 91144.0 91331.5 63692.7 64130.7 66799.3 74443.0 75425.6 67160.7 69059.0 69188.7 69696.7 69907.0 8315.7 8557.4 8603.5 8607.0 8850.7 45334.6 46168.6 47706.5 49613.5 50327.0 60763.0 60930.1 62831.4 65840.7 66257.0 11217.6 11334.6 11377.0 11727.2 11773.4 12091.9 12255.1 12265.3 12448.0 12544.1 11570.4 11803.5 11977.8 12020.0 12121.3 11533.4 11752.6 11779.3 12059.2 12344.9 11333.3 11455.4 11540.1 11629.5 12472.9 8905.2 9136.9 9159.1 9369.0 9612.1 9860.0 9916.9 9941.0 9948.3 9950.5
05:05:13 INFO - PROCESS | 1671 | -------- Summary: end --------
05:05:13 INFO - PROCESS | 1671 |
05:05:14 INFO - TEST-INFO | 1671: exit 0
05:05:14 INFO - TEST-OK | dromaeo_css | took 1112883ms
05:05:14 INFO - TEST-START | kraken
05:05:14 INFO - operating with platform_type : mac_
05:05:14 INFO - Initialising browser for kraken test...
05:05:14 INFO - TEST-INFO | started process 1677 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/tmpOHkAjv/profile http://localhost:49277/getInfo.html)
05:05:16 INFO - PROCESS | 1677 | __metrics Screen width/height:1600/1200
05:05:16 INFO - PROCESS | 1677 | colorDepth:24
05:05:16 INFO - PROCESS | 1677 | Browser inner width/height: 1024/689
05:05:16 INFO - PROCESS | 1677 | __metrics
05:05:16 INFO - PROCESS | 1677 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows"
05:05:16 ERROR - PROCESS | 1677 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null
05:05:16 INFO - TEST-INFO | 1677: exit 0
05:05:16 INFO - Browser initialized.
05:05:16 INFO - Running cycle 1/1 for kraken test...
05:05:16 INFO - TEST-INFO | started process 1680 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/tmpOHkAjv/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1)
05:05:28 INFO - PROCESS | 1680 | RSS: Main: 160935936
05:05:28 INFO - PROCESS | 1680 |
05:05:31 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49277/tests/kraken/driver.html?testName=audio-beat-detection)
05:05:32 INFO - PROCESS | 1680 | RSS: Main: 223977472
05:05:32 INFO - PROCESS | 1680 |
05:05:34 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49277/tests/kraken/driver.html?testName=audio-dft)
05:05:35 INFO - PROCESS | 1680 | RSS: Main: 239632384
05:05:35 INFO - PROCESS | 1680 |
05:05:38 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49277/tests/kraken/driver.html?testName=audio-fft)
05:05:39 INFO - PROCESS | 1680 | RSS: Main: 324632576
05:05:39 INFO - PROCESS | 1680 |
05:05:41 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49277/tests/kraken/driver.html?testName=audio-oscillator)
05:05:41 INFO - PROCESS | 1680 | RSS: Main: 231473152
05:05:41 INFO - PROCESS | 1680 |
05:05:44 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49277/tests/kraken/driver.html?testName=imaging-gaussian-blur)
05:05:44 INFO - PROCESS | 1680 | RSS: Main: 231059456
05:05:44 INFO - PROCESS | 1680 |
05:05:47 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49277/tests/kraken/driver.html?testName=imaging-darkroom)
05:05:47 INFO - PROCESS | 1680 | RSS: Main: 270282752
05:05:47 INFO - PROCESS | 1680 |
05:05:50 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49277/tests/kraken/driver.html?testName=imaging-desaturate)
05:05:51 INFO - PROCESS | 1680 | RSS: Main: 272003072
05:05:51 INFO - PROCESS | 1680 |
05:05:54 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49277/tests/kraken/driver.html?testName=json-parse-financial)
05:05:54 INFO - PROCESS | 1680 | RSS: Main: 268742656
05:05:54 INFO - PROCESS | 1680 |
05:05:56 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49277/tests/kraken/driver.html?testName=json-stringify-tinderbox)
05:05:57 INFO - PROCESS | 1680 | RSS: Main: 261103616
05:05:57 INFO - PROCESS | 1680 |
05:05:58 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-aes)
05:05:59 INFO - PROCESS | 1680 | RSS: Main: 267821056
05:05:59 INFO - PROCESS | 1680 |
05:06:01 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-ccm)
05:06:02 INFO - PROCESS | 1680 | RSS: Main: 271380480
05:06:02 INFO - PROCESS | 1680 |
05:06:04 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2)
05:06:05 INFO - PROCESS | 1680 | RSS: Main: 278364160
05:06:05 INFO - PROCESS | 1680 |
05:06:08 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative)
05:06:08 INFO - PROCESS | 1680 | RSS: Main: 275582976
05:06:08 INFO - PROCESS | 1680 |
05:06:10 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49277/tests/kraken/driver.html?testName=ai-astar)
05:06:10 INFO - PROCESS | 1680 | RSS: Main: 336363520
05:06:10 INFO - PROCESS | 1680 |
05:06:10 INFO - PROCESS | 1680 | __start_tp_report
05:06:10 INFO - PROCESS | 1680 | _x_x_mozilla_page_load
05:06:10 INFO - PROCESS | 1680 | _x_x_mozilla_page_load_details
05:06:10 INFO - PROCESS | 1680 | |i|pagename|runs|
05:06:10 INFO - PROCESS | 1680 | |0;ai-astar;113;113;117;114;112;112;114;113;114;134
05:06:10 INFO - PROCESS | 1680 | |1;audio-beat-detection;156;119;145;163;116;140;135;116;163;115
05:06:10 INFO - PROCESS | 1680 | |2;audio-dft;184;185;185;186;184;185;184;197;263;207
05:06:10 INFO - PROCESS | 1680 | |3;audio-fft;70;77;72;158;61;76;82;87;160;56
05:06:10 INFO - PROCESS | 1680 | |4;audio-oscillator;89;88;146;85;113;139;132;78;78;125
05:06:10 INFO - PROCESS | 1680 | |5;imaging-gaussian-blur;86;84;86;83;86;83;87;84;87;83
05:06:10 INFO - PROCESS | 1680 | |6;imaging-darkroom;92;108;148;92;92;92;92;117;151;110
05:06:10 INFO - PROCESS | 1680 | |7;imaging-desaturate;86;84;88;86;85;83;86;86;87;85
05:06:10 INFO - PROCESS | 1680 | |8;json-parse-financial;55;60;62;60;62;62;63;76;58;56
05:06:10 INFO - PROCESS | 1680 | |9;json-stringify-tinderbox;60;64;64;76;71;67;64;63;66;65
05:06:10 INFO - PROCESS | 1680 | |10;stanford-crypto-aes;80;80;79;80;80;75;81;80;80;83
05:06:10 INFO - PROCESS | 1680 | |11;stanford-crypto-ccm;129;146;146;117;122;126;124;125;152;160
05:06:10 INFO - PROCESS | 1680 | |12;stanford-crypto-pbkdf2;155;162;162;158;162;161;163;168;160;156
05:06:10 INFO - PROCESS | 1680 | |13;stanford-crypto-sha256-iterative;64;65;65;67;67;65;66;68;67;66
05:06:10 INFO - PROCESS | 1680 | __end_tp_report
05:06:10 INFO - PROCESS | 1680 | __start_cc_report
05:06:10 INFO - PROCESS | 1680 | _x_x_mozilla_cycle_collect,1890
05:06:10 INFO - PROCESS | 1680 | __end_cc_report
05:06:10 INFO - PROCESS | 1680 | __startTimestamp1467029170825__endTimestamp
05:06:10 INFO - PROCESS | 1680 |
05:06:10 INFO - PROCESS | 1680 | ------- Summary: start -------
05:06:10 INFO - PROCESS | 1680 | Number of tests: 14
05:06:10 INFO - PROCESS | 1680 |
05:06:10 INFO - PROCESS | 1680 | [#0] ai-astar Cycles:10 Average:115.60 Median:114.00 stddev:6.62 (5.8%) stddev-sans-first:6.95
05:06:10 INFO - PROCESS | 1680 | Values: 113.0 113.0 117.0 114.0 112.0 112.0 114.0 113.0 114.0 134.0
05:06:10 INFO - PROCESS | 1680 |
05:06:10 INFO - PROCESS | 1680 | [#1] audio-beat-detection Cycles:10 Average:136.80 Median:142.50 stddev:19.66 (13.8%) stddev-sans-first:19.59
05:06:10 INFO - PROCESS | 1680 | Values: 156.0 119.0 145.0 163.0 116.0 140.0 135.0 116.0 163.0 115.0
05:06:10 INFO - PROCESS | 1680 |
05:06:10 INFO - PROCESS | 1680 | [#2] audio-dft Cycles:10 Average:196.00 Median:185.50 stddev:24.73 (13.3%) stddev-sans-first:25.85
05:06:10 INFO - PROCESS | 1680 | Values: 184.0 185.0 185.0 186.0 184.0 185.0 184.0 197.0 263.0 207.0
05:06:10 INFO - PROCESS | 1680 |
05:06:10 INFO - PROCESS | 1680 | [#3] audio-fft Cycles:10 Average:89.90 Median:74.00 stddev:37.54 (50.7%) stddev-sans-first:39.12
05:06:10 INFO - PROCESS | 1680 | Values: 70.0 77.0 72.0 158.0 61.0 76.0 82.0 87.0 160.0 56.0
05:06:10 INFO - PROCESS | 1680 |
05:06:10 INFO - PROCESS | 1680 | [#4] audio-oscillator Cycles:10 Average:107.30 Median:78.00 stddev:26.63 (34.1%) stddev-sans-first:27.40
05:06:10 INFO - PROCESS | 1680 | Values: 89.0 88.0 146.0 85.0 113.0 139.0 132.0 78.0 78.0 125.0
05:06:10 INFO - PROCESS | 1680 |
05:06:10 INFO - PROCESS | 1680 | [#5] imaging-gaussian-blur Cycles:10 Average:84.90 Median:86.00 stddev:1.66 (1.9%) stddev-sans-first:1.72
05:06:10 INFO - PROCESS | 1680 | Values: 86.0 84.0 86.0 83.0 86.0 83.0 87.0 84.0 87.0 83.0
05:06:10 INFO - PROCESS | 1680 |
05:06:10 INFO - PROCESS | 1680 | [#6] imaging-darkroom Cycles:10 Average:109.40 Median:92.00 stddev:23.08 (25.1%) stddev-sans-first:23.61
05:06:10 INFO - PROCESS | 1680 | Values: 92.0 108.0 148.0 92.0 92.0 92.0 92.0 117.0 151.0 110.0
05:06:10 INFO - PROCESS | 1680 |
05:06:10 INFO - PROCESS | 1680 | [#7] imaging-desaturate Cycles:10 Average:85.60 Median:86.00 stddev:1.43 (1.7%) stddev-sans-first:1.51
05:06:10 INFO - PROCESS | 1680 | Values: 86.0 84.0 88.0 86.0 85.0 83.0 86.0 86.0 87.0 85.0
05:06:10 INFO - PROCESS | 1680 |
05:06:10 INFO - PROCESS | 1680 | [#8] json-parse-financial Cycles:10 Average:61.40 Median:62.00 stddev:5.80 (9.3%) stddev-sans-first:5.67
05:06:10 INFO - PROCESS | 1680 | Values: 55.0 60.0 62.0 60.0 62.0 62.0 63.0 76.0 58.0 56.0
05:06:10 INFO - PROCESS | 1680 |
05:06:10 INFO - PROCESS | 1680 | [#9] json-stringify-tinderbox Cycles:10 Average:66.00 Median:65.50 stddev:4.52 (6.9%) stddev-sans-first:4.24
05:06:10 INFO - PROCESS | 1680 | Values: 60.0 64.0 64.0 76.0 71.0 67.0 64.0 63.0 66.0 65.0
05:06:10 INFO - PROCESS | 1680 |
05:06:10 INFO - PROCESS | 1680 | [#10] stanford-crypto-aes Cycles:10 Average:79.80 Median:80.00 stddev:1.99 (2.5%) stddev-sans-first:2.11
05:06:10 INFO - PROCESS | 1680 | Values: 80.0 80.0 79.0 80.0 80.0 75.0 81.0 80.0 80.0 83.0
05:06:10 INFO - PROCESS | 1680 |
05:06:10 INFO - PROCESS | 1680 | [#11] stanford-crypto-ccm Cycles:10 Average:134.70 Median:137.50 stddev:14.86 (10.8%) stddev-sans-first:15.61
05:06:10 INFO - PROCESS | 1680 | Values: 129.0 146.0 146.0 117.0 122.0 126.0 124.0 125.0 152.0 160.0
05:06:10 INFO - PROCESS | 1680 |
05:06:10 INFO - PROCESS | 1680 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:160.70 Median:162.00 stddev:3.74 (2.3%) stddev-sans-first:3.35
05:06:10 INFO - PROCESS | 1680 | Values: 155.0 162.0 162.0 158.0 162.0 161.0 163.0 168.0 160.0 156.0
05:06:10 INFO - PROCESS | 1680 |
05:06:10 INFO - PROCESS | 1680 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.00 Median:66.50 stddev:1.25 (1.9%) stddev-sans-first:1.09
05:06:10 INFO - PROCESS | 1680 | Values: 64.0 65.0 65.0 67.0 67.0 65.0 66.0 68.0 67.0 66.0
05:06:10 INFO - PROCESS | 1680 | -------- Summary: end --------
05:06:10 INFO - PROCESS | 1680 |
05:06:11 INFO - TEST-INFO | 1680: exit 0
05:06:11 INFO - TEST-OK | kraken | took 57431ms
05:06:11 INFO - SUITE-END | took 1170s
05:06:11 INFO - Completed test suite (00:19:30)
05:06:11 INFO - javascript benchmark
05:06:12 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3345.330933813237, 3628.546431696038, 3633.5821380541247, 3642.4900513928055, 3712.4988126602902, 5930.792422265222, 5944.851378715534, 5954.880902381949, 5965.88068238635, 5996.610220335675, 5946.791862284821, 5987.221661184051, 6000.33996260411, 6012.2184116064855, 6019.097135429688, 5286.471352864722, 5351.357837059559, 5383.515483606475, 5438.401775804658, 5473.890522189554, 5422.728863556826, 5460.645058071213, 5465.234867118606, 5480.150576660604, 5481.205225242337, 5403.189521571776, 5440.9934162180125, 5448.210009548613, 5477.726113694319, 5492.752826122814, 4674.994876101655, 5447.645903016291, 5460.180972854104, 5463.535599474009, 5487.176923461473, 5300.681959082467, 5318.494742999409, 5322.361316641989, 5400.270963419927, 5410.621256512046, 5834.008218602848, 5834.2998840139035, 5861.736221870026, 5879.235699359098, 5915.526757859361, 6289.559730818844, 6300.275468321156, 6337.651429171394, 6340.112384266206, 6341.0, 5555.500004999529, 6201.720922558496, 6224.439800417938, 6412.198475190601, 6421.614703117782, 6077.270727512682, 6083.756649734005, 6114.9694251529345, 6126.111713801474, 6171.25944886612, 6502.804915852532, 6514.739410423625, 6518.934810651833, 6525.738970441129, 6526.804195874131, 6194.752209911643, 6221.5333849961435, 6272.278687950853, 6296.275928268216, 6318.8420289492215, 6381.3937675920715, 6382.20222472191, 6394.712237949303, 6395.232572091379, 6400.871982560323, 6233.407826256498, 6278.623282603058, 6295.087212354183, 6298.968505157445, 6323.588966717149, 6213.751449942042, 6250.562460627803, 6265.937340626627, 6320.652364120017, 6336.841578960563, 5040.974795126001, 5067.442581316052, 5077.568406685473, 5092.28707980883, 5112.258722485219, 4611.262198048296, 4612.238980568236, 4615.215413379734, 4623.9075218495445, 4636.188666983265, 5876.236089308362, 5974.731137098841, 6014.609050411709, 6111.113888486234, 6115.082737589397, 6345.524085693592, 6386.936130638727, 6402.071699603532, 6404.679766011681, 6420.839479013062, 6031.125486804476, 6051.485623722033, 6074.210352654127, 6076.118962750376, 6107.969460152671, 5416.7020813855615, 5540.335159780852, 5556.16657501378, 5590.636608620427, 5633.859153521113, 6581.144451221407, 6606.174228221473, 6631.20425548918, 6648.767293144717, 6656.334366563305, 6216.937830621636, 6369.363063693593, 6385.425311721968, 6390.616563006234, 6413.8717225656155, 6518.706658200487, 6616.172978377703, 6618.205815302194, 6632.336766323329, 6657.567258128206, 6305.054241863573, 6319.715612797527, 6339.651319177489, 6352.0, 6379.011253255753, 6242.438180563771, 6280.371962803683, 6342.143810585512, 6350.872982540416, 6426.646534440651, 6229.657368844757, 6254.749810007731, 6274.686265686789, 6279.497640188682, 6293.811185664437, 5306.548943339705, 5401.432849550741, 5606.523445507178, 6072.271327440735, 6260.186175797299, 6083.969580152071, 6111.663858487648, 6165.475934545614, 6177.227846519185, 6186.505079593531, 6127.758165267708, 6157.353477884758, 6218.782342618168, 6219.813405597839, 6245.18812554365, 6060.454559089703, 6128.325884152829, 6128.66292353907, 6128.693565321805, 6130.9577371846], "value": 5874.809597333099, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [516.5428595692812, 523.8585581892889, 608.8812681527104, 611.6788685939878, 641.0640464921215, 32957.670423295756, 33603.327933441375, 33604.327913441746, 33616.32767344652, 33630.32739345211, 39299.21401571967, 43141.35287970683, 43145.78427107868, 43163.115319621225, 43189.35215971763, 9697.903020969807, 9897.50512474377, 9909.950450247774, 9923.851142232872, 9950.552225149886, 9164.8625270621, 9367.531623418836, 9679.225661947028, 9807.558659860324, 11994.040476761882, 11885.227460215101, 11973.880261197408, 11980.98161656256, 11987.40062996851, 11994.220375675553, 11773.352465614387, 11853.703657408547, 11873.990710789592, 11875.82186267211, 11889.821652675217, 11693.883061169365, 11790.056795456429, 11791.056715462742, 11799.292042477393, 11804.173707840455, 41305.38041929373, 41668.37497437541, 41758.37362439566, 43783.0, 44054.0, 3487.668671476206, 3537.6970579000103, 3558.857645694169, 3724.981375093107, 3998.8000599969887, 27803.0, 28455.857720711472, 28648.570271445944, 28706.282342941384, 28877.278068048257, 2695.649565556465, 2716.5925111233278, 2724.5368287391193, 2740.2190375742816, 2742.012875364867, 407885.0, 413800.9309953461, 415267.0, 415409.0, 416173.0, 67236.66381668061, 67574.0, 67701.0, 67715.0, 67869.0, 71916.92124618134, 75977.86033209506, 75992.62003689946, 76008.0, 76012.61993689997, 18034.917904925747, 18258.726119108225, 18322.175502102436, 18324.90837545804, 18326.992015439013, 12433.191842530206, 13278.601641950758, 14702.852971470362, 14805.777913331309, 14819.0, 17924.193411296525, 18106.0, 18119.547011324823, 18139.09304534768, 20805.791942080687, 7886.960565197137, 7898.921010789819, 7919.802004949807, 7925.286724194735, 7969.800754981172, 1004.2468148888333, 1056.3186744549876, 1056.5562463765236, 1077.9029887310023, 1103.624767579027, 31740.206494837814, 31920.0, 32069.67930320667, 32130.83934580312, 32270.83864580662, 1080.438172150493, 1105.0, 1154.3708678769922, 1167.795635763738, 1175.535663412947, 16299.674006519803, 16325.59186020359, 16383.918080409521, 16556.172191390382, 16648.833511664972, 649366.0, 736437.0, 738718.0, 739265.0, 741428.0, 51167.744161278955, 63895.68052159803, 64519.67740161363, 64637.0, 64644.0, 1529.755239161751, 1530.3266562712372, 1543.398074750848, 1547.257316488092, 1549.527394144774, 29213.85393073021, 31317.23976561631, 32862.0, 33025.50461743124, 33386.0, 58124.709376454535, 58512.707436462544, 58614.70692646509, 58647.4135258659, 58702.412975869694, 491715.0, 507085.0, 507142.0, 507403.0, 573481.0, 26264.4747105053, 26863.86568067147, 29034.709652903202, 29912.0, 30194.24514387158, 41192.0, 41202.79398602988, 45424.77287613541, 45571.316430254235, 45577.860553486425, 36316.91049268526, 37291.06772330713, 37419.69031083899, 37432.625673742914, 38124.04689882775, 38202.617973819906, 38725.80637096796, 38963.415548767356, 39448.605513944494, 39605.00987475336, 39235.822925312284, 39432.6056739429, 39489.802550987064, 39738.801305993285, 40151.59848401479, 33092.17269568184, 33360.583044734194, 34527.65472345244, 35041.12397190091, 35166.82416587901, 25780.742192578586, 26045.739542604333, 26148.477030459664, 26278.94884204687, 26458.86770566135], "value": 20931.679708189033, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [400.9478767760191, 418.82409388057033, 422.7379025004497, 423.86648205815163, 432.5307041859582, 16382.344706211752, 20853.663413853785, 21269.787302126955, 21634.675479867816, 21653.67519487209, 21181.470463238387, 21433.785662143375, 22279.77720222797, 22570.887145564247, 22575.887120564374, 4700.882477938053, 6104.08438734189, 6151.969240153793, 6158.56890017699, 6169.753209871622, 4432.578905004036, 5772.682502462363, 5975.671338076404, 6682.198136223636, 6847.520673552854, 6767.796966091025, 6784.321567843201, 6786.253512113663, 6792.117024786796, 6799.286074962132, 6416.550841441078, 6669.032990216391, 6671.966640166816, 6676.098726671886, 6684.398404143651, 5537.473939975695, 5837.474627283523, 5857.619254748428, 5864.06175011996, 5866.178734977107, 13481.730365392637, 13674.93162534191, 13715.794263086062, 13741.24423156726, 14604.70790584193, 2196.8242540596716, 2308.3998160478227, 2369.6563998220163, 2384.225126833786, 2510.1590967025886, 9854.753631159278, 10193.23550733698, 11236.606718764886, 11298.209125361229, 14677.77983330251, 1807.7921039080543, 1828.9725654115198, 1850.0009994603033, 1857.1735577667944, 1903.381401044666, 107816.99999999921, 109592.0, 119872.0, 120281.0, 121280.57438851261, 11257.099432045417, 11554.94222528882, 11719.238249513925, 12021.0, 12144.453499592539, 36036.819815901275, 36558.90323290305, 36972.26055478875, 37006.29937006278, 37010.629893701254, 11318.434078296052, 11437.885621143681, 11583.826242606368, 11601.419929003516, 11914.880851191378, 8550.957245213734, 9607.471589062528, 9745.220382369393, 10000.999900009941, 10167.135793457639, 10105.595776169019, 10182.796344073226, 10213.642522511676, 10258.640947566948, 10268.127209187154, 3332.8833490827706, 3968.5634580196156, 3975.6819454443576, 4009.4363198352667, 4125.443065186221, 913.6299798581585, 969.5152423788106, 978.1196922769423, 987.1411871671692, 995.7162208770466, 20040.298589549588, 21324.253651122137, 21328.573428531654, 21397.46506337354, 21706.78293217079, 900.9009009009004, 991.4844280973854, 1004.5780772075746, 1018.6689325969089, 1116.2911551164907, 11097.667069987914, 11325.490352934137, 11364.715882103013, 11431.0, 11446.0, 439520.0, 639102.0224494425, 650452.0, 654766.0, 663968.0, 92289.53855230815, 111371.0, 127103.0, 127690.0, 128872.35563822309, 465.6717014504766, 488.8899997500534, 489.40781654198105, 489.6009752052066, 490.95581397673664, 8313.875291870747, 17343.132843358035, 18818.059097045367, 19392.0, 19561.119749611586, 40082.799586001885, 47501.287480687126, 51503.74248128735, 51529.742351288005, 53217.20174197313, 75695.0, 77190.6140469294, 80113.0, 82447.93880153044, 83147.16852831394, 10252.0, 11205.887941120485, 12092.0, 12142.300077989265, 12501.749965000467, 13155.802662960255, 13341.599752007456, 13545.051846371054, 13701.0, 13862.514811981533, 10300.587976480856, 11420.714482138013, 11481.942590286995, 11641.76716465649, 11740.236884602473, 11344.319340839576, 11444.084473241954, 11449.484773185393, 11828.17202795796, 11873.643790686294, 6034.939650603438, 8276.503409795432, 11656.426382438261, 11859.110566707704, 12075.637730868088, 12221.877781222074, 12277.631671049883, 12486.438110284877, 12667.239965602093, 12738.0, 11144.387058711525, 11731.530738770354, 11761.647150585497, 11819.586314479297, 12108.273503589813], "value": 10401.745091619481, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1488.4641529049534, 1617.5632579203614, 1631.9020858748472, 1887.311131437025, 2006.5284658105352, 8348.499090054602, 9014.143656352644, 9168.174864262215, 9172.357934944557, 9183.908160918381, 8815.162559556848, 9052.366334356597, 9064.682736104238, 9075.273978081754, 9087.091290870909, 2564.358910272432, 2591.753783390575, 2592.8833202505934, 2593.9351516212055, 2594.675665541807, 2327.278543651465, 2459.004103338142, 2503.912363067293, 2574.034736973635, 2588.3011586871535, 2548.808839337057, 2575.626534152556, 2597.571400718879, 2600.9349766255805, 2604.5572252717084, 2281.6577513372963, 2455.754424557539, 2573.45957348956, 2584.0, 2588.8576128312934, 2535.0620270500035, 2574.1505303249883, 2581.0450133450554, 2583.3799888026924, 2587.45663410684, 8928.0, 9189.081091890825, 9252.306077044248, 9266.934302555157, 9281.860772088425, 5564.415736347666, 5641.464060914206, 5661.46216109469, 5682.943170568283, 5692.658440493584, 3601.927961440783, 3692.9815350923072, 3728.3475391806323, 3730.570984336809, 3766.2090960898245, 1028.7325295423243, 1060.6075751971744, 1065.6802959112235, 1065.8241390170613, 1067.871855377352, 12384.009279257638, 12691.809622855664, 12696.1747486414, 12715.173513721578, 12725.745485090247, 5027.874303142377, 5034.647574669738, 5133.512316329943, 5176.767045482962, 5196.4803519648485, 10930.070943969851, 10931.289466184831, 10934.015938565408, 10938.0, 10955.17836162291, 5899.675517846474, 5906.084556893688, 5908.970455147697, 5917.289925208916, 5930.199423077829, 4289.249381358312, 4658.464276608166, 4732.5977291929885, 5262.657927234794, 5306.602004849575, 4222.303319952235, 4249.702520823513, 4335.046289816235, 4354.586314300136, 4370.847020354272, 1378.9724205515834, 1418.581518452059, 1419.6592817723879, 1430.799688043654, 1435.4904009076627, 896.1038961038961, 932.2355668352022, 933.2906990687127, 950.0, 984.5815528400401, 6001.669908155006, 6053.636781793107, 6074.878502429927, 6175.320714721377, 6220.0, 491.55268705477846, 492.65514140101357, 493.7629937629958, 501.51353187408154, 501.9548240658285, 3328.5340052392685, 3940.231654827281, 4079.63283304504, 4094.9869128254477, 4116.773577453209, 10797.08224800885, 11135.220534562503, 11262.04272636835, 11275.887241127484, 11282.210245282751, 11028.117750579935, 11046.33721976684, 11133.33200007986, 11139.944300278446, 11163.939425754541, 7100.5029647925185, 8648.827023459495, 8649.740507784776, 8665.090165532529, 8666.739997800076, 5570.749316280858, 5833.766649333979, 5851.268591426072, 5884.146798714248, 5956.8808623828145, 9895.455749933823, 9945.602175912882, 10070.949645251727, 10091.0, 10104.646337378157, 9927.1375464684, 10067.798644027225, 10155.949220253851, 10389.532471038972, 10454.790904181722, 6879.071325371211, 7057.823554411181, 7432.665530051053, 7764.767056988299, 7840.490368126054, 7728.497647653109, 7991.4006449515355, 8501.19238672325, 8511.74464766058, 8572.314214862672, 7455.0, 7599.278068583476, 7646.9617651911385, 7657.846843063219, 7721.845563088819, 7421.8144546386775, 7477.551346919202, 7482.551046937201, 7598.772036838904, 7672.769816905502, 6789.524733268624, 6871.3128687132885, 6881.071055407656, 6922.79231623032, 6941.062956500808, 4840.152973229741, 4842.128416884994, 4913.70517768935, 5027.698338099725, 5039.319691841554, 5006.974965125151, 5034.874128146826, 5065.746712664426, 5131.10205714006, 5299.602529810202], "value": 4699.979538028291, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [336.15624781797686, 343.54651859545396, 349.26305495404694, 352.96999755020806, 356.5917025006369, 6661.367170118831, 6661.833454163642, 6674.532782705212, 6690.966545167267, 6717.932820671792, 6620.668966551665, 6638.900416493744, 6645.966770166143, 6655.935050391939, 6693.866122677544, 4722.622190224791, 4805.303231031498, 4858.149823780842, 4912.0175964807, 4925.891674373255, 4850.660453768238, 4900.607951363901, 4918.950810491885, 4930.211166213407, 4937.65436419449, 4849.224124140138, 4868.975655121737, 4869.245266983623, 4925.138100832358, 4925.310456536088, 4701.365315682373, 4790.449098353699, 4909.509049095098, 4935.753212339387, 4979.751012449363, 4230.978845105755, 4241.1093670329265, 4258.382534532507, 4258.893527661802, 4268.9786551067045, 6403.775867844603, 6433.517486188554, 6447.226332840042, 6451.774187903401, 6459.35406459355, 5828.504577110951, 5834.095715164156, 5836.270466191726, 5842.73707683151, 5852.531797456193, 5728.68492232927, 7089.971954066683, 7106.0, 7116.395106415961, 7133.144022717255, 4441.555844415565, 4580.564846339593, 4586.816527338902, 4587.587117159472, 4599.103174880899, 7165.140183178052, 7488.288612581796, 7491.438142139361, 7500.099988001475, 7511.774646760551, 4691.358024691331, 4693.155232058262, 4699.0, 4707.31743897133, 4707.505711900201, 4750.097481478508, 4755.476897541268, 4761.119192949315, 4768.403949506312, 4772.260299653559, 5170.1986192140275, 5221.973890130525, 5693.658380497101, 5699.116636921284, 5701.144828275709, 6838.589684618939, 7067.22260551339, 7091.6454177290925, 7186.209516953131, 7207.747728829466, 1637.8853480256269, 1647.1928754910248, 1660.65126323471, 1662.3683000459748, 1668.7079761041769, 996.5366104761321, 1043.0716662170673, 1043.9530221140067, 1061.994690026545, 1070.352436775755, 6622.106015687917, 6745.460363170934, 6777.0, 6777.864442711217, 6789.660516974231, 7445.813854653568, 7448.180700122982, 7451.701931922771, 7454.515456495311, 7471.626418679044, 6738.079647300496, 6740.427063699641, 6741.191057073084, 6747.662616869137, 6777.932220677829, 3870.0905287257474, 3903.4535165076345, 3913.2369188008634, 3922.764634121962, 3925.725199236083, 7489.737859174903, 7719.0, 7733.729319473791, 7748.651310691032, 7748.883766743504, 7645.923540764632, 7729.729459468892, 7731.729389471341, 7735.845283094307, 7737.92262077372, 7810.29707326332, 7815.29662330393, 7815.960920195363, 7819.843603127905, 7825.7652270430835, 6937.549059311129, 7167.462440316893, 7399.8150046249275, 7444.032275804111, 7451.8882216765705, 7265.418766498773, 7333.6333183341685, 7335.523190992569, 7336.449766267445, 7340.926590734024, 7785.688572457038, 7789.298963093349, 7792.0, 7799.220077992155, 7813.609319533887, 4247.808848601882, 4499.302608095751, 4676.92984605238, 4749.976250118727, 4755.500672429345, 7119.0, 7367.300106489874, 7431.40548756109, 7433.293837085468, 7437.181909990005, 7151.53515021522, 7164.104486939133, 7175.677094530654, 7190.245024272585, 7198.820029499305, 7135.179454362607, 7167.8566428672175, 7181.35367816899, 7187.137543494814, 7195.13658361, 6981.685824137824, 7002.75490357835, 7004.334588214111, 7020.087388639444, 7022.367986881205, 6261.436470717657, 6936.0, 7066.152061752417, 7157.069580954442, 7170.892436613351, 6719.529632925648, 6719.865602687821, 6899.724011039502, 6955.826104347432, 7013.509054366145], "value": 5226.945565038756, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [770.6416516319914, 779.244133190805, 785.7328508307174, 791.8930944322515, 793.5714714054411, 15108.622284442894, 15489.14809685467, 15498.845011549882, 15526.767098493503, 15565.0, 13129.277889716062, 14247.145171289703, 14388.424463021493, 14401.567952961428, 14429.062110962781, 949.9667511637094, 952.4237836109158, 966.5457235099492, 967.9516024198762, 968.239931653652, 801.53110430398, 961.6105477281712, 962.8988956159608, 963.8120566489538, 965.584798536629, 1020.34187948773, 1020.6886899495651, 1020.9943205942178, 1024.2011231239644, 1026.7073883943078, 1014.9951547967532, 1015.0864222200005, 1015.9796804063877, 1016.2784423059636, 1016.7712264740449, 754.8678981178272, 754.9547027178332, 755.6335177438938, 762.8474305138939, 765.7128576783706, 13083.41124649393, 13133.212007279593, 13144.60566183016, 13187.208767473887, 15288.847111528854, 12319.0, 12348.320842353667, 12393.256404615662, 12587.24476531411, 13533.255670938095, 9974.152197063258, 10033.397996120184, 10185.185185185168, 10398.168146548258, 10581.941805819433, 3484.61669216386, 3526.3828829954655, 3542.574891013069, 3574.410222313315, 3663.9633603663888, 73068.63465682691, 78031.0, 80207.0, 82884.17115828824, 83307.5834620823, 9609.231261499202, 9676.371035882787, 9787.0, 9952.552135153936, 10305.175585953106, 21604.56790864174, 21703.34889953304, 21757.67363489549, 21763.67354489684, 21807.67288490674, 7555.206703296075, 7567.545947243183, 7708.653110609924, 7712.614369281513, 7728.343090837229, 4125.814338354781, 4165.981963678348, 4194.454720886265, 4202.789860506962, 4253.234417804825, 3838.9424158637644, 3880.5537363203593, 3894.065424298148, 3894.6689531389584, 3905.3946638271113, 687.3916583823352, 695.5478938689891, 697.0554898113085, 711.7259854955879, 724.72460465024, 540.1627477409999, 575.323994306688, 587.0020964360604, 603.6076550242377, 613.515322894917, 8352.206540378655, 8356.973468589582, 8367.456115352483, 8377.45546539473, 8463.576821158917, 566.4279078131117, 582.205289779448, 599.3736545310161, 617.6973283091343, 635.5455849067938, 3814.6948244140403, 3905.0, 3956.208758248304, 3962.782046987386, 3990.461287726178, 89185.0, 89705.55147224222, 90239.0, 91144.0, 91331.54334228286, 63692.726145477754, 64130.717385652955, 66799.33200668028, 74443.0, 75425.6228718853, 67160.6641966787, 69059.0, 69188.6540567304, 69696.6515167431, 69906.9513957291, 8315.667373305, 8557.400981931454, 8603.52680602573, 8606.956965215133, 8850.690225842065, 45334.63996080123, 46168.61494155046, 47706.522934771594, 49613.50386496089, 50326.99346013132, 60763.0, 60930.08604871019, 62831.37168628438, 65840.6707966457, 66257.00614490882, 11217.55129794799, 11334.60328888485, 11377.0, 11727.179097463095, 11773.352465614473, 12091.939540302243, 12255.080868935043, 12265.26408415498, 12448.0, 12544.059195560187, 11570.363630000431, 11803.527858885549, 11977.760444791229, 12020.0383969284, 12121.27272363661, 11533.423328833693, 11752.588659397223, 11779.293242405483, 12059.21615095016, 12344.938275308567, 11333.320000799979, 11455.369954652575, 11540.134489913458, 11629.53481860716, 12472.937635311766, 8905.154010369004, 9136.862947055666, 9159.084091590788, 9369.0, 9612.086851749073, 9860.0, 9916.900830991597, 9941.0, 9948.303618746619, 9950.502474876372], "value": 7032.42218704846, "unit": "score"}], "name": "dromaeo_css", "value": 7775.5643012524915, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 113.0, 117.0, 114.0, 112.0, 112.0, 114.0, 113.0, 114.0, 134.0], "value": 115.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [156.0, 119.0, 145.0, 163.0, 116.0, 140.0, 135.0, 116.0, 163.0, 115.0], "value": 136.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [184.0, 185.0, 185.0, 186.0, 184.0, 185.0, 184.0, 197.0, 263.0, 207.0], "value": 196.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 77.0, 72.0, 158.0, 61.0, 76.0, 82.0, 87.0, 160.0, 56.0], "value": 89.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 88.0, 146.0, 85.0, 113.0, 139.0, 132.0, 78.0, 78.0, 125.0], "value": 107.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 84.0, 86.0, 83.0, 86.0, 83.0, 87.0, 84.0, 87.0, 83.0], "value": 84.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 108.0, 148.0, 92.0, 92.0, 92.0, 92.0, 117.0, 151.0, 110.0], "value": 109.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 84.0, 88.0, 86.0, 85.0, 83.0, 86.0, 86.0, 87.0, 85.0], "value": 85.6, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 60.0, 62.0, 60.0, 62.0, 62.0, 63.0, 76.0, 58.0, 56.0], "value": 61.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 64.0, 64.0, 76.0, 71.0, 67.0, 64.0, 63.0, 66.0, 65.0], "value": 66.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 80.0, 79.0, 80.0, 80.0, 75.0, 81.0, 80.0, 80.0, 83.0], "value": 79.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [129.0, 146.0, 146.0, 117.0, 122.0, 126.0, 124.0, 125.0, 152.0, 160.0], "value": 134.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [155.0, 162.0, 162.0, 158.0, 162.0, 161.0, 163.0, 168.0, 160.0, 156.0], "value": 160.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 65.0, 65.0, 67.0, 67.0, 65.0, 66.0, 68.0, 67.0, 66.0], "value": 66.0, "unit": "score"}], "name": "kraken", "value": 1494.1, "alertThreshold": 2.0}]}
05:06:12 INFO - Return code: 0
05:06:12 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json
05:06:12 ERROR - # TBPL SUCCESS #
05:06:12 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'
05:06:12 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build
05:06:12 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType"
05:06:12 INFO - 2016-06-27 05:06:12.164 screenresolution[1684:10318] starting screenresolution argv=screenresolution get
05:06:12 INFO - 2016-06-27 05:06:12.167 screenresolution[1684:10318] Display 0: 1600x1200x32@60
05:06:12 INFO - 2016-06-27 05:06:12.172 screenresolution[1685:10321] starting screenresolution argv=screenresolution list
05:06:12 INFO - Available Modes on Display 0
05:06:12 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75
05:06:12 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60
05:06:12 INFO - 800x600x32@75 800x600x32@60 640x480x32@75
05:06:12 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60
05:06:12 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays:
05:06:12 INFO - Intel Iris:
05:06:12 INFO - Chipset Model: Intel Iris
05:06:12 INFO - Type: GPU
05:06:12 INFO - Bus: Built-In
05:06:12 INFO - VRAM (Dynamic, Max): 1536 MB
05:06:12 INFO - Vendor: Intel (0x8086)
05:06:12 INFO - Device ID: 0x0a2e
05:06:12 INFO - Revision ID: 0x0009
05:06:12 INFO - Displays:
05:06:12 INFO - Display:
05:06:12 INFO - Resolution: 1600 x 1200 @ 60 Hz
05:06:12 INFO - Pixel Depth: 32-Bit Color (ARGB8888)
05:06:12 INFO - Display Serial Number: 1600x1200 60
05:06:12 INFO - Main Display: Yes
05:06:12 INFO - Mirror: Off
05:06:12 INFO - Online: Yes
05:06:12 INFO - Rotation: Supported
05:06:12 INFO - Return code: 0
05:06:12 INFO - Running post-action listener: _resource_record_post_action
05:06:12 INFO - Running post-run listener: _resource_record_post_run
05:06:13 INFO - Total resource usage - Wall time: 1194s; CPU: 53.0%; Read bytes: 32558592; Write bytes: 495490048; Read time: 312; Write time: 1628
05:06:13 INFO - install - Wall time: 22s; CPU: 54.0%; Read bytes: 237568; Write bytes: 196433920; Read time: 4; Write time: 489
05:06:13 INFO - run-tests - Wall time: 1173s; CPU: 53.0%; Read bytes: 30776832; Write bytes: 298208256; Read time: 294; Write time: 1138
05:06:13 INFO - Running post-run listener: _upload_blobber_files
05:06:13 INFO - Blob upload gear active.
05:06:13 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir.
05:06:13 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com
05:06:13 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-Beta', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json']
05:06:13 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-Beta -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json
05:06:13 INFO - (blobuploader) - INFO - Open directory for files ...
05:06:13 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ...
05:06:13 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com
05:06:13 INFO - (blobuploader) - INFO - Uploading, attempt #1.
05:06:14 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded
05:06:14 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded!
05:06:14 INFO - (blobuploader) - INFO - Done attempting.
05:06:14 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ...
05:06:14 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com
05:06:14 INFO - (blobuploader) - INFO - Uploading, attempt #1.
05:06:16 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded
05:06:16 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded!
05:06:16 INFO - (blobuploader) - INFO - Done attempting.
05:06:16 INFO - (blobuploader) - INFO - Iteration through files over.
05:06:16 INFO - Return code: 0
05:06:16 INFO - rmtree: /builds/slave/test/build/uploaded_files.json
05:06:16 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1
05:06:16 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/859ebfecabb29cce4135111697831c79b09b36c64a416c1ccc25478d8ab888a5b68fd637ac02eb1c5b951a8520df5960feaa83e70fa87b61f820735a66a9e61d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/7b7e37be260454c06072bc1a56ff9f8d1c99336c662d46d2dec8a8c3dd6446065626dd2acc2221fab2a7ded6de053e1caff23fb603b811a095848e8d23393382"}
05:06:16 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files
05:06:16 INFO - Writing to file /builds/slave/test/properties/blobber_files
05:06:16 INFO - Contents:
05:06:16 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/859ebfecabb29cce4135111697831c79b09b36c64a416c1ccc25478d8ab888a5b68fd637ac02eb1c5b951a8520df5960feaa83e70fa87b61f820735a66a9e61d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/7b7e37be260454c06072bc1a56ff9f8d1c99336c662d46d2dec8a8c3dd6446065626dd2acc2221fab2a7ded6de053e1caff23fb603b811a095848e8d23393382"}
05:06:16 INFO - Running post-run listener: copy_logs_to_upload_dir
05:06:16 INFO - Copying logs to upload dir...
05:06:16 INFO - mkdir: /builds/slave/test/build/upload/logs
05:06:16 INFO - Copying logs to upload dir...
program finished with exit code 0
elapsedTime=1280.443529
========= master_lag: 0.96 =========
========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 21 secs) (at 2016-06-27 05:06:17.206717) =========
========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-27 05:06:17.210454) =========
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.ILS8nAVdl1/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.Xa2o4KSJuj/Listeners
TMPDIR=/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/
TWISTD_LOG_PATH=/builds/slave/twistd.log
USER=cltbld
VERSIONER_PYTHON_PREFER_32_BIT=no
VERSIONER_PYTHON_VERSION=2.7
XPCOM_DEBUG_BREAK=warn
XPC_FLAGS=0x0
XPC_SERVICE_NAME=0
__CF_USER_TEXT_ENCODING=0x1C:0:0
using PTY: False
blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/859ebfecabb29cce4135111697831c79b09b36c64a416c1ccc25478d8ab888a5b68fd637ac02eb1c5b951a8520df5960feaa83e70fa87b61f820735a66a9e61d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/7b7e37be260454c06072bc1a56ff9f8d1c99336c662d46d2dec8a8c3dd6446065626dd2acc2221fab2a7ded6de053e1caff23fb603b811a095848e8d23393382"}
build_url:https://queue.taskcluster.net/v1/task/Ml2LMrcDQ9iVEnpQTiMd3g/artifacts/public/build/firefox-48.0.en-US.mac.dmg
program finished with exit code 0
elapsedTime=0.010838
build_url: 'https://queue.taskcluster.net/v1/task/Ml2LMrcDQ9iVEnpQTiMd3g/artifacts/public/build/firefox-48.0.en-US.mac.dmg'
blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/859ebfecabb29cce4135111697831c79b09b36c64a416c1ccc25478d8ab888a5b68fd637ac02eb1c5b951a8520df5960feaa83e70fa87b61f820735a66a9e61d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/7b7e37be260454c06072bc1a56ff9f8d1c99336c662d46d2dec8a8c3dd6446065626dd2acc2221fab2a7ded6de053e1caff23fb603b811a095848e8d23393382"}'
========= master_lag: 0.62 =========
========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-27 05:06:17.837894) =========
========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-06-27 05:06:17.838227) =========
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.ILS8nAVdl1/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.Xa2o4KSJuj/Listeners
TMPDIR=/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/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.004953
========= master_lag: 0.02 =========
========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-06-27 05:06:17.863743) =========
========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-27 05:06:17.864080) =========
========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-27 05:06:17.864442) =========
========= Total master_lag: 1.66 =========