builder: mozilla-central_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0083 starttime: 1482466195.41 results: success (0) buildid: 20161222182453 builduid: 335f367e40164d33934720f884c1448b revision: 82574d098a94527588b6be7d800785ccb11df961 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-12-22 20:09:55.413943) ========= master: http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-12-22 20:09:55.414418) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-22 20:09:55.414700) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-22 20:09:55.729508) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-12-22 20:09:55.729814) ========= 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.DfbnYBb4Lz/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.qUlRm63fjb/Listeners TMPDIR=/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-12-22 20:09:55-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 65.6M=0s 2016-12-22 20:09:55 (65.6 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.127304 ========= master_lag: 2.35 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-12-22 20:09:58.211810) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 1 secs) (at 2016-12-22 20:09:58.212168) ========= 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.DfbnYBb4Lz/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.qUlRm63fjb/Listeners TMPDIR=/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/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.027248 ========= master_lag: 1.26 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 1 secs) (at 2016-12-22 20:09:59.501087) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-22 20:09:59.501536) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 82574d098a94527588b6be7d800785ccb11df961 --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 82574d098a94527588b6be7d800785ccb11df961 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.DfbnYBb4Lz/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.qUlRm63fjb/Listeners TMPDIR=/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-12-22 20:09:59,596 truncating revision to first 12 chars 2016-12-22 20:09:59,597 Setting DEBUG logging. 2016-12-22 20:09:59,597 attempt 1/10 2016-12-22 20:09:59,597 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/82574d098a94?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-12-22 20:10:00,285 unpacking tar archive at: mozilla-central-82574d098a94/testing/mozharness/ program finished with exit code 0 elapsedTime=0.962999 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-22 20:10:00.488637) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-22 20:10:00.488957) ========= script_repo_revision: 82574d098a94527588b6be7d800785ccb11df961 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-22 20:10:00.492388) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-22 20:10:00.492669) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-22 20:10:00.512596) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 8 secs) (at 2016-12-22 20:10:00.512911) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.DfbnYBb4Lz/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.qUlRm63fjb/Listeners TMPDIR=/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/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 20:10:00 INFO - MultiFileLogger online at 20161222 20:10:00 in /builds/slave/test 20:10:00 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 20:10:00 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 20:10:00 INFO - {'append_to_log': False, 20:10:00 INFO - 'base_work_dir': '/builds/slave/test', 20:10:00 INFO - 'blob_upload_branch': 'Firefox', 20:10:00 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 20:10:00 INFO - 'branch': 'Firefox', 20:10:00 INFO - 'buildbot_json_path': 'buildprops.json', 20:10:00 INFO - 'config_files': ('talos/mac_config.py',), 20:10:00 INFO - 'default_actions': ('clobber', 20:10:00 INFO - 'read-buildbot-config', 20:10:00 INFO - 'download-and-extract', 20:10:00 INFO - 'populate-webroot', 20:10:00 INFO - 'create-virtualenv', 20:10:00 INFO - 'install', 20:10:00 INFO - 'run-tests'), 20:10:00 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 20:10:00 INFO - 'download_minidump_stackwalk': True, 20:10:00 INFO - 'download_symbols': 'ondemand', 20:10:00 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 20:10:00 INFO - 'tooltool.py': '/tools/tooltool.py', 20:10:00 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 20:10:00 INFO - '/tools/misc-python/virtualenv.py')}, 20:10:00 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 20:10:00 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 20:10:00 INFO - 'installer_path': 'installer.exe', 20:10:00 INFO - 'log_level': 'info', 20:10:00 INFO - 'log_name': 'talos', 20:10:00 INFO - 'log_to_console': True, 20:10:00 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 20:10:00 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 20:10:00 INFO - 'opt_config_files': (), 20:10:00 INFO - 'pip_index': False, 20:10:00 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 20:10:00 INFO - 'cmd': ('bash', 20:10:00 INFO - '-c', 20:10:00 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 20:10:00 INFO - 'enabled': True, 20:10:00 INFO - 'halt_on_failure': False, 20:10:00 INFO - 'name': 'check_screen_resolution'},), 20:10:00 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 20:10:00 INFO - 'cmd': ('bash', 20:10:00 INFO - '-c', 20:10:00 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 20:10:00 INFO - 'enabled': True, 20:10:00 INFO - 'halt_on_failure': False, 20:10:00 INFO - 'name': 'check_screen_resolution'},), 20:10:00 INFO - 'run_cmd_checks_enabled': True, 20:10:00 INFO - 'sps_profile': False, 20:10:00 INFO - 'sps_profile_interval': 0, 20:10:00 INFO - 'suite': 'dromaeojs', 20:10:00 INFO - 'system_bits': '32', 20:10:00 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 20:10:00 INFO - 'title': 't-yosemite-r7-0083', 20:10:00 INFO - 'tooltool_cache': '/builds/tooltool_cache', 20:10:00 INFO - 'use_talos_json': True, 20:10:00 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 20:10:00 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 20:10:00 INFO - 'work_dir': 'build'} 20:10:00 INFO - [mozharness: 2016-12-23 04:10:00.721781Z] Running clobber step. 20:10:00 INFO - Running pre-action listener: _resource_record_pre_action 20:10:00 INFO - Running main action method: clobber 20:10:00 INFO - rmtree: /builds/slave/test/build 20:10:00 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 20:10:03 INFO - Running post-action listener: _resource_record_post_action 20:10:03 INFO - [mozharness: 2016-12-23 04:10:03.608971Z] Finished clobber step (success) 20:10:03 INFO - [mozharness: 2016-12-23 04:10:03.609104Z] Running read-buildbot-config step. 20:10:03 INFO - Running pre-action listener: _resource_record_pre_action 20:10:03 INFO - Running main action method: read_buildbot_config 20:10:03 INFO - Using buildbot properties: 20:10:03 INFO - { 20:10:03 INFO - "project": "", 20:10:03 INFO - "product": "firefox", 20:10:03 INFO - "script_repo_revision": "production", 20:10:03 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs", 20:10:03 INFO - "repository": "", 20:10:03 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs", 20:10:03 INFO - "buildid": "20161222182453", 20:10:03 INFO - "pgo_build": "False", 20:10:03 INFO - "basedir": "/builds/slave/test", 20:10:03 INFO - "buildnumber": 157, 20:10:03 INFO - "slavename": "t-yosemite-r7-0083", 20:10:03 INFO - "master": "http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/", 20:10:03 INFO - "platform": "yosemite_r7", 20:10:03 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 20:10:03 INFO - "branch": "mozilla-central", 20:10:03 INFO - "repo_path": "mozilla-central", 20:10:03 INFO - "revision": "82574d098a94527588b6be7d800785ccb11df961", 20:10:03 INFO - "stage_platform": "macosx64", 20:10:03 INFO - "builduid": "335f367e40164d33934720f884c1448b", 20:10:03 INFO - "slavebuilddir": "test" 20:10:03 INFO - } 20:10:03 INFO - Found installer url https://queue.taskcluster.net/v1/task/Y6GVFfccRXWga3ceP1u3GQ/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg. 20:10:03 INFO - Running post-action listener: _resource_record_post_action 20:10:03 INFO - [mozharness: 2016-12-23 04:10:03.612257Z] Finished read-buildbot-config step (success) 20:10:03 INFO - [mozharness: 2016-12-23 04:10:03.612362Z] Running download-and-extract step. 20:10:03 INFO - Running pre-action listener: _resource_record_pre_action 20:10:03 INFO - Running main action method: download_and_extract 20:10:03 INFO - mkdir: /builds/slave/test/build/tests 20:10:03 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')]} 20:10:03 INFO - trying https://queue.taskcluster.net/v1/task/Y6GVFfccRXWga3ceP1u3GQ/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json 20:10:03 INFO - Downloading https://queue.taskcluster.net/v1/task/Y6GVFfccRXWga3ceP1u3GQ/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 20:10:03 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Y6GVFfccRXWga3ceP1u3GQ/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 20:10:04 INFO - Downloaded 1199 bytes. 20:10:04 INFO - Reading from file /builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json 20:10:04 INFO - Using the following test package requirements: 20:10:04 INFO - {u'common': [u'firefox-53.0a1.en-US.mac.common.tests.zip'], 20:10:04 INFO - u'cppunittest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 20:10:04 INFO - u'firefox-53.0a1.en-US.mac.cppunittest.tests.zip'], 20:10:04 INFO - u'gtest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 20:10:04 INFO - u'firefox-53.0a1.en-US.mac.gtest.tests.zip'], 20:10:04 INFO - u'jittest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 20:10:04 INFO - u'jsshell-mac.zip'], 20:10:04 INFO - u'mochitest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 20:10:04 INFO - u'firefox-53.0a1.en-US.mac.mochitest.tests.zip'], 20:10:04 INFO - u'mozbase': [u'firefox-53.0a1.en-US.mac.common.tests.zip'], 20:10:04 INFO - u'reftest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 20:10:04 INFO - u'firefox-53.0a1.en-US.mac.reftest.tests.zip'], 20:10:04 INFO - u'talos': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 20:10:04 INFO - u'firefox-53.0a1.en-US.mac.talos.tests.zip'], 20:10:04 INFO - u'web-platform': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 20:10:04 INFO - u'firefox-53.0a1.en-US.mac.web-platform.tests.zip'], 20:10:04 INFO - u'xpcshell': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 20:10:04 INFO - u'firefox-53.0a1.en-US.mac.xpcshell.tests.zip']} 20:10:04 INFO - Downloading packages: [u'firefox-53.0a1.en-US.mac.common.tests.zip'] for test suite category: common 20:10:04 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Y6GVFfccRXWga3ceP1u3GQ/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip 20:10:04 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Y6GVFfccRXWga3ceP1u3GQ/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip'}, attempt #1 20:10:04 INFO - Fetch https://queue.taskcluster.net/v1/task/Y6GVFfccRXWga3ceP1u3GQ/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip into memory 20:10:05 INFO - Expected file size: 16289323 20:10:05 INFO - Obtained file size: 16289323 20:10:12 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 20:10:12 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Y6GVFfccRXWga3ceP1u3GQ/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip 20:10:12 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Y6GVFfccRXWga3ceP1u3GQ/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip'}, attempt #1 20:10:12 INFO - Fetch https://queue.taskcluster.net/v1/task/Y6GVFfccRXWga3ceP1u3GQ/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip into memory 20:10:13 INFO - Expected file size: 16289323 20:10:13 INFO - Obtained file size: 16289323 20:10:19 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Y6GVFfccRXWga3ceP1u3GQ/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip 20:10:19 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Y6GVFfccRXWga3ceP1u3GQ/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip'}, attempt #1 20:10:19 INFO - Fetch https://queue.taskcluster.net/v1/task/Y6GVFfccRXWga3ceP1u3GQ/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip into memory 20:10:20 INFO - Expected file size: 14409776 20:10:20 INFO - Obtained file size: 14409776 20:10:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:10:21 INFO - trying https://queue.taskcluster.net/v1/task/Y6GVFfccRXWga3ceP1u3GQ/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 20:10:21 INFO - Downloading https://queue.taskcluster.net/v1/task/Y6GVFfccRXWga3ceP1u3GQ/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg 20:10:21 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Y6GVFfccRXWga3ceP1u3GQ/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 20:10:23 INFO - Downloaded 69670011 bytes. 20:10:23 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Y6GVFfccRXWga3ceP1u3GQ/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 20:10:23 INFO - mkdir: /builds/slave/test/properties 20:10:23 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 20:10:23 INFO - Writing to file /builds/slave/test/properties/build_url 20:10:23 INFO - Contents: 20:10:23 INFO - build_url:https://queue.taskcluster.net/v1/task/Y6GVFfccRXWga3ceP1u3GQ/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 20:10:23 INFO - Running post-action listener: _resource_record_post_action 20:10:23 INFO - Running post-action listener: set_extra_try_arguments 20:10:23 INFO - [mozharness: 2016-12-23 04:10:23.860682Z] Finished download-and-extract step (success) 20:10:23 INFO - [mozharness: 2016-12-23 04:10:23.860832Z] Running populate-webroot step. 20:10:23 INFO - Running pre-action listener: _resource_record_pre_action 20:10:23 INFO - Running main action method: populate_webroot 20:10:23 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 20:10:23 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 20:10:23 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 20:10:23 INFO - '--tppagecycles', 20:10:23 INFO - '7'], 20:10:23 INFO - 'tests': ['tsvgm']}}, 20:10:23 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 20:10:23 INFO - 'tests': ['tresize', 'tcanvasmark']}, 20:10:23 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 20:10:23 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 20:10:23 INFO - 'tests': ['dromaeo_css', 'kraken']}, 20:10:23 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 20:10:23 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 20:10:23 INFO - 'talos_options': ['--disable-e10s'], 20:10:23 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 20:10:23 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 20:10:23 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 20:10:23 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 20:10:23 INFO - 'talos_options': ['--disable-e10s'], 20:10:23 INFO - 'tests': ['damp', 'tps']}, 20:10:23 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 20:10:23 INFO - 'tests': ['damp', 'tps']}, 20:10:23 INFO - 'g3': {'talos_options': ['--disable-e10s'], 20:10:23 INFO - 'tests': ['dromaeo_dom']}, 20:10:23 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 20:10:23 INFO - 'g4': {'talos_options': ['--disable-e10s'], 20:10:23 INFO - 'tests': ['basic_compositor_video']}, 20:10:23 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 20:10:23 INFO - 'other': {'talos_options': ['--disable-e10s'], 20:10:23 INFO - 'tests': ['a11yr', 20:10:23 INFO - 'ts_paint', 20:10:23 INFO - 'tpaint', 20:10:23 INFO - 'sessionrestore', 20:10:23 INFO - 'sessionrestore_no_auto_restore', 20:10:23 INFO - 'tabpaint']}, 20:10:23 INFO - 'other-e10s': {'tests': ['a11yr', 20:10:23 INFO - 'ts_paint', 20:10:23 INFO - 'tpaint', 20:10:23 INFO - 'sessionrestore', 20:10:23 INFO - 'sessionrestore_no_auto_restore', 20:10:23 INFO - 'tabpaint']}, 20:10:23 INFO - 'other-e10s_l64': {'tests': ['a11yr', 20:10:23 INFO - 'ts_paint', 20:10:23 INFO - 'tpaint', 20:10:23 INFO - 'sessionrestore', 20:10:23 INFO - 'sessionrestore_no_auto_restore', 20:10:23 INFO - 'tabpaint']}, 20:10:23 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 20:10:23 INFO - 'ts_paint', 20:10:23 INFO - 'tpaint', 20:10:23 INFO - 'sessionrestore', 20:10:23 INFO - 'sessionrestore_no_auto_restore', 20:10:23 INFO - 'tabpaint']}, 20:10:23 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 20:10:23 INFO - 'tests': ['a11yr', 20:10:23 INFO - 'ts_paint', 20:10:23 INFO - 'tpaint', 20:10:23 INFO - 'sessionrestore', 20:10:23 INFO - 'sessionrestore_no_auto_restore', 20:10:23 INFO - 'tabpaint']}, 20:10:23 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 20:10:23 INFO - 'tests': ['a11yr', 20:10:23 INFO - 'ts_paint', 20:10:23 INFO - 'tpaint', 20:10:23 INFO - 'sessionrestore', 20:10:23 INFO - 'sessionrestore_no_auto_restore', 20:10:23 INFO - 'tabpaint']}, 20:10:23 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 20:10:23 INFO - 'tests': ['tsvgx', 20:10:23 INFO - 'tsvgr_opacity', 20:10:23 INFO - 'tart', 20:10:23 INFO - 'tscrollx', 20:10:23 INFO - 'cart', 20:10:23 INFO - 'tsvg_static']}, 20:10:23 INFO - 'svgr-e10s': {'tests': ['tsvgx', 20:10:23 INFO - 'tsvgr_opacity', 20:10:23 INFO - 'tart', 20:10:23 INFO - 'tscrollx', 20:10:23 INFO - 'cart', 20:10:23 INFO - 'tsvg_static']}, 20:10:23 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 20:10:23 INFO - 'talos_options': ['--disable-e10s'], 20:10:23 INFO - 'tests': ['tp5o']}, 20:10:23 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 20:10:23 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 20:10:23 INFO - 'talos_options': ['--disable-e10s', 20:10:23 INFO - '--xperf_path', 20:10:23 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 20:10:23 INFO - 'tests': ['tp5n']}, 20:10:23 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 20:10:23 INFO - 'talos_options': ['--xperf_path', 20:10:23 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 20:10:23 INFO - 'tests': ['tp5n']}}, 20:10:23 INFO - 'talos.zip': {'path': '', 20:10:23 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 20:10:23 INFO - Running post-action listener: _resource_record_post_action 20:10:23 INFO - [mozharness: 2016-12-23 04:10:23.879596Z] Finished populate-webroot step (success) 20:10:23 INFO - [mozharness: 2016-12-23 04:10:23.879684Z] Running create-virtualenv step. 20:10:23 INFO - Running pre-action listener: _resource_record_pre_action 20:10:23 INFO - Running main action method: create_virtualenv 20:10:23 INFO - Creating virtualenv /builds/slave/test/build/venv 20:10: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 20:10:23 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 20:10:24 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 20:10:24 INFO - Using real prefix '/tools/python27' 20:10:24 INFO - New python executable in /builds/slave/test/build/venv/bin/python 20:10:24 INFO - Installing distribute.............................................................................................................................................................................................done. 20:10:27 INFO - Installing pip.................done. 20:10:27 INFO - Return code: 0 20:10:27 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 20:10:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 20:10:27 INFO - Reading from file tmpfile_stdout 20:10:27 INFO - Output received: 20:10: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) 20:10:27 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 20:10: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')]} 20:10:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:10:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:10: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')]} 20:10:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:10:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:10: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 0x10c77eb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c781768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8792db7d40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c55cd30>, '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 0x10c483db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c551be8>, '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.DfbnYBb4Lz/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.qUlRm63fjb/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/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 20:10: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 20:10: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 20:10:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.DfbnYBb4Lz/Render', 20:10:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:10:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:10:27 INFO - 'HOME': '/Users/cltbld', 20:10:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:10:27 INFO - 'LOGNAME': 'cltbld', 20:10:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:10:27 INFO - 'MOZ_NO_REMOTE': '1', 20:10:27 INFO - 'NO_EM_RESTART': '1', 20:10:27 INFO - 'PAGER': '/bin/cat', 20:10:27 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:10:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:10:27 INFO - 'PWD': '/builds/slave/test', 20:10:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:10:27 INFO - 'SHELL': '/bin/bash', 20:10:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.qUlRm63fjb/Listeners', 20:10:27 INFO - 'TMPDIR': '/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/T/', 20:10:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:10:27 INFO - 'USER': 'cltbld', 20:10:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:10:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:10:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:10:27 INFO - 'XPC_FLAGS': '0x0', 20:10:27 INFO - 'XPC_SERVICE_NAME': '0', 20:10:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:10:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:10:28 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 20:10:28 INFO - Cleaning up... 20:10:28 INFO - Return code: 0 20:10:28 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 20:10:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:10:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:10:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:10:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:10:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:10:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:10:28 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 0x10c77eb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c781768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8792db7d40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c55cd30>, '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 0x10c483db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c551be8>, '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.DfbnYBb4Lz/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.qUlRm63fjb/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/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 20:10:28 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 20:10:28 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 20:10:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.DfbnYBb4Lz/Render', 20:10:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:10:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:10:28 INFO - 'HOME': '/Users/cltbld', 20:10:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:10:28 INFO - 'LOGNAME': 'cltbld', 20:10:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:10:28 INFO - 'MOZ_NO_REMOTE': '1', 20:10:28 INFO - 'NO_EM_RESTART': '1', 20:10:28 INFO - 'PAGER': '/bin/cat', 20:10:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:10:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:10:28 INFO - 'PWD': '/builds/slave/test', 20:10:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:10:28 INFO - 'SHELL': '/bin/bash', 20:10:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.qUlRm63fjb/Listeners', 20:10:28 INFO - 'TMPDIR': '/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/T/', 20:10:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:10:28 INFO - 'USER': 'cltbld', 20:10:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:10:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:10:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:10:28 INFO - 'XPC_FLAGS': '0x0', 20:10:28 INFO - 'XPC_SERVICE_NAME': '0', 20:10:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:10:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:10:28 INFO - Downloading/unpacking psutil>=3.1.1 20:10:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:10:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:10:28 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:10:28 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:10:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:10:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:10:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 20:10:32 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 20:10:32 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 20:10:32 INFO - Installing collected packages: psutil 20:10:32 INFO - Running setup.py install for psutil 20:10:32 INFO - building 'psutil._psutil_osx' extension 20:10:32 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 20:10:33 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 20:10:33 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 20:10:33 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 20:10:33 INFO - building 'psutil._psutil_posix' extension 20:10:33 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 20:10:33 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 20:10:33 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 20:10:33 INFO - ^ 20:10:33 INFO - 1 warning generated. 20:10:33 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 20:10:34 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 20:10:34 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 20:10:34 INFO - Successfully installed psutil 20:10:34 INFO - Cleaning up... 20:10:34 INFO - Return code: 0 20:10:34 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 20:10:34 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')]} 20:10:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:10:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:10:34 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')]} 20:10:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:10:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:10:34 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 0x10c77eb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c781768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8792db7d40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c55cd30>, '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 0x10c483db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c551be8>, '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.DfbnYBb4Lz/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.qUlRm63fjb/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/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 20:10:34 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 20:10:34 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 20:10:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.DfbnYBb4Lz/Render', 20:10:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:10:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:10:34 INFO - 'HOME': '/Users/cltbld', 20:10:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:10:34 INFO - 'LOGNAME': 'cltbld', 20:10:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:10:34 INFO - 'MOZ_NO_REMOTE': '1', 20:10:34 INFO - 'NO_EM_RESTART': '1', 20:10:34 INFO - 'PAGER': '/bin/cat', 20:10:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:10:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:10:34 INFO - 'PWD': '/builds/slave/test', 20:10:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:10:34 INFO - 'SHELL': '/bin/bash', 20:10:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.qUlRm63fjb/Listeners', 20:10:34 INFO - 'TMPDIR': '/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/T/', 20:10:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:10:34 INFO - 'USER': 'cltbld', 20:10:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:10:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:10:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:10:34 INFO - 'XPC_FLAGS': '0x0', 20:10:34 INFO - 'XPC_SERVICE_NAME': '0', 20:10:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:10:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:10:34 INFO - Downloading/unpacking mozsystemmonitor==0.3 20:10:34 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 20:10:34 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 20:10:34 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 20:10:34 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 20:10:34 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 20:10:34 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 20:10:37 INFO - Downloading mozsystemmonitor-0.3.tar.gz 20:10:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 20:10:37 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 20:10:37 INFO - Installing collected packages: mozsystemmonitor 20:10:37 INFO - Running setup.py install for mozsystemmonitor 20:10:37 INFO - Successfully installed mozsystemmonitor 20:10:37 INFO - Cleaning up... 20:10:37 INFO - Return code: 0 20:10:37 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 20:10:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:10:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:10:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:10:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:10:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:10:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:10:37 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10c77eb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c781768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8792db7d40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c55cd30>, '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 0x10c483db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c551be8>, '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.DfbnYBb4Lz/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.qUlRm63fjb/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/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 20:10:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 20:10:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 20:10:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.DfbnYBb4Lz/Render', 20:10:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:10:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:10:37 INFO - 'HOME': '/Users/cltbld', 20:10:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:10:37 INFO - 'LOGNAME': 'cltbld', 20:10:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:10:37 INFO - 'MOZ_NO_REMOTE': '1', 20:10:37 INFO - 'NO_EM_RESTART': '1', 20:10:37 INFO - 'PAGER': '/bin/cat', 20:10:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:10:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:10:37 INFO - 'PWD': '/builds/slave/test', 20:10:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:10:37 INFO - 'SHELL': '/bin/bash', 20:10:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.qUlRm63fjb/Listeners', 20:10:37 INFO - 'TMPDIR': '/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/T/', 20:10:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:10:37 INFO - 'USER': 'cltbld', 20:10:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:10:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:10:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:10:37 INFO - 'XPC_FLAGS': '0x0', 20:10:37 INFO - 'XPC_SERVICE_NAME': '0', 20:10:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:10:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:10:37 INFO - Downloading/unpacking jsonschema==2.5.1 20:10:37 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 20:10:37 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 20:10:37 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 20:10:37 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 20:10:37 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 20:10:37 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 20:10:40 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 20:10:40 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 20:10:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:10:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:10:40 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:10:40 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:10:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:10:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:10:40 INFO - Downloading functools32-3.2.3-2.tar.gz 20:10:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 20:10:40 INFO - warning: no files found matching '*.txt' 20:10:40 INFO - no previously-included directories found matching 'build' 20:10:40 INFO - no previously-included directories found matching 'dist' 20:10:40 INFO - no previously-included directories found matching '.git*' 20:10:40 INFO - Installing collected packages: jsonschema, functools32 20:10:40 INFO - Running setup.py install for functools32 20:10:40 INFO - warning: no files found matching '*.txt' 20:10:40 INFO - no previously-included directories found matching 'build' 20:10:40 INFO - no previously-included directories found matching 'dist' 20:10:40 INFO - no previously-included directories found matching '.git*' 20:10:40 INFO - Successfully installed jsonschema functools32 20:10:40 INFO - Cleaning up... 20:10:41 INFO - Return code: 0 20:10:41 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 20:10:41 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')]} 20:10:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:10:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:10:41 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')]} 20:10:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:10:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:10:41 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 0x10c77eb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c781768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8792db7d40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c55cd30>, '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 0x10c483db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c551be8>, '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.DfbnYBb4Lz/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.qUlRm63fjb/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/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 20:10:41 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 20:10:41 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 20:10:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.DfbnYBb4Lz/Render', 20:10:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:10:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:10:41 INFO - 'HOME': '/Users/cltbld', 20:10:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:10:41 INFO - 'LOGNAME': 'cltbld', 20:10:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:10:41 INFO - 'MOZ_NO_REMOTE': '1', 20:10:41 INFO - 'NO_EM_RESTART': '1', 20:10:41 INFO - 'PAGER': '/bin/cat', 20:10:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:10:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:10:41 INFO - 'PWD': '/builds/slave/test', 20:10:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:10:41 INFO - 'SHELL': '/bin/bash', 20:10:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.qUlRm63fjb/Listeners', 20:10:41 INFO - 'TMPDIR': '/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/T/', 20:10:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:10:41 INFO - 'USER': 'cltbld', 20:10:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:10:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:10:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:10:41 INFO - 'XPC_FLAGS': '0x0', 20:10:41 INFO - 'XPC_SERVICE_NAME': '0', 20:10:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:10:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:10:41 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 20:10:41 INFO - Cleaning up... 20:10:41 INFO - Return code: 0 20:10:41 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 20:10:41 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')]} 20:10:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:10:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:10:41 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')]} 20:10:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:10:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:10:41 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 0x10c77eb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c781768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8792db7d40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c55cd30>, '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 0x10c483db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c551be8>, '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.DfbnYBb4Lz/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.qUlRm63fjb/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/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 20:10:41 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 20:10:41 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 20:10:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.DfbnYBb4Lz/Render', 20:10:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:10:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:10:41 INFO - 'HOME': '/Users/cltbld', 20:10:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:10:41 INFO - 'LOGNAME': 'cltbld', 20:10:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:10:41 INFO - 'MOZ_NO_REMOTE': '1', 20:10:41 INFO - 'NO_EM_RESTART': '1', 20:10:41 INFO - 'PAGER': '/bin/cat', 20:10:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:10:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:10:41 INFO - 'PWD': '/builds/slave/test', 20:10:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:10:41 INFO - 'SHELL': '/bin/bash', 20:10:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.qUlRm63fjb/Listeners', 20:10:41 INFO - 'TMPDIR': '/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/T/', 20:10:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:10:41 INFO - 'USER': 'cltbld', 20:10:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:10:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:10:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:10:41 INFO - 'XPC_FLAGS': '0x0', 20:10:41 INFO - 'XPC_SERVICE_NAME': '0', 20:10:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:10:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:10:41 INFO - Downloading/unpacking blobuploader==1.2.4 20:10:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:10:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:10:41 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:10:41 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:10:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:10:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:10:44 INFO - Downloading blobuploader-1.2.4.tar.gz 20:10:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 20:10:44 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 20:10:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:10:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:10:44 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:10:44 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:10:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:10:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:10:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 20:10:44 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 20:10:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:10:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:10:44 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:10:44 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:10:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:10:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:10:45 INFO - Downloading docopt-0.6.1.tar.gz 20:10:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 20:10:45 INFO - Installing collected packages: blobuploader, requests, docopt 20:10:45 INFO - Running setup.py install for blobuploader 20:10:45 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 20:10:45 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 20:10:45 INFO - Running setup.py install for requests 20:10:46 INFO - Running setup.py install for docopt 20:10:46 INFO - Successfully installed blobuploader requests docopt 20:10:46 INFO - Cleaning up... 20:10:46 INFO - Return code: 0 20:10:46 INFO - Installing None into virtualenv /builds/slave/test/build/venv 20:10:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:10:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:10:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:10:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:10:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:10:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:10:46 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 0x10c77eb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c781768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8792db7d40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c55cd30>, '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 0x10c483db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c551be8>, '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.DfbnYBb4Lz/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.qUlRm63fjb/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/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 20:10:46 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 20:10:46 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 20:10:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.DfbnYBb4Lz/Render', 20:10:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:10:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:10:46 INFO - 'HOME': '/Users/cltbld', 20:10:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:10:46 INFO - 'LOGNAME': 'cltbld', 20:10:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:10:46 INFO - 'MOZ_NO_REMOTE': '1', 20:10:46 INFO - 'NO_EM_RESTART': '1', 20:10:46 INFO - 'PAGER': '/bin/cat', 20:10:46 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:10:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:10:46 INFO - 'PWD': '/builds/slave/test', 20:10:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:10:46 INFO - 'SHELL': '/bin/bash', 20:10:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.qUlRm63fjb/Listeners', 20:10:46 INFO - 'TMPDIR': '/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/T/', 20:10:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:10:46 INFO - 'USER': 'cltbld', 20:10:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:10:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:10:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:10:46 INFO - 'XPC_FLAGS': '0x0', 20:10:46 INFO - 'XPC_SERVICE_NAME': '0', 20:10:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:10:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:10:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 20:10:46 INFO - Running setup.py (path:/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/T/pip-8zKcM6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 20:10:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 20:10:46 INFO - Running setup.py (path:/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/T/pip-NzvdzV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 20:10:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 20:10:46 INFO - Running setup.py (path:/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/T/pip-dE6qiO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 20:10:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 20:10:46 INFO - Running setup.py (path:/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/T/pip-uW6WzM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 20:10:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 20:10:47 INFO - Running setup.py (path:/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/T/pip-R4Z5Z6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 20:10:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 20:10:47 INFO - Running setup.py (path:/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/T/pip-ZarcCm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 20:10:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 20:10:47 INFO - Running setup.py (path:/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/T/pip-s8knrR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 20:10:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 20:10:47 INFO - Running setup.py (path:/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/T/pip-9syRhk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 20:10:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 20:10:47 INFO - Running setup.py (path:/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/T/pip-ACCpyF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 20:10:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 20:10:47 INFO - Running setup.py (path:/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/T/pip-Hw38uc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 20:10:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 20:10:47 INFO - Running setup.py (path:/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/T/pip-ZN9Dds-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 20:10:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 20:10:47 INFO - Running setup.py (path:/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/T/pip-ZKTid5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 20:10:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 20:10:47 INFO - Running setup.py (path:/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/T/pip-LGbKoK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 20:10:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 20:10:47 INFO - Running setup.py (path:/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/T/pip-ReJPCS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 20:10:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 20:10:48 INFO - Running setup.py (path:/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/T/pip-ApCSp7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 20:10:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 20:10:48 INFO - Running setup.py (path:/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/T/pip-GfY75P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 20:10:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 20:10:48 INFO - Running setup.py (path:/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/T/pip-kL3gLw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 20:10:48 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 20:10:48 INFO - Running setup.py install for manifestparser 20:10:48 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 20:10:48 INFO - Running setup.py install for mozcrash 20:10:48 INFO - Running setup.py install for mozdebug 20:10:48 INFO - Running setup.py install for mozdevice 20:10:48 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 20:10:48 INFO - Installing dm script to /builds/slave/test/build/venv/bin 20:10:48 INFO - Running setup.py install for mozfile 20:10:49 INFO - Running setup.py install for mozhttpd 20:10:49 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 20:10:49 INFO - Running setup.py install for mozinfo 20:10:49 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 20:10:49 INFO - Running setup.py install for mozInstall 20:10:49 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 20:10:49 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 20:10:49 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 20:10:49 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 20:10:49 INFO - Running setup.py install for mozleak 20:10:49 INFO - Running setup.py install for mozlog 20:10:49 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 20:10:49 INFO - Running setup.py install for moznetwork 20:10:49 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 20:10:50 INFO - Running setup.py install for mozprocess 20:10:50 INFO - Running setup.py install for mozprofile 20:10:50 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 20:10:50 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 20:10:50 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 20:10:50 INFO - Running setup.py install for mozrunner 20:10:50 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 20:10:50 INFO - Running setup.py install for mozscreenshot 20:10:50 INFO - Running setup.py install for moztest 20:10:50 INFO - Running setup.py install for mozversion 20:10:50 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 20:10:50 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 20:10:50 INFO - Cleaning up... 20:10:50 INFO - Return code: 0 20:10:50 INFO - Installing None into virtualenv /builds/slave/test/build/venv 20:10:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:10:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:10:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:10:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:10:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:10:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:10:51 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 0x10c77eb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c781768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8792db7d40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c55cd30>, '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 0x10c483db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c551be8>, '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.DfbnYBb4Lz/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.qUlRm63fjb/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/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 20:10:51 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 20:10:51 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 20:10:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.DfbnYBb4Lz/Render', 20:10:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:10:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:10:51 INFO - 'HOME': '/Users/cltbld', 20:10:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:10:51 INFO - 'LOGNAME': 'cltbld', 20:10:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:10:51 INFO - 'MOZ_NO_REMOTE': '1', 20:10:51 INFO - 'NO_EM_RESTART': '1', 20:10:51 INFO - 'PAGER': '/bin/cat', 20:10:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:10:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:10:51 INFO - 'PWD': '/builds/slave/test', 20:10:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:10:51 INFO - 'SHELL': '/bin/bash', 20:10:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.qUlRm63fjb/Listeners', 20:10:51 INFO - 'TMPDIR': '/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/T/', 20:10:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:10:51 INFO - 'USER': 'cltbld', 20:10:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:10:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:10:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:10:51 INFO - 'XPC_FLAGS': '0x0', 20:10:51 INFO - 'XPC_SERVICE_NAME': '0', 20:10:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:10:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:10:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 20:10:51 INFO - Running setup.py (path:/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/T/pip-9gw0ZB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 20:10:52 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)) 20:10:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 20:10:52 INFO - Running setup.py (path:/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/T/pip-3YIbM8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 20:10:52 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)) 20:10:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 20:10:52 INFO - Running setup.py (path:/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/T/pip-gBxaFw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 20:10:52 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)) 20:10:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 20:10:52 INFO - Running setup.py (path:/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/T/pip-hH3nId-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 20:10:52 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)) 20:10:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 20:10:52 INFO - Running setup.py (path:/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/T/pip-ar7l0x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 20:10:52 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)) 20:10:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 20:10:52 INFO - Running setup.py (path:/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/T/pip-YEX71n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 20:10:52 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)) 20:10:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 20:10:52 INFO - Running setup.py (path:/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/T/pip-SDmzbd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 20:10:52 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)) 20:10:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 20:10:52 INFO - Running setup.py (path:/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/T/pip-LA2cRn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 20:10:52 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)) 20:10:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 20:10:52 INFO - Running setup.py (path:/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/T/pip-2ZhpGw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 20:10:52 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)) 20:10:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 20:10:52 INFO - Running setup.py (path:/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/T/pip-s1Y9ku-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 20:10:53 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)) 20:10:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 20:10:53 INFO - Running setup.py (path:/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/T/pip-WaCWAb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 20:10:53 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)) 20:10:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 20:10:53 INFO - Running setup.py (path:/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/T/pip-USOsUI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 20:10:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 20:10:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 20:10:53 INFO - Running setup.py (path:/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/T/pip-Re6tWe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 20:10:53 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)) 20:10:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 20:10:53 INFO - Running setup.py (path:/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/T/pip-uHfMnx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 20:10:53 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)) 20:10:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 20:10:53 INFO - Running setup.py (path:/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/T/pip-TiZDrS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 20:10:53 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)) 20:10:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 20:10:53 INFO - Running setup.py (path:/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/T/pip-pvY746-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 20:10:53 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 20:10:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 20:10:53 INFO - Running setup.py (path:/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/T/pip-ApQ_vw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 20:10:53 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)) 20:10:53 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)) 20:10:53 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)) 20:10:53 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)) 20:10:53 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)) 20:10:53 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)) 20:10:53 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 20:10:53 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 20:10:53 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 20:10:53 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 20:10:53 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 20:10:53 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 20:10:53 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 20:10:56 INFO - Downloading blessings-1.6.tar.gz 20:10:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 20:10:56 INFO - Installing collected packages: blessings 20:10:56 INFO - Running setup.py install for blessings 20:10:57 INFO - Successfully installed blessings 20:10:57 INFO - Cleaning up... 20:10:57 INFO - Return code: 0 20:10:57 INFO - Done creating virtualenv /builds/slave/test/build/venv. 20:10:57 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 20:10:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 20:10:57 INFO - Reading from file tmpfile_stdout 20:10:57 INFO - Current package versions: 20:10:57 INFO - blessings == 1.6 20:10:57 INFO - blobuploader == 1.2.4 20:10:57 INFO - docopt == 0.6.1 20:10:57 INFO - functools32 == 3.2.3-2 20:10:57 INFO - jsonschema == 2.5.1 20:10:57 INFO - manifestparser == 1.1 20:10:57 INFO - mozInstall == 1.12 20:10:57 INFO - mozcrash == 1.0 20:10:57 INFO - mozdebug == 0.1 20:10:57 INFO - mozdevice == 0.49 20:10:57 INFO - mozfile == 1.2 20:10:57 INFO - mozhttpd == 0.7 20:10:57 INFO - mozinfo == 0.9 20:10:57 INFO - mozleak == 0.1 20:10:57 INFO - mozlog == 3.4 20:10:57 INFO - moznetwork == 0.27 20:10:57 INFO - mozprocess == 0.23 20:10:57 INFO - mozprofile == 0.28 20:10:57 INFO - mozrunner == 6.13 20:10:57 INFO - mozscreenshot == 0.1 20:10:57 INFO - mozsystemmonitor == 0.3 20:10:57 INFO - moztest == 0.7 20:10:57 INFO - mozversion == 1.4 20:10:57 INFO - psutil == 3.1.1 20:10:57 INFO - requests == 1.2.3 20:10:57 INFO - wsgiref == 0.1.2 20:10:57 INFO - Installing None into virtualenv /builds/slave/test/build/venv 20:10:57 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')]} 20:10:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:10:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:10:57 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')]} 20:10:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:10:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:10:57 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 0x10c77eb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c781768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8792db7d40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c55cd30>, '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 0x10c483db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c551be8>, '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.DfbnYBb4Lz/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.qUlRm63fjb/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/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 20:10:57 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 20:10:57 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 20:10:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.DfbnYBb4Lz/Render', 20:10:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:10:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:10:57 INFO - 'HOME': '/Users/cltbld', 20:10:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:10:57 INFO - 'LOGNAME': 'cltbld', 20:10:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:10:57 INFO - 'MOZ_NO_REMOTE': '1', 20:10:57 INFO - 'NO_EM_RESTART': '1', 20:10:57 INFO - 'PAGER': '/bin/cat', 20:10:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:10:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:10:57 INFO - 'PWD': '/builds/slave/test', 20:10:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:10:57 INFO - 'SHELL': '/bin/bash', 20:10:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.qUlRm63fjb/Listeners', 20:10:57 INFO - 'TMPDIR': '/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/T/', 20:10:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:10:57 INFO - 'USER': 'cltbld', 20:10:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:10:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:10:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:10:57 INFO - 'XPC_FLAGS': '0x0', 20:10:57 INFO - 'XPC_SERVICE_NAME': '0', 20:10:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:10:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:10:57 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)) 20:10:57 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)) 20:10:57 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)) 20:10:57 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)) 20:10:57 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)) 20:10:57 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)) 20:10:57 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)) 20:10:57 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)) 20:10:57 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)) 20:10:57 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)) 20:10:57 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)) 20:10:57 INFO - Cleaning up... 20:10:57 INFO - Return code: 0 20:10:57 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 20:10:57 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')]} 20:10:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:10:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:10:57 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')]} 20:10:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:10:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:10:57 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 0x10c77eb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c781768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8792db7d40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c55cd30>, '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 0x10c483db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c551be8>, '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.DfbnYBb4Lz/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.qUlRm63fjb/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/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 20:10:57 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 20:10:57 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 20:10:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.DfbnYBb4Lz/Render', 20:10:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:10:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:10:57 INFO - 'HOME': '/Users/cltbld', 20:10:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:10:57 INFO - 'LOGNAME': 'cltbld', 20:10:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:10:57 INFO - 'MOZ_NO_REMOTE': '1', 20:10:57 INFO - 'NO_EM_RESTART': '1', 20:10:57 INFO - 'PAGER': '/bin/cat', 20:10:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:10:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:10:57 INFO - 'PWD': '/builds/slave/test', 20:10:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:10:57 INFO - 'SHELL': '/bin/bash', 20:10:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.qUlRm63fjb/Listeners', 20:10:57 INFO - 'TMPDIR': '/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/T/', 20:10:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:10:57 INFO - 'USER': 'cltbld', 20:10:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:10:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:10:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:10:57 INFO - 'XPC_FLAGS': '0x0', 20:10:57 INFO - 'XPC_SERVICE_NAME': '0', 20:10:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:10:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:10:57 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 20:10:57 INFO - Cleaning up... 20:10:57 INFO - Return code: 0 20:10:57 INFO - Running post-action listener: _resource_record_post_action 20:10:57 INFO - Running post-action listener: _start_resource_monitoring 20:10:57 INFO - Starting resource monitoring. 20:10:57 INFO - [mozharness: 2016-12-23 04:10:57.944682Z] Finished create-virtualenv step (success) 20:10:57 INFO - [mozharness: 2016-12-23 04:10:57.945735Z] Running install step. 20:10:57 INFO - Running pre-action listener: _resource_record_pre_action 20:10:57 INFO - Running main action method: install 20:10:57 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 20:10:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 20:10:58 INFO - Reading from file tmpfile_stdout 20:10:58 INFO - Detecting whether we're running mozinstall >=1.0... 20:10:58 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 20:10:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 20:10:58 INFO - Reading from file tmpfile_stdout 20:10:58 INFO - Output received: 20:10:58 INFO - Usage: mozinstall [options] installer 20:10:58 INFO - Options: 20:10:58 INFO - -h, --help show this help message and exit 20:10:58 INFO - -d DEST, --destination=DEST 20:10:58 INFO - Directory to install application into. [default: 20:10:58 INFO - "/builds/slave/test"] 20:10:58 INFO - --app=APP Application being installed. [default: firefox] 20:10:58 INFO - mkdir: /builds/slave/test/build/application 20:10:58 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'] 20:10:58 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 20:11:20 INFO - Reading from file tmpfile_stdout 20:11:20 INFO - Output received: 20:11:20 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 20:11:20 INFO - Running post-action listener: _resource_record_post_action 20:11:20 INFO - [mozharness: 2016-12-23 04:11:20.206029Z] Finished install step (success) 20:11:20 INFO - [mozharness: 2016-12-23 04:11:20.206172Z] Running run-tests step. 20:11:20 INFO - Running pre-action listener: _resource_record_pre_action 20:11:20 INFO - Running main action method: run_tests 20:11:20 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 20:11:20 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 20:11:20 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 20:11:20 INFO - 2016-12-22 20:11:20.220 screenresolution[1622:7327] starting screenresolution argv=screenresolution get 20:11:20 INFO - 2016-12-22 20:11:20.223 screenresolution[1622:7327] Display 0: 1600x1200x32@60 20:11:20 INFO - 2016-12-22 20:11:20.229 screenresolution[1623:7330] starting screenresolution argv=screenresolution list 20:11:20 INFO - Available Modes on Display 0 20:11:20 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 20:11:20 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 20:11:20 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 20:11:20 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 20:11:20 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 20:11:20 INFO - Intel Iris: 20:11:20 INFO - Chipset Model: Intel Iris 20:11:20 INFO - Type: GPU 20:11:20 INFO - Bus: Built-In 20:11:20 INFO - VRAM (Dynamic, Max): 1536 MB 20:11:20 INFO - Vendor: Intel (0x8086) 20:11:20 INFO - Device ID: 0x0a2e 20:11:20 INFO - Revision ID: 0x0009 20:11:20 INFO - Displays: 20:11:20 INFO - Display: 20:11:20 INFO - Resolution: 1600 x 1200 @ 60 Hz 20:11:20 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 20:11:20 INFO - Display Serial Number: 1600x1200 60 20:11:20 INFO - Main Display: Yes 20:11:20 INFO - Mirror: Off 20:11:20 INFO - Online: Yes 20:11:20 INFO - Rotation: Supported 20:11:20 INFO - Return code: 0 20:11:20 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 20:11:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 20:11:20 INFO - Python 2.7.3 20:11:20 INFO - Return code: 0 20:11:20 INFO - grabbing minidump binary from tooltool 20:11: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')]} 20:11:20 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 0x10c55cd30>, '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 0x10c483db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c551be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 20:11:20 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 20:11:20 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 20:11:20 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 20:11:20 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 20:11:20 INFO - Return code: 0 20:11:20 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 20:11:20 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 20:11:20 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 20:11:20 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 20:11:20 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 20:11:20 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Y6GVFfccRXWga3ceP1u3GQ/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0083', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 20:11:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/Y6GVFfccRXWga3ceP1u3GQ/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0083 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 20:11:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.DfbnYBb4Lz/Render', 20:11:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:11:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:11:20 INFO - 'HOME': '/Users/cltbld', 20:11:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:11:20 INFO - 'LOGNAME': 'cltbld', 20:11:20 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 20:11:20 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 20:11:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:11:20 INFO - 'MOZ_NO_REMOTE': '1', 20:11:20 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 20:11:20 INFO - 'NO_EM_RESTART': '1', 20:11:20 INFO - 'PAGER': '/bin/cat', 20:11:20 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:11:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:11:20 INFO - 'PWD': '/builds/slave/test', 20:11:20 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 20:11:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:11:20 INFO - 'SHELL': '/bin/bash', 20:11:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.qUlRm63fjb/Listeners', 20:11:20 INFO - 'TMPDIR': '/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/T/', 20:11:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:11:20 INFO - 'USER': 'cltbld', 20:11:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:11:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:11:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:11:20 INFO - 'XPC_FLAGS': '0x0', 20:11:20 INFO - 'XPC_SERVICE_NAME': '0', 20:11:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:11:20 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Y6GVFfccRXWga3ceP1u3GQ/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0083', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 20:11:20 INFO - mozversion application_buildid: 20161222182453 20:11:20 INFO - mozversion application_changeset: 82574d098a94527588b6be7d800785ccb11df961 20:11:20 INFO - mozversion application_display_name: Nightly 20:11:20 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 20:11:20 INFO - mozversion application_name: Firefox 20:11:20 INFO - mozversion application_remotingname: firefox 20:11:20 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 20:11:20 INFO - mozversion application_vendor: Mozilla 20:11:20 INFO - mozversion application_version: 53.0a1 20:11:20 INFO - mozversion platform_buildid: 20161222182453 20:11:20 INFO - mozversion platform_changeset: 82574d098a94527588b6be7d800785ccb11df961 20:11:20 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 20:11:20 INFO - mozversion platform_version: 53.0a1 20:11:20 INFO - using testdate: 1482466280 20:11:20 INFO - actual date: 1482466280 20:11:20 INFO - starting webserver on 'localhost:49246' 20:11:20 INFO - SUITE-START | Running 2 tests 20:11:20 INFO - TEST-START | dromaeo_css 20:11:20 INFO - operating with platform_type : mac_ 20:11:20 INFO - Initialising browser for dromaeo_css test... 20:11:20 INFO - TEST-INFO | started process 1633 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/T/tmpznIlNn/profile http://localhost:49246/getInfo.html) 20:11:21 INFO - PROCESS | 1633 | 1482466281426 addons.xpi WARN Exception running bootstrap method install on shield-recipe-client@mozilla.org: [Exception... "Component returned failure code: 0x8000ffff (NS_ERROR_UNEXPECTED) [nsIPrefBranch.getBoolPref]" nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js :: this.install :: line 38" data: no] Stack trace: this.install()@resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js:38 < callBootstrapMethod()@resource://gre/modules/addons/XPIProvider.jsm:4979 < processFileChanges()@resource://gre/modules/addons/XPIProvider.jsm -> resource://gre/modules/addons/XPIProviderUtils.js:2097 < checkForChanges()@resource://gre/modules/addons/XPIProvider.jsm:3825 < startup()@resource://gre/modules/addons/XPIProvider.jsm:2829 < callProvider()@resource://gre/modules/AddonManager.jsm:264 < _startProvider()@resource://gre/modules/AddonManager.jsm:771 < startup()@resource://gre/modules/AddonManager.jsm:957 < startup()@resource://gre/modules/AddonManager.jsm:2923 < observe()@resource://gre/components/addonManager.js:65 20:11:23 INFO - PROCESS | 1633 | __metrics Screen width/height:1600/1200 20:11:23 INFO - PROCESS | 1633 | colorDepth:24 20:11:23 INFO - PROCESS | 1633 | Browser inner width/height: 1024/689 20:11:23 INFO - PROCESS | 1633 | __metrics 20:11:23 INFO - PROCESS | 1633 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 20:11:23 INFO - PROCESS | 1633 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 20:11:24 INFO - TEST-INFO | 1633: exit 0 20:11:24 INFO - Browser initialized. 20:11:24 INFO - Running cycle 1/1 for dromaeo_css test... 20:11:24 INFO - TEST-INFO | started process 1635 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/T/tmpznIlNn/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 20:11:36 INFO - PROCESS | 1635 | RSS: Main: 234409984 20:11:36 INFO - PROCESS | 1635 | 20:12:26 INFO - PROCESS | 1635 | 1482466346990 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 20:14:41 INFO - PROCESS | 1635 | Cycle 1(1): loaded http://localhost:49246/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49246/tests/dromaeo/cssquery-ext.html) 20:14:43 INFO - PROCESS | 1635 | RSS: Main: 367239168 20:14:43 INFO - PROCESS | 1635 | 20:16:25 INFO - PROCESS | 1635 | ************************* 20:16:25 INFO - PROCESS | 1635 | A coding exception was thrown and uncaught in a Task. 20:16:25 INFO - PROCESS | 1635 | 20:16:25 INFO - PROCESS | 1635 | Full message: ReferenceError: fetch is not defined 20:16:25 INFO - PROCESS | 1635 | Full stack: apiCall@resource://shield-recipe-client/lib/NormandyApi.jsm:37:5 20:16:25 INFO - PROCESS | 1635 | get@resource://shield-recipe-client/lib/NormandyApi.jsm:44:12 20:16:25 INFO - PROCESS | 1635 | this.NormandyApi.fetchRecipes<@resource://shield-recipe-client/lib/NormandyApi.jsm:52:34 20:16:25 INFO - PROCESS | 1635 | TaskImpl_run@resource://gre/modules/Task.jsm:319:42 20:16:25 INFO - PROCESS | 1635 | TaskImpl@resource://gre/modules/Task.jsm:277:3 20:16:25 INFO - PROCESS | 1635 | asyncFunction@resource://gre/modules/Task.jsm:252:14 20:16:25 INFO - PROCESS | 1635 | this.RecipeRunner.start<@resource://shield-recipe-client/lib/RecipeRunner.jsm:64:23 20:16:25 INFO - PROCESS | 1635 | TaskImpl_run@resource://gre/modules/Task.jsm:319:42 20:16:25 INFO - PROCESS | 1635 | TaskImpl@resource://gre/modules/Task.jsm:277:3 20:16:25 INFO - PROCESS | 1635 | asyncFunction@resource://gre/modules/Task.jsm:252:14 20:16:25 INFO - PROCESS | 1635 | setTimeout_timer@resource://gre/modules/Timer.jsm:30:5 20:16:25 INFO - PROCESS | 1635 | 20:16:25 INFO - PROCESS | 1635 | ************************* 20:17:46 INFO - PROCESS | 1635 | Cycle 1(1): loaded http://localhost:49246/tests/dromaeo/cssquery-ext.html (next: http://localhost:49246/tests/dromaeo/cssquery-jquery.html) 20:17:46 INFO - PROCESS | 1635 | RSS: Main: 371785728 20:17:46 INFO - PROCESS | 1635 | 20:20:49 INFO - PROCESS | 1635 | Cycle 1(1): loaded http://localhost:49246/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49246/tests/dromaeo/cssquery-mootools.html) 20:20:49 INFO - PROCESS | 1635 | RSS: Main: 358227968 20:20:49 INFO - PROCESS | 1635 | 20:23:52 INFO - PROCESS | 1635 | Cycle 1(1): loaded http://localhost:49246/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49246/tests/dromaeo/cssquery-prototype.html) 20:23:53 INFO - PROCESS | 1635 | RSS: Main: 338984960 20:23:53 INFO - PROCESS | 1635 | 20:26:56 INFO - PROCESS | 1635 | Cycle 1(1): loaded http://localhost:49246/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49246/tests/dromaeo/cssquery-yui.html) 20:26:57 INFO - PROCESS | 1635 | RSS: Main: 339832832 20:26:57 INFO - PROCESS | 1635 | 20:30:00 INFO - PROCESS | 1635 | Cycle 1(1): loaded http://localhost:49246/tests/dromaeo/cssquery-yui.html (next: http://localhost:49246/tests/dromaeo/cssquery-dojo.html) 20:30:01 INFO - PROCESS | 1635 | RSS: Main: 586567680 20:30:01 INFO - PROCESS | 1635 | 20:30:01 INFO - PROCESS | 1635 | __start_tp_report 20:30:01 INFO - PROCESS | 1635 | _x_x_mozilla_page_load 20:30:01 INFO - PROCESS | 1635 | _x_x_mozilla_page_load_details 20:30:01 INFO - PROCESS | 1635 | |i|pagename|runs| 20:30:01 INFO - PROCESS | 1635 | |0;dojo.html;3329.633740288568;3340.7661463697536;3369.629340772513;3377.4258376076064;3408.386490431723;5344.931013797242;5534.059209934311;5616.101423772192;5620.57845661575;5677.886442271152;5592.5805564582715;5612.41069687683;5647.774089036444;5711.343195532515;5723.942760572393;5034.421041580228;5089.007643509517;5102.260172275018;5120.615953803461;5149.382074151107;4608.861734147981;5210.557102646279;5260.237265596486;5260.92108618371;5261.421243663194;5104.055749686283;5139.357580302462;5223.555997740196;5236.345456817898;5261.631685781997;5189.377274727057;5201.141811601081;5204.687718736888;5210.947890521122;5222.764975576109;5065.518775716338;5141.408737995103;5147.7683504242395;5160.793568257265;5162.303089082963;5646.322441307028;5652.689102099383;5666.150077488369;5668.603197776116;5683.914741278884;6104.519788040277;6108.623149146864;6121.219063936594;6128.3503509891225;6139.447449729501;6146.569740118193;6155.045967874987;6162.260528736536;6233.968830155865;6275.905861412082;5717.942820571824;5748.597598168171;5750.769969201227;5752.568557358215;5753.7123143843055;6311.558190926683;6332.430081292614;6340.207474065742;6344.968275158594;6357.936420635734;5900.586958912835;5969.015112506475;5986.910196347058;6016.969915150396;6099.481544068715;5983.371745966698;6189.907151392733;6203.47270482005;6225.875482490324;6229.813105606839;5923.437273459014;6029.969850150721;6081.209442772411;6104.580421837809;6143.047827586731;6188.4739797117745;6190.380961903864;6192.195014648157;6204.81385558434;6208.286047104641;4814.20565606678;4847.95152048482;4866.172750632401;4913.582345500601;4948.035133149001;4324.962009117789;4346.630536404441;4367.497737760134;4384.714993525411;4413.757243351646;5702.45826646468;5710.65736055838;5715.1713001614535;5723.255976723;5724.398938111522;6099.3900609938655;6222.191115155001;6242.1261023456755;6242.531810114259;6251.343608921089;5628;5691.8292451226525;5693.857653558694;5735.885282294331;5757.769689212385;5309.814156504581;5366.490183432567;5375.301210842644;5380.219868119101;5392.4607539246545;6307.684615769285;6343.904841427475;6353.078803573363;6354.4281014708895;6355.777547785805;6101.908471373022;6137.110119032626;6139.815805525842;6146.78486252979;6250.4374606285655;6255.843603909939;6259.6870156492905;6287.528435367457;6296.527760418078;6312.368763123651;6144.293406258159;6144.938550614615;6154.19995400613;6168.167297414791;6172.876542469216;5767.307923049261;6121.270068921507;6123.357047509948;6166.907496387647;6246.6876656166105;5977.282726072899;5998.550108741774;6021.066734656135;6072;6105.908411373922;5858.121281807763;6005.249343832021;6044.7279872406725;6052.364501727256;6057.787977420945;5672.943270567406;5772.9711351442975;5814.854628634318;5849.1226316052935;5852.122181672783;5877.823665290048;6051;6060.727267272895;6076.848078797889;6089.756409743561;5767.134929760569;5774.307083150049;5805.680687562224;5813.447722466359;5814.360420353673 20:30:01 INFO - PROCESS | 1635 | |1;ext.html;427.3162939297125;431.86828017454684;449.22060225508756;511.9078565858144;514.1080225808223;30141.54787678184;38647.227055458876;40231.79884100575;40289.194216115735;40315.79842100785;41840.79079604598;42123.368149477785;42204.788976055155;42295;42295.365569516405;8319.043310019335;8566.78583035426;9337.393069450482;9586.808263834719;9589;11052.723681907935;11345.26255793375;11387.601433949814;11548;11549.6535103947;11394.202405831595;11463.713407164845;11473.655790326262;11477.540898364055;11497.195196336259;11141.944290278538;11183.049440797542;11199.384033878134;11213.589078348114;11219.66341009771;11216.719582010433;11295.435228238555;11309.09527237819;11311.604093856758;11337.489812958436;38681;38705.03309404306;39107.217855642724;39191.60808391908;39578;3235.110344655215;3260.152360386293;3340.1148695595684;3383.424817780983;3465.4108801503803;27514.724852751417;27525.587116193274;28617.427651447062;28629;28721.28196795076;3098.411301852663;3258.6415494295607;3301.4387554115856;3313.0557791029682;3314.419976504126;443879;444108;444792;445679;446981.4763892721;52277.954440910966;52606;52825.207621885704;52836.471635283924;52898.94202115936;54586.18120728192;54611;54670;54717.1792423106;54770.726146369016;14671.799230115355;14933;14944.626384340261;14971.401143954337;15011.474598389223;10476.214283928735;10679.252452328417;10703.518341674644;11350.602728904614;11555.711107222387;12989.675258118623;14407.927960360132;15937.601559961095;16834.831651683573;16860.819742618143;5200.023990883516;7234.204237533867;7253.601051942193;7255.854882902312;7272.854542909112;828.2110641015378;888.5823662878437;984.7095106943469;985.6500942165571;989.5843745626854;29213.920613369744;29278;29719.40561188764;29953.850230748707;30011.94958176497;1039.6880935719255;1117.9944100279447;1126.4987080748988;1148.1618418554501;1216.3127832774453;15031.398744050115;15184.316705748268;15344;15717.292721827545;15728.842711572966;522226;525989;527568;528376;529898;58064;58309.41690583125;58447.4155258442;58514.537136571074;58626;1200.7058270723728;1361.4350044731523;1370.3148425787106;1400.908940918837;1401.4674423719125;26074.86962565175;26631.467370652867;28846.567301490075;29570.852145739133;29629.5555566671;47777.522224777305;48204.276935845286;48235.75882120567;48350;48362.75818620884;560602;563881;565786;566054;567299;25073.62095084788;25702.871485642452;25712.614310785728;25888.352791180372;26049.82775775057;45868;46574.76712616415;46718.065638686356;47044.9432585115;47184.7640761794;36545.45181822222;36659.90020299395;37167.88496345114;37659.24681506409;38164;38210.853674389815;38595;39296.60703392929;39359.40960885532;39982.60017399789;34614.480782787774;36974.26051479009;37778.67774627875;37817.81091094527;38078.619213807506;32946.84686035977;33936;34030.65969340275;34345.656543435245;34687.653123468444;20388.796112039283;24713.505729884942;24879.875600621883;25356.366090847878;25850.870745646152 20:30:01 INFO - PROCESS | 1635 | |2;jquery.html;350.86316336628704;365.5905385967716;371.20191588085595;373.5461414381526;395.79023117747585;14809.407623695053;14873.925630371847;16231.107289099109;20623.27818526352;20645.896770516127;16768.496945091665;17819.01995390253;19695.21219151232;20826.958652067413;20994;5466.590005749575;5781.421857814206;5855.736491857855;5892.823215303548;5894.764209431618;4724.62203023759;5769.3076830780365;5795.072788353864;5817.592768506206;5825.96091368022;5614.073677843151;5747.741351639165;5773.538116950634;5773.538116950634;5776.277965254343;5617.943820561783;5752.5110365618975;5772.740226689788;5849.122631605251;5849.210356601847;4898.485659005789;4999.975000125012;5111.539961403491;5111.667741596784;5113.718745468997;18807.435776926577;24395.878020609962;24661.0135594576;24828.875855620787;25160.370990725194;1805.8465030472425;2170.3597438755605;2205.4376134085705;2355.187460326185;2952.3652414730723;14252.358643860947;15089.622759430993;15215.08709477435;15398.769018464733;16157.91921040399;1788.624388878324;1845.9815401845945;1851.907404629763;1879.135597625094;1910.8757930734457;111677.44161279142;115222;115373;116247;117454;10188.134008609202;10414.427206503715;10503.684889453329;10713.410762407986;11255.212135150625;13300.667483312995;13320.20078795256;13324.400401981935;13325.13386629866;13343.199408035549;8029.558374289353;8209.507429554245;8284.544350060805;8381;8467.32261419085;8259.132791056973;8274.503529788231;8317;8478.5336806475;8510.978682558132;6404.967975160094;6510.424471283943;6521.47828173745;6542.345765423514;6552.475801935833;1794;1800.9009504477117;1801.3245033112582;1811.429399739078;1813.238439855264;843.2242337049929;865.2688478235881;880.7229517200085;981.8478135888948;1001.1790331927895;15120.656298273785;17558.82441175572;21578.518470375795;21749.456263593536;21828.690278583337;947.824652439301;952.0431965874252;961.4567769210374;982.6708052802248;1044.5038606661774;11035.2275340727;11155.051820595178;11849.881501185051;11923.403829808474;12255.754884902251;564247;567675.8081047924;570984;575811.1209443926;576633;88729;96043.51978240065;118172;133217.00174497196;134147.65852341548;1009.6668099527138;1048.3866937841449;1077.6389909380289;1107.4186052322596;1110.5113749949996;14523.709525809214;14526.20105894186;19055.714164287823;19081.61836763229;19271.807281927002;50872.74563627158;53171.73414133059;54140.72929635327;57744;58529;82788;83017;83084;83353;84398;7159.355657990805;7813.296803287732;7928.960355198187;8234.835303294021;8414.495130292202;9143.725688229364;12248.938755306166;12698.809517857424;13042;13111.344432778515;9450;11231.887681123084;11485.793991630759;11674.94925456713;11715.297082175097;10588.729352477752;11035.558577657124;11088.445577721242;11133.276337038391;11739.647810565697;11579;11616.477258523555;11815.40922953866;11866.584669536525;11928.642140735792;7882.763517094496;7895.210478952059;7902.960485197537;8393.28657064132;8408.2432581068;8313.584320784057;10414.375137491776;11209.55161793519;11544.307341559534;11558.133140014364 20:30:01 INFO - PROCESS | 1635 | |3;mootools.html;1347.7371912477072;1461.4373466215507;1507.6984603079384;1706.3686436018675;1903.2482169543032;7433.479656424046;7838.216178382159;8128.146544612812;8376.62305196266;8388;8209.13804050575;8220.547869867154;8236.876446853303;8364.958175209116;8370.497770133781;2041.1427200575795;2265.8074063704603;2317.6407656813135;2317.7218733751974;2317.8029867461287;2079.4177630263475;2251.223327951854;2333.3000099970027;2346.9882650586724;2349.4713689419914;2268.965965510519;2288.874111923852;2292.919747808827;2301.631738921773;2301.689271948282;2264.1056782570877;2291.667708182304;2294.5410917816503;2296.6440201768587;2297.8046866016407;2259.35608351619;2287.954240915189;2323.453988312745;2326.6510023496444;2329.464223228667;8330.416870819045;8391.790205244857;8397.664093436255;8401.705940292122;8404.957975210085;5024.598032157455;5191.402988656315;5202.115640341151;5213.983273261715;5215.400228973641;3320.8007519548905;3394.131022435619;3475.0288503329793;3545.8758943437115;3547.3614749345106;1029.490402250883;1057.7567159553346;1064.180580952662;1069.3744159666526;1080.7298175456135;10325.173986081096;10600.946995264974;10620.893791062068;10814.675559733221;10820.837687434694;4311.50417701962;4386.276264416336;4413.867583972486;4414.933775993362;4425.269830478;8628.35287353451;8632.525211113323;8633.740987770378;8642.135786421308;8648.26489748366;5038.370203724535;5043.092243396224;5043.722595257296;5044.495550444926;5060.898782024339;4231.132617813341;4377.058932329564;4530.365748795171;4532.070925460339;4643.744594047293;3557.982210088933;3608.9819550902075;3659.249853779969;3667.0832291927018;3705.6479634434686;1129.6498085593328;1133;1134.6085600467825;1147.2944139354238;1153.4982282707049;757.7347928225078;887.8002449448823;894.9910500894907;899.5592159841631;911.1389736698756;5199;5555.749991250404;5568.6658800472105;5804.709764511757;5917.733701983421;850.4429598612918;978.8238117138841;1001.8998100189923;1004.6584161385164;1067.1782727299935;3579.1231148368815;3692.316921369555;3742.6631603155847;3763.6236376362695;3773.8301776420126;9847.113759761656;10035.09684128432;10093.24300677452;10184.796304073878;10193;9413.717588472357;9853.950730246303;9863.260255480867;10116.898831011596;10138.746531336628;7640.617969101522;7875.133735289113;7878.566678832604;7895.328897043815;7903.525788452711;5382.919256211082;5396.730163491889;5494.615376923577;5671.092625179951;5949.791757288474;9147.268218542633;9294.163525282756;9422.905770942478;9445.338826282094;9456.621735130519;8944.68693595721;9361.719148425558;9365.625374985197;9480.288978326515;9522.857157142787;6527.445167160845;7079.2566780487305;7238.782836514913;7246.405790726032;7265.963670181615;6339.936600634119;7347.448941329529;7542.736004239825;7590.1650818411035;7670.463067585216;6858.931410685829;6880.277570855188;6919.792406227822;7039.6832142552685;7187.748428804966;6829.795106146824;6903.551269167488;6913.827154321182;6920.757773477904;6940.41006514452;6508.056331831953;6598;6612.603243805387;6685.465162787063;6686.231083425469;4710.764461776829;4722.787474563722;4762;4770.117528257145;4798.880027999328;4677.134730074947;4783.880402989953;4787.234042553175;4794.188620047141;4986.675866068694 20:30:01 INFO - PROCESS | 1635 | |4;prototype.html;311.8585975330292;342.3119529745212;353.03621114357804;355.2947399412166;366.40459253712714;4511.165434394639;5259.474052594738;5383.159272189674;5462.918056229159;5480;4976.909697926871;5222.373315202171;5386.852123864622;5413.783448662059;5433.809816656418;3178.6319192201786;3820.7134464915102;4120.9285585747775;4131.29767939451;4131.814068366931;3445.6898879100877;3786.4131059685656;4201.411802347674;4211.368294755781;4219.573745096566;4152.688548358885;4169.353750168729;4198.349255865346;4202.285611446062;4231.830726770925;3044.549137943954;3380.4760262159402;3863.208042351311;3904.902377440558;3986.7807270600106;3230.6607806180245;3267.901962941092;3687.575928768199;3698.5746639136573;3702.611225821304;4752.59602933751;5268.262443257909;5286.3392075990505;5293.311869456985;5319.255304257407;4230.957690423118;4697;4728.452839149515;4778.761061946923;4835.806567737286;5686.772529098831;5696.715164241813;5700.486956173964;5701.543876489871;5726.31284245889;3223.564818749462;3587.910302242439;3656.9394875486078;4039.313316736162;4122.56713045129;5411.891762164734;5795.609053827051;5866.823995280149;5871.882562348772;6011.669358185298;3412.897613071612;3506.3513250048823;3817.5991520889906;3877.2439374321734;3893.493845800028;3592.6766591006935;3918.490596222473;3924.3917192834583;3928.4107383892647;3929.4302326162547;3810.790406527612;4307.030918043415;4552.931706024412;4557.680962332671;4570.223062079454;4458.308962110878;5051.898962020739;5254.97372513135;5681.204631351614;5726.5683579105225;1157.8768594463525;1209.4980583057932;1274.4833647958899;1287.2726909296218;1292.8642492538336;775.8603451378806;819.4755356571799;821.7699044267615;863.9524826134498;877.4252864373718;4061.8375264989068;4610.423697037871;4990.351254336985;5228.712420816892;5258.579313654898;4814.975925120422;4876.926846097312;5679.574031947621;5778.5377169826315;5782.335031471434;5100.872478188075;5170.8190213343105;5213.817516386984;5271.973640131851;5339.866503337447;2479.6032634778708;2875.9359037156387;2894.4789937811397;2948.248196709825;2953.7341639252572;4689.7655117244;5154.829779685541;5789.594728368974;5822.563307751935;5825.621334613236;5313.937212557504;5321.308229930161;5610.92211548541;5664;5741.540676745851;4935.802567897387;5081.932794113203;5794.710264486759;5811.999999999915;5832.737526811219;5009.7244651544515;5149.124648809637;5635.464041181567;5648.71756412186;5695.572832037697;5020.874478138076;5136.457453560512;5337.54630856366;5509.559235261249;5549.889002219852;5016.319532956501;5705.885882282414;5775.639392258818;5834.024647429756;5877.529797616266;3310.6027276725986;3319.9170020749675;3660.0784588091465;3660.194757153422;3663.0768846355877;4611.939253971538;4918.532739389752;5541.473560011793;5850.94149058504;5909.763609455746;4734.366643507984;5007.7496125192865;5719.885602287848;5736.5046204901;5738.254026976467;5220.955808838324;5717.313922329347;5720.942790572041;5722.570807189394;5734.8780951048375;4531.297648864432;5230.372355317386;5288.3918349390315;5560.555155587463;5645.7579332546775;4889.608831293417;5010.340383443287;5024.618229986637;5674.74463649145;5750.6262092963825;4472.932906006347;5141.485851414828;5390.622656414013;5556.305461817273;5626.718664066862 20:30:01 INFO - PROCESS | 1635 | |5;yui.html;728.5737843361633;734.7318228846473;740.6592967235072;771.3096778383347;829.6930135849736;14841.851581484181;15521.456749013787;15547.922260388683;15549.06705597665;15569.14369709667;14097.859021409782;14283.428662853474;14284.000119991604;14302;14327.140371577712;671.5064427645676;694.1427337238532;695.5826504097552;842.7219017724136;850.086157380815;852.7654894903889;857.5112186053951;871.930245580352;874.1739056591524;875.347865839947;920.2040235196574;924.5931790012406;926.4256161180077;929.6095639831287;929.8186853563558;713.9464540159457;735.4043224987777;925.3846192282158;925.8564922437033;928.475411392561;662.261578340152;684.0525871667726;688.686647575352;689.6551724137931;692.6571347183177;13713.382897769625;13717;13990;14196.219207943559;14261.572152835432;13086.018548608892;13153.210807351494;13178.802317965237;13180.53868114621;13183.670408239775;12147.635570932885;12263.69340766479;12269.447874845564;12473.126881118325;12664;4333.804978775963;4407.955920440819;4482.968917149074;4503.594676479132;4510.458744950594;95660.52169739177;98251.50874245583;102147;105298.47350763273;109548.90451095546;10129.139023183112;10374.066334029974;10417.687469375931;10418.062374386342;10828;21342.78657213439;21556.568868622853;21693;21736.565268694536;21911.01400436984;7784.338331241795;7810.0237470316215;7885.605719713992;7894.921050789533;7912.525248484994;4345.391645169679;4395.7362558246605;4406.911861762811;4407.669424793089;4513.81944722214;3471.4792781082533;3599.9280014399565;3755.0987762936697;3933.331333673282;3936.9015774605864;670.1823774994499;710.332287649618;713.4613366908055;723.6973447793949;725.818545363659;460.5118574311174;555.2531844668914;555.5611067256822;607.7872744539376;642.8974309059985;8886.822263554694;8972.955135224413;9131.223845973034;9435.716928492157;9443.669471568463;581.3517927510795;607.6931149769338;640.9647469389135;653.2585515439915;655.2170156662837;4269.487661480643;4297.462817147856;4526.456825180933;4557.498675145731;4580.244259697112;102036;104291.4785426068;104571.4771426138;105923;108225;61188.694056529435;95925.52037239769;98493.50753246188;98557.01442985478;99228;68664;83291;86987;87690.56154719186;87813.56093219493;7331.596762178131;8816.382853200472;8854.24738897201;8909.775742026297;8969.192772650493;35744.82127589345;45442.40951566679;52382.69043273949;53073.61168747493;55930.720346398004;61275;67168.6641566789;67942.66028669824;70908.64545677238;72177.6391118041;11984.520619175135;12607.023203871448;12907.935460322638;13183.813456788936;13637.318134093453;14669.92665036668;14788.964772465824;14913.95602307828;15192.544223673307;15342.386304547694;13165.53920612774;13753.79369309441;14266.928665356607;14341.56975290743;14593.343299551758;10372.948135259578;11724.237924534878;13811.309434528033;13882;13963.720725585228;13327.533536326182;13637.659058523219;13697.178169309873;13840.238786866415;14214.147151170962;10717.089047430743;10771.838422423512;10888.401137937173;11046.061084807881;11166.944165279121;9866.421372580353;10709.785804283714;11432.371219583003;11522.654320370402;11564.826527601925 20:30:01 INFO - PROCESS | 1635 | __end_tp_report 20:30:01 INFO - PROCESS | 1635 | __start_cc_report 20:30:01 INFO - PROCESS | 1635 | _x_x_mozilla_cycle_collect,1400 20:30:01 INFO - PROCESS | 1635 | __end_cc_report 20:30:01 INFO - PROCESS | 1635 | __startTimestamp1482467401145__endTimestamp 20:30:01 INFO - PROCESS | 1635 | 20:30:01 INFO - PROCESS | 1635 | ------- Summary: start ------- 20:30:01 INFO - PROCESS | 1635 | Number of tests: 6 20:30:01 INFO - PROCESS | 1635 | 20:30:01 INFO - PROCESS | 1635 | [#0] dojo.html Cycles:165 Average:5688.76 Median:5814.36 stddev:632.93 (10.9%) stddev-sans-first:607.21 20:30:01 INFO - PROCESS | 1635 | Values: 3329.6 3340.8 3369.6 3377.4 3408.4 5344.9 5534.1 5616.1 5620.6 5677.9 5592.6 5612.4 5647.8 5711.3 5723.9 5034.4 5089.0 5102.3 5120.6 5149.4 4608.9 5210.6 5260.2 5260.9 5261.4 5104.1 5139.4 5223.6 5236.3 5261.6 5189.4 5201.1 5204.7 5210.9 5222.8 5065.5 5141.4 5147.8 5160.8 5162.3 5646.3 5652.7 5666.2 5668.6 5683.9 6104.5 6108.6 6121.2 6128.4 6139.4 6146.6 6155.0 6162.3 6234.0 6275.9 5717.9 5748.6 5750.8 5752.6 5753.7 6311.6 6332.4 6340.2 6345.0 6357.9 5900.6 5969.0 5986.9 6017.0 6099.5 5983.4 6189.9 6203.5 6225.9 6229.8 5923.4 6030.0 6081.2 6104.6 6143.0 6188.5 6190.4 6192.2 6204.8 6208.3 4814.2 4848.0 4866.2 4913.6 4948.0 4325.0 4346.6 4367.5 4384.7 4413.8 5702.5 5710.7 5715.2 5723.3 5724.4 6099.4 6222.2 6242.1 6242.5 6251.3 5628.0 5691.8 5693.9 5735.9 5757.8 5309.8 5366.5 5375.3 5380.2 5392.5 6307.7 6343.9 6353.1 6354.4 6355.8 6101.9 6137.1 6139.8 6146.8 6250.4 6255.8 6259.7 6287.5 6296.5 6312.4 6144.3 6144.9 6154.2 6168.2 6172.9 5767.3 6121.3 6123.4 6166.9 6246.7 5977.3 5998.6 6021.1 6072.0 6105.9 5858.1 6005.2 6044.7 6052.4 6057.8 5672.9 5773.0 5814.9 5849.1 5852.1 5877.8 6051.0 6060.7 6076.8 6089.8 5767.1 5774.3 5805.7 5813.4 5814.4 20:30:01 INFO - PROCESS | 1635 | 20:30:01 INFO - PROCESS | 1635 | [#1] ext.html Cycles:180 Average:64667.71 Median:19076.53 stddev:137119.02 (718.8%) stddev-sans-first:137418.84 20:30:01 INFO - PROCESS | 1635 | Values: 427.3 431.9 449.2 511.9 514.1 30141.5 38647.2 40231.8 40289.2 40315.8 41840.8 42123.4 42204.8 42295.0 42295.4 8319.0 8566.8 9337.4 9586.8 9589.0 11052.7 11345.3 11387.6 11548.0 11549.7 11394.2 11463.7 11473.7 11477.5 11497.2 11141.9 11183.0 11199.4 11213.6 11219.7 11216.7 11295.4 11309.1 11311.6 11337.5 38681.0 38705.0 39107.2 39191.6 39578.0 3235.1 3260.2 3340.1 3383.4 3465.4 27514.7 27525.6 28617.4 28629.0 28721.3 3098.4 3258.6 3301.4 3313.1 3314.4 443879.0 444108.0 444792.0 445679.0 446981.5 52278.0 52606.0 52825.2 52836.5 52898.9 54586.2 54611.0 54670.0 54717.2 54770.7 14671.8 14933.0 14944.6 14971.4 15011.5 10476.2 10679.3 10703.5 11350.6 11555.7 12989.7 14407.9 15937.6 16834.8 16860.8 5200.0 7234.2 7253.6 7255.9 7272.9 828.2 888.6 984.7 985.7 989.6 29213.9 29278.0 29719.4 29953.9 30011.9 1039.7 1118.0 1126.5 1148.2 1216.3 15031.4 15184.3 15344.0 15717.3 15728.8 522226.0 525989.0 527568.0 528376.0 529898.0 58064.0 58309.4 58447.4 58514.5 58626.0 1200.7 1361.4 1370.3 1400.9 1401.5 26074.9 26631.5 28846.6 29570.9 29629.6 47777.5 48204.3 48235.8 48350.0 48362.8 560602.0 563881.0 565786.0 566054.0 567299.0 25073.6 25702.9 25712.6 25888.4 26049.8 45868.0 46574.8 46718.1 47044.9 47184.8 36545.5 36659.9 37167.9 37659.2 38164.0 38210.9 38595.0 39296.6 39359.4 39982.6 34614.5 36974.3 37778.7 37817.8 38078.6 32946.8 33936.0 34030.7 34345.7 34687.7 20388.8 24713.5 24879.9 25356.4 25850.9 20:30:01 INFO - PROCESS | 1635 | 20:30:01 INFO - PROCESS | 1635 | [#2] jquery.html Cycles:180 Average:33873.58 Median:2280.31 stddev:95303.38 (4179.4%) stddev-sans-first:95537.49 20:30:01 INFO - PROCESS | 1635 | Values: 350.9 365.6 371.2 373.5 395.8 14809.4 14873.9 16231.1 20623.3 20645.9 16768.5 17819.0 19695.2 20827.0 20994.0 5466.6 5781.4 5855.7 5892.8 5894.8 4724.6 5769.3 5795.1 5817.6 5826.0 5614.1 5747.7 5773.5 5773.5 5776.3 5617.9 5752.5 5772.7 5849.1 5849.2 4898.5 5000.0 5111.5 5111.7 5113.7 18807.4 24395.9 24661.0 24828.9 25160.4 1805.8 2170.4 2205.4 2355.2 2952.4 14252.4 15089.6 15215.1 15398.8 16157.9 1788.6 1846.0 1851.9 1879.1 1910.9 111677.4 115222.0 115373.0 116247.0 117454.0 10188.1 10414.4 10503.7 10713.4 11255.2 13300.7 13320.2 13324.4 13325.1 13343.2 8029.6 8209.5 8284.5 8381.0 8467.3 8259.1 8274.5 8317.0 8478.5 8511.0 6405.0 6510.4 6521.5 6542.3 6552.5 1794.0 1800.9 1801.3 1811.4 1813.2 843.2 865.3 880.7 981.8 1001.2 15120.7 17558.8 21578.5 21749.5 21828.7 947.8 952.0 961.5 982.7 1044.5 11035.2 11155.1 11849.9 11923.4 12255.8 564247.0 567675.8 570984.0 575811.1 576633.0 88729.0 96043.5 118172.0 133217.0 134147.7 1009.7 1048.4 1077.6 1107.4 1110.5 14523.7 14526.2 19055.7 19081.6 19271.8 50872.7 53171.7 54140.7 57744.0 58529.0 82788.0 83017.0 83084.0 83353.0 84398.0 7159.4 7813.3 7929.0 8234.8 8414.5 9143.7 12248.9 12698.8 13042.0 13111.3 9450.0 11231.9 11485.8 11674.9 11715.3 10588.7 11035.6 11088.4 11133.3 11739.6 11579.0 11616.5 11815.4 11866.6 11928.6 7882.8 7895.2 7903.0 8393.3 8408.2 8313.6 10414.4 11209.6 11544.3 11558.1 20:30:01 INFO - PROCESS | 1635 | 20:30:01 INFO - PROCESS | 1635 | [#3] mootools.html Cycles:180 Average:5360.60 Median:5040.73 stddev:2929.69 (58.1%) stddev-sans-first:2922.38 20:30:01 INFO - PROCESS | 1635 | Values: 1347.7 1461.4 1507.7 1706.4 1903.2 7433.5 7838.2 8128.1 8376.6 8388.0 8209.1 8220.5 8236.9 8365.0 8370.5 2041.1 2265.8 2317.6 2317.7 2317.8 2079.4 2251.2 2333.3 2347.0 2349.5 2269.0 2288.9 2292.9 2301.6 2301.7 2264.1 2291.7 2294.5 2296.6 2297.8 2259.4 2288.0 2323.5 2326.7 2329.5 8330.4 8391.8 8397.7 8401.7 8405.0 5024.6 5191.4 5202.1 5214.0 5215.4 3320.8 3394.1 3475.0 3545.9 3547.4 1029.5 1057.8 1064.2 1069.4 1080.7 10325.2 10600.9 10620.9 10814.7 10820.8 4311.5 4386.3 4413.9 4414.9 4425.3 8628.4 8632.5 8633.7 8642.1 8648.3 5038.4 5043.1 5043.7 5044.5 5060.9 4231.1 4377.1 4530.4 4532.1 4643.7 3558.0 3609.0 3659.2 3667.1 3705.6 1129.6 1133.0 1134.6 1147.3 1153.5 757.7 887.8 895.0 899.6 911.1 5199.0 5555.7 5568.7 5804.7 5917.7 850.4 978.8 1001.9 1004.7 1067.2 3579.1 3692.3 3742.7 3763.6 3773.8 9847.1 10035.1 10093.2 10184.8 10193.0 9413.7 9854.0 9863.3 10116.9 10138.7 7640.6 7875.1 7878.6 7895.3 7903.5 5382.9 5396.7 5494.6 5671.1 5949.8 9147.3 9294.2 9422.9 9445.3 9456.6 8944.7 9361.7 9365.6 9480.3 9522.9 6527.4 7079.3 7238.8 7246.4 7266.0 6339.9 7347.4 7542.7 7590.2 7670.5 6858.9 6880.3 6919.8 7039.7 7187.7 6829.8 6903.6 6913.8 6920.8 6940.4 6508.1 6598.0 6612.6 6685.5 6686.2 4710.8 4722.8 4762.0 4770.1 4798.9 4677.1 4783.9 4787.2 4794.2 4986.7 20:30:01 INFO - PROCESS | 1635 | 20:30:01 INFO - PROCESS | 1635 | [#4] prototype.html Cycles:180 Average:4475.51 Median:5018.60 stddev:1392.10 (27.7%) stddev-sans-first:1360.47 20:30:01 INFO - PROCESS | 1635 | Values: 311.9 342.3 353.0 355.3 366.4 4511.2 5259.5 5383.2 5462.9 5480.0 4976.9 5222.4 5386.9 5413.8 5433.8 3178.6 3820.7 4120.9 4131.3 4131.8 3445.7 3786.4 4201.4 4211.4 4219.6 4152.7 4169.4 4198.3 4202.3 4231.8 3044.5 3380.5 3863.2 3904.9 3986.8 3230.7 3267.9 3687.6 3698.6 3702.6 4752.6 5268.3 5286.3 5293.3 5319.3 4231.0 4697.0 4728.5 4778.8 4835.8 5686.8 5696.7 5700.5 5701.5 5726.3 3223.6 3587.9 3656.9 4039.3 4122.6 5411.9 5795.6 5866.8 5871.9 6011.7 3412.9 3506.4 3817.6 3877.2 3893.5 3592.7 3918.5 3924.4 3928.4 3929.4 3810.8 4307.0 4552.9 4557.7 4570.2 4458.3 5051.9 5255.0 5681.2 5726.6 1157.9 1209.5 1274.5 1287.3 1292.9 775.9 819.5 821.8 864.0 877.4 4061.8 4610.4 4990.4 5228.7 5258.6 4815.0 4876.9 5679.6 5778.5 5782.3 5100.9 5170.8 5213.8 5272.0 5339.9 2479.6 2875.9 2894.5 2948.2 2953.7 4689.8 5154.8 5789.6 5822.6 5825.6 5313.9 5321.3 5610.9 5664.0 5741.5 4935.8 5081.9 5794.7 5812.0 5832.7 5009.7 5149.1 5635.5 5648.7 5695.6 5020.9 5136.5 5337.5 5509.6 5549.9 5016.3 5705.9 5775.6 5834.0 5877.5 3310.6 3319.9 3660.1 3660.2 3663.1 4611.9 4918.5 5541.5 5850.9 5909.8 4734.4 5007.7 5719.9 5736.5 5738.3 5221.0 5717.3 5720.9 5722.6 5734.9 4531.3 5230.4 5288.4 5560.6 5645.8 4889.6 5010.3 5024.6 5674.7 5750.6 4472.9 5141.5 5390.6 5556.3 5626.7 20:30:01 INFO - PROCESS | 1635 | 20:30:01 INFO - PROCESS | 1635 | [#5] yui.html Cycles:180 Average:20502.79 Median:4445.46 stddev:29793.90 (670.2%) stddev-sans-first:29840.48 20:30:01 INFO - PROCESS | 1635 | Values: 728.6 734.7 740.7 771.3 829.7 14841.9 15521.5 15547.9 15549.1 15569.1 14097.9 14283.4 14284.0 14302.0 14327.1 671.5 694.1 695.6 842.7 850.1 852.8 857.5 871.9 874.2 875.3 920.2 924.6 926.4 929.6 929.8 713.9 735.4 925.4 925.9 928.5 662.3 684.1 688.7 689.7 692.7 13713.4 13717.0 13990.0 14196.2 14261.6 13086.0 13153.2 13178.8 13180.5 13183.7 12147.6 12263.7 12269.4 12473.1 12664.0 4333.8 4408.0 4483.0 4503.6 4510.5 95660.5 98251.5 102147.0 105298.5 109548.9 10129.1 10374.1 10417.7 10418.1 10828.0 21342.8 21556.6 21693.0 21736.6 21911.0 7784.3 7810.0 7885.6 7894.9 7912.5 4345.4 4395.7 4406.9 4407.7 4513.8 3471.5 3599.9 3755.1 3933.3 3936.9 670.2 710.3 713.5 723.7 725.8 460.5 555.3 555.6 607.8 642.9 8886.8 8973.0 9131.2 9435.7 9443.7 581.4 607.7 641.0 653.3 655.2 4269.5 4297.5 4526.5 4557.5 4580.2 102036.0 104291.5 104571.5 105923.0 108225.0 61188.7 95925.5 98493.5 98557.0 99228.0 68664.0 83291.0 86987.0 87690.6 87813.6 7331.6 8816.4 8854.2 8909.8 8969.2 35744.8 45442.4 52382.7 53073.6 55930.7 61275.0 67168.7 67942.7 70908.6 72177.6 11984.5 12607.0 12907.9 13183.8 13637.3 14669.9 14789.0 14914.0 15192.5 15342.4 13165.5 13753.8 14266.9 14341.6 14593.3 10372.9 11724.2 13811.3 13882.0 13963.7 13327.5 13637.7 13697.2 13840.2 14214.1 10717.1 10771.8 10888.4 11046.1 11166.9 9866.4 10709.8 11432.4 11522.7 11564.8 20:30:01 INFO - PROCESS | 1635 | -------- Summary: end -------- 20:30:01 INFO - PROCESS | 1635 | 20:30:01 INFO - TEST-INFO | 1635: exit 0 20:30:01 INFO - TEST-OK | dromaeo_css | took 1121049ms 20:30:01 INFO - TEST-START | kraken 20:30:01 INFO - operating with platform_type : mac_ 20:30:01 INFO - Initialising browser for kraken test... 20:30:01 INFO - TEST-INFO | started process 1646 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/T/tmp4s6Xxq/profile http://localhost:49246/getInfo.html) 20:30:02 INFO - PROCESS | 1646 | 1482467402358 addons.xpi WARN Exception running bootstrap method install on shield-recipe-client@mozilla.org: [Exception... "Component returned failure code: 0x8000ffff (NS_ERROR_UNEXPECTED) [nsIPrefBranch.getBoolPref]" nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js :: this.install :: line 38" data: no] Stack trace: this.install()@resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js:38 < callBootstrapMethod()@resource://gre/modules/addons/XPIProvider.jsm:4979 < processFileChanges()@resource://gre/modules/addons/XPIProvider.jsm -> resource://gre/modules/addons/XPIProviderUtils.js:2097 < checkForChanges()@resource://gre/modules/addons/XPIProvider.jsm:3825 < startup()@resource://gre/modules/addons/XPIProvider.jsm:2829 < callProvider()@resource://gre/modules/AddonManager.jsm:264 < _startProvider()@resource://gre/modules/AddonManager.jsm:771 < startup()@resource://gre/modules/AddonManager.jsm:957 < startup()@resource://gre/modules/AddonManager.jsm:2923 < observe()@resource://gre/components/addonManager.js:65 20:30:04 INFO - PROCESS | 1646 | __metrics Screen width/height:1600/1200 20:30:04 INFO - PROCESS | 1646 | colorDepth:24 20:30:04 INFO - PROCESS | 1646 | Browser inner width/height: 1024/689 20:30:04 INFO - PROCESS | 1646 | __metrics 20:30:04 INFO - PROCESS | 1646 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 20:30:04 INFO - PROCESS | 1646 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 20:30:05 INFO - TEST-INFO | 1646: exit 0 20:30:05 INFO - Browser initialized. 20:30:05 INFO - Running cycle 1/1 for kraken test... 20:30:05 INFO - TEST-INFO | started process 1647 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/T/tmp4s6Xxq/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 20:30:17 INFO - PROCESS | 1647 | RSS: Main: 234418176 20:30:17 INFO - PROCESS | 1647 | 20:30:19 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49246/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49246/tests/kraken/driver.html?testName=audio-beat-detection) 20:30:20 INFO - PROCESS | 1647 | RSS: Main: 287334400 20:30:20 INFO - PROCESS | 1647 | 20:30:23 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49246/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49246/tests/kraken/driver.html?testName=audio-dft) 20:30:23 INFO - PROCESS | 1647 | RSS: Main: 288120832 20:30:23 INFO - PROCESS | 1647 | 20:30:27 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49246/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49246/tests/kraken/driver.html?testName=audio-fft) 20:30:28 INFO - PROCESS | 1647 | RSS: Main: 380514304 20:30:28 INFO - PROCESS | 1647 | 20:30:30 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49246/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49246/tests/kraken/driver.html?testName=audio-oscillator) 20:30:30 INFO - PROCESS | 1647 | RSS: Main: 282935296 20:30:30 INFO - PROCESS | 1647 | 20:30:32 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49246/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49246/tests/kraken/driver.html?testName=imaging-gaussian-blur) 20:30:33 INFO - PROCESS | 1647 | RSS: Main: 287387648 20:30:33 INFO - PROCESS | 1647 | 20:30:36 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49246/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49246/tests/kraken/driver.html?testName=imaging-darkroom) 20:30:36 INFO - PROCESS | 1647 | RSS: Main: 329187328 20:30:36 INFO - PROCESS | 1647 | 20:30:39 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49246/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49246/tests/kraken/driver.html?testName=imaging-desaturate) 20:30:40 INFO - PROCESS | 1647 | RSS: Main: 331288576 20:30:40 INFO - PROCESS | 1647 | 20:30:42 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49246/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49246/tests/kraken/driver.html?testName=json-parse-financial) 20:30:43 INFO - PROCESS | 1647 | RSS: Main: 326471680 20:30:43 INFO - PROCESS | 1647 | 20:30:45 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49246/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49246/tests/kraken/driver.html?testName=json-stringify-tinderbox) 20:30:45 INFO - PROCESS | 1647 | RSS: Main: 304152576 20:30:45 INFO - PROCESS | 1647 | 20:30:47 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49246/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49246/tests/kraken/driver.html?testName=stanford-crypto-aes) 20:30:48 INFO - PROCESS | 1647 | RSS: Main: 307757056 20:30:48 INFO - PROCESS | 1647 | 20:30:50 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49246/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49246/tests/kraken/driver.html?testName=stanford-crypto-ccm) 20:30:51 INFO - PROCESS | 1647 | RSS: Main: 309915648 20:30:51 INFO - PROCESS | 1647 | 20:30:54 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49246/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49246/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 20:30:54 INFO - PROCESS | 1647 | RSS: Main: 322412544 20:30:54 INFO - PROCESS | 1647 | 20:30:57 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49246/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49246/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 20:30:58 INFO - PROCESS | 1647 | RSS: Main: 316985344 20:30:58 INFO - PROCESS | 1647 | 20:31:00 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49246/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49246/tests/kraken/driver.html?testName=ai-astar) 20:31:00 INFO - PROCESS | 1647 | RSS: Main: 361058304 20:31:00 INFO - PROCESS | 1647 | 20:31:00 INFO - PROCESS | 1647 | __start_tp_report 20:31:00 INFO - PROCESS | 1647 | _x_x_mozilla_page_load 20:31:00 INFO - PROCESS | 1647 | _x_x_mozilla_page_load_details 20:31:00 INFO - PROCESS | 1647 | |i|pagename|runs| 20:31:00 INFO - PROCESS | 1647 | |0;ai-astar;114;113;113;113;117;114;112;113;113;112 20:31:00 INFO - PROCESS | 1647 | |1;audio-beat-detection;147;111;110;148;113;110;110;110;130;112 20:31:00 INFO - PROCESS | 1647 | |2;audio-dft;188;180;182;194;258;181;178;185;183;181 20:31:00 INFO - PROCESS | 1647 | |3;audio-fft;78;59;58;88;62;58;61;57;74;59 20:31:00 INFO - PROCESS | 1647 | |4;audio-oscillator;89;71;85;69;69;70;83;69;69;85 20:31:00 INFO - PROCESS | 1647 | |5;imaging-gaussian-blur;90;86;90;86;89;86;89;87;90;85 20:31:00 INFO - PROCESS | 1647 | |6;imaging-darkroom;95;94;93;95;94;94;94;95;94;95 20:31:00 INFO - PROCESS | 1647 | |7;imaging-desaturate;84;85;82;89;88;87;85;85;95;87 20:31:00 INFO - PROCESS | 1647 | |8;json-parse-financial;56;55;55;54;54;54;54;56;54;56 20:31:00 INFO - PROCESS | 1647 | |9;json-stringify-tinderbox;67;69;81;73;74;69;70;72;70;71 20:31:00 INFO - PROCESS | 1647 | |10;stanford-crypto-aes;86;87;86;87;85;87;90;85;87;90 20:31:00 INFO - PROCESS | 1647 | |11;stanford-crypto-ccm;146;157;147;137;140;141;138;140;187;173 20:31:00 INFO - PROCESS | 1647 | |12;stanford-crypto-pbkdf2;204;176;176;173;174;183;239;199;179;172 20:31:00 INFO - PROCESS | 1647 | |13;stanford-crypto-sha256-iterative;68;68;68;68;67;67;65;67;66;66 20:31:00 INFO - PROCESS | 1647 | __end_tp_report 20:31:00 INFO - PROCESS | 1647 | __start_cc_report 20:31:00 INFO - PROCESS | 1647 | _x_x_mozilla_cycle_collect,2037 20:31:00 INFO - PROCESS | 1647 | __end_cc_report 20:31:00 INFO - PROCESS | 1647 | __startTimestamp1482467460554__endTimestamp 20:31:00 INFO - PROCESS | 1647 | 20:31:00 INFO - PROCESS | 1647 | ------- Summary: start ------- 20:31:00 INFO - PROCESS | 1647 | Number of tests: 14 20:31:00 INFO - PROCESS | 1647 | 20:31:00 INFO - PROCESS | 1647 | [#0] ai-astar Cycles:10 Average:113.40 Median:113.00 stddev:1.43 (1.3%) stddev-sans-first:1.50 20:31:00 INFO - PROCESS | 1647 | Values: 114.0 113.0 113.0 113.0 117.0 114.0 112.0 113.0 113.0 112.0 20:31:00 INFO - PROCESS | 1647 | 20:31:00 INFO - PROCESS | 1647 | [#1] audio-beat-detection Cycles:10 Average:120.10 Median:112.50 stddev:15.66 (13.9%) stddev-sans-first:13.24 20:31:00 INFO - PROCESS | 1647 | Values: 147.0 111.0 110.0 148.0 113.0 110.0 110.0 110.0 130.0 112.0 20:31:00 INFO - PROCESS | 1647 | 20:31:00 INFO - PROCESS | 1647 | [#2] audio-dft Cycles:10 Average:191.00 Median:184.00 stddev:23.99 (13.0%) stddev-sans-first:25.42 20:31:00 INFO - PROCESS | 1647 | Values: 188.0 180.0 182.0 194.0 258.0 181.0 178.0 185.0 183.0 181.0 20:31:00 INFO - PROCESS | 1647 | 20:31:00 INFO - PROCESS | 1647 | [#3] audio-fft Cycles:10 Average:65.40 Median:61.50 stddev:10.73 (17.4%) stddev-sans-first:10.37 20:31:00 INFO - PROCESS | 1647 | Values: 78.0 59.0 58.0 88.0 62.0 58.0 61.0 57.0 74.0 59.0 20:31:00 INFO - PROCESS | 1647 | 20:31:00 INFO - PROCESS | 1647 | [#4] audio-oscillator Cycles:10 Average:75.90 Median:77.00 stddev:8.41 (10.9%) stddev-sans-first:7.47 20:31:00 INFO - PROCESS | 1647 | Values: 89.0 71.0 85.0 69.0 69.0 70.0 83.0 69.0 69.0 85.0 20:31:00 INFO - PROCESS | 1647 | 20:31:00 INFO - PROCESS | 1647 | [#5] imaging-gaussian-blur Cycles:10 Average:87.80 Median:89.00 stddev:1.99 (2.2%) stddev-sans-first:1.94 20:31:00 INFO - PROCESS | 1647 | Values: 90.0 86.0 90.0 86.0 89.0 86.0 89.0 87.0 90.0 85.0 20:31:00 INFO - PROCESS | 1647 | 20:31:00 INFO - PROCESS | 1647 | [#6] imaging-darkroom Cycles:10 Average:94.30 Median:94.50 stddev:0.67 (0.7%) stddev-sans-first:0.67 20:31:00 INFO - PROCESS | 1647 | Values: 95.0 94.0 93.0 95.0 94.0 94.0 94.0 95.0 94.0 95.0 20:31:00 INFO - PROCESS | 1647 | 20:31:00 INFO - PROCESS | 1647 | [#7] imaging-desaturate Cycles:10 Average:86.70 Median:87.00 stddev:3.56 (4.1%) stddev-sans-first:3.64 20:31:00 INFO - PROCESS | 1647 | Values: 84.0 85.0 82.0 89.0 88.0 87.0 85.0 85.0 95.0 87.0 20:31:00 INFO - PROCESS | 1647 | 20:31:00 INFO - PROCESS | 1647 | [#8] json-parse-financial Cycles:10 Average:54.80 Median:55.00 stddev:0.92 (1.7%) stddev-sans-first:0.87 20:31:00 INFO - PROCESS | 1647 | Values: 56.0 55.0 55.0 54.0 54.0 54.0 54.0 56.0 54.0 56.0 20:31:00 INFO - PROCESS | 1647 | 20:31:00 INFO - PROCESS | 1647 | [#9] json-stringify-tinderbox Cycles:10 Average:71.60 Median:71.50 stddev:3.89 (5.4%) stddev-sans-first:3.76 20:31:00 INFO - PROCESS | 1647 | Values: 67.0 69.0 81.0 73.0 74.0 69.0 70.0 72.0 70.0 71.0 20:31:00 INFO - PROCESS | 1647 | 20:31:00 INFO - PROCESS | 1647 | [#10] stanford-crypto-aes Cycles:10 Average:87.00 Median:87.00 stddev:1.76 (2.0%) stddev-sans-first:1.83 20:31:00 INFO - PROCESS | 1647 | Values: 86.0 87.0 86.0 87.0 85.0 87.0 90.0 85.0 87.0 90.0 20:31:00 INFO - PROCESS | 1647 | 20:31:00 INFO - PROCESS | 1647 | [#11] stanford-crypto-ccm Cycles:10 Average:150.60 Median:146.50 stddev:16.87 (11.5%) stddev-sans-first:17.81 20:31:00 INFO - PROCESS | 1647 | Values: 146.0 157.0 147.0 137.0 140.0 141.0 138.0 140.0 187.0 173.0 20:31:00 INFO - PROCESS | 1647 | 20:31:00 INFO - PROCESS | 1647 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:187.50 Median:181.00 stddev:21.20 (11.7%) stddev-sans-first:21.63 20:31:00 INFO - PROCESS | 1647 | Values: 204.0 176.0 176.0 173.0 174.0 183.0 239.0 199.0 179.0 172.0 20:31:00 INFO - PROCESS | 1647 | 20:31:00 INFO - PROCESS | 1647 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.00 Median:67.50 stddev:1.05 (1.6%) stddev-sans-first:1.05 20:31:00 INFO - PROCESS | 1647 | Values: 68.0 68.0 68.0 68.0 67.0 67.0 65.0 67.0 66.0 66.0 20:31:00 INFO - PROCESS | 1647 | -------- Summary: end -------- 20:31:00 INFO - PROCESS | 1647 | 20:31:01 INFO - TEST-INFO | 1647: exit 0 20:31:01 INFO - TEST-OK | kraken | took 59591ms 20:31:01 INFO - SUITE-END | took 1180s 20:31:01 INFO - Completed test suite (00:19:40) 20:31:01 INFO - javascript benchmark 20:31:01 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3329.633740288568, 3340.7661463697536, 3369.629340772513, 3377.4258376076064, 3408.386490431723, 5344.931013797242, 5534.059209934311, 5616.101423772192, 5620.57845661575, 5677.886442271152, 5592.5805564582715, 5612.41069687683, 5647.774089036444, 5711.343195532515, 5723.942760572393, 5034.421041580228, 5089.007643509517, 5102.260172275018, 5120.615953803461, 5149.382074151107, 4608.861734147981, 5210.557102646279, 5260.237265596486, 5260.92108618371, 5261.421243663194, 5104.055749686283, 5139.357580302462, 5223.555997740196, 5236.345456817898, 5261.631685781997, 5189.377274727057, 5201.141811601081, 5204.687718736888, 5210.947890521122, 5222.764975576109, 5065.518775716338, 5141.408737995103, 5147.7683504242395, 5160.793568257265, 5162.303089082963, 5646.322441307028, 5652.689102099383, 5666.150077488369, 5668.603197776116, 5683.914741278884, 6104.519788040277, 6108.623149146864, 6121.219063936594, 6128.3503509891225, 6139.447449729501, 6146.569740118193, 6155.045967874987, 6162.260528736536, 6233.968830155865, 6275.905861412082, 5717.942820571824, 5748.597598168171, 5750.769969201227, 5752.568557358215, 5753.7123143843055, 6311.558190926683, 6332.430081292614, 6340.207474065742, 6344.968275158594, 6357.936420635734, 5900.586958912835, 5969.015112506475, 5986.910196347058, 6016.969915150396, 6099.481544068715, 5983.371745966698, 6189.907151392733, 6203.47270482005, 6225.875482490324, 6229.813105606839, 5923.437273459014, 6029.969850150721, 6081.209442772411, 6104.580421837809, 6143.047827586731, 6188.4739797117745, 6190.380961903864, 6192.195014648157, 6204.81385558434, 6208.286047104641, 4814.20565606678, 4847.95152048482, 4866.172750632401, 4913.582345500601, 4948.035133149001, 4324.962009117789, 4346.630536404441, 4367.497737760134, 4384.714993525411, 4413.757243351646, 5702.45826646468, 5710.65736055838, 5715.1713001614535, 5723.255976723, 5724.398938111522, 6099.3900609938655, 6222.191115155001, 6242.1261023456755, 6242.531810114259, 6251.343608921089, 5628.0, 5691.8292451226525, 5693.857653558694, 5735.885282294331, 5757.769689212385, 5309.814156504581, 5366.490183432567, 5375.301210842644, 5380.219868119101, 5392.4607539246545, 6307.684615769285, 6343.904841427475, 6353.078803573363, 6354.4281014708895, 6355.777547785805, 6101.908471373022, 6137.110119032626, 6139.815805525842, 6146.78486252979, 6250.4374606285655, 6255.843603909939, 6259.6870156492905, 6287.528435367457, 6296.527760418078, 6312.368763123651, 6144.293406258159, 6144.938550614615, 6154.19995400613, 6168.167297414791, 6172.876542469216, 5767.307923049261, 6121.270068921507, 6123.357047509948, 6166.907496387647, 6246.6876656166105, 5977.282726072899, 5998.550108741774, 6021.066734656135, 6072.0, 6105.908411373922, 5858.121281807763, 6005.249343832021, 6044.7279872406725, 6052.364501727256, 6057.787977420945, 5672.943270567406, 5772.9711351442975, 5814.854628634318, 5849.1226316052935, 5852.122181672783, 5877.823665290048, 6051.0, 6060.727267272895, 6076.848078797889, 6089.756409743561, 5767.134929760569, 5774.307083150049, 5805.680687562224, 5813.447722466359, 5814.360420353673], "value": 5647.846034699396, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [427.3162939297125, 431.86828017454684, 449.22060225508756, 511.9078565858144, 514.1080225808223, 30141.54787678184, 38647.227055458876, 40231.79884100575, 40289.194216115735, 40315.79842100785, 41840.79079604598, 42123.368149477785, 42204.788976055155, 42295.0, 42295.365569516405, 8319.043310019335, 8566.78583035426, 9337.393069450482, 9586.808263834719, 9589.0, 11052.723681907935, 11345.26255793375, 11387.601433949814, 11548.0, 11549.6535103947, 11394.202405831595, 11463.713407164845, 11473.655790326262, 11477.540898364055, 11497.195196336259, 11141.944290278538, 11183.049440797542, 11199.384033878134, 11213.589078348114, 11219.66341009771, 11216.719582010433, 11295.435228238555, 11309.09527237819, 11311.604093856758, 11337.489812958436, 38681.0, 38705.03309404306, 39107.217855642724, 39191.60808391908, 39578.0, 3235.110344655215, 3260.152360386293, 3340.1148695595684, 3383.424817780983, 3465.4108801503803, 27514.724852751417, 27525.587116193274, 28617.427651447062, 28629.0, 28721.28196795076, 3098.411301852663, 3258.6415494295607, 3301.4387554115856, 3313.0557791029682, 3314.419976504126, 443879.0, 444108.0, 444792.0, 445679.0, 446981.4763892721, 52277.954440910966, 52606.0, 52825.207621885704, 52836.471635283924, 52898.94202115936, 54586.18120728192, 54611.0, 54670.0, 54717.1792423106, 54770.726146369016, 14671.799230115355, 14933.0, 14944.626384340261, 14971.401143954337, 15011.474598389223, 10476.214283928735, 10679.252452328417, 10703.518341674644, 11350.602728904614, 11555.711107222387, 12989.675258118623, 14407.927960360132, 15937.601559961095, 16834.831651683573, 16860.819742618143, 5200.023990883516, 7234.204237533867, 7253.601051942193, 7255.854882902312, 7272.854542909112, 828.2110641015378, 888.5823662878437, 984.7095106943469, 985.6500942165571, 989.5843745626854, 29213.920613369744, 29278.0, 29719.40561188764, 29953.850230748707, 30011.94958176497, 1039.6880935719255, 1117.9944100279447, 1126.4987080748988, 1148.1618418554501, 1216.3127832774453, 15031.398744050115, 15184.316705748268, 15344.0, 15717.292721827545, 15728.842711572966, 522226.0, 525989.0, 527568.0, 528376.0, 529898.0, 58064.0, 58309.41690583125, 58447.4155258442, 58514.537136571074, 58626.0, 1200.7058270723728, 1361.4350044731523, 1370.3148425787106, 1400.908940918837, 1401.4674423719125, 26074.86962565175, 26631.467370652867, 28846.567301490075, 29570.852145739133, 29629.5555566671, 47777.522224777305, 48204.276935845286, 48235.75882120567, 48350.0, 48362.75818620884, 560602.0, 563881.0, 565786.0, 566054.0, 567299.0, 25073.62095084788, 25702.871485642452, 25712.614310785728, 25888.352791180372, 26049.82775775057, 45868.0, 46574.76712616415, 46718.065638686356, 47044.9432585115, 47184.7640761794, 36545.45181822222, 36659.90020299395, 37167.88496345114, 37659.24681506409, 38164.0, 38210.853674389815, 38595.0, 39296.60703392929, 39359.40960885532, 39982.60017399789, 34614.480782787774, 36974.26051479009, 37778.67774627875, 37817.81091094527, 38078.619213807506, 32946.84686035977, 33936.0, 34030.65969340275, 34345.656543435245, 34687.653123468444, 20388.796112039283, 24713.505729884942, 24879.875600621883, 25356.366090847878, 25850.870745646152], "value": 19428.117052855963, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [350.86316336628704, 365.5905385967716, 371.20191588085595, 373.5461414381526, 395.79023117747585, 14809.407623695053, 14873.925630371847, 16231.107289099109, 20623.27818526352, 20645.896770516127, 16768.496945091665, 17819.01995390253, 19695.21219151232, 20826.958652067413, 20994.0, 5466.590005749575, 5781.421857814206, 5855.736491857855, 5892.823215303548, 5894.764209431618, 4724.62203023759, 5769.3076830780365, 5795.072788353864, 5817.592768506206, 5825.96091368022, 5614.073677843151, 5747.741351639165, 5773.538116950634, 5773.538116950634, 5776.277965254343, 5617.943820561783, 5752.5110365618975, 5772.740226689788, 5849.122631605251, 5849.210356601847, 4898.485659005789, 4999.975000125012, 5111.539961403491, 5111.667741596784, 5113.718745468997, 18807.435776926577, 24395.878020609962, 24661.0135594576, 24828.875855620787, 25160.370990725194, 1805.8465030472425, 2170.3597438755605, 2205.4376134085705, 2355.187460326185, 2952.3652414730723, 14252.358643860947, 15089.622759430993, 15215.08709477435, 15398.769018464733, 16157.91921040399, 1788.624388878324, 1845.9815401845945, 1851.907404629763, 1879.135597625094, 1910.8757930734457, 111677.44161279142, 115222.0, 115373.0, 116247.0, 117454.0, 10188.134008609202, 10414.427206503715, 10503.684889453329, 10713.410762407986, 11255.212135150625, 13300.667483312995, 13320.20078795256, 13324.400401981935, 13325.13386629866, 13343.199408035549, 8029.558374289353, 8209.507429554245, 8284.544350060805, 8381.0, 8467.32261419085, 8259.132791056973, 8274.503529788231, 8317.0, 8478.5336806475, 8510.978682558132, 6404.967975160094, 6510.424471283943, 6521.47828173745, 6542.345765423514, 6552.475801935833, 1794.0, 1800.9009504477117, 1801.3245033112582, 1811.429399739078, 1813.238439855264, 843.2242337049929, 865.2688478235881, 880.7229517200085, 981.8478135888948, 1001.1790331927895, 15120.656298273785, 17558.82441175572, 21578.518470375795, 21749.456263593536, 21828.690278583337, 947.824652439301, 952.0431965874252, 961.4567769210374, 982.6708052802248, 1044.5038606661774, 11035.2275340727, 11155.051820595178, 11849.881501185051, 11923.403829808474, 12255.754884902251, 564247.0, 567675.8081047924, 570984.0, 575811.1209443926, 576633.0, 88729.0, 96043.51978240065, 118172.0, 133217.00174497196, 134147.65852341548, 1009.6668099527138, 1048.3866937841449, 1077.6389909380289, 1107.4186052322596, 1110.5113749949996, 14523.709525809214, 14526.20105894186, 19055.714164287823, 19081.61836763229, 19271.807281927002, 50872.74563627158, 53171.73414133059, 54140.72929635327, 57744.0, 58529.0, 82788.0, 83017.0, 83084.0, 83353.0, 84398.0, 7159.355657990805, 7813.296803287732, 7928.960355198187, 8234.835303294021, 8414.495130292202, 9143.725688229364, 12248.938755306166, 12698.809517857424, 13042.0, 13111.344432778515, 9450.0, 11231.887681123084, 11485.793991630759, 11674.94925456713, 11715.297082175097, 10588.729352477752, 11035.558577657124, 11088.445577721242, 11133.276337038391, 11739.647810565697, 11579.0, 11616.477258523555, 11815.40922953866, 11866.584669536525, 11928.642140735792, 7882.763517094496, 7895.210478952059, 7902.960485197537, 8393.28657064132, 8408.2432581068, 8313.584320784057, 10414.375137491776, 11209.55161793519, 11544.307341559534, 11558.133140014364], "value": 9538.867727849454, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1347.7371912477072, 1461.4373466215507, 1507.6984603079384, 1706.3686436018675, 1903.2482169543032, 7433.479656424046, 7838.216178382159, 8128.146544612812, 8376.62305196266, 8388.0, 8209.13804050575, 8220.547869867154, 8236.876446853303, 8364.958175209116, 8370.497770133781, 2041.1427200575795, 2265.8074063704603, 2317.6407656813135, 2317.7218733751974, 2317.8029867461287, 2079.4177630263475, 2251.223327951854, 2333.3000099970027, 2346.9882650586724, 2349.4713689419914, 2268.965965510519, 2288.874111923852, 2292.919747808827, 2301.631738921773, 2301.689271948282, 2264.1056782570877, 2291.667708182304, 2294.5410917816503, 2296.6440201768587, 2297.8046866016407, 2259.35608351619, 2287.954240915189, 2323.453988312745, 2326.6510023496444, 2329.464223228667, 8330.416870819045, 8391.790205244857, 8397.664093436255, 8401.705940292122, 8404.957975210085, 5024.598032157455, 5191.402988656315, 5202.115640341151, 5213.983273261715, 5215.400228973641, 3320.8007519548905, 3394.131022435619, 3475.0288503329793, 3545.8758943437115, 3547.3614749345106, 1029.490402250883, 1057.7567159553346, 1064.180580952662, 1069.3744159666526, 1080.7298175456135, 10325.173986081096, 10600.946995264974, 10620.893791062068, 10814.675559733221, 10820.837687434694, 4311.50417701962, 4386.276264416336, 4413.867583972486, 4414.933775993362, 4425.269830478, 8628.35287353451, 8632.525211113323, 8633.740987770378, 8642.135786421308, 8648.26489748366, 5038.370203724535, 5043.092243396224, 5043.722595257296, 5044.495550444926, 5060.898782024339, 4231.132617813341, 4377.058932329564, 4530.365748795171, 4532.070925460339, 4643.744594047293, 3557.982210088933, 3608.9819550902075, 3659.249853779969, 3667.0832291927018, 3705.6479634434686, 1129.6498085593328, 1133.0, 1134.6085600467825, 1147.2944139354238, 1153.4982282707049, 757.7347928225078, 887.8002449448823, 894.9910500894907, 899.5592159841631, 911.1389736698756, 5199.0, 5555.749991250404, 5568.6658800472105, 5804.709764511757, 5917.733701983421, 850.4429598612918, 978.8238117138841, 1001.8998100189923, 1004.6584161385164, 1067.1782727299935, 3579.1231148368815, 3692.316921369555, 3742.6631603155847, 3763.6236376362695, 3773.8301776420126, 9847.113759761656, 10035.09684128432, 10093.24300677452, 10184.796304073878, 10193.0, 9413.717588472357, 9853.950730246303, 9863.260255480867, 10116.898831011596, 10138.746531336628, 7640.617969101522, 7875.133735289113, 7878.566678832604, 7895.328897043815, 7903.525788452711, 5382.919256211082, 5396.730163491889, 5494.615376923577, 5671.092625179951, 5949.791757288474, 9147.268218542633, 9294.163525282756, 9422.905770942478, 9445.338826282094, 9456.621735130519, 8944.68693595721, 9361.719148425558, 9365.625374985197, 9480.288978326515, 9522.857157142787, 6527.445167160845, 7079.2566780487305, 7238.782836514913, 7246.405790726032, 7265.963670181615, 6339.936600634119, 7347.448941329529, 7542.736004239825, 7590.1650818411035, 7670.463067585216, 6858.931410685829, 6880.277570855188, 6919.792406227822, 7039.6832142552685, 7187.748428804966, 6829.795106146824, 6903.551269167488, 6913.827154321182, 6920.757773477904, 6940.41006514452, 6508.056331831953, 6598.0, 6612.603243805387, 6685.465162787063, 6686.231083425469, 4710.764461776829, 4722.787474563722, 4762.0, 4770.117528257145, 4798.880027999328, 4677.134730074947, 4783.880402989953, 4787.234042553175, 4794.188620047141, 4986.675866068694], "value": 4353.056036413667, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [311.8585975330292, 342.3119529745212, 353.03621114357804, 355.2947399412166, 366.40459253712714, 4511.165434394639, 5259.474052594738, 5383.159272189674, 5462.918056229159, 5480.0, 4976.909697926871, 5222.373315202171, 5386.852123864622, 5413.783448662059, 5433.809816656418, 3178.6319192201786, 3820.7134464915102, 4120.9285585747775, 4131.29767939451, 4131.814068366931, 3445.6898879100877, 3786.4131059685656, 4201.411802347674, 4211.368294755781, 4219.573745096566, 4152.688548358885, 4169.353750168729, 4198.349255865346, 4202.285611446062, 4231.830726770925, 3044.549137943954, 3380.4760262159402, 3863.208042351311, 3904.902377440558, 3986.7807270600106, 3230.6607806180245, 3267.901962941092, 3687.575928768199, 3698.5746639136573, 3702.611225821304, 4752.59602933751, 5268.262443257909, 5286.3392075990505, 5293.311869456985, 5319.255304257407, 4230.957690423118, 4697.0, 4728.452839149515, 4778.761061946923, 4835.806567737286, 5686.772529098831, 5696.715164241813, 5700.486956173964, 5701.543876489871, 5726.31284245889, 3223.564818749462, 3587.910302242439, 3656.9394875486078, 4039.313316736162, 4122.56713045129, 5411.891762164734, 5795.609053827051, 5866.823995280149, 5871.882562348772, 6011.669358185298, 3412.897613071612, 3506.3513250048823, 3817.5991520889906, 3877.2439374321734, 3893.493845800028, 3592.6766591006935, 3918.490596222473, 3924.3917192834583, 3928.4107383892647, 3929.4302326162547, 3810.790406527612, 4307.030918043415, 4552.931706024412, 4557.680962332671, 4570.223062079454, 4458.308962110878, 5051.898962020739, 5254.97372513135, 5681.204631351614, 5726.5683579105225, 1157.8768594463525, 1209.4980583057932, 1274.4833647958899, 1287.2726909296218, 1292.8642492538336, 775.8603451378806, 819.4755356571799, 821.7699044267615, 863.9524826134498, 877.4252864373718, 4061.8375264989068, 4610.423697037871, 4990.351254336985, 5228.712420816892, 5258.579313654898, 4814.975925120422, 4876.926846097312, 5679.574031947621, 5778.5377169826315, 5782.335031471434, 5100.872478188075, 5170.8190213343105, 5213.817516386984, 5271.973640131851, 5339.866503337447, 2479.6032634778708, 2875.9359037156387, 2894.4789937811397, 2948.248196709825, 2953.7341639252572, 4689.7655117244, 5154.829779685541, 5789.594728368974, 5822.563307751935, 5825.621334613236, 5313.937212557504, 5321.308229930161, 5610.92211548541, 5664.0, 5741.540676745851, 4935.802567897387, 5081.932794113203, 5794.710264486759, 5811.999999999915, 5832.737526811219, 5009.7244651544515, 5149.124648809637, 5635.464041181567, 5648.71756412186, 5695.572832037697, 5020.874478138076, 5136.457453560512, 5337.54630856366, 5509.559235261249, 5549.889002219852, 5016.319532956501, 5705.885882282414, 5775.639392258818, 5834.024647429756, 5877.529797616266, 3310.6027276725986, 3319.9170020749675, 3660.0784588091465, 3660.194757153422, 3663.0768846355877, 4611.939253971538, 4918.532739389752, 5541.473560011793, 5850.94149058504, 5909.763609455746, 4734.366643507984, 5007.7496125192865, 5719.885602287848, 5736.5046204901, 5738.254026976467, 5220.955808838324, 5717.313922329347, 5720.942790572041, 5722.570807189394, 5734.8780951048375, 4531.297648864432, 5230.372355317386, 5288.3918349390315, 5560.555155587463, 5645.7579332546775, 4889.608831293417, 5010.340383443287, 5024.618229986637, 5674.74463649145, 5750.6262092963825, 4472.932906006347, 5141.485851414828, 5390.622656414013, 5556.305461817273, 5626.718664066862], "value": 4040.793438919316, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [728.5737843361633, 734.7318228846473, 740.6592967235072, 771.3096778383347, 829.6930135849736, 14841.851581484181, 15521.456749013787, 15547.922260388683, 15549.06705597665, 15569.14369709667, 14097.859021409782, 14283.428662853474, 14284.000119991604, 14302.0, 14327.140371577712, 671.5064427645676, 694.1427337238532, 695.5826504097552, 842.7219017724136, 850.086157380815, 852.7654894903889, 857.5112186053951, 871.930245580352, 874.1739056591524, 875.347865839947, 920.2040235196574, 924.5931790012406, 926.4256161180077, 929.6095639831287, 929.8186853563558, 713.9464540159457, 735.4043224987777, 925.3846192282158, 925.8564922437033, 928.475411392561, 662.261578340152, 684.0525871667726, 688.686647575352, 689.6551724137931, 692.6571347183177, 13713.382897769625, 13717.0, 13990.0, 14196.219207943559, 14261.572152835432, 13086.018548608892, 13153.210807351494, 13178.802317965237, 13180.53868114621, 13183.670408239775, 12147.635570932885, 12263.69340766479, 12269.447874845564, 12473.126881118325, 12664.0, 4333.804978775963, 4407.955920440819, 4482.968917149074, 4503.594676479132, 4510.458744950594, 95660.52169739177, 98251.50874245583, 102147.0, 105298.47350763273, 109548.90451095546, 10129.139023183112, 10374.066334029974, 10417.687469375931, 10418.062374386342, 10828.0, 21342.78657213439, 21556.568868622853, 21693.0, 21736.565268694536, 21911.01400436984, 7784.338331241795, 7810.0237470316215, 7885.605719713992, 7894.921050789533, 7912.525248484994, 4345.391645169679, 4395.7362558246605, 4406.911861762811, 4407.669424793089, 4513.81944722214, 3471.4792781082533, 3599.9280014399565, 3755.0987762936697, 3933.331333673282, 3936.9015774605864, 670.1823774994499, 710.332287649618, 713.4613366908055, 723.6973447793949, 725.818545363659, 460.5118574311174, 555.2531844668914, 555.5611067256822, 607.7872744539376, 642.8974309059985, 8886.822263554694, 8972.955135224413, 9131.223845973034, 9435.716928492157, 9443.669471568463, 581.3517927510795, 607.6931149769338, 640.9647469389135, 653.2585515439915, 655.2170156662837, 4269.487661480643, 4297.462817147856, 4526.456825180933, 4557.498675145731, 4580.244259697112, 102036.0, 104291.4785426068, 104571.4771426138, 105923.0, 108225.0, 61188.694056529435, 95925.52037239769, 98493.50753246188, 98557.01442985478, 99228.0, 68664.0, 83291.0, 86987.0, 87690.56154719186, 87813.56093219493, 7331.596762178131, 8816.382853200472, 8854.24738897201, 8909.775742026297, 8969.192772650493, 35744.82127589345, 45442.40951566679, 52382.69043273949, 53073.61168747493, 55930.720346398004, 61275.0, 67168.6641566789, 67942.66028669824, 70908.64545677238, 72177.6391118041, 11984.520619175135, 12607.023203871448, 12907.935460322638, 13183.813456788936, 13637.318134093453, 14669.92665036668, 14788.964772465824, 14913.95602307828, 15192.544223673307, 15342.386304547694, 13165.53920612774, 13753.79369309441, 14266.928665356607, 14341.56975290743, 14593.343299551758, 10372.948135259578, 11724.237924534878, 13811.309434528033, 13882.0, 13963.720725585228, 13327.533536326182, 13637.659058523219, 13697.178169309873, 13840.238786866415, 14214.147151170962, 10717.089047430743, 10771.838422423512, 10888.401137937173, 11046.061084807881, 11166.944165279121, 9866.421372580353, 10709.785804283714, 11432.371219583003, 11522.654320370402, 11564.826527601925], "value": 7434.266925092906, "unit": "score"}], "name": "dromaeo_css", "value": 7178.926108350755, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 113.0, 113.0, 113.0, 117.0, 114.0, 112.0, 113.0, 113.0, 112.0], "value": 113.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 111.0, 110.0, 148.0, 113.0, 110.0, 110.0, 110.0, 130.0, 112.0], "value": 120.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [188.0, 180.0, 182.0, 194.0, 258.0, 181.0, 178.0, 185.0, 183.0, 181.0], "value": 191.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 59.0, 58.0, 88.0, 62.0, 58.0, 61.0, 57.0, 74.0, 59.0], "value": 65.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 71.0, 85.0, 69.0, 69.0, 70.0, 83.0, 69.0, 69.0, 85.0], "value": 75.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 86.0, 90.0, 86.0, 89.0, 86.0, 89.0, 87.0, 90.0, 85.0], "value": 87.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 94.0, 93.0, 95.0, 94.0, 94.0, 94.0, 95.0, 94.0, 95.0], "value": 94.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 85.0, 82.0, 89.0, 88.0, 87.0, 85.0, 85.0, 95.0, 87.0], "value": 86.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 55.0, 55.0, 54.0, 54.0, 54.0, 54.0, 56.0, 54.0, 56.0], "value": 54.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 69.0, 81.0, 73.0, 74.0, 69.0, 70.0, 72.0, 70.0, 71.0], "value": 71.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 87.0, 86.0, 87.0, 85.0, 87.0, 90.0, 85.0, 87.0, 90.0], "value": 87.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 157.0, 147.0, 137.0, 140.0, 141.0, 138.0, 140.0, 187.0, 173.0], "value": 150.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [204.0, 176.0, 176.0, 173.0, 174.0, 183.0, 239.0, 199.0, 179.0, 172.0], "value": 187.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 68.0, 68.0, 68.0, 67.0, 67.0, 65.0, 67.0, 66.0, 66.0], "value": 67.0, "unit": "score"}], "name": "kraken", "value": 1453.1, "alertThreshold": 2.0}]} 20:31:01 INFO - Return code: 0 20:31:01 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 20:31:01 INFO - # TBPL SUCCESS # 20:31:01 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 20:31:01 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 20:31:01 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 20:31:01 INFO - 2016-12-22 20:31:01.870 screenresolution[1651:9760] starting screenresolution argv=screenresolution get 20:31:01 INFO - 2016-12-22 20:31:01.873 screenresolution[1651:9760] Display 0: 1600x1200x32@60 20:31:01 INFO - 2016-12-22 20:31:01.879 screenresolution[1652:9763] starting screenresolution argv=screenresolution list 20:31:01 INFO - Available Modes on Display 0 20:31:01 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 20:31:01 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 20:31:01 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 20:31:01 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 20:31:02 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 20:31:02 INFO - Intel Iris: 20:31:02 INFO - Chipset Model: Intel Iris 20:31:02 INFO - Type: GPU 20:31:02 INFO - Bus: Built-In 20:31:02 INFO - VRAM (Dynamic, Max): 1536 MB 20:31:02 INFO - Vendor: Intel (0x8086) 20:31:02 INFO - Device ID: 0x0a2e 20:31:02 INFO - Revision ID: 0x0009 20:31:02 INFO - Displays: 20:31:02 INFO - Display: 20:31:02 INFO - Resolution: 1600 x 1200 @ 60 Hz 20:31:02 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 20:31:02 INFO - Display Serial Number: 1600x1200 60 20:31:02 INFO - Main Display: Yes 20:31:02 INFO - Mirror: Off 20:31:02 INFO - Online: Yes 20:31:02 INFO - Rotation: Supported 20:31:02 INFO - Return code: 0 20:31:02 INFO - Running post-action listener: _resource_record_post_action 20:31:02 INFO - [mozharness: 2016-12-23 04:31:02.113484Z] Finished run-tests step (success) 20:31:02 INFO - Running post-run listener: _resource_record_post_run 20:31:02 INFO - Total resource usage - Wall time: 1203s; CPU: 54.0%; Read bytes: 38831104; Write bytes: 551505408; Read time: 378; Write time: 1775 20:31:02 INFO - TinderboxPrint: CPU usage
53.7% 20:31:02 INFO - TinderboxPrint: I/O read bytes / time
38,831,104 / 378 20:31:02 INFO - TinderboxPrint: I/O write bytes / time
551,505,408 / 1,775 20:31:02 INFO - TinderboxPrint: CPU idle
2,229.5 (46.3%) 20:31:02 INFO - TinderboxPrint: CPU system
964.0 (20.0%) 20:31:02 INFO - TinderboxPrint: CPU user
1,620.1 (33.7%) 20:31:02 INFO - TinderboxPrint: Swap in / out
288,567,296 / 0 20:31:02 INFO - install - Wall time: 22s; CPU: 54.0%; Read bytes: 245758464; Write bytes: 240330752; Read time: 17893; Write time: 507 20:31:02 INFO - run-tests - Wall time: 1182s; CPU: 54.0%; Read bytes: 34444288; Write bytes: 297371136; Read time: 346; Write time: 1235 20:31:02 INFO - Running post-run listener: _upload_blobber_files 20:31:02 INFO - Blob upload gear active. 20:31:02 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 20:31:02 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 20:31:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 20:31:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 20:31:03 INFO - (blobuploader) - INFO - Open directory for files ... 20:31:03 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 20:31:03 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 20:31:03 INFO - (blobuploader) - INFO - Uploading, attempt #1. 20:31:04 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 20:31:04 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 20:31:04 INFO - (blobuploader) - INFO - Done attempting. 20:31:04 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 20:31:04 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 20:31:04 INFO - (blobuploader) - INFO - Uploading, attempt #1. 20:31:05 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 20:31:05 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 20:31:05 INFO - (blobuploader) - INFO - Done attempting. 20:31:05 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 20:31:05 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 20:31:05 INFO - (blobuploader) - INFO - Uploading, attempt #1. 20:31:06 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 20:31:06 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 20:31:06 INFO - (blobuploader) - INFO - Done attempting. 20:31:06 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 20:31:06 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 20:31:06 INFO - (blobuploader) - INFO - Uploading, attempt #1. 20:31:08 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 20:31:08 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 20:31:08 INFO - (blobuploader) - INFO - Done attempting. 20:31:08 INFO - (blobuploader) - INFO - Iteration through files over. 20:31:08 INFO - Return code: 0 20:31:08 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 20:31:08 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 20:31:08 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/663dd391444330edb97487888d58c215fe23dc9b66156b6fe688ce24ad70803cb30f93e98a3b46f09616031934e9237ef5ab3df38d338ee27074b302aef221c6", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d57744c390d019c3507e79fccbfb1a12d448624c63974e071d8d12032c46db8c2bb3575dc4c9ac8411632499b24c9d1c1763e4da72debed634dcfbda1aa1ea0f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/59eee8fc6f38d166668a5e708b7fe6997e646f5eb72136c856ec653d1ad96d0573d55eb050cbd2e14f04e801ed8c663fba1a3cb2694c738d3ea839b47f883dfe", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2436d153ec84a4ed8622681d58de19ad4a91818081ea15d224e755a63940b72d66cdd81410a11804e7615c48d456ffb6a99571d02b55d7c7b3d84bbf0e095ebd"} 20:31:08 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 20:31:08 INFO - Writing to file /builds/slave/test/properties/blobber_files 20:31:08 INFO - Contents: 20:31:08 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/663dd391444330edb97487888d58c215fe23dc9b66156b6fe688ce24ad70803cb30f93e98a3b46f09616031934e9237ef5ab3df38d338ee27074b302aef221c6", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d57744c390d019c3507e79fccbfb1a12d448624c63974e071d8d12032c46db8c2bb3575dc4c9ac8411632499b24c9d1c1763e4da72debed634dcfbda1aa1ea0f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/59eee8fc6f38d166668a5e708b7fe6997e646f5eb72136c856ec653d1ad96d0573d55eb050cbd2e14f04e801ed8c663fba1a3cb2694c738d3ea839b47f883dfe", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2436d153ec84a4ed8622681d58de19ad4a91818081ea15d224e755a63940b72d66cdd81410a11804e7615c48d456ffb6a99571d02b55d7c7b3d84bbf0e095ebd"} 20:31:08 INFO - Running post-run listener: copy_logs_to_upload_dir 20:31:08 INFO - Copying logs to upload dir... 20:31:08 INFO - mkdir: /builds/slave/test/build/upload/logs 20:31:08 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1268.198916 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 8 secs) (at 2016-12-22 20:31:08.752261) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-22 20:31:08.755454) ========= 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.DfbnYBb4Lz/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.qUlRm63fjb/Listeners TMPDIR=/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/663dd391444330edb97487888d58c215fe23dc9b66156b6fe688ce24ad70803cb30f93e98a3b46f09616031934e9237ef5ab3df38d338ee27074b302aef221c6", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d57744c390d019c3507e79fccbfb1a12d448624c63974e071d8d12032c46db8c2bb3575dc4c9ac8411632499b24c9d1c1763e4da72debed634dcfbda1aa1ea0f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/59eee8fc6f38d166668a5e708b7fe6997e646f5eb72136c856ec653d1ad96d0573d55eb050cbd2e14f04e801ed8c663fba1a3cb2694c738d3ea839b47f883dfe", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2436d153ec84a4ed8622681d58de19ad4a91818081ea15d224e755a63940b72d66cdd81410a11804e7615c48d456ffb6a99571d02b55d7c7b3d84bbf0e095ebd"} build_url:https://queue.taskcluster.net/v1/task/Y6GVFfccRXWga3ceP1u3GQ/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011081 build_url: 'https://queue.taskcluster.net/v1/task/Y6GVFfccRXWga3ceP1u3GQ/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/663dd391444330edb97487888d58c215fe23dc9b66156b6fe688ce24ad70803cb30f93e98a3b46f09616031934e9237ef5ab3df38d338ee27074b302aef221c6", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d57744c390d019c3507e79fccbfb1a12d448624c63974e071d8d12032c46db8c2bb3575dc4c9ac8411632499b24c9d1c1763e4da72debed634dcfbda1aa1ea0f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/59eee8fc6f38d166668a5e708b7fe6997e646f5eb72136c856ec653d1ad96d0573d55eb050cbd2e14f04e801ed8c663fba1a3cb2694c738d3ea839b47f883dfe", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2436d153ec84a4ed8622681d58de19ad4a91818081ea15d224e755a63940b72d66cdd81410a11804e7615c48d456ffb6a99571d02b55d7c7b3d84bbf0e095ebd"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-22 20:31:08.790103) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-22 20:31:08.790509) ========= 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.DfbnYBb4Lz/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.qUlRm63fjb/Listeners TMPDIR=/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/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.005130 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-22 20:31:08.839899) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-22 20:31:08.840233) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-22 20:31:08.840548) ========= ========= Total master_lag: 3.75 =========