builder: mozilla-central_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0365 starttime: 1483215230.93 results: success (0) buildid: 20161231110819 builduid: d78d49bc59b44c8dbfbfaea0b694ac0e revision: cb9f43eb552570e185e95dfc8acb9f9fbe47ae17 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-12-31 12:13:50.929844) ========= master: http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-12-31 12:13:50.930378) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-31 12:13:50.930738) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-31 12:13:51.038903) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-31 12:13:51.039402) ========= 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.zt15ehN6Sk/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.2mlI09uo26/Listeners TMPDIR=/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/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-31 12:13:51-- 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% 18.6M=0.001s 2016-12-31 12:13:51 (18.6 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.661118 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-31 12:13:51.731111) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-31 12:13:51.731433) ========= 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.zt15ehN6Sk/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.2mlI09uo26/Listeners TMPDIR=/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/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.026328 ========= master_lag: 0.11 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-31 12:13:51.867091) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-12-31 12:13:51.867458) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev cb9f43eb552570e185e95dfc8acb9f9fbe47ae17 --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 cb9f43eb552570e185e95dfc8acb9f9fbe47ae17 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.zt15ehN6Sk/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.2mlI09uo26/Listeners TMPDIR=/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/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-31 12:13:51,973 truncating revision to first 12 chars 2016-12-31 12:13:51,973 Setting DEBUG logging. 2016-12-31 12:13:51,973 attempt 1/10 2016-12-31 12:13:51,974 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/cb9f43eb5525?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-12-31 12:13:52,748 unpacking tar archive at: mozilla-central-cb9f43eb5525/testing/mozharness/ program finished with exit code 0 elapsedTime=1.038375 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-12-31 12:13:52.935694) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-31 12:13:52.936039) ========= script_repo_revision: cb9f43eb552570e185e95dfc8acb9f9fbe47ae17 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-31 12:13:52.936500) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-31 12:13:52.936808) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-31 12:13:52.977931) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 5 secs) (at 2016-12-31 12:13:52.978288) ========= /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.zt15ehN6Sk/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.2mlI09uo26/Listeners TMPDIR=/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/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 12:13:53 INFO - MultiFileLogger online at 20161231 12:13:53 in /builds/slave/test 12:13:53 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 12:13:53 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 12:13:53 INFO - {'append_to_log': False, 12:13:53 INFO - 'base_work_dir': '/builds/slave/test', 12:13:53 INFO - 'blob_upload_branch': 'Firefox', 12:13:53 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 12:13:53 INFO - 'branch': 'Firefox', 12:13:53 INFO - 'buildbot_json_path': 'buildprops.json', 12:13:53 INFO - 'config_files': ('talos/mac_config.py',), 12:13:53 INFO - 'default_actions': ('clobber', 12:13:53 INFO - 'read-buildbot-config', 12:13:53 INFO - 'download-and-extract', 12:13:53 INFO - 'populate-webroot', 12:13:53 INFO - 'create-virtualenv', 12:13:53 INFO - 'install', 12:13:53 INFO - 'run-tests'), 12:13:53 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 12:13:53 INFO - 'download_minidump_stackwalk': True, 12:13:53 INFO - 'download_symbols': 'ondemand', 12:13:53 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 12:13:53 INFO - 'tooltool.py': '/tools/tooltool.py', 12:13:53 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 12:13:53 INFO - '/tools/misc-python/virtualenv.py')}, 12:13:53 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 12:13:53 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 12:13:53 INFO - 'installer_path': 'installer.exe', 12:13:53 INFO - 'log_level': 'info', 12:13:53 INFO - 'log_name': 'talos', 12:13:53 INFO - 'log_to_console': True, 12:13:53 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 12:13:53 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 12:13:53 INFO - 'opt_config_files': (), 12:13:53 INFO - 'pip_index': False, 12:13:53 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 12:13:53 INFO - 'cmd': ('bash', 12:13:53 INFO - '-c', 12:13:53 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 12:13:53 INFO - 'enabled': True, 12:13:53 INFO - 'halt_on_failure': False, 12:13:53 INFO - 'name': 'check_screen_resolution'},), 12:13:53 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 12:13:53 INFO - 'cmd': ('bash', 12:13:53 INFO - '-c', 12:13:53 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 12:13:53 INFO - 'enabled': True, 12:13:53 INFO - 'halt_on_failure': False, 12:13:53 INFO - 'name': 'check_screen_resolution'},), 12:13:53 INFO - 'run_cmd_checks_enabled': True, 12:13:53 INFO - 'sps_profile': False, 12:13:53 INFO - 'sps_profile_interval': 0, 12:13:53 INFO - 'suite': 'dromaeojs', 12:13:53 INFO - 'system_bits': '32', 12:13:53 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 12:13:53 INFO - 'title': 't-yosemite-r7-0365', 12:13:53 INFO - 'tooltool_cache': '/builds/tooltool_cache', 12:13:53 INFO - 'use_talos_json': True, 12:13:53 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 12:13:53 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 12:13:53 INFO - 'work_dir': 'build'} 12:13:53 INFO - [mozharness: 2016-12-31 20:13:53.189549Z] Running clobber step. 12:13:53 INFO - Running pre-action listener: _resource_record_pre_action 12:13:53 INFO - Running main action method: clobber 12:13:53 INFO - rmtree: /builds/slave/test/build 12:13:53 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 12:13:55 INFO - Running post-action listener: _resource_record_post_action 12:13:55 INFO - [mozharness: 2016-12-31 20:13:55.598608Z] Finished clobber step (success) 12:13:55 INFO - [mozharness: 2016-12-31 20:13:55.598779Z] Running read-buildbot-config step. 12:13:55 INFO - Running pre-action listener: _resource_record_pre_action 12:13:55 INFO - Running main action method: read_buildbot_config 12:13:55 INFO - Using buildbot properties: 12:13:55 INFO - { 12:13:55 INFO - "project": "", 12:13:55 INFO - "product": "firefox", 12:13:55 INFO - "script_repo_revision": "production", 12:13:55 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs", 12:13:55 INFO - "repository": "", 12:13:55 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs", 12:13:55 INFO - "buildid": "20161231110819", 12:13:55 INFO - "pgo_build": "False", 12:13:55 INFO - "basedir": "/builds/slave/test", 12:13:55 INFO - "buildnumber": 14, 12:13:55 INFO - "slavename": "t-yosemite-r7-0365", 12:13:55 INFO - "master": "http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/", 12:13:55 INFO - "platform": "yosemite_r7", 12:13:55 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 12:13:55 INFO - "branch": "mozilla-central", 12:13:55 INFO - "repo_path": "mozilla-central", 12:13:55 INFO - "revision": "cb9f43eb552570e185e95dfc8acb9f9fbe47ae17", 12:13:55 INFO - "stage_platform": "macosx64", 12:13:55 INFO - "builduid": "d78d49bc59b44c8dbfbfaea0b694ac0e", 12:13:55 INFO - "slavebuilddir": "test" 12:13:55 INFO - } 12:13:55 INFO - Found installer url https://queue.taskcluster.net/v1/task/HLf5heFdRsaYnjwsLdBnDw/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg. 12:13:55 INFO - Running post-action listener: _resource_record_post_action 12:13:55 INFO - [mozharness: 2016-12-31 20:13:55.602165Z] Finished read-buildbot-config step (success) 12:13:55 INFO - [mozharness: 2016-12-31 20:13:55.602295Z] Running download-and-extract step. 12:13:55 INFO - Running pre-action listener: _resource_record_pre_action 12:13:55 INFO - Running main action method: download_and_extract 12:13:55 INFO - mkdir: /builds/slave/test/build/tests 12:13:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:13:55 INFO - trying https://queue.taskcluster.net/v1/task/HLf5heFdRsaYnjwsLdBnDw/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json 12:13:55 INFO - Downloading https://queue.taskcluster.net/v1/task/HLf5heFdRsaYnjwsLdBnDw/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 12:13:55 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/HLf5heFdRsaYnjwsLdBnDw/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 12:13:56 INFO - Downloaded 1199 bytes. 12:13:56 INFO - Reading from file /builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json 12:13:56 INFO - Using the following test package requirements: 12:13:56 INFO - {u'common': [u'firefox-53.0a1.en-US.mac.common.tests.zip'], 12:13:56 INFO - u'cppunittest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 12:13:56 INFO - u'firefox-53.0a1.en-US.mac.cppunittest.tests.zip'], 12:13:56 INFO - u'gtest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 12:13:56 INFO - u'firefox-53.0a1.en-US.mac.gtest.tests.zip'], 12:13:56 INFO - u'jittest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 12:13:56 INFO - u'jsshell-mac.zip'], 12:13:56 INFO - u'mochitest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 12:13:56 INFO - u'firefox-53.0a1.en-US.mac.mochitest.tests.zip'], 12:13:56 INFO - u'mozbase': [u'firefox-53.0a1.en-US.mac.common.tests.zip'], 12:13:56 INFO - u'reftest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 12:13:56 INFO - u'firefox-53.0a1.en-US.mac.reftest.tests.zip'], 12:13:56 INFO - u'talos': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 12:13:56 INFO - u'firefox-53.0a1.en-US.mac.talos.tests.zip'], 12:13:56 INFO - u'web-platform': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 12:13:56 INFO - u'firefox-53.0a1.en-US.mac.web-platform.tests.zip'], 12:13:56 INFO - u'xpcshell': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 12:13:56 INFO - u'firefox-53.0a1.en-US.mac.xpcshell.tests.zip']} 12:13:56 INFO - Downloading packages: [u'firefox-53.0a1.en-US.mac.common.tests.zip'] for test suite category: common 12:13:56 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/HLf5heFdRsaYnjwsLdBnDw/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip 12:13:56 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/HLf5heFdRsaYnjwsLdBnDw/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip'}, attempt #1 12:13:56 INFO - Fetch https://queue.taskcluster.net/v1/task/HLf5heFdRsaYnjwsLdBnDw/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip into memory 12:13:57 INFO - Expected file size: 16299746 12:13:57 INFO - Obtained file size: 16299746 12:14:04 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 12:14:04 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/HLf5heFdRsaYnjwsLdBnDw/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip 12:14:04 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/HLf5heFdRsaYnjwsLdBnDw/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip'}, attempt #1 12:14:04 INFO - Fetch https://queue.taskcluster.net/v1/task/HLf5heFdRsaYnjwsLdBnDw/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip into memory 12:14:04 INFO - Expected file size: 16299746 12:14:04 INFO - Obtained file size: 16299746 12:14:11 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/HLf5heFdRsaYnjwsLdBnDw/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip 12:14:11 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/HLf5heFdRsaYnjwsLdBnDw/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip'}, attempt #1 12:14:11 INFO - Fetch https://queue.taskcluster.net/v1/task/HLf5heFdRsaYnjwsLdBnDw/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip into memory 12:14:12 INFO - Expected file size: 14409776 12:14:12 INFO - Obtained file size: 14409776 12:14:12 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')]} 12:14:12 INFO - trying https://queue.taskcluster.net/v1/task/HLf5heFdRsaYnjwsLdBnDw/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 12:14:12 INFO - Downloading https://queue.taskcluster.net/v1/task/HLf5heFdRsaYnjwsLdBnDw/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg 12:14:12 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/HLf5heFdRsaYnjwsLdBnDw/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 12:14:14 INFO - Downloaded 69753650 bytes. 12:14:14 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/HLf5heFdRsaYnjwsLdBnDw/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 12:14:14 INFO - mkdir: /builds/slave/test/properties 12:14:14 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 12:14:14 INFO - Writing to file /builds/slave/test/properties/build_url 12:14:14 INFO - Contents: 12:14:14 INFO - build_url:https://queue.taskcluster.net/v1/task/HLf5heFdRsaYnjwsLdBnDw/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 12:14:15 INFO - Running post-action listener: _resource_record_post_action 12:14:15 INFO - Running post-action listener: set_extra_try_arguments 12:14:15 INFO - [mozharness: 2016-12-31 20:14:15.677777Z] Finished download-and-extract step (success) 12:14:15 INFO - [mozharness: 2016-12-31 20:14:15.677926Z] Running populate-webroot step. 12:14:15 INFO - Running pre-action listener: _resource_record_pre_action 12:14:15 INFO - Running main action method: populate_webroot 12:14:15 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 12:14:15 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 12:14:15 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 12:14:15 INFO - '--tppagecycles', 12:14:15 INFO - '7'], 12:14:15 INFO - 'tests': ['tsvgm']}}, 12:14:15 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 12:14:15 INFO - 'tests': ['tresize', 'tcanvasmark']}, 12:14:15 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 12:14:15 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 12:14:15 INFO - 'tests': ['dromaeo_css', 'kraken']}, 12:14:15 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 12:14:15 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 12:14:15 INFO - 'talos_options': ['--disable-e10s'], 12:14:15 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:14:15 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 12:14:15 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:14:15 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 12:14:15 INFO - 'talos_options': ['--disable-e10s'], 12:14:15 INFO - 'tests': ['damp', 'tps']}, 12:14:15 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 12:14:15 INFO - 'tests': ['damp', 'tps']}, 12:14:15 INFO - 'g3': {'talos_options': ['--disable-e10s'], 12:14:15 INFO - 'tests': ['dromaeo_dom']}, 12:14:15 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 12:14:15 INFO - 'g4': {'talos_options': ['--disable-e10s'], 12:14:15 INFO - 'tests': ['basic_compositor_video']}, 12:14:15 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 12:14:15 INFO - 'other': {'talos_options': ['--disable-e10s'], 12:14:15 INFO - 'tests': ['a11yr', 12:14:15 INFO - 'ts_paint', 12:14:15 INFO - 'tpaint', 12:14:15 INFO - 'sessionrestore', 12:14:15 INFO - 'sessionrestore_no_auto_restore', 12:14:15 INFO - 'tabpaint']}, 12:14:15 INFO - 'other-e10s': {'tests': ['a11yr', 12:14:15 INFO - 'ts_paint', 12:14:15 INFO - 'tpaint', 12:14:15 INFO - 'sessionrestore', 12:14:15 INFO - 'sessionrestore_no_auto_restore', 12:14:15 INFO - 'tabpaint']}, 12:14:15 INFO - 'other-e10s_l64': {'tests': ['a11yr', 12:14:15 INFO - 'ts_paint', 12:14:15 INFO - 'tpaint', 12:14:15 INFO - 'sessionrestore', 12:14:15 INFO - 'sessionrestore_no_auto_restore', 12:14:15 INFO - 'tabpaint']}, 12:14:15 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 12:14:15 INFO - 'ts_paint', 12:14:15 INFO - 'tpaint', 12:14:15 INFO - 'sessionrestore', 12:14:15 INFO - 'sessionrestore_no_auto_restore', 12:14:15 INFO - 'tabpaint']}, 12:14:15 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 12:14:15 INFO - 'tests': ['a11yr', 12:14:15 INFO - 'ts_paint', 12:14:15 INFO - 'tpaint', 12:14:15 INFO - 'sessionrestore', 12:14:15 INFO - 'sessionrestore_no_auto_restore', 12:14:15 INFO - 'tabpaint']}, 12:14:15 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 12:14:15 INFO - 'tests': ['a11yr', 12:14:15 INFO - 'ts_paint', 12:14:15 INFO - 'tpaint', 12:14:15 INFO - 'sessionrestore', 12:14:15 INFO - 'sessionrestore_no_auto_restore', 12:14:15 INFO - 'tabpaint']}, 12:14:15 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 12:14:15 INFO - 'tests': ['tsvgx', 12:14:15 INFO - 'tsvgr_opacity', 12:14:15 INFO - 'tart', 12:14:15 INFO - 'tscrollx', 12:14:15 INFO - 'cart', 12:14:15 INFO - 'tsvg_static']}, 12:14:15 INFO - 'svgr-e10s': {'tests': ['tsvgx', 12:14:15 INFO - 'tsvgr_opacity', 12:14:15 INFO - 'tart', 12:14:15 INFO - 'tscrollx', 12:14:15 INFO - 'cart', 12:14:15 INFO - 'tsvg_static']}, 12:14:15 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 12:14:15 INFO - 'talos_options': ['--disable-e10s'], 12:14:15 INFO - 'tests': ['tp5o']}, 12:14:15 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 12:14:15 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 12:14:15 INFO - 'talos_options': ['--disable-e10s', 12:14:15 INFO - '--xperf_path', 12:14:15 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:14:15 INFO - 'tests': ['tp5n']}, 12:14:15 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 12:14:15 INFO - 'talos_options': ['--xperf_path', 12:14:15 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:14:15 INFO - 'tests': ['tp5n']}}, 12:14:15 INFO - 'talos.zip': {'path': '', 12:14:15 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 12:14:15 INFO - Running post-action listener: _resource_record_post_action 12:14:15 INFO - [mozharness: 2016-12-31 20:14:15.696592Z] Finished populate-webroot step (success) 12:14:15 INFO - [mozharness: 2016-12-31 20:14:15.696733Z] Running create-virtualenv step. 12:14:15 INFO - Running pre-action listener: _resource_record_pre_action 12:14:15 INFO - Running main action method: create_virtualenv 12:14:15 INFO - Creating virtualenv /builds/slave/test/build/venv 12:14:15 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 12:14:15 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 12:14:15 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 12:14:15 INFO - Using real prefix '/tools/python27' 12:14:15 INFO - New python executable in /builds/slave/test/build/venv/bin/python 12:14:16 INFO - Installing distribute.............................................................................................................................................................................................done. 12:14:19 INFO - Installing pip.................done. 12:14:19 INFO - Return code: 0 12:14:19 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 12:14:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 12:14:19 INFO - Reading from file tmpfile_stdout 12:14:19 INFO - Output received: 12:14:19 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) 12:14:19 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 12:14:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:14:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:14:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:14:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:14:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:14:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:14:19 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 0x10a828b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a82b768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbd18d82f90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a606d30>, '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 0x10a52ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a5fbbe8>, '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.zt15ehN6Sk/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.2mlI09uo26/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/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 12:14:19 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 12:14:19 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 12:14:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zt15ehN6Sk/Render', 12:14:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:14:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:14:19 INFO - 'HOME': '/Users/cltbld', 12:14:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:14:19 INFO - 'LOGNAME': 'cltbld', 12:14:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:14:19 INFO - 'MOZ_NO_REMOTE': '1', 12:14:19 INFO - 'NO_EM_RESTART': '1', 12:14:19 INFO - 'PAGER': '/bin/cat', 12:14:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:14:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:14:19 INFO - 'PWD': '/builds/slave/test', 12:14:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:14:19 INFO - 'SHELL': '/bin/bash', 12:14:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.2mlI09uo26/Listeners', 12:14:19 INFO - 'TMPDIR': '/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/', 12:14:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:14:19 INFO - 'USER': 'cltbld', 12:14:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:14:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:14:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:14:19 INFO - 'XPC_FLAGS': '0x0', 12:14:19 INFO - 'XPC_SERVICE_NAME': '0', 12:14:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:14:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:14:19 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 12:14:19 INFO - Cleaning up... 12:14:19 INFO - Return code: 0 12:14:19 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 12:14:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:14:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:14:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:14:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:14:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:14:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:14:19 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 0x10a828b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a82b768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbd18d82f90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a606d30>, '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 0x10a52ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a5fbbe8>, '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.zt15ehN6Sk/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.2mlI09uo26/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/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 12:14:19 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 12:14:19 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 12:14:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zt15ehN6Sk/Render', 12:14:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:14:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:14:19 INFO - 'HOME': '/Users/cltbld', 12:14:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:14:19 INFO - 'LOGNAME': 'cltbld', 12:14:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:14:19 INFO - 'MOZ_NO_REMOTE': '1', 12:14:19 INFO - 'NO_EM_RESTART': '1', 12:14:19 INFO - 'PAGER': '/bin/cat', 12:14:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:14:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:14:19 INFO - 'PWD': '/builds/slave/test', 12:14:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:14:19 INFO - 'SHELL': '/bin/bash', 12:14:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.2mlI09uo26/Listeners', 12:14:19 INFO - 'TMPDIR': '/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/', 12:14:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:14:19 INFO - 'USER': 'cltbld', 12:14:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:14:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:14:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:14:19 INFO - 'XPC_FLAGS': '0x0', 12:14:19 INFO - 'XPC_SERVICE_NAME': '0', 12:14:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:14:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:14:20 INFO - Downloading/unpacking psutil>=3.1.1 12:14:20 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 12:14:20 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 12:14:20 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 12:14:20 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 12:14:20 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 12:14:20 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 12:14:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 12:14:24 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:14:24 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:14:24 INFO - Installing collected packages: psutil 12:14:24 INFO - Running setup.py install for psutil 12:14:24 INFO - building 'psutil._psutil_osx' extension 12:14:24 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 12:14:24 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 12:14:24 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 12:14:24 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 12:14:24 INFO - building 'psutil._psutil_posix' extension 12:14:24 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 12:14:25 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 12:14:25 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 12:14:25 INFO - ^ 12:14:25 INFO - 1 warning generated. 12:14:25 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 12:14:25 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:14:25 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:14:25 INFO - Successfully installed psutil 12:14:25 INFO - Cleaning up... 12:14:25 INFO - Return code: 0 12:14:25 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 12:14:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:14:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:14:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:14:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:14:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:14:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:14:25 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 0x10a828b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a82b768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbd18d82f90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a606d30>, '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 0x10a52ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a5fbbe8>, '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.zt15ehN6Sk/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.2mlI09uo26/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/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 12:14:25 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 12:14:25 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 12:14:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zt15ehN6Sk/Render', 12:14:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:14:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:14:25 INFO - 'HOME': '/Users/cltbld', 12:14:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:14:25 INFO - 'LOGNAME': 'cltbld', 12:14:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:14:25 INFO - 'MOZ_NO_REMOTE': '1', 12:14:25 INFO - 'NO_EM_RESTART': '1', 12:14:25 INFO - 'PAGER': '/bin/cat', 12:14:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:14:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:14:25 INFO - 'PWD': '/builds/slave/test', 12:14:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:14:25 INFO - 'SHELL': '/bin/bash', 12:14:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.2mlI09uo26/Listeners', 12:14:25 INFO - 'TMPDIR': '/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/', 12:14:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:14:25 INFO - 'USER': 'cltbld', 12:14:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:14:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:14:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:14:25 INFO - 'XPC_FLAGS': '0x0', 12:14:25 INFO - 'XPC_SERVICE_NAME': '0', 12:14:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:14:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:14:25 INFO - Downloading/unpacking mozsystemmonitor==0.3 12:14:25 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 12:14:25 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 12:14:25 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 12:14:25 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 12:14:25 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 12:14:25 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 12:14:28 INFO - Downloading mozsystemmonitor-0.3.tar.gz 12:14:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 12:14:28 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 12:14:28 INFO - Installing collected packages: mozsystemmonitor 12:14:28 INFO - Running setup.py install for mozsystemmonitor 12:14:28 INFO - Successfully installed mozsystemmonitor 12:14:28 INFO - Cleaning up... 12:14:28 INFO - Return code: 0 12:14:28 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 12:14: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')]} 12:14:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:14:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:14: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')]} 12:14:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:14:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:14: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', '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 0x10a828b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a82b768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbd18d82f90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a606d30>, '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 0x10a52ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a5fbbe8>, '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.zt15ehN6Sk/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.2mlI09uo26/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/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 12:14: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', 'jsonschema==2.5.1'] in /builds/slave/test/build 12:14: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 jsonschema==2.5.1 12:14:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zt15ehN6Sk/Render', 12:14:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:14:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:14:28 INFO - 'HOME': '/Users/cltbld', 12:14:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:14:28 INFO - 'LOGNAME': 'cltbld', 12:14:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:14:28 INFO - 'MOZ_NO_REMOTE': '1', 12:14:28 INFO - 'NO_EM_RESTART': '1', 12:14:28 INFO - 'PAGER': '/bin/cat', 12:14:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:14:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:14:28 INFO - 'PWD': '/builds/slave/test', 12:14:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:14:28 INFO - 'SHELL': '/bin/bash', 12:14:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.2mlI09uo26/Listeners', 12:14:28 INFO - 'TMPDIR': '/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/', 12:14:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:14:28 INFO - 'USER': 'cltbld', 12:14:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:14:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:14:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:14:28 INFO - 'XPC_FLAGS': '0x0', 12:14:28 INFO - 'XPC_SERVICE_NAME': '0', 12:14:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:14:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:14:28 INFO - Downloading/unpacking jsonschema==2.5.1 12:14: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 12:14: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 12:14: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 12:14: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 12:14: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 12:14: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 12:14:31 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 12:14:31 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 12:14:31 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 12:14:31 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 12:14:31 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 12:14:31 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 12:14:31 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 12:14:31 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 12:14:31 INFO - Downloading functools32-3.2.3-2.tar.gz 12:14:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 12:14:31 INFO - warning: no files found matching '*.txt' 12:14:31 INFO - no previously-included directories found matching 'build' 12:14:31 INFO - no previously-included directories found matching 'dist' 12:14:31 INFO - no previously-included directories found matching '.git*' 12:14:31 INFO - Installing collected packages: jsonschema, functools32 12:14:31 INFO - Running setup.py install for functools32 12:14:32 INFO - warning: no files found matching '*.txt' 12:14:32 INFO - no previously-included directories found matching 'build' 12:14:32 INFO - no previously-included directories found matching 'dist' 12:14:32 INFO - no previously-included directories found matching '.git*' 12:14:32 INFO - Successfully installed jsonschema functools32 12:14:32 INFO - Cleaning up... 12:14:32 INFO - Return code: 0 12:14:32 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 12:14:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:14:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:14:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:14:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:14:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:14:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:14:32 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10a828b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a82b768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbd18d82f90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a606d30>, '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 0x10a52ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a5fbbe8>, '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.zt15ehN6Sk/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.2mlI09uo26/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/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 12:14:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 12:14:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 12:14:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zt15ehN6Sk/Render', 12:14:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:14:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:14:32 INFO - 'HOME': '/Users/cltbld', 12:14:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:14:32 INFO - 'LOGNAME': 'cltbld', 12:14:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:14:32 INFO - 'MOZ_NO_REMOTE': '1', 12:14:32 INFO - 'NO_EM_RESTART': '1', 12:14:32 INFO - 'PAGER': '/bin/cat', 12:14:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:14:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:14:32 INFO - 'PWD': '/builds/slave/test', 12:14:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:14:32 INFO - 'SHELL': '/bin/bash', 12:14:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.2mlI09uo26/Listeners', 12:14:32 INFO - 'TMPDIR': '/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/', 12:14:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:14:32 INFO - 'USER': 'cltbld', 12:14:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:14:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:14:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:14:32 INFO - 'XPC_FLAGS': '0x0', 12:14:32 INFO - 'XPC_SERVICE_NAME': '0', 12:14:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:14:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:14:32 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 12:14:32 INFO - Cleaning up... 12:14:32 INFO - Return code: 0 12:14:32 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 12:14:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:14:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:14:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:14:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:14:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:14:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:14:32 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10a828b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a82b768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbd18d82f90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a606d30>, '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 0x10a52ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a5fbbe8>, '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.zt15ehN6Sk/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.2mlI09uo26/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/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 12:14:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 12:14:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 12:14:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zt15ehN6Sk/Render', 12:14:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:14:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:14:32 INFO - 'HOME': '/Users/cltbld', 12:14:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:14:32 INFO - 'LOGNAME': 'cltbld', 12:14:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:14:32 INFO - 'MOZ_NO_REMOTE': '1', 12:14:32 INFO - 'NO_EM_RESTART': '1', 12:14:32 INFO - 'PAGER': '/bin/cat', 12:14:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:14:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:14:32 INFO - 'PWD': '/builds/slave/test', 12:14:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:14:32 INFO - 'SHELL': '/bin/bash', 12:14:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.2mlI09uo26/Listeners', 12:14:32 INFO - 'TMPDIR': '/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/', 12:14:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:14:32 INFO - 'USER': 'cltbld', 12:14:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:14:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:14:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:14:32 INFO - 'XPC_FLAGS': '0x0', 12:14:32 INFO - 'XPC_SERVICE_NAME': '0', 12:14:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:14:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:14:32 INFO - Downloading/unpacking blobuploader==1.2.4 12:14:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:14:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:14:32 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:14:32 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:14:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:14:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:14:35 INFO - Downloading blobuploader-1.2.4.tar.gz 12:14:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 12:14:35 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 12:14:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:14:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:14:35 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:14:35 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:14:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:14:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:14:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 12:14:36 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 12:14:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:14:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:14:36 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:14:36 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:14:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:14:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:14:36 INFO - Downloading docopt-0.6.1.tar.gz 12:14:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 12:14:36 INFO - Installing collected packages: blobuploader, requests, docopt 12:14:36 INFO - Running setup.py install for blobuploader 12:14:36 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 12:14:36 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 12:14:36 INFO - Running setup.py install for requests 12:14:37 INFO - Running setup.py install for docopt 12:14:37 INFO - Successfully installed blobuploader requests docopt 12:14:37 INFO - Cleaning up... 12:14:37 INFO - Return code: 0 12:14:37 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:14: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')]} 12:14:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:14:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:14: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')]} 12:14:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:14:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:14:37 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 0x10a828b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a82b768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbd18d82f90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a606d30>, '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 0x10a52ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a5fbbe8>, '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.zt15ehN6Sk/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.2mlI09uo26/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/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 12:14:37 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 12:14:37 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 12:14:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zt15ehN6Sk/Render', 12:14:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:14:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:14:37 INFO - 'HOME': '/Users/cltbld', 12:14:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:14:37 INFO - 'LOGNAME': 'cltbld', 12:14:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:14:37 INFO - 'MOZ_NO_REMOTE': '1', 12:14:37 INFO - 'NO_EM_RESTART': '1', 12:14:37 INFO - 'PAGER': '/bin/cat', 12:14:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:14:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:14:37 INFO - 'PWD': '/builds/slave/test', 12:14:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:14:37 INFO - 'SHELL': '/bin/bash', 12:14:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.2mlI09uo26/Listeners', 12:14:37 INFO - 'TMPDIR': '/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/', 12:14:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:14:37 INFO - 'USER': 'cltbld', 12:14:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:14:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:14:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:14:37 INFO - 'XPC_FLAGS': '0x0', 12:14:37 INFO - 'XPC_SERVICE_NAME': '0', 12:14:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:14:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:14:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:14:37 INFO - Running setup.py (path:/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/pip-LpDlqg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:14:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:14:37 INFO - Running setup.py (path:/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/pip-dWEdMZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:14:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:14:38 INFO - Running setup.py (path:/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/pip-imiAUd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:14:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:14:38 INFO - Running setup.py (path:/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/pip-pyo7Dt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:14:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:14:38 INFO - Running setup.py (path:/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/pip-LLFJIq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:14:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:14:38 INFO - Running setup.py (path:/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/pip-IeJfRl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:14:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:14:38 INFO - Running setup.py (path:/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/pip-K_vX2u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:14:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:14:38 INFO - Running setup.py (path:/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/pip-iC1MlU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:14:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:14:38 INFO - Running setup.py (path:/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/pip-8OW4jY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:14:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:14:38 INFO - Running setup.py (path:/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/pip-eIz7bY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:14:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:14:38 INFO - Running setup.py (path:/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/pip-tgdrvA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:14:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:14:38 INFO - Running setup.py (path:/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/pip-lWIV5x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:14:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:14:39 INFO - Running setup.py (path:/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/pip-rwhfM3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:14:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:14:39 INFO - Running setup.py (path:/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/pip-a5UqLx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:14:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:14:39 INFO - Running setup.py (path:/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/pip-b0TyuX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:14:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:14:39 INFO - Running setup.py (path:/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/pip-OjP2LQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:14:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:14:39 INFO - Running setup.py (path:/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/pip-0waIHB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:14:39 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 12:14:39 INFO - Running setup.py install for manifestparser 12:14:39 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 12:14:39 INFO - Running setup.py install for mozcrash 12:14:39 INFO - Running setup.py install for mozdebug 12:14:40 INFO - Running setup.py install for mozdevice 12:14:40 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 12:14:40 INFO - Installing dm script to /builds/slave/test/build/venv/bin 12:14:40 INFO - Running setup.py install for mozfile 12:14:40 INFO - Running setup.py install for mozhttpd 12:14:40 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 12:14:40 INFO - Running setup.py install for mozinfo 12:14:40 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 12:14:40 INFO - Running setup.py install for mozInstall 12:14:40 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 12:14:40 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 12:14:40 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 12:14:40 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 12:14:40 INFO - Running setup.py install for mozleak 12:14:40 INFO - Running setup.py install for mozlog 12:14:41 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 12:14:41 INFO - Running setup.py install for moznetwork 12:14:41 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 12:14:41 INFO - Running setup.py install for mozprocess 12:14:41 INFO - Running setup.py install for mozprofile 12:14:41 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 12:14:41 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 12:14:41 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 12:14:41 INFO - Running setup.py install for mozrunner 12:14:41 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 12:14:41 INFO - Running setup.py install for mozscreenshot 12:14:41 INFO - Running setup.py install for moztest 12:14:42 INFO - Running setup.py install for mozversion 12:14:42 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 12:14:42 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 12:14:42 INFO - Cleaning up... 12:14:42 INFO - Return code: 0 12:14:42 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:14:42 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')]} 12:14:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:14:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:14:42 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')]} 12:14:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:14:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:14:42 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 0x10a828b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a82b768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbd18d82f90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a606d30>, '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 0x10a52ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a5fbbe8>, '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.zt15ehN6Sk/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.2mlI09uo26/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/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 12:14:42 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 12:14:42 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 12:14:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zt15ehN6Sk/Render', 12:14:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:14:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:14:42 INFO - 'HOME': '/Users/cltbld', 12:14:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:14:42 INFO - 'LOGNAME': 'cltbld', 12:14:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:14:42 INFO - 'MOZ_NO_REMOTE': '1', 12:14:42 INFO - 'NO_EM_RESTART': '1', 12:14:42 INFO - 'PAGER': '/bin/cat', 12:14:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:14:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:14:42 INFO - 'PWD': '/builds/slave/test', 12:14:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:14:42 INFO - 'SHELL': '/bin/bash', 12:14:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.2mlI09uo26/Listeners', 12:14:42 INFO - 'TMPDIR': '/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/', 12:14:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:14:42 INFO - 'USER': 'cltbld', 12:14:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:14:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:14:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:14:42 INFO - 'XPC_FLAGS': '0x0', 12:14:42 INFO - 'XPC_SERVICE_NAME': '0', 12:14:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:14:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:14:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:14:42 INFO - Running setup.py (path:/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/pip-muIU5R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:14:42 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)) 12:14:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:14:42 INFO - Running setup.py (path:/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/pip-wamPvC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:14:42 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)) 12:14:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:14:42 INFO - Running setup.py (path:/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/pip-21Q8Zu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:14:42 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)) 12:14:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:14:42 INFO - Running setup.py (path:/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/pip-tox9NR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:14:42 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)) 12:14:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:14:42 INFO - Running setup.py (path:/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/pip-923lJO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:14:43 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)) 12:14:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:14:43 INFO - Running setup.py (path:/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/pip-lIQV2X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:14:43 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)) 12:14:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:14:43 INFO - Running setup.py (path:/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/pip-Dg9KUk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:14:43 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)) 12:14:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:14:43 INFO - Running setup.py (path:/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/pip-vffeBN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:14:43 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)) 12:14:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:14:43 INFO - Running setup.py (path:/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/pip-MsCy68-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:14:43 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)) 12:14:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:14:43 INFO - Running setup.py (path:/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/pip-g3dXM4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:14:43 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)) 12:14:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:14:43 INFO - Running setup.py (path:/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/pip-Qbff16-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:14:43 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)) 12:14:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:14:43 INFO - Running setup.py (path:/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/pip-KTxBWP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:14:43 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)) 12:14:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:14:43 INFO - Running setup.py (path:/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/pip-dHyWnx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:14:43 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)) 12:14:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:14:43 INFO - Running setup.py (path:/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/pip-D2W_4T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:14:43 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)) 12:14:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:14:43 INFO - Running setup.py (path:/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/pip-TpY297-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:14:44 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)) 12:14:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:14:44 INFO - Running setup.py (path:/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/pip-rmbRml-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:14:44 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 12:14:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:14:44 INFO - Running setup.py (path:/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/pip-Szk9HB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:14:44 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)) 12:14:44 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)) 12:14:44 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)) 12:14:44 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)) 12:14:44 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)) 12:14:44 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)) 12:14:44 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 12:14: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 12:14: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 12:14: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 12:14: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 12:14: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 12:14: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 12:14:46 INFO - Downloading blessings-1.6.tar.gz 12:14:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 12:14:47 INFO - Installing collected packages: blessings 12:14:47 INFO - Running setup.py install for blessings 12:14:47 INFO - Successfully installed blessings 12:14:47 INFO - Cleaning up... 12:14:47 INFO - Return code: 0 12:14:47 INFO - Done creating virtualenv /builds/slave/test/build/venv. 12:14:47 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:14:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:14:47 INFO - Reading from file tmpfile_stdout 12:14:47 INFO - Current package versions: 12:14:47 INFO - blessings == 1.6 12:14:47 INFO - blobuploader == 1.2.4 12:14:47 INFO - docopt == 0.6.1 12:14:47 INFO - functools32 == 3.2.3-2 12:14:47 INFO - jsonschema == 2.5.1 12:14:47 INFO - manifestparser == 1.1 12:14:47 INFO - mozInstall == 1.12 12:14:47 INFO - mozcrash == 1.0 12:14:47 INFO - mozdebug == 0.1 12:14:47 INFO - mozdevice == 0.49 12:14:47 INFO - mozfile == 1.2 12:14:47 INFO - mozhttpd == 0.7 12:14:47 INFO - mozinfo == 0.9 12:14:47 INFO - mozleak == 0.1 12:14:47 INFO - mozlog == 3.4 12:14:47 INFO - moznetwork == 0.27 12:14:47 INFO - mozprocess == 0.23 12:14:47 INFO - mozprofile == 0.28 12:14:47 INFO - mozrunner == 6.13 12:14:47 INFO - mozscreenshot == 0.1 12:14:47 INFO - mozsystemmonitor == 0.3 12:14:47 INFO - moztest == 0.8 12:14:47 INFO - mozversion == 1.4 12:14:47 INFO - psutil == 3.1.1 12:14:47 INFO - requests == 1.2.3 12:14:47 INFO - wsgiref == 0.1.2 12:14:47 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:14:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:14:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:14:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:14:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:14:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:14:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:14:47 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 0x10a828b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a82b768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbd18d82f90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a606d30>, '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 0x10a52ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a5fbbe8>, '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.zt15ehN6Sk/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.2mlI09uo26/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/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 12:14:47 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 12:14:47 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 12:14:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zt15ehN6Sk/Render', 12:14:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:14:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:14:47 INFO - 'HOME': '/Users/cltbld', 12:14:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:14:47 INFO - 'LOGNAME': 'cltbld', 12:14:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:14:47 INFO - 'MOZ_NO_REMOTE': '1', 12:14:47 INFO - 'NO_EM_RESTART': '1', 12:14:47 INFO - 'PAGER': '/bin/cat', 12:14:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:14:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:14:47 INFO - 'PWD': '/builds/slave/test', 12:14:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:14:47 INFO - 'SHELL': '/bin/bash', 12:14:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.2mlI09uo26/Listeners', 12:14:47 INFO - 'TMPDIR': '/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/', 12:14:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:14:47 INFO - 'USER': 'cltbld', 12:14:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:14:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:14:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:14:47 INFO - 'XPC_FLAGS': '0x0', 12:14:47 INFO - 'XPC_SERVICE_NAME': '0', 12:14:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:14:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:14:47 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)) 12:14:47 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)) 12:14:47 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)) 12:14:47 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)) 12:14:47 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)) 12:14:47 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)) 12:14:47 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)) 12:14:47 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)) 12:14:47 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)) 12:14:47 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)) 12:14:47 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)) 12:14:47 INFO - Cleaning up... 12:14:47 INFO - Return code: 0 12:14:47 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 12:14:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:14:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:14:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:14:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:14:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:14:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:14:47 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 0x10a828b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a82b768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbd18d82f90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a606d30>, '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 0x10a52ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a5fbbe8>, '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.zt15ehN6Sk/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.2mlI09uo26/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/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 12:14:47 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 12:14:47 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 12:14:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zt15ehN6Sk/Render', 12:14:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:14:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:14:47 INFO - 'HOME': '/Users/cltbld', 12:14:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:14:47 INFO - 'LOGNAME': 'cltbld', 12:14:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:14:47 INFO - 'MOZ_NO_REMOTE': '1', 12:14:47 INFO - 'NO_EM_RESTART': '1', 12:14:47 INFO - 'PAGER': '/bin/cat', 12:14:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:14:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:14:47 INFO - 'PWD': '/builds/slave/test', 12:14:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:14:47 INFO - 'SHELL': '/bin/bash', 12:14:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.2mlI09uo26/Listeners', 12:14:47 INFO - 'TMPDIR': '/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/', 12:14:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:14:47 INFO - 'USER': 'cltbld', 12:14:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:14:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:14:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:14:47 INFO - 'XPC_FLAGS': '0x0', 12:14:47 INFO - 'XPC_SERVICE_NAME': '0', 12:14:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:14:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:14:48 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 12:14:48 INFO - Cleaning up... 12:14:48 INFO - Return code: 0 12:14:48 INFO - Running post-action listener: _resource_record_post_action 12:14:48 INFO - Running post-action listener: _start_resource_monitoring 12:14:48 INFO - Starting resource monitoring. 12:14:48 INFO - [mozharness: 2016-12-31 20:14:48.144325Z] Finished create-virtualenv step (success) 12:14:48 INFO - [mozharness: 2016-12-31 20:14:48.145381Z] Running install step. 12:14:48 INFO - Running pre-action listener: _resource_record_pre_action 12:14:48 INFO - Running main action method: install 12:14:48 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:14:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:14:48 INFO - Reading from file tmpfile_stdout 12:14:48 INFO - Detecting whether we're running mozinstall >=1.0... 12:14:48 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 12:14:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 12:14:48 INFO - Reading from file tmpfile_stdout 12:14:48 INFO - Output received: 12:14:48 INFO - Usage: mozinstall [options] installer 12:14:48 INFO - Options: 12:14:48 INFO - -h, --help show this help message and exit 12:14:48 INFO - -d DEST, --destination=DEST 12:14:48 INFO - Directory to install application into. [default: 12:14:48 INFO - "/builds/slave/test"] 12:14:48 INFO - --app=APP Application being installed. [default: firefox] 12:14:48 INFO - mkdir: /builds/slave/test/build/application 12:14:48 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'] 12:14:48 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 12:15:10 INFO - Reading from file tmpfile_stdout 12:15:10 INFO - Output received: 12:15:10 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 12:15:10 INFO - Running post-action listener: _resource_record_post_action 12:15:10 INFO - [mozharness: 2016-12-31 20:15:10.097579Z] Finished install step (success) 12:15:10 INFO - [mozharness: 2016-12-31 20:15:10.097712Z] Running run-tests step. 12:15:10 INFO - Running pre-action listener: _resource_record_pre_action 12:15:10 INFO - Running main action method: run_tests 12:15:10 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 12:15:10 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 12:15:10 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 12:15:10 INFO - 2016-12-31 12:15:10.112 screenresolution[1641:10677] starting screenresolution argv=screenresolution get 12:15:10 INFO - 2016-12-31 12:15:10.115 screenresolution[1641:10677] Display 0: 1600x1200x32@60 12:15:10 INFO - 2016-12-31 12:15:10.120 screenresolution[1642:10680] starting screenresolution argv=screenresolution list 12:15:10 INFO - Available Modes on Display 0 12:15:10 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 12:15:10 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 12:15:10 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 12:15:10 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 12:15:10 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 12:15:10 INFO - Intel Iris: 12:15:10 INFO - Chipset Model: Intel Iris 12:15:10 INFO - Type: GPU 12:15:10 INFO - Bus: Built-In 12:15:10 INFO - VRAM (Dynamic, Max): 1536 MB 12:15:10 INFO - Vendor: Intel (0x8086) 12:15:10 INFO - Device ID: 0x0a2e 12:15:10 INFO - Revision ID: 0x0009 12:15:10 INFO - Displays: 12:15:10 INFO - Display: 12:15:10 INFO - Resolution: 1600 x 1200 @ 60 Hz 12:15:10 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 12:15:10 INFO - Display Serial Number: 1600x1200 60 12:15:10 INFO - Main Display: Yes 12:15:10 INFO - Mirror: Off 12:15:10 INFO - Online: Yes 12:15:10 INFO - Rotation: Supported 12:15:10 INFO - Return code: 0 12:15:10 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 12:15:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 12:15:10 INFO - Python 2.7.3 12:15:10 INFO - Return code: 0 12:15:10 INFO - grabbing minidump binary from tooltool 12:15:10 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')]} 12:15:10 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 0x10a606d30>, '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 0x10a52ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a5fbbe8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 12:15:10 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 12:15:10 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 12:15:10 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 12:15:10 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 12:15:10 INFO - Return code: 0 12:15:10 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 12:15:10 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 12:15:10 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 12:15:10 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 12:15:10 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 12:15:10 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/HLf5heFdRsaYnjwsLdBnDw/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0365', '--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 12:15:10 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/HLf5heFdRsaYnjwsLdBnDw/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0365 --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 12:15:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zt15ehN6Sk/Render', 12:15:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:15:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:15:10 INFO - 'HOME': '/Users/cltbld', 12:15:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:15:10 INFO - 'LOGNAME': 'cltbld', 12:15:10 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 12:15:10 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 12:15:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:15:10 INFO - 'MOZ_NO_REMOTE': '1', 12:15:10 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 12:15:10 INFO - 'NO_EM_RESTART': '1', 12:15:10 INFO - 'PAGER': '/bin/cat', 12:15:10 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:15:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:15:10 INFO - 'PWD': '/builds/slave/test', 12:15:10 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 12:15:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:15:10 INFO - 'SHELL': '/bin/bash', 12:15:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.2mlI09uo26/Listeners', 12:15:10 INFO - 'TMPDIR': '/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/', 12:15:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:15:10 INFO - 'USER': 'cltbld', 12:15:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:15:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:15:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:15:10 INFO - 'XPC_FLAGS': '0x0', 12:15:10 INFO - 'XPC_SERVICE_NAME': '0', 12:15:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:15:10 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/HLf5heFdRsaYnjwsLdBnDw/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0365', '--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 12:15:10 INFO - mozversion application_buildid: 20161231110819 12:15:10 INFO - mozversion application_changeset: cb9f43eb552570e185e95dfc8acb9f9fbe47ae17 12:15:10 INFO - mozversion application_display_name: Nightly 12:15:10 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 12:15:10 INFO - mozversion application_name: Firefox 12:15:10 INFO - mozversion application_remotingname: firefox 12:15:10 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 12:15:10 INFO - mozversion application_vendor: Mozilla 12:15:10 INFO - mozversion application_version: 53.0a1 12:15:10 INFO - mozversion platform_buildid: 20161231110819 12:15:10 INFO - mozversion platform_changeset: cb9f43eb552570e185e95dfc8acb9f9fbe47ae17 12:15:10 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 12:15:10 INFO - mozversion platform_version: 53.0a1 12:15:10 INFO - using testdate: 1483215310 12:15:10 INFO - actual date: 1483215310 12:15:10 INFO - starting webserver on 'localhost:49235' 12:15:10 INFO - SUITE-START | Running 2 tests 12:15:10 INFO - TEST-START | dromaeo_css 12:15:10 INFO - operating with platform_type : mac_ 12:15:10 INFO - Initialising browser for dromaeo_css test... 12:15:10 INFO - TEST-INFO | started process 1652 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/tmphYbAfD/profile http://localhost:49235/getInfo.html) 12:15:11 INFO - PROCESS | 1652 | 1483215311238 addons.xpi WARN Exception running bootstrap method install on shield-recipe-client@mozilla.org: [Exception... "Component returned failure code: 0x8000ffff (NS_ERROR_UNEXPECTED) [nsIPrefBranch.getBoolPref]" nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js :: this.install :: line 38" data: no] Stack trace: this.install()@resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js:38 < callBootstrapMethod()@resource://gre/modules/addons/XPIProvider.jsm:4986 < processFileChanges()@resource://gre/modules/addons/XPIProvider.jsm -> resource://gre/modules/addons/XPIProviderUtils.js:2097 < checkForChanges()@resource://gre/modules/addons/XPIProvider.jsm:3827 < startup()@resource://gre/modules/addons/XPIProvider.jsm:2831 < callProvider()@resource://gre/modules/AddonManager.jsm:264 < _startProvider()@resource://gre/modules/AddonManager.jsm:771 < startup()@resource://gre/modules/AddonManager.jsm:957 < startup()@resource://gre/modules/AddonManager.jsm:2923 < observe()@resource://gre/components/addonManager.js:65 12:15:13 INFO - PROCESS | 1652 | __metrics Screen width/height:1600/1200 12:15:13 INFO - PROCESS | 1652 | colorDepth:24 12:15:13 INFO - PROCESS | 1652 | Browser inner width/height: 1024/689 12:15:13 INFO - PROCESS | 1652 | __metrics 12:15:13 INFO - PROCESS | 1652 | 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" 12:15:13 INFO - PROCESS | 1652 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 12:15:14 INFO - TEST-INFO | 1652: exit 0 12:15:14 INFO - Browser initialized. 12:15:14 INFO - Running cycle 1/1 for dromaeo_css test... 12:15:14 INFO - TEST-INFO | started process 1655 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/tmphYbAfD/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 12:15:26 INFO - PROCESS | 1655 | RSS: Main: 234209280 12:15:26 INFO - PROCESS | 1655 | 12:16:17 INFO - PROCESS | 1655 | 1483215377221 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] 12:18:31 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49235/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49235/tests/dromaeo/cssquery-ext.html) 12:18:32 INFO - PROCESS | 1655 | RSS: Main: 354308096 12:18:32 INFO - PROCESS | 1655 | 12:20:15 INFO - PROCESS | 1655 | ************************* 12:20:15 INFO - PROCESS | 1655 | A coding exception was thrown and uncaught in a Task. 12:20:15 INFO - PROCESS | 1655 | 12:20:15 INFO - PROCESS | 1655 | Full message: ReferenceError: fetch is not defined 12:20:15 INFO - PROCESS | 1655 | Full stack: apiCall@resource://shield-recipe-client/lib/NormandyApi.jsm:37:5 12:20:15 INFO - PROCESS | 1655 | get@resource://shield-recipe-client/lib/NormandyApi.jsm:44:12 12:20:15 INFO - PROCESS | 1655 | this.NormandyApi.fetchRecipes<@resource://shield-recipe-client/lib/NormandyApi.jsm:52:34 12:20:15 INFO - PROCESS | 1655 | TaskImpl_run@resource://gre/modules/Task.jsm:319:42 12:20:15 INFO - PROCESS | 1655 | TaskImpl@resource://gre/modules/Task.jsm:277:3 12:20:15 INFO - PROCESS | 1655 | asyncFunction@resource://gre/modules/Task.jsm:252:14 12:20:15 INFO - PROCESS | 1655 | this.RecipeRunner.start<@resource://shield-recipe-client/lib/RecipeRunner.jsm:64:23 12:20:15 INFO - PROCESS | 1655 | TaskImpl_run@resource://gre/modules/Task.jsm:319:42 12:20:15 INFO - PROCESS | 1655 | TaskImpl@resource://gre/modules/Task.jsm:277:3 12:20:15 INFO - PROCESS | 1655 | asyncFunction@resource://gre/modules/Task.jsm:252:14 12:20:15 INFO - PROCESS | 1655 | setTimeout_timer@resource://gre/modules/Timer.jsm:30:5 12:20:15 INFO - PROCESS | 1655 | 12:20:15 INFO - PROCESS | 1655 | ************************* 12:21:35 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49235/tests/dromaeo/cssquery-ext.html (next: http://localhost:49235/tests/dromaeo/cssquery-jquery.html) 12:21:35 INFO - PROCESS | 1655 | RSS: Main: 365178880 12:21:35 INFO - PROCESS | 1655 | 12:24:38 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49235/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49235/tests/dromaeo/cssquery-mootools.html) 12:24:39 INFO - PROCESS | 1655 | RSS: Main: 341286912 12:24:39 INFO - PROCESS | 1655 | 12:27:41 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49235/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49235/tests/dromaeo/cssquery-prototype.html) 12:27:42 INFO - PROCESS | 1655 | RSS: Main: 351051776 12:27:42 INFO - PROCESS | 1655 | 12:30:45 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49235/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49235/tests/dromaeo/cssquery-yui.html) 12:30:46 INFO - PROCESS | 1655 | RSS: Main: 330731520 12:30:46 INFO - PROCESS | 1655 | 12:33:49 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49235/tests/dromaeo/cssquery-yui.html (next: http://localhost:49235/tests/dromaeo/cssquery-dojo.html) 12:33:49 INFO - PROCESS | 1655 | RSS: Main: 496947200 12:33:49 INFO - PROCESS | 1655 | 12:33:49 INFO - PROCESS | 1655 | __start_tp_report 12:33:49 INFO - PROCESS | 1655 | _x_x_mozilla_page_load 12:33:49 INFO - PROCESS | 1655 | _x_x_mozilla_page_load_details 12:33:49 INFO - PROCESS | 1655 | |i|pagename|runs| 12:33:49 INFO - PROCESS | 1655 | |0;dojo.html;3256.902292931212;3349.2129349602847;3379.307242015386;3425.640307767686;3433.6909678128977;5590.552755779538;5662.6602403855695;5667.4049224831415;5691.914621280679;5707.14392841074;5654.6041777075625;5683.3464151622575;5756.222909907161;5769.394213607574;5796.536277097822;5100.33695619569;5143.871403214931;5155.072087024334;5200.661956972804;5249.527542521172;5299.735013249341;5319.989202051617;5333.813316533922;5346.6791992480385;5390.083685773408;5150.098732721796;5270.314859068335;5281.07581173297;5297.8940421191555;5310.309659744247;5164.664296820694;5175.223716442526;5222.268882356473;5230.712310822903;5273;5202.76587553561;5203.219517072432;5211.973940130275;5237.952409518073;5260.237265596467;5640.09758438652;5703.800366987177;5717.371089180187;5723.885522289573;5787;6091.756329746805;6131.601445906002;6133;6145.770845830852;6209.379062093754;5228.8692782680355;6258.092576576417;6302.054691796221;6337;6340.936590634081;5778.711064446845;5792.507636850831;5804;5806.680632565165;5859.121131830217;5741.45456181662;5953.464188223081;6019.735855470312;6262.874742505124;6275.215598050244;5670.5463562914865;5747.3103227612955;6082.1789058477425;6136.151476879633;6180.81457556274;6108.463491842691;6288.371162883675;6296.8425789354715;6298.8425289367215;6301.4328710416285;6060.545459090586;6106.236720409949;6114.908276375858;6130.807181375102;6149.538784591174;5216.426193118754;6207.782727604603;6233.501319894398;6243.094751261042;6249.1563638908165;4789.9042019160115;4793.520647935178;4796.328514008042;4912.582430493448;4938.703677779345;4315.654747620183;4336.262835318003;4354.455693038371;4360.389545463638;4405;5641.407652196543;5673.773049078073;5676.574256930664;5680.545556355564;5686.0333743263745;6100.823402702991;6157.96921015392;6161.876762464725;6165.075238714139;6165.660888651168;5701.515371193412;5741.62679425836;5776.624519406225;5816.47651711348;5829.7376618052285;5368.516833485005;5422.186671999153;5429.158480435539;5442.23808666779;5461.344638643388;6255.061740738925;6298.968505157536;6304.243490780952;6308.589941653778;6323.683815809283;5942.494887934574;6028.90956635642;6042.395760423922;6153.876922461436;6321.399467050623;5369.382521010134;6250.874982500233;6285.59143655661;6296.087067375296;6315;6161.876762464636;6164.106204600218;6164.969175154096;6186.133941248138;6190.350013248544;6126.578745377254;6147.5245940972945;6186.041163619646;6201.3178550358525;6259.280182779039;5986.161937328865;6000.159977603052;6028.216331876828;6088.086786981811;6117.571769976059;5908.645481271137;6016.699165041818;6022.638641681513;6038.335783063947;6042.969785151046;5802;5828.388019258086;5854.94145058561;5917.644941303535;5932.49573786233;5919.082542205965;5986.580939334205;6132.78535251264;6146.6004709693725;6147.631142131308;4688.531146885284;5857.912131318119;5870.471657550841;5882.029465138205;5948 12:33:49 INFO - PROCESS | 1655 | |1;ext.html;451.2689443102175;469.85199662106453;490.5290920716112;498.20038837665544;504.79051193754594;29305.706942930592;37724.811375943114;39655.603443965585;39747;39794;40849.7957510212;40966.38550421746;41009.79495102528;41097.273813511456;41183.79408102963;8121.553314567696;9170.312226583017;9348.532573371338;9378.437293762361;9428.481433521154;8266.669333226664;8567.100454452255;10862.076723478474;11208;11222.943885280562;11115.999560039594;11149.72125696856;11166.497507612135;11201.551937922513;11238.438078096104;11126.49930753114;11207.271527350696;11212.04697600705;11214.831777523303;11232.3260604364;8277.668893244263;8553.101924297984;10686.46567671621;11025.393603351813;11030.558777648885;32478;38659;39004.609953900384;39017.41473877894;39038.414423783666;3963.623455771697;3971.324874771267;3985.4021896715435;3990.501187351581;4122.9381559276635;24460.633090503656;29126.70873291261;29260.8536957316;30013.849930750424;30551.847240763876;3016.1253236561593;3078.184281165493;3081.445339838828;3103.2707313781243;3181.125190572588;436300;441436;441663.750437437;442505;442926;52550;53120.203196952076;53225.2016219757;53309.73345133327;53347.466525335025;55007.72496137494;55050;55119.448805512235;55222.17166742502;55234.447655523734;14021.5092471763;14447.49433769813;14451.566453006426;14480.565583032527;14485.85514144845;10084.344517606332;10811.243212975016;11183.384913829655;11265.436728163559;11290.209685322114;14309;14695.853041469449;16239;16672.748813981158;16880.577985550215;6333.118431113832;6770.119884414995;6934.930650693529;7089.610071446017;7120.291130128835;837.351052933981;848.3255811629646;849.6813694864426;880.2561835249204;892.312919052326;22544.323670289916;24173.395665108514;29232.26919327034;29421.11736647904;29685.70704737498;937.329809186435;981.7874924468276;1036.3678156324636;1068.3963560588243;1195.8983486403754;14641.414343426357;15080.095194288378;15350.539483815503;15437.996530225499;15521.611959701098;642852;645265.773671138;646420;647866;648659;56447.15329270064;57023.14465283024;57147.714261428424;57170.714146429;57343.71328143332;1355.3697530648296;1396.4484028808563;1401.5094716849226;1402.7194561088027;1409.302395314326;27958.58062129029;28873.566896496148;29048.854755726086;29482.557761634023;29501.26246843846;48234;48548;48598.71019347126;48657.27014094721;48849.5115048845;516668;517970;518512;518843;519250;25467.981280749304;25815.225543233733;25830;25841.483170336112;25911.222663319375;44993.87515312144;45126.77436612796;46117.76941115273;46123;46125.538744612124;36611;36905.87600330751;37117.62882371142;37442.251154976206;38550;35908.922732318075;36442.63557364392;36599.8630267106;37778.62221377861;37817.81091094527;36054.8197259012;36099.45850812292;36418.4537231947;36769.63230367662;36895;31667.36665266728;33122.17194570155;33133.83433082819;33171;33404.164895877795;23322.416939576648;24444.899979501322;24479;24998;25021.624675629515 12:33:49 INFO - PROCESS | 1655 | |2;jquery.html;348.78201124297317;367.6397130811806;368.49516162856884;376.3226192852864;397.97413168144067;14957.326920288586;15158.84841151588;15848.76226856598;19594.314199003038;20637.793622063775;19446.416607501797;19985.100670469827;20455.488612784688;20498.180072797106;20665.070071846767;5802.941970580293;5809.506191973666;6074.848128796793;6079.574429789916;6095.542834287424;5960.821175364746;5972.940270597303;5984.9401505984815;6019.5485338599565;6035.064564992434;4160.583941605845;4356.520782713915;5992.161097446361;6277.686115694197;6566.212054553436;5760.2511673482595;6676.833079173011;6693.163354580677;6696.598204107745;6696.631685257309;5209.01028804526;5685.573581981368;5763;5864.794732184396;5870.706464676749;12955.287459189742;14516;21760.455988600253;24108;24323.02707891682;2549.859757713325;2593.714691383965;2607.29603007187;2611.308003379106;2620.7248238934826;9601.711948641481;9646.035396460367;9654.179394751454;10137.986201379877;10157.035081667229;1763.1008185825194;1794.8142047865013;1815.6731788278105;1815.8910465371987;1853.2494339792406;55953.72023139858;92734.07265927322;92973;93523;105729;10977.780444391068;11134.777304453866;11240.943795280971;11250.774984500264;11449.771004579861;14264.714705705828;14317.498887538886;14317.57047288583;14326;14341.139531628136;8018.158093400109;8125.512469251864;8145.144759800254;8153;8311.127331630212;7939.3251573615735;7940.880886786703;8070.677172913135;8100.108988011314;8131.9593402032615;4307.932192547532;4406.086533594047;4423.734575925455;4462.486814016364;4548;1821.8178182181675;1881.74596429483;1886.4246404846654;1886.6510445031868;1894.1760334254643;878.8374150782125;906.9229115525122;919.5356345045709;922.3543519536217;924.2698268367914;19636.90181549083;20306;20334.186632534827;20364.79635203629;20400.387988360075;881.9901213110952;912.6395073945754;951.3435729346728;951.5527701980059;967.2455484721787;10310.896891031143;10582.312149710244;10602.57589696419;10606.840897386546;10616.628418005334;529071;533107;541167;558251;560839;91838;95095.52452237833;103873.48063259636;114430.42784786022;116259.41870290764;975.2490582251627;989.5200827598609;1028.3624153025023;1028.377831412;1031.4945676618404;18185;18226.90886545559;18596.44210673682;18858;18980.525486862938;39913.80043099766;48661.05355785729;49689.503104968484;49824.25263620976;50739.47781566559;67649.66175169093;69748;70779;71252;71898;6733.360330768569;7836.981192444946;8019.83960320802;8068.394870384862;8214.096449390681;9191.540422978691;10813.945930270298;11697;12430.813537797121;12823.42294596764;8991.23574496149;10847.620333288298;11039.172062095535;11219.7756044877;11241.775164496501;10000.749981250236;10841.457927103456;10871.184661150288;10998.835017474905;11084.556617735201;11331.32012079278;11342.092632589209;11437.542498300307;11457.312561246352;11508.42457877086;6541.803745887631;7907.802304942193;7944.721934732257;8323.6670533178;8350.958245208978;9881.654142104991;10590.89409105899;10838.512266948164;11116.331610568655;11116.777664446827 12:33:49 INFO - PROCESS | 1655 | |3;mootools.html;1379.813725147105;1384.986150138499;1399.5801259622117;1401.117296103455;1414.6816966182616;7859.724909628164;8260.050094239157;8270.669173233064;8275.420720549564;8315.251627353537;6488.610683359002;7329.780106596797;7608.923910760905;7872.01599800025;8085.353171746261;2235.1506427557506;2285.90856365746;2288.8741119238434;2297.069686776858;2297.115610489965;2283.22370394066;2306.3888069661552;2312.2138472919205;2315.2012555668266;2315.2822624986225;1993.1130646862152;2076.6262072826885;2182.0835249195297;2203.94490137747;2209;2273.15893119546;2281.942951426211;2283.2693538067824;2288.1533832481923;2292.747797742247;2234.1063574570303;2245.2141750387395;2252.966205506919;2259.581977334182;2264.173576644513;7075.433965282817;8039.95980020102;8231.382646301552;8243.464174828618;8263.338932885415;4910.558049775537;5089.847304580831;5095.8980820383385;5098.745062746848;5100.438951715309;2891.291633549794;3238.1257060593744;3396.983015084909;3411.5053317268857;3446.2590543033116;1162.436218434059;1195.910306726999;1225.7671042501895;1245.7508498300376;1253.7868562344424;10549.630762923338;10605.045545900906;10658.253922225447;10680.51937662807;10726.356418614909;4196.412502249687;4343.67422443318;4453.153900758845;4519.209138400766;4524.796384162721;8710.303175745925;8712.869306960401;8735.606897689619;8735.825283494294;8740.956295218482;4536.523665015192;4949.950500495021;4956.479569645207;4959.256111583291;4965.627577931597;4205.894852628709;4213.157368526307;4294.463192100988;4537.636989040869;4609.585137337589;3693.445983102556;3703.6481834035044;3772.5095737554493;3815.847366105325;3816.5229346331707;1216.6289281768968;1235.363143834405;1246.5948566715854;1248.6753444104597;1254.4856608790353;835.9916400836036;855.3584811391456;855.9657613695382;904.882365292521;908.3913777768911;4621;5298.576113910878;5460.808871689472;5497.972510137503;5555.416681248411;523.588982648619;526.9100489273675;535.6250624562822;536.2171230004237;544.7412479072409;3491;3527.4356103023392;3583.4266517357096;3601.6938560222675;3629.8729544466346;9525.380850244712;9856.50717464124;9872.802543949081;9944.35361701487;9960.153386962045;9844.3601165924;9846.803063938825;9857.852132218022;9862.901370986197;9891;6193.256809182837;7516.210797866145;7562.924370756332;7627.122880868771;7699.4610377274175;4579.862604121881;5355.919661205007;5435.048866448276;5442.945570544244;5463.371712253142;9145.314101442551;9272.907270927204;9340.34617576763;9363;9378.906210937803;9042.276617870686;9154.450732956044;9395.154436100784;9421.434713917188;9424.293178011814;6456.451201647913;6658.201015878126;6761.188657360952;6835.726570937306;6912.274211207952;6680.498962577729;7780.727674531364;7821.804454888674;7848.489848159851;7849.254320839511;6761.290064543349;6948.062011628365;6959.373656370951;6989.161300643752;7013.2986701329455;6815.284395138439;6842.178938527409;6859.451243900576;6887.931120688729;6923.792286231421;6109.389061093855;6226.968865155826;6308.810735677937;6330.683465826783;6333.018382150774;4593.471750748573;4657.278121891112;4687.460941991714;4709.976450117728;4840.661153719206;4668.766561671835;4749.3113498541825;4760.642951778561;4811.3264143019305;4831.444383895897 12:33:49 INFO - PROCESS | 1655 | |4;prototype.html;343.50878244110925;348.44597090625905;351.93665140274743;363.3786225554302;367.2819637608474;5132.820351287705;5171.663841850277;5179.663321884075;5190.169572868337;5199.506046925546;4378.496472905609;4528.886777830556;5009.14844476439;5026.210453198482;5031.471695471978;3896.9415458768;4014.3376342903384;4029.6776257899296;4031.2743706132883;4160.230357383879;4068.166025964685;4107;4134.8139333729905;4137.8965525861795;4138.917221655565;3439.6216416194197;3893.3770596704535;3968.3921049883397;4007.7194596378267;4082.7142100053006;3705.7961812100325;4059.269331520325;4074.5925407459313;4088.2232375848644;4090.5683010946227;2926.75122614578;3230.3862266169353;3268.7711860169798;3604.4953706481115;3653.2328211075705;4555.492270951382;4830.903381932377;5020.347354843847;5054.620903432257;5077.136886729265;3905.433712111728;4476.865694029184;4637.86086417408;4647.512011238805;4689;4788.449328327252;4926.5812405945535;5062.215356619729;5195.610329225286;5407.161889907071;3359.395308844407;3950.0572073805197;3965.345717956534;3969.7816620085882;3970.06703424695;5450.236966824608;5640.943590564083;5655.660660360391;5738.655680659173;5761.366249712528;3897.2010737798682;4023.75857448554;4037.2934736421007;4047.919041619151;4103.692223083281;3513.894583162509;4150.543440221573;4152.501699796044;4154.356074808409;4161.604647558477;4113.095119073799;4517.864464066083;4527.959023718084;4627.5885854814605;4734.715917044989;4733.668643195012;4905.70565766048;4966.304717339575;5154.329937108227;5443.727813609304;1323.3912400295778;1338.43785610044;1342.744878473087;1349.912255703376;1358.280111540885;801.9278264956182;885.9468431894107;914.3736540469799;921.9078092190862;967.5355829202023;4547.8180872764715;4610.216263235258;4755.453122890843;4819.325294458778;4821.879453013703;4504.338010159469;4703.882402939886;4731.550502702238;5063;5271.130263506555;4377.117839329056;4519.299508576176;4640.656468483394;4710.317004034464;4726.480087190475;2441.8633695516423;2597.7921766258655;2680.276325392133;2769.7259260739884;2792.6509186351705;4386.0569977455;5195.5971887590895;5333.586599551122;5465.59008074396;5494.186918316946;4710.670253082251;5041.899162016813;5174.050791049066;5345.35855697311;5476.479734425303;4511.210538155875;4957.769003948238;5329.493698098674;5347;5362.758675859595;4290.63529599975;4462.955370446384;4776.49234152059;4843.474305593868;5039;4837.7097374157665;5147.382314122329;5355.973220133875;5522.5858060646415;5662.462066103713;4929.728864912464;5765.798197063083;5767.365589785204;5790.1893734877995;5791.276090488689;3229.6931791479774;3472.2708231270703;3806.1834242660784;3807.3717836557767;3808.990617486313;4958.727270000185;5145.7941682333785;5273.736313184402;5389.8113566025;5634.816688495298;4843.830465933675;4881.877168251251;5156.510131537498;5366.7191443637075;5422;4479.417675702138;4672.696274742267;4700.2949557566635;4961.578265847299;5447.346318441812;4114.811021458394;4701.1537923174155;5319.41486436484;5373.838784836461;5378.919316210182;4965.7089156818765;5304.893902122013;5375.489328513785;5385.246065550904;5426.197814933891;4537.954620453753;5235.947640523698;5266.236395722675;5288.365396152486;5301.9926214019215 12:33:49 INFO - PROCESS | 1655 | |5;yui.html;715.6386025057345;718.2279050021227;723.4218941218269;762.1311704656692;807.2573232625983;14422.495212667558;15051.62370940727;15545.378184872605;15562.299696513655;15563.221838908066;13756.518521851736;13966.581002569914;14076.78884816726;14199.290035498236;14202;682.3994884501657;703.085988215318;706.2937062937064;710.3784188834769;864.425157270416;697.909271794666;835.2274146414572;865.8614621660536;869.7999460124175;871.877937088808;915.1854849184232;921.7004473546124;922.5802259971697;924.4129977464291;928.4846909964966;916.7616419730851;919.296737995434;920.7559996600907;921.272194965976;921.9769505762342;527.699211449474;683.2689022745664;686.354826463128;686.8179932317939;686.9038334633156;12365.505379784889;13707.177569345871;14176.220307883063;14278;14517.491887783877;13636.863631363658;13882.722345553133;13925.303734813218;13946.23295718735;13986.370613322424;11693.00609448198;11807.58673446434;11868.584599538975;11969.760604787856;12214;3029.742471889892;3586.982065089684;3697.038769919813;3777.6033516480925;3826.1773718650625;91194.5440272801;91702.0829791707;92902;93075.06924930731;93150;10712.839307410395;10764.946175269073;10797.190210734227;10869.29349592274;10979.451027448597;19768.70346944797;20711.378658640264;20794.376168714964;21991.670124948138;22005.559888802134;7697.23027697237;7704.306612404798;7768.339691126317;7777.650005749755;7896.960515197387;3558.6085530591613;4126.711130220916;4158.209940111369;4171.499420069611;4197.97901010493;3484.6166921639106;3534.3814832404228;3561.6082230954576;3590.2460483298278;3600.2259514204047;651.4592887903029;652.7617419641891;657.2605818454239;663.7444583835259;665.490899461912;507.75373943637305;642.8874946884276;650.3853858104046;661.1801366305747;662.953593248468;7193.496455248082;7774.455788094892;8833.602487887933;8839.983401908734;8991.640334386682;613.5459759777822;650.9088727578143;655.275920108283;664.8238216872533;680.2992917295194;3995.260876737813;4127.731697439657;4366.432363792686;4369.584889435499;4377.4528183977;98750.5062474683;99671;100662;101474.49262753638;105200.42199367016;84015.57992210123;84751;86203;86477;87581.05236845394;53700;65773;66375.00437493442;68082.9787553187;71741;9329;9381.437113773196;9557.791645516607;9585.376950498196;9701.07839755222;42645.57354426416;45457.54542454533;46340.30489542592;47106;48516;52984.47015529795;55260;57732.71133644305;57999.13001304899;61013.69493152506;11424;12591.685207869876;12985.870141298845;13113.147645403018;13154.255583743796;14339.713205736036;14438.85561144375;14655.92672036633;14697.92651036738;15284.388624454898;13347.733045339233;13523.661908452368;13568.050236483352;13702.725945480835;13762.311884405939;13256.602301930958;13322.33388330599;13489.190648561118;13737.313134343443;13742.519011834538;13188.340582971005;13322.933385333012;13382.93308533451;13507.729845403233;13948.4420623178;10098.646547370807;10444.63443779464;10479.842802358124;10881.292715973754;11074.446277685922;8512.74461766148;10222.97096422549;10333.276670632984;10362.890942957014;10444.2166837489 12:33:49 INFO - PROCESS | 1655 | __end_tp_report 12:33:49 INFO - PROCESS | 1655 | __start_cc_report 12:33:49 INFO - PROCESS | 1655 | _x_x_mozilla_cycle_collect,1300 12:33:49 INFO - PROCESS | 1655 | __end_cc_report 12:33:49 INFO - PROCESS | 1655 | __startTimestamp1483216429754__endTimestamp 12:33:49 INFO - PROCESS | 1655 | 12:33:49 INFO - PROCESS | 1655 | ------- Summary: start ------- 12:33:49 INFO - PROCESS | 1655 | Number of tests: 6 12:33:49 INFO - PROCESS | 1655 | 12:33:49 INFO - PROCESS | 1655 | [#0] dojo.html Cycles:165 Average:5692.57 Median:5829.74 stddev:626.31 (10.7%) stddev-sans-first:598.38 12:33:49 INFO - PROCESS | 1655 | Values: 3256.9 3349.2 3379.3 3425.6 3433.7 5590.6 5662.7 5667.4 5691.9 5707.1 5654.6 5683.3 5756.2 5769.4 5796.5 5100.3 5143.9 5155.1 5200.7 5249.5 5299.7 5320.0 5333.8 5346.7 5390.1 5150.1 5270.3 5281.1 5297.9 5310.3 5164.7 5175.2 5222.3 5230.7 5273.0 5202.8 5203.2 5212.0 5238.0 5260.2 5640.1 5703.8 5717.4 5723.9 5787.0 6091.8 6131.6 6133.0 6145.8 6209.4 5228.9 6258.1 6302.1 6337.0 6340.9 5778.7 5792.5 5804.0 5806.7 5859.1 5741.5 5953.5 6019.7 6262.9 6275.2 5670.5 5747.3 6082.2 6136.2 6180.8 6108.5 6288.4 6296.8 6298.8 6301.4 6060.5 6106.2 6114.9 6130.8 6149.5 5216.4 6207.8 6233.5 6243.1 6249.2 4789.9 4793.5 4796.3 4912.6 4938.7 4315.7 4336.3 4354.5 4360.4 4405.0 5641.4 5673.8 5676.6 5680.5 5686.0 6100.8 6158.0 6161.9 6165.1 6165.7 5701.5 5741.6 5776.6 5816.5 5829.7 5368.5 5422.2 5429.2 5442.2 5461.3 6255.1 6299.0 6304.2 6308.6 6323.7 5942.5 6028.9 6042.4 6153.9 6321.4 5369.4 6250.9 6285.6 6296.1 6315.0 6161.9 6164.1 6165.0 6186.1 6190.4 6126.6 6147.5 6186.0 6201.3 6259.3 5986.2 6000.2 6028.2 6088.1 6117.6 5908.6 6016.7 6022.6 6038.3 6043.0 5802.0 5828.4 5854.9 5917.6 5932.5 5919.1 5986.6 6132.8 6146.6 6147.6 4688.5 5857.9 5870.5 5882.0 5948.0 12:33:49 INFO - PROCESS | 1655 | 12:33:49 INFO - PROCESS | 1655 | [#1] ext.html Cycles:180 Average:66243.07 Median:37011.75 stddev:144835.33 (391.3%) stddev-sans-first:145157.40 12:33:49 INFO - PROCESS | 1655 | Values: 451.3 469.9 490.5 498.2 504.8 29305.7 37724.8 39655.6 39747.0 39794.0 40849.8 40966.4 41009.8 41097.3 41183.8 8121.6 9170.3 9348.5 9378.4 9428.5 8266.7 8567.1 10862.1 11208.0 11222.9 11116.0 11149.7 11166.5 11201.6 11238.4 11126.5 11207.3 11212.0 11214.8 11232.3 8277.7 8553.1 10686.5 11025.4 11030.6 32478.0 38659.0 39004.6 39017.4 39038.4 3963.6 3971.3 3985.4 3990.5 4122.9 24460.6 29126.7 29260.9 30013.8 30551.8 3016.1 3078.2 3081.4 3103.3 3181.1 436300.0 441436.0 441663.8 442505.0 442926.0 52550.0 53120.2 53225.2 53309.7 53347.5 55007.7 55050.0 55119.4 55222.2 55234.4 14021.5 14447.5 14451.6 14480.6 14485.9 10084.3 10811.2 11183.4 11265.4 11290.2 14309.0 14695.9 16239.0 16672.7 16880.6 6333.1 6770.1 6934.9 7089.6 7120.3 837.4 848.3 849.7 880.3 892.3 22544.3 24173.4 29232.3 29421.1 29685.7 937.3 981.8 1036.4 1068.4 1195.9 14641.4 15080.1 15350.5 15438.0 15521.6 642852.0 645265.8 646420.0 647866.0 648659.0 56447.2 57023.1 57147.7 57170.7 57343.7 1355.4 1396.4 1401.5 1402.7 1409.3 27958.6 28873.6 29048.9 29482.6 29501.3 48234.0 48548.0 48598.7 48657.3 48849.5 516668.0 517970.0 518512.0 518843.0 519250.0 25468.0 25815.2 25830.0 25841.5 25911.2 44993.9 45126.8 46117.8 46123.0 46125.5 36611.0 36905.9 37117.6 37442.3 38550.0 35908.9 36442.6 36599.9 37778.6 37817.8 36054.8 36099.5 36418.5 36769.6 36895.0 31667.4 33122.2 33133.8 33171.0 33404.2 23322.4 24444.9 24479.0 24998.0 25021.6 12:33:49 INFO - PROCESS | 1655 | 12:33:49 INFO - PROCESS | 1655 | [#2] jquery.html Cycles:180 Average:31303.92 Median:20155.89 stddev:90175.18 (447.4%) stddev-sans-first:90398.19 12:33:49 INFO - PROCESS | 1655 | Values: 348.8 367.6 368.5 376.3 398.0 14957.3 15158.8 15848.8 19594.3 20637.8 19446.4 19985.1 20455.5 20498.2 20665.1 5802.9 5809.5 6074.8 6079.6 6095.5 5960.8 5972.9 5984.9 6019.5 6035.1 4160.6 4356.5 5992.2 6277.7 6566.2 5760.3 6676.8 6693.2 6696.6 6696.6 5209.0 5685.6 5763.0 5864.8 5870.7 12955.3 14516.0 21760.5 24108.0 24323.0 2549.9 2593.7 2607.3 2611.3 2620.7 9601.7 9646.0 9654.2 10138.0 10157.0 1763.1 1794.8 1815.7 1815.9 1853.2 55953.7 92734.1 92973.0 93523.0 105729.0 10977.8 11134.8 11240.9 11250.8 11449.8 14264.7 14317.5 14317.6 14326.0 14341.1 8018.2 8125.5 8145.1 8153.0 8311.1 7939.3 7940.9 8070.7 8100.1 8132.0 4307.9 4406.1 4423.7 4462.5 4548.0 1821.8 1881.7 1886.4 1886.7 1894.2 878.8 906.9 919.5 922.4 924.3 19636.9 20306.0 20334.2 20364.8 20400.4 882.0 912.6 951.3 951.6 967.2 10310.9 10582.3 10602.6 10606.8 10616.6 529071.0 533107.0 541167.0 558251.0 560839.0 91838.0 95095.5 103873.5 114430.4 116259.4 975.2 989.5 1028.4 1028.4 1031.5 18185.0 18226.9 18596.4 18858.0 18980.5 39913.8 48661.1 49689.5 49824.3 50739.5 67649.7 69748.0 70779.0 71252.0 71898.0 6733.4 7837.0 8019.8 8068.4 8214.1 9191.5 10813.9 11697.0 12430.8 12823.4 8991.2 10847.6 11039.2 11219.8 11241.8 10000.7 10841.5 10871.2 10998.8 11084.6 11331.3 11342.1 11437.5 11457.3 11508.4 6541.8 7907.8 7944.7 8323.7 8351.0 9881.7 10590.9 10838.5 11116.3 11116.8 12:33:49 INFO - PROCESS | 1655 | 12:33:49 INFO - PROCESS | 1655 | [#3] mootools.html Cycles:180 Average:5245.33 Median:5099.59 stddev:2904.04 (56.9%) stddev-sans-first:2897.66 12:33:49 INFO - PROCESS | 1655 | Values: 1379.8 1385.0 1399.6 1401.1 1414.7 7859.7 8260.1 8270.7 8275.4 8315.3 6488.6 7329.8 7608.9 7872.0 8085.4 2235.2 2285.9 2288.9 2297.1 2297.1 2283.2 2306.4 2312.2 2315.2 2315.3 1993.1 2076.6 2182.1 2203.9 2209.0 2273.2 2281.9 2283.3 2288.2 2292.7 2234.1 2245.2 2253.0 2259.6 2264.2 7075.4 8040.0 8231.4 8243.5 8263.3 4910.6 5089.8 5095.9 5098.7 5100.4 2891.3 3238.1 3397.0 3411.5 3446.3 1162.4 1195.9 1225.8 1245.8 1253.8 10549.6 10605.0 10658.3 10680.5 10726.4 4196.4 4343.7 4453.2 4519.2 4524.8 8710.3 8712.9 8735.6 8735.8 8741.0 4536.5 4950.0 4956.5 4959.3 4965.6 4205.9 4213.2 4294.5 4537.6 4609.6 3693.4 3703.6 3772.5 3815.8 3816.5 1216.6 1235.4 1246.6 1248.7 1254.5 836.0 855.4 856.0 904.9 908.4 4621.0 5298.6 5460.8 5498.0 5555.4 523.6 526.9 535.6 536.2 544.7 3491.0 3527.4 3583.4 3601.7 3629.9 9525.4 9856.5 9872.8 9944.4 9960.2 9844.4 9846.8 9857.9 9862.9 9891.0 6193.3 7516.2 7562.9 7627.1 7699.5 4579.9 5355.9 5435.0 5442.9 5463.4 9145.3 9272.9 9340.3 9363.0 9378.9 9042.3 9154.5 9395.2 9421.4 9424.3 6456.5 6658.2 6761.2 6835.7 6912.3 6680.5 7780.7 7821.8 7848.5 7849.3 6761.3 6948.1 6959.4 6989.2 7013.3 6815.3 6842.2 6859.5 6887.9 6923.8 6109.4 6227.0 6308.8 6330.7 6333.0 4593.5 4657.3 4687.5 4710.0 4840.7 4668.8 4749.3 4760.6 4811.3 4831.4 12:33:49 INFO - PROCESS | 1655 | 12:33:49 INFO - PROCESS | 1655 | [#4] prototype.html Cycles:180 Average:4322.11 Median:4718.58 stddev:1259.50 (26.7%) stddev-sans-first:1227.12 12:33:49 INFO - PROCESS | 1655 | Values: 343.5 348.4 351.9 363.4 367.3 5132.8 5171.7 5179.7 5190.2 5199.5 4378.5 4528.9 5009.1 5026.2 5031.5 3896.9 4014.3 4029.7 4031.3 4160.2 4068.2 4107.0 4134.8 4137.9 4138.9 3439.6 3893.4 3968.4 4007.7 4082.7 3705.8 4059.3 4074.6 4088.2 4090.6 2926.8 3230.4 3268.8 3604.5 3653.2 4555.5 4830.9 5020.3 5054.6 5077.1 3905.4 4476.9 4637.9 4647.5 4689.0 4788.4 4926.6 5062.2 5195.6 5407.2 3359.4 3950.1 3965.3 3969.8 3970.1 5450.2 5640.9 5655.7 5738.7 5761.4 3897.2 4023.8 4037.3 4047.9 4103.7 3513.9 4150.5 4152.5 4154.4 4161.6 4113.1 4517.9 4528.0 4627.6 4734.7 4733.7 4905.7 4966.3 5154.3 5443.7 1323.4 1338.4 1342.7 1349.9 1358.3 801.9 885.9 914.4 921.9 967.5 4547.8 4610.2 4755.5 4819.3 4821.9 4504.3 4703.9 4731.6 5063.0 5271.1 4377.1 4519.3 4640.7 4710.3 4726.5 2441.9 2597.8 2680.3 2769.7 2792.7 4386.1 5195.6 5333.6 5465.6 5494.2 4710.7 5041.9 5174.1 5345.4 5476.5 4511.2 4957.8 5329.5 5347.0 5362.8 4290.6 4463.0 4776.5 4843.5 5039.0 4837.7 5147.4 5356.0 5522.6 5662.5 4929.7 5765.8 5767.4 5790.2 5791.3 3229.7 3472.3 3806.2 3807.4 3809.0 4958.7 5145.8 5273.7 5389.8 5634.8 4843.8 4881.9 5156.5 5366.7 5422.0 4479.4 4672.7 4700.3 4961.6 5447.3 4114.8 4701.2 5319.4 5373.8 5378.9 4965.7 5304.9 5375.5 5385.2 5426.2 4538.0 5235.9 5266.2 5288.4 5302.0 12:33:49 INFO - PROCESS | 1655 | 12:33:49 INFO - PROCESS | 1655 | [#5] yui.html Cycles:180 Average:19013.70 Median:4368.01 stddev:26804.32 (613.7%) stddev-sans-first:26844.30 12:33:49 INFO - PROCESS | 1655 | Values: 715.6 718.2 723.4 762.1 807.3 14422.5 15051.6 15545.4 15562.3 15563.2 13756.5 13966.6 14076.8 14199.3 14202.0 682.4 703.1 706.3 710.4 864.4 697.9 835.2 865.9 869.8 871.9 915.2 921.7 922.6 924.4 928.5 916.8 919.3 920.8 921.3 922.0 527.7 683.3 686.4 686.8 686.9 12365.5 13707.2 14176.2 14278.0 14517.5 13636.9 13882.7 13925.3 13946.2 13986.4 11693.0 11807.6 11868.6 11969.8 12214.0 3029.7 3587.0 3697.0 3777.6 3826.2 91194.5 91702.1 92902.0 93075.1 93150.0 10712.8 10764.9 10797.2 10869.3 10979.5 19768.7 20711.4 20794.4 21991.7 22005.6 7697.2 7704.3 7768.3 7777.7 7897.0 3558.6 4126.7 4158.2 4171.5 4198.0 3484.6 3534.4 3561.6 3590.2 3600.2 651.5 652.8 657.3 663.7 665.5 507.8 642.9 650.4 661.2 663.0 7193.5 7774.5 8833.6 8840.0 8991.6 613.5 650.9 655.3 664.8 680.3 3995.3 4127.7 4366.4 4369.6 4377.5 98750.5 99671.0 100662.0 101474.5 105200.4 84015.6 84751.0 86203.0 86477.0 87581.1 53700.0 65773.0 66375.0 68083.0 71741.0 9329.0 9381.4 9557.8 9585.4 9701.1 42645.6 45457.5 46340.3 47106.0 48516.0 52984.5 55260.0 57732.7 57999.1 61013.7 11424.0 12591.7 12985.9 13113.1 13154.3 14339.7 14438.9 14655.9 14697.9 15284.4 13347.7 13523.7 13568.1 13702.7 13762.3 13256.6 13322.3 13489.2 13737.3 13742.5 13188.3 13322.9 13382.9 13507.7 13948.4 10098.6 10444.6 10479.8 10881.3 11074.4 8512.7 10223.0 10333.3 10362.9 10444.2 12:33:49 INFO - PROCESS | 1655 | -------- Summary: end -------- 12:33:49 INFO - PROCESS | 1655 | 12:33:50 INFO - TEST-INFO | 1655: exit 0 12:33:50 INFO - TEST-OK | dromaeo_css | took 1119686ms 12:33:50 INFO - TEST-START | kraken 12:33:50 INFO - operating with platform_type : mac_ 12:33:50 INFO - Initialising browser for kraken test... 12:33:50 INFO - TEST-INFO | started process 1660 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/tmpGBqRwz/profile http://localhost:49235/getInfo.html) 12:33:50 INFO - PROCESS | 1660 | 1483216430885 addons.xpi WARN Exception running bootstrap method install on shield-recipe-client@mozilla.org: [Exception... "Component returned failure code: 0x8000ffff (NS_ERROR_UNEXPECTED) [nsIPrefBranch.getBoolPref]" nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js :: this.install :: line 38" data: no] Stack trace: this.install()@resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js:38 < callBootstrapMethod()@resource://gre/modules/addons/XPIProvider.jsm:4986 < processFileChanges()@resource://gre/modules/addons/XPIProvider.jsm -> resource://gre/modules/addons/XPIProviderUtils.js:2097 < checkForChanges()@resource://gre/modules/addons/XPIProvider.jsm:3827 < startup()@resource://gre/modules/addons/XPIProvider.jsm:2831 < callProvider()@resource://gre/modules/AddonManager.jsm:264 < _startProvider()@resource://gre/modules/AddonManager.jsm:771 < startup()@resource://gre/modules/AddonManager.jsm:957 < startup()@resource://gre/modules/AddonManager.jsm:2923 < observe()@resource://gre/components/addonManager.js:65 12:33:52 INFO - PROCESS | 1660 | __metrics Screen width/height:1600/1200 12:33:52 INFO - PROCESS | 1660 | colorDepth:24 12:33:52 INFO - PROCESS | 1660 | Browser inner width/height: 1024/689 12:33:52 INFO - PROCESS | 1660 | __metrics 12:33:52 INFO - PROCESS | 1660 | 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" 12:33:52 INFO - PROCESS | 1660 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 12:33:53 INFO - TEST-INFO | 1660: exit 0 12:33:53 INFO - Browser initialized. 12:33:53 INFO - Running cycle 1/1 for kraken test... 12:33:53 INFO - TEST-INFO | started process 1661 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/tmpGBqRwz/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 12:34:05 INFO - PROCESS | 1661 | RSS: Main: 234266624 12:34:05 INFO - PROCESS | 1661 | 12:34:08 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49235/tests/kraken/driver.html?testName=audio-beat-detection) 12:34:09 INFO - PROCESS | 1661 | RSS: Main: 285818880 12:34:09 INFO - PROCESS | 1661 | 12:34:11 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49235/tests/kraken/driver.html?testName=audio-dft) 12:34:12 INFO - PROCESS | 1661 | RSS: Main: 286355456 12:34:12 INFO - PROCESS | 1661 | 12:34:15 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49235/tests/kraken/driver.html?testName=audio-fft) 12:34:16 INFO - PROCESS | 1661 | RSS: Main: 378671104 12:34:16 INFO - PROCESS | 1661 | 12:34:18 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49235/tests/kraken/driver.html?testName=audio-oscillator) 12:34:18 INFO - PROCESS | 1661 | RSS: Main: 282992640 12:34:18 INFO - PROCESS | 1661 | 12:34:21 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49235/tests/kraken/driver.html?testName=imaging-gaussian-blur) 12:34:21 INFO - PROCESS | 1661 | RSS: Main: 286740480 12:34:21 INFO - PROCESS | 1661 | 12:34:24 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49235/tests/kraken/driver.html?testName=imaging-darkroom) 12:34:25 INFO - PROCESS | 1661 | RSS: Main: 327282688 12:34:25 INFO - PROCESS | 1661 | 12:34:27 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49235/tests/kraken/driver.html?testName=imaging-desaturate) 12:34:28 INFO - PROCESS | 1661 | RSS: Main: 329834496 12:34:28 INFO - PROCESS | 1661 | 12:34:31 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49235/tests/kraken/driver.html?testName=json-parse-financial) 12:34:32 INFO - PROCESS | 1661 | RSS: Main: 328687616 12:34:32 INFO - PROCESS | 1661 | 12:34:33 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49235/tests/kraken/driver.html?testName=json-stringify-tinderbox) 12:34:34 INFO - PROCESS | 1661 | RSS: Main: 303382528 12:34:34 INFO - PROCESS | 1661 | 12:34:36 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49235/tests/kraken/driver.html?testName=stanford-crypto-aes) 12:34:37 INFO - PROCESS | 1661 | RSS: Main: 307572736 12:34:37 INFO - PROCESS | 1661 | 12:34:39 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49235/tests/kraken/driver.html?testName=stanford-crypto-ccm) 12:34:39 INFO - PROCESS | 1661 | RSS: Main: 309526528 12:34:39 INFO - PROCESS | 1661 | 12:34:42 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49235/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 12:34:43 INFO - PROCESS | 1661 | RSS: Main: 323977216 12:34:43 INFO - PROCESS | 1661 | 12:34:46 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49235/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 12:34:47 INFO - PROCESS | 1661 | RSS: Main: 318345216 12:34:47 INFO - PROCESS | 1661 | 12:34:48 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49235/tests/kraken/driver.html?testName=ai-astar) 12:34:49 INFO - PROCESS | 1661 | RSS: Main: 362536960 12:34:49 INFO - PROCESS | 1661 | 12:34:49 INFO - PROCESS | 1661 | __start_tp_report 12:34:49 INFO - PROCESS | 1661 | _x_x_mozilla_page_load 12:34:49 INFO - PROCESS | 1661 | _x_x_mozilla_page_load_details 12:34:49 INFO - PROCESS | 1661 | |i|pagename|runs| 12:34:49 INFO - PROCESS | 1661 | |0;ai-astar;114;114;115;114;114;115;113;114;114;114 12:34:49 INFO - PROCESS | 1661 | |1;audio-beat-detection;143;112;109;145;112;109;110;109;129;110 12:34:49 INFO - PROCESS | 1661 | |2;audio-dft;184;184;185;184;184;185;181;184;185;183 12:34:49 INFO - PROCESS | 1661 | |3;audio-fft;79;59;58;86;60;59;58;60;83;83 12:34:49 INFO - PROCESS | 1661 | |4;audio-oscillator;92;71;112;99;100;69;84;89;98;115 12:34:49 INFO - PROCESS | 1661 | |5;imaging-gaussian-blur;88;85;89;99;113;85;89;87;89;85 12:34:49 INFO - PROCESS | 1661 | |6;imaging-darkroom;92;93;94;94;92;94;94;93;93;94 12:34:49 INFO - PROCESS | 1661 | |7;imaging-desaturate;85;84;89;86;86;85;97;143;134;92 12:34:49 INFO - PROCESS | 1661 | |8;json-parse-financial;57;55;55;55;55;56;56;56;56;56 12:34:49 INFO - PROCESS | 1661 | |9;json-stringify-tinderbox;69;69;103;113;107;69;71;70;69;72 12:34:49 INFO - PROCESS | 1661 | |10;stanford-crypto-aes;87;87;86;89;86;86;86;91;87;87 12:34:49 INFO - PROCESS | 1661 | |11;stanford-crypto-ccm;140;141;140;136;139;139;139;139;189;151 12:34:49 INFO - PROCESS | 1661 | |12;stanford-crypto-pbkdf2;171;173;174;187;233;204;170;168;170;227 12:34:49 INFO - PROCESS | 1661 | |13;stanford-crypto-sha256-iterative;67;68;66;68;67;67;67;66;66;65 12:34:49 INFO - PROCESS | 1661 | __end_tp_report 12:34:49 INFO - PROCESS | 1661 | __start_cc_report 12:34:49 INFO - PROCESS | 1661 | _x_x_mozilla_cycle_collect,2004 12:34:49 INFO - PROCESS | 1661 | __end_cc_report 12:34:49 INFO - PROCESS | 1661 | __startTimestamp1483216489113__endTimestamp 12:34:49 INFO - PROCESS | 1661 | 12:34:49 INFO - PROCESS | 1661 | ------- Summary: start ------- 12:34:49 INFO - PROCESS | 1661 | Number of tests: 14 12:34:49 INFO - PROCESS | 1661 | 12:34:49 INFO - PROCESS | 1661 | [#0] ai-astar Cycles:10 Average:114.10 Median:114.00 stddev:0.57 (0.5%) stddev-sans-first:0.60 12:34:49 INFO - PROCESS | 1661 | Values: 114.0 114.0 115.0 114.0 114.0 115.0 113.0 114.0 114.0 114.0 12:34:49 INFO - PROCESS | 1661 | 12:34:49 INFO - PROCESS | 1661 | [#1] audio-beat-detection Cycles:10 Average:118.80 Median:112.00 stddev:14.57 (13.0%) stddev-sans-first:12.55 12:34:49 INFO - PROCESS | 1661 | Values: 143.0 112.0 109.0 145.0 112.0 109.0 110.0 109.0 129.0 110.0 12:34:49 INFO - PROCESS | 1661 | 12:34:49 INFO - PROCESS | 1661 | [#2] audio-dft Cycles:10 Average:183.90 Median:184.00 stddev:1.20 (0.7%) stddev-sans-first:1.27 12:34:49 INFO - PROCESS | 1661 | Values: 184.0 184.0 185.0 184.0 184.0 185.0 181.0 184.0 185.0 183.0 12:34:49 INFO - PROCESS | 1661 | 12:34:49 INFO - PROCESS | 1661 | [#3] audio-fft Cycles:10 Average:68.50 Median:69.50 stddev:12.39 (17.8%) stddev-sans-first:12.55 12:34:49 INFO - PROCESS | 1661 | Values: 79.0 59.0 58.0 86.0 60.0 59.0 58.0 60.0 83.0 83.0 12:34:49 INFO - PROCESS | 1661 | 12:34:49 INFO - PROCESS | 1661 | [#4] audio-oscillator Cycles:10 Average:92.90 Median:86.50 stddev:15.32 (17.7%) stddev-sans-first:16.25 12:34:49 INFO - PROCESS | 1661 | Values: 92.0 71.0 112.0 99.0 100.0 69.0 84.0 89.0 98.0 115.0 12:34:49 INFO - PROCESS | 1661 | 12:34:49 INFO - PROCESS | 1661 | [#5] imaging-gaussian-blur Cycles:10 Average:90.90 Median:88.50 stddev:8.77 (9.9%) stddev-sans-first:9.24 12:34:49 INFO - PROCESS | 1661 | Values: 88.0 85.0 89.0 99.0 113.0 85.0 89.0 87.0 89.0 85.0 12:34:49 INFO - PROCESS | 1661 | 12:34:49 INFO - PROCESS | 1661 | [#6] imaging-darkroom Cycles:10 Average:93.30 Median:94.00 stddev:0.82 (0.9%) stddev-sans-first:0.73 12:34:49 INFO - PROCESS | 1661 | Values: 92.0 93.0 94.0 94.0 92.0 94.0 94.0 93.0 93.0 94.0 12:34:49 INFO - PROCESS | 1661 | 12:34:49 INFO - PROCESS | 1661 | [#7] imaging-desaturate Cycles:10 Average:98.10 Median:86.00 stddev:21.76 (25.3%) stddev-sans-first:22.56 12:34:49 INFO - PROCESS | 1661 | Values: 85.0 84.0 89.0 86.0 86.0 85.0 97.0 143.0 134.0 92.0 12:34:49 INFO - PROCESS | 1661 | 12:34:49 INFO - PROCESS | 1661 | [#8] json-parse-financial Cycles:10 Average:55.70 Median:56.00 stddev:0.67 (1.2%) stddev-sans-first:0.53 12:34:49 INFO - PROCESS | 1661 | Values: 57.0 55.0 55.0 55.0 55.0 56.0 56.0 56.0 56.0 56.0 12:34:49 INFO - PROCESS | 1661 | 12:34:49 INFO - PROCESS | 1661 | [#9] json-stringify-tinderbox Cycles:10 Average:81.20 Median:69.00 stddev:18.44 (26.7%) stddev-sans-first:19.03 12:34:49 INFO - PROCESS | 1661 | Values: 69.0 69.0 103.0 113.0 107.0 69.0 71.0 70.0 69.0 72.0 12:34:49 INFO - PROCESS | 1661 | 12:34:49 INFO - PROCESS | 1661 | [#10] stanford-crypto-aes Cycles:10 Average:87.20 Median:87.00 stddev:1.62 (1.9%) stddev-sans-first:1.72 12:34:49 INFO - PROCESS | 1661 | Values: 87.0 87.0 86.0 89.0 86.0 86.0 86.0 91.0 87.0 87.0 12:34:49 INFO - PROCESS | 1661 | 12:34:49 INFO - PROCESS | 1661 | [#11] stanford-crypto-ccm Cycles:10 Average:145.30 Median:140.00 stddev:15.85 (11.3%) stddev-sans-first:16.70 12:34:49 INFO - PROCESS | 1661 | Values: 140.0 141.0 140.0 136.0 139.0 139.0 139.0 139.0 189.0 151.0 12:34:49 INFO - PROCESS | 1661 | 12:34:49 INFO - PROCESS | 1661 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:187.70 Median:180.50 stddev:24.86 (13.8%) stddev-sans-first:25.62 12:34:49 INFO - PROCESS | 1661 | Values: 171.0 173.0 174.0 187.0 233.0 204.0 170.0 168.0 170.0 227.0 12:34:49 INFO - PROCESS | 1661 | 12:34:49 INFO - PROCESS | 1661 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.70 Median:67.00 stddev:0.95 (1.4%) stddev-sans-first:1.00 12:34:49 INFO - PROCESS | 1661 | Values: 67.0 68.0 66.0 68.0 67.0 67.0 67.0 66.0 66.0 65.0 12:34:49 INFO - PROCESS | 1661 | -------- Summary: end -------- 12:34:49 INFO - PROCESS | 1661 | 12:34:49 INFO - TEST-INFO | 1661: exit 0 12:34:50 INFO - TEST-OK | kraken | took 59613ms 12:34:50 INFO - SUITE-END | took 1179s 12:34:50 INFO - Completed test suite (00:19:39) 12:34:50 INFO - javascript benchmark 12:34:50 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3256.902292931212, 3349.2129349602847, 3379.307242015386, 3425.640307767686, 3433.6909678128977, 5590.552755779538, 5662.6602403855695, 5667.4049224831415, 5691.914621280679, 5707.14392841074, 5654.6041777075625, 5683.3464151622575, 5756.222909907161, 5769.394213607574, 5796.536277097822, 5100.33695619569, 5143.871403214931, 5155.072087024334, 5200.661956972804, 5249.527542521172, 5299.735013249341, 5319.989202051617, 5333.813316533922, 5346.6791992480385, 5390.083685773408, 5150.098732721796, 5270.314859068335, 5281.07581173297, 5297.8940421191555, 5310.309659744247, 5164.664296820694, 5175.223716442526, 5222.268882356473, 5230.712310822903, 5273.0, 5202.76587553561, 5203.219517072432, 5211.973940130275, 5237.952409518073, 5260.237265596467, 5640.09758438652, 5703.800366987177, 5717.371089180187, 5723.885522289573, 5787.0, 6091.756329746805, 6131.601445906002, 6133.0, 6145.770845830852, 6209.379062093754, 5228.8692782680355, 6258.092576576417, 6302.054691796221, 6337.0, 6340.936590634081, 5778.711064446845, 5792.507636850831, 5804.0, 5806.680632565165, 5859.121131830217, 5741.45456181662, 5953.464188223081, 6019.735855470312, 6262.874742505124, 6275.215598050244, 5670.5463562914865, 5747.3103227612955, 6082.1789058477425, 6136.151476879633, 6180.81457556274, 6108.463491842691, 6288.371162883675, 6296.8425789354715, 6298.8425289367215, 6301.4328710416285, 6060.545459090586, 6106.236720409949, 6114.908276375858, 6130.807181375102, 6149.538784591174, 5216.426193118754, 6207.782727604603, 6233.501319894398, 6243.094751261042, 6249.1563638908165, 4789.9042019160115, 4793.520647935178, 4796.328514008042, 4912.582430493448, 4938.703677779345, 4315.654747620183, 4336.262835318003, 4354.455693038371, 4360.389545463638, 4405.0, 5641.407652196543, 5673.773049078073, 5676.574256930664, 5680.545556355564, 5686.0333743263745, 6100.823402702991, 6157.96921015392, 6161.876762464725, 6165.075238714139, 6165.660888651168, 5701.515371193412, 5741.62679425836, 5776.624519406225, 5816.47651711348, 5829.7376618052285, 5368.516833485005, 5422.186671999153, 5429.158480435539, 5442.23808666779, 5461.344638643388, 6255.061740738925, 6298.968505157536, 6304.243490780952, 6308.589941653778, 6323.683815809283, 5942.494887934574, 6028.90956635642, 6042.395760423922, 6153.876922461436, 6321.399467050623, 5369.382521010134, 6250.874982500233, 6285.59143655661, 6296.087067375296, 6315.0, 6161.876762464636, 6164.106204600218, 6164.969175154096, 6186.133941248138, 6190.350013248544, 6126.578745377254, 6147.5245940972945, 6186.041163619646, 6201.3178550358525, 6259.280182779039, 5986.161937328865, 6000.159977603052, 6028.216331876828, 6088.086786981811, 6117.571769976059, 5908.645481271137, 6016.699165041818, 6022.638641681513, 6038.335783063947, 6042.969785151046, 5802.0, 5828.388019258086, 5854.94145058561, 5917.644941303535, 5932.49573786233, 5919.082542205965, 5986.580939334205, 6132.78535251264, 6146.6004709693725, 6147.631142131308, 4688.531146885284, 5857.912131318119, 5870.471657550841, 5882.029465138205, 5948.0], "value": 5654.2058369110055, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [451.2689443102175, 469.85199662106453, 490.5290920716112, 498.20038837665544, 504.79051193754594, 29305.706942930592, 37724.811375943114, 39655.603443965585, 39747.0, 39794.0, 40849.7957510212, 40966.38550421746, 41009.79495102528, 41097.273813511456, 41183.79408102963, 8121.553314567696, 9170.312226583017, 9348.532573371338, 9378.437293762361, 9428.481433521154, 8266.669333226664, 8567.100454452255, 10862.076723478474, 11208.0, 11222.943885280562, 11115.999560039594, 11149.72125696856, 11166.497507612135, 11201.551937922513, 11238.438078096104, 11126.49930753114, 11207.271527350696, 11212.04697600705, 11214.831777523303, 11232.3260604364, 8277.668893244263, 8553.101924297984, 10686.46567671621, 11025.393603351813, 11030.558777648885, 32478.0, 38659.0, 39004.609953900384, 39017.41473877894, 39038.414423783666, 3963.623455771697, 3971.324874771267, 3985.4021896715435, 3990.501187351581, 4122.9381559276635, 24460.633090503656, 29126.70873291261, 29260.8536957316, 30013.849930750424, 30551.847240763876, 3016.1253236561593, 3078.184281165493, 3081.445339838828, 3103.2707313781243, 3181.125190572588, 436300.0, 441436.0, 441663.750437437, 442505.0, 442926.0, 52550.0, 53120.203196952076, 53225.2016219757, 53309.73345133327, 53347.466525335025, 55007.72496137494, 55050.0, 55119.448805512235, 55222.17166742502, 55234.447655523734, 14021.5092471763, 14447.49433769813, 14451.566453006426, 14480.565583032527, 14485.85514144845, 10084.344517606332, 10811.243212975016, 11183.384913829655, 11265.436728163559, 11290.209685322114, 14309.0, 14695.853041469449, 16239.0, 16672.748813981158, 16880.577985550215, 6333.118431113832, 6770.119884414995, 6934.930650693529, 7089.610071446017, 7120.291130128835, 837.351052933981, 848.3255811629646, 849.6813694864426, 880.2561835249204, 892.312919052326, 22544.323670289916, 24173.395665108514, 29232.26919327034, 29421.11736647904, 29685.70704737498, 937.329809186435, 981.7874924468276, 1036.3678156324636, 1068.3963560588243, 1195.8983486403754, 14641.414343426357, 15080.095194288378, 15350.539483815503, 15437.996530225499, 15521.611959701098, 642852.0, 645265.773671138, 646420.0, 647866.0, 648659.0, 56447.15329270064, 57023.14465283024, 57147.714261428424, 57170.714146429, 57343.71328143332, 1355.3697530648296, 1396.4484028808563, 1401.5094716849226, 1402.7194561088027, 1409.302395314326, 27958.58062129029, 28873.566896496148, 29048.854755726086, 29482.557761634023, 29501.26246843846, 48234.0, 48548.0, 48598.71019347126, 48657.27014094721, 48849.5115048845, 516668.0, 517970.0, 518512.0, 518843.0, 519250.0, 25467.981280749304, 25815.225543233733, 25830.0, 25841.483170336112, 25911.222663319375, 44993.87515312144, 45126.77436612796, 46117.76941115273, 46123.0, 46125.538744612124, 36611.0, 36905.87600330751, 37117.62882371142, 37442.251154976206, 38550.0, 35908.922732318075, 36442.63557364392, 36599.8630267106, 37778.62221377861, 37817.81091094527, 36054.8197259012, 36099.45850812292, 36418.4537231947, 36769.63230367662, 36895.0, 31667.36665266728, 33122.17194570155, 33133.83433082819, 33171.0, 33404.164895877795, 23322.416939576648, 24444.899979501322, 24479.0, 24998.0, 25021.624675629515], "value": 19211.762712584015, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [348.78201124297317, 367.6397130811806, 368.49516162856884, 376.3226192852864, 397.97413168144067, 14957.326920288586, 15158.84841151588, 15848.76226856598, 19594.314199003038, 20637.793622063775, 19446.416607501797, 19985.100670469827, 20455.488612784688, 20498.180072797106, 20665.070071846767, 5802.941970580293, 5809.506191973666, 6074.848128796793, 6079.574429789916, 6095.542834287424, 5960.821175364746, 5972.940270597303, 5984.9401505984815, 6019.5485338599565, 6035.064564992434, 4160.583941605845, 4356.520782713915, 5992.161097446361, 6277.686115694197, 6566.212054553436, 5760.2511673482595, 6676.833079173011, 6693.163354580677, 6696.598204107745, 6696.631685257309, 5209.01028804526, 5685.573581981368, 5763.0, 5864.794732184396, 5870.706464676749, 12955.287459189742, 14516.0, 21760.455988600253, 24108.0, 24323.02707891682, 2549.859757713325, 2593.714691383965, 2607.29603007187, 2611.308003379106, 2620.7248238934826, 9601.711948641481, 9646.035396460367, 9654.179394751454, 10137.986201379877, 10157.035081667229, 1763.1008185825194, 1794.8142047865013, 1815.6731788278105, 1815.8910465371987, 1853.2494339792406, 55953.72023139858, 92734.07265927322, 92973.0, 93523.0, 105729.0, 10977.780444391068, 11134.777304453866, 11240.943795280971, 11250.774984500264, 11449.771004579861, 14264.714705705828, 14317.498887538886, 14317.57047288583, 14326.0, 14341.139531628136, 8018.158093400109, 8125.512469251864, 8145.144759800254, 8153.0, 8311.127331630212, 7939.3251573615735, 7940.880886786703, 8070.677172913135, 8100.108988011314, 8131.9593402032615, 4307.932192547532, 4406.086533594047, 4423.734575925455, 4462.486814016364, 4548.0, 1821.8178182181675, 1881.74596429483, 1886.4246404846654, 1886.6510445031868, 1894.1760334254643, 878.8374150782125, 906.9229115525122, 919.5356345045709, 922.3543519536217, 924.2698268367914, 19636.90181549083, 20306.0, 20334.186632534827, 20364.79635203629, 20400.387988360075, 881.9901213110952, 912.6395073945754, 951.3435729346728, 951.5527701980059, 967.2455484721787, 10310.896891031143, 10582.312149710244, 10602.57589696419, 10606.840897386546, 10616.628418005334, 529071.0, 533107.0, 541167.0, 558251.0, 560839.0, 91838.0, 95095.52452237833, 103873.48063259636, 114430.42784786022, 116259.41870290764, 975.2490582251627, 989.5200827598609, 1028.3624153025023, 1028.377831412, 1031.4945676618404, 18185.0, 18226.90886545559, 18596.44210673682, 18858.0, 18980.525486862938, 39913.80043099766, 48661.05355785729, 49689.503104968484, 49824.25263620976, 50739.47781566559, 67649.66175169093, 69748.0, 70779.0, 71252.0, 71898.0, 6733.360330768569, 7836.981192444946, 8019.83960320802, 8068.394870384862, 8214.096449390681, 9191.540422978691, 10813.945930270298, 11697.0, 12430.813537797121, 12823.42294596764, 8991.23574496149, 10847.620333288298, 11039.172062095535, 11219.7756044877, 11241.775164496501, 10000.749981250236, 10841.457927103456, 10871.184661150288, 10998.835017474905, 11084.556617735201, 11331.32012079278, 11342.092632589209, 11437.542498300307, 11457.312561246352, 11508.42457877086, 6541.803745887631, 7907.802304942193, 7944.721934732257, 8323.6670533178, 8350.958245208978, 9881.654142104991, 10590.89409105899, 10838.512266948164, 11116.331610568655, 11116.777664446827], "value": 9151.958873346986, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1379.813725147105, 1384.986150138499, 1399.5801259622117, 1401.117296103455, 1414.6816966182616, 7859.724909628164, 8260.050094239157, 8270.669173233064, 8275.420720549564, 8315.251627353537, 6488.610683359002, 7329.780106596797, 7608.923910760905, 7872.01599800025, 8085.353171746261, 2235.1506427557506, 2285.90856365746, 2288.8741119238434, 2297.069686776858, 2297.115610489965, 2283.22370394066, 2306.3888069661552, 2312.2138472919205, 2315.2012555668266, 2315.2822624986225, 1993.1130646862152, 2076.6262072826885, 2182.0835249195297, 2203.94490137747, 2209.0, 2273.15893119546, 2281.942951426211, 2283.2693538067824, 2288.1533832481923, 2292.747797742247, 2234.1063574570303, 2245.2141750387395, 2252.966205506919, 2259.581977334182, 2264.173576644513, 7075.433965282817, 8039.95980020102, 8231.382646301552, 8243.464174828618, 8263.338932885415, 4910.558049775537, 5089.847304580831, 5095.8980820383385, 5098.745062746848, 5100.438951715309, 2891.291633549794, 3238.1257060593744, 3396.983015084909, 3411.5053317268857, 3446.2590543033116, 1162.436218434059, 1195.910306726999, 1225.7671042501895, 1245.7508498300376, 1253.7868562344424, 10549.630762923338, 10605.045545900906, 10658.253922225447, 10680.51937662807, 10726.356418614909, 4196.412502249687, 4343.67422443318, 4453.153900758845, 4519.209138400766, 4524.796384162721, 8710.303175745925, 8712.869306960401, 8735.606897689619, 8735.825283494294, 8740.956295218482, 4536.523665015192, 4949.950500495021, 4956.479569645207, 4959.256111583291, 4965.627577931597, 4205.894852628709, 4213.157368526307, 4294.463192100988, 4537.636989040869, 4609.585137337589, 3693.445983102556, 3703.6481834035044, 3772.5095737554493, 3815.847366105325, 3816.5229346331707, 1216.6289281768968, 1235.363143834405, 1246.5948566715854, 1248.6753444104597, 1254.4856608790353, 835.9916400836036, 855.3584811391456, 855.9657613695382, 904.882365292521, 908.3913777768911, 4621.0, 5298.576113910878, 5460.808871689472, 5497.972510137503, 5555.416681248411, 523.588982648619, 526.9100489273675, 535.6250624562822, 536.2171230004237, 544.7412479072409, 3491.0, 3527.4356103023392, 3583.4266517357096, 3601.6938560222675, 3629.8729544466346, 9525.380850244712, 9856.50717464124, 9872.802543949081, 9944.35361701487, 9960.153386962045, 9844.3601165924, 9846.803063938825, 9857.852132218022, 9862.901370986197, 9891.0, 6193.256809182837, 7516.210797866145, 7562.924370756332, 7627.122880868771, 7699.4610377274175, 4579.862604121881, 5355.919661205007, 5435.048866448276, 5442.945570544244, 5463.371712253142, 9145.314101442551, 9272.907270927204, 9340.34617576763, 9363.0, 9378.906210937803, 9042.276617870686, 9154.450732956044, 9395.154436100784, 9421.434713917188, 9424.293178011814, 6456.451201647913, 6658.201015878126, 6761.188657360952, 6835.726570937306, 6912.274211207952, 6680.498962577729, 7780.727674531364, 7821.804454888674, 7848.489848159851, 7849.254320839511, 6761.290064543349, 6948.062011628365, 6959.373656370951, 6989.161300643752, 7013.2986701329455, 6815.284395138439, 6842.178938527409, 6859.451243900576, 6887.931120688729, 6923.792286231421, 6109.389061093855, 6226.968865155826, 6308.810735677937, 6330.683465826783, 6333.018382150774, 4593.471750748573, 4657.278121891112, 4687.460941991714, 4709.976450117728, 4840.661153719206, 4668.766561671835, 4749.3113498541825, 4760.642951778561, 4811.3264143019305, 4831.444383895897], "value": 4215.400822754011, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [343.50878244110925, 348.44597090625905, 351.93665140274743, 363.3786225554302, 367.2819637608474, 5132.820351287705, 5171.663841850277, 5179.663321884075, 5190.169572868337, 5199.506046925546, 4378.496472905609, 4528.886777830556, 5009.14844476439, 5026.210453198482, 5031.471695471978, 3896.9415458768, 4014.3376342903384, 4029.6776257899296, 4031.2743706132883, 4160.230357383879, 4068.166025964685, 4107.0, 4134.8139333729905, 4137.8965525861795, 4138.917221655565, 3439.6216416194197, 3893.3770596704535, 3968.3921049883397, 4007.7194596378267, 4082.7142100053006, 3705.7961812100325, 4059.269331520325, 4074.5925407459313, 4088.2232375848644, 4090.5683010946227, 2926.75122614578, 3230.3862266169353, 3268.7711860169798, 3604.4953706481115, 3653.2328211075705, 4555.492270951382, 4830.903381932377, 5020.347354843847, 5054.620903432257, 5077.136886729265, 3905.433712111728, 4476.865694029184, 4637.86086417408, 4647.512011238805, 4689.0, 4788.449328327252, 4926.5812405945535, 5062.215356619729, 5195.610329225286, 5407.161889907071, 3359.395308844407, 3950.0572073805197, 3965.345717956534, 3969.7816620085882, 3970.06703424695, 5450.236966824608, 5640.943590564083, 5655.660660360391, 5738.655680659173, 5761.366249712528, 3897.2010737798682, 4023.75857448554, 4037.2934736421007, 4047.919041619151, 4103.692223083281, 3513.894583162509, 4150.543440221573, 4152.501699796044, 4154.356074808409, 4161.604647558477, 4113.095119073799, 4517.864464066083, 4527.959023718084, 4627.5885854814605, 4734.715917044989, 4733.668643195012, 4905.70565766048, 4966.304717339575, 5154.329937108227, 5443.727813609304, 1323.3912400295778, 1338.43785610044, 1342.744878473087, 1349.912255703376, 1358.280111540885, 801.9278264956182, 885.9468431894107, 914.3736540469799, 921.9078092190862, 967.5355829202023, 4547.8180872764715, 4610.216263235258, 4755.453122890843, 4819.325294458778, 4821.879453013703, 4504.338010159469, 4703.882402939886, 4731.550502702238, 5063.0, 5271.130263506555, 4377.117839329056, 4519.299508576176, 4640.656468483394, 4710.317004034464, 4726.480087190475, 2441.8633695516423, 2597.7921766258655, 2680.276325392133, 2769.7259260739884, 2792.6509186351705, 4386.0569977455, 5195.5971887590895, 5333.586599551122, 5465.59008074396, 5494.186918316946, 4710.670253082251, 5041.899162016813, 5174.050791049066, 5345.35855697311, 5476.479734425303, 4511.210538155875, 4957.769003948238, 5329.493698098674, 5347.0, 5362.758675859595, 4290.63529599975, 4462.955370446384, 4776.49234152059, 4843.474305593868, 5039.0, 4837.7097374157665, 5147.382314122329, 5355.973220133875, 5522.5858060646415, 5662.462066103713, 4929.728864912464, 5765.798197063083, 5767.365589785204, 5790.1893734877995, 5791.276090488689, 3229.6931791479774, 3472.2708231270703, 3806.1834242660784, 3807.3717836557767, 3808.990617486313, 4958.727270000185, 5145.7941682333785, 5273.736313184402, 5389.8113566025, 5634.816688495298, 4843.830465933675, 4881.877168251251, 5156.510131537498, 5366.7191443637075, 5422.0, 4479.417675702138, 4672.696274742267, 4700.2949557566635, 4961.578265847299, 5447.346318441812, 4114.811021458394, 4701.1537923174155, 5319.41486436484, 5373.838784836461, 5378.919316210182, 4965.7089156818765, 5304.893902122013, 5375.489328513785, 5385.246065550904, 5426.197814933891, 4537.954620453753, 5235.947640523698, 5266.236395722675, 5288.365396152486, 5301.9926214019215], "value": 3941.1546752432932, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [715.6386025057345, 718.2279050021227, 723.4218941218269, 762.1311704656692, 807.2573232625983, 14422.495212667558, 15051.62370940727, 15545.378184872605, 15562.299696513655, 15563.221838908066, 13756.518521851736, 13966.581002569914, 14076.78884816726, 14199.290035498236, 14202.0, 682.3994884501657, 703.085988215318, 706.2937062937064, 710.3784188834769, 864.425157270416, 697.909271794666, 835.2274146414572, 865.8614621660536, 869.7999460124175, 871.877937088808, 915.1854849184232, 921.7004473546124, 922.5802259971697, 924.4129977464291, 928.4846909964966, 916.7616419730851, 919.296737995434, 920.7559996600907, 921.272194965976, 921.9769505762342, 527.699211449474, 683.2689022745664, 686.354826463128, 686.8179932317939, 686.9038334633156, 12365.505379784889, 13707.177569345871, 14176.220307883063, 14278.0, 14517.491887783877, 13636.863631363658, 13882.722345553133, 13925.303734813218, 13946.23295718735, 13986.370613322424, 11693.00609448198, 11807.58673446434, 11868.584599538975, 11969.760604787856, 12214.0, 3029.742471889892, 3586.982065089684, 3697.038769919813, 3777.6033516480925, 3826.1773718650625, 91194.5440272801, 91702.0829791707, 92902.0, 93075.06924930731, 93150.0, 10712.839307410395, 10764.946175269073, 10797.190210734227, 10869.29349592274, 10979.451027448597, 19768.70346944797, 20711.378658640264, 20794.376168714964, 21991.670124948138, 22005.559888802134, 7697.23027697237, 7704.306612404798, 7768.339691126317, 7777.650005749755, 7896.960515197387, 3558.6085530591613, 4126.711130220916, 4158.209940111369, 4171.499420069611, 4197.97901010493, 3484.6166921639106, 3534.3814832404228, 3561.6082230954576, 3590.2460483298278, 3600.2259514204047, 651.4592887903029, 652.7617419641891, 657.2605818454239, 663.7444583835259, 665.490899461912, 507.75373943637305, 642.8874946884276, 650.3853858104046, 661.1801366305747, 662.953593248468, 7193.496455248082, 7774.455788094892, 8833.602487887933, 8839.983401908734, 8991.640334386682, 613.5459759777822, 650.9088727578143, 655.275920108283, 664.8238216872533, 680.2992917295194, 3995.260876737813, 4127.731697439657, 4366.432363792686, 4369.584889435499, 4377.4528183977, 98750.5062474683, 99671.0, 100662.0, 101474.49262753638, 105200.42199367016, 84015.57992210123, 84751.0, 86203.0, 86477.0, 87581.05236845394, 53700.0, 65773.0, 66375.00437493442, 68082.9787553187, 71741.0, 9329.0, 9381.437113773196, 9557.791645516607, 9585.376950498196, 9701.07839755222, 42645.57354426416, 45457.54542454533, 46340.30489542592, 47106.0, 48516.0, 52984.47015529795, 55260.0, 57732.71133644305, 57999.13001304899, 61013.69493152506, 11424.0, 12591.685207869876, 12985.870141298845, 13113.147645403018, 13154.255583743796, 14339.713205736036, 14438.85561144375, 14655.92672036633, 14697.92651036738, 15284.388624454898, 13347.733045339233, 13523.661908452368, 13568.050236483352, 13702.725945480835, 13762.311884405939, 13256.602301930958, 13322.33388330599, 13489.190648561118, 13737.313134343443, 13742.519011834538, 13188.340582971005, 13322.933385333012, 13382.93308533451, 13507.729845403233, 13948.4420623178, 10098.646547370807, 10444.63443779464, 10479.842802358124, 10881.292715973754, 11074.446277685922, 8512.74461766148, 10222.97096422549, 10333.276670632984, 10362.890942957014, 10444.2166837489], "value": 7185.0069104807535, "unit": "score"}], "name": "dromaeo_css", "value": 7010.223655895017, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 114.0, 115.0, 114.0, 114.0, 115.0, 113.0, 114.0, 114.0, 114.0], "value": 114.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [143.0, 112.0, 109.0, 145.0, 112.0, 109.0, 110.0, 109.0, 129.0, 110.0], "value": 118.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [184.0, 184.0, 185.0, 184.0, 184.0, 185.0, 181.0, 184.0, 185.0, 183.0], "value": 183.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 59.0, 58.0, 86.0, 60.0, 59.0, 58.0, 60.0, 83.0, 83.0], "value": 68.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 71.0, 112.0, 99.0, 100.0, 69.0, 84.0, 89.0, 98.0, 115.0], "value": 92.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 85.0, 89.0, 99.0, 113.0, 85.0, 89.0, 87.0, 89.0, 85.0], "value": 90.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 93.0, 94.0, 94.0, 92.0, 94.0, 94.0, 93.0, 93.0, 94.0], "value": 93.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 84.0, 89.0, 86.0, 86.0, 85.0, 97.0, 143.0, 134.0, 92.0], "value": 98.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 55.0, 55.0, 55.0, 55.0, 56.0, 56.0, 56.0, 56.0, 56.0], "value": 55.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 69.0, 103.0, 113.0, 107.0, 69.0, 71.0, 70.0, 69.0, 72.0], "value": 81.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 87.0, 86.0, 89.0, 86.0, 86.0, 86.0, 91.0, 87.0, 87.0], "value": 87.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [140.0, 141.0, 140.0, 136.0, 139.0, 139.0, 139.0, 139.0, 189.0, 151.0], "value": 145.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [171.0, 173.0, 174.0, 187.0, 233.0, 204.0, 170.0, 168.0, 170.0, 227.0], "value": 187.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 68.0, 66.0, 68.0, 67.0, 67.0, 67.0, 66.0, 66.0, 65.0], "value": 66.7, "unit": "score"}], "name": "kraken", "value": 1484.3, "alertThreshold": 2.0}]} 12:34:50 INFO - Return code: 0 12:34:50 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 12:34:50 INFO - # TBPL SUCCESS # 12:34:50 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 12:34:50 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 12:34:50 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 12:34:50 INFO - 2016-12-31 12:34:50.446 screenresolution[1665:12849] starting screenresolution argv=screenresolution get 12:34:50 INFO - 2016-12-31 12:34:50.448 screenresolution[1665:12849] Display 0: 1600x1200x32@60 12:34:50 INFO - 2016-12-31 12:34:50.454 screenresolution[1666:12852] starting screenresolution argv=screenresolution list 12:34:50 INFO - Available Modes on Display 0 12:34:50 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 12:34:50 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 12:34:50 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 12:34:50 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 12:34:50 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 12:34:50 INFO - Intel Iris: 12:34:50 INFO - Chipset Model: Intel Iris 12:34:50 INFO - Type: GPU 12:34:50 INFO - Bus: Built-In 12:34:50 INFO - VRAM (Dynamic, Max): 1536 MB 12:34:50 INFO - Vendor: Intel (0x8086) 12:34:50 INFO - Device ID: 0x0a2e 12:34:50 INFO - Revision ID: 0x0009 12:34:50 INFO - Displays: 12:34:50 INFO - Display: 12:34:50 INFO - Resolution: 1600 x 1200 @ 60 Hz 12:34:50 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 12:34:50 INFO - Display Serial Number: 1600x1200 60 12:34:50 INFO - Main Display: Yes 12:34:50 INFO - Mirror: Off 12:34:50 INFO - Online: Yes 12:34:50 INFO - Rotation: Supported 12:34:50 INFO - Return code: 0 12:34:50 INFO - Running post-action listener: _resource_record_post_action 12:34:50 INFO - [mozharness: 2016-12-31 20:34:50.690788Z] Finished run-tests step (success) 12:34:50 INFO - Running post-run listener: _resource_record_post_run 12:34:50 INFO - Total resource usage - Wall time: 1202s; CPU: 54.0%; Read bytes: 30944256; Write bytes: 552112128; Read time: 185; Write time: 1651 12:34:50 INFO - TinderboxPrint: CPU usage
53.7% 12:34:50 INFO - TinderboxPrint: I/O read bytes / time
30,944,256 / 185 12:34:50 INFO - TinderboxPrint: I/O write bytes / time
552,112,128 / 1,651 12:34:50 INFO - TinderboxPrint: CPU idle
2,224.9 (46.3%) 12:34:50 INFO - TinderboxPrint: CPU system
963.6 (20.0%) 12:34:50 INFO - TinderboxPrint: CPU user
1,621.2 (33.7%) 12:34:50 INFO - TinderboxPrint: Swap in / out
276,107,264 / 0 12:34:50 INFO - install - Wall time: 22s; CPU: 54.0%; Read bytes: 228968960; Write bytes: 222779392; Read time: 16523; Write time: 477 12:34:50 INFO - run-tests - Wall time: 1181s; CPU: 54.0%; Read bytes: 30039040; Write bytes: 299239424; Read time: 174; Write time: 1103 12:34:51 INFO - Running post-run listener: _upload_blobber_files 12:34:51 INFO - Blob upload gear active. 12:34:51 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 12:34:51 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 12:34:51 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'] 12:34:51 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 12:34:51 INFO - (blobuploader) - INFO - Open directory for files ... 12:34:51 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 12:34:51 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:34:51 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:34:53 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 12:34:53 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:34:53 INFO - (blobuploader) - INFO - Done attempting. 12:34:53 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 12:34:53 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:34:53 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:34:54 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 12:34:54 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:34:54 INFO - (blobuploader) - INFO - Done attempting. 12:34:54 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 12:34:54 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:34:54 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:34:55 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 12:34:55 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:34:55 INFO - (blobuploader) - INFO - Done attempting. 12:34:55 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 12:34:55 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:34:55 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:34:57 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 12:34:57 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:34:57 INFO - (blobuploader) - INFO - Done attempting. 12:34:57 INFO - (blobuploader) - INFO - Iteration through files over. 12:34:57 INFO - Return code: 0 12:34:57 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 12:34:57 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 12:34:57 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/df02c03ec75a597700bd5dc25f8e6843ab1abbe46e6aff649c19ad07263ddc4aa518e9209cfe7260624582f11ec69734fe61c459749e8b7abde6476ba14692b5", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/dc26f6ac6b77c00ff3290d11dfa9a3276d947edf98415b978a7d3e66b5c22d82cd41fca0162fb6c006caf9131fe5b82dc5d87a25daf6d6a1cf530b48590520a7", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/63e90c8341420314e79118f4cf95959eb9bd8a991b8007aa5e68b534b395f6ab6ca06474ca207852aca6cb29514e070bb4ff1153cda45dcef96a98eb72ba87a4", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/89c13cdf25f2eee28a23221e62f967300452d9b6a4a4155b47ef1726191537e765be5fe2da344cd7fc72e894b1d528d722d6b7997888520ce29f45579fbbba24"} 12:34:57 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 12:34:57 INFO - Writing to file /builds/slave/test/properties/blobber_files 12:34:57 INFO - Contents: 12:34:57 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/df02c03ec75a597700bd5dc25f8e6843ab1abbe46e6aff649c19ad07263ddc4aa518e9209cfe7260624582f11ec69734fe61c459749e8b7abde6476ba14692b5", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/dc26f6ac6b77c00ff3290d11dfa9a3276d947edf98415b978a7d3e66b5c22d82cd41fca0162fb6c006caf9131fe5b82dc5d87a25daf6d6a1cf530b48590520a7", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/63e90c8341420314e79118f4cf95959eb9bd8a991b8007aa5e68b534b395f6ab6ca06474ca207852aca6cb29514e070bb4ff1153cda45dcef96a98eb72ba87a4", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/89c13cdf25f2eee28a23221e62f967300452d9b6a4a4155b47ef1726191537e765be5fe2da344cd7fc72e894b1d528d722d6b7997888520ce29f45579fbbba24"} 12:34:57 INFO - Running post-run listener: copy_logs_to_upload_dir 12:34:57 INFO - Copying logs to upload dir... 12:34:57 INFO - mkdir: /builds/slave/test/build/upload/logs 12:34:57 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1264.242577 ========= master_lag: 1.50 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 5 secs) (at 2016-12-31 12:34:58.720974) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-31 12:34:58.728388) ========= 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.zt15ehN6Sk/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.2mlI09uo26/Listeners TMPDIR=/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/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/df02c03ec75a597700bd5dc25f8e6843ab1abbe46e6aff649c19ad07263ddc4aa518e9209cfe7260624582f11ec69734fe61c459749e8b7abde6476ba14692b5", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/dc26f6ac6b77c00ff3290d11dfa9a3276d947edf98415b978a7d3e66b5c22d82cd41fca0162fb6c006caf9131fe5b82dc5d87a25daf6d6a1cf530b48590520a7", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/63e90c8341420314e79118f4cf95959eb9bd8a991b8007aa5e68b534b395f6ab6ca06474ca207852aca6cb29514e070bb4ff1153cda45dcef96a98eb72ba87a4", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/89c13cdf25f2eee28a23221e62f967300452d9b6a4a4155b47ef1726191537e765be5fe2da344cd7fc72e894b1d528d722d6b7997888520ce29f45579fbbba24"} build_url:https://queue.taskcluster.net/v1/task/HLf5heFdRsaYnjwsLdBnDw/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011247 build_url: 'https://queue.taskcluster.net/v1/task/HLf5heFdRsaYnjwsLdBnDw/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/df02c03ec75a597700bd5dc25f8e6843ab1abbe46e6aff649c19ad07263ddc4aa518e9209cfe7260624582f11ec69734fe61c459749e8b7abde6476ba14692b5", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/dc26f6ac6b77c00ff3290d11dfa9a3276d947edf98415b978a7d3e66b5c22d82cd41fca0162fb6c006caf9131fe5b82dc5d87a25daf6d6a1cf530b48590520a7", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/63e90c8341420314e79118f4cf95959eb9bd8a991b8007aa5e68b534b395f6ab6ca06474ca207852aca6cb29514e070bb4ff1153cda45dcef96a98eb72ba87a4", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/89c13cdf25f2eee28a23221e62f967300452d9b6a4a4155b47ef1726191537e765be5fe2da344cd7fc72e894b1d528d722d6b7997888520ce29f45579fbbba24"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-31 12:34:58.767708) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-31 12:34:58.768049) ========= 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.zt15ehN6Sk/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.2mlI09uo26/Listeners TMPDIR=/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False program finished with exit code 0 elapsedTime=0.005152 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-31 12:34:58.816831) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-31 12:34:58.817152) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-31 12:34:58.817457) ========= ========= Total master_lag: 1.74 =========