builder: mozilla-central_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0151 starttime: 1483114136.53 results: success (0) buildid: 20161230070523 builduid: afb0b5352b8f4566975fca5d1460a192 revision: 21dc2d95071cb7712af8552e3880ec325b1069f1 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-12-30 08:08:56.527811) ========= master: http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-12-30 08:08:56.528553) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-30 08:08:56.528856) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-30 08:08:56.612914) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-30 08:08:56.613266) ========= 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.u2rbdYT9AW/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.B6pRCw4ESf/Listeners TMPDIR=/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-12-30 08:08:56-- 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% 12.2M=0.001s 2016-12-30 08:08:56 (12.2 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.169881 ========= master_lag: 0.07 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-30 08:08:56.854106) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-30 08:08:56.854537) ========= 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.u2rbdYT9AW/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.B6pRCw4ESf/Listeners TMPDIR=/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/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.027277 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-30 08:08:56.905770) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-12-30 08:08:56.906108) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 21dc2d95071cb7712af8552e3880ec325b1069f1 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev 21dc2d95071cb7712af8552e3880ec325b1069f1 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.u2rbdYT9AW/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.B6pRCw4ESf/Listeners TMPDIR=/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-12-30 08:08:57,010 truncating revision to first 12 chars 2016-12-30 08:08:57,010 Setting DEBUG logging. 2016-12-30 08:08:57,010 attempt 1/10 2016-12-30 08:08:57,010 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/21dc2d95071c?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-12-30 08:08:57,816 unpacking tar archive at: mozilla-central-21dc2d95071c/testing/mozharness/ program finished with exit code 0 elapsedTime=1.069046 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-12-30 08:08:58.002607) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-30 08:08:58.003379) ========= script_repo_revision: 21dc2d95071cb7712af8552e3880ec325b1069f1 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-30 08:08:58.004430) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-30 08:08:58.005169) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-30 08:08:58.026629) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 9 secs) (at 2016-12-30 08:08:58.026929) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.u2rbdYT9AW/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.B6pRCw4ESf/Listeners TMPDIR=/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/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 08:08:58 INFO - MultiFileLogger online at 20161230 08:08:58 in /builds/slave/test 08:08:58 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 08:08:58 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 08:08:58 INFO - {'append_to_log': False, 08:08:58 INFO - 'base_work_dir': '/builds/slave/test', 08:08:58 INFO - 'blob_upload_branch': 'Firefox', 08:08:58 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 08:08:58 INFO - 'branch': 'Firefox', 08:08:58 INFO - 'buildbot_json_path': 'buildprops.json', 08:08:58 INFO - 'config_files': ('talos/mac_config.py',), 08:08:58 INFO - 'default_actions': ('clobber', 08:08:58 INFO - 'read-buildbot-config', 08:08:58 INFO - 'download-and-extract', 08:08:58 INFO - 'populate-webroot', 08:08:58 INFO - 'create-virtualenv', 08:08:58 INFO - 'install', 08:08:58 INFO - 'run-tests'), 08:08:58 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 08:08:58 INFO - 'download_minidump_stackwalk': True, 08:08:58 INFO - 'download_symbols': 'ondemand', 08:08:58 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 08:08:58 INFO - 'tooltool.py': '/tools/tooltool.py', 08:08:58 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 08:08:58 INFO - '/tools/misc-python/virtualenv.py')}, 08:08:58 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 08:08:58 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 08:08:58 INFO - 'installer_path': 'installer.exe', 08:08:58 INFO - 'log_level': 'info', 08:08:58 INFO - 'log_name': 'talos', 08:08:58 INFO - 'log_to_console': True, 08:08:58 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 08:08:58 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 08:08:58 INFO - 'opt_config_files': (), 08:08:58 INFO - 'pip_index': False, 08:08:58 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 08:08:58 INFO - 'cmd': ('bash', 08:08:58 INFO - '-c', 08:08:58 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 08:08:58 INFO - 'enabled': True, 08:08:58 INFO - 'halt_on_failure': False, 08:08:58 INFO - 'name': 'check_screen_resolution'},), 08:08:58 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 08:08:58 INFO - 'cmd': ('bash', 08:08:58 INFO - '-c', 08:08:58 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 08:08:58 INFO - 'enabled': True, 08:08:58 INFO - 'halt_on_failure': False, 08:08:58 INFO - 'name': 'check_screen_resolution'},), 08:08:58 INFO - 'run_cmd_checks_enabled': True, 08:08:58 INFO - 'sps_profile': False, 08:08:58 INFO - 'sps_profile_interval': 0, 08:08:58 INFO - 'suite': 'dromaeojs', 08:08:58 INFO - 'system_bits': '32', 08:08:58 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 08:08:58 INFO - 'title': 't-yosemite-r7-0151', 08:08:58 INFO - 'tooltool_cache': '/builds/tooltool_cache', 08:08:58 INFO - 'use_talos_json': True, 08:08:58 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 08:08:58 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 08:08:58 INFO - 'work_dir': 'build'} 08:08:58 INFO - [mozharness: 2016-12-30 16:08:58.253128Z] Running clobber step. 08:08:58 INFO - Running pre-action listener: _resource_record_pre_action 08:08:58 INFO - Running main action method: clobber 08:08:58 INFO - rmtree: /builds/slave/test/build 08:08:58 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 08:09:00 INFO - Running post-action listener: _resource_record_post_action 08:09:00 INFO - [mozharness: 2016-12-30 16:09:00.580895Z] Finished clobber step (success) 08:09:00 INFO - [mozharness: 2016-12-30 16:09:00.581027Z] Running read-buildbot-config step. 08:09:00 INFO - Running pre-action listener: _resource_record_pre_action 08:09:00 INFO - Running main action method: read_buildbot_config 08:09:00 INFO - Using buildbot properties: 08:09:00 INFO - { 08:09:00 INFO - "project": "", 08:09:00 INFO - "product": "firefox", 08:09:00 INFO - "script_repo_revision": "production", 08:09:00 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs", 08:09:00 INFO - "repository": "", 08:09:00 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs", 08:09:00 INFO - "buildid": "20161230070523", 08:09:00 INFO - "pgo_build": "False", 08:09:00 INFO - "basedir": "/builds/slave/test", 08:09:00 INFO - "buildnumber": 65, 08:09:00 INFO - "slavename": "t-yosemite-r7-0151", 08:09:00 INFO - "master": "http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/", 08:09:00 INFO - "platform": "yosemite_r7", 08:09:00 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 08:09:00 INFO - "branch": "mozilla-central", 08:09:00 INFO - "repo_path": "mozilla-central", 08:09:00 INFO - "revision": "21dc2d95071cb7712af8552e3880ec325b1069f1", 08:09:00 INFO - "stage_platform": "macosx64", 08:09:00 INFO - "builduid": "afb0b5352b8f4566975fca5d1460a192", 08:09:00 INFO - "slavebuilddir": "test" 08:09:00 INFO - } 08:09:00 INFO - Found installer url https://queue.taskcluster.net/v1/task/aE6HW-0aQu2b659JrKFlgQ/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg. 08:09:00 INFO - Running post-action listener: _resource_record_post_action 08:09:00 INFO - [mozharness: 2016-12-30 16:09:00.584542Z] Finished read-buildbot-config step (success) 08:09:00 INFO - [mozharness: 2016-12-30 16:09:00.584640Z] Running download-and-extract step. 08:09:00 INFO - Running pre-action listener: _resource_record_pre_action 08:09:00 INFO - Running main action method: download_and_extract 08:09:00 INFO - mkdir: /builds/slave/test/build/tests 08:09: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')]} 08:09:00 INFO - trying https://queue.taskcluster.net/v1/task/aE6HW-0aQu2b659JrKFlgQ/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json 08:09:00 INFO - Downloading https://queue.taskcluster.net/v1/task/aE6HW-0aQu2b659JrKFlgQ/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json 08:09:00 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/aE6HW-0aQu2b659JrKFlgQ/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json'}, attempt #1 08:09:02 INFO - Downloaded 1199 bytes. 08:09:02 INFO - Reading from file /builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json 08:09:02 INFO - Using the following test package requirements: 08:09:02 INFO - {u'common': [u'firefox-53.0a1.en-US.mac.common.tests.zip'], 08:09:02 INFO - u'cppunittest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 08:09:02 INFO - u'firefox-53.0a1.en-US.mac.cppunittest.tests.zip'], 08:09:02 INFO - u'gtest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 08:09:02 INFO - u'firefox-53.0a1.en-US.mac.gtest.tests.zip'], 08:09:02 INFO - u'jittest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 08:09:02 INFO - u'jsshell-mac.zip'], 08:09:02 INFO - u'mochitest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 08:09:02 INFO - u'firefox-53.0a1.en-US.mac.mochitest.tests.zip'], 08:09:02 INFO - u'mozbase': [u'firefox-53.0a1.en-US.mac.common.tests.zip'], 08:09:02 INFO - u'reftest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 08:09:02 INFO - u'firefox-53.0a1.en-US.mac.reftest.tests.zip'], 08:09:02 INFO - u'talos': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 08:09:02 INFO - u'firefox-53.0a1.en-US.mac.talos.tests.zip'], 08:09:02 INFO - u'web-platform': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 08:09:02 INFO - u'firefox-53.0a1.en-US.mac.web-platform.tests.zip'], 08:09:02 INFO - u'xpcshell': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 08:09:02 INFO - u'firefox-53.0a1.en-US.mac.xpcshell.tests.zip']} 08:09:02 INFO - Downloading packages: [u'firefox-53.0a1.en-US.mac.common.tests.zip'] for test suite category: common 08:09:02 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/aE6HW-0aQu2b659JrKFlgQ/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip 08:09:02 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/aE6HW-0aQu2b659JrKFlgQ/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip'}, attempt #1 08:09:02 INFO - Fetch https://queue.taskcluster.net/v1/task/aE6HW-0aQu2b659JrKFlgQ/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip into memory 08:09:03 INFO - Expected file size: 16299110 08:09:03 INFO - Obtained file size: 16299110 08:09:09 INFO - Downloading packages: [u'firefox-53.0a1.en-US.mac.common.tests.zip', u'firefox-53.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 08:09:09 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/aE6HW-0aQu2b659JrKFlgQ/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip 08:09:09 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/aE6HW-0aQu2b659JrKFlgQ/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip'}, attempt #1 08:09:09 INFO - Fetch https://queue.taskcluster.net/v1/task/aE6HW-0aQu2b659JrKFlgQ/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip into memory 08:09:10 INFO - Expected file size: 16299110 08:09:10 INFO - Obtained file size: 16299110 08:09:16 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/aE6HW-0aQu2b659JrKFlgQ/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip 08:09:16 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/aE6HW-0aQu2b659JrKFlgQ/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip'}, attempt #1 08:09:16 INFO - Fetch https://queue.taskcluster.net/v1/task/aE6HW-0aQu2b659JrKFlgQ/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip into memory 08:09:17 INFO - Expected file size: 14409776 08:09:17 INFO - Obtained file size: 14409776 08:09:18 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')]} 08:09:18 INFO - trying https://queue.taskcluster.net/v1/task/aE6HW-0aQu2b659JrKFlgQ/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 08:09:18 INFO - Downloading https://queue.taskcluster.net/v1/task/aE6HW-0aQu2b659JrKFlgQ/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg 08:09:18 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/aE6HW-0aQu2b659JrKFlgQ/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg'}, attempt #1 08:09:22 INFO - Downloaded 69684627 bytes. 08:09:22 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/aE6HW-0aQu2b659JrKFlgQ/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 08:09:22 INFO - mkdir: /builds/slave/test/properties 08:09:22 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 08:09:22 INFO - Writing to file /builds/slave/test/properties/build_url 08:09:22 INFO - Contents: 08:09:22 INFO - build_url:https://queue.taskcluster.net/v1/task/aE6HW-0aQu2b659JrKFlgQ/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 08:09:23 INFO - Running post-action listener: _resource_record_post_action 08:09:23 INFO - Running post-action listener: set_extra_try_arguments 08:09:23 INFO - [mozharness: 2016-12-30 16:09:23.565764Z] Finished download-and-extract step (success) 08:09:23 INFO - [mozharness: 2016-12-30 16:09:23.565889Z] Running populate-webroot step. 08:09:23 INFO - Running pre-action listener: _resource_record_pre_action 08:09:23 INFO - Running main action method: populate_webroot 08:09:23 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 08:09:23 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 08:09:23 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 08:09:23 INFO - '--tppagecycles', 08:09:23 INFO - '7'], 08:09:23 INFO - 'tests': ['tsvgm']}}, 08:09:23 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 08:09:23 INFO - 'tests': ['tresize', 'tcanvasmark']}, 08:09:23 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 08:09:23 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 08:09:23 INFO - 'tests': ['dromaeo_css', 'kraken']}, 08:09:23 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 08:09:23 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 08:09:23 INFO - 'talos_options': ['--disable-e10s'], 08:09:23 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:09:23 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 08:09:23 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:09:23 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 08:09:23 INFO - 'talos_options': ['--disable-e10s'], 08:09:23 INFO - 'tests': ['damp', 'tps']}, 08:09:23 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 08:09:23 INFO - 'tests': ['damp', 'tps']}, 08:09:23 INFO - 'g3': {'talos_options': ['--disable-e10s'], 08:09:23 INFO - 'tests': ['dromaeo_dom']}, 08:09:23 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 08:09:23 INFO - 'g4': {'talos_options': ['--disable-e10s'], 08:09:23 INFO - 'tests': ['basic_compositor_video']}, 08:09:23 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 08:09:23 INFO - 'other': {'talos_options': ['--disable-e10s'], 08:09:23 INFO - 'tests': ['a11yr', 08:09:23 INFO - 'ts_paint', 08:09:23 INFO - 'tpaint', 08:09:23 INFO - 'sessionrestore', 08:09:23 INFO - 'sessionrestore_no_auto_restore', 08:09:23 INFO - 'tabpaint']}, 08:09:23 INFO - 'other-e10s': {'tests': ['a11yr', 08:09:23 INFO - 'ts_paint', 08:09:23 INFO - 'tpaint', 08:09:23 INFO - 'sessionrestore', 08:09:23 INFO - 'sessionrestore_no_auto_restore', 08:09:23 INFO - 'tabpaint']}, 08:09:23 INFO - 'other-e10s_l64': {'tests': ['a11yr', 08:09:23 INFO - 'ts_paint', 08:09:23 INFO - 'tpaint', 08:09:23 INFO - 'sessionrestore', 08:09:23 INFO - 'sessionrestore_no_auto_restore', 08:09:23 INFO - 'tabpaint']}, 08:09:23 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 08:09:23 INFO - 'ts_paint', 08:09:23 INFO - 'tpaint', 08:09:23 INFO - 'sessionrestore', 08:09:23 INFO - 'sessionrestore_no_auto_restore', 08:09:23 INFO - 'tabpaint']}, 08:09:23 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 08:09:23 INFO - 'tests': ['a11yr', 08:09:23 INFO - 'ts_paint', 08:09:23 INFO - 'tpaint', 08:09:23 INFO - 'sessionrestore', 08:09:23 INFO - 'sessionrestore_no_auto_restore', 08:09:23 INFO - 'tabpaint']}, 08:09:23 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 08:09:23 INFO - 'tests': ['a11yr', 08:09:23 INFO - 'ts_paint', 08:09:23 INFO - 'tpaint', 08:09:23 INFO - 'sessionrestore', 08:09:23 INFO - 'sessionrestore_no_auto_restore', 08:09:23 INFO - 'tabpaint']}, 08:09:23 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 08:09:23 INFO - 'tests': ['tsvgx', 08:09:23 INFO - 'tsvgr_opacity', 08:09:23 INFO - 'tart', 08:09:23 INFO - 'tscrollx', 08:09:23 INFO - 'cart', 08:09:23 INFO - 'tsvg_static']}, 08:09:23 INFO - 'svgr-e10s': {'tests': ['tsvgx', 08:09:23 INFO - 'tsvgr_opacity', 08:09:23 INFO - 'tart', 08:09:23 INFO - 'tscrollx', 08:09:23 INFO - 'cart', 08:09:23 INFO - 'tsvg_static']}, 08:09:23 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 08:09:23 INFO - 'talos_options': ['--disable-e10s'], 08:09:23 INFO - 'tests': ['tp5o']}, 08:09:23 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 08:09:23 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 08:09:23 INFO - 'talos_options': ['--disable-e10s', 08:09:23 INFO - '--xperf_path', 08:09:23 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:09:23 INFO - 'tests': ['tp5n']}, 08:09:23 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 08:09:23 INFO - 'talos_options': ['--xperf_path', 08:09:23 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:09:23 INFO - 'tests': ['tp5n']}}, 08:09:23 INFO - 'talos.zip': {'path': '', 08:09:23 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 08:09:23 INFO - Running post-action listener: _resource_record_post_action 08:09:23 INFO - [mozharness: 2016-12-30 16:09:23.584922Z] Finished populate-webroot step (success) 08:09:23 INFO - [mozharness: 2016-12-30 16:09:23.585055Z] Running create-virtualenv step. 08:09:23 INFO - Running pre-action listener: _resource_record_pre_action 08:09:23 INFO - Running main action method: create_virtualenv 08:09:23 INFO - Creating virtualenv /builds/slave/test/build/venv 08:09:23 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 08:09:23 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 08:09:23 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 08:09:23 INFO - Using real prefix '/tools/python27' 08:09:23 INFO - New python executable in /builds/slave/test/build/venv/bin/python 08:09:24 INFO - Installing distribute.............................................................................................................................................................................................done. 08:09:27 INFO - Installing pip.................done. 08:09:27 INFO - Return code: 0 08:09:27 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 08:09:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 08:09:27 INFO - Reading from file tmpfile_stdout 08:09:27 INFO - Output received: 08:09:27 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) 08:09:27 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 08:09:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:09:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:09:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:09:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:09:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:09:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:09:27 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 0x10e9fdb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ea00768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcdb0ccdf10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e7dbd30>, '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 0x10e702db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e7d0be8>, '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.u2rbdYT9AW/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.B6pRCw4ESf/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/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 08:09:27 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 08:09:27 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 08:09:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.u2rbdYT9AW/Render', 08:09:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:09:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:09:27 INFO - 'HOME': '/Users/cltbld', 08:09:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:09:27 INFO - 'LOGNAME': 'cltbld', 08:09:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:09:27 INFO - 'MOZ_NO_REMOTE': '1', 08:09:27 INFO - 'NO_EM_RESTART': '1', 08:09:27 INFO - 'PAGER': '/bin/cat', 08:09:27 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:09:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:09:27 INFO - 'PWD': '/builds/slave/test', 08:09:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:09:27 INFO - 'SHELL': '/bin/bash', 08:09:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.B6pRCw4ESf/Listeners', 08:09:27 INFO - 'TMPDIR': '/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/', 08:09:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:09:27 INFO - 'USER': 'cltbld', 08:09:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:09:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:09:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:09:27 INFO - 'XPC_FLAGS': '0x0', 08:09:27 INFO - 'XPC_SERVICE_NAME': '0', 08:09:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:09:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:09:27 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 08:09:27 INFO - Cleaning up... 08:09:27 INFO - Return code: 0 08:09:27 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 08:09:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:09:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:09:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:09:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:09:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:09:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:09:27 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 0x10e9fdb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ea00768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcdb0ccdf10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e7dbd30>, '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 0x10e702db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e7d0be8>, '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.u2rbdYT9AW/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.B6pRCw4ESf/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/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 08:09:27 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 08:09:27 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 08:09:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.u2rbdYT9AW/Render', 08:09:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:09:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:09:27 INFO - 'HOME': '/Users/cltbld', 08:09:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:09:27 INFO - 'LOGNAME': 'cltbld', 08:09:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:09:27 INFO - 'MOZ_NO_REMOTE': '1', 08:09:27 INFO - 'NO_EM_RESTART': '1', 08:09:27 INFO - 'PAGER': '/bin/cat', 08:09:27 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:09:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:09:27 INFO - 'PWD': '/builds/slave/test', 08:09:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:09:27 INFO - 'SHELL': '/bin/bash', 08:09:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.B6pRCw4ESf/Listeners', 08:09:27 INFO - 'TMPDIR': '/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/', 08:09:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:09:27 INFO - 'USER': 'cltbld', 08:09:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:09:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:09:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:09:27 INFO - 'XPC_FLAGS': '0x0', 08:09:27 INFO - 'XPC_SERVICE_NAME': '0', 08:09:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:09:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:09:28 INFO - Downloading/unpacking psutil>=3.1.1 08:09:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:09:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:09:28 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:09:28 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:09:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:09:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:09:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 08:09:32 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:09:32 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:09:32 INFO - Installing collected packages: psutil 08:09:32 INFO - Running setup.py install for psutil 08:09:32 INFO - building 'psutil._psutil_osx' extension 08:09:32 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 08:09:32 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 08:09:32 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 08:09:32 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 08:09:33 INFO - building 'psutil._psutil_posix' extension 08:09:33 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 08:09:33 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 08:09:33 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 08:09:33 INFO - ^ 08:09:33 INFO - 1 warning generated. 08:09:33 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 08:09:33 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:09:33 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:09:33 INFO - Successfully installed psutil 08:09:33 INFO - Cleaning up... 08:09:33 INFO - Return code: 0 08:09:33 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 08:09:33 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')]} 08:09:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:09:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:09:33 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')]} 08:09:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:09:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:09:33 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 0x10e9fdb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ea00768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcdb0ccdf10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e7dbd30>, '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 0x10e702db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e7d0be8>, '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.u2rbdYT9AW/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.B6pRCw4ESf/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/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 08:09:33 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 08:09:33 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 08:09:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.u2rbdYT9AW/Render', 08:09:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:09:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:09:33 INFO - 'HOME': '/Users/cltbld', 08:09:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:09:33 INFO - 'LOGNAME': 'cltbld', 08:09:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:09:33 INFO - 'MOZ_NO_REMOTE': '1', 08:09:33 INFO - 'NO_EM_RESTART': '1', 08:09:33 INFO - 'PAGER': '/bin/cat', 08:09:33 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:09:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:09:33 INFO - 'PWD': '/builds/slave/test', 08:09:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:09:33 INFO - 'SHELL': '/bin/bash', 08:09:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.B6pRCw4ESf/Listeners', 08:09:33 INFO - 'TMPDIR': '/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/', 08:09:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:09:33 INFO - 'USER': 'cltbld', 08:09:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:09:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:09:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:09:33 INFO - 'XPC_FLAGS': '0x0', 08:09:33 INFO - 'XPC_SERVICE_NAME': '0', 08:09:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:09:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:09:33 INFO - Downloading/unpacking mozsystemmonitor==0.3 08:09:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:09:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:09:33 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:09:33 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:09:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:09:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:09:36 INFO - Downloading mozsystemmonitor-0.3.tar.gz 08:09:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 08:09:36 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 08:09:36 INFO - Installing collected packages: mozsystemmonitor 08:09:36 INFO - Running setup.py install for mozsystemmonitor 08:09:36 INFO - Successfully installed mozsystemmonitor 08:09:36 INFO - Cleaning up... 08:09:36 INFO - Return code: 0 08:09:36 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 08:09:36 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')]} 08:09:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:09:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:09:36 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')]} 08:09:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:09:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:09:36 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 0x10e9fdb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ea00768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcdb0ccdf10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e7dbd30>, '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 0x10e702db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e7d0be8>, '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.u2rbdYT9AW/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.B6pRCw4ESf/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/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 08:09:36 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 08:09:36 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 08:09:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.u2rbdYT9AW/Render', 08:09:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:09:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:09:36 INFO - 'HOME': '/Users/cltbld', 08:09:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:09:36 INFO - 'LOGNAME': 'cltbld', 08:09:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:09:36 INFO - 'MOZ_NO_REMOTE': '1', 08:09:36 INFO - 'NO_EM_RESTART': '1', 08:09:36 INFO - 'PAGER': '/bin/cat', 08:09:36 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:09:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:09:36 INFO - 'PWD': '/builds/slave/test', 08:09:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:09:36 INFO - 'SHELL': '/bin/bash', 08:09:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.B6pRCw4ESf/Listeners', 08:09:36 INFO - 'TMPDIR': '/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/', 08:09:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:09:36 INFO - 'USER': 'cltbld', 08:09:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:09:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:09:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:09:36 INFO - 'XPC_FLAGS': '0x0', 08:09:36 INFO - 'XPC_SERVICE_NAME': '0', 08:09:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:09:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:09:36 INFO - Downloading/unpacking jsonschema==2.5.1 08:09:36 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 08:09:36 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 08:09:36 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 08:09:36 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 08:09:36 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 08:09:36 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 08:09:39 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 08:09:39 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 08:09:39 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 08:09:39 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 08:09:39 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 08:09:39 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 08:09:39 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 08:09:39 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 08:09:39 INFO - Downloading functools32-3.2.3-2.tar.gz 08:09:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 08:09:39 INFO - warning: no files found matching '*.txt' 08:09:39 INFO - no previously-included directories found matching 'build' 08:09:39 INFO - no previously-included directories found matching 'dist' 08:09:39 INFO - no previously-included directories found matching '.git*' 08:09:39 INFO - Installing collected packages: jsonschema, functools32 08:09:39 INFO - Running setup.py install for functools32 08:09:40 INFO - warning: no files found matching '*.txt' 08:09:40 INFO - no previously-included directories found matching 'build' 08:09:40 INFO - no previously-included directories found matching 'dist' 08:09:40 INFO - no previously-included directories found matching '.git*' 08:09:40 INFO - Successfully installed jsonschema functools32 08:09:40 INFO - Cleaning up... 08:09:40 INFO - Return code: 0 08:09:40 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 08:09: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')]} 08:09:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:09:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:09: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')]} 08:09:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:09:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:09: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', '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 0x10e9fdb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ea00768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcdb0ccdf10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e7dbd30>, '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 0x10e702db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e7d0be8>, '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.u2rbdYT9AW/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.B6pRCw4ESf/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/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 08:09: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', 'functools32==3.2.3-2'] in /builds/slave/test/build 08:09: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 functools32==3.2.3-2 08:09:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.u2rbdYT9AW/Render', 08:09:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:09:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:09:40 INFO - 'HOME': '/Users/cltbld', 08:09:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:09:40 INFO - 'LOGNAME': 'cltbld', 08:09:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:09:40 INFO - 'MOZ_NO_REMOTE': '1', 08:09:40 INFO - 'NO_EM_RESTART': '1', 08:09:40 INFO - 'PAGER': '/bin/cat', 08:09:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:09:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:09:40 INFO - 'PWD': '/builds/slave/test', 08:09:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:09:40 INFO - 'SHELL': '/bin/bash', 08:09:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.B6pRCw4ESf/Listeners', 08:09:40 INFO - 'TMPDIR': '/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/', 08:09:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:09:40 INFO - 'USER': 'cltbld', 08:09:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:09:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:09:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:09:40 INFO - 'XPC_FLAGS': '0x0', 08:09:40 INFO - 'XPC_SERVICE_NAME': '0', 08:09:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:09:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:09:40 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 08:09:40 INFO - Cleaning up... 08:09:40 INFO - Return code: 0 08:09:40 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 08:09: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')]} 08:09:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:09:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:09: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')]} 08:09:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:09:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:09: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', '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 0x10e9fdb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ea00768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcdb0ccdf10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e7dbd30>, '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 0x10e702db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e7d0be8>, '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.u2rbdYT9AW/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.B6pRCw4ESf/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/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 08:09: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', 'blobuploader==1.2.4'] in /builds/slave/test/build 08:09: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 blobuploader==1.2.4 08:09:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.u2rbdYT9AW/Render', 08:09:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:09:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:09:40 INFO - 'HOME': '/Users/cltbld', 08:09:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:09:40 INFO - 'LOGNAME': 'cltbld', 08:09:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:09:40 INFO - 'MOZ_NO_REMOTE': '1', 08:09:40 INFO - 'NO_EM_RESTART': '1', 08:09:40 INFO - 'PAGER': '/bin/cat', 08:09:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:09:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:09:40 INFO - 'PWD': '/builds/slave/test', 08:09:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:09:40 INFO - 'SHELL': '/bin/bash', 08:09:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.B6pRCw4ESf/Listeners', 08:09:40 INFO - 'TMPDIR': '/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/', 08:09:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:09:40 INFO - 'USER': 'cltbld', 08:09:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:09:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:09:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:09:40 INFO - 'XPC_FLAGS': '0x0', 08:09:40 INFO - 'XPC_SERVICE_NAME': '0', 08:09:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:09:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:09:40 INFO - Downloading/unpacking blobuploader==1.2.4 08:09:40 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 08:09:40 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 08:09:40 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 08:09:40 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 08:09:40 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 08:09:40 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 08:09:43 INFO - Downloading blobuploader-1.2.4.tar.gz 08:09:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 08:09:43 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 08:09:43 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 08:09:43 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 08:09:43 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 08:09:43 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 08:09:43 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 08:09:43 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 08:09:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 08:09:44 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 08:09: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 08:09: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 08:09: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 08:09: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 08:09: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 08:09: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 08:09:44 INFO - Downloading docopt-0.6.1.tar.gz 08:09:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 08:09:44 INFO - Installing collected packages: blobuploader, requests, docopt 08:09:44 INFO - Running setup.py install for blobuploader 08:09:44 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 08:09:44 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 08:09:44 INFO - Running setup.py install for requests 08:09:45 INFO - Running setup.py install for docopt 08:09:45 INFO - Successfully installed blobuploader requests docopt 08:09:45 INFO - Cleaning up... 08:09:45 INFO - Return code: 0 08:09:45 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:09: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')]} 08:09:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:09:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:09: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')]} 08:09:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:09:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:09:45 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 0x10e9fdb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ea00768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcdb0ccdf10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e7dbd30>, '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 0x10e702db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e7d0be8>, '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.u2rbdYT9AW/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.B6pRCw4ESf/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/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 08:09:45 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 08:09:45 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 08:09:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.u2rbdYT9AW/Render', 08:09:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:09:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:09:45 INFO - 'HOME': '/Users/cltbld', 08:09:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:09:45 INFO - 'LOGNAME': 'cltbld', 08:09:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:09:45 INFO - 'MOZ_NO_REMOTE': '1', 08:09:45 INFO - 'NO_EM_RESTART': '1', 08:09:45 INFO - 'PAGER': '/bin/cat', 08:09:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:09:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:09:45 INFO - 'PWD': '/builds/slave/test', 08:09:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:09:45 INFO - 'SHELL': '/bin/bash', 08:09:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.B6pRCw4ESf/Listeners', 08:09:45 INFO - 'TMPDIR': '/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/', 08:09:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:09:45 INFO - 'USER': 'cltbld', 08:09:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:09:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:09:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:09:45 INFO - 'XPC_FLAGS': '0x0', 08:09:45 INFO - 'XPC_SERVICE_NAME': '0', 08:09:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:09:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:09:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:09:45 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-l5E3qX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:09:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:09:45 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-koXicE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:09:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:09:45 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-8g4JGL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:09:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:09:46 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-Kad2l2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:09:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:09:46 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-rf84gg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:09:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:09:46 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-vRaAPU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:09:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:09:46 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-UxCHnW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:09:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:09:46 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-R7yTtK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:09:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:09:46 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-UNAN8c-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:09:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:09:46 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-UWTiUR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:09:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:09:46 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-ltmnYS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:09:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:09:46 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-BpJABH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:09:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:09:47 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-pOImg3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:09:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:09:47 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-cg4y0L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:09:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:09:47 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-eC7s6t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:09:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:09:47 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-_qe6UV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:09:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:09:47 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-OEu3cg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:09:47 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 08:09:47 INFO - Running setup.py install for manifestparser 08:09:47 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 08:09:47 INFO - Running setup.py install for mozcrash 08:09:47 INFO - Running setup.py install for mozdebug 08:09:47 INFO - Running setup.py install for mozdevice 08:09:48 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 08:09:48 INFO - Installing dm script to /builds/slave/test/build/venv/bin 08:09:48 INFO - Running setup.py install for mozfile 08:09:48 INFO - Running setup.py install for mozhttpd 08:09:48 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 08:09:48 INFO - Running setup.py install for mozinfo 08:09:48 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 08:09:48 INFO - Running setup.py install for mozInstall 08:09:48 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 08:09:48 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 08:09:48 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 08:09:48 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 08:09:48 INFO - Running setup.py install for mozleak 08:09:48 INFO - Running setup.py install for mozlog 08:09:49 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 08:09:49 INFO - Running setup.py install for moznetwork 08:09:49 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 08:09:49 INFO - Running setup.py install for mozprocess 08:09:49 INFO - Running setup.py install for mozprofile 08:09:49 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 08:09:49 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 08:09:49 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 08:09:49 INFO - Running setup.py install for mozrunner 08:09:49 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 08:09:49 INFO - Running setup.py install for mozscreenshot 08:09:49 INFO - Running setup.py install for moztest 08:09:49 INFO - Running setup.py install for mozversion 08:09:50 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 08:09:50 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 08:09:50 INFO - Cleaning up... 08:09:50 INFO - Return code: 0 08:09:50 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:09: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')]} 08:09:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:09:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:09: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')]} 08:09:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:09:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:09:50 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 0x10e9fdb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ea00768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcdb0ccdf10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e7dbd30>, '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 0x10e702db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e7d0be8>, '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.u2rbdYT9AW/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.B6pRCw4ESf/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/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 08:09:50 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 08:09:50 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 08:09:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.u2rbdYT9AW/Render', 08:09:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:09:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:09:50 INFO - 'HOME': '/Users/cltbld', 08:09:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:09:50 INFO - 'LOGNAME': 'cltbld', 08:09:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:09:50 INFO - 'MOZ_NO_REMOTE': '1', 08:09:50 INFO - 'NO_EM_RESTART': '1', 08:09:50 INFO - 'PAGER': '/bin/cat', 08:09:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:09:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:09:50 INFO - 'PWD': '/builds/slave/test', 08:09:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:09:50 INFO - 'SHELL': '/bin/bash', 08:09:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.B6pRCw4ESf/Listeners', 08:09:50 INFO - 'TMPDIR': '/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/', 08:09:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:09:50 INFO - 'USER': 'cltbld', 08:09:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:09:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:09:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:09:50 INFO - 'XPC_FLAGS': '0x0', 08:09:50 INFO - 'XPC_SERVICE_NAME': '0', 08:09:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:09:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:09:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:09:50 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-2GPEYz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:09:50 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)) 08:09:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:09:50 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-6lHUzF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:09:50 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)) 08:09:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:09:50 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-LmbM6P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:09:50 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)) 08:09:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:09:50 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-0TnOB1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:09:50 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)) 08:09:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:09:50 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-HLfdk9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:09:50 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)) 08:09:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:09:50 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-SCluAY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:09:51 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)) 08:09:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:09:51 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-Y9kOax-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:09:51 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)) 08:09:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:09:51 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-CFzomZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:09:51 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)) 08:09:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:09:51 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-05COp1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:09:51 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)) 08:09:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:09:51 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-yrC4uY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:09:51 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)) 08:09:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:09:51 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-Z0eDMX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:09:51 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)) 08:09:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:09:51 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-HoVjDw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:09:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 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)) 08:09:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:09:51 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-RdyLTP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:09:51 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)) 08:09:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:09:51 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-IfFbs4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:09:51 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)) 08:09:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:09:51 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-CgrCDk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:09:51 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)) 08:09:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:09:51 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-TB9hoi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:09:52 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)) 08:09:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:09:52 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-kqNhru-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:09:52 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)) 08:09:52 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)) 08:09:52 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)) 08:09:52 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)) 08:09:52 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)) 08:09:52 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)) 08:09:52 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 08:09:52 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 08:09:52 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 08:09:52 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 08:09:52 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 08:09:52 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 08:09:52 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 08:09:54 INFO - Downloading blessings-1.6.tar.gz 08:09:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 08:09:54 INFO - Installing collected packages: blessings 08:09:54 INFO - Running setup.py install for blessings 08:09:55 INFO - Successfully installed blessings 08:09:55 INFO - Cleaning up... 08:09:55 INFO - Return code: 0 08:09:55 INFO - Done creating virtualenv /builds/slave/test/build/venv. 08:09:55 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:09:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:09:55 INFO - Reading from file tmpfile_stdout 08:09:55 INFO - Current package versions: 08:09:55 INFO - blessings == 1.6 08:09:55 INFO - blobuploader == 1.2.4 08:09:55 INFO - docopt == 0.6.1 08:09:55 INFO - functools32 == 3.2.3-2 08:09:55 INFO - jsonschema == 2.5.1 08:09:55 INFO - manifestparser == 1.1 08:09:55 INFO - mozInstall == 1.12 08:09:55 INFO - mozcrash == 1.0 08:09:55 INFO - mozdebug == 0.1 08:09:55 INFO - mozdevice == 0.49 08:09:55 INFO - mozfile == 1.2 08:09:55 INFO - mozhttpd == 0.7 08:09:55 INFO - mozinfo == 0.9 08:09:55 INFO - mozleak == 0.1 08:09:55 INFO - mozlog == 3.4 08:09:55 INFO - moznetwork == 0.27 08:09:55 INFO - mozprocess == 0.23 08:09:55 INFO - mozprofile == 0.28 08:09:55 INFO - mozrunner == 6.13 08:09:55 INFO - mozscreenshot == 0.1 08:09:55 INFO - mozsystemmonitor == 0.3 08:09:55 INFO - moztest == 0.8 08:09:55 INFO - mozversion == 1.4 08:09:55 INFO - psutil == 3.1.1 08:09:55 INFO - requests == 1.2.3 08:09:55 INFO - wsgiref == 0.1.2 08:09:55 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:09:55 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')]} 08:09:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:09:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:09:55 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')]} 08:09:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:09:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:09:55 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 0x10e9fdb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ea00768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcdb0ccdf10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e7dbd30>, '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 0x10e702db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e7d0be8>, '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.u2rbdYT9AW/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.B6pRCw4ESf/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/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 08:09:55 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 08:09:55 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 08:09:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.u2rbdYT9AW/Render', 08:09:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:09:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:09:55 INFO - 'HOME': '/Users/cltbld', 08:09:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:09:55 INFO - 'LOGNAME': 'cltbld', 08:09:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:09:55 INFO - 'MOZ_NO_REMOTE': '1', 08:09:55 INFO - 'NO_EM_RESTART': '1', 08:09:55 INFO - 'PAGER': '/bin/cat', 08:09:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:09:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:09:55 INFO - 'PWD': '/builds/slave/test', 08:09:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:09:55 INFO - 'SHELL': '/bin/bash', 08:09:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.B6pRCw4ESf/Listeners', 08:09:55 INFO - 'TMPDIR': '/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/', 08:09:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:09:55 INFO - 'USER': 'cltbld', 08:09:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:09:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:09:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:09:55 INFO - 'XPC_FLAGS': '0x0', 08:09:55 INFO - 'XPC_SERVICE_NAME': '0', 08:09:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:09:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:09:55 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)) 08:09:55 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)) 08:09:55 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)) 08:09:55 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)) 08:09:55 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)) 08:09:55 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)) 08:09:55 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)) 08:09:55 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)) 08:09:55 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)) 08:09:55 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)) 08:09:55 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)) 08:09:55 INFO - Cleaning up... 08:09:55 INFO - Return code: 0 08:09:55 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 08:09:55 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')]} 08:09:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:09:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:09:55 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')]} 08:09:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:09:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:09:55 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 0x10e9fdb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ea00768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcdb0ccdf10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e7dbd30>, '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 0x10e702db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e7d0be8>, '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.u2rbdYT9AW/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.B6pRCw4ESf/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/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 08:09:55 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 08:09:55 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 08:09:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.u2rbdYT9AW/Render', 08:09:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:09:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:09:55 INFO - 'HOME': '/Users/cltbld', 08:09:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:09:55 INFO - 'LOGNAME': 'cltbld', 08:09:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:09:55 INFO - 'MOZ_NO_REMOTE': '1', 08:09:55 INFO - 'NO_EM_RESTART': '1', 08:09:55 INFO - 'PAGER': '/bin/cat', 08:09:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:09:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:09:55 INFO - 'PWD': '/builds/slave/test', 08:09:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:09:55 INFO - 'SHELL': '/bin/bash', 08:09:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.B6pRCw4ESf/Listeners', 08:09:55 INFO - 'TMPDIR': '/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/', 08:09:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:09:55 INFO - 'USER': 'cltbld', 08:09:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:09:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:09:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:09:55 INFO - 'XPC_FLAGS': '0x0', 08:09:55 INFO - 'XPC_SERVICE_NAME': '0', 08:09:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:09:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:09:55 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 08:09:55 INFO - Cleaning up... 08:09:55 INFO - Return code: 0 08:09:55 INFO - Running post-action listener: _resource_record_post_action 08:09:55 INFO - Running post-action listener: _start_resource_monitoring 08:09:56 INFO - Starting resource monitoring. 08:09:56 INFO - [mozharness: 2016-12-30 16:09:56.023668Z] Finished create-virtualenv step (success) 08:09:56 INFO - [mozharness: 2016-12-30 16:09:56.024624Z] Running install step. 08:09:56 INFO - Running pre-action listener: _resource_record_pre_action 08:09:56 INFO - Running main action method: install 08:09:56 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:09:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:09:56 INFO - Reading from file tmpfile_stdout 08:09:56 INFO - Detecting whether we're running mozinstall >=1.0... 08:09:56 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 08:09:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 08:09:56 INFO - Reading from file tmpfile_stdout 08:09:56 INFO - Output received: 08:09:56 INFO - Usage: mozinstall [options] installer 08:09:56 INFO - Options: 08:09:56 INFO - -h, --help show this help message and exit 08:09:56 INFO - -d DEST, --destination=DEST 08:09:56 INFO - Directory to install application into. [default: 08:09:56 INFO - "/builds/slave/test"] 08:09:56 INFO - --app=APP Application being installed. [default: firefox] 08:09:56 INFO - mkdir: /builds/slave/test/build/application 08:09:56 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 08:09:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 08:10:18 INFO - Reading from file tmpfile_stdout 08:10:18 INFO - Output received: 08:10:18 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 08:10:18 INFO - Running post-action listener: _resource_record_post_action 08:10:18 INFO - [mozharness: 2016-12-30 16:10:18.997825Z] Finished install step (success) 08:10:18 INFO - [mozharness: 2016-12-30 16:10:18.997982Z] Running run-tests step. 08:10:18 INFO - Running pre-action listener: _resource_record_pre_action 08:10:18 INFO - Running main action method: run_tests 08:10:19 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 08:10:19 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 08:10:19 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 08:10:19 INFO - 2016-12-30 08:10:19.012 screenresolution[1633:8777] starting screenresolution argv=screenresolution get 08:10:19 INFO - 2016-12-30 08:10:19.015 screenresolution[1633:8777] Display 0: 1600x1200x32@60 08:10:19 INFO - 2016-12-30 08:10:19.021 screenresolution[1634:8780] starting screenresolution argv=screenresolution list 08:10:19 INFO - Available Modes on Display 0 08:10:19 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 08:10:19 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 08:10:19 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 08:10:19 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 08:10:19 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 08:10:19 INFO - Intel Iris: 08:10:19 INFO - Chipset Model: Intel Iris 08:10:19 INFO - Type: GPU 08:10:19 INFO - Bus: Built-In 08:10:19 INFO - VRAM (Dynamic, Max): 1536 MB 08:10:19 INFO - Vendor: Intel (0x8086) 08:10:19 INFO - Device ID: 0x0a2e 08:10:19 INFO - Revision ID: 0x0009 08:10:19 INFO - Displays: 08:10:19 INFO - Display: 08:10:19 INFO - Resolution: 1600 x 1200 @ 60 Hz 08:10:19 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 08:10:19 INFO - Display Serial Number: 1600x1200 60 08:10:19 INFO - Main Display: Yes 08:10:19 INFO - Mirror: Off 08:10:19 INFO - Online: Yes 08:10:19 INFO - Rotation: Supported 08:10:19 INFO - Return code: 0 08:10:19 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 08:10:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 08:10:19 INFO - Python 2.7.3 08:10:19 INFO - Return code: 0 08:10:19 INFO - grabbing minidump binary from tooltool 08:10:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:10:19 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 0x10e7dbd30>, '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 0x10e702db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e7d0be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 08:10:19 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 08:10:19 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 08:10:19 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 08:10:19 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 08:10:19 INFO - Return code: 0 08:10:19 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 08:10:19 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 08:10:19 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 08:10:19 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 08:10:19 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 08:10:19 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/aE6HW-0aQu2b659JrKFlgQ/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0151', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 08:10:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/aE6HW-0aQu2b659JrKFlgQ/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0151 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 08:10:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.u2rbdYT9AW/Render', 08:10:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:10:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:10:19 INFO - 'HOME': '/Users/cltbld', 08:10:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:10:19 INFO - 'LOGNAME': 'cltbld', 08:10:19 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 08:10:19 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 08:10:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:10:19 INFO - 'MOZ_NO_REMOTE': '1', 08:10:19 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 08:10:19 INFO - 'NO_EM_RESTART': '1', 08:10:19 INFO - 'PAGER': '/bin/cat', 08:10:19 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:10:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:10:19 INFO - 'PWD': '/builds/slave/test', 08:10:19 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 08:10:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:10:19 INFO - 'SHELL': '/bin/bash', 08:10:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.B6pRCw4ESf/Listeners', 08:10:19 INFO - 'TMPDIR': '/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/', 08:10:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:10:19 INFO - 'USER': 'cltbld', 08:10:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:10:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:10:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:10:19 INFO - 'XPC_FLAGS': '0x0', 08:10:19 INFO - 'XPC_SERVICE_NAME': '0', 08:10:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:10:19 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/aE6HW-0aQu2b659JrKFlgQ/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0151', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 08:10:19 INFO - mozversion application_buildid: 20161230070523 08:10:19 INFO - mozversion application_changeset: 21dc2d95071cb7712af8552e3880ec325b1069f1 08:10:19 INFO - mozversion application_display_name: Nightly 08:10:19 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 08:10:19 INFO - mozversion application_name: Firefox 08:10:19 INFO - mozversion application_remotingname: firefox 08:10:19 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 08:10:19 INFO - mozversion application_vendor: Mozilla 08:10:19 INFO - mozversion application_version: 53.0a1 08:10:19 INFO - mozversion platform_buildid: 20161230070523 08:10:19 INFO - mozversion platform_changeset: 21dc2d95071cb7712af8552e3880ec325b1069f1 08:10:19 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 08:10:19 INFO - mozversion platform_version: 53.0a1 08:10:19 INFO - using testdate: 1483114219 08:10:19 INFO - actual date: 1483114219 08:10:19 INFO - starting webserver on 'localhost:49243' 08:10:19 INFO - SUITE-START | Running 2 tests 08:10:19 INFO - TEST-START | dromaeo_css 08:10:19 INFO - operating with platform_type : mac_ 08:10:19 INFO - Initialising browser for dromaeo_css test... 08:10:19 INFO - TEST-INFO | started process 1644 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/tmpsik1RL/profile http://localhost:49243/getInfo.html) 08:10:20 INFO - PROCESS | 1644 | 1483114220159 addons.xpi WARN Exception running bootstrap method install on shield-recipe-client@mozilla.org: [Exception... "Component returned failure code: 0x8000ffff (NS_ERROR_UNEXPECTED) [nsIPrefBranch.getBoolPref]" nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js :: this.install :: line 38" data: no] Stack trace: this.install()@resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js:38 < callBootstrapMethod()@resource://gre/modules/addons/XPIProvider.jsm:4986 < processFileChanges()@resource://gre/modules/addons/XPIProvider.jsm -> resource://gre/modules/addons/XPIProviderUtils.js:2097 < checkForChanges()@resource://gre/modules/addons/XPIProvider.jsm:3827 < startup()@resource://gre/modules/addons/XPIProvider.jsm:2831 < callProvider()@resource://gre/modules/AddonManager.jsm:264 < _startProvider()@resource://gre/modules/AddonManager.jsm:771 < startup()@resource://gre/modules/AddonManager.jsm:957 < startup()@resource://gre/modules/AddonManager.jsm:2923 < observe()@resource://gre/components/addonManager.js:65 08:10:21 INFO - PROCESS | 1644 | __metrics Screen width/height:1600/1200 08:10:21 INFO - PROCESS | 1644 | colorDepth:24 08:10:21 INFO - PROCESS | 1644 | Browser inner width/height: 1024/689 08:10:21 INFO - PROCESS | 1644 | __metrics 08:10:22 INFO - PROCESS | 1644 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 08:10:22 INFO - PROCESS | 1644 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 08:10:22 INFO - TEST-INFO | 1644: exit 0 08:10:22 INFO - Browser initialized. 08:10:22 INFO - Running cycle 1/1 for dromaeo_css test... 08:10:22 INFO - TEST-INFO | started process 1647 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/tmpsik1RL/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:10:34 INFO - PROCESS | 1647 | RSS: Main: 234262528 08:10:34 INFO - PROCESS | 1647 | 08:11:26 INFO - PROCESS | 1647 | 1483114286276 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 08:13:39 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49243/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49243/tests/dromaeo/cssquery-ext.html) 08:13:40 INFO - PROCESS | 1647 | RSS: Main: 382746624 08:13:40 INFO - PROCESS | 1647 | 08:15:23 INFO - PROCESS | 1647 | ************************* 08:15:23 INFO - PROCESS | 1647 | A coding exception was thrown and uncaught in a Task. 08:15:23 INFO - PROCESS | 1647 | 08:15:23 INFO - PROCESS | 1647 | Full message: ReferenceError: fetch is not defined 08:15:23 INFO - PROCESS | 1647 | Full stack: apiCall@resource://shield-recipe-client/lib/NormandyApi.jsm:37:5 08:15:23 INFO - PROCESS | 1647 | get@resource://shield-recipe-client/lib/NormandyApi.jsm:44:12 08:15:23 INFO - PROCESS | 1647 | this.NormandyApi.fetchRecipes<@resource://shield-recipe-client/lib/NormandyApi.jsm:52:34 08:15:23 INFO - PROCESS | 1647 | TaskImpl_run@resource://gre/modules/Task.jsm:319:42 08:15:23 INFO - PROCESS | 1647 | TaskImpl@resource://gre/modules/Task.jsm:277:3 08:15:23 INFO - PROCESS | 1647 | asyncFunction@resource://gre/modules/Task.jsm:252:14 08:15:23 INFO - PROCESS | 1647 | this.RecipeRunner.start<@resource://shield-recipe-client/lib/RecipeRunner.jsm:64:23 08:15:23 INFO - PROCESS | 1647 | TaskImpl_run@resource://gre/modules/Task.jsm:319:42 08:15:23 INFO - PROCESS | 1647 | TaskImpl@resource://gre/modules/Task.jsm:277:3 08:15:23 INFO - PROCESS | 1647 | asyncFunction@resource://gre/modules/Task.jsm:252:14 08:15:23 INFO - PROCESS | 1647 | setTimeout_timer@resource://gre/modules/Timer.jsm:30:5 08:15:23 INFO - PROCESS | 1647 | 08:15:23 INFO - PROCESS | 1647 | ************************* 08:16:43 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49243/tests/dromaeo/cssquery-ext.html (next: http://localhost:49243/tests/dromaeo/cssquery-jquery.html) 08:16:44 INFO - PROCESS | 1647 | RSS: Main: 366190592 08:16:44 INFO - PROCESS | 1647 | 08:19:46 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49243/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49243/tests/dromaeo/cssquery-mootools.html) 08:19:47 INFO - PROCESS | 1647 | RSS: Main: 361406464 08:19:47 INFO - PROCESS | 1647 | 08:22:49 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49243/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49243/tests/dromaeo/cssquery-prototype.html) 08:22:50 INFO - PROCESS | 1647 | RSS: Main: 337276928 08:22:50 INFO - PROCESS | 1647 | 08:25:54 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49243/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49243/tests/dromaeo/cssquery-yui.html) 08:25:54 INFO - PROCESS | 1647 | RSS: Main: 325492736 08:25:54 INFO - PROCESS | 1647 | 08:28:57 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49243/tests/dromaeo/cssquery-yui.html (next: http://localhost:49243/tests/dromaeo/cssquery-dojo.html) 08:28:58 INFO - PROCESS | 1647 | RSS: Main: 454955008 08:28:58 INFO - PROCESS | 1647 | 08:28:58 INFO - PROCESS | 1647 | __start_tp_report 08:28:58 INFO - PROCESS | 1647 | _x_x_mozilla_page_load 08:28:58 INFO - PROCESS | 1647 | _x_x_mozilla_page_load_details 08:28:58 INFO - PROCESS | 1647 | |i|pagename|runs| 08:28:58 INFO - PROCESS | 1647 | |0;dojo.html;3097.876084956601;3343.2310568569237;3365.394229038772;3375.493675948609;3426.177717347837;5436.565074794017;5480.424555421683;5619.831405057845;5648.26572545569;5678.943210567893;4897;5533.557315414767;5717.456841600052;5719.1421286807;5763.682997435139;5059.822906198284;5072.137736584771;5078.974605126969;5148.613853960949;5158.9742051289695;5154.6134039946965;5228.947710522884;5238.607104467161;5240.816571420001;5296.523312901838;5065.518775716301;5170.870728231825;5195.584353251768;5195.6622819516615;5231.476852314757;5077.796888124471;5112.744362781846;5136.101182293084;5137.049645815536;5145.819896303648;5031.597472202252;5084.847454576368;5088.185890257578;5095.337606111182;5096.51583099605;5553.028220061514;5557.666540007613;5557.972210138923;5559.082751346023;5570.6657600544095;6103.633781973096;6111.35830737775;6112;6121.143039974407;6121.785737499211;6106.603070800428;6111.47019340724;6117.248930028882;6130.256732558205;6136.470306274282;5579.525740312079;5644.97177514118;5651.886962260814;5655.293088363955;5665.631733937281;6230.7819226327765;6279.120923070774;6285.277193122758;6305.180326557518;6318.620882746958;6032.245969253843;6038.215032045894;6044.516438684895;6049.1833602463985;6058.1215723719815;6041.214642096587;6079.300880398722;6094.421030002142;6124.136831511461;6153.538484613672;5290.259363689087;5908.438698323653;6083;6156.846078847975;6159.23009623797;6119.724612392453;6126.210730859295;6140.057253910645;6156.322804491502;6172.135900973867;4086.4687590613626;4776.259679749644;4870.902581948341;4877.975610121926;4898.755062246873;4265.168292182994;4302.290122129876;4305.354196870432;4348.478182618106;4354.346847972829;5593.133064375028;5692.829215123553;5700.144978253295;5714.000049991235;5743.2246646703;6211.65835879031;6218.005119181;6221.968890155611;6222.377762223741;6233.844153896189;5564.054110801174;5592.608517403824;5618.634788738719;5647.124695672177;5682.346530149086;5295.788168473218;5311.707856067954;5317.494837990384;5320.068987927175;5392.676439413648;6273.905891411632;6278.968605156945;6299.559030867796;6307.653079080511;6314.400131987454;6177.907331390123;6179.721912514037;6198.473129784019;6241.937580624136;6268;6220.129181914445;6225.190725205694;6248.531360148098;6261.217347831521;6292.339304373158;5949.286085669747;6011.308699499614;6024.0662697282;6026.276846778414;6144.662043587646;6136.028952951454;6138;6180.505559555315;6191.938080619317;6216.689165541795;5997.730102145327;6002.849928751816;6011.428914253139;6013.037913933749;6013.789517366871;5832.562557808266;5882.5293976482635;5882.999890018621;5983.670898100646;6029;5838.211841401357;5911.970440147772;5919.970400147971;5963.194968679346;5977.581569290108;6051.969740151271;6054.84862878414;6055.2430946131735;6063.5452341073715;6072.210612620331;5660.4905558498285;5677.318721753416;5739.540836732968;5851.736671849691;5907.5273978080795 08:28:58 INFO - PROCESS | 1647 | |1;ext.html;435.7385568658805;500.17471172565286;500.33887493521524;516.8604476791267;517.8938317644885;29194.854025729866;38346.42480362797;39406.80296598517;39449;41822.745317640445;40618;41035.38446923299;41065.794671026604;41152;41227.175456490855;8325.583720813966;9083.591238394289;9288.164065234128;9311.068893110703;9312.30157738169;8368.995720513547;10873.401962892038;11125.499352529116;11136.777264454706;11336.943315283412;11081.722956926102;11137.443127843617;11143.665690029313;11157.2747771395;11167;11060.834087488694;11150.832737508943;11153.888461115366;11180.329180249211;11197.776044479146;10907.400092994882;11007.944960275147;11009.229353945228;11017.889821101766;11023.503942322535;38292.425613615815;38355.808220959;38594.22811543753;38654;38656.61343386587;3913.2173565287057;3973.1259122992897;3985.70107241958;3996.140829721624;4040.2121586290687;28927.132186034454;29275.414491710046;29535.261618459495;29795.828583998256;29853.552196717068;3209.47043737783;3211.277462570926;3219.356128774231;3238.6599407062154;3251.1709514073996;338383;338990.6100938984;339105.3044734785;339414.30292848375;339826;53349;53894.191587126224;53895;53933;54037;56104.15843762347;57421.42578574161;57702.71148644314;57708.13437798437;59035.70482147648;15001;15195.924020379827;15213;15242.542723718307;15266.313015914311;10586.788264234827;10818.891811081945;10825.78348433027;11802.763944721057;11882.76234475323;16586.477374760794;17388.565285867702;19842.404727858186;20207.191712331634;20664.690029649566;8007.479513831582;8139.9475196801195;8370.79073023167;8380.790480238044;8398.244158025685;801.338895411288;841.3984001439001;843.409613270713;856.178069053703;879.784452809053;26336.34159146037;28261.72822223005;28742;28927.56608650872;29196.562051569243;1014.9949250253849;1048.7168464514539;1061.532925512772;1063.9361638301573;1116.8715597706205;13750.931245343709;14226.430942762381;14656.974011819071;15050.473233436996;15166.317515711598;581227;616165;616285;616747;617636;54547.45452545503;54974.90050198974;55027.724861375435;55122.17316740252;55183.72408137934;1166.8191430328313;1189.5895915908998;1381.0608786025598;1385.9792103118664;1388.1462900316233;27105;27762;28357.291067723472;28572;28738.568921465776;46336.30495542637;47041.52958470371;47133.76433117812;47137.76431117822;47169.764151180396;556108.2194589163;556289;558126;558566;560591;25880.094196703027;26042;26243;26291.474170516864;26357.472850543265;43850.342244865715;44081;44622.77688611536;45143;45164;36332.27335453223;36988;37337;37761.81119094387;37999.43000854934;34547.82726086354;35633.72395312579;36105;37021;37499.625003749614;33728.98813035613;33829;34274;34403.655963441044;34678.479822802175;29400.7059929398;31018;31129.221769455944;31162.532562011136;31365.372692545567;23278.883605581865;23402;23462.296131116094;23635.29094127179;23724.762752372255 08:28:58 INFO - PROCESS | 1647 | |2;jquery.html;297.8853141540509;369.24120931485805;370.63492459926965;375.82148479472244;399.7581463214755;14454.132752034871;14688.339024743886;15625.3749850006;19474.221031158737;19986.200551977905;18903.621927561442;19838.900805495952;20012.399628011146;20021.299254526093;20284;5434.266374039514;5737.454941780545;5739.655620662753;5752.079667253241;5759.740811663485;4999.475055119215;5665.886682266352;6347.968260158693;6387.105805187278;6447.226332840042;6031.457168854802;6396.552241343108;6407.87184256317;6422;6430.839229019265;6296.087067375228;6332.303446620868;6370.58591191571;6390.648514331687;6415.1981002374705;4795.928061079087;5280.392754833205;5492.011437941169;5543.417941116165;5565.6104072714925;24550.140745074015;24575;24783.5043299133;24942.00231990718;25101.87449062743;2727.236373815315;2736.9178924632292;2748.1068652687754;2753.600727894444;2831.7309855563894;15015.849841501555;15226.010309329859;15270.694586108215;15369.846301536953;15459.922700386425;1810.8189181081918;1826.6346730653922;1832.651796158739;1843.3548258109688;1893.2427029188298;107187.46406267997;109096.4545177277;109661.45169274183;109671;110644.4467777664;10364.585416583403;10636.946815265874;10656.307340022873;10710.303830251009;10814.405207713495;13238.53665121716;13249.205047697169;13250.602481925558;13264.73470530584;13290.734185316433;7965.084015338194;8102.230288122619;8123.350131989427;8264.421490495608;8290.917090829014;8066.395020373496;8067.798305042421;8090.231428014329;8265.338772898154;8273;5844.386339434298;5874.0014197586515;6089.360617135226;6201.720922558496;6324.3043265240785;1738.97391539127;1777.680017596845;1778.386456672446;1786.0712429536827;1786.56229223841;996.5465713100522;1006.597361055569;1013.4426065663855;1024.7284469615558;1051.8737751469873;14151.85848141526;19967;20893.164273428894;20940.685889711665;21149;1018.4856647393019;1021.7241344837001;1030.6444276724667;1039.3504059962524;1076.3757020927842;10610.787784244272;10935.331944119898;10988.835167472495;11097.500612472459;11146.386948717736;504443;508928;546832;562239;563954;74032.6354310397;91645;127938;130691.34654326858;131193.68806312006;994.7314225159166;1024.5696807340935;1066.2056767708107;1081.8755843078147;1083.9403832789271;18069.00620465887;18229.726554101966;18392.26430942747;18620.813791861907;18738.063096845377;44505.77747111244;47593.52406476029;51670.70823229449;51673.7416312916;52068;71945;72250;72777;74861.62569187119;74898;6621.370969757865;7228.301349182832;8016.959915200387;8022.713526645111;8176.550289734122;12601.369931503572;12613.873861261636;12699.492020319083;13006.869931300565;13104.279264640536;10844;11070.114390848874;11159.819451456297;11246.268992515461;11254.94372528132;5258.8948221036135;9620.460726283762;10960.671179864617;10971.670849874517;11272.380019099028;11055.778884422105;11362.886371136514;11399.316041037233;11412.603353295568;11462.656120316404;8013.959930200312;8020.518768873886;8023.398245131521;8087.150849160988;8092.231238032377;8161.918380816116;10563.735906602396;11162.939520745516;11189.552417903193;11231.40595009589 08:28:58 INFO - PROCESS | 1647 | |3;mootools.html;1313.4417872404229;1347.89216862651;1370.616227456312;1371.8559551247113;1373.7527245095878;7948.284654381112;8294.046184688748;8335.499870007796;8340.833183336345;8357.582120893954;7321.6705248263825;7579.6589153488085;7592.50648707835;8040.276375126252;8075.394345424102;2250.8086812620945;2254.616715158427;2260.9434764130947;2269.54609078185;2295.288460577218;2259.491614386758;2292.8509646872985;2296.5292115116445;2298.839081264312;2301.5857145713767;2011.1050582490798;2021.403685912652;2085.4577809769494;2099.748030236374;2131.6269652810774;2255.875926824024;2258.6386178211487;2261.8642881427168;2264.8980795864227;2268.886555672218;2255.1994042114975;2266.580682573729;2273.8067264282554;2274.385915802724;2280.2019293247395;8178.100408955011;8249.587520624005;8261.297789687824;8286.544240066854;8286.8342633147;4953.43035550909;5116.334876466036;5124.92312615311;5129.717865517395;5136.974315128437;3116.143060658337;3262.902112936592;3286.5398844161837;3401.3197360527993;3484.216051388443;1161.181367136167;1260.0927332320714;1266.0567876931746;1702.2680247493572;1749.8425141737305;10609.787804243872;10623.628173013985;10692.518836652369;10711;10755.946220268926;4122.484689413824;4130.463039804806;4321.2005778931;4383.62739167168;4391.5388884166705;8496.192861678132;8499.702510412106;8513.19124683154;8531.658733650584;8545.529995850287;4749.715017098985;4837.201861692852;4846.103470857903;4850.175470170079;4867;4204.516480604709;4214.831406743757;4352.586504282088;4532.093581283756;4548.909021819612;3459.41189997701;3475.461303497962;3601.1717305019993;3607.5129857469433;3616.4213725803747;990.5443495991781;996.0935548650693;1004.6935684616262;1008.7629407089328;1011.3173607814696;855.3584811391456;864.3603733237359;874.7856775090016;911.8358695434886;915.9954200228956;4653.60444362233;5519.144532597454;5656.604037717402;5665.3201615806365;5894.498967587803;861.7113267055606;891.2112780189578;911.5487833522317;915.514777168102;922.9446233225893;3638.290533345972;3828.2152158807376;3899.259140763246;3900.8829735107424;3920;9324.300677449219;9701.175400091071;9889.950550247202;9905.009499050037;9911.256655750847;9555.18780903631;9828.852567211497;9846.310758246997;9863.506824658738;9910.207183425453;7367.336939675455;7623.809404764926;7629.923700763033;7718.073831140299;7735.61321933901;5236.502532259429;5477.123660214346;5485.204645326485;5504.422035686195;5627.268455100811;9090.727278181665;9133.132352426508;9180.495072770795;9206.769830754018;9333.719988400358;9257.583408746488;9279.907200927904;9288.72133835986;9343.81312373735;9375.859362109437;6538.313477084837;6753;6997.440204783707;7160.212376638461;7198.820029499305;7171.533850299714;7833.764987050397;7913.881291780743;7968.282854543119;7984.8802267967185;7018.684159212746;7073.292670732885;7083.150021997393;7138.072050633385;7241.601711905896;6965.79102626922;6997.475189360716;7018.75434359795;7057.894131588133;7092.680829362588;6159.938400615936;6284.057391391145;6345.333739957238;6358.650274234961;6376.840578985562;4658.93011604833;4683.625309975126;4721;4760.428748550196;4829.613630909589;4642.86071417858;4678.742669153093;4754.667173297974;4825.4933232010135;4928.605711542997 08:28:58 INFO - PROCESS | 1647 | |4;prototype.html;348.1661420896952;353.126013117534;357.8210894552724;366.67915573872864;369.1030795167743;4414.735115893045;4638.605718513922;5122.897542049157;5287.206918962158;5329.786808527659;4786.760661966897;5094.974525127369;5192.299039629658;5221.24291977664;5279.868003299919;3426.4688973209068;4083.8570650027254;4126.31915733905;4137.565555616662;4180.853670121539;3377.847996840148;3456.965430345702;3887.552931412881;3996.700247481436;4095.870147523286;3624.3476174288617;4038.899027524306;4052.371882358239;4089.1003979124544;4103.312330423562;3786.5456145262747;4024.074462873541;4040.4949381327333;4054.229696357712;4073.2668119738437;3142.9471127172424;3486.5594084141203;3573.5711714594154;3584.6773790358734;3596.946045809315;4221.387898754694;5024.8662384974705;5109.003744269869;5134.971686183973;5144.456663001122;4248.468941382327;4427.889302767424;4604.066292157268;4676.438827340707;4697.2484402495775;5127.512886275797;5439.77605038867;5457.688426143382;5493.697846618435;5519.475649813261;3797.715171362159;3928.5482169550583;3929.1748732766155;3935.645791878716;3961.564227934925;5080;5725.799597014126;5746.942530574683;5767.011535022176;5795.565332600072;3428.103252751421;4005.499312585927;4024.839006439768;4028.67770578353;4046.8785936421955;3597.9776987330247;3921.941170882439;4084.8546619103718;4094.6692324994497;4123.278426275389;3909.6872250219913;4627.316156629147;4633.7683115844075;4655.790489427984;4665.4168228971375;5298.337707786527;5546.77812887488;5562.30349743324;5567.137093750475;5570.888582228333;1181.5569161564413;1198.5804968261214;1238.219921449499;1272.866349033338;1307.6338625184962;810.789194809342;882.7156487310947;893.5702875399309;926.6525053105086;947.701648741229;4077.938830917539;4896;4916.508349165055;4969.3291405659775;4970.776315065831;4814.9518504815205;4961.454240033593;5103.464136265638;5240.0418104104265;5423.728813559306;4323.329883868006;4375.649948004152;4489;4677.006715701954;4895.951040489549;2606.283272100188;2654.6548948636555;2753.747045094477;2776.2642899631614;2839.134064110466;4706.835260765857;4733.881652958704;5135.255387968724;5591.804286849941;5631.058259218564;4771.475137734847;4991.376077990251;5494.36814766299;5518.724063796794;5525;5051.564935843661;5186.766595503212;5413.756380962866;5558;5605.607607467519;4195.748295071344;4630.421197350332;4672.48602653701;5263.6313658852905;5392.514673679388;5301.269846029375;5311.893762124813;5328.626996110235;5331.733413329241;5348.572114230775;4915.230517013801;5043.470435604208;5058.822941197041;5061.392632884077;5640.88718225625;3341.899743007714;3672.614375490535;3794.3170229357875;3867.5358956925347;3909.5730058529;4763.7602533745685;4991.650584459053;5348.411674715856;5360.705161216015;5377.731113444234;5110.798962243795;5203.836930455696;5213;5241.187615919539;5254.211868219798;4652.976735116303;4905.48492408292;5169.974150129226;5245.570834186598;5304.363476382859;4822.68652537584;4981.8043669519775;5108;5120.411152717336;5251.789928402821;4947.356843610308;5221.947780522146;5349.518543331119;5379.386184144819;5406.729663516887;4643;4743.430788305425;5181.58524342071;5411.783528658967;5439.972800135974 08:28:58 INFO - PROCESS | 1647 | |5;yui.html;708.5288283291611;714.5391222661383;714.5569746757011;759.6695437484691;846.4963346808648;14244.928775356122;14763.261836908165;15185.392584296642;15189.164595947219;15268.236588170588;13626.250556219404;13722.039457237997;13863.445462181526;13908.930455347709;15056.473023444181;838.6980686952708;865.5412631305418;868.6525389844049;869.3002133282705;870.3254977392509;862.2627653356384;863.1799790199304;865.3336930563462;865.6537385045968;866.9133086691344;925.3383830561147;927.3832901120746;928.0348437624862;928.0811996123823;930.2790337488433;918.9035151309083;919.5861862161987;920.8756817829608;920.8848893888264;921.9769505762376;678.995087270837;679.1578442731027;679.3546131175403;679.5718697220768;680.3774546290188;15016.948813583054;15017.624559386102;15026.624334391618;15052.397904083824;15083.698326033527;13210.66973325665;13338.855429201625;13608.31958402086;13748.725025499534;13796;11436.37099959502;11497.540098396054;11550;11814.11394145434;12087.153899227058;3628.129248980252;3631.1466805300734;3645.5989841117457;3657.4696668982847;3793.867214647502;62960.01699512376;86523;87283;106810;107622.92377076208;10577.62978295756;10773.353598784099;10785.083267922157;10817;10993;20826.583468330547;21401.67897481539;21436.892815535823;21466.24868129608;21487.57024859494;7609.923900761032;7649.770506884803;7754.302112809874;7800.804979875548;7908.248716371935;4232.470941132358;4313.741175529415;4329.350597410414;4339.045410009856;4429.446319210098;3275.8034517929;3502.824858757052;3513.718902487795;3593.4969104325414;3630.328389247945;560.716837996809;649.8407890066964;657.8750037492957;662.5505219569102;673.4242222899449;608.3672980100735;631.9399657032575;653.9149910511603;654.4012228810681;679.8572299817093;8619.913800862036;9272;9281.164695177466;9366;9379.624815007459;545.9945400546023;622.7882519943241;634.6572850660684;668.7960172147444;677.0926957876371;3607.549056367954;4299;4490.820367185276;4499.280115181556;4528.954710452919;105358;106750;107136.39295410574;108275;108287.45856270668;91904;91923.540382299;93296.53351733198;94929.5760563592;95189.0481095194;81660.59169704113;83547.58226209073;83696.16303837005;84650.57674711588;84970;8620.741377758677;8747.387682862138;8774.473531588124;8906.421082629608;8970.147835955573;47491.28763068626;51509.48490515047;52070.21894671659;53152.46847531475;53665.73167134295;52468.47531524636;67513.66243168752;68872.6556367215;69209;69514.65242673755;12706.428210730355;12975.158695943348;13262.005349598625;13410.865891340962;13480.460781568627;11004.944975275072;13440.93279533596;13462.461501539829;14072.155670659793;14354.712905742035;13283.40169212778;13460.461581536629;13541.526046588322;13641.863581364456;13697.863021370058;13500.32498375097;13535.255560944242;13797.586072417844;13874;14198.787018194942;12717.618471445872;13075.346232688518;13213.405396756976;13337.933310333387;13781.586552403443;10707.57169713234;11102.833457497982;11109.722256943642;11220.719482012688;11702.473388697359;10552.94614677297;10743.140548755924;10806.446790060612;10832.67501974942;10841.18691098155 08:28:58 INFO - PROCESS | 1647 | __end_tp_report 08:28:58 INFO - PROCESS | 1647 | __start_cc_report 08:28:58 INFO - PROCESS | 1647 | _x_x_mozilla_cycle_collect,1419 08:28:58 INFO - PROCESS | 1647 | __end_cc_report 08:28:58 INFO - PROCESS | 1647 | __startTimestamp1483115338110__endTimestamp 08:28:58 INFO - PROCESS | 1647 | 08:28:58 INFO - PROCESS | 1647 | ------- Summary: start ------- 08:28:58 INFO - PROCESS | 1647 | Number of tests: 6 08:28:58 INFO - PROCESS | 1647 | 08:28:58 INFO - PROCESS | 1647 | [#0] dojo.html Cycles:165 Average:5653.58 Median:5882.53 stddev:646.85 (11.0%) stddev-sans-first:616.99 08:28:58 INFO - PROCESS | 1647 | Values: 3097.9 3343.2 3365.4 3375.5 3426.2 5436.6 5480.4 5619.8 5648.3 5678.9 4897.0 5533.6 5717.5 5719.1 5763.7 5059.8 5072.1 5079.0 5148.6 5159.0 5154.6 5228.9 5238.6 5240.8 5296.5 5065.5 5170.9 5195.6 5195.7 5231.5 5077.8 5112.7 5136.1 5137.0 5145.8 5031.6 5084.8 5088.2 5095.3 5096.5 5553.0 5557.7 5558.0 5559.1 5570.7 6103.6 6111.4 6112.0 6121.1 6121.8 6106.6 6111.5 6117.2 6130.3 6136.5 5579.5 5645.0 5651.9 5655.3 5665.6 6230.8 6279.1 6285.3 6305.2 6318.6 6032.2 6038.2 6044.5 6049.2 6058.1 6041.2 6079.3 6094.4 6124.1 6153.5 5290.3 5908.4 6083.0 6156.8 6159.2 6119.7 6126.2 6140.1 6156.3 6172.1 4086.5 4776.3 4870.9 4878.0 4898.8 4265.2 4302.3 4305.4 4348.5 4354.3 5593.1 5692.8 5700.1 5714.0 5743.2 6211.7 6218.0 6222.0 6222.4 6233.8 5564.1 5592.6 5618.6 5647.1 5682.3 5295.8 5311.7 5317.5 5320.1 5392.7 6273.9 6279.0 6299.6 6307.7 6314.4 6177.9 6179.7 6198.5 6241.9 6268.0 6220.1 6225.2 6248.5 6261.2 6292.3 5949.3 6011.3 6024.1 6026.3 6144.7 6136.0 6138.0 6180.5 6191.9 6216.7 5997.7 6002.8 6011.4 6013.0 6013.8 5832.6 5882.5 5883.0 5983.7 6029.0 5838.2 5912.0 5920.0 5963.2 5977.6 6052.0 6054.8 6055.2 6063.5 6072.2 5660.5 5677.3 5739.5 5851.7 5907.5 08:28:58 INFO - PROCESS | 1647 | 08:28:58 INFO - PROCESS | 1647 | [#1] ext.html Cycles:180 Average:63548.04 Median:339620.15 stddev:137911.53 (40.6%) stddev-sans-first:138217.00 08:28:58 INFO - PROCESS | 1647 | Values: 435.7 500.2 500.3 516.9 517.9 29194.9 38346.4 39406.8 39449.0 41822.7 40618.0 41035.4 41065.8 41152.0 41227.2 8325.6 9083.6 9288.2 9311.1 9312.3 8369.0 10873.4 11125.5 11136.8 11336.9 11081.7 11137.4 11143.7 11157.3 11167.0 11060.8 11150.8 11153.9 11180.3 11197.8 10907.4 11007.9 11009.2 11017.9 11023.5 38292.4 38355.8 38594.2 38654.0 38656.6 3913.2 3973.1 3985.7 3996.1 4040.2 28927.1 29275.4 29535.3 29795.8 29853.6 3209.5 3211.3 3219.4 3238.7 3251.2 338383.0 338990.6 339105.3 339414.3 339826.0 53349.0 53894.2 53895.0 53933.0 54037.0 56104.2 57421.4 57702.7 57708.1 59035.7 15001.0 15195.9 15213.0 15242.5 15266.3 10586.8 10818.9 10825.8 11802.8 11882.8 16586.5 17388.6 19842.4 20207.2 20664.7 8007.5 8139.9 8370.8 8380.8 8398.2 801.3 841.4 843.4 856.2 879.8 26336.3 28261.7 28742.0 28927.6 29196.6 1015.0 1048.7 1061.5 1063.9 1116.9 13750.9 14226.4 14657.0 15050.5 15166.3 581227.0 616165.0 616285.0 616747.0 617636.0 54547.5 54974.9 55027.7 55122.2 55183.7 1166.8 1189.6 1381.1 1386.0 1388.1 27105.0 27762.0 28357.3 28572.0 28738.6 46336.3 47041.5 47133.8 47137.8 47169.8 556108.2 556289.0 558126.0 558566.0 560591.0 25880.1 26042.0 26243.0 26291.5 26357.5 43850.3 44081.0 44622.8 45143.0 45164.0 36332.3 36988.0 37337.0 37761.8 37999.4 34547.8 35633.7 36105.0 37021.0 37499.6 33729.0 33829.0 34274.0 34403.7 34678.5 29400.7 31018.0 31129.2 31162.5 31365.4 23278.9 23402.0 23462.3 23635.3 23724.8 08:28:58 INFO - PROCESS | 1647 | 08:28:58 INFO - PROCESS | 1647 | [#2] jquery.html Cycles:180 Average:32299.35 Median:20916.93 stddev:89728.68 (429.0%) stddev-sans-first:89948.22 08:28:58 INFO - PROCESS | 1647 | Values: 297.9 369.2 370.6 375.8 399.8 14454.1 14688.3 15625.4 19474.2 19986.2 18903.6 19838.9 20012.4 20021.3 20284.0 5434.3 5737.5 5739.7 5752.1 5759.7 4999.5 5665.9 6348.0 6387.1 6447.2 6031.5 6396.6 6407.9 6422.0 6430.8 6296.1 6332.3 6370.6 6390.6 6415.2 4795.9 5280.4 5492.0 5543.4 5565.6 24550.1 24575.0 24783.5 24942.0 25101.9 2727.2 2736.9 2748.1 2753.6 2831.7 15015.8 15226.0 15270.7 15369.8 15459.9 1810.8 1826.6 1832.7 1843.4 1893.2 107187.5 109096.5 109661.5 109671.0 110644.4 10364.6 10636.9 10656.3 10710.3 10814.4 13238.5 13249.2 13250.6 13264.7 13290.7 7965.1 8102.2 8123.4 8264.4 8290.9 8066.4 8067.8 8090.2 8265.3 8273.0 5844.4 5874.0 6089.4 6201.7 6324.3 1739.0 1777.7 1778.4 1786.1 1786.6 996.5 1006.6 1013.4 1024.7 1051.9 14151.9 19967.0 20893.2 20940.7 21149.0 1018.5 1021.7 1030.6 1039.4 1076.4 10610.8 10935.3 10988.8 11097.5 11146.4 504443.0 508928.0 546832.0 562239.0 563954.0 74032.6 91645.0 127938.0 130691.3 131193.7 994.7 1024.6 1066.2 1081.9 1083.9 18069.0 18229.7 18392.3 18620.8 18738.1 44505.8 47593.5 51670.7 51673.7 52068.0 71945.0 72250.0 72777.0 74861.6 74898.0 6621.4 7228.3 8017.0 8022.7 8176.6 12601.4 12613.9 12699.5 13006.9 13104.3 10844.0 11070.1 11159.8 11246.3 11254.9 5258.9 9620.5 10960.7 10971.7 11272.4 11055.8 11362.9 11399.3 11412.6 11462.7 8014.0 8020.5 8023.4 8087.2 8092.2 8161.9 10563.7 11162.9 11189.6 11231.4 08:28:58 INFO - PROCESS | 1647 | 08:28:58 INFO - PROCESS | 1647 | [#3] mootools.html Cycles:180 Average:5296.22 Median:5481.16 stddev:2909.02 (53.1%) stddev-sans-first:2901.78 08:28:58 INFO - PROCESS | 1647 | Values: 1313.4 1347.9 1370.6 1371.9 1373.8 7948.3 8294.0 8335.5 8340.8 8357.6 7321.7 7579.7 7592.5 8040.3 8075.4 2250.8 2254.6 2260.9 2269.5 2295.3 2259.5 2292.9 2296.5 2298.8 2301.6 2011.1 2021.4 2085.5 2099.7 2131.6 2255.9 2258.6 2261.9 2264.9 2268.9 2255.2 2266.6 2273.8 2274.4 2280.2 8178.1 8249.6 8261.3 8286.5 8286.8 4953.4 5116.3 5124.9 5129.7 5137.0 3116.1 3262.9 3286.5 3401.3 3484.2 1161.2 1260.1 1266.1 1702.3 1749.8 10609.8 10623.6 10692.5 10711.0 10755.9 4122.5 4130.5 4321.2 4383.6 4391.5 8496.2 8499.7 8513.2 8531.7 8545.5 4749.7 4837.2 4846.1 4850.2 4867.0 4204.5 4214.8 4352.6 4532.1 4548.9 3459.4 3475.5 3601.2 3607.5 3616.4 990.5 996.1 1004.7 1008.8 1011.3 855.4 864.4 874.8 911.8 916.0 4653.6 5519.1 5656.6 5665.3 5894.5 861.7 891.2 911.5 915.5 922.9 3638.3 3828.2 3899.3 3900.9 3920.0 9324.3 9701.2 9890.0 9905.0 9911.3 9555.2 9828.9 9846.3 9863.5 9910.2 7367.3 7623.8 7629.9 7718.1 7735.6 5236.5 5477.1 5485.2 5504.4 5627.3 9090.7 9133.1 9180.5 9206.8 9333.7 9257.6 9279.9 9288.7 9343.8 9375.9 6538.3 6753.0 6997.4 7160.2 7198.8 7171.5 7833.8 7913.9 7968.3 7984.9 7018.7 7073.3 7083.2 7138.1 7241.6 6965.8 6997.5 7018.8 7057.9 7092.7 6159.9 6284.1 6345.3 6358.7 6376.8 4658.9 4683.6 4721.0 4760.4 4829.6 4642.9 4678.7 4754.7 4825.5 4928.6 08:28:58 INFO - PROCESS | 1647 | 08:28:58 INFO - PROCESS | 1647 | [#4] prototype.html Cycles:180 Average:4356.50 Median:4859.32 stddev:1282.60 (26.4%) stddev-sans-first:1250.41 08:28:58 INFO - PROCESS | 1647 | Values: 348.2 353.1 357.8 366.7 369.1 4414.7 4638.6 5122.9 5287.2 5329.8 4786.8 5095.0 5192.3 5221.2 5279.9 3426.5 4083.9 4126.3 4137.6 4180.9 3377.8 3457.0 3887.6 3996.7 4095.9 3624.3 4038.9 4052.4 4089.1 4103.3 3786.5 4024.1 4040.5 4054.2 4073.3 3142.9 3486.6 3573.6 3584.7 3596.9 4221.4 5024.9 5109.0 5135.0 5144.5 4248.5 4427.9 4604.1 4676.4 4697.2 5127.5 5439.8 5457.7 5493.7 5519.5 3797.7 3928.5 3929.2 3935.6 3961.6 5080.0 5725.8 5746.9 5767.0 5795.6 3428.1 4005.5 4024.8 4028.7 4046.9 3598.0 3921.9 4084.9 4094.7 4123.3 3909.7 4627.3 4633.8 4655.8 4665.4 5298.3 5546.8 5562.3 5567.1 5570.9 1181.6 1198.6 1238.2 1272.9 1307.6 810.8 882.7 893.6 926.7 947.7 4077.9 4896.0 4916.5 4969.3 4970.8 4815.0 4961.5 5103.5 5240.0 5423.7 4323.3 4375.6 4489.0 4677.0 4896.0 2606.3 2654.7 2753.7 2776.3 2839.1 4706.8 4733.9 5135.3 5591.8 5631.1 4771.5 4991.4 5494.4 5518.7 5525.0 5051.6 5186.8 5413.8 5558.0 5605.6 4195.7 4630.4 4672.5 5263.6 5392.5 5301.3 5311.9 5328.6 5331.7 5348.6 4915.2 5043.5 5058.8 5061.4 5640.9 3341.9 3672.6 3794.3 3867.5 3909.6 4763.8 4991.7 5348.4 5360.7 5377.7 5110.8 5203.8 5213.0 5241.2 5254.2 4653.0 4905.5 5170.0 5245.6 5304.4 4822.7 4981.8 5108.0 5120.4 5251.8 4947.4 5221.9 5349.5 5379.4 5406.7 4643.0 4743.4 5181.6 5411.8 5440.0 08:28:58 INFO - PROCESS | 1647 | 08:28:58 INFO - PROCESS | 1647 | [#5] yui.html Cycles:180 Average:20286.50 Median:4321.55 stddev:29327.30 (678.6%) stddev-sans-first:29372.73 08:28:58 INFO - PROCESS | 1647 | Values: 708.5 714.5 714.6 759.7 846.5 14244.9 14763.3 15185.4 15189.2 15268.2 13626.3 13722.0 13863.4 13908.9 15056.5 838.7 865.5 868.7 869.3 870.3 862.3 863.2 865.3 865.7 866.9 925.3 927.4 928.0 928.1 930.3 918.9 919.6 920.9 920.9 922.0 679.0 679.2 679.4 679.6 680.4 15016.9 15017.6 15026.6 15052.4 15083.7 13210.7 13338.9 13608.3 13748.7 13796.0 11436.4 11497.5 11550.0 11814.1 12087.2 3628.1 3631.1 3645.6 3657.5 3793.9 62960.0 86523.0 87283.0 106810.0 107622.9 10577.6 10773.4 10785.1 10817.0 10993.0 20826.6 21401.7 21436.9 21466.2 21487.6 7609.9 7649.8 7754.3 7800.8 7908.2 4232.5 4313.7 4329.4 4339.0 4429.4 3275.8 3502.8 3513.7 3593.5 3630.3 560.7 649.8 657.9 662.6 673.4 608.4 631.9 653.9 654.4 679.9 8619.9 9272.0 9281.2 9366.0 9379.6 546.0 622.8 634.7 668.8 677.1 3607.5 4299.0 4490.8 4499.3 4529.0 105358.0 106750.0 107136.4 108275.0 108287.5 91904.0 91923.5 93296.5 94929.6 95189.0 81660.6 83547.6 83696.2 84650.6 84970.0 8620.7 8747.4 8774.5 8906.4 8970.1 47491.3 51509.5 52070.2 53152.5 53665.7 52468.5 67513.7 68872.7 69209.0 69514.7 12706.4 12975.2 13262.0 13410.9 13480.5 11004.9 13440.9 13462.5 14072.2 14354.7 13283.4 13460.5 13541.5 13641.9 13697.9 13500.3 13535.3 13797.6 13874.0 14198.8 12717.6 13075.3 13213.4 13337.9 13781.6 10707.6 11102.8 11109.7 11220.7 11702.5 10552.9 10743.1 10806.4 10832.7 10841.2 08:28:58 INFO - PROCESS | 1647 | -------- Summary: end -------- 08:28:58 INFO - PROCESS | 1647 | 08:28:58 INFO - TEST-INFO | 1647: exit 0 08:28:58 INFO - TEST-OK | dromaeo_css | took 1119140ms 08:28:58 INFO - TEST-START | kraken 08:28:58 INFO - operating with platform_type : mac_ 08:28:58 INFO - Initialising browser for kraken test... 08:28:58 INFO - TEST-INFO | started process 1653 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/tmpjYxiKe/profile http://localhost:49243/getInfo.html) 08:28:59 INFO - PROCESS | 1653 | 1483115339243 addons.xpi WARN Exception running bootstrap method install on shield-recipe-client@mozilla.org: [Exception... "Component returned failure code: 0x8000ffff (NS_ERROR_UNEXPECTED) [nsIPrefBranch.getBoolPref]" nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js :: this.install :: line 38" data: no] Stack trace: this.install()@resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js:38 < callBootstrapMethod()@resource://gre/modules/addons/XPIProvider.jsm:4986 < processFileChanges()@resource://gre/modules/addons/XPIProvider.jsm -> resource://gre/modules/addons/XPIProviderUtils.js:2097 < checkForChanges()@resource://gre/modules/addons/XPIProvider.jsm:3827 < startup()@resource://gre/modules/addons/XPIProvider.jsm:2831 < callProvider()@resource://gre/modules/AddonManager.jsm:264 < _startProvider()@resource://gre/modules/AddonManager.jsm:771 < startup()@resource://gre/modules/AddonManager.jsm:957 < startup()@resource://gre/modules/AddonManager.jsm:2923 < observe()@resource://gre/components/addonManager.js:65 08:29:01 INFO - PROCESS | 1653 | __metrics Screen width/height:1600/1200 08:29:01 INFO - PROCESS | 1653 | colorDepth:24 08:29:01 INFO - PROCESS | 1653 | Browser inner width/height: 1024/689 08:29:01 INFO - PROCESS | 1653 | __metrics 08:29:01 INFO - PROCESS | 1653 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 08:29:01 INFO - PROCESS | 1653 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 08:29:01 INFO - TEST-INFO | 1653: exit 0 08:29:01 INFO - Browser initialized. 08:29:01 INFO - Running cycle 1/1 for kraken test... 08:29:01 INFO - TEST-INFO | started process 1654 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/tmpjYxiKe/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:29:13 INFO - PROCESS | 1654 | RSS: Main: 234057728 08:29:13 INFO - PROCESS | 1654 | 08:29:16 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49243/tests/kraken/driver.html?testName=audio-beat-detection) 08:29:17 INFO - PROCESS | 1654 | RSS: Main: 286412800 08:29:17 INFO - PROCESS | 1654 | 08:29:20 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49243/tests/kraken/driver.html?testName=audio-dft) 08:29:20 INFO - PROCESS | 1654 | RSS: Main: 286302208 08:29:20 INFO - PROCESS | 1654 | 08:29:24 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49243/tests/kraken/driver.html?testName=audio-fft) 08:29:24 INFO - PROCESS | 1654 | RSS: Main: 379674624 08:29:24 INFO - PROCESS | 1654 | 08:29:27 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49243/tests/kraken/driver.html?testName=audio-oscillator) 08:29:27 INFO - PROCESS | 1654 | RSS: Main: 282222592 08:29:27 INFO - PROCESS | 1654 | 08:29:29 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49243/tests/kraken/driver.html?testName=imaging-gaussian-blur) 08:29:30 INFO - PROCESS | 1654 | RSS: Main: 286130176 08:29:30 INFO - PROCESS | 1654 | 08:29:33 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49243/tests/kraken/driver.html?testName=imaging-darkroom) 08:29:33 INFO - PROCESS | 1654 | RSS: Main: 326942720 08:29:33 INFO - PROCESS | 1654 | 08:29:36 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49243/tests/kraken/driver.html?testName=imaging-desaturate) 08:29:37 INFO - PROCESS | 1654 | RSS: Main: 328843264 08:29:37 INFO - PROCESS | 1654 | 08:29:40 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49243/tests/kraken/driver.html?testName=json-parse-financial) 08:29:40 INFO - PROCESS | 1654 | RSS: Main: 326066176 08:29:40 INFO - PROCESS | 1654 | 08:29:42 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49243/tests/kraken/driver.html?testName=json-stringify-tinderbox) 08:29:43 INFO - PROCESS | 1654 | RSS: Main: 304115712 08:29:43 INFO - PROCESS | 1654 | 08:29:44 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49243/tests/kraken/driver.html?testName=stanford-crypto-aes) 08:29:45 INFO - PROCESS | 1654 | RSS: Main: 305336320 08:29:45 INFO - PROCESS | 1654 | 08:29:47 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49243/tests/kraken/driver.html?testName=stanford-crypto-ccm) 08:29:48 INFO - PROCESS | 1654 | RSS: Main: 308789248 08:29:48 INFO - PROCESS | 1654 | 08:29:51 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49243/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 08:29:52 INFO - PROCESS | 1654 | RSS: Main: 325566464 08:29:52 INFO - PROCESS | 1654 | 08:29:55 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49243/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 08:29:55 INFO - PROCESS | 1654 | RSS: Main: 320086016 08:29:55 INFO - PROCESS | 1654 | 08:29:57 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49243/tests/kraken/driver.html?testName=ai-astar) 08:29:57 INFO - PROCESS | 1654 | RSS: Main: 364167168 08:29:57 INFO - PROCESS | 1654 | 08:29:57 INFO - PROCESS | 1654 | __start_tp_report 08:29:57 INFO - PROCESS | 1654 | _x_x_mozilla_page_load 08:29:57 INFO - PROCESS | 1654 | _x_x_mozilla_page_load_details 08:29:57 INFO - PROCESS | 1654 | |i|pagename|runs| 08:29:57 INFO - PROCESS | 1654 | |0;ai-astar;115;115;115;114;115;115;115;115;115;113 08:29:57 INFO - PROCESS | 1654 | |1;audio-beat-detection;148;115;111;149;113;110;137;139;156;112 08:29:57 INFO - PROCESS | 1654 | |2;audio-dft;188;182;184;187;182;195;260;177;260;166 08:29:57 INFO - PROCESS | 1654 | |3;audio-fft;79;58;59;87;77;79;82;51;76;79 08:29:57 INFO - PROCESS | 1654 | |4;audio-oscillator;90;69;87;69;69;69;83;69;69;84 08:29:57 INFO - PROCESS | 1654 | |5;imaging-gaussian-blur;93;90;92;89;92;89;92;89;92;87 08:29:57 INFO - PROCESS | 1654 | |6;imaging-darkroom;133;96;97;99;97;97;96;99;97;96 08:29:57 INFO - PROCESS | 1654 | |7;imaging-desaturate;88;86;88;87;88;84;86;87;85;88 08:29:57 INFO - PROCESS | 1654 | |8;json-parse-financial;57;57;57;53;56;58;58;57;58;55 08:29:57 INFO - PROCESS | 1654 | |9;json-stringify-tinderbox;67;74;82;77;73;71;71;72;71;73 08:29:57 INFO - PROCESS | 1654 | |10;stanford-crypto-aes;88;90;88;87;91;86;86;86;93;86 08:29:57 INFO - PROCESS | 1654 | |11;stanford-crypto-ccm;148;157;156;146;148;160;146;161;184;139 08:29:57 INFO - PROCESS | 1654 | |12;stanford-crypto-pbkdf2;176;174;172;169;188;177;174;170;175;169 08:29:57 INFO - PROCESS | 1654 | |13;stanford-crypto-sha256-iterative;67;67;68;66;67;66;67;67;65;67 08:29:57 INFO - PROCESS | 1654 | __end_tp_report 08:29:57 INFO - PROCESS | 1654 | __start_cc_report 08:29:57 INFO - PROCESS | 1654 | _x_x_mozilla_cycle_collect,1981 08:29:57 INFO - PROCESS | 1654 | __end_cc_report 08:29:57 INFO - PROCESS | 1654 | __startTimestamp1483115397730__endTimestamp 08:29:57 INFO - PROCESS | 1654 | 08:29:57 INFO - PROCESS | 1654 | ------- Summary: start ------- 08:29:57 INFO - PROCESS | 1654 | Number of tests: 14 08:29:57 INFO - PROCESS | 1654 | 08:29:57 INFO - PROCESS | 1654 | [#0] ai-astar Cycles:10 Average:114.70 Median:115.00 stddev:0.67 (0.6%) stddev-sans-first:0.71 08:29:57 INFO - PROCESS | 1654 | Values: 115.0 115.0 115.0 114.0 115.0 115.0 115.0 115.0 115.0 113.0 08:29:57 INFO - PROCESS | 1654 | 08:29:57 INFO - PROCESS | 1654 | [#1] audio-beat-detection Cycles:10 Average:129.00 Median:138.00 stddev:18.50 (13.4%) stddev-sans-first:18.30 08:29:57 INFO - PROCESS | 1654 | Values: 148.0 115.0 111.0 149.0 113.0 110.0 137.0 139.0 156.0 112.0 08:29:57 INFO - PROCESS | 1654 | 08:29:57 INFO - PROCESS | 1654 | [#2] audio-dft Cycles:10 Average:198.10 Median:187.50 stddev:33.48 (17.9%) stddev-sans-first:35.32 08:29:57 INFO - PROCESS | 1654 | Values: 188.0 182.0 184.0 187.0 182.0 195.0 260.0 177.0 260.0 166.0 08:29:57 INFO - PROCESS | 1654 | 08:29:57 INFO - PROCESS | 1654 | [#3] audio-fft Cycles:10 Average:72.70 Median:79.00 stddev:12.08 (15.3%) stddev-sans-first:12.60 08:29:57 INFO - PROCESS | 1654 | Values: 79.0 58.0 59.0 87.0 77.0 79.0 82.0 51.0 76.0 79.0 08:29:57 INFO - PROCESS | 1654 | 08:29:57 INFO - PROCESS | 1654 | [#4] audio-oscillator Cycles:10 Average:75.80 Median:76.00 stddev:8.97 (11.8%) stddev-sans-first:7.90 08:29:57 INFO - PROCESS | 1654 | Values: 90.0 69.0 87.0 69.0 69.0 69.0 83.0 69.0 69.0 84.0 08:29:57 INFO - PROCESS | 1654 | 08:29:57 INFO - PROCESS | 1654 | [#5] imaging-gaussian-blur Cycles:10 Average:90.50 Median:92.00 stddev:1.96 (2.1%) stddev-sans-first:1.86 08:29:57 INFO - PROCESS | 1654 | Values: 93.0 90.0 92.0 89.0 92.0 89.0 92.0 89.0 92.0 87.0 08:29:57 INFO - PROCESS | 1654 | 08:29:57 INFO - PROCESS | 1654 | [#6] imaging-darkroom Cycles:10 Average:100.70 Median:97.00 stddev:11.40 (11.8%) stddev-sans-first:1.17 08:29:57 INFO - PROCESS | 1654 | Values: 133.0 96.0 97.0 99.0 97.0 97.0 96.0 99.0 97.0 96.0 08:29:57 INFO - PROCESS | 1654 | 08:29:57 INFO - PROCESS | 1654 | [#7] imaging-desaturate Cycles:10 Average:86.70 Median:87.50 stddev:1.42 (1.6%) stddev-sans-first:1.42 08:29:57 INFO - PROCESS | 1654 | Values: 88.0 86.0 88.0 87.0 88.0 84.0 86.0 87.0 85.0 88.0 08:29:57 INFO - PROCESS | 1654 | 08:29:57 INFO - PROCESS | 1654 | [#8] json-parse-financial Cycles:10 Average:56.60 Median:57.00 stddev:1.58 (2.8%) stddev-sans-first:1.67 08:29:57 INFO - PROCESS | 1654 | Values: 57.0 57.0 57.0 53.0 56.0 58.0 58.0 57.0 58.0 55.0 08:29:57 INFO - PROCESS | 1654 | 08:29:57 INFO - PROCESS | 1654 | [#9] json-stringify-tinderbox Cycles:10 Average:73.10 Median:73.00 stddev:4.04 (5.5%) stddev-sans-first:3.63 08:29:57 INFO - PROCESS | 1654 | Values: 67.0 74.0 82.0 77.0 73.0 71.0 71.0 72.0 71.0 73.0 08:29:57 INFO - PROCESS | 1654 | 08:29:57 INFO - PROCESS | 1654 | [#10] stanford-crypto-aes Cycles:10 Average:88.10 Median:88.00 stddev:2.47 (2.8%) stddev-sans-first:2.62 08:29:57 INFO - PROCESS | 1654 | Values: 88.0 90.0 88.0 87.0 91.0 86.0 86.0 86.0 93.0 86.0 08:29:57 INFO - PROCESS | 1654 | 08:29:57 INFO - PROCESS | 1654 | [#11] stanford-crypto-ccm Cycles:10 Average:154.50 Median:156.50 stddev:12.56 (8.0%) stddev-sans-first:13.10 08:29:57 INFO - PROCESS | 1654 | Values: 148.0 157.0 156.0 146.0 148.0 160.0 146.0 161.0 184.0 139.0 08:29:57 INFO - PROCESS | 1654 | 08:29:57 INFO - PROCESS | 1654 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:174.40 Median:174.50 stddev:5.56 (3.2%) stddev-sans-first:5.87 08:29:57 INFO - PROCESS | 1654 | Values: 176.0 174.0 172.0 169.0 188.0 177.0 174.0 170.0 175.0 169.0 08:29:57 INFO - PROCESS | 1654 | 08:29:57 INFO - PROCESS | 1654 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.70 Median:67.00 stddev:0.82 (1.2%) stddev-sans-first:0.87 08:29:57 INFO - PROCESS | 1654 | Values: 67.0 67.0 68.0 66.0 67.0 66.0 67.0 67.0 65.0 67.0 08:29:57 INFO - PROCESS | 1654 | -------- Summary: end -------- 08:29:57 INFO - PROCESS | 1654 | 08:29:58 INFO - TEST-INFO | 1654: exit 0 08:29:58 INFO - TEST-OK | kraken | took 60024ms 08:29:58 INFO - SUITE-END | took 1179s 08:29:58 INFO - Completed test suite (00:19:39) 08:29:58 INFO - javascript benchmark 08:29:58 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3097.876084956601, 3343.2310568569237, 3365.394229038772, 3375.493675948609, 3426.177717347837, 5436.565074794017, 5480.424555421683, 5619.831405057845, 5648.26572545569, 5678.943210567893, 4897.0, 5533.557315414767, 5717.456841600052, 5719.1421286807, 5763.682997435139, 5059.822906198284, 5072.137736584771, 5078.974605126969, 5148.613853960949, 5158.9742051289695, 5154.6134039946965, 5228.947710522884, 5238.607104467161, 5240.816571420001, 5296.523312901838, 5065.518775716301, 5170.870728231825, 5195.584353251768, 5195.6622819516615, 5231.476852314757, 5077.796888124471, 5112.744362781846, 5136.101182293084, 5137.049645815536, 5145.819896303648, 5031.597472202252, 5084.847454576368, 5088.185890257578, 5095.337606111182, 5096.51583099605, 5553.028220061514, 5557.666540007613, 5557.972210138923, 5559.082751346023, 5570.6657600544095, 6103.633781973096, 6111.35830737775, 6112.0, 6121.143039974407, 6121.785737499211, 6106.603070800428, 6111.47019340724, 6117.248930028882, 6130.256732558205, 6136.470306274282, 5579.525740312079, 5644.97177514118, 5651.886962260814, 5655.293088363955, 5665.631733937281, 6230.7819226327765, 6279.120923070774, 6285.277193122758, 6305.180326557518, 6318.620882746958, 6032.245969253843, 6038.215032045894, 6044.516438684895, 6049.1833602463985, 6058.1215723719815, 6041.214642096587, 6079.300880398722, 6094.421030002142, 6124.136831511461, 6153.538484613672, 5290.259363689087, 5908.438698323653, 6083.0, 6156.846078847975, 6159.23009623797, 6119.724612392453, 6126.210730859295, 6140.057253910645, 6156.322804491502, 6172.135900973867, 4086.4687590613626, 4776.259679749644, 4870.902581948341, 4877.975610121926, 4898.755062246873, 4265.168292182994, 4302.290122129876, 4305.354196870432, 4348.478182618106, 4354.346847972829, 5593.133064375028, 5692.829215123553, 5700.144978253295, 5714.000049991235, 5743.2246646703, 6211.65835879031, 6218.005119181, 6221.968890155611, 6222.377762223741, 6233.844153896189, 5564.054110801174, 5592.608517403824, 5618.634788738719, 5647.124695672177, 5682.346530149086, 5295.788168473218, 5311.707856067954, 5317.494837990384, 5320.068987927175, 5392.676439413648, 6273.905891411632, 6278.968605156945, 6299.559030867796, 6307.653079080511, 6314.400131987454, 6177.907331390123, 6179.721912514037, 6198.473129784019, 6241.937580624136, 6268.0, 6220.129181914445, 6225.190725205694, 6248.531360148098, 6261.217347831521, 6292.339304373158, 5949.286085669747, 6011.308699499614, 6024.0662697282, 6026.276846778414, 6144.662043587646, 6136.028952951454, 6138.0, 6180.505559555315, 6191.938080619317, 6216.689165541795, 5997.730102145327, 6002.849928751816, 6011.428914253139, 6013.037913933749, 6013.789517366871, 5832.562557808266, 5882.5293976482635, 5882.999890018621, 5983.670898100646, 6029.0, 5838.211841401357, 5911.970440147772, 5919.970400147971, 5963.194968679346, 5977.581569290108, 6051.969740151271, 6054.84862878414, 6055.2430946131735, 6063.5452341073715, 6072.210612620331, 5660.4905558498285, 5677.318721753416, 5739.540836732968, 5851.736671849691, 5907.5273978080795], "value": 5611.181810749545, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [435.7385568658805, 500.17471172565286, 500.33887493521524, 516.8604476791267, 517.8938317644885, 29194.854025729866, 38346.42480362797, 39406.80296598517, 39449.0, 41822.745317640445, 40618.0, 41035.38446923299, 41065.794671026604, 41152.0, 41227.175456490855, 8325.583720813966, 9083.591238394289, 9288.164065234128, 9311.068893110703, 9312.30157738169, 8368.995720513547, 10873.401962892038, 11125.499352529116, 11136.777264454706, 11336.943315283412, 11081.722956926102, 11137.443127843617, 11143.665690029313, 11157.2747771395, 11167.0, 11060.834087488694, 11150.832737508943, 11153.888461115366, 11180.329180249211, 11197.776044479146, 10907.400092994882, 11007.944960275147, 11009.229353945228, 11017.889821101766, 11023.503942322535, 38292.425613615815, 38355.808220959, 38594.22811543753, 38654.0, 38656.61343386587, 3913.2173565287057, 3973.1259122992897, 3985.70107241958, 3996.140829721624, 4040.2121586290687, 28927.132186034454, 29275.414491710046, 29535.261618459495, 29795.828583998256, 29853.552196717068, 3209.47043737783, 3211.277462570926, 3219.356128774231, 3238.6599407062154, 3251.1709514073996, 338383.0, 338990.6100938984, 339105.3044734785, 339414.30292848375, 339826.0, 53349.0, 53894.191587126224, 53895.0, 53933.0, 54037.0, 56104.15843762347, 57421.42578574161, 57702.71148644314, 57708.13437798437, 59035.70482147648, 15001.0, 15195.924020379827, 15213.0, 15242.542723718307, 15266.313015914311, 10586.788264234827, 10818.891811081945, 10825.78348433027, 11802.763944721057, 11882.76234475323, 16586.477374760794, 17388.565285867702, 19842.404727858186, 20207.191712331634, 20664.690029649566, 8007.479513831582, 8139.9475196801195, 8370.79073023167, 8380.790480238044, 8398.244158025685, 801.338895411288, 841.3984001439001, 843.409613270713, 856.178069053703, 879.784452809053, 26336.34159146037, 28261.72822223005, 28742.0, 28927.56608650872, 29196.562051569243, 1014.9949250253849, 1048.7168464514539, 1061.532925512772, 1063.9361638301573, 1116.8715597706205, 13750.931245343709, 14226.430942762381, 14656.974011819071, 15050.473233436996, 15166.317515711598, 581227.0, 616165.0, 616285.0, 616747.0, 617636.0, 54547.45452545503, 54974.90050198974, 55027.724861375435, 55122.17316740252, 55183.72408137934, 1166.8191430328313, 1189.5895915908998, 1381.0608786025598, 1385.9792103118664, 1388.1462900316233, 27105.0, 27762.0, 28357.291067723472, 28572.0, 28738.568921465776, 46336.30495542637, 47041.52958470371, 47133.76433117812, 47137.76431117822, 47169.764151180396, 556108.2194589163, 556289.0, 558126.0, 558566.0, 560591.0, 25880.094196703027, 26042.0, 26243.0, 26291.474170516864, 26357.472850543265, 43850.342244865715, 44081.0, 44622.77688611536, 45143.0, 45164.0, 36332.27335453223, 36988.0, 37337.0, 37761.81119094387, 37999.43000854934, 34547.82726086354, 35633.72395312579, 36105.0, 37021.0, 37499.625003749614, 33728.98813035613, 33829.0, 34274.0, 34403.655963441044, 34678.479822802175, 29400.7059929398, 31018.0, 31129.221769455944, 31162.532562011136, 31365.372692545567, 23278.883605581865, 23402.0, 23462.296131116094, 23635.29094127179, 23724.762752372255], "value": 19294.624475869623, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [297.8853141540509, 369.24120931485805, 370.63492459926965, 375.82148479472244, 399.7581463214755, 14454.132752034871, 14688.339024743886, 15625.3749850006, 19474.221031158737, 19986.200551977905, 18903.621927561442, 19838.900805495952, 20012.399628011146, 20021.299254526093, 20284.0, 5434.266374039514, 5737.454941780545, 5739.655620662753, 5752.079667253241, 5759.740811663485, 4999.475055119215, 5665.886682266352, 6347.968260158693, 6387.105805187278, 6447.226332840042, 6031.457168854802, 6396.552241343108, 6407.87184256317, 6422.0, 6430.839229019265, 6296.087067375228, 6332.303446620868, 6370.58591191571, 6390.648514331687, 6415.1981002374705, 4795.928061079087, 5280.392754833205, 5492.011437941169, 5543.417941116165, 5565.6104072714925, 24550.140745074015, 24575.0, 24783.5043299133, 24942.00231990718, 25101.87449062743, 2727.236373815315, 2736.9178924632292, 2748.1068652687754, 2753.600727894444, 2831.7309855563894, 15015.849841501555, 15226.010309329859, 15270.694586108215, 15369.846301536953, 15459.922700386425, 1810.8189181081918, 1826.6346730653922, 1832.651796158739, 1843.3548258109688, 1893.2427029188298, 107187.46406267997, 109096.4545177277, 109661.45169274183, 109671.0, 110644.4467777664, 10364.585416583403, 10636.946815265874, 10656.307340022873, 10710.303830251009, 10814.405207713495, 13238.53665121716, 13249.205047697169, 13250.602481925558, 13264.73470530584, 13290.734185316433, 7965.084015338194, 8102.230288122619, 8123.350131989427, 8264.421490495608, 8290.917090829014, 8066.395020373496, 8067.798305042421, 8090.231428014329, 8265.338772898154, 8273.0, 5844.386339434298, 5874.0014197586515, 6089.360617135226, 6201.720922558496, 6324.3043265240785, 1738.97391539127, 1777.680017596845, 1778.386456672446, 1786.0712429536827, 1786.56229223841, 996.5465713100522, 1006.597361055569, 1013.4426065663855, 1024.7284469615558, 1051.8737751469873, 14151.85848141526, 19967.0, 20893.164273428894, 20940.685889711665, 21149.0, 1018.4856647393019, 1021.7241344837001, 1030.6444276724667, 1039.3504059962524, 1076.3757020927842, 10610.787784244272, 10935.331944119898, 10988.835167472495, 11097.500612472459, 11146.386948717736, 504443.0, 508928.0, 546832.0, 562239.0, 563954.0, 74032.6354310397, 91645.0, 127938.0, 130691.34654326858, 131193.68806312006, 994.7314225159166, 1024.5696807340935, 1066.2056767708107, 1081.8755843078147, 1083.9403832789271, 18069.00620465887, 18229.726554101966, 18392.26430942747, 18620.813791861907, 18738.063096845377, 44505.77747111244, 47593.52406476029, 51670.70823229449, 51673.7416312916, 52068.0, 71945.0, 72250.0, 72777.0, 74861.62569187119, 74898.0, 6621.370969757865, 7228.301349182832, 8016.959915200387, 8022.713526645111, 8176.550289734122, 12601.369931503572, 12613.873861261636, 12699.492020319083, 13006.869931300565, 13104.279264640536, 10844.0, 11070.114390848874, 11159.819451456297, 11246.268992515461, 11254.94372528132, 5258.8948221036135, 9620.460726283762, 10960.671179864617, 10971.670849874517, 11272.380019099028, 11055.778884422105, 11362.886371136514, 11399.316041037233, 11412.603353295568, 11462.656120316404, 8013.959930200312, 8020.518768873886, 8023.398245131521, 8087.150849160988, 8092.231238032377, 8161.918380816116, 10563.735906602396, 11162.939520745516, 11189.552417903193, 11231.40595009589], "value": 9529.541883676657, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1313.4417872404229, 1347.89216862651, 1370.616227456312, 1371.8559551247113, 1373.7527245095878, 7948.284654381112, 8294.046184688748, 8335.499870007796, 8340.833183336345, 8357.582120893954, 7321.6705248263825, 7579.6589153488085, 7592.50648707835, 8040.276375126252, 8075.394345424102, 2250.8086812620945, 2254.616715158427, 2260.9434764130947, 2269.54609078185, 2295.288460577218, 2259.491614386758, 2292.8509646872985, 2296.5292115116445, 2298.839081264312, 2301.5857145713767, 2011.1050582490798, 2021.403685912652, 2085.4577809769494, 2099.748030236374, 2131.6269652810774, 2255.875926824024, 2258.6386178211487, 2261.8642881427168, 2264.8980795864227, 2268.886555672218, 2255.1994042114975, 2266.580682573729, 2273.8067264282554, 2274.385915802724, 2280.2019293247395, 8178.100408955011, 8249.587520624005, 8261.297789687824, 8286.544240066854, 8286.8342633147, 4953.43035550909, 5116.334876466036, 5124.92312615311, 5129.717865517395, 5136.974315128437, 3116.143060658337, 3262.902112936592, 3286.5398844161837, 3401.3197360527993, 3484.216051388443, 1161.181367136167, 1260.0927332320714, 1266.0567876931746, 1702.2680247493572, 1749.8425141737305, 10609.787804243872, 10623.628173013985, 10692.518836652369, 10711.0, 10755.946220268926, 4122.484689413824, 4130.463039804806, 4321.2005778931, 4383.62739167168, 4391.5388884166705, 8496.192861678132, 8499.702510412106, 8513.19124683154, 8531.658733650584, 8545.529995850287, 4749.715017098985, 4837.201861692852, 4846.103470857903, 4850.175470170079, 4867.0, 4204.516480604709, 4214.831406743757, 4352.586504282088, 4532.093581283756, 4548.909021819612, 3459.41189997701, 3475.461303497962, 3601.1717305019993, 3607.5129857469433, 3616.4213725803747, 990.5443495991781, 996.0935548650693, 1004.6935684616262, 1008.7629407089328, 1011.3173607814696, 855.3584811391456, 864.3603733237359, 874.7856775090016, 911.8358695434886, 915.9954200228956, 4653.60444362233, 5519.144532597454, 5656.604037717402, 5665.3201615806365, 5894.498967587803, 861.7113267055606, 891.2112780189578, 911.5487833522317, 915.514777168102, 922.9446233225893, 3638.290533345972, 3828.2152158807376, 3899.259140763246, 3900.8829735107424, 3920.0, 9324.300677449219, 9701.175400091071, 9889.950550247202, 9905.009499050037, 9911.256655750847, 9555.18780903631, 9828.852567211497, 9846.310758246997, 9863.506824658738, 9910.207183425453, 7367.336939675455, 7623.809404764926, 7629.923700763033, 7718.073831140299, 7735.61321933901, 5236.502532259429, 5477.123660214346, 5485.204645326485, 5504.422035686195, 5627.268455100811, 9090.727278181665, 9133.132352426508, 9180.495072770795, 9206.769830754018, 9333.719988400358, 9257.583408746488, 9279.907200927904, 9288.72133835986, 9343.81312373735, 9375.859362109437, 6538.313477084837, 6753.0, 6997.440204783707, 7160.212376638461, 7198.820029499305, 7171.533850299714, 7833.764987050397, 7913.881291780743, 7968.282854543119, 7984.8802267967185, 7018.684159212746, 7073.292670732885, 7083.150021997393, 7138.072050633385, 7241.601711905896, 6965.79102626922, 6997.475189360716, 7018.75434359795, 7057.894131588133, 7092.680829362588, 6159.938400615936, 6284.057391391145, 6345.333739957238, 6358.650274234961, 6376.840578985562, 4658.93011604833, 4683.625309975126, 4721.0, 4760.428748550196, 4829.613630909589, 4642.86071417858, 4678.742669153093, 4754.667173297974, 4825.4933232010135, 4928.605711542997], "value": 4293.417433226504, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [348.1661420896952, 353.126013117534, 357.8210894552724, 366.67915573872864, 369.1030795167743, 4414.735115893045, 4638.605718513922, 5122.897542049157, 5287.206918962158, 5329.786808527659, 4786.760661966897, 5094.974525127369, 5192.299039629658, 5221.24291977664, 5279.868003299919, 3426.4688973209068, 4083.8570650027254, 4126.31915733905, 4137.565555616662, 4180.853670121539, 3377.847996840148, 3456.965430345702, 3887.552931412881, 3996.700247481436, 4095.870147523286, 3624.3476174288617, 4038.899027524306, 4052.371882358239, 4089.1003979124544, 4103.312330423562, 3786.5456145262747, 4024.074462873541, 4040.4949381327333, 4054.229696357712, 4073.2668119738437, 3142.9471127172424, 3486.5594084141203, 3573.5711714594154, 3584.6773790358734, 3596.946045809315, 4221.387898754694, 5024.8662384974705, 5109.003744269869, 5134.971686183973, 5144.456663001122, 4248.468941382327, 4427.889302767424, 4604.066292157268, 4676.438827340707, 4697.2484402495775, 5127.512886275797, 5439.77605038867, 5457.688426143382, 5493.697846618435, 5519.475649813261, 3797.715171362159, 3928.5482169550583, 3929.1748732766155, 3935.645791878716, 3961.564227934925, 5080.0, 5725.799597014126, 5746.942530574683, 5767.011535022176, 5795.565332600072, 3428.103252751421, 4005.499312585927, 4024.839006439768, 4028.67770578353, 4046.8785936421955, 3597.9776987330247, 3921.941170882439, 4084.8546619103718, 4094.6692324994497, 4123.278426275389, 3909.6872250219913, 4627.316156629147, 4633.7683115844075, 4655.790489427984, 4665.4168228971375, 5298.337707786527, 5546.77812887488, 5562.30349743324, 5567.137093750475, 5570.888582228333, 1181.5569161564413, 1198.5804968261214, 1238.219921449499, 1272.866349033338, 1307.6338625184962, 810.789194809342, 882.7156487310947, 893.5702875399309, 926.6525053105086, 947.701648741229, 4077.938830917539, 4896.0, 4916.508349165055, 4969.3291405659775, 4970.776315065831, 4814.9518504815205, 4961.454240033593, 5103.464136265638, 5240.0418104104265, 5423.728813559306, 4323.329883868006, 4375.649948004152, 4489.0, 4677.006715701954, 4895.951040489549, 2606.283272100188, 2654.6548948636555, 2753.747045094477, 2776.2642899631614, 2839.134064110466, 4706.835260765857, 4733.881652958704, 5135.255387968724, 5591.804286849941, 5631.058259218564, 4771.475137734847, 4991.376077990251, 5494.36814766299, 5518.724063796794, 5525.0, 5051.564935843661, 5186.766595503212, 5413.756380962866, 5558.0, 5605.607607467519, 4195.748295071344, 4630.421197350332, 4672.48602653701, 5263.6313658852905, 5392.514673679388, 5301.269846029375, 5311.893762124813, 5328.626996110235, 5331.733413329241, 5348.572114230775, 4915.230517013801, 5043.470435604208, 5058.822941197041, 5061.392632884077, 5640.88718225625, 3341.899743007714, 3672.614375490535, 3794.3170229357875, 3867.5358956925347, 3909.5730058529, 4763.7602533745685, 4991.650584459053, 5348.411674715856, 5360.705161216015, 5377.731113444234, 5110.798962243795, 5203.836930455696, 5213.0, 5241.187615919539, 5254.211868219798, 4652.976735116303, 4905.48492408292, 5169.974150129226, 5245.570834186598, 5304.363476382859, 4822.68652537584, 4981.8043669519775, 5108.0, 5120.411152717336, 5251.789928402821, 4947.356843610308, 5221.947780522146, 5349.518543331119, 5379.386184144819, 5406.729663516887, 4643.0, 4743.430788305425, 5181.58524342071, 5411.783528658967, 5439.972800135974], "value": 3963.7282375435952, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [708.5288283291611, 714.5391222661383, 714.5569746757011, 759.6695437484691, 846.4963346808648, 14244.928775356122, 14763.261836908165, 15185.392584296642, 15189.164595947219, 15268.236588170588, 13626.250556219404, 13722.039457237997, 13863.445462181526, 13908.930455347709, 15056.473023444181, 838.6980686952708, 865.5412631305418, 868.6525389844049, 869.3002133282705, 870.3254977392509, 862.2627653356384, 863.1799790199304, 865.3336930563462, 865.6537385045968, 866.9133086691344, 925.3383830561147, 927.3832901120746, 928.0348437624862, 928.0811996123823, 930.2790337488433, 918.9035151309083, 919.5861862161987, 920.8756817829608, 920.8848893888264, 921.9769505762376, 678.995087270837, 679.1578442731027, 679.3546131175403, 679.5718697220768, 680.3774546290188, 15016.948813583054, 15017.624559386102, 15026.624334391618, 15052.397904083824, 15083.698326033527, 13210.66973325665, 13338.855429201625, 13608.31958402086, 13748.725025499534, 13796.0, 11436.37099959502, 11497.540098396054, 11550.0, 11814.11394145434, 12087.153899227058, 3628.129248980252, 3631.1466805300734, 3645.5989841117457, 3657.4696668982847, 3793.867214647502, 62960.01699512376, 86523.0, 87283.0, 106810.0, 107622.92377076208, 10577.62978295756, 10773.353598784099, 10785.083267922157, 10817.0, 10993.0, 20826.583468330547, 21401.67897481539, 21436.892815535823, 21466.24868129608, 21487.57024859494, 7609.923900761032, 7649.770506884803, 7754.302112809874, 7800.804979875548, 7908.248716371935, 4232.470941132358, 4313.741175529415, 4329.350597410414, 4339.045410009856, 4429.446319210098, 3275.8034517929, 3502.824858757052, 3513.718902487795, 3593.4969104325414, 3630.328389247945, 560.716837996809, 649.8407890066964, 657.8750037492957, 662.5505219569102, 673.4242222899449, 608.3672980100735, 631.9399657032575, 653.9149910511603, 654.4012228810681, 679.8572299817093, 8619.913800862036, 9272.0, 9281.164695177466, 9366.0, 9379.624815007459, 545.9945400546023, 622.7882519943241, 634.6572850660684, 668.7960172147444, 677.0926957876371, 3607.549056367954, 4299.0, 4490.820367185276, 4499.280115181556, 4528.954710452919, 105358.0, 106750.0, 107136.39295410574, 108275.0, 108287.45856270668, 91904.0, 91923.540382299, 93296.53351733198, 94929.5760563592, 95189.0481095194, 81660.59169704113, 83547.58226209073, 83696.16303837005, 84650.57674711588, 84970.0, 8620.741377758677, 8747.387682862138, 8774.473531588124, 8906.421082629608, 8970.147835955573, 47491.28763068626, 51509.48490515047, 52070.21894671659, 53152.46847531475, 53665.73167134295, 52468.47531524636, 67513.66243168752, 68872.6556367215, 69209.0, 69514.65242673755, 12706.428210730355, 12975.158695943348, 13262.005349598625, 13410.865891340962, 13480.460781568627, 11004.944975275072, 13440.93279533596, 13462.461501539829, 14072.155670659793, 14354.712905742035, 13283.40169212778, 13460.461581536629, 13541.526046588322, 13641.863581364456, 13697.863021370058, 13500.32498375097, 13535.255560944242, 13797.586072417844, 13874.0, 14198.787018194942, 12717.618471445872, 13075.346232688518, 13213.405396756976, 13337.933310333387, 13781.586552403443, 10707.57169713234, 11102.833457497982, 11109.722256943642, 11220.719482012688, 11702.473388697359, 10552.94614677297, 10743.140548755924, 10806.446790060612, 10832.67501974942, 10841.18691098155], "value": 7396.885419533197, "unit": "score"}], "name": "dromaeo_css", "value": 7116.4142097232825, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [115.0, 115.0, 115.0, 114.0, 115.0, 115.0, 115.0, 115.0, 115.0, 113.0], "value": 114.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 115.0, 111.0, 149.0, 113.0, 110.0, 137.0, 139.0, 156.0, 112.0], "value": 129.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [188.0, 182.0, 184.0, 187.0, 182.0, 195.0, 260.0, 177.0, 260.0, 166.0], "value": 198.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 58.0, 59.0, 87.0, 77.0, 79.0, 82.0, 51.0, 76.0, 79.0], "value": 72.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 69.0, 87.0, 69.0, 69.0, 69.0, 83.0, 69.0, 69.0, 84.0], "value": 75.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 90.0, 92.0, 89.0, 92.0, 89.0, 92.0, 89.0, 92.0, 87.0], "value": 90.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [133.0, 96.0, 97.0, 99.0, 97.0, 97.0, 96.0, 99.0, 97.0, 96.0], "value": 100.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 86.0, 88.0, 87.0, 88.0, 84.0, 86.0, 87.0, 85.0, 88.0], "value": 86.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 57.0, 57.0, 53.0, 56.0, 58.0, 58.0, 57.0, 58.0, 55.0], "value": 56.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 74.0, 82.0, 77.0, 73.0, 71.0, 71.0, 72.0, 71.0, 73.0], "value": 73.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 90.0, 88.0, 87.0, 91.0, 86.0, 86.0, 86.0, 93.0, 86.0], "value": 88.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 157.0, 156.0, 146.0, 148.0, 160.0, 146.0, 161.0, 184.0, 139.0], "value": 154.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [176.0, 174.0, 172.0, 169.0, 188.0, 177.0, 174.0, 170.0, 175.0, 169.0], "value": 174.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 67.0, 68.0, 66.0, 67.0, 66.0, 67.0, 67.0, 65.0, 67.0], "value": 66.7, "unit": "score"}], "name": "kraken", "value": 1481.6000000000001, "alertThreshold": 2.0}]} 08:29:58 INFO - Return code: 0 08:29:59 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 08:29:59 INFO - # TBPL SUCCESS # 08:29:59 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 08:29:59 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 08:29:59 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 08:29:59 INFO - 2016-12-30 08:29:59.052 screenresolution[1658:11036] starting screenresolution argv=screenresolution get 08:29:59 INFO - 2016-12-30 08:29:59.054 screenresolution[1658:11036] Display 0: 1600x1200x32@60 08:29:59 INFO - 2016-12-30 08:29:59.060 screenresolution[1659:11039] starting screenresolution argv=screenresolution list 08:29:59 INFO - Available Modes on Display 0 08:29:59 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 08:29:59 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 08:29:59 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 08:29:59 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 08:29:59 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 08:29:59 INFO - Intel Iris: 08:29:59 INFO - Chipset Model: Intel Iris 08:29:59 INFO - Type: GPU 08:29:59 INFO - Bus: Built-In 08:29:59 INFO - VRAM (Dynamic, Max): 1536 MB 08:29:59 INFO - Vendor: Intel (0x8086) 08:29:59 INFO - Device ID: 0x0a2e 08:29:59 INFO - Revision ID: 0x0009 08:29:59 INFO - Displays: 08:29:59 INFO - Display: 08:29:59 INFO - Resolution: 1600 x 1200 @ 60 Hz 08:29:59 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 08:29:59 INFO - Display Serial Number: 1600x1200 60 08:29:59 INFO - Main Display: Yes 08:29:59 INFO - Mirror: Off 08:29:59 INFO - Online: Yes 08:29:59 INFO - Rotation: Supported 08:29:59 INFO - Return code: 0 08:29:59 INFO - Running post-action listener: _resource_record_post_action 08:29:59 INFO - [mozharness: 2016-12-30 16:29:59.309660Z] Finished run-tests step (success) 08:29:59 INFO - Running post-run listener: _resource_record_post_run 08:29:59 INFO - Total resource usage - Wall time: 1202s; CPU: 54.0%; Read bytes: 33332224; Write bytes: 553178624; Read time: 309; Write time: 1745 08:29:59 INFO - TinderboxPrint: CPU usage
54.0% 08:29:59 INFO - TinderboxPrint: I/O read bytes / time
33,332,224 / 309 08:29:59 INFO - TinderboxPrint: I/O write bytes / time
553,178,624 / 1,745 08:29:59 INFO - TinderboxPrint: CPU idle
2,211.8 (46.0%) 08:29:59 INFO - TinderboxPrint: CPU system
965.5 (20.1%) 08:29:59 INFO - TinderboxPrint: CPU user
1,632.4 (33.9%) 08:29:59 INFO - TinderboxPrint: Swap in / out
278,151,168 / 0 08:29:59 INFO - install - Wall time: 23s; CPU: 54.0%; Read bytes: 228903424; Write bytes: 225080832; Read time: 17349; Write time: 514 08:29:59 INFO - run-tests - Wall time: 1180s; CPU: 54.0%; Read bytes: 32390144; Write bytes: 298983424; Read time: 294; Write time: 1172 08:29:59 INFO - Running post-run listener: _upload_blobber_files 08:29:59 INFO - Blob upload gear active. 08:29:59 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 08:29:59 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 08:29:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 08:29:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 08:30:00 INFO - (blobuploader) - INFO - Open directory for files ... 08:30:00 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 08:30:00 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:30:00 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:30:01 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 08:30:01 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:30:01 INFO - (blobuploader) - INFO - Done attempting. 08:30:01 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 08:30:01 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:30:01 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:30:03 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 08:30:03 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:30:03 INFO - (blobuploader) - INFO - Done attempting. 08:30:03 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 08:30:03 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:30:03 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:30:05 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 08:30:05 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:30:05 INFO - (blobuploader) - INFO - Done attempting. 08:30:05 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 08:30:05 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:30:05 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:30:07 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 08:30:07 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:30:07 INFO - (blobuploader) - INFO - Done attempting. 08:30:07 INFO - (blobuploader) - INFO - Iteration through files over. 08:30:07 INFO - Return code: 0 08:30:07 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 08:30:07 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 08:30:07 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/22097a29f98fac1a6b54808010ca7d7e0877a75bdaf8150d82b8832ea6e63cda513d622a459e13062b29cf3188b541c7a781f08692ecbe5312eaa874ada4908b", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b6a0f93294037b2539faf49d9ff3ce99d7c96ccb02fff0e1b7db26d5b5fa273b986739420b8992fc878a44ea72c784f16e57938b5313c7e295c077c914141c9e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/aa8565560ae05e111837150a554983e0c10254a9e070e144f3a3195b48cc96fca45d3be2aa817cae123fd6b74c3121914f9059336f8e51caacfe94c76aa281ae", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d59ffc9d8f9f71a29e53bb463fd484188ba37cee743278ad250bdd464d96cc0e10698dc5cdfff5de40c62f6e2746e407973d9781bfa4984382d3ee89f3052339"} 08:30:07 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 08:30:07 INFO - Writing to file /builds/slave/test/properties/blobber_files 08:30:07 INFO - Contents: 08:30:07 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/22097a29f98fac1a6b54808010ca7d7e0877a75bdaf8150d82b8832ea6e63cda513d622a459e13062b29cf3188b541c7a781f08692ecbe5312eaa874ada4908b", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b6a0f93294037b2539faf49d9ff3ce99d7c96ccb02fff0e1b7db26d5b5fa273b986739420b8992fc878a44ea72c784f16e57938b5313c7e295c077c914141c9e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/aa8565560ae05e111837150a554983e0c10254a9e070e144f3a3195b48cc96fca45d3be2aa817cae123fd6b74c3121914f9059336f8e51caacfe94c76aa281ae", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d59ffc9d8f9f71a29e53bb463fd484188ba37cee743278ad250bdd464d96cc0e10698dc5cdfff5de40c62f6e2746e407973d9781bfa4984382d3ee89f3052339"} 08:30:07 INFO - Running post-run listener: copy_logs_to_upload_dir 08:30:07 INFO - Copying logs to upload dir... 08:30:07 INFO - mkdir: /builds/slave/test/build/upload/logs 08:30:07 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1269.917018 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 9 secs) (at 2016-12-30 08:30:07.983962) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-30 08:30:07.987056) ========= 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.u2rbdYT9AW/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.B6pRCw4ESf/Listeners TMPDIR=/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/22097a29f98fac1a6b54808010ca7d7e0877a75bdaf8150d82b8832ea6e63cda513d622a459e13062b29cf3188b541c7a781f08692ecbe5312eaa874ada4908b", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b6a0f93294037b2539faf49d9ff3ce99d7c96ccb02fff0e1b7db26d5b5fa273b986739420b8992fc878a44ea72c784f16e57938b5313c7e295c077c914141c9e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/aa8565560ae05e111837150a554983e0c10254a9e070e144f3a3195b48cc96fca45d3be2aa817cae123fd6b74c3121914f9059336f8e51caacfe94c76aa281ae", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d59ffc9d8f9f71a29e53bb463fd484188ba37cee743278ad250bdd464d96cc0e10698dc5cdfff5de40c62f6e2746e407973d9781bfa4984382d3ee89f3052339"} build_url:https://queue.taskcluster.net/v1/task/aE6HW-0aQu2b659JrKFlgQ/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011110 build_url: 'https://queue.taskcluster.net/v1/task/aE6HW-0aQu2b659JrKFlgQ/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/22097a29f98fac1a6b54808010ca7d7e0877a75bdaf8150d82b8832ea6e63cda513d622a459e13062b29cf3188b541c7a781f08692ecbe5312eaa874ada4908b", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b6a0f93294037b2539faf49d9ff3ce99d7c96ccb02fff0e1b7db26d5b5fa273b986739420b8992fc878a44ea72c784f16e57938b5313c7e295c077c914141c9e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/aa8565560ae05e111837150a554983e0c10254a9e070e144f3a3195b48cc96fca45d3be2aa817cae123fd6b74c3121914f9059336f8e51caacfe94c76aa281ae", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d59ffc9d8f9f71a29e53bb463fd484188ba37cee743278ad250bdd464d96cc0e10698dc5cdfff5de40c62f6e2746e407973d9781bfa4984382d3ee89f3052339"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-30 08:30:08.020757) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-30 08:30:08.021069) ========= 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.u2rbdYT9AW/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.B6pRCw4ESf/Listeners TMPDIR=/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/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.005156 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-30 08:30:08.069862) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-30 08:30:08.070204) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-30 08:30:08.070553) ========= ========= Total master_lag: 0.23 =========