builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0305 starttime: 1484051937.64 results: success (0) buildid: 20170110031007 builduid: 76d992d6642c45859d6128c62c22c374 revision: acd4177b28d7862528f63edb40e3d5a5f0b7c8b3 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-01-10 04:38:57.638884) ========= master: http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-01-10 04:38:57.639342) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-10 04:38:57.639653) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-10 04:38:57.665212) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-10 04:38:57.665472) ========= 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.KwuVbZbFgu/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.Hm0cOpBB0I/Listeners TMPDIR=/var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2017-01-10 04:38:57-- 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% 137M=0s 2017-01-10 04:38:57 (137 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.127517 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-10 04:38:57.819863) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-10 04:38:57.820613) ========= 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.KwuVbZbFgu/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.Hm0cOpBB0I/Listeners TMPDIR=/var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/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.025415 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-10 04:38:57.872475) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-01-10 04:38:57.873235) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev acd4177b28d7862528f63edb40e3d5a5f0b7c8b3 --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 acd4177b28d7862528f63edb40e3d5a5f0b7c8b3 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.KwuVbZbFgu/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.Hm0cOpBB0I/Listeners TMPDIR=/var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2017-01-10 04:38:57,972 truncating revision to first 12 chars 2017-01-10 04:38:57,972 Setting DEBUG logging. 2017-01-10 04:38:57,972 attempt 1/10 2017-01-10 04:38:57,972 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/acd4177b28d7?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-01-10 04:38:59,680 unpacking tar archive at: mozilla-central-acd4177b28d7/testing/mozharness/ program finished with exit code 0 elapsedTime=1.985195 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-01-10 04:38:59.883425) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-10 04:38:59.883755) ========= script_repo_revision: acd4177b28d7862528f63edb40e3d5a5f0b7c8b3 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-10 04:38:59.884128) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-10 04:38:59.884389) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-10 04:38:59.903749) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 6 secs) (at 2017-01-10 04:38:59.904168) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --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-e10s', '--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.KwuVbZbFgu/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.Hm0cOpBB0I/Listeners TMPDIR=/var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 04:39:00 INFO - MultiFileLogger online at 20170110 04:39:00 in /builds/slave/test 04:39:00 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 04:39:00 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 04:39:00 INFO - {'append_to_log': False, 04:39:00 INFO - 'base_work_dir': '/builds/slave/test', 04:39:00 INFO - 'blob_upload_branch': 'Firefox', 04:39:00 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 04:39:00 INFO - 'branch': 'Firefox', 04:39:00 INFO - 'buildbot_json_path': 'buildprops.json', 04:39:00 INFO - 'config_files': ('talos/mac_config.py',), 04:39:00 INFO - 'default_actions': ('clobber', 04:39:00 INFO - 'read-buildbot-config', 04:39:00 INFO - 'download-and-extract', 04:39:00 INFO - 'populate-webroot', 04:39:00 INFO - 'create-virtualenv', 04:39:00 INFO - 'install', 04:39:00 INFO - 'run-tests'), 04:39:00 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 04:39:00 INFO - 'download_minidump_stackwalk': True, 04:39:00 INFO - 'download_symbols': 'ondemand', 04:39:00 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 04:39:00 INFO - 'tooltool.py': '/tools/tooltool.py', 04:39:00 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 04:39:00 INFO - '/tools/misc-python/virtualenv.py')}, 04:39:00 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 04:39:00 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 04:39:00 INFO - 'installer_path': 'installer.exe', 04:39:00 INFO - 'log_level': 'info', 04:39:00 INFO - 'log_name': 'talos', 04:39:00 INFO - 'log_to_console': True, 04:39:00 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 04:39:00 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 04:39:00 INFO - 'opt_config_files': (), 04:39:00 INFO - 'pip_index': False, 04:39:00 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 04:39:00 INFO - 'cmd': ('bash', 04:39:00 INFO - '-c', 04:39:00 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 04:39:00 INFO - 'enabled': True, 04:39:00 INFO - 'halt_on_failure': False, 04:39:00 INFO - 'name': 'check_screen_resolution'},), 04:39:00 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 04:39:00 INFO - 'cmd': ('bash', 04:39:00 INFO - '-c', 04:39:00 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 04:39:00 INFO - 'enabled': True, 04:39:00 INFO - 'halt_on_failure': False, 04:39:00 INFO - 'name': 'check_screen_resolution'},), 04:39:00 INFO - 'run_cmd_checks_enabled': True, 04:39:00 INFO - 'sps_profile': False, 04:39:00 INFO - 'sps_profile_interval': 0, 04:39:00 INFO - 'suite': 'dromaeojs-e10s', 04:39:00 INFO - 'system_bits': '32', 04:39:00 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 04:39:00 INFO - 'title': 't-yosemite-r7-0305', 04:39:00 INFO - 'tooltool_cache': '/builds/tooltool_cache', 04:39:00 INFO - 'use_talos_json': True, 04:39:00 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 04:39:00 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 04:39:00 INFO - 'work_dir': 'build'} 04:39:00 INFO - [mozharness: 2017-01-10 12:39:00.110091Z] Running clobber step. 04:39:00 INFO - Running pre-action listener: _resource_record_pre_action 04:39:00 INFO - Running main action method: clobber 04:39:00 INFO - rmtree: /builds/slave/test/build 04:39:00 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 04:39:02 INFO - Running post-action listener: _resource_record_post_action 04:39:02 INFO - [mozharness: 2017-01-10 12:39:02.440363Z] Finished clobber step (success) 04:39:02 INFO - [mozharness: 2017-01-10 12:39:02.440518Z] Running read-buildbot-config step. 04:39:02 INFO - Running pre-action listener: _resource_record_pre_action 04:39:02 INFO - Running main action method: read_buildbot_config 04:39:02 INFO - Using buildbot properties: 04:39:02 INFO - { 04:39:02 INFO - "project": "", 04:39:02 INFO - "product": "firefox", 04:39:02 INFO - "script_repo_revision": "production", 04:39:02 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 04:39:02 INFO - "repository": "", 04:39:02 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 04:39:02 INFO - "buildid": "20170110031007", 04:39:02 INFO - "pgo_build": "False", 04:39:02 INFO - "basedir": "/builds/slave/test", 04:39:02 INFO - "buildnumber": 120, 04:39:02 INFO - "slavename": "t-yosemite-r7-0305", 04:39:02 INFO - "master": "http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/", 04:39:02 INFO - "platform": "yosemite_r7", 04:39:02 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 04:39:02 INFO - "branch": "mozilla-central", 04:39:02 INFO - "repo_path": "mozilla-central", 04:39:02 INFO - "revision": "acd4177b28d7862528f63edb40e3d5a5f0b7c8b3", 04:39:02 INFO - "stage_platform": "macosx64", 04:39:02 INFO - "builduid": "76d992d6642c45859d6128c62c22c374", 04:39:02 INFO - "slavebuilddir": "test" 04:39:02 INFO - } 04:39:02 INFO - Found installer url https://queue.taskcluster.net/v1/task/csOqVboeQXaSs_1-ycqtmw/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg. 04:39:02 INFO - Running post-action listener: _resource_record_post_action 04:39:02 INFO - [mozharness: 2017-01-10 12:39:02.444518Z] Finished read-buildbot-config step (success) 04:39:02 INFO - [mozharness: 2017-01-10 12:39:02.444656Z] Running download-and-extract step. 04:39:02 INFO - Running pre-action listener: _resource_record_pre_action 04:39:02 INFO - Running main action method: download_and_extract 04:39:02 INFO - mkdir: /builds/slave/test/build/tests 04:39:02 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')]} 04:39:02 INFO - trying https://queue.taskcluster.net/v1/task/csOqVboeQXaSs_1-ycqtmw/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json 04:39:02 INFO - Downloading https://queue.taskcluster.net/v1/task/csOqVboeQXaSs_1-ycqtmw/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 04:39:02 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/csOqVboeQXaSs_1-ycqtmw/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 04:39:03 INFO - Downloaded 1199 bytes. 04:39:03 INFO - Reading from file /builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json 04:39:03 INFO - Using the following test package requirements: 04:39:03 INFO - {u'common': [u'firefox-53.0a1.en-US.mac.common.tests.zip'], 04:39:03 INFO - u'cppunittest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 04:39:03 INFO - u'firefox-53.0a1.en-US.mac.cppunittest.tests.zip'], 04:39:03 INFO - u'gtest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 04:39:03 INFO - u'firefox-53.0a1.en-US.mac.gtest.tests.zip'], 04:39:03 INFO - u'jittest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 04:39:03 INFO - u'jsshell-mac.zip'], 04:39:03 INFO - u'mochitest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 04:39:03 INFO - u'firefox-53.0a1.en-US.mac.mochitest.tests.zip'], 04:39:03 INFO - u'mozbase': [u'firefox-53.0a1.en-US.mac.common.tests.zip'], 04:39:03 INFO - u'reftest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 04:39:03 INFO - u'firefox-53.0a1.en-US.mac.reftest.tests.zip'], 04:39:03 INFO - u'talos': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 04:39:03 INFO - u'firefox-53.0a1.en-US.mac.talos.tests.zip'], 04:39:03 INFO - u'web-platform': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 04:39:03 INFO - u'firefox-53.0a1.en-US.mac.web-platform.tests.zip'], 04:39:03 INFO - u'xpcshell': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 04:39:03 INFO - u'firefox-53.0a1.en-US.mac.xpcshell.tests.zip']} 04:39:03 INFO - Downloading packages: [u'firefox-53.0a1.en-US.mac.common.tests.zip'] for test suite category: common 04:39:03 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/csOqVboeQXaSs_1-ycqtmw/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip 04:39:03 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/csOqVboeQXaSs_1-ycqtmw/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip'}, attempt #1 04:39:03 INFO - Fetch https://queue.taskcluster.net/v1/task/csOqVboeQXaSs_1-ycqtmw/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip into memory 04:39:04 INFO - Expected file size: 16309928 04:39:04 INFO - Obtained file size: 16309928 04:39:11 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 04:39:11 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/csOqVboeQXaSs_1-ycqtmw/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip 04:39:11 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/csOqVboeQXaSs_1-ycqtmw/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip'}, attempt #1 04:39:11 INFO - Fetch https://queue.taskcluster.net/v1/task/csOqVboeQXaSs_1-ycqtmw/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip into memory 04:39:12 INFO - Expected file size: 16309928 04:39:12 INFO - Obtained file size: 16309928 04:39:18 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/csOqVboeQXaSs_1-ycqtmw/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip 04:39:18 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/csOqVboeQXaSs_1-ycqtmw/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip'}, attempt #1 04:39:18 INFO - Fetch https://queue.taskcluster.net/v1/task/csOqVboeQXaSs_1-ycqtmw/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip into memory 04:39:19 INFO - Expected file size: 14403537 04:39:19 INFO - Obtained file size: 14403537 04:39:20 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')]} 04:39:20 INFO - trying https://queue.taskcluster.net/v1/task/csOqVboeQXaSs_1-ycqtmw/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 04:39:20 INFO - Downloading https://queue.taskcluster.net/v1/task/csOqVboeQXaSs_1-ycqtmw/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg 04:39:20 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/csOqVboeQXaSs_1-ycqtmw/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 04:39:22 INFO - Downloaded 69816419 bytes. 04:39:22 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/csOqVboeQXaSs_1-ycqtmw/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 04:39:22 INFO - mkdir: /builds/slave/test/properties 04:39:22 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 04:39:22 INFO - Writing to file /builds/slave/test/properties/build_url 04:39:22 INFO - Contents: 04:39:22 INFO - build_url:https://queue.taskcluster.net/v1/task/csOqVboeQXaSs_1-ycqtmw/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 04:39:22 INFO - Running post-action listener: _resource_record_post_action 04:39:22 INFO - Running post-action listener: set_extra_try_arguments 04:39:22 INFO - [mozharness: 2017-01-10 12:39:22.991637Z] Finished download-and-extract step (success) 04:39:22 INFO - [mozharness: 2017-01-10 12:39:22.991773Z] Running populate-webroot step. 04:39:22 INFO - Running pre-action listener: _resource_record_pre_action 04:39:22 INFO - Running main action method: populate_webroot 04:39:23 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 04:39:23 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 04:39:23 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 04:39:23 INFO - '--tppagecycles', 04:39:23 INFO - '7'], 04:39:23 INFO - 'tests': ['tsvgm']}}, 04:39:23 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 04:39:23 INFO - 'tests': ['tresize', 'tcanvasmark']}, 04:39:23 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 04:39:23 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 04:39:23 INFO - 'tests': ['dromaeo_css', 'kraken']}, 04:39:23 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 04:39:23 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 04:39:23 INFO - 'talos_options': ['--disable-e10s'], 04:39:23 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:39:23 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 04:39:23 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:39:23 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 04:39:23 INFO - 'talos_options': ['--disable-e10s'], 04:39:23 INFO - 'tests': ['damp', 'tps']}, 04:39:23 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 04:39:23 INFO - 'tests': ['damp', 'tps']}, 04:39:23 INFO - 'g3': {'talos_options': ['--disable-e10s'], 04:39:23 INFO - 'tests': ['dromaeo_dom']}, 04:39:23 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 04:39:23 INFO - 'g4': {'talos_options': ['--disable-e10s'], 04:39:23 INFO - 'tests': ['basic_compositor_video']}, 04:39:23 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 04:39:23 INFO - 'other': {'talos_options': ['--disable-e10s'], 04:39:23 INFO - 'tests': ['a11yr', 04:39:23 INFO - 'ts_paint', 04:39:23 INFO - 'tpaint', 04:39:23 INFO - 'sessionrestore', 04:39:23 INFO - 'sessionrestore_no_auto_restore', 04:39:23 INFO - 'tabpaint']}, 04:39:23 INFO - 'other-e10s': {'tests': ['a11yr', 04:39:23 INFO - 'ts_paint', 04:39:23 INFO - 'tpaint', 04:39:23 INFO - 'sessionrestore', 04:39:23 INFO - 'sessionrestore_no_auto_restore', 04:39:23 INFO - 'tabpaint']}, 04:39:23 INFO - 'other-e10s_l64': {'tests': ['a11yr', 04:39:23 INFO - 'ts_paint', 04:39:23 INFO - 'tpaint', 04:39:23 INFO - 'sessionrestore', 04:39:23 INFO - 'sessionrestore_no_auto_restore', 04:39:23 INFO - 'tabpaint']}, 04:39:23 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 04:39:23 INFO - 'ts_paint', 04:39:23 INFO - 'tpaint', 04:39:23 INFO - 'sessionrestore', 04:39:23 INFO - 'sessionrestore_no_auto_restore', 04:39:23 INFO - 'tabpaint']}, 04:39:23 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 04:39:23 INFO - 'tests': ['a11yr', 04:39:23 INFO - 'ts_paint', 04:39:23 INFO - 'tpaint', 04:39:23 INFO - 'sessionrestore', 04:39:23 INFO - 'sessionrestore_no_auto_restore', 04:39:23 INFO - 'tabpaint']}, 04:39:23 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 04:39:23 INFO - 'tests': ['a11yr', 04:39:23 INFO - 'ts_paint', 04:39:23 INFO - 'tpaint', 04:39:23 INFO - 'sessionrestore', 04:39:23 INFO - 'sessionrestore_no_auto_restore', 04:39:23 INFO - 'tabpaint']}, 04:39:23 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 04:39:23 INFO - 'tests': ['tsvgx', 04:39:23 INFO - 'tsvgr_opacity', 04:39:23 INFO - 'tart', 04:39:23 INFO - 'tscrollx', 04:39:23 INFO - 'cart', 04:39:23 INFO - 'tsvg_static']}, 04:39:23 INFO - 'svgr-e10s': {'tests': ['tsvgx', 04:39:23 INFO - 'tsvgr_opacity', 04:39:23 INFO - 'tart', 04:39:23 INFO - 'tscrollx', 04:39:23 INFO - 'cart', 04:39:23 INFO - 'tsvg_static']}, 04:39:23 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 04:39:23 INFO - 'talos_options': ['--disable-e10s'], 04:39:23 INFO - 'tests': ['tp5o']}, 04:39:23 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 04:39:23 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 04:39:23 INFO - 'talos_options': ['--disable-e10s', 04:39:23 INFO - '--xperf_path', 04:39:23 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:39:23 INFO - 'tests': ['tp5n']}, 04:39:23 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 04:39:23 INFO - 'talos_options': ['--xperf_path', 04:39:23 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:39:23 INFO - 'tests': ['tp5n']}}, 04:39:23 INFO - 'talos.zip': {'path': '', 04:39:23 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 04:39:23 INFO - Running post-action listener: _resource_record_post_action 04:39:23 INFO - [mozharness: 2017-01-10 12:39:23.010346Z] Finished populate-webroot step (success) 04:39:23 INFO - [mozharness: 2017-01-10 12:39:23.010484Z] Running create-virtualenv step. 04:39:23 INFO - Running pre-action listener: _resource_record_pre_action 04:39:23 INFO - Running main action method: create_virtualenv 04:39:23 INFO - Creating virtualenv /builds/slave/test/build/venv 04:39: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 04:39:23 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 04:39:23 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 04:39:23 INFO - Using real prefix '/tools/python27' 04:39:23 INFO - New python executable in /builds/slave/test/build/venv/bin/python 04:39:23 INFO - Installing distribute.............................................................................................................................................................................................done. 04:39:26 INFO - Installing pip.................done. 04:39:26 INFO - Return code: 0 04:39:26 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 04:39:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 04:39:27 INFO - Reading from file tmpfile_stdout 04:39:27 INFO - Output received: 04:39: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) 04:39:27 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 04:39: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')]} 04:39:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:39:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:39: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')]} 04:39:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:39:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:39: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 0x107b5eb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107b61768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa6b8c668e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10793cd30>, '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 0x107863db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107931be8>, '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.KwuVbZbFgu/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.Hm0cOpBB0I/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 04:39: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 04:39: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 04:39:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.KwuVbZbFgu/Render', 04:39:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:39:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:39:27 INFO - 'HOME': '/Users/cltbld', 04:39:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:39:27 INFO - 'LOGNAME': 'cltbld', 04:39:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:39:27 INFO - 'MOZ_NO_REMOTE': '1', 04:39:27 INFO - 'NO_EM_RESTART': '1', 04:39:27 INFO - 'PAGER': '/bin/cat', 04:39:27 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:39:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:39:27 INFO - 'PWD': '/builds/slave/test', 04:39:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:39:27 INFO - 'SHELL': '/bin/bash', 04:39:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Hm0cOpBB0I/Listeners', 04:39:27 INFO - 'TMPDIR': '/var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/T/', 04:39:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:39:27 INFO - 'USER': 'cltbld', 04:39:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:39:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:39:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:39:27 INFO - 'XPC_FLAGS': '0x0', 04:39:27 INFO - 'XPC_SERVICE_NAME': '0', 04:39:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:39:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:39: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 04:39:27 INFO - Cleaning up... 04:39:27 INFO - Return code: 0 04:39:27 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 04:39: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')]} 04:39:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:39:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:39: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')]} 04:39:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:39:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:39: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 0x107b5eb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107b61768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa6b8c668e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10793cd30>, '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 0x107863db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107931be8>, '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.KwuVbZbFgu/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.Hm0cOpBB0I/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 04:39: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 04:39: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 04:39:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.KwuVbZbFgu/Render', 04:39:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:39:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:39:27 INFO - 'HOME': '/Users/cltbld', 04:39:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:39:27 INFO - 'LOGNAME': 'cltbld', 04:39:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:39:27 INFO - 'MOZ_NO_REMOTE': '1', 04:39:27 INFO - 'NO_EM_RESTART': '1', 04:39:27 INFO - 'PAGER': '/bin/cat', 04:39:27 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:39:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:39:27 INFO - 'PWD': '/builds/slave/test', 04:39:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:39:27 INFO - 'SHELL': '/bin/bash', 04:39:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Hm0cOpBB0I/Listeners', 04:39:27 INFO - 'TMPDIR': '/var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/T/', 04:39:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:39:27 INFO - 'USER': 'cltbld', 04:39:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:39:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:39:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:39:27 INFO - 'XPC_FLAGS': '0x0', 04:39:27 INFO - 'XPC_SERVICE_NAME': '0', 04:39:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:39:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:39:27 INFO - Downloading/unpacking psutil>=3.1.1 04:39:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:39:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:39:27 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:39:27 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:39:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:39:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:39:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 04:39:31 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:39:31 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:39:31 INFO - Installing collected packages: psutil 04:39:31 INFO - Running setup.py install for psutil 04:39:31 INFO - building 'psutil._psutil_osx' extension 04:39:31 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 04:39:31 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 04:39:31 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 04:39:31 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 04:39:32 INFO - building 'psutil._psutil_posix' extension 04:39:32 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 04:39:32 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 04:39:32 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 04:39:32 INFO - ^ 04:39:32 INFO - 1 warning generated. 04:39:32 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 04:39:32 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:39:32 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:39:32 INFO - Successfully installed psutil 04:39:32 INFO - Cleaning up... 04:39:32 INFO - Return code: 0 04:39:32 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 04:39:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:39:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:39:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:39:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:39:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:39:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:39:32 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x107b5eb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107b61768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa6b8c668e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10793cd30>, '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 0x107863db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107931be8>, '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.KwuVbZbFgu/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.Hm0cOpBB0I/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 04:39:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 04:39:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 04:39:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.KwuVbZbFgu/Render', 04:39:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:39:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:39:32 INFO - 'HOME': '/Users/cltbld', 04:39:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:39:32 INFO - 'LOGNAME': 'cltbld', 04:39:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:39:32 INFO - 'MOZ_NO_REMOTE': '1', 04:39:32 INFO - 'NO_EM_RESTART': '1', 04:39:32 INFO - 'PAGER': '/bin/cat', 04:39:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:39:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:39:32 INFO - 'PWD': '/builds/slave/test', 04:39:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:39:32 INFO - 'SHELL': '/bin/bash', 04:39:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Hm0cOpBB0I/Listeners', 04:39:32 INFO - 'TMPDIR': '/var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/T/', 04:39:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:39:32 INFO - 'USER': 'cltbld', 04:39:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:39:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:39:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:39:32 INFO - 'XPC_FLAGS': '0x0', 04:39:32 INFO - 'XPC_SERVICE_NAME': '0', 04:39:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:39:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:39:32 INFO - Downloading/unpacking mozsystemmonitor==0.3 04:39:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:39:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:39:32 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:39:32 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:39:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:39:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:39:35 INFO - Downloading mozsystemmonitor-0.3.tar.gz 04:39:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 04:39:35 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 04:39:35 INFO - Installing collected packages: mozsystemmonitor 04:39:35 INFO - Running setup.py install for mozsystemmonitor 04:39:35 INFO - Successfully installed mozsystemmonitor 04:39:35 INFO - Cleaning up... 04:39:35 INFO - Return code: 0 04:39:35 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 04:39:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:39:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:39:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:39:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:39:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:39:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:39:35 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 0x107b5eb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107b61768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa6b8c668e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10793cd30>, '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 0x107863db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107931be8>, '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.KwuVbZbFgu/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.Hm0cOpBB0I/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 04:39:35 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 04:39:35 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 04:39:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.KwuVbZbFgu/Render', 04:39:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:39:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:39:35 INFO - 'HOME': '/Users/cltbld', 04:39:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:39:35 INFO - 'LOGNAME': 'cltbld', 04:39:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:39:35 INFO - 'MOZ_NO_REMOTE': '1', 04:39:35 INFO - 'NO_EM_RESTART': '1', 04:39:35 INFO - 'PAGER': '/bin/cat', 04:39:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:39:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:39:35 INFO - 'PWD': '/builds/slave/test', 04:39:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:39:35 INFO - 'SHELL': '/bin/bash', 04:39:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Hm0cOpBB0I/Listeners', 04:39:35 INFO - 'TMPDIR': '/var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/T/', 04:39:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:39:35 INFO - 'USER': 'cltbld', 04:39:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:39:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:39:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:39:35 INFO - 'XPC_FLAGS': '0x0', 04:39:35 INFO - 'XPC_SERVICE_NAME': '0', 04:39:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:39:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:39:35 INFO - Downloading/unpacking jsonschema==2.5.1 04:39:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:39:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:39:35 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:39:35 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:39:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:39:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:39:38 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 04:39:38 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 04:39:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:39:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:39:38 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:39:38 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:39:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:39:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:39:38 INFO - Downloading functools32-3.2.3-2.tar.gz 04:39:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 04:39:38 INFO - warning: no files found matching '*.txt' 04:39:38 INFO - no previously-included directories found matching 'build' 04:39:38 INFO - no previously-included directories found matching 'dist' 04:39:38 INFO - no previously-included directories found matching '.git*' 04:39:38 INFO - Installing collected packages: jsonschema, functools32 04:39:39 INFO - Running setup.py install for functools32 04:39:39 INFO - warning: no files found matching '*.txt' 04:39:39 INFO - no previously-included directories found matching 'build' 04:39:39 INFO - no previously-included directories found matching 'dist' 04:39:39 INFO - no previously-included directories found matching '.git*' 04:39:39 INFO - Successfully installed jsonschema functools32 04:39:39 INFO - Cleaning up... 04:39:39 INFO - Return code: 0 04:39:39 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 04:39:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:39:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:39:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:39:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:39:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:39:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:39:39 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 0x107b5eb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107b61768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa6b8c668e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10793cd30>, '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 0x107863db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107931be8>, '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.KwuVbZbFgu/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.Hm0cOpBB0I/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 04:39:39 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 04:39:39 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 04:39:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.KwuVbZbFgu/Render', 04:39:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:39:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:39:39 INFO - 'HOME': '/Users/cltbld', 04:39:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:39:39 INFO - 'LOGNAME': 'cltbld', 04:39:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:39:39 INFO - 'MOZ_NO_REMOTE': '1', 04:39:39 INFO - 'NO_EM_RESTART': '1', 04:39:39 INFO - 'PAGER': '/bin/cat', 04:39:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:39:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:39:39 INFO - 'PWD': '/builds/slave/test', 04:39:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:39:39 INFO - 'SHELL': '/bin/bash', 04:39:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Hm0cOpBB0I/Listeners', 04:39:39 INFO - 'TMPDIR': '/var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/T/', 04:39:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:39:39 INFO - 'USER': 'cltbld', 04:39:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:39:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:39:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:39:39 INFO - 'XPC_FLAGS': '0x0', 04:39:39 INFO - 'XPC_SERVICE_NAME': '0', 04:39:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:39:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:39:39 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 04:39:39 INFO - Cleaning up... 04:39:39 INFO - Return code: 0 04:39:39 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 04:39:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:39:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:39:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:39:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:39:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:39:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:39:39 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 0x107b5eb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107b61768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa6b8c668e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10793cd30>, '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 0x107863db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107931be8>, '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.KwuVbZbFgu/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.Hm0cOpBB0I/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 04:39:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 04:39:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 04:39:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.KwuVbZbFgu/Render', 04:39:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:39:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:39:39 INFO - 'HOME': '/Users/cltbld', 04:39:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:39:39 INFO - 'LOGNAME': 'cltbld', 04:39:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:39:39 INFO - 'MOZ_NO_REMOTE': '1', 04:39:39 INFO - 'NO_EM_RESTART': '1', 04:39:39 INFO - 'PAGER': '/bin/cat', 04:39:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:39:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:39:39 INFO - 'PWD': '/builds/slave/test', 04:39:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:39:39 INFO - 'SHELL': '/bin/bash', 04:39:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Hm0cOpBB0I/Listeners', 04:39:39 INFO - 'TMPDIR': '/var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/T/', 04:39:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:39:39 INFO - 'USER': 'cltbld', 04:39:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:39:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:39:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:39:39 INFO - 'XPC_FLAGS': '0x0', 04:39:39 INFO - 'XPC_SERVICE_NAME': '0', 04:39:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:39:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:39:39 INFO - Downloading/unpacking blobuploader==1.2.4 04:39: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 04:39: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 04:39: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 04:39: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 04:39: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 04:39: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 04:39:42 INFO - Downloading blobuploader-1.2.4.tar.gz 04:39:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 04:39:42 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 04:39:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:39:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:39:42 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:39:42 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:39:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:39:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:39:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 04:39:43 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 04:39: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 04:39: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 04:39: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 04:39: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 04:39: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 04:39: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 04:39:43 INFO - Downloading docopt-0.6.1.tar.gz 04:39:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 04:39:43 INFO - Installing collected packages: blobuploader, requests, docopt 04:39:43 INFO - Running setup.py install for blobuploader 04:39:43 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 04:39:43 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 04:39:43 INFO - Running setup.py install for requests 04:39:44 INFO - Running setup.py install for docopt 04:39:44 INFO - Successfully installed blobuploader requests docopt 04:39:44 INFO - Cleaning up... 04:39:44 INFO - Return code: 0 04:39:44 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:39:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:39:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:39:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:39:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:39:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:39:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:39:44 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 0x107b5eb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107b61768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa6b8c668e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10793cd30>, '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 0x107863db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107931be8>, '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.KwuVbZbFgu/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.Hm0cOpBB0I/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 04:39:44 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 04:39:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 04:39:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.KwuVbZbFgu/Render', 04:39:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:39:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:39:44 INFO - 'HOME': '/Users/cltbld', 04:39:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:39:44 INFO - 'LOGNAME': 'cltbld', 04:39:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:39:44 INFO - 'MOZ_NO_REMOTE': '1', 04:39:44 INFO - 'NO_EM_RESTART': '1', 04:39:44 INFO - 'PAGER': '/bin/cat', 04:39:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:39:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:39:44 INFO - 'PWD': '/builds/slave/test', 04:39:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:39:44 INFO - 'SHELL': '/bin/bash', 04:39:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Hm0cOpBB0I/Listeners', 04:39:44 INFO - 'TMPDIR': '/var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/T/', 04:39:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:39:44 INFO - 'USER': 'cltbld', 04:39:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:39:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:39:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:39:44 INFO - 'XPC_FLAGS': '0x0', 04:39:44 INFO - 'XPC_SERVICE_NAME': '0', 04:39:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:39:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:39:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:39:44 INFO - Running setup.py (path:/var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/T/pip-NawMRP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:39:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:39:44 INFO - Running setup.py (path:/var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/T/pip-JBeRL7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:39:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:39:44 INFO - Running setup.py (path:/var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/T/pip-irSZU0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:39:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:39:45 INFO - Running setup.py (path:/var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/T/pip-yQQSU_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:39:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:39:45 INFO - Running setup.py (path:/var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/T/pip-vJscdm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:39:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:39:45 INFO - Running setup.py (path:/var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/T/pip-Y3sWpf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:39:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:39:45 INFO - Running setup.py (path:/var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/T/pip-AXYoid-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:39:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:39:45 INFO - Running setup.py (path:/var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/T/pip-t3UFF_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:39:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:39:45 INFO - Running setup.py (path:/var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/T/pip-4Skigi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:39:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:39:45 INFO - Running setup.py (path:/var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/T/pip-wqPzoR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:39:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:39:45 INFO - Running setup.py (path:/var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/T/pip-uEobKe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:39:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:39:45 INFO - Running setup.py (path:/var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/T/pip-t2Ooyx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:39:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:39:46 INFO - Running setup.py (path:/var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/T/pip-WITAhz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:39:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:39:46 INFO - Running setup.py (path:/var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/T/pip-KeOSks-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:39:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:39:46 INFO - Running setup.py (path:/var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/T/pip-bLSfZm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:39:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:39:46 INFO - Running setup.py (path:/var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/T/pip-rDhvFM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:39:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:39:46 INFO - Running setup.py (path:/var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/T/pip-qHBqIB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:39:46 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 04:39:46 INFO - Running setup.py install for manifestparser 04:39:46 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 04:39:46 INFO - Running setup.py install for mozcrash 04:39:46 INFO - Running setup.py install for mozdebug 04:39:46 INFO - Running setup.py install for mozdevice 04:39:47 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 04:39:47 INFO - Installing dm script to /builds/slave/test/build/venv/bin 04:39:47 INFO - Running setup.py install for mozfile 04:39:47 INFO - Running setup.py install for mozhttpd 04:39:47 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 04:39:47 INFO - Running setup.py install for mozinfo 04:39:47 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 04:39:47 INFO - Running setup.py install for mozInstall 04:39:47 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 04:39:47 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 04:39:47 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 04:39:47 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 04:39:47 INFO - Running setup.py install for mozleak 04:39:47 INFO - Running setup.py install for mozlog 04:39:48 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 04:39:48 INFO - Running setup.py install for moznetwork 04:39:48 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 04:39:48 INFO - Running setup.py install for mozprocess 04:39:48 INFO - Running setup.py install for mozprofile 04:39:48 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 04:39:48 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 04:39:48 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 04:39:48 INFO - Running setup.py install for mozrunner 04:39:48 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 04:39:48 INFO - Running setup.py install for mozscreenshot 04:39:48 INFO - Running setup.py install for moztest 04:39:48 INFO - Running setup.py install for mozversion 04:39:49 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 04:39:49 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 04:39:49 INFO - Cleaning up... 04:39:49 INFO - Return code: 0 04:39:49 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:39:49 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')]} 04:39:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:39:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:39:49 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')]} 04:39:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:39:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:39:49 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 0x107b5eb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107b61768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa6b8c668e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10793cd30>, '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 0x107863db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107931be8>, '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.KwuVbZbFgu/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.Hm0cOpBB0I/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 04:39:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 04:39:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 04:39:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.KwuVbZbFgu/Render', 04:39:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:39:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:39:49 INFO - 'HOME': '/Users/cltbld', 04:39:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:39:49 INFO - 'LOGNAME': 'cltbld', 04:39:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:39:49 INFO - 'MOZ_NO_REMOTE': '1', 04:39:49 INFO - 'NO_EM_RESTART': '1', 04:39:49 INFO - 'PAGER': '/bin/cat', 04:39:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:39:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:39:49 INFO - 'PWD': '/builds/slave/test', 04:39:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:39:49 INFO - 'SHELL': '/bin/bash', 04:39:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Hm0cOpBB0I/Listeners', 04:39:49 INFO - 'TMPDIR': '/var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/T/', 04:39:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:39:49 INFO - 'USER': 'cltbld', 04:39:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:39:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:39:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:39:49 INFO - 'XPC_FLAGS': '0x0', 04:39:49 INFO - 'XPC_SERVICE_NAME': '0', 04:39:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:39:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:39:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:39:49 INFO - Running setup.py (path:/var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/T/pip-_vlmVN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:39:49 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 04:39:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:39:49 INFO - Running setup.py (path:/var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/T/pip-jJPTkc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:39:49 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)) 04:39:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:39:49 INFO - Running setup.py (path:/var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/T/pip-8a07ln-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:39:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 04:39:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:39:49 INFO - Running setup.py (path:/var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/T/pip-1wWqO_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:39:49 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)) 04:39:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:39:49 INFO - Running setup.py (path:/var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/T/pip-Bj5WA4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:39:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 04:39:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:39:49 INFO - Running setup.py (path:/var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/T/pip-ZNy4KT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:39:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 04:39:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:39:50 INFO - Running setup.py (path:/var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/T/pip-JPPczX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:39:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 04:39:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:39:50 INFO - Running setup.py (path:/var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/T/pip-30Xn75-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:39:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 04:39:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:39:50 INFO - Running setup.py (path:/var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/T/pip-dMUXVf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:39:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 04:39:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:39:50 INFO - Running setup.py (path:/var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/T/pip-eLdIdg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:39:50 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)) 04:39:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:39:50 INFO - Running setup.py (path:/var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/T/pip-IeCgpt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:39:50 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 04:39:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:39:50 INFO - Running setup.py (path:/var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/T/pip-w6Hm2c-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:39:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.24 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 04:39:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:39:50 INFO - Running setup.py (path:/var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/T/pip-Rr1Wkn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:39:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 04:39:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:39:50 INFO - Running setup.py (path:/var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/T/pip-_BIuum-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:39:50 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)) 04:39:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:39:50 INFO - Running setup.py (path:/var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/T/pip-_GsBXQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:39:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 04:39:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:39:50 INFO - Running setup.py (path:/var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/T/pip-AUUUaY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:39:51 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)) 04:39:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:39:51 INFO - Running setup.py (path:/var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/T/pip-1IgTKb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:39:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 04:39:51 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)) 04:39:51 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)) 04:39:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 04:39:51 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)) 04:39:51 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)) 04:39:51 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 04:39:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:39:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:39:51 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:39:51 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:39:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:39:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:39:53 INFO - Downloading blessings-1.6.tar.gz 04:39:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 04:39:53 INFO - Installing collected packages: blessings 04:39:53 INFO - Running setup.py install for blessings 04:39:54 INFO - Successfully installed blessings 04:39:54 INFO - Cleaning up... 04:39:54 INFO - Return code: 0 04:39:54 INFO - Done creating virtualenv /builds/slave/test/build/venv. 04:39:54 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:39:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:39:54 INFO - Reading from file tmpfile_stdout 04:39:54 INFO - Current package versions: 04:39:54 INFO - blessings == 1.6 04:39:54 INFO - blobuploader == 1.2.4 04:39:54 INFO - docopt == 0.6.1 04:39:54 INFO - functools32 == 3.2.3-2 04:39:54 INFO - jsonschema == 2.5.1 04:39:54 INFO - manifestparser == 1.1 04:39:54 INFO - mozInstall == 1.12 04:39:54 INFO - mozcrash == 1.0 04:39:54 INFO - mozdebug == 0.1 04:39:54 INFO - mozdevice == 0.49 04:39:54 INFO - mozfile == 1.2 04:39:54 INFO - mozhttpd == 0.7 04:39:54 INFO - mozinfo == 0.9 04:39:54 INFO - mozleak == 0.1 04:39:54 INFO - mozlog == 3.4 04:39:54 INFO - moznetwork == 0.27 04:39:54 INFO - mozprocess == 0.24 04:39:54 INFO - mozprofile == 0.28 04:39:54 INFO - mozrunner == 6.13 04:39:54 INFO - mozscreenshot == 0.1 04:39:54 INFO - mozsystemmonitor == 0.3 04:39:54 INFO - moztest == 0.8 04:39:54 INFO - mozversion == 1.4 04:39:54 INFO - psutil == 3.1.1 04:39:54 INFO - requests == 1.2.3 04:39:54 INFO - wsgiref == 0.1.2 04:39:54 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:39:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:39:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:39:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:39:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:39:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:39:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:39: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 0x107b5eb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107b61768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa6b8c668e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10793cd30>, '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 0x107863db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107931be8>, '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.KwuVbZbFgu/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.Hm0cOpBB0I/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 04:39: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 04:39: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 04:39:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.KwuVbZbFgu/Render', 04:39:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:39:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:39:55 INFO - 'HOME': '/Users/cltbld', 04:39:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:39:55 INFO - 'LOGNAME': 'cltbld', 04:39:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:39:55 INFO - 'MOZ_NO_REMOTE': '1', 04:39:55 INFO - 'NO_EM_RESTART': '1', 04:39:55 INFO - 'PAGER': '/bin/cat', 04:39:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:39:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:39:55 INFO - 'PWD': '/builds/slave/test', 04:39:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:39:55 INFO - 'SHELL': '/bin/bash', 04:39:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Hm0cOpBB0I/Listeners', 04:39:55 INFO - 'TMPDIR': '/var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/T/', 04:39:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:39:55 INFO - 'USER': 'cltbld', 04:39:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:39:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:39:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:39:55 INFO - 'XPC_FLAGS': '0x0', 04:39:55 INFO - 'XPC_SERVICE_NAME': '0', 04:39:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:39:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:39: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)) 04:39: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)) 04:39: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)) 04:39: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)) 04:39: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)) 04:39: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)) 04:39: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)) 04:39: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)) 04:39: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)) 04:39: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)) 04:39: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)) 04:39:55 INFO - Cleaning up... 04:39:55 INFO - Return code: 0 04:39:55 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 04:39: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')]} 04:39:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:39:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:39: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')]} 04:39:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:39:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:39: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 0x107b5eb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107b61768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa6b8c668e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10793cd30>, '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 0x107863db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107931be8>, '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.KwuVbZbFgu/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.Hm0cOpBB0I/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 04:39: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 04:39: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 04:39:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.KwuVbZbFgu/Render', 04:39:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:39:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:39:55 INFO - 'HOME': '/Users/cltbld', 04:39:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:39:55 INFO - 'LOGNAME': 'cltbld', 04:39:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:39:55 INFO - 'MOZ_NO_REMOTE': '1', 04:39:55 INFO - 'NO_EM_RESTART': '1', 04:39:55 INFO - 'PAGER': '/bin/cat', 04:39:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:39:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:39:55 INFO - 'PWD': '/builds/slave/test', 04:39:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:39:55 INFO - 'SHELL': '/bin/bash', 04:39:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Hm0cOpBB0I/Listeners', 04:39:55 INFO - 'TMPDIR': '/var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/T/', 04:39:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:39:55 INFO - 'USER': 'cltbld', 04:39:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:39:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:39:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:39:55 INFO - 'XPC_FLAGS': '0x0', 04:39:55 INFO - 'XPC_SERVICE_NAME': '0', 04:39:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:39:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:39:56 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 04:39:56 INFO - Cleaning up... 04:39:56 INFO - Return code: 0 04:39:56 INFO - Running post-action listener: _resource_record_post_action 04:39:56 INFO - Running post-action listener: _start_resource_monitoring 04:39:56 INFO - Starting resource monitoring. 04:39:56 INFO - [mozharness: 2017-01-10 12:39:56.202675Z] Finished create-virtualenv step (success) 04:39:56 INFO - [mozharness: 2017-01-10 12:39:56.203645Z] Running install step. 04:39:56 INFO - Running pre-action listener: _resource_record_pre_action 04:39:56 INFO - Running main action method: install 04:39:56 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:39:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:39:56 INFO - Reading from file tmpfile_stdout 04:39:56 INFO - Detecting whether we're running mozinstall >=1.0... 04:39:56 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 04:39:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 04:39:56 INFO - Reading from file tmpfile_stdout 04:39:56 INFO - Output received: 04:39:56 INFO - Usage: mozinstall [options] installer 04:39:56 INFO - Options: 04:39:56 INFO - -h, --help show this help message and exit 04:39:56 INFO - -d DEST, --destination=DEST 04:39:56 INFO - Directory to install application into. [default: 04:39:56 INFO - "/builds/slave/test"] 04:39:56 INFO - --app=APP Application being installed. [default: firefox] 04:39:56 INFO - mkdir: /builds/slave/test/build/application 04:39: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'] 04:39: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 04:40:18 INFO - Reading from file tmpfile_stdout 04:40:18 INFO - Output received: 04:40:18 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 04:40:18 INFO - Running post-action listener: _resource_record_post_action 04:40:18 INFO - [mozharness: 2017-01-10 12:40:18.292136Z] Finished install step (success) 04:40:18 INFO - [mozharness: 2017-01-10 12:40:18.292262Z] Running run-tests step. 04:40:18 INFO - Running pre-action listener: _resource_record_pre_action 04:40:18 INFO - Running main action method: run_tests 04:40:18 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 04:40:18 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 04:40:18 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 04:40:18 INFO - 2017-01-10 04:40:18.306 screenresolution[1632:7399] starting screenresolution argv=screenresolution get 04:40:18 INFO - 2017-01-10 04:40:18.309 screenresolution[1632:7399] Display 0: 1600x1200x32@60 04:40:18 INFO - 2017-01-10 04:40:18.315 screenresolution[1633:7401] starting screenresolution argv=screenresolution list 04:40:18 INFO - Available Modes on Display 0 04:40:18 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 04:40:18 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 04:40:18 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 04:40:18 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 04:40:18 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 04:40:18 INFO - Intel Iris: 04:40:18 INFO - Chipset Model: Intel Iris 04:40:18 INFO - Type: GPU 04:40:18 INFO - Bus: Built-In 04:40:18 INFO - VRAM (Dynamic, Max): 1536 MB 04:40:18 INFO - Vendor: Intel (0x8086) 04:40:18 INFO - Device ID: 0x0a2e 04:40:18 INFO - Revision ID: 0x0009 04:40:18 INFO - Displays: 04:40:18 INFO - Display: 04:40:18 INFO - Resolution: 1600 x 1200 @ 60 Hz 04:40:18 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 04:40:18 INFO - Display Serial Number: 1600x1200 60 04:40:18 INFO - Main Display: Yes 04:40:18 INFO - Mirror: Off 04:40:18 INFO - Online: Yes 04:40:18 INFO - Rotation: Supported 04:40:18 INFO - Return code: 0 04:40:18 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 04:40:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 04:40:18 INFO - Python 2.7.3 04:40:18 INFO - Return code: 0 04:40:18 INFO - grabbing minidump binary from tooltool 04:40: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')]} 04:40:18 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 0x10793cd30>, '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 0x107863db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107931be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 04:40:18 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 04:40:18 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 04:40:18 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 04:40:18 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 04:40:18 INFO - Return code: 0 04:40:18 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 04:40:18 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 04:40:18 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 04:40:18 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 04:40:18 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 04:40:18 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/csOqVboeQXaSs_1-ycqtmw/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0305', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 04:40:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/csOqVboeQXaSs_1-ycqtmw/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0305 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 04:40:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.KwuVbZbFgu/Render', 04:40:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:40:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:40:18 INFO - 'HOME': '/Users/cltbld', 04:40:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:40:18 INFO - 'LOGNAME': 'cltbld', 04:40:18 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 04:40:18 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 04:40:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:40:18 INFO - 'MOZ_NO_REMOTE': '1', 04:40:18 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 04:40:18 INFO - 'NO_EM_RESTART': '1', 04:40:18 INFO - 'PAGER': '/bin/cat', 04:40:18 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:40:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:40:18 INFO - 'PWD': '/builds/slave/test', 04:40:18 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 04:40:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:40:18 INFO - 'SHELL': '/bin/bash', 04:40:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Hm0cOpBB0I/Listeners', 04:40:18 INFO - 'TMPDIR': '/var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/T/', 04:40:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:40:18 INFO - 'USER': 'cltbld', 04:40:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:40:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:40:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:40:18 INFO - 'XPC_FLAGS': '0x0', 04:40:18 INFO - 'XPC_SERVICE_NAME': '0', 04:40:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:40:18 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/csOqVboeQXaSs_1-ycqtmw/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0305', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 04:40:18 INFO - mozversion application_buildid: 20170110031007 04:40:18 INFO - mozversion application_changeset: acd4177b28d7862528f63edb40e3d5a5f0b7c8b3 04:40:18 INFO - mozversion application_display_name: Nightly 04:40:18 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 04:40:18 INFO - mozversion application_name: Firefox 04:40:18 INFO - mozversion application_remotingname: firefox 04:40:18 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 04:40:18 INFO - mozversion application_vendor: Mozilla 04:40:18 INFO - mozversion application_version: 53.0a1 04:40:18 INFO - mozversion platform_buildid: 20170110031007 04:40:18 INFO - mozversion platform_changeset: acd4177b28d7862528f63edb40e3d5a5f0b7c8b3 04:40:18 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 04:40:18 INFO - mozversion platform_version: 53.0a1 04:40:18 INFO - using testdate: 1484052018 04:40:18 INFO - actual date: 1484052018 04:40:18 INFO - starting webserver on 'localhost:49251' 04:40:18 INFO - SUITE-START | Running 2 tests 04:40:18 INFO - TEST-START | dromaeo_css 04:40:18 INFO - operating with platform_type : mac_ 04:40:18 INFO - Initialising browser for dromaeo_css test... 04:40:18 INFO - TEST-INFO | started process 1643 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/T/tmpXtyKoL/profile http://localhost:49251/getInfo.html) 04:40:21 INFO - PROCESS | 1643 | 2017-01-10 04:40:21.661 plugin-container[1645:7566] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4947, name = 'com.apple.tsm.portname' 04:40:21 INFO - PROCESS | 1643 | See /usr/include/servers/bootstrap_defs.h for the error codes. 04:40:21 INFO - PROCESS | 1643 | 2017-01-10 04:40:21.664 plugin-container[1645:7566] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9503, name = 'com.apple.CFPasteboardClient' 04:40:21 INFO - PROCESS | 1643 | See /usr/include/servers/bootstrap_defs.h for the error codes. 04:40:21 INFO - PROCESS | 1643 | 2017-01-10 04:40:21.664 plugin-container[1645:7566] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 04:40:21 INFO - PROCESS | 1643 | __metrics Screen width/height:1600/1200 04:40:21 INFO - PROCESS | 1643 | colorDepth:24 04:40:21 INFO - PROCESS | 1643 | Browser inner width/height: 1024/689 04:40:21 INFO - PROCESS | 1643 | __metrics 04:40:21 ERROR - PROCESS | 1643 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3252: TypeError: this.tabs is undefined 04:40:22 INFO - TEST-INFO | 1643: exit 0 04:40:22 INFO - Browser initialized. 04:40:22 INFO - Running cycle 1/1 for dromaeo_css test... 04:40:22 INFO - TEST-INFO | started process 1646 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/T/tmpXtyKoL/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:40:24 INFO - PROCESS | 1646 | 2017-01-10 04:40:24.779 plugin-container[1647:7687] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4447, name = 'com.apple.tsm.portname' 04:40:24 INFO - PROCESS | 1646 | See /usr/include/servers/bootstrap_defs.h for the error codes. 04:40:24 INFO - PROCESS | 1646 | 2017-01-10 04:40:24.781 plugin-container[1647:7687] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8e0b, name = 'com.apple.CFPasteboardClient' 04:40:24 INFO - PROCESS | 1646 | See /usr/include/servers/bootstrap_defs.h for the error codes. 04:40:24 INFO - PROCESS | 1646 | 2017-01-10 04:40:24.781 plugin-container[1647:7687] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 04:40:34 INFO - PROCESS | 1646 | RSS: Main: 233353216 04:40:34 INFO - PROCESS | 1646 | 04:43:38 INFO - PROCESS | 1646 | Cycle 1(1): loaded http://localhost:49251/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49251/tests/dromaeo/cssquery-ext.html) 04:43:39 INFO - PROCESS | 1646 | RSS: Main: 250654720 04:43:39 INFO - PROCESS | 1646 | 04:45:22 INFO - PROCESS | 1646 | ************************* 04:45:22 INFO - PROCESS | 1646 | A coding exception was thrown and uncaught in a Task. 04:45:22 INFO - PROCESS | 1646 | 04:45:22 INFO - PROCESS | 1646 | Full message: ReferenceError: fetch is not defined 04:45:22 INFO - PROCESS | 1646 | Full stack: apiCall@resource://shield-recipe-client/lib/NormandyApi.jsm:37:5 04:45:22 INFO - PROCESS | 1646 | get@resource://shield-recipe-client/lib/NormandyApi.jsm:44:12 04:45:22 INFO - PROCESS | 1646 | this.NormandyApi.fetchRecipes<@resource://shield-recipe-client/lib/NormandyApi.jsm:52:34 04:45:22 INFO - PROCESS | 1646 | TaskImpl_run@resource://gre/modules/Task.jsm:319:42 04:45:22 INFO - PROCESS | 1646 | TaskImpl@resource://gre/modules/Task.jsm:277:3 04:45:22 INFO - PROCESS | 1646 | asyncFunction@resource://gre/modules/Task.jsm:252:14 04:45:22 INFO - PROCESS | 1646 | this.RecipeRunner.start<@resource://shield-recipe-client/lib/RecipeRunner.jsm:64:23 04:45:22 INFO - PROCESS | 1646 | TaskImpl_run@resource://gre/modules/Task.jsm:319:42 04:45:22 INFO - PROCESS | 1646 | TaskImpl@resource://gre/modules/Task.jsm:277:3 04:45:22 INFO - PROCESS | 1646 | asyncFunction@resource://gre/modules/Task.jsm:252:14 04:45:22 INFO - PROCESS | 1646 | setTimeout_timer@resource://gre/modules/Timer.jsm:30:5 04:45:22 INFO - PROCESS | 1646 | 04:45:22 INFO - PROCESS | 1646 | ************************* 04:46:41 INFO - PROCESS | 1646 | Cycle 1(1): loaded http://localhost:49251/tests/dromaeo/cssquery-ext.html (next: http://localhost:49251/tests/dromaeo/cssquery-jquery.html) 04:46:42 INFO - PROCESS | 1646 | RSS: Main: 260648960 04:46:42 INFO - PROCESS | 1646 | 04:49:44 INFO - PROCESS | 1646 | Cycle 1(1): loaded http://localhost:49251/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49251/tests/dromaeo/cssquery-mootools.html) 04:49:45 INFO - PROCESS | 1646 | RSS: Main: 272269312 04:49:45 INFO - PROCESS | 1646 | 04:52:47 INFO - PROCESS | 1646 | Cycle 1(1): loaded http://localhost:49251/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49251/tests/dromaeo/cssquery-prototype.html) 04:52:47 INFO - PROCESS | 1646 | RSS: Main: 266473472 04:52:47 INFO - PROCESS | 1646 | 04:55:50 INFO - PROCESS | 1646 | Cycle 1(1): loaded http://localhost:49251/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49251/tests/dromaeo/cssquery-yui.html) 04:55:51 INFO - PROCESS | 1646 | RSS: Main: 271081472 04:55:51 INFO - PROCESS | 1646 | 04:58:54 INFO - PROCESS | 1646 | Cycle 1(1): loaded http://localhost:49251/tests/dromaeo/cssquery-yui.html (next: http://localhost:49251/tests/dromaeo/cssquery-dojo.html) 04:58:54 INFO - PROCESS | 1646 | RSS: Main: 275189760 04:58:54 INFO - PROCESS | 1646 | 04:58:54 INFO - PROCESS | 1646 | __start_tp_report 04:58:54 INFO - PROCESS | 1646 | _x_x_mozilla_page_load 04:58:54 INFO - PROCESS | 1646 | _x_x_mozilla_page_load_details 04:58:54 INFO - PROCESS | 1646 | |i|pagename|runs| 04:58:54 INFO - PROCESS | 1646 | |0;dojo.html;3292.078218098933;3334.499825026246;3351.983240083799;3365.8821941232063;3369.292448585797;5479.986202552531;5619.1852181433715;5627.099664053752;5634.267545219116;5688.146777983305;4379.3430985352215;5456.263404440409;5497.917531237024;5562.916556251659;5654.547636189105;4939.036887806879;5032.723200223986;5079.2635067915135;5123.180291153416;5139.023585518758;5026.924596131061;5229.450907654698;5256.369235691704;5257.842264732045;5284.154535274357;5076.340075790124;5086.1353569892835;5178.689278643255;5185.22221666753;5210.687358758468;5110.8466745997675;5148.819791307324;5178.093833579109;5178.40448348441;5196.948030519684;4245.915081698348;5060.645754797166;5071.909539449;5122.692638441706;5128.333316668846;5563.915036567831;5642.463965923231;5662.348829884533;5669;5726.713664316809;6078.635281883057;6105.8168254952425;6107.083937409379;6147.415995480422;6238.750449981995;6173.783917562864;6176.042713379434;6188.628682279078;6196.81409557714;6216.067589861512;5887.705614719247;5896.292444906553;5903.49820265278;5908.054711246266;5924;6170.197874276315;6263.467605253513;6276.717547710364;6284.780032698925;6292.685365731787;5965.731542080617;5991.64050156992;6075.149479072933;6080.635161890301;6130.979101201984;5215.348081489814;6195.411435913581;6215.844603884939;6216.875662486816;6272.843178920472;6027.608205466719;6028.367021462771;6086.330503644595;6134.171886795315;6149.754009839557;6147.801178770186;6170.012797952396;6183.690815459299;6197.318294987548;6280.120783090371;4819.036192761461;4829.082474329866;4830.985493046429;4935.876603084952;4986.775595098229;4327.415798867112;4347.65218782503;4397.538258482878;4439.533848945879;4493.460784705856;4887.193613053877;5776.508996735324;5782.132680098019;5796.159556864315;5797.797077102281;4704;5218.269442278084;5526.309211348582;6286.245650521967;6352.61884286935;5596.048671725816;5609.719514024282;5617.662940223599;5705.115707065412;5713.428657134254;5433.701146436984;5481.945180548143;5496.230527726121;5525.585581081355;5534.695591742493;5562.7774889004795;5867.618604790675;6404.903926441107;6406.935930640727;6480.513961452815;6096.207493025879;6126.724297406538;6247.656378899204;6254.655993920378;6283.528735344776;6199.380061993765;6201.255849297932;6209.410106039919;6253.249610046823;6273.811785646438;6166.075088736726;6168.814935551941;6170.53720970938;6171.105189747551;6181.721822517908;6095.817125486243;6124.14262003311;6196.876062478816;6206.410391012847;6229.2524897012645;5954.10688396744;5979.820605381846;5990.850228744316;6048.969755151372;6148.985417406257;6055.303640081271;6115.0720943201395;6119.929102025315;6144.508439324933;6151.6001459904965;5773.422657734193;5817.70911454434;5819.388964158702;5833.445822646841;5875.6474611523445;5993.61041532299;6032.517398608188;6054.515638748978;6054.636721796706;6085.087236914498;5774.133879918046;5824.388439213822;5851.853703657443;5872.794452194153;5900.64596124234 04:58:54 INFO - PROCESS | 1646 | |1;ext.html;442.6038695367644;458.4132310642379;482.9830955916543;487.7414970065864;529.9682019078855;40290;43252.567474325246;43307;45181.32228016583;45218.547814521844;43380.78309608447;43943;44026.33960490595;44092.77953610227;44321.335179972324;8448.07071222165;8671.69649062283;9270.397424167424;9512.952435237814;9528.285378596598;11215.439228038606;11525.13561482888;11597.420128993559;11659.06727461801;11748.004258913965;11452.656420307363;11462.083033357354;11501.482433290523;11552.191346605741;11569.190156689036;11481.483333250024;11483.368414737228;11557.711057223552;11569.305841649528;11573.594924177656;11322.433878306052;11392.886071139266;11417.714557136054;11419.942900285445;11475.196736228383;31284.843575782204;33354;38894.611053889384;41814.79092604518;42294;3424.743144264165;3539.6460353964653;3907.7264591478606;4094.979525102385;4110.979445102786;31442.842785786153;31575.84212078925;31687.20781980446;32003;32475;3032.1964679359985;3175.539546765706;3272.051105179495;3326.101952472843;3340.064781861083;484966;487779.56110219576;488147;488607;489655;63978.08065758034;64464;64518.67740661267;64526.6773666138;64621.67689161618;67549.98675019879;67865.55277248654;67896;67903;68116;16295.592610184603;16503.422380216634;16516.42192523256;16540.83459165417;16609;11391.430428478543;11668.299902005738;12449.688757780947;12676.429560669792;12780.552680656132;18037.458876233733;18112.45662630123;18124.912505249726;18212.08939553017;18344.266229350942;6602;7685.730999414994;7812.1016083150025;7818.8827167592535;7843.72546960865;783.1620166421874;794.5788731972063;803.3974519110667;803.5580430763174;812.7968007998;24675.62986555203;25657.74342256591;30036.937206748615;30190.396192076474;30396.240093997825;928.3176865004217;993.1161266472845;1075.8493810866485;1097.5993762276871;1103.773726386105;13079.41142648583;15803.762943555856;15817.130057846698;16016;16093.114878681785;545673;599511;622053;623740;625320;59769.402305976386;59887.40112598818;59935.10097348543;59979.70010149921;60098.39901600928;1256.6292943581666;1401.439424230316;1440.7478691228994;1466.413434626158;1467.3910327214087;27073.729262707122;29369;29578.260543486584;29918.25204369908;30583;52586.73706631595;52884.471155289495;52961.735191323794;52997.470025299255;53073;528703;528792;530722.3463882656;530813;531336.6866331287;36529.07767378657;41300.38049429201;41519;41788.58211417847;42039.7898010508;39571.81284561468;46293.30560041535;47719.76140119277;48478.757606211744;48960.510394895595;34243.82878085594;35956.46065309075;38168.236635266585;39652.448657188594;41109.97225069397;39056;39294;39301.60698392979;40198.19603607853;40478.79760601296;37544;37909.62090379061;38614;38637.2272554553;38708.6129138705;30864.537031944088;34191.4871276936;34702.13244668904;34848;34868.9539313821;21623.783762162177;22958.77041229633;23105.422364441023;25717.871410643576;25974.425211842885 04:58:54 INFO - PROCESS | 1646 | |2;jquery.html;386.7640739149119;388.35726872026805;393.10568456760865;397.7851959941632;420.02763602260785;12808.423620937057;16651.66696666066;19873.900630496846;21155;21243.787562124395;17041.954965404137;20467.897660511713;21468.033938472767;21614.459638509044;21890.452738681543;5719.771209151629;5721.799737009205;5838.591298609099;5924.792632257871;5929.229200203968;4616.653750968674;5913.379095195007;5925.4963328117165;5926.585139040269;5931.940680593182;4134.896627584304;6480.805575832733;6548.869022619545;6596.505262105337;6626.602403855784;6623.072769812206;6648.4681225501845;6658.201015878101;7301.780946571611;7624.771256862303;7288.744893928687;7567.962160189219;7586.8482630347635;7605.163432022473;7621.504602200839;28321.71678283232;28457.288567785763;28693.426131477252;28724;28975.710242897723;3276.6231883333485;3361.1429085583018;3390.2541440883215;3400.4219282721997;3504.316658251642;14859;16140.273687684083;17114.31542738289;17358.56603584908;17505;1692.272322901152;1934.216642259887;1967.842572594189;2027.482991837087;2040.4819707779045;101213.4939325306;103685.48157259241;123367.38316308451;133401;143182;22239;23040.65439018416;23080.307590772303;23163.76836231616;23309.06763729466;39348.016299592164;39520;39972.00069998273;39987.60012399897;40087;10788.78422431547;10943.343399396063;11108.222424430212;11132.220744547965;11323;7548.433867459962;8879.822403551892;8897.223266768191;9031.36780425364;9085.727428177166;10730.570777168981;11064.336139831636;11075.778484430266;11178.888211117948;11195.4962026709;3657.5062366580705;4203.180379825966;4254.553271906467;4327.290720165517;4343.761093139908;988.4711679251566;999.93000489965;1006.4062203300089;1013;1049.8110340138696;20628.58742825135;20954.161833526487;21120.78879211188;21132.049057792436;21241.575168496543;1041.4324193314662;1043.7129789307849;1075.419273592267;1076.9138468922467;1091.4051841746268;8763.780905477413;10841.16057162478;10959.888963224048;10966.890331096747;10998.06516446111;679015.6049219723;694618;744626;749915;756990;93782;97734;104664.83379155211;144056.27971860283;146662;833.737372727601;873.8645509945842;943.7593413679468;1007.2948935745007;1014.4674046125696;17217.39739109125;17909.10454477245;18637.627247455246;18664.720029199845;18857.717134242725;57527.71236143792;58533.70733146307;58687.70656146692;59223;59342.703286483295;60315.49211269753;75918.62040689982;81919.59040204762;82320.17679823124;82790;8133.552654604054;8280;8442.071372148936;8504.574771261288;8557.572121393781;10858.782824343627;11412.543498259976;12823.422945967268;13076.215427074407;13260;10833.349999000086;11024.228304018641;11086.556537738401;11188.776224475301;11257.635896461834;10851;10994.28537145083;10995.120390368591;11318.547258109913;11536.192466527262;11039.39283339424;11133.777324453627;11378.829317560409;11468;11494.080473562262;7042.577445353295;7885.422915416825;8429.199226073513;8434.746957591282;8574.142585741376;8122.1065682776025;10321.74195645115;10566.947165264124;11253.212275140662;11283.266587671775 04:58:54 INFO - PROCESS | 1646 | |3;mootools.html;1243.9004879609631;1359.3475131936673;1449.3333066789276;1469.5077149155027;1483.399223314558;7566.167721550625;7742.535447873118;7975.003124609424;8395.664173433062;8402.033766116898;8002.799930001753;8288.83422331553;8306.252437280644;8308.75073747787;8326.167383261685;1770.9026003569863;2275.4766403727112;2300.7469178390365;2301.8849057547054;2302.182725132579;2032.634125857345;2070.9585808283823;2114.048678094856;2117.1637203304685;2119.576084783042;2053.1992522916075;2206.106526856626;2275.4311422144465;2277.339571524256;2294.380517260339;2247.381885042783;2256.724950403017;2266.1162146762777;2269.5914735347715;2273.26119011322;2231.1744654477784;2275.7724227577273;2282.7945484906436;2299.4366380236947;2311.3643747969277;8220.219079187467;8310.501369917823;8348.540830254333;8360.45657032291;8363.456375335583;4986.975065124688;5093.057784309877;5128.615353848439;5131.384233891919;5149.407818100929;3453.2230248194205;3477.7391695622678;3478.704310133616;3485;3559.75081744278;1075.381655548063;1094.7865166292577;1205.8191271309286;1225.650689553473;1236.8620868800724;9243.537823108818;10138.39169649816;10610.363378197333;10714.142868570496;10719.249652524326;4302.247106756305;4527.456705195397;4579.519150489217;4593.035462552902;4604.1252162089095;9251.121143491358;9350.345475816763;9361.531923403938;9371.625134994523;9402.576884040234;5101.183810590288;5128.333316668809;5167.844964651066;5169.638125331191;5172.5344718975475;3612.9819350903076;3814.675752561008;4049.817758200888;4401.383806267125;4751.786169622375;3021.9848900755796;3649.5073165122367;3669.4495825625836;3680.6319368063514;3698.130939229279;1072.4798472740715;1135.9261647992853;1140.9942950285194;1141.7725944609579;1147.1109889835445;824.8040341504842;927.5547737086101;942.9952850235842;943.5801068524578;959.3859929645033;4852.344933434012;5366.195070739421;5610.13042978339;5620.353659329147;5831.9708401458565;888.6758729493009;1007.3401921741271;1015.1827778638267;1042.1246153231323;1046.884842667306;3641.4719865619336;3929.135590170158;3942.3100957332927;3980.283548961157;3993.306191772657;9577.808443831223;9733.610655573697;9910.058544438267;9910.653127140515;9933.50332483373;9630.181434577999;9832.557534910946;9871.851922221173;9909.900900991042;9916.851247231298;7536.886946695804;7632.274933881273;7722.6910923563555;7748.4188685848785;7828.295453409107;4835.58897493717;5446.455354464521;5472.452754724496;5785.334686511105;5820.388859169726;9017.50403727775;9145.496997665192;9175.541222938693;9202.723918282461;9215.354925155174;8140.87788683182;9086.318526110435;9089.77275568116;9193.218576421079;9223.907760922304;5994.28068631767;6975.965120174366;7072.929270707227;7074.964625176841;7080.752173673896;6932.722691092502;7597.734079307198;7647.273509016634;7653.617319134132;7664.386849051951;6400.455961243347;7007.614581198083;7068.787936361918;7110.822229444305;7121.323474269936;5616.803411880564;7041.5422997505;7049.894251586128;7106.395956343768;7143.964280178566;6285.685715714105;6389.041643753289;6397;6406.807795766134;6408.263049749338;4366.628836548802;4913.091078150554;4960.23116416956;5077.391360663276;5213.739313034257;4602.769861506844;4833.492483289204;5191.117510023229;5205.557527610195;5285.603579731459 04:58:54 INFO - PROCESS | 1646 | |4;prototype.html;358.7452908435011;362.2627952117438;364.0334910811795;365.9652333028363;375.45746396457116;4497.707649002812;5020.046191223665;5037.841296501807;5411.215373770806;5602.551795856332;4927.260910863372;5094.949050509494;5350.839474815762;5421.918671219935;5496.697681627509;3645.671889529948;3816.9618303817024;4165.354370072643;4173.749575025508;4194.727342722713;3481.495183198435;4007.5773100549322;4238.347044652588;4238.512571054337;4270.487541495025;3646.9635303647024;4133.937990930139;4199.307114326132;4206.095689426773;4225.6830737694645;3701.870434534792;4076.4496792932873;4193.874183774492;4225.83096676131;4238.4490016298;2986.7610591152657;3231.5637388952428;3268.8038717677014;3336.349411864687;3551.3962626353323;4306.935395969063;5023.748812559394;5228.398734145584;5291.761870715827;5308.230306605559;3839.865604703822;4877.707337559757;4891.9209336032045;4912.582430493377;4915.188993816016;5012.168125009358;5419.701916394597;5438.91841622376;5715.714214289311;5719.742611582489;4102.384642303648;4126.91746165078;4133.42132101503;4138.627523522868;4172.916541669149;5037.647364684473;5330.880515091874;5788.5369170466265;6016.909746353765;6087.939120608781;3521.8591256349973;3915.530136383652;3919.333713268751;4098.631123198927;4201.894952626209;4244.0477380404745;4274.337477690963;4295.78521073945;4312.805923733439;4345.500267469281;4725.527447255316;4952.380952380952;4973.52751488608;4994.675346102565;4996.7001979881325;4681.769578990486;4694.929576056362;5781.566382521328;5794.942050579524;5821.563382746311;1346.2730125731996;1370.8964283751477;1373.9038267321391;1375.21612680771;1395.6510872281929;889.8298737160613;923.8774888510492;956.4022485946283;990.3612170150357;992.5583115513527;4690.296455531698;4884.511548845087;5188.688678679291;5229.320188375563;5237.528622424;4985.903101317705;5053.747312634354;5100.659068310263;5266.130523664121;5731.168980497888;4614.72354628211;4623.745693986863;5052.595792336604;5138.717370544581;5230.450802665741;2498.925032249035;2898.6231789867184;2978.523436250233;2992.027591032923;3026.2888221267986;4949.406071271398;5271.497623177453;5925.34821169671;5929.242902050481;6027.758889644365;5437.646552974044;5559.833205003775;5821.854453638693;5830.475257226785;5834.387389324144;5372.785088596413;5842;5982.3976570191235;5989.562504998769;6038.486728628116;4569.771511424349;4782.536993448446;5271.4464981176425;5290.206469029677;5666.2350582670815;4981.775820088177;5212.582993360446;5455.9454405455435;5562.332520097614;5636.126400407943;5335.27827872328;5672.631278966854;5714.857028594215;6013.128096425906;6115.156452249726;3278.524763750432;4043.575424580377;4134.607212314825;4272.401863739017;4282.886449523084;5857.502112320501;5982.46157845796;5987.461128498456;5995.310539288038;6004.279486461653;5405.945940540544;5763.538916886555;5799.4921320457825;5872.911906321494;5881.441263080001;5108.872278193075;5438.830651409966;5537.9169312459535;5749.310082790092;5802.970985145047;4302.870913872589;5106.8254301510115;5338.3060202173465;5672.31932168126;5694.715264236855;5267.683938963674;5706.4864162225595;5756.079027355603;5822.592418530662;5844.26946631671;5143.3996202657545;5221.582273418041;5672.886542269214;5704.572157088152;5764.394738552392 04:58:54 INFO - PROCESS | 1646 | |5;yui.html;722.8290169924719;724.6195747232703;795.7652492514708;832.2551316571664;837.1544739812791;15345.079295242293;15884.841151588495;16006.83993160068;16054.03675779454;16082.195890205472;14701;14833.480828171016;14839.183844888526;14877.479288224915;14894.851051489482;685.8936864785966;713.7680253917472;862.5256109139948;871.4771137317606;891.1445012787732;881.2069137775989;891.5141248019812;891.9464832110061;893.1738142218454;893.7765558610347;946.4699768129835;959.7696552827306;960.2989817433278;962.3936919740554;962.4995002598645;932.1889955738483;952.2524818017823;955.6655170690237;955.8088382323493;956.4882787708577;713.8286811165335;713.9678714457862;716.2300526933515;716.6595866963203;718.314010120339;14364.64088397788;14467;14978.850211497855;15163.09021458705;15198.46805361807;14262.57212283633;14431;14508.419663213564;14635.341409636485;14753.114813111248;11413.315201087962;11582.362970036645;11842.46708898093;11898.10764192689;11983.580574679845;4501.954980450187;4532.818687252506;4543.863684089482;4734.4081989751285;4755.42934847817;107854.92145078606;109162.90837091686;109783.35324970132;110058.89941100567;111419.44290278497;10839.295445795999;10863.67408977732;10905.83641245382;11008.119350451945;11016.228863979604;15982.280797364147;17231.22459489326;17534.561635959202;20999.685004724943;21395.78604213969;7542.057242844645;7559.811004724816;7584.5070070445245;7665.386769058462;7829.982102326661;3649.908752281214;4096.832402765198;4144.171165766858;4231.11146659205;4262.914741705211;3383.5601371821504;3485.4946032825605;3735.458358538051;3918.5101862267215;3924.293627147141;653.8463461086641;691.1050190003856;708.3518580498888;721.3291638775988;724.8948902409227;669.6283562622791;674.5514233035075;690.8065741592362;707.6426404665611;726.4878260826105;9617.663381781604;9842.360246584092;10508.159347252202;10564.683059508227;10805.675829725122;659.1918427498794;688.0951548713426;693.4244577001077;697.8394969157122;700.2402393403214;4846.539578740014;5117.488251174927;5127.897442051213;5133;5154.175331946871;92878;93984.53007735054;104063.95936040542;104106;106933;68369.63260734758;71199.93200102003;73813;93165.5341723287;95888;69883;69983;79556;82023.17976820275;84029;9246.4914429707;10739.624113156002;11103.333799972028;11229.391339767575;11367.374794386058;43760.56239437565;44674.77662611666;51058.489415105374;51244.74377628088;53003;58723;59011.81976360363;60001;64819.70360592856;67331.66334168494;13072.411741471466;13424.93287533556;13577.253251070892;13627.727445451234;13918.443262269397;14720.852791471949;14982.625434363792;15161.393544258106;15325.46360877364;15470.767938480707;11295.491702873225;13703.314834258446;13982.720345593234;14342.426302947766;14409.639759006108;13793.24137172425;13837;14217;14585.854141458874;14668.55994320172;13677.931610341884;13687.589372318846;13761.38073786702;13765.724685506435;13782.448702051806;9322.440653560809;11352.716182095513;11437.59968401102;11564.421778910853;11884.821727674265;9884.159846413135;11246.972944212837;11353.37556434371;11372.147088968526;11385.544578216779 04:58:54 INFO - PROCESS | 1646 | __end_tp_report 04:58:54 INFO - PROCESS | 1646 | __start_cc_report 04:58:54 INFO - PROCESS | 1646 | _x_x_mozilla_cycle_collect,380 04:58:54 INFO - PROCESS | 1646 | __end_cc_report 04:58:54 INFO - PROCESS | 1646 | __startTimestamp1484053134421__endTimestamp 04:58:54 INFO - PROCESS | 1646 | 04:58:54 INFO - PROCESS | 1646 | ------- Summary: start ------- 04:58:54 INFO - PROCESS | 1646 | Number of tests: 6 04:58:54 INFO - PROCESS | 1646 | 04:58:54 INFO - PROCESS | 1646 | [#0] dojo.html Cycles:165 Average:5657.14 Median:5867.62 stddev:657.00 (11.2%) stddev-sans-first:632.28 04:58:54 INFO - PROCESS | 1646 | Values: 3292.1 3334.5 3352.0 3365.9 3369.3 5480.0 5619.2 5627.1 5634.3 5688.1 4379.3 5456.3 5497.9 5562.9 5654.5 4939.0 5032.7 5079.3 5123.2 5139.0 5026.9 5229.5 5256.4 5257.8 5284.2 5076.3 5086.1 5178.7 5185.2 5210.7 5110.8 5148.8 5178.1 5178.4 5196.9 4245.9 5060.6 5071.9 5122.7 5128.3 5563.9 5642.5 5662.3 5669.0 5726.7 6078.6 6105.8 6107.1 6147.4 6238.8 6173.8 6176.0 6188.6 6196.8 6216.1 5887.7 5896.3 5903.5 5908.1 5924.0 6170.2 6263.5 6276.7 6284.8 6292.7 5965.7 5991.6 6075.1 6080.6 6131.0 5215.3 6195.4 6215.8 6216.9 6272.8 6027.6 6028.4 6086.3 6134.2 6149.8 6147.8 6170.0 6183.7 6197.3 6280.1 4819.0 4829.1 4831.0 4935.9 4986.8 4327.4 4347.7 4397.5 4439.5 4493.5 4887.2 5776.5 5782.1 5796.2 5797.8 4704.0 5218.3 5526.3 6286.2 6352.6 5596.0 5609.7 5617.7 5705.1 5713.4 5433.7 5481.9 5496.2 5525.6 5534.7 5562.8 5867.6 6404.9 6406.9 6480.5 6096.2 6126.7 6247.7 6254.7 6283.5 6199.4 6201.3 6209.4 6253.2 6273.8 6166.1 6168.8 6170.5 6171.1 6181.7 6095.8 6124.1 6196.9 6206.4 6229.3 5954.1 5979.8 5990.9 6049.0 6149.0 6055.3 6115.1 6119.9 6144.5 6151.6 5773.4 5817.7 5819.4 5833.4 5875.6 5993.6 6032.5 6054.5 6054.6 6085.1 5774.1 5824.4 5851.9 5872.8 5900.6 04:58:54 INFO - PROCESS | 1646 | 04:58:54 INFO - PROCESS | 1646 | [#1] ext.html Cycles:180 Average:68793.21 Median:38801.61 stddev:144565.62 (372.6%) stddev-sans-first:144880.08 04:58:54 INFO - PROCESS | 1646 | Values: 442.6 458.4 483.0 487.7 530.0 40290.0 43252.6 43307.0 45181.3 45218.5 43380.8 43943.0 44026.3 44092.8 44321.3 8448.1 8671.7 9270.4 9513.0 9528.3 11215.4 11525.1 11597.4 11659.1 11748.0 11452.7 11462.1 11501.5 11552.2 11569.2 11481.5 11483.4 11557.7 11569.3 11573.6 11322.4 11392.9 11417.7 11419.9 11475.2 31284.8 33354.0 38894.6 41814.8 42294.0 3424.7 3539.6 3907.7 4095.0 4111.0 31442.8 31575.8 31687.2 32003.0 32475.0 3032.2 3175.5 3272.1 3326.1 3340.1 484966.0 487779.6 488147.0 488607.0 489655.0 63978.1 64464.0 64518.7 64526.7 64621.7 67550.0 67865.6 67896.0 67903.0 68116.0 16295.6 16503.4 16516.4 16540.8 16609.0 11391.4 11668.3 12449.7 12676.4 12780.6 18037.5 18112.5 18124.9 18212.1 18344.3 6602.0 7685.7 7812.1 7818.9 7843.7 783.2 794.6 803.4 803.6 812.8 24675.6 25657.7 30036.9 30190.4 30396.2 928.3 993.1 1075.8 1097.6 1103.8 13079.4 15803.8 15817.1 16016.0 16093.1 545673.0 599511.0 622053.0 623740.0 625320.0 59769.4 59887.4 59935.1 59979.7 60098.4 1256.6 1401.4 1440.7 1466.4 1467.4 27073.7 29369.0 29578.3 29918.3 30583.0 52586.7 52884.5 52961.7 52997.5 53073.0 528703.0 528792.0 530722.3 530813.0 531336.7 36529.1 41300.4 41519.0 41788.6 42039.8 39571.8 46293.3 47719.8 48478.8 48960.5 34243.8 35956.5 38168.2 39652.4 41110.0 39056.0 39294.0 39301.6 40198.2 40478.8 37544.0 37909.6 38614.0 38637.2 38708.6 30864.5 34191.5 34702.1 34848.0 34869.0 21623.8 22958.8 23105.4 25717.9 25974.4 04:58:54 INFO - PROCESS | 1646 | 04:58:54 INFO - PROCESS | 1646 | [#2] jquery.html Cycles:180 Average:40100.73 Median:25815.39 stddev:119776.38 (464.0%) stddev-sans-first:120075.26 04:58:54 INFO - PROCESS | 1646 | Values: 386.8 388.4 393.1 397.8 420.0 12808.4 16651.7 19873.9 21155.0 21243.8 17042.0 20467.9 21468.0 21614.5 21890.5 5719.8 5721.8 5838.6 5924.8 5929.2 4616.7 5913.4 5925.5 5926.6 5931.9 4134.9 6480.8 6548.9 6596.5 6626.6 6623.1 6648.5 6658.2 7301.8 7624.8 7288.7 7568.0 7586.8 7605.2 7621.5 28321.7 28457.3 28693.4 28724.0 28975.7 3276.6 3361.1 3390.3 3400.4 3504.3 14859.0 16140.3 17114.3 17358.6 17505.0 1692.3 1934.2 1967.8 2027.5 2040.5 101213.5 103685.5 123367.4 133401.0 143182.0 22239.0 23040.7 23080.3 23163.8 23309.1 39348.0 39520.0 39972.0 39987.6 40087.0 10788.8 10943.3 11108.2 11132.2 11323.0 7548.4 8879.8 8897.2 9031.4 9085.7 10730.6 11064.3 11075.8 11178.9 11195.5 3657.5 4203.2 4254.6 4327.3 4343.8 988.5 999.9 1006.4 1013.0 1049.8 20628.6 20954.2 21120.8 21132.0 21241.6 1041.4 1043.7 1075.4 1076.9 1091.4 8763.8 10841.2 10959.9 10966.9 10998.1 679015.6 694618.0 744626.0 749915.0 756990.0 93782.0 97734.0 104664.8 144056.3 146662.0 833.7 873.9 943.8 1007.3 1014.5 17217.4 17909.1 18637.6 18664.7 18857.7 57527.7 58533.7 58687.7 59223.0 59342.7 60315.5 75918.6 81919.6 82320.2 82790.0 8133.6 8280.0 8442.1 8504.6 8557.6 10858.8 11412.5 12823.4 13076.2 13260.0 10833.3 11024.2 11086.6 11188.8 11257.6 10851.0 10994.3 10995.1 11318.5 11536.2 11039.4 11133.8 11378.8 11468.0 11494.1 7042.6 7885.4 8429.2 8434.7 8574.1 8122.1 10321.7 10566.9 11253.2 11283.3 04:58:54 INFO - PROCESS | 1646 | 04:58:54 INFO - PROCESS | 1646 | [#3] mootools.html Cycles:180 Average:5318.00 Median:5181.83 stddev:2906.17 (56.1%) stddev-sans-first:2898.19 04:58:54 INFO - PROCESS | 1646 | Values: 1243.9 1359.3 1449.3 1469.5 1483.4 7566.2 7742.5 7975.0 8395.7 8402.0 8002.8 8288.8 8306.3 8308.8 8326.2 1770.9 2275.5 2300.7 2301.9 2302.2 2032.6 2071.0 2114.0 2117.2 2119.6 2053.2 2206.1 2275.4 2277.3 2294.4 2247.4 2256.7 2266.1 2269.6 2273.3 2231.2 2275.8 2282.8 2299.4 2311.4 8220.2 8310.5 8348.5 8360.5 8363.5 4987.0 5093.1 5128.6 5131.4 5149.4 3453.2 3477.7 3478.7 3485.0 3559.8 1075.4 1094.8 1205.8 1225.7 1236.9 9243.5 10138.4 10610.4 10714.1 10719.2 4302.2 4527.5 4579.5 4593.0 4604.1 9251.1 9350.3 9361.5 9371.6 9402.6 5101.2 5128.3 5167.8 5169.6 5172.5 3613.0 3814.7 4049.8 4401.4 4751.8 3022.0 3649.5 3669.4 3680.6 3698.1 1072.5 1135.9 1141.0 1141.8 1147.1 824.8 927.6 943.0 943.6 959.4 4852.3 5366.2 5610.1 5620.4 5832.0 888.7 1007.3 1015.2 1042.1 1046.9 3641.5 3929.1 3942.3 3980.3 3993.3 9577.8 9733.6 9910.1 9910.7 9933.5 9630.2 9832.6 9871.9 9909.9 9916.9 7536.9 7632.3 7722.7 7748.4 7828.3 4835.6 5446.5 5472.5 5785.3 5820.4 9017.5 9145.5 9175.5 9202.7 9215.4 8140.9 9086.3 9089.8 9193.2 9223.9 5994.3 6976.0 7072.9 7075.0 7080.8 6932.7 7597.7 7647.3 7653.6 7664.4 6400.5 7007.6 7068.8 7110.8 7121.3 5616.8 7041.5 7049.9 7106.4 7144.0 6285.7 6389.0 6397.0 6406.8 6408.3 4366.6 4913.1 4960.2 5077.4 5213.7 4602.8 4833.5 5191.1 5205.6 5285.6 04:58:54 INFO - PROCESS | 1646 | 04:58:54 INFO - PROCESS | 1646 | [#4] prototype.html Cycles:180 Average:4588.22 Median:5053.17 stddev:1378.22 (27.3%) stddev-sans-first:1345.03 04:58:54 INFO - PROCESS | 1646 | Values: 358.7 362.3 364.0 366.0 375.5 4497.7 5020.0 5037.8 5411.2 5602.6 4927.3 5094.9 5350.8 5421.9 5496.7 3645.7 3817.0 4165.4 4173.7 4194.7 3481.5 4007.6 4238.3 4238.5 4270.5 3647.0 4133.9 4199.3 4206.1 4225.7 3701.9 4076.4 4193.9 4225.8 4238.4 2986.8 3231.6 3268.8 3336.3 3551.4 4306.9 5023.7 5228.4 5291.8 5308.2 3839.9 4877.7 4891.9 4912.6 4915.2 5012.2 5419.7 5438.9 5715.7 5719.7 4102.4 4126.9 4133.4 4138.6 4172.9 5037.6 5330.9 5788.5 6016.9 6087.9 3521.9 3915.5 3919.3 4098.6 4201.9 4244.0 4274.3 4295.8 4312.8 4345.5 4725.5 4952.4 4973.5 4994.7 4996.7 4681.8 4694.9 5781.6 5794.9 5821.6 1346.3 1370.9 1373.9 1375.2 1395.7 889.8 923.9 956.4 990.4 992.6 4690.3 4884.5 5188.7 5229.3 5237.5 4985.9 5053.7 5100.7 5266.1 5731.2 4614.7 4623.7 5052.6 5138.7 5230.5 2498.9 2898.6 2978.5 2992.0 3026.3 4949.4 5271.5 5925.3 5929.2 6027.8 5437.6 5559.8 5821.9 5830.5 5834.4 5372.8 5842.0 5982.4 5989.6 6038.5 4569.8 4782.5 5271.4 5290.2 5666.2 4981.8 5212.6 5455.9 5562.3 5636.1 5335.3 5672.6 5714.9 6013.1 6115.2 3278.5 4043.6 4134.6 4272.4 4282.9 5857.5 5982.5 5987.5 5995.3 6004.3 5405.9 5763.5 5799.5 5872.9 5881.4 5108.9 5438.8 5537.9 5749.3 5803.0 4302.9 5106.8 5338.3 5672.3 5694.7 5267.7 5706.5 5756.1 5822.6 5844.3 5143.4 5221.6 5672.9 5704.6 5764.4 04:58:54 INFO - PROCESS | 1646 | 04:58:54 INFO - PROCESS | 1646 | [#5] yui.html Cycles:180 Average:20147.58 Median:4187.64 stddev:28691.91 (685.2%) stddev-sans-first:28735.33 04:58:54 INFO - PROCESS | 1646 | Values: 722.8 724.6 795.8 832.3 837.2 15345.1 15884.8 16006.8 16054.0 16082.2 14701.0 14833.5 14839.2 14877.5 14894.9 685.9 713.8 862.5 871.5 891.1 881.2 891.5 891.9 893.2 893.8 946.5 959.8 960.3 962.4 962.5 932.2 952.3 955.7 955.8 956.5 713.8 714.0 716.2 716.7 718.3 14364.6 14467.0 14978.9 15163.1 15198.5 14262.6 14431.0 14508.4 14635.3 14753.1 11413.3 11582.4 11842.5 11898.1 11983.6 4502.0 4532.8 4543.9 4734.4 4755.4 107854.9 109162.9 109783.4 110058.9 111419.4 10839.3 10863.7 10905.8 11008.1 11016.2 15982.3 17231.2 17534.6 20999.7 21395.8 7542.1 7559.8 7584.5 7665.4 7830.0 3649.9 4096.8 4144.2 4231.1 4262.9 3383.6 3485.5 3735.5 3918.5 3924.3 653.8 691.1 708.4 721.3 724.9 669.6 674.6 690.8 707.6 726.5 9617.7 9842.4 10508.2 10564.7 10805.7 659.2 688.1 693.4 697.8 700.2 4846.5 5117.5 5127.9 5133.0 5154.2 92878.0 93984.5 104064.0 104106.0 106933.0 68369.6 71199.9 73813.0 93165.5 95888.0 69883.0 69983.0 79556.0 82023.2 84029.0 9246.5 10739.6 11103.3 11229.4 11367.4 43760.6 44674.8 51058.5 51244.7 53003.0 58723.0 59011.8 60001.0 64819.7 67331.7 13072.4 13424.9 13577.3 13627.7 13918.4 14720.9 14982.6 15161.4 15325.5 15470.8 11295.5 13703.3 13982.7 14342.4 14409.6 13793.2 13837.0 14217.0 14585.9 14668.6 13677.9 13687.6 13761.4 13765.7 13782.4 9322.4 11352.7 11437.6 11564.4 11884.8 9884.2 11247.0 11353.4 11372.1 11385.5 04:58:54 INFO - PROCESS | 1646 | -------- Summary: end -------- 04:58:54 INFO - PROCESS | 1646 | 04:58:55 INFO - TEST-INFO | 1646: exit 0 04:58:55 INFO - TEST-OK | dromaeo_css | took 1116141ms 04:58:55 INFO - TEST-START | kraken 04:58:55 INFO - operating with platform_type : mac_ 04:58:55 INFO - Initialising browser for kraken test... 04:58:55 INFO - TEST-INFO | started process 1658 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/T/tmp5Z33sS/profile http://localhost:49251/getInfo.html) 04:58:57 INFO - PROCESS | 1658 | 2017-01-10 04:58:57.770 plugin-container[1659:10104] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4647, name = 'com.apple.tsm.portname' 04:58:57 INFO - PROCESS | 1658 | See /usr/include/servers/bootstrap_defs.h for the error codes. 04:58:57 INFO - PROCESS | 1658 | 2017-01-10 04:58:57.772 plugin-container[1659:10104] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x900b, name = 'com.apple.CFPasteboardClient' 04:58:57 INFO - PROCESS | 1658 | See /usr/include/servers/bootstrap_defs.h for the error codes. 04:58:57 INFO - PROCESS | 1658 | 2017-01-10 04:58:57.772 plugin-container[1659:10104] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 04:58:57 INFO - PROCESS | 1658 | __metrics Screen width/height:1600/1200 04:58:57 INFO - PROCESS | 1658 | colorDepth:24 04:58:57 INFO - PROCESS | 1658 | Browser inner width/height: 1024/689 04:58:57 INFO - PROCESS | 1658 | __metrics 04:58:57 ERROR - PROCESS | 1658 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3252: TypeError: this.tabs is undefined 04:58:58 INFO - TEST-INFO | 1658: exit 0 04:58:58 INFO - Browser initialized. 04:58:58 INFO - Running cycle 1/1 for kraken test... 04:58:58 INFO - TEST-INFO | started process 1660 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/T/tmp5Z33sS/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:59:00 INFO - PROCESS | 1660 | 2017-01-10 04:59:00.882 plugin-container[1661:10224] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x434b, name = 'com.apple.tsm.portname' 04:59:00 INFO - PROCESS | 1660 | See /usr/include/servers/bootstrap_defs.h for the error codes. 04:59:00 INFO - PROCESS | 1660 | 2017-01-10 04:59:00.883 plugin-container[1661:10224] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x460b, name = 'com.apple.CFPasteboardClient' 04:59:00 INFO - PROCESS | 1660 | See /usr/include/servers/bootstrap_defs.h for the error codes. 04:59:00 INFO - PROCESS | 1660 | 2017-01-10 04:59:00.883 plugin-container[1661:10224] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 04:59:10 INFO - PROCESS | 1660 | RSS: Main: 233357312 04:59:10 INFO - PROCESS | 1660 | 04:59:13 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49251/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49251/tests/kraken/driver.html?testName=audio-beat-detection) 04:59:14 INFO - PROCESS | 1660 | RSS: Main: 246140928 04:59:14 INFO - PROCESS | 1660 | 04:59:17 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49251/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49251/tests/kraken/driver.html?testName=audio-dft) 04:59:18 INFO - PROCESS | 1660 | RSS: Main: 254140416 04:59:18 INFO - PROCESS | 1660 | 04:59:21 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49251/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49251/tests/kraken/driver.html?testName=audio-fft) 04:59:22 INFO - PROCESS | 1660 | RSS: Main: 256806912 04:59:22 INFO - PROCESS | 1660 | 04:59:24 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49251/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49251/tests/kraken/driver.html?testName=audio-oscillator) 04:59:24 INFO - PROCESS | 1660 | RSS: Main: 257986560 04:59:24 INFO - PROCESS | 1660 | 04:59:26 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49251/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49251/tests/kraken/driver.html?testName=imaging-gaussian-blur) 04:59:27 INFO - PROCESS | 1660 | RSS: Main: 258387968 04:59:27 INFO - PROCESS | 1660 | 04:59:30 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49251/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49251/tests/kraken/driver.html?testName=imaging-darkroom) 04:59:31 INFO - PROCESS | 1660 | RSS: Main: 264900608 04:59:31 INFO - PROCESS | 1660 | 04:59:34 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49251/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49251/tests/kraken/driver.html?testName=imaging-desaturate) 04:59:35 INFO - PROCESS | 1660 | RSS: Main: 263938048 04:59:35 INFO - PROCESS | 1660 | 04:59:38 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49251/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49251/tests/kraken/driver.html?testName=json-parse-financial) 04:59:39 INFO - PROCESS | 1660 | RSS: Main: 264171520 04:59:39 INFO - PROCESS | 1660 | 04:59:40 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49251/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49251/tests/kraken/driver.html?testName=json-stringify-tinderbox) 04:59:41 INFO - PROCESS | 1660 | RSS: Main: 263610368 04:59:41 INFO - PROCESS | 1660 | 04:59:43 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49251/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49251/tests/kraken/driver.html?testName=stanford-crypto-aes) 04:59:44 INFO - PROCESS | 1660 | RSS: Main: 263561216 04:59:44 INFO - PROCESS | 1660 | 04:59:46 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49251/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49251/tests/kraken/driver.html?testName=stanford-crypto-ccm) 04:59:46 INFO - PROCESS | 1660 | RSS: Main: 263647232 04:59:46 INFO - PROCESS | 1660 | 04:59:50 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49251/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49251/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 04:59:50 INFO - PROCESS | 1660 | RSS: Main: 263852032 04:59:50 INFO - PROCESS | 1660 | 04:59:54 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49251/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49251/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 04:59:54 INFO - PROCESS | 1660 | RSS: Main: 261337088 04:59:54 INFO - PROCESS | 1660 | 04:59:56 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49251/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49251/tests/kraken/driver.html?testName=ai-astar) 04:59:56 INFO - PROCESS | 1660 | RSS: Main: 264679424 04:59:56 INFO - PROCESS | 1660 | 04:59:56 INFO - PROCESS | 1660 | __start_tp_report 04:59:56 INFO - PROCESS | 1660 | _x_x_mozilla_page_load 04:59:56 INFO - PROCESS | 1660 | _x_x_mozilla_page_load_details 04:59:56 INFO - PROCESS | 1660 | |i|pagename|runs| 04:59:56 INFO - PROCESS | 1660 | |0;ai-astar;108;115;117;121;116;117;136;108;176;170 04:59:56 INFO - PROCESS | 1660 | |1;audio-beat-detection;141;106;131;135;117;142;141;142;130;108 04:59:56 INFO - PROCESS | 1660 | |2;audio-dft;281;257;199;195;253;271;196;200;256;216 04:59:56 INFO - PROCESS | 1660 | |3;audio-fft;83;59;60;85;60;61;52;60;87;82 04:59:56 INFO - PROCESS | 1660 | |4;audio-oscillator;89;71;86;71;72;84;116;71;71;85 04:59:56 INFO - PROCESS | 1660 | |5;imaging-gaussian-blur;85;88;80;126;138;89;111;113;88;132 04:59:56 INFO - PROCESS | 1660 | |6;imaging-darkroom;147;136;96;107;153;113;105;151;112;105 04:59:56 INFO - PROCESS | 1660 | |7;imaging-desaturate;75;143;99;149;86;148;142;113;94;135 04:59:56 INFO - PROCESS | 1660 | |8;json-parse-financial;62;63;62;63;64;64;70;87;99;95 04:59:56 INFO - PROCESS | 1660 | |9;json-stringify-tinderbox;73;65;75;75;74;69;71;74;72;72 04:59:56 INFO - PROCESS | 1660 | |10;stanford-crypto-aes;91;93;91;99;97;90;94;126;79;94 04:59:56 INFO - PROCESS | 1660 | |11;stanford-crypto-ccm;154;157;135;141;144;160;148;169;225;148 04:59:56 INFO - PROCESS | 1660 | |12;stanford-crypto-pbkdf2;172;240;245;182;179;251;215;175;177;180 04:59:56 INFO - PROCESS | 1660 | |13;stanford-crypto-sha256-iterative;68;71;73;72;67;72;85;89;79;68 04:59:56 INFO - PROCESS | 1660 | __end_tp_report 04:59:56 INFO - PROCESS | 1660 | __start_cc_report 04:59:56 INFO - PROCESS | 1660 | _x_x_mozilla_cycle_collect,925 04:59:56 INFO - PROCESS | 1660 | __end_cc_report 04:59:56 INFO - PROCESS | 1660 | __startTimestamp1484053196836__endTimestamp 04:59:56 INFO - PROCESS | 1660 | 04:59:56 INFO - PROCESS | 1660 | ------- Summary: start ------- 04:59:56 INFO - PROCESS | 1660 | Number of tests: 14 04:59:56 INFO - PROCESS | 1660 | 04:59:56 INFO - PROCESS | 1660 | [#0] ai-astar Cycles:10 Average:128.40 Median:119.00 stddev:24.80 (20.8%) stddev-sans-first:25.18 04:59:56 INFO - PROCESS | 1660 | Values: 108.0 115.0 117.0 121.0 116.0 117.0 136.0 108.0 176.0 170.0 04:59:56 INFO - PROCESS | 1660 | 04:59:56 INFO - PROCESS | 1660 | [#1] audio-beat-detection Cycles:10 Average:129.30 Median:138.00 stddev:14.06 (10.2%) stddev-sans-first:14.27 04:59:56 INFO - PROCESS | 1660 | Values: 141.0 106.0 131.0 135.0 117.0 142.0 141.0 142.0 130.0 108.0 04:59:56 INFO - PROCESS | 1660 | 04:59:56 INFO - PROCESS | 1660 | [#2] audio-dft Cycles:10 Average:232.40 Median:254.50 stddev:34.31 (13.5%) stddev-sans-first:31.57 04:59:56 INFO - PROCESS | 1660 | Values: 281.0 257.0 199.0 195.0 253.0 271.0 196.0 200.0 256.0 216.0 04:59:56 INFO - PROCESS | 1660 | 04:59:56 INFO - PROCESS | 1660 | [#3] audio-fft Cycles:10 Average:68.90 Median:71.50 stddev:13.50 (18.9%) stddev-sans-first:13.32 04:59:56 INFO - PROCESS | 1660 | Values: 83.0 59.0 60.0 85.0 60.0 61.0 52.0 60.0 87.0 82.0 04:59:56 INFO - PROCESS | 1660 | 04:59:56 INFO - PROCESS | 1660 | [#4] audio-oscillator Cycles:10 Average:81.60 Median:78.00 stddev:14.21 (18.2%) stddev-sans-first:14.81 04:59:56 INFO - PROCESS | 1660 | Values: 89.0 71.0 86.0 71.0 72.0 84.0 116.0 71.0 71.0 85.0 04:59:56 INFO - PROCESS | 1660 | 04:59:56 INFO - PROCESS | 1660 | [#5] imaging-gaussian-blur Cycles:10 Average:105.00 Median:82.50 stddev:21.65 (26.2%) stddev-sans-first:21.72 04:59:56 INFO - PROCESS | 1660 | Values: 85.0 88.0 80.0 126.0 138.0 89.0 111.0 113.0 88.0 132.0 04:59:56 INFO - PROCESS | 1660 | 04:59:56 INFO - PROCESS | 1660 | [#6] imaging-darkroom Cycles:10 Average:122.50 Median:141.50 stddev:21.81 (15.4%) stddev-sans-first:21.25 04:59:56 INFO - PROCESS | 1660 | Values: 147.0 136.0 96.0 107.0 153.0 113.0 105.0 151.0 112.0 105.0 04:59:56 INFO - PROCESS | 1660 | 04:59:56 INFO - PROCESS | 1660 | [#7] imaging-desaturate Cycles:10 Average:118.40 Median:112.00 stddev:28.25 (25.2%) stddev-sans-first:25.23 04:59:56 INFO - PROCESS | 1660 | Values: 75.0 143.0 99.0 149.0 86.0 148.0 142.0 113.0 94.0 135.0 04:59:56 INFO - PROCESS | 1660 | 04:59:56 INFO - PROCESS | 1660 | [#8] json-parse-financial Cycles:10 Average:72.90 Median:67.00 stddev:14.79 (22.1%) stddev-sans-first:15.15 04:59:56 INFO - PROCESS | 1660 | Values: 62.0 63.0 62.0 63.0 64.0 64.0 70.0 87.0 99.0 95.0 04:59:56 INFO - PROCESS | 1660 | 04:59:56 INFO - PROCESS | 1660 | [#9] json-stringify-tinderbox Cycles:10 Average:72.00 Median:73.50 stddev:3.09 (4.2%) stddev-sans-first:3.26 04:59:56 INFO - PROCESS | 1660 | Values: 73.0 65.0 75.0 75.0 74.0 69.0 71.0 74.0 72.0 72.0 04:59:56 INFO - PROCESS | 1660 | 04:59:56 INFO - PROCESS | 1660 | [#10] stanford-crypto-aes Cycles:10 Average:95.40 Median:93.50 stddev:12.01 (12.8%) stddev-sans-first:12.63 04:59:56 INFO - PROCESS | 1660 | Values: 91.0 93.0 91.0 99.0 97.0 90.0 94.0 126.0 79.0 94.0 04:59:56 INFO - PROCESS | 1660 | 04:59:56 INFO - PROCESS | 1660 | [#11] stanford-crypto-ccm Cycles:10 Average:158.10 Median:155.50 stddev:25.48 (16.4%) stddev-sans-first:26.99 04:59:56 INFO - PROCESS | 1660 | Values: 154.0 157.0 135.0 141.0 144.0 160.0 148.0 169.0 225.0 148.0 04:59:56 INFO - PROCESS | 1660 | 04:59:56 INFO - PROCESS | 1660 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:201.60 Median:198.50 stddev:32.54 (16.4%) stddev-sans-first:32.70 04:59:56 INFO - PROCESS | 1660 | Values: 172.0 240.0 245.0 182.0 179.0 251.0 215.0 175.0 177.0 180.0 04:59:56 INFO - PROCESS | 1660 | 04:59:56 INFO - PROCESS | 1660 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:74.40 Median:72.50 stddev:7.52 (10.4%) stddev-sans-first:7.61 04:59:56 INFO - PROCESS | 1660 | Values: 68.0 71.0 73.0 72.0 67.0 72.0 85.0 89.0 79.0 68.0 04:59:56 INFO - PROCESS | 1660 | -------- Summary: end -------- 04:59:56 INFO - PROCESS | 1660 | 04:59:57 INFO - TEST-INFO | 1660: exit 0 04:59:57 INFO - TEST-OK | kraken | took 62634ms 04:59:57 INFO - SUITE-END | took 1178s 04:59:58 INFO - Completed test suite (00:19:39) 04:59:58 INFO - javascript benchmark 04:59:58 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7486.477977367324, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3292.078218098933, 3334.499825026246, 3351.983240083799, 3365.8821941232063, 3369.292448585797, 5479.986202552531, 5619.1852181433715, 5627.099664053752, 5634.267545219116, 5688.146777983305, 4379.3430985352215, 5456.263404440409, 5497.917531237024, 5562.916556251659, 5654.547636189105, 4939.036887806879, 5032.723200223986, 5079.2635067915135, 5123.180291153416, 5139.023585518758, 5026.924596131061, 5229.450907654698, 5256.369235691704, 5257.842264732045, 5284.154535274357, 5076.340075790124, 5086.1353569892835, 5178.689278643255, 5185.22221666753, 5210.687358758468, 5110.8466745997675, 5148.819791307324, 5178.093833579109, 5178.40448348441, 5196.948030519684, 4245.915081698348, 5060.645754797166, 5071.909539449, 5122.692638441706, 5128.333316668846, 5563.915036567831, 5642.463965923231, 5662.348829884533, 5669.0, 5726.713664316809, 6078.635281883057, 6105.8168254952425, 6107.083937409379, 6147.415995480422, 6238.750449981995, 6173.783917562864, 6176.042713379434, 6188.628682279078, 6196.81409557714, 6216.067589861512, 5887.705614719247, 5896.292444906553, 5903.49820265278, 5908.054711246266, 5924.0, 6170.197874276315, 6263.467605253513, 6276.717547710364, 6284.780032698925, 6292.685365731787, 5965.731542080617, 5991.64050156992, 6075.149479072933, 6080.635161890301, 6130.979101201984, 5215.348081489814, 6195.411435913581, 6215.844603884939, 6216.875662486816, 6272.843178920472, 6027.608205466719, 6028.367021462771, 6086.330503644595, 6134.171886795315, 6149.754009839557, 6147.801178770186, 6170.012797952396, 6183.690815459299, 6197.318294987548, 6280.120783090371, 4819.036192761461, 4829.082474329866, 4830.985493046429, 4935.876603084952, 4986.775595098229, 4327.415798867112, 4347.65218782503, 4397.538258482878, 4439.533848945879, 4493.460784705856, 4887.193613053877, 5776.508996735324, 5782.132680098019, 5796.159556864315, 5797.797077102281, 4704.0, 5218.269442278084, 5526.309211348582, 6286.245650521967, 6352.61884286935, 5596.048671725816, 5609.719514024282, 5617.662940223599, 5705.115707065412, 5713.428657134254, 5433.701146436984, 5481.945180548143, 5496.230527726121, 5525.585581081355, 5534.695591742493, 5562.7774889004795, 5867.618604790675, 6404.903926441107, 6406.935930640727, 6480.513961452815, 6096.207493025879, 6126.724297406538, 6247.656378899204, 6254.655993920378, 6283.528735344776, 6199.380061993765, 6201.255849297932, 6209.410106039919, 6253.249610046823, 6273.811785646438, 6166.075088736726, 6168.814935551941, 6170.53720970938, 6171.105189747551, 6181.721822517908, 6095.817125486243, 6124.14262003311, 6196.876062478816, 6206.410391012847, 6229.2524897012645, 5954.10688396744, 5979.820605381846, 5990.850228744316, 6048.969755151372, 6148.985417406257, 6055.303640081271, 6115.0720943201395, 6119.929102025315, 6144.508439324933, 6151.6001459904965, 5773.422657734193, 5817.70911454434, 5819.388964158702, 5833.445822646841, 5875.6474611523445, 5993.61041532299, 6032.517398608188, 6054.515638748978, 6054.636721796706, 6085.087236914498, 5774.133879918046, 5824.388439213822, 5851.853703657443, 5872.794452194153, 5900.64596124234], "value": 5616.242652441036, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [442.6038695367644, 458.4132310642379, 482.9830955916543, 487.7414970065864, 529.9682019078855, 40290.0, 43252.567474325246, 43307.0, 45181.32228016583, 45218.547814521844, 43380.78309608447, 43943.0, 44026.33960490595, 44092.77953610227, 44321.335179972324, 8448.07071222165, 8671.69649062283, 9270.397424167424, 9512.952435237814, 9528.285378596598, 11215.439228038606, 11525.13561482888, 11597.420128993559, 11659.06727461801, 11748.004258913965, 11452.656420307363, 11462.083033357354, 11501.482433290523, 11552.191346605741, 11569.190156689036, 11481.483333250024, 11483.368414737228, 11557.711057223552, 11569.305841649528, 11573.594924177656, 11322.433878306052, 11392.886071139266, 11417.714557136054, 11419.942900285445, 11475.196736228383, 31284.843575782204, 33354.0, 38894.611053889384, 41814.79092604518, 42294.0, 3424.743144264165, 3539.6460353964653, 3907.7264591478606, 4094.979525102385, 4110.979445102786, 31442.842785786153, 31575.84212078925, 31687.20781980446, 32003.0, 32475.0, 3032.1964679359985, 3175.539546765706, 3272.051105179495, 3326.101952472843, 3340.064781861083, 484966.0, 487779.56110219576, 488147.0, 488607.0, 489655.0, 63978.08065758034, 64464.0, 64518.67740661267, 64526.6773666138, 64621.67689161618, 67549.98675019879, 67865.55277248654, 67896.0, 67903.0, 68116.0, 16295.592610184603, 16503.422380216634, 16516.42192523256, 16540.83459165417, 16609.0, 11391.430428478543, 11668.299902005738, 12449.688757780947, 12676.429560669792, 12780.552680656132, 18037.458876233733, 18112.45662630123, 18124.912505249726, 18212.08939553017, 18344.266229350942, 6602.0, 7685.730999414994, 7812.1016083150025, 7818.8827167592535, 7843.72546960865, 783.1620166421874, 794.5788731972063, 803.3974519110667, 803.5580430763174, 812.7968007998, 24675.62986555203, 25657.74342256591, 30036.937206748615, 30190.396192076474, 30396.240093997825, 928.3176865004217, 993.1161266472845, 1075.8493810866485, 1097.5993762276871, 1103.773726386105, 13079.41142648583, 15803.762943555856, 15817.130057846698, 16016.0, 16093.114878681785, 545673.0, 599511.0, 622053.0, 623740.0, 625320.0, 59769.402305976386, 59887.40112598818, 59935.10097348543, 59979.70010149921, 60098.39901600928, 1256.6292943581666, 1401.439424230316, 1440.7478691228994, 1466.413434626158, 1467.3910327214087, 27073.729262707122, 29369.0, 29578.260543486584, 29918.25204369908, 30583.0, 52586.73706631595, 52884.471155289495, 52961.735191323794, 52997.470025299255, 53073.0, 528703.0, 528792.0, 530722.3463882656, 530813.0, 531336.6866331287, 36529.07767378657, 41300.38049429201, 41519.0, 41788.58211417847, 42039.7898010508, 39571.81284561468, 46293.30560041535, 47719.76140119277, 48478.757606211744, 48960.510394895595, 34243.82878085594, 35956.46065309075, 38168.236635266585, 39652.448657188594, 41109.97225069397, 39056.0, 39294.0, 39301.60698392979, 40198.19603607853, 40478.79760601296, 37544.0, 37909.62090379061, 38614.0, 38637.2272554553, 38708.6129138705, 30864.537031944088, 34191.4871276936, 34702.13244668904, 34848.0, 34868.9539313821, 21623.783762162177, 22958.77041229633, 23105.422364441023, 25717.871410643576, 25974.425211842885], "value": 20498.059893233018, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [386.7640739149119, 388.35726872026805, 393.10568456760865, 397.7851959941632, 420.02763602260785, 12808.423620937057, 16651.66696666066, 19873.900630496846, 21155.0, 21243.787562124395, 17041.954965404137, 20467.897660511713, 21468.033938472767, 21614.459638509044, 21890.452738681543, 5719.771209151629, 5721.799737009205, 5838.591298609099, 5924.792632257871, 5929.229200203968, 4616.653750968674, 5913.379095195007, 5925.4963328117165, 5926.585139040269, 5931.940680593182, 4134.896627584304, 6480.805575832733, 6548.869022619545, 6596.505262105337, 6626.602403855784, 6623.072769812206, 6648.4681225501845, 6658.201015878101, 7301.780946571611, 7624.771256862303, 7288.744893928687, 7567.962160189219, 7586.8482630347635, 7605.163432022473, 7621.504602200839, 28321.71678283232, 28457.288567785763, 28693.426131477252, 28724.0, 28975.710242897723, 3276.6231883333485, 3361.1429085583018, 3390.2541440883215, 3400.4219282721997, 3504.316658251642, 14859.0, 16140.273687684083, 17114.31542738289, 17358.56603584908, 17505.0, 1692.272322901152, 1934.216642259887, 1967.842572594189, 2027.482991837087, 2040.4819707779045, 101213.4939325306, 103685.48157259241, 123367.38316308451, 133401.0, 143182.0, 22239.0, 23040.65439018416, 23080.307590772303, 23163.76836231616, 23309.06763729466, 39348.016299592164, 39520.0, 39972.00069998273, 39987.60012399897, 40087.0, 10788.78422431547, 10943.343399396063, 11108.222424430212, 11132.220744547965, 11323.0, 7548.433867459962, 8879.822403551892, 8897.223266768191, 9031.36780425364, 9085.727428177166, 10730.570777168981, 11064.336139831636, 11075.778484430266, 11178.888211117948, 11195.4962026709, 3657.5062366580705, 4203.180379825966, 4254.553271906467, 4327.290720165517, 4343.761093139908, 988.4711679251566, 999.93000489965, 1006.4062203300089, 1013.0, 1049.8110340138696, 20628.58742825135, 20954.161833526487, 21120.78879211188, 21132.049057792436, 21241.575168496543, 1041.4324193314662, 1043.7129789307849, 1075.419273592267, 1076.9138468922467, 1091.4051841746268, 8763.780905477413, 10841.16057162478, 10959.888963224048, 10966.890331096747, 10998.06516446111, 679015.6049219723, 694618.0, 744626.0, 749915.0, 756990.0, 93782.0, 97734.0, 104664.83379155211, 144056.27971860283, 146662.0, 833.737372727601, 873.8645509945842, 943.7593413679468, 1007.2948935745007, 1014.4674046125696, 17217.39739109125, 17909.10454477245, 18637.627247455246, 18664.720029199845, 18857.717134242725, 57527.71236143792, 58533.70733146307, 58687.70656146692, 59223.0, 59342.703286483295, 60315.49211269753, 75918.62040689982, 81919.59040204762, 82320.17679823124, 82790.0, 8133.552654604054, 8280.0, 8442.071372148936, 8504.574771261288, 8557.572121393781, 10858.782824343627, 11412.543498259976, 12823.422945967268, 13076.215427074407, 13260.0, 10833.349999000086, 11024.228304018641, 11086.556537738401, 11188.776224475301, 11257.635896461834, 10851.0, 10994.28537145083, 10995.120390368591, 11318.547258109913, 11536.192466527262, 11039.39283339424, 11133.777324453627, 11378.829317560409, 11468.0, 11494.080473562262, 7042.577445353295, 7885.422915416825, 8429.199226073513, 8434.746957591282, 8574.142585741376, 8122.1065682776025, 10321.74195645115, 10566.947165264124, 11253.212275140662, 11283.266587671775], "value": 11112.62139651894, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1243.9004879609631, 1359.3475131936673, 1449.3333066789276, 1469.5077149155027, 1483.399223314558, 7566.167721550625, 7742.535447873118, 7975.003124609424, 8395.664173433062, 8402.033766116898, 8002.799930001753, 8288.83422331553, 8306.252437280644, 8308.75073747787, 8326.167383261685, 1770.9026003569863, 2275.4766403727112, 2300.7469178390365, 2301.8849057547054, 2302.182725132579, 2032.634125857345, 2070.9585808283823, 2114.048678094856, 2117.1637203304685, 2119.576084783042, 2053.1992522916075, 2206.106526856626, 2275.4311422144465, 2277.339571524256, 2294.380517260339, 2247.381885042783, 2256.724950403017, 2266.1162146762777, 2269.5914735347715, 2273.26119011322, 2231.1744654477784, 2275.7724227577273, 2282.7945484906436, 2299.4366380236947, 2311.3643747969277, 8220.219079187467, 8310.501369917823, 8348.540830254333, 8360.45657032291, 8363.456375335583, 4986.975065124688, 5093.057784309877, 5128.615353848439, 5131.384233891919, 5149.407818100929, 3453.2230248194205, 3477.7391695622678, 3478.704310133616, 3485.0, 3559.75081744278, 1075.381655548063, 1094.7865166292577, 1205.8191271309286, 1225.650689553473, 1236.8620868800724, 9243.537823108818, 10138.39169649816, 10610.363378197333, 10714.142868570496, 10719.249652524326, 4302.247106756305, 4527.456705195397, 4579.519150489217, 4593.035462552902, 4604.1252162089095, 9251.121143491358, 9350.345475816763, 9361.531923403938, 9371.625134994523, 9402.576884040234, 5101.183810590288, 5128.333316668809, 5167.844964651066, 5169.638125331191, 5172.5344718975475, 3612.9819350903076, 3814.675752561008, 4049.817758200888, 4401.383806267125, 4751.786169622375, 3021.9848900755796, 3649.5073165122367, 3669.4495825625836, 3680.6319368063514, 3698.130939229279, 1072.4798472740715, 1135.9261647992853, 1140.9942950285194, 1141.7725944609579, 1147.1109889835445, 824.8040341504842, 927.5547737086101, 942.9952850235842, 943.5801068524578, 959.3859929645033, 4852.344933434012, 5366.195070739421, 5610.13042978339, 5620.353659329147, 5831.9708401458565, 888.6758729493009, 1007.3401921741271, 1015.1827778638267, 1042.1246153231323, 1046.884842667306, 3641.4719865619336, 3929.135590170158, 3942.3100957332927, 3980.283548961157, 3993.306191772657, 9577.808443831223, 9733.610655573697, 9910.058544438267, 9910.653127140515, 9933.50332483373, 9630.181434577999, 9832.557534910946, 9871.851922221173, 9909.900900991042, 9916.851247231298, 7536.886946695804, 7632.274933881273, 7722.6910923563555, 7748.4188685848785, 7828.295453409107, 4835.58897493717, 5446.455354464521, 5472.452754724496, 5785.334686511105, 5820.388859169726, 9017.50403727775, 9145.496997665192, 9175.541222938693, 9202.723918282461, 9215.354925155174, 8140.87788683182, 9086.318526110435, 9089.77275568116, 9193.218576421079, 9223.907760922304, 5994.28068631767, 6975.965120174366, 7072.929270707227, 7074.964625176841, 7080.752173673896, 6932.722691092502, 7597.734079307198, 7647.273509016634, 7653.617319134132, 7664.386849051951, 6400.455961243347, 7007.614581198083, 7068.787936361918, 7110.822229444305, 7121.323474269936, 5616.803411880564, 7041.5422997505, 7049.894251586128, 7106.395956343768, 7143.964280178566, 6285.685715714105, 6389.041643753289, 6397.0, 6406.807795766134, 6408.263049749338, 4366.628836548802, 4913.091078150554, 4960.23116416956, 5077.391360663276, 5213.739313034257, 4602.769861506844, 4833.492483289204, 5191.117510023229, 5205.557527610195, 5285.603579731459], "value": 4322.330161072236, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [358.7452908435011, 362.2627952117438, 364.0334910811795, 365.9652333028363, 375.45746396457116, 4497.707649002812, 5020.046191223665, 5037.841296501807, 5411.215373770806, 5602.551795856332, 4927.260910863372, 5094.949050509494, 5350.839474815762, 5421.918671219935, 5496.697681627509, 3645.671889529948, 3816.9618303817024, 4165.354370072643, 4173.749575025508, 4194.727342722713, 3481.495183198435, 4007.5773100549322, 4238.347044652588, 4238.512571054337, 4270.487541495025, 3646.9635303647024, 4133.937990930139, 4199.307114326132, 4206.095689426773, 4225.6830737694645, 3701.870434534792, 4076.4496792932873, 4193.874183774492, 4225.83096676131, 4238.4490016298, 2986.7610591152657, 3231.5637388952428, 3268.8038717677014, 3336.349411864687, 3551.3962626353323, 4306.935395969063, 5023.748812559394, 5228.398734145584, 5291.761870715827, 5308.230306605559, 3839.865604703822, 4877.707337559757, 4891.9209336032045, 4912.582430493377, 4915.188993816016, 5012.168125009358, 5419.701916394597, 5438.91841622376, 5715.714214289311, 5719.742611582489, 4102.384642303648, 4126.91746165078, 4133.42132101503, 4138.627523522868, 4172.916541669149, 5037.647364684473, 5330.880515091874, 5788.5369170466265, 6016.909746353765, 6087.939120608781, 3521.8591256349973, 3915.530136383652, 3919.333713268751, 4098.631123198927, 4201.894952626209, 4244.0477380404745, 4274.337477690963, 4295.78521073945, 4312.805923733439, 4345.500267469281, 4725.527447255316, 4952.380952380952, 4973.52751488608, 4994.675346102565, 4996.7001979881325, 4681.769578990486, 4694.929576056362, 5781.566382521328, 5794.942050579524, 5821.563382746311, 1346.2730125731996, 1370.8964283751477, 1373.9038267321391, 1375.21612680771, 1395.6510872281929, 889.8298737160613, 923.8774888510492, 956.4022485946283, 990.3612170150357, 992.5583115513527, 4690.296455531698, 4884.511548845087, 5188.688678679291, 5229.320188375563, 5237.528622424, 4985.903101317705, 5053.747312634354, 5100.659068310263, 5266.130523664121, 5731.168980497888, 4614.72354628211, 4623.745693986863, 5052.595792336604, 5138.717370544581, 5230.450802665741, 2498.925032249035, 2898.6231789867184, 2978.523436250233, 2992.027591032923, 3026.2888221267986, 4949.406071271398, 5271.497623177453, 5925.34821169671, 5929.242902050481, 6027.758889644365, 5437.646552974044, 5559.833205003775, 5821.854453638693, 5830.475257226785, 5834.387389324144, 5372.785088596413, 5842.0, 5982.3976570191235, 5989.562504998769, 6038.486728628116, 4569.771511424349, 4782.536993448446, 5271.4464981176425, 5290.206469029677, 5666.2350582670815, 4981.775820088177, 5212.582993360446, 5455.9454405455435, 5562.332520097614, 5636.126400407943, 5335.27827872328, 5672.631278966854, 5714.857028594215, 6013.128096425906, 6115.156452249726, 3278.524763750432, 4043.575424580377, 4134.607212314825, 4272.401863739017, 4282.886449523084, 5857.502112320501, 5982.46157845796, 5987.461128498456, 5995.310539288038, 6004.279486461653, 5405.945940540544, 5763.538916886555, 5799.4921320457825, 5872.911906321494, 5881.441263080001, 5108.872278193075, 5438.830651409966, 5537.9169312459535, 5749.310082790092, 5802.970985145047, 4302.870913872589, 5106.8254301510115, 5338.3060202173465, 5672.31932168126, 5694.715264236855, 5267.683938963674, 5706.4864162225595, 5756.079027355603, 5822.592418530662, 5844.26946631671, 5143.3996202657545, 5221.582273418041, 5672.886542269214, 5704.572157088152, 5764.394738552392], "value": 4169.083943040254, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [722.8290169924719, 724.6195747232703, 795.7652492514708, 832.2551316571664, 837.1544739812791, 15345.079295242293, 15884.841151588495, 16006.83993160068, 16054.03675779454, 16082.195890205472, 14701.0, 14833.480828171016, 14839.183844888526, 14877.479288224915, 14894.851051489482, 685.8936864785966, 713.7680253917472, 862.5256109139948, 871.4771137317606, 891.1445012787732, 881.2069137775989, 891.5141248019812, 891.9464832110061, 893.1738142218454, 893.7765558610347, 946.4699768129835, 959.7696552827306, 960.2989817433278, 962.3936919740554, 962.4995002598645, 932.1889955738483, 952.2524818017823, 955.6655170690237, 955.8088382323493, 956.4882787708577, 713.8286811165335, 713.9678714457862, 716.2300526933515, 716.6595866963203, 718.314010120339, 14364.64088397788, 14467.0, 14978.850211497855, 15163.09021458705, 15198.46805361807, 14262.57212283633, 14431.0, 14508.419663213564, 14635.341409636485, 14753.114813111248, 11413.315201087962, 11582.362970036645, 11842.46708898093, 11898.10764192689, 11983.580574679845, 4501.954980450187, 4532.818687252506, 4543.863684089482, 4734.4081989751285, 4755.42934847817, 107854.92145078606, 109162.90837091686, 109783.35324970132, 110058.89941100567, 111419.44290278497, 10839.295445795999, 10863.67408977732, 10905.83641245382, 11008.119350451945, 11016.228863979604, 15982.280797364147, 17231.22459489326, 17534.561635959202, 20999.685004724943, 21395.78604213969, 7542.057242844645, 7559.811004724816, 7584.5070070445245, 7665.386769058462, 7829.982102326661, 3649.908752281214, 4096.832402765198, 4144.171165766858, 4231.11146659205, 4262.914741705211, 3383.5601371821504, 3485.4946032825605, 3735.458358538051, 3918.5101862267215, 3924.293627147141, 653.8463461086641, 691.1050190003856, 708.3518580498888, 721.3291638775988, 724.8948902409227, 669.6283562622791, 674.5514233035075, 690.8065741592362, 707.6426404665611, 726.4878260826105, 9617.663381781604, 9842.360246584092, 10508.159347252202, 10564.683059508227, 10805.675829725122, 659.1918427498794, 688.0951548713426, 693.4244577001077, 697.8394969157122, 700.2402393403214, 4846.539578740014, 5117.488251174927, 5127.897442051213, 5133.0, 5154.175331946871, 92878.0, 93984.53007735054, 104063.95936040542, 104106.0, 106933.0, 68369.63260734758, 71199.93200102003, 73813.0, 93165.5341723287, 95888.0, 69883.0, 69983.0, 79556.0, 82023.17976820275, 84029.0, 9246.4914429707, 10739.624113156002, 11103.333799972028, 11229.391339767575, 11367.374794386058, 43760.56239437565, 44674.77662611666, 51058.489415105374, 51244.74377628088, 53003.0, 58723.0, 59011.81976360363, 60001.0, 64819.70360592856, 67331.66334168494, 13072.411741471466, 13424.93287533556, 13577.253251070892, 13627.727445451234, 13918.443262269397, 14720.852791471949, 14982.625434363792, 15161.393544258106, 15325.46360877364, 15470.767938480707, 11295.491702873225, 13703.314834258446, 13982.720345593234, 14342.426302947766, 14409.639759006108, 13793.24137172425, 13837.0, 14217.0, 14585.854141458874, 14668.55994320172, 13677.931610341884, 13687.589372318846, 13761.38073786702, 13765.724685506435, 13782.448702051806, 9322.440653560809, 11352.716182095513, 11437.59968401102, 11564.421778910853, 11884.821727674265, 9884.159846413135, 11246.972944212837, 11353.37556434371, 11372.147088968526, 11385.544578216779], "value": 7636.257280679486, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1660.9, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [108.0, 115.0, 117.0, 121.0, 116.0, 117.0, 136.0, 108.0, 176.0, 170.0], "value": 128.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [141.0, 106.0, 131.0, 135.0, 117.0, 142.0, 141.0, 142.0, 130.0, 108.0], "value": 129.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [281.0, 257.0, 199.0, 195.0, 253.0, 271.0, 196.0, 200.0, 256.0, 216.0], "value": 232.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 59.0, 60.0, 85.0, 60.0, 61.0, 52.0, 60.0, 87.0, 82.0], "value": 68.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 71.0, 86.0, 71.0, 72.0, 84.0, 116.0, 71.0, 71.0, 85.0], "value": 81.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 88.0, 80.0, 126.0, 138.0, 89.0, 111.0, 113.0, 88.0, 132.0], "value": 105.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 136.0, 96.0, 107.0, 153.0, 113.0, 105.0, 151.0, 112.0, 105.0], "value": 122.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 143.0, 99.0, 149.0, 86.0, 148.0, 142.0, 113.0, 94.0, 135.0], "value": 118.4, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 63.0, 62.0, 63.0, 64.0, 64.0, 70.0, 87.0, 99.0, 95.0], "value": 72.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 65.0, 75.0, 75.0, 74.0, 69.0, 71.0, 74.0, 72.0, 72.0], "value": 72.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 93.0, 91.0, 99.0, 97.0, 90.0, 94.0, 126.0, 79.0, 94.0], "value": 95.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [154.0, 157.0, 135.0, 141.0, 144.0, 160.0, 148.0, 169.0, 225.0, 148.0], "value": 158.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [172.0, 240.0, 245.0, 182.0, 179.0, 251.0, 215.0, 175.0, 177.0, 180.0], "value": 201.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 71.0, 73.0, 72.0, 67.0, 72.0, 85.0, 89.0, 79.0, 68.0], "value": 74.4, "unit": "score"}]}]} 04:59:58 INFO - Return code: 0 04:59:58 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 04:59:58 ERROR - # TBPL SUCCESS # 04:59:58 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 04:59:58 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 04:59:58 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 04:59:58 INFO - 2017-01-10 04:59:58.136 screenresolution[1665:10616] starting screenresolution argv=screenresolution get 04:59:58 INFO - 2017-01-10 04:59:58.139 screenresolution[1665:10616] Display 0: 1600x1200x32@60 04:59:58 INFO - 2017-01-10 04:59:58.145 screenresolution[1666:10619] starting screenresolution argv=screenresolution list 04:59:58 INFO - Available Modes on Display 0 04:59:58 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 04:59:58 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 04:59:58 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 04:59:58 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 04:59:58 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 04:59:58 INFO - Intel Iris: 04:59:58 INFO - Chipset Model: Intel Iris 04:59:58 INFO - Type: GPU 04:59:58 INFO - Bus: Built-In 04:59:58 INFO - VRAM (Dynamic, Max): 1536 MB 04:59:58 INFO - Vendor: Intel (0x8086) 04:59:58 INFO - Device ID: 0x0a2e 04:59:58 INFO - Revision ID: 0x0009 04:59:58 INFO - Displays: 04:59:58 INFO - Display: 04:59:58 INFO - Resolution: 1600 x 1200 @ 60 Hz 04:59:58 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 04:59:58 INFO - Display Serial Number: 1600x1200 60 04:59:58 INFO - Main Display: Yes 04:59:58 INFO - Mirror: Off 04:59:58 INFO - Online: Yes 04:59:58 INFO - Rotation: Supported 04:59:58 INFO - Return code: 0 04:59:58 INFO - Running post-action listener: _resource_record_post_action 04:59:58 INFO - [mozharness: 2017-01-10 12:59:58.381940Z] Finished run-tests step (success) 04:59:58 INFO - Running post-run listener: _resource_record_post_run 04:59:58 INFO - Total resource usage - Wall time: 1201s; CPU: 55.0%; Read bytes: 38742528; Write bytes: 554736128; Read time: 248; Write time: 1727 04:59:58 INFO - TinderboxPrint: CPU usage
54.6% 04:59:58 INFO - TinderboxPrint: I/O read bytes / time
38,742,528 / 248 04:59:58 INFO - TinderboxPrint: I/O write bytes / time
554,736,128 / 1,727 04:59:58 INFO - TinderboxPrint: CPU idle
2,180.3 (45.4%) 04:59:58 INFO - TinderboxPrint: CPU system
970.9 (20.2%) 04:59:58 INFO - TinderboxPrint: CPU user
1,654.5 (34.4%) 04:59:58 INFO - TinderboxPrint: Swap in / out
288,563,200 / 0 04:59:58 INFO - install - Wall time: 22s; CPU: 54.0%; Read bytes: 250917376; Write bytes: 250621952; Read time: 18281; Write time: 543 04:59:58 INFO - run-tests - Wall time: 1180s; CPU: 55.0%; Read bytes: 35371520; Write bytes: 302537216; Read time: 225; Write time: 1178 04:59:58 INFO - Running post-run listener: _upload_blobber_files 04:59:58 INFO - Blob upload gear active. 04:59:58 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 04:59:58 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 04:59:58 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'] 04:59:58 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 04:59:59 INFO - (blobuploader) - INFO - Open directory for files ... 04:59:59 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 04:59:59 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:59:59 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:59:59 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 04:59:59 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:59:59 INFO - (blobuploader) - INFO - Done attempting. 04:59:59 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 04:59:59 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:59:59 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:00:00 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 05:00:00 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:00:00 INFO - (blobuploader) - INFO - Done attempting. 05:00:00 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 05:00:00 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:00:00 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:00:02 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 05:00:02 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:00:02 INFO - (blobuploader) - INFO - Done attempting. 05:00:02 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 05:00:02 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:00:02 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:00:05 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 05:00:05 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:00:05 INFO - (blobuploader) - INFO - Done attempting. 05:00:05 INFO - (blobuploader) - INFO - Iteration through files over. 05:00:05 INFO - Return code: 0 05:00:05 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 05:00:05 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 05:00:05 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/df30a87aac523d22b4784fd7177455e337548b8e5bc691175fc48ad9be2b33852a0614d6929d4fd4dfc38bdd44fadcbc2c6f75e371a6ef3dbfaee5e7528aa514", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/aa61342bbcc32b5d2727d82ed4cde4d8433ffefd0832bb01bcc31ba864433b96a4f62a11ba620fc3e87666741ae288b1d248314629962428cd0fd28b5ca815b5", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b97d4d1ebb12e23d3863304e44394a35e217e052e2921b04a3c963751633028ef5401ba481d8ad61f8da1d82c59d616ea5f34f6bccd79b39d663b709c5fc1dca", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3f92c51ab4488de45ef41c359deaab70bfabd83c0f29d002c623e06467ff4fe659e3b8a48af443572b1a920f669c3001669182b92f394cc411350a357250b257"} 05:00:05 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 05:00:05 INFO - Writing to file /builds/slave/test/properties/blobber_files 05:00:05 INFO - Contents: 05:00:05 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/df30a87aac523d22b4784fd7177455e337548b8e5bc691175fc48ad9be2b33852a0614d6929d4fd4dfc38bdd44fadcbc2c6f75e371a6ef3dbfaee5e7528aa514", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/aa61342bbcc32b5d2727d82ed4cde4d8433ffefd0832bb01bcc31ba864433b96a4f62a11ba620fc3e87666741ae288b1d248314629962428cd0fd28b5ca815b5", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b97d4d1ebb12e23d3863304e44394a35e217e052e2921b04a3c963751633028ef5401ba481d8ad61f8da1d82c59d616ea5f34f6bccd79b39d663b709c5fc1dca", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3f92c51ab4488de45ef41c359deaab70bfabd83c0f29d002c623e06467ff4fe659e3b8a48af443572b1a920f669c3001669182b92f394cc411350a357250b257"} 05:00:05 INFO - Running post-run listener: copy_logs_to_upload_dir 05:00:05 INFO - Copying logs to upload dir... 05:00:05 INFO - mkdir: /builds/slave/test/build/upload/logs 05:00:05 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1266.014511 ========= master_lag: 0.11 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 6 secs) (at 2017-01-10 05:00:06.025208) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-10 05:00:06.030630) ========= 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.KwuVbZbFgu/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.Hm0cOpBB0I/Listeners TMPDIR=/var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/df30a87aac523d22b4784fd7177455e337548b8e5bc691175fc48ad9be2b33852a0614d6929d4fd4dfc38bdd44fadcbc2c6f75e371a6ef3dbfaee5e7528aa514", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/aa61342bbcc32b5d2727d82ed4cde4d8433ffefd0832bb01bcc31ba864433b96a4f62a11ba620fc3e87666741ae288b1d248314629962428cd0fd28b5ca815b5", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b97d4d1ebb12e23d3863304e44394a35e217e052e2921b04a3c963751633028ef5401ba481d8ad61f8da1d82c59d616ea5f34f6bccd79b39d663b709c5fc1dca", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3f92c51ab4488de45ef41c359deaab70bfabd83c0f29d002c623e06467ff4fe659e3b8a48af443572b1a920f669c3001669182b92f394cc411350a357250b257"} build_url:https://queue.taskcluster.net/v1/task/csOqVboeQXaSs_1-ycqtmw/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011170 build_url: 'https://queue.taskcluster.net/v1/task/csOqVboeQXaSs_1-ycqtmw/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/df30a87aac523d22b4784fd7177455e337548b8e5bc691175fc48ad9be2b33852a0614d6929d4fd4dfc38bdd44fadcbc2c6f75e371a6ef3dbfaee5e7528aa514", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/aa61342bbcc32b5d2727d82ed4cde4d8433ffefd0832bb01bcc31ba864433b96a4f62a11ba620fc3e87666741ae288b1d248314629962428cd0fd28b5ca815b5", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b97d4d1ebb12e23d3863304e44394a35e217e052e2921b04a3c963751633028ef5401ba481d8ad61f8da1d82c59d616ea5f34f6bccd79b39d663b709c5fc1dca", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3f92c51ab4488de45ef41c359deaab70bfabd83c0f29d002c623e06467ff4fe659e3b8a48af443572b1a920f669c3001669182b92f394cc411350a357250b257"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-10 05:00:06.071990) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 3 secs) (at 2017-01-10 05:00:06.072312) ========= 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.KwuVbZbFgu/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.Hm0cOpBB0I/Listeners TMPDIR=/var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/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.005152 ========= master_lag: 3.26 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 3 secs) (at 2017-01-10 05:00:09.335365) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-10 05:00:09.335768) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-10 05:00:09.341248) ========= ========= Total master_lag: 3.47 =========