builder: graphics_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0007 starttime: 1483981369.52 results: success (0) buildid: 20170109070508 builduid: 8ba13f142ce94f48b28f77837b5c107d revision: 5de7e7c04c4e190ec7b4713bced396e7bbd441a4 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-01-09 09:02:49.524862) ========= master: http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-01-09 09:02:49.525213) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-09 09:02:49.525494) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-09 09:02:49.552091) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-09 09:02:49.552388) ========= 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.AsiP3nGOUD/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.d6Cdl0cXf0/Listeners TMPDIR=/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2017-01-09 09:02:49-- 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% 42.9M=0s 2017-01-09 09:02:49 (42.9 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.105630 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-09 09:02:49.687325) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-09 09:02:49.687649) ========= 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.AsiP3nGOUD/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.d6Cdl0cXf0/Listeners TMPDIR=/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/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.027407 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-09 09:02:49.736359) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-01-09 09:02:49.736716) ========= bash -c 'python archiver_client.py mozharness --repo projects/graphics --rev 5de7e7c04c4e190ec7b4713bced396e7bbd441a4 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo projects/graphics --rev 5de7e7c04c4e190ec7b4713bced396e7bbd441a4 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.AsiP3nGOUD/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.d6Cdl0cXf0/Listeners TMPDIR=/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2017-01-09 09:02:49,829 truncating revision to first 12 chars 2017-01-09 09:02:49,829 Setting DEBUG logging. 2017-01-09 09:02:49,829 attempt 1/10 2017-01-09 09:02:49,829 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/projects/graphics/5de7e7c04c4e?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-01-09 09:02:50,916 unpacking tar archive at: graphics-5de7e7c04c4e/testing/mozharness/ program finished with exit code 0 elapsedTime=1.358413 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-01-09 09:02:51.118642) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-09 09:02:51.118992) ========= script_repo_revision: 5de7e7c04c4e190ec7b4713bced396e7bbd441a4 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-09 09:02:51.119405) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-09 09:02:51.119671) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-09 09:02:51.139874) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 3 secs) (at 2017-01-09 09:02:51.140273) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Graphics --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Graphics 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', 'Graphics', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Graphics'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.AsiP3nGOUD/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.d6Cdl0cXf0/Listeners TMPDIR=/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/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 09:02:51 INFO - MultiFileLogger online at 20170109 09:02:51 in /builds/slave/test 09:02:51 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Graphics --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Graphics 09:02:51 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 09:02:51 INFO - {'append_to_log': False, 09:02:51 INFO - 'base_work_dir': '/builds/slave/test', 09:02:51 INFO - 'blob_upload_branch': 'Graphics', 09:02:51 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 09:02:51 INFO - 'branch': 'Graphics', 09:02:51 INFO - 'buildbot_json_path': 'buildprops.json', 09:02:51 INFO - 'config_files': ('talos/mac_config.py',), 09:02:51 INFO - 'default_actions': ('clobber', 09:02:51 INFO - 'read-buildbot-config', 09:02:51 INFO - 'download-and-extract', 09:02:51 INFO - 'populate-webroot', 09:02:51 INFO - 'create-virtualenv', 09:02:51 INFO - 'install', 09:02:51 INFO - 'run-tests'), 09:02:51 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:02:51 INFO - 'download_minidump_stackwalk': True, 09:02:51 INFO - 'download_symbols': 'ondemand', 09:02:51 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 09:02:51 INFO - 'tooltool.py': '/tools/tooltool.py', 09:02:51 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 09:02:51 INFO - '/tools/misc-python/virtualenv.py')}, 09:02:51 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:02:51 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:02:51 INFO - 'installer_path': 'installer.exe', 09:02:51 INFO - 'log_level': 'info', 09:02:51 INFO - 'log_name': 'talos', 09:02:51 INFO - 'log_to_console': True, 09:02:51 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 09:02:51 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 09:02:51 INFO - 'opt_config_files': (), 09:02:51 INFO - 'pip_index': False, 09:02:51 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:02:51 INFO - 'cmd': ('bash', 09:02:51 INFO - '-c', 09:02:51 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:02:51 INFO - 'enabled': True, 09:02:51 INFO - 'halt_on_failure': False, 09:02:51 INFO - 'name': 'check_screen_resolution'},), 09:02:51 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:02:51 INFO - 'cmd': ('bash', 09:02:51 INFO - '-c', 09:02:51 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:02:51 INFO - 'enabled': True, 09:02:51 INFO - 'halt_on_failure': False, 09:02:51 INFO - 'name': 'check_screen_resolution'},), 09:02:51 INFO - 'run_cmd_checks_enabled': True, 09:02:51 INFO - 'sps_profile': False, 09:02:51 INFO - 'sps_profile_interval': 0, 09:02:51 INFO - 'suite': 'dromaeojs', 09:02:51 INFO - 'system_bits': '32', 09:02:51 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:02:51 INFO - 'title': 't-yosemite-r7-0007', 09:02:51 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:02:51 INFO - 'use_talos_json': True, 09:02:51 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 09:02:51 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:02:51 INFO - 'work_dir': 'build'} 09:02:51 INFO - [mozharness: 2017-01-09 17:02:51.341994Z] Running clobber step. 09:02:51 INFO - Running pre-action listener: _resource_record_pre_action 09:02:51 INFO - Running main action method: clobber 09:02:51 INFO - rmtree: /builds/slave/test/build 09:02:51 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 09:02:53 INFO - Running post-action listener: _resource_record_post_action 09:02:53 INFO - [mozharness: 2017-01-09 17:02:53.768695Z] Finished clobber step (success) 09:02:53 INFO - [mozharness: 2017-01-09 17:02:53.768871Z] Running read-buildbot-config step. 09:02:53 INFO - Running pre-action listener: _resource_record_pre_action 09:02:53 INFO - Running main action method: read_buildbot_config 09:02:53 INFO - Using buildbot properties: 09:02:53 INFO - { 09:02:53 INFO - "project": "", 09:02:53 INFO - "product": "firefox", 09:02:53 INFO - "script_repo_revision": "default", 09:02:53 INFO - "builddir": "graphics_yosemite_r7_test-dromaeojs", 09:02:53 INFO - "repository": "", 09:02:53 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 graphics talos dromaeojs", 09:02:53 INFO - "buildid": "20170109070508", 09:02:53 INFO - "pgo_build": "False", 09:02:53 INFO - "basedir": "/builds/slave/test", 09:02:53 INFO - "buildnumber": 1, 09:02:53 INFO - "slavename": "t-yosemite-r7-0007", 09:02:53 INFO - "master": "http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/", 09:02:53 INFO - "platform": "yosemite_r7", 09:02:53 INFO - "scheduler": "tests-graphics-macosx64-talos", 09:02:53 INFO - "branch": "graphics", 09:02:53 INFO - "repo_path": "projects/graphics", 09:02:53 INFO - "revision": "5de7e7c04c4e190ec7b4713bced396e7bbd441a4", 09:02:53 INFO - "stage_platform": "macosx64", 09:02:53 INFO - "builduid": "8ba13f142ce94f48b28f77837b5c107d", 09:02:53 INFO - "slavebuilddir": "test" 09:02:53 INFO - } 09:02:53 INFO - Found installer url https://queue.taskcluster.net/v1/task/cQUlzIhBT9umb3bxCQLZ6Q/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg. 09:02:53 INFO - Running post-action listener: _resource_record_post_action 09:02:53 INFO - [mozharness: 2017-01-09 17:02:53.772223Z] Finished read-buildbot-config step (success) 09:02:53 INFO - [mozharness: 2017-01-09 17:02:53.772358Z] Running download-and-extract step. 09:02:53 INFO - Running pre-action listener: _resource_record_pre_action 09:02:53 INFO - Running main action method: download_and_extract 09:02:53 INFO - mkdir: /builds/slave/test/build/tests 09:02:53 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')]} 09:02:53 INFO - trying https://queue.taskcluster.net/v1/task/cQUlzIhBT9umb3bxCQLZ6Q/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json 09:02:53 INFO - Downloading https://queue.taskcluster.net/v1/task/cQUlzIhBT9umb3bxCQLZ6Q/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 09:02:53 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/cQUlzIhBT9umb3bxCQLZ6Q/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 09:02:54 INFO - Downloaded 1199 bytes. 09:02:54 INFO - Reading from file /builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json 09:02:54 INFO - Using the following test package requirements: 09:02:54 INFO - {u'common': [u'firefox-53.0a1.en-US.mac.common.tests.zip'], 09:02:54 INFO - u'cppunittest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 09:02:54 INFO - u'firefox-53.0a1.en-US.mac.cppunittest.tests.zip'], 09:02:54 INFO - u'gtest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 09:02:54 INFO - u'firefox-53.0a1.en-US.mac.gtest.tests.zip'], 09:02:54 INFO - u'jittest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 09:02:54 INFO - u'jsshell-mac.zip'], 09:02:54 INFO - u'mochitest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 09:02:54 INFO - u'firefox-53.0a1.en-US.mac.mochitest.tests.zip'], 09:02:54 INFO - u'mozbase': [u'firefox-53.0a1.en-US.mac.common.tests.zip'], 09:02:54 INFO - u'reftest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 09:02:54 INFO - u'firefox-53.0a1.en-US.mac.reftest.tests.zip'], 09:02:54 INFO - u'talos': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 09:02:54 INFO - u'firefox-53.0a1.en-US.mac.talos.tests.zip'], 09:02:54 INFO - u'web-platform': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 09:02:54 INFO - u'firefox-53.0a1.en-US.mac.web-platform.tests.zip'], 09:02:54 INFO - u'xpcshell': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 09:02:54 INFO - u'firefox-53.0a1.en-US.mac.xpcshell.tests.zip']} 09:02:54 INFO - Downloading packages: [u'firefox-53.0a1.en-US.mac.common.tests.zip'] for test suite category: common 09:02:54 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/cQUlzIhBT9umb3bxCQLZ6Q/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip 09:02:54 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/cQUlzIhBT9umb3bxCQLZ6Q/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip'}, attempt #1 09:02:54 INFO - Fetch https://queue.taskcluster.net/v1/task/cQUlzIhBT9umb3bxCQLZ6Q/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip into memory 09:02:55 INFO - Expected file size: 16300394 09:02:55 INFO - Obtained file size: 16300394 09:03:02 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 09:03:02 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/cQUlzIhBT9umb3bxCQLZ6Q/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip 09:03:02 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/cQUlzIhBT9umb3bxCQLZ6Q/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip'}, attempt #1 09:03:02 INFO - Fetch https://queue.taskcluster.net/v1/task/cQUlzIhBT9umb3bxCQLZ6Q/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip into memory 09:03:02 INFO - Expected file size: 16300394 09:03:02 INFO - Obtained file size: 16300394 09:03:09 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/cQUlzIhBT9umb3bxCQLZ6Q/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip 09:03:09 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/cQUlzIhBT9umb3bxCQLZ6Q/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip'}, attempt #1 09:03:09 INFO - Fetch https://queue.taskcluster.net/v1/task/cQUlzIhBT9umb3bxCQLZ6Q/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip into memory 09:03:10 INFO - Expected file size: 14403730 09:03:10 INFO - Obtained file size: 14403730 09:03:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:03:10 INFO - trying https://queue.taskcluster.net/v1/task/cQUlzIhBT9umb3bxCQLZ6Q/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 09:03:10 INFO - Downloading https://queue.taskcluster.net/v1/task/cQUlzIhBT9umb3bxCQLZ6Q/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg 09:03:10 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/cQUlzIhBT9umb3bxCQLZ6Q/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 09:03:12 INFO - Downloaded 69767103 bytes. 09:03:12 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/cQUlzIhBT9umb3bxCQLZ6Q/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 09:03:12 INFO - mkdir: /builds/slave/test/properties 09:03:12 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 09:03:12 INFO - Writing to file /builds/slave/test/properties/build_url 09:03:12 INFO - Contents: 09:03:12 INFO - build_url:https://queue.taskcluster.net/v1/task/cQUlzIhBT9umb3bxCQLZ6Q/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 09:03:13 INFO - Running post-action listener: _resource_record_post_action 09:03:13 INFO - Running post-action listener: set_extra_try_arguments 09:03:13 INFO - [mozharness: 2017-01-09 17:03:13.423518Z] Finished download-and-extract step (success) 09:03:13 INFO - [mozharness: 2017-01-09 17:03:13.423648Z] Running populate-webroot step. 09:03:13 INFO - Running pre-action listener: _resource_record_pre_action 09:03:13 INFO - Running main action method: populate_webroot 09:03:13 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:03:13 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:03:13 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:03:13 INFO - '--tppagecycles', 09:03:13 INFO - '7'], 09:03:13 INFO - 'tests': ['tsvgm']}}, 09:03:13 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 09:03:13 INFO - 'tests': ['tresize', 'tcanvasmark']}, 09:03:13 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 09:03:13 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 09:03:13 INFO - 'tests': ['dromaeo_css', 'kraken']}, 09:03:13 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 09:03:13 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 09:03:13 INFO - 'talos_options': ['--disable-e10s'], 09:03:13 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:03:13 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 09:03:13 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:03:13 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 09:03:13 INFO - 'talos_options': ['--disable-e10s'], 09:03:13 INFO - 'tests': ['damp', 'tps']}, 09:03:13 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 09:03:13 INFO - 'tests': ['damp', 'tps']}, 09:03:13 INFO - 'g3': {'talos_options': ['--disable-e10s'], 09:03:13 INFO - 'tests': ['dromaeo_dom']}, 09:03:13 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:03:13 INFO - 'g4': {'talos_options': ['--disable-e10s'], 09:03:13 INFO - 'tests': ['basic_compositor_video']}, 09:03:13 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 09:03:13 INFO - 'other': {'talos_options': ['--disable-e10s'], 09:03:13 INFO - 'tests': ['a11yr', 09:03:13 INFO - 'ts_paint', 09:03:13 INFO - 'tpaint', 09:03:13 INFO - 'sessionrestore', 09:03:13 INFO - 'sessionrestore_no_auto_restore', 09:03:13 INFO - 'tabpaint']}, 09:03:13 INFO - 'other-e10s': {'tests': ['a11yr', 09:03:13 INFO - 'ts_paint', 09:03:13 INFO - 'tpaint', 09:03:13 INFO - 'sessionrestore', 09:03:13 INFO - 'sessionrestore_no_auto_restore', 09:03:13 INFO - 'tabpaint']}, 09:03:13 INFO - 'other-e10s_l64': {'tests': ['a11yr', 09:03:13 INFO - 'ts_paint', 09:03:13 INFO - 'tpaint', 09:03:13 INFO - 'sessionrestore', 09:03:13 INFO - 'sessionrestore_no_auto_restore', 09:03:13 INFO - 'tabpaint']}, 09:03:13 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 09:03:13 INFO - 'ts_paint', 09:03:13 INFO - 'tpaint', 09:03:13 INFO - 'sessionrestore', 09:03:13 INFO - 'sessionrestore_no_auto_restore', 09:03:13 INFO - 'tabpaint']}, 09:03:13 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 09:03:13 INFO - 'tests': ['a11yr', 09:03:13 INFO - 'ts_paint', 09:03:13 INFO - 'tpaint', 09:03:13 INFO - 'sessionrestore', 09:03:13 INFO - 'sessionrestore_no_auto_restore', 09:03:13 INFO - 'tabpaint']}, 09:03:13 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 09:03:13 INFO - 'tests': ['a11yr', 09:03:13 INFO - 'ts_paint', 09:03:13 INFO - 'tpaint', 09:03:13 INFO - 'sessionrestore', 09:03:13 INFO - 'sessionrestore_no_auto_restore', 09:03:13 INFO - 'tabpaint']}, 09:03:13 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 09:03:13 INFO - 'tests': ['tsvgx', 09:03:13 INFO - 'tsvgr_opacity', 09:03:13 INFO - 'tart', 09:03:13 INFO - 'tscrollx', 09:03:13 INFO - 'cart', 09:03:13 INFO - 'tsvg_static']}, 09:03:13 INFO - 'svgr-e10s': {'tests': ['tsvgx', 09:03:13 INFO - 'tsvgr_opacity', 09:03:13 INFO - 'tart', 09:03:13 INFO - 'tscrollx', 09:03:13 INFO - 'cart', 09:03:13 INFO - 'tsvg_static']}, 09:03:13 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 09:03:13 INFO - 'talos_options': ['--disable-e10s'], 09:03:13 INFO - 'tests': ['tp5o']}, 09:03:13 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 09:03:13 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 09:03:13 INFO - 'talos_options': ['--disable-e10s', 09:03:13 INFO - '--xperf_path', 09:03:13 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:03:13 INFO - 'tests': ['tp5n']}, 09:03:13 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 09:03:13 INFO - 'talos_options': ['--xperf_path', 09:03:13 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:03:13 INFO - 'tests': ['tp5n']}}, 09:03:13 INFO - 'talos.zip': {'path': '', 09:03:13 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:03:13 INFO - Running post-action listener: _resource_record_post_action 09:03:13 INFO - [mozharness: 2017-01-09 17:03:13.442635Z] Finished populate-webroot step (success) 09:03:13 INFO - [mozharness: 2017-01-09 17:03:13.442766Z] Running create-virtualenv step. 09:03:13 INFO - Running pre-action listener: _resource_record_pre_action 09:03:13 INFO - Running main action method: create_virtualenv 09:03:13 INFO - Creating virtualenv /builds/slave/test/build/venv 09:03:13 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 09:03:13 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 09:03:13 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:03:13 INFO - Using real prefix '/tools/python27' 09:03:13 INFO - New python executable in /builds/slave/test/build/venv/bin/python 09:03:14 INFO - Installing distribute.............................................................................................................................................................................................done. 09:03:17 INFO - Installing pip.................done. 09:03:17 INFO - Return code: 0 09:03:17 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 09:03:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 09:03:17 INFO - Reading from file tmpfile_stdout 09:03:17 INFO - Output received: 09:03:17 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) 09:03:17 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 09:03:17 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')]} 09:03:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:03:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:03:17 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')]} 09:03:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:03:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:03:17 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 0x108fbcb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108fbf768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9e214bbd50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108d9ad30>, '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 0x108cc1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108d8fbe8>, '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.AsiP3nGOUD/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.d6Cdl0cXf0/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/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 09:03:17 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 09:03:17 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 09:03:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.AsiP3nGOUD/Render', 09:03:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:03:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:03:17 INFO - 'HOME': '/Users/cltbld', 09:03:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:03:17 INFO - 'LOGNAME': 'cltbld', 09:03:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:03:17 INFO - 'MOZ_NO_REMOTE': '1', 09:03:17 INFO - 'NO_EM_RESTART': '1', 09:03:17 INFO - 'PAGER': '/bin/cat', 09:03:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:03:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:03:17 INFO - 'PWD': '/builds/slave/test', 09:03:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:03:17 INFO - 'SHELL': '/bin/bash', 09:03:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.d6Cdl0cXf0/Listeners', 09:03:17 INFO - 'TMPDIR': '/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/', 09:03:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:03:17 INFO - 'USER': 'cltbld', 09:03:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:03:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:03:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:03:17 INFO - 'XPC_FLAGS': '0x0', 09:03:17 INFO - 'XPC_SERVICE_NAME': '0', 09:03:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:03:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:03:17 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 09:03:17 INFO - Cleaning up... 09:03:17 INFO - Return code: 0 09:03:17 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 09:03:17 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')]} 09:03:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:03:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:03:17 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')]} 09:03:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:03:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:03:17 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 0x108fbcb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108fbf768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9e214bbd50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108d9ad30>, '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 0x108cc1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108d8fbe8>, '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.AsiP3nGOUD/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.d6Cdl0cXf0/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/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 09:03:17 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 09:03:17 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 09:03:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.AsiP3nGOUD/Render', 09:03:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:03:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:03:17 INFO - 'HOME': '/Users/cltbld', 09:03:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:03:17 INFO - 'LOGNAME': 'cltbld', 09:03:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:03:17 INFO - 'MOZ_NO_REMOTE': '1', 09:03:17 INFO - 'NO_EM_RESTART': '1', 09:03:17 INFO - 'PAGER': '/bin/cat', 09:03:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:03:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:03:17 INFO - 'PWD': '/builds/slave/test', 09:03:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:03:17 INFO - 'SHELL': '/bin/bash', 09:03:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.d6Cdl0cXf0/Listeners', 09:03:17 INFO - 'TMPDIR': '/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/', 09:03:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:03:17 INFO - 'USER': 'cltbld', 09:03:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:03:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:03:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:03:17 INFO - 'XPC_FLAGS': '0x0', 09:03:17 INFO - 'XPC_SERVICE_NAME': '0', 09:03:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:03:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:03:17 INFO - Downloading/unpacking psutil>=3.1.1 09:03:17 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 09:03:17 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 09:03:17 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 09:03:17 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 09:03:17 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 09:03:17 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 09:03:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 09:03:21 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:03:21 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:03:21 INFO - Installing collected packages: psutil 09:03:21 INFO - Running setup.py install for psutil 09:03:21 INFO - building 'psutil._psutil_osx' extension 09:03:21 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 09:03:22 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 09:03:22 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 09:03:22 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 09:03:22 INFO - building 'psutil._psutil_posix' extension 09:03:22 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 09:03:22 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 09:03:22 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 09:03:22 INFO - ^ 09:03:22 INFO - 1 warning generated. 09:03:22 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 09:03:22 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:03:22 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:03:22 INFO - Successfully installed psutil 09:03:22 INFO - Cleaning up... 09:03:22 INFO - Return code: 0 09:03:22 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 09:03:22 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')]} 09:03:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:03:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:03:22 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')]} 09:03:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:03:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:03:22 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 0x108fbcb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108fbf768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9e214bbd50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108d9ad30>, '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 0x108cc1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108d8fbe8>, '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.AsiP3nGOUD/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.d6Cdl0cXf0/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/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 09:03:22 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 09:03:22 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 09:03:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.AsiP3nGOUD/Render', 09:03:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:03:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:03:22 INFO - 'HOME': '/Users/cltbld', 09:03:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:03:22 INFO - 'LOGNAME': 'cltbld', 09:03:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:03:22 INFO - 'MOZ_NO_REMOTE': '1', 09:03:22 INFO - 'NO_EM_RESTART': '1', 09:03:22 INFO - 'PAGER': '/bin/cat', 09:03:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:03:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:03:22 INFO - 'PWD': '/builds/slave/test', 09:03:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:03:22 INFO - 'SHELL': '/bin/bash', 09:03:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.d6Cdl0cXf0/Listeners', 09:03:22 INFO - 'TMPDIR': '/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/', 09:03:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:03:22 INFO - 'USER': 'cltbld', 09:03:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:03:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:03:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:03:22 INFO - 'XPC_FLAGS': '0x0', 09:03:22 INFO - 'XPC_SERVICE_NAME': '0', 09:03:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:03:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:03:22 INFO - Downloading/unpacking mozsystemmonitor==0.3 09:03:22 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 09:03:22 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 09:03:22 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 09:03:22 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 09:03:22 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 09:03:22 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 09:03:25 INFO - Downloading mozsystemmonitor-0.3.tar.gz 09:03:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:03:25 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 09:03:25 INFO - Installing collected packages: mozsystemmonitor 09:03:25 INFO - Running setup.py install for mozsystemmonitor 09:03:25 INFO - Successfully installed mozsystemmonitor 09:03:25 INFO - Cleaning up... 09:03:25 INFO - Return code: 0 09:03:25 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 09:03:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:03:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:03:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:03:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:03:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:03:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:03:25 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x108fbcb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108fbf768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9e214bbd50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108d9ad30>, '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 0x108cc1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108d8fbe8>, '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.AsiP3nGOUD/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.d6Cdl0cXf0/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/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 09:03:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 09:03:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 09:03:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.AsiP3nGOUD/Render', 09:03:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:03:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:03:25 INFO - 'HOME': '/Users/cltbld', 09:03:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:03:25 INFO - 'LOGNAME': 'cltbld', 09:03:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:03:25 INFO - 'MOZ_NO_REMOTE': '1', 09:03:25 INFO - 'NO_EM_RESTART': '1', 09:03:25 INFO - 'PAGER': '/bin/cat', 09:03:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:03:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:03:25 INFO - 'PWD': '/builds/slave/test', 09:03:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:03:25 INFO - 'SHELL': '/bin/bash', 09:03:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.d6Cdl0cXf0/Listeners', 09:03:25 INFO - 'TMPDIR': '/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/', 09:03:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:03:25 INFO - 'USER': 'cltbld', 09:03:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:03:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:03:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:03:25 INFO - 'XPC_FLAGS': '0x0', 09:03:25 INFO - 'XPC_SERVICE_NAME': '0', 09:03:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:03:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:03:25 INFO - Downloading/unpacking jsonschema==2.5.1 09:03:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:03:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:03:25 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:03:25 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:03:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:03:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:03:28 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:03:28 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 09:03:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:03:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:03:28 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:03:28 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:03:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:03:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:03:29 INFO - Downloading functools32-3.2.3-2.tar.gz 09:03:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 09:03:29 INFO - warning: no files found matching '*.txt' 09:03:29 INFO - no previously-included directories found matching 'build' 09:03:29 INFO - no previously-included directories found matching 'dist' 09:03:29 INFO - no previously-included directories found matching '.git*' 09:03:29 INFO - Installing collected packages: jsonschema, functools32 09:03:29 INFO - Running setup.py install for functools32 09:03:29 INFO - warning: no files found matching '*.txt' 09:03:29 INFO - no previously-included directories found matching 'build' 09:03:29 INFO - no previously-included directories found matching 'dist' 09:03:29 INFO - no previously-included directories found matching '.git*' 09:03:29 INFO - Successfully installed jsonschema functools32 09:03:29 INFO - Cleaning up... 09:03:29 INFO - Return code: 0 09:03:29 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 09:03:29 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')]} 09:03:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:03:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:03:29 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')]} 09:03:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:03:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:03:29 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 0x108fbcb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108fbf768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9e214bbd50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108d9ad30>, '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 0x108cc1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108d8fbe8>, '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.AsiP3nGOUD/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.d6Cdl0cXf0/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/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 09:03:29 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 09:03:29 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 09:03:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.AsiP3nGOUD/Render', 09:03:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:03:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:03:29 INFO - 'HOME': '/Users/cltbld', 09:03:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:03:29 INFO - 'LOGNAME': 'cltbld', 09:03:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:03:29 INFO - 'MOZ_NO_REMOTE': '1', 09:03:29 INFO - 'NO_EM_RESTART': '1', 09:03:29 INFO - 'PAGER': '/bin/cat', 09:03:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:03:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:03:29 INFO - 'PWD': '/builds/slave/test', 09:03:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:03:29 INFO - 'SHELL': '/bin/bash', 09:03:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.d6Cdl0cXf0/Listeners', 09:03:29 INFO - 'TMPDIR': '/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/', 09:03:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:03:29 INFO - 'USER': 'cltbld', 09:03:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:03:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:03:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:03:29 INFO - 'XPC_FLAGS': '0x0', 09:03:29 INFO - 'XPC_SERVICE_NAME': '0', 09:03:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:03:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:03:29 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 09:03:29 INFO - Cleaning up... 09:03:29 INFO - Return code: 0 09:03:29 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 09:03:29 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')]} 09:03:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:03:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:03:29 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')]} 09:03:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:03:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:03:29 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 0x108fbcb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108fbf768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9e214bbd50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108d9ad30>, '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 0x108cc1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108d8fbe8>, '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.AsiP3nGOUD/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.d6Cdl0cXf0/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/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 09:03:29 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 09:03:29 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 09:03:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.AsiP3nGOUD/Render', 09:03:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:03:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:03:29 INFO - 'HOME': '/Users/cltbld', 09:03:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:03:29 INFO - 'LOGNAME': 'cltbld', 09:03:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:03:29 INFO - 'MOZ_NO_REMOTE': '1', 09:03:29 INFO - 'NO_EM_RESTART': '1', 09:03:29 INFO - 'PAGER': '/bin/cat', 09:03:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:03:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:03:29 INFO - 'PWD': '/builds/slave/test', 09:03:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:03:29 INFO - 'SHELL': '/bin/bash', 09:03:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.d6Cdl0cXf0/Listeners', 09:03:29 INFO - 'TMPDIR': '/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/', 09:03:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:03:29 INFO - 'USER': 'cltbld', 09:03:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:03:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:03:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:03:29 INFO - 'XPC_FLAGS': '0x0', 09:03:29 INFO - 'XPC_SERVICE_NAME': '0', 09:03:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:03:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:03:29 INFO - Downloading/unpacking blobuploader==1.2.4 09:03:29 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 09:03:29 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 09:03:29 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 09:03:29 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 09:03:29 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 09:03:29 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 09:03:32 INFO - Downloading blobuploader-1.2.4.tar.gz 09:03:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:03:32 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:03: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 09:03: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 09:03: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 09:03: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 09:03: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 09:03: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 09:03:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 09:03:33 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:03:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:03:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:03:33 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:03:33 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:03:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:03:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:03:33 INFO - Downloading docopt-0.6.1.tar.gz 09:03:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 09:03:33 INFO - Installing collected packages: blobuploader, requests, docopt 09:03:33 INFO - Running setup.py install for blobuploader 09:03:34 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:03:34 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 09:03:34 INFO - Running setup.py install for requests 09:03:34 INFO - Running setup.py install for docopt 09:03:34 INFO - Successfully installed blobuploader requests docopt 09:03:34 INFO - Cleaning up... 09:03:34 INFO - Return code: 0 09:03:34 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:03:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:03:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:03:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:03:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:03:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:03:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:03:34 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 0x108fbcb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108fbf768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9e214bbd50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108d9ad30>, '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 0x108cc1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108d8fbe8>, '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.AsiP3nGOUD/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.d6Cdl0cXf0/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/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 09:03:34 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 09:03:34 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 09:03:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.AsiP3nGOUD/Render', 09:03:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:03:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:03:34 INFO - 'HOME': '/Users/cltbld', 09:03:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:03:34 INFO - 'LOGNAME': 'cltbld', 09:03:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:03:34 INFO - 'MOZ_NO_REMOTE': '1', 09:03:34 INFO - 'NO_EM_RESTART': '1', 09:03:34 INFO - 'PAGER': '/bin/cat', 09:03:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:03:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:03:34 INFO - 'PWD': '/builds/slave/test', 09:03:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:03:34 INFO - 'SHELL': '/bin/bash', 09:03:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.d6Cdl0cXf0/Listeners', 09:03:34 INFO - 'TMPDIR': '/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/', 09:03:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:03:34 INFO - 'USER': 'cltbld', 09:03:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:03:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:03:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:03:34 INFO - 'XPC_FLAGS': '0x0', 09:03:34 INFO - 'XPC_SERVICE_NAME': '0', 09:03:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:03:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:03:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:03:35 INFO - Running setup.py (path:/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/pip-WYteJ8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:03:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:03:35 INFO - Running setup.py (path:/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/pip-vNqQR8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:03:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:03:35 INFO - Running setup.py (path:/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/pip-jttW_t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:03:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:03:35 INFO - Running setup.py (path:/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/pip-6RdH5T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:03:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:03:35 INFO - Running setup.py (path:/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/pip-usFEsA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:03:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:03:35 INFO - Running setup.py (path:/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/pip-WhQEv0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:03:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:03:35 INFO - Running setup.py (path:/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/pip-fhzLnX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:03:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:03:35 INFO - Running setup.py (path:/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/pip-scQHew-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:03:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:03:35 INFO - Running setup.py (path:/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/pip-zBkqzS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:03:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:03:36 INFO - Running setup.py (path:/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/pip-1rl3B4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:03:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:03:36 INFO - Running setup.py (path:/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/pip-d0aOgr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:03:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:03:36 INFO - Running setup.py (path:/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/pip-SgRQca-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:03:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:03:36 INFO - Running setup.py (path:/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/pip-O9SiyK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:03:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:03:36 INFO - Running setup.py (path:/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/pip-Vcu9Wi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:03:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:03:36 INFO - Running setup.py (path:/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/pip-QJdyIZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:03:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:03:36 INFO - Running setup.py (path:/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/pip-PENPBU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:03:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:03:36 INFO - Running setup.py (path:/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/pip-aRYg2w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:03:36 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:03:36 INFO - Running setup.py install for manifestparser 09:03:36 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 09:03:36 INFO - Running setup.py install for mozcrash 09:03:37 INFO - Running setup.py install for mozdebug 09:03:37 INFO - Running setup.py install for mozdevice 09:03:37 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 09:03:37 INFO - Installing dm script to /builds/slave/test/build/venv/bin 09:03:37 INFO - Running setup.py install for mozfile 09:03:37 INFO - Running setup.py install for mozhttpd 09:03:37 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 09:03:37 INFO - Running setup.py install for mozinfo 09:03:37 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 09:03:37 INFO - Running setup.py install for mozInstall 09:03:38 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 09:03:38 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 09:03:38 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 09:03:38 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 09:03:38 INFO - Running setup.py install for mozleak 09:03:38 INFO - Running setup.py install for mozlog 09:03:38 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 09:03:38 INFO - Running setup.py install for moznetwork 09:03:38 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 09:03:38 INFO - Running setup.py install for mozprocess 09:03:38 INFO - Running setup.py install for mozprofile 09:03:38 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 09:03:38 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 09:03:38 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 09:03:38 INFO - Running setup.py install for mozrunner 09:03:38 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 09:03:38 INFO - Running setup.py install for mozscreenshot 09:03:39 INFO - Running setup.py install for moztest 09:03:39 INFO - Running setup.py install for mozversion 09:03:39 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 09:03:39 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:03:39 INFO - Cleaning up... 09:03:39 INFO - Return code: 0 09:03:39 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:03:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:03:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:03:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:03:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:03:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:03:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:03:39 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 0x108fbcb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108fbf768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9e214bbd50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108d9ad30>, '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 0x108cc1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108d8fbe8>, '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.AsiP3nGOUD/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.d6Cdl0cXf0/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/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 09:03:39 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 09:03:39 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 09:03:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.AsiP3nGOUD/Render', 09:03:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:03:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:03:39 INFO - 'HOME': '/Users/cltbld', 09:03:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:03:39 INFO - 'LOGNAME': 'cltbld', 09:03:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:03:39 INFO - 'MOZ_NO_REMOTE': '1', 09:03:39 INFO - 'NO_EM_RESTART': '1', 09:03:39 INFO - 'PAGER': '/bin/cat', 09:03:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:03:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:03:39 INFO - 'PWD': '/builds/slave/test', 09:03:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:03:39 INFO - 'SHELL': '/bin/bash', 09:03:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.d6Cdl0cXf0/Listeners', 09:03:39 INFO - 'TMPDIR': '/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/', 09:03:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:03:39 INFO - 'USER': 'cltbld', 09:03:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:03:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:03:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:03:39 INFO - 'XPC_FLAGS': '0x0', 09:03:39 INFO - 'XPC_SERVICE_NAME': '0', 09:03:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:03:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:03:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:03:39 INFO - Running setup.py (path:/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/pip-aNetM8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:03:39 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)) 09:03:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:03:39 INFO - Running setup.py (path:/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/pip-P1ZnTr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:03:39 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)) 09:03:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:03:39 INFO - Running setup.py (path:/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/pip-3_yjSM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:03:40 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)) 09:03:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:03:40 INFO - Running setup.py (path:/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/pip-TadmXY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:03:40 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)) 09:03:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:03:40 INFO - Running setup.py (path:/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/pip-neLuLp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:03:40 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)) 09:03:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:03:40 INFO - Running setup.py (path:/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/pip-rdeo9y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:03:40 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)) 09:03:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:03:40 INFO - Running setup.py (path:/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/pip-5UEdKt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:03:40 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)) 09:03:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:03:40 INFO - Running setup.py (path:/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/pip-WbxLSb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:03:40 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)) 09:03:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:03:40 INFO - Running setup.py (path:/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/pip-nnUTHq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:03:40 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)) 09:03:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:03:40 INFO - Running setup.py (path:/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/pip-xFkpFP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:03:40 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)) 09:03:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:03:40 INFO - Running setup.py (path:/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/pip-eb4ZKG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:03:40 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)) 09:03:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:03:40 INFO - Running setup.py (path:/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/pip-C3Il5U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:03:41 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)) 09:03:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:03:41 INFO - Running setup.py (path:/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/pip-7PLyRy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:03:41 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)) 09:03:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:03:41 INFO - Running setup.py (path:/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/pip-LmYayn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:03:41 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)) 09:03:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:03:41 INFO - Running setup.py (path:/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/pip-H1OMTw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:03:41 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)) 09:03:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:03:41 INFO - Running setup.py (path:/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/pip-UaH5s7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:03:41 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 09:03:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:03:41 INFO - Running setup.py (path:/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/pip-gzR6OV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:03:41 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)) 09:03:41 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)) 09:03:41 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)) 09:03:41 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)) 09:03:41 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)) 09:03:41 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)) 09:03:41 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:03:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:03:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:03:41 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:03:41 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:03:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:03:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:03:44 INFO - Downloading blessings-1.6.tar.gz 09:03:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 09:03:44 INFO - Installing collected packages: blessings 09:03:44 INFO - Running setup.py install for blessings 09:03:44 INFO - Successfully installed blessings 09:03:44 INFO - Cleaning up... 09:03:44 INFO - Return code: 0 09:03:44 INFO - Done creating virtualenv /builds/slave/test/build/venv. 09:03:44 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:03:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:03:44 INFO - Reading from file tmpfile_stdout 09:03:44 INFO - Current package versions: 09:03:44 INFO - blessings == 1.6 09:03:44 INFO - blobuploader == 1.2.4 09:03:44 INFO - docopt == 0.6.1 09:03:44 INFO - functools32 == 3.2.3-2 09:03:44 INFO - jsonschema == 2.5.1 09:03:44 INFO - manifestparser == 1.1 09:03:44 INFO - mozInstall == 1.12 09:03:44 INFO - mozcrash == 1.0 09:03:44 INFO - mozdebug == 0.1 09:03:44 INFO - mozdevice == 0.49 09:03:44 INFO - mozfile == 1.2 09:03:44 INFO - mozhttpd == 0.7 09:03:44 INFO - mozinfo == 0.9 09:03:44 INFO - mozleak == 0.1 09:03:44 INFO - mozlog == 3.4 09:03:44 INFO - moznetwork == 0.27 09:03:44 INFO - mozprocess == 0.23 09:03:44 INFO - mozprofile == 0.28 09:03:44 INFO - mozrunner == 6.13 09:03:44 INFO - mozscreenshot == 0.1 09:03:44 INFO - mozsystemmonitor == 0.3 09:03:44 INFO - moztest == 0.8 09:03:44 INFO - mozversion == 1.4 09:03:44 INFO - psutil == 3.1.1 09:03:44 INFO - requests == 1.2.3 09:03:44 INFO - wsgiref == 0.1.2 09:03:44 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:03:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:03:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:03:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:03:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:03:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:03:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:03:44 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 0x108fbcb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108fbf768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9e214bbd50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108d9ad30>, '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 0x108cc1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108d8fbe8>, '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.AsiP3nGOUD/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.d6Cdl0cXf0/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/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 09:03:44 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 09:03:44 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 09:03:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.AsiP3nGOUD/Render', 09:03:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:03:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:03:44 INFO - 'HOME': '/Users/cltbld', 09:03:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:03:44 INFO - 'LOGNAME': 'cltbld', 09:03:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:03:44 INFO - 'MOZ_NO_REMOTE': '1', 09:03:44 INFO - 'NO_EM_RESTART': '1', 09:03:44 INFO - 'PAGER': '/bin/cat', 09:03:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:03:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:03:44 INFO - 'PWD': '/builds/slave/test', 09:03:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:03:44 INFO - 'SHELL': '/bin/bash', 09:03:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.d6Cdl0cXf0/Listeners', 09:03:44 INFO - 'TMPDIR': '/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/', 09:03:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:03:44 INFO - 'USER': 'cltbld', 09:03:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:03:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:03:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:03:44 INFO - 'XPC_FLAGS': '0x0', 09:03:44 INFO - 'XPC_SERVICE_NAME': '0', 09:03:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:03:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:03:44 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)) 09:03:44 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)) 09:03:44 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)) 09:03:44 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)) 09:03:44 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)) 09:03:44 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)) 09:03:44 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)) 09:03:44 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)) 09:03:44 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)) 09:03:44 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)) 09:03:44 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)) 09:03:44 INFO - Cleaning up... 09:03:45 INFO - Return code: 0 09:03:45 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 09:03:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:03:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:03:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:03:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:03:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:03:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:03:45 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 0x108fbcb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108fbf768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9e214bbd50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108d9ad30>, '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 0x108cc1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108d8fbe8>, '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.AsiP3nGOUD/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.d6Cdl0cXf0/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/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 09:03:45 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 09:03:45 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 09:03:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.AsiP3nGOUD/Render', 09:03:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:03:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:03:45 INFO - 'HOME': '/Users/cltbld', 09:03:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:03:45 INFO - 'LOGNAME': 'cltbld', 09:03:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:03:45 INFO - 'MOZ_NO_REMOTE': '1', 09:03:45 INFO - 'NO_EM_RESTART': '1', 09:03:45 INFO - 'PAGER': '/bin/cat', 09:03:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:03:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:03:45 INFO - 'PWD': '/builds/slave/test', 09:03:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:03:45 INFO - 'SHELL': '/bin/bash', 09:03:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.d6Cdl0cXf0/Listeners', 09:03:45 INFO - 'TMPDIR': '/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/', 09:03:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:03:45 INFO - 'USER': 'cltbld', 09:03:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:03:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:03:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:03:45 INFO - 'XPC_FLAGS': '0x0', 09:03:45 INFO - 'XPC_SERVICE_NAME': '0', 09:03:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:03:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:03:45 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 09:03:45 INFO - Cleaning up... 09:03:45 INFO - Return code: 0 09:03:45 INFO - Running post-action listener: _resource_record_post_action 09:03:45 INFO - Running post-action listener: _start_resource_monitoring 09:03:45 INFO - Starting resource monitoring. 09:03:45 INFO - [mozharness: 2017-01-09 17:03:45.298521Z] Finished create-virtualenv step (success) 09:03:45 INFO - [mozharness: 2017-01-09 17:03:45.299477Z] Running install step. 09:03:45 INFO - Running pre-action listener: _resource_record_pre_action 09:03:45 INFO - Running main action method: install 09:03:45 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:03:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:03:45 INFO - Reading from file tmpfile_stdout 09:03:45 INFO - Detecting whether we're running mozinstall >=1.0... 09:03:45 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 09:03:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 09:03:45 INFO - Reading from file tmpfile_stdout 09:03:45 INFO - Output received: 09:03:45 INFO - Usage: mozinstall [options] installer 09:03:45 INFO - Options: 09:03:45 INFO - -h, --help show this help message and exit 09:03:45 INFO - -d DEST, --destination=DEST 09:03:45 INFO - Directory to install application into. [default: 09:03:45 INFO - "/builds/slave/test"] 09:03:45 INFO - --app=APP Application being installed. [default: firefox] 09:03:45 INFO - mkdir: /builds/slave/test/build/application 09:03:45 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'] 09:03:45 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 09:04:07 INFO - Reading from file tmpfile_stdout 09:04:07 INFO - Output received: 09:04:07 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 09:04:07 INFO - Running post-action listener: _resource_record_post_action 09:04:07 INFO - [mozharness: 2017-01-09 17:04:07.724298Z] Finished install step (success) 09:04:07 INFO - [mozharness: 2017-01-09 17:04:07.724423Z] Running run-tests step. 09:04:07 INFO - Running pre-action listener: _resource_record_pre_action 09:04:07 INFO - Running main action method: run_tests 09:04:07 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:04:07 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:04:07 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:04:07 INFO - 2017-01-09 09:04:07.738 screenresolution[1623:7493] starting screenresolution argv=screenresolution get 09:04:07 INFO - 2017-01-09 09:04:07.741 screenresolution[1623:7493] Display 0: 1600x1200x32@60 09:04:07 INFO - 2017-01-09 09:04:07.747 screenresolution[1624:7495] starting screenresolution argv=screenresolution list 09:04:07 INFO - Available Modes on Display 0 09:04:07 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:04:07 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:04:07 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:04:07 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:04:07 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:04:07 INFO - Intel Iris: 09:04:07 INFO - Chipset Model: Intel Iris 09:04:07 INFO - Type: GPU 09:04:07 INFO - Bus: Built-In 09:04:07 INFO - VRAM (Dynamic, Max): 1536 MB 09:04:07 INFO - Vendor: Intel (0x8086) 09:04:07 INFO - Device ID: 0x0a2e 09:04:07 INFO - Revision ID: 0x0009 09:04:07 INFO - Displays: 09:04:07 INFO - Display: 09:04:07 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:04:07 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:04:07 INFO - Display Serial Number: 1600x1200 60 09:04:07 INFO - Main Display: Yes 09:04:07 INFO - Mirror: Off 09:04:07 INFO - Online: Yes 09:04:07 INFO - Rotation: Supported 09:04:07 INFO - Return code: 0 09:04:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 09:04:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 09:04:07 INFO - Python 2.7.3 09:04:07 INFO - Return code: 0 09:04:07 INFO - grabbing minidump binary from tooltool 09:04:07 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')]} 09:04:07 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 0x108d9ad30>, '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 0x108cc1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108d8fbe8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 09:04:07 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 09:04:07 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 09:04:07 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 09:04:08 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:04:08 INFO - Return code: 0 09:04:08 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 09:04:08 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 09:04:08 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 09:04:08 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 09:04:08 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 09:04:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Graphics', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/cQUlzIhBT9umb3bxCQLZ6Q/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0007', '--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 09:04:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Graphics --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/cQUlzIhBT9umb3bxCQLZ6Q/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0007 --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 09:04:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.AsiP3nGOUD/Render', 09:04:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:04:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:04:08 INFO - 'HOME': '/Users/cltbld', 09:04:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:04:08 INFO - 'LOGNAME': 'cltbld', 09:04:08 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:04:08 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 09:04:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:04:08 INFO - 'MOZ_NO_REMOTE': '1', 09:04:08 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:04:08 INFO - 'NO_EM_RESTART': '1', 09:04:08 INFO - 'PAGER': '/bin/cat', 09:04:08 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:04:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:04:08 INFO - 'PWD': '/builds/slave/test', 09:04:08 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:04:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:04:08 INFO - 'SHELL': '/bin/bash', 09:04:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.d6Cdl0cXf0/Listeners', 09:04:08 INFO - 'TMPDIR': '/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/', 09:04:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:04:08 INFO - 'USER': 'cltbld', 09:04:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:04:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:04:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:04:08 INFO - 'XPC_FLAGS': '0x0', 09:04:08 INFO - 'XPC_SERVICE_NAME': '0', 09:04:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:04:08 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Graphics', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/cQUlzIhBT9umb3bxCQLZ6Q/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0007', '--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 09:04:08 INFO - mozversion application_buildid: 20170109070508 09:04:08 INFO - mozversion application_changeset: 5de7e7c04c4e190ec7b4713bced396e7bbd441a4 09:04:08 INFO - mozversion application_display_name: Nightly 09:04:08 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:04:08 INFO - mozversion application_name: Firefox 09:04:08 INFO - mozversion application_remotingname: firefox 09:04:08 INFO - mozversion application_repository: https://hg.mozilla.org/projects/graphics 09:04:08 INFO - mozversion application_vendor: Mozilla 09:04:08 INFO - mozversion application_version: 53.0a1 09:04:08 INFO - mozversion platform_buildid: 20170109070508 09:04:08 INFO - mozversion platform_changeset: 5de7e7c04c4e190ec7b4713bced396e7bbd441a4 09:04:08 INFO - mozversion platform_repository: https://hg.mozilla.org/projects/graphics 09:04:08 INFO - mozversion platform_version: 53.0a1 09:04:08 INFO - using testdate: 1483981448 09:04:08 INFO - actual date: 1483981448 09:04:08 INFO - starting webserver on 'localhost:49241' 09:04:08 INFO - SUITE-START | Running 2 tests 09:04:08 INFO - TEST-START | dromaeo_css 09:04:08 INFO - operating with platform_type : mac_ 09:04:08 INFO - Initialising browser for dromaeo_css test... 09:04:08 INFO - TEST-INFO | started process 1634 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/tmponnuQd/profile http://localhost:49241/getInfo.html) 09:04:08 INFO - PROCESS | 1634 | 1483981448863 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:4907 < processFileChanges()@resource://gre/modules/addons/XPIProvider.jsm -> resource://gre/modules/addons/XPIProviderUtils.js:2060 < checkForChanges()@resource://gre/modules/addons/XPIProvider.jsm:3762 < startup()@resource://gre/modules/addons/XPIProvider.jsm:2795 < callProvider()@resource://gre/modules/AddonManager.jsm:267 < _startProvider()@resource://gre/modules/AddonManager.jsm:973 < startup()@resource://gre/modules/AddonManager.jsm:1155 < startup()@resource://gre/modules/AddonManager.jsm:3124 < observe()@resource://gre/components/addonManager.js:65 09:04:10 INFO - PROCESS | 1634 | __metrics Screen width/height:1600/1200 09:04:10 INFO - PROCESS | 1634 | colorDepth:24 09:04:10 INFO - PROCESS | 1634 | Browser inner width/height: 1024/689 09:04:10 INFO - PROCESS | 1634 | __metrics 09:04:10 INFO - PROCESS | 1634 | 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" 09:04:10 INFO - PROCESS | 1634 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:04:11 INFO - TEST-INFO | 1634: exit 0 09:04:11 INFO - Browser initialized. 09:04:11 INFO - Running cycle 1/1 for dromaeo_css test... 09:04:11 INFO - TEST-INFO | started process 1636 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/tmponnuQd/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:04:23 INFO - PROCESS | 1636 | RSS: Main: 234831872 09:04:23 INFO - PROCESS | 1636 | 09:07:27 INFO - PROCESS | 1636 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49241/tests/dromaeo/cssquery-ext.html) 09:07:29 INFO - PROCESS | 1636 | RSS: Main: 385630208 09:07:29 INFO - PROCESS | 1636 | 09:09:12 INFO - PROCESS | 1636 | ************************* 09:09:12 INFO - PROCESS | 1636 | A coding exception was thrown and uncaught in a Task. 09:09:12 INFO - PROCESS | 1636 | 09:09:12 INFO - PROCESS | 1636 | Full message: ReferenceError: fetch is not defined 09:09:12 INFO - PROCESS | 1636 | Full stack: apiCall@resource://shield-recipe-client/lib/NormandyApi.jsm:37:5 09:09:12 INFO - PROCESS | 1636 | get@resource://shield-recipe-client/lib/NormandyApi.jsm:44:12 09:09:12 INFO - PROCESS | 1636 | this.NormandyApi.fetchRecipes<@resource://shield-recipe-client/lib/NormandyApi.jsm:52:34 09:09:12 INFO - PROCESS | 1636 | TaskImpl_run@resource://gre/modules/Task.jsm:319:42 09:09:12 INFO - PROCESS | 1636 | TaskImpl@resource://gre/modules/Task.jsm:277:3 09:09:12 INFO - PROCESS | 1636 | asyncFunction@resource://gre/modules/Task.jsm:252:14 09:09:12 INFO - PROCESS | 1636 | this.RecipeRunner.start<@resource://shield-recipe-client/lib/RecipeRunner.jsm:64:23 09:09:12 INFO - PROCESS | 1636 | TaskImpl_run@resource://gre/modules/Task.jsm:319:42 09:09:12 INFO - PROCESS | 1636 | TaskImpl@resource://gre/modules/Task.jsm:277:3 09:09:12 INFO - PROCESS | 1636 | asyncFunction@resource://gre/modules/Task.jsm:252:14 09:09:12 INFO - PROCESS | 1636 | setTimeout_timer@resource://gre/modules/Timer.jsm:30:5 09:09:12 INFO - PROCESS | 1636 | 09:09:12 INFO - PROCESS | 1636 | ************************* 09:10:32 INFO - PROCESS | 1636 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-ext.html (next: http://localhost:49241/tests/dromaeo/cssquery-jquery.html) 09:10:32 INFO - PROCESS | 1636 | RSS: Main: 368656384 09:10:32 INFO - PROCESS | 1636 | 09:13:35 INFO - PROCESS | 1636 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49241/tests/dromaeo/cssquery-mootools.html) 09:13:35 INFO - PROCESS | 1636 | RSS: Main: 337833984 09:13:35 INFO - PROCESS | 1636 | 09:16:38 INFO - PROCESS | 1636 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49241/tests/dromaeo/cssquery-prototype.html) 09:16:39 INFO - PROCESS | 1636 | RSS: Main: 334897152 09:16:39 INFO - PROCESS | 1636 | 09:19:42 INFO - PROCESS | 1636 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49241/tests/dromaeo/cssquery-yui.html) 09:19:43 INFO - PROCESS | 1636 | RSS: Main: 327176192 09:19:43 INFO - PROCESS | 1636 | 09:22:46 INFO - PROCESS | 1636 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-yui.html (next: http://localhost:49241/tests/dromaeo/cssquery-dojo.html) 09:22:46 INFO - PROCESS | 1636 | RSS: Main: 528265216 09:22:46 INFO - PROCESS | 1636 | 09:22:46 INFO - PROCESS | 1636 | __start_tp_report 09:22:46 INFO - PROCESS | 1636 | _x_x_mozilla_page_load 09:22:46 INFO - PROCESS | 1636 | _x_x_mozilla_page_load_details 09:22:46 INFO - PROCESS | 1636 | |i|pagename|runs| 09:22:46 INFO - PROCESS | 1636 | |0;dojo.html;3235.36910302491;3242.1084201844506;3249.756268279878;3255.9023229303116;3311.0894504011394;5433.8369848904595;5473.917891231625;5474.47992440718;5478.45215478452;5519.724013799309;5474.671519708821;5499.3675727291375;5577.9163312550345;5578.44215578442;5609.270794796671;4977.601791856643;5035.899282014358;5038.974805125988;5063.949360506384;5119.641625086246;4522.45730512339;4958;5069.112905241568;5144.485551444863;5158.3552055993005;4951.133551628451;5101.515356041189;5111.412187598399;5139.408967968694;5154.07226699194;5002.349694539723;5055.494450554952;5079.568236699885;5085.313482679828;5107.540321371057;5053.216751403502;5059.19052951526;5075.086484432801;5092.668976516515;5140.897182056376;5537.307836520435;5551.278333816618;5555.916661250085;5583.637063590853;5595.300587426572;5862.618929769551;5915.438033386821;5920.763169473259;6037.003894357425;6092.573519853612;6108.137390846266;6111.174991376151;6137.631742095444;6138.664296166084;6142.78500252489;5673.489385955284;5675.858103547444;5676.801311954103;5690.7439165237665;5702.8289151325525;6112;6116.366205733216;6234.781782637586;6280.905786413207;6298;5820.912686309709;5843.503302219274;5853.209816674779;5953.880922381528;5955.076963070731;5931.7923872665115;6052.394760524;6064.36324185963;6091.390860913873;6096.451319381277;5845.064789633639;5859.560532960045;5942.37605051472;5943;6044.909326360108;6109.114178444101;6120.204373431426;6123.475981923542;6132.570720049643;6140.969295153495;4801.615870730403;4839.370881785346;4848.272759086165;4880.145974454456;4885.827401423633;4361.890952726208;4381.824727010948;4412.051408947092;4414.977925110354;4417.712848664826;5585.218069470277;5603.439656034364;5606.467385598362;5606.747696353674;5614.382417934024;6103.84740381494;6124.601900876429;6129.264488261437;6135.263768347827;6139.57023008394;5543.1685247213245;5596.3284405871555;5600.691961942132;5605.943940560624;5625.521830644359;4879.41807059231;5293.073712100367;5307.62846600742;5314.096603577401;5317.175837745156;5775.335836378787;6245.5628106032145;6283.465905398092;6292.874142517033;6312.747490100436;6070.271567411939;6084.665343406155;6085.969570152298;6092.390760923872;6106.9083963739695;5080.542751152266;6160.845978850564;6200.441960223421;6344.777932772331;6350.936490635219;5942.64344139353;5951.851203719942;5982.910256346071;5996.5502587307;6051.243594550681;6107.877842443215;6113.938860611515;6114.113453549299;6130.570860039755;6134.957057300359;5878.2064421302575;5882.558808089325;5888.234529511136;5897.587168898136;5897.852553686193;4542.227821270458;4778.761061946958;5706.514946229451;5773.422657734193;5916.053431450947;5796.94203057964;5805.158252053513;5808.099744539603;5808.157817116579;5828;4982.526659967298;5936.1986131871645;5957.285125784933;5958.791442299499;5978.163057172086;5639.407862174577;5691.658500489983;5700.059490184073;5709.9714501427225;5780.479756821906 09:22:46 INFO - PROCESS | 1636 | |1;ext.html;489.6278828090651;500.9398872135343;507.82733870484026;508.26809394471934;538.6794857060048;24442.633360499607;39090;40982.99999999996;41052.79473602628;43422.348664770056;41915.58084419155;42227.57772422275;42268;42300;42335.57664423355;8533.957330213372;9344.15902568767;9532.616845788552;9544.90455095447;9548.952255238713;8453.070162282143;11246.437678116103;11385.032272256869;11434;11606.825897611501;11350.20548561601;11414.972652461276;11437.599684011062;11443.027342675883;11445.542178312857;11475.827862582068;11508.769824603545;11528.538858445652;11538.423078846108;11561.710957226052;11316.830247546293;11368.317900925971;11378.658640240807;11399.829002564968;11418.143639227092;39386.60613393858;39667.20665586701;39693.00767480836;39707.00732481682;39748.20503589912;3954.3574667397306;3984.7409918355506;4022.73852199609;4037.858674946392;4124.257633625946;29408.70591294081;30250.243743906358;30264.54603180932;30654.386912261853;30752.38495230083;3135.890243841455;3268.6404495505476;3281.442154833684;3330.283988942365;3333.5666363372848;476116.9999999965;476251;479491;480750;481413.9999999965;60550.69724651348;60801.39198608046;60878.39121608727;60927.39072609217;60995.6950215255;64100.038499422546;64146;64226.6788666063;64264.35735642584;64291.357086429474;13556.796648050287;15469.922650386676;16058.598535036717;16079.598010049842;16098.953568018042;11130.666080017612;11414.94292528532;12268.938655306845;12385.62843114708;12421.813672794915;17161.91419042922;17574.912125439292;17880.46358609244;17978.03362445182;18032.819671803114;6203.883301005862;6481.254655714653;6723.596584204964;6857.314268573203;6869.622170780555;861.5132450165762;871.6556960000764;872.4590753732599;879.2921698033144;894.141624040915;28734.27594344304;29424.26439338991;29491.262718431783;29524;30095.548566771515;1018.5111146649501;1028.8559601655775;1059.2585190366774;1071.7267096890398;1191.3209470601673;14441.35013924376;14695.191764453059;15416.614584635474;15464.5360639181;15480.303386347641;586983;593728;821239;840458;841100;44530.77734611371;50576.494235057915;51320.48679513157;52201.73899130556;55532.16701749477;1394.079907261223;1410.5627255550812;1422;1430.5279257844886;1437.8202724659418;27966;27971.44057118887;28069.719302807527;28778.424431510834;28817.84728610832;46086.769566151954;46441.767791160826;46487.07025859532;46608;46671;552183;553786;555438.4456155386;556088.0498742535;557244;23883.28350149498;24036.27891163268;24288.87855560711;24297.757022430258;24372.390690232176;31791.682083178872;40222.39666404948;41826.58173418227;41866.3480430138;41950.79024604979;32761.508577371835;33473.15897625654;33542.99371018873;34048;34870.779522716584;33321;33689;33715;34824;34994.650053499136;33120.50319245261;33775.64897404076;33813.830930846176;33912;33971.83014085013;23115;23703;28897.79839628887;29140.27149321284;30517.93187238525;19245.230190792212;22482.988265528416;23135.88432057829;23225.651615225448;23436.531269374856 09:22:46 INFO - PROCESS | 1636 | |2;jquery.html;363.96906262967644;375.5287114671088;379.614691088545;386.1446895127293;408.10217521452813;15515.146666933315;15622.921885390571;18543.72184417235;21060.89469552654;21090.15639374427;20797.792022079775;21725.23961661342;21768.346949591498;21809.890950545265;22290;4626.606738427237;5871.853203669921;5923.111533270023;5930.436608522195;6167.62994220346;5977.5815692901515;5998.310194327642;6038.788642397517;6045.183900173486;6054.334023257438;4198.496180458349;4283.341065896399;4383.3863259143745;4487.483939346968;6627.072209890619;5708.914366284467;6656.234533028715;6678.799636010927;6690.966545167292;6700.731970721214;4657.86026419208;5801.7099145042575;5858.443447872488;5860.70696465175;5863.208466857005;25676.229713108638;26168;26181.345466363302;26214.9514019439;26347.868260658764;2635.736426357368;2762.9171124866084;2764.8202866813594;2770.542860428027;2801.8739156737997;10391.84412233817;10573.524191411405;14899.255037248095;15228.847711522854;15286.770698439532;1852.110986726379;1859.1076283383916;1874.4095609882843;1879.642867855103;1926.3450426854797;119405.80594194034;121679.174812377;131767;136890;137664;11642.24325418845;11704.765904681859;11867.58463453775;11874.703132421759;12052.75894482123;15279.159646219343;15300.311485983157;15309.311081001382;15323.387064517518;15324.080555166725;8286.318055860122;8502.74491765248;8505.447145935617;8632.093630168867;8671.08953559867;8294.419390642597;8297.336213102937;8315.04376996653;8460.746177614681;8474.491530508189;4642.65180111493;4649.721016739007;4665.650076244227;4668.50980647034;4729.290606409066;1670.13987796285;1774.2636805725479;1918.1202421626883;1949.3567122849427;1997.3209108903043;840.8822764812932;983.5623147699205;988.4464699768153;1014.6499457687086;1020.8821340632145;19679.704804427944;21290.78709212919;21362.572748544942;21502.892485537785;21652.675209871864;998.6354980432089;999.2655398282257;1032.695354870315;1040.8074506216374;1043.1967385113421;10759.3544387337;11043.337399756041;11180.664580062612;11187;11187.4965626547;687483;719100;722333;727281;744923;115784.42107789408;130272.17847637906;135980.32009839886;139734;149282;940.3887473142512;954.9665761698446;983.179045497018;986.6448078691665;986.9851952220722;14989;15262.923685381502;16799.580010499834;18628.161732722332;19475;55892.16161757658;57233;57543;58399.70800145972;58641.12038319343;75523;76726;76819.61590192237;76980.46039079074;78860;8147.388945829205;8348.66605335804;8433.072362040048;8473.703420380258;8491;10618.575256989634;13083.280419577015;13398.598042058753;13419.329033548478;13579;11275.436228188393;11287.41976123327;11469.942650286695;11501.942490287494;11510.251833630788;10932;10999.395033273247;11087.501062452047;11341.206115571831;11352.489137988645;11138.665840024812;11540.480678369662;11682.123840712151;11744.76510469803;11748.412579370826;8135.75592732219;8153.22544358285;8389.244967952669;8613;8639.95680021596;9000.954995224982;9586.801649793775;10842.186835987175;11448.3703396314;11475 09:22:46 INFO - PROCESS | 1636 | |3;mootools.html;1416.4688241909284;1427.4718354208944;1429.1925062339772;1433.670255841157;1438.02214494144;7945.4438189326775;8247.67009319628;8262.710805121822;8369.246767790897;8371.706990255343;7348.706051757923;7583.241675832414;7583.58290294035;7959.761207163794;8178.550179740112;2267.7505474397844;2286.6455699366625;2317.872517011572;2320.872352020638;2323.6979192704925;2298.161171172518;2322.3381336319153;2331.1491305673476;2335.159342636658;2347.4600841806396;1677.370985880295;2118.8834614096218;2228.944276393087;2301.3671240408853;2338.2166974063707;2277.3737222263844;2279.031411650042;2282.5092605089985;2285.942851428719;2289.439087423592;2262.2760716570706;2275.2832857649787;2279.168103642175;2285.245868863271;2287.3252390544662;8110.472819266729;8305.8338833223;8310.418270721051;8310.626021828972;8323.334133269324;4997.625178111656;5145;5149.819756308511;5163.406208286057;5167.121589329823;3280.901572952815;3394.62659107498;3452.016175390027;3470.9826450867836;3546.1489242581592;1168.1063986050679;1231.7290196156832;1249.5251804314303;1259.0431272233168;1280.5146030604546;10627.884072172388;10644.946775266075;10745.83881241782;10772.353658780497;10855.945720271427;3693.5567731872347;4527.388802511685;4554.111948170142;4670.53294670537;4687.789049492712;9234.630614775468;9239.445633261892;9265.814683706289;9266.305027122993;9271.07289271081;5144.305518754995;5145.202493613496;5157.484251574812;5170.819021334235;5173.456787037382;3870.9419358709642;4216.51510076339;4730.101280756714;4730.550597693214;4735.786889589976;3727.254549090193;3748.737588368788;3807.124361396894;3835.7506762060375;3851.5350175887456;1029.248648486609;1219.5243854896598;1220.4995951659955;1227.895628871556;1230.5446984615571;837;858.5321000054985;864.2524216552637;887.1439061305871;904.2314033071837;5477.945220547823;5517.586181036439;5693.345265294462;5733.254676891977;5987.940120598825;952.7760976170595;967.2310513141929;970.8689326940914;976.7851072763981;1045.9320144190604;3602.441621548664;3677.4116141417244;3732.4401339799247;3774.6602805747616;3809.3333666608232;9594.040595940489;9840.557174927146;9874.111329980193;9939.900600993897;9950.054744799232;9893.65372211969;9908.504574771232;9916.157126644315;9923.851142232872;9928.50357482123;7257.564546127249;7571.129320128146;7673.309402153833;7760.146383897767;7761.534307941429;5364.678119312854;5563.13771365439;5582.051051321367;5668.659880407189;5690.2887139107615;8967.641294348154;9086.136817002374;9121.543922803967;9232.769180770534;9357.85963210566;8774.254188394058;9090.181883630505;9197.034311397207;9230.830754772489;9402.15380615748;6497.285298617054;6699.43054840344;6703.4972377070935;6888.724451021903;7084.822879428056;7362.558246505227;7407.518496300654;7431.516951398142;7435.256474352522;7610.086789585285;6825.897611535732;7046.718131274692;7107;7130.714771409085;7206.927930720625;6979.092717946634;6987.7204911802955;7025.051618031501;7074.929250707427;7109.146902371542;6049.818505444844;6242.438180563771;6340.048992651139;6373.553851230369;6396.072569477493;4085.2442298174933;4841.298011788201;4948.084604348207;4978.950210497848;5006.324146240211;4951.282064100757;5007.424146223231;5015.971725796276;5050.39395272555;5061.848144555669 09:22:46 INFO - PROCESS | 1636 | |4;prototype.html;342.2846251334711;347.67839748232876;356.2714249360058;364.1715098151703;364.2969069695487;5207.244949482323;5244.224158605803;5251.422343542207;5276.234945932837;5279.4984476474765;4167.103967694699;4209.86365157957;4946.282788995593;4989.101961646911;5023.799048038074;3226.5482832403363;3336.933261334772;3912.0611053347207;4076.9796151019204;4102.738323033181;3987.401889716551;4009.656764983734;4018.7588744675413;4056.533498647649;4082.714210005286;3666.633336666339;3878.564930975529;3972.682185425159;4048.210598933209;4051.756894586334;3487.1343831050394;3897.5322961244833;3908.921821563553;3910.569837317893;3916.236333914888;2783.3320003199287;2841.9431611367863;3382.222088919538;3397.813120278384;3399.507071474647;4207.957920420788;4227.019950934584;4985.476524964863;5038.622103342264;5107.361579802525;4259.701820872509;4478.350639157336;4503.558861164429;4509.1658043262105;4512.954870451286;5005.974970125162;5432.375276843135;5446.509814116748;5455.645383050089;5460.672359658433;3729.701623870084;3729.9440508392395;3747.3254814133447;3757.5678796938428;3758.289978059976;5328.467153284718;5459.262999495057;5686.86262747448;5745.913811292834;5764.279465066867;3221.8872339468476;3313.4698448248164;3711.3319602471815;3742.9064273392837;3785.167263202091;3393.6086204656467;3718.378543127089;3821.6942644588366;3872.8644497442456;3884.2231553689376;4068.633822955889;4071.3892916062237;4157.8336866525;4365.585269399402;4511.255642818964;5102.571280041594;5418.399168399191;5476.644018138808;5493.7802487900835;5510.834674959758;1177.3289225141586;1177.9810463948795;1238.5169783784152;1245.3586403002462;1247.2204871955028;803.5982008995502;812.7443100409881;832.6877420967137;843.5571325054273;845.2055068235838;4190.554258780716;4767.928481072718;4851.2723091535845;4853.344798452164;4890.679516530517;4639.87051524137;5119.46245644202;5287.651648829601;5331.546818520469;5341.611181092944;4008.1382502762044;4203.243416185055;4300.957100325684;4355.586219309161;4552.658550608718;2326.5579539887704;2436.0888737958326;2463.901443942222;2561.8078644101765;2772.944541109207;5217.660852044605;5258.7370631468275;5396.35913114721;5457.808976685877;5488.063885821067;4490.530208385375;4836.371271734652;4838.564529192389;5078.644494885322;5449.945500545023;4754.619630429557;4788.749287834712;5099.974500127476;5287.629865909349;5501.614886957875;4629.583337499641;4786.545278198566;5087.389513258433;5106.170372220834;5301.284326615858;5075.8071853115225;5132.820351287836;5148.176291793295;5156.61325400604;5215.739213039409;4912.901741965212;5337.599680023936;5518.972405137949;5752.6653516766455;5760.107183386582;3761.868334608385;3771.6982641388076;3818.46541484198;3820.541535015816;3830.9616903830606;4653.557911998355;4848.272759086165;5596.524295434933;5608.747606357805;5695.288088988877;4988.501149885127;5420.828815143028;5428.131498960148;5432.59255555827;5508.228847961369;4513.796879140381;5343.011542864582;5445.5841481214375;5456.48163424474;5468.863278418072;5097.719625420637;5350.3312085989255;5350.491703288181;5355.571554275726;5362.65142765719;5089.51649593288;5252.501012403815;5321.716018604905;5409.647588102975;5422.674639521641;4199.70602057853;4301.591348821857;5219.739013049257;5286.497782710637;5333.866653333697 09:22:46 INFO - PROCESS | 1636 | |5;yui.html;668.254895791193;696.4776417686735;746.87676533372;756.1228974389709;795.9442839001271;14829.480968166103;15679.45121920733;15721.449749258778;15725.854829034182;15729.685406291881;14209.360578773954;14345.641358966031;14356.210408427532;14376.137431754101;14377.568672939802;668.8929771236603;856.0412338181246;870.5299138465222;878.97802554936;879.6217626420637;693.1266604078871;707.8088915992679;837.7821766340734;858.9992658552778;862.1766213266316;930.2650905784423;930.5254320296664;940.2431043010374;942.580551654514;945.5697657565826;933.6218831373338;934.7149119518544;936.3726303376754;936.5457752989795;937.0208132501484;521.2676189953122;540.9594280428984;696.9372756451944;697.3828162076549;698.5110422704128;14082.436702531886;14579.562613121623;14642.780358294527;14971.326290316962;14983.250837458083;13953.930230348884;14098.577042688736;14165.929170354186;14167.149971001772;14221.502247421395;11763.941180294129;11795.646130616009;11872.881271187263;11892.643220703392;12059.819102713467;3624.963750362489;3684.5578530576236;3818.713596480275;3873.1672690371424;3877.0695033191832;100192.9980700191;101196;107399.46300268527;108416;108700;9030.503322317336;9390.436573805593;11111.83332250017;11284.830727539094;11313.038391736794;21245.46886327823;22911.885440573024;22937.082516699145;22980.965856536495;22998.080076797076;6699.196096468455;7683.462157649023;7703.499272547267;7738.380929525624;7832.569208693462;4231.238377092153;4291.69958102936;4306.720063195945;4342.34864770287;4442.3336499524685;3454.101933497309;3506.807125608116;3520.7359448041498;3539.4159963605716;3555.715542756573;639.5587044938977;641.6054126712132;647.3106141958799;656.1830521001384;657.3525077798365;632.7342516143231;641.5990006246096;659.5482094765101;675.5271310082961;685.4995853027318;8644.438111522857;9679.951600241953;10036.698899033041;10351;10410.80557068543;641.2401304454237;657.4050484311706;684.8185230913812;691.8132104331802;692.0864458914185;4922.571028143789;4958.858478136561;4983.127952608279;4989.725565093882;5005.249212618136;98778.62824403479;103265;103876.48061759643;104055;106603;88099;89796;90092;90341.54829225811;90481.07259426561;76307;79612;80674.56289904112;81522.59238703887;83026;8094.352451803724;9295.953520232355;9695.66065187715;9769.609215631295;9793.559289832117;43147;53931;54172.83308667609;54797.45202547924;54927;66371.33628663652;68403.97394038994;69390.65304673444;69805.60388792297;70465.64767176131;11778;11831.230969986924;11974.64076077719;12056.457459414172;12184.451699673713;11587.944309251288;12598.307093109966;12648.557300494807;12846.67883302925;13080;12017.459214335551;12302.938485307874;12513.687157821127;12587.811182832447;12601.369931503204;11915.702107447383;12197.75604487923;12413.069019823368;12591.622251332476;12594.622161335175;11842.940785296018;12064.457099430721;12092.153549251469;12165.939170304091;12472.625821225378;9946.45294508809;10194.337368071052;10304.690859274233;10501.9474902625;10783.245172837826;8584.364264580372;8657.264132548551;9261.398009129385;9773.511324433608;9773.951130244302 09:22:46 INFO - PROCESS | 1636 | __end_tp_report 09:22:46 INFO - PROCESS | 1636 | __start_cc_report 09:22:46 INFO - PROCESS | 1636 | _x_x_mozilla_cycle_collect,1480 09:22:46 INFO - PROCESS | 1636 | __end_cc_report 09:22:46 INFO - PROCESS | 1636 | __startTimestamp1483982566687__endTimestamp 09:22:46 INFO - PROCESS | 1636 | 09:22:46 INFO - PROCESS | 1636 | ------- Summary: start ------- 09:22:46 INFO - PROCESS | 1636 | Number of tests: 6 09:22:46 INFO - PROCESS | 1636 | 09:22:46 INFO - PROCESS | 1636 | [#0] dojo.html Cycles:165 Average:5569.21 Median:5706.51 stddev:633.09 (11.1%) stddev-sans-first:607.98 09:22:46 INFO - PROCESS | 1636 | Values: 3235.4 3242.1 3249.8 3255.9 3311.1 5433.8 5473.9 5474.5 5478.5 5519.7 5474.7 5499.4 5577.9 5578.4 5609.3 4977.6 5035.9 5039.0 5063.9 5119.6 4522.5 4958.0 5069.1 5144.5 5158.4 4951.1 5101.5 5111.4 5139.4 5154.1 5002.3 5055.5 5079.6 5085.3 5107.5 5053.2 5059.2 5075.1 5092.7 5140.9 5537.3 5551.3 5555.9 5583.6 5595.3 5862.6 5915.4 5920.8 6037.0 6092.6 6108.1 6111.2 6137.6 6138.7 6142.8 5673.5 5675.9 5676.8 5690.7 5702.8 6112.0 6116.4 6234.8 6280.9 6298.0 5820.9 5843.5 5853.2 5953.9 5955.1 5931.8 6052.4 6064.4 6091.4 6096.5 5845.1 5859.6 5942.4 5943.0 6044.9 6109.1 6120.2 6123.5 6132.6 6141.0 4801.6 4839.4 4848.3 4880.1 4885.8 4361.9 4381.8 4412.1 4415.0 4417.7 5585.2 5603.4 5606.5 5606.7 5614.4 6103.8 6124.6 6129.3 6135.3 6139.6 5543.2 5596.3 5600.7 5605.9 5625.5 4879.4 5293.1 5307.6 5314.1 5317.2 5775.3 6245.6 6283.5 6292.9 6312.7 6070.3 6084.7 6086.0 6092.4 6106.9 5080.5 6160.8 6200.4 6344.8 6350.9 5942.6 5951.9 5982.9 5996.6 6051.2 6107.9 6113.9 6114.1 6130.6 6135.0 5878.2 5882.6 5888.2 5897.6 5897.9 4542.2 4778.8 5706.5 5773.4 5916.1 5796.9 5805.2 5808.1 5808.2 5828.0 4982.5 5936.2 5957.3 5958.8 5978.2 5639.4 5691.7 5700.1 5710.0 5780.5 09:22:46 INFO - PROCESS | 1636 | 09:22:46 INFO - PROCESS | 1636 | [#1] ext.html Cycles:180 Average:70765.83 Median:33616.00 stddev:162250.74 (482.7%) stddev-sans-first:162620.10 09:22:46 INFO - PROCESS | 1636 | Values: 489.6 500.9 507.8 508.3 538.7 24442.6 39090.0 40983.0 41052.8 43422.3 41915.6 42227.6 42268.0 42300.0 42335.6 8534.0 9344.2 9532.6 9544.9 9549.0 8453.1 11246.4 11385.0 11434.0 11606.8 11350.2 11415.0 11437.6 11443.0 11445.5 11475.8 11508.8 11528.5 11538.4 11561.7 11316.8 11368.3 11378.7 11399.8 11418.1 39386.6 39667.2 39693.0 39707.0 39748.2 3954.4 3984.7 4022.7 4037.9 4124.3 29408.7 30250.2 30264.5 30654.4 30752.4 3135.9 3268.6 3281.4 3330.3 3333.6 476117.0 476251.0 479491.0 480750.0 481414.0 60550.7 60801.4 60878.4 60927.4 60995.7 64100.0 64146.0 64226.7 64264.4 64291.4 13556.8 15469.9 16058.6 16079.6 16099.0 11130.7 11414.9 12268.9 12385.6 12421.8 17161.9 17574.9 17880.5 17978.0 18032.8 6203.9 6481.3 6723.6 6857.3 6869.6 861.5 871.7 872.5 879.3 894.1 28734.3 29424.3 29491.3 29524.0 30095.5 1018.5 1028.9 1059.3 1071.7 1191.3 14441.4 14695.2 15416.6 15464.5 15480.3 586983.0 593728.0 821239.0 840458.0 841100.0 44530.8 50576.5 51320.5 52201.7 55532.2 1394.1 1410.6 1422.0 1430.5 1437.8 27966.0 27971.4 28069.7 28778.4 28817.8 46086.8 46441.8 46487.1 46608.0 46671.0 552183.0 553786.0 555438.4 556088.0 557244.0 23883.3 24036.3 24288.9 24297.8 24372.4 31791.7 40222.4 41826.6 41866.3 41950.8 32761.5 33473.2 33543.0 34048.0 34870.8 33321.0 33689.0 33715.0 34824.0 34994.7 33120.5 33775.6 33813.8 33912.0 33971.8 23115.0 23703.0 28897.8 29140.3 30517.9 19245.2 22483.0 23135.9 23225.7 23436.5 09:22:46 INFO - PROCESS | 1636 | 09:22:46 INFO - PROCESS | 1636 | [#2] jquery.html Cycles:180 Average:39122.28 Median:26198.15 stddev:119617.12 (456.6%) stddev-sans-first:119917.27 09:22:46 INFO - PROCESS | 1636 | Values: 364.0 375.5 379.6 386.1 408.1 15515.1 15622.9 18543.7 21060.9 21090.2 20797.8 21725.2 21768.3 21809.9 22290.0 4626.6 5871.9 5923.1 5930.4 6167.6 5977.6 5998.3 6038.8 6045.2 6054.3 4198.5 4283.3 4383.4 4487.5 6627.1 5708.9 6656.2 6678.8 6691.0 6700.7 4657.9 5801.7 5858.4 5860.7 5863.2 25676.2 26168.0 26181.3 26215.0 26347.9 2635.7 2762.9 2764.8 2770.5 2801.9 10391.8 10573.5 14899.3 15228.8 15286.8 1852.1 1859.1 1874.4 1879.6 1926.3 119405.8 121679.2 131767.0 136890.0 137664.0 11642.2 11704.8 11867.6 11874.7 12052.8 15279.2 15300.3 15309.3 15323.4 15324.1 8286.3 8502.7 8505.4 8632.1 8671.1 8294.4 8297.3 8315.0 8460.7 8474.5 4642.7 4649.7 4665.7 4668.5 4729.3 1670.1 1774.3 1918.1 1949.4 1997.3 840.9 983.6 988.4 1014.6 1020.9 19679.7 21290.8 21362.6 21502.9 21652.7 998.6 999.3 1032.7 1040.8 1043.2 10759.4 11043.3 11180.7 11187.0 11187.5 687483.0 719100.0 722333.0 727281.0 744923.0 115784.4 130272.2 135980.3 139734.0 149282.0 940.4 955.0 983.2 986.6 987.0 14989.0 15262.9 16799.6 18628.2 19475.0 55892.2 57233.0 57543.0 58399.7 58641.1 75523.0 76726.0 76819.6 76980.5 78860.0 8147.4 8348.7 8433.1 8473.7 8491.0 10618.6 13083.3 13398.6 13419.3 13579.0 11275.4 11287.4 11469.9 11501.9 11510.3 10932.0 10999.4 11087.5 11341.2 11352.5 11138.7 11540.5 11682.1 11744.8 11748.4 8135.8 8153.2 8389.2 8613.0 8640.0 9001.0 9586.8 10842.2 11448.4 11475.0 09:22:46 INFO - PROCESS | 1636 | 09:22:46 INFO - PROCESS | 1636 | [#3] mootools.html Cycles:180 Average:5351.77 Median:5269.07 stddev:2909.52 (55.2%) stddev-sans-first:2902.65 09:22:46 INFO - PROCESS | 1636 | Values: 1416.5 1427.5 1429.2 1433.7 1438.0 7945.4 8247.7 8262.7 8369.2 8371.7 7348.7 7583.2 7583.6 7959.8 8178.6 2267.8 2286.6 2317.9 2320.9 2323.7 2298.2 2322.3 2331.1 2335.2 2347.5 1677.4 2118.9 2228.9 2301.4 2338.2 2277.4 2279.0 2282.5 2285.9 2289.4 2262.3 2275.3 2279.2 2285.2 2287.3 8110.5 8305.8 8310.4 8310.6 8323.3 4997.6 5145.0 5149.8 5163.4 5167.1 3280.9 3394.6 3452.0 3471.0 3546.1 1168.1 1231.7 1249.5 1259.0 1280.5 10627.9 10644.9 10745.8 10772.4 10855.9 3693.6 4527.4 4554.1 4670.5 4687.8 9234.6 9239.4 9265.8 9266.3 9271.1 5144.3 5145.2 5157.5 5170.8 5173.5 3870.9 4216.5 4730.1 4730.6 4735.8 3727.3 3748.7 3807.1 3835.8 3851.5 1029.2 1219.5 1220.5 1227.9 1230.5 837.0 858.5 864.3 887.1 904.2 5477.9 5517.6 5693.3 5733.3 5987.9 952.8 967.2 970.9 976.8 1045.9 3602.4 3677.4 3732.4 3774.7 3809.3 9594.0 9840.6 9874.1 9939.9 9950.1 9893.7 9908.5 9916.2 9923.9 9928.5 7257.6 7571.1 7673.3 7760.1 7761.5 5364.7 5563.1 5582.1 5668.7 5690.3 8967.6 9086.1 9121.5 9232.8 9357.9 8774.3 9090.2 9197.0 9230.8 9402.2 6497.3 6699.4 6703.5 6888.7 7084.8 7362.6 7407.5 7431.5 7435.3 7610.1 6825.9 7046.7 7107.0 7130.7 7206.9 6979.1 6987.7 7025.1 7074.9 7109.1 6049.8 6242.4 6340.0 6373.6 6396.1 4085.2 4841.3 4948.1 4979.0 5006.3 4951.3 5007.4 5016.0 5050.4 5061.8 09:22:46 INFO - PROCESS | 1636 | 09:22:46 INFO - PROCESS | 1636 | [#4] prototype.html Cycles:180 Average:4293.43 Median:4812.56 stddev:1315.15 (27.3%) stddev-sans-first:1284.97 09:22:46 INFO - PROCESS | 1636 | Values: 342.3 347.7 356.3 364.2 364.3 5207.2 5244.2 5251.4 5276.2 5279.5 4167.1 4209.9 4946.3 4989.1 5023.8 3226.5 3336.9 3912.1 4077.0 4102.7 3987.4 4009.7 4018.8 4056.5 4082.7 3666.6 3878.6 3972.7 4048.2 4051.8 3487.1 3897.5 3908.9 3910.6 3916.2 2783.3 2841.9 3382.2 3397.8 3399.5 4208.0 4227.0 4985.5 5038.6 5107.4 4259.7 4478.4 4503.6 4509.2 4513.0 5006.0 5432.4 5446.5 5455.6 5460.7 3729.7 3729.9 3747.3 3757.6 3758.3 5328.5 5459.3 5686.9 5745.9 5764.3 3221.9 3313.5 3711.3 3742.9 3785.2 3393.6 3718.4 3821.7 3872.9 3884.2 4068.6 4071.4 4157.8 4365.6 4511.3 5102.6 5418.4 5476.6 5493.8 5510.8 1177.3 1178.0 1238.5 1245.4 1247.2 803.6 812.7 832.7 843.6 845.2 4190.6 4767.9 4851.3 4853.3 4890.7 4639.9 5119.5 5287.7 5331.5 5341.6 4008.1 4203.2 4301.0 4355.6 4552.7 2326.6 2436.1 2463.9 2561.8 2772.9 5217.7 5258.7 5396.4 5457.8 5488.1 4490.5 4836.4 4838.6 5078.6 5449.9 4754.6 4788.7 5100.0 5287.6 5501.6 4629.6 4786.5 5087.4 5106.2 5301.3 5075.8 5132.8 5148.2 5156.6 5215.7 4912.9 5337.6 5519.0 5752.7 5760.1 3761.9 3771.7 3818.5 3820.5 3831.0 4653.6 4848.3 5596.5 5608.7 5695.3 4988.5 5420.8 5428.1 5432.6 5508.2 4513.8 5343.0 5445.6 5456.5 5468.9 5097.7 5350.3 5350.5 5355.6 5362.7 5089.5 5252.5 5321.7 5409.6 5422.7 4199.7 4301.6 5219.7 5286.5 5333.9 09:22:46 INFO - PROCESS | 1636 | 09:22:46 INFO - PROCESS | 1636 | [#5] yui.html Cycles:180 Average:20394.21 Median:4940.71 stddev:29739.71 (601.9%) stddev-sans-first:29786.25 09:22:46 INFO - PROCESS | 1636 | Values: 668.3 696.5 746.9 756.1 795.9 14829.5 15679.5 15721.4 15725.9 15729.7 14209.4 14345.6 14356.2 14376.1 14377.6 668.9 856.0 870.5 879.0 879.6 693.1 707.8 837.8 859.0 862.2 930.3 930.5 940.2 942.6 945.6 933.6 934.7 936.4 936.5 937.0 521.3 541.0 696.9 697.4 698.5 14082.4 14579.6 14642.8 14971.3 14983.3 13953.9 14098.6 14165.9 14167.1 14221.5 11763.9 11795.6 11872.9 11892.6 12059.8 3625.0 3684.6 3818.7 3873.2 3877.1 100193.0 101196.0 107399.5 108416.0 108700.0 9030.5 9390.4 11111.8 11284.8 11313.0 21245.5 22911.9 22937.1 22981.0 22998.1 6699.2 7683.5 7703.5 7738.4 7832.6 4231.2 4291.7 4306.7 4342.3 4442.3 3454.1 3506.8 3520.7 3539.4 3555.7 639.6 641.6 647.3 656.2 657.4 632.7 641.6 659.5 675.5 685.5 8644.4 9680.0 10036.7 10351.0 10410.8 641.2 657.4 684.8 691.8 692.1 4922.6 4958.9 4983.1 4989.7 5005.2 98778.6 103265.0 103876.5 104055.0 106603.0 88099.0 89796.0 90092.0 90341.5 90481.1 76307.0 79612.0 80674.6 81522.6 83026.0 8094.4 9296.0 9695.7 9769.6 9793.6 43147.0 53931.0 54172.8 54797.5 54927.0 66371.3 68404.0 69390.7 69805.6 70465.6 11778.0 11831.2 11974.6 12056.5 12184.5 11587.9 12598.3 12648.6 12846.7 13080.0 12017.5 12302.9 12513.7 12587.8 12601.4 11915.7 12197.8 12413.1 12591.6 12594.6 11842.9 12064.5 12092.2 12165.9 12472.6 9946.5 10194.3 10304.7 10501.9 10783.2 8584.4 8657.3 9261.4 9773.5 9774.0 09:22:46 INFO - PROCESS | 1636 | -------- Summary: end -------- 09:22:46 INFO - PROCESS | 1636 | 09:22:47 INFO - TEST-INFO | 1636: exit 0 09:22:47 INFO - TEST-OK | dromaeo_css | took 1119022ms 09:22:47 INFO - TEST-START | kraken 09:22:47 INFO - operating with platform_type : mac_ 09:22:47 INFO - Initialising browser for kraken test... 09:22:47 INFO - TEST-INFO | started process 1645 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/tmp_tTQf9/profile http://localhost:49241/getInfo.html) 09:22:47 INFO - PROCESS | 1645 | 1483982567834 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:4907 < processFileChanges()@resource://gre/modules/addons/XPIProvider.jsm -> resource://gre/modules/addons/XPIProviderUtils.js:2060 < checkForChanges()@resource://gre/modules/addons/XPIProvider.jsm:3762 < startup()@resource://gre/modules/addons/XPIProvider.jsm:2795 < callProvider()@resource://gre/modules/AddonManager.jsm:267 < _startProvider()@resource://gre/modules/AddonManager.jsm:973 < startup()@resource://gre/modules/AddonManager.jsm:1155 < startup()@resource://gre/modules/AddonManager.jsm:3124 < observe()@resource://gre/components/addonManager.js:65 09:22:49 INFO - PROCESS | 1645 | __metrics Screen width/height:1600/1200 09:22:49 INFO - PROCESS | 1645 | colorDepth:24 09:22:49 INFO - PROCESS | 1645 | Browser inner width/height: 1024/689 09:22:49 INFO - PROCESS | 1645 | __metrics 09:22:49 INFO - PROCESS | 1645 | 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" 09:22:49 INFO - PROCESS | 1645 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:22:50 INFO - TEST-INFO | 1645: exit 0 09:22:50 INFO - Browser initialized. 09:22:50 INFO - Running cycle 1/1 for kraken test... 09:22:50 INFO - TEST-INFO | started process 1646 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/tmp_tTQf9/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:23:02 INFO - PROCESS | 1646 | RSS: Main: 226316288 09:23:02 INFO - PROCESS | 1646 | 09:23:05 INFO - PROCESS | 1646 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49241/tests/kraken/driver.html?testName=audio-beat-detection) 09:23:06 INFO - PROCESS | 1646 | RSS: Main: 286085120 09:23:06 INFO - PROCESS | 1646 | 09:23:08 INFO - PROCESS | 1646 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49241/tests/kraken/driver.html?testName=audio-dft) 09:23:09 INFO - PROCESS | 1646 | RSS: Main: 287563776 09:23:09 INFO - PROCESS | 1646 | 09:23:12 INFO - PROCESS | 1646 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49241/tests/kraken/driver.html?testName=audio-fft) 09:23:13 INFO - PROCESS | 1646 | RSS: Main: 378859520 09:23:13 INFO - PROCESS | 1646 | 09:23:15 INFO - PROCESS | 1646 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49241/tests/kraken/driver.html?testName=audio-oscillator) 09:23:15 INFO - PROCESS | 1646 | RSS: Main: 283144192 09:23:15 INFO - PROCESS | 1646 | 09:23:18 INFO - PROCESS | 1646 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49241/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:23:18 INFO - PROCESS | 1646 | RSS: Main: 285773824 09:23:18 INFO - PROCESS | 1646 | 09:23:21 INFO - PROCESS | 1646 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49241/tests/kraken/driver.html?testName=imaging-darkroom) 09:23:22 INFO - PROCESS | 1646 | RSS: Main: 328032256 09:23:22 INFO - PROCESS | 1646 | 09:23:25 INFO - PROCESS | 1646 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49241/tests/kraken/driver.html?testName=imaging-desaturate) 09:23:25 INFO - PROCESS | 1646 | RSS: Main: 328167424 09:23:25 INFO - PROCESS | 1646 | 09:23:28 INFO - PROCESS | 1646 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49241/tests/kraken/driver.html?testName=json-parse-financial) 09:23:29 INFO - PROCESS | 1646 | RSS: Main: 328126464 09:23:29 INFO - PROCESS | 1646 | 09:23:30 INFO - PROCESS | 1646 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49241/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:23:31 INFO - PROCESS | 1646 | RSS: Main: 302804992 09:23:31 INFO - PROCESS | 1646 | 09:23:33 INFO - PROCESS | 1646 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:23:34 INFO - PROCESS | 1646 | RSS: Main: 306393088 09:23:34 INFO - PROCESS | 1646 | 09:23:36 INFO - PROCESS | 1646 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:23:36 INFO - PROCESS | 1646 | RSS: Main: 307662848 09:23:36 INFO - PROCESS | 1646 | 09:23:39 INFO - PROCESS | 1646 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:23:40 INFO - PROCESS | 1646 | RSS: Main: 323231744 09:23:40 INFO - PROCESS | 1646 | 09:23:43 INFO - PROCESS | 1646 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:23:44 INFO - PROCESS | 1646 | RSS: Main: 317329408 09:23:44 INFO - PROCESS | 1646 | 09:23:46 INFO - PROCESS | 1646 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49241/tests/kraken/driver.html?testName=ai-astar) 09:23:46 INFO - PROCESS | 1646 | RSS: Main: 361689088 09:23:46 INFO - PROCESS | 1646 | 09:23:46 INFO - PROCESS | 1646 | __start_tp_report 09:23:46 INFO - PROCESS | 1646 | _x_x_mozilla_page_load 09:23:46 INFO - PROCESS | 1646 | _x_x_mozilla_page_load_details 09:23:46 INFO - PROCESS | 1646 | |i|pagename|runs| 09:23:46 INFO - PROCESS | 1646 | |0;ai-astar;110;114;114;113;113;115;114;113;113;113 09:23:46 INFO - PROCESS | 1646 | |1;audio-beat-detection;141;111;112;173;139;138;107;107;128;109 09:23:46 INFO - PROCESS | 1646 | |2;audio-dft;185;184;183;185;182;182;179;182;182;183 09:23:46 INFO - PROCESS | 1646 | |3;audio-fft;78;57;76;112;85;58;58;58;74;58 09:23:46 INFO - PROCESS | 1646 | |4;audio-oscillator;90;69;87;70;70;70;96;92;69;84 09:23:46 INFO - PROCESS | 1646 | |5;imaging-gaussian-blur;88;87;89;87;89;130;89;87;89;86 09:23:46 INFO - PROCESS | 1646 | |6;imaging-darkroom;95;96;95;98;94;95;95;97;94;94 09:23:46 INFO - PROCESS | 1646 | |7;imaging-desaturate;85;93;129;84;97;114;89;86;86;86 09:23:46 INFO - PROCESS | 1646 | |8;json-parse-financial;57;57;57;57;55;55;56;55;56;56 09:23:46 INFO - PROCESS | 1646 | |9;json-stringify-tinderbox;66;67;82;77;73;84;103;103;88;71 09:23:46 INFO - PROCESS | 1646 | |10;stanford-crypto-aes;91;87;88;86;87;87;85;87;87;86 09:23:46 INFO - PROCESS | 1646 | |11;stanford-crypto-ccm;138;139;161;145;153;135;139;152;205;171 09:23:46 INFO - PROCESS | 1646 | |12;stanford-crypto-pbkdf2;234;170;173;224;219;169;225;221;170;170 09:23:46 INFO - PROCESS | 1646 | |13;stanford-crypto-sha256-iterative;67;68;67;68;67;68;67;68;65;66 09:23:46 INFO - PROCESS | 1646 | __end_tp_report 09:23:46 INFO - PROCESS | 1646 | __start_cc_report 09:23:46 INFO - PROCESS | 1646 | _x_x_mozilla_cycle_collect,1993 09:23:46 INFO - PROCESS | 1646 | __end_cc_report 09:23:46 INFO - PROCESS | 1646 | __startTimestamp1483982626442__endTimestamp 09:23:46 INFO - PROCESS | 1646 | 09:23:46 INFO - PROCESS | 1646 | ------- Summary: start ------- 09:23:46 INFO - PROCESS | 1646 | Number of tests: 14 09:23:46 INFO - PROCESS | 1646 | 09:23:46 INFO - PROCESS | 1646 | [#0] ai-astar Cycles:10 Average:113.20 Median:113.50 stddev:1.32 (1.2%) stddev-sans-first:0.73 09:23:46 INFO - PROCESS | 1646 | Values: 110.0 114.0 114.0 113.0 113.0 115.0 114.0 113.0 113.0 113.0 09:23:46 INFO - PROCESS | 1646 | 09:23:46 INFO - PROCESS | 1646 | [#1] audio-beat-detection Cycles:10 Average:126.50 Median:133.00 stddev:21.55 (16.2%) stddev-sans-first:22.21 09:23:46 INFO - PROCESS | 1646 | Values: 141.0 111.0 112.0 173.0 139.0 138.0 107.0 107.0 128.0 109.0 09:23:46 INFO - PROCESS | 1646 | 09:23:46 INFO - PROCESS | 1646 | [#2] audio-dft Cycles:10 Average:182.70 Median:183.00 stddev:1.77 (1.0%) stddev-sans-first:1.67 09:23:46 INFO - PROCESS | 1646 | Values: 185.0 184.0 183.0 185.0 182.0 182.0 179.0 182.0 182.0 183.0 09:23:46 INFO - PROCESS | 1646 | 09:23:46 INFO - PROCESS | 1646 | [#3] audio-fft Cycles:10 Average:71.40 Median:66.00 stddev:17.73 (26.9%) stddev-sans-first:18.65 09:23:46 INFO - PROCESS | 1646 | Values: 78.0 57.0 76.0 112.0 85.0 58.0 58.0 58.0 74.0 58.0 09:23:46 INFO - PROCESS | 1646 | 09:23:46 INFO - PROCESS | 1646 | [#4] audio-oscillator Cycles:10 Average:79.70 Median:85.50 stddev:11.09 (13.0%) stddev-sans-first:11.11 09:23:46 INFO - PROCESS | 1646 | Values: 90.0 69.0 87.0 70.0 70.0 70.0 96.0 92.0 69.0 84.0 09:23:46 INFO - PROCESS | 1646 | 09:23:46 INFO - PROCESS | 1646 | [#5] imaging-gaussian-blur Cycles:10 Average:92.10 Median:88.50 stddev:13.36 (15.1%) stddev-sans-first:14.09 09:23:46 INFO - PROCESS | 1646 | Values: 88.0 87.0 89.0 87.0 89.0 130.0 89.0 87.0 89.0 86.0 09:23:46 INFO - PROCESS | 1646 | 09:23:46 INFO - PROCESS | 1646 | [#6] imaging-darkroom Cycles:10 Average:95.30 Median:95.00 stddev:1.34 (1.4%) stddev-sans-first:1.41 09:23:46 INFO - PROCESS | 1646 | Values: 95.0 96.0 95.0 98.0 94.0 95.0 95.0 97.0 94.0 94.0 09:23:46 INFO - PROCESS | 1646 | 09:23:46 INFO - PROCESS | 1646 | [#7] imaging-desaturate Cycles:10 Average:94.90 Median:86.00 stddev:15.00 (17.4%) stddev-sans-first:15.48 09:23:46 INFO - PROCESS | 1646 | Values: 85.0 93.0 129.0 84.0 97.0 114.0 89.0 86.0 86.0 86.0 09:23:46 INFO - PROCESS | 1646 | 09:23:46 INFO - PROCESS | 1646 | [#8] json-parse-financial Cycles:10 Average:56.10 Median:56.50 stddev:0.88 (1.5%) stddev-sans-first:0.87 09:23:46 INFO - PROCESS | 1646 | Values: 57.0 57.0 57.0 57.0 55.0 55.0 56.0 55.0 56.0 56.0 09:23:46 INFO - PROCESS | 1646 | 09:23:46 INFO - PROCESS | 1646 | [#9] json-stringify-tinderbox Cycles:10 Average:81.40 Median:75.00 stddev:13.44 (17.9%) stddev-sans-first:13.05 09:23:46 INFO - PROCESS | 1646 | Values: 66.0 67.0 82.0 77.0 73.0 84.0 103.0 103.0 88.0 71.0 09:23:46 INFO - PROCESS | 1646 | 09:23:46 INFO - PROCESS | 1646 | [#10] stanford-crypto-aes Cycles:10 Average:87.10 Median:87.00 stddev:1.60 (1.8%) stddev-sans-first:0.87 09:23:46 INFO - PROCESS | 1646 | Values: 91.0 87.0 88.0 86.0 87.0 87.0 85.0 87.0 87.0 86.0 09:23:46 INFO - PROCESS | 1646 | 09:23:46 INFO - PROCESS | 1646 | [#11] stanford-crypto-ccm Cycles:10 Average:153.80 Median:152.50 stddev:21.32 (14.0%) stddev-sans-first:21.84 09:23:46 INFO - PROCESS | 1646 | Values: 138.0 139.0 161.0 145.0 153.0 135.0 139.0 152.0 205.0 171.0 09:23:46 INFO - PROCESS | 1646 | 09:23:46 INFO - PROCESS | 1646 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:197.50 Median:220.00 stddev:28.84 (13.1%) stddev-sans-first:27.40 09:23:46 INFO - PROCESS | 1646 | Values: 234.0 170.0 173.0 224.0 219.0 169.0 225.0 221.0 170.0 170.0 09:23:46 INFO - PROCESS | 1646 | 09:23:46 INFO - PROCESS | 1646 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.10 Median:67.50 stddev:0.99 (1.5%) stddev-sans-first:1.05 09:23:46 INFO - PROCESS | 1646 | Values: 67.0 68.0 67.0 68.0 67.0 68.0 67.0 68.0 65.0 66.0 09:23:46 INFO - PROCESS | 1646 | -------- Summary: end -------- 09:23:46 INFO - PROCESS | 1646 | 09:23:47 INFO - TEST-INFO | 1646: exit 0 09:23:47 INFO - TEST-OK | kraken | took 60016ms 09:23:47 INFO - SUITE-END | took 1179s 09:23:47 INFO - Completed test suite (00:19:39) 09:23:47 INFO - javascript benchmark 09:23:47 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3235.36910302491, 3242.1084201844506, 3249.756268279878, 3255.9023229303116, 3311.0894504011394, 5433.8369848904595, 5473.917891231625, 5474.47992440718, 5478.45215478452, 5519.724013799309, 5474.671519708821, 5499.3675727291375, 5577.9163312550345, 5578.44215578442, 5609.270794796671, 4977.601791856643, 5035.899282014358, 5038.974805125988, 5063.949360506384, 5119.641625086246, 4522.45730512339, 4958.0, 5069.112905241568, 5144.485551444863, 5158.3552055993005, 4951.133551628451, 5101.515356041189, 5111.412187598399, 5139.408967968694, 5154.07226699194, 5002.349694539723, 5055.494450554952, 5079.568236699885, 5085.313482679828, 5107.540321371057, 5053.216751403502, 5059.19052951526, 5075.086484432801, 5092.668976516515, 5140.897182056376, 5537.307836520435, 5551.278333816618, 5555.916661250085, 5583.637063590853, 5595.300587426572, 5862.618929769551, 5915.438033386821, 5920.763169473259, 6037.003894357425, 6092.573519853612, 6108.137390846266, 6111.174991376151, 6137.631742095444, 6138.664296166084, 6142.78500252489, 5673.489385955284, 5675.858103547444, 5676.801311954103, 5690.7439165237665, 5702.8289151325525, 6112.0, 6116.366205733216, 6234.781782637586, 6280.905786413207, 6298.0, 5820.912686309709, 5843.503302219274, 5853.209816674779, 5953.880922381528, 5955.076963070731, 5931.7923872665115, 6052.394760524, 6064.36324185963, 6091.390860913873, 6096.451319381277, 5845.064789633639, 5859.560532960045, 5942.37605051472, 5943.0, 6044.909326360108, 6109.114178444101, 6120.204373431426, 6123.475981923542, 6132.570720049643, 6140.969295153495, 4801.615870730403, 4839.370881785346, 4848.272759086165, 4880.145974454456, 4885.827401423633, 4361.890952726208, 4381.824727010948, 4412.051408947092, 4414.977925110354, 4417.712848664826, 5585.218069470277, 5603.439656034364, 5606.467385598362, 5606.747696353674, 5614.382417934024, 6103.84740381494, 6124.601900876429, 6129.264488261437, 6135.263768347827, 6139.57023008394, 5543.1685247213245, 5596.3284405871555, 5600.691961942132, 5605.943940560624, 5625.521830644359, 4879.41807059231, 5293.073712100367, 5307.62846600742, 5314.096603577401, 5317.175837745156, 5775.335836378787, 6245.5628106032145, 6283.465905398092, 6292.874142517033, 6312.747490100436, 6070.271567411939, 6084.665343406155, 6085.969570152298, 6092.390760923872, 6106.9083963739695, 5080.542751152266, 6160.845978850564, 6200.441960223421, 6344.777932772331, 6350.936490635219, 5942.64344139353, 5951.851203719942, 5982.910256346071, 5996.5502587307, 6051.243594550681, 6107.877842443215, 6113.938860611515, 6114.113453549299, 6130.570860039755, 6134.957057300359, 5878.2064421302575, 5882.558808089325, 5888.234529511136, 5897.587168898136, 5897.852553686193, 4542.227821270458, 4778.761061946958, 5706.514946229451, 5773.422657734193, 5916.053431450947, 5796.94203057964, 5805.158252053513, 5808.099744539603, 5808.157817116579, 5828.0, 4982.526659967298, 5936.1986131871645, 5957.285125784933, 5958.791442299499, 5978.163057172086, 5639.407862174577, 5691.658500489983, 5700.059490184073, 5709.9714501427225, 5780.479756821906], "value": 5529.599715587435, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [489.6278828090651, 500.9398872135343, 507.82733870484026, 508.26809394471934, 538.6794857060048, 24442.633360499607, 39090.0, 40982.99999999996, 41052.79473602628, 43422.348664770056, 41915.58084419155, 42227.57772422275, 42268.0, 42300.0, 42335.57664423355, 8533.957330213372, 9344.15902568767, 9532.616845788552, 9544.90455095447, 9548.952255238713, 8453.070162282143, 11246.437678116103, 11385.032272256869, 11434.0, 11606.825897611501, 11350.20548561601, 11414.972652461276, 11437.599684011062, 11443.027342675883, 11445.542178312857, 11475.827862582068, 11508.769824603545, 11528.538858445652, 11538.423078846108, 11561.710957226052, 11316.830247546293, 11368.317900925971, 11378.658640240807, 11399.829002564968, 11418.143639227092, 39386.60613393858, 39667.20665586701, 39693.00767480836, 39707.00732481682, 39748.20503589912, 3954.3574667397306, 3984.7409918355506, 4022.73852199609, 4037.858674946392, 4124.257633625946, 29408.70591294081, 30250.243743906358, 30264.54603180932, 30654.386912261853, 30752.38495230083, 3135.890243841455, 3268.6404495505476, 3281.442154833684, 3330.283988942365, 3333.5666363372848, 476116.9999999965, 476251.0, 479491.0, 480750.0, 481413.9999999965, 60550.69724651348, 60801.39198608046, 60878.39121608727, 60927.39072609217, 60995.6950215255, 64100.038499422546, 64146.0, 64226.6788666063, 64264.35735642584, 64291.357086429474, 13556.796648050287, 15469.922650386676, 16058.598535036717, 16079.598010049842, 16098.953568018042, 11130.666080017612, 11414.94292528532, 12268.938655306845, 12385.62843114708, 12421.813672794915, 17161.91419042922, 17574.912125439292, 17880.46358609244, 17978.03362445182, 18032.819671803114, 6203.883301005862, 6481.254655714653, 6723.596584204964, 6857.314268573203, 6869.622170780555, 861.5132450165762, 871.6556960000764, 872.4590753732599, 879.2921698033144, 894.141624040915, 28734.27594344304, 29424.26439338991, 29491.262718431783, 29524.0, 30095.548566771515, 1018.5111146649501, 1028.8559601655775, 1059.2585190366774, 1071.7267096890398, 1191.3209470601673, 14441.35013924376, 14695.191764453059, 15416.614584635474, 15464.5360639181, 15480.303386347641, 586983.0, 593728.0, 821239.0, 840458.0, 841100.0, 44530.77734611371, 50576.494235057915, 51320.48679513157, 52201.73899130556, 55532.16701749477, 1394.079907261223, 1410.5627255550812, 1422.0, 1430.5279257844886, 1437.8202724659418, 27966.0, 27971.44057118887, 28069.719302807527, 28778.424431510834, 28817.84728610832, 46086.769566151954, 46441.767791160826, 46487.07025859532, 46608.0, 46671.0, 552183.0, 553786.0, 555438.4456155386, 556088.0498742535, 557244.0, 23883.28350149498, 24036.27891163268, 24288.87855560711, 24297.757022430258, 24372.390690232176, 31791.682083178872, 40222.39666404948, 41826.58173418227, 41866.3480430138, 41950.79024604979, 32761.508577371835, 33473.15897625654, 33542.99371018873, 34048.0, 34870.779522716584, 33321.0, 33689.0, 33715.0, 34824.0, 34994.650053499136, 33120.50319245261, 33775.64897404076, 33813.830930846176, 33912.0, 33971.83014085013, 23115.0, 23703.0, 28897.79839628887, 29140.27149321284, 30517.93187238525, 19245.230190792212, 22482.988265528416, 23135.88432057829, 23225.651615225448, 23436.531269374856], "value": 19518.377367402147, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [363.96906262967644, 375.5287114671088, 379.614691088545, 386.1446895127293, 408.10217521452813, 15515.146666933315, 15622.921885390571, 18543.72184417235, 21060.89469552654, 21090.15639374427, 20797.792022079775, 21725.23961661342, 21768.346949591498, 21809.890950545265, 22290.0, 4626.606738427237, 5871.853203669921, 5923.111533270023, 5930.436608522195, 6167.62994220346, 5977.5815692901515, 5998.310194327642, 6038.788642397517, 6045.183900173486, 6054.334023257438, 4198.496180458349, 4283.341065896399, 4383.3863259143745, 4487.483939346968, 6627.072209890619, 5708.914366284467, 6656.234533028715, 6678.799636010927, 6690.966545167292, 6700.731970721214, 4657.86026419208, 5801.7099145042575, 5858.443447872488, 5860.70696465175, 5863.208466857005, 25676.229713108638, 26168.0, 26181.345466363302, 26214.9514019439, 26347.868260658764, 2635.736426357368, 2762.9171124866084, 2764.8202866813594, 2770.542860428027, 2801.8739156737997, 10391.84412233817, 10573.524191411405, 14899.255037248095, 15228.847711522854, 15286.770698439532, 1852.110986726379, 1859.1076283383916, 1874.4095609882843, 1879.642867855103, 1926.3450426854797, 119405.80594194034, 121679.174812377, 131767.0, 136890.0, 137664.0, 11642.24325418845, 11704.765904681859, 11867.58463453775, 11874.703132421759, 12052.75894482123, 15279.159646219343, 15300.311485983157, 15309.311081001382, 15323.387064517518, 15324.080555166725, 8286.318055860122, 8502.74491765248, 8505.447145935617, 8632.093630168867, 8671.08953559867, 8294.419390642597, 8297.336213102937, 8315.04376996653, 8460.746177614681, 8474.491530508189, 4642.65180111493, 4649.721016739007, 4665.650076244227, 4668.50980647034, 4729.290606409066, 1670.13987796285, 1774.2636805725479, 1918.1202421626883, 1949.3567122849427, 1997.3209108903043, 840.8822764812932, 983.5623147699205, 988.4464699768153, 1014.6499457687086, 1020.8821340632145, 19679.704804427944, 21290.78709212919, 21362.572748544942, 21502.892485537785, 21652.675209871864, 998.6354980432089, 999.2655398282257, 1032.695354870315, 1040.8074506216374, 1043.1967385113421, 10759.3544387337, 11043.337399756041, 11180.664580062612, 11187.0, 11187.4965626547, 687483.0, 719100.0, 722333.0, 727281.0, 744923.0, 115784.42107789408, 130272.17847637906, 135980.32009839886, 139734.0, 149282.0, 940.3887473142512, 954.9665761698446, 983.179045497018, 986.6448078691665, 986.9851952220722, 14989.0, 15262.923685381502, 16799.580010499834, 18628.161732722332, 19475.0, 55892.16161757658, 57233.0, 57543.0, 58399.70800145972, 58641.12038319343, 75523.0, 76726.0, 76819.61590192237, 76980.46039079074, 78860.0, 8147.388945829205, 8348.66605335804, 8433.072362040048, 8473.703420380258, 8491.0, 10618.575256989634, 13083.280419577015, 13398.598042058753, 13419.329033548478, 13579.0, 11275.436228188393, 11287.41976123327, 11469.942650286695, 11501.942490287494, 11510.251833630788, 10932.0, 10999.395033273247, 11087.501062452047, 11341.206115571831, 11352.489137988645, 11138.665840024812, 11540.480678369662, 11682.123840712151, 11744.76510469803, 11748.412579370826, 8135.75592732219, 8153.22544358285, 8389.244967952669, 8613.0, 8639.95680021596, 9000.954995224982, 9586.801649793775, 10842.186835987175, 11448.3703396314, 11475.0], "value": 9832.123837744943, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1416.4688241909284, 1427.4718354208944, 1429.1925062339772, 1433.670255841157, 1438.02214494144, 7945.4438189326775, 8247.67009319628, 8262.710805121822, 8369.246767790897, 8371.706990255343, 7348.706051757923, 7583.241675832414, 7583.58290294035, 7959.761207163794, 8178.550179740112, 2267.7505474397844, 2286.6455699366625, 2317.872517011572, 2320.872352020638, 2323.6979192704925, 2298.161171172518, 2322.3381336319153, 2331.1491305673476, 2335.159342636658, 2347.4600841806396, 1677.370985880295, 2118.8834614096218, 2228.944276393087, 2301.3671240408853, 2338.2166974063707, 2277.3737222263844, 2279.031411650042, 2282.5092605089985, 2285.942851428719, 2289.439087423592, 2262.2760716570706, 2275.2832857649787, 2279.168103642175, 2285.245868863271, 2287.3252390544662, 8110.472819266729, 8305.8338833223, 8310.418270721051, 8310.626021828972, 8323.334133269324, 4997.625178111656, 5145.0, 5149.819756308511, 5163.406208286057, 5167.121589329823, 3280.901572952815, 3394.62659107498, 3452.016175390027, 3470.9826450867836, 3546.1489242581592, 1168.1063986050679, 1231.7290196156832, 1249.5251804314303, 1259.0431272233168, 1280.5146030604546, 10627.884072172388, 10644.946775266075, 10745.83881241782, 10772.353658780497, 10855.945720271427, 3693.5567731872347, 4527.388802511685, 4554.111948170142, 4670.53294670537, 4687.789049492712, 9234.630614775468, 9239.445633261892, 9265.814683706289, 9266.305027122993, 9271.07289271081, 5144.305518754995, 5145.202493613496, 5157.484251574812, 5170.819021334235, 5173.456787037382, 3870.9419358709642, 4216.51510076339, 4730.101280756714, 4730.550597693214, 4735.786889589976, 3727.254549090193, 3748.737588368788, 3807.124361396894, 3835.7506762060375, 3851.5350175887456, 1029.248648486609, 1219.5243854896598, 1220.4995951659955, 1227.895628871556, 1230.5446984615571, 837.0, 858.5321000054985, 864.2524216552637, 887.1439061305871, 904.2314033071837, 5477.945220547823, 5517.586181036439, 5693.345265294462, 5733.254676891977, 5987.940120598825, 952.7760976170595, 967.2310513141929, 970.8689326940914, 976.7851072763981, 1045.9320144190604, 3602.441621548664, 3677.4116141417244, 3732.4401339799247, 3774.6602805747616, 3809.3333666608232, 9594.040595940489, 9840.557174927146, 9874.111329980193, 9939.900600993897, 9950.054744799232, 9893.65372211969, 9908.504574771232, 9916.157126644315, 9923.851142232872, 9928.50357482123, 7257.564546127249, 7571.129320128146, 7673.309402153833, 7760.146383897767, 7761.534307941429, 5364.678119312854, 5563.13771365439, 5582.051051321367, 5668.659880407189, 5690.2887139107615, 8967.641294348154, 9086.136817002374, 9121.543922803967, 9232.769180770534, 9357.85963210566, 8774.254188394058, 9090.181883630505, 9197.034311397207, 9230.830754772489, 9402.15380615748, 6497.285298617054, 6699.43054840344, 6703.4972377070935, 6888.724451021903, 7084.822879428056, 7362.558246505227, 7407.518496300654, 7431.516951398142, 7435.256474352522, 7610.086789585285, 6825.897611535732, 7046.718131274692, 7107.0, 7130.714771409085, 7206.927930720625, 6979.092717946634, 6987.7204911802955, 7025.051618031501, 7074.929250707427, 7109.146902371542, 6049.818505444844, 6242.438180563771, 6340.048992651139, 6373.553851230369, 6396.072569477493, 4085.2442298174933, 4841.298011788201, 4948.084604348207, 4978.950210497848, 5006.324146240211, 4951.282064100757, 5007.424146223231, 5015.971725796276, 5050.39395272555, 5061.848144555669], "value": 4359.45478053872, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [342.2846251334711, 347.67839748232876, 356.2714249360058, 364.1715098151703, 364.2969069695487, 5207.244949482323, 5244.224158605803, 5251.422343542207, 5276.234945932837, 5279.4984476474765, 4167.103967694699, 4209.86365157957, 4946.282788995593, 4989.101961646911, 5023.799048038074, 3226.5482832403363, 3336.933261334772, 3912.0611053347207, 4076.9796151019204, 4102.738323033181, 3987.401889716551, 4009.656764983734, 4018.7588744675413, 4056.533498647649, 4082.714210005286, 3666.633336666339, 3878.564930975529, 3972.682185425159, 4048.210598933209, 4051.756894586334, 3487.1343831050394, 3897.5322961244833, 3908.921821563553, 3910.569837317893, 3916.236333914888, 2783.3320003199287, 2841.9431611367863, 3382.222088919538, 3397.813120278384, 3399.507071474647, 4207.957920420788, 4227.019950934584, 4985.476524964863, 5038.622103342264, 5107.361579802525, 4259.701820872509, 4478.350639157336, 4503.558861164429, 4509.1658043262105, 4512.954870451286, 5005.974970125162, 5432.375276843135, 5446.509814116748, 5455.645383050089, 5460.672359658433, 3729.701623870084, 3729.9440508392395, 3747.3254814133447, 3757.5678796938428, 3758.289978059976, 5328.467153284718, 5459.262999495057, 5686.86262747448, 5745.913811292834, 5764.279465066867, 3221.8872339468476, 3313.4698448248164, 3711.3319602471815, 3742.9064273392837, 3785.167263202091, 3393.6086204656467, 3718.378543127089, 3821.6942644588366, 3872.8644497442456, 3884.2231553689376, 4068.633822955889, 4071.3892916062237, 4157.8336866525, 4365.585269399402, 4511.255642818964, 5102.571280041594, 5418.399168399191, 5476.644018138808, 5493.7802487900835, 5510.834674959758, 1177.3289225141586, 1177.9810463948795, 1238.5169783784152, 1245.3586403002462, 1247.2204871955028, 803.5982008995502, 812.7443100409881, 832.6877420967137, 843.5571325054273, 845.2055068235838, 4190.554258780716, 4767.928481072718, 4851.2723091535845, 4853.344798452164, 4890.679516530517, 4639.87051524137, 5119.46245644202, 5287.651648829601, 5331.546818520469, 5341.611181092944, 4008.1382502762044, 4203.243416185055, 4300.957100325684, 4355.586219309161, 4552.658550608718, 2326.5579539887704, 2436.0888737958326, 2463.901443942222, 2561.8078644101765, 2772.944541109207, 5217.660852044605, 5258.7370631468275, 5396.35913114721, 5457.808976685877, 5488.063885821067, 4490.530208385375, 4836.371271734652, 4838.564529192389, 5078.644494885322, 5449.945500545023, 4754.619630429557, 4788.749287834712, 5099.974500127476, 5287.629865909349, 5501.614886957875, 4629.583337499641, 4786.545278198566, 5087.389513258433, 5106.170372220834, 5301.284326615858, 5075.8071853115225, 5132.820351287836, 5148.176291793295, 5156.61325400604, 5215.739213039409, 4912.901741965212, 5337.599680023936, 5518.972405137949, 5752.6653516766455, 5760.107183386582, 3761.868334608385, 3771.6982641388076, 3818.46541484198, 3820.541535015816, 3830.9616903830606, 4653.557911998355, 4848.272759086165, 5596.524295434933, 5608.747606357805, 5695.288088988877, 4988.501149885127, 5420.828815143028, 5428.131498960148, 5432.59255555827, 5508.228847961369, 4513.796879140381, 5343.011542864582, 5445.5841481214375, 5456.48163424474, 5468.863278418072, 5097.719625420637, 5350.3312085989255, 5350.491703288181, 5355.571554275726, 5362.65142765719, 5089.51649593288, 5252.501012403815, 5321.716018604905, 5409.647588102975, 5422.674639521641, 4199.70602057853, 4301.591348821857, 5219.739013049257, 5286.497782710637, 5333.866653333697], "value": 3888.319537730329, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [668.254895791193, 696.4776417686735, 746.87676533372, 756.1228974389709, 795.9442839001271, 14829.480968166103, 15679.45121920733, 15721.449749258778, 15725.854829034182, 15729.685406291881, 14209.360578773954, 14345.641358966031, 14356.210408427532, 14376.137431754101, 14377.568672939802, 668.8929771236603, 856.0412338181246, 870.5299138465222, 878.97802554936, 879.6217626420637, 693.1266604078871, 707.8088915992679, 837.7821766340734, 858.9992658552778, 862.1766213266316, 930.2650905784423, 930.5254320296664, 940.2431043010374, 942.580551654514, 945.5697657565826, 933.6218831373338, 934.7149119518544, 936.3726303376754, 936.5457752989795, 937.0208132501484, 521.2676189953122, 540.9594280428984, 696.9372756451944, 697.3828162076549, 698.5110422704128, 14082.436702531886, 14579.562613121623, 14642.780358294527, 14971.326290316962, 14983.250837458083, 13953.930230348884, 14098.577042688736, 14165.929170354186, 14167.149971001772, 14221.502247421395, 11763.941180294129, 11795.646130616009, 11872.881271187263, 11892.643220703392, 12059.819102713467, 3624.963750362489, 3684.5578530576236, 3818.713596480275, 3873.1672690371424, 3877.0695033191832, 100192.9980700191, 101196.0, 107399.46300268527, 108416.0, 108700.0, 9030.503322317336, 9390.436573805593, 11111.83332250017, 11284.830727539094, 11313.038391736794, 21245.46886327823, 22911.885440573024, 22937.082516699145, 22980.965856536495, 22998.080076797076, 6699.196096468455, 7683.462157649023, 7703.499272547267, 7738.380929525624, 7832.569208693462, 4231.238377092153, 4291.69958102936, 4306.720063195945, 4342.34864770287, 4442.3336499524685, 3454.101933497309, 3506.807125608116, 3520.7359448041498, 3539.4159963605716, 3555.715542756573, 639.5587044938977, 641.6054126712132, 647.3106141958799, 656.1830521001384, 657.3525077798365, 632.7342516143231, 641.5990006246096, 659.5482094765101, 675.5271310082961, 685.4995853027318, 8644.438111522857, 9679.951600241953, 10036.698899033041, 10351.0, 10410.80557068543, 641.2401304454237, 657.4050484311706, 684.8185230913812, 691.8132104331802, 692.0864458914185, 4922.571028143789, 4958.858478136561, 4983.127952608279, 4989.725565093882, 5005.249212618136, 98778.62824403479, 103265.0, 103876.48061759643, 104055.0, 106603.0, 88099.0, 89796.0, 90092.0, 90341.54829225811, 90481.07259426561, 76307.0, 79612.0, 80674.56289904112, 81522.59238703887, 83026.0, 8094.352451803724, 9295.953520232355, 9695.66065187715, 9769.609215631295, 9793.559289832117, 43147.0, 53931.0, 54172.83308667609, 54797.45202547924, 54927.0, 66371.33628663652, 68403.97394038994, 69390.65304673444, 69805.60388792297, 70465.64767176131, 11778.0, 11831.230969986924, 11974.64076077719, 12056.457459414172, 12184.451699673713, 11587.944309251288, 12598.307093109966, 12648.557300494807, 12846.67883302925, 13080.0, 12017.459214335551, 12302.938485307874, 12513.687157821127, 12587.811182832447, 12601.369931503204, 11915.702107447383, 12197.75604487923, 12413.069019823368, 12591.622251332476, 12594.622161335175, 11842.940785296018, 12064.457099430721, 12092.153549251469, 12165.939170304091, 12472.625821225378, 9946.45294508809, 10194.337368071052, 10304.690859274233, 10501.9474902625, 10783.245172837826, 8584.364264580372, 8657.264132548551, 9261.398009129385, 9773.511324433608, 9773.951130244302], "value": 7341.593779697963, "unit": "score"}], "name": "dromaeo_css", "value": 7136.247684817785, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [110.0, 114.0, 114.0, 113.0, 113.0, 115.0, 114.0, 113.0, 113.0, 113.0], "value": 113.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [141.0, 111.0, 112.0, 173.0, 139.0, 138.0, 107.0, 107.0, 128.0, 109.0], "value": 126.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [185.0, 184.0, 183.0, 185.0, 182.0, 182.0, 179.0, 182.0, 182.0, 183.0], "value": 182.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 57.0, 76.0, 112.0, 85.0, 58.0, 58.0, 58.0, 74.0, 58.0], "value": 71.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 69.0, 87.0, 70.0, 70.0, 70.0, 96.0, 92.0, 69.0, 84.0], "value": 79.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 87.0, 89.0, 87.0, 89.0, 130.0, 89.0, 87.0, 89.0, 86.0], "value": 92.1, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 96.0, 95.0, 98.0, 94.0, 95.0, 95.0, 97.0, 94.0, 94.0], "value": 95.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 93.0, 129.0, 84.0, 97.0, 114.0, 89.0, 86.0, 86.0, 86.0], "value": 94.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 57.0, 57.0, 57.0, 55.0, 55.0, 56.0, 55.0, 56.0, 56.0], "value": 56.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 67.0, 82.0, 77.0, 73.0, 84.0, 103.0, 103.0, 88.0, 71.0], "value": 81.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 87.0, 88.0, 86.0, 87.0, 87.0, 85.0, 87.0, 87.0, 86.0], "value": 87.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [138.0, 139.0, 161.0, 145.0, 153.0, 135.0, 139.0, 152.0, 205.0, 171.0], "value": 153.8, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [234.0, 170.0, 173.0, 224.0, 219.0, 169.0, 225.0, 221.0, 170.0, 170.0], "value": 197.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 68.0, 67.0, 68.0, 67.0, 68.0, 67.0, 68.0, 65.0, 66.0], "value": 67.1, "unit": "score"}], "name": "kraken", "value": 1498.7999999999997, "alertThreshold": 2.0}]} 09:23:47 INFO - Return code: 0 09:23:47 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 09:23:47 INFO - # TBPL SUCCESS # 09:23:47 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:23:47 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:23:47 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:23:47 INFO - 2017-01-09 09:23:47.791 screenresolution[1650:9886] starting screenresolution argv=screenresolution get 09:23:47 INFO - 2017-01-09 09:23:47.794 screenresolution[1650:9886] Display 0: 1600x1200x32@60 09:23:47 INFO - 2017-01-09 09:23:47.800 screenresolution[1651:9889] starting screenresolution argv=screenresolution list 09:23:47 INFO - Available Modes on Display 0 09:23:47 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:23:47 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:23:47 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:23:47 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:23:48 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:23:48 INFO - Intel Iris: 09:23:48 INFO - Chipset Model: Intel Iris 09:23:48 INFO - Type: GPU 09:23:48 INFO - Bus: Built-In 09:23:48 INFO - VRAM (Dynamic, Max): 1536 MB 09:23:48 INFO - Vendor: Intel (0x8086) 09:23:48 INFO - Device ID: 0x0a2e 09:23:48 INFO - Revision ID: 0x0009 09:23:48 INFO - Displays: 09:23:48 INFO - Display: 09:23:48 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:23:48 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:23:48 INFO - Display Serial Number: 1600x1200 60 09:23:48 INFO - Main Display: Yes 09:23:48 INFO - Mirror: Off 09:23:48 INFO - Online: Yes 09:23:48 INFO - Rotation: Supported 09:23:48 INFO - Return code: 0 09:23:48 INFO - Running post-action listener: _resource_record_post_action 09:23:48 INFO - [mozharness: 2017-01-09 17:23:48.035627Z] Finished run-tests step (success) 09:23:48 INFO - Running post-run listener: _resource_record_post_run 09:23:48 INFO - Total resource usage - Wall time: 1202s; CPU: 54.0%; Read bytes: 39048192; Write bytes: 550535680; Read time: 376; Write time: 1849 09:23:48 INFO - TinderboxPrint: CPU usage
53.8% 09:23:48 INFO - TinderboxPrint: I/O read bytes / time
39,048,192 / 376 09:23:48 INFO - TinderboxPrint: I/O write bytes / time
550,535,680 / 1,849 09:23:48 INFO - TinderboxPrint: CPU idle
2,220.9 (46.2%) 09:23:48 INFO - TinderboxPrint: CPU system
966.1 (20.1%) 09:23:48 INFO - TinderboxPrint: CPU user
1,622.7 (33.7%) 09:23:48 INFO - TinderboxPrint: Swap in / out
288,362,496 / 0 09:23:48 INFO - install - Wall time: 22s; CPU: 54.0%; Read bytes: 242027008; Write bytes: 234352640; Read time: 17943; Write time: 447 09:23:48 INFO - run-tests - Wall time: 1180s; CPU: 54.0%; Read bytes: 36996096; Write bytes: 298729984; Read time: 353; Write time: 1361 09:23:48 INFO - Running post-run listener: _upload_blobber_files 09:23:48 INFO - Blob upload gear active. 09:23:48 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:23:48 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 09:23:48 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', 'Graphics', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 09:23:48 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 Graphics -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 09:23:48 INFO - (blobuploader) - INFO - Open directory for files ... 09:23:48 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 09:23:48 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:23:48 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:23:49 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 09:23:49 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:23:49 INFO - (blobuploader) - INFO - Done attempting. 09:23:49 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 09:23:49 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:23:49 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:23:50 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 09:23:50 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:23:50 INFO - (blobuploader) - INFO - Done attempting. 09:23:50 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 09:23:50 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:23:50 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:23:51 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 09:23:51 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:23:51 INFO - (blobuploader) - INFO - Done attempting. 09:23:51 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 09:23:51 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:23:51 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:23:54 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 09:23:54 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:23:54 INFO - (blobuploader) - INFO - Done attempting. 09:23:54 INFO - (blobuploader) - INFO - Iteration through files over. 09:23:54 INFO - Return code: 0 09:23:54 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:23:54 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:23:54 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/0217bb5db56b1cfad52c610d5e2d6c48593b2b9d9a178890729cf486a1f5580a9617518fc9150850b3f47ba9d62f7b2dfa5be645822236fe89b3b36579091fac", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/3c79b04d953436cb31f319c2c831e4cd891b8cf142625487a76fd409d8beca60501d69e3b7c461e4e40d5bea0aa5a525658442a6a7bb7b22139ecb1bae5203bf", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/a24698859eff9658188d331772a914879fbc3f76deb34d4ff60165fee0b6bf2ce29c93d4e22095153cbe51635cef105eca17a488a92a8a5055f274afeecb8e89", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/65678de033f6f2f086a47e0959c787f2a13828d334daceb63b39171b0ea36af53ce7ae66f6df58f3161ebcdb94c8df1a44d8fb3399e02453b0f10c236e38d855"} 09:23:54 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:23:54 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:23:54 INFO - Contents: 09:23:54 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/0217bb5db56b1cfad52c610d5e2d6c48593b2b9d9a178890729cf486a1f5580a9617518fc9150850b3f47ba9d62f7b2dfa5be645822236fe89b3b36579091fac", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/3c79b04d953436cb31f319c2c831e4cd891b8cf142625487a76fd409d8beca60501d69e3b7c461e4e40d5bea0aa5a525658442a6a7bb7b22139ecb1bae5203bf", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/a24698859eff9658188d331772a914879fbc3f76deb34d4ff60165fee0b6bf2ce29c93d4e22095153cbe51635cef105eca17a488a92a8a5055f274afeecb8e89", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/65678de033f6f2f086a47e0959c787f2a13828d334daceb63b39171b0ea36af53ce7ae66f6df58f3161ebcdb94c8df1a44d8fb3399e02453b0f10c236e38d855"} 09:23:54 INFO - Running post-run listener: copy_logs_to_upload_dir 09:23:54 INFO - Copying logs to upload dir... 09:23:54 INFO - mkdir: /builds/slave/test/build/upload/logs 09:23:54 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1263.100108 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 3 secs) (at 2017-01-09 09:23:54.280818) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-09 09:23:54.284862) ========= 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.AsiP3nGOUD/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.d6Cdl0cXf0/Listeners TMPDIR=/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/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/Graphics/sha512/0217bb5db56b1cfad52c610d5e2d6c48593b2b9d9a178890729cf486a1f5580a9617518fc9150850b3f47ba9d62f7b2dfa5be645822236fe89b3b36579091fac", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/3c79b04d953436cb31f319c2c831e4cd891b8cf142625487a76fd409d8beca60501d69e3b7c461e4e40d5bea0aa5a525658442a6a7bb7b22139ecb1bae5203bf", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/a24698859eff9658188d331772a914879fbc3f76deb34d4ff60165fee0b6bf2ce29c93d4e22095153cbe51635cef105eca17a488a92a8a5055f274afeecb8e89", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/65678de033f6f2f086a47e0959c787f2a13828d334daceb63b39171b0ea36af53ce7ae66f6df58f3161ebcdb94c8df1a44d8fb3399e02453b0f10c236e38d855"} build_url:https://queue.taskcluster.net/v1/task/cQUlzIhBT9umb3bxCQLZ6Q/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011268 build_url: 'https://queue.taskcluster.net/v1/task/cQUlzIhBT9umb3bxCQLZ6Q/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/0217bb5db56b1cfad52c610d5e2d6c48593b2b9d9a178890729cf486a1f5580a9617518fc9150850b3f47ba9d62f7b2dfa5be645822236fe89b3b36579091fac", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/3c79b04d953436cb31f319c2c831e4cd891b8cf142625487a76fd409d8beca60501d69e3b7c461e4e40d5bea0aa5a525658442a6a7bb7b22139ecb1bae5203bf", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/a24698859eff9658188d331772a914879fbc3f76deb34d4ff60165fee0b6bf2ce29c93d4e22095153cbe51635cef105eca17a488a92a8a5055f274afeecb8e89", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/65678de033f6f2f086a47e0959c787f2a13828d334daceb63b39171b0ea36af53ce7ae66f6df58f3161ebcdb94c8df1a44d8fb3399e02453b0f10c236e38d855"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-09 09:23:54.319727) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-09 09:23:54.320079) ========= 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.AsiP3nGOUD/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.d6Cdl0cXf0/Listeners TMPDIR=/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/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.005139 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-09 09:23:54.368458) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-09 09:23:54.368824) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-09 09:23:54.369164) ========= ========= Total master_lag: 0.18 =========