builder: mozilla-central_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0154 starttime: 1482854560.24 results: success (0) buildid: 20161227064436 builduid: bfe7680df3a849c5bb19b5b6d5547a42 revision: dccfc624915b1bebe7975076e9b42bd46684cbc6 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-12-27 08:02:40.243188) ========= master: http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-12-27 08:02:40.243690) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-27 08:02:40.243986) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-27 08:02:40.270999) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-27 08:02:40.271397) ========= 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.JXq6ilxw7t/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.fSuLBGiLYO/Listeners TMPDIR=/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-12-27 08:02:40-- 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% 126M=0s 2016-12-27 08:02:40 (126 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.111985 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-27 08:02:40.405467) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-27 08:02:40.405788) ========= 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.JXq6ilxw7t/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.fSuLBGiLYO/Listeners TMPDIR=/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/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.025887 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-27 08:02:40.456864) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 3 secs) (at 2016-12-27 08:02:40.457233) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev dccfc624915b1bebe7975076e9b42bd46684cbc6 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev dccfc624915b1bebe7975076e9b42bd46684cbc6 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.JXq6ilxw7t/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.fSuLBGiLYO/Listeners TMPDIR=/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-12-27 08:02:40,550 truncating revision to first 12 chars 2016-12-27 08:02:40,551 Setting DEBUG logging. 2016-12-27 08:02:40,551 attempt 1/10 2016-12-27 08:02:40,551 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/dccfc624915b?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-12-27 08:02:42,561 unpacking tar archive at: mozilla-central-dccfc624915b/testing/mozharness/ program finished with exit code 0 elapsedTime=2.262721 ========= master_lag: 0.92 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 3 secs) (at 2016-12-27 08:02:43.643649) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-27 08:02:43.643973) ========= script_repo_revision: dccfc624915b1bebe7975076e9b42bd46684cbc6 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-27 08:02:43.644383) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-27 08:02:43.644647) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-27 08:02:44.392537) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 2 secs) (at 2016-12-27 08:02:44.392946) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.JXq6ilxw7t/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.fSuLBGiLYO/Listeners TMPDIR=/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/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 08:02:44 INFO - MultiFileLogger online at 20161227 08:02:44 in /builds/slave/test 08:02:44 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 08:02:44 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 08:02:44 INFO - {'append_to_log': False, 08:02:44 INFO - 'base_work_dir': '/builds/slave/test', 08:02:44 INFO - 'blob_upload_branch': 'Firefox', 08:02:44 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 08:02:44 INFO - 'branch': 'Firefox', 08:02:44 INFO - 'buildbot_json_path': 'buildprops.json', 08:02:44 INFO - 'config_files': ('talos/mac_config.py',), 08:02:44 INFO - 'default_actions': ('clobber', 08:02:44 INFO - 'read-buildbot-config', 08:02:44 INFO - 'download-and-extract', 08:02:44 INFO - 'populate-webroot', 08:02:44 INFO - 'create-virtualenv', 08:02:44 INFO - 'install', 08:02:44 INFO - 'run-tests'), 08:02:44 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 08:02:44 INFO - 'download_minidump_stackwalk': True, 08:02:44 INFO - 'download_symbols': 'ondemand', 08:02:44 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 08:02:44 INFO - 'tooltool.py': '/tools/tooltool.py', 08:02:44 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 08:02:44 INFO - '/tools/misc-python/virtualenv.py')}, 08:02:44 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 08:02:44 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 08:02:44 INFO - 'installer_path': 'installer.exe', 08:02:44 INFO - 'log_level': 'info', 08:02:44 INFO - 'log_name': 'talos', 08:02:44 INFO - 'log_to_console': True, 08:02:44 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 08:02:44 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 08:02:44 INFO - 'opt_config_files': (), 08:02:44 INFO - 'pip_index': False, 08:02:44 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 08:02:44 INFO - 'cmd': ('bash', 08:02:44 INFO - '-c', 08:02:44 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 08:02:44 INFO - 'enabled': True, 08:02:44 INFO - 'halt_on_failure': False, 08:02:44 INFO - 'name': 'check_screen_resolution'},), 08:02:44 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 08:02:44 INFO - 'cmd': ('bash', 08:02:44 INFO - '-c', 08:02:44 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 08:02:44 INFO - 'enabled': True, 08:02:44 INFO - 'halt_on_failure': False, 08:02:44 INFO - 'name': 'check_screen_resolution'},), 08:02:44 INFO - 'run_cmd_checks_enabled': True, 08:02:44 INFO - 'sps_profile': False, 08:02:44 INFO - 'sps_profile_interval': 0, 08:02:44 INFO - 'suite': 'dromaeojs', 08:02:44 INFO - 'system_bits': '32', 08:02:44 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 08:02:44 INFO - 'title': 't-yosemite-r7-0154', 08:02:44 INFO - 'tooltool_cache': '/builds/tooltool_cache', 08:02:44 INFO - 'use_talos_json': True, 08:02:44 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 08:02:44 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 08:02:44 INFO - 'work_dir': 'build'} 08:02:44 INFO - [mozharness: 2016-12-27 16:02:44.597433Z] Running clobber step. 08:02:44 INFO - Running pre-action listener: _resource_record_pre_action 08:02:44 INFO - Running main action method: clobber 08:02:44 INFO - rmtree: /builds/slave/test/build 08:02:44 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 08:02:47 INFO - Running post-action listener: _resource_record_post_action 08:02:47 INFO - [mozharness: 2016-12-27 16:02:47.023562Z] Finished clobber step (success) 08:02:47 INFO - [mozharness: 2016-12-27 16:02:47.023727Z] Running read-buildbot-config step. 08:02:47 INFO - Running pre-action listener: _resource_record_pre_action 08:02:47 INFO - Running main action method: read_buildbot_config 08:02:47 INFO - Using buildbot properties: 08:02:47 INFO - { 08:02:47 INFO - "project": "", 08:02:47 INFO - "product": "firefox", 08:02:47 INFO - "script_repo_revision": "production", 08:02:47 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs", 08:02:47 INFO - "repository": "", 08:02:47 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs", 08:02:47 INFO - "buildid": "20161227064436", 08:02:47 INFO - "pgo_build": "False", 08:02:47 INFO - "basedir": "/builds/slave/test", 08:02:47 INFO - "buildnumber": 164, 08:02:47 INFO - "slavename": "t-yosemite-r7-0154", 08:02:47 INFO - "master": "http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/", 08:02:47 INFO - "platform": "yosemite_r7", 08:02:47 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 08:02:47 INFO - "branch": "mozilla-central", 08:02:47 INFO - "repo_path": "mozilla-central", 08:02:47 INFO - "revision": "dccfc624915b1bebe7975076e9b42bd46684cbc6", 08:02:47 INFO - "stage_platform": "macosx64", 08:02:47 INFO - "builduid": "bfe7680df3a849c5bb19b5b6d5547a42", 08:02:47 INFO - "slavebuilddir": "test" 08:02:47 INFO - } 08:02:47 INFO - Found installer url https://queue.taskcluster.net/v1/task/IcDfeMPSQpuREzSUetvSmg/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg. 08:02:47 INFO - Running post-action listener: _resource_record_post_action 08:02:47 INFO - [mozharness: 2016-12-27 16:02:47.026981Z] Finished read-buildbot-config step (success) 08:02:47 INFO - [mozharness: 2016-12-27 16:02:47.027093Z] Running download-and-extract step. 08:02:47 INFO - Running pre-action listener: _resource_record_pre_action 08:02:47 INFO - Running main action method: download_and_extract 08:02:47 INFO - mkdir: /builds/slave/test/build/tests 08:02:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:02:47 INFO - trying https://queue.taskcluster.net/v1/task/IcDfeMPSQpuREzSUetvSmg/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json 08:02:47 INFO - Downloading https://queue.taskcluster.net/v1/task/IcDfeMPSQpuREzSUetvSmg/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 08:02:47 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/IcDfeMPSQpuREzSUetvSmg/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 08:02:48 INFO - Downloaded 1199 bytes. 08:02:48 INFO - Reading from file /builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json 08:02:48 INFO - Using the following test package requirements: 08:02:48 INFO - {u'common': [u'firefox-53.0a1.en-US.mac.common.tests.zip'], 08:02:48 INFO - u'cppunittest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 08:02:48 INFO - u'firefox-53.0a1.en-US.mac.cppunittest.tests.zip'], 08:02:48 INFO - u'gtest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 08:02:48 INFO - u'firefox-53.0a1.en-US.mac.gtest.tests.zip'], 08:02:48 INFO - u'jittest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 08:02:48 INFO - u'jsshell-mac.zip'], 08:02:48 INFO - u'mochitest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 08:02:48 INFO - u'firefox-53.0a1.en-US.mac.mochitest.tests.zip'], 08:02:48 INFO - u'mozbase': [u'firefox-53.0a1.en-US.mac.common.tests.zip'], 08:02:48 INFO - u'reftest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 08:02:48 INFO - u'firefox-53.0a1.en-US.mac.reftest.tests.zip'], 08:02:48 INFO - u'talos': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 08:02:48 INFO - u'firefox-53.0a1.en-US.mac.talos.tests.zip'], 08:02:48 INFO - u'web-platform': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 08:02:48 INFO - u'firefox-53.0a1.en-US.mac.web-platform.tests.zip'], 08:02:48 INFO - u'xpcshell': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 08:02:48 INFO - u'firefox-53.0a1.en-US.mac.xpcshell.tests.zip']} 08:02:48 INFO - Downloading packages: [u'firefox-53.0a1.en-US.mac.common.tests.zip'] for test suite category: common 08:02:48 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/IcDfeMPSQpuREzSUetvSmg/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip 08:02:48 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/IcDfeMPSQpuREzSUetvSmg/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip'}, attempt #1 08:02:48 INFO - Fetch https://queue.taskcluster.net/v1/task/IcDfeMPSQpuREzSUetvSmg/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip into memory 08:02:49 INFO - Expected file size: 16294458 08:02:49 INFO - Obtained file size: 16294458 08:02:56 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 08:02:56 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/IcDfeMPSQpuREzSUetvSmg/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip 08:02:56 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/IcDfeMPSQpuREzSUetvSmg/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip'}, attempt #1 08:02:56 INFO - Fetch https://queue.taskcluster.net/v1/task/IcDfeMPSQpuREzSUetvSmg/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip into memory 08:02:56 INFO - Expected file size: 16294458 08:02:56 INFO - Obtained file size: 16294458 08:03:03 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/IcDfeMPSQpuREzSUetvSmg/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip 08:03:03 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/IcDfeMPSQpuREzSUetvSmg/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip'}, attempt #1 08:03:03 INFO - Fetch https://queue.taskcluster.net/v1/task/IcDfeMPSQpuREzSUetvSmg/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip into memory 08:03:04 INFO - Expected file size: 14409776 08:03:04 INFO - Obtained file size: 14409776 08:03:04 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')]} 08:03:04 INFO - trying https://queue.taskcluster.net/v1/task/IcDfeMPSQpuREzSUetvSmg/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 08:03:04 INFO - Downloading https://queue.taskcluster.net/v1/task/IcDfeMPSQpuREzSUetvSmg/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg 08:03:04 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/IcDfeMPSQpuREzSUetvSmg/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 08:03:06 INFO - Downloaded 69623876 bytes. 08:03:06 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/IcDfeMPSQpuREzSUetvSmg/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 08:03:06 INFO - mkdir: /builds/slave/test/properties 08:03:06 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 08:03:06 INFO - Writing to file /builds/slave/test/properties/build_url 08:03:06 INFO - Contents: 08:03:06 INFO - build_url:https://queue.taskcluster.net/v1/task/IcDfeMPSQpuREzSUetvSmg/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 08:03:07 INFO - Running post-action listener: _resource_record_post_action 08:03:07 INFO - Running post-action listener: set_extra_try_arguments 08:03:07 INFO - [mozharness: 2016-12-27 16:03:07.150679Z] Finished download-and-extract step (success) 08:03:07 INFO - [mozharness: 2016-12-27 16:03:07.150805Z] Running populate-webroot step. 08:03:07 INFO - Running pre-action listener: _resource_record_pre_action 08:03:07 INFO - Running main action method: populate_webroot 08:03:07 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 08:03:07 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 08:03:07 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 08:03:07 INFO - '--tppagecycles', 08:03:07 INFO - '7'], 08:03:07 INFO - 'tests': ['tsvgm']}}, 08:03:07 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 08:03:07 INFO - 'tests': ['tresize', 'tcanvasmark']}, 08:03:07 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 08:03:07 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 08:03:07 INFO - 'tests': ['dromaeo_css', 'kraken']}, 08:03:07 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 08:03:07 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 08:03:07 INFO - 'talos_options': ['--disable-e10s'], 08:03:07 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:03:07 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 08:03:07 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:03:07 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 08:03:07 INFO - 'talos_options': ['--disable-e10s'], 08:03:07 INFO - 'tests': ['damp', 'tps']}, 08:03:07 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 08:03:07 INFO - 'tests': ['damp', 'tps']}, 08:03:07 INFO - 'g3': {'talos_options': ['--disable-e10s'], 08:03:07 INFO - 'tests': ['dromaeo_dom']}, 08:03:07 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 08:03:07 INFO - 'g4': {'talos_options': ['--disable-e10s'], 08:03:07 INFO - 'tests': ['basic_compositor_video']}, 08:03:07 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 08:03:07 INFO - 'other': {'talos_options': ['--disable-e10s'], 08:03:07 INFO - 'tests': ['a11yr', 08:03:07 INFO - 'ts_paint', 08:03:07 INFO - 'tpaint', 08:03:07 INFO - 'sessionrestore', 08:03:07 INFO - 'sessionrestore_no_auto_restore', 08:03:07 INFO - 'tabpaint']}, 08:03:07 INFO - 'other-e10s': {'tests': ['a11yr', 08:03:07 INFO - 'ts_paint', 08:03:07 INFO - 'tpaint', 08:03:07 INFO - 'sessionrestore', 08:03:07 INFO - 'sessionrestore_no_auto_restore', 08:03:07 INFO - 'tabpaint']}, 08:03:07 INFO - 'other-e10s_l64': {'tests': ['a11yr', 08:03:07 INFO - 'ts_paint', 08:03:07 INFO - 'tpaint', 08:03:07 INFO - 'sessionrestore', 08:03:07 INFO - 'sessionrestore_no_auto_restore', 08:03:07 INFO - 'tabpaint']}, 08:03:07 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 08:03:07 INFO - 'ts_paint', 08:03:07 INFO - 'tpaint', 08:03:07 INFO - 'sessionrestore', 08:03:07 INFO - 'sessionrestore_no_auto_restore', 08:03:07 INFO - 'tabpaint']}, 08:03:07 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 08:03:07 INFO - 'tests': ['a11yr', 08:03:07 INFO - 'ts_paint', 08:03:07 INFO - 'tpaint', 08:03:07 INFO - 'sessionrestore', 08:03:07 INFO - 'sessionrestore_no_auto_restore', 08:03:07 INFO - 'tabpaint']}, 08:03:07 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 08:03:07 INFO - 'tests': ['a11yr', 08:03:07 INFO - 'ts_paint', 08:03:07 INFO - 'tpaint', 08:03:07 INFO - 'sessionrestore', 08:03:07 INFO - 'sessionrestore_no_auto_restore', 08:03:07 INFO - 'tabpaint']}, 08:03:07 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 08:03:07 INFO - 'tests': ['tsvgx', 08:03:07 INFO - 'tsvgr_opacity', 08:03:07 INFO - 'tart', 08:03:07 INFO - 'tscrollx', 08:03:07 INFO - 'cart', 08:03:07 INFO - 'tsvg_static']}, 08:03:07 INFO - 'svgr-e10s': {'tests': ['tsvgx', 08:03:07 INFO - 'tsvgr_opacity', 08:03:07 INFO - 'tart', 08:03:07 INFO - 'tscrollx', 08:03:07 INFO - 'cart', 08:03:07 INFO - 'tsvg_static']}, 08:03:07 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 08:03:07 INFO - 'talos_options': ['--disable-e10s'], 08:03:07 INFO - 'tests': ['tp5o']}, 08:03:07 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 08:03:07 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 08:03:07 INFO - 'talos_options': ['--disable-e10s', 08:03:07 INFO - '--xperf_path', 08:03:07 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:03:07 INFO - 'tests': ['tp5n']}, 08:03:07 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 08:03:07 INFO - 'talos_options': ['--xperf_path', 08:03:07 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:03:07 INFO - 'tests': ['tp5n']}}, 08:03:07 INFO - 'talos.zip': {'path': '', 08:03:07 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 08:03:07 INFO - Running post-action listener: _resource_record_post_action 08:03:07 INFO - [mozharness: 2016-12-27 16:03:07.169191Z] Finished populate-webroot step (success) 08:03:07 INFO - [mozharness: 2016-12-27 16:03:07.169281Z] Running create-virtualenv step. 08:03:07 INFO - Running pre-action listener: _resource_record_pre_action 08:03:07 INFO - Running main action method: create_virtualenv 08:03:07 INFO - Creating virtualenv /builds/slave/test/build/venv 08:03:07 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 08:03:07 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 08:03:07 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 08:03:07 INFO - Using real prefix '/tools/python27' 08:03:07 INFO - New python executable in /builds/slave/test/build/venv/bin/python 08:03:08 INFO - Installing distribute.............................................................................................................................................................................................done. 08:03:10 INFO - Installing pip.................done. 08:03:10 INFO - Return code: 0 08:03:10 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 08:03:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 08:03:11 INFO - Reading from file tmpfile_stdout 08:03:11 INFO - Output received: 08:03:11 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) 08:03:11 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 08:03:11 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')]} 08:03:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:03:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:03:11 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')]} 08:03:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:03:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:03:11 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 0x10120eb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101211768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff6f859de50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100fecd30>, '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 0x100f13db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100fe1be8>, '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.JXq6ilxw7t/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.fSuLBGiLYO/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/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 08:03:11 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 08:03:11 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 08:03:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.JXq6ilxw7t/Render', 08:03:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:03:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:03:11 INFO - 'HOME': '/Users/cltbld', 08:03:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:03:11 INFO - 'LOGNAME': 'cltbld', 08:03:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:03:11 INFO - 'MOZ_NO_REMOTE': '1', 08:03:11 INFO - 'NO_EM_RESTART': '1', 08:03:11 INFO - 'PAGER': '/bin/cat', 08:03:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:03:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:03:11 INFO - 'PWD': '/builds/slave/test', 08:03:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:03:11 INFO - 'SHELL': '/bin/bash', 08:03:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.fSuLBGiLYO/Listeners', 08:03:11 INFO - 'TMPDIR': '/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/', 08:03:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:03:11 INFO - 'USER': 'cltbld', 08:03:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:03:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:03:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:03:11 INFO - 'XPC_FLAGS': '0x0', 08:03:11 INFO - 'XPC_SERVICE_NAME': '0', 08:03:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:03:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:03:11 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 08:03:11 INFO - Cleaning up... 08:03:11 INFO - Return code: 0 08:03:11 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 08:03:11 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')]} 08:03:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:03:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:03:11 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')]} 08:03:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:03:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:03:11 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 0x10120eb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101211768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff6f859de50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100fecd30>, '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 0x100f13db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100fe1be8>, '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.JXq6ilxw7t/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.fSuLBGiLYO/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/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 08:03:11 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 08:03:11 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 08:03:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.JXq6ilxw7t/Render', 08:03:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:03:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:03:11 INFO - 'HOME': '/Users/cltbld', 08:03:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:03:11 INFO - 'LOGNAME': 'cltbld', 08:03:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:03:11 INFO - 'MOZ_NO_REMOTE': '1', 08:03:11 INFO - 'NO_EM_RESTART': '1', 08:03:11 INFO - 'PAGER': '/bin/cat', 08:03:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:03:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:03:11 INFO - 'PWD': '/builds/slave/test', 08:03:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:03:11 INFO - 'SHELL': '/bin/bash', 08:03:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.fSuLBGiLYO/Listeners', 08:03:11 INFO - 'TMPDIR': '/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/', 08:03:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:03:11 INFO - 'USER': 'cltbld', 08:03:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:03:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:03:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:03:11 INFO - 'XPC_FLAGS': '0x0', 08:03:11 INFO - 'XPC_SERVICE_NAME': '0', 08:03:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:03:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:03:11 INFO - Downloading/unpacking psutil>=3.1.1 08:03:11 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 08:03:11 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 08:03:11 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 08:03:11 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 08:03:11 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 08:03:11 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 08:03:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 08:03:15 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:03:15 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:03:15 INFO - Installing collected packages: psutil 08:03:15 INFO - Running setup.py install for psutil 08:03:15 INFO - building 'psutil._psutil_osx' extension 08:03:15 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 08:03:15 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 08:03:15 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 08:03:15 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 08:03:16 INFO - building 'psutil._psutil_posix' extension 08:03:16 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 08:03:16 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 08:03:16 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 08:03:16 INFO - ^ 08:03:16 INFO - 1 warning generated. 08:03:16 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 08:03:16 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:03:16 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:03:16 INFO - Successfully installed psutil 08:03:16 INFO - Cleaning up... 08:03:16 INFO - Return code: 0 08:03:16 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 08:03:16 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')]} 08:03:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:03:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:03:16 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')]} 08:03:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:03:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:03:16 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 0x10120eb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101211768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff6f859de50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100fecd30>, '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 0x100f13db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100fe1be8>, '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.JXq6ilxw7t/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.fSuLBGiLYO/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/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 08:03:16 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 08:03:16 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 08:03:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.JXq6ilxw7t/Render', 08:03:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:03:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:03:16 INFO - 'HOME': '/Users/cltbld', 08:03:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:03:16 INFO - 'LOGNAME': 'cltbld', 08:03:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:03:16 INFO - 'MOZ_NO_REMOTE': '1', 08:03:16 INFO - 'NO_EM_RESTART': '1', 08:03:16 INFO - 'PAGER': '/bin/cat', 08:03:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:03:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:03:16 INFO - 'PWD': '/builds/slave/test', 08:03:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:03:16 INFO - 'SHELL': '/bin/bash', 08:03:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.fSuLBGiLYO/Listeners', 08:03:16 INFO - 'TMPDIR': '/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/', 08:03:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:03:16 INFO - 'USER': 'cltbld', 08:03:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:03:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:03:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:03:16 INFO - 'XPC_FLAGS': '0x0', 08:03:16 INFO - 'XPC_SERVICE_NAME': '0', 08:03:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:03:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:03:16 INFO - Downloading/unpacking mozsystemmonitor==0.3 08:03:16 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 08:03:16 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 08:03:16 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 08:03:16 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 08:03:16 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 08:03:16 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 08:03:19 INFO - Downloading mozsystemmonitor-0.3.tar.gz 08:03:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 08:03:19 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 08:03:19 INFO - Installing collected packages: mozsystemmonitor 08:03:19 INFO - Running setup.py install for mozsystemmonitor 08:03:19 INFO - Successfully installed mozsystemmonitor 08:03:19 INFO - Cleaning up... 08:03:19 INFO - Return code: 0 08:03:19 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 08:03:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:03:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:03:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:03:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:03:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:03:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:03:19 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10120eb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101211768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff6f859de50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100fecd30>, '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 0x100f13db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100fe1be8>, '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.JXq6ilxw7t/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.fSuLBGiLYO/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/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 08:03:19 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 08:03:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 08:03:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.JXq6ilxw7t/Render', 08:03:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:03:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:03:19 INFO - 'HOME': '/Users/cltbld', 08:03:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:03:19 INFO - 'LOGNAME': 'cltbld', 08:03:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:03:19 INFO - 'MOZ_NO_REMOTE': '1', 08:03:19 INFO - 'NO_EM_RESTART': '1', 08:03:19 INFO - 'PAGER': '/bin/cat', 08:03:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:03:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:03:19 INFO - 'PWD': '/builds/slave/test', 08:03:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:03:19 INFO - 'SHELL': '/bin/bash', 08:03:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.fSuLBGiLYO/Listeners', 08:03:19 INFO - 'TMPDIR': '/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/', 08:03:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:03:19 INFO - 'USER': 'cltbld', 08:03:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:03:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:03:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:03:19 INFO - 'XPC_FLAGS': '0x0', 08:03:19 INFO - 'XPC_SERVICE_NAME': '0', 08:03:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:03:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:03:19 INFO - Downloading/unpacking jsonschema==2.5.1 08:03:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:03:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:03:19 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:03:19 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:03:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:03:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:03:22 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 08:03:22 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 08: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 08: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 08: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 08: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 08: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 08: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 08:03:22 INFO - Downloading functools32-3.2.3-2.tar.gz 08:03:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 08:03:23 INFO - warning: no files found matching '*.txt' 08:03:23 INFO - no previously-included directories found matching 'build' 08:03:23 INFO - no previously-included directories found matching 'dist' 08:03:23 INFO - no previously-included directories found matching '.git*' 08:03:23 INFO - Installing collected packages: jsonschema, functools32 08:03:23 INFO - Running setup.py install for functools32 08:03:23 INFO - warning: no files found matching '*.txt' 08:03:23 INFO - no previously-included directories found matching 'build' 08:03:23 INFO - no previously-included directories found matching 'dist' 08:03:23 INFO - no previously-included directories found matching '.git*' 08:03:23 INFO - Successfully installed jsonschema functools32 08:03:23 INFO - Cleaning up... 08:03:23 INFO - Return code: 0 08:03:23 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 08:03:23 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')]} 08:03:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:03:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:03:23 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')]} 08:03:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:03:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:03:23 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 0x10120eb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101211768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff6f859de50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100fecd30>, '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 0x100f13db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100fe1be8>, '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.JXq6ilxw7t/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.fSuLBGiLYO/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/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 08:03:23 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 08:03:23 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 08:03:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.JXq6ilxw7t/Render', 08:03:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:03:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:03:23 INFO - 'HOME': '/Users/cltbld', 08:03:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:03:23 INFO - 'LOGNAME': 'cltbld', 08:03:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:03:23 INFO - 'MOZ_NO_REMOTE': '1', 08:03:23 INFO - 'NO_EM_RESTART': '1', 08:03:23 INFO - 'PAGER': '/bin/cat', 08:03:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:03:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:03:23 INFO - 'PWD': '/builds/slave/test', 08:03:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:03:23 INFO - 'SHELL': '/bin/bash', 08:03:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.fSuLBGiLYO/Listeners', 08:03:23 INFO - 'TMPDIR': '/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/', 08:03:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:03:23 INFO - 'USER': 'cltbld', 08:03:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:03:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:03:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:03:23 INFO - 'XPC_FLAGS': '0x0', 08:03:23 INFO - 'XPC_SERVICE_NAME': '0', 08:03:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:03:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:03:23 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 08:03:23 INFO - Cleaning up... 08:03:23 INFO - Return code: 0 08:03:23 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 08:03:23 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')]} 08:03:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:03:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:03:23 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')]} 08:03:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:03:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:03:23 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 0x10120eb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101211768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff6f859de50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100fecd30>, '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 0x100f13db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100fe1be8>, '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.JXq6ilxw7t/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.fSuLBGiLYO/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/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 08:03:23 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 08:03:23 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 08:03:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.JXq6ilxw7t/Render', 08:03:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:03:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:03:23 INFO - 'HOME': '/Users/cltbld', 08:03:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:03:23 INFO - 'LOGNAME': 'cltbld', 08:03:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:03:23 INFO - 'MOZ_NO_REMOTE': '1', 08:03:23 INFO - 'NO_EM_RESTART': '1', 08:03:23 INFO - 'PAGER': '/bin/cat', 08:03:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:03:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:03:23 INFO - 'PWD': '/builds/slave/test', 08:03:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:03:23 INFO - 'SHELL': '/bin/bash', 08:03:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.fSuLBGiLYO/Listeners', 08:03:23 INFO - 'TMPDIR': '/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/', 08:03:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:03:23 INFO - 'USER': 'cltbld', 08:03:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:03:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:03:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:03:23 INFO - 'XPC_FLAGS': '0x0', 08:03:23 INFO - 'XPC_SERVICE_NAME': '0', 08:03:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:03:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:03:23 INFO - Downloading/unpacking blobuploader==1.2.4 08:03:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:03:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:03:23 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:03:23 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:03:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:03:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:03:26 INFO - Downloading blobuploader-1.2.4.tar.gz 08:03:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 08:03:26 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 08:03:26 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 08:03:26 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 08:03:26 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 08:03:26 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 08:03:26 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 08:03:26 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 08:03:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 08:03:27 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 08:03:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:03:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:03:27 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:03:27 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:03:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:03:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:03:27 INFO - Downloading docopt-0.6.1.tar.gz 08:03:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 08:03:27 INFO - Installing collected packages: blobuploader, requests, docopt 08:03:27 INFO - Running setup.py install for blobuploader 08:03:27 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 08:03:27 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 08:03:27 INFO - Running setup.py install for requests 08:03:28 INFO - Running setup.py install for docopt 08:03:28 INFO - Successfully installed blobuploader requests docopt 08:03:28 INFO - Cleaning up... 08:03:28 INFO - Return code: 0 08:03:28 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:03:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:03:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:03:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:03:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:03:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:03:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:03:28 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 0x10120eb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101211768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff6f859de50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100fecd30>, '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 0x100f13db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100fe1be8>, '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.JXq6ilxw7t/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.fSuLBGiLYO/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/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 08:03:28 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 08:03:28 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 08:03:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.JXq6ilxw7t/Render', 08:03:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:03:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:03:28 INFO - 'HOME': '/Users/cltbld', 08:03:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:03:28 INFO - 'LOGNAME': 'cltbld', 08:03:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:03:28 INFO - 'MOZ_NO_REMOTE': '1', 08:03:28 INFO - 'NO_EM_RESTART': '1', 08:03:28 INFO - 'PAGER': '/bin/cat', 08:03:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:03:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:03:28 INFO - 'PWD': '/builds/slave/test', 08:03:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:03:28 INFO - 'SHELL': '/bin/bash', 08:03:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.fSuLBGiLYO/Listeners', 08:03:28 INFO - 'TMPDIR': '/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/', 08:03:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:03:28 INFO - 'USER': 'cltbld', 08:03:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:03:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:03:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:03:28 INFO - 'XPC_FLAGS': '0x0', 08:03:28 INFO - 'XPC_SERVICE_NAME': '0', 08:03:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:03:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:03:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:03:28 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-W_cCap-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:03:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:03:28 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-Paw1gX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:03:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:03:29 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-cj1Z2H-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:03:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:03:29 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-F5yRBV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:03:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:03:29 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-WxS6xx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:03:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:03:29 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-b7QIow-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:03:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:03:29 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-tTIcEh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:03:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:03:29 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-t3qrK6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:03:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:03:29 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-MDvYVG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:03:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:03:29 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-11U5aW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:03:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:03:29 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-48P1po-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:03:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:03:29 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-qVaeLR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:03:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:03:30 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-TfeWvo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:03:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:03:30 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-3rVBuG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:03:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:03:30 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-fFtJjA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:03:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:03:30 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-Q4FOls-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:03:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:03:30 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-fdj2Px-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:03:30 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 08:03:30 INFO - Running setup.py install for manifestparser 08:03:30 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 08:03:30 INFO - Running setup.py install for mozcrash 08:03:30 INFO - Running setup.py install for mozdebug 08:03:31 INFO - Running setup.py install for mozdevice 08:03:31 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 08:03:31 INFO - Installing dm script to /builds/slave/test/build/venv/bin 08:03:31 INFO - Running setup.py install for mozfile 08:03:31 INFO - Running setup.py install for mozhttpd 08:03:31 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 08:03:31 INFO - Running setup.py install for mozinfo 08:03:31 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 08:03:31 INFO - Running setup.py install for mozInstall 08:03:31 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 08:03:31 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 08:03:31 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 08:03:31 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 08:03:31 INFO - Running setup.py install for mozleak 08:03:31 INFO - Running setup.py install for mozlog 08:03:32 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 08:03:32 INFO - Running setup.py install for moznetwork 08:03:32 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 08:03:32 INFO - Running setup.py install for mozprocess 08:03:32 INFO - Running setup.py install for mozprofile 08:03:32 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 08:03:32 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 08:03:32 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 08:03:32 INFO - Running setup.py install for mozrunner 08:03:32 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 08:03:32 INFO - Running setup.py install for mozscreenshot 08:03:32 INFO - Running setup.py install for moztest 08:03:33 INFO - Running setup.py install for mozversion 08:03:33 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 08:03:33 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 08:03:33 INFO - Cleaning up... 08:03:33 INFO - Return code: 0 08:03:33 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:03:33 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')]} 08:03:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:03:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:03:33 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')]} 08:03:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:03:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:03:33 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 0x10120eb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101211768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff6f859de50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100fecd30>, '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 0x100f13db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100fe1be8>, '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.JXq6ilxw7t/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.fSuLBGiLYO/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/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 08:03:33 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 08:03:33 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 08:03:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.JXq6ilxw7t/Render', 08:03:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:03:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:03:33 INFO - 'HOME': '/Users/cltbld', 08:03:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:03:33 INFO - 'LOGNAME': 'cltbld', 08:03:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:03:33 INFO - 'MOZ_NO_REMOTE': '1', 08:03:33 INFO - 'NO_EM_RESTART': '1', 08:03:33 INFO - 'PAGER': '/bin/cat', 08:03:33 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:03:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:03:33 INFO - 'PWD': '/builds/slave/test', 08:03:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:03:33 INFO - 'SHELL': '/bin/bash', 08:03:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.fSuLBGiLYO/Listeners', 08:03:33 INFO - 'TMPDIR': '/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/', 08:03:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:03:33 INFO - 'USER': 'cltbld', 08:03:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:03:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:03:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:03:33 INFO - 'XPC_FLAGS': '0x0', 08:03:33 INFO - 'XPC_SERVICE_NAME': '0', 08:03:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:03:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:03:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:03:33 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-MRs7bc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:03:33 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)) 08:03:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:03:33 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-ZLI9PS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:03:33 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)) 08:03:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:03:33 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-fY1mMg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:03:33 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)) 08:03:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:03:33 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-133l5A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:03:33 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)) 08:03:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:03:33 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-774chG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:03:33 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)) 08:03:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:03:33 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-Wi7nIe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:03:34 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)) 08:03:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:03:34 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-F8qQOh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:03:34 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)) 08:03:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:03:34 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-6opql4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:03:34 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)) 08:03:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:03:34 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-_p6Lak-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:03:34 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)) 08:03:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:03:34 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-Nl1HyS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:03:34 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)) 08:03:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:03:34 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-BIcfMG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:03:34 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)) 08:03:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:03:34 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-3sHxBy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:03:34 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)) 08:03:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:03:34 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-4Jmu7e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:03:34 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)) 08:03:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:03:34 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-0uYvHf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:03:34 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)) 08:03:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:03:34 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-nP8M_O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:03:35 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)) 08:03:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:03:35 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-8Yypfh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:03:35 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 08:03:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:03:35 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-dgkc9q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:03:35 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)) 08:03:35 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)) 08:03:35 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)) 08:03:35 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)) 08:03:35 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)) 08:03:35 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)) 08:03:35 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 08:03:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:03:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:03:35 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:03:35 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:03:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:03:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:03:38 INFO - Downloading blessings-1.6.tar.gz 08:03:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 08:03:38 INFO - Installing collected packages: blessings 08:03:38 INFO - Running setup.py install for blessings 08:03:38 INFO - Successfully installed blessings 08:03:38 INFO - Cleaning up... 08:03:38 INFO - Return code: 0 08:03:38 INFO - Done creating virtualenv /builds/slave/test/build/venv. 08:03:38 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:03:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:03:38 INFO - Reading from file tmpfile_stdout 08:03:38 INFO - Current package versions: 08:03:38 INFO - blessings == 1.6 08:03:38 INFO - blobuploader == 1.2.4 08:03:38 INFO - docopt == 0.6.1 08:03:38 INFO - functools32 == 3.2.3-2 08:03:38 INFO - jsonschema == 2.5.1 08:03:38 INFO - manifestparser == 1.1 08:03:38 INFO - mozInstall == 1.12 08:03:38 INFO - mozcrash == 1.0 08:03:38 INFO - mozdebug == 0.1 08:03:38 INFO - mozdevice == 0.49 08:03:38 INFO - mozfile == 1.2 08:03:38 INFO - mozhttpd == 0.7 08:03:38 INFO - mozinfo == 0.9 08:03:38 INFO - mozleak == 0.1 08:03:38 INFO - mozlog == 3.4 08:03:38 INFO - moznetwork == 0.27 08:03:38 INFO - mozprocess == 0.23 08:03:38 INFO - mozprofile == 0.28 08:03:38 INFO - mozrunner == 6.13 08:03:38 INFO - mozscreenshot == 0.1 08:03:38 INFO - mozsystemmonitor == 0.3 08:03:38 INFO - moztest == 0.7 08:03:38 INFO - mozversion == 1.4 08:03:38 INFO - psutil == 3.1.1 08:03:38 INFO - requests == 1.2.3 08:03:38 INFO - wsgiref == 0.1.2 08:03:38 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:03:38 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')]} 08:03:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:03:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:03:38 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')]} 08:03:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:03:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:03:38 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 0x10120eb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101211768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff6f859de50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100fecd30>, '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 0x100f13db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100fe1be8>, '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.JXq6ilxw7t/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.fSuLBGiLYO/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/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 08:03:38 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 08:03:38 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 08:03:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.JXq6ilxw7t/Render', 08:03:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:03:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:03:38 INFO - 'HOME': '/Users/cltbld', 08:03:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:03:38 INFO - 'LOGNAME': 'cltbld', 08:03:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:03:38 INFO - 'MOZ_NO_REMOTE': '1', 08:03:38 INFO - 'NO_EM_RESTART': '1', 08:03:38 INFO - 'PAGER': '/bin/cat', 08:03:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:03:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:03:38 INFO - 'PWD': '/builds/slave/test', 08:03:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:03:38 INFO - 'SHELL': '/bin/bash', 08:03:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.fSuLBGiLYO/Listeners', 08:03:38 INFO - 'TMPDIR': '/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/', 08:03:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:03:38 INFO - 'USER': 'cltbld', 08:03:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:03:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:03:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:03:38 INFO - 'XPC_FLAGS': '0x0', 08:03:38 INFO - 'XPC_SERVICE_NAME': '0', 08:03:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:03:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:03:39 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)) 08:03:39 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)) 08:03:39 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)) 08:03:39 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)) 08:03:39 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)) 08:03:39 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)) 08:03:39 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)) 08:03:39 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)) 08:03:39 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)) 08:03:39 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)) 08:03:39 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)) 08:03:39 INFO - Cleaning up... 08:03:39 INFO - Return code: 0 08:03:39 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 08: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')]} 08:03:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:03:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08: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')]} 08:03:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:03:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:03:39 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10120eb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101211768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff6f859de50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100fecd30>, '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 0x100f13db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100fe1be8>, '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.JXq6ilxw7t/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.fSuLBGiLYO/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/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 08:03:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 08:03:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 08:03:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.JXq6ilxw7t/Render', 08:03:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:03:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:03:39 INFO - 'HOME': '/Users/cltbld', 08:03:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:03:39 INFO - 'LOGNAME': 'cltbld', 08:03:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:03:39 INFO - 'MOZ_NO_REMOTE': '1', 08:03:39 INFO - 'NO_EM_RESTART': '1', 08:03:39 INFO - 'PAGER': '/bin/cat', 08:03:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:03:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:03:39 INFO - 'PWD': '/builds/slave/test', 08:03:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:03:39 INFO - 'SHELL': '/bin/bash', 08:03:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.fSuLBGiLYO/Listeners', 08:03:39 INFO - 'TMPDIR': '/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/', 08:03:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:03:39 INFO - 'USER': 'cltbld', 08:03:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:03:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:03:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:03:39 INFO - 'XPC_FLAGS': '0x0', 08:03:39 INFO - 'XPC_SERVICE_NAME': '0', 08:03:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:03:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:03:39 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 08:03:39 INFO - Cleaning up... 08:03:39 INFO - Return code: 0 08:03:39 INFO - Running post-action listener: _resource_record_post_action 08:03:39 INFO - Running post-action listener: _start_resource_monitoring 08:03:39 INFO - Starting resource monitoring. 08:03:39 INFO - [mozharness: 2016-12-27 16:03:39.491900Z] Finished create-virtualenv step (success) 08:03:39 INFO - [mozharness: 2016-12-27 16:03:39.492934Z] Running install step. 08:03:39 INFO - Running pre-action listener: _resource_record_pre_action 08:03:39 INFO - Running main action method: install 08:03:39 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:03:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:03:39 INFO - Reading from file tmpfile_stdout 08:03:39 INFO - Detecting whether we're running mozinstall >=1.0... 08:03:39 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 08:03:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 08:03:39 INFO - Reading from file tmpfile_stdout 08:03:39 INFO - Output received: 08:03:39 INFO - Usage: mozinstall [options] installer 08:03:39 INFO - Options: 08:03:39 INFO - -h, --help show this help message and exit 08:03:39 INFO - -d DEST, --destination=DEST 08:03:39 INFO - Directory to install application into. [default: 08:03:39 INFO - "/builds/slave/test"] 08:03:39 INFO - --app=APP Application being installed. [default: firefox] 08:03:39 INFO - mkdir: /builds/slave/test/build/application 08:03:39 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'] 08:03:39 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 08:04:01 INFO - Reading from file tmpfile_stdout 08:04:01 INFO - Output received: 08:04:01 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 08:04:01 INFO - Running post-action listener: _resource_record_post_action 08:04:01 INFO - [mozharness: 2016-12-27 16:04:01.378991Z] Finished install step (success) 08:04:01 INFO - [mozharness: 2016-12-27 16:04:01.379123Z] Running run-tests step. 08:04:01 INFO - Running pre-action listener: _resource_record_pre_action 08:04:01 INFO - Running main action method: run_tests 08:04:01 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 08:04:01 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 08:04:01 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 08:04:01 INFO - 2016-12-27 08:04:01.393 screenresolution[1623:7385] starting screenresolution argv=screenresolution get 08:04:01 INFO - 2016-12-27 08:04:01.396 screenresolution[1623:7385] Display 0: 1600x1200x32@60 08:04:01 INFO - 2016-12-27 08:04:01.402 screenresolution[1624:7387] starting screenresolution argv=screenresolution list 08:04:01 INFO - Available Modes on Display 0 08:04:01 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 08:04:01 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 08:04:01 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 08:04:01 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 08:04:01 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 08:04:01 INFO - Intel Iris: 08:04:01 INFO - Chipset Model: Intel Iris 08:04:01 INFO - Type: GPU 08:04:01 INFO - Bus: Built-In 08:04:01 INFO - VRAM (Dynamic, Max): 1536 MB 08:04:01 INFO - Vendor: Intel (0x8086) 08:04:01 INFO - Device ID: 0x0a2e 08:04:01 INFO - Revision ID: 0x0009 08:04:01 INFO - Displays: 08:04:01 INFO - Display: 08:04:01 INFO - Resolution: 1600 x 1200 @ 60 Hz 08:04:01 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 08:04:01 INFO - Display Serial Number: 1600x1200 60 08:04:01 INFO - Main Display: Yes 08:04:01 INFO - Mirror: Off 08:04:01 INFO - Online: Yes 08:04:01 INFO - Rotation: Supported 08:04:01 INFO - Return code: 0 08:04:01 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 08:04:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 08:04:01 INFO - Python 2.7.3 08:04:01 INFO - Return code: 0 08:04:01 INFO - grabbing minidump binary from tooltool 08:04:01 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')]} 08:04:01 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 0x100fecd30>, '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 0x100f13db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100fe1be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 08:04:01 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 08:04:01 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 08:04:01 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 08:04:01 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 08:04:01 INFO - Return code: 0 08:04:01 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 08:04:01 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 08:04:01 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 08:04:01 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 08:04:01 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 08:04:01 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/IcDfeMPSQpuREzSUetvSmg/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0154', '--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 08:04:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/IcDfeMPSQpuREzSUetvSmg/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0154 --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 08:04:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.JXq6ilxw7t/Render', 08:04:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:04:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:04:01 INFO - 'HOME': '/Users/cltbld', 08:04:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:04:01 INFO - 'LOGNAME': 'cltbld', 08:04:01 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 08:04:01 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 08:04:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:04:01 INFO - 'MOZ_NO_REMOTE': '1', 08:04:01 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 08:04:01 INFO - 'NO_EM_RESTART': '1', 08:04:01 INFO - 'PAGER': '/bin/cat', 08:04:01 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:04:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:04:01 INFO - 'PWD': '/builds/slave/test', 08:04:01 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 08:04:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:04:01 INFO - 'SHELL': '/bin/bash', 08:04:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.fSuLBGiLYO/Listeners', 08:04:01 INFO - 'TMPDIR': '/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/', 08:04:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:04:01 INFO - 'USER': 'cltbld', 08:04:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:04:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:04:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:04:01 INFO - 'XPC_FLAGS': '0x0', 08:04:01 INFO - 'XPC_SERVICE_NAME': '0', 08:04:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:04:01 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/IcDfeMPSQpuREzSUetvSmg/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0154', '--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 08:04:01 INFO - mozversion application_buildid: 20161227064436 08:04:01 INFO - mozversion application_changeset: dccfc624915b1bebe7975076e9b42bd46684cbc6 08:04:01 INFO - mozversion application_display_name: Nightly 08:04:01 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 08:04:01 INFO - mozversion application_name: Firefox 08:04:01 INFO - mozversion application_remotingname: firefox 08:04:01 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 08:04:01 INFO - mozversion application_vendor: Mozilla 08:04:01 INFO - mozversion application_version: 53.0a1 08:04:01 INFO - mozversion platform_buildid: 20161227064436 08:04:01 INFO - mozversion platform_changeset: dccfc624915b1bebe7975076e9b42bd46684cbc6 08:04:01 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 08:04:01 INFO - mozversion platform_version: 53.0a1 08:04:01 INFO - using testdate: 1482854641 08:04:01 INFO - actual date: 1482854641 08:04:01 INFO - starting webserver on 'localhost:49241' 08:04:01 INFO - SUITE-START | Running 2 tests 08:04:01 INFO - TEST-START | dromaeo_css 08:04:01 INFO - operating with platform_type : mac_ 08:04:01 INFO - Initialising browser for dromaeo_css test... 08:04:02 INFO - TEST-INFO | started process 1634 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/tmpuaiMph/profile http://localhost:49241/getInfo.html) 08:04:02 INFO - PROCESS | 1634 | 1482854642541 addons.xpi WARN Exception running bootstrap method install on shield-recipe-client@mozilla.org: [Exception... "Component returned failure code: 0x8000ffff (NS_ERROR_UNEXPECTED) [nsIPrefBranch.getBoolPref]" nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js :: this.install :: line 38" data: no] Stack trace: this.install()@resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js:38 < callBootstrapMethod()@resource://gre/modules/addons/XPIProvider.jsm:4979 < processFileChanges()@resource://gre/modules/addons/XPIProvider.jsm -> resource://gre/modules/addons/XPIProviderUtils.js:2097 < checkForChanges()@resource://gre/modules/addons/XPIProvider.jsm:3825 < startup()@resource://gre/modules/addons/XPIProvider.jsm:2829 < callProvider()@resource://gre/modules/AddonManager.jsm:264 < _startProvider()@resource://gre/modules/AddonManager.jsm:771 < startup()@resource://gre/modules/AddonManager.jsm:957 < startup()@resource://gre/modules/AddonManager.jsm:2923 < observe()@resource://gre/components/addonManager.js:65 08:04:04 INFO - PROCESS | 1634 | __metrics Screen width/height:1600/1200 08:04:04 INFO - PROCESS | 1634 | colorDepth:24 08:04:04 INFO - PROCESS | 1634 | Browser inner width/height: 1024/689 08:04:04 INFO - PROCESS | 1634 | __metrics 08:04:04 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" 08:04:04 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] 08:04:05 INFO - TEST-INFO | 1634: exit 0 08:04:05 INFO - Browser initialized. 08:04:05 INFO - Running cycle 1/1 for dromaeo_css test... 08:04:05 INFO - TEST-INFO | started process 1636 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/tmpuaiMph/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:04:17 INFO - PROCESS | 1636 | RSS: Main: 234151936 08:04:17 INFO - PROCESS | 1636 | 08:07:20 INFO - PROCESS | 1636 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49241/tests/dromaeo/cssquery-ext.html) 08:07:23 INFO - PROCESS | 1636 | RSS: Main: 436727808 08:07:23 INFO - PROCESS | 1636 | 08:09:06 INFO - PROCESS | 1636 | ************************* 08:09:06 INFO - PROCESS | 1636 | A coding exception was thrown and uncaught in a Task. 08:09:06 INFO - PROCESS | 1636 | 08:09:06 INFO - PROCESS | 1636 | Full message: ReferenceError: fetch is not defined 08:09:06 INFO - PROCESS | 1636 | Full stack: apiCall@resource://shield-recipe-client/lib/NormandyApi.jsm:37:5 08:09:06 INFO - PROCESS | 1636 | get@resource://shield-recipe-client/lib/NormandyApi.jsm:44:12 08:09:06 INFO - PROCESS | 1636 | this.NormandyApi.fetchRecipes<@resource://shield-recipe-client/lib/NormandyApi.jsm:52:34 08:09:06 INFO - PROCESS | 1636 | TaskImpl_run@resource://gre/modules/Task.jsm:319:42 08:09:06 INFO - PROCESS | 1636 | TaskImpl@resource://gre/modules/Task.jsm:277:3 08:09:06 INFO - PROCESS | 1636 | asyncFunction@resource://gre/modules/Task.jsm:252:14 08:09:06 INFO - PROCESS | 1636 | this.RecipeRunner.start<@resource://shield-recipe-client/lib/RecipeRunner.jsm:64:23 08:09:06 INFO - PROCESS | 1636 | TaskImpl_run@resource://gre/modules/Task.jsm:319:42 08:09:06 INFO - PROCESS | 1636 | TaskImpl@resource://gre/modules/Task.jsm:277:3 08:09:06 INFO - PROCESS | 1636 | asyncFunction@resource://gre/modules/Task.jsm:252:14 08:09:06 INFO - PROCESS | 1636 | setTimeout_timer@resource://gre/modules/Timer.jsm:30:5 08:09:06 INFO - PROCESS | 1636 | 08:09:06 INFO - PROCESS | 1636 | ************************* 08:10:26 INFO - PROCESS | 1636 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-ext.html (next: http://localhost:49241/tests/dromaeo/cssquery-jquery.html) 08:10:26 INFO - PROCESS | 1636 | RSS: Main: 371052544 08:10:26 INFO - PROCESS | 1636 | 08:13:29 INFO - PROCESS | 1636 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49241/tests/dromaeo/cssquery-mootools.html) 08:13:29 INFO - PROCESS | 1636 | RSS: Main: 357044224 08:13:29 INFO - PROCESS | 1636 | 08:16:32 INFO - PROCESS | 1636 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49241/tests/dromaeo/cssquery-prototype.html) 08:16:33 INFO - PROCESS | 1636 | RSS: Main: 355975168 08:16:33 INFO - PROCESS | 1636 | 08:19:36 INFO - PROCESS | 1636 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49241/tests/dromaeo/cssquery-yui.html) 08:19:36 INFO - PROCESS | 1636 | RSS: Main: 339320832 08:19:36 INFO - PROCESS | 1636 | 08:22:40 INFO - PROCESS | 1636 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-yui.html (next: http://localhost:49241/tests/dromaeo/cssquery-dojo.html) 08:22:40 INFO - PROCESS | 1636 | RSS: Main: 475123712 08:22:40 INFO - PROCESS | 1636 | 08:22:40 INFO - PROCESS | 1636 | __start_tp_report 08:22:40 INFO - PROCESS | 1636 | _x_x_mozilla_page_load 08:22:40 INFO - PROCESS | 1636 | _x_x_mozilla_page_load_details 08:22:40 INFO - PROCESS | 1636 | |i|pagename|runs| 08:22:40 INFO - PROCESS | 1636 | |0;dojo.html;3346.9330613387733;3367.7810942288734;3370.4438767603347;3392.609849867266;3428.1601007753106;5645.802396916109;5660.490555849972;5705.315362156542;5754.136879468082;5818.650880947146;4807.807687692488;5080.136376815941;5747.568932330071;5758.5393168546525;5814.970925145379;5242.1088414969545;5254.500822421883;5272.472752724735;5278.973605131969;5295.682259064469;5202.141646628302;5335.7332133393365;5339.733013349336;5365.409804921455;5373.032854086263;4405.118976204756;5286.418493965661;5287.867803304929;5300.549453296474;5309.283246761695;5156.329677141947;5188.766505507261;5282.709450980195;5301.575873930076;5346.171343441753;5131.692098474104;5187.221916712486;5200.089984252779;5203.557697595709;5259.894802103975;5641.774329026834;5688.544916406678;5703.9429605704245;5713.457221563986;5722.513586345166;6277.121203031579;6294.3705629437145;6299.118123462719;6313.305536390993;6317.905231421532;6126.5176737973225;6162;6220.751169953196;6221.657808820513;6266.530010249204;5834.124881267759;5840.795572154954;5860.120981852756;5875.764969401176;5897.439743224387;6142.477889379364;6274.937250627435;6276.246850377984;6278.30938596754;6281.120643109968;6025.18659980897;6079.422454866781;6088.056351265561;6197;6218.595791273643;6164.537659675542;6214.689265536705;6243.687815609202;6257.436830685169;6297.937020629826;6143.692815359214;6173.660448675276;6276.529260305404;6283.905741413882;6285.340039295899;6063.969680151571;6181.567290289637;6184.938150618526;6187.690615469209;6244.718987645567;4912.4350699669285;4959.82640607577;4974;4975.975120124376;4985;4360.454943132108;4376.496702879146;4382.364557139261;4384.276594361958;4398.758068306274;5803.071508558611;5844.094165404369;5850.297964244318;5866.882662346729;5875.618084824473;6267.749290028348;6276.497880169667;6280.842978925563;6291.5281353898645;6321.55749097568;5661.094224923992;5694.6583205007;5700.486956173964;5835.56233282497;5875.529957603381;5350.732463376816;5384.757685904144;5399.352077750616;5406.891862162814;5414.620976531605;6224.439800417984;6235.937640623536;6241.282252540835;6260.530460215411;6269.780557680641;6212.409821066992;6258.843528911813;6280.748770049329;6314.431701146918;6323.588966717149;6339.683015849281;6348.111264422892;6351;6359.841003974938;6362.522810789117;6072.787452439144;6135.293912123373;6138.02057325484;6139.785107521394;6244.500439964882;6251.437370636664;6267.717952692049;6286.968565157145;6293.748250070129;6310.558260921875;6136.570440069152;6148.415900489446;6166.599171053867;6167.105769663284;6167.167432396748;5969.462748352669;6031.819045428644;6074.878502429839;6140.416660417253;6151.84620384494;5589;5841.18223448726;5855.063189889597;5948.583599148018;5992.250968628921;6104.359042300494;6104.755809767559;6115.4190351916495;6119.388061193845;6126.969365153146;5871.73577189036;5877.353491115889;5928.525717942641;5932.080527518242;5934.406559344204 08:22:40 INFO - PROCESS | 1636 | |1;ext.html;413.27882844436465;458.51397518630233;489.7379901752563;501.75664802570725;511.0519985427033;33117.00648980532;38315.23369532611;38365.80817095918;38430.23139537207;40049;38785;39214.60785392145;39360.40959385604;39360.80319598405;39468.80265598668;8401.95799021004;9292.395994260369;9503.90496095037;9504.382215156027;9515.857262141039;8480.872786908201;11107;11350.20548561601;11357.375344356056;11519.827202591969;11272;11335.319880807137;11349.659510214666;11350.829737553902;11359.375234362107;8474.872876906851;8696.304295656333;11296.322220666745;11379.487923043442;11406.600768973047;8991.595378207996;11246.718832029183;11285.887141128647;11445.08439324852;11452.141089418245;37999.81000095009;38377.42433863494;38384.616153838666;38448.23103537913;38522;3969.563348031714;3994.141259629194;4012.658923991435;4035.636792688643;4057.39139129133;28968.42063158746;29696.554551681744;29697.406051879057;29737.959171429113;29831.552526712116;3180.77734558581;3299.6700329967052;3302.735781137503;3312.188513814107;3351.396748585278;448219;450722.64521644503;452866;454032;454442;54768.23708206668;55037;55128.897422051334;55143.72428137834;55202.9999999992;56215;56319.71840140773;56564.43435565592;58309;58346.41653583495;14604.561863144123;14816.40734370613;14826.33281502335;14827.851721482648;14835;10441.919633708074;10697.732556686145;10712.571497140027;11367.658970230907;11483.483243254073;13616.77449029592;15583.766243506356;15754.842451575567;15873.52379428619;15961.44134955271;6780.897556978056;6788.626625535544;6847.109875716125;6871.347222013901;6893.620850853151;860.5009094725044;863.3725427568952;866.4627930682891;891.4829398948594;891.7815135291895;28751.137465876058;28834.567481487793;29170;29196.7080329194;29387.11838644844;1037.8080055189814;1039.7556574205055;1059.390850261103;1071.2715353559654;1185.4961641302411;14320.283985800668;14886.255687215813;14903.329350179269;14970.850291497163;15110.320035598425;607140;609450;610024;610472;612491;56091.15863262054;56141.43858561362;56357.71821140868;56371.436285637436;56444.153337699965;1358.381936219018;1387.6600232942994;1408.0425310788762;1408.7112142010862;1409.0207305922286;22944.655830162894;24723.134690285755;27838.582421264102;28384.71615283903;28723;47353.89691546296;47668.52331476779;47791.522084778706;47980.5201947976;48034.75982620064;528692;533171;533603.3319833376;533673;535853;37223.9465646966;37876.242475150895;38128.809355953046;38212.23575528529;38223.42664860085;41406.585934140276;41585.792071039454;41605.79197103995;43379.566204337556;44061;32485.837570811993;34364.65635343615;35684.107897302776;36359.09102272464;36993;35843.82078089593;36196.484632766354;36695.63304366922;36877.44683829798;37392.25215495729;33956.830215848764;35239.714042892745;35553.46669800007;36185.81907090448;36331.45502817513;31326.843365783025;32354.83822580872;32405.35189296248;32856.67143328536;32868.83565582157;22982;23628;24028.39929001789;24456.510869782862;24760 08:22:40 INFO - PROCESS | 1636 | |2;jquery.html;316.9746420286377;349.5839950458952;360.4881068882187;363.7072156913685;397.4376257595501;14402.639934001656;14702.779458308134;15384.307706153208;15765.448209312677;19821.801781982213;18807.24771009158;19434.805651943476;20108.69836952443;20357.491062723402;20385.796142038576;5650.886982260363;5980.132880732292;5988.2215312009375;6001.369856165084;6001.759929602811;5628.409017053212;5786.247787787603;5825.708714564276;5868.706564671771;5939.049752039675;5801.07182850744;5801.883962320751;5807.332156801958;5817.505512031461;5823.01008828498;5642.294713160834;5642.915356269659;5768.942310576925;5773.624714393593;5799.072148456226;5048.747562621854;5053.9241911371355;5066.505380912603;5067.847964561069;5090.2364645303505;26146.73853261462;26390;27003.45993080147;28385.716142838515;28789.71210287891;3155.2015351249797;3236.546883436321;3282.0646115857103;3313.4698448248164;3393.406153923054;15524.663677130045;15700.528984130495;16478.011319320878;16488.83511164885;16517.58706032347;1985.9602807943904;1988.5823976964855;2011.2457828314382;2011.6077364913847;2084.3642688979853;97743.0225697741;98863.50568247185;99588.0041199586;110944;121688.78311216863;18942.590333739783;20200.181983621544;20364;20672.379828605164;20831.791682083287;33044.339113217604;33856.32287354239;33964.66035339664;34045.65954340425;34106.65893341084;8131.79670508242;10055.530841859547;10229.386236825665;10254.43600601959;10376.792464150674;8232.917670823215;8263.834723305501;8273.089960104504;8404;8436.114208008194;8069.798255043671;8114.43198976066;9488.667896623585;9759.609615615438;9875.358101723508;3182.840857957139;3767.2970364991247;3829.381234733206;3830.1465408271015;3843.3466310727244;974.8001659659752;984.3798407003542;989.2877128467492;998.8701468809008;1017.872765904262;16092;19936;20026.399208023784;20068.39794806158;20111.095000725003;994.6469003503797;998.2463240253562;1013.9391636501833;1015.6648306058983;1018.3889666200221;8442.915570844336;10168.745781355377;10492.685219443429;10511.52698128586;10547.103496202755;539244;548284;551894;553701;553926;109532;121817.39091304663;122282.38858805649;122659;123970.38014809869;1000.2997901469001;1000.6497725795913;1025.6307729217476;1026.0304012708068;1027.660871912267;17251;17406;17617.910447761195;17729.202185901922;17798.91100544489;42958.57041429546;52873;52907;53681.97385196048;55263.34209973707;76395;77389;77675;77696.61151694205;78742.81885771603;6609.636469994197;6893.586384816927;7067.893981590178;7171.677274522554;7302.2697730226555;8907.777305567153;10900.509477073709;12963.15739476931;13132.671683207996;13693.178409295475;10217.795644087226;11536.307821530734;11785.587504437304;11818.704532386759;11934.761304774029;11228.813559322203;11336.433178341214;11580.131490138105;11649.650510484698;11860.466279017637;11366.488508016993;11450.82823757661;11905.523779049088;11946.98450631706;11988.700282493008;7025.964870175617;7081.8937715935335;7161.928380716126;7267.672954717156;7382.818749000135;9109.772255693395;9865.704028879145;11343.319400835977;11748.29510229389;11826.645200643994 08:22:40 INFO - PROCESS | 1636 | |3;mootools.html;1209.7157168065503;1298.5714714144322;1409.22492629054;1421.5735279416172;1435.3756116089503;6415.358464153588;7867.449278550504;8079.555624440653;8344.123866993954;8345.165483451652;8235.258826705594;8326.500409975406;8336.16638336166;8348.998120225566;8351.540665263408;2065.9070341834654;2302.343832007878;2342.61346877765;2351.8824058797077;2353.6587194856734;2136.038782547852;2251.876146811933;2306.054517647765;2309.261036468331;2330.5455436189864;2267.319804058776;2278.977210227901;2290.9083636654514;2294.5869743446174;2302.1482051640915;2288.553732022256;2299.482616411311;2313.514162025985;2315.4674424882373;2317.1426572168407;2288.8969996350206;2318.6869772580653;2319.8608083514873;2326.336993956731;2327.243645815117;8157;8275.04836943747;8466.957665211696;8500.702475413393;8501.532415717133;4729.219678753002;4896.751328411268;4989.476105008958;5165.096108181091;5172.250023746536;3152.274976755359;3274.2305558193807;3355.144438168276;3359.6808303211155;3363.983180084084;1157.5427706056146;1211.1037832004254;1211.2066596379386;1249.0507214516942;1262.873712628739;10741.51663175159;10868.239223254375;10885.510152043098;10892.836607450894;10903.563857445693;4424.734515929054;4540.88647783808;4578.038611891474;4619.422572178478;4619.584237418649;9258.907410925805;9532.237421006303;9542.61829526825;9543.570539325608;9564.282678799118;5159.148740457783;5167.0957582422925;5167.354080739908;5170.6122040847085;5173.844784656466;4635.698679585884;4655.348251244828;4717.24524076153;4740.407449068866;4744.833930812474;3774.490443790053;3792.1278106035215;3837.059920319484;3868.593797651206;3879.359905615598;1251.8310028146084;1255.1026016398318;1258.9685257868443;1268.403850190409;1273.2551457397342;741.5847125609567;751.6655088485571;772.5094564951296;777.3547955197173;798.4969469234345;5496;5629.746661400247;5850.5027072699295;5869.178315035899;5879.588428809942;896.9327300452492;901.4456109492614;915.8122584870218;916.3860213656866;949.5821838391086;3677.4116141417244;3760.0975765816556;3852.8651497197466;3861.532617605292;3882;9679.225661947028;10033.598656053822;10056.296059275926;10069.79860402788;10187.592496300213;9403.388779729295;9886.505674716236;9893.010698930193;10146.898531014744;10152.644657436955;7564.810879727941;7567.848643027219;7729.381649468029;7773.416993725493;7781.027371578552;5398.271233383443;5554.555635549227;5582.413846546055;5689.77240910359;5931.466168044724;9133.817323653357;9163.908360916306;9243.2143267823;9266.53667316618;9271.02654221297;8592.699255526026;9148.542572871464;9246.95376523113;9429.057094290516;9454.290928180551;6786.423154031962;6877.1747390313485;6935.167779866449;7003.054587630605;7154.534955227893;7817.765467035998;7906.051273847175;7914.485558438683;7971.12317645047;8007;7020.648967551704;7057.117860267466;7100.289971002859;7104.893426598708;7131.964340178266;5950.899083669535;6023.849403764941;6865.965670171618;6952.652367381712;6964.756233531802;6273;6320.557560970872;6360.173177486897;6415.230172379344;6418.775342862978;4636.072785442858;4710.528947105262;4755.738434386004;4790.496997815179;4911.63162762787;4719.929201062056;4789.281607758864;4909.4354149273295;5034.84895453137;5035.244713293036 08:22:40 INFO - PROCESS | 1636 | |4;prototype.html;308.28170363054085;324.4209086780097;328.404125812753;348.2634228606498;355.2132027558956;4122.3816427535885;4318.82724691012;4462.977685111574;4714.669973101884;4768.950830817219;4191.407265239212;4591.380163677903;5016.272640467139;5023.572996295319;5058.392992840855;3284.310294838087;3465.1856813648774;3924.4309575111592;3935.704822138344;4027.697922655798;3582.5521809773777;3612.518867264422;3802.8098595070273;4006.399040143973;4010.7393019453643;3070.6468756093;3116.8467666963256;3735.981320093396;3845.634664706862;3861.6331448512346;3408.9147771305666;3819.140693344003;3837.980810095946;3849.248591890711;3853.807309634535;3326.8170250636203;3348.94040165299;3351.1957130288797;3354.899353019389;3355.1779813945495;4061.6141466560925;4839.306242814987;4859.441164266084;4909.3126962225315;4987.226979818134;3539.3275277697153;3775.2826962876966;4126.690498212616;4358.368036634702;4369.579886536888;4632.239748895398;4714.740689262089;5243.764030618631;5345.919811202835;5367.865803354908;3647.963520364789;3667.331364229269;3669.2478042001594;3679.4848721179055;3680.4847321375028;4722.409698787652;4736.478987311393;5270.5520030797425;5569.498745112919;5593.937151941159;3637.6907962823457;3649.543807024122;3650.5215387768;3654.9634503655157;3671.2290419011774;3674.5039419678;3679.4848721179055;3685.4840322354894;3701.8714239312503;3709.46212799148;3475.9304813904087;4133.586641335843;4304.225239456865;4319;4351.303791393362;4969.925451118236;5217.9478205217465;5276.894462110737;5283.418823929364;5306.787728490895;1069.6363236499628;1153.5904753812445;1159.7042754097652;1202.909781766371;1218.5795900414344;784.8312612788278;827.7806381308958;833.6498670558259;839.7732612194674;845.218173189797;3908.726389152732;4166.520850102216;4767.904641907212;4784.784684689197;4911.943932054625;4228.281192197333;4543.931841022388;4978.332258693378;5221.780743184131;5233.581313494911;4154.543000269968;4435.933460998088;4573.706931009668;4785.95214047862;4806.399200099988;2527.886245118974;2598.7141414444395;2670.8798104085363;2695.355832941904;2740.643716316866;4561.589456948891;4649.651276154302;5170.293803045061;5458.781648734085;5497.697626630494;4745.596624286905;5135.460776618476;5398;5419.674819510763;5458.808941687022;4313.331433627793;4987.675801072919;5078.568321692697;5687.971560142173;5695.031844586513;5170.810713535832;5443.7822487100075;5480.91778623313;5496.725163741717;5504.504594586507;4769.472179744602;4818.903621927612;4881.877953051202;5442.292501974718;5461.235427040297;5277.551408130352;5472.808451704172;5485.396606373216;5671.52540339523;5704.515116215168;3657.158853463665;3706.3699171140433;3713.997220750436;3714.814259287079;3716.869909553153;4662.53374662531;4891.3124971884645;5517.448255174611;5534.446555344433;5545.528630066328;4561.950751327147;4869.440014398389;5324.387695415076;5391.2182733504205;5413.702246376487;5326.893462130658;5337.968096051437;5351.919721204107;5372.704501252469;5379.838604841861;3592.928141437209;4436.977815111033;4646.256598944153;5143.5627971622835;5230.660007099526;4702.753770250954;4769.523047695203;5039.061719514969;5181.3005244293045;5335.493128152819;4449.688521803443;5090.0583392072585;5132.6150538709;5242.68543887368;5252.395974462986 08:22:40 INFO - PROCESS | 1636 | |5;yui.html;701.7754318618045;730.1566690472506;759.7492827366967;767.1829501580812;775.7750252426795;14574.854251457495;15214.695706085871;15219.923900380496;15235.695286094286;15248.69502609947;13863.861361386384;14015.649608759786;14018.579442616712;14033.438662453515;14034.929825350859;705.1080383315085;724.0298000679089;729.6898818002354;735.485160387728;873.611242996865;718.6191318601124;747.5925620536793;873.6723728601775;900.5587262241487;914.0950459045564;941.5433514745343;953.3326671330062;962.6197651927484;963.6001059560274;964.7973925475659;954.5609019850842;956.7464621875139;956.9712908612753;958.5063692198523;958.6788425877339;548.081962712459;568.9487946084831;628.6448156791444;714.1965289049818;716.298027932629;13641.590752277349;14150.00949933505;14224.502142425068;14471.276436178148;14485.42058317666;12584.244945303311;12693.492260309577;12731.108822382437;12943.870561294361;12992.740145197044;11555.595554155649;11584.95735383811;11610.303381797119;11784.882151178464;11799.292042477478;3707.5334562184175;3722.348588996942;3763.7741735495956;3764.5482542095124;3798.639129282714;98304;104348.47825760898;107171.46414267957;107568;107861.46069269604;10430.582776689;10514.369137851754;10813.56745730162;10848.294860834178;11015.118790496741;20057.29799457041;20425.285115020903;20819.271325503534;20846.583068338547;20893.37319880406;7419.740309089156;7597.468177227541;7628.313451789255;7636.121845987672;7880.487768295043;4244;4330.935035974463;4351.06452112791;4372.191144638252;4445.244308467568;3345.364380767695;3412.5051867479574;3454.360943225511;3456.948145777815;3458.1873259784434;629.2291942370651;633.4299130782333;634.9111124442582;647.601724939159;651.5569412799342;614.27822308787;622.6233128956916;625.4558534075384;626.7621447640872;629.8362425769333;8585.270252028522;8809.911900881038;8886.688965886164;8916.465012099296;9200.539973001323;609.387565496673;624.6127401011311;630.0832289019465;630.8139098965817;632.5951391109694;3411.368896754059;4240.769488022914;4387.122575484916;4392.3191905254735;4539.7049191802425;73994;104114.47942760237;107417;112023;112623;96051;96358;98662.02675946441;98786.5060674692;101707.47438788424;64939.67530162319;85928;86900.56549717211;87602.56198718966;88113.55943220243;8439.240468357877;8535.402521823413;8544.658213671384;8571.871421928801;8688.435251708619;54495.36513904589;55215.72392138014;55612.72193639006;55779;56217;69953;74199;74590;74949.50100998058;75020.62489687516;10569.365838049742;12420.751584968431;12652.304123273309;12687.302198379179;12905.612831614691;14349.426022959382;14430.639234018814;14706;14738.042027268193;15176.544703658908;12892.419841106985;13343.266120363473;13424.838300126992;13442.782511967698;13454;12881.484740610646;13112.606621801362;13191.934040329737;13333.533326333532;13411.865881341062;12940.15888967214;13056.151350162208;13147.60557183286;13230.867691323348;13656.425012841404;10725.678229653124;11033.227674062739;11309.830352544554;11355.54577816878;11623;9951.303408761607;9962.773266708307;10060.565776616002;10352.637657682246;10907.181961352771 08:22:40 INFO - PROCESS | 1636 | __end_tp_report 08:22:40 INFO - PROCESS | 1636 | __start_cc_report 08:22:40 INFO - PROCESS | 1636 | _x_x_mozilla_cycle_collect,2500 08:22:40 INFO - PROCESS | 1636 | __end_cc_report 08:22:40 INFO - PROCESS | 1636 | __startTimestamp1482855760464__endTimestamp 08:22:40 INFO - PROCESS | 1636 | 08:22:40 INFO - PROCESS | 1636 | ------- Summary: start ------- 08:22:40 INFO - PROCESS | 1636 | Number of tests: 6 08:22:40 INFO - PROCESS | 1636 | 08:22:40 INFO - PROCESS | 1636 | [#0] dojo.html Cycles:165 Average:5751.40 Median:5934.41 stddev:646.20 (10.9%) stddev-sans-first:620.05 08:22:40 INFO - PROCESS | 1636 | Values: 3346.9 3367.8 3370.4 3392.6 3428.2 5645.8 5660.5 5705.3 5754.1 5818.7 4807.8 5080.1 5747.6 5758.5 5815.0 5242.1 5254.5 5272.5 5279.0 5295.7 5202.1 5335.7 5339.7 5365.4 5373.0 4405.1 5286.4 5287.9 5300.5 5309.3 5156.3 5188.8 5282.7 5301.6 5346.2 5131.7 5187.2 5200.1 5203.6 5259.9 5641.8 5688.5 5703.9 5713.5 5722.5 6277.1 6294.4 6299.1 6313.3 6317.9 6126.5 6162.0 6220.8 6221.7 6266.5 5834.1 5840.8 5860.1 5875.8 5897.4 6142.5 6274.9 6276.2 6278.3 6281.1 6025.2 6079.4 6088.1 6197.0 6218.6 6164.5 6214.7 6243.7 6257.4 6297.9 6143.7 6173.7 6276.5 6283.9 6285.3 6064.0 6181.6 6184.9 6187.7 6244.7 4912.4 4959.8 4974.0 4976.0 4985.0 4360.5 4376.5 4382.4 4384.3 4398.8 5803.1 5844.1 5850.3 5866.9 5875.6 6267.7 6276.5 6280.8 6291.5 6321.6 5661.1 5694.7 5700.5 5835.6 5875.5 5350.7 5384.8 5399.4 5406.9 5414.6 6224.4 6235.9 6241.3 6260.5 6269.8 6212.4 6258.8 6280.7 6314.4 6323.6 6339.7 6348.1 6351.0 6359.8 6362.5 6072.8 6135.3 6138.0 6139.8 6244.5 6251.4 6267.7 6287.0 6293.7 6310.6 6136.6 6148.4 6166.6 6167.1 6167.2 5969.5 6031.8 6074.9 6140.4 6151.8 5589.0 5841.2 5855.1 5948.6 5992.3 6104.4 6104.8 6115.4 6119.4 6127.0 5871.7 5877.4 5928.5 5932.1 5934.4 08:22:40 INFO - PROCESS | 1636 | 08:22:40 INFO - PROCESS | 1636 | [#1] ext.html Cycles:180 Average:66163.99 Median:37108.47 stddev:142953.34 (385.2%) stddev-sans-first:143269.12 08:22:40 INFO - PROCESS | 1636 | Values: 413.3 458.5 489.7 501.8 511.1 33117.0 38315.2 38365.8 38430.2 40049.0 38785.0 39214.6 39360.4 39360.8 39468.8 8402.0 9292.4 9503.9 9504.4 9515.9 8480.9 11107.0 11350.2 11357.4 11519.8 11272.0 11335.3 11349.7 11350.8 11359.4 8474.9 8696.3 11296.3 11379.5 11406.6 8991.6 11246.7 11285.9 11445.1 11452.1 37999.8 38377.4 38384.6 38448.2 38522.0 3969.6 3994.1 4012.7 4035.6 4057.4 28968.4 29696.6 29697.4 29738.0 29831.6 3180.8 3299.7 3302.7 3312.2 3351.4 448219.0 450722.6 452866.0 454032.0 454442.0 54768.2 55037.0 55128.9 55143.7 55203.0 56215.0 56319.7 56564.4 58309.0 58346.4 14604.6 14816.4 14826.3 14827.9 14835.0 10441.9 10697.7 10712.6 11367.7 11483.5 13616.8 15583.8 15754.8 15873.5 15961.4 6780.9 6788.6 6847.1 6871.3 6893.6 860.5 863.4 866.5 891.5 891.8 28751.1 28834.6 29170.0 29196.7 29387.1 1037.8 1039.8 1059.4 1071.3 1185.5 14320.3 14886.3 14903.3 14970.9 15110.3 607140.0 609450.0 610024.0 610472.0 612491.0 56091.2 56141.4 56357.7 56371.4 56444.2 1358.4 1387.7 1408.0 1408.7 1409.0 22944.7 24723.1 27838.6 28384.7 28723.0 47353.9 47668.5 47791.5 47980.5 48034.8 528692.0 533171.0 533603.3 533673.0 535853.0 37223.9 37876.2 38128.8 38212.2 38223.4 41406.6 41585.8 41605.8 43379.6 44061.0 32485.8 34364.7 35684.1 36359.1 36993.0 35843.8 36196.5 36695.6 36877.4 37392.3 33956.8 35239.7 35553.5 36185.8 36331.5 31326.8 32354.8 32405.4 32856.7 32868.8 22982.0 23628.0 24028.4 24456.5 24760.0 08:22:40 INFO - PROCESS | 1636 | 08:22:40 INFO - PROCESS | 1636 | [#2] jquery.html Cycles:180 Average:34000.22 Median:18163.20 stddev:91522.91 (503.9%) stddev-sans-first:91744.71 08:22:40 INFO - PROCESS | 1636 | Values: 317.0 349.6 360.5 363.7 397.4 14402.6 14702.8 15384.3 15765.4 19821.8 18807.2 19434.8 20108.7 20357.5 20385.8 5650.9 5980.1 5988.2 6001.4 6001.8 5628.4 5786.2 5825.7 5868.7 5939.0 5801.1 5801.9 5807.3 5817.5 5823.0 5642.3 5642.9 5768.9 5773.6 5799.1 5048.7 5053.9 5066.5 5067.8 5090.2 26146.7 26390.0 27003.5 28385.7 28789.7 3155.2 3236.5 3282.1 3313.5 3393.4 15524.7 15700.5 16478.0 16488.8 16517.6 1986.0 1988.6 2011.2 2011.6 2084.4 97743.0 98863.5 99588.0 110944.0 121688.8 18942.6 20200.2 20364.0 20672.4 20831.8 33044.3 33856.3 33964.7 34045.7 34106.7 8131.8 10055.5 10229.4 10254.4 10376.8 8232.9 8263.8 8273.1 8404.0 8436.1 8069.8 8114.4 9488.7 9759.6 9875.4 3182.8 3767.3 3829.4 3830.1 3843.3 974.8 984.4 989.3 998.9 1017.9 16092.0 19936.0 20026.4 20068.4 20111.1 994.6 998.2 1013.9 1015.7 1018.4 8442.9 10168.7 10492.7 10511.5 10547.1 539244.0 548284.0 551894.0 553701.0 553926.0 109532.0 121817.4 122282.4 122659.0 123970.4 1000.3 1000.6 1025.6 1026.0 1027.7 17251.0 17406.0 17617.9 17729.2 17798.9 42958.6 52873.0 52907.0 53682.0 55263.3 76395.0 77389.0 77675.0 77696.6 78742.8 6609.6 6893.6 7067.9 7171.7 7302.3 8907.8 10900.5 12963.2 13132.7 13693.2 10217.8 11536.3 11785.6 11818.7 11934.8 11228.8 11336.4 11580.1 11649.7 11860.5 11366.5 11450.8 11905.5 11947.0 11988.7 7026.0 7081.9 7161.9 7267.7 7382.8 9109.8 9865.7 11343.3 11748.3 11826.6 08:22:40 INFO - PROCESS | 1636 | 08:22:40 INFO - PROCESS | 1636 | [#3] mootools.html Cycles:180 Average:5390.67 Median:5166.10 stddev:2955.36 (57.2%) stddev-sans-first:2946.94 08:22:40 INFO - PROCESS | 1636 | Values: 1209.7 1298.6 1409.2 1421.6 1435.4 6415.4 7867.4 8079.6 8344.1 8345.2 8235.3 8326.5 8336.2 8349.0 8351.5 2065.9 2302.3 2342.6 2351.9 2353.7 2136.0 2251.9 2306.1 2309.3 2330.5 2267.3 2279.0 2290.9 2294.6 2302.1 2288.6 2299.5 2313.5 2315.5 2317.1 2288.9 2318.7 2319.9 2326.3 2327.2 8157.0 8275.0 8467.0 8500.7 8501.5 4729.2 4896.8 4989.5 5165.1 5172.3 3152.3 3274.2 3355.1 3359.7 3364.0 1157.5 1211.1 1211.2 1249.1 1262.9 10741.5 10868.2 10885.5 10892.8 10903.6 4424.7 4540.9 4578.0 4619.4 4619.6 9258.9 9532.2 9542.6 9543.6 9564.3 5159.1 5167.1 5167.4 5170.6 5173.8 4635.7 4655.3 4717.2 4740.4 4744.8 3774.5 3792.1 3837.1 3868.6 3879.4 1251.8 1255.1 1259.0 1268.4 1273.3 741.6 751.7 772.5 777.4 798.5 5496.0 5629.7 5850.5 5869.2 5879.6 896.9 901.4 915.8 916.4 949.6 3677.4 3760.1 3852.9 3861.5 3882.0 9679.2 10033.6 10056.3 10069.8 10187.6 9403.4 9886.5 9893.0 10146.9 10152.6 7564.8 7567.8 7729.4 7773.4 7781.0 5398.3 5554.6 5582.4 5689.8 5931.5 9133.8 9163.9 9243.2 9266.5 9271.0 8592.7 9148.5 9247.0 9429.1 9454.3 6786.4 6877.2 6935.2 7003.1 7154.5 7817.8 7906.1 7914.5 7971.1 8007.0 7020.6 7057.1 7100.3 7104.9 7132.0 5950.9 6023.8 6866.0 6952.7 6964.8 6273.0 6320.6 6360.2 6415.2 6418.8 4636.1 4710.5 4755.7 4790.5 4911.6 4719.9 4789.3 4909.4 5034.8 5035.2 08:22:40 INFO - PROCESS | 1636 | 08:22:40 INFO - PROCESS | 1636 | [#4] prototype.html Cycles:180 Average:4198.29 Median:4647.95 stddev:1285.73 (27.7%) stddev-sans-first:1255.75 08:22:40 INFO - PROCESS | 1636 | Values: 308.3 324.4 328.4 348.3 355.2 4122.4 4318.8 4463.0 4714.7 4769.0 4191.4 4591.4 5016.3 5023.6 5058.4 3284.3 3465.2 3924.4 3935.7 4027.7 3582.6 3612.5 3802.8 4006.4 4010.7 3070.6 3116.8 3736.0 3845.6 3861.6 3408.9 3819.1 3838.0 3849.2 3853.8 3326.8 3348.9 3351.2 3354.9 3355.2 4061.6 4839.3 4859.4 4909.3 4987.2 3539.3 3775.3 4126.7 4358.4 4369.6 4632.2 4714.7 5243.8 5345.9 5367.9 3648.0 3667.3 3669.2 3679.5 3680.5 4722.4 4736.5 5270.6 5569.5 5593.9 3637.7 3649.5 3650.5 3655.0 3671.2 3674.5 3679.5 3685.5 3701.9 3709.5 3475.9 4133.6 4304.2 4319.0 4351.3 4969.9 5217.9 5276.9 5283.4 5306.8 1069.6 1153.6 1159.7 1202.9 1218.6 784.8 827.8 833.6 839.8 845.2 3908.7 4166.5 4767.9 4784.8 4911.9 4228.3 4543.9 4978.3 5221.8 5233.6 4154.5 4435.9 4573.7 4786.0 4806.4 2527.9 2598.7 2670.9 2695.4 2740.6 4561.6 4649.7 5170.3 5458.8 5497.7 4745.6 5135.5 5398.0 5419.7 5458.8 4313.3 4987.7 5078.6 5688.0 5695.0 5170.8 5443.8 5480.9 5496.7 5504.5 4769.5 4818.9 4881.9 5442.3 5461.2 5277.6 5472.8 5485.4 5671.5 5704.5 3657.2 3706.4 3714.0 3714.8 3716.9 4662.5 4891.3 5517.4 5534.4 5545.5 4562.0 4869.4 5324.4 5391.2 5413.7 5326.9 5338.0 5351.9 5372.7 5379.8 3592.9 4437.0 4646.3 5143.6 5230.7 4702.8 4769.5 5039.1 5181.3 5335.5 4449.7 5090.1 5132.6 5242.7 5252.4 08:22:40 INFO - PROCESS | 1636 | 08:22:40 INFO - PROCESS | 1636 | [#5] yui.html Cycles:180 Average:20889.49 Median:4341.00 stddev:30785.08 (709.2%) stddev-sans-first:30834.12 08:22:40 INFO - PROCESS | 1636 | Values: 701.8 730.2 759.7 767.2 775.8 14574.9 15214.7 15219.9 15235.7 15248.7 13863.9 14015.6 14018.6 14033.4 14034.9 705.1 724.0 729.7 735.5 873.6 718.6 747.6 873.7 900.6 914.1 941.5 953.3 962.6 963.6 964.8 954.6 956.7 957.0 958.5 958.7 548.1 568.9 628.6 714.2 716.3 13641.6 14150.0 14224.5 14471.3 14485.4 12584.2 12693.5 12731.1 12943.9 12992.7 11555.6 11585.0 11610.3 11784.9 11799.3 3707.5 3722.3 3763.8 3764.5 3798.6 98304.0 104348.5 107171.5 107568.0 107861.5 10430.6 10514.4 10813.6 10848.3 11015.1 20057.3 20425.3 20819.3 20846.6 20893.4 7419.7 7597.5 7628.3 7636.1 7880.5 4244.0 4330.9 4351.1 4372.2 4445.2 3345.4 3412.5 3454.4 3456.9 3458.2 629.2 633.4 634.9 647.6 651.6 614.3 622.6 625.5 626.8 629.8 8585.3 8809.9 8886.7 8916.5 9200.5 609.4 624.6 630.1 630.8 632.6 3411.4 4240.8 4387.1 4392.3 4539.7 73994.0 104114.5 107417.0 112023.0 112623.0 96051.0 96358.0 98662.0 98786.5 101707.5 64939.7 85928.0 86900.6 87602.6 88113.6 8439.2 8535.4 8544.7 8571.9 8688.4 54495.4 55215.7 55612.7 55779.0 56217.0 69953.0 74199.0 74590.0 74949.5 75020.6 10569.4 12420.8 12652.3 12687.3 12905.6 14349.4 14430.6 14706.0 14738.0 15176.5 12892.4 13343.3 13424.8 13442.8 13454.0 12881.5 13112.6 13191.9 13333.5 13411.9 12940.2 13056.2 13147.6 13230.9 13656.4 10725.7 11033.2 11309.8 11355.5 11623.0 9951.3 9962.8 10060.6 10352.6 10907.2 08:22:40 INFO - PROCESS | 1636 | -------- Summary: end -------- 08:22:40 INFO - PROCESS | 1636 | 08:22:41 INFO - TEST-INFO | 1636: exit 0 08:22:41 INFO - TEST-OK | dromaeo_css | took 1119127ms 08:22:41 INFO - TEST-START | kraken 08:22:41 INFO - operating with platform_type : mac_ 08:22:41 INFO - Initialising browser for kraken test... 08:22:41 INFO - TEST-INFO | started process 1646 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/tmpJ5WI3_/profile http://localhost:49241/getInfo.html) 08:22:41 INFO - PROCESS | 1646 | 1482855761604 addons.xpi WARN Exception running bootstrap method install on shield-recipe-client@mozilla.org: [Exception... "Component returned failure code: 0x8000ffff (NS_ERROR_UNEXPECTED) [nsIPrefBranch.getBoolPref]" nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js :: this.install :: line 38" data: no] Stack trace: this.install()@resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js:38 < callBootstrapMethod()@resource://gre/modules/addons/XPIProvider.jsm:4979 < processFileChanges()@resource://gre/modules/addons/XPIProvider.jsm -> resource://gre/modules/addons/XPIProviderUtils.js:2097 < checkForChanges()@resource://gre/modules/addons/XPIProvider.jsm:3825 < startup()@resource://gre/modules/addons/XPIProvider.jsm:2829 < callProvider()@resource://gre/modules/AddonManager.jsm:264 < _startProvider()@resource://gre/modules/AddonManager.jsm:771 < startup()@resource://gre/modules/AddonManager.jsm:957 < startup()@resource://gre/modules/AddonManager.jsm:2923 < observe()@resource://gre/components/addonManager.js:65 08:22:43 INFO - PROCESS | 1646 | __metrics Screen width/height:1600/1200 08:22:43 INFO - PROCESS | 1646 | colorDepth:24 08:22:43 INFO - PROCESS | 1646 | Browser inner width/height: 1024/689 08:22:43 INFO - PROCESS | 1646 | __metrics 08:22:43 INFO - PROCESS | 1646 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 08:22:43 INFO - PROCESS | 1646 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 08:22:44 INFO - TEST-INFO | 1646: exit 0 08:22:44 INFO - Browser initialized. 08:22:44 INFO - Running cycle 1/1 for kraken test... 08:22:44 INFO - TEST-INFO | started process 1647 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/tmpJ5WI3_/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:22:56 INFO - PROCESS | 1647 | RSS: Main: 233967616 08:22:56 INFO - PROCESS | 1647 | 08:22:58 INFO - PROCESS | 1647 | 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) 08:22:59 INFO - PROCESS | 1647 | RSS: Main: 285474816 08:22:59 INFO - PROCESS | 1647 | 08:23:02 INFO - PROCESS | 1647 | 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) 08:23:03 INFO - PROCESS | 1647 | RSS: Main: 286797824 08:23:03 INFO - PROCESS | 1647 | 08:23:06 INFO - PROCESS | 1647 | 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) 08:23:06 INFO - PROCESS | 1647 | RSS: Main: 379162624 08:23:06 INFO - PROCESS | 1647 | 08:23:09 INFO - PROCESS | 1647 | 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) 08:23:09 INFO - PROCESS | 1647 | RSS: Main: 283054080 08:23:09 INFO - PROCESS | 1647 | 08:23:11 INFO - PROCESS | 1647 | 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) 08:23:12 INFO - PROCESS | 1647 | RSS: Main: 285175808 08:23:12 INFO - PROCESS | 1647 | 08:23:15 INFO - PROCESS | 1647 | 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) 08:23:15 INFO - PROCESS | 1647 | RSS: Main: 328540160 08:23:15 INFO - PROCESS | 1647 | 08:23:18 INFO - PROCESS | 1647 | 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) 08:23:19 INFO - PROCESS | 1647 | RSS: Main: 328830976 08:23:19 INFO - PROCESS | 1647 | 08:23:21 INFO - PROCESS | 1647 | 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) 08:23:22 INFO - PROCESS | 1647 | RSS: Main: 324059136 08:23:22 INFO - PROCESS | 1647 | 08:23:24 INFO - PROCESS | 1647 | 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) 08:23:24 INFO - PROCESS | 1647 | RSS: Main: 302129152 08:23:24 INFO - PROCESS | 1647 | 08:23:26 INFO - PROCESS | 1647 | 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) 08:23:27 INFO - PROCESS | 1647 | RSS: Main: 304410624 08:23:27 INFO - PROCESS | 1647 | 08:23:29 INFO - PROCESS | 1647 | 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) 08:23:29 INFO - PROCESS | 1647 | RSS: Main: 307429376 08:23:29 INFO - PROCESS | 1647 | 08:23:32 INFO - PROCESS | 1647 | 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) 08:23:33 INFO - PROCESS | 1647 | RSS: Main: 325095424 08:23:33 INFO - PROCESS | 1647 | 08:23:36 INFO - PROCESS | 1647 | 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) 08:23:37 INFO - PROCESS | 1647 | RSS: Main: 318357504 08:23:37 INFO - PROCESS | 1647 | 08:23:38 INFO - PROCESS | 1647 | 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) 08:23:39 INFO - PROCESS | 1647 | RSS: Main: 362364928 08:23:39 INFO - PROCESS | 1647 | 08:23:39 INFO - PROCESS | 1647 | __start_tp_report 08:23:39 INFO - PROCESS | 1647 | _x_x_mozilla_page_load 08:23:39 INFO - PROCESS | 1647 | _x_x_mozilla_page_load_details 08:23:39 INFO - PROCESS | 1647 | |i|pagename|runs| 08:23:39 INFO - PROCESS | 1647 | |0;ai-astar;112;112;113;113;112;113;111;113;112;119 08:23:39 INFO - PROCESS | 1647 | |1;audio-beat-detection;134;109;103;139;112;109;107;109;127;109 08:23:39 INFO - PROCESS | 1647 | |2;audio-dft;185;183;183;183;191;179;183;178;184;183 08:23:39 INFO - PROCESS | 1647 | |3;audio-fft;81;74;85;112;63;58;57;58;75;57 08:23:39 INFO - PROCESS | 1647 | |4;audio-oscillator;89;68;87;70;69;68;83;68;67;84 08:23:39 INFO - PROCESS | 1647 | |5;imaging-gaussian-blur;87;84;87;84;86;84;87;83;86;84 08:23:39 INFO - PROCESS | 1647 | |6;imaging-darkroom;92;93;93;93;92;92;93;101;92;92 08:23:39 INFO - PROCESS | 1647 | |7;imaging-desaturate;87;86;86;84;86;86;86;85;85;85 08:23:39 INFO - PROCESS | 1647 | |8;json-parse-financial;58;53;53;54;54;55;53;55;53;54 08:23:39 INFO - PROCESS | 1647 | |9;json-stringify-tinderbox;65;72;66;76;73;69;68;68;69;71 08:23:39 INFO - PROCESS | 1647 | |10;stanford-crypto-aes;86;87;90;87;86;86;84;89;88;90 08:23:39 INFO - PROCESS | 1647 | |11;stanford-crypto-ccm;140;139;141;141;136;139;140;140;185;147 08:23:39 INFO - PROCESS | 1647 | |12;stanford-crypto-pbkdf2;170;167;169;165;170;171;168;168;171;166 08:23:39 INFO - PROCESS | 1647 | |13;stanford-crypto-sha256-iterative;67;67;68;76;85;83;79;67;65;67 08:23:39 INFO - PROCESS | 1647 | __end_tp_report 08:23:39 INFO - PROCESS | 1647 | __start_cc_report 08:23:39 INFO - PROCESS | 1647 | _x_x_mozilla_cycle_collect,2009 08:23:39 INFO - PROCESS | 1647 | __end_cc_report 08:23:39 INFO - PROCESS | 1647 | __startTimestamp1482855819097__endTimestamp 08:23:39 INFO - PROCESS | 1647 | 08:23:39 INFO - PROCESS | 1647 | ------- Summary: start ------- 08:23:39 INFO - PROCESS | 1647 | Number of tests: 14 08:23:39 INFO - PROCESS | 1647 | 08:23:39 INFO - PROCESS | 1647 | [#0] ai-astar Cycles:10 Average:113.00 Median:113.00 stddev:2.21 (2.0%) stddev-sans-first:2.32 08:23:39 INFO - PROCESS | 1647 | Values: 112.0 112.0 113.0 113.0 112.0 113.0 111.0 113.0 112.0 119.0 08:23:39 INFO - PROCESS | 1647 | 08:23:39 INFO - PROCESS | 1647 | [#1] audio-beat-detection Cycles:10 Average:115.80 Median:110.50 stddev:12.63 (11.4%) stddev-sans-first:11.55 08:23:39 INFO - PROCESS | 1647 | Values: 134.0 109.0 103.0 139.0 112.0 109.0 107.0 109.0 127.0 109.0 08:23:39 INFO - PROCESS | 1647 | 08:23:39 INFO - PROCESS | 1647 | [#2] audio-dft Cycles:10 Average:183.20 Median:183.00 stddev:3.49 (1.9%) stddev-sans-first:3.64 08:23:39 INFO - PROCESS | 1647 | Values: 185.0 183.0 183.0 183.0 191.0 179.0 183.0 178.0 184.0 183.0 08:23:39 INFO - PROCESS | 1647 | 08:23:39 INFO - PROCESS | 1647 | [#3] audio-fft Cycles:10 Average:72.00 Median:68.50 stddev:17.59 (25.7%) stddev-sans-first:18.36 08:23:39 INFO - PROCESS | 1647 | Values: 81.0 74.0 85.0 112.0 63.0 58.0 57.0 58.0 75.0 57.0 08:23:39 INFO - PROCESS | 1647 | 08:23:39 INFO - PROCESS | 1647 | [#4] audio-oscillator Cycles:10 Average:75.30 Median:76.50 stddev:9.17 (12.0%) stddev-sans-first:8.27 08:23:39 INFO - PROCESS | 1647 | Values: 89.0 68.0 87.0 70.0 69.0 68.0 83.0 68.0 67.0 84.0 08:23:39 INFO - PROCESS | 1647 | 08:23:39 INFO - PROCESS | 1647 | [#5] imaging-gaussian-blur Cycles:10 Average:85.20 Median:86.00 stddev:1.55 (1.8%) stddev-sans-first:1.50 08:23:39 INFO - PROCESS | 1647 | Values: 87.0 84.0 87.0 84.0 86.0 84.0 87.0 83.0 86.0 84.0 08:23:39 INFO - PROCESS | 1647 | 08:23:39 INFO - PROCESS | 1647 | [#6] imaging-darkroom Cycles:10 Average:93.30 Median:92.50 stddev:2.75 (3.0%) stddev-sans-first:2.88 08:23:39 INFO - PROCESS | 1647 | Values: 92.0 93.0 93.0 93.0 92.0 92.0 93.0 101.0 92.0 92.0 08:23:39 INFO - PROCESS | 1647 | 08:23:39 INFO - PROCESS | 1647 | [#7] imaging-desaturate Cycles:10 Average:85.60 Median:86.00 stddev:0.84 (1.0%) stddev-sans-first:0.73 08:23:39 INFO - PROCESS | 1647 | Values: 87.0 86.0 86.0 84.0 86.0 86.0 86.0 85.0 85.0 85.0 08:23:39 INFO - PROCESS | 1647 | 08:23:39 INFO - PROCESS | 1647 | [#8] json-parse-financial Cycles:10 Average:54.20 Median:54.00 stddev:1.55 (2.9%) stddev-sans-first:0.83 08:23:39 INFO - PROCESS | 1647 | Values: 58.0 53.0 53.0 54.0 54.0 55.0 53.0 55.0 53.0 54.0 08:23:39 INFO - PROCESS | 1647 | 08:23:39 INFO - PROCESS | 1647 | [#9] json-stringify-tinderbox Cycles:10 Average:69.70 Median:70.00 stddev:3.33 (4.8%) stddev-sans-first:3.07 08:23:39 INFO - PROCESS | 1647 | Values: 65.0 72.0 66.0 76.0 73.0 69.0 68.0 68.0 69.0 71.0 08:23:39 INFO - PROCESS | 1647 | 08:23:39 INFO - PROCESS | 1647 | [#10] stanford-crypto-aes Cycles:10 Average:87.30 Median:87.50 stddev:1.95 (2.2%) stddev-sans-first:2.01 08:23:39 INFO - PROCESS | 1647 | Values: 86.0 87.0 90.0 87.0 86.0 86.0 84.0 89.0 88.0 90.0 08:23:39 INFO - PROCESS | 1647 | 08:23:39 INFO - PROCESS | 1647 | [#11] stanford-crypto-ccm Cycles:10 Average:144.80 Median:140.50 stddev:14.39 (10.2%) stddev-sans-first:15.16 08:23:39 INFO - PROCESS | 1647 | Values: 140.0 139.0 141.0 141.0 136.0 139.0 140.0 140.0 185.0 147.0 08:23:39 INFO - PROCESS | 1647 | 08:23:39 INFO - PROCESS | 1647 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:168.50 Median:169.50 stddev:2.07 (1.2%) stddev-sans-first:2.12 08:23:39 INFO - PROCESS | 1647 | Values: 170.0 167.0 169.0 165.0 170.0 171.0 168.0 168.0 171.0 166.0 08:23:39 INFO - PROCESS | 1647 | 08:23:39 INFO - PROCESS | 1647 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:72.40 Median:72.00 stddev:7.59 (10.5%) stddev-sans-first:7.79 08:23:39 INFO - PROCESS | 1647 | Values: 67.0 67.0 68.0 76.0 85.0 83.0 79.0 67.0 65.0 67.0 08:23:39 INFO - PROCESS | 1647 | -------- Summary: end -------- 08:23:39 INFO - PROCESS | 1647 | 08:23:39 INFO - TEST-INFO | 1647: exit 0 08:23:39 INFO - TEST-OK | kraken | took 58864ms 08:23:39 INFO - SUITE-END | took 1177s 08:23:40 INFO - Completed test suite (00:19:38) 08:23:40 INFO - javascript benchmark 08:23:40 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3346.9330613387733, 3367.7810942288734, 3370.4438767603347, 3392.609849867266, 3428.1601007753106, 5645.802396916109, 5660.490555849972, 5705.315362156542, 5754.136879468082, 5818.650880947146, 4807.807687692488, 5080.136376815941, 5747.568932330071, 5758.5393168546525, 5814.970925145379, 5242.1088414969545, 5254.500822421883, 5272.472752724735, 5278.973605131969, 5295.682259064469, 5202.141646628302, 5335.7332133393365, 5339.733013349336, 5365.409804921455, 5373.032854086263, 4405.118976204756, 5286.418493965661, 5287.867803304929, 5300.549453296474, 5309.283246761695, 5156.329677141947, 5188.766505507261, 5282.709450980195, 5301.575873930076, 5346.171343441753, 5131.692098474104, 5187.221916712486, 5200.089984252779, 5203.557697595709, 5259.894802103975, 5641.774329026834, 5688.544916406678, 5703.9429605704245, 5713.457221563986, 5722.513586345166, 6277.121203031579, 6294.3705629437145, 6299.118123462719, 6313.305536390993, 6317.905231421532, 6126.5176737973225, 6162.0, 6220.751169953196, 6221.657808820513, 6266.530010249204, 5834.124881267759, 5840.795572154954, 5860.120981852756, 5875.764969401176, 5897.439743224387, 6142.477889379364, 6274.937250627435, 6276.246850377984, 6278.30938596754, 6281.120643109968, 6025.18659980897, 6079.422454866781, 6088.056351265561, 6197.0, 6218.595791273643, 6164.537659675542, 6214.689265536705, 6243.687815609202, 6257.436830685169, 6297.937020629826, 6143.692815359214, 6173.660448675276, 6276.529260305404, 6283.905741413882, 6285.340039295899, 6063.969680151571, 6181.567290289637, 6184.938150618526, 6187.690615469209, 6244.718987645567, 4912.4350699669285, 4959.82640607577, 4974.0, 4975.975120124376, 4985.0, 4360.454943132108, 4376.496702879146, 4382.364557139261, 4384.276594361958, 4398.758068306274, 5803.071508558611, 5844.094165404369, 5850.297964244318, 5866.882662346729, 5875.618084824473, 6267.749290028348, 6276.497880169667, 6280.842978925563, 6291.5281353898645, 6321.55749097568, 5661.094224923992, 5694.6583205007, 5700.486956173964, 5835.56233282497, 5875.529957603381, 5350.732463376816, 5384.757685904144, 5399.352077750616, 5406.891862162814, 5414.620976531605, 6224.439800417984, 6235.937640623536, 6241.282252540835, 6260.530460215411, 6269.780557680641, 6212.409821066992, 6258.843528911813, 6280.748770049329, 6314.431701146918, 6323.588966717149, 6339.683015849281, 6348.111264422892, 6351.0, 6359.841003974938, 6362.522810789117, 6072.787452439144, 6135.293912123373, 6138.02057325484, 6139.785107521394, 6244.500439964882, 6251.437370636664, 6267.717952692049, 6286.968565157145, 6293.748250070129, 6310.558260921875, 6136.570440069152, 6148.415900489446, 6166.599171053867, 6167.105769663284, 6167.167432396748, 5969.462748352669, 6031.819045428644, 6074.878502429839, 6140.416660417253, 6151.84620384494, 5589.0, 5841.18223448726, 5855.063189889597, 5948.583599148018, 5992.250968628921, 6104.359042300494, 6104.755809767559, 6115.4190351916495, 6119.388061193845, 6126.969365153146, 5871.73577189036, 5877.353491115889, 5928.525717942641, 5932.080527518242, 5934.406559344204], "value": 5709.614547903424, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [413.27882844436465, 458.51397518630233, 489.7379901752563, 501.75664802570725, 511.0519985427033, 33117.00648980532, 38315.23369532611, 38365.80817095918, 38430.23139537207, 40049.0, 38785.0, 39214.60785392145, 39360.40959385604, 39360.80319598405, 39468.80265598668, 8401.95799021004, 9292.395994260369, 9503.90496095037, 9504.382215156027, 9515.857262141039, 8480.872786908201, 11107.0, 11350.20548561601, 11357.375344356056, 11519.827202591969, 11272.0, 11335.319880807137, 11349.659510214666, 11350.829737553902, 11359.375234362107, 8474.872876906851, 8696.304295656333, 11296.322220666745, 11379.487923043442, 11406.600768973047, 8991.595378207996, 11246.718832029183, 11285.887141128647, 11445.08439324852, 11452.141089418245, 37999.81000095009, 38377.42433863494, 38384.616153838666, 38448.23103537913, 38522.0, 3969.563348031714, 3994.141259629194, 4012.658923991435, 4035.636792688643, 4057.39139129133, 28968.42063158746, 29696.554551681744, 29697.406051879057, 29737.959171429113, 29831.552526712116, 3180.77734558581, 3299.6700329967052, 3302.735781137503, 3312.188513814107, 3351.396748585278, 448219.0, 450722.64521644503, 452866.0, 454032.0, 454442.0, 54768.23708206668, 55037.0, 55128.897422051334, 55143.72428137834, 55202.9999999992, 56215.0, 56319.71840140773, 56564.43435565592, 58309.0, 58346.41653583495, 14604.561863144123, 14816.40734370613, 14826.33281502335, 14827.851721482648, 14835.0, 10441.919633708074, 10697.732556686145, 10712.571497140027, 11367.658970230907, 11483.483243254073, 13616.77449029592, 15583.766243506356, 15754.842451575567, 15873.52379428619, 15961.44134955271, 6780.897556978056, 6788.626625535544, 6847.109875716125, 6871.347222013901, 6893.620850853151, 860.5009094725044, 863.3725427568952, 866.4627930682891, 891.4829398948594, 891.7815135291895, 28751.137465876058, 28834.567481487793, 29170.0, 29196.7080329194, 29387.11838644844, 1037.8080055189814, 1039.7556574205055, 1059.390850261103, 1071.2715353559654, 1185.4961641302411, 14320.283985800668, 14886.255687215813, 14903.329350179269, 14970.850291497163, 15110.320035598425, 607140.0, 609450.0, 610024.0, 610472.0, 612491.0, 56091.15863262054, 56141.43858561362, 56357.71821140868, 56371.436285637436, 56444.153337699965, 1358.381936219018, 1387.6600232942994, 1408.0425310788762, 1408.7112142010862, 1409.0207305922286, 22944.655830162894, 24723.134690285755, 27838.582421264102, 28384.71615283903, 28723.0, 47353.89691546296, 47668.52331476779, 47791.522084778706, 47980.5201947976, 48034.75982620064, 528692.0, 533171.0, 533603.3319833376, 533673.0, 535853.0, 37223.9465646966, 37876.242475150895, 38128.809355953046, 38212.23575528529, 38223.42664860085, 41406.585934140276, 41585.792071039454, 41605.79197103995, 43379.566204337556, 44061.0, 32485.837570811993, 34364.65635343615, 35684.107897302776, 36359.09102272464, 36993.0, 35843.82078089593, 36196.484632766354, 36695.63304366922, 36877.44683829798, 37392.25215495729, 33956.830215848764, 35239.714042892745, 35553.46669800007, 36185.81907090448, 36331.45502817513, 31326.843365783025, 32354.83822580872, 32405.35189296248, 32856.67143328536, 32868.83565582157, 22982.0, 23628.0, 24028.39929001789, 24456.510869782862, 24760.0], "value": 19425.09820107139, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [316.9746420286377, 349.5839950458952, 360.4881068882187, 363.7072156913685, 397.4376257595501, 14402.639934001656, 14702.779458308134, 15384.307706153208, 15765.448209312677, 19821.801781982213, 18807.24771009158, 19434.805651943476, 20108.69836952443, 20357.491062723402, 20385.796142038576, 5650.886982260363, 5980.132880732292, 5988.2215312009375, 6001.369856165084, 6001.759929602811, 5628.409017053212, 5786.247787787603, 5825.708714564276, 5868.706564671771, 5939.049752039675, 5801.07182850744, 5801.883962320751, 5807.332156801958, 5817.505512031461, 5823.01008828498, 5642.294713160834, 5642.915356269659, 5768.942310576925, 5773.624714393593, 5799.072148456226, 5048.747562621854, 5053.9241911371355, 5066.505380912603, 5067.847964561069, 5090.2364645303505, 26146.73853261462, 26390.0, 27003.45993080147, 28385.716142838515, 28789.71210287891, 3155.2015351249797, 3236.546883436321, 3282.0646115857103, 3313.4698448248164, 3393.406153923054, 15524.663677130045, 15700.528984130495, 16478.011319320878, 16488.83511164885, 16517.58706032347, 1985.9602807943904, 1988.5823976964855, 2011.2457828314382, 2011.6077364913847, 2084.3642688979853, 97743.0225697741, 98863.50568247185, 99588.0041199586, 110944.0, 121688.78311216863, 18942.590333739783, 20200.181983621544, 20364.0, 20672.379828605164, 20831.791682083287, 33044.339113217604, 33856.32287354239, 33964.66035339664, 34045.65954340425, 34106.65893341084, 8131.79670508242, 10055.530841859547, 10229.386236825665, 10254.43600601959, 10376.792464150674, 8232.917670823215, 8263.834723305501, 8273.089960104504, 8404.0, 8436.114208008194, 8069.798255043671, 8114.43198976066, 9488.667896623585, 9759.609615615438, 9875.358101723508, 3182.840857957139, 3767.2970364991247, 3829.381234733206, 3830.1465408271015, 3843.3466310727244, 974.8001659659752, 984.3798407003542, 989.2877128467492, 998.8701468809008, 1017.872765904262, 16092.0, 19936.0, 20026.399208023784, 20068.39794806158, 20111.095000725003, 994.6469003503797, 998.2463240253562, 1013.9391636501833, 1015.6648306058983, 1018.3889666200221, 8442.915570844336, 10168.745781355377, 10492.685219443429, 10511.52698128586, 10547.103496202755, 539244.0, 548284.0, 551894.0, 553701.0, 553926.0, 109532.0, 121817.39091304663, 122282.38858805649, 122659.0, 123970.38014809869, 1000.2997901469001, 1000.6497725795913, 1025.6307729217476, 1026.0304012708068, 1027.660871912267, 17251.0, 17406.0, 17617.910447761195, 17729.202185901922, 17798.91100544489, 42958.57041429546, 52873.0, 52907.0, 53681.97385196048, 55263.34209973707, 76395.0, 77389.0, 77675.0, 77696.61151694205, 78742.81885771603, 6609.636469994197, 6893.586384816927, 7067.893981590178, 7171.677274522554, 7302.2697730226555, 8907.777305567153, 10900.509477073709, 12963.15739476931, 13132.671683207996, 13693.178409295475, 10217.795644087226, 11536.307821530734, 11785.587504437304, 11818.704532386759, 11934.761304774029, 11228.813559322203, 11336.433178341214, 11580.131490138105, 11649.650510484698, 11860.466279017637, 11366.488508016993, 11450.82823757661, 11905.523779049088, 11946.98450631706, 11988.700282493008, 7025.964870175617, 7081.8937715935335, 7161.928380716126, 7267.672954717156, 7382.818749000135, 9109.772255693395, 9865.704028879145, 11343.319400835977, 11748.29510229389, 11826.645200643994], "value": 10351.88846361759, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1209.7157168065503, 1298.5714714144322, 1409.22492629054, 1421.5735279416172, 1435.3756116089503, 6415.358464153588, 7867.449278550504, 8079.555624440653, 8344.123866993954, 8345.165483451652, 8235.258826705594, 8326.500409975406, 8336.16638336166, 8348.998120225566, 8351.540665263408, 2065.9070341834654, 2302.343832007878, 2342.61346877765, 2351.8824058797077, 2353.6587194856734, 2136.038782547852, 2251.876146811933, 2306.054517647765, 2309.261036468331, 2330.5455436189864, 2267.319804058776, 2278.977210227901, 2290.9083636654514, 2294.5869743446174, 2302.1482051640915, 2288.553732022256, 2299.482616411311, 2313.514162025985, 2315.4674424882373, 2317.1426572168407, 2288.8969996350206, 2318.6869772580653, 2319.8608083514873, 2326.336993956731, 2327.243645815117, 8157.0, 8275.04836943747, 8466.957665211696, 8500.702475413393, 8501.532415717133, 4729.219678753002, 4896.751328411268, 4989.476105008958, 5165.096108181091, 5172.250023746536, 3152.274976755359, 3274.2305558193807, 3355.144438168276, 3359.6808303211155, 3363.983180084084, 1157.5427706056146, 1211.1037832004254, 1211.2066596379386, 1249.0507214516942, 1262.873712628739, 10741.51663175159, 10868.239223254375, 10885.510152043098, 10892.836607450894, 10903.563857445693, 4424.734515929054, 4540.88647783808, 4578.038611891474, 4619.422572178478, 4619.584237418649, 9258.907410925805, 9532.237421006303, 9542.61829526825, 9543.570539325608, 9564.282678799118, 5159.148740457783, 5167.0957582422925, 5167.354080739908, 5170.6122040847085, 5173.844784656466, 4635.698679585884, 4655.348251244828, 4717.24524076153, 4740.407449068866, 4744.833930812474, 3774.490443790053, 3792.1278106035215, 3837.059920319484, 3868.593797651206, 3879.359905615598, 1251.8310028146084, 1255.1026016398318, 1258.9685257868443, 1268.403850190409, 1273.2551457397342, 741.5847125609567, 751.6655088485571, 772.5094564951296, 777.3547955197173, 798.4969469234345, 5496.0, 5629.746661400247, 5850.5027072699295, 5869.178315035899, 5879.588428809942, 896.9327300452492, 901.4456109492614, 915.8122584870218, 916.3860213656866, 949.5821838391086, 3677.4116141417244, 3760.0975765816556, 3852.8651497197466, 3861.532617605292, 3882.0, 9679.225661947028, 10033.598656053822, 10056.296059275926, 10069.79860402788, 10187.592496300213, 9403.388779729295, 9886.505674716236, 9893.010698930193, 10146.898531014744, 10152.644657436955, 7564.810879727941, 7567.848643027219, 7729.381649468029, 7773.416993725493, 7781.027371578552, 5398.271233383443, 5554.555635549227, 5582.413846546055, 5689.77240910359, 5931.466168044724, 9133.817323653357, 9163.908360916306, 9243.2143267823, 9266.53667316618, 9271.02654221297, 8592.699255526026, 9148.542572871464, 9246.95376523113, 9429.057094290516, 9454.290928180551, 6786.423154031962, 6877.1747390313485, 6935.167779866449, 7003.054587630605, 7154.534955227893, 7817.765467035998, 7906.051273847175, 7914.485558438683, 7971.12317645047, 8007.0, 7020.648967551704, 7057.117860267466, 7100.289971002859, 7104.893426598708, 7131.964340178266, 5950.899083669535, 6023.849403764941, 6865.965670171618, 6952.652367381712, 6964.756233531802, 6273.0, 6320.557560970872, 6360.173177486897, 6415.230172379344, 6418.775342862978, 4636.072785442858, 4710.528947105262, 4755.738434386004, 4790.496997815179, 4911.63162762787, 4719.929201062056, 4789.281607758864, 4909.4354149273295, 5034.84895453137, 5035.244713293036], "value": 4364.748965265319, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [308.28170363054085, 324.4209086780097, 328.404125812753, 348.2634228606498, 355.2132027558956, 4122.3816427535885, 4318.82724691012, 4462.977685111574, 4714.669973101884, 4768.950830817219, 4191.407265239212, 4591.380163677903, 5016.272640467139, 5023.572996295319, 5058.392992840855, 3284.310294838087, 3465.1856813648774, 3924.4309575111592, 3935.704822138344, 4027.697922655798, 3582.5521809773777, 3612.518867264422, 3802.8098595070273, 4006.399040143973, 4010.7393019453643, 3070.6468756093, 3116.8467666963256, 3735.981320093396, 3845.634664706862, 3861.6331448512346, 3408.9147771305666, 3819.140693344003, 3837.980810095946, 3849.248591890711, 3853.807309634535, 3326.8170250636203, 3348.94040165299, 3351.1957130288797, 3354.899353019389, 3355.1779813945495, 4061.6141466560925, 4839.306242814987, 4859.441164266084, 4909.3126962225315, 4987.226979818134, 3539.3275277697153, 3775.2826962876966, 4126.690498212616, 4358.368036634702, 4369.579886536888, 4632.239748895398, 4714.740689262089, 5243.764030618631, 5345.919811202835, 5367.865803354908, 3647.963520364789, 3667.331364229269, 3669.2478042001594, 3679.4848721179055, 3680.4847321375028, 4722.409698787652, 4736.478987311393, 5270.5520030797425, 5569.498745112919, 5593.937151941159, 3637.6907962823457, 3649.543807024122, 3650.5215387768, 3654.9634503655157, 3671.2290419011774, 3674.5039419678, 3679.4848721179055, 3685.4840322354894, 3701.8714239312503, 3709.46212799148, 3475.9304813904087, 4133.586641335843, 4304.225239456865, 4319.0, 4351.303791393362, 4969.925451118236, 5217.9478205217465, 5276.894462110737, 5283.418823929364, 5306.787728490895, 1069.6363236499628, 1153.5904753812445, 1159.7042754097652, 1202.909781766371, 1218.5795900414344, 784.8312612788278, 827.7806381308958, 833.6498670558259, 839.7732612194674, 845.218173189797, 3908.726389152732, 4166.520850102216, 4767.904641907212, 4784.784684689197, 4911.943932054625, 4228.281192197333, 4543.931841022388, 4978.332258693378, 5221.780743184131, 5233.581313494911, 4154.543000269968, 4435.933460998088, 4573.706931009668, 4785.95214047862, 4806.399200099988, 2527.886245118974, 2598.7141414444395, 2670.8798104085363, 2695.355832941904, 2740.643716316866, 4561.589456948891, 4649.651276154302, 5170.293803045061, 5458.781648734085, 5497.697626630494, 4745.596624286905, 5135.460776618476, 5398.0, 5419.674819510763, 5458.808941687022, 4313.331433627793, 4987.675801072919, 5078.568321692697, 5687.971560142173, 5695.031844586513, 5170.810713535832, 5443.7822487100075, 5480.91778623313, 5496.725163741717, 5504.504594586507, 4769.472179744602, 4818.903621927612, 4881.877953051202, 5442.292501974718, 5461.235427040297, 5277.551408130352, 5472.808451704172, 5485.396606373216, 5671.52540339523, 5704.515116215168, 3657.158853463665, 3706.3699171140433, 3713.997220750436, 3714.814259287079, 3716.869909553153, 4662.53374662531, 4891.3124971884645, 5517.448255174611, 5534.446555344433, 5545.528630066328, 4561.950751327147, 4869.440014398389, 5324.387695415076, 5391.2182733504205, 5413.702246376487, 5326.893462130658, 5337.968096051437, 5351.919721204107, 5372.704501252469, 5379.838604841861, 3592.928141437209, 4436.977815111033, 4646.256598944153, 5143.5627971622835, 5230.660007099526, 4702.753770250954, 4769.523047695203, 5039.061719514969, 5181.3005244293045, 5335.493128152819, 4449.688521803443, 5090.0583392072585, 5132.6150538709, 5242.68543887368, 5252.395974462986], "value": 3802.9981537770955, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [701.7754318618045, 730.1566690472506, 759.7492827366967, 767.1829501580812, 775.7750252426795, 14574.854251457495, 15214.695706085871, 15219.923900380496, 15235.695286094286, 15248.69502609947, 13863.861361386384, 14015.649608759786, 14018.579442616712, 14033.438662453515, 14034.929825350859, 705.1080383315085, 724.0298000679089, 729.6898818002354, 735.485160387728, 873.611242996865, 718.6191318601124, 747.5925620536793, 873.6723728601775, 900.5587262241487, 914.0950459045564, 941.5433514745343, 953.3326671330062, 962.6197651927484, 963.6001059560274, 964.7973925475659, 954.5609019850842, 956.7464621875139, 956.9712908612753, 958.5063692198523, 958.6788425877339, 548.081962712459, 568.9487946084831, 628.6448156791444, 714.1965289049818, 716.298027932629, 13641.590752277349, 14150.00949933505, 14224.502142425068, 14471.276436178148, 14485.42058317666, 12584.244945303311, 12693.492260309577, 12731.108822382437, 12943.870561294361, 12992.740145197044, 11555.595554155649, 11584.95735383811, 11610.303381797119, 11784.882151178464, 11799.292042477478, 3707.5334562184175, 3722.348588996942, 3763.7741735495956, 3764.5482542095124, 3798.639129282714, 98304.0, 104348.47825760898, 107171.46414267957, 107568.0, 107861.46069269604, 10430.582776689, 10514.369137851754, 10813.56745730162, 10848.294860834178, 11015.118790496741, 20057.29799457041, 20425.285115020903, 20819.271325503534, 20846.583068338547, 20893.37319880406, 7419.740309089156, 7597.468177227541, 7628.313451789255, 7636.121845987672, 7880.487768295043, 4244.0, 4330.935035974463, 4351.06452112791, 4372.191144638252, 4445.244308467568, 3345.364380767695, 3412.5051867479574, 3454.360943225511, 3456.948145777815, 3458.1873259784434, 629.2291942370651, 633.4299130782333, 634.9111124442582, 647.601724939159, 651.5569412799342, 614.27822308787, 622.6233128956916, 625.4558534075384, 626.7621447640872, 629.8362425769333, 8585.270252028522, 8809.911900881038, 8886.688965886164, 8916.465012099296, 9200.539973001323, 609.387565496673, 624.6127401011311, 630.0832289019465, 630.8139098965817, 632.5951391109694, 3411.368896754059, 4240.769488022914, 4387.122575484916, 4392.3191905254735, 4539.7049191802425, 73994.0, 104114.47942760237, 107417.0, 112023.0, 112623.0, 96051.0, 96358.0, 98662.02675946441, 98786.5060674692, 101707.47438788424, 64939.67530162319, 85928.0, 86900.56549717211, 87602.56198718966, 88113.55943220243, 8439.240468357877, 8535.402521823413, 8544.658213671384, 8571.871421928801, 8688.435251708619, 54495.36513904589, 55215.72392138014, 55612.72193639006, 55779.0, 56217.0, 69953.0, 74199.0, 74590.0, 74949.50100998058, 75020.62489687516, 10569.365838049742, 12420.751584968431, 12652.304123273309, 12687.302198379179, 12905.612831614691, 14349.426022959382, 14430.639234018814, 14706.0, 14738.042027268193, 15176.544703658908, 12892.419841106985, 13343.266120363473, 13424.838300126992, 13442.782511967698, 13454.0, 12881.484740610646, 13112.606621801362, 13191.934040329737, 13333.533326333532, 13411.865881341062, 12940.15888967214, 13056.151350162208, 13147.60557183286, 13230.867691323348, 13656.425012841404, 10725.678229653124, 11033.227674062739, 11309.830352544554, 11355.54577816878, 11623.0, 9951.303408761607, 9962.773266708307, 10060.565776616002, 10352.637657682246, 10907.181961352771], "value": 7354.847716838876, "unit": "score"}], "name": "dromaeo_css", "value": 7207.478789202775, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 112.0, 113.0, 113.0, 112.0, 113.0, 111.0, 113.0, 112.0, 119.0], "value": 113.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [134.0, 109.0, 103.0, 139.0, 112.0, 109.0, 107.0, 109.0, 127.0, 109.0], "value": 115.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [185.0, 183.0, 183.0, 183.0, 191.0, 179.0, 183.0, 178.0, 184.0, 183.0], "value": 183.2, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 74.0, 85.0, 112.0, 63.0, 58.0, 57.0, 58.0, 75.0, 57.0], "value": 72.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 68.0, 87.0, 70.0, 69.0, 68.0, 83.0, 68.0, 67.0, 84.0], "value": 75.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 84.0, 87.0, 84.0, 86.0, 84.0, 87.0, 83.0, 86.0, 84.0], "value": 85.2, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 93.0, 93.0, 93.0, 92.0, 92.0, 93.0, 101.0, 92.0, 92.0], "value": 93.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 86.0, 86.0, 84.0, 86.0, 86.0, 86.0, 85.0, 85.0, 85.0], "value": 85.6, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 53.0, 53.0, 54.0, 54.0, 55.0, 53.0, 55.0, 53.0, 54.0], "value": 54.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 72.0, 66.0, 76.0, 73.0, 69.0, 68.0, 68.0, 69.0, 71.0], "value": 69.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 87.0, 90.0, 87.0, 86.0, 86.0, 84.0, 89.0, 88.0, 90.0], "value": 87.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [140.0, 139.0, 141.0, 141.0, 136.0, 139.0, 140.0, 140.0, 185.0, 147.0], "value": 144.8, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [170.0, 167.0, 169.0, 165.0, 170.0, 171.0, 168.0, 168.0, 171.0, 166.0], "value": 168.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 67.0, 68.0, 76.0, 85.0, 83.0, 79.0, 67.0, 65.0, 67.0], "value": 72.4, "unit": "score"}], "name": "kraken", "value": 1420.3000000000002, "alertThreshold": 2.0}]} 08:23:40 INFO - Return code: 0 08:23:40 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 08:23:40 INFO - # TBPL SUCCESS # 08:23:40 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 08:23:40 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 08:23:40 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 08:23:40 INFO - 2016-12-27 08:23:40.409 screenresolution[1651:9718] starting screenresolution argv=screenresolution get 08:23:40 INFO - 2016-12-27 08:23:40.412 screenresolution[1651:9718] Display 0: 1600x1200x32@60 08:23:40 INFO - 2016-12-27 08:23:40.418 screenresolution[1652:9721] starting screenresolution argv=screenresolution list 08:23:40 INFO - Available Modes on Display 0 08:23:40 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 08:23:40 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 08:23:40 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 08:23:40 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 08:23:40 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 08:23:40 INFO - Intel Iris: 08:23:40 INFO - Chipset Model: Intel Iris 08:23:40 INFO - Type: GPU 08:23:40 INFO - Bus: Built-In 08:23:40 INFO - VRAM (Dynamic, Max): 1536 MB 08:23:40 INFO - Vendor: Intel (0x8086) 08:23:40 INFO - Device ID: 0x0a2e 08:23:40 INFO - Revision ID: 0x0009 08:23:40 INFO - Displays: 08:23:40 INFO - Display: 08:23:40 INFO - Resolution: 1600 x 1200 @ 60 Hz 08:23:40 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 08:23:40 INFO - Display Serial Number: 1600x1200 60 08:23:40 INFO - Main Display: Yes 08:23:40 INFO - Mirror: Off 08:23:40 INFO - Online: Yes 08:23:40 INFO - Rotation: Supported 08:23:40 INFO - Return code: 0 08:23:40 INFO - Running post-action listener: _resource_record_post_action 08:23:40 INFO - [mozharness: 2016-12-27 16:23:40.653790Z] Finished run-tests step (success) 08:23:40 INFO - Running post-run listener: _resource_record_post_run 08:23:40 INFO - Total resource usage - Wall time: 1200s; CPU: 54.0%; Read bytes: 38720512; Write bytes: 550337536; Read time: 382; Write time: 1736 08:23:40 INFO - TinderboxPrint: CPU usage
53.8% 08:23:40 INFO - TinderboxPrint: I/O read bytes / time
38,720,512 / 382 08:23:40 INFO - TinderboxPrint: I/O write bytes / time
550,337,536 / 1,736 08:23:40 INFO - TinderboxPrint: CPU idle
2,217.4 (46.2%) 08:23:40 INFO - TinderboxPrint: CPU system
962.5 (20.0%) 08:23:40 INFO - TinderboxPrint: CPU user
1,621.8 (33.8%) 08:23:40 INFO - TinderboxPrint: Swap in / out
287,760,384 / 0 08:23:40 INFO - install - Wall time: 22s; CPU: 54.0%; Read bytes: 230812160; Write bytes: 220975104; Read time: 16529; Write time: 416 08:23:40 INFO - run-tests - Wall time: 1179s; CPU: 54.0%; Read bytes: 37790720; Write bytes: 300289024; Read time: 365; Write time: 1267 08:23:41 INFO - Running post-run listener: _upload_blobber_files 08:23:41 INFO - Blob upload gear active. 08:23:41 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 08:23:41 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 08:23:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 08:23:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 08:23:41 INFO - (blobuploader) - INFO - Open directory for files ... 08:23:41 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 08:23:41 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:23:41 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:23:42 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 08:23:42 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:23:42 INFO - (blobuploader) - INFO - Done attempting. 08:23:42 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 08:23:42 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:23:42 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:23:43 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 08:23:43 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:23:43 INFO - (blobuploader) - INFO - Done attempting. 08:23:43 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 08:23:43 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:23:43 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:23:44 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 08:23:44 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:23:44 INFO - (blobuploader) - INFO - Done attempting. 08:23:44 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 08:23:44 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:23:44 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:23:46 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 08:23:46 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:23:46 INFO - (blobuploader) - INFO - Done attempting. 08:23:46 INFO - (blobuploader) - INFO - Iteration through files over. 08:23:46 INFO - Return code: 0 08:23:46 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 08:23:46 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 08:23:46 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9eecc4f805e292be4015842397409d43ede2797e458871abc3745817c5646161e1da61ebd3d0b64ad6a4e82294579d97c6328fef5fdf573ece3a539035717522", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9490eacff0737a13e5f3e8ff4ec89d0f130647d7066944c6373f6fc832fc430a4d00e3f4c444b57b2a239e45f52e4b131fdb4653473cbae9aa58c4da91202bc2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/461465b0d178b6f812a0cdc9c39c3192b6365d2c9ffd15b6dcb9cd0345cd06aa4cdd6b5c92eefb62ec7eb3dced42023c29f0eb262db091498985b2df2fdeb207", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b9bc27057d20ad904573d4a401a6bdbcbccfa0a69333fd6e4257b689c0daefc331b811d3103571ab58c6257c9177810e1a62790366caac166f13249c45c6bc2f"} 08:23:46 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 08:23:46 INFO - Writing to file /builds/slave/test/properties/blobber_files 08:23:46 INFO - Contents: 08:23:46 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9eecc4f805e292be4015842397409d43ede2797e458871abc3745817c5646161e1da61ebd3d0b64ad6a4e82294579d97c6328fef5fdf573ece3a539035717522", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9490eacff0737a13e5f3e8ff4ec89d0f130647d7066944c6373f6fc832fc430a4d00e3f4c444b57b2a239e45f52e4b131fdb4653473cbae9aa58c4da91202bc2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/461465b0d178b6f812a0cdc9c39c3192b6365d2c9ffd15b6dcb9cd0345cd06aa4cdd6b5c92eefb62ec7eb3dced42023c29f0eb262db091498985b2df2fdeb207", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b9bc27057d20ad904573d4a401a6bdbcbccfa0a69333fd6e4257b689c0daefc331b811d3103571ab58c6257c9177810e1a62790366caac166f13249c45c6bc2f"} 08:23:46 INFO - Running post-run listener: copy_logs_to_upload_dir 08:23:46 INFO - Copying logs to upload dir... 08:23:46 INFO - mkdir: /builds/slave/test/build/upload/logs 08:23:46 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1262.193244 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 2 secs) (at 2016-12-27 08:23:46.634245) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-27 08:23:46.639465) ========= 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.JXq6ilxw7t/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.fSuLBGiLYO/Listeners TMPDIR=/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9eecc4f805e292be4015842397409d43ede2797e458871abc3745817c5646161e1da61ebd3d0b64ad6a4e82294579d97c6328fef5fdf573ece3a539035717522", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9490eacff0737a13e5f3e8ff4ec89d0f130647d7066944c6373f6fc832fc430a4d00e3f4c444b57b2a239e45f52e4b131fdb4653473cbae9aa58c4da91202bc2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/461465b0d178b6f812a0cdc9c39c3192b6365d2c9ffd15b6dcb9cd0345cd06aa4cdd6b5c92eefb62ec7eb3dced42023c29f0eb262db091498985b2df2fdeb207", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b9bc27057d20ad904573d4a401a6bdbcbccfa0a69333fd6e4257b689c0daefc331b811d3103571ab58c6257c9177810e1a62790366caac166f13249c45c6bc2f"} build_url:https://queue.taskcluster.net/v1/task/IcDfeMPSQpuREzSUetvSmg/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011340 build_url: 'https://queue.taskcluster.net/v1/task/IcDfeMPSQpuREzSUetvSmg/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9eecc4f805e292be4015842397409d43ede2797e458871abc3745817c5646161e1da61ebd3d0b64ad6a4e82294579d97c6328fef5fdf573ece3a539035717522", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9490eacff0737a13e5f3e8ff4ec89d0f130647d7066944c6373f6fc832fc430a4d00e3f4c444b57b2a239e45f52e4b131fdb4653473cbae9aa58c4da91202bc2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/461465b0d178b6f812a0cdc9c39c3192b6365d2c9ffd15b6dcb9cd0345cd06aa4cdd6b5c92eefb62ec7eb3dced42023c29f0eb262db091498985b2df2fdeb207", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b9bc27057d20ad904573d4a401a6bdbcbccfa0a69333fd6e4257b689c0daefc331b811d3103571ab58c6257c9177810e1a62790366caac166f13249c45c6bc2f"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-27 08:23:46.674209) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-27 08:23:46.674558) ========= 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.JXq6ilxw7t/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.fSuLBGiLYO/Listeners TMPDIR=/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/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.005182 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-27 08:23:46.725988) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-27 08:23:46.726775) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-27 08:23:46.727504) ========= ========= Total master_lag: 1.09 =========