builder: mozilla-aurora_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0019 starttime: 1491687702.25 results: success (0) buildid: 20170408132921 builduid: 94d601dac6e64ed5b881474bb65e306b revision: 20c110248317f6f6592d004cbb5f71fa1731639b ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-04-08 14:41:42.252729) ========= master: http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-04-08 14:41:42.253361) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-04-08 14:41:42.253690) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-04-08 14:41:42.499495) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-08 14:41:42.499760) ========= 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.we4Zb3T4P2/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.Eu1fNmFiJN/Listeners TMPDIR=/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2017-04-08 14:41:42-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 36.4M=0s 2017-04-08 14:41:43 (36.4 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.509696 ========= master_lag: 0.04 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-08 14:41:43.050770) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-04-08 14:41:43.051077) ========= 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.we4Zb3T4P2/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.Eu1fNmFiJN/Listeners TMPDIR=/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/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.035162 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-04-08 14:41:43.108017) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-04-08 14:41:43.108378) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 20c110248317f6f6592d004cbb5f71fa1731639b --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 20c110248317f6f6592d004cbb5f71fa1731639b --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.we4Zb3T4P2/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.Eu1fNmFiJN/Listeners TMPDIR=/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2017-04-08 14:41:43,212 truncating revision to first 12 chars 2017-04-08 14:41:43,212 Setting DEBUG logging. 2017-04-08 14:41:43,212 attempt 1/10 2017-04-08 14:41:43,212 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/20c110248317?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-04-08 14:41:44,186 unpacking tar archive at: mozilla-aurora-20c110248317/testing/mozharness/ program finished with exit code 0 elapsedTime=1.238509 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-04-08 14:41:44.370356) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-04-08 14:41:44.370677) ========= script_repo_revision: 20c110248317f6f6592d004cbb5f71fa1731639b ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-04-08 14:41:44.371043) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-04-08 14:41:44.371319) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-04-08 14:41:44.390960) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 24 secs) (at 2017-04-08 14:41:44.391228) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Aurora', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.we4Zb3T4P2/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.Eu1fNmFiJN/Listeners TMPDIR=/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/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 14:41:44 INFO - MultiFileLogger online at 20170408 14:41:44 in /builds/slave/test 14:41:44 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 14:41:44 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 14:41:44 INFO - {'append_to_log': False, 14:41:44 INFO - 'base_work_dir': '/builds/slave/test', 14:41:44 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 14:41:44 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 14:41:44 INFO - 'branch': 'Mozilla-Aurora', 14:41:44 INFO - 'buildbot_json_path': 'buildprops.json', 14:41:44 INFO - 'config_files': ('talos/mac_config.py',), 14:41:44 INFO - 'default_actions': ('clobber', 14:41:44 INFO - 'read-buildbot-config', 14:41:44 INFO - 'download-and-extract', 14:41:44 INFO - 'populate-webroot', 14:41:44 INFO - 'create-virtualenv', 14:41:44 INFO - 'install', 14:41:44 INFO - 'run-tests'), 14:41:44 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 14:41:44 INFO - 'download_minidump_stackwalk': True, 14:41:44 INFO - 'download_symbols': 'ondemand', 14:41:44 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 14:41:44 INFO - 'tooltool.py': '/tools/tooltool.py', 14:41:44 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 14:41:44 INFO - '/tools/misc-python/virtualenv.py')}, 14:41:44 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 14:41:44 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 14:41:44 INFO - 'gecko_profile': False, 14:41:44 INFO - 'gecko_profile_interval': 0, 14:41:44 INFO - 'installer_path': 'installer.exe', 14:41:44 INFO - 'log_level': 'info', 14:41:44 INFO - 'log_name': 'talos', 14:41:44 INFO - 'log_to_console': True, 14:41:44 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 14:41:44 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 14:41:44 INFO - 'opt_config_files': (), 14:41:44 INFO - 'pip_index': False, 14:41:44 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 14:41:44 INFO - 'cmd': ('bash', 14:41:44 INFO - '-c', 14:41:44 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 14:41:44 INFO - 'enabled': True, 14:41:44 INFO - 'halt_on_failure': False, 14:41:44 INFO - 'name': 'check_screen_resolution'},), 14:41:44 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 14:41:44 INFO - 'cmd': ('bash', 14:41:44 INFO - '-c', 14:41:44 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 14:41:44 INFO - 'enabled': True, 14:41:44 INFO - 'halt_on_failure': False, 14:41:44 INFO - 'name': 'check_screen_resolution'},), 14:41:44 INFO - 'run_cmd_checks_enabled': True, 14:41:44 INFO - 'suite': 'dromaeojs-e10s', 14:41:44 INFO - 'system_bits': '32', 14:41:44 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 14:41:44 INFO - 'title': 't-yosemite-r7-0019', 14:41:44 INFO - 'tooltool_cache': '/builds/tooltool_cache', 14:41:44 INFO - 'use_talos_json': True, 14:41:44 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 14:41:44 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 14:41:44 INFO - 'work_dir': 'build'} 14:41:44 INFO - [mozharness: 2017-04-08 21:41:44.610650Z] Running clobber step. 14:41:44 INFO - Running pre-action listener: _resource_record_pre_action 14:41:44 INFO - Running main action method: clobber 14:41:44 INFO - rmtree: /builds/slave/test/build 14:41:44 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 14:41:47 INFO - Running post-action listener: _resource_record_post_action 14:41:47 INFO - [mozharness: 2017-04-08 21:41:47.287455Z] Finished clobber step (success) 14:41:47 INFO - [mozharness: 2017-04-08 21:41:47.287732Z] Running read-buildbot-config step. 14:41:47 INFO - Running pre-action listener: _resource_record_pre_action 14:41:47 INFO - Running main action method: read_buildbot_config 14:41:47 INFO - Using buildbot properties: 14:41:47 INFO - { 14:41:47 INFO - "project": "", 14:41:47 INFO - "product": "firefox", 14:41:47 INFO - "script_repo_revision": "production", 14:41:47 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs-e10s", 14:41:47 INFO - "repository": "", 14:41:47 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs-e10s", 14:41:47 INFO - "buildid": "20170408132921", 14:41:47 INFO - "pgo_build": "False", 14:41:47 INFO - "basedir": "/builds/slave/test", 14:41:47 INFO - "buildnumber": 38, 14:41:47 INFO - "slavename": "t-yosemite-r7-0019", 14:41:47 INFO - "master": "http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/", 14:41:47 INFO - "platform": "yosemite_r7", 14:41:47 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 14:41:47 INFO - "branch": "mozilla-aurora", 14:41:47 INFO - "repo_path": "releases/mozilla-aurora", 14:41:47 INFO - "revision": "20c110248317f6f6592d004cbb5f71fa1731639b", 14:41:47 INFO - "stage_platform": "macosx64", 14:41:47 INFO - "builduid": "94d601dac6e64ed5b881474bb65e306b", 14:41:47 INFO - "slavebuilddir": "test" 14:41:47 INFO - } 14:41:47 INFO - Found installer url https://queue.taskcluster.net/v1/task/TPpnrsxHSLmcU0b3Zbk9wA/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg. 14:41:47 INFO - Running post-action listener: _resource_record_post_action 14:41:47 INFO - [mozharness: 2017-04-08 21:41:47.293560Z] Finished read-buildbot-config step (success) 14:41:47 INFO - [mozharness: 2017-04-08 21:41:47.293801Z] Running download-and-extract step. 14:41:47 INFO - Running pre-action listener: _resource_record_pre_action 14:41:47 INFO - Running main action method: download_and_extract 14:41:47 INFO - mkdir: /builds/slave/test/build/tests 14:41:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:41:47 INFO - trying https://queue.taskcluster.net/v1/task/TPpnrsxHSLmcU0b3Zbk9wA/artifacts/public/build/firefox-54.0a2.en-US.mac.test_packages.json 14:41:47 INFO - Downloading https://queue.taskcluster.net/v1/task/TPpnrsxHSLmcU0b3Zbk9wA/artifacts/public/build/firefox-54.0a2.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-54.0a2.en-US.mac.test_packages.json 14:41:47 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/TPpnrsxHSLmcU0b3Zbk9wA/artifacts/public/build/firefox-54.0a2.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-54.0a2.en-US.mac.test_packages.json'}, attempt #1 14:41:48 INFO - Downloaded 1199 bytes. 14:41:48 INFO - Reading from file /builds/slave/test/build/firefox-54.0a2.en-US.mac.test_packages.json 14:41:48 INFO - Using the following test package requirements: 14:41:48 INFO - {u'common': [u'firefox-54.0a2.en-US.mac.common.tests.zip'], 14:41:48 INFO - u'cppunittest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 14:41:48 INFO - u'firefox-54.0a2.en-US.mac.cppunittest.tests.zip'], 14:41:48 INFO - u'gtest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 14:41:48 INFO - u'firefox-54.0a2.en-US.mac.gtest.tests.zip'], 14:41:48 INFO - u'jittest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 14:41:48 INFO - u'jsshell-mac.zip'], 14:41:48 INFO - u'mochitest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 14:41:48 INFO - u'firefox-54.0a2.en-US.mac.mochitest.tests.zip'], 14:41:48 INFO - u'mozbase': [u'firefox-54.0a2.en-US.mac.common.tests.zip'], 14:41:48 INFO - u'reftest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 14:41:48 INFO - u'firefox-54.0a2.en-US.mac.reftest.tests.zip'], 14:41:48 INFO - u'talos': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 14:41:48 INFO - u'firefox-54.0a2.en-US.mac.talos.tests.zip'], 14:41:48 INFO - u'web-platform': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 14:41:48 INFO - u'firefox-54.0a2.en-US.mac.web-platform.tests.zip'], 14:41:48 INFO - u'xpcshell': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 14:41:48 INFO - u'firefox-54.0a2.en-US.mac.xpcshell.tests.zip']} 14:41:48 INFO - Downloading packages: [u'firefox-54.0a2.en-US.mac.common.tests.zip'] for test suite category: common 14:41:48 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/TPpnrsxHSLmcU0b3Zbk9wA/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip 14:41:48 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/TPpnrsxHSLmcU0b3Zbk9wA/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip'}, attempt #1 14:41:48 INFO - Fetch https://queue.taskcluster.net/v1/task/TPpnrsxHSLmcU0b3Zbk9wA/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip into memory 14:41:50 INFO - Expected file size: 51789922 14:41:50 INFO - Obtained file size: 51789922 14:42:06 INFO - Downloading packages: [u'firefox-54.0a2.en-US.mac.common.tests.zip', u'firefox-54.0a2.en-US.mac.talos.tests.zip'] for test suite category: talos 14:42:06 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/TPpnrsxHSLmcU0b3Zbk9wA/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip 14:42:06 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/TPpnrsxHSLmcU0b3Zbk9wA/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip'}, attempt #1 14:42:06 INFO - Fetch https://queue.taskcluster.net/v1/task/TPpnrsxHSLmcU0b3Zbk9wA/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip into memory 14:42:08 INFO - Expected file size: 51789922 14:42:08 INFO - Obtained file size: 51789922 14:42:23 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/TPpnrsxHSLmcU0b3Zbk9wA/artifacts/public/build/firefox-54.0a2.en-US.mac.talos.tests.zip 14:42:23 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/TPpnrsxHSLmcU0b3Zbk9wA/artifacts/public/build/firefox-54.0a2.en-US.mac.talos.tests.zip'}, attempt #1 14:42:23 INFO - Fetch https://queue.taskcluster.net/v1/task/TPpnrsxHSLmcU0b3Zbk9wA/artifacts/public/build/firefox-54.0a2.en-US.mac.talos.tests.zip into memory 14:42:24 INFO - Expected file size: 14404424 14:42:24 INFO - Obtained file size: 14404424 14:42:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:42:25 INFO - trying https://queue.taskcluster.net/v1/task/TPpnrsxHSLmcU0b3Zbk9wA/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg 14:42:25 INFO - Downloading https://queue.taskcluster.net/v1/task/TPpnrsxHSLmcU0b3Zbk9wA/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-54.0a2.en-US.mac.dmg 14:42:25 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/TPpnrsxHSLmcU0b3Zbk9wA/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-54.0a2.en-US.mac.dmg'}, attempt #1 14:42:27 INFO - Downloaded 74696713 bytes. 14:42:27 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/TPpnrsxHSLmcU0b3Zbk9wA/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg 14:42:27 INFO - mkdir: /builds/slave/test/properties 14:42:27 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 14:42:27 INFO - Writing to file /builds/slave/test/properties/build_url 14:42:27 INFO - Contents: 14:42:27 INFO - build_url:https://queue.taskcluster.net/v1/task/TPpnrsxHSLmcU0b3Zbk9wA/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg 14:42:27 INFO - Running post-action listener: _resource_record_post_action 14:42:27 INFO - Running post-action listener: set_extra_try_arguments 14:42:27 INFO - [mozharness: 2017-04-08 21:42:27.835449Z] Finished download-and-extract step (success) 14:42:27 INFO - [mozharness: 2017-04-08 21:42:27.835600Z] Running populate-webroot step. 14:42:27 INFO - Running pre-action listener: _resource_record_pre_action 14:42:27 INFO - Running main action method: populate_webroot 14:42:27 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 14:42:27 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 14:42:27 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 14:42:27 INFO - '--tppagecycles', 14:42:27 INFO - '7'], 14:42:27 INFO - 'tests': ['tsvgm']}}, 14:42:27 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 14:42:27 INFO - 'tests': ['tresize', 'tcanvasmark']}, 14:42:27 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 14:42:27 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 14:42:27 INFO - 'tests': ['dromaeo_css', 'kraken']}, 14:42:27 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 14:42:27 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 14:42:27 INFO - 'talos_options': ['--disable-e10s'], 14:42:27 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 14:42:27 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 14:42:27 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 14:42:27 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 14:42:27 INFO - 'talos_options': ['--disable-e10s'], 14:42:27 INFO - 'tests': ['damp', 'tps']}, 14:42:27 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 14:42:27 INFO - 'tests': ['damp', 'tps']}, 14:42:27 INFO - 'g3': {'talos_options': ['--disable-e10s'], 14:42:27 INFO - 'tests': ['dromaeo_dom']}, 14:42:27 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 14:42:27 INFO - 'g4': {'talos_options': ['--disable-e10s'], 14:42:27 INFO - 'tests': ['basic_compositor_video']}, 14:42:27 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 14:42:27 INFO - 'other': {'talos_options': ['--disable-e10s'], 14:42:27 INFO - 'tests': ['a11yr', 14:42:27 INFO - 'ts_paint', 14:42:27 INFO - 'tpaint', 14:42:27 INFO - 'sessionrestore', 14:42:27 INFO - 'sessionrestore_no_auto_restore', 14:42:27 INFO - 'tabpaint']}, 14:42:27 INFO - 'other-e10s': {'tests': ['a11yr', 14:42:27 INFO - 'ts_paint', 14:42:27 INFO - 'tpaint', 14:42:27 INFO - 'sessionrestore', 14:42:27 INFO - 'sessionrestore_no_auto_restore', 14:42:27 INFO - 'tabpaint']}, 14:42:27 INFO - 'other-e10s_l64': {'tests': ['a11yr', 14:42:27 INFO - 'ts_paint', 14:42:27 INFO - 'tpaint', 14:42:27 INFO - 'sessionrestore', 14:42:27 INFO - 'sessionrestore_no_auto_restore', 14:42:27 INFO - 'tabpaint']}, 14:42:27 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 14:42:27 INFO - 'ts_paint', 14:42:27 INFO - 'tpaint', 14:42:27 INFO - 'sessionrestore', 14:42:27 INFO - 'sessionrestore_no_auto_restore', 14:42:27 INFO - 'tabpaint']}, 14:42:27 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 14:42:27 INFO - 'tests': ['a11yr', 14:42:27 INFO - 'ts_paint', 14:42:27 INFO - 'tpaint', 14:42:27 INFO - 'sessionrestore', 14:42:27 INFO - 'sessionrestore_no_auto_restore', 14:42:27 INFO - 'tabpaint']}, 14:42:27 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 14:42:27 INFO - 'tests': ['a11yr', 14:42:27 INFO - 'ts_paint', 14:42:27 INFO - 'tpaint', 14:42:27 INFO - 'sessionrestore', 14:42:27 INFO - 'sessionrestore_no_auto_restore', 14:42:27 INFO - 'tabpaint']}, 14:42:27 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 14:42:27 INFO - 'tests': ['tsvgx', 14:42:27 INFO - 'tsvgr_opacity', 14:42:27 INFO - 'tart', 14:42:27 INFO - 'tscrollx', 14:42:27 INFO - 'cart', 14:42:27 INFO - 'tsvg_static']}, 14:42:27 INFO - 'svgr-e10s': {'tests': ['tsvgx', 14:42:27 INFO - 'tsvgr_opacity', 14:42:27 INFO - 'tart', 14:42:27 INFO - 'tscrollx', 14:42:27 INFO - 'cart', 14:42:27 INFO - 'tsvg_static']}, 14:42:27 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 14:42:27 INFO - 'talos_options': ['--disable-e10s'], 14:42:27 INFO - 'tests': ['tp5o']}, 14:42:27 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 14:42:27 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 14:42:27 INFO - 'talos_options': ['--disable-e10s', 14:42:27 INFO - '--xperf_path', 14:42:27 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 14:42:27 INFO - 'tests': ['tp5n']}, 14:42:27 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 14:42:27 INFO - 'talos_options': ['--xperf_path', 14:42:27 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 14:42:27 INFO - 'tests': ['tp5n']}}, 14:42:27 INFO - 'talos.zip': {'path': '', 14:42:27 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 14:42:27 INFO - Running post-action listener: _resource_record_post_action 14:42:27 INFO - [mozharness: 2017-04-08 21:42:27.854340Z] Finished populate-webroot step (success) 14:42:27 INFO - [mozharness: 2017-04-08 21:42:27.854443Z] Running create-virtualenv step. 14:42:27 INFO - Running pre-action listener: _resource_record_pre_action 14:42:27 INFO - Running main action method: create_virtualenv 14:42:27 INFO - Creating virtualenv /builds/slave/test/build/venv 14:42:27 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 14:42:27 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 14:42:27 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 14:42:27 INFO - Using real prefix '/tools/python27' 14:42:27 INFO - New python executable in /builds/slave/test/build/venv/bin/python 14:42:28 INFO - Installing distribute.............................................................................................................................................................................................done. 14:42:31 INFO - Installing pip.................done. 14:42:31 INFO - Return code: 0 14:42:31 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 14:42:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 14:42:31 INFO - Reading from file tmpfile_stdout 14:42:31 INFO - Output received: 14:42:31 INFO - pip 1.5.5 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 14:42:31 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 14:42:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:42:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:42:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:42:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:42:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:42:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:42:31 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10d1bfb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d1c2768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa95155aab0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cf9dd30>, '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 0x10cec4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cf92be8>, '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.we4Zb3T4P2/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.Eu1fNmFiJN/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/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 14:42:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 14:42:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 14:42:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.we4Zb3T4P2/Render', 14:42:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:42:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:42:31 INFO - 'HOME': '/Users/cltbld', 14:42:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:42:31 INFO - 'LOGNAME': 'cltbld', 14:42:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:42:31 INFO - 'MOZ_NO_REMOTE': '1', 14:42:31 INFO - 'NO_EM_RESTART': '1', 14:42:31 INFO - 'PAGER': '/bin/cat', 14:42:31 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:42:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:42:31 INFO - 'PWD': '/builds/slave/test', 14:42:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:42:31 INFO - 'SHELL': '/bin/bash', 14:42:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Eu1fNmFiJN/Listeners', 14:42:31 INFO - 'TMPDIR': '/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/', 14:42:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:42:31 INFO - 'USER': 'cltbld', 14:42:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:42:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:42:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:42:31 INFO - 'XPC_FLAGS': '0x0', 14:42:31 INFO - 'XPC_SERVICE_NAME': '0', 14:42:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:42:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:42:32 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 14:42:32 INFO - Cleaning up... 14:42:32 INFO - Return code: 0 14:42:32 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 14:42:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:42:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:42:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:42:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:42:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:42:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:42:32 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10d1bfb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d1c2768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa95155aab0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cf9dd30>, '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 0x10cec4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cf92be8>, '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.we4Zb3T4P2/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.Eu1fNmFiJN/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/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 14:42:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 14:42:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 14:42:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.we4Zb3T4P2/Render', 14:42:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:42:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:42:32 INFO - 'HOME': '/Users/cltbld', 14:42:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:42:32 INFO - 'LOGNAME': 'cltbld', 14:42:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:42:32 INFO - 'MOZ_NO_REMOTE': '1', 14:42:32 INFO - 'NO_EM_RESTART': '1', 14:42:32 INFO - 'PAGER': '/bin/cat', 14:42:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:42:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:42:32 INFO - 'PWD': '/builds/slave/test', 14:42:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:42:32 INFO - 'SHELL': '/bin/bash', 14:42:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Eu1fNmFiJN/Listeners', 14:42:32 INFO - 'TMPDIR': '/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/', 14:42:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:42:32 INFO - 'USER': 'cltbld', 14:42:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:42:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:42:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:42:32 INFO - 'XPC_FLAGS': '0x0', 14:42:32 INFO - 'XPC_SERVICE_NAME': '0', 14:42:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:42:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:42:32 INFO - Downloading/unpacking psutil>=3.1.1 14:42:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:42:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:42:32 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:42:32 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:42:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:42:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:42:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 14:42:36 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 14:42:36 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 14:42:36 INFO - Installing collected packages: psutil 14:42:36 INFO - Running setup.py install for psutil 14:42:36 INFO - building 'psutil._psutil_osx' extension 14:42:36 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 14:42:37 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 14:42:37 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 14:42:37 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 14:42:37 INFO - building 'psutil._psutil_posix' extension 14:42:37 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 14:42:37 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 14:42:37 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 14:42:37 INFO - ^ 14:42:37 INFO - 1 warning generated. 14:42:37 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 14:42:37 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 14:42:37 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 14:42:37 INFO - Successfully installed psutil 14:42:37 INFO - Cleaning up... 14:42:37 INFO - Return code: 0 14:42:37 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 14:42: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:42:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:42:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:42: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:42:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:42:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:42:37 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10d1bfb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d1c2768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa95155aab0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cf9dd30>, '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 0x10cec4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cf92be8>, '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.we4Zb3T4P2/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.Eu1fNmFiJN/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/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 14:42:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 14:42:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 14:42:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.we4Zb3T4P2/Render', 14:42:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:42:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:42:37 INFO - 'HOME': '/Users/cltbld', 14:42:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:42:37 INFO - 'LOGNAME': 'cltbld', 14:42:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:42:37 INFO - 'MOZ_NO_REMOTE': '1', 14:42:37 INFO - 'NO_EM_RESTART': '1', 14:42:37 INFO - 'PAGER': '/bin/cat', 14:42:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:42:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:42:37 INFO - 'PWD': '/builds/slave/test', 14:42:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:42:37 INFO - 'SHELL': '/bin/bash', 14:42:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Eu1fNmFiJN/Listeners', 14:42:37 INFO - 'TMPDIR': '/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/', 14:42:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:42:37 INFO - 'USER': 'cltbld', 14:42:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:42:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:42:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:42:37 INFO - 'XPC_FLAGS': '0x0', 14:42:37 INFO - 'XPC_SERVICE_NAME': '0', 14:42:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:42:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:42:38 INFO - Downloading/unpacking mozsystemmonitor==0.3 14:42:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:42:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:42:38 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:42:38 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:42:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:42:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:42:40 INFO - Downloading mozsystemmonitor-0.3.tar.gz 14:42:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 14:42:40 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 14:42:40 INFO - Installing collected packages: mozsystemmonitor 14:42:40 INFO - Running setup.py install for mozsystemmonitor 14:42:40 INFO - Successfully installed mozsystemmonitor 14:42:40 INFO - Cleaning up... 14:42:40 INFO - Return code: 0 14:42:40 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 14:42:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:42:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:42:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:42:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:42:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:42:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:42:40 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10d1bfb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d1c2768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa95155aab0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cf9dd30>, '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 0x10cec4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cf92be8>, '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.we4Zb3T4P2/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.Eu1fNmFiJN/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/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 14:42:40 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 14:42:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 14:42:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.we4Zb3T4P2/Render', 14:42:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:42:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:42:40 INFO - 'HOME': '/Users/cltbld', 14:42:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:42:40 INFO - 'LOGNAME': 'cltbld', 14:42:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:42:40 INFO - 'MOZ_NO_REMOTE': '1', 14:42:40 INFO - 'NO_EM_RESTART': '1', 14:42:40 INFO - 'PAGER': '/bin/cat', 14:42:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:42:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:42:40 INFO - 'PWD': '/builds/slave/test', 14:42:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:42:40 INFO - 'SHELL': '/bin/bash', 14:42:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Eu1fNmFiJN/Listeners', 14:42:40 INFO - 'TMPDIR': '/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/', 14:42:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:42:40 INFO - 'USER': 'cltbld', 14:42:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:42:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:42:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:42:40 INFO - 'XPC_FLAGS': '0x0', 14:42:40 INFO - 'XPC_SERVICE_NAME': '0', 14:42:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:42:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:42:41 INFO - Downloading/unpacking jsonschema==2.5.1 14:42:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:42:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:42:41 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:42:41 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:42:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:42:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:42:44 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 14:42:44 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 14:42:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:42:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:42:44 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:42:44 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:42:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:42:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:42:44 INFO - Downloading functools32-3.2.3-2.tar.gz 14:42:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 14:42:44 INFO - warning: no files found matching '*.txt' 14:42:44 INFO - no previously-included directories found matching 'build' 14:42:44 INFO - no previously-included directories found matching 'dist' 14:42:44 INFO - no previously-included directories found matching '.git*' 14:42:44 INFO - Installing collected packages: jsonschema, functools32 14:42:44 INFO - Running setup.py install for functools32 14:42:44 INFO - warning: no files found matching '*.txt' 14:42:44 INFO - no previously-included directories found matching 'build' 14:42:44 INFO - no previously-included directories found matching 'dist' 14:42:44 INFO - no previously-included directories found matching '.git*' 14:42:44 INFO - Successfully installed jsonschema functools32 14:42:44 INFO - Cleaning up... 14:42:44 INFO - Return code: 0 14:42:44 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 14:42:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:42:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:42:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:42:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:42:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:42:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:42:44 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10d1bfb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d1c2768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa95155aab0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cf9dd30>, '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 0x10cec4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cf92be8>, '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.we4Zb3T4P2/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.Eu1fNmFiJN/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/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 14:42:44 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 14:42:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 14:42:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.we4Zb3T4P2/Render', 14:42:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:42:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:42:44 INFO - 'HOME': '/Users/cltbld', 14:42:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:42:44 INFO - 'LOGNAME': 'cltbld', 14:42:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:42:44 INFO - 'MOZ_NO_REMOTE': '1', 14:42:44 INFO - 'NO_EM_RESTART': '1', 14:42:44 INFO - 'PAGER': '/bin/cat', 14:42:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:42:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:42:44 INFO - 'PWD': '/builds/slave/test', 14:42:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:42:44 INFO - 'SHELL': '/bin/bash', 14:42:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Eu1fNmFiJN/Listeners', 14:42:44 INFO - 'TMPDIR': '/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/', 14:42:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:42:44 INFO - 'USER': 'cltbld', 14:42:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:42:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:42:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:42:44 INFO - 'XPC_FLAGS': '0x0', 14:42:44 INFO - 'XPC_SERVICE_NAME': '0', 14:42:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:42:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:42:45 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 14:42:45 INFO - Cleaning up... 14:42:45 INFO - Return code: 0 14:42:45 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 14:42:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:42:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:42:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:42:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:42:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:42:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:42:45 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10d1bfb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d1c2768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa95155aab0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cf9dd30>, '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 0x10cec4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cf92be8>, '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.we4Zb3T4P2/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.Eu1fNmFiJN/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/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 14:42:45 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 14:42:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 14:42:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.we4Zb3T4P2/Render', 14:42:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:42:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:42:45 INFO - 'HOME': '/Users/cltbld', 14:42:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:42:45 INFO - 'LOGNAME': 'cltbld', 14:42:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:42:45 INFO - 'MOZ_NO_REMOTE': '1', 14:42:45 INFO - 'NO_EM_RESTART': '1', 14:42:45 INFO - 'PAGER': '/bin/cat', 14:42:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:42:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:42:45 INFO - 'PWD': '/builds/slave/test', 14:42:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:42:45 INFO - 'SHELL': '/bin/bash', 14:42:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Eu1fNmFiJN/Listeners', 14:42:45 INFO - 'TMPDIR': '/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/', 14:42:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:42:45 INFO - 'USER': 'cltbld', 14:42:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:42:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:42:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:42:45 INFO - 'XPC_FLAGS': '0x0', 14:42:45 INFO - 'XPC_SERVICE_NAME': '0', 14:42:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:42:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:42:45 INFO - Downloading/unpacking blobuploader==1.2.4 14:42:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:42:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:42:45 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:42:45 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:42:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:42:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:42:48 INFO - Downloading blobuploader-1.2.4.tar.gz 14:42:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 14:42:48 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 14:42:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:42:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:42:48 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:42:48 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:42:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:42:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:42:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 14:42:48 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 14:42:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:42:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:42:48 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:42:48 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:42:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:42:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:42:49 INFO - Downloading docopt-0.6.1.tar.gz 14:42:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 14:42:49 INFO - Installing collected packages: blobuploader, requests, docopt 14:42:49 INFO - Running setup.py install for blobuploader 14:42:49 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 14:42:49 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 14:42:49 INFO - Running setup.py install for requests 14:42:50 INFO - Running setup.py install for docopt 14:42:50 INFO - Successfully installed blobuploader requests docopt 14:42:50 INFO - Cleaning up... 14:42:50 INFO - Return code: 0 14:42:50 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:42:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:42:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:42:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:42:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:42:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:42:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:42:50 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 0x10d1bfb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d1c2768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa95155aab0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cf9dd30>, '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 0x10cec4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cf92be8>, '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.we4Zb3T4P2/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.Eu1fNmFiJN/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/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 14:42:50 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 14:42:50 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 14:42:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.we4Zb3T4P2/Render', 14:42:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:42:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:42:50 INFO - 'HOME': '/Users/cltbld', 14:42:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:42:50 INFO - 'LOGNAME': 'cltbld', 14:42:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:42:50 INFO - 'MOZ_NO_REMOTE': '1', 14:42:50 INFO - 'NO_EM_RESTART': '1', 14:42:50 INFO - 'PAGER': '/bin/cat', 14:42:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:42:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:42:50 INFO - 'PWD': '/builds/slave/test', 14:42:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:42:50 INFO - 'SHELL': '/bin/bash', 14:42:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Eu1fNmFiJN/Listeners', 14:42:50 INFO - 'TMPDIR': '/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/', 14:42:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:42:50 INFO - 'USER': 'cltbld', 14:42:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:42:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:42:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:42:50 INFO - 'XPC_FLAGS': '0x0', 14:42:50 INFO - 'XPC_SERVICE_NAME': '0', 14:42:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:42:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:42:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 14:42:50 INFO - Running setup.py (path:/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/pip-8xt16M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 14:42:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 14:42:50 INFO - Running setup.py (path:/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/pip-ft6ju7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 14:42:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 14:42:50 INFO - Running setup.py (path:/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/pip-q_eoq1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 14:42:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 14:42:50 INFO - Running setup.py (path:/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/pip-PfQW2S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 14:42:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 14:42:50 INFO - Running setup.py (path:/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/pip-p6FIzt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 14:42:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 14:42:51 INFO - Running setup.py (path:/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/pip-7JB8q8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 14:42:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 14:42:51 INFO - Running setup.py (path:/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/pip-R0rEwG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 14:42:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 14:42:51 INFO - Running setup.py (path:/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/pip-KfFUKw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 14:42:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 14:42:51 INFO - Running setup.py (path:/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/pip-WnHgN2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 14:42:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 14:42:51 INFO - Running setup.py (path:/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/pip-sTBP_i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 14:42:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 14:42:51 INFO - Running setup.py (path:/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/pip-1lPjrB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 14:42:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 14:42:51 INFO - Running setup.py (path:/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/pip-GEaFTM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 14:42:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 14:42:51 INFO - Running setup.py (path:/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/pip-jMDr_D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 14:42:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 14:42:51 INFO - Running setup.py (path:/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/pip-8j9I0Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 14:42:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 14:42:51 INFO - Running setup.py (path:/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/pip-wAu2Jf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 14:42:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 14:42:52 INFO - Running setup.py (path:/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/pip-927iJZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 14:42:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 14:42:52 INFO - Running setup.py (path:/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/pip-jQsQ54-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 14:42:52 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 14:42:52 INFO - Running setup.py install for manifestparser 14:42:52 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 14:42:52 INFO - Running setup.py install for mozcrash 14:42:52 INFO - Running setup.py install for mozdebug 14:42:52 INFO - Running setup.py install for mozdevice 14:42:52 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 14:42:52 INFO - Installing dm script to /builds/slave/test/build/venv/bin 14:42:52 INFO - Running setup.py install for mozfile 14:42:53 INFO - Running setup.py install for mozhttpd 14:42:53 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 14:42:53 INFO - Running setup.py install for mozinfo 14:42:53 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 14:42:53 INFO - Running setup.py install for mozInstall 14:42:53 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 14:42:53 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 14:42:53 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 14:42:53 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 14:42:53 INFO - Running setup.py install for mozleak 14:42:53 INFO - Running setup.py install for mozlog 14:42:53 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 14:42:53 INFO - Running setup.py install for moznetwork 14:42:53 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 14:42:53 INFO - Running setup.py install for mozprocess 14:42:54 INFO - Running setup.py install for mozprofile 14:42:54 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 14:42:54 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 14:42:54 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 14:42:54 INFO - Running setup.py install for mozrunner 14:42:54 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 14:42:54 INFO - Running setup.py install for mozscreenshot 14:42:54 INFO - Running setup.py install for moztest 14:42:54 INFO - Running setup.py install for mozversion 14:42:54 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 14:42:54 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 14:42:54 INFO - Cleaning up... 14:42:54 INFO - Return code: 0 14:42:54 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:42: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:42:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:42:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:42: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:42:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:42:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:42:54 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 0x10d1bfb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d1c2768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa95155aab0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cf9dd30>, '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 0x10cec4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cf92be8>, '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.we4Zb3T4P2/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.Eu1fNmFiJN/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/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 14:42:54 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 14:42:54 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 14:42:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.we4Zb3T4P2/Render', 14:42:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:42:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:42:54 INFO - 'HOME': '/Users/cltbld', 14:42:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:42:54 INFO - 'LOGNAME': 'cltbld', 14:42:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:42:54 INFO - 'MOZ_NO_REMOTE': '1', 14:42:54 INFO - 'NO_EM_RESTART': '1', 14:42:54 INFO - 'PAGER': '/bin/cat', 14:42:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:42:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:42:54 INFO - 'PWD': '/builds/slave/test', 14:42:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:42:54 INFO - 'SHELL': '/bin/bash', 14:42:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Eu1fNmFiJN/Listeners', 14:42:54 INFO - 'TMPDIR': '/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/', 14:42:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:42:54 INFO - 'USER': 'cltbld', 14:42:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:42:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:42:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:42:54 INFO - 'XPC_FLAGS': '0x0', 14:42:54 INFO - 'XPC_SERVICE_NAME': '0', 14:42:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:42:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:42:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 14:42:55 INFO - Running setup.py (path:/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/pip-SLj7yy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 14:42:55 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 14:42:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 14:42:55 INFO - Running setup.py (path:/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/pip-8UjTkj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 14:42:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 14:42:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 14:42:55 INFO - Running setup.py (path:/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/pip-w2TwyQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 14:42:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 14:42:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 14:42:55 INFO - Running setup.py (path:/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/pip-tggs0x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 14:42:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 14:42:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 14:42:55 INFO - Running setup.py (path:/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/pip-qBeKP7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 14:42:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 14:42:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 14:42:55 INFO - Running setup.py (path:/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/pip-OjyMbr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 14:42:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 14:42:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 14:42:55 INFO - Running setup.py (path:/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/pip-xsmkBe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 14:42:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 14:42:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 14:42:55 INFO - Running setup.py (path:/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/pip-48kkV7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 14:42:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 14:42:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 14:42:55 INFO - Running setup.py (path:/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/pip-4HZ5FZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 14:42:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 14:42:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 14:42:56 INFO - Running setup.py (path:/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/pip-es0HGH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 14:42:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 14:42:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 14:42:56 INFO - Running setup.py (path:/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/pip-UF_sEV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 14:42:56 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 14:42:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 14:42:56 INFO - Running setup.py (path:/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/pip-OuupIg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 14:42:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 14:42:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 14:42:56 INFO - Running setup.py (path:/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/pip-yH_lUQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 14:42:56 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)) 14:42:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 14:42:56 INFO - Running setup.py (path:/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/pip-S5paZF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 14:42:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 14:42:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 14:42:56 INFO - Running setup.py (path:/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/pip-3dogEK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 14:42:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 14:42:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 14:42:56 INFO - Running setup.py (path:/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/pip-LbXTJ0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 14:42:56 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 14:42:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 14:42:56 INFO - Running setup.py (path:/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/pip-5jyjaf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 14:42:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 14:42:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 14:42:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 14:42:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 14:42:56 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 14:42:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 14:42:56 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 14:42:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:42:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:42:56 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:42:56 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:42:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:42:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:42:59 INFO - Downloading blessings-1.6.tar.gz 14:42:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 14:42:59 INFO - Installing collected packages: blessings 14:42:59 INFO - Running setup.py install for blessings 14:42:59 INFO - Successfully installed blessings 14:42:59 INFO - Cleaning up... 14:42:59 INFO - Return code: 0 14:42:59 INFO - Done creating virtualenv /builds/slave/test/build/venv. 14:42:59 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 14:42:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 14:43:00 INFO - Reading from file tmpfile_stdout 14:43:00 INFO - Current package versions: 14:43:00 INFO - blessings == 1.6 14:43:00 INFO - blobuploader == 1.2.4 14:43:00 INFO - docopt == 0.6.1 14:43:00 INFO - functools32 == 3.2.3-2 14:43:00 INFO - jsonschema == 2.5.1 14:43:00 INFO - manifestparser == 1.1 14:43:00 INFO - mozInstall == 1.12 14:43:00 INFO - mozcrash == 1.0 14:43:00 INFO - mozdebug == 0.1 14:43:00 INFO - mozdevice == 0.49 14:43:00 INFO - mozfile == 1.2 14:43:00 INFO - mozhttpd == 0.7 14:43:00 INFO - mozinfo == 0.9 14:43:00 INFO - mozleak == 0.1 14:43:00 INFO - mozlog == 3.4 14:43:00 INFO - moznetwork == 0.27 14:43:00 INFO - mozprocess == 0.25 14:43:00 INFO - mozprofile == 0.28 14:43:00 INFO - mozrunner == 6.13 14:43:00 INFO - mozscreenshot == 0.1 14:43:00 INFO - mozsystemmonitor == 0.3 14:43:00 INFO - moztest == 0.8 14:43:00 INFO - mozversion == 1.4 14:43:00 INFO - psutil == 3.1.1 14:43:00 INFO - requests == 1.2.3 14:43:00 INFO - wsgiref == 0.1.2 14:43:00 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:43:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:43:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:43:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:43:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:43:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:43:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:43:00 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 0x10d1bfb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d1c2768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa95155aab0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cf9dd30>, '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 0x10cec4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cf92be8>, '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.we4Zb3T4P2/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.Eu1fNmFiJN/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/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 14:43:00 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 14:43:00 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 14:43:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.we4Zb3T4P2/Render', 14:43:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:43:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:43:00 INFO - 'HOME': '/Users/cltbld', 14:43:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:43:00 INFO - 'LOGNAME': 'cltbld', 14:43:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:43:00 INFO - 'MOZ_NO_REMOTE': '1', 14:43:00 INFO - 'NO_EM_RESTART': '1', 14:43:00 INFO - 'PAGER': '/bin/cat', 14:43:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:43:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:43:00 INFO - 'PWD': '/builds/slave/test', 14:43:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:43:00 INFO - 'SHELL': '/bin/bash', 14:43:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Eu1fNmFiJN/Listeners', 14:43:00 INFO - 'TMPDIR': '/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/', 14:43:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:43:00 INFO - 'USER': 'cltbld', 14:43:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:43:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:43:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:43:00 INFO - 'XPC_FLAGS': '0x0', 14:43:00 INFO - 'XPC_SERVICE_NAME': '0', 14:43:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:43:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:43:00 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)) 14:43:00 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)) 14:43:00 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)) 14:43:00 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)) 14:43:00 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)) 14:43:00 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)) 14:43:00 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)) 14:43:00 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)) 14:43:00 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)) 14:43:00 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)) 14:43:00 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)) 14:43:00 INFO - Cleaning up... 14:43:00 INFO - Return code: 0 14:43:00 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 14:43:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:43:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:43:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:43:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:43:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:43:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:43:00 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10d1bfb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d1c2768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa95155aab0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cf9dd30>, '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 0x10cec4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cf92be8>, '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.we4Zb3T4P2/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.Eu1fNmFiJN/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/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 14:43:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 14:43:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 14:43:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.we4Zb3T4P2/Render', 14:43:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:43:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:43:00 INFO - 'HOME': '/Users/cltbld', 14:43:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:43:00 INFO - 'LOGNAME': 'cltbld', 14:43:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:43:00 INFO - 'MOZ_NO_REMOTE': '1', 14:43:00 INFO - 'NO_EM_RESTART': '1', 14:43:00 INFO - 'PAGER': '/bin/cat', 14:43:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:43:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:43:00 INFO - 'PWD': '/builds/slave/test', 14:43:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:43:00 INFO - 'SHELL': '/bin/bash', 14:43:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Eu1fNmFiJN/Listeners', 14:43:00 INFO - 'TMPDIR': '/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/', 14:43:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:43:00 INFO - 'USER': 'cltbld', 14:43:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:43:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:43:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:43:00 INFO - 'XPC_FLAGS': '0x0', 14:43:00 INFO - 'XPC_SERVICE_NAME': '0', 14:43:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:43:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:43:00 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 14:43:00 INFO - Cleaning up... 14:43:00 INFO - Return code: 0 14:43:00 INFO - Running post-action listener: _resource_record_post_action 14:43:00 INFO - Running post-action listener: _start_resource_monitoring 14:43:00 INFO - Starting resource monitoring. 14:43:00 INFO - [mozharness: 2017-04-08 21:43:00.703408Z] Finished create-virtualenv step (success) 14:43:00 INFO - [mozharness: 2017-04-08 21:43:00.704382Z] Running install step. 14:43:00 INFO - Running pre-action listener: _resource_record_pre_action 14:43:00 INFO - Running main action method: install 14:43:00 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 14:43:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 14:43:00 INFO - Reading from file tmpfile_stdout 14:43:00 INFO - Detecting whether we're running mozinstall >=1.0... 14:43:00 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 14:43:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 14:43:01 INFO - Reading from file tmpfile_stdout 14:43:01 INFO - Output received: 14:43:01 INFO - Usage: mozinstall [options] installer 14:43:01 INFO - Options: 14:43:01 INFO - -h, --help show this help message and exit 14:43:01 INFO - -d DEST, --destination=DEST 14:43:01 INFO - Directory to install application into. [default: 14:43:01 INFO - "/builds/slave/test"] 14:43:01 INFO - --app=APP Application being installed. [default: firefox] 14:43:01 INFO - mkdir: /builds/slave/test/build/application 14:43:01 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-54.0a2.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 14:43:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-54.0a2.en-US.mac.dmg --destination /builds/slave/test/build/application 14:43:21 INFO - Reading from file tmpfile_stdout 14:43:21 INFO - Output received: 14:43:21 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 14:43:21 INFO - Running post-action listener: _resource_record_post_action 14:43:21 INFO - [mozharness: 2017-04-08 21:43:21.132567Z] Finished install step (success) 14:43:21 INFO - [mozharness: 2017-04-08 21:43:21.132743Z] Running run-tests step. 14:43:21 INFO - Running pre-action listener: _resource_record_pre_action 14:43:21 INFO - Running main action method: run_tests 14:43:21 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 14:43:21 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 14:43:21 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 14:43:21 INFO - 2017-04-08 14:43:21.147 screenresolution[1644:13927] starting screenresolution argv=screenresolution get 14:43:21 INFO - 2017-04-08 14:43:21.150 screenresolution[1644:13927] Display 0: 1600x1200x32@60 14:43:21 INFO - 2017-04-08 14:43:21.155 screenresolution[1645:13929] starting screenresolution argv=screenresolution list 14:43:21 INFO - Available Modes on Display 0 14:43:21 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 14:43:21 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 14:43:21 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 14:43:21 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 14:43:21 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 14:43:21 INFO - Intel Iris: 14:43:21 INFO - Chipset Model: Intel Iris 14:43:21 INFO - Type: GPU 14:43:21 INFO - Bus: Built-In 14:43:21 INFO - VRAM (Dynamic, Max): 1536 MB 14:43:21 INFO - Vendor: Intel (0x8086) 14:43:21 INFO - Device ID: 0x0a2e 14:43:21 INFO - Revision ID: 0x0009 14:43:21 INFO - Displays: 14:43:21 INFO - Display: 14:43:21 INFO - Resolution: 1600 x 1200 @ 60 Hz 14:43:21 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 14:43:21 INFO - Display Serial Number: 1600x1200 60 14:43:21 INFO - Main Display: Yes 14:43:21 INFO - Mirror: Off 14:43:21 INFO - Online: Yes 14:43:21 INFO - Rotation: Supported 14:43:21 INFO - Return code: 0 14:43:21 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 14:43:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 14:43:21 INFO - Python 2.7.3 14:43:21 INFO - Return code: 0 14:43:21 INFO - grabbing minidump binary from tooltool 14:43:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:43:21 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cf9dd30>, '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 0x10cec4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cf92be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 14:43:21 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 14:43:21 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 14:43:21 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 14:43:21 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 14:43:21 INFO - Return code: 0 14:43:21 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 14:43:21 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 14:43:21 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 14:43:21 INFO - ENV: RUST_BACKTRACE is now 1 14:43:21 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 14:43:21 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 14:43:21 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/TPpnrsxHSLmcU0b3Zbk9wA/artifacts/public/build/firefox-54.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0019', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 14:43:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/TPpnrsxHSLmcU0b3Zbk9wA/artifacts/public/build/firefox-54.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0019 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 14:43:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.we4Zb3T4P2/Render', 14:43:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:43:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:43:21 INFO - 'HOME': '/Users/cltbld', 14:43:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:43:21 INFO - 'LOGNAME': 'cltbld', 14:43:21 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 14:43:21 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 14:43:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:43:21 INFO - 'MOZ_NO_REMOTE': '1', 14:43:21 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 14:43:21 INFO - 'NO_EM_RESTART': '1', 14:43:21 INFO - 'PAGER': '/bin/cat', 14:43:21 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:43:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:43:21 INFO - 'PWD': '/builds/slave/test', 14:43:21 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 14:43:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:43:21 INFO - 'RUST_BACKTRACE': '1', 14:43:21 INFO - 'SHELL': '/bin/bash', 14:43:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Eu1fNmFiJN/Listeners', 14:43:21 INFO - 'TMPDIR': '/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/', 14:43:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:43:21 INFO - 'USER': 'cltbld', 14:43:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:43:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:43:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:43:21 INFO - 'XPC_FLAGS': '0x0', 14:43:21 INFO - 'XPC_SERVICE_NAME': '0', 14:43:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:43:21 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/TPpnrsxHSLmcU0b3Zbk9wA/artifacts/public/build/firefox-54.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0019', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 14:43:21 INFO - mozversion application_buildid: 20170408132921 14:43:21 INFO - mozversion application_changeset: 20c110248317f6f6592d004cbb5f71fa1731639b 14:43:21 INFO - mozversion application_display_name: FirefoxDeveloperEdition 14:43:21 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 14:43:21 INFO - mozversion application_name: Firefox 14:43:21 INFO - mozversion application_remotingname: firefox-dev 14:43:21 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 14:43:21 INFO - mozversion application_vendor: Mozilla 14:43:21 INFO - mozversion application_version: 54.0a2 14:43:21 INFO - mozversion platform_buildid: 20170408132921 14:43:21 INFO - mozversion platform_changeset: 20c110248317f6f6592d004cbb5f71fa1731639b 14:43:21 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 14:43:21 INFO - mozversion platform_version: 54.0a2 14:43:21 INFO - using testdate: 1491687801 14:43:21 INFO - actual date: 1491687801 14:43:21 INFO - starting webserver on 'localhost:49245' 14:43:21 INFO - SUITE-START | Running 2 tests 14:43:21 INFO - TEST-START | dromaeo_css 14:43:21 INFO - operating with platform_type : mac_ 14:43:21 INFO - Initialising browser for dromaeo_css test... 14:43:21 INFO - TEST-INFO | started process 1655 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/tmpeFEisH/profile http://localhost:49245/getInfo.html) 14:43:24 INFO - PROCESS | 1655 | 2017-04-08 14:43:24.819 plugin-container[1658:14115] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8e3f, name = 'com.apple.tsm.portname' 14:43:24 INFO - PROCESS | 1655 | See /usr/include/servers/bootstrap_defs.h for the error codes. 14:43:24 INFO - PROCESS | 1655 | 2017-04-08 14:43:24.822 plugin-container[1658:14115] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4927, name = 'com.apple.CFPasteboardClient' 14:43:24 INFO - PROCESS | 1655 | See /usr/include/servers/bootstrap_defs.h for the error codes. 14:43:24 INFO - PROCESS | 1655 | 2017-04-08 14:43:24.822 plugin-container[1658:14115] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 14:43:24 INFO - PROCESS | 1655 | __metrics Screen width/height:1600/1200 14:43:24 INFO - PROCESS | 1655 | colorDepth:24 14:43:24 INFO - PROCESS | 1655 | Browser inner width/height: 1024/689 14:43:24 INFO - PROCESS | 1655 | __metrics 14:43:25 ERROR - PROCESS | 1655 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3360: TypeError: this.tabs is undefined 14:43:25 INFO - TEST-INFO | 1655: exit 0 14:43:25 INFO - Browser initialized. 14:43:25 INFO - Running cycle 1/1 for dromaeo_css test... 14:43:25 INFO - TEST-INFO | started process 1659 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/tmpeFEisH/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 14:43:28 INFO - PROCESS | 1659 | 2017-04-08 14:43:28.211 plugin-container[1660:14233] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x444b, name = 'com.apple.tsm.portname' 14:43:28 INFO - PROCESS | 1659 | See /usr/include/servers/bootstrap_defs.h for the error codes. 14:43:28 INFO - PROCESS | 1659 | 2017-04-08 14:43:28.211 plugin-container[1660:14233] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x470b, name = 'com.apple.CFPasteboardClient' 14:43:28 INFO - PROCESS | 1659 | See /usr/include/servers/bootstrap_defs.h for the error codes. 14:43:28 INFO - PROCESS | 1659 | 2017-04-08 14:43:28.211 plugin-container[1660:14233] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 14:43:37 INFO - PROCESS | 1659 | RSS: Main: 220725248 14:43:37 INFO - PROCESS | 1659 | 14:46:42 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49245/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49245/tests/dromaeo/cssquery-ext.html) 14:46:43 INFO - PROCESS | 1659 | RSS: Main: 238047232 14:46:43 INFO - PROCESS | 1659 | 14:48:26 INFO - PROCESS | 1659 | ************************* 14:48:26 INFO - PROCESS | 1659 | A coding exception was thrown and uncaught in a Task. 14:48:26 INFO - PROCESS | 1659 | 14:48:26 ERROR - PROCESS | 1659 | Full message: TypeError: NetworkError when attempting to fetch resource. 14:48:26 INFO - PROCESS | 1659 | Full stack: 14:48:26 INFO - PROCESS | 1659 | ************************* 14:48:26 INFO - PROCESS | 1659 | ************************* 14:48:26 INFO - PROCESS | 1659 | A coding exception was thrown and uncaught in a Task. 14:48:26 INFO - PROCESS | 1659 | 14:48:26 ERROR - PROCESS | 1659 | Full message: TypeError: NetworkError when attempting to fetch resource. 14:48:26 INFO - PROCESS | 1659 | Full stack: 14:48:26 INFO - PROCESS | 1659 | ************************* 14:48:26 INFO - PROCESS | 1659 | ************************* 14:48:26 INFO - PROCESS | 1659 | A coding exception was thrown and uncaught in a Task. 14:48:26 INFO - PROCESS | 1659 | 14:48:26 ERROR - PROCESS | 1659 | Full message: TypeError: NetworkError when attempting to fetch resource. 14:48:26 INFO - PROCESS | 1659 | Full stack: 14:48:26 INFO - PROCESS | 1659 | ************************* 14:48:26 INFO - PROCESS | 1659 | ************************* 14:48:26 INFO - PROCESS | 1659 | A coding exception was thrown and uncaught in a Task. 14:48:26 INFO - PROCESS | 1659 | 14:48:26 ERROR - PROCESS | 1659 | Full message: TypeError: NetworkError when attempting to fetch resource. 14:48:26 INFO - PROCESS | 1659 | Full stack: 14:48:26 INFO - PROCESS | 1659 | ************************* 14:49:45 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49245/tests/dromaeo/cssquery-ext.html (next: http://localhost:49245/tests/dromaeo/cssquery-jquery.html) 14:49:46 INFO - PROCESS | 1659 | RSS: Main: 248250368 14:49:46 INFO - PROCESS | 1659 | 14:52:48 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49245/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49245/tests/dromaeo/cssquery-mootools.html) 14:52:49 INFO - PROCESS | 1659 | RSS: Main: 254705664 14:52:49 INFO - PROCESS | 1659 | 14:55:51 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49245/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49245/tests/dromaeo/cssquery-prototype.html) 14:55:51 INFO - PROCESS | 1659 | RSS: Main: 251928576 14:55:51 INFO - PROCESS | 1659 | 14:58:55 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49245/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49245/tests/dromaeo/cssquery-yui.html) 14:58:55 INFO - PROCESS | 1659 | RSS: Main: 256884736 14:58:55 INFO - PROCESS | 1659 | 15:01:58 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49245/tests/dromaeo/cssquery-yui.html (next: http://localhost:49245/tests/dromaeo/cssquery-dojo.html) 15:01:58 INFO - PROCESS | 1659 | RSS: Main: 253394944 15:01:58 INFO - PROCESS | 1659 | 15:01:58 INFO - PROCESS | 1659 | __start_tp_report 15:01:58 INFO - PROCESS | 1659 | _x_x_mozilla_page_load 15:01:58 INFO - PROCESS | 1659 | _x_x_mozilla_page_load_details 15:01:58 INFO - PROCESS | 1659 | |i|pagename|runs| 15:01:58 INFO - PROCESS | 1659 | |0;dojo.html;3455.4644030175314;3475.3223121491305;3488.9651103488973;3510.666486683764;3522.4364101743727;6046.153538504607;6058.757649694013;6069.544784141185;6098.298695650002;6108.847278818032;6047.697615119248;6092.451679348857;6124.663143527116;6132.356102609228;6159.5380346474085;5495.477929596692;5502.944970550303;5516.503514683678;5517.2551705519845;5610.046292130348;4523.434570678665;5337.439568845273;5588.133839254922;5588.161775733653;5622.465865742748;5393.002294575496;5560.6941618210985;5572.693501857396;5578.553715702734;5598.692071936042;5416.566674666017;5475.425080366544;5485.862853428656;5492.286002819648;5512.062949298629;5445.863853403657;5535.861603459905;5548.278723765925;5558.86102847428;5569.916451253234;6162.257795281102;6178.127170865524;6187.4740647045055;6191.783287584958;6194.101855230966;6215.937840621581;6221.191245138149;6335.8416039598915;6448.129502517147;6494.480441564663;6517.120188774549;6562.803115906483;6581.144451221358;6586.538942274043;6630.071789949411;6209.472194863397;6216.129741836146;6219.284782250099;6232.0963460298;6234.532410069172;6404.1674582304;6628.734850606018;6647.169103862017;6734.797956061326;6778.8305292367095;6269.529785266123;6288.591241569284;6294.527910406738;6314.242290925118;6322.873542529123;6552.8361790955605;6556.508261880378;6559.573627714183;6563.737450501926;6572.309907459744;6390.7763228286785;6402.327755585689;6454.580452270681;6460.289368169498;6464.353564643592;6213.1922850029205;6244.500439964792;6269.780557680459;6382.425581697669;6398.360163983658;5110.131277682803;5157.484251574888;5240.135377662719;5280.6303558750515;5311.628186027018;4631.976840115778;4652.464966528825;4661.627069834405;4674.813007479731;4676.976615116902;6172.969135154295;6174.783882564088;6227.906581401283;6247.406496382927;6269.059641053878;5359.383670877821;6238.563300569007;6294.992801151794;6467.223933128054;6508.479321654257;6241.625502469867;6243.0635404688755;6243.719032643451;6248.094026366151;6262.5929314594405;4975.776090075955;5162.760937375003;5805.564582656318;5819.70901454934;5846.56150788684;5464.535514481314;6463.515236357292;6706.429953454011;6718.630475323904;6822.420094292041;6594.769183078761;6614.140161779131;6615.536912416277;6617.139771829631;6617.139771829631;6773.221079575715;6775.119234499483;6821.965890170716;6845.931540684529;6873.896891546531;6238.500919926486;6274.937250627618;6333.493320534254;6355.841103972438;6470.029495575702;4887.169181239269;6534.869302613826;6571.5399922005;6594.571352862049;6599.9010014848855;6087.710036952616;6342.460890824332;6354.523410744305;6383.0744542042075;6386.105945167587;6160.260768707604;6189.938100618936;6210.254769427697;6297.968510157603;6358.650274234961;6120.17287048654;6134.079888016833;6223.688815559294;6245.313015568193;6269.247690277196;6332.0818481320875;6341.8731625368155;6361.872762544631;6363.745450181941;6366.045093236052;6113;6122.173506576733;6148.846278843064;6166.321704612579;6226.097215903759 15:01:58 INFO - PROCESS | 1659 | |1;ext.html;696.7108649910288;728.9088863892014;809.3484744780451;931.3945935142152;933.5425641435698;37237.25525489489;37879;39106.608933910655;39138.21723565527;39249.41125883114;37964.620353796454;38270;38350.808245958804;38371.808140959256;38401.8878961657;7871.330936870373;8005.279524842763;10738.248322617419;11112.16658750593;11147.108231341514;10728.409937453436;10784.838227426595;10846.566137354497;10847.945760271188;10881.782364352708;9194.12655797698;10813.675589732282;10854.620088296911;10885.727856803564;10914.399708016095;10780.676579702622;10798.568057277738;10846.945765271203;10851.077658399046;10858.131349492021;10480.266381353309;10508.211884108723;10551.78896422075;10553.525091370906;10575.312604680672;34512.48231276533;34623.65376346255;34650.653493465;34689;34740.82629586861;3708.49935258742;3773.9622603773887;3778.3576791945434;3789.052736815796;3795.6014618465215;25259.115930942327;25266.747332526622;25378.492430151477;26733.732662673512;27004.45991080187;3048.314129320885;3055.8777648894015;3127.155667969658;3177.9046628601177;3285.3100848821778;359405;359466;364157;381107;392035;43659;43667;43898.90252743643;43908.341374879405;43966.340504892454;46041.5395846044;46083.07833843371;46162.538374616495;46230;46248.76875615668;12741.872581274254;12791.296478693575;12828.55100071493;12849.164804287691;12854.421551030226;10941.83587246192;10964.56141754337;10976.341419514854;11001.394923279136;11027.393493357782;12024.87975120255;12056.035517158607;12063;12079.818802717966;12124.757504849853;5167.958656330728;5515.696636684941;5543.334799824047;5544.584156188222;5569.248151499496;1087.3747595132672;1102.079763397572;1106.7731115121542;1121.3103941076179;1181.722295260613;19846.900765496368;20716.482087947923;26225.757136718938;26309.81605827742;26753.464930701277;1058.862347894784;1154.751728378386;1225.9816102758466;1245;1269.8476182858117;14621.034224331752;15484;15575.143367114917;15692.607684807743;15899.841001590068;478145;478435.60782195866;482696;482966;483814;52359;52563;52689.47310526922;52749.736251319264;52756.47243527516;1471.2864260833487;1481.8073650425376;1504.4884739188728;1510.4864346122151;1514.6894886548232;21562.814045227035;27792.305192369542;28761.568576470952;28810.703518342143;29528;38367.61632383752;48423;48815.755921221586;48953.755231223615;49041;480405;486419;489459;491529;492259;31713.682863171074;32380.190495237806;34011.4898276531;34850.82574587111;35171;31685.683143169197;32038.83980580082;38583.61416385912;38834.4466221348;39631.00922476961;26967.595486068116;27688;28102.578461323505;28296.009639662512;28551;22592.887035564716;28268.85865570659;28683;28890.711092889644;29153.125406237847;32277.51583726293;32713.34573308568;32731.509027365086;32758.689652413635;32820.50769238511;27293.863530682218;27384.863075684494;29192.124236272946;29346.853265733534;29405.55891661584;21998;22671;22838.771612283665;23257.886294777873;23319.883400582887 15:01:58 INFO - PROCESS | 1659 | |2;jquery.html;413.7414116177389;430.7781492531347;435.515873015873;437.9321205213191;438.9934150987735;18929.62140757184;20020.399388018366;20624.69062964057;21348.46628834278;21566.784332156672;21346.46633834155;21615.1353945842;22109.778902210972;22235.888820555876;22546.774532254673;6513.283538810727;6812.046313516112;6824.181098268215;6846.144231971004;6847.144106986627;5605.943940560604;6446.452051575622;6615.569987950792;6625.1387319648375;6712.429443497309;6447.742690175418;6574.276829548746;6681.198256209262;6689;6697.866042679144;6661.733530658767;6696.497762667819;6698.966505167467;6726.327367263283;6749.831254218635;6541.21505419348;6726.058351830748;7019.192792828839;7178.353948144692;7183.856322873566;25340.239792806246;25397.49205015908;25552.48895022089;25740.97036118572;25837.870810645825;2737.603047558113;2776.6667999840147;2818.436312737461;2842.6730925943575;2846;13520.18878867271;15513.224338783128;15623.531294061197;15771.369145234177;15880.52358429249;1825.799162092169;2016.0826823795137;2087.686846972951;2092.089940875724;2097.800708932649;138560;156451;157945.21027394905;164609.17695411446;167654;19698.01509924498;20052.295554877997;20177.99110044492;20252.29116980928;20864.895675521828;31662;31899.84050079735;32102.87639932638;32177.35645287128;32317.838410808265;9692.854607180898;9870.358426702242;10055.899441005642;10212.897871021194;10228;6613.338666133348;8104.432689711663;8204.917950820416;8355.331573474108;8360.58197090143;9515.857262141073;9563.760905977268;9599.856002159833;9669.419834809934;9718.9028109718;3350.3801796667694;3903.4144878268485;3990.620891015349;3999.300122478555;4021.678265738734;790.6837265094008;944.2304521814837;960.3853533738413;990.895955924632;996.1383403356045;20099.698504522443;20867.582648346946;20997.370078897657;21316.14735410597;21356.893215534135;945.8581212817995;955.7562821480478;976.8876579193342;986.782907760306;992.553350992042;9450.763730906645;10900.018998290192;11005.34919762042;11042.834357484644;11082.889171108347;746958;747084;748928;758492;772120;130819;132709;146589;161918;168250;1167.3054532553117;1250.9249445033145;1270.8284381608548;1272.732726127505;1278.1564167649308;19569.60860782805;20380.38858834237;20471;20598;20611.86634735104;56890.715546422005;64021.679891600244;64170;66077;68367.3163268361;89507;89518.55240723754;90049.54975225082;91413;91502.08497915202;9826.950865245628;10874.347539147677;11407.030402415887;12028.879711202775;12055.819162712742;11060;12740.872591273968;12865.035122365673;12994.285314307805;13166.47334106625;11222.775544489228;11491.712707182387;11526.250793698382;11648.534058637559;11651.941740291584;10547.736306592396;10620.256582039492;10623.309484883457;10755.19336049815;10834.566617335533;11342.209643020724;11382.94308528452;11542.480588373375;11648.708782280171;11747.530098795953;9388.95305523468;11539.88460115388;11645.5341786331;11679.824802627798;11844.644660660195;11142.27575207609;11276.041536469493;11360.886391135982;11487.36819474937;11602.709932251424 15:01:58 INFO - PROCESS | 1659 | |3;mootools.html;1310.9541166059184;1411.5483045425462;1520.8631223189925;1575.306864979409;1584.9286782094807;10614.628488002922;11213.439328033597;11216.831747523795;11505.942470287637;11656.125790565698;11117.999380055793;11221.60724374647;11269.380184089872;11286.097112231022;11303.26528775629;2390.3665528634924;2436.281297017384;2449.216250799745;2450.7304196538366;2456.955793787642;2344.953100937989;2420.0803694596116;2425.684660994077;2434.57394955882;2436.305652888927;2363.1492662641435;2419.11702228687;2426.3206302235403;2439.4511234972165;2440.7437219091917;1822.9635407291846;2340.32130682102;2403.07481619576;2408.1812183857573;2415.685960825099;2399.076355603088;2399.3881560202035;2415.6618073469554;2416.226807421626;2434.6469761884427;10911.399873006982;11058.22592418531;11137.832932506019;11166.944165279203;11203.935626115506;5858.326292476378;5886.705664716747;6010;6048.546359023091;6070.453659170696;3524.6122926478065;3632.328019316435;3649.817509124533;3698.389765688658;3703.4444833274956;1121.8822023687558;1122.9730336742177;1133.5012594458494;1229.9877001229963;1255.6358655989843;13398.330083495885;13604.795928061087;14713.705725885422;15436.691266174614;15640.4525841595;4022.27599032177;4621.376114224604;4665.020345727367;4818.373611430492;4829.9034019319415;11583.957443830095;11904.404779760976;11904.583339583072;11957.52169913211;11978.161528692908;4931.753412329369;5940.524758019348;5955;5972.522198224132;5974.492168165668;5227.451117632594;5241.868953276123;5293.153095504701;5299.947000530023;5377.193420986883;3859.749116307431;3969.4641223435046;3975.4633124528395;4024.3158663027357;4047.352423612208;913.8720579118929;1057.0539367266254;1064.8668916385452;1076.0960792933972;1079.3577821196375;925.2782829393084;934.4626839567276;941.1153515695224;946.6260826973307;993.6522217224058;5592;6538.803835884931;6630.569013014138;6700.430463410665;6735.29279425663;975.926805489577;978.3396207559869;992.2111921022804;1004.8593196952432;1032.6592224565875;4075.5924407559005;4149.647279981175;4179.958200418017;4273.615374616299;4306.676999225071;13419.329033548283;14028.228447435285;14083.507077252452;14093.506727264701;14102;13831.100978436369;13845.792313115311;13989.790153147711;13993.440262389391;13998.930005350112;8601.78495537604;9607.51962401891;10179.54192061359;10223.284370094021;10307.484625768682;5887.411258874078;6778.186617605918;6940.02209370327;7024.754133605299;7076.398506127037;11442.94278528602;11678.182527223013;12231.143819932518;12231.204971676812;12443.942264907584;12707.174033687781;12720.300383478998;12721;12751.362431878555;12909.354532273537;9239.953800230956;9308.255339572954;9345.766355841159;9402.34183607141;9466.810663786548;10172.79654406893;10374.481275936323;10418.843717344094;10421.895781042092;10445.529951152064;8898.866517002121;8929.821403572023;8947.59735811877;9024.278057755233;9067.68263110788;8418.5790710463;8498.022727386182;8675.566221689016;8752.431091979;8755.387122901337;7651.5409075455655;7767.3786097113225;7820;7892.0529536456;7894.1316455188735;5914.881702365842;5918.17145599625;5943.881122377615;6027.638341699512;6062.636241825505;5688.5733569981585;5933.584649074523;6076.453119219292;6134.6625935573975;6179.62922224668 15:01:58 INFO - PROCESS | 1659 | |4;prototype.html;358.3800025955098;375.8271195250185;384.7057001393934;386.6539447194761;393.69291952277234;4391.978040109799;4625.6299496040365;5225.947740522593;5284.920726189101;5312.814051508198;4353.6286069887965;4777.822747872669;4886.462489126193;5253.007714137255;5258.107081450675;3749.381352076904;3779.77157423837;4160.356659119646;4418.9337159942625;4453.777311134447;3455.2225749206355;3851.265601422993;3965.4646622705854;4373.650107991353;4404.639406987092;3895.8636447724193;3899.493065901429;4472.875384192515;4486.349479325496;4489.4612646482465;3611.9097022574383;3836.7122465814896;4229.196452673982;4275.719486434251;4415.668824838134;3650.3064417760806;3952.3873799561115;3979.343408337621;3989.900252493682;4010.598940105995;4923.77842997066;4934.827281045146;5166.302549155891;5171;5241.68549887008;4347.239233134189;4394.450693663292;4567.314902764612;5086.79652813887;5125.974805039007;4815.277708343777;5364.383095943977;5660.405657405995;5684;5700.862176683743;3843.3082045231845;4213.47331583552;4438.977805110953;4563.269876819693;4591.586757191835;5043.823466178703;5733.168690539848;5883.852903677357;5989.825392541959;6060.1516537456055;3648.908777280589;4161.292580261363;4431.40176076232;4611.7232966021475;4667.323238130452;4082.652974497201;4322.0491491871735;4346.6522678185665;4722.645801564897;4729.55069268419;4205.684573656988;4573.6569757268335;4778.522147785263;4869.366982292279;5109.489051094861;5011.4236862761245;5222.895542089137;5318.468153184651;5400.110940646735;5666.744996475169;1499.9500349755215;1513.7577987521943;1571.4385551551127;1581.8452529653418;1596.8283134936478;1055.0135623192339;1104.7127746785893;1125.5272785430138;1136.5340210513816;1212.5933916656636;5358.5981051421295;5362.812301569505;5372.70450125239;5389.655499857575;5422.972885135549;4887.877803054952;5199.532042116228;5369.946300537023;5483.75323110461;5616.085924815786;4687.531246875285;4828.5412885775795;5237.397699264633;5411.458854114557;5449.373322067933;2544.8091393145587;2864.9856750716112;2899.8405087719957;3107.7979931304394;3111.98444007783;5116.513931176532;5688.975984322861;6141.324454310023;6233.906491402542;6284.748610055546;5103.770330335144;5728.169415434739;5791.362950075489;5804.182528501334;5969.492813018543;4980.402351717745;5693.516051135617;6085.04069183614;6193.77024271271;6221.253449585988;5584.87247141602;5907;5981.820545383645;5983.700814959322;6057.878842423039;5327.9733601331745;5404.486573775485;5555.638883472561;5634.52106570946;5870.911936321044;5363.946360536501;5398.919016214839;6270.74917003333;6275.6862156892885;6279.529035322461;3769.981150094232;4144.5547127193895;4361.168309310034;4505.085338731092;4658.277966915133;4935.4570997190995;5270.288511050959;5305.946940530545;5859.150423188699;5860.2967643883;5719.142128680731;5726.828195154152;5728.598998070095;5766.933117373299;5775.9133612995;5031.6917601423165;5403.216533602685;5660.37735849048;5691.8861622768145;5735.311762588516;5212.54048866318;5320.388155362183;5368.973155134199;5557.972210138923;5578.732269813515;4995.650304478651;5109.386873575195;5554.277943867433;5724.742386592697;5741.827745167651;4931.802727890988;5078.974605127099;5368.443151486025;5495.422980586981;5613.691246981455 15:01:58 INFO - PROCESS | 1659 | |5;yui.html;702.4591064880045;707.0772641702576;725.7387340557399;762.0893032825772;808.4017826808162;12700.110992230548;15843.84156158438;16215.675686486264;16268.186590670448;16305.75541366879;12382.504699811996;14907.627309317246;14910.105393676387;14920.701585968302;15097.245137743124;751.372603875763;907.4691305586207;929.3355250995537;931.5575101826646;932.361332487245;934.2946075712828;936.2135805923024;936.4381371177274;936.5832204668925;937.2267878999818;971.6696323250094;975.395254941937;975.8438649816031;975.9219262458986;976.0873583199731;962.7689354554926;969.1035791892542;971.4220039076738;972.4797233480055;975.1077763846072;635.1869606903125;687.7077242172107;718.4719231364943;719.255570484546;720.6828995242076;12899.355032248444;15421.306041228172;15901.840981590152;16471.34114635413;16670.249838757285;13844.10013349129;14065.71868562623;14105.64735881601;14174.645633859132;14239.288035598178;11690.223058156744;11691.181617286793;11693.532258709727;11715;11782.469788859522;3628.5827129880136;3637.217998130415;3652.8356223969718;3700.5004324416127;3837.7313588048846;73243;98660;101657.49171254097;101771;102835.48582257041;9832.705018849447;9914.107730304164;9915.652952146784;9933;10103.898961010444;18340;21715.34853954384;22061.33815985523;22164.667529987062;22250.88874555649;7760.844783104306;7776.805579860549;7820.139784623687;7859.017622797151;7969.760907172794;4287.8851498609965;4290.549492303326;4321.632661223831;4387.407699960529;4551.180787458223;3478.217401084786;3503.7722548034294;3533.5406397168204;3533.982330088333;3565.0552603560077;620.1132380695558;627.5920651576512;638.6008744534665;646.4375992886125;654.0973456629821;602.6831373449028;620.5687047502033;667.7262322447871;676.8984652302194;689.5276735436242;9326.673566425143;9420.858687119699;9516.38143520669;10297.9485102574;10352.430616316175;642.5662677692538;667.7662818013656;672.8116127484215;696.9128858892639;698.8742026435189;4967.677100988424;5032.496750325012;5035.697858128524;5038.345015148007;5047.141985862412;63928.68035659792;78435.60782196053;80974.78537821937;81172.18827811646;97957.5306370405;67870.46202145646;69970.65014674894;74714;77062;79189.60405197938;58272.54318642068;59227.70386148042;62972.370276297566;63762;65047;10540.262181647211;11052.723681908017;11198.888011119785;11312;11330.773384532427;45650.315245270685;46123.07753844972;46620.067598648515;46837.2974405391;47179.52820471889;58368.12447813201;59902.70048649903;61280;61456;61807;10669.253152279265;11211.215214934877;12438.502459901503;12812.231266124063;12905.419256133684;12379.69050773738;12548.874511254771;13804.258029840019;14584.68737813602;14966.401343946556;12278.386080695751;13617.727645447234;13740.65648358799;13743.725125497234;13766.862331376959;13672.52146174879;13903.304834758019;13970.79043814364;13991.720165596835;14050.718985620435;13200.933995329962;13394.483508915531;13446.865531344562;13662.975276854475;13951.441942322599;8017.799555010938;9081.77295567616;9127.954360228156;9369.297302702462;9630.759231019281;10760.946195268974;11120.888791111985;11452.484638191134;11472.94263528677;11492.310461372344 15:01:58 INFO - PROCESS | 1659 | __end_tp_report 15:01:58 INFO - PROCESS | 1659 | __start_cc_report 15:01:58 INFO - PROCESS | 1659 | _x_x_mozilla_cycle_collect,419 15:01:58 INFO - PROCESS | 1659 | __end_cc_report 15:01:58 INFO - PROCESS | 1659 | __startTimestamp1491688918932__endTimestamp 15:01:58 INFO - PROCESS | 1659 | 15:01:58 INFO - PROCESS | 1659 | ------- Summary: start ------- 15:01:58 INFO - PROCESS | 1659 | Number of tests: 6 15:01:58 INFO - PROCESS | 1659 | 15:01:58 INFO - PROCESS | 1659 | [#0] dojo.html Cycles:165 Average:6011.66 Median:6226.10 stddev:678.76 (10.9%) stddev-sans-first:650.55 15:01:58 INFO - PROCESS | 1659 | Values: 3455.5 3475.3 3489.0 3510.7 3522.4 6046.2 6058.8 6069.5 6098.3 6108.8 6047.7 6092.5 6124.7 6132.4 6159.5 5495.5 5502.9 5516.5 5517.3 5610.0 4523.4 5337.4 5588.1 5588.2 5622.5 5393.0 5560.7 5572.7 5578.6 5598.7 5416.6 5475.4 5485.9 5492.3 5512.1 5445.9 5535.9 5548.3 5558.9 5569.9 6162.3 6178.1 6187.5 6191.8 6194.1 6215.9 6221.2 6335.8 6448.1 6494.5 6517.1 6562.8 6581.1 6586.5 6630.1 6209.5 6216.1 6219.3 6232.1 6234.5 6404.2 6628.7 6647.2 6734.8 6778.8 6269.5 6288.6 6294.5 6314.2 6322.9 6552.8 6556.5 6559.6 6563.7 6572.3 6390.8 6402.3 6454.6 6460.3 6464.4 6213.2 6244.5 6269.8 6382.4 6398.4 5110.1 5157.5 5240.1 5280.6 5311.6 4632.0 4652.5 4661.6 4674.8 4677.0 6173.0 6174.8 6227.9 6247.4 6269.1 5359.4 6238.6 6295.0 6467.2 6508.5 6241.6 6243.1 6243.7 6248.1 6262.6 4975.8 5162.8 5805.6 5819.7 5846.6 5464.5 6463.5 6706.4 6718.6 6822.4 6594.8 6614.1 6615.5 6617.1 6617.1 6773.2 6775.1 6822.0 6845.9 6873.9 6238.5 6274.9 6333.5 6355.8 6470.0 4887.2 6534.9 6571.5 6594.6 6599.9 6087.7 6342.5 6354.5 6383.1 6386.1 6160.3 6189.9 6210.3 6298.0 6358.7 6120.2 6134.1 6223.7 6245.3 6269.2 6332.1 6341.9 6361.9 6363.7 6366.0 6113.0 6122.2 6148.8 6166.3 6226.1 15:01:58 INFO - PROCESS | 1659 | 15:01:58 INFO - PROCESS | 1659 | [#1] ext.html Cycles:180 Average:56900.94 Median:29466.78 stddev:119827.11 (406.7%) stddev-sans-first:120088.95 15:01:58 INFO - PROCESS | 1659 | Values: 696.7 728.9 809.3 931.4 933.5 37237.3 37879.0 39106.6 39138.2 39249.4 37964.6 38270.0 38350.8 38371.8 38401.9 7871.3 8005.3 10738.2 11112.2 11147.1 10728.4 10784.8 10846.6 10847.9 10881.8 9194.1 10813.7 10854.6 10885.7 10914.4 10780.7 10798.6 10846.9 10851.1 10858.1 10480.3 10508.2 10551.8 10553.5 10575.3 34512.5 34623.7 34650.7 34689.0 34740.8 3708.5 3774.0 3778.4 3789.1 3795.6 25259.1 25266.7 25378.5 26733.7 27004.5 3048.3 3055.9 3127.2 3177.9 3285.3 359405.0 359466.0 364157.0 381107.0 392035.0 43659.0 43667.0 43898.9 43908.3 43966.3 46041.5 46083.1 46162.5 46230.0 46248.8 12741.9 12791.3 12828.6 12849.2 12854.4 10941.8 10964.6 10976.3 11001.4 11027.4 12024.9 12056.0 12063.0 12079.8 12124.8 5168.0 5515.7 5543.3 5544.6 5569.2 1087.4 1102.1 1106.8 1121.3 1181.7 19846.9 20716.5 26225.8 26309.8 26753.5 1058.9 1154.8 1226.0 1245.0 1269.8 14621.0 15484.0 15575.1 15692.6 15899.8 478145.0 478435.6 482696.0 482966.0 483814.0 52359.0 52563.0 52689.5 52749.7 52756.5 1471.3 1481.8 1504.5 1510.5 1514.7 21562.8 27792.3 28761.6 28810.7 29528.0 38367.6 48423.0 48815.8 48953.8 49041.0 480405.0 486419.0 489459.0 491529.0 492259.0 31713.7 32380.2 34011.5 34850.8 35171.0 31685.7 32038.8 38583.6 38834.4 39631.0 26967.6 27688.0 28102.6 28296.0 28551.0 22592.9 28268.9 28683.0 28890.7 29153.1 32277.5 32713.3 32731.5 32758.7 32820.5 27293.9 27384.9 29192.1 29346.9 29405.6 21998.0 22671.0 22838.8 23257.9 23319.9 15:01:58 INFO - PROCESS | 1659 | 15:01:58 INFO - PROCESS | 1659 | [#2] jquery.html Cycles:180 Average:43193.81 Median:25368.87 stddev:125886.10 (496.2%) stddev-sans-first:126198.26 15:01:58 INFO - PROCESS | 1659 | Values: 413.7 430.8 435.5 437.9 439.0 18929.6 20020.4 20624.7 21348.5 21566.8 21346.5 21615.1 22109.8 22235.9 22546.8 6513.3 6812.0 6824.2 6846.1 6847.1 5605.9 6446.5 6615.6 6625.1 6712.4 6447.7 6574.3 6681.2 6689.0 6697.9 6661.7 6696.5 6699.0 6726.3 6749.8 6541.2 6726.1 7019.2 7178.4 7183.9 25340.2 25397.5 25552.5 25741.0 25837.9 2737.6 2776.7 2818.4 2842.7 2846.0 13520.2 15513.2 15623.5 15771.4 15880.5 1825.8 2016.1 2087.7 2092.1 2097.8 138560.0 156451.0 157945.2 164609.2 167654.0 19698.0 20052.3 20178.0 20252.3 20864.9 31662.0 31899.8 32102.9 32177.4 32317.8 9692.9 9870.4 10055.9 10212.9 10228.0 6613.3 8104.4 8204.9 8355.3 8360.6 9515.9 9563.8 9599.9 9669.4 9718.9 3350.4 3903.4 3990.6 3999.3 4021.7 790.7 944.2 960.4 990.9 996.1 20099.7 20867.6 20997.4 21316.1 21356.9 945.9 955.8 976.9 986.8 992.6 9450.8 10900.0 11005.3 11042.8 11082.9 746958.0 747084.0 748928.0 758492.0 772120.0 130819.0 132709.0 146589.0 161918.0 168250.0 1167.3 1250.9 1270.8 1272.7 1278.2 19569.6 20380.4 20471.0 20598.0 20611.9 56890.7 64021.7 64170.0 66077.0 68367.3 89507.0 89518.6 90049.5 91413.0 91502.1 9827.0 10874.3 11407.0 12028.9 12055.8 11060.0 12740.9 12865.0 12994.3 13166.5 11222.8 11491.7 11526.3 11648.5 11651.9 10547.7 10620.3 10623.3 10755.2 10834.6 11342.2 11382.9 11542.5 11648.7 11747.5 9389.0 11539.9 11645.5 11679.8 11844.6 11142.3 11276.0 11360.9 11487.4 11602.7 15:01:58 INFO - PROCESS | 1659 | 15:01:58 INFO - PROCESS | 1659 | [#3] mootools.html Cycles:180 Average:6770.06 Median:2444.98 stddev:4232.45 (173.1%) stddev-sans-first:4224.44 15:01:58 INFO - PROCESS | 1659 | Values: 1311.0 1411.5 1520.9 1575.3 1584.9 10614.6 11213.4 11216.8 11505.9 11656.1 11118.0 11221.6 11269.4 11286.1 11303.3 2390.4 2436.3 2449.2 2450.7 2457.0 2345.0 2420.1 2425.7 2434.6 2436.3 2363.1 2419.1 2426.3 2439.5 2440.7 1823.0 2340.3 2403.1 2408.2 2415.7 2399.1 2399.4 2415.7 2416.2 2434.6 10911.4 11058.2 11137.8 11166.9 11203.9 5858.3 5886.7 6010.0 6048.5 6070.5 3524.6 3632.3 3649.8 3698.4 3703.4 1121.9 1123.0 1133.5 1230.0 1255.6 13398.3 13604.8 14713.7 15436.7 15640.5 4022.3 4621.4 4665.0 4818.4 4829.9 11584.0 11904.4 11904.6 11957.5 11978.2 4931.8 5940.5 5955.0 5972.5 5974.5 5227.5 5241.9 5293.2 5299.9 5377.2 3859.7 3969.5 3975.5 4024.3 4047.4 913.9 1057.1 1064.9 1076.1 1079.4 925.3 934.5 941.1 946.6 993.7 5592.0 6538.8 6630.6 6700.4 6735.3 975.9 978.3 992.2 1004.9 1032.7 4075.6 4149.6 4180.0 4273.6 4306.7 13419.3 14028.2 14083.5 14093.5 14102.0 13831.1 13845.8 13989.8 13993.4 13998.9 8601.8 9607.5 10179.5 10223.3 10307.5 5887.4 6778.2 6940.0 7024.8 7076.4 11442.9 11678.2 12231.1 12231.2 12443.9 12707.2 12720.3 12721.0 12751.4 12909.4 9240.0 9308.3 9345.8 9402.3 9466.8 10172.8 10374.5 10418.8 10421.9 10445.5 8898.9 8929.8 8947.6 9024.3 9067.7 8418.6 8498.0 8675.6 8752.4 8755.4 7651.5 7767.4 7820.0 7892.1 7894.1 5914.9 5918.2 5943.9 6027.6 6062.6 5688.6 5933.6 6076.5 6134.7 6179.6 15:01:58 INFO - PROCESS | 1659 | 15:01:58 INFO - PROCESS | 1659 | [#4] prototype.html Cycles:180 Average:4666.71 Median:5082.89 stddev:1340.29 (26.4%) stddev-sans-first:1304.46 15:01:58 INFO - PROCESS | 1659 | Values: 358.4 375.8 384.7 386.7 393.7 4392.0 4625.6 5225.9 5284.9 5312.8 4353.6 4777.8 4886.5 5253.0 5258.1 3749.4 3779.8 4160.4 4418.9 4453.8 3455.2 3851.3 3965.5 4373.7 4404.6 3895.9 3899.5 4472.9 4486.3 4489.5 3611.9 3836.7 4229.2 4275.7 4415.7 3650.3 3952.4 3979.3 3989.9 4010.6 4923.8 4934.8 5166.3 5171.0 5241.7 4347.2 4394.5 4567.3 5086.8 5126.0 4815.3 5364.4 5660.4 5684.0 5700.9 3843.3 4213.5 4439.0 4563.3 4591.6 5043.8 5733.2 5883.9 5989.8 6060.2 3648.9 4161.3 4431.4 4611.7 4667.3 4082.7 4322.0 4346.7 4722.6 4729.6 4205.7 4573.7 4778.5 4869.4 5109.5 5011.4 5222.9 5318.5 5400.1 5666.7 1500.0 1513.8 1571.4 1581.8 1596.8 1055.0 1104.7 1125.5 1136.5 1212.6 5358.6 5362.8 5372.7 5389.7 5423.0 4887.9 5199.5 5369.9 5483.8 5616.1 4687.5 4828.5 5237.4 5411.5 5449.4 2544.8 2865.0 2899.8 3107.8 3112.0 5116.5 5689.0 6141.3 6233.9 6284.7 5103.8 5728.2 5791.4 5804.2 5969.5 4980.4 5693.5 6085.0 6193.8 6221.3 5584.9 5907.0 5981.8 5983.7 6057.9 5328.0 5404.5 5555.6 5634.5 5870.9 5363.9 5398.9 6270.7 6275.7 6279.5 3770.0 4144.6 4361.2 4505.1 4658.3 4935.5 5270.3 5305.9 5859.2 5860.3 5719.1 5726.8 5728.6 5766.9 5775.9 5031.7 5403.2 5660.4 5691.9 5735.3 5212.5 5320.4 5369.0 5558.0 5578.7 4995.7 5109.4 5554.3 5724.7 5741.8 4931.8 5079.0 5368.4 5495.4 5613.7 15:01:58 INFO - PROCESS | 1659 | 15:01:58 INFO - PROCESS | 1659 | [#5] yui.html Cycles:180 Average:18348.75 Median:26073.60 stddev:24738.63 (94.9%) stddev-sans-first:24772.55 15:01:58 INFO - PROCESS | 1659 | Values: 702.5 707.1 725.7 762.1 808.4 12700.1 15843.8 16215.7 16268.2 16305.8 12382.5 14907.6 14910.1 14920.7 15097.2 751.4 907.5 929.3 931.6 932.4 934.3 936.2 936.4 936.6 937.2 971.7 975.4 975.8 975.9 976.1 962.8 969.1 971.4 972.5 975.1 635.2 687.7 718.5 719.3 720.7 12899.4 15421.3 15901.8 16471.3 16670.2 13844.1 14065.7 14105.6 14174.6 14239.3 11690.2 11691.2 11693.5 11715.0 11782.5 3628.6 3637.2 3652.8 3700.5 3837.7 73243.0 98660.0 101657.5 101771.0 102835.5 9832.7 9914.1 9915.7 9933.0 10103.9 18340.0 21715.3 22061.3 22164.7 22250.9 7760.8 7776.8 7820.1 7859.0 7969.8 4287.9 4290.5 4321.6 4387.4 4551.2 3478.2 3503.8 3533.5 3534.0 3565.1 620.1 627.6 638.6 646.4 654.1 602.7 620.6 667.7 676.9 689.5 9326.7 9420.9 9516.4 10297.9 10352.4 642.6 667.8 672.8 696.9 698.9 4967.7 5032.5 5035.7 5038.3 5047.1 63928.7 78435.6 80974.8 81172.2 97957.5 67870.5 69970.7 74714.0 77062.0 79189.6 58272.5 59227.7 62972.4 63762.0 65047.0 10540.3 11052.7 11198.9 11312.0 11330.8 45650.3 46123.1 46620.1 46837.3 47179.5 58368.1 59902.7 61280.0 61456.0 61807.0 10669.3 11211.2 12438.5 12812.2 12905.4 12379.7 12548.9 13804.3 14584.7 14966.4 12278.4 13617.7 13740.7 13743.7 13766.9 13672.5 13903.3 13970.8 13991.7 14050.7 13200.9 13394.5 13446.9 13663.0 13951.4 8017.8 9081.8 9128.0 9369.3 9630.8 10760.9 11120.9 11452.5 11472.9 11492.3 15:01:58 INFO - PROCESS | 1659 | -------- Summary: end -------- 15:01:58 INFO - PROCESS | 1659 | 15:01:59 INFO - TEST-INFO | 1659: exit 0 15:01:59 INFO - TEST-OK | dromaeo_css | took 1117845ms 15:01:59 INFO - TEST-START | kraken 15:01:59 INFO - operating with platform_type : mac_ 15:01:59 INFO - Initialising browser for kraken test... 15:01:59 INFO - TEST-INFO | started process 1668 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/tmpOPUIQi/profile http://localhost:49245/getInfo.html) 15:02:02 INFO - PROCESS | 1668 | 2017-04-08 15:02:02.602 plugin-container[1669:16657] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4647, name = 'com.apple.tsm.portname' 15:02:02 INFO - PROCESS | 1668 | See /usr/include/servers/bootstrap_defs.h for the error codes. 15:02:02 INFO - PROCESS | 1668 | 2017-04-08 15:02:02.608 plugin-container[1669:16657] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x901f, name = 'com.apple.CFPasteboardClient' 15:02:02 INFO - PROCESS | 1668 | See /usr/include/servers/bootstrap_defs.h for the error codes. 15:02:02 INFO - PROCESS | 1668 | 2017-04-08 15:02:02.608 plugin-container[1669:16657] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 15:02:02 INFO - PROCESS | 1668 | __metrics Screen width/height:1600/1200 15:02:02 INFO - PROCESS | 1668 | colorDepth:24 15:02:02 INFO - PROCESS | 1668 | Browser inner width/height: 1024/689 15:02:02 INFO - PROCESS | 1668 | __metrics 15:02:02 ERROR - PROCESS | 1668 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3360: TypeError: this.tabs is undefined 15:02:03 INFO - TEST-INFO | 1668: exit 0 15:02:03 INFO - Browser initialized. 15:02:03 INFO - Running cycle 1/1 for kraken test... 15:02:03 INFO - TEST-INFO | started process 1670 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/tmpOPUIQi/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 15:02:05 INFO - PROCESS | 1670 | 2017-04-08 15:02:05.938 plugin-container[1671:16782] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8837, name = 'com.apple.tsm.portname' 15:02:05 INFO - PROCESS | 1670 | See /usr/include/servers/bootstrap_defs.h for the error codes. 15:02:05 INFO - PROCESS | 1670 | 2017-04-08 15:02:05.938 plugin-container[1671:16782] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4607, name = 'com.apple.CFPasteboardClient' 15:02:05 INFO - PROCESS | 1670 | See /usr/include/servers/bootstrap_defs.h for the error codes. 15:02:05 INFO - PROCESS | 1670 | 2017-04-08 15:02:05.938 plugin-container[1671:16782] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 15:02:15 INFO - PROCESS | 1670 | RSS: Main: 220938240 15:02:15 INFO - PROCESS | 1670 | 15:02:18 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49245/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49245/tests/kraken/driver.html?testName=audio-beat-detection) 15:02:19 INFO - PROCESS | 1670 | RSS: Main: 235327488 15:02:19 INFO - PROCESS | 1670 | 15:02:22 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49245/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49245/tests/kraken/driver.html?testName=audio-dft) 15:02:22 INFO - PROCESS | 1670 | RSS: Main: 243269632 15:02:22 INFO - PROCESS | 1670 | 15:02:26 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49245/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49245/tests/kraken/driver.html?testName=audio-fft) 15:02:27 INFO - PROCESS | 1670 | RSS: Main: 244781056 15:02:27 INFO - PROCESS | 1670 | 15:02:28 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49245/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49245/tests/kraken/driver.html?testName=audio-oscillator) 15:02:29 INFO - PROCESS | 1670 | RSS: Main: 247603200 15:02:29 INFO - PROCESS | 1670 | 15:02:31 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49245/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49245/tests/kraken/driver.html?testName=imaging-gaussian-blur) 15:02:32 INFO - PROCESS | 1670 | RSS: Main: 248307712 15:02:32 INFO - PROCESS | 1670 | 15:02:35 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49245/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49245/tests/kraken/driver.html?testName=imaging-darkroom) 15:02:35 INFO - PROCESS | 1670 | RSS: Main: 252493824 15:02:35 INFO - PROCESS | 1670 | 15:02:39 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49245/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49245/tests/kraken/driver.html?testName=imaging-desaturate) 15:02:39 INFO - PROCESS | 1670 | RSS: Main: 249905152 15:02:39 INFO - PROCESS | 1670 | 15:02:43 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49245/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49245/tests/kraken/driver.html?testName=json-parse-financial) 15:02:43 INFO - PROCESS | 1670 | RSS: Main: 251199488 15:02:43 INFO - PROCESS | 1670 | 15:02:45 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49245/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49245/tests/kraken/driver.html?testName=json-stringify-tinderbox) 15:02:46 INFO - PROCESS | 1670 | RSS: Main: 250912768 15:02:46 INFO - PROCESS | 1670 | 15:02:47 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49245/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49245/tests/kraken/driver.html?testName=stanford-crypto-aes) 15:02:48 INFO - PROCESS | 1670 | RSS: Main: 250966016 15:02:48 INFO - PROCESS | 1670 | 15:02:50 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49245/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49245/tests/kraken/driver.html?testName=stanford-crypto-ccm) 15:02:51 INFO - PROCESS | 1670 | RSS: Main: 251006976 15:02:51 INFO - PROCESS | 1670 | 15:02:54 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49245/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49245/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 15:02:55 INFO - PROCESS | 1670 | RSS: Main: 251392000 15:02:55 INFO - PROCESS | 1670 | 15:02:58 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49245/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49245/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 15:02:58 INFO - PROCESS | 1670 | RSS: Main: 251199488 15:02:58 INFO - PROCESS | 1670 | 15:03:00 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49245/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49245/tests/kraken/driver.html?testName=ai-astar) 15:03:00 INFO - PROCESS | 1670 | RSS: Main: 253128704 15:03:00 INFO - PROCESS | 1670 | 15:03:00 INFO - PROCESS | 1670 | __start_tp_report 15:03:00 INFO - PROCESS | 1670 | _x_x_mozilla_page_load 15:03:00 INFO - PROCESS | 1670 | _x_x_mozilla_page_load_details 15:03:00 INFO - PROCESS | 1670 | |i|pagename|runs| 15:03:00 INFO - PROCESS | 1670 | |0;ai-astar;109;123;121;122;126;118;124;121;121;120 15:03:00 INFO - PROCESS | 1670 | |1;audio-beat-detection;182;128;111;145;119;139;112;113;130;123 15:03:00 INFO - PROCESS | 1670 | |2;audio-dft;204;197;193;199;196;223;211;278;273;194 15:03:00 INFO - PROCESS | 1670 | |3;audio-fft;80;62;61;87;60;60;62;61;88;89 15:03:00 INFO - PROCESS | 1670 | |4;audio-oscillator;92;72;86;73;72;71;84;71;72;83 15:03:00 INFO - PROCESS | 1670 | |5;imaging-gaussian-blur;89;90;89;89;104;89;98;135;100;89 15:03:00 INFO - PROCESS | 1670 | |6;imaging-darkroom;131;97;132;113;148;130;103;106;147;155 15:03:00 INFO - PROCESS | 1670 | |7;imaging-desaturate;90;93;82;144;143;93;146;146;91;103 15:03:00 INFO - PROCESS | 1670 | |8;json-parse-financial;66;66;66;67;70;70;70;69;70;69 15:03:00 INFO - PROCESS | 1670 | |9;json-stringify-tinderbox;58;62;64;60;58;58;71;50;52;53 15:03:00 INFO - PROCESS | 1670 | |10;stanford-crypto-aes;88;83;93;87;76;105;102;105;84;83 15:03:00 INFO - PROCESS | 1670 | |11;stanford-crypto-ccm;136;137;138;132;154;152;156;144;187;146 15:03:00 INFO - PROCESS | 1670 | |12;stanford-crypto-pbkdf2;162;182;167;168;169;164;173;166;163;168 15:03:00 INFO - PROCESS | 1670 | |13;stanford-crypto-sha256-iterative;68;68;67;66;66;67;62;65;66;66 15:03:00 INFO - PROCESS | 1670 | __end_tp_report 15:03:00 INFO - PROCESS | 1670 | __start_cc_report 15:03:00 INFO - PROCESS | 1670 | _x_x_mozilla_cycle_collect,1034 15:03:00 INFO - PROCESS | 1670 | __end_cc_report 15:03:00 INFO - PROCESS | 1670 | __startTimestamp1491688980679__endTimestamp 15:03:00 INFO - PROCESS | 1670 | 15:03:00 INFO - PROCESS | 1670 | ------- Summary: start ------- 15:03:00 INFO - PROCESS | 1670 | Number of tests: 14 15:03:00 INFO - PROCESS | 1670 | 15:03:00 INFO - PROCESS | 1670 | [#0] ai-astar Cycles:10 Average:120.50 Median:121.50 stddev:4.60 (3.8%) stddev-sans-first:2.33 15:03:00 INFO - PROCESS | 1670 | Values: 109.0 123.0 121.0 122.0 126.0 118.0 124.0 121.0 121.0 120.0 15:03:00 INFO - PROCESS | 1670 | 15:03:00 INFO - PROCESS | 1670 | [#1] audio-beat-detection Cycles:10 Average:130.20 Median:129.00 stddev:21.49 (16.7%) stddev-sans-first:12.13 15:03:00 INFO - PROCESS | 1670 | Values: 182.0 128.0 111.0 145.0 119.0 139.0 112.0 113.0 130.0 123.0 15:03:00 INFO - PROCESS | 1670 | 15:03:00 INFO - PROCESS | 1670 | [#2] audio-dft Cycles:10 Average:216.80 Median:207.50 stddev:32.26 (15.5%) stddev-sans-first:33.89 15:03:00 INFO - PROCESS | 1670 | Values: 204.0 197.0 193.0 199.0 196.0 223.0 211.0 278.0 273.0 194.0 15:03:00 INFO - PROCESS | 1670 | 15:03:00 INFO - PROCESS | 1670 | [#3] audio-fft Cycles:10 Average:71.00 Median:71.00 stddev:13.14 (18.5%) stddev-sans-first:13.53 15:03:00 INFO - PROCESS | 1670 | Values: 80.0 62.0 61.0 87.0 60.0 60.0 62.0 61.0 88.0 89.0 15:03:00 INFO - PROCESS | 1670 | 15:03:00 INFO - PROCESS | 1670 | [#4] audio-oscillator Cycles:10 Average:77.60 Median:78.00 stddev:7.82 (10.0%) stddev-sans-first:6.32 15:03:00 INFO - PROCESS | 1670 | Values: 92.0 72.0 86.0 73.0 72.0 71.0 84.0 71.0 72.0 83.0 15:03:00 INFO - PROCESS | 1670 | 15:03:00 INFO - PROCESS | 1670 | [#5] imaging-gaussian-blur Cycles:10 Average:97.20 Median:89.00 stddev:14.42 (16.2%) stddev-sans-first:14.99 15:03:00 INFO - PROCESS | 1670 | Values: 89.0 90.0 89.0 89.0 104.0 89.0 98.0 135.0 100.0 89.0 15:03:00 INFO - PROCESS | 1670 | 15:03:00 INFO - PROCESS | 1670 | [#6] imaging-darkroom Cycles:10 Average:126.20 Median:139.50 stddev:20.50 (14.7%) stddev-sans-first:21.67 15:03:00 INFO - PROCESS | 1670 | Values: 131.0 97.0 132.0 113.0 148.0 130.0 103.0 106.0 147.0 155.0 15:03:00 INFO - PROCESS | 1670 | 15:03:00 INFO - PROCESS | 1670 | [#7] imaging-desaturate Cycles:10 Average:113.10 Median:86.00 stddev:27.71 (32.2%) stddev-sans-first:28.11 15:03:00 INFO - PROCESS | 1670 | Values: 90.0 93.0 82.0 144.0 143.0 93.0 146.0 146.0 91.0 103.0 15:03:00 INFO - PROCESS | 1670 | 15:03:00 INFO - PROCESS | 1670 | [#8] json-parse-financial Cycles:10 Average:68.30 Median:69.50 stddev:1.83 (2.6%) stddev-sans-first:1.74 15:03:00 INFO - PROCESS | 1670 | Values: 66.0 66.0 66.0 67.0 70.0 70.0 70.0 69.0 70.0 69.0 15:03:00 INFO - PROCESS | 1670 | 15:03:00 INFO - PROCESS | 1670 | [#9] json-stringify-tinderbox Cycles:10 Average:58.60 Median:59.00 stddev:6.20 (10.5%) stddev-sans-first:6.58 15:03:00 INFO - PROCESS | 1670 | Values: 58.0 62.0 64.0 60.0 58.0 58.0 71.0 50.0 52.0 53.0 15:03:00 INFO - PROCESS | 1670 | 15:03:00 INFO - PROCESS | 1670 | [#10] stanford-crypto-aes Cycles:10 Average:90.60 Median:83.50 stddev:10.23 (12.3%) stddev-sans-first:10.81 15:03:00 INFO - PROCESS | 1670 | Values: 88.0 83.0 93.0 87.0 76.0 105.0 102.0 105.0 84.0 83.0 15:03:00 INFO - PROCESS | 1670 | 15:03:00 INFO - PROCESS | 1670 | [#11] stanford-crypto-ccm Cycles:10 Average:148.20 Median:149.00 stddev:15.91 (10.7%) stddev-sans-first:16.25 15:03:00 INFO - PROCESS | 1670 | Values: 136.0 137.0 138.0 132.0 154.0 152.0 156.0 144.0 187.0 146.0 15:03:00 INFO - PROCESS | 1670 | 15:03:00 INFO - PROCESS | 1670 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:168.20 Median:168.00 stddev:5.81 (3.5%) stddev-sans-first:5.71 15:03:00 INFO - PROCESS | 1670 | Values: 162.0 182.0 167.0 168.0 169.0 164.0 173.0 166.0 163.0 168.0 15:03:00 INFO - PROCESS | 1670 | 15:03:00 INFO - PROCESS | 1670 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.10 Median:66.50 stddev:1.73 (2.6%) stddev-sans-first:1.69 15:03:00 INFO - PROCESS | 1670 | Values: 68.0 68.0 67.0 66.0 66.0 67.0 62.0 65.0 66.0 66.0 15:03:00 INFO - PROCESS | 1670 | -------- Summary: end -------- 15:03:00 INFO - PROCESS | 1670 | 15:03:01 INFO - TEST-INFO | 1670: exit 0 15:03:01 INFO - TEST-OK | kraken | took 62166ms 15:03:01 INFO - SUITE-END | took 1180s 15:03:01 INFO - Completed test suite (00:19:40) 15:03:01 INFO - javascript benchmark 15:03:01 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7662.942168568002, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3455.4644030175314, 3475.3223121491305, 3488.9651103488973, 3510.666486683764, 3522.4364101743727, 6046.153538504607, 6058.757649694013, 6069.544784141185, 6098.298695650002, 6108.847278818032, 6047.697615119248, 6092.451679348857, 6124.663143527116, 6132.356102609228, 6159.5380346474085, 5495.477929596692, 5502.944970550303, 5516.503514683678, 5517.2551705519845, 5610.046292130348, 4523.434570678665, 5337.439568845273, 5588.133839254922, 5588.161775733653, 5622.465865742748, 5393.002294575496, 5560.6941618210985, 5572.693501857396, 5578.553715702734, 5598.692071936042, 5416.566674666017, 5475.425080366544, 5485.862853428656, 5492.286002819648, 5512.062949298629, 5445.863853403657, 5535.861603459905, 5548.278723765925, 5558.86102847428, 5569.916451253234, 6162.257795281102, 6178.127170865524, 6187.4740647045055, 6191.783287584958, 6194.101855230966, 6215.937840621581, 6221.191245138149, 6335.8416039598915, 6448.129502517147, 6494.480441564663, 6517.120188774549, 6562.803115906483, 6581.144451221358, 6586.538942274043, 6630.071789949411, 6209.472194863397, 6216.129741836146, 6219.284782250099, 6232.0963460298, 6234.532410069172, 6404.1674582304, 6628.734850606018, 6647.169103862017, 6734.797956061326, 6778.8305292367095, 6269.529785266123, 6288.591241569284, 6294.527910406738, 6314.242290925118, 6322.873542529123, 6552.8361790955605, 6556.508261880378, 6559.573627714183, 6563.737450501926, 6572.309907459744, 6390.7763228286785, 6402.327755585689, 6454.580452270681, 6460.289368169498, 6464.353564643592, 6213.1922850029205, 6244.500439964792, 6269.780557680459, 6382.425581697669, 6398.360163983658, 5110.131277682803, 5157.484251574888, 5240.135377662719, 5280.6303558750515, 5311.628186027018, 4631.976840115778, 4652.464966528825, 4661.627069834405, 4674.813007479731, 4676.976615116902, 6172.969135154295, 6174.783882564088, 6227.906581401283, 6247.406496382927, 6269.059641053878, 5359.383670877821, 6238.563300569007, 6294.992801151794, 6467.223933128054, 6508.479321654257, 6241.625502469867, 6243.0635404688755, 6243.719032643451, 6248.094026366151, 6262.5929314594405, 4975.776090075955, 5162.760937375003, 5805.564582656318, 5819.70901454934, 5846.56150788684, 5464.535514481314, 6463.515236357292, 6706.429953454011, 6718.630475323904, 6822.420094292041, 6594.769183078761, 6614.140161779131, 6615.536912416277, 6617.139771829631, 6617.139771829631, 6773.221079575715, 6775.119234499483, 6821.965890170716, 6845.931540684529, 6873.896891546531, 6238.500919926486, 6274.937250627618, 6333.493320534254, 6355.841103972438, 6470.029495575702, 4887.169181239269, 6534.869302613826, 6571.5399922005, 6594.571352862049, 6599.9010014848855, 6087.710036952616, 6342.460890824332, 6354.523410744305, 6383.0744542042075, 6386.105945167587, 6160.260768707604, 6189.938100618936, 6210.254769427697, 6297.968510157603, 6358.650274234961, 6120.17287048654, 6134.079888016833, 6223.688815559294, 6245.313015568193, 6269.247690277196, 6332.0818481320875, 6341.8731625368155, 6361.872762544631, 6363.745450181941, 6366.045093236052, 6113.0, 6122.173506576733, 6148.846278843064, 6166.321704612579, 6226.097215903759], "value": 5969.547237096946, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [696.7108649910288, 728.9088863892014, 809.3484744780451, 931.3945935142152, 933.5425641435698, 37237.25525489489, 37879.0, 39106.608933910655, 39138.21723565527, 39249.41125883114, 37964.620353796454, 38270.0, 38350.808245958804, 38371.808140959256, 38401.8878961657, 7871.330936870373, 8005.279524842763, 10738.248322617419, 11112.16658750593, 11147.108231341514, 10728.409937453436, 10784.838227426595, 10846.566137354497, 10847.945760271188, 10881.782364352708, 9194.12655797698, 10813.675589732282, 10854.620088296911, 10885.727856803564, 10914.399708016095, 10780.676579702622, 10798.568057277738, 10846.945765271203, 10851.077658399046, 10858.131349492021, 10480.266381353309, 10508.211884108723, 10551.78896422075, 10553.525091370906, 10575.312604680672, 34512.48231276533, 34623.65376346255, 34650.653493465, 34689.0, 34740.82629586861, 3708.49935258742, 3773.9622603773887, 3778.3576791945434, 3789.052736815796, 3795.6014618465215, 25259.115930942327, 25266.747332526622, 25378.492430151477, 26733.732662673512, 27004.45991080187, 3048.314129320885, 3055.8777648894015, 3127.155667969658, 3177.9046628601177, 3285.3100848821778, 359405.0, 359466.0, 364157.0, 381107.0, 392035.0, 43659.0, 43667.0, 43898.90252743643, 43908.341374879405, 43966.340504892454, 46041.5395846044, 46083.07833843371, 46162.538374616495, 46230.0, 46248.76875615668, 12741.872581274254, 12791.296478693575, 12828.55100071493, 12849.164804287691, 12854.421551030226, 10941.83587246192, 10964.56141754337, 10976.341419514854, 11001.394923279136, 11027.393493357782, 12024.87975120255, 12056.035517158607, 12063.0, 12079.818802717966, 12124.757504849853, 5167.958656330728, 5515.696636684941, 5543.334799824047, 5544.584156188222, 5569.248151499496, 1087.3747595132672, 1102.079763397572, 1106.7731115121542, 1121.3103941076179, 1181.722295260613, 19846.900765496368, 20716.482087947923, 26225.757136718938, 26309.81605827742, 26753.464930701277, 1058.862347894784, 1154.751728378386, 1225.9816102758466, 1245.0, 1269.8476182858117, 14621.034224331752, 15484.0, 15575.143367114917, 15692.607684807743, 15899.841001590068, 478145.0, 478435.60782195866, 482696.0, 482966.0, 483814.0, 52359.0, 52563.0, 52689.47310526922, 52749.736251319264, 52756.47243527516, 1471.2864260833487, 1481.8073650425376, 1504.4884739188728, 1510.4864346122151, 1514.6894886548232, 21562.814045227035, 27792.305192369542, 28761.568576470952, 28810.703518342143, 29528.0, 38367.61632383752, 48423.0, 48815.755921221586, 48953.755231223615, 49041.0, 480405.0, 486419.0, 489459.0, 491529.0, 492259.0, 31713.682863171074, 32380.190495237806, 34011.4898276531, 34850.82574587111, 35171.0, 31685.683143169197, 32038.83980580082, 38583.61416385912, 38834.4466221348, 39631.00922476961, 26967.595486068116, 27688.0, 28102.578461323505, 28296.009639662512, 28551.0, 22592.887035564716, 28268.85865570659, 28683.0, 28890.711092889644, 29153.125406237847, 32277.51583726293, 32713.34573308568, 32731.509027365086, 32758.689652413635, 32820.50769238511, 27293.863530682218, 27384.863075684494, 29192.124236272946, 29346.853265733534, 29405.55891661584, 21998.0, 22671.0, 22838.771612283665, 23257.886294777873, 23319.883400582887], "value": 18269.224842773867, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [413.7414116177389, 430.7781492531347, 435.515873015873, 437.9321205213191, 438.9934150987735, 18929.62140757184, 20020.399388018366, 20624.69062964057, 21348.46628834278, 21566.784332156672, 21346.46633834155, 21615.1353945842, 22109.778902210972, 22235.888820555876, 22546.774532254673, 6513.283538810727, 6812.046313516112, 6824.181098268215, 6846.144231971004, 6847.144106986627, 5605.943940560604, 6446.452051575622, 6615.569987950792, 6625.1387319648375, 6712.429443497309, 6447.742690175418, 6574.276829548746, 6681.198256209262, 6689.0, 6697.866042679144, 6661.733530658767, 6696.497762667819, 6698.966505167467, 6726.327367263283, 6749.831254218635, 6541.21505419348, 6726.058351830748, 7019.192792828839, 7178.353948144692, 7183.856322873566, 25340.239792806246, 25397.49205015908, 25552.48895022089, 25740.97036118572, 25837.870810645825, 2737.603047558113, 2776.6667999840147, 2818.436312737461, 2842.6730925943575, 2846.0, 13520.18878867271, 15513.224338783128, 15623.531294061197, 15771.369145234177, 15880.52358429249, 1825.799162092169, 2016.0826823795137, 2087.686846972951, 2092.089940875724, 2097.800708932649, 138560.0, 156451.0, 157945.21027394905, 164609.17695411446, 167654.0, 19698.01509924498, 20052.295554877997, 20177.99110044492, 20252.29116980928, 20864.895675521828, 31662.0, 31899.84050079735, 32102.87639932638, 32177.35645287128, 32317.838410808265, 9692.854607180898, 9870.358426702242, 10055.899441005642, 10212.897871021194, 10228.0, 6613.338666133348, 8104.432689711663, 8204.917950820416, 8355.331573474108, 8360.58197090143, 9515.857262141073, 9563.760905977268, 9599.856002159833, 9669.419834809934, 9718.9028109718, 3350.3801796667694, 3903.4144878268485, 3990.620891015349, 3999.300122478555, 4021.678265738734, 790.6837265094008, 944.2304521814837, 960.3853533738413, 990.895955924632, 996.1383403356045, 20099.698504522443, 20867.582648346946, 20997.370078897657, 21316.14735410597, 21356.893215534135, 945.8581212817995, 955.7562821480478, 976.8876579193342, 986.782907760306, 992.553350992042, 9450.763730906645, 10900.018998290192, 11005.34919762042, 11042.834357484644, 11082.889171108347, 746958.0, 747084.0, 748928.0, 758492.0, 772120.0, 130819.0, 132709.0, 146589.0, 161918.0, 168250.0, 1167.3054532553117, 1250.9249445033145, 1270.8284381608548, 1272.732726127505, 1278.1564167649308, 19569.60860782805, 20380.38858834237, 20471.0, 20598.0, 20611.86634735104, 56890.715546422005, 64021.679891600244, 64170.0, 66077.0, 68367.3163268361, 89507.0, 89518.55240723754, 90049.54975225082, 91413.0, 91502.08497915202, 9826.950865245628, 10874.347539147677, 11407.030402415887, 12028.879711202775, 12055.819162712742, 11060.0, 12740.872591273968, 12865.035122365673, 12994.285314307805, 13166.47334106625, 11222.775544489228, 11491.712707182387, 11526.250793698382, 11648.534058637559, 11651.941740291584, 10547.736306592396, 10620.256582039492, 10623.309484883457, 10755.19336049815, 10834.566617335533, 11342.209643020724, 11382.94308528452, 11542.480588373375, 11648.708782280171, 11747.530098795953, 9388.95305523468, 11539.88460115388, 11645.5341786331, 11679.824802627798, 11844.644660660195, 11142.27575207609, 11276.041536469493, 11360.886391135982, 11487.36819474937, 11602.709932251424], "value": 11486.405249028534, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1310.9541166059184, 1411.5483045425462, 1520.8631223189925, 1575.306864979409, 1584.9286782094807, 10614.628488002922, 11213.439328033597, 11216.831747523795, 11505.942470287637, 11656.125790565698, 11117.999380055793, 11221.60724374647, 11269.380184089872, 11286.097112231022, 11303.26528775629, 2390.3665528634924, 2436.281297017384, 2449.216250799745, 2450.7304196538366, 2456.955793787642, 2344.953100937989, 2420.0803694596116, 2425.684660994077, 2434.57394955882, 2436.305652888927, 2363.1492662641435, 2419.11702228687, 2426.3206302235403, 2439.4511234972165, 2440.7437219091917, 1822.9635407291846, 2340.32130682102, 2403.07481619576, 2408.1812183857573, 2415.685960825099, 2399.076355603088, 2399.3881560202035, 2415.6618073469554, 2416.226807421626, 2434.6469761884427, 10911.399873006982, 11058.22592418531, 11137.832932506019, 11166.944165279203, 11203.935626115506, 5858.326292476378, 5886.705664716747, 6010.0, 6048.546359023091, 6070.453659170696, 3524.6122926478065, 3632.328019316435, 3649.817509124533, 3698.389765688658, 3703.4444833274956, 1121.8822023687558, 1122.9730336742177, 1133.5012594458494, 1229.9877001229963, 1255.6358655989843, 13398.330083495885, 13604.795928061087, 14713.705725885422, 15436.691266174614, 15640.4525841595, 4022.27599032177, 4621.376114224604, 4665.020345727367, 4818.373611430492, 4829.9034019319415, 11583.957443830095, 11904.404779760976, 11904.583339583072, 11957.52169913211, 11978.161528692908, 4931.753412329369, 5940.524758019348, 5955.0, 5972.522198224132, 5974.492168165668, 5227.451117632594, 5241.868953276123, 5293.153095504701, 5299.947000530023, 5377.193420986883, 3859.749116307431, 3969.4641223435046, 3975.4633124528395, 4024.3158663027357, 4047.352423612208, 913.8720579118929, 1057.0539367266254, 1064.8668916385452, 1076.0960792933972, 1079.3577821196375, 925.2782829393084, 934.4626839567276, 941.1153515695224, 946.6260826973307, 993.6522217224058, 5592.0, 6538.803835884931, 6630.569013014138, 6700.430463410665, 6735.29279425663, 975.926805489577, 978.3396207559869, 992.2111921022804, 1004.8593196952432, 1032.6592224565875, 4075.5924407559005, 4149.647279981175, 4179.958200418017, 4273.615374616299, 4306.676999225071, 13419.329033548283, 14028.228447435285, 14083.507077252452, 14093.506727264701, 14102.0, 13831.100978436369, 13845.792313115311, 13989.790153147711, 13993.440262389391, 13998.930005350112, 8601.78495537604, 9607.51962401891, 10179.54192061359, 10223.284370094021, 10307.484625768682, 5887.411258874078, 6778.186617605918, 6940.02209370327, 7024.754133605299, 7076.398506127037, 11442.94278528602, 11678.182527223013, 12231.143819932518, 12231.204971676812, 12443.942264907584, 12707.174033687781, 12720.300383478998, 12721.0, 12751.362431878555, 12909.354532273537, 9239.953800230956, 9308.255339572954, 9345.766355841159, 9402.34183607141, 9466.810663786548, 10172.79654406893, 10374.481275936323, 10418.843717344094, 10421.895781042092, 10445.529951152064, 8898.866517002121, 8929.821403572023, 8947.59735811877, 9024.278057755233, 9067.68263110788, 8418.5790710463, 8498.022727386182, 8675.566221689016, 8752.431091979, 8755.387122901337, 7651.5409075455655, 7767.3786097113225, 7820.0, 7892.0529536456, 7894.1316455188735, 5914.881702365842, 5918.17145599625, 5943.881122377615, 6027.638341699512, 6062.636241825505, 5688.5733569981585, 5933.584649074523, 6076.453119219292, 6134.6625935573975, 6179.62922224668], "value": 5154.545642880196, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [358.3800025955098, 375.8271195250185, 384.7057001393934, 386.6539447194761, 393.69291952277234, 4391.978040109799, 4625.6299496040365, 5225.947740522593, 5284.920726189101, 5312.814051508198, 4353.6286069887965, 4777.822747872669, 4886.462489126193, 5253.007714137255, 5258.107081450675, 3749.381352076904, 3779.77157423837, 4160.356659119646, 4418.9337159942625, 4453.777311134447, 3455.2225749206355, 3851.265601422993, 3965.4646622705854, 4373.650107991353, 4404.639406987092, 3895.8636447724193, 3899.493065901429, 4472.875384192515, 4486.349479325496, 4489.4612646482465, 3611.9097022574383, 3836.7122465814896, 4229.196452673982, 4275.719486434251, 4415.668824838134, 3650.3064417760806, 3952.3873799561115, 3979.343408337621, 3989.900252493682, 4010.598940105995, 4923.77842997066, 4934.827281045146, 5166.302549155891, 5171.0, 5241.68549887008, 4347.239233134189, 4394.450693663292, 4567.314902764612, 5086.79652813887, 5125.974805039007, 4815.277708343777, 5364.383095943977, 5660.405657405995, 5684.0, 5700.862176683743, 3843.3082045231845, 4213.47331583552, 4438.977805110953, 4563.269876819693, 4591.586757191835, 5043.823466178703, 5733.168690539848, 5883.852903677357, 5989.825392541959, 6060.1516537456055, 3648.908777280589, 4161.292580261363, 4431.40176076232, 4611.7232966021475, 4667.323238130452, 4082.652974497201, 4322.0491491871735, 4346.6522678185665, 4722.645801564897, 4729.55069268419, 4205.684573656988, 4573.6569757268335, 4778.522147785263, 4869.366982292279, 5109.489051094861, 5011.4236862761245, 5222.895542089137, 5318.468153184651, 5400.110940646735, 5666.744996475169, 1499.9500349755215, 1513.7577987521943, 1571.4385551551127, 1581.8452529653418, 1596.8283134936478, 1055.0135623192339, 1104.7127746785893, 1125.5272785430138, 1136.5340210513816, 1212.5933916656636, 5358.5981051421295, 5362.812301569505, 5372.70450125239, 5389.655499857575, 5422.972885135549, 4887.877803054952, 5199.532042116228, 5369.946300537023, 5483.75323110461, 5616.085924815786, 4687.531246875285, 4828.5412885775795, 5237.397699264633, 5411.458854114557, 5449.373322067933, 2544.8091393145587, 2864.9856750716112, 2899.8405087719957, 3107.7979931304394, 3111.98444007783, 5116.513931176532, 5688.975984322861, 6141.324454310023, 6233.906491402542, 6284.748610055546, 5103.770330335144, 5728.169415434739, 5791.362950075489, 5804.182528501334, 5969.492813018543, 4980.402351717745, 5693.516051135617, 6085.04069183614, 6193.77024271271, 6221.253449585988, 5584.87247141602, 5907.0, 5981.820545383645, 5983.700814959322, 6057.878842423039, 5327.9733601331745, 5404.486573775485, 5555.638883472561, 5634.52106570946, 5870.911936321044, 5363.946360536501, 5398.919016214839, 6270.74917003333, 6275.6862156892885, 6279.529035322461, 3769.981150094232, 4144.5547127193895, 4361.168309310034, 4505.085338731092, 4658.277966915133, 4935.4570997190995, 5270.288511050959, 5305.946940530545, 5859.150423188699, 5860.2967643883, 5719.142128680731, 5726.828195154152, 5728.598998070095, 5766.933117373299, 5775.9133612995, 5031.6917601423165, 5403.216533602685, 5660.37735849048, 5691.8861622768145, 5735.311762588516, 5212.54048866318, 5320.388155362183, 5368.973155134199, 5557.972210138923, 5578.732269813515, 4995.650304478651, 5109.386873575195, 5554.277943867433, 5724.742386592697, 5741.827745167651, 4931.802727890988, 5078.974605127099, 5368.443151486025, 5495.422980586981, 5613.691246981455], "value": 4276.027147165596, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [702.4591064880045, 707.0772641702576, 725.7387340557399, 762.0893032825772, 808.4017826808162, 12700.110992230548, 15843.84156158438, 16215.675686486264, 16268.186590670448, 16305.75541366879, 12382.504699811996, 14907.627309317246, 14910.105393676387, 14920.701585968302, 15097.245137743124, 751.372603875763, 907.4691305586207, 929.3355250995537, 931.5575101826646, 932.361332487245, 934.2946075712828, 936.2135805923024, 936.4381371177274, 936.5832204668925, 937.2267878999818, 971.6696323250094, 975.395254941937, 975.8438649816031, 975.9219262458986, 976.0873583199731, 962.7689354554926, 969.1035791892542, 971.4220039076738, 972.4797233480055, 975.1077763846072, 635.1869606903125, 687.7077242172107, 718.4719231364943, 719.255570484546, 720.6828995242076, 12899.355032248444, 15421.306041228172, 15901.840981590152, 16471.34114635413, 16670.249838757285, 13844.10013349129, 14065.71868562623, 14105.64735881601, 14174.645633859132, 14239.288035598178, 11690.223058156744, 11691.181617286793, 11693.532258709727, 11715.0, 11782.469788859522, 3628.5827129880136, 3637.217998130415, 3652.8356223969718, 3700.5004324416127, 3837.7313588048846, 73243.0, 98660.0, 101657.49171254097, 101771.0, 102835.48582257041, 9832.705018849447, 9914.107730304164, 9915.652952146784, 9933.0, 10103.898961010444, 18340.0, 21715.34853954384, 22061.33815985523, 22164.667529987062, 22250.88874555649, 7760.844783104306, 7776.805579860549, 7820.139784623687, 7859.017622797151, 7969.760907172794, 4287.8851498609965, 4290.549492303326, 4321.632661223831, 4387.407699960529, 4551.180787458223, 3478.217401084786, 3503.7722548034294, 3533.5406397168204, 3533.982330088333, 3565.0552603560077, 620.1132380695558, 627.5920651576512, 638.6008744534665, 646.4375992886125, 654.0973456629821, 602.6831373449028, 620.5687047502033, 667.7262322447871, 676.8984652302194, 689.5276735436242, 9326.673566425143, 9420.858687119699, 9516.38143520669, 10297.9485102574, 10352.430616316175, 642.5662677692538, 667.7662818013656, 672.8116127484215, 696.9128858892639, 698.8742026435189, 4967.677100988424, 5032.496750325012, 5035.697858128524, 5038.345015148007, 5047.141985862412, 63928.68035659792, 78435.60782196053, 80974.78537821937, 81172.18827811646, 97957.5306370405, 67870.46202145646, 69970.65014674894, 74714.0, 77062.0, 79189.60405197938, 58272.54318642068, 59227.70386148042, 62972.370276297566, 63762.0, 65047.0, 10540.262181647211, 11052.723681908017, 11198.888011119785, 11312.0, 11330.773384532427, 45650.315245270685, 46123.07753844972, 46620.067598648515, 46837.2974405391, 47179.52820471889, 58368.12447813201, 59902.70048649903, 61280.0, 61456.0, 61807.0, 10669.253152279265, 11211.215214934877, 12438.502459901503, 12812.231266124063, 12905.419256133684, 12379.69050773738, 12548.874511254771, 13804.258029840019, 14584.68737813602, 14966.401343946556, 12278.386080695751, 13617.727645447234, 13740.65648358799, 13743.725125497234, 13766.862331376959, 13672.52146174879, 13903.304834758019, 13970.79043814364, 13991.720165596835, 14050.718985620435, 13200.933995329962, 13394.483508915531, 13446.865531344562, 13662.975276854475, 13951.441942322599, 8017.799555010938, 9081.77295567616, 9127.954360228156, 9369.297302702462, 9630.759231019281, 10760.946195268974, 11120.888791111985, 11452.484638191134, 11472.94263528677, 11492.310461372344], "value": 7332.591208805217, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1552.6, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [109.0, 123.0, 121.0, 122.0, 126.0, 118.0, 124.0, 121.0, 121.0, 120.0], "value": 120.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [182.0, 128.0, 111.0, 145.0, 119.0, 139.0, 112.0, 113.0, 130.0, 123.0], "value": 130.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [204.0, 197.0, 193.0, 199.0, 196.0, 223.0, 211.0, 278.0, 273.0, 194.0], "value": 216.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 62.0, 61.0, 87.0, 60.0, 60.0, 62.0, 61.0, 88.0, 89.0], "value": 71.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 72.0, 86.0, 73.0, 72.0, 71.0, 84.0, 71.0, 72.0, 83.0], "value": 77.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 90.0, 89.0, 89.0, 104.0, 89.0, 98.0, 135.0, 100.0, 89.0], "value": 97.2, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [131.0, 97.0, 132.0, 113.0, 148.0, 130.0, 103.0, 106.0, 147.0, 155.0], "value": 126.2, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 93.0, 82.0, 144.0, 143.0, 93.0, 146.0, 146.0, 91.0, 103.0], "value": 113.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 66.0, 66.0, 67.0, 70.0, 70.0, 70.0, 69.0, 70.0, 69.0], "value": 68.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 62.0, 64.0, 60.0, 58.0, 58.0, 71.0, 50.0, 52.0, 53.0], "value": 58.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 83.0, 93.0, 87.0, 76.0, 105.0, 102.0, 105.0, 84.0, 83.0], "value": 90.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [136.0, 137.0, 138.0, 132.0, 154.0, 152.0, 156.0, 144.0, 187.0, 146.0], "value": 148.2, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 182.0, 167.0, 168.0, 169.0, 164.0, 173.0, 166.0, 163.0, 168.0], "value": 168.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 68.0, 67.0, 66.0, 66.0, 67.0, 62.0, 65.0, 66.0, 66.0], "value": 66.1, "unit": "score"}]}]} 15:03:01 INFO - Return code: 0 15:03:02 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 15:03:02 ERROR - # TBPL SUCCESS # 15:03:02 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 15:03:02 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 15:03:02 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 15:03:02 INFO - 2017-04-08 15:03:02.040 screenresolution[1675:17160] starting screenresolution argv=screenresolution get 15:03:02 INFO - 2017-04-08 15:03:02.042 screenresolution[1675:17160] Display 0: 1600x1200x32@60 15:03:02 INFO - 2017-04-08 15:03:02.048 screenresolution[1676:17163] starting screenresolution argv=screenresolution list 15:03:02 INFO - Available Modes on Display 0 15:03:02 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 15:03:02 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 15:03:02 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 15:03:02 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 15:03:02 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 15:03:02 INFO - Intel Iris: 15:03:02 INFO - Chipset Model: Intel Iris 15:03:02 INFO - Type: GPU 15:03:02 INFO - Bus: Built-In 15:03:02 INFO - VRAM (Dynamic, Max): 1536 MB 15:03:02 INFO - Vendor: Intel (0x8086) 15:03:02 INFO - Device ID: 0x0a2e 15:03:02 INFO - Revision ID: 0x0009 15:03:02 INFO - Displays: 15:03:02 INFO - Display: 15:03:02 INFO - Resolution: 1600 x 1200 @ 60 Hz 15:03:02 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 15:03:02 INFO - Display Serial Number: 1600x1200 60 15:03:02 INFO - Main Display: Yes 15:03:02 INFO - Mirror: Off 15:03:02 INFO - Online: Yes 15:03:02 INFO - Rotation: Supported 15:03:02 INFO - Return code: 0 15:03:02 INFO - Running post-action listener: _resource_record_post_action 15:03:02 INFO - [mozharness: 2017-04-08 22:03:02.288388Z] Finished run-tests step (success) 15:03:02 INFO - Running post-run listener: _resource_record_post_run 15:03:02 INFO - Total resource usage - Wall time: 1201s; CPU: 54.0%; Read bytes: 34779648; Write bytes: 518041600; Read time: 316; Write time: 1569 15:03:02 INFO - TinderboxPrint: CPU usage
54.4% 15:03:02 INFO - TinderboxPrint: I/O read bytes / time
34,779,648 / 316 15:03:02 INFO - TinderboxPrint: I/O write bytes / time
518,041,600 / 1,569 15:03:02 INFO - TinderboxPrint: CPU idle
2,189.1 (45.6%) 15:03:02 INFO - TinderboxPrint: CPU system
959.7 (20.0%) 15:03:02 INFO - TinderboxPrint: CPU user
1,656.9 (34.5%) 15:03:02 INFO - TinderboxPrint: Swap in / out
238,886,912 / 0 15:03:02 INFO - install - Wall time: 20s; CPU: 54.0%; Read bytes: 203848192; Write bytes: 203543552; Read time: 15564; Write time: 424 15:03:02 INFO - run-tests - Wall time: 1181s; CPU: 54.0%; Read bytes: 32748032; Write bytes: 303627264; Read time: 295; Write time: 1110 15:03:02 INFO - Running post-run listener: _upload_blobber_files 15:03:02 INFO - Blob upload gear active. 15:03:02 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 15:03:02 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 15:03:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 15:03:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 15:03:03 INFO - (blobuploader) - INFO - Open directory for files ... 15:03:03 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 15:03:03 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:03:03 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:03:04 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 15:03:04 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:03:04 INFO - (blobuploader) - INFO - Done attempting. 15:03:04 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 15:03:04 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:03:04 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:03:05 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 15:03:05 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:03:05 INFO - (blobuploader) - INFO - Done attempting. 15:03:05 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 15:03:05 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:03:05 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:03:06 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 15:03:06 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:03:06 INFO - (blobuploader) - INFO - Done attempting. 15:03:06 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 15:03:06 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:03:06 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:03:09 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 15:03:09 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:03:09 INFO - (blobuploader) - INFO - Done attempting. 15:03:09 INFO - (blobuploader) - INFO - Iteration through files over. 15:03:09 INFO - Return code: 0 15:03:09 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 15:03:09 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 15:03:09 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/d4f4ffd8d8ccd5ef363f89bc222031ee6892cb2d7cfa3c022133b0483c59e600d617f853e63bd7fd86d64419e081132429e81b7f68983d43a2d4508499002aad", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/9586591bb2822464c37d802c1aad628cf78f4990dbcfed3d6c57554ce0f139fd8db5247d25325f02d5ec00fd1aae6e6924baa643cd435ee68a2cc6285555e298", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/42b7e464f88f759d70932901b61dfda4529e08c115ae4043850dd86e6c9cddd7c7cadabec21f01021cfc74c6b4f03baf41973aaf8db72d6d793be4ed18e06321", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/13119a1c2e8acefe67e9daf0dbec7a297fad23d9536c3bd35449e2a92c2ee7e73c04b23a6d83ba52636526cbfa33de90ee5bc0ec08ccd27ba637866f1ca3b7db"} 15:03:09 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 15:03:09 INFO - Writing to file /builds/slave/test/properties/blobber_files 15:03:09 INFO - Contents: 15:03:09 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/d4f4ffd8d8ccd5ef363f89bc222031ee6892cb2d7cfa3c022133b0483c59e600d617f853e63bd7fd86d64419e081132429e81b7f68983d43a2d4508499002aad", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/9586591bb2822464c37d802c1aad628cf78f4990dbcfed3d6c57554ce0f139fd8db5247d25325f02d5ec00fd1aae6e6924baa643cd435ee68a2cc6285555e298", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/42b7e464f88f759d70932901b61dfda4529e08c115ae4043850dd86e6c9cddd7c7cadabec21f01021cfc74c6b4f03baf41973aaf8db72d6d793be4ed18e06321", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/13119a1c2e8acefe67e9daf0dbec7a297fad23d9536c3bd35449e2a92c2ee7e73c04b23a6d83ba52636526cbfa33de90ee5bc0ec08ccd27ba637866f1ca3b7db"} 15:03:09 INFO - Running post-run listener: copy_logs_to_upload_dir 15:03:09 INFO - Copying logs to upload dir... 15:03:09 INFO - mkdir: /builds/slave/test/build/upload/logs 15:03:09 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1284.657430 ========= master_lag: 0.34 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 24 secs) (at 2017-04-08 15:03:09.384335) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-04-08 15:03:09.387954) ========= 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.we4Zb3T4P2/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.Eu1fNmFiJN/Listeners TMPDIR=/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/d4f4ffd8d8ccd5ef363f89bc222031ee6892cb2d7cfa3c022133b0483c59e600d617f853e63bd7fd86d64419e081132429e81b7f68983d43a2d4508499002aad", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/9586591bb2822464c37d802c1aad628cf78f4990dbcfed3d6c57554ce0f139fd8db5247d25325f02d5ec00fd1aae6e6924baa643cd435ee68a2cc6285555e298", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/42b7e464f88f759d70932901b61dfda4529e08c115ae4043850dd86e6c9cddd7c7cadabec21f01021cfc74c6b4f03baf41973aaf8db72d6d793be4ed18e06321", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/13119a1c2e8acefe67e9daf0dbec7a297fad23d9536c3bd35449e2a92c2ee7e73c04b23a6d83ba52636526cbfa33de90ee5bc0ec08ccd27ba637866f1ca3b7db"} build_url:https://queue.taskcluster.net/v1/task/TPpnrsxHSLmcU0b3Zbk9wA/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011520 build_url: 'https://queue.taskcluster.net/v1/task/TPpnrsxHSLmcU0b3Zbk9wA/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/d4f4ffd8d8ccd5ef363f89bc222031ee6892cb2d7cfa3c022133b0483c59e600d617f853e63bd7fd86d64419e081132429e81b7f68983d43a2d4508499002aad", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/9586591bb2822464c37d802c1aad628cf78f4990dbcfed3d6c57554ce0f139fd8db5247d25325f02d5ec00fd1aae6e6924baa643cd435ee68a2cc6285555e298", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/42b7e464f88f759d70932901b61dfda4529e08c115ae4043850dd86e6c9cddd7c7cadabec21f01021cfc74c6b4f03baf41973aaf8db72d6d793be4ed18e06321", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/13119a1c2e8acefe67e9daf0dbec7a297fad23d9536c3bd35449e2a92c2ee7e73c04b23a6d83ba52636526cbfa33de90ee5bc0ec08ccd27ba637866f1ca3b7db"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-04-08 15:03:09.428975) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-04-08 15:03:09.429402) ========= 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.we4Zb3T4P2/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.Eu1fNmFiJN/Listeners TMPDIR=/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/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.005166 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-04-08 15:03:09.478804) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-04-08 15:03:09.479211) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-04-08 15:03:09.479774) ========= ========= Total master_lag: 0.50 =========