builder: mozilla-central_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0015 starttime: 1482972277.34 results: success (0) buildid: 20161228160136 builduid: 2cac69f328644f2b8a6ebff0882aeaf2 revision: b882b98ee9ddba735a0a3a57623e0b9d9fc7742a ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-12-28 16:44:37.343287) ========= master: http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-12-28 16:44:37.343773) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-28 16:44:37.344061) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-28 16:44:37.464427) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-28 16:44:37.464812) ========= 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.8uJxNx2qBp/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.SVmM1OEo8i/Listeners TMPDIR=/var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/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-28 16:44:37-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 21.4M=0.001s 2016-12-28 16:44:37 (21.4 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.368913 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-28 16:44:37.856944) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-28 16:44:37.857257) ========= 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.8uJxNx2qBp/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.SVmM1OEo8i/Listeners TMPDIR=/var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/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.019933 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-28 16:44:37.905647) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-12-28 16:44:37.906018) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev b882b98ee9ddba735a0a3a57623e0b9d9fc7742a --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 b882b98ee9ddba735a0a3a57623e0b9d9fc7742a --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.8uJxNx2qBp/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.SVmM1OEo8i/Listeners TMPDIR=/var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/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-28 16:44:37,973 truncating revision to first 12 chars 2016-12-28 16:44:37,973 Setting DEBUG logging. 2016-12-28 16:44:37,973 attempt 1/10 2016-12-28 16:44:37,973 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/b882b98ee9dd?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-12-28 16:44:39,141 unpacking tar archive at: mozilla-central-b882b98ee9dd/testing/mozharness/ program finished with exit code 0 elapsedTime=1.423384 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-12-28 16:44:39.352264) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-28 16:44:39.352570) ========= script_repo_revision: b882b98ee9ddba735a0a3a57623e0b9d9fc7742a ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-28 16:44:39.353003) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-28 16:44:39.353288) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-28 16:44:39.370651) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 8 secs) (at 2016-12-28 16:44:39.371100) ========= /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.8uJxNx2qBp/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.SVmM1OEo8i/Listeners TMPDIR=/var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/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 16:44:39 INFO - MultiFileLogger online at 20161228 16:44:39 in /builds/slave/test 16:44:39 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 16:44:39 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 16:44:39 INFO - {'append_to_log': False, 16:44:39 INFO - 'base_work_dir': '/builds/slave/test', 16:44:39 INFO - 'blob_upload_branch': 'Firefox', 16:44:39 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 16:44:39 INFO - 'branch': 'Firefox', 16:44:39 INFO - 'buildbot_json_path': 'buildprops.json', 16:44:39 INFO - 'config_files': ('talos/mac_config.py',), 16:44:39 INFO - 'default_actions': ('clobber', 16:44:39 INFO - 'read-buildbot-config', 16:44:39 INFO - 'download-and-extract', 16:44:39 INFO - 'populate-webroot', 16:44:39 INFO - 'create-virtualenv', 16:44:39 INFO - 'install', 16:44:39 INFO - 'run-tests'), 16:44:39 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 16:44:39 INFO - 'download_minidump_stackwalk': True, 16:44:39 INFO - 'download_symbols': 'ondemand', 16:44:39 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 16:44:39 INFO - 'tooltool.py': '/tools/tooltool.py', 16:44:39 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 16:44:39 INFO - '/tools/misc-python/virtualenv.py')}, 16:44:39 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 16:44:39 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 16:44:39 INFO - 'installer_path': 'installer.exe', 16:44:39 INFO - 'log_level': 'info', 16:44:39 INFO - 'log_name': 'talos', 16:44:39 INFO - 'log_to_console': True, 16:44:39 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 16:44:39 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 16:44:39 INFO - 'opt_config_files': (), 16:44:39 INFO - 'pip_index': False, 16:44:39 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 16:44:39 INFO - 'cmd': ('bash', 16:44:39 INFO - '-c', 16:44:39 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 16:44:39 INFO - 'enabled': True, 16:44:39 INFO - 'halt_on_failure': False, 16:44:39 INFO - 'name': 'check_screen_resolution'},), 16:44:39 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 16:44:39 INFO - 'cmd': ('bash', 16:44:39 INFO - '-c', 16:44:39 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 16:44:39 INFO - 'enabled': True, 16:44:39 INFO - 'halt_on_failure': False, 16:44:39 INFO - 'name': 'check_screen_resolution'},), 16:44:39 INFO - 'run_cmd_checks_enabled': True, 16:44:39 INFO - 'sps_profile': False, 16:44:39 INFO - 'sps_profile_interval': 0, 16:44:39 INFO - 'suite': 'dromaeojs', 16:44:39 INFO - 'system_bits': '32', 16:44:39 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 16:44:39 INFO - 'title': 't-yosemite-r7-0015', 16:44:39 INFO - 'tooltool_cache': '/builds/tooltool_cache', 16:44:39 INFO - 'use_talos_json': True, 16:44:39 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 16:44:39 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 16:44:39 INFO - 'work_dir': 'build'} 16:44:39 INFO - [mozharness: 2016-12-29 00:44:39.561237Z] Running clobber step. 16:44:39 INFO - Running pre-action listener: _resource_record_pre_action 16:44:39 INFO - Running main action method: clobber 16:44:39 INFO - rmtree: /builds/slave/test/build 16:44:39 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 16:44:41 INFO - Running post-action listener: _resource_record_post_action 16:44:41 INFO - [mozharness: 2016-12-29 00:44:41.790472Z] Finished clobber step (success) 16:44:41 INFO - [mozharness: 2016-12-29 00:44:41.790675Z] Running read-buildbot-config step. 16:44:41 INFO - Running pre-action listener: _resource_record_pre_action 16:44:41 INFO - Running main action method: read_buildbot_config 16:44:41 INFO - Using buildbot properties: 16:44:41 INFO - { 16:44:41 INFO - "project": "", 16:44:41 INFO - "product": "firefox", 16:44:41 INFO - "script_repo_revision": "production", 16:44:41 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs", 16:44:41 INFO - "repository": "", 16:44:41 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs", 16:44:41 INFO - "buildid": "20161228160136", 16:44:41 INFO - "pgo_build": "False", 16:44:41 INFO - "basedir": "/builds/slave/test", 16:44:41 INFO - "buildnumber": 50, 16:44:41 INFO - "slavename": "t-yosemite-r7-0015", 16:44:41 INFO - "master": "http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/", 16:44:41 INFO - "platform": "yosemite_r7", 16:44:41 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 16:44:41 INFO - "branch": "mozilla-central", 16:44:41 INFO - "repo_path": "mozilla-central", 16:44:41 INFO - "revision": "b882b98ee9ddba735a0a3a57623e0b9d9fc7742a", 16:44:41 INFO - "stage_platform": "macosx64", 16:44:41 INFO - "builduid": "2cac69f328644f2b8a6ebff0882aeaf2", 16:44:41 INFO - "slavebuilddir": "test" 16:44:41 INFO - } 16:44:41 INFO - Found installer url https://queue.taskcluster.net/v1/task/Rbu5pkLUSrKT-4K6y0xLnQ/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg. 16:44:41 INFO - Running post-action listener: _resource_record_post_action 16:44:41 INFO - [mozharness: 2016-12-29 00:44:41.794016Z] Finished read-buildbot-config step (success) 16:44:41 INFO - [mozharness: 2016-12-29 00:44:41.794149Z] Running download-and-extract step. 16:44:41 INFO - Running pre-action listener: _resource_record_pre_action 16:44:41 INFO - Running main action method: download_and_extract 16:44:41 INFO - mkdir: /builds/slave/test/build/tests 16:44:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:44:41 INFO - trying https://queue.taskcluster.net/v1/task/Rbu5pkLUSrKT-4K6y0xLnQ/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json 16:44:41 INFO - Downloading https://queue.taskcluster.net/v1/task/Rbu5pkLUSrKT-4K6y0xLnQ/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 16:44:41 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Rbu5pkLUSrKT-4K6y0xLnQ/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 16:44:42 INFO - Downloaded 1199 bytes. 16:44:42 INFO - Reading from file /builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json 16:44:42 INFO - Using the following test package requirements: 16:44:42 INFO - {u'common': [u'firefox-53.0a1.en-US.mac.common.tests.zip'], 16:44:42 INFO - u'cppunittest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 16:44:42 INFO - u'firefox-53.0a1.en-US.mac.cppunittest.tests.zip'], 16:44:42 INFO - u'gtest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 16:44:42 INFO - u'firefox-53.0a1.en-US.mac.gtest.tests.zip'], 16:44:42 INFO - u'jittest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 16:44:42 INFO - u'jsshell-mac.zip'], 16:44:42 INFO - u'mochitest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 16:44:42 INFO - u'firefox-53.0a1.en-US.mac.mochitest.tests.zip'], 16:44:42 INFO - u'mozbase': [u'firefox-53.0a1.en-US.mac.common.tests.zip'], 16:44:42 INFO - u'reftest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 16:44:42 INFO - u'firefox-53.0a1.en-US.mac.reftest.tests.zip'], 16:44:42 INFO - u'talos': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 16:44:42 INFO - u'firefox-53.0a1.en-US.mac.talos.tests.zip'], 16:44:42 INFO - u'web-platform': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 16:44:42 INFO - u'firefox-53.0a1.en-US.mac.web-platform.tests.zip'], 16:44:42 INFO - u'xpcshell': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 16:44:42 INFO - u'firefox-53.0a1.en-US.mac.xpcshell.tests.zip']} 16:44:42 INFO - Downloading packages: [u'firefox-53.0a1.en-US.mac.common.tests.zip'] for test suite category: common 16:44:42 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Rbu5pkLUSrKT-4K6y0xLnQ/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip 16:44:42 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Rbu5pkLUSrKT-4K6y0xLnQ/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip'}, attempt #1 16:44:42 INFO - Fetch https://queue.taskcluster.net/v1/task/Rbu5pkLUSrKT-4K6y0xLnQ/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip into memory 16:44:43 INFO - Expected file size: 16295323 16:44:43 INFO - Obtained file size: 16295323 16:44:50 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 16:44:50 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Rbu5pkLUSrKT-4K6y0xLnQ/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip 16:44:50 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Rbu5pkLUSrKT-4K6y0xLnQ/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip'}, attempt #1 16:44:50 INFO - Fetch https://queue.taskcluster.net/v1/task/Rbu5pkLUSrKT-4K6y0xLnQ/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip into memory 16:44:51 INFO - Expected file size: 16295323 16:44:51 INFO - Obtained file size: 16295323 16:44:58 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Rbu5pkLUSrKT-4K6y0xLnQ/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip 16:44:58 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Rbu5pkLUSrKT-4K6y0xLnQ/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip'}, attempt #1 16:44:58 INFO - Fetch https://queue.taskcluster.net/v1/task/Rbu5pkLUSrKT-4K6y0xLnQ/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip into memory 16:44:59 INFO - Expected file size: 14409776 16:44:59 INFO - Obtained file size: 14409776 16:44:59 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')]} 16:44:59 INFO - trying https://queue.taskcluster.net/v1/task/Rbu5pkLUSrKT-4K6y0xLnQ/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 16:44:59 INFO - Downloading https://queue.taskcluster.net/v1/task/Rbu5pkLUSrKT-4K6y0xLnQ/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg 16:44:59 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Rbu5pkLUSrKT-4K6y0xLnQ/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 16:45:02 INFO - Downloaded 69629180 bytes. 16:45:02 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Rbu5pkLUSrKT-4K6y0xLnQ/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 16:45:02 INFO - mkdir: /builds/slave/test/properties 16:45:02 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 16:45:02 INFO - Writing to file /builds/slave/test/properties/build_url 16:45:02 INFO - Contents: 16:45:02 INFO - build_url:https://queue.taskcluster.net/v1/task/Rbu5pkLUSrKT-4K6y0xLnQ/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 16:45:02 INFO - Running post-action listener: _resource_record_post_action 16:45:02 INFO - Running post-action listener: set_extra_try_arguments 16:45:02 INFO - [mozharness: 2016-12-29 00:45:02.725601Z] Finished download-and-extract step (success) 16:45:02 INFO - [mozharness: 2016-12-29 00:45:02.725726Z] Running populate-webroot step. 16:45:02 INFO - Running pre-action listener: _resource_record_pre_action 16:45:02 INFO - Running main action method: populate_webroot 16:45:02 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 16:45:02 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 16:45:02 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 16:45:02 INFO - '--tppagecycles', 16:45:02 INFO - '7'], 16:45:02 INFO - 'tests': ['tsvgm']}}, 16:45:02 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 16:45:02 INFO - 'tests': ['tresize', 'tcanvasmark']}, 16:45:02 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 16:45:02 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 16:45:02 INFO - 'tests': ['dromaeo_css', 'kraken']}, 16:45:02 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 16:45:02 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 16:45:02 INFO - 'talos_options': ['--disable-e10s'], 16:45:02 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:45:02 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 16:45:02 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:45:02 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 16:45:02 INFO - 'talos_options': ['--disable-e10s'], 16:45:02 INFO - 'tests': ['damp', 'tps']}, 16:45:02 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 16:45:02 INFO - 'tests': ['damp', 'tps']}, 16:45:02 INFO - 'g3': {'talos_options': ['--disable-e10s'], 16:45:02 INFO - 'tests': ['dromaeo_dom']}, 16:45:02 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 16:45:02 INFO - 'g4': {'talos_options': ['--disable-e10s'], 16:45:02 INFO - 'tests': ['basic_compositor_video']}, 16:45:02 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 16:45:02 INFO - 'other': {'talos_options': ['--disable-e10s'], 16:45:02 INFO - 'tests': ['a11yr', 16:45:02 INFO - 'ts_paint', 16:45:02 INFO - 'tpaint', 16:45:02 INFO - 'sessionrestore', 16:45:02 INFO - 'sessionrestore_no_auto_restore', 16:45:02 INFO - 'tabpaint']}, 16:45:02 INFO - 'other-e10s': {'tests': ['a11yr', 16:45:02 INFO - 'ts_paint', 16:45:02 INFO - 'tpaint', 16:45:02 INFO - 'sessionrestore', 16:45:02 INFO - 'sessionrestore_no_auto_restore', 16:45:02 INFO - 'tabpaint']}, 16:45:02 INFO - 'other-e10s_l64': {'tests': ['a11yr', 16:45:02 INFO - 'ts_paint', 16:45:02 INFO - 'tpaint', 16:45:02 INFO - 'sessionrestore', 16:45:02 INFO - 'sessionrestore_no_auto_restore', 16:45:02 INFO - 'tabpaint']}, 16:45:02 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 16:45:02 INFO - 'ts_paint', 16:45:02 INFO - 'tpaint', 16:45:02 INFO - 'sessionrestore', 16:45:02 INFO - 'sessionrestore_no_auto_restore', 16:45:02 INFO - 'tabpaint']}, 16:45:02 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 16:45:02 INFO - 'tests': ['a11yr', 16:45:02 INFO - 'ts_paint', 16:45:02 INFO - 'tpaint', 16:45:02 INFO - 'sessionrestore', 16:45:02 INFO - 'sessionrestore_no_auto_restore', 16:45:02 INFO - 'tabpaint']}, 16:45:02 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 16:45:02 INFO - 'tests': ['a11yr', 16:45:02 INFO - 'ts_paint', 16:45:02 INFO - 'tpaint', 16:45:02 INFO - 'sessionrestore', 16:45:02 INFO - 'sessionrestore_no_auto_restore', 16:45:02 INFO - 'tabpaint']}, 16:45:02 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 16:45:02 INFO - 'tests': ['tsvgx', 16:45:02 INFO - 'tsvgr_opacity', 16:45:02 INFO - 'tart', 16:45:02 INFO - 'tscrollx', 16:45:02 INFO - 'cart', 16:45:02 INFO - 'tsvg_static']}, 16:45:02 INFO - 'svgr-e10s': {'tests': ['tsvgx', 16:45:02 INFO - 'tsvgr_opacity', 16:45:02 INFO - 'tart', 16:45:02 INFO - 'tscrollx', 16:45:02 INFO - 'cart', 16:45:02 INFO - 'tsvg_static']}, 16:45:02 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 16:45:02 INFO - 'talos_options': ['--disable-e10s'], 16:45:02 INFO - 'tests': ['tp5o']}, 16:45:02 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 16:45:02 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 16:45:02 INFO - 'talos_options': ['--disable-e10s', 16:45:02 INFO - '--xperf_path', 16:45:02 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:45:02 INFO - 'tests': ['tp5n']}, 16:45:02 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 16:45:02 INFO - 'talos_options': ['--xperf_path', 16:45:02 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:45:02 INFO - 'tests': ['tp5n']}}, 16:45:02 INFO - 'talos.zip': {'path': '', 16:45:02 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 16:45:02 INFO - Running post-action listener: _resource_record_post_action 16:45:02 INFO - [mozharness: 2016-12-29 00:45:02.744335Z] Finished populate-webroot step (success) 16:45:02 INFO - [mozharness: 2016-12-29 00:45:02.744418Z] Running create-virtualenv step. 16:45:02 INFO - Running pre-action listener: _resource_record_pre_action 16:45:02 INFO - Running main action method: create_virtualenv 16:45:02 INFO - Creating virtualenv /builds/slave/test/build/venv 16:45:02 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 16:45:02 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 16:45:02 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 16:45:02 INFO - Using real prefix '/tools/python27' 16:45:02 INFO - New python executable in /builds/slave/test/build/venv/bin/python 16:45:03 INFO - Installing distribute.............................................................................................................................................................................................done. 16:45:06 INFO - Installing pip.................done. 16:45:06 INFO - Return code: 0 16:45:06 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 16:45:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 16:45:06 INFO - Reading from file tmpfile_stdout 16:45:06 INFO - Output received: 16:45:06 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) 16:45:06 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 16:45:06 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')]} 16:45:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:45:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:45:06 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')]} 16:45:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:45:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:45:06 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 0x10fff6b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fff9768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcdd35886e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fdd4d30>, '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 0x10fcfbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fdc9be8>, '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.8uJxNx2qBp/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.SVmM1OEo8i/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/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 16:45:06 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 16:45:06 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 16:45:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.8uJxNx2qBp/Render', 16:45:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:45:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:45:06 INFO - 'HOME': '/Users/cltbld', 16:45:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:45:06 INFO - 'LOGNAME': 'cltbld', 16:45:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:45:06 INFO - 'MOZ_NO_REMOTE': '1', 16:45:06 INFO - 'NO_EM_RESTART': '1', 16:45:06 INFO - 'PAGER': '/bin/cat', 16:45:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:45:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:45:06 INFO - 'PWD': '/builds/slave/test', 16:45:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:45:06 INFO - 'SHELL': '/bin/bash', 16:45:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.SVmM1OEo8i/Listeners', 16:45:06 INFO - 'TMPDIR': '/var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/T/', 16:45:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:45:06 INFO - 'USER': 'cltbld', 16:45:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:45:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:45:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:45:06 INFO - 'XPC_FLAGS': '0x0', 16:45:06 INFO - 'XPC_SERVICE_NAME': '0', 16:45:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:45:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:45:06 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 16:45:06 INFO - Cleaning up... 16:45:06 INFO - Return code: 0 16:45:06 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 16:45:06 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')]} 16:45:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:45:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:45:06 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')]} 16:45:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:45:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:45:06 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 0x10fff6b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fff9768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcdd35886e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fdd4d30>, '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 0x10fcfbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fdc9be8>, '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.8uJxNx2qBp/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.SVmM1OEo8i/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/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 16:45:06 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 16:45:06 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 16:45:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.8uJxNx2qBp/Render', 16:45:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:45:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:45:06 INFO - 'HOME': '/Users/cltbld', 16:45:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:45:06 INFO - 'LOGNAME': 'cltbld', 16:45:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:45:06 INFO - 'MOZ_NO_REMOTE': '1', 16:45:06 INFO - 'NO_EM_RESTART': '1', 16:45:06 INFO - 'PAGER': '/bin/cat', 16:45:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:45:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:45:06 INFO - 'PWD': '/builds/slave/test', 16:45:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:45:06 INFO - 'SHELL': '/bin/bash', 16:45:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.SVmM1OEo8i/Listeners', 16:45:06 INFO - 'TMPDIR': '/var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/T/', 16:45:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:45:06 INFO - 'USER': 'cltbld', 16:45:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:45:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:45:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:45:06 INFO - 'XPC_FLAGS': '0x0', 16:45:06 INFO - 'XPC_SERVICE_NAME': '0', 16:45:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:45:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:45:07 INFO - Downloading/unpacking psutil>=3.1.1 16:45:07 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 16:45:07 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 16:45:07 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 16:45:07 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 16:45:07 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 16:45:07 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 16:45:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 16:45:11 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:45:11 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:45:11 INFO - Installing collected packages: psutil 16:45:11 INFO - Running setup.py install for psutil 16:45:11 INFO - building 'psutil._psutil_osx' extension 16:45:11 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 16:45:11 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 16:45:11 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 16:45:11 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 16:45:11 INFO - building 'psutil._psutil_posix' extension 16:45:11 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 16:45:11 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 16:45:11 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 16:45:11 INFO - ^ 16:45:11 INFO - 1 warning generated. 16:45:11 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 16:45:12 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:45:12 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:45:12 INFO - Successfully installed psutil 16:45:12 INFO - Cleaning up... 16:45:12 INFO - Return code: 0 16:45:12 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 16:45: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')]} 16:45:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:45:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:45: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')]} 16:45:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:45:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:45:12 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 0x10fff6b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fff9768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcdd35886e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fdd4d30>, '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 0x10fcfbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fdc9be8>, '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.8uJxNx2qBp/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.SVmM1OEo8i/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/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 16:45:12 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 16:45:12 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 16:45:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.8uJxNx2qBp/Render', 16:45:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:45:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:45:12 INFO - 'HOME': '/Users/cltbld', 16:45:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:45:12 INFO - 'LOGNAME': 'cltbld', 16:45:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:45:12 INFO - 'MOZ_NO_REMOTE': '1', 16:45:12 INFO - 'NO_EM_RESTART': '1', 16:45:12 INFO - 'PAGER': '/bin/cat', 16:45:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:45:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:45:12 INFO - 'PWD': '/builds/slave/test', 16:45:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:45:12 INFO - 'SHELL': '/bin/bash', 16:45:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.SVmM1OEo8i/Listeners', 16:45:12 INFO - 'TMPDIR': '/var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/T/', 16:45:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:45:12 INFO - 'USER': 'cltbld', 16:45:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:45:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:45:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:45:12 INFO - 'XPC_FLAGS': '0x0', 16:45:12 INFO - 'XPC_SERVICE_NAME': '0', 16:45:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:45:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:45:12 INFO - Downloading/unpacking mozsystemmonitor==0.3 16:45:12 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 16:45:12 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 16:45:12 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 16:45:12 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 16:45:12 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 16:45:12 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 16:45:15 INFO - Downloading mozsystemmonitor-0.3.tar.gz 16:45:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 16:45:15 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 16:45:15 INFO - Installing collected packages: mozsystemmonitor 16:45:15 INFO - Running setup.py install for mozsystemmonitor 16:45:15 INFO - Successfully installed mozsystemmonitor 16:45:15 INFO - Cleaning up... 16:45:15 INFO - Return code: 0 16:45:15 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 16:45:15 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')]} 16:45:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:45:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:45:15 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')]} 16:45:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:45:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:45:15 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 0x10fff6b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fff9768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcdd35886e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fdd4d30>, '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 0x10fcfbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fdc9be8>, '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.8uJxNx2qBp/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.SVmM1OEo8i/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/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 16:45:15 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 16:45:15 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 16:45:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.8uJxNx2qBp/Render', 16:45:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:45:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:45:15 INFO - 'HOME': '/Users/cltbld', 16:45:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:45:15 INFO - 'LOGNAME': 'cltbld', 16:45:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:45:15 INFO - 'MOZ_NO_REMOTE': '1', 16:45:15 INFO - 'NO_EM_RESTART': '1', 16:45:15 INFO - 'PAGER': '/bin/cat', 16:45:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:45:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:45:15 INFO - 'PWD': '/builds/slave/test', 16:45:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:45:15 INFO - 'SHELL': '/bin/bash', 16:45:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.SVmM1OEo8i/Listeners', 16:45:15 INFO - 'TMPDIR': '/var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/T/', 16:45:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:45:15 INFO - 'USER': 'cltbld', 16:45:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:45:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:45:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:45:15 INFO - 'XPC_FLAGS': '0x0', 16:45:15 INFO - 'XPC_SERVICE_NAME': '0', 16:45:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:45:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:45:15 INFO - Downloading/unpacking jsonschema==2.5.1 16:45:15 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 16:45:15 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 16:45:15 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 16:45:15 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 16:45:15 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 16:45:15 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 16:45:18 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 16:45:19 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 16:45:19 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 16:45:19 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 16:45:19 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 16:45:19 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 16:45:19 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 16:45:19 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 16:45:19 INFO - Downloading functools32-3.2.3-2.tar.gz 16:45:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 16:45:19 INFO - warning: no files found matching '*.txt' 16:45:19 INFO - no previously-included directories found matching 'build' 16:45:19 INFO - no previously-included directories found matching 'dist' 16:45:19 INFO - no previously-included directories found matching '.git*' 16:45:19 INFO - Installing collected packages: jsonschema, functools32 16:45:19 INFO - Running setup.py install for functools32 16:45:19 INFO - warning: no files found matching '*.txt' 16:45:19 INFO - no previously-included directories found matching 'build' 16:45:19 INFO - no previously-included directories found matching 'dist' 16:45:19 INFO - no previously-included directories found matching '.git*' 16:45:19 INFO - Successfully installed jsonschema functools32 16:45:19 INFO - Cleaning up... 16:45:20 INFO - Return code: 0 16:45:20 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 16:45:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:45:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:45:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:45:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:45:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:45:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:45:20 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 0x10fff6b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fff9768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcdd35886e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fdd4d30>, '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 0x10fcfbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fdc9be8>, '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.8uJxNx2qBp/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.SVmM1OEo8i/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/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 16:45:20 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 16:45:20 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 16:45:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.8uJxNx2qBp/Render', 16:45:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:45:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:45:20 INFO - 'HOME': '/Users/cltbld', 16:45:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:45:20 INFO - 'LOGNAME': 'cltbld', 16:45:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:45:20 INFO - 'MOZ_NO_REMOTE': '1', 16:45:20 INFO - 'NO_EM_RESTART': '1', 16:45:20 INFO - 'PAGER': '/bin/cat', 16:45:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:45:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:45:20 INFO - 'PWD': '/builds/slave/test', 16:45:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:45:20 INFO - 'SHELL': '/bin/bash', 16:45:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.SVmM1OEo8i/Listeners', 16:45:20 INFO - 'TMPDIR': '/var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/T/', 16:45:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:45:20 INFO - 'USER': 'cltbld', 16:45:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:45:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:45:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:45:20 INFO - 'XPC_FLAGS': '0x0', 16:45:20 INFO - 'XPC_SERVICE_NAME': '0', 16:45:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:45:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:45:20 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 16:45:20 INFO - Cleaning up... 16:45:20 INFO - Return code: 0 16:45:20 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 16:45:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:45:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:45:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:45:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:45:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:45:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:45:20 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 0x10fff6b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fff9768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcdd35886e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fdd4d30>, '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 0x10fcfbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fdc9be8>, '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.8uJxNx2qBp/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.SVmM1OEo8i/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/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 16:45:20 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 16:45:20 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 16:45:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.8uJxNx2qBp/Render', 16:45:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:45:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:45:20 INFO - 'HOME': '/Users/cltbld', 16:45:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:45:20 INFO - 'LOGNAME': 'cltbld', 16:45:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:45:20 INFO - 'MOZ_NO_REMOTE': '1', 16:45:20 INFO - 'NO_EM_RESTART': '1', 16:45:20 INFO - 'PAGER': '/bin/cat', 16:45:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:45:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:45:20 INFO - 'PWD': '/builds/slave/test', 16:45:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:45:20 INFO - 'SHELL': '/bin/bash', 16:45:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.SVmM1OEo8i/Listeners', 16:45:20 INFO - 'TMPDIR': '/var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/T/', 16:45:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:45:20 INFO - 'USER': 'cltbld', 16:45:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:45:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:45:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:45:20 INFO - 'XPC_FLAGS': '0x0', 16:45:20 INFO - 'XPC_SERVICE_NAME': '0', 16:45:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:45:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:45:20 INFO - Downloading/unpacking blobuploader==1.2.4 16:45: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 16:45: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 16:45: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 16:45: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 16:45: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 16:45: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 16:45:23 INFO - Downloading blobuploader-1.2.4.tar.gz 16:45:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 16:45:23 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 16:45:23 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 16:45:23 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 16:45:23 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 16:45:23 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 16:45:23 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 16:45:23 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 16:45:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 16:45:24 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 16:45:24 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 16:45:24 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 16:45:24 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 16:45:24 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 16:45:24 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 16:45:24 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 16:45:25 INFO - Downloading docopt-0.6.1.tar.gz 16:45:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 16:45:25 INFO - Installing collected packages: blobuploader, requests, docopt 16:45:25 INFO - Running setup.py install for blobuploader 16:45:25 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 16:45:25 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 16:45:25 INFO - Running setup.py install for requests 16:45:25 INFO - Running setup.py install for docopt 16:45:26 INFO - Successfully installed blobuploader requests docopt 16:45:26 INFO - Cleaning up... 16:45:26 INFO - Return code: 0 16:45:26 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:45:26 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')]} 16:45:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:45:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:45:26 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')]} 16:45:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:45:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:45:26 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 0x10fff6b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fff9768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcdd35886e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fdd4d30>, '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 0x10fcfbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fdc9be8>, '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.8uJxNx2qBp/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.SVmM1OEo8i/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/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 16:45:26 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 16:45:26 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 16:45:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.8uJxNx2qBp/Render', 16:45:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:45:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:45:26 INFO - 'HOME': '/Users/cltbld', 16:45:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:45:26 INFO - 'LOGNAME': 'cltbld', 16:45:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:45:26 INFO - 'MOZ_NO_REMOTE': '1', 16:45:26 INFO - 'NO_EM_RESTART': '1', 16:45:26 INFO - 'PAGER': '/bin/cat', 16:45:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:45:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:45:26 INFO - 'PWD': '/builds/slave/test', 16:45:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:45:26 INFO - 'SHELL': '/bin/bash', 16:45:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.SVmM1OEo8i/Listeners', 16:45:26 INFO - 'TMPDIR': '/var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/T/', 16:45:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:45:26 INFO - 'USER': 'cltbld', 16:45:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:45:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:45:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:45:26 INFO - 'XPC_FLAGS': '0x0', 16:45:26 INFO - 'XPC_SERVICE_NAME': '0', 16:45:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:45:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:45:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:45:26 INFO - Running setup.py (path:/var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/T/pip-LQOZ0_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:45:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:45:26 INFO - Running setup.py (path:/var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/T/pip-3CBSeh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:45:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:45:26 INFO - Running setup.py (path:/var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/T/pip-LchSe3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:45:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:45:26 INFO - Running setup.py (path:/var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/T/pip-hxeO5J-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:45:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:45:26 INFO - Running setup.py (path:/var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/T/pip-x1SWiZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:45:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:45:26 INFO - Running setup.py (path:/var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/T/pip-qdOKDm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:45:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:45:27 INFO - Running setup.py (path:/var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/T/pip-69dzhV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:45:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:45:27 INFO - Running setup.py (path:/var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/T/pip-GPS_AF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:45:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:45:27 INFO - Running setup.py (path:/var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/T/pip-4rQ8nY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:45:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:45:27 INFO - Running setup.py (path:/var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/T/pip-0OebRD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:45:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:45:27 INFO - Running setup.py (path:/var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/T/pip-A_3BgI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:45:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:45:27 INFO - Running setup.py (path:/var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/T/pip-vEWhkR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:45:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:45:27 INFO - Running setup.py (path:/var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/T/pip-tAvfRT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:45:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:45:27 INFO - Running setup.py (path:/var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/T/pip-7wi_Wg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:45:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:45:27 INFO - Running setup.py (path:/var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/T/pip-rJ79Fh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:45:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:45:27 INFO - Running setup.py (path:/var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/T/pip-3snp4w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:45:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:45:28 INFO - Running setup.py (path:/var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/T/pip-4tlRLB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:45:28 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 16:45:28 INFO - Running setup.py install for manifestparser 16:45:28 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 16:45:28 INFO - Running setup.py install for mozcrash 16:45:28 INFO - Running setup.py install for mozdebug 16:45:28 INFO - Running setup.py install for mozdevice 16:45:28 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 16:45:28 INFO - Installing dm script to /builds/slave/test/build/venv/bin 16:45:28 INFO - Running setup.py install for mozfile 16:45:28 INFO - Running setup.py install for mozhttpd 16:45:29 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 16:45:29 INFO - Running setup.py install for mozinfo 16:45:29 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 16:45:29 INFO - Running setup.py install for mozInstall 16:45:29 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 16:45:29 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 16:45:29 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 16:45:29 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 16:45:29 INFO - Running setup.py install for mozleak 16:45:29 INFO - Running setup.py install for mozlog 16:45:29 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 16:45:29 INFO - Running setup.py install for moznetwork 16:45:29 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 16:45:29 INFO - Running setup.py install for mozprocess 16:45:30 INFO - Running setup.py install for mozprofile 16:45:30 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 16:45:30 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 16:45:30 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 16:45:30 INFO - Running setup.py install for mozrunner 16:45:30 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 16:45:30 INFO - Running setup.py install for mozscreenshot 16:45:30 INFO - Running setup.py install for moztest 16:45:30 INFO - Running setup.py install for mozversion 16:45:30 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 16:45:30 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 16:45:30 INFO - Cleaning up... 16:45:30 INFO - Return code: 0 16:45:30 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:45:30 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')]} 16:45:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:45:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:45:30 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')]} 16:45:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:45:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:45:30 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 0x10fff6b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fff9768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcdd35886e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fdd4d30>, '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 0x10fcfbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fdc9be8>, '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.8uJxNx2qBp/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.SVmM1OEo8i/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/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 16:45:30 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 16:45:30 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 16:45:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.8uJxNx2qBp/Render', 16:45:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:45:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:45:30 INFO - 'HOME': '/Users/cltbld', 16:45:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:45:30 INFO - 'LOGNAME': 'cltbld', 16:45:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:45:30 INFO - 'MOZ_NO_REMOTE': '1', 16:45:30 INFO - 'NO_EM_RESTART': '1', 16:45:30 INFO - 'PAGER': '/bin/cat', 16:45:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:45:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:45:30 INFO - 'PWD': '/builds/slave/test', 16:45:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:45:30 INFO - 'SHELL': '/bin/bash', 16:45:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.SVmM1OEo8i/Listeners', 16:45:30 INFO - 'TMPDIR': '/var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/T/', 16:45:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:45:30 INFO - 'USER': 'cltbld', 16:45:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:45:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:45:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:45:30 INFO - 'XPC_FLAGS': '0x0', 16:45:30 INFO - 'XPC_SERVICE_NAME': '0', 16:45:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:45:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:45:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:45:31 INFO - Running setup.py (path:/var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/T/pip-zEi6ps-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:45:31 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)) 16:45:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:45:31 INFO - Running setup.py (path:/var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/T/pip-snJ499-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:45:31 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)) 16:45:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:45:31 INFO - Running setup.py (path:/var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/T/pip-nJUpMz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:45:31 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)) 16:45:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:45:31 INFO - Running setup.py (path:/var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/T/pip-MOewV0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:45:31 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)) 16:45:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:45:31 INFO - Running setup.py (path:/var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/T/pip-QDZ4vT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:45:31 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)) 16:45:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:45:31 INFO - Running setup.py (path:/var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/T/pip-4CuZjY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:45:31 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)) 16:45:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:45:31 INFO - Running setup.py (path:/var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/T/pip-oXHB2W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:45:31 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)) 16:45:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:45:31 INFO - Running setup.py (path:/var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/T/pip-QOClz4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:45:31 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)) 16:45:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:45:31 INFO - Running setup.py (path:/var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/T/pip-Hy23i_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:45:32 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)) 16:45:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:45:32 INFO - Running setup.py (path:/var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/T/pip-qgw5mu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:45:32 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)) 16:45:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:45:32 INFO - Running setup.py (path:/var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/T/pip-tksh7i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:45:32 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)) 16:45:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:45:32 INFO - Running setup.py (path:/var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/T/pip-KziXdd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:45:32 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)) 16:45:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:45:32 INFO - Running setup.py (path:/var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/T/pip-zbbY0_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:45:32 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)) 16:45:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:45:32 INFO - Running setup.py (path:/var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/T/pip-1oBMVn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:45:32 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)) 16:45:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:45:32 INFO - Running setup.py (path:/var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/T/pip-Khie9k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:45:32 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)) 16:45:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:45:32 INFO - Running setup.py (path:/var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/T/pip-QZknCh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:45:32 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 16:45:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:45:32 INFO - Running setup.py (path:/var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/T/pip-okLlmt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:45:32 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)) 16:45:32 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)) 16:45:32 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)) 16:45:32 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)) 16:45:32 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)) 16:45:32 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)) 16:45:32 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 16:45: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 16:45: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 16:45: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 16:45: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 16:45: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 16:45: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 16:45:35 INFO - Downloading blessings-1.6.tar.gz 16:45:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 16:45:35 INFO - Installing collected packages: blessings 16:45:35 INFO - Running setup.py install for blessings 16:45:35 INFO - Successfully installed blessings 16:45:35 INFO - Cleaning up... 16:45:35 INFO - Return code: 0 16:45:35 INFO - Done creating virtualenv /builds/slave/test/build/venv. 16:45:35 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:45:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:45:36 INFO - Reading from file tmpfile_stdout 16:45:36 INFO - Current package versions: 16:45:36 INFO - blessings == 1.6 16:45:36 INFO - blobuploader == 1.2.4 16:45:36 INFO - docopt == 0.6.1 16:45:36 INFO - functools32 == 3.2.3-2 16:45:36 INFO - jsonschema == 2.5.1 16:45:36 INFO - manifestparser == 1.1 16:45:36 INFO - mozInstall == 1.12 16:45:36 INFO - mozcrash == 1.0 16:45:36 INFO - mozdebug == 0.1 16:45:36 INFO - mozdevice == 0.49 16:45:36 INFO - mozfile == 1.2 16:45:36 INFO - mozhttpd == 0.7 16:45:36 INFO - mozinfo == 0.9 16:45:36 INFO - mozleak == 0.1 16:45:36 INFO - mozlog == 3.4 16:45:36 INFO - moznetwork == 0.27 16:45:36 INFO - mozprocess == 0.23 16:45:36 INFO - mozprofile == 0.28 16:45:36 INFO - mozrunner == 6.13 16:45:36 INFO - mozscreenshot == 0.1 16:45:36 INFO - mozsystemmonitor == 0.3 16:45:36 INFO - moztest == 0.7 16:45:36 INFO - mozversion == 1.4 16:45:36 INFO - psutil == 3.1.1 16:45:36 INFO - requests == 1.2.3 16:45:36 INFO - wsgiref == 0.1.2 16:45:36 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:45:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:45:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:45:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:45:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:45:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:45:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:45:36 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 0x10fff6b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fff9768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcdd35886e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fdd4d30>, '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 0x10fcfbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fdc9be8>, '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.8uJxNx2qBp/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.SVmM1OEo8i/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/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 16:45:36 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 16:45:36 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 16:45:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.8uJxNx2qBp/Render', 16:45:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:45:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:45:36 INFO - 'HOME': '/Users/cltbld', 16:45:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:45:36 INFO - 'LOGNAME': 'cltbld', 16:45:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:45:36 INFO - 'MOZ_NO_REMOTE': '1', 16:45:36 INFO - 'NO_EM_RESTART': '1', 16:45:36 INFO - 'PAGER': '/bin/cat', 16:45:36 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:45:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:45:36 INFO - 'PWD': '/builds/slave/test', 16:45:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:45:36 INFO - 'SHELL': '/bin/bash', 16:45:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.SVmM1OEo8i/Listeners', 16:45:36 INFO - 'TMPDIR': '/var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/T/', 16:45:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:45:36 INFO - 'USER': 'cltbld', 16:45:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:45:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:45:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:45:36 INFO - 'XPC_FLAGS': '0x0', 16:45:36 INFO - 'XPC_SERVICE_NAME': '0', 16:45:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:45:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:45:36 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)) 16:45:36 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)) 16:45:36 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)) 16:45:36 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)) 16:45:36 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)) 16:45:36 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)) 16:45:36 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)) 16:45:36 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)) 16:45:36 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)) 16:45:36 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)) 16:45:36 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)) 16:45:36 INFO - Cleaning up... 16:45:36 INFO - Return code: 0 16:45:36 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 16:45:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:45:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:45:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:45:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:45:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:45:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:45:36 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10fff6b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fff9768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcdd35886e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fdd4d30>, '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 0x10fcfbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fdc9be8>, '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.8uJxNx2qBp/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.SVmM1OEo8i/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/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 16:45:36 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 16:45:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 16:45:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.8uJxNx2qBp/Render', 16:45:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:45:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:45:36 INFO - 'HOME': '/Users/cltbld', 16:45:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:45:36 INFO - 'LOGNAME': 'cltbld', 16:45:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:45:36 INFO - 'MOZ_NO_REMOTE': '1', 16:45:36 INFO - 'NO_EM_RESTART': '1', 16:45:36 INFO - 'PAGER': '/bin/cat', 16:45:36 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:45:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:45:36 INFO - 'PWD': '/builds/slave/test', 16:45:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:45:36 INFO - 'SHELL': '/bin/bash', 16:45:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.SVmM1OEo8i/Listeners', 16:45:36 INFO - 'TMPDIR': '/var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/T/', 16:45:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:45:36 INFO - 'USER': 'cltbld', 16:45:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:45:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:45:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:45:36 INFO - 'XPC_FLAGS': '0x0', 16:45:36 INFO - 'XPC_SERVICE_NAME': '0', 16:45:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:45:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:45:36 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 16:45:36 INFO - Cleaning up... 16:45:36 INFO - Return code: 0 16:45:36 INFO - Running post-action listener: _resource_record_post_action 16:45:36 INFO - Running post-action listener: _start_resource_monitoring 16:45:36 INFO - Starting resource monitoring. 16:45:36 INFO - [mozharness: 2016-12-29 00:45:36.724906Z] Finished create-virtualenv step (success) 16:45:36 INFO - [mozharness: 2016-12-29 00:45:36.726004Z] Running install step. 16:45:36 INFO - Running pre-action listener: _resource_record_pre_action 16:45:36 INFO - Running main action method: install 16:45:36 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:45:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:45:36 INFO - Reading from file tmpfile_stdout 16:45:36 INFO - Detecting whether we're running mozinstall >=1.0... 16:45:36 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 16:45:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 16:45:37 INFO - Reading from file tmpfile_stdout 16:45:37 INFO - Output received: 16:45:37 INFO - Usage: mozinstall [options] installer 16:45:37 INFO - Options: 16:45:37 INFO - -h, --help show this help message and exit 16:45:37 INFO - -d DEST, --destination=DEST 16:45:37 INFO - Directory to install application into. [default: 16:45:37 INFO - "/builds/slave/test"] 16:45:37 INFO - --app=APP Application being installed. [default: firefox] 16:45:37 INFO - mkdir: /builds/slave/test/build/application 16:45:37 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'] 16:45:37 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 16:45:58 INFO - Reading from file tmpfile_stdout 16:45:58 INFO - Output received: 16:45:58 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 16:45:58 INFO - Running post-action listener: _resource_record_post_action 16:45:58 INFO - [mozharness: 2016-12-29 00:45:58.790487Z] Finished install step (success) 16:45:58 INFO - [mozharness: 2016-12-29 00:45:58.790608Z] Running run-tests step. 16:45:58 INFO - Running pre-action listener: _resource_record_pre_action 16:45:58 INFO - Running main action method: run_tests 16:45:58 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 16:45:58 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 16:45:58 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 16:45:58 INFO - 2016-12-28 16:45:58.805 screenresolution[1955:9585] starting screenresolution argv=screenresolution get 16:45:58 INFO - 2016-12-28 16:45:58.807 screenresolution[1955:9585] Display 0: 1600x1200x32@60 16:45:58 INFO - 2016-12-28 16:45:58.813 screenresolution[1956:9587] starting screenresolution argv=screenresolution list 16:45:58 INFO - Available Modes on Display 0 16:45:58 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 16:45:58 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 16:45:58 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 16:45:58 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 16:45:59 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 16:45:59 INFO - Intel Iris: 16:45:59 INFO - Chipset Model: Intel Iris 16:45:59 INFO - Type: GPU 16:45:59 INFO - Bus: Built-In 16:45:59 INFO - VRAM (Dynamic, Max): 1536 MB 16:45:59 INFO - Vendor: Intel (0x8086) 16:45:59 INFO - Device ID: 0x0a2e 16:45:59 INFO - Revision ID: 0x0009 16:45:59 INFO - Displays: 16:45:59 INFO - Display: 16:45:59 INFO - Resolution: 1600 x 1200 @ 60 Hz 16:45:59 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 16:45:59 INFO - Display Serial Number: 1600x1200 60 16:45:59 INFO - Main Display: Yes 16:45:59 INFO - Mirror: Off 16:45:59 INFO - Online: Yes 16:45:59 INFO - Rotation: Supported 16:45:59 INFO - Return code: 0 16:45:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 16:45:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 16:45:59 INFO - Python 2.7.3 16:45:59 INFO - Return code: 0 16:45:59 INFO - grabbing minidump binary from tooltool 16:45:59 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')]} 16:45:59 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 0x10fdd4d30>, '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 0x10fcfbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fdc9be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 16:45:59 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 16:45:59 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 16:45:59 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 16:45:59 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 16:45:59 INFO - Return code: 0 16:45:59 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 16:45:59 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 16:45:59 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 16:45:59 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 16:45:59 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 16:45:59 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/Rbu5pkLUSrKT-4K6y0xLnQ/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0015', '--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 16:45:59 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/Rbu5pkLUSrKT-4K6y0xLnQ/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0015 --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 16:45:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.8uJxNx2qBp/Render', 16:45:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:45:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:45:59 INFO - 'HOME': '/Users/cltbld', 16:45:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:45:59 INFO - 'LOGNAME': 'cltbld', 16:45:59 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 16:45:59 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 16:45:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:45:59 INFO - 'MOZ_NO_REMOTE': '1', 16:45:59 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 16:45:59 INFO - 'NO_EM_RESTART': '1', 16:45:59 INFO - 'PAGER': '/bin/cat', 16:45:59 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:45:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:45:59 INFO - 'PWD': '/builds/slave/test', 16:45:59 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 16:45:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:45:59 INFO - 'SHELL': '/bin/bash', 16:45:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.SVmM1OEo8i/Listeners', 16:45:59 INFO - 'TMPDIR': '/var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/T/', 16:45:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:45:59 INFO - 'USER': 'cltbld', 16:45:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:45:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:45:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:45:59 INFO - 'XPC_FLAGS': '0x0', 16:45:59 INFO - 'XPC_SERVICE_NAME': '0', 16:45:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:45:59 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/Rbu5pkLUSrKT-4K6y0xLnQ/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0015', '--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 16:45:59 INFO - mozversion application_buildid: 20161228160136 16:45:59 INFO - mozversion application_changeset: b882b98ee9ddba735a0a3a57623e0b9d9fc7742a 16:45:59 INFO - mozversion application_display_name: Nightly 16:45:59 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 16:45:59 INFO - mozversion application_name: Firefox 16:45:59 INFO - mozversion application_remotingname: firefox 16:45:59 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 16:45:59 INFO - mozversion application_vendor: Mozilla 16:45:59 INFO - mozversion application_version: 53.0a1 16:45:59 INFO - mozversion platform_buildid: 20161228160136 16:45:59 INFO - mozversion platform_changeset: b882b98ee9ddba735a0a3a57623e0b9d9fc7742a 16:45:59 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 16:45:59 INFO - mozversion platform_version: 53.0a1 16:45:59 INFO - using testdate: 1482972359 16:45:59 INFO - actual date: 1482972359 16:45:59 INFO - starting webserver on 'localhost:49321' 16:45:59 INFO - SUITE-START | Running 2 tests 16:45:59 INFO - TEST-START | dromaeo_css 16:45:59 INFO - operating with platform_type : mac_ 16:45:59 INFO - Initialising browser for dromaeo_css test... 16:45:59 INFO - TEST-INFO | started process 1966 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/T/tmpxS622a/profile http://localhost:49321/getInfo.html) 16:45:59 INFO - PROCESS | 1966 | 1482972359912 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 16:46:01 INFO - PROCESS | 1966 | __metrics Screen width/height:1600/1200 16:46:01 INFO - PROCESS | 1966 | colorDepth:24 16:46:01 INFO - PROCESS | 1966 | Browser inner width/height: 1024/689 16:46:01 INFO - PROCESS | 1966 | __metrics 16:46:01 INFO - PROCESS | 1966 | 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" 16:46:01 INFO - PROCESS | 1966 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 16:46:02 INFO - TEST-INFO | 1966: exit 0 16:46:02 INFO - Browser initialized. 16:46:02 INFO - Running cycle 1/1 for dromaeo_css test... 16:46:02 INFO - TEST-INFO | started process 1968 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/T/tmpxS622a/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:46:14 INFO - PROCESS | 1968 | RSS: Main: 233086976 16:46:14 INFO - PROCESS | 1968 | 16:49:19 INFO - PROCESS | 1968 | Cycle 1(1): loaded http://localhost:49321/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49321/tests/dromaeo/cssquery-ext.html) 16:49:21 INFO - PROCESS | 1968 | RSS: Main: 348450816 16:49:21 INFO - PROCESS | 1968 | 16:51:03 INFO - PROCESS | 1968 | ************************* 16:51:03 INFO - PROCESS | 1968 | A coding exception was thrown and uncaught in a Task. 16:51:03 INFO - PROCESS | 1968 | 16:51:03 INFO - PROCESS | 1968 | Full message: ReferenceError: fetch is not defined 16:51:03 INFO - PROCESS | 1968 | Full stack: apiCall@resource://shield-recipe-client/lib/NormandyApi.jsm:37:5 16:51:03 INFO - PROCESS | 1968 | get@resource://shield-recipe-client/lib/NormandyApi.jsm:44:12 16:51:03 INFO - PROCESS | 1968 | this.NormandyApi.fetchRecipes<@resource://shield-recipe-client/lib/NormandyApi.jsm:52:34 16:51:03 INFO - PROCESS | 1968 | TaskImpl_run@resource://gre/modules/Task.jsm:319:42 16:51:03 INFO - PROCESS | 1968 | TaskImpl@resource://gre/modules/Task.jsm:277:3 16:51:03 INFO - PROCESS | 1968 | asyncFunction@resource://gre/modules/Task.jsm:252:14 16:51:03 INFO - PROCESS | 1968 | this.RecipeRunner.start<@resource://shield-recipe-client/lib/RecipeRunner.jsm:64:23 16:51:03 INFO - PROCESS | 1968 | TaskImpl_run@resource://gre/modules/Task.jsm:319:42 16:51:03 INFO - PROCESS | 1968 | TaskImpl@resource://gre/modules/Task.jsm:277:3 16:51:03 INFO - PROCESS | 1968 | asyncFunction@resource://gre/modules/Task.jsm:252:14 16:51:03 INFO - PROCESS | 1968 | setTimeout_timer@resource://gre/modules/Timer.jsm:30:5 16:51:03 INFO - PROCESS | 1968 | 16:51:03 INFO - PROCESS | 1968 | ************************* 16:52:24 INFO - PROCESS | 1968 | Cycle 1(1): loaded http://localhost:49321/tests/dromaeo/cssquery-ext.html (next: http://localhost:49321/tests/dromaeo/cssquery-jquery.html) 16:52:25 INFO - PROCESS | 1968 | RSS: Main: 372400128 16:52:25 INFO - PROCESS | 1968 | 16:55:27 INFO - PROCESS | 1968 | Cycle 1(1): loaded http://localhost:49321/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49321/tests/dromaeo/cssquery-mootools.html) 16:55:28 INFO - PROCESS | 1968 | RSS: Main: 337739776 16:55:28 INFO - PROCESS | 1968 | 16:58:30 INFO - PROCESS | 1968 | Cycle 1(1): loaded http://localhost:49321/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49321/tests/dromaeo/cssquery-prototype.html) 16:58:31 INFO - PROCESS | 1968 | RSS: Main: 349667328 16:58:31 INFO - PROCESS | 1968 | 17:01:34 INFO - PROCESS | 1968 | Cycle 1(1): loaded http://localhost:49321/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49321/tests/dromaeo/cssquery-yui.html) 17:01:35 INFO - PROCESS | 1968 | RSS: Main: 326246400 17:01:35 INFO - PROCESS | 1968 | 17:04:38 INFO - PROCESS | 1968 | Cycle 1(1): loaded http://localhost:49321/tests/dromaeo/cssquery-yui.html (next: http://localhost:49321/tests/dromaeo/cssquery-dojo.html) 17:04:38 INFO - PROCESS | 1968 | RSS: Main: 486551552 17:04:38 INFO - PROCESS | 1968 | 17:04:38 INFO - PROCESS | 1968 | __start_tp_report 17:04:38 INFO - PROCESS | 1968 | _x_x_mozilla_page_load 17:04:38 INFO - PROCESS | 1968 | _x_x_mozilla_page_load_details 17:04:38 INFO - PROCESS | 1968 | |i|pagename|runs| 17:04:38 INFO - PROCESS | 1968 | |0;dojo.html;3312.188513814116;3316.9336613267737;3335.7831740936854;3348.5312056312105;3384.593848738152;5579.609427340078;5634.408387119355;5683.886322273552;5756.539476841848;5835.854103647405;5670.631408958425;5716.799912003081;5729.026065568853;5732.111522713975;5754.769809207637;5139.640225184238;5171.250168725533;5187.325647665798;5212.322398088243;5244.475552444763;5199.168133098705;5273.1826566882;5286.07493688603;5294.100002999499;5303.681779093247;5263.526282634562;5264.631475796696;5265.7893684252585;5279;5280.102382594968;5249.475052494739;5265.7893684252585;5269.473052694738;5272.92090618641;5284.894302113936;5024.723640199788;5179.792808287664;5230.267762513213;5238.004779091961;5244.39689435716;5696.231008813798;5756.424357564252;5849.941500585025;5895.64626122434;5896.616719913192;5284.81503147388;6104.450599446027;6216.160818289518;6242.282137554149;6243.906341404882;6145.9692701536205;6155.969220153916;6171.105189747462;6187.8143655689955;6190.721417536221;5865.618734782311;5869.471747542656;5870.383609721003;5886.558508111909;5911.142884281798;6229.968850155811;6243.094751261042;6254.155688981929;6260.46786023184;6273.498120150377;5996.16053752475;6049.425304596057;6057.273127224761;6119.197913140417;6129.233845769279;5084.542391184811;6192.071189321638;6213.96893015532;6214.564980451325;6215.782447614311;6081.817545473555;6192.566520343533;6212;6221.377862213742;6253.749850005949;6115.694215289218;6116.66358350295;6126.908096378558;6146.200993870858;6243.0635404688755;4922.606191504671;4936.506349365035;4945.950540494549;4988.825391111366;4999.75001249936;3742.7567208131386;4415.735055896656;4418.359337896051;4424.6902716810155;4434.84478043267;5611.719414029364;5837.620554663933;5844.035734103825;5873.383294754075;5900.675462849498;6135.04893764628;6148.923938310778;6176.536759743038;6191.752329906843;6244.438000579969;5852.005159122959;5912.320083190402;5912.46787789101;5922.200502932135;5937.228160339128;5276.023935571855;5295.046891559557;5307.071262529119;5324.254604355393;5372.140457526777;6222.62664240147;6237.09562113491;6270.717817698123;6276.811695649138;6292.748290068346;6123.526657168292;6124.594542075475;6154.753809847556;6171.228596425447;6178.196834411498;6267.46726528232;6291.779787707408;6300.716467759054;6301.747930082929;6348.047792831112;6214.40963108504;6226.346233645583;6230.034344676583;6255.749770009149;6257.906131408124;6117.979653188475;6162.630242185483;6195.659238741912;6203.720832562455;6209.5032397409;6102.156009074812;6115.571909966259;6136.447719705248;6173.969130154321;6174.876502470015;6010.519158467399;6057.515398768;6151.384861513813;6203.844903877259;6221.5333849960525;5886.028805247086;5929.673867937305;6072.635641861502;6083.148359229623;6090.056041313604;4980.825671101639;6106.938930610637;6113.658745237643;6159.476444502088;6161.845953851011;6032.366601506778;6035.305939816802;6043.637381757108;6056.152138700673;6079.756809727738 17:04:38 INFO - PROCESS | 1968 | |1;ext.html;441.6422697614932;445.9487158976719;472.5203918023205;496.2878269683005;514.2723046888652;29956.550651740214;38995.80502097489;40623.79688101563;40650.39024414636;42358.94102647436;41395;41706.58293417065;41728;41796.791016044874;41859.58140418595;9437.622495100188;9613.903860961405;9632.711018669415;9646.710598682052;9649.855252171188;7044.859102817941;8654.87017694735;8679.479231246114;8783.429077109999;8785.648574057062;11328.32030078198;11621.302721836675;11640.941795291012;11804.055675545937;11808.527658893678;11461.713457163554;11476.942615286871;11583.420828958602;11589.594364197214;11598.826017609743;8439.957800211021;8668.349873759493;11383.943080284545;11450.656480305604;11490.94254528722;38264;38843;38958.22083558313;40931.795341023404;41047;3934.626210509997;3938.9664574724206;3944.5660977292764;3962.583928687504;4087.998440382096;28944.42111157786;29252.414951700848;30082.398352033055;30115.69884301151;30169.849150754108;3128.749700023993;3161.2611223571243;3239.951400728967;3303.6035675718826;3348.564686590752;483575;488046.99999999645;488234;489083;489977;55818.74356333732;55892.161617575766;55987.16019259714;56436.71781641065;56445.43554564402;60003.39996600065;60084.39915600875;60145.39854601398;60340.396596034356;60427;12979.35103244834;14816.481423150139;14989.625259368602;15272.31274592646;15406.460773872861;11946.223495472766;12034.81947770784;12037.458314375874;12046.397680116135;12063.939680301542;13521.459141634421;13808.895288376905;13833.446662133603;15083.114610673665;15275.930684851954;4485.349624304457;5103.974480127576;5869.383714709979;6004.189434426384;6057.121717350959;861.291441597703;863.7020228021448;867.9088695686988;871.324723339417;892.1569117184329;29012.984545540803;29107.7089229105;29156.708432915824;29564.704352956625;29706.25734356615;995.4475266227169;1046.8063408269495;1067.6690226029955;1076.05844885725;1179.127445690183;14603.415863365346;15077;15118.395264189528;15376.308066137051;15514.456994005155;574691;831195;842924;844272;848219;48838.511614884104;49543.50456495461;49702.75148624306;49709.50290497049;49752.50247497551;1322.3123975532862;1359.1165742267606;1363.3319673359783;1366.583192126411;1379.0760190672274;26206.606900896884;27098.32254193661;27107.30746543169;27424.725752743016;27457.86271068632;43175;43552.78223608862;43555.34666979934;43710.56289437065;43744.78127609342;509246;511700;514265;514376;518548;19649.705254421482;23003.6549451755;23130.306090817303;23399.298021058716;23536.411589710395;33668.326633466706;34614.82692586622;36276.93764169595;38954;39709.60290397175;27623;32479.35041299208;33415;33914.8304258487;34487.82756086203;33148;33325.50011749777;33451.83274083614;33892.15269618279;34071;32459.513107303883;32921.34157316888;33189.83405082959;34072.82963585166;34764.65235347614;24562.263132106065;27412.314692132855;28218.294542635776;31284.37431251408;31430.84284578654;20119.497012574804;21610.13559457604;23283.370164088352;23665.763342367045;24079.879600601886 17:04:38 INFO - PROCESS | 1968 | |2;jquery.html;323.6536905511103;343.8968309507149;370.2096024986651;376.8473768123909;392.45644781976955;18879.905600471997;18990.525236869085;19546.022698865072;20205.696914546294;20405.795942040575;19236;19577;19723.802761972376;20124.798752012477;20171.899140504313;4750.501197374278;5733.770649174038;5910;5956.791512297071;5958.28500579931;6372.553921225516;6574.079628851964;6597.340265973412;6599.307072757363;6602.702878370461;6204.534659900503;6330.240371155468;6430.196225471816;6497.870042599146;6518.771842985497;6357.950938095255;6408.51936104794;6412.39082287182;6412.551121421502;6414.294427612959;5052.393712754456;5315.521603055705;5360.624756267063;5397.136458166714;5405.729713514309;18339.816601833943;23401.531969360516;23471.530569388517;23522.059117635275;23781;2154.2675490333204;2497.2882728422314;2583.19920824545;2583.3412479817716;2590.6114082887716;9465.574049167804;9576.23390128788;9946.403215807075;12547.811782823264;14704.55886323412;1741.738739189119;1790.9791418891236;1792.4891405949243;1796.4700413377955;1802.5763945472804;108668;114014.18765903616;114651.42674286575;115640.42179789048;117977.23034154408;8166.632501537445;9225.400348977295;9625.614975400906;9644.662436814679;9752.658656946973;11607.187496875307;12016.939915300542;12142.635720928387;12212.267263964191;12234;7424.257574242532;7629.580373079423;7644.541327520255;7792.259735325135;7794.844103117905;6598;7922.088959769585;7928.4450088494405;7933.404994625426;8103.351731861437;3748.887533374003;3794.905127371838;3808.086059345737;3866.3427217373114;3871.8838434847;1669.741190115534;1752.8948263104255;1830.0209387977368;1863.1243315641627;1940.640981418414;800.5756948817136;985.6944347252374;1003.4130033930087;1019.5588847274147;1040.2406243442329;19504.219831206592;19548.902255488632;19607.823530587924;19686.606267874562;20073.29743458972;906.8869533985929;969.699393188114;996.3922007575374;1046.1630695443614;1053.489057806963;9771.902280977241;10283.434411107311;10313.74215644615;10534.36793792375;10780.795489759;466764;469544;474952;506342;508303;122012;122200;124416;125191.74808251983;127766;1023.97440063999;1036.99481502592;1094.9926068017444;1095.6658219243047;1096.2819353323587;17574.20916058806;17701.645967080844;17815.52597243525;18001.369952051617;18035.18841652155;52544.737276313375;54174.645633859465;54237.45762542324;55069.724651376484;55287;59536.10695839652;71562.64218678873;72955;73787.89318160339;77746.6112669433;6433.002884552901;7239.710411583477;7862.803429914298;8065.596720163851;8274.089850116363;7368.521046131984;9088.954555227181;11429.714257143638;11715.531378745096;12833.230006199658;10628.946855265674;10762.300450470695;10775.515101820594;11199.720006999563;11285.153613478858;10776.784464310513;10875;10927.508262128064;10978.294434649062;11178.385188814693;11111.888881111085;11222.046126079049;11269.098472122374;11319.151063670422;11334.886651133384;7785.805354866174;7909.564973926259;7963.960180199062;8188.467749596257;8245.95877020611;10034.548445319831;10671.35971841692;10727.249092563447;10881.836772448261;11075.72947545735 17:04:38 INFO - PROCESS | 1968 | |3;mootools.html;1301.4468850738433;1389.854065323141;1391.3113009060514;1412.4632639596953;1414.6251243420493;6470.902936455951;7842.568658723768;8067.193280671937;8327.583620818965;8332.541710205936;8175.91824081759;8232.958835205816;8266.421350505467;8274.089850116497;8275.131111233324;2036.867603605761;2275.351524815424;2316;2319.118734880743;2323.3843031596557;2107.2729908181727;2268.8298377621663;2349.447879748258;2350.1421980976993;2360.150345875492;2298.19563152896;2301.675307938977;2307.3308740465327;2309.4226443389152;2310.6880571122933;2281.9657705134437;2307.688462057626;2308.56137333906;2315.6526521021815;2316.4440534271653;2285.9657105143438;2308.4921317310163;2309.8614083155067;2311.4452531392512;2312.9653055204017;8121.106678265386;8209.548474833882;8211.548364839931;8234.217749313806;8277.875831862528;5010.949890501085;5168.612354073422;5170.043541944752;5197.922031169535;5200.843974680765;3333.7666363354574;3371.4268574342423;3481.3385456763385;3538.9646103538894;3583.140046388848;1020.9081182693519;1182.4206138991833;1206.8595177557213;1229.1088960503653;1237.616338934933;10773.299735517166;10846.349219046804;10849.02358787713;10859.67420977364;10865.3480791152;4380.802863871133;4536.070105628338;4538.909221815611;4619.607333376701;4621.2837010263465;8041.6381262843315;9410.247180225566;9418.952905235567;9425.764355891157;9441.575129119206;5052.671576347526;5129.589632829365;5142.640015198975;5156.561692256125;5162.66442681232;4642.303654451792;4661.86014419568;4695.929561056587;4705.988212534322;4764.737939413296;3245.4644983577446;3778.9433158502643;3785.432185172191;3877;3887.7084218683713;1270.6696258972734;1276.50216415598;1281.2696762845276;1291.8126871603565;1295.8185853980451;722.8084557592242;865.7532603208182;868.9826203475895;923.7483210250149;931.897491275959;5021.719461537285;5484.314460692413;5560.443955604488;5604.85987850307;5748.45389687979;836.3351135847016;885.181207383168;886.2732559301311;889.1153302463961;893.3210759822451;3666.6150054244454;3710.5176327077306;3711.6823527647834;3832.5784163741964;3833.769973801581;9616.807663846685;9719.465429401309;9860.06329398706;9988.550515226832;9998.300118991747;9924.404535727735;9932.00679932001;9935.552900119512;9944.651937182163;9944.900550994542;7265.4550908681;7630.504017238862;7758.9612051939375;7772.222777722295;7775.844483110306;5573.331200255995;5653.406392328746;5774.538036956949;5803.680797556006;5835.854103647443;8140.959295203486;8932.642694292155;9023.233025192932;9087.772805679911;9215.953920230357;9187;9196.08039196075;9275.76810579741;9380.859287110563;9408.52957352116;7054.400375967895;7271.927280727125;7288.198298187302;7303.853922921406;7305.51612451769;7758.3017528422715;7778.261065198797;7778.961105194438;7846.646900889359;7910.5649189295145;7045.471589630695;7077.823054423475;7118.074650295635;7118.715251389886;7148.106486689164;6755.831104222434;6928.411085057826;7031.43748500129;7088.787336379917;7097.290270972861;6191.288001879661;6334.049892516159;6338.841528961629;6346.01636746305;6364.713587888648;4627.444706635225;4746.5490778376;4758.119747846659;4772.596316239207;4877.170880950176;4647.86056418308;4687;4795.448523419712;4937.333459983;4947.628927830355 17:04:38 INFO - PROCESS | 1968 | |4;prototype.html;324.5391544007512;344.4506012909409;346.6204506065858;357.7138289368505;367.8321329777956;4114.938275925856;4204.132198377492;4705.670603057787;5260.053190425722;5273.736313184345;4432.623227025699;5206.609504287184;5217.139316905482;5247.422783493813;5258.737063146847;4127.442795222644;4142.606452387018;4144.544100148981;4162.271602469556;4171.707980441371;3080.4301204277167;3599.94600080999;3783.148791521913;4088.9182216355657;4207.516135644394;4069.2471892699796;4072.312273899227;4103.425520427142;4118.620262212163;4118.691098167635;4099.052949848828;4103.809895130408;4111.465509483778;4119.331670673382;4131.380292956081;2885.567164925257;3407.2504049109402;3567.5362202913716;3599.0102721751464;3604.116991337113;5076.340075790124;5111.568760746997;5116.590672746172;5140.768665410065;5153.768080436389;3975.1056012397266;4522.4573051234065;4766.285057241407;4773.212419950704;4778.354922085508;5248.580113590903;5466.781328746845;5514.821645450065;5525.729082311051;5532.612717109804;3419.5383623210796;3997.6008397061087;3999.140184860269;4000.259951908877;4019.9297361858667;5331.466853314676;5508.834734957958;5658.292713410824;5669.376368599451;5793.420657934173;3333.2000719611624;3859.6684143970288;3917.353636649978;3925;4092.5702801205443;3185.4584720597554;3547.7871327719904;4141.751494910315;4147.958520414757;4169.353750168729;4577;4609.478827522499;4682.672212945129;4712.387389639325;4718.528147185322;5451.400345961941;5518.834434966958;5548.639338442988;5562.471565201299;5583.413741557078;1336.5388940815403;1341.6243451833502;1351.7634413977514;1363.9727205455836;1368.473137841938;909.6132212571431;958.7603099225194;960.8318544254728;971.6502059258803;972.154225823538;4308.849190278388;4911.807325238624;4934.200665487554;4939.456659767423;4960.627952903546;4516;4695.577398034194;5321.93561287744;5406.351237851483;5431.375391829911;4585.862424127215;4742.95257047432;4953.975230123899;4959.677620954626;5042.42012168598;2623.252373073665;2687.2475706802124;2839.957400638992;2842.3462603600874;2872.3680790226117;4508.45898492183;4686.976565117221;5354;5395.70323632204;5407.242985981966;4689.015306785545;4818.855434336975;4952.752362381866;5542.778288868481;5608.270924779753;5501.669899806025;5558.999380111619;5611.298587676541;5631.887362252814;5633.352164501135;4469.614419529956;4656.8137274508645;4726.385569875894;4771.809127634856;5166.793328266827;4448.466184057934;4732.763361831963;4859.975700121477;4981.850544483671;5337.653052551572;5068.82259120929;5080.517350851663;5538.806141785019;5670.914936276042;5778.942210578009;3516.824158792101;3939.3303138467104;4034.890405138493;4069.2471892699946;4113.568075352045;4947.942686996628;5683.5453163747625;5698.6580805151825;5707.571932105026;5717.885642287048;4647.9767601161775;5051.257316225896;5218.9739051304505;5435.8256697321385;5438.592105592017;4862.717665575793;5084.339035925306;5492.80775172867;5494.36814766307;5499.532539734168;4539.228331183773;4980.800767969241;5179.30079439286;5375.489328513785;5400.432954539874;4871.951280487291;5457.181422786613;5468.4531546845;5488.408361575099;5516.503514683698;4215.641670458045;5280.551153152025;5357.678539287655;5380.381256155592;5381.408045114956 17:04:38 INFO - PROCESS | 1968 | |5;yui.html;716.0548076538971;721.9855602887939;765.3800421658453;793.9563324017179;800.9839803203935;14074.929625351871;14892.851071489282;14936.477223297186;14958.626034349147;14973.625659358522;14165.858341416582;14355.425782968668;14369.066010709323;14373.065750726222;14413.207273599948;685.7736946807541;711.9715211391538;712.9679164437587;853.9957300213521;865.6191275838643;719.7085180501905;881.7663319220412;891.5230351761809;892.3664198419131;894.2711689972684;948.1087777489138;948.9051094890525;949.6628696812636;954.141272854433;954.2080073538946;731.8719224135808;760.8250102476406;951.70497145885;953.0326718380849;954.2652157838426;660.201156600514;682.7405585877384;684.8732984397902;690.102866273847;692.757534862799;14309.209704107703;14538;14710.632234194123;14841.777373339408;14857.257137143208;12944.482220711161;12947.611571652866;12970.546030888874;13296.933515332457;13336.866631333756;12711.491540338375;12727.618171454871;12766.361681915867;12925.741485170243;13047.739045219138;4368.467266260218;4418.116376724668;4454.443194600675;4483.14820184164;4488.618467430272;104836.47581762119;107168.4641576795;108364.37453438283;109872.90127098786;110652;9909.851352229722;10302.484875756183;10470.738231544272;10511;10729.78540429187;19241.230350786092;19264;19762.407127786188;19833.504162395766;19858.60282794336;7405.555666660018;7619.238076192337;7636.198199189005;7752.496087754278;7757.340626046736;4227.175700738327;4273;4290.914181716348;4302.591253830881;4400.031992961543;3291.2101095736853;3298.5546951161764;3301.669833016679;3344.3144155448076;3391.3217356528794;614.757170917494;622.7789134857061;626.1234272019119;631.8799428108645;642.540583482812;515.4664921805913;516.19215927074;516.6770768269831;521.8356217791384;540.1096163428002;8504.064552899175;8640.956795215983;8801.25189358899;8899.110088991178;8945;586.8972929737364;612.0390986151788;627.5230824573266;632.1024145713099;641.8010416770722;4195.16096780639;4210.85262015828;4298.613124818781;4365.890852728708;4453.287474004143;98739.50630246947;100829.49585252027;101374.49312753389;101467;103393;90600.5469972646;91454;91466.54266728624;92227;93120;79611;79688.60155699184;80967;81961.77057344145;83098;8151.199660449809;8390.370722195737;8401.369897257608;8518.599824379311;8613.569321534023;45398;49831.252531212784;52208.73895630497;53691.73154134361;54393.72803135959;60256.69871650614;62070.689646553285;66971;69191;69334;12954.805677915027;12990;13084.80372794428;13440.663983400102;13525.72948541004;14502.202378869264;14569.198694071503;14588.416463341347;14754.557363279118;14982.475613353481;13349.733005340033;13573.660658483617;13705.451781928612;13925.164490130624;14242.287885605885;13558.86441135576;13688.31558422055;13741.793873091712;13926.373313201133;14236.359363828446;12932.547360842702;13137.34313284351;13293.53472628492;13699.109557878706;13720.313984300945;10043.89956100459;10922.453877306261;11151.498182581641;11241.213115082193;11483.540658373906;8779;9419.717408477756;10868.836967445653;10871.836922446011;10908.454577271263 17:04:38 INFO - PROCESS | 1968 | __end_tp_report 17:04:38 INFO - PROCESS | 1968 | __start_cc_report 17:04:38 INFO - PROCESS | 1968 | _x_x_mozilla_cycle_collect,1771 17:04:38 INFO - PROCESS | 1968 | __end_cc_report 17:04:38 INFO - PROCESS | 1968 | __startTimestamp1482973478740__endTimestamp 17:04:38 INFO - PROCESS | 1968 | 17:04:38 INFO - PROCESS | 1968 | ------- Summary: start ------- 17:04:38 INFO - PROCESS | 1968 | Number of tests: 6 17:04:38 INFO - PROCESS | 1968 | 17:04:38 INFO - PROCESS | 1968 | [#0] dojo.html Cycles:165 Average:5736.49 Median:6032.37 stddev:645.22 (10.7%) stddev-sans-first:618.54 17:04:38 INFO - PROCESS | 1968 | Values: 3312.2 3316.9 3335.8 3348.5 3384.6 5579.6 5634.4 5683.9 5756.5 5835.9 5670.6 5716.8 5729.0 5732.1 5754.8 5139.6 5171.3 5187.3 5212.3 5244.5 5199.2 5273.2 5286.1 5294.1 5303.7 5263.5 5264.6 5265.8 5279.0 5280.1 5249.5 5265.8 5269.5 5272.9 5284.9 5024.7 5179.8 5230.3 5238.0 5244.4 5696.2 5756.4 5849.9 5895.6 5896.6 5284.8 6104.5 6216.2 6242.3 6243.9 6146.0 6156.0 6171.1 6187.8 6190.7 5865.6 5869.5 5870.4 5886.6 5911.1 6230.0 6243.1 6254.2 6260.5 6273.5 5996.2 6049.4 6057.3 6119.2 6129.2 5084.5 6192.1 6214.0 6214.6 6215.8 6081.8 6192.6 6212.0 6221.4 6253.7 6115.7 6116.7 6126.9 6146.2 6243.1 4922.6 4936.5 4946.0 4988.8 4999.8 3742.8 4415.7 4418.4 4424.7 4434.8 5611.7 5837.6 5844.0 5873.4 5900.7 6135.0 6148.9 6176.5 6191.8 6244.4 5852.0 5912.3 5912.5 5922.2 5937.2 5276.0 5295.0 5307.1 5324.3 5372.1 6222.6 6237.1 6270.7 6276.8 6292.7 6123.5 6124.6 6154.8 6171.2 6178.2 6267.5 6291.8 6300.7 6301.7 6348.0 6214.4 6226.3 6230.0 6255.7 6257.9 6118.0 6162.6 6195.7 6203.7 6209.5 6102.2 6115.6 6136.4 6174.0 6174.9 6010.5 6057.5 6151.4 6203.8 6221.5 5886.0 5929.7 6072.6 6083.1 6090.1 4980.8 6106.9 6113.7 6159.5 6161.8 6032.4 6035.3 6043.6 6056.2 6079.8 17:04:38 INFO - PROCESS | 1968 | 17:04:38 INFO - PROCESS | 1968 | [#1] ext.html Cycles:180 Average:70522.80 Median:34071.91 stddev:165604.10 (486.0%) stddev-sans-first:165985.07 17:04:38 INFO - PROCESS | 1968 | Values: 441.6 445.9 472.5 496.3 514.3 29956.6 38995.8 40623.8 40650.4 42358.9 41395.0 41706.6 41728.0 41796.8 41859.6 9437.6 9613.9 9632.7 9646.7 9649.9 7044.9 8654.9 8679.5 8783.4 8785.6 11328.3 11621.3 11640.9 11804.1 11808.5 11461.7 11476.9 11583.4 11589.6 11598.8 8440.0 8668.3 11383.9 11450.7 11490.9 38264.0 38843.0 38958.2 40931.8 41047.0 3934.6 3939.0 3944.6 3962.6 4088.0 28944.4 29252.4 30082.4 30115.7 30169.8 3128.7 3161.3 3240.0 3303.6 3348.6 483575.0 488047.0 488234.0 489083.0 489977.0 55818.7 55892.2 55987.2 56436.7 56445.4 60003.4 60084.4 60145.4 60340.4 60427.0 12979.4 14816.5 14989.6 15272.3 15406.5 11946.2 12034.8 12037.5 12046.4 12063.9 13521.5 13808.9 13833.4 15083.1 15275.9 4485.3 5104.0 5869.4 6004.2 6057.1 861.3 863.7 867.9 871.3 892.2 29013.0 29107.7 29156.7 29564.7 29706.3 995.4 1046.8 1067.7 1076.1 1179.1 14603.4 15077.0 15118.4 15376.3 15514.5 574691.0 831195.0 842924.0 844272.0 848219.0 48838.5 49543.5 49702.8 49709.5 49752.5 1322.3 1359.1 1363.3 1366.6 1379.1 26206.6 27098.3 27107.3 27424.7 27457.9 43175.0 43552.8 43555.3 43710.6 43744.8 509246.0 511700.0 514265.0 514376.0 518548.0 19649.7 23003.7 23130.3 23399.3 23536.4 33668.3 34614.8 36276.9 38954.0 39709.6 27623.0 32479.4 33415.0 33914.8 34487.8 33148.0 33325.5 33451.8 33892.2 34071.0 32459.5 32921.3 33189.8 34072.8 34764.7 24562.3 27412.3 28218.3 31284.4 31430.8 20119.5 21610.1 23283.4 23665.8 24079.9 17:04:38 INFO - PROCESS | 1968 | 17:04:38 INFO - PROCESS | 1968 | [#2] jquery.html Cycles:180 Average:31117.03 Median:2540.24 stddev:82037.10 (3229.5%) stddev-sans-first:82234.66 17:04:38 INFO - PROCESS | 1968 | Values: 323.7 343.9 370.2 376.8 392.5 18879.9 18990.5 19546.0 20205.7 20405.8 19236.0 19577.0 19723.8 20124.8 20171.9 4750.5 5733.8 5910.0 5956.8 5958.3 6372.6 6574.1 6597.3 6599.3 6602.7 6204.5 6330.2 6430.2 6497.9 6518.8 6358.0 6408.5 6412.4 6412.6 6414.3 5052.4 5315.5 5360.6 5397.1 5405.7 18339.8 23401.5 23471.5 23522.1 23781.0 2154.3 2497.3 2583.2 2583.3 2590.6 9465.6 9576.2 9946.4 12547.8 14704.6 1741.7 1791.0 1792.5 1796.5 1802.6 108668.0 114014.2 114651.4 115640.4 117977.2 8166.6 9225.4 9625.6 9644.7 9752.7 11607.2 12016.9 12142.6 12212.3 12234.0 7424.3 7629.6 7644.5 7792.3 7794.8 6598.0 7922.1 7928.4 7933.4 8103.4 3748.9 3794.9 3808.1 3866.3 3871.9 1669.7 1752.9 1830.0 1863.1 1940.6 800.6 985.7 1003.4 1019.6 1040.2 19504.2 19548.9 19607.8 19686.6 20073.3 906.9 969.7 996.4 1046.2 1053.5 9771.9 10283.4 10313.7 10534.4 10780.8 466764.0 469544.0 474952.0 506342.0 508303.0 122012.0 122200.0 124416.0 125191.7 127766.0 1024.0 1037.0 1095.0 1095.7 1096.3 17574.2 17701.6 17815.5 18001.4 18035.2 52544.7 54174.6 54237.5 55069.7 55287.0 59536.1 71562.6 72955.0 73787.9 77746.6 6433.0 7239.7 7862.8 8065.6 8274.1 7368.5 9089.0 11429.7 11715.5 12833.2 10628.9 10762.3 10775.5 11199.7 11285.2 10776.8 10875.0 10927.5 10978.3 11178.4 11111.9 11222.0 11269.1 11319.2 11334.9 7785.8 7909.6 7964.0 8188.5 8246.0 10034.5 10671.4 10727.2 10881.8 11075.7 17:04:38 INFO - PROCESS | 1968 | 17:04:38 INFO - PROCESS | 1968 | [#3] mootools.html Cycles:180 Average:5376.14 Median:5342.58 stddev:2925.99 (54.8%) stddev-sans-first:2918.17 17:04:38 INFO - PROCESS | 1968 | Values: 1301.4 1389.9 1391.3 1412.5 1414.6 6470.9 7842.6 8067.2 8327.6 8332.5 8175.9 8233.0 8266.4 8274.1 8275.1 2036.9 2275.4 2316.0 2319.1 2323.4 2107.3 2268.8 2349.4 2350.1 2360.2 2298.2 2301.7 2307.3 2309.4 2310.7 2282.0 2307.7 2308.6 2315.7 2316.4 2286.0 2308.5 2309.9 2311.4 2313.0 8121.1 8209.5 8211.5 8234.2 8277.9 5010.9 5168.6 5170.0 5197.9 5200.8 3333.8 3371.4 3481.3 3539.0 3583.1 1020.9 1182.4 1206.9 1229.1 1237.6 10773.3 10846.3 10849.0 10859.7 10865.3 4380.8 4536.1 4538.9 4619.6 4621.3 8041.6 9410.2 9419.0 9425.8 9441.6 5052.7 5129.6 5142.6 5156.6 5162.7 4642.3 4661.9 4695.9 4706.0 4764.7 3245.5 3778.9 3785.4 3877.0 3887.7 1270.7 1276.5 1281.3 1291.8 1295.8 722.8 865.8 869.0 923.7 931.9 5021.7 5484.3 5560.4 5604.9 5748.5 836.3 885.2 886.3 889.1 893.3 3666.6 3710.5 3711.7 3832.6 3833.8 9616.8 9719.5 9860.1 9988.6 9998.3 9924.4 9932.0 9935.6 9944.7 9944.9 7265.5 7630.5 7759.0 7772.2 7775.8 5573.3 5653.4 5774.5 5803.7 5835.9 8141.0 8932.6 9023.2 9087.8 9216.0 9187.0 9196.1 9275.8 9380.9 9408.5 7054.4 7271.9 7288.2 7303.9 7305.5 7758.3 7778.3 7779.0 7846.6 7910.6 7045.5 7077.8 7118.1 7118.7 7148.1 6755.8 6928.4 7031.4 7088.8 7097.3 6191.3 6334.0 6338.8 6346.0 6364.7 4627.4 4746.5 4758.1 4772.6 4877.2 4647.9 4687.0 4795.4 4937.3 4947.6 17:04:38 INFO - PROCESS | 1968 | 17:04:38 INFO - PROCESS | 1968 | [#4] prototype.html Cycles:180 Average:4397.33 Median:4798.61 stddev:1290.77 (26.9%) stddev-sans-first:1257.68 17:04:38 INFO - PROCESS | 1968 | Values: 324.5 344.5 346.6 357.7 367.8 4114.9 4204.1 4705.7 5260.1 5273.7 4432.6 5206.6 5217.1 5247.4 5258.7 4127.4 4142.6 4144.5 4162.3 4171.7 3080.4 3599.9 3783.1 4088.9 4207.5 4069.2 4072.3 4103.4 4118.6 4118.7 4099.1 4103.8 4111.5 4119.3 4131.4 2885.6 3407.3 3567.5 3599.0 3604.1 5076.3 5111.6 5116.6 5140.8 5153.8 3975.1 4522.5 4766.3 4773.2 4778.4 5248.6 5466.8 5514.8 5525.7 5532.6 3419.5 3997.6 3999.1 4000.3 4019.9 5331.5 5508.8 5658.3 5669.4 5793.4 3333.2 3859.7 3917.4 3925.0 4092.6 3185.5 3547.8 4141.8 4148.0 4169.4 4577.0 4609.5 4682.7 4712.4 4718.5 5451.4 5518.8 5548.6 5562.5 5583.4 1336.5 1341.6 1351.8 1364.0 1368.5 909.6 958.8 960.8 971.7 972.2 4308.8 4911.8 4934.2 4939.5 4960.6 4516.0 4695.6 5321.9 5406.4 5431.4 4585.9 4743.0 4954.0 4959.7 5042.4 2623.3 2687.2 2840.0 2842.3 2872.4 4508.5 4687.0 5354.0 5395.7 5407.2 4689.0 4818.9 4952.8 5542.8 5608.3 5501.7 5559.0 5611.3 5631.9 5633.4 4469.6 4656.8 4726.4 4771.8 5166.8 4448.5 4732.8 4860.0 4981.9 5337.7 5068.8 5080.5 5538.8 5670.9 5778.9 3516.8 3939.3 4034.9 4069.2 4113.6 4947.9 5683.5 5698.7 5707.6 5717.9 4648.0 5051.3 5219.0 5435.8 5438.6 4862.7 5084.3 5492.8 5494.4 5499.5 4539.2 4980.8 5179.3 5375.5 5400.4 4872.0 5457.2 5468.5 5488.4 5516.5 4215.6 5280.6 5357.7 5380.4 5381.4 17:04:38 INFO - PROCESS | 1968 | 17:04:38 INFO - PROCESS | 1968 | [#5] yui.html Cycles:180 Average:20453.06 Median:4409.07 stddev:29772.64 (675.3%) stddev-sans-first:29819.28 17:04:38 INFO - PROCESS | 1968 | Values: 716.1 722.0 765.4 794.0 801.0 14074.9 14892.9 14936.5 14958.6 14973.6 14165.9 14355.4 14369.1 14373.1 14413.2 685.8 712.0 713.0 854.0 865.6 719.7 881.8 891.5 892.4 894.3 948.1 948.9 949.7 954.1 954.2 731.9 760.8 951.7 953.0 954.3 660.2 682.7 684.9 690.1 692.8 14309.2 14538.0 14710.6 14841.8 14857.3 12944.5 12947.6 12970.5 13296.9 13336.9 12711.5 12727.6 12766.4 12925.7 13047.7 4368.5 4418.1 4454.4 4483.1 4488.6 104836.5 107168.5 108364.4 109872.9 110652.0 9909.9 10302.5 10470.7 10511.0 10729.8 19241.2 19264.0 19762.4 19833.5 19858.6 7405.6 7619.2 7636.2 7752.5 7757.3 4227.2 4273.0 4290.9 4302.6 4400.0 3291.2 3298.6 3301.7 3344.3 3391.3 614.8 622.8 626.1 631.9 642.5 515.5 516.2 516.7 521.8 540.1 8504.1 8641.0 8801.3 8899.1 8945.0 586.9 612.0 627.5 632.1 641.8 4195.2 4210.9 4298.6 4365.9 4453.3 98739.5 100829.5 101374.5 101467.0 103393.0 90600.5 91454.0 91466.5 92227.0 93120.0 79611.0 79688.6 80967.0 81961.8 83098.0 8151.2 8390.4 8401.4 8518.6 8613.6 45398.0 49831.3 52208.7 53691.7 54393.7 60256.7 62070.7 66971.0 69191.0 69334.0 12954.8 12990.0 13084.8 13440.7 13525.7 14502.2 14569.2 14588.4 14754.6 14982.5 13349.7 13573.7 13705.5 13925.2 14242.3 13558.9 13688.3 13741.8 13926.4 14236.4 12932.5 13137.3 13293.5 13699.1 13720.3 10043.9 10922.5 11151.5 11241.2 11483.5 8779.0 9419.7 10868.8 10871.8 10908.5 17:04:38 INFO - PROCESS | 1968 | -------- Summary: end -------- 17:04:38 INFO - PROCESS | 1968 | 17:04:39 INFO - TEST-INFO | 1968: exit 0 17:04:39 INFO - TEST-OK | dromaeo_css | took 1120031ms 17:04:39 INFO - TEST-START | kraken 17:04:39 INFO - operating with platform_type : mac_ 17:04:39 INFO - Initialising browser for kraken test... 17:04:39 INFO - TEST-INFO | started process 1976 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/T/tmpALWMvx/profile http://localhost:49321/getInfo.html) 17:04:39 INFO - PROCESS | 1976 | 1482973479925 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 17:04:41 INFO - PROCESS | 1976 | __metrics Screen width/height:1600/1200 17:04:41 INFO - PROCESS | 1976 | colorDepth:24 17:04:41 INFO - PROCESS | 1976 | Browser inner width/height: 1024/689 17:04:41 INFO - PROCESS | 1976 | __metrics 17:04:41 INFO - PROCESS | 1976 | 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" 17:04:41 INFO - PROCESS | 1976 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 17:04:42 INFO - TEST-INFO | 1976: exit 0 17:04:42 INFO - Browser initialized. 17:04:42 INFO - Running cycle 1/1 for kraken test... 17:04:42 INFO - TEST-INFO | started process 1977 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/T/tmpALWMvx/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 17:04:54 INFO - PROCESS | 1977 | RSS: Main: 232611840 17:04:54 INFO - PROCESS | 1977 | 17:04:57 INFO - PROCESS | 1977 | Cycle 1(1): loaded http://localhost:49321/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49321/tests/kraken/driver.html?testName=audio-beat-detection) 17:04:58 INFO - PROCESS | 1977 | RSS: Main: 286277632 17:04:58 INFO - PROCESS | 1977 | 17:05:01 INFO - PROCESS | 1977 | Cycle 1(1): loaded http://localhost:49321/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49321/tests/kraken/driver.html?testName=audio-dft) 17:05:01 INFO - PROCESS | 1977 | RSS: Main: 286793728 17:05:01 INFO - PROCESS | 1977 | 17:05:05 INFO - PROCESS | 1977 | Cycle 1(1): loaded http://localhost:49321/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49321/tests/kraken/driver.html?testName=audio-fft) 17:05:05 INFO - PROCESS | 1977 | RSS: Main: 378470400 17:05:05 INFO - PROCESS | 1977 | 17:05:07 INFO - PROCESS | 1977 | Cycle 1(1): loaded http://localhost:49321/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49321/tests/kraken/driver.html?testName=audio-oscillator) 17:05:08 INFO - PROCESS | 1977 | RSS: Main: 282243072 17:05:08 INFO - PROCESS | 1977 | 17:05:10 INFO - PROCESS | 1977 | Cycle 1(1): loaded http://localhost:49321/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49321/tests/kraken/driver.html?testName=imaging-gaussian-blur) 17:05:11 INFO - PROCESS | 1977 | RSS: Main: 285876224 17:05:11 INFO - PROCESS | 1977 | 17:05:13 INFO - PROCESS | 1977 | Cycle 1(1): loaded http://localhost:49321/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49321/tests/kraken/driver.html?testName=imaging-darkroom) 17:05:14 INFO - PROCESS | 1977 | RSS: Main: 328519680 17:05:14 INFO - PROCESS | 1977 | 17:05:17 INFO - PROCESS | 1977 | Cycle 1(1): loaded http://localhost:49321/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49321/tests/kraken/driver.html?testName=imaging-desaturate) 17:05:17 INFO - PROCESS | 1977 | RSS: Main: 328749056 17:05:17 INFO - PROCESS | 1977 | 17:05:20 INFO - PROCESS | 1977 | Cycle 1(1): loaded http://localhost:49321/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49321/tests/kraken/driver.html?testName=json-parse-financial) 17:05:21 INFO - PROCESS | 1977 | RSS: Main: 329084928 17:05:21 INFO - PROCESS | 1977 | 17:05:22 INFO - PROCESS | 1977 | Cycle 1(1): loaded http://localhost:49321/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49321/tests/kraken/driver.html?testName=json-stringify-tinderbox) 17:05:23 INFO - PROCESS | 1977 | RSS: Main: 303939584 17:05:23 INFO - PROCESS | 1977 | 17:05:25 INFO - PROCESS | 1977 | Cycle 1(1): loaded http://localhost:49321/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49321/tests/kraken/driver.html?testName=stanford-crypto-aes) 17:05:26 INFO - PROCESS | 1977 | RSS: Main: 308600832 17:05:26 INFO - PROCESS | 1977 | 17:05:28 INFO - PROCESS | 1977 | Cycle 1(1): loaded http://localhost:49321/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49321/tests/kraken/driver.html?testName=stanford-crypto-ccm) 17:05:29 INFO - PROCESS | 1977 | RSS: Main: 315326464 17:05:29 INFO - PROCESS | 1977 | 17:05:32 INFO - PROCESS | 1977 | Cycle 1(1): loaded http://localhost:49321/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49321/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 17:05:32 INFO - PROCESS | 1977 | RSS: Main: 326197248 17:05:32 INFO - PROCESS | 1977 | 17:05:35 INFO - PROCESS | 1977 | Cycle 1(1): loaded http://localhost:49321/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49321/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 17:05:36 INFO - PROCESS | 1977 | RSS: Main: 320077824 17:05:36 INFO - PROCESS | 1977 | 17:05:38 INFO - PROCESS | 1977 | Cycle 1(1): loaded http://localhost:49321/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49321/tests/kraken/driver.html?testName=ai-astar) 17:05:38 INFO - PROCESS | 1977 | RSS: Main: 364212224 17:05:38 INFO - PROCESS | 1977 | 17:05:38 INFO - PROCESS | 1977 | __start_tp_report 17:05:38 INFO - PROCESS | 1977 | _x_x_mozilla_page_load 17:05:38 INFO - PROCESS | 1977 | _x_x_mozilla_page_load_details 17:05:38 INFO - PROCESS | 1977 | |i|pagename|runs| 17:05:38 INFO - PROCESS | 1977 | |0;ai-astar;114;123;169;152;115;114;114;115;113;113 17:05:38 INFO - PROCESS | 1977 | |1;audio-beat-detection;137;95;109;171;142;114;110;110;127;105 17:05:38 INFO - PROCESS | 1977 | |2;audio-dft;187;182;185;184;185;182;183;186;184;186 17:05:38 INFO - PROCESS | 1977 | |3;audio-fft;80;59;59;89;73;75;81;81;68;76 17:05:38 INFO - PROCESS | 1977 | |4;audio-oscillator;88;69;86;71;69;68;84;69;69;85 17:05:38 INFO - PROCESS | 1977 | |5;imaging-gaussian-blur;91;87;89;88;90;87;91;87;88;85 17:05:38 INFO - PROCESS | 1977 | |6;imaging-darkroom;95;96;96;96;96;96;96;96;94;95 17:05:38 INFO - PROCESS | 1977 | |7;imaging-desaturate;87;84;84;85;84;88;84;87;85;85 17:05:38 INFO - PROCESS | 1977 | |8;json-parse-financial;57;55;54;56;56;58;57;56;56;57 17:05:38 INFO - PROCESS | 1977 | |9;json-stringify-tinderbox;72;70;65;76;72;71;70;72;71;72 17:05:38 INFO - PROCESS | 1977 | |10;stanford-crypto-aes;87;92;86;102;106;105;89;89;93;92 17:05:38 INFO - PROCESS | 1977 | |11;stanford-crypto-ccm;149;149;140;141;140;143;159;155;187;161 17:05:38 INFO - PROCESS | 1977 | |12;stanford-crypto-pbkdf2;174;173;179;173;174;171;173;173;175;174 17:05:38 INFO - PROCESS | 1977 | |13;stanford-crypto-sha256-iterative;64;66;67;67;67;71;66;66;67;65 17:05:38 INFO - PROCESS | 1977 | __end_tp_report 17:05:38 INFO - PROCESS | 1977 | __start_cc_report 17:05:38 INFO - PROCESS | 1977 | _x_x_mozilla_cycle_collect,1995 17:05:38 INFO - PROCESS | 1977 | __end_cc_report 17:05:38 INFO - PROCESS | 1977 | __startTimestamp1482973538304__endTimestamp 17:05:38 INFO - PROCESS | 1977 | 17:05:38 INFO - PROCESS | 1977 | ------- Summary: start ------- 17:05:38 INFO - PROCESS | 1977 | Number of tests: 14 17:05:38 INFO - PROCESS | 1977 | 17:05:38 INFO - PROCESS | 1977 | [#0] ai-astar Cycles:10 Average:124.20 Median:115.00 stddev:19.76 (17.2%) stddev-sans-first:20.61 17:05:38 INFO - PROCESS | 1977 | Values: 114.0 123.0 169.0 152.0 115.0 114.0 114.0 115.0 113.0 113.0 17:05:38 INFO - PROCESS | 1977 | 17:05:38 INFO - PROCESS | 1977 | [#1] audio-beat-detection Cycles:10 Average:122.00 Median:132.00 stddev:22.58 (17.1%) stddev-sans-first:23.29 17:05:38 INFO - PROCESS | 1977 | Values: 137.0 95.0 109.0 171.0 142.0 114.0 110.0 110.0 127.0 105.0 17:05:38 INFO - PROCESS | 1977 | 17:05:38 INFO - PROCESS | 1977 | [#2] audio-dft Cycles:10 Average:184.40 Median:185.00 stddev:1.71 (0.9%) stddev-sans-first:1.54 17:05:38 INFO - PROCESS | 1977 | Values: 187.0 182.0 185.0 184.0 185.0 182.0 183.0 186.0 184.0 186.0 17:05:38 INFO - PROCESS | 1977 | 17:05:38 INFO - PROCESS | 1977 | [#3] audio-fft Cycles:10 Average:74.10 Median:78.00 stddev:9.72 (12.5%) stddev-sans-first:10.08 17:05:38 INFO - PROCESS | 1977 | Values: 80.0 59.0 59.0 89.0 73.0 75.0 81.0 81.0 68.0 76.0 17:05:38 INFO - PROCESS | 1977 | 17:05:38 INFO - PROCESS | 1977 | [#4] audio-oscillator Cycles:10 Average:75.80 Median:77.50 stddev:8.65 (11.2%) stddev-sans-first:7.97 17:05:38 INFO - PROCESS | 1977 | Values: 88.0 69.0 86.0 71.0 69.0 68.0 84.0 69.0 69.0 85.0 17:05:38 INFO - PROCESS | 1977 | 17:05:38 INFO - PROCESS | 1977 | [#5] imaging-gaussian-blur Cycles:10 Average:88.30 Median:88.50 stddev:1.95 (2.2%) stddev-sans-first:1.80 17:05:38 INFO - PROCESS | 1977 | Values: 91.0 87.0 89.0 88.0 90.0 87.0 91.0 87.0 88.0 85.0 17:05:38 INFO - PROCESS | 1977 | 17:05:38 INFO - PROCESS | 1977 | [#6] imaging-darkroom Cycles:10 Average:95.60 Median:96.00 stddev:0.70 (0.7%) stddev-sans-first:0.71 17:05:38 INFO - PROCESS | 1977 | Values: 95.0 96.0 96.0 96.0 96.0 96.0 96.0 96.0 94.0 95.0 17:05:38 INFO - PROCESS | 1977 | 17:05:38 INFO - PROCESS | 1977 | [#7] imaging-desaturate Cycles:10 Average:85.30 Median:85.00 stddev:1.49 (1.8%) stddev-sans-first:1.45 17:05:38 INFO - PROCESS | 1977 | Values: 87.0 84.0 84.0 85.0 84.0 88.0 84.0 87.0 85.0 85.0 17:05:38 INFO - PROCESS | 1977 | 17:05:38 INFO - PROCESS | 1977 | [#8] json-parse-financial Cycles:10 Average:56.20 Median:56.50 stddev:1.14 (2.0%) stddev-sans-first:1.17 17:05:38 INFO - PROCESS | 1977 | Values: 57.0 55.0 54.0 56.0 56.0 58.0 57.0 56.0 56.0 57.0 17:05:38 INFO - PROCESS | 1977 | 17:05:38 INFO - PROCESS | 1977 | [#9] json-stringify-tinderbox Cycles:10 Average:71.10 Median:72.00 stddev:2.73 (3.8%) stddev-sans-first:2.87 17:05:38 INFO - PROCESS | 1977 | Values: 72.0 70.0 65.0 76.0 72.0 71.0 70.0 72.0 71.0 72.0 17:05:38 INFO - PROCESS | 1977 | 17:05:38 INFO - PROCESS | 1977 | [#10] stanford-crypto-aes Cycles:10 Average:94.10 Median:89.00 stddev:7.46 (8.4%) stddev-sans-first:7.46 17:05:38 INFO - PROCESS | 1977 | Values: 87.0 92.0 86.0 102.0 106.0 105.0 89.0 89.0 93.0 92.0 17:05:38 INFO - PROCESS | 1977 | 17:05:38 INFO - PROCESS | 1977 | [#11] stanford-crypto-ccm Cycles:10 Average:152.40 Median:152.00 stddev:14.42 (9.5%) stddev-sans-first:15.24 17:05:38 INFO - PROCESS | 1977 | Values: 149.0 149.0 140.0 141.0 140.0 143.0 159.0 155.0 187.0 161.0 17:05:38 INFO - PROCESS | 1977 | 17:05:38 INFO - PROCESS | 1977 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:173.90 Median:174.00 stddev:2.08 (1.2%) stddev-sans-first:2.20 17:05:38 INFO - PROCESS | 1977 | Values: 174.0 173.0 179.0 173.0 174.0 171.0 173.0 173.0 175.0 174.0 17:05:38 INFO - PROCESS | 1977 | 17:05:38 INFO - PROCESS | 1977 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.60 Median:67.00 stddev:1.84 (2.7%) stddev-sans-first:1.69 17:05:38 INFO - PROCESS | 1977 | Values: 64.0 66.0 67.0 67.0 67.0 71.0 66.0 66.0 67.0 65.0 17:05:38 INFO - PROCESS | 1977 | -------- Summary: end -------- 17:05:38 INFO - PROCESS | 1977 | 17:05:39 INFO - TEST-INFO | 1977: exit 0 17:05:39 INFO - TEST-OK | kraken | took 59777ms 17:05:39 INFO - SUITE-END | took 1179s 17:05:39 INFO - Completed test suite (00:19:40) 17:05:39 INFO - javascript benchmark 17:05:39 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3312.188513814116, 3316.9336613267737, 3335.7831740936854, 3348.5312056312105, 3384.593848738152, 5579.609427340078, 5634.408387119355, 5683.886322273552, 5756.539476841848, 5835.854103647405, 5670.631408958425, 5716.799912003081, 5729.026065568853, 5732.111522713975, 5754.769809207637, 5139.640225184238, 5171.250168725533, 5187.325647665798, 5212.322398088243, 5244.475552444763, 5199.168133098705, 5273.1826566882, 5286.07493688603, 5294.100002999499, 5303.681779093247, 5263.526282634562, 5264.631475796696, 5265.7893684252585, 5279.0, 5280.102382594968, 5249.475052494739, 5265.7893684252585, 5269.473052694738, 5272.92090618641, 5284.894302113936, 5024.723640199788, 5179.792808287664, 5230.267762513213, 5238.004779091961, 5244.39689435716, 5696.231008813798, 5756.424357564252, 5849.941500585025, 5895.64626122434, 5896.616719913192, 5284.81503147388, 6104.450599446027, 6216.160818289518, 6242.282137554149, 6243.906341404882, 6145.9692701536205, 6155.969220153916, 6171.105189747462, 6187.8143655689955, 6190.721417536221, 5865.618734782311, 5869.471747542656, 5870.383609721003, 5886.558508111909, 5911.142884281798, 6229.968850155811, 6243.094751261042, 6254.155688981929, 6260.46786023184, 6273.498120150377, 5996.16053752475, 6049.425304596057, 6057.273127224761, 6119.197913140417, 6129.233845769279, 5084.542391184811, 6192.071189321638, 6213.96893015532, 6214.564980451325, 6215.782447614311, 6081.817545473555, 6192.566520343533, 6212.0, 6221.377862213742, 6253.749850005949, 6115.694215289218, 6116.66358350295, 6126.908096378558, 6146.200993870858, 6243.0635404688755, 4922.606191504671, 4936.506349365035, 4945.950540494549, 4988.825391111366, 4999.75001249936, 3742.7567208131386, 4415.735055896656, 4418.359337896051, 4424.6902716810155, 4434.84478043267, 5611.719414029364, 5837.620554663933, 5844.035734103825, 5873.383294754075, 5900.675462849498, 6135.04893764628, 6148.923938310778, 6176.536759743038, 6191.752329906843, 6244.438000579969, 5852.005159122959, 5912.320083190402, 5912.46787789101, 5922.200502932135, 5937.228160339128, 5276.023935571855, 5295.046891559557, 5307.071262529119, 5324.254604355393, 5372.140457526777, 6222.62664240147, 6237.09562113491, 6270.717817698123, 6276.811695649138, 6292.748290068346, 6123.526657168292, 6124.594542075475, 6154.753809847556, 6171.228596425447, 6178.196834411498, 6267.46726528232, 6291.779787707408, 6300.716467759054, 6301.747930082929, 6348.047792831112, 6214.40963108504, 6226.346233645583, 6230.034344676583, 6255.749770009149, 6257.906131408124, 6117.979653188475, 6162.630242185483, 6195.659238741912, 6203.720832562455, 6209.5032397409, 6102.156009074812, 6115.571909966259, 6136.447719705248, 6173.969130154321, 6174.876502470015, 6010.519158467399, 6057.515398768, 6151.384861513813, 6203.844903877259, 6221.5333849960525, 5886.028805247086, 5929.673867937305, 6072.635641861502, 6083.148359229623, 6090.056041313604, 4980.825671101639, 6106.938930610637, 6113.658745237643, 6159.476444502088, 6161.845953851011, 6032.366601506778, 6035.305939816802, 6043.637381757108, 6056.152138700673, 6079.756809727738], "value": 5695.022013009536, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [441.6422697614932, 445.9487158976719, 472.5203918023205, 496.2878269683005, 514.2723046888652, 29956.550651740214, 38995.80502097489, 40623.79688101563, 40650.39024414636, 42358.94102647436, 41395.0, 41706.58293417065, 41728.0, 41796.791016044874, 41859.58140418595, 9437.622495100188, 9613.903860961405, 9632.711018669415, 9646.710598682052, 9649.855252171188, 7044.859102817941, 8654.87017694735, 8679.479231246114, 8783.429077109999, 8785.648574057062, 11328.32030078198, 11621.302721836675, 11640.941795291012, 11804.055675545937, 11808.527658893678, 11461.713457163554, 11476.942615286871, 11583.420828958602, 11589.594364197214, 11598.826017609743, 8439.957800211021, 8668.349873759493, 11383.943080284545, 11450.656480305604, 11490.94254528722, 38264.0, 38843.0, 38958.22083558313, 40931.795341023404, 41047.0, 3934.626210509997, 3938.9664574724206, 3944.5660977292764, 3962.583928687504, 4087.998440382096, 28944.42111157786, 29252.414951700848, 30082.398352033055, 30115.69884301151, 30169.849150754108, 3128.749700023993, 3161.2611223571243, 3239.951400728967, 3303.6035675718826, 3348.564686590752, 483575.0, 488046.99999999645, 488234.0, 489083.0, 489977.0, 55818.74356333732, 55892.161617575766, 55987.16019259714, 56436.71781641065, 56445.43554564402, 60003.39996600065, 60084.39915600875, 60145.39854601398, 60340.396596034356, 60427.0, 12979.35103244834, 14816.481423150139, 14989.625259368602, 15272.31274592646, 15406.460773872861, 11946.223495472766, 12034.81947770784, 12037.458314375874, 12046.397680116135, 12063.939680301542, 13521.459141634421, 13808.895288376905, 13833.446662133603, 15083.114610673665, 15275.930684851954, 4485.349624304457, 5103.974480127576, 5869.383714709979, 6004.189434426384, 6057.121717350959, 861.291441597703, 863.7020228021448, 867.9088695686988, 871.324723339417, 892.1569117184329, 29012.984545540803, 29107.7089229105, 29156.708432915824, 29564.704352956625, 29706.25734356615, 995.4475266227169, 1046.8063408269495, 1067.6690226029955, 1076.05844885725, 1179.127445690183, 14603.415863365346, 15077.0, 15118.395264189528, 15376.308066137051, 15514.456994005155, 574691.0, 831195.0, 842924.0, 844272.0, 848219.0, 48838.511614884104, 49543.50456495461, 49702.75148624306, 49709.50290497049, 49752.50247497551, 1322.3123975532862, 1359.1165742267606, 1363.3319673359783, 1366.583192126411, 1379.0760190672274, 26206.606900896884, 27098.32254193661, 27107.30746543169, 27424.725752743016, 27457.86271068632, 43175.0, 43552.78223608862, 43555.34666979934, 43710.56289437065, 43744.78127609342, 509246.0, 511700.0, 514265.0, 514376.0, 518548.0, 19649.705254421482, 23003.6549451755, 23130.306090817303, 23399.298021058716, 23536.411589710395, 33668.326633466706, 34614.82692586622, 36276.93764169595, 38954.0, 39709.60290397175, 27623.0, 32479.35041299208, 33415.0, 33914.8304258487, 34487.82756086203, 33148.0, 33325.50011749777, 33451.83274083614, 33892.15269618279, 34071.0, 32459.513107303883, 32921.34157316888, 33189.83405082959, 34072.82963585166, 34764.65235347614, 24562.263132106065, 27412.314692132855, 28218.294542635776, 31284.37431251408, 31430.84284578654, 20119.497012574804, 21610.13559457604, 23283.370164088352, 23665.763342367045, 24079.879600601886], "value": 18814.89155332578, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [323.6536905511103, 343.8968309507149, 370.2096024986651, 376.8473768123909, 392.45644781976955, 18879.905600471997, 18990.525236869085, 19546.022698865072, 20205.696914546294, 20405.795942040575, 19236.0, 19577.0, 19723.802761972376, 20124.798752012477, 20171.899140504313, 4750.501197374278, 5733.770649174038, 5910.0, 5956.791512297071, 5958.28500579931, 6372.553921225516, 6574.079628851964, 6597.340265973412, 6599.307072757363, 6602.702878370461, 6204.534659900503, 6330.240371155468, 6430.196225471816, 6497.870042599146, 6518.771842985497, 6357.950938095255, 6408.51936104794, 6412.39082287182, 6412.551121421502, 6414.294427612959, 5052.393712754456, 5315.521603055705, 5360.624756267063, 5397.136458166714, 5405.729713514309, 18339.816601833943, 23401.531969360516, 23471.530569388517, 23522.059117635275, 23781.0, 2154.2675490333204, 2497.2882728422314, 2583.19920824545, 2583.3412479817716, 2590.6114082887716, 9465.574049167804, 9576.23390128788, 9946.403215807075, 12547.811782823264, 14704.55886323412, 1741.738739189119, 1790.9791418891236, 1792.4891405949243, 1796.4700413377955, 1802.5763945472804, 108668.0, 114014.18765903616, 114651.42674286575, 115640.42179789048, 117977.23034154408, 8166.632501537445, 9225.400348977295, 9625.614975400906, 9644.662436814679, 9752.658656946973, 11607.187496875307, 12016.939915300542, 12142.635720928387, 12212.267263964191, 12234.0, 7424.257574242532, 7629.580373079423, 7644.541327520255, 7792.259735325135, 7794.844103117905, 6598.0, 7922.088959769585, 7928.4450088494405, 7933.404994625426, 8103.351731861437, 3748.887533374003, 3794.905127371838, 3808.086059345737, 3866.3427217373114, 3871.8838434847, 1669.741190115534, 1752.8948263104255, 1830.0209387977368, 1863.1243315641627, 1940.640981418414, 800.5756948817136, 985.6944347252374, 1003.4130033930087, 1019.5588847274147, 1040.2406243442329, 19504.219831206592, 19548.902255488632, 19607.823530587924, 19686.606267874562, 20073.29743458972, 906.8869533985929, 969.699393188114, 996.3922007575374, 1046.1630695443614, 1053.489057806963, 9771.902280977241, 10283.434411107311, 10313.74215644615, 10534.36793792375, 10780.795489759, 466764.0, 469544.0, 474952.0, 506342.0, 508303.0, 122012.0, 122200.0, 124416.0, 125191.74808251983, 127766.0, 1023.97440063999, 1036.99481502592, 1094.9926068017444, 1095.6658219243047, 1096.2819353323587, 17574.20916058806, 17701.645967080844, 17815.52597243525, 18001.369952051617, 18035.18841652155, 52544.737276313375, 54174.645633859465, 54237.45762542324, 55069.724651376484, 55287.0, 59536.10695839652, 71562.64218678873, 72955.0, 73787.89318160339, 77746.6112669433, 6433.002884552901, 7239.710411583477, 7862.803429914298, 8065.596720163851, 8274.089850116363, 7368.521046131984, 9088.954555227181, 11429.714257143638, 11715.531378745096, 12833.230006199658, 10628.946855265674, 10762.300450470695, 10775.515101820594, 11199.720006999563, 11285.153613478858, 10776.784464310513, 10875.0, 10927.508262128064, 10978.294434649062, 11178.385188814693, 11111.888881111085, 11222.046126079049, 11269.098472122374, 11319.151063670422, 11334.886651133384, 7785.805354866174, 7909.564973926259, 7963.960180199062, 8188.467749596257, 8245.95877020611, 10034.548445319831, 10671.35971841692, 10727.249092563447, 10881.836772448261, 11075.72947545735], "value": 9215.588995547701, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1301.4468850738433, 1389.854065323141, 1391.3113009060514, 1412.4632639596953, 1414.6251243420493, 6470.902936455951, 7842.568658723768, 8067.193280671937, 8327.583620818965, 8332.541710205936, 8175.91824081759, 8232.958835205816, 8266.421350505467, 8274.089850116497, 8275.131111233324, 2036.867603605761, 2275.351524815424, 2316.0, 2319.118734880743, 2323.3843031596557, 2107.2729908181727, 2268.8298377621663, 2349.447879748258, 2350.1421980976993, 2360.150345875492, 2298.19563152896, 2301.675307938977, 2307.3308740465327, 2309.4226443389152, 2310.6880571122933, 2281.9657705134437, 2307.688462057626, 2308.56137333906, 2315.6526521021815, 2316.4440534271653, 2285.9657105143438, 2308.4921317310163, 2309.8614083155067, 2311.4452531392512, 2312.9653055204017, 8121.106678265386, 8209.548474833882, 8211.548364839931, 8234.217749313806, 8277.875831862528, 5010.949890501085, 5168.612354073422, 5170.043541944752, 5197.922031169535, 5200.843974680765, 3333.7666363354574, 3371.4268574342423, 3481.3385456763385, 3538.9646103538894, 3583.140046388848, 1020.9081182693519, 1182.4206138991833, 1206.8595177557213, 1229.1088960503653, 1237.616338934933, 10773.299735517166, 10846.349219046804, 10849.02358787713, 10859.67420977364, 10865.3480791152, 4380.802863871133, 4536.070105628338, 4538.909221815611, 4619.607333376701, 4621.2837010263465, 8041.6381262843315, 9410.247180225566, 9418.952905235567, 9425.764355891157, 9441.575129119206, 5052.671576347526, 5129.589632829365, 5142.640015198975, 5156.561692256125, 5162.66442681232, 4642.303654451792, 4661.86014419568, 4695.929561056587, 4705.988212534322, 4764.737939413296, 3245.4644983577446, 3778.9433158502643, 3785.432185172191, 3877.0, 3887.7084218683713, 1270.6696258972734, 1276.50216415598, 1281.2696762845276, 1291.8126871603565, 1295.8185853980451, 722.8084557592242, 865.7532603208182, 868.9826203475895, 923.7483210250149, 931.897491275959, 5021.719461537285, 5484.314460692413, 5560.443955604488, 5604.85987850307, 5748.45389687979, 836.3351135847016, 885.181207383168, 886.2732559301311, 889.1153302463961, 893.3210759822451, 3666.6150054244454, 3710.5176327077306, 3711.6823527647834, 3832.5784163741964, 3833.769973801581, 9616.807663846685, 9719.465429401309, 9860.06329398706, 9988.550515226832, 9998.300118991747, 9924.404535727735, 9932.00679932001, 9935.552900119512, 9944.651937182163, 9944.900550994542, 7265.4550908681, 7630.504017238862, 7758.9612051939375, 7772.222777722295, 7775.844483110306, 5573.331200255995, 5653.406392328746, 5774.538036956949, 5803.680797556006, 5835.854103647443, 8140.959295203486, 8932.642694292155, 9023.233025192932, 9087.772805679911, 9215.953920230357, 9187.0, 9196.08039196075, 9275.76810579741, 9380.859287110563, 9408.52957352116, 7054.400375967895, 7271.927280727125, 7288.198298187302, 7303.853922921406, 7305.51612451769, 7758.3017528422715, 7778.261065198797, 7778.961105194438, 7846.646900889359, 7910.5649189295145, 7045.471589630695, 7077.823054423475, 7118.074650295635, 7118.715251389886, 7148.106486689164, 6755.831104222434, 6928.411085057826, 7031.43748500129, 7088.787336379917, 7097.290270972861, 6191.288001879661, 6334.049892516159, 6338.841528961629, 6346.01636746305, 6364.713587888648, 4627.444706635225, 4746.5490778376, 4758.119747846659, 4772.596316239207, 4877.170880950176, 4647.86056418308, 4687.0, 4795.448523419712, 4937.333459983, 4947.628927830355], "value": 4367.246296229126, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [324.5391544007512, 344.4506012909409, 346.6204506065858, 357.7138289368505, 367.8321329777956, 4114.938275925856, 4204.132198377492, 4705.670603057787, 5260.053190425722, 5273.736313184345, 4432.623227025699, 5206.609504287184, 5217.139316905482, 5247.422783493813, 5258.737063146847, 4127.442795222644, 4142.606452387018, 4144.544100148981, 4162.271602469556, 4171.707980441371, 3080.4301204277167, 3599.94600080999, 3783.148791521913, 4088.9182216355657, 4207.516135644394, 4069.2471892699796, 4072.312273899227, 4103.425520427142, 4118.620262212163, 4118.691098167635, 4099.052949848828, 4103.809895130408, 4111.465509483778, 4119.331670673382, 4131.380292956081, 2885.567164925257, 3407.2504049109402, 3567.5362202913716, 3599.0102721751464, 3604.116991337113, 5076.340075790124, 5111.568760746997, 5116.590672746172, 5140.768665410065, 5153.768080436389, 3975.1056012397266, 4522.4573051234065, 4766.285057241407, 4773.212419950704, 4778.354922085508, 5248.580113590903, 5466.781328746845, 5514.821645450065, 5525.729082311051, 5532.612717109804, 3419.5383623210796, 3997.6008397061087, 3999.140184860269, 4000.259951908877, 4019.9297361858667, 5331.466853314676, 5508.834734957958, 5658.292713410824, 5669.376368599451, 5793.420657934173, 3333.2000719611624, 3859.6684143970288, 3917.353636649978, 3925.0, 4092.5702801205443, 3185.4584720597554, 3547.7871327719904, 4141.751494910315, 4147.958520414757, 4169.353750168729, 4577.0, 4609.478827522499, 4682.672212945129, 4712.387389639325, 4718.528147185322, 5451.400345961941, 5518.834434966958, 5548.639338442988, 5562.471565201299, 5583.413741557078, 1336.5388940815403, 1341.6243451833502, 1351.7634413977514, 1363.9727205455836, 1368.473137841938, 909.6132212571431, 958.7603099225194, 960.8318544254728, 971.6502059258803, 972.154225823538, 4308.849190278388, 4911.807325238624, 4934.200665487554, 4939.456659767423, 4960.627952903546, 4516.0, 4695.577398034194, 5321.93561287744, 5406.351237851483, 5431.375391829911, 4585.862424127215, 4742.95257047432, 4953.975230123899, 4959.677620954626, 5042.42012168598, 2623.252373073665, 2687.2475706802124, 2839.957400638992, 2842.3462603600874, 2872.3680790226117, 4508.45898492183, 4686.976565117221, 5354.0, 5395.70323632204, 5407.242985981966, 4689.015306785545, 4818.855434336975, 4952.752362381866, 5542.778288868481, 5608.270924779753, 5501.669899806025, 5558.999380111619, 5611.298587676541, 5631.887362252814, 5633.352164501135, 4469.614419529956, 4656.8137274508645, 4726.385569875894, 4771.809127634856, 5166.793328266827, 4448.466184057934, 4732.763361831963, 4859.975700121477, 4981.850544483671, 5337.653052551572, 5068.82259120929, 5080.517350851663, 5538.806141785019, 5670.914936276042, 5778.942210578009, 3516.824158792101, 3939.3303138467104, 4034.890405138493, 4069.2471892699946, 4113.568075352045, 4947.942686996628, 5683.5453163747625, 5698.6580805151825, 5707.571932105026, 5717.885642287048, 4647.9767601161775, 5051.257316225896, 5218.9739051304505, 5435.8256697321385, 5438.592105592017, 4862.717665575793, 5084.339035925306, 5492.80775172867, 5494.36814766307, 5499.532539734168, 4539.228331183773, 4980.800767969241, 5179.30079439286, 5375.489328513785, 5400.432954539874, 4871.951280487291, 5457.181422786613, 5468.4531546845, 5488.408361575099, 5516.503514683698, 4215.641670458045, 5280.551153152025, 5357.678539287655, 5380.381256155592, 5381.408045114956], "value": 4007.821264449934, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [716.0548076538971, 721.9855602887939, 765.3800421658453, 793.9563324017179, 800.9839803203935, 14074.929625351871, 14892.851071489282, 14936.477223297186, 14958.626034349147, 14973.625659358522, 14165.858341416582, 14355.425782968668, 14369.066010709323, 14373.065750726222, 14413.207273599948, 685.7736946807541, 711.9715211391538, 712.9679164437587, 853.9957300213521, 865.6191275838643, 719.7085180501905, 881.7663319220412, 891.5230351761809, 892.3664198419131, 894.2711689972684, 948.1087777489138, 948.9051094890525, 949.6628696812636, 954.141272854433, 954.2080073538946, 731.8719224135808, 760.8250102476406, 951.70497145885, 953.0326718380849, 954.2652157838426, 660.201156600514, 682.7405585877384, 684.8732984397902, 690.102866273847, 692.757534862799, 14309.209704107703, 14538.0, 14710.632234194123, 14841.777373339408, 14857.257137143208, 12944.482220711161, 12947.611571652866, 12970.546030888874, 13296.933515332457, 13336.866631333756, 12711.491540338375, 12727.618171454871, 12766.361681915867, 12925.741485170243, 13047.739045219138, 4368.467266260218, 4418.116376724668, 4454.443194600675, 4483.14820184164, 4488.618467430272, 104836.47581762119, 107168.4641576795, 108364.37453438283, 109872.90127098786, 110652.0, 9909.851352229722, 10302.484875756183, 10470.738231544272, 10511.0, 10729.78540429187, 19241.230350786092, 19264.0, 19762.407127786188, 19833.504162395766, 19858.60282794336, 7405.555666660018, 7619.238076192337, 7636.198199189005, 7752.496087754278, 7757.340626046736, 4227.175700738327, 4273.0, 4290.914181716348, 4302.591253830881, 4400.031992961543, 3291.2101095736853, 3298.5546951161764, 3301.669833016679, 3344.3144155448076, 3391.3217356528794, 614.757170917494, 622.7789134857061, 626.1234272019119, 631.8799428108645, 642.540583482812, 515.4664921805913, 516.19215927074, 516.6770768269831, 521.8356217791384, 540.1096163428002, 8504.064552899175, 8640.956795215983, 8801.25189358899, 8899.110088991178, 8945.0, 586.8972929737364, 612.0390986151788, 627.5230824573266, 632.1024145713099, 641.8010416770722, 4195.16096780639, 4210.85262015828, 4298.613124818781, 4365.890852728708, 4453.287474004143, 98739.50630246947, 100829.49585252027, 101374.49312753389, 101467.0, 103393.0, 90600.5469972646, 91454.0, 91466.54266728624, 92227.0, 93120.0, 79611.0, 79688.60155699184, 80967.0, 81961.77057344145, 83098.0, 8151.199660449809, 8390.370722195737, 8401.369897257608, 8518.599824379311, 8613.569321534023, 45398.0, 49831.252531212784, 52208.73895630497, 53691.73154134361, 54393.72803135959, 60256.69871650614, 62070.689646553285, 66971.0, 69191.0, 69334.0, 12954.805677915027, 12990.0, 13084.80372794428, 13440.663983400102, 13525.72948541004, 14502.202378869264, 14569.198694071503, 14588.416463341347, 14754.557363279118, 14982.475613353481, 13349.733005340033, 13573.660658483617, 13705.451781928612, 13925.164490130624, 14242.287885605885, 13558.86441135576, 13688.31558422055, 13741.793873091712, 13926.373313201133, 14236.359363828446, 12932.547360842702, 13137.34313284351, 13293.53472628492, 13699.109557878706, 13720.313984300945, 10043.89956100459, 10922.453877306261, 11151.498182581641, 11241.213115082193, 11483.540658373906, 8779.0, 9419.717408477756, 10868.836967445653, 10871.836922446011, 10908.454577271263], "value": 7332.806020172951, "unit": "score"}], "name": "dromaeo_css", "value": 7087.483266232245, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 123.0, 169.0, 152.0, 115.0, 114.0, 114.0, 115.0, 113.0, 113.0], "value": 124.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [137.0, 95.0, 109.0, 171.0, 142.0, 114.0, 110.0, 110.0, 127.0, 105.0], "value": 122.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [187.0, 182.0, 185.0, 184.0, 185.0, 182.0, 183.0, 186.0, 184.0, 186.0], "value": 184.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 59.0, 59.0, 89.0, 73.0, 75.0, 81.0, 81.0, 68.0, 76.0], "value": 74.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 69.0, 86.0, 71.0, 69.0, 68.0, 84.0, 69.0, 69.0, 85.0], "value": 75.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 87.0, 89.0, 88.0, 90.0, 87.0, 91.0, 87.0, 88.0, 85.0], "value": 88.3, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 96.0, 96.0, 96.0, 96.0, 96.0, 96.0, 96.0, 94.0, 95.0], "value": 95.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 84.0, 84.0, 85.0, 84.0, 88.0, 84.0, 87.0, 85.0, 85.0], "value": 85.3, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 55.0, 54.0, 56.0, 56.0, 58.0, 57.0, 56.0, 56.0, 57.0], "value": 56.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [72.0, 70.0, 65.0, 76.0, 72.0, 71.0, 70.0, 72.0, 71.0, 72.0], "value": 71.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 92.0, 86.0, 102.0, 106.0, 105.0, 89.0, 89.0, 93.0, 92.0], "value": 94.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 149.0, 140.0, 141.0, 140.0, 143.0, 159.0, 155.0, 187.0, 161.0], "value": 152.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [174.0, 173.0, 179.0, 173.0, 174.0, 171.0, 173.0, 173.0, 175.0, 174.0], "value": 173.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 66.0, 67.0, 67.0, 67.0, 71.0, 66.0, 66.0, 67.0, 65.0], "value": 66.6, "unit": "score"}], "name": "kraken", "value": 1464.0, "alertThreshold": 2.0}]} 17:05:39 INFO - Return code: 0 17:05:39 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 17:05:39 INFO - # TBPL SUCCESS # 17:05:39 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 17:05:39 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 17:05:39 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 17:05:39 INFO - 2016-12-28 17:05:39.581 screenresolution[1981:11837] starting screenresolution argv=screenresolution get 17:05:39 INFO - 2016-12-28 17:05:39.583 screenresolution[1981:11837] Display 0: 1600x1200x32@60 17:05:39 INFO - 2016-12-28 17:05:39.590 screenresolution[1982:11841] starting screenresolution argv=screenresolution list 17:05:39 INFO - Available Modes on Display 0 17:05:39 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 17:05:39 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 17:05:39 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 17:05:39 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 17:05:39 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 17:05:39 INFO - Intel Iris: 17:05:39 INFO - Chipset Model: Intel Iris 17:05:39 INFO - Type: GPU 17:05:39 INFO - Bus: Built-In 17:05:39 INFO - VRAM (Dynamic, Max): 1536 MB 17:05:39 INFO - Vendor: Intel (0x8086) 17:05:39 INFO - Device ID: 0x0a2e 17:05:39 INFO - Revision ID: 0x0009 17:05:39 INFO - Displays: 17:05:39 INFO - Display: 17:05:39 INFO - Resolution: 1600 x 1200 @ 60 Hz 17:05:39 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 17:05:39 INFO - Display Serial Number: 1600x1200 60 17:05:39 INFO - Main Display: Yes 17:05:39 INFO - Mirror: Off 17:05:39 INFO - Online: Yes 17:05:39 INFO - Rotation: Supported 17:05:39 INFO - Return code: 0 17:05:39 INFO - Running post-action listener: _resource_record_post_action 17:05:39 INFO - [mozharness: 2016-12-29 01:05:39.831117Z] Finished run-tests step (success) 17:05:39 INFO - Running post-run listener: _resource_record_post_run 17:05:39 INFO - Total resource usage - Wall time: 1202s; CPU: 54.0%; Read bytes: 1486848; Write bytes: 551628800; Read time: 19; Write time: 1615 17:05:39 INFO - TinderboxPrint: CPU usage
53.8% 17:05:39 INFO - TinderboxPrint: I/O read bytes / time
1,486,848 / 19 17:05:39 INFO - TinderboxPrint: I/O write bytes / time
551,628,800 / 1,615 17:05:39 INFO - TinderboxPrint: CPU idle
2,221.7 (46.2%) 17:05:39 INFO - TinderboxPrint: CPU system
960.1 (20.0%) 17:05:39 INFO - TinderboxPrint: CPU user
1,627.9 (33.8%) 17:05:39 INFO - TinderboxPrint: Swap in / out
251,174,912 / 0 17:05:39 INFO - install - Wall time: 22s; CPU: 54.0%; Read bytes: 250055168; Write bytes: 251692544; Read time: 18126; Write time: 505 17:05:39 INFO - run-tests - Wall time: 1181s; CPU: 54.0%; Read bytes: 1396736; Write bytes: 298416640; Read time: 18; Write time: 1103 17:05:40 INFO - Running post-run listener: _upload_blobber_files 17:05:40 INFO - Blob upload gear active. 17:05:40 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 17:05:40 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 17:05:40 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'] 17:05:40 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 17:05:40 INFO - (blobuploader) - INFO - Open directory for files ... 17:05:40 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 17:05:40 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:05:40 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:05:41 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 17:05:41 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:05:41 INFO - (blobuploader) - INFO - Done attempting. 17:05:41 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 17:05:41 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:05:41 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:05:42 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 17:05:42 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:05:42 INFO - (blobuploader) - INFO - Done attempting. 17:05:42 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 17:05:42 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:05:42 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:05:43 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 17:05:43 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:05:43 INFO - (blobuploader) - INFO - Done attempting. 17:05:43 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 17:05:43 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:05:43 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:05:45 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 17:05:45 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:05:45 INFO - (blobuploader) - INFO - Done attempting. 17:05:45 INFO - (blobuploader) - INFO - Iteration through files over. 17:05:46 INFO - Return code: 0 17:05:46 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 17:05:46 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 17:05:46 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9e02bf42c4da624acb81b2f28225371e99237ffcefbac6e59c3575f7c7dfc26e0ce10f93dd9aabcb0bc289836754cb6415062c6bb54f9aab7ee144c0bfd05bdb", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/89ec5af800faa509f60e27085ca75e09222f5b4d0cf92ab69b5d141d6c111ee1658c62d45f16e16d7cd29f7600920b634c973e3ecd9f744dab159122389e2a09", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e37c1681d30fcdeec92854dffa56a1895a5e2099f02a454b907faf15b6d018e789534df825779e1e6115577d7b16d17a5c03f7710ce58b45e74ae9e6005fc154", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/be789f90f36fc6c73b8c5e9d85560511d86c7909e9ce4c1d974a69e8543c15c65a95977bd99070ef870685a2e1bda4733906187537c8876b717b780edb846347"} 17:05:46 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 17:05:46 INFO - Writing to file /builds/slave/test/properties/blobber_files 17:05:46 INFO - Contents: 17:05:46 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9e02bf42c4da624acb81b2f28225371e99237ffcefbac6e59c3575f7c7dfc26e0ce10f93dd9aabcb0bc289836754cb6415062c6bb54f9aab7ee144c0bfd05bdb", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/89ec5af800faa509f60e27085ca75e09222f5b4d0cf92ab69b5d141d6c111ee1658c62d45f16e16d7cd29f7600920b634c973e3ecd9f744dab159122389e2a09", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e37c1681d30fcdeec92854dffa56a1895a5e2099f02a454b907faf15b6d018e789534df825779e1e6115577d7b16d17a5c03f7710ce58b45e74ae9e6005fc154", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/be789f90f36fc6c73b8c5e9d85560511d86c7909e9ce4c1d974a69e8543c15c65a95977bd99070ef870685a2e1bda4733906187537c8876b717b780edb846347"} 17:05:46 INFO - Running post-run listener: copy_logs_to_upload_dir 17:05:46 INFO - Copying logs to upload dir... 17:05:46 INFO - mkdir: /builds/slave/test/build/upload/logs 17:05:46 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1266.665193 ========= master_lag: 2.16 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 8 secs) (at 2016-12-28 17:05:48.193318) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-28 17:05:48.197109) ========= 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.8uJxNx2qBp/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.SVmM1OEo8i/Listeners TMPDIR=/var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/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/9e02bf42c4da624acb81b2f28225371e99237ffcefbac6e59c3575f7c7dfc26e0ce10f93dd9aabcb0bc289836754cb6415062c6bb54f9aab7ee144c0bfd05bdb", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/89ec5af800faa509f60e27085ca75e09222f5b4d0cf92ab69b5d141d6c111ee1658c62d45f16e16d7cd29f7600920b634c973e3ecd9f744dab159122389e2a09", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e37c1681d30fcdeec92854dffa56a1895a5e2099f02a454b907faf15b6d018e789534df825779e1e6115577d7b16d17a5c03f7710ce58b45e74ae9e6005fc154", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/be789f90f36fc6c73b8c5e9d85560511d86c7909e9ce4c1d974a69e8543c15c65a95977bd99070ef870685a2e1bda4733906187537c8876b717b780edb846347"} build_url:https://queue.taskcluster.net/v1/task/Rbu5pkLUSrKT-4K6y0xLnQ/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011563 build_url: 'https://queue.taskcluster.net/v1/task/Rbu5pkLUSrKT-4K6y0xLnQ/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/9e02bf42c4da624acb81b2f28225371e99237ffcefbac6e59c3575f7c7dfc26e0ce10f93dd9aabcb0bc289836754cb6415062c6bb54f9aab7ee144c0bfd05bdb", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/89ec5af800faa509f60e27085ca75e09222f5b4d0cf92ab69b5d141d6c111ee1658c62d45f16e16d7cd29f7600920b634c973e3ecd9f744dab159122389e2a09", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e37c1681d30fcdeec92854dffa56a1895a5e2099f02a454b907faf15b6d018e789534df825779e1e6115577d7b16d17a5c03f7710ce58b45e74ae9e6005fc154", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/be789f90f36fc6c73b8c5e9d85560511d86c7909e9ce4c1d974a69e8543c15c65a95977bd99070ef870685a2e1bda4733906187537c8876b717b780edb846347"}' ========= master_lag: 0.11 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-28 17:05:48.317143) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-28 17:05:48.317487) ========= 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.8uJxNx2qBp/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.SVmM1OEo8i/Listeners TMPDIR=/var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/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.005183 ========= master_lag: 0.02 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-28 17:05:48.346248) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-28 17:05:48.346709) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-28 17:05:48.347194) ========= ========= Total master_lag: 2.36 =========