builder: mozilla-central_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0231 starttime: 1483069608.15 results: success (0) buildid: 20161229182419 builduid: 55410856d22443e5a334ea2074a9e4d7 revision: 6f63f95e28ffc05c0d2f5ef6cd6e05905fe8ea5a ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-12-29 19:46:48.154778) ========= master: http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-12-29 19:46:48.155229) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-29 19:46:48.155530) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-29 19:46:48.183072) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-29 19:46:48.183345) ========= 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.XAxEqqjmZN/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.YW7HwvDW5a/Listeners TMPDIR=/var/folders/9_/bq7njmms6gbgk1ygh6pll6r400000w/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-29 19:46:48-- 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% 18.6M=0.001s 2016-12-29 19:46:48 (18.6 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.353393 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-29 19:46:48.560465) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-29 19:46:48.560805) ========= 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.XAxEqqjmZN/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.YW7HwvDW5a/Listeners TMPDIR=/var/folders/9_/bq7njmms6gbgk1ygh6pll6r400000w/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.025792 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-29 19:46:48.609691) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-12-29 19:46:48.610044) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 6f63f95e28ffc05c0d2f5ef6cd6e05905fe8ea5a --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 6f63f95e28ffc05c0d2f5ef6cd6e05905fe8ea5a --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.XAxEqqjmZN/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.YW7HwvDW5a/Listeners TMPDIR=/var/folders/9_/bq7njmms6gbgk1ygh6pll6r400000w/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-29 19:46:48,704 truncating revision to first 12 chars 2016-12-29 19:46:48,704 Setting DEBUG logging. 2016-12-29 19:46:48,704 attempt 1/10 2016-12-29 19:46:48,704 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/6f63f95e28ff?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-12-29 19:46:49,756 unpacking tar archive at: mozilla-central-6f63f95e28ff/testing/mozharness/ program finished with exit code 0 elapsedTime=1.327546 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-12-29 19:46:49.961262) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-29 19:46:49.961572) ========= script_repo_revision: 6f63f95e28ffc05c0d2f5ef6cd6e05905fe8ea5a ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-29 19:46:49.961987) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-29 19:46:49.962257) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-29 19:46:49.981857) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 7 secs) (at 2016-12-29 19:46:49.982217) ========= /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.XAxEqqjmZN/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.YW7HwvDW5a/Listeners TMPDIR=/var/folders/9_/bq7njmms6gbgk1ygh6pll6r400000w/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 19:46:50 INFO - MultiFileLogger online at 20161229 19:46:50 in /builds/slave/test 19:46:50 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 19:46:50 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 19:46:50 INFO - {'append_to_log': False, 19:46:50 INFO - 'base_work_dir': '/builds/slave/test', 19:46:50 INFO - 'blob_upload_branch': 'Firefox', 19:46:50 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 19:46:50 INFO - 'branch': 'Firefox', 19:46:50 INFO - 'buildbot_json_path': 'buildprops.json', 19:46:50 INFO - 'config_files': ('talos/mac_config.py',), 19:46:50 INFO - 'default_actions': ('clobber', 19:46:50 INFO - 'read-buildbot-config', 19:46:50 INFO - 'download-and-extract', 19:46:50 INFO - 'populate-webroot', 19:46:50 INFO - 'create-virtualenv', 19:46:50 INFO - 'install', 19:46:50 INFO - 'run-tests'), 19:46:50 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 19:46:50 INFO - 'download_minidump_stackwalk': True, 19:46:50 INFO - 'download_symbols': 'ondemand', 19:46:50 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 19:46:50 INFO - 'tooltool.py': '/tools/tooltool.py', 19:46:50 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 19:46:50 INFO - '/tools/misc-python/virtualenv.py')}, 19:46:50 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 19:46:50 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 19:46:50 INFO - 'installer_path': 'installer.exe', 19:46:50 INFO - 'log_level': 'info', 19:46:50 INFO - 'log_name': 'talos', 19:46:50 INFO - 'log_to_console': True, 19:46:50 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 19:46:50 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 19:46:50 INFO - 'opt_config_files': (), 19:46:50 INFO - 'pip_index': False, 19:46:50 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 19:46:50 INFO - 'cmd': ('bash', 19:46:50 INFO - '-c', 19:46:50 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 19:46:50 INFO - 'enabled': True, 19:46:50 INFO - 'halt_on_failure': False, 19:46:50 INFO - 'name': 'check_screen_resolution'},), 19:46:50 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 19:46:50 INFO - 'cmd': ('bash', 19:46:50 INFO - '-c', 19:46:50 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 19:46:50 INFO - 'enabled': True, 19:46:50 INFO - 'halt_on_failure': False, 19:46:50 INFO - 'name': 'check_screen_resolution'},), 19:46:50 INFO - 'run_cmd_checks_enabled': True, 19:46:50 INFO - 'sps_profile': False, 19:46:50 INFO - 'sps_profile_interval': 0, 19:46:50 INFO - 'suite': 'dromaeojs', 19:46:50 INFO - 'system_bits': '32', 19:46:50 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 19:46:50 INFO - 'title': 't-yosemite-r7-0231', 19:46:50 INFO - 'tooltool_cache': '/builds/tooltool_cache', 19:46:50 INFO - 'use_talos_json': True, 19:46:50 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 19:46:50 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 19:46:50 INFO - 'work_dir': 'build'} 19:46:50 INFO - [mozharness: 2016-12-30 03:46:50.180534Z] Running clobber step. 19:46:50 INFO - Running pre-action listener: _resource_record_pre_action 19:46:50 INFO - Running main action method: clobber 19:46:50 INFO - rmtree: /builds/slave/test/build 19:46:50 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 19:46:52 INFO - Running post-action listener: _resource_record_post_action 19:46:52 INFO - [mozharness: 2016-12-30 03:46:52.493535Z] Finished clobber step (success) 19:46:52 INFO - [mozharness: 2016-12-30 03:46:52.493722Z] Running read-buildbot-config step. 19:46:52 INFO - Running pre-action listener: _resource_record_pre_action 19:46:52 INFO - Running main action method: read_buildbot_config 19:46:52 INFO - Using buildbot properties: 19:46:52 INFO - { 19:46:52 INFO - "project": "", 19:46:52 INFO - "product": "firefox", 19:46:52 INFO - "script_repo_revision": "production", 19:46:52 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs", 19:46:52 INFO - "repository": "", 19:46:52 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs", 19:46:52 INFO - "buildid": "20161229182419", 19:46:52 INFO - "pgo_build": "False", 19:46:52 INFO - "basedir": "/builds/slave/test", 19:46:52 INFO - "buildnumber": 63, 19:46:52 INFO - "slavename": "t-yosemite-r7-0231", 19:46:52 INFO - "master": "http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/", 19:46:52 INFO - "platform": "yosemite_r7", 19:46:52 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 19:46:52 INFO - "branch": "mozilla-central", 19:46:52 INFO - "repo_path": "mozilla-central", 19:46:52 INFO - "revision": "6f63f95e28ffc05c0d2f5ef6cd6e05905fe8ea5a", 19:46:52 INFO - "stage_platform": "macosx64", 19:46:52 INFO - "builduid": "55410856d22443e5a334ea2074a9e4d7", 19:46:52 INFO - "slavebuilddir": "test" 19:46:52 INFO - } 19:46:52 INFO - Found installer url https://queue.taskcluster.net/v1/task/Snmb8HRcQWOBKk1aw_4KVw/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg. 19:46:52 INFO - Running post-action listener: _resource_record_post_action 19:46:52 INFO - [mozharness: 2016-12-30 03:46:52.497063Z] Finished read-buildbot-config step (success) 19:46:52 INFO - [mozharness: 2016-12-30 03:46:52.497170Z] Running download-and-extract step. 19:46:52 INFO - Running pre-action listener: _resource_record_pre_action 19:46:52 INFO - Running main action method: download_and_extract 19:46:52 INFO - mkdir: /builds/slave/test/build/tests 19:46:52 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')]} 19:46:52 INFO - trying https://queue.taskcluster.net/v1/task/Snmb8HRcQWOBKk1aw_4KVw/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json 19:46:52 INFO - Downloading https://queue.taskcluster.net/v1/task/Snmb8HRcQWOBKk1aw_4KVw/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 19:46:52 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Snmb8HRcQWOBKk1aw_4KVw/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 19:46:53 INFO - Downloaded 1199 bytes. 19:46:53 INFO - Reading from file /builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json 19:46:53 INFO - Using the following test package requirements: 19:46:53 INFO - {u'common': [u'firefox-53.0a1.en-US.mac.common.tests.zip'], 19:46:53 INFO - u'cppunittest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 19:46:53 INFO - u'firefox-53.0a1.en-US.mac.cppunittest.tests.zip'], 19:46:53 INFO - u'gtest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 19:46:53 INFO - u'firefox-53.0a1.en-US.mac.gtest.tests.zip'], 19:46:53 INFO - u'jittest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 19:46:53 INFO - u'jsshell-mac.zip'], 19:46:53 INFO - u'mochitest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 19:46:53 INFO - u'firefox-53.0a1.en-US.mac.mochitest.tests.zip'], 19:46:53 INFO - u'mozbase': [u'firefox-53.0a1.en-US.mac.common.tests.zip'], 19:46:53 INFO - u'reftest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 19:46:53 INFO - u'firefox-53.0a1.en-US.mac.reftest.tests.zip'], 19:46:53 INFO - u'talos': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 19:46:53 INFO - u'firefox-53.0a1.en-US.mac.talos.tests.zip'], 19:46:53 INFO - u'web-platform': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 19:46:53 INFO - u'firefox-53.0a1.en-US.mac.web-platform.tests.zip'], 19:46:53 INFO - u'xpcshell': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 19:46:53 INFO - u'firefox-53.0a1.en-US.mac.xpcshell.tests.zip']} 19:46:53 INFO - Downloading packages: [u'firefox-53.0a1.en-US.mac.common.tests.zip'] for test suite category: common 19:46:53 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Snmb8HRcQWOBKk1aw_4KVw/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip 19:46:53 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Snmb8HRcQWOBKk1aw_4KVw/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip'}, attempt #1 19:46:53 INFO - Fetch https://queue.taskcluster.net/v1/task/Snmb8HRcQWOBKk1aw_4KVw/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip into memory 19:46:54 INFO - Expected file size: 16299192 19:46:54 INFO - Obtained file size: 16299192 19:47:01 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 19:47:01 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Snmb8HRcQWOBKk1aw_4KVw/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip 19:47:01 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Snmb8HRcQWOBKk1aw_4KVw/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip'}, attempt #1 19:47:01 INFO - Fetch https://queue.taskcluster.net/v1/task/Snmb8HRcQWOBKk1aw_4KVw/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip into memory 19:47:02 INFO - Expected file size: 16299192 19:47:02 INFO - Obtained file size: 16299192 19:47:08 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Snmb8HRcQWOBKk1aw_4KVw/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip 19:47:08 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Snmb8HRcQWOBKk1aw_4KVw/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip'}, attempt #1 19:47:08 INFO - Fetch https://queue.taskcluster.net/v1/task/Snmb8HRcQWOBKk1aw_4KVw/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip into memory 19:47:10 INFO - Expected file size: 14409776 19:47:10 INFO - Obtained file size: 14409776 19:47: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')]} 19:47:11 INFO - trying https://queue.taskcluster.net/v1/task/Snmb8HRcQWOBKk1aw_4KVw/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 19:47:11 INFO - Downloading https://queue.taskcluster.net/v1/task/Snmb8HRcQWOBKk1aw_4KVw/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg 19:47:11 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Snmb8HRcQWOBKk1aw_4KVw/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 19:47:13 INFO - Downloaded 69734529 bytes. 19:47:13 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Snmb8HRcQWOBKk1aw_4KVw/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 19:47:13 INFO - mkdir: /builds/slave/test/properties 19:47:13 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 19:47:13 INFO - Writing to file /builds/slave/test/properties/build_url 19:47:13 INFO - Contents: 19:47:13 INFO - build_url:https://queue.taskcluster.net/v1/task/Snmb8HRcQWOBKk1aw_4KVw/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 19:47:14 INFO - Running post-action listener: _resource_record_post_action 19:47:14 INFO - Running post-action listener: set_extra_try_arguments 19:47:14 INFO - [mozharness: 2016-12-30 03:47:14.282417Z] Finished download-and-extract step (success) 19:47:14 INFO - [mozharness: 2016-12-30 03:47:14.282545Z] Running populate-webroot step. 19:47:14 INFO - Running pre-action listener: _resource_record_pre_action 19:47:14 INFO - Running main action method: populate_webroot 19:47:14 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 19:47:14 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 19:47:14 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 19:47:14 INFO - '--tppagecycles', 19:47:14 INFO - '7'], 19:47:14 INFO - 'tests': ['tsvgm']}}, 19:47:14 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 19:47:14 INFO - 'tests': ['tresize', 'tcanvasmark']}, 19:47:14 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 19:47:14 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 19:47:14 INFO - 'tests': ['dromaeo_css', 'kraken']}, 19:47:14 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 19:47:14 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 19:47:14 INFO - 'talos_options': ['--disable-e10s'], 19:47:14 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 19:47:14 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 19:47:14 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 19:47:14 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 19:47:14 INFO - 'talos_options': ['--disable-e10s'], 19:47:14 INFO - 'tests': ['damp', 'tps']}, 19:47:14 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 19:47:14 INFO - 'tests': ['damp', 'tps']}, 19:47:14 INFO - 'g3': {'talos_options': ['--disable-e10s'], 19:47:14 INFO - 'tests': ['dromaeo_dom']}, 19:47:14 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 19:47:14 INFO - 'g4': {'talos_options': ['--disable-e10s'], 19:47:14 INFO - 'tests': ['basic_compositor_video']}, 19:47:14 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 19:47:14 INFO - 'other': {'talos_options': ['--disable-e10s'], 19:47:14 INFO - 'tests': ['a11yr', 19:47:14 INFO - 'ts_paint', 19:47:14 INFO - 'tpaint', 19:47:14 INFO - 'sessionrestore', 19:47:14 INFO - 'sessionrestore_no_auto_restore', 19:47:14 INFO - 'tabpaint']}, 19:47:14 INFO - 'other-e10s': {'tests': ['a11yr', 19:47:14 INFO - 'ts_paint', 19:47:14 INFO - 'tpaint', 19:47:14 INFO - 'sessionrestore', 19:47:14 INFO - 'sessionrestore_no_auto_restore', 19:47:14 INFO - 'tabpaint']}, 19:47:14 INFO - 'other-e10s_l64': {'tests': ['a11yr', 19:47:14 INFO - 'ts_paint', 19:47:14 INFO - 'tpaint', 19:47:14 INFO - 'sessionrestore', 19:47:14 INFO - 'sessionrestore_no_auto_restore', 19:47:14 INFO - 'tabpaint']}, 19:47:14 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 19:47:14 INFO - 'ts_paint', 19:47:14 INFO - 'tpaint', 19:47:14 INFO - 'sessionrestore', 19:47:14 INFO - 'sessionrestore_no_auto_restore', 19:47:14 INFO - 'tabpaint']}, 19:47:14 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 19:47:14 INFO - 'tests': ['a11yr', 19:47:14 INFO - 'ts_paint', 19:47:14 INFO - 'tpaint', 19:47:14 INFO - 'sessionrestore', 19:47:14 INFO - 'sessionrestore_no_auto_restore', 19:47:14 INFO - 'tabpaint']}, 19:47:14 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 19:47:14 INFO - 'tests': ['a11yr', 19:47:14 INFO - 'ts_paint', 19:47:14 INFO - 'tpaint', 19:47:14 INFO - 'sessionrestore', 19:47:14 INFO - 'sessionrestore_no_auto_restore', 19:47:14 INFO - 'tabpaint']}, 19:47:14 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 19:47:14 INFO - 'tests': ['tsvgx', 19:47:14 INFO - 'tsvgr_opacity', 19:47:14 INFO - 'tart', 19:47:14 INFO - 'tscrollx', 19:47:14 INFO - 'cart', 19:47:14 INFO - 'tsvg_static']}, 19:47:14 INFO - 'svgr-e10s': {'tests': ['tsvgx', 19:47:14 INFO - 'tsvgr_opacity', 19:47:14 INFO - 'tart', 19:47:14 INFO - 'tscrollx', 19:47:14 INFO - 'cart', 19:47:14 INFO - 'tsvg_static']}, 19:47:14 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 19:47:14 INFO - 'talos_options': ['--disable-e10s'], 19:47:14 INFO - 'tests': ['tp5o']}, 19:47:14 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 19:47:14 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 19:47:14 INFO - 'talos_options': ['--disable-e10s', 19:47:14 INFO - '--xperf_path', 19:47:14 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 19:47:14 INFO - 'tests': ['tp5n']}, 19:47:14 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 19:47:14 INFO - 'talos_options': ['--xperf_path', 19:47:14 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 19:47:14 INFO - 'tests': ['tp5n']}}, 19:47:14 INFO - 'talos.zip': {'path': '', 19:47:14 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 19:47:14 INFO - Running post-action listener: _resource_record_post_action 19:47:14 INFO - [mozharness: 2016-12-30 03:47:14.300751Z] Finished populate-webroot step (success) 19:47:14 INFO - [mozharness: 2016-12-30 03:47:14.300866Z] Running create-virtualenv step. 19:47:14 INFO - Running pre-action listener: _resource_record_pre_action 19:47:14 INFO - Running main action method: create_virtualenv 19:47:14 INFO - Creating virtualenv /builds/slave/test/build/venv 19:47:14 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 19:47:14 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 19:47:14 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 19:47:14 INFO - Using real prefix '/tools/python27' 19:47:14 INFO - New python executable in /builds/slave/test/build/venv/bin/python 19:47:15 INFO - Installing distribute.............................................................................................................................................................................................done. 19:47:17 INFO - Installing pip.................done. 19:47:17 INFO - Return code: 0 19:47:17 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 19:47:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 19:47:18 INFO - Reading from file tmpfile_stdout 19:47:18 INFO - Output received: 19:47:18 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) 19:47:18 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 19:47:18 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')]} 19:47:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:47:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:47:18 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')]} 19:47:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:47:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:47:18 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 0x10f9f7b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f9fa768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffd0848e550>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f7d5d30>, '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 0x10f6fcdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f7cabe8>, '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.XAxEqqjmZN/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.YW7HwvDW5a/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9_/bq7njmms6gbgk1ygh6pll6r400000w/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 19:47:18 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 19:47:18 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 19:47:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.XAxEqqjmZN/Render', 19:47:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:47:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:47:18 INFO - 'HOME': '/Users/cltbld', 19:47:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:47:18 INFO - 'LOGNAME': 'cltbld', 19:47:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:47:18 INFO - 'MOZ_NO_REMOTE': '1', 19:47:18 INFO - 'NO_EM_RESTART': '1', 19:47:18 INFO - 'PAGER': '/bin/cat', 19:47:18 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:47:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:47:18 INFO - 'PWD': '/builds/slave/test', 19:47:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:47:18 INFO - 'SHELL': '/bin/bash', 19:47:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YW7HwvDW5a/Listeners', 19:47:18 INFO - 'TMPDIR': '/var/folders/9_/bq7njmms6gbgk1ygh6pll6r400000w/T/', 19:47:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:47:18 INFO - 'USER': 'cltbld', 19:47:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:47:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:47:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:47:18 INFO - 'XPC_FLAGS': '0x0', 19:47:18 INFO - 'XPC_SERVICE_NAME': '0', 19:47:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:47:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:47:18 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 19:47:18 INFO - Cleaning up... 19:47:18 INFO - Return code: 0 19:47:18 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 19:47:18 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')]} 19:47:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:47:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:47:18 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')]} 19:47:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:47:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:47:18 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 0x10f9f7b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f9fa768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffd0848e550>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f7d5d30>, '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 0x10f6fcdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f7cabe8>, '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.XAxEqqjmZN/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.YW7HwvDW5a/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9_/bq7njmms6gbgk1ygh6pll6r400000w/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 19:47:18 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 19:47:18 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 19:47:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.XAxEqqjmZN/Render', 19:47:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:47:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:47:18 INFO - 'HOME': '/Users/cltbld', 19:47:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:47:18 INFO - 'LOGNAME': 'cltbld', 19:47:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:47:18 INFO - 'MOZ_NO_REMOTE': '1', 19:47:18 INFO - 'NO_EM_RESTART': '1', 19:47:18 INFO - 'PAGER': '/bin/cat', 19:47:18 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:47:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:47:18 INFO - 'PWD': '/builds/slave/test', 19:47:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:47:18 INFO - 'SHELL': '/bin/bash', 19:47:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YW7HwvDW5a/Listeners', 19:47:18 INFO - 'TMPDIR': '/var/folders/9_/bq7njmms6gbgk1ygh6pll6r400000w/T/', 19:47:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:47:18 INFO - 'USER': 'cltbld', 19:47:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:47:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:47:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:47:18 INFO - 'XPC_FLAGS': '0x0', 19:47:18 INFO - 'XPC_SERVICE_NAME': '0', 19:47:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:47:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:47:18 INFO - Downloading/unpacking psutil>=3.1.1 19:47:18 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 19:47:18 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 19:47:18 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 19:47:18 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 19:47:18 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 19:47:18 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 19:47:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 19:47:22 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 19:47:22 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 19:47:22 INFO - Installing collected packages: psutil 19:47:22 INFO - Running setup.py install for psutil 19:47:22 INFO - building 'psutil._psutil_osx' extension 19:47:22 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 19:47:22 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 19:47:22 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 19:47:23 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 19:47:23 INFO - building 'psutil._psutil_posix' extension 19:47:23 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 19:47:23 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 19:47:23 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 19:47:23 INFO - ^ 19:47:23 INFO - 1 warning generated. 19:47:23 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 19:47:23 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 19:47:23 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 19:47:23 INFO - Successfully installed psutil 19:47:23 INFO - Cleaning up... 19:47:23 INFO - Return code: 0 19:47:23 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 19:47: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')]} 19:47:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:47:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:47: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')]} 19:47:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:47:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:47: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', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10f9f7b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f9fa768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffd0848e550>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f7d5d30>, '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 0x10f6fcdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f7cabe8>, '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.XAxEqqjmZN/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.YW7HwvDW5a/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9_/bq7njmms6gbgk1ygh6pll6r400000w/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 19:47: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', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 19:47: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 mozsystemmonitor==0.3 19:47:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.XAxEqqjmZN/Render', 19:47:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:47:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:47:23 INFO - 'HOME': '/Users/cltbld', 19:47:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:47:23 INFO - 'LOGNAME': 'cltbld', 19:47:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:47:23 INFO - 'MOZ_NO_REMOTE': '1', 19:47:23 INFO - 'NO_EM_RESTART': '1', 19:47:23 INFO - 'PAGER': '/bin/cat', 19:47:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:47:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:47:23 INFO - 'PWD': '/builds/slave/test', 19:47:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:47:23 INFO - 'SHELL': '/bin/bash', 19:47:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YW7HwvDW5a/Listeners', 19:47:23 INFO - 'TMPDIR': '/var/folders/9_/bq7njmms6gbgk1ygh6pll6r400000w/T/', 19:47:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:47:23 INFO - 'USER': 'cltbld', 19:47:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:47:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:47:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:47:23 INFO - 'XPC_FLAGS': '0x0', 19:47:23 INFO - 'XPC_SERVICE_NAME': '0', 19:47:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:47:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:47:23 INFO - Downloading/unpacking mozsystemmonitor==0.3 19:47: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 19:47: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 19:47: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 19:47: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 19:47: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 19:47: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 19:47:26 INFO - Downloading mozsystemmonitor-0.3.tar.gz 19:47:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 19:47:26 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 19:47:26 INFO - Installing collected packages: mozsystemmonitor 19:47:26 INFO - Running setup.py install for mozsystemmonitor 19:47:26 INFO - Successfully installed mozsystemmonitor 19:47:26 INFO - Cleaning up... 19:47:26 INFO - Return code: 0 19:47:26 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 19:47:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:47:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:47:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:47:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:47:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:47:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:47:26 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 0x10f9f7b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f9fa768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffd0848e550>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f7d5d30>, '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 0x10f6fcdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f7cabe8>, '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.XAxEqqjmZN/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.YW7HwvDW5a/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9_/bq7njmms6gbgk1ygh6pll6r400000w/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 19:47:26 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 19:47:26 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 19:47:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.XAxEqqjmZN/Render', 19:47:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:47:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:47:26 INFO - 'HOME': '/Users/cltbld', 19:47:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:47:26 INFO - 'LOGNAME': 'cltbld', 19:47:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:47:26 INFO - 'MOZ_NO_REMOTE': '1', 19:47:26 INFO - 'NO_EM_RESTART': '1', 19:47:26 INFO - 'PAGER': '/bin/cat', 19:47:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:47:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:47:26 INFO - 'PWD': '/builds/slave/test', 19:47:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:47:26 INFO - 'SHELL': '/bin/bash', 19:47:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YW7HwvDW5a/Listeners', 19:47:26 INFO - 'TMPDIR': '/var/folders/9_/bq7njmms6gbgk1ygh6pll6r400000w/T/', 19:47:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:47:26 INFO - 'USER': 'cltbld', 19:47:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:47:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:47:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:47:26 INFO - 'XPC_FLAGS': '0x0', 19:47:26 INFO - 'XPC_SERVICE_NAME': '0', 19:47:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:47:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:47:26 INFO - Downloading/unpacking jsonschema==2.5.1 19:47: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 19:47: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 19:47: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 19:47: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 19:47: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 19:47: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 19:47:29 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 19:47:29 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 19:47:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:47:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:47:29 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:47:29 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:47:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:47:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:47:29 INFO - Downloading functools32-3.2.3-2.tar.gz 19:47:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 19:47:29 INFO - warning: no files found matching '*.txt' 19:47:29 INFO - no previously-included directories found matching 'build' 19:47:29 INFO - no previously-included directories found matching 'dist' 19:47:29 INFO - no previously-included directories found matching '.git*' 19:47:29 INFO - Installing collected packages: jsonschema, functools32 19:47:29 INFO - Running setup.py install for functools32 19:47:30 INFO - warning: no files found matching '*.txt' 19:47:30 INFO - no previously-included directories found matching 'build' 19:47:30 INFO - no previously-included directories found matching 'dist' 19:47:30 INFO - no previously-included directories found matching '.git*' 19:47:30 INFO - Successfully installed jsonschema functools32 19:47:30 INFO - Cleaning up... 19:47:30 INFO - Return code: 0 19:47:30 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 19:47:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:47:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:47:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:47:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:47:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:47:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:47:30 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 0x10f9f7b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f9fa768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffd0848e550>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f7d5d30>, '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 0x10f6fcdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f7cabe8>, '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.XAxEqqjmZN/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.YW7HwvDW5a/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9_/bq7njmms6gbgk1ygh6pll6r400000w/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 19:47:30 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 19:47:30 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 19:47:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.XAxEqqjmZN/Render', 19:47:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:47:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:47:30 INFO - 'HOME': '/Users/cltbld', 19:47:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:47:30 INFO - 'LOGNAME': 'cltbld', 19:47:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:47:30 INFO - 'MOZ_NO_REMOTE': '1', 19:47:30 INFO - 'NO_EM_RESTART': '1', 19:47:30 INFO - 'PAGER': '/bin/cat', 19:47:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:47:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:47:30 INFO - 'PWD': '/builds/slave/test', 19:47:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:47:30 INFO - 'SHELL': '/bin/bash', 19:47:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YW7HwvDW5a/Listeners', 19:47:30 INFO - 'TMPDIR': '/var/folders/9_/bq7njmms6gbgk1ygh6pll6r400000w/T/', 19:47:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:47:30 INFO - 'USER': 'cltbld', 19:47:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:47:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:47:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:47:30 INFO - 'XPC_FLAGS': '0x0', 19:47:30 INFO - 'XPC_SERVICE_NAME': '0', 19:47:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:47:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:47:30 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 19:47:30 INFO - Cleaning up... 19:47:30 INFO - Return code: 0 19:47:30 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 19:47:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:47:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:47:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:47:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:47:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:47:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:47:30 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 0x10f9f7b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f9fa768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffd0848e550>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f7d5d30>, '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 0x10f6fcdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f7cabe8>, '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.XAxEqqjmZN/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.YW7HwvDW5a/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9_/bq7njmms6gbgk1ygh6pll6r400000w/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 19:47:30 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 19:47:30 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 19:47:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.XAxEqqjmZN/Render', 19:47:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:47:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:47:30 INFO - 'HOME': '/Users/cltbld', 19:47:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:47:30 INFO - 'LOGNAME': 'cltbld', 19:47:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:47:30 INFO - 'MOZ_NO_REMOTE': '1', 19:47:30 INFO - 'NO_EM_RESTART': '1', 19:47:30 INFO - 'PAGER': '/bin/cat', 19:47:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:47:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:47:30 INFO - 'PWD': '/builds/slave/test', 19:47:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:47:30 INFO - 'SHELL': '/bin/bash', 19:47:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YW7HwvDW5a/Listeners', 19:47:30 INFO - 'TMPDIR': '/var/folders/9_/bq7njmms6gbgk1ygh6pll6r400000w/T/', 19:47:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:47:30 INFO - 'USER': 'cltbld', 19:47:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:47:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:47:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:47:30 INFO - 'XPC_FLAGS': '0x0', 19:47:30 INFO - 'XPC_SERVICE_NAME': '0', 19:47:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:47:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:47:30 INFO - Downloading/unpacking blobuploader==1.2.4 19:47:30 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 19:47:30 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 19:47:30 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 19:47:30 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 19:47:30 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 19:47:30 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 19:47:33 INFO - Downloading blobuploader-1.2.4.tar.gz 19:47:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 19:47:33 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 19:47:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:47:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:47:33 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:47:33 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:47:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:47:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:47:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 19:47:34 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 19:47:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:47:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:47:34 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:47:34 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:47:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:47:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:47:34 INFO - Downloading docopt-0.6.1.tar.gz 19:47:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 19:47:34 INFO - Installing collected packages: blobuploader, requests, docopt 19:47:34 INFO - Running setup.py install for blobuploader 19:47:34 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 19:47:34 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 19:47:34 INFO - Running setup.py install for requests 19:47:35 INFO - Running setup.py install for docopt 19:47:35 INFO - Successfully installed blobuploader requests docopt 19:47:35 INFO - Cleaning up... 19:47:35 INFO - Return code: 0 19:47:35 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:47:35 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')]} 19:47:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:47:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:47:35 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')]} 19:47:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:47:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:47:36 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 0x10f9f7b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f9fa768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffd0848e550>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f7d5d30>, '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 0x10f6fcdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f7cabe8>, '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.XAxEqqjmZN/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.YW7HwvDW5a/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9_/bq7njmms6gbgk1ygh6pll6r400000w/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 19:47:36 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 19:47:36 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 19:47:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.XAxEqqjmZN/Render', 19:47:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:47:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:47:36 INFO - 'HOME': '/Users/cltbld', 19:47:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:47:36 INFO - 'LOGNAME': 'cltbld', 19:47:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:47:36 INFO - 'MOZ_NO_REMOTE': '1', 19:47:36 INFO - 'NO_EM_RESTART': '1', 19:47:36 INFO - 'PAGER': '/bin/cat', 19:47:36 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:47:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:47:36 INFO - 'PWD': '/builds/slave/test', 19:47:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:47:36 INFO - 'SHELL': '/bin/bash', 19:47:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YW7HwvDW5a/Listeners', 19:47:36 INFO - 'TMPDIR': '/var/folders/9_/bq7njmms6gbgk1ygh6pll6r400000w/T/', 19:47:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:47:36 INFO - 'USER': 'cltbld', 19:47:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:47:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:47:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:47:36 INFO - 'XPC_FLAGS': '0x0', 19:47:36 INFO - 'XPC_SERVICE_NAME': '0', 19:47:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:47:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:47:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 19:47:36 INFO - Running setup.py (path:/var/folders/9_/bq7njmms6gbgk1ygh6pll6r400000w/T/pip-iubwHt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 19:47:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 19:47:36 INFO - Running setup.py (path:/var/folders/9_/bq7njmms6gbgk1ygh6pll6r400000w/T/pip-Z2gsxE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 19:47:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 19:47:36 INFO - Running setup.py (path:/var/folders/9_/bq7njmms6gbgk1ygh6pll6r400000w/T/pip-6RbMis-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 19:47:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 19:47:36 INFO - Running setup.py (path:/var/folders/9_/bq7njmms6gbgk1ygh6pll6r400000w/T/pip-7KnNIG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 19:47:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 19:47:36 INFO - Running setup.py (path:/var/folders/9_/bq7njmms6gbgk1ygh6pll6r400000w/T/pip-qcImMC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 19:47:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 19:47:37 INFO - Running setup.py (path:/var/folders/9_/bq7njmms6gbgk1ygh6pll6r400000w/T/pip-Mjgjfz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 19:47:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 19:47:37 INFO - Running setup.py (path:/var/folders/9_/bq7njmms6gbgk1ygh6pll6r400000w/T/pip-ykDjH0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 19:47:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 19:47:37 INFO - Running setup.py (path:/var/folders/9_/bq7njmms6gbgk1ygh6pll6r400000w/T/pip-8tKYzb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 19:47:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 19:47:37 INFO - Running setup.py (path:/var/folders/9_/bq7njmms6gbgk1ygh6pll6r400000w/T/pip-bkUNXy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 19:47:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 19:47:37 INFO - Running setup.py (path:/var/folders/9_/bq7njmms6gbgk1ygh6pll6r400000w/T/pip-vgWJfi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 19:47:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 19:47:37 INFO - Running setup.py (path:/var/folders/9_/bq7njmms6gbgk1ygh6pll6r400000w/T/pip-Lyu1Fa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 19:47:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 19:47:37 INFO - Running setup.py (path:/var/folders/9_/bq7njmms6gbgk1ygh6pll6r400000w/T/pip-HzUEVj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 19:47:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 19:47:37 INFO - Running setup.py (path:/var/folders/9_/bq7njmms6gbgk1ygh6pll6r400000w/T/pip-LdsrN9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 19:47:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 19:47:37 INFO - Running setup.py (path:/var/folders/9_/bq7njmms6gbgk1ygh6pll6r400000w/T/pip-iSfwus-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 19:47:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 19:47:38 INFO - Running setup.py (path:/var/folders/9_/bq7njmms6gbgk1ygh6pll6r400000w/T/pip-QYySy5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 19:47:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 19:47:38 INFO - Running setup.py (path:/var/folders/9_/bq7njmms6gbgk1ygh6pll6r400000w/T/pip-V8Z5Zk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 19:47:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 19:47:38 INFO - Running setup.py (path:/var/folders/9_/bq7njmms6gbgk1ygh6pll6r400000w/T/pip-BCHemS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 19:47:38 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 19:47:38 INFO - Running setup.py install for manifestparser 19:47:38 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 19:47:38 INFO - Running setup.py install for mozcrash 19:47:38 INFO - Running setup.py install for mozdebug 19:47:38 INFO - Running setup.py install for mozdevice 19:47:38 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 19:47:38 INFO - Installing dm script to /builds/slave/test/build/venv/bin 19:47:38 INFO - Running setup.py install for mozfile 19:47:39 INFO - Running setup.py install for mozhttpd 19:47:39 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 19:47:39 INFO - Running setup.py install for mozinfo 19:47:39 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 19:47:39 INFO - Running setup.py install for mozInstall 19:47:39 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 19:47:39 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 19:47:39 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 19:47:39 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 19:47:39 INFO - Running setup.py install for mozleak 19:47:39 INFO - Running setup.py install for mozlog 19:47:39 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 19:47:39 INFO - Running setup.py install for moznetwork 19:47:39 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 19:47:39 INFO - Running setup.py install for mozprocess 19:47:40 INFO - Running setup.py install for mozprofile 19:47:40 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 19:47:40 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 19:47:40 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 19:47:40 INFO - Running setup.py install for mozrunner 19:47:40 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 19:47:40 INFO - Running setup.py install for mozscreenshot 19:47:40 INFO - Running setup.py install for moztest 19:47:40 INFO - Running setup.py install for mozversion 19:47:40 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 19:47:40 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 19:47:40 INFO - Cleaning up... 19:47:40 INFO - Return code: 0 19:47:40 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:47:40 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')]} 19:47:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:47:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:47:40 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')]} 19:47:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:47:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:47:41 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 0x10f9f7b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f9fa768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffd0848e550>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f7d5d30>, '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 0x10f6fcdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f7cabe8>, '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.XAxEqqjmZN/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.YW7HwvDW5a/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9_/bq7njmms6gbgk1ygh6pll6r400000w/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 19:47:41 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 19:47:41 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 19:47:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.XAxEqqjmZN/Render', 19:47:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:47:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:47:41 INFO - 'HOME': '/Users/cltbld', 19:47:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:47:41 INFO - 'LOGNAME': 'cltbld', 19:47:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:47:41 INFO - 'MOZ_NO_REMOTE': '1', 19:47:41 INFO - 'NO_EM_RESTART': '1', 19:47:41 INFO - 'PAGER': '/bin/cat', 19:47:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:47:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:47:41 INFO - 'PWD': '/builds/slave/test', 19:47:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:47:41 INFO - 'SHELL': '/bin/bash', 19:47:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YW7HwvDW5a/Listeners', 19:47:41 INFO - 'TMPDIR': '/var/folders/9_/bq7njmms6gbgk1ygh6pll6r400000w/T/', 19:47:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:47:41 INFO - 'USER': 'cltbld', 19:47:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:47:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:47:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:47:41 INFO - 'XPC_FLAGS': '0x0', 19:47:41 INFO - 'XPC_SERVICE_NAME': '0', 19:47:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:47:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:47:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 19:47:41 INFO - Running setup.py (path:/var/folders/9_/bq7njmms6gbgk1ygh6pll6r400000w/T/pip-JIkqG0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 19:47:41 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)) 19:47:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 19:47:41 INFO - Running setup.py (path:/var/folders/9_/bq7njmms6gbgk1ygh6pll6r400000w/T/pip-Rxpr63-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 19:47:41 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)) 19:47:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 19:47:41 INFO - Running setup.py (path:/var/folders/9_/bq7njmms6gbgk1ygh6pll6r400000w/T/pip-B9lF9W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 19:47:41 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)) 19:47:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 19:47:41 INFO - Running setup.py (path:/var/folders/9_/bq7njmms6gbgk1ygh6pll6r400000w/T/pip-f5TC7c-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 19:47:41 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)) 19:47:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 19:47:41 INFO - Running setup.py (path:/var/folders/9_/bq7njmms6gbgk1ygh6pll6r400000w/T/pip-I53bvo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 19:47:42 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)) 19:47:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 19:47:42 INFO - Running setup.py (path:/var/folders/9_/bq7njmms6gbgk1ygh6pll6r400000w/T/pip-dpxbxz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 19:47:42 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)) 19:47:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 19:47:42 INFO - Running setup.py (path:/var/folders/9_/bq7njmms6gbgk1ygh6pll6r400000w/T/pip-RrUOB8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 19:47:42 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)) 19:47:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 19:47:42 INFO - Running setup.py (path:/var/folders/9_/bq7njmms6gbgk1ygh6pll6r400000w/T/pip-gwmMYT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 19:47:42 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)) 19:47:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 19:47:42 INFO - Running setup.py (path:/var/folders/9_/bq7njmms6gbgk1ygh6pll6r400000w/T/pip-NWEnt1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 19:47:42 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)) 19:47:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 19:47:42 INFO - Running setup.py (path:/var/folders/9_/bq7njmms6gbgk1ygh6pll6r400000w/T/pip-CX5XeF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 19:47:42 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)) 19:47:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 19:47:42 INFO - Running setup.py (path:/var/folders/9_/bq7njmms6gbgk1ygh6pll6r400000w/T/pip-3cGhek-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 19:47:42 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)) 19:47:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 19:47:42 INFO - Running setup.py (path:/var/folders/9_/bq7njmms6gbgk1ygh6pll6r400000w/T/pip-9e7rQQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 19:47:42 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)) 19:47:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 19:47:42 INFO - Running setup.py (path:/var/folders/9_/bq7njmms6gbgk1ygh6pll6r400000w/T/pip-q4fS6s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 19:47:42 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)) 19:47:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 19:47:42 INFO - Running setup.py (path:/var/folders/9_/bq7njmms6gbgk1ygh6pll6r400000w/T/pip-GkvlTQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 19:47:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 19:47:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 19:47:43 INFO - Running setup.py (path:/var/folders/9_/bq7njmms6gbgk1ygh6pll6r400000w/T/pip-5uyWAc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 19:47:43 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)) 19:47:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 19:47:43 INFO - Running setup.py (path:/var/folders/9_/bq7njmms6gbgk1ygh6pll6r400000w/T/pip-7mkL8n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 19:47:43 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 19:47:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 19:47:43 INFO - Running setup.py (path:/var/folders/9_/bq7njmms6gbgk1ygh6pll6r400000w/T/pip-_bi3GF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 19:47:43 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)) 19:47:43 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)) 19:47:43 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)) 19:47:43 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)) 19:47:43 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)) 19:47:43 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)) 19:47:43 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 19:47:43 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 19:47:43 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 19:47:43 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 19:47:43 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 19:47:43 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 19:47:43 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 19:47:46 INFO - Downloading blessings-1.6.tar.gz 19:47:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 19:47:46 INFO - Installing collected packages: blessings 19:47:46 INFO - Running setup.py install for blessings 19:47:46 INFO - Successfully installed blessings 19:47:46 INFO - Cleaning up... 19:47:46 INFO - Return code: 0 19:47:46 INFO - Done creating virtualenv /builds/slave/test/build/venv. 19:47:46 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 19:47:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 19:47:47 INFO - Reading from file tmpfile_stdout 19:47:47 INFO - Current package versions: 19:47:47 INFO - blessings == 1.6 19:47:47 INFO - blobuploader == 1.2.4 19:47:47 INFO - docopt == 0.6.1 19:47:47 INFO - functools32 == 3.2.3-2 19:47:47 INFO - jsonschema == 2.5.1 19:47:47 INFO - manifestparser == 1.1 19:47:47 INFO - mozInstall == 1.12 19:47:47 INFO - mozcrash == 1.0 19:47:47 INFO - mozdebug == 0.1 19:47:47 INFO - mozdevice == 0.49 19:47:47 INFO - mozfile == 1.2 19:47:47 INFO - mozhttpd == 0.7 19:47:47 INFO - mozinfo == 0.9 19:47:47 INFO - mozleak == 0.1 19:47:47 INFO - mozlog == 3.4 19:47:47 INFO - moznetwork == 0.27 19:47:47 INFO - mozprocess == 0.23 19:47:47 INFO - mozprofile == 0.28 19:47:47 INFO - mozrunner == 6.13 19:47:47 INFO - mozscreenshot == 0.1 19:47:47 INFO - mozsystemmonitor == 0.3 19:47:47 INFO - moztest == 0.8 19:47:47 INFO - mozversion == 1.4 19:47:47 INFO - psutil == 3.1.1 19:47:47 INFO - requests == 1.2.3 19:47:47 INFO - wsgiref == 0.1.2 19:47:47 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:47: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')]} 19:47:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:47:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:47: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')]} 19:47:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:47:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:47:47 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10f9f7b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f9fa768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffd0848e550>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f7d5d30>, '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 0x10f6fcdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f7cabe8>, '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.XAxEqqjmZN/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.YW7HwvDW5a/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9_/bq7njmms6gbgk1ygh6pll6r400000w/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 19:47:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 19:47:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 19:47:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.XAxEqqjmZN/Render', 19:47:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:47:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:47:47 INFO - 'HOME': '/Users/cltbld', 19:47:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:47:47 INFO - 'LOGNAME': 'cltbld', 19:47:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:47:47 INFO - 'MOZ_NO_REMOTE': '1', 19:47:47 INFO - 'NO_EM_RESTART': '1', 19:47:47 INFO - 'PAGER': '/bin/cat', 19:47:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:47:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:47:47 INFO - 'PWD': '/builds/slave/test', 19:47:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:47:47 INFO - 'SHELL': '/bin/bash', 19:47:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YW7HwvDW5a/Listeners', 19:47:47 INFO - 'TMPDIR': '/var/folders/9_/bq7njmms6gbgk1ygh6pll6r400000w/T/', 19:47:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:47:47 INFO - 'USER': 'cltbld', 19:47:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:47:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:47:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:47:47 INFO - 'XPC_FLAGS': '0x0', 19:47:47 INFO - 'XPC_SERVICE_NAME': '0', 19:47:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:47:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:47:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 19:47:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 19:47:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 19:47:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 19:47:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 19:47:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 19:47:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 19:47:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 19:47:47 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 19:47:47 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 19:47:47 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 19:47:47 INFO - Cleaning up... 19:47:47 INFO - Return code: 0 19:47:47 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 19:47: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')]} 19:47:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:47:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:47: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')]} 19:47:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:47:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:47:47 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10f9f7b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f9fa768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffd0848e550>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f7d5d30>, '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 0x10f6fcdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f7cabe8>, '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.XAxEqqjmZN/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.YW7HwvDW5a/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9_/bq7njmms6gbgk1ygh6pll6r400000w/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 19:47:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 19:47:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 19:47:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.XAxEqqjmZN/Render', 19:47:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:47:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:47:47 INFO - 'HOME': '/Users/cltbld', 19:47:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:47:47 INFO - 'LOGNAME': 'cltbld', 19:47:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:47:47 INFO - 'MOZ_NO_REMOTE': '1', 19:47:47 INFO - 'NO_EM_RESTART': '1', 19:47:47 INFO - 'PAGER': '/bin/cat', 19:47:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:47:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:47:47 INFO - 'PWD': '/builds/slave/test', 19:47:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:47:47 INFO - 'SHELL': '/bin/bash', 19:47:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YW7HwvDW5a/Listeners', 19:47:47 INFO - 'TMPDIR': '/var/folders/9_/bq7njmms6gbgk1ygh6pll6r400000w/T/', 19:47:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:47:47 INFO - 'USER': 'cltbld', 19:47:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:47:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:47:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:47:47 INFO - 'XPC_FLAGS': '0x0', 19:47:47 INFO - 'XPC_SERVICE_NAME': '0', 19:47:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:47:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:47:47 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 19:47:47 INFO - Cleaning up... 19:47:47 INFO - Return code: 0 19:47:47 INFO - Running post-action listener: _resource_record_post_action 19:47:47 INFO - Running post-action listener: _start_resource_monitoring 19:47:47 INFO - Starting resource monitoring. 19:47:47 INFO - [mozharness: 2016-12-30 03:47:47.627467Z] Finished create-virtualenv step (success) 19:47:47 INFO - [mozharness: 2016-12-30 03:47:47.628390Z] Running install step. 19:47:47 INFO - Running pre-action listener: _resource_record_pre_action 19:47:47 INFO - Running main action method: install 19:47:47 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 19:47:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 19:47:47 INFO - Reading from file tmpfile_stdout 19:47:47 INFO - Detecting whether we're running mozinstall >=1.0... 19:47:47 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 19:47:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 19:47:47 INFO - Reading from file tmpfile_stdout 19:47:47 INFO - Output received: 19:47:47 INFO - Usage: mozinstall [options] installer 19:47:47 INFO - Options: 19:47:47 INFO - -h, --help show this help message and exit 19:47:47 INFO - -d DEST, --destination=DEST 19:47:47 INFO - Directory to install application into. [default: 19:47:47 INFO - "/builds/slave/test"] 19:47:47 INFO - --app=APP Application being installed. [default: firefox] 19:47:47 INFO - mkdir: /builds/slave/test/build/application 19:47:47 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'] 19:47:47 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 19:48:09 INFO - Reading from file tmpfile_stdout 19:48:09 INFO - Output received: 19:48:09 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 19:48:09 INFO - Running post-action listener: _resource_record_post_action 19:48:09 INFO - [mozharness: 2016-12-30 03:48:09.632739Z] Finished install step (success) 19:48:09 INFO - [mozharness: 2016-12-30 03:48:09.632876Z] Running run-tests step. 19:48:09 INFO - Running pre-action listener: _resource_record_pre_action 19:48:09 INFO - Running main action method: run_tests 19:48:09 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 19:48:09 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 19:48:09 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 19:48:09 INFO - 2016-12-29 19:48:09.647 screenresolution[1632:7579] starting screenresolution argv=screenresolution get 19:48:09 INFO - 2016-12-29 19:48:09.649 screenresolution[1632:7579] Display 0: 1600x1200x32@60 19:48:09 INFO - 2016-12-29 19:48:09.655 screenresolution[1633:7581] starting screenresolution argv=screenresolution list 19:48:09 INFO - Available Modes on Display 0 19:48:09 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 19:48:09 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 19:48:09 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 19:48:09 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 19:48:09 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 19:48:09 INFO - Intel Iris: 19:48:09 INFO - Chipset Model: Intel Iris 19:48:09 INFO - Type: GPU 19:48:09 INFO - Bus: Built-In 19:48:09 INFO - VRAM (Dynamic, Max): 1536 MB 19:48:09 INFO - Vendor: Intel (0x8086) 19:48:09 INFO - Device ID: 0x0a2e 19:48:09 INFO - Revision ID: 0x0009 19:48:09 INFO - Displays: 19:48:09 INFO - Display: 19:48:09 INFO - Resolution: 1600 x 1200 @ 60 Hz 19:48:09 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 19:48:09 INFO - Display Serial Number: 1600x1200 60 19:48:09 INFO - Main Display: Yes 19:48:09 INFO - Mirror: Off 19:48:09 INFO - Online: Yes 19:48:09 INFO - Rotation: Supported 19:48:09 INFO - Return code: 0 19:48:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 19:48:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 19:48:09 INFO - Python 2.7.3 19:48:09 INFO - Return code: 0 19:48:09 INFO - grabbing minidump binary from tooltool 19:48:09 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')]} 19:48:09 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 0x10f7d5d30>, '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 0x10f6fcdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f7cabe8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 19:48:09 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 19:48:09 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 19:48:09 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 19:48:09 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 19:48:10 INFO - Return code: 0 19:48:10 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 19:48:10 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 19:48:10 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 19:48:10 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 19:48:10 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 19:48:10 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Snmb8HRcQWOBKk1aw_4KVw/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0231', '--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 19:48:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/Snmb8HRcQWOBKk1aw_4KVw/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0231 --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 19:48:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.XAxEqqjmZN/Render', 19:48:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:48:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:48:10 INFO - 'HOME': '/Users/cltbld', 19:48:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:48:10 INFO - 'LOGNAME': 'cltbld', 19:48:10 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 19:48:10 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 19:48:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:48:10 INFO - 'MOZ_NO_REMOTE': '1', 19:48:10 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 19:48:10 INFO - 'NO_EM_RESTART': '1', 19:48:10 INFO - 'PAGER': '/bin/cat', 19:48:10 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:48:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:48:10 INFO - 'PWD': '/builds/slave/test', 19:48:10 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 19:48:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:48:10 INFO - 'SHELL': '/bin/bash', 19:48:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YW7HwvDW5a/Listeners', 19:48:10 INFO - 'TMPDIR': '/var/folders/9_/bq7njmms6gbgk1ygh6pll6r400000w/T/', 19:48:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:48:10 INFO - 'USER': 'cltbld', 19:48:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:48:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:48:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:48:10 INFO - 'XPC_FLAGS': '0x0', 19:48:10 INFO - 'XPC_SERVICE_NAME': '0', 19:48:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:48:10 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Snmb8HRcQWOBKk1aw_4KVw/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0231', '--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 19:48:10 INFO - mozversion application_buildid: 20161229182419 19:48:10 INFO - mozversion application_changeset: 6f63f95e28ffc05c0d2f5ef6cd6e05905fe8ea5a 19:48:10 INFO - mozversion application_display_name: Nightly 19:48:10 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 19:48:10 INFO - mozversion application_name: Firefox 19:48:10 INFO - mozversion application_remotingname: firefox 19:48:10 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 19:48:10 INFO - mozversion application_vendor: Mozilla 19:48:10 INFO - mozversion application_version: 53.0a1 19:48:10 INFO - mozversion platform_buildid: 20161229182419 19:48:10 INFO - mozversion platform_changeset: 6f63f95e28ffc05c0d2f5ef6cd6e05905fe8ea5a 19:48:10 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 19:48:10 INFO - mozversion platform_version: 53.0a1 19:48:10 INFO - using testdate: 1483069690 19:48:10 INFO - actual date: 1483069690 19:48:10 INFO - starting webserver on 'localhost:49244' 19:48:10 INFO - SUITE-START | Running 2 tests 19:48:10 INFO - TEST-START | dromaeo_css 19:48:10 INFO - operating with platform_type : mac_ 19:48:10 INFO - Initialising browser for dromaeo_css test... 19:48:10 INFO - TEST-INFO | started process 1643 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/9_/bq7njmms6gbgk1ygh6pll6r400000w/T/tmp3hGtPJ/profile http://localhost:49244/getInfo.html) 19:48:10 INFO - PROCESS | 1643 | 1483069690769 addons.xpi WARN Exception running bootstrap method install on shield-recipe-client@mozilla.org: [Exception... "Component returned failure code: 0x8000ffff (NS_ERROR_UNEXPECTED) [nsIPrefBranch.getBoolPref]" nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js :: this.install :: line 38" data: no] Stack trace: this.install()@resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js:38 < callBootstrapMethod()@resource://gre/modules/addons/XPIProvider.jsm:4986 < processFileChanges()@resource://gre/modules/addons/XPIProvider.jsm -> resource://gre/modules/addons/XPIProviderUtils.js:2097 < checkForChanges()@resource://gre/modules/addons/XPIProvider.jsm:3827 < startup()@resource://gre/modules/addons/XPIProvider.jsm:2831 < callProvider()@resource://gre/modules/AddonManager.jsm:264 < _startProvider()@resource://gre/modules/AddonManager.jsm:771 < startup()@resource://gre/modules/AddonManager.jsm:957 < startup()@resource://gre/modules/AddonManager.jsm:2923 < observe()@resource://gre/components/addonManager.js:65 19:48:12 INFO - PROCESS | 1643 | __metrics Screen width/height:1600/1200 19:48:12 INFO - PROCESS | 1643 | colorDepth:24 19:48:12 INFO - PROCESS | 1643 | Browser inner width/height: 1024/689 19:48:12 INFO - PROCESS | 1643 | __metrics 19:48:12 INFO - PROCESS | 1643 | 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" 19:48:12 INFO - PROCESS | 1643 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 19:48:13 INFO - TEST-INFO | 1643: exit 0 19:48:13 INFO - Browser initialized. 19:48:13 INFO - Running cycle 1/1 for dromaeo_css test... 19:48:13 INFO - TEST-INFO | started process 1645 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/9_/bq7njmms6gbgk1ygh6pll6r400000w/T/tmp3hGtPJ/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 19:48:25 INFO - PROCESS | 1645 | RSS: Main: 233889792 19:48:25 INFO - PROCESS | 1645 | 19:51:29 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49244/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49244/tests/dromaeo/cssquery-ext.html) 19:51:32 INFO - PROCESS | 1645 | RSS: Main: 442220544 19:51:32 INFO - PROCESS | 1645 | 19:53:14 INFO - PROCESS | 1645 | ************************* 19:53:14 INFO - PROCESS | 1645 | A coding exception was thrown and uncaught in a Task. 19:53:14 INFO - PROCESS | 1645 | 19:53:14 INFO - PROCESS | 1645 | Full message: ReferenceError: fetch is not defined 19:53:14 INFO - PROCESS | 1645 | Full stack: apiCall@resource://shield-recipe-client/lib/NormandyApi.jsm:37:5 19:53:14 INFO - PROCESS | 1645 | get@resource://shield-recipe-client/lib/NormandyApi.jsm:44:12 19:53:14 INFO - PROCESS | 1645 | this.NormandyApi.fetchRecipes<@resource://shield-recipe-client/lib/NormandyApi.jsm:52:34 19:53:14 INFO - PROCESS | 1645 | TaskImpl_run@resource://gre/modules/Task.jsm:319:42 19:53:14 INFO - PROCESS | 1645 | TaskImpl@resource://gre/modules/Task.jsm:277:3 19:53:14 INFO - PROCESS | 1645 | asyncFunction@resource://gre/modules/Task.jsm:252:14 19:53:14 INFO - PROCESS | 1645 | this.RecipeRunner.start<@resource://shield-recipe-client/lib/RecipeRunner.jsm:64:23 19:53:14 INFO - PROCESS | 1645 | TaskImpl_run@resource://gre/modules/Task.jsm:319:42 19:53:14 INFO - PROCESS | 1645 | TaskImpl@resource://gre/modules/Task.jsm:277:3 19:53:14 INFO - PROCESS | 1645 | asyncFunction@resource://gre/modules/Task.jsm:252:14 19:53:14 INFO - PROCESS | 1645 | setTimeout_timer@resource://gre/modules/Timer.jsm:30:5 19:53:14 INFO - PROCESS | 1645 | 19:53:14 INFO - PROCESS | 1645 | ************************* 19:54:35 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49244/tests/dromaeo/cssquery-ext.html (next: http://localhost:49244/tests/dromaeo/cssquery-jquery.html) 19:54:35 INFO - PROCESS | 1645 | RSS: Main: 367996928 19:54:35 INFO - PROCESS | 1645 | 19:57:38 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49244/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49244/tests/dromaeo/cssquery-mootools.html) 19:57:39 INFO - PROCESS | 1645 | RSS: Main: 354861056 19:57:39 INFO - PROCESS | 1645 | 20:00:41 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49244/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49244/tests/dromaeo/cssquery-prototype.html) 20:00:42 INFO - PROCESS | 1645 | RSS: Main: 345473024 20:00:42 INFO - PROCESS | 1645 | 20:03:45 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49244/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49244/tests/dromaeo/cssquery-yui.html) 20:03:46 INFO - PROCESS | 1645 | RSS: Main: 329551872 20:03:46 INFO - PROCESS | 1645 | 20:06:49 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49244/tests/dromaeo/cssquery-yui.html (next: http://localhost:49244/tests/dromaeo/cssquery-dojo.html) 20:06:49 INFO - PROCESS | 1645 | RSS: Main: 451186688 20:06:49 INFO - PROCESS | 1645 | 20:06:49 INFO - PROCESS | 1645 | __start_tp_report 20:06:49 INFO - PROCESS | 1645 | _x_x_mozilla_page_load 20:06:49 INFO - PROCESS | 1645 | _x_x_mozilla_page_load_details 20:06:49 INFO - PROCESS | 1645 | |i|pagename|runs| 20:06:49 INFO - PROCESS | 1645 | |0;dojo.html;3340.3987282289186;3354.765166438349;3357.7817441866277;3370.8146051967165;3427.828608569571;5602.60781745278;5678.26182596263;5763.56773242007;5775.971120144404;5790.160426738121;5719.542436605062;5761.769529218836;5824.446677565635;5832.270966129234;5841.445062719045;5190.610704197182;5191.325127733389;5196.92204616931;5210.843674689765;5269.683818970874;5311.973440132794;5312.521873031426;5320.4679532046675;5338.546223571001;5365.248865158881;4249.192653395876;5211.895762084775;5282.260483532308;5296.390915044781;5317.707526086064;5178.533931946105;5230.738463076831;5246.527812496894;5259.947400525984;5327.893442131136;5203.349581302337;5233.084210263227;5246.37043554772;5247.553957913582;5264.2893209416625;5728.8854222915725;5755.971220143914;5762.0985074925175;5766.7981620643495;5799.304083509964;6137.806028951355;6142.723577439026;6153.938460615382;6167.167432396614;6228.346023667495;6280.560360774748;6337.588056776295;6354;6359.936400635981;6376.585521941059;4943.72809495474;5813.186153938452;5822.126680997884;5830.679312637761;5835.299764028301;6196.1635179251125;6222.533310001768;6248.125262463259;6265.091561723524;6293.244810622755;6108.8964221451015;6141.815745527641;6176.752929882754;6220.222472190976;6223.533235007392;6122.438812974205;6304;6314.273858506239;6323.810285691437;6327.335629758901;5115.232715092691;6080.179175811298;6180.598261113013;6182.474489668429;6197.876042479125;6177.752889884444;6184.5052395808225;6201.875962480725;6204.038374052029;6207.5654704171175;4918.336024636628;4929.556339929423;4942.431620363633;4946.752662366867;4946.851594452172;4307.396964424983;4312.331588603772;4488.842890498817;4492.168948744493;4498.100379924028;5679.971600142056;5764.596478246483;5886.470217680429;5889.734961926723;5890.49930755882;6224.782132625336;6249.281332646804;6251.812445626639;6267.655278959701;6356.586821856565;5802.854928626818;5850.151727999416;5853.853653658607;5859.2090067841145;5896.587238893237;5434.864128396822;5435.483629055233;5437.293151890308;5442.292501974797;5446.128619420873;6251.99968005117;6252.249730032425;6262.874742505215;6296.496280297657;6306.432421082125;5340.798320377884;6322.557420980487;6323.08315294289;6327.683615819283;6332.240131184288;5364.758585863724;6255.40573645503;6306.243250809932;6312.084747711648;6328.082428047999;6107.097787096225;6126.724297406538;6155.876882462415;6194.132821405097;6262;5094.5160209780015;6304.873902522016;6318.905216421849;6319.210098737658;6323.272823625342;6137.815865524041;6141.723622436912;6144.293406258337;6154.753809847556;6163.722632481459;5953.553483488669;6029.60807547508;6112.388761123852;6143.078538219303;6152.292486364126;5905.379935106752;5948.643081415129;5959.046552551571;5965.313988891159;5966.134910437875;6051.062085376773;6082.482988945989;6085.817425477243;6087.391260873877;6094.603850749687;5810.622309549865;5812.099124635688;5823.679697616672;5846.56150788684;5915.171875937458 20:06:49 INFO - PROCESS | 1645 | |1;ext.html;444.7220487195503;444.7420496112251;480.72598618787305;506.8226120857699;516.8242797448867;22769.77230227697;28937.855310723444;29025.984090556834;35621.82189089051;37200.88397348077;36304.455433168456;36757.81621091891;36807.07982300437;36835.447468287995;36898.26203475929;8965.775855603597;9161.03809100045;9164.816703665923;9165.954170229139;9206.631734730636;8395.748127556182;8516.446430981998;9042.412243204204;9153.29977656593;10709.464526773669;10939.617113401033;11086.279391839505;11121.22151449399;11122.221444498848;11182.27315224512;11085.722856928602;11138.220324577242;11159.832602510969;11166.497507612095;11169.0506306964;8177.100518942912;8393.958030209871;10679.145668346591;10989;11035.944820275927;36206.732764353386;36571.26857462862;36582.26835463302;36588.451173232424;36589.26821463583;3817.8663746769007;3859.8070096495344;3865.1303456722294;3891.8443262269475;3986.142979259473;27851;28674.856625716737;28897.56653650197;29049.419011619648;29361.265968350963;3086.753059755237;3213.0682102190335;3237.3848968695875;3272.7872688275424;3298.043567365461;464043.9999999966;467158;469501;469838;471064;51775.22337164945;52103;52119.957600847774;52146;52173;52771;53913.1913021305;54000.729996349764;54048.18927716088;54242.18636720452;13933.512327068504;14412.783808242884;14413.78379324311;14439.85560144385;14441.566752997427;11123.27698699582;11205.99146076857;11207.383593902414;11208.607698730668;11212.439378031066;12103.152779305365;12512.249265043944;13672.658183545293;13998;14114.71770564583;5831.970840145772;5873.006415221776;5899.941000589939;5914.338547653573;5923.17075609415;814.2305521282332;827.9213474719892;839.6515446089804;845.8139209373928;851.2764150472049;27815.02647407331;27855.30361740932;28124.71875281262;28651.426971460452;28703;915.2586405011829;970.5001924009141;971.3977334052933;1008.1229330482381;1110.0952723530297;13058.443208025566;14006.159630422004;14198.929005354907;14382.918234367304;14614.196219208046;605601;614865;616038;616242;616403;51552.48447515477;51717;51761.48238517567;51783;51794.96410071778;1291.6318849127954;1302.446460254388;1323.1531819635634;1323.4441534555701;1331.8734720201564;23499.76500234976;27069;27631.30921726973;28178.591070446804;28260.15219543417;43575;44063.55936440723;44078.55921440873;44104.77947610241;44206.336904945805;517205;521034;522311.1653325279;523618;524850;35062.29875402529;35138.12154696153;35544.82227588846;35704.107397315274;35950;33916.660833391354;38130.04674883039;40334.79832600818;42669.933251668954;44369;29128.27179320449;34184.82907585446;34923.47614785831;35708;36296.911092667266;32304;34811.65188348084;34964;35514.64485355217;35995.46006809847;34627.30745385129;35111.64888351084;35211;35312.76405325801;35364.823175883954;31075.223119422197;31674.524882126327;31748.68251317457;31774.841125794224;31893.362132756753;19502.414927552196;23165.652515211947;23919;24297.51404971926;24429.51140977135 20:06:49 INFO - PROCESS | 1645 | |2;jquery.html;347.7878494118586;367.17385881766046;369.5583777386024;374.3841380928373;396.4627929155994;14282.714345713079;14651.413943442263;15469.613259668515;19409.4177174685;19985.800851948894;17679.82320176801;17805.821941780578;18613.813861861378;18792.43622691318;18844.152013159408;4912.901741965167;5165.74171291434;5194.013137503862;5198.350206224222;5210.957808438308;5140.640155189139;5176.430592634807;5350.732463376835;5625.6905870177125;5713.14302854571;5749.971250143743;5755.510781583571;5757.596968212227;5760.596758226926;5762.596618236725;5597.720113994284;5651.208830763697;5713.428657134295;5718.342390625048;5754.338251101114;5447.128459446509;5514.641548299348;5522.22688823565;5528.695921724304;5530.723463826793;12541.498340066386;12665.240085594802;12686.682832929158;12689.809652855214;13392;2612.8171028028046;2621.423286876884;2632.4735052989286;2633.776129029035;2645.854478003709;9345.579448924815;9378.343515953886;9504.904950950471;9924.255680823895;10096.040876116756;1361.2853252042758;1756.1745979389666;1769.8141695122085;1798.307651554161;1816.061478963781;97300;108496;108581;108883;109001.45499272452;10995.890041099487;11094.334339939469;11138.777224455465;11274.605388811353;11365.431728413547;13877.097988630707;13878.375473103735;13889.652758680912;13902.16587004783;13924.86075139256;7698.769036928901;7829;7853.607319633996;7951.483153594998;7995.760127196193;7527.021487206628;7557.2820582044615;7581.924180758232;7699.461037727306;7721.652525636247;4273.87178384649;4287.485501739811;4289.185054839633;4305.385480444833;4377.205345808267;1621.799868097623;1669.599296168935;1803.6933721267417;1840.2179073893542;1864.3847530314963;933.9579718912529;949.8601677986444;969.4764826993345;979.7207795778169;981.1758123176566;19135.521611959535;19965.500862478555;20120.89939550322;20537.38387848367;21256.36230913075;976.4727047394327;984.9162821160138;1003.6136087606178;1014.7970405918845;1019.8266294729765;9940.502974851228;10364.740881477872;10381.532831022621;10393.740156496147;10438.321509101885;344346;382151;488593.55703221256;577760;578474;127550;130827;133036;133395;137272;977.2231076294221;982.8918818929913;990.9405435673739;1005.3666190300211;1006.7080546641538;14698.485553005594;17618.91190544039;17655.735163972295;17769.20038598292;17828.46514604564;50404.08400184595;53960;54302.64243393789;54880.9999999984;55034.348969530976;77594;78205;78665;78674.6066269665;79957.60021199856;6784.215784215784;7653.6938522460705;7820.335271501986;7845.097813751263;8118.918810811816;11468.936425130287;11844.407779611158;12221.266723996589;12240.08199385032;12267.141300108906;8886.866696999421;9073.818523629358;9086.909130908607;9216.164742292627;9253.58358873839;9505.809883802423;10187.388756674623;10307.123894469054;10620.256582039183;10897.346159230472;9941.453220072965;10262.640807571699;10567.313124646873;10954.56181752721;11038.889611103787;7689.538627682357;7727.111382190895;7777.766666999999;7899;7939.007624046994;10136.695899123039;10215.540300686338;10282.691519254435;10714.035736783728;10732.302400343953 20:06:49 INFO - PROCESS | 1645 | |3;mootools.html;1307.444336157133;1315.8092076648886;1332.2605953695702;1333.7065845513996;1334.739725753478;8000.279974802266;8477.194666506688;8479.236868681817;8492.193241642035;8495.745127646172;6615.966920165404;7411.888821667666;7606.695732170707;7612.92387076129;8260.91739082609;2240.383894429033;2316.5598536278053;2319.82601304902;2323.175272778165;2323.53529294141;2295.1622657729977;2316.455632926261;2339.6490526421003;2339.847909885852;2340.216027630746;1696.635223426963;2079.139170442453;2294.265834932822;2333.311673056444;2336.19401306549;2225.4770129019666;2316.837821352506;2317.73346065203;2327.650852372141;2334.3580515358244;2310.4685922237977;2332.2187067332466;2335.1710142899196;2336.719593648756;2345.519168570439;8207.425480216387;8400.369972252045;8420.368472364597;8430.957845210796;8431.578421078983;5004.699718016931;5168.302279192299;5172.456892026321;5179.093658609729;5190.377154741417;3304.454764963778;3488.372093023255;3505.8597656093725;3553.4847447120023;3554.751167418282;1181.3650457239255;1205.3310412720937;1236.9196002259823;1259.4017841525294;1262.6338361875046;10962.342259464458;10982.780344393148;11011.119110471223;11011.559537618487;11026.0076593107;4466.240739074366;4500.302453119772;4609.423822022247;4664.486906440289;4749.073930583572;8704.390692651581;8752.47485150893;8774.429662072072;8782.692605758768;8785.51679657625;5057.8735531612;5074.16276314404;5078.568321692624;5081.18701007837;5096.974515127401;4609.585137337655;4704.411948506437;4760.309755085494;4769.59458446029;4783.043391321749;3451.3442445935193;3509.9122521937156;3531.422061894831;3582.3730847102174;3588.5334906461994;1103.2773533335678;1107.1917500224881;1108.5233349659563;1118.2004866520476;1119.5465836336298;693.1231991530718;701.1200942816835;720.7477382916043;726.5241266970143;748.831512909591;5048.697078175322;5358.9550037742265;5401.865608222317;5499.1476321170285;5705.144228365778;498.9725565093955;525.687216106416;528.2736237673199;888.9555522223862;919.0131972271479;3608.548931383577;3711.758735682172;3738.420544815558;3782.3759079751535;3788.0908581940134;9410.858837117448;9649.469279189572;9778.266630002636;10074.345167564084;10192.592296308065;10054.849177262347;10073.992600739868;10076.193904487624;10078.193744500422;10078.647247346307;7252.456065795086;7476.214997425301;7583.886241706379;7634.274743899321;7745.961270193726;5434.347878254634;5649.37856835756;5657.462541058593;5663.688497132697;5767.961766882002;9004.819903602021;9207.723768286962;9216.769580760534;9248.537573121183;9395.671151509665;7290.9949763815375;9314;9437;9469.47917864524;9483.336166468282;6499.1551098358805;6697.756251655555;7034.788956331318;7216.819579510554;7240.312170344019;6968.756093536702;7735.6905723770415;7821.843563128592;7975.322097621767;8021.679132834622;6952.965235173791;7016.6491675415;7064.32888875556;7102.254263302279;7192.928070719435;5965.552583556338;7001.684924178322;7020.929790702027;7062.823429414306;7073.363397294268;5534.225208470898;6064.272287325549;6314.8737025258315;6355.237371515448;6420.903686444613;4179.26862799015;4576.656750743774;4733.9053218936115;4739.478657347758;4755.239161734106;4223.965128543527;4709.599684026896;4849.3210950466255;4858.3441235434175;4860.270959356125 20:06:49 INFO - PROCESS | 1645 | |4;prototype.html;345.57321707691005;354.5036948272418;360.0746082567801;372.9608391118932;374.43460374834024;4827.5172482751705;5244.449332820056;5277.313949186605;5282.339707536558;5315.76737770137;4231.851885184019;4336.522982471925;4903.803847846082;5066.746662666861;5200.427952925175;3724.590295067554;3791.5829258781514;3944.5463771666336;4085.730411719745;4087.4481944937497;4071.9389209161886;4087.652549533293;4108.506979162505;4118.528882670389;4122.752634841904;3740.9812950935207;3918.8824335269987;4065.166640838636;4083.5099788025477;4086.1827634473075;3303.620083690382;3743.7566558173903;3928.823202955874;3965.5439624443125;3981.5023122109737;3404.438267685829;3419.230673098533;3420.931581368359;3444.6555344465605;3447.3105378924065;4500.594946454835;4972.270796902323;5074.771635276422;5081.618878584084;5089.567386772129;3601.7658852174527;4206.91586168278;4282.336237883133;4464.866054018385;4481.3726078349055;4949.925751113737;5175.792968281265;5434.483724046209;5449.6730196188355;5473.303275984457;3316.8839090631714;3599.4420864766003;3719.3863012602887;3743.794091324976;3752.7935963521995;5352.545033672142;5630.789380283259;5656.575756818214;5702.144678298247;5754.395788442195;3592.5688917329567;3600.477930700034;4026.5570787213383;4078.6940979426067;4103.671706263492;3881.4760007399204;4010.8569057818963;4164.37534369847;4167.895802604899;4234.534201237861;4022.2759903217116;4204.348326009474;4536.931946020813;4700.435947686231;4720.716756994522;4682.555157260055;4886.560209581155;5332.493413125747;5496.148097044965;5546.500814926595;1234.6850604106598;1235.950561977511;1352.4852165574619;1357.8234829472106;1359.9864001360058;891.1222445890793;920.6650356982402;929.4330458420358;940.6707652321633;949.1410273702172;4313.84901528445;4673.95326046742;4744.505480773545;4921.342475063498;4942.678725882878;5339.580325969145;5356.758945847446;5374.220737992969;5379.515843574097;5389.784408623611;4539.459221806996;4634.629229661619;4752.287156926489;4796.25658023007;4827.879303017453;2664.5603475426724;2727.4545090981883;2728.3042824079735;2809.7471227589617;2823.43531293738;4798.521072222611;5159.071367153948;5455.645383050089;5497.8075767347955;5594.8810237952575;5056.317397151411;5097.745112744348;5233.607479439057;5503.3395992481155;5518.558515318765;4896.461389247252;5199.024370739095;5217.399999000164;5304.867378315495;5520.834374968757;4077.044142996304;4520.59314661682;5188.429272780067;5338.733063346895;5381.246625472359;4184.958150418457;4759.14335419614;5114.46298138705;5215.6609820361555;5247.195783057253;5239.633225674166;5276.683398996072;5324.9733751331;5445.863853403696;5506.339239291311;3384.5600071990484;3759.8684046058256;3874.4188371744463;3891.2023035277134;3894.169740222091;5191.740412979411;5618.6628802272;5640.182173584889;5660.745266463102;5664.4509970514255;4893.437254715754;5472.781088756406;5486.107292983914;5506.64206826555;5525.557955363481;4798.47216806144;4842.564169224786;4910.729909854992;4988.850334489971;5495.313085864267;4757.5718185363485;5097.286379906742;5127.897442051213;5390.4879036491475;5415.59383046281;4744.644151688706;4869.95130048695;4950.777215025405;5377.973110134424;5475.534579560782;5169.7673604687125;5380.919286210788;5391.541718953933;5400.324959380077;5402.918956215739 20:06:49 INFO - PROCESS | 1645 | |5;yui.html;623.9001759718444;662.4799532367093;692.9099217101776;737.5279820914612;745.3440971944688;14049.648758781035;14963.476278330261;15008.774868376982;15021.849781502182;15033.473828416008;13071.934640326808;13178.406971686272;13329.666758331046;13477.730445391086;13526.32368381582;781.3592853859838;789.763071078677;797.9985118676053;801.1307731111722;807.725373373056;783.0603276068712;788.1527358090048;789.8183417813906;790.0953408347439;790.9564973926432;812.3947658994028;814.8818421328904;815.4006804998321;816.3428440105713;818.9262966333029;807.6446363599997;808.2523665609317;809.5062012172571;811.6631597886899;813.6297984417106;607.5109536822856;608.367298010069;610.3042531514078;610.4475449718012;611.9020956646959;12420.627381178489;12433.813492797615;12692.492300307977;12698.492060317576;12907.031972601999;11345;11509.48207330672;11509.76980460386;11696.88303116975;11893.940530297294;10139.290249682525;10221.591136354537;10290.176785857113;10307.330023548446;10451.738706532322;3510;3523.859045638171;3534.717222622184;3546;3547.0777597824494;98947.50526247395;99156;99166;99222;100774;8578.742637720878;8677.563150074295;8898.555072246361;8919.643214271486;9074.682386116454;18612.720809187875;18636.347727829732;18646.44060678182;18652.720209196872;18722.157502912403;7294;7503.62481875904;7572.39420846331;7652.502587331805;7714.537127772352;4018.6985976051915;4152.958470415318;4216.536181020086;4337.436133302651;4344.847930322424;3345.063382252973;3416.555847739778;3421.8460169291943;3461.6884480396884;3465.86136554535;617.7127635649445;623.2863371439767;624.54720327762;630.6909614289057;638.7955854126727;576.907694768835;629.4303655192059;631.0849268560605;639.9296077431479;649.2890285137767;7205.531640443354;8157.493909911312;8442.240198382175;8478.703245386505;8865.404227239158;565.1656940443678;594.8334466349429;623.2738859228947;646.9061986012002;654.4698793976894;4288.914221715549;4441.089576636782;4456.665750068758;4470.307102399133;4553.453585569687;73593.63203184056;74941.5011699763;80910.5954470224;83503.58248208721;98912;73821.15447113865;76917;78007.82988255181;78009.82985255226;81451;73631;74675.1331216713;75055.1236219099;76059.61970190113;76838;7328.267173282629;9523.28832548921;9655.53053490391;9713.320067595201;9767.9023209767;47043.76478117587;48268;48578.757106214245;49252.26121608107;50235;46600.7669961648;58402.70798646149;62271.6886415565;64274.67862660657;66580.66709666421;12443.813342799684;12517.812232816697;12576.434060467118;12799.936000319938;13083;14197.929010354883;14266.928665356607;14554;14616.196109214095;15121.243937803285;13321.400536975665;13472.663183420493;13505.392257348247;13682.179069255877;13690.041697081508;13034.08761386694;13192.274424906722;13228.603141905758;13256.668583285109;13356.131851429625;12875.871241287467;13019.479220831061;13022.348882555645;13089.018323625576;13518.188908665512;10047.648332308334;10321.690349289533;10360.896391035993;10389.272750907365;10803.243772935819;10236.283460157718;10244.948775256076;10249.077583017564;10265.794684106426;10395.376277423378 20:06:49 INFO - PROCESS | 1645 | __end_tp_report 20:06:49 INFO - PROCESS | 1645 | __start_cc_report 20:06:49 INFO - PROCESS | 1645 | _x_x_mozilla_cycle_collect,2640 20:06:49 INFO - PROCESS | 1645 | __end_cc_report 20:06:49 INFO - PROCESS | 1645 | __startTimestamp1483070809637__endTimestamp 20:06:49 INFO - PROCESS | 1645 | 20:06:49 INFO - PROCESS | 1645 | ------- Summary: start ------- 20:06:49 INFO - PROCESS | 1645 | Number of tests: 6 20:06:49 INFO - PROCESS | 1645 | 20:06:49 INFO - PROCESS | 1645 | [#0] dojo.html Cycles:165 Average:5737.83 Median:5896.59 stddev:646.38 (11.0%) stddev-sans-first:620.40 20:06:49 INFO - PROCESS | 1645 | Values: 3340.4 3354.8 3357.8 3370.8 3427.8 5602.6 5678.3 5763.6 5776.0 5790.2 5719.5 5761.8 5824.4 5832.3 5841.4 5190.6 5191.3 5196.9 5210.8 5269.7 5312.0 5312.5 5320.5 5338.5 5365.2 4249.2 5211.9 5282.3 5296.4 5317.7 5178.5 5230.7 5246.5 5259.9 5327.9 5203.3 5233.1 5246.4 5247.6 5264.3 5728.9 5756.0 5762.1 5766.8 5799.3 6137.8 6142.7 6153.9 6167.2 6228.3 6280.6 6337.6 6354.0 6359.9 6376.6 4943.7 5813.2 5822.1 5830.7 5835.3 6196.2 6222.5 6248.1 6265.1 6293.2 6108.9 6141.8 6176.8 6220.2 6223.5 6122.4 6304.0 6314.3 6323.8 6327.3 5115.2 6080.2 6180.6 6182.5 6197.9 6177.8 6184.5 6201.9 6204.0 6207.6 4918.3 4929.6 4942.4 4946.8 4946.9 4307.4 4312.3 4488.8 4492.2 4498.1 5680.0 5764.6 5886.5 5889.7 5890.5 6224.8 6249.3 6251.8 6267.7 6356.6 5802.9 5850.2 5853.9 5859.2 5896.6 5434.9 5435.5 5437.3 5442.3 5446.1 6252.0 6252.2 6262.9 6296.5 6306.4 5340.8 6322.6 6323.1 6327.7 6332.2 5364.8 6255.4 6306.2 6312.1 6328.1 6107.1 6126.7 6155.9 6194.1 6262.0 5094.5 6304.9 6318.9 6319.2 6323.3 6137.8 6141.7 6144.3 6154.8 6163.7 5953.6 6029.6 6112.4 6143.1 6152.3 5905.4 5948.6 5959.0 5965.3 5966.1 6051.1 6082.5 6085.8 6087.4 6094.6 5810.6 5812.1 5823.7 5846.6 5915.2 20:06:49 INFO - PROCESS | 1645 | 20:06:49 INFO - PROCESS | 1645 | [#1] ext.html Cycles:180 Average:65200.34 Median:35662.96 stddev:143858.63 (403.4%) stddev-sans-first:144180.03 20:06:49 INFO - PROCESS | 1645 | Values: 444.7 444.7 480.7 506.8 516.8 22769.8 28937.9 29026.0 35621.8 37200.9 36304.5 36757.8 36807.1 36835.4 36898.3 8965.8 9161.0 9164.8 9166.0 9206.6 8395.7 8516.4 9042.4 9153.3 10709.5 10939.6 11086.3 11121.2 11122.2 11182.3 11085.7 11138.2 11159.8 11166.5 11169.1 8177.1 8394.0 10679.1 10989.0 11035.9 36206.7 36571.3 36582.3 36588.5 36589.3 3817.9 3859.8 3865.1 3891.8 3986.1 27851.0 28674.9 28897.6 29049.4 29361.3 3086.8 3213.1 3237.4 3272.8 3298.0 464044.0 467158.0 469501.0 469838.0 471064.0 51775.2 52103.0 52120.0 52146.0 52173.0 52771.0 53913.2 54000.7 54048.2 54242.2 13933.5 14412.8 14413.8 14439.9 14441.6 11123.3 11206.0 11207.4 11208.6 11212.4 12103.2 12512.2 13672.7 13998.0 14114.7 5832.0 5873.0 5899.9 5914.3 5923.2 814.2 827.9 839.7 845.8 851.3 27815.0 27855.3 28124.7 28651.4 28703.0 915.3 970.5 971.4 1008.1 1110.1 13058.4 14006.2 14198.9 14382.9 14614.2 605601.0 614865.0 616038.0 616242.0 616403.0 51552.5 51717.0 51761.5 51783.0 51795.0 1291.6 1302.4 1323.2 1323.4 1331.9 23499.8 27069.0 27631.3 28178.6 28260.2 43575.0 44063.6 44078.6 44104.8 44206.3 517205.0 521034.0 522311.2 523618.0 524850.0 35062.3 35138.1 35544.8 35704.1 35950.0 33916.7 38130.0 40334.8 42669.9 44369.0 29128.3 34184.8 34923.5 35708.0 36296.9 32304.0 34811.7 34964.0 35514.6 35995.5 34627.3 35111.6 35211.0 35312.8 35364.8 31075.2 31674.5 31748.7 31774.8 31893.4 19502.4 23165.7 23919.0 24297.5 24429.5 20:06:49 INFO - PROCESS | 1645 | 20:06:49 INFO - PROCESS | 1645 | [#2] jquery.html Cycles:180 Average:30562.28 Median:371.97 stddev:82164.09 (22088.8%) stddev-sans-first:82363.26 20:06:49 INFO - PROCESS | 1645 | Values: 347.8 367.2 369.6 374.4 396.5 14282.7 14651.4 15469.6 19409.4 19985.8 17679.8 17805.8 18613.8 18792.4 18844.2 4912.9 5165.7 5194.0 5198.4 5211.0 5140.6 5176.4 5350.7 5625.7 5713.1 5750.0 5755.5 5757.6 5760.6 5762.6 5597.7 5651.2 5713.4 5718.3 5754.3 5447.1 5514.6 5522.2 5528.7 5530.7 12541.5 12665.2 12686.7 12689.8 13392.0 2612.8 2621.4 2632.5 2633.8 2645.9 9345.6 9378.3 9504.9 9924.3 10096.0 1361.3 1756.2 1769.8 1798.3 1816.1 97300.0 108496.0 108581.0 108883.0 109001.5 10995.9 11094.3 11138.8 11274.6 11365.4 13877.1 13878.4 13889.7 13902.2 13924.9 7698.8 7829.0 7853.6 7951.5 7995.8 7527.0 7557.3 7581.9 7699.5 7721.7 4273.9 4287.5 4289.2 4305.4 4377.2 1621.8 1669.6 1803.7 1840.2 1864.4 934.0 949.9 969.5 979.7 981.2 19135.5 19965.5 20120.9 20537.4 21256.4 976.5 984.9 1003.6 1014.8 1019.8 9940.5 10364.7 10381.5 10393.7 10438.3 344346.0 382151.0 488593.6 577760.0 578474.0 127550.0 130827.0 133036.0 133395.0 137272.0 977.2 982.9 990.9 1005.4 1006.7 14698.5 17618.9 17655.7 17769.2 17828.5 50404.1 53960.0 54302.6 54881.0 55034.3 77594.0 78205.0 78665.0 78674.6 79957.6 6784.2 7653.7 7820.3 7845.1 8118.9 11468.9 11844.4 12221.3 12240.1 12267.1 8886.9 9073.8 9086.9 9216.2 9253.6 9505.8 10187.4 10307.1 10620.3 10897.3 9941.5 10262.6 10567.3 10954.6 11038.9 7689.5 7727.1 7777.8 7899.0 7939.0 10136.7 10215.5 10282.7 10714.0 10732.3 20:06:49 INFO - PROCESS | 1645 | 20:06:49 INFO - PROCESS | 1645 | [#3] mootools.html Cycles:180 Average:5317.60 Median:5066.02 stddev:2946.46 (58.2%) stddev-sans-first:2939.31 20:06:49 INFO - PROCESS | 1645 | Values: 1307.4 1315.8 1332.3 1333.7 1334.7 8000.3 8477.2 8479.2 8492.2 8495.7 6616.0 7411.9 7606.7 7612.9 8260.9 2240.4 2316.6 2319.8 2323.2 2323.5 2295.2 2316.5 2339.6 2339.8 2340.2 1696.6 2079.1 2294.3 2333.3 2336.2 2225.5 2316.8 2317.7 2327.7 2334.4 2310.5 2332.2 2335.2 2336.7 2345.5 8207.4 8400.4 8420.4 8431.0 8431.6 5004.7 5168.3 5172.5 5179.1 5190.4 3304.5 3488.4 3505.9 3553.5 3554.8 1181.4 1205.3 1236.9 1259.4 1262.6 10962.3 10982.8 11011.1 11011.6 11026.0 4466.2 4500.3 4609.4 4664.5 4749.1 8704.4 8752.5 8774.4 8782.7 8785.5 5057.9 5074.2 5078.6 5081.2 5097.0 4609.6 4704.4 4760.3 4769.6 4783.0 3451.3 3509.9 3531.4 3582.4 3588.5 1103.3 1107.2 1108.5 1118.2 1119.5 693.1 701.1 720.7 726.5 748.8 5048.7 5359.0 5401.9 5499.1 5705.1 499.0 525.7 528.3 889.0 919.0 3608.5 3711.8 3738.4 3782.4 3788.1 9410.9 9649.5 9778.3 10074.3 10192.6 10054.8 10074.0 10076.2 10078.2 10078.6 7252.5 7476.2 7583.9 7634.3 7746.0 5434.3 5649.4 5657.5 5663.7 5768.0 9004.8 9207.7 9216.8 9248.5 9395.7 7291.0 9314.0 9437.0 9469.5 9483.3 6499.2 6697.8 7034.8 7216.8 7240.3 6968.8 7735.7 7821.8 7975.3 8021.7 6953.0 7016.6 7064.3 7102.3 7192.9 5965.6 7001.7 7020.9 7062.8 7073.4 5534.2 6064.3 6314.9 6355.2 6420.9 4179.3 4576.7 4733.9 4739.5 4755.2 4224.0 4709.6 4849.3 4858.3 4860.3 20:06:49 INFO - PROCESS | 1645 | 20:06:49 INFO - PROCESS | 1645 | [#4] prototype.html Cycles:180 Average:4357.17 Median:4835.22 stddev:1283.93 (26.6%) stddev-sans-first:1251.73 20:06:49 INFO - PROCESS | 1645 | Values: 345.6 354.5 360.1 373.0 374.4 4827.5 5244.4 5277.3 5282.3 5315.8 4231.9 4336.5 4903.8 5066.7 5200.4 3724.6 3791.6 3944.5 4085.7 4087.4 4071.9 4087.7 4108.5 4118.5 4122.8 3741.0 3918.9 4065.2 4083.5 4086.2 3303.6 3743.8 3928.8 3965.5 3981.5 3404.4 3419.2 3420.9 3444.7 3447.3 4500.6 4972.3 5074.8 5081.6 5089.6 3601.8 4206.9 4282.3 4464.9 4481.4 4949.9 5175.8 5434.5 5449.7 5473.3 3316.9 3599.4 3719.4 3743.8 3752.8 5352.5 5630.8 5656.6 5702.1 5754.4 3592.6 3600.5 4026.6 4078.7 4103.7 3881.5 4010.9 4164.4 4167.9 4234.5 4022.3 4204.3 4536.9 4700.4 4720.7 4682.6 4886.6 5332.5 5496.1 5546.5 1234.7 1236.0 1352.5 1357.8 1360.0 891.1 920.7 929.4 940.7 949.1 4313.8 4674.0 4744.5 4921.3 4942.7 5339.6 5356.8 5374.2 5379.5 5389.8 4539.5 4634.6 4752.3 4796.3 4827.9 2664.6 2727.5 2728.3 2809.7 2823.4 4798.5 5159.1 5455.6 5497.8 5594.9 5056.3 5097.7 5233.6 5503.3 5518.6 4896.5 5199.0 5217.4 5304.9 5520.8 4077.0 4520.6 5188.4 5338.7 5381.2 4185.0 4759.1 5114.5 5215.7 5247.2 5239.6 5276.7 5325.0 5445.9 5506.3 3384.6 3759.9 3874.4 3891.2 3894.2 5191.7 5618.7 5640.2 5660.7 5664.5 4893.4 5472.8 5486.1 5506.6 5525.6 4798.5 4842.6 4910.7 4988.9 5495.3 4757.6 5097.3 5127.9 5390.5 5415.6 4744.6 4870.0 4950.8 5378.0 5475.5 5169.8 5380.9 5391.5 5400.3 5402.9 20:06:49 INFO - PROCESS | 1645 | 20:06:49 INFO - PROCESS | 1645 | [#5] yui.html Cycles:180 Average:18551.61 Median:49743.63 stddev:26244.19 (52.8%) stddev-sans-first:26283.29 20:06:49 INFO - PROCESS | 1645 | Values: 623.9 662.5 692.9 737.5 745.3 14049.6 14963.5 15008.8 15021.8 15033.5 13071.9 13178.4 13329.7 13477.7 13526.3 781.4 789.8 798.0 801.1 807.7 783.1 788.2 789.8 790.1 791.0 812.4 814.9 815.4 816.3 818.9 807.6 808.3 809.5 811.7 813.6 607.5 608.4 610.3 610.4 611.9 12420.6 12433.8 12692.5 12698.5 12907.0 11345.0 11509.5 11509.8 11696.9 11893.9 10139.3 10221.6 10290.2 10307.3 10451.7 3510.0 3523.9 3534.7 3546.0 3547.1 98947.5 99156.0 99166.0 99222.0 100774.0 8578.7 8677.6 8898.6 8919.6 9074.7 18612.7 18636.3 18646.4 18652.7 18722.2 7294.0 7503.6 7572.4 7652.5 7714.5 4018.7 4153.0 4216.5 4337.4 4344.8 3345.1 3416.6 3421.8 3461.7 3465.9 617.7 623.3 624.5 630.7 638.8 576.9 629.4 631.1 639.9 649.3 7205.5 8157.5 8442.2 8478.7 8865.4 565.2 594.8 623.3 646.9 654.5 4288.9 4441.1 4456.7 4470.3 4553.5 73593.6 74941.5 80910.6 83503.6 98912.0 73821.2 76917.0 78007.8 78009.8 81451.0 73631.0 74675.1 75055.1 76059.6 76838.0 7328.3 9523.3 9655.5 9713.3 9767.9 47043.8 48268.0 48578.8 49252.3 50235.0 46600.8 58402.7 62271.7 64274.7 66580.7 12443.8 12517.8 12576.4 12799.9 13083.0 14197.9 14266.9 14554.0 14616.2 15121.2 13321.4 13472.7 13505.4 13682.2 13690.0 13034.1 13192.3 13228.6 13256.7 13356.1 12875.9 13019.5 13022.3 13089.0 13518.2 10047.6 10321.7 10360.9 10389.3 10803.2 10236.3 10244.9 10249.1 10265.8 10395.4 20:06:49 INFO - PROCESS | 1645 | -------- Summary: end -------- 20:06:49 INFO - PROCESS | 1645 | 20:06:50 INFO - TEST-INFO | 1645: exit 0 20:06:50 INFO - TEST-OK | dromaeo_css | took 1120073ms 20:06:50 INFO - TEST-START | kraken 20:06:50 INFO - operating with platform_type : mac_ 20:06:50 INFO - Initialising browser for kraken test... 20:06:50 INFO - TEST-INFO | started process 1652 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/9_/bq7njmms6gbgk1ygh6pll6r400000w/T/tmpaXQEgv/profile http://localhost:49244/getInfo.html) 20:06:50 INFO - PROCESS | 1652 | 1483070810807 addons.xpi WARN Exception running bootstrap method install on shield-recipe-client@mozilla.org: [Exception... "Component returned failure code: 0x8000ffff (NS_ERROR_UNEXPECTED) [nsIPrefBranch.getBoolPref]" nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js :: this.install :: line 38" data: no] Stack trace: this.install()@resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js:38 < callBootstrapMethod()@resource://gre/modules/addons/XPIProvider.jsm:4986 < processFileChanges()@resource://gre/modules/addons/XPIProvider.jsm -> resource://gre/modules/addons/XPIProviderUtils.js:2097 < checkForChanges()@resource://gre/modules/addons/XPIProvider.jsm:3827 < startup()@resource://gre/modules/addons/XPIProvider.jsm:2831 < callProvider()@resource://gre/modules/AddonManager.jsm:264 < _startProvider()@resource://gre/modules/AddonManager.jsm:771 < startup()@resource://gre/modules/AddonManager.jsm:957 < startup()@resource://gre/modules/AddonManager.jsm:2923 < observe()@resource://gre/components/addonManager.js:65 20:06:52 INFO - PROCESS | 1652 | __metrics Screen width/height:1600/1200 20:06:52 INFO - PROCESS | 1652 | colorDepth:24 20:06:52 INFO - PROCESS | 1652 | Browser inner width/height: 1024/689 20:06:52 INFO - PROCESS | 1652 | __metrics 20:06:52 INFO - PROCESS | 1652 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 20:06:52 INFO - PROCESS | 1652 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 20:06:53 INFO - TEST-INFO | 1652: exit 0 20:06:53 INFO - Browser initialized. 20:06:53 INFO - Running cycle 1/1 for kraken test... 20:06:53 INFO - TEST-INFO | started process 1653 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/9_/bq7njmms6gbgk1ygh6pll6r400000w/T/tmpaXQEgv/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 20:07:05 INFO - PROCESS | 1653 | RSS: Main: 234340352 20:07:05 INFO - PROCESS | 1653 | 20:07:08 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49244/tests/kraken/driver.html?testName=audio-beat-detection) 20:07:09 INFO - PROCESS | 1653 | RSS: Main: 286584832 20:07:09 INFO - PROCESS | 1653 | 20:07:11 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49244/tests/kraken/driver.html?testName=audio-dft) 20:07:12 INFO - PROCESS | 1653 | RSS: Main: 286720000 20:07:12 INFO - PROCESS | 1653 | 20:07:15 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49244/tests/kraken/driver.html?testName=audio-fft) 20:07:16 INFO - PROCESS | 1653 | RSS: Main: 378376192 20:07:16 INFO - PROCESS | 1653 | 20:07:18 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49244/tests/kraken/driver.html?testName=audio-oscillator) 20:07:19 INFO - PROCESS | 1653 | RSS: Main: 282091520 20:07:19 INFO - PROCESS | 1653 | 20:07:21 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49244/tests/kraken/driver.html?testName=imaging-gaussian-blur) 20:07:21 INFO - PROCESS | 1653 | RSS: Main: 284442624 20:07:21 INFO - PROCESS | 1653 | 20:07:24 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49244/tests/kraken/driver.html?testName=imaging-darkroom) 20:07:24 INFO - PROCESS | 1653 | RSS: Main: 328310784 20:07:24 INFO - PROCESS | 1653 | 20:07:27 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49244/tests/kraken/driver.html?testName=imaging-desaturate) 20:07:28 INFO - PROCESS | 1653 | RSS: Main: 329457664 20:07:28 INFO - PROCESS | 1653 | 20:07:31 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49244/tests/kraken/driver.html?testName=json-parse-financial) 20:07:32 INFO - PROCESS | 1653 | RSS: Main: 326615040 20:07:32 INFO - PROCESS | 1653 | 20:07:33 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49244/tests/kraken/driver.html?testName=json-stringify-tinderbox) 20:07:34 INFO - PROCESS | 1653 | RSS: Main: 303824896 20:07:34 INFO - PROCESS | 1653 | 20:07:36 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49244/tests/kraken/driver.html?testName=stanford-crypto-aes) 20:07:36 INFO - PROCESS | 1653 | RSS: Main: 306302976 20:07:36 INFO - PROCESS | 1653 | 20:07:39 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49244/tests/kraken/driver.html?testName=stanford-crypto-ccm) 20:07:39 INFO - PROCESS | 1653 | RSS: Main: 307822592 20:07:39 INFO - PROCESS | 1653 | 20:07:42 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49244/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 20:07:43 INFO - PROCESS | 1653 | RSS: Main: 318738432 20:07:43 INFO - PROCESS | 1653 | 20:07:46 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49244/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 20:07:46 INFO - PROCESS | 1653 | RSS: Main: 317722624 20:07:46 INFO - PROCESS | 1653 | 20:07:48 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49244/tests/kraken/driver.html?testName=ai-astar) 20:07:48 INFO - PROCESS | 1653 | RSS: Main: 361340928 20:07:48 INFO - PROCESS | 1653 | 20:07:48 INFO - PROCESS | 1653 | __start_tp_report 20:07:48 INFO - PROCESS | 1653 | _x_x_mozilla_page_load 20:07:48 INFO - PROCESS | 1653 | _x_x_mozilla_page_load_details 20:07:48 INFO - PROCESS | 1653 | |i|pagename|runs| 20:07:48 INFO - PROCESS | 1653 | |0;ai-astar;113;108;113;113;112;114;114;113;114;113 20:07:48 INFO - PROCESS | 1653 | |1;audio-beat-detection;134;110;110;142;114;113;140;142;131;109 20:07:48 INFO - PROCESS | 1653 | |2;audio-dft;186;183;182;180;186;182;183;184;179;185 20:07:48 INFO - PROCESS | 1653 | |3;audio-fft;94;80;59;87;63;58;58;59;91;81 20:07:48 INFO - PROCESS | 1653 | |4;audio-oscillator;90;69;87;69;70;69;83;70;70;84 20:07:48 INFO - PROCESS | 1653 | |5;imaging-gaussian-blur;87;83;87;83;87;84;87;84;87;83 20:07:48 INFO - PROCESS | 1653 | |6;imaging-darkroom;96;97;97;96;112;165;108;97;96;95 20:07:48 INFO - PROCESS | 1653 | |7;imaging-desaturate;88;86;86;87;87;87;88;105;138;139 20:07:48 INFO - PROCESS | 1653 | |8;json-parse-financial;56;55;54;53;54;55;53;55;55;55 20:07:48 INFO - PROCESS | 1653 | |9;json-stringify-tinderbox;64;73;71;75;72;69;66;68;69;72 20:07:48 INFO - PROCESS | 1653 | |10;stanford-crypto-aes;86;90;87;87;87;86;102;87;87;88 20:07:48 INFO - PROCESS | 1653 | |11;stanford-crypto-ccm;140;150;151;143;148;148;140;138;227;150 20:07:48 INFO - PROCESS | 1653 | |12;stanford-crypto-pbkdf2;171;173;172;171;173;173;173;170;178;171 20:07:48 INFO - PROCESS | 1653 | |13;stanford-crypto-sha256-iterative;68;67;68;67;69;67;66;67;66;67 20:07:48 INFO - PROCESS | 1653 | __end_tp_report 20:07:48 INFO - PROCESS | 1653 | __start_cc_report 20:07:48 INFO - PROCESS | 1653 | _x_x_mozilla_cycle_collect,1945 20:07:48 INFO - PROCESS | 1653 | __end_cc_report 20:07:48 INFO - PROCESS | 1653 | __startTimestamp1483070868904__endTimestamp 20:07:48 INFO - PROCESS | 1653 | 20:07:48 INFO - PROCESS | 1653 | ------- Summary: start ------- 20:07:48 INFO - PROCESS | 1653 | Number of tests: 14 20:07:48 INFO - PROCESS | 1653 | 20:07:48 INFO - PROCESS | 1653 | [#0] ai-astar Cycles:10 Average:112.70 Median:113.00 stddev:1.77 (1.6%) stddev-sans-first:1.87 20:07:48 INFO - PROCESS | 1653 | Values: 113.0 108.0 113.0 113.0 112.0 114.0 114.0 113.0 114.0 113.0 20:07:48 INFO - PROCESS | 1653 | 20:07:48 INFO - PROCESS | 1653 | [#1] audio-beat-detection Cycles:10 Average:124.50 Median:132.50 stddev:14.49 (10.9%) stddev-sans-first:14.95 20:07:48 INFO - PROCESS | 1653 | Values: 134.0 110.0 110.0 142.0 114.0 113.0 140.0 142.0 131.0 109.0 20:07:48 INFO - PROCESS | 1653 | 20:07:48 INFO - PROCESS | 1653 | [#2] audio-dft Cycles:10 Average:183.00 Median:183.50 stddev:2.36 (1.3%) stddev-sans-first:2.24 20:07:48 INFO - PROCESS | 1653 | Values: 186.0 183.0 182.0 180.0 186.0 182.0 183.0 184.0 179.0 185.0 20:07:48 INFO - PROCESS | 1653 | 20:07:48 INFO - PROCESS | 1653 | [#3] audio-fft Cycles:10 Average:73.00 Median:80.50 stddev:14.97 (18.6%) stddev-sans-first:13.81 20:07:48 INFO - PROCESS | 1653 | Values: 94.0 80.0 59.0 87.0 63.0 58.0 58.0 59.0 91.0 81.0 20:07:48 INFO - PROCESS | 1653 | 20:07:48 INFO - PROCESS | 1653 | [#4] audio-oscillator Cycles:10 Average:76.10 Median:76.50 stddev:8.72 (11.4%) stddev-sans-first:7.67 20:07:48 INFO - PROCESS | 1653 | Values: 90.0 69.0 87.0 69.0 70.0 69.0 83.0 70.0 70.0 84.0 20:07:48 INFO - PROCESS | 1653 | 20:07:48 INFO - PROCESS | 1653 | [#5] imaging-gaussian-blur Cycles:10 Average:85.20 Median:87.00 stddev:1.93 (2.2%) stddev-sans-first:1.94 20:07:48 INFO - PROCESS | 1653 | Values: 87.0 83.0 87.0 83.0 87.0 84.0 87.0 84.0 87.0 83.0 20:07:48 INFO - PROCESS | 1653 | 20:07:48 INFO - PROCESS | 1653 | [#6] imaging-darkroom Cycles:10 Average:105.90 Median:96.00 stddev:21.56 (22.5%) stddev-sans-first:22.57 20:07:48 INFO - PROCESS | 1653 | Values: 96.0 97.0 97.0 96.0 112.0 165.0 108.0 97.0 96.0 95.0 20:07:48 INFO - PROCESS | 1653 | 20:07:48 INFO - PROCESS | 1653 | [#7] imaging-desaturate Cycles:10 Average:99.10 Median:87.00 stddev:21.52 (24.7%) stddev-sans-first:22.45 20:07:48 INFO - PROCESS | 1653 | Values: 88.0 86.0 86.0 87.0 87.0 87.0 88.0 105.0 138.0 139.0 20:07:48 INFO - PROCESS | 1653 | 20:07:48 INFO - PROCESS | 1653 | [#8] json-parse-financial Cycles:10 Average:54.50 Median:55.00 stddev:0.97 (1.8%) stddev-sans-first:0.87 20:07:48 INFO - PROCESS | 1653 | Values: 56.0 55.0 54.0 53.0 54.0 55.0 53.0 55.0 55.0 55.0 20:07:48 INFO - PROCESS | 1653 | 20:07:48 INFO - PROCESS | 1653 | [#9] json-stringify-tinderbox Cycles:10 Average:69.90 Median:71.50 stddev:3.35 (4.7%) stddev-sans-first:2.79 20:07:48 INFO - PROCESS | 1653 | Values: 64.0 73.0 71.0 75.0 72.0 69.0 66.0 68.0 69.0 72.0 20:07:48 INFO - PROCESS | 1653 | 20:07:48 INFO - PROCESS | 1653 | [#10] stanford-crypto-aes Cycles:10 Average:88.70 Median:87.00 stddev:4.81 (5.5%) stddev-sans-first:5.00 20:07:48 INFO - PROCESS | 1653 | Values: 86.0 90.0 87.0 87.0 87.0 86.0 102.0 87.0 87.0 88.0 20:07:48 INFO - PROCESS | 1653 | 20:07:48 INFO - PROCESS | 1653 | [#11] stanford-crypto-ccm Cycles:10 Average:153.50 Median:149.00 stddev:26.26 (17.6%) stddev-sans-first:27.40 20:07:48 INFO - PROCESS | 1653 | Values: 140.0 150.0 151.0 143.0 148.0 148.0 140.0 138.0 227.0 150.0 20:07:48 INFO - PROCESS | 1653 | 20:07:48 INFO - PROCESS | 1653 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:172.50 Median:173.00 stddev:2.22 (1.3%) stddev-sans-first:2.29 20:07:48 INFO - PROCESS | 1653 | Values: 171.0 173.0 172.0 171.0 173.0 173.0 173.0 170.0 178.0 171.0 20:07:48 INFO - PROCESS | 1653 | 20:07:48 INFO - PROCESS | 1653 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.20 Median:67.00 stddev:0.92 (1.4%) stddev-sans-first:0.93 20:07:48 INFO - PROCESS | 1653 | Values: 68.0 67.0 68.0 67.0 69.0 67.0 66.0 67.0 66.0 67.0 20:07:48 INFO - PROCESS | 1653 | -------- Summary: end -------- 20:07:48 INFO - PROCESS | 1653 | 20:07:49 INFO - TEST-INFO | 1653: exit 0 20:07:49 INFO - TEST-OK | kraken | took 59490ms 20:07:49 INFO - SUITE-END | took 1179s 20:07:50 INFO - Completed test suite (00:19:39) 20:07:50 INFO - javascript benchmark 20:07:50 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3340.3987282289186, 3354.765166438349, 3357.7817441866277, 3370.8146051967165, 3427.828608569571, 5602.60781745278, 5678.26182596263, 5763.56773242007, 5775.971120144404, 5790.160426738121, 5719.542436605062, 5761.769529218836, 5824.446677565635, 5832.270966129234, 5841.445062719045, 5190.610704197182, 5191.325127733389, 5196.92204616931, 5210.843674689765, 5269.683818970874, 5311.973440132794, 5312.521873031426, 5320.4679532046675, 5338.546223571001, 5365.248865158881, 4249.192653395876, 5211.895762084775, 5282.260483532308, 5296.390915044781, 5317.707526086064, 5178.533931946105, 5230.738463076831, 5246.527812496894, 5259.947400525984, 5327.893442131136, 5203.349581302337, 5233.084210263227, 5246.37043554772, 5247.553957913582, 5264.2893209416625, 5728.8854222915725, 5755.971220143914, 5762.0985074925175, 5766.7981620643495, 5799.304083509964, 6137.806028951355, 6142.723577439026, 6153.938460615382, 6167.167432396614, 6228.346023667495, 6280.560360774748, 6337.588056776295, 6354.0, 6359.936400635981, 6376.585521941059, 4943.72809495474, 5813.186153938452, 5822.126680997884, 5830.679312637761, 5835.299764028301, 6196.1635179251125, 6222.533310001768, 6248.125262463259, 6265.091561723524, 6293.244810622755, 6108.8964221451015, 6141.815745527641, 6176.752929882754, 6220.222472190976, 6223.533235007392, 6122.438812974205, 6304.0, 6314.273858506239, 6323.810285691437, 6327.335629758901, 5115.232715092691, 6080.179175811298, 6180.598261113013, 6182.474489668429, 6197.876042479125, 6177.752889884444, 6184.5052395808225, 6201.875962480725, 6204.038374052029, 6207.5654704171175, 4918.336024636628, 4929.556339929423, 4942.431620363633, 4946.752662366867, 4946.851594452172, 4307.396964424983, 4312.331588603772, 4488.842890498817, 4492.168948744493, 4498.100379924028, 5679.971600142056, 5764.596478246483, 5886.470217680429, 5889.734961926723, 5890.49930755882, 6224.782132625336, 6249.281332646804, 6251.812445626639, 6267.655278959701, 6356.586821856565, 5802.854928626818, 5850.151727999416, 5853.853653658607, 5859.2090067841145, 5896.587238893237, 5434.864128396822, 5435.483629055233, 5437.293151890308, 5442.292501974797, 5446.128619420873, 6251.99968005117, 6252.249730032425, 6262.874742505215, 6296.496280297657, 6306.432421082125, 5340.798320377884, 6322.557420980487, 6323.08315294289, 6327.683615819283, 6332.240131184288, 5364.758585863724, 6255.40573645503, 6306.243250809932, 6312.084747711648, 6328.082428047999, 6107.097787096225, 6126.724297406538, 6155.876882462415, 6194.132821405097, 6262.0, 5094.5160209780015, 6304.873902522016, 6318.905216421849, 6319.210098737658, 6323.272823625342, 6137.815865524041, 6141.723622436912, 6144.293406258337, 6154.753809847556, 6163.722632481459, 5953.553483488669, 6029.60807547508, 6112.388761123852, 6143.078538219303, 6152.292486364126, 5905.379935106752, 5948.643081415129, 5959.046552551571, 5965.313988891159, 5966.134910437875, 6051.062085376773, 6082.482988945989, 6085.817425477243, 6087.391260873877, 6094.603850749687, 5810.622309549865, 5812.099124635688, 5823.679697616672, 5846.56150788684, 5915.171875937458], "value": 5697.765422767998, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [444.7220487195503, 444.7420496112251, 480.72598618787305, 506.8226120857699, 516.8242797448867, 22769.77230227697, 28937.855310723444, 29025.984090556834, 35621.82189089051, 37200.88397348077, 36304.455433168456, 36757.81621091891, 36807.07982300437, 36835.447468287995, 36898.26203475929, 8965.775855603597, 9161.03809100045, 9164.816703665923, 9165.954170229139, 9206.631734730636, 8395.748127556182, 8516.446430981998, 9042.412243204204, 9153.29977656593, 10709.464526773669, 10939.617113401033, 11086.279391839505, 11121.22151449399, 11122.221444498848, 11182.27315224512, 11085.722856928602, 11138.220324577242, 11159.832602510969, 11166.497507612095, 11169.0506306964, 8177.100518942912, 8393.958030209871, 10679.145668346591, 10989.0, 11035.944820275927, 36206.732764353386, 36571.26857462862, 36582.26835463302, 36588.451173232424, 36589.26821463583, 3817.8663746769007, 3859.8070096495344, 3865.1303456722294, 3891.8443262269475, 3986.142979259473, 27851.0, 28674.856625716737, 28897.56653650197, 29049.419011619648, 29361.265968350963, 3086.753059755237, 3213.0682102190335, 3237.3848968695875, 3272.7872688275424, 3298.043567365461, 464043.9999999966, 467158.0, 469501.0, 469838.0, 471064.0, 51775.22337164945, 52103.0, 52119.957600847774, 52146.0, 52173.0, 52771.0, 53913.1913021305, 54000.729996349764, 54048.18927716088, 54242.18636720452, 13933.512327068504, 14412.783808242884, 14413.78379324311, 14439.85560144385, 14441.566752997427, 11123.27698699582, 11205.99146076857, 11207.383593902414, 11208.607698730668, 11212.439378031066, 12103.152779305365, 12512.249265043944, 13672.658183545293, 13998.0, 14114.71770564583, 5831.970840145772, 5873.006415221776, 5899.941000589939, 5914.338547653573, 5923.17075609415, 814.2305521282332, 827.9213474719892, 839.6515446089804, 845.8139209373928, 851.2764150472049, 27815.02647407331, 27855.30361740932, 28124.71875281262, 28651.426971460452, 28703.0, 915.2586405011829, 970.5001924009141, 971.3977334052933, 1008.1229330482381, 1110.0952723530297, 13058.443208025566, 14006.159630422004, 14198.929005354907, 14382.918234367304, 14614.196219208046, 605601.0, 614865.0, 616038.0, 616242.0, 616403.0, 51552.48447515477, 51717.0, 51761.48238517567, 51783.0, 51794.96410071778, 1291.6318849127954, 1302.446460254388, 1323.1531819635634, 1323.4441534555701, 1331.8734720201564, 23499.76500234976, 27069.0, 27631.30921726973, 28178.591070446804, 28260.15219543417, 43575.0, 44063.55936440723, 44078.55921440873, 44104.77947610241, 44206.336904945805, 517205.0, 521034.0, 522311.1653325279, 523618.0, 524850.0, 35062.29875402529, 35138.12154696153, 35544.82227588846, 35704.107397315274, 35950.0, 33916.660833391354, 38130.04674883039, 40334.79832600818, 42669.933251668954, 44369.0, 29128.27179320449, 34184.82907585446, 34923.47614785831, 35708.0, 36296.911092667266, 32304.0, 34811.65188348084, 34964.0, 35514.64485355217, 35995.46006809847, 34627.30745385129, 35111.64888351084, 35211.0, 35312.76405325801, 35364.823175883954, 31075.223119422197, 31674.524882126327, 31748.68251317457, 31774.841125794224, 31893.362132756753, 19502.414927552196, 23165.652515211947, 23919.0, 24297.51404971926, 24429.51140977135], "value": 18525.63884027177, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [347.7878494118586, 367.17385881766046, 369.5583777386024, 374.3841380928373, 396.4627929155994, 14282.714345713079, 14651.413943442263, 15469.613259668515, 19409.4177174685, 19985.800851948894, 17679.82320176801, 17805.821941780578, 18613.813861861378, 18792.43622691318, 18844.152013159408, 4912.901741965167, 5165.74171291434, 5194.013137503862, 5198.350206224222, 5210.957808438308, 5140.640155189139, 5176.430592634807, 5350.732463376835, 5625.6905870177125, 5713.14302854571, 5749.971250143743, 5755.510781583571, 5757.596968212227, 5760.596758226926, 5762.596618236725, 5597.720113994284, 5651.208830763697, 5713.428657134295, 5718.342390625048, 5754.338251101114, 5447.128459446509, 5514.641548299348, 5522.22688823565, 5528.695921724304, 5530.723463826793, 12541.498340066386, 12665.240085594802, 12686.682832929158, 12689.809652855214, 13392.0, 2612.8171028028046, 2621.423286876884, 2632.4735052989286, 2633.776129029035, 2645.854478003709, 9345.579448924815, 9378.343515953886, 9504.904950950471, 9924.255680823895, 10096.040876116756, 1361.2853252042758, 1756.1745979389666, 1769.8141695122085, 1798.307651554161, 1816.061478963781, 97300.0, 108496.0, 108581.0, 108883.0, 109001.45499272452, 10995.890041099487, 11094.334339939469, 11138.777224455465, 11274.605388811353, 11365.431728413547, 13877.097988630707, 13878.375473103735, 13889.652758680912, 13902.16587004783, 13924.86075139256, 7698.769036928901, 7829.0, 7853.607319633996, 7951.483153594998, 7995.760127196193, 7527.021487206628, 7557.2820582044615, 7581.924180758232, 7699.461037727306, 7721.652525636247, 4273.87178384649, 4287.485501739811, 4289.185054839633, 4305.385480444833, 4377.205345808267, 1621.799868097623, 1669.599296168935, 1803.6933721267417, 1840.2179073893542, 1864.3847530314963, 933.9579718912529, 949.8601677986444, 969.4764826993345, 979.7207795778169, 981.1758123176566, 19135.521611959535, 19965.500862478555, 20120.89939550322, 20537.38387848367, 21256.36230913075, 976.4727047394327, 984.9162821160138, 1003.6136087606178, 1014.7970405918845, 1019.8266294729765, 9940.502974851228, 10364.740881477872, 10381.532831022621, 10393.740156496147, 10438.321509101885, 344346.0, 382151.0, 488593.55703221256, 577760.0, 578474.0, 127550.0, 130827.0, 133036.0, 133395.0, 137272.0, 977.2231076294221, 982.8918818929913, 990.9405435673739, 1005.3666190300211, 1006.7080546641538, 14698.485553005594, 17618.91190544039, 17655.735163972295, 17769.20038598292, 17828.46514604564, 50404.08400184595, 53960.0, 54302.64243393789, 54880.9999999984, 55034.348969530976, 77594.0, 78205.0, 78665.0, 78674.6066269665, 79957.60021199856, 6784.215784215784, 7653.6938522460705, 7820.335271501986, 7845.097813751263, 8118.918810811816, 11468.936425130287, 11844.407779611158, 12221.266723996589, 12240.08199385032, 12267.141300108906, 8886.866696999421, 9073.818523629358, 9086.909130908607, 9216.164742292627, 9253.58358873839, 9505.809883802423, 10187.388756674623, 10307.123894469054, 10620.256582039183, 10897.346159230472, 9941.453220072965, 10262.640807571699, 10567.313124646873, 10954.56181752721, 11038.889611103787, 7689.538627682357, 7727.111382190895, 7777.766666999999, 7899.0, 7939.007624046994, 10136.695899123039, 10215.540300686338, 10282.691519254435, 10714.035736783728, 10732.302400343953], "value": 8912.965343928789, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1307.444336157133, 1315.8092076648886, 1332.2605953695702, 1333.7065845513996, 1334.739725753478, 8000.279974802266, 8477.194666506688, 8479.236868681817, 8492.193241642035, 8495.745127646172, 6615.966920165404, 7411.888821667666, 7606.695732170707, 7612.92387076129, 8260.91739082609, 2240.383894429033, 2316.5598536278053, 2319.82601304902, 2323.175272778165, 2323.53529294141, 2295.1622657729977, 2316.455632926261, 2339.6490526421003, 2339.847909885852, 2340.216027630746, 1696.635223426963, 2079.139170442453, 2294.265834932822, 2333.311673056444, 2336.19401306549, 2225.4770129019666, 2316.837821352506, 2317.73346065203, 2327.650852372141, 2334.3580515358244, 2310.4685922237977, 2332.2187067332466, 2335.1710142899196, 2336.719593648756, 2345.519168570439, 8207.425480216387, 8400.369972252045, 8420.368472364597, 8430.957845210796, 8431.578421078983, 5004.699718016931, 5168.302279192299, 5172.456892026321, 5179.093658609729, 5190.377154741417, 3304.454764963778, 3488.372093023255, 3505.8597656093725, 3553.4847447120023, 3554.751167418282, 1181.3650457239255, 1205.3310412720937, 1236.9196002259823, 1259.4017841525294, 1262.6338361875046, 10962.342259464458, 10982.780344393148, 11011.119110471223, 11011.559537618487, 11026.0076593107, 4466.240739074366, 4500.302453119772, 4609.423822022247, 4664.486906440289, 4749.073930583572, 8704.390692651581, 8752.47485150893, 8774.429662072072, 8782.692605758768, 8785.51679657625, 5057.8735531612, 5074.16276314404, 5078.568321692624, 5081.18701007837, 5096.974515127401, 4609.585137337655, 4704.411948506437, 4760.309755085494, 4769.59458446029, 4783.043391321749, 3451.3442445935193, 3509.9122521937156, 3531.422061894831, 3582.3730847102174, 3588.5334906461994, 1103.2773533335678, 1107.1917500224881, 1108.5233349659563, 1118.2004866520476, 1119.5465836336298, 693.1231991530718, 701.1200942816835, 720.7477382916043, 726.5241266970143, 748.831512909591, 5048.697078175322, 5358.9550037742265, 5401.865608222317, 5499.1476321170285, 5705.144228365778, 498.9725565093955, 525.687216106416, 528.2736237673199, 888.9555522223862, 919.0131972271479, 3608.548931383577, 3711.758735682172, 3738.420544815558, 3782.3759079751535, 3788.0908581940134, 9410.858837117448, 9649.469279189572, 9778.266630002636, 10074.345167564084, 10192.592296308065, 10054.849177262347, 10073.992600739868, 10076.193904487624, 10078.193744500422, 10078.647247346307, 7252.456065795086, 7476.214997425301, 7583.886241706379, 7634.274743899321, 7745.961270193726, 5434.347878254634, 5649.37856835756, 5657.462541058593, 5663.688497132697, 5767.961766882002, 9004.819903602021, 9207.723768286962, 9216.769580760534, 9248.537573121183, 9395.671151509665, 7290.9949763815375, 9314.0, 9437.0, 9469.47917864524, 9483.336166468282, 6499.1551098358805, 6697.756251655555, 7034.788956331318, 7216.819579510554, 7240.312170344019, 6968.756093536702, 7735.6905723770415, 7821.843563128592, 7975.322097621767, 8021.679132834622, 6952.965235173791, 7016.6491675415, 7064.32888875556, 7102.254263302279, 7192.928070719435, 5965.552583556338, 7001.684924178322, 7020.929790702027, 7062.823429414306, 7073.363397294268, 5534.225208470898, 6064.272287325549, 6314.8737025258315, 6355.237371515448, 6420.903686444613, 4179.26862799015, 4576.656750743774, 4733.9053218936115, 4739.478657347758, 4755.239161734106, 4223.965128543527, 4709.599684026896, 4849.3210950466255, 4858.3441235434175, 4860.270959356125], "value": 4261.350782226671, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [345.57321707691005, 354.5036948272418, 360.0746082567801, 372.9608391118932, 374.43460374834024, 4827.5172482751705, 5244.449332820056, 5277.313949186605, 5282.339707536558, 5315.76737770137, 4231.851885184019, 4336.522982471925, 4903.803847846082, 5066.746662666861, 5200.427952925175, 3724.590295067554, 3791.5829258781514, 3944.5463771666336, 4085.730411719745, 4087.4481944937497, 4071.9389209161886, 4087.652549533293, 4108.506979162505, 4118.528882670389, 4122.752634841904, 3740.9812950935207, 3918.8824335269987, 4065.166640838636, 4083.5099788025477, 4086.1827634473075, 3303.620083690382, 3743.7566558173903, 3928.823202955874, 3965.5439624443125, 3981.5023122109737, 3404.438267685829, 3419.230673098533, 3420.931581368359, 3444.6555344465605, 3447.3105378924065, 4500.594946454835, 4972.270796902323, 5074.771635276422, 5081.618878584084, 5089.567386772129, 3601.7658852174527, 4206.91586168278, 4282.336237883133, 4464.866054018385, 4481.3726078349055, 4949.925751113737, 5175.792968281265, 5434.483724046209, 5449.6730196188355, 5473.303275984457, 3316.8839090631714, 3599.4420864766003, 3719.3863012602887, 3743.794091324976, 3752.7935963521995, 5352.545033672142, 5630.789380283259, 5656.575756818214, 5702.144678298247, 5754.395788442195, 3592.5688917329567, 3600.477930700034, 4026.5570787213383, 4078.6940979426067, 4103.671706263492, 3881.4760007399204, 4010.8569057818963, 4164.37534369847, 4167.895802604899, 4234.534201237861, 4022.2759903217116, 4204.348326009474, 4536.931946020813, 4700.435947686231, 4720.716756994522, 4682.555157260055, 4886.560209581155, 5332.493413125747, 5496.148097044965, 5546.500814926595, 1234.6850604106598, 1235.950561977511, 1352.4852165574619, 1357.8234829472106, 1359.9864001360058, 891.1222445890793, 920.6650356982402, 929.4330458420358, 940.6707652321633, 949.1410273702172, 4313.84901528445, 4673.95326046742, 4744.505480773545, 4921.342475063498, 4942.678725882878, 5339.580325969145, 5356.758945847446, 5374.220737992969, 5379.515843574097, 5389.784408623611, 4539.459221806996, 4634.629229661619, 4752.287156926489, 4796.25658023007, 4827.879303017453, 2664.5603475426724, 2727.4545090981883, 2728.3042824079735, 2809.7471227589617, 2823.43531293738, 4798.521072222611, 5159.071367153948, 5455.645383050089, 5497.8075767347955, 5594.8810237952575, 5056.317397151411, 5097.745112744348, 5233.607479439057, 5503.3395992481155, 5518.558515318765, 4896.461389247252, 5199.024370739095, 5217.399999000164, 5304.867378315495, 5520.834374968757, 4077.044142996304, 4520.59314661682, 5188.429272780067, 5338.733063346895, 5381.246625472359, 4184.958150418457, 4759.14335419614, 5114.46298138705, 5215.6609820361555, 5247.195783057253, 5239.633225674166, 5276.683398996072, 5324.9733751331, 5445.863853403696, 5506.339239291311, 3384.5600071990484, 3759.8684046058256, 3874.4188371744463, 3891.2023035277134, 3894.169740222091, 5191.740412979411, 5618.6628802272, 5640.182173584889, 5660.745266463102, 5664.4509970514255, 4893.437254715754, 5472.781088756406, 5486.107292983914, 5506.64206826555, 5525.557955363481, 4798.47216806144, 4842.564169224786, 4910.729909854992, 4988.850334489971, 5495.313085864267, 4757.5718185363485, 5097.286379906742, 5127.897442051213, 5390.4879036491475, 5415.59383046281, 4744.644151688706, 4869.95130048695, 4950.777215025405, 5377.973110134424, 5475.534579560782, 5169.7673604687125, 5380.919286210788, 5391.541718953933, 5400.324959380077, 5402.918956215739], "value": 3970.111917886973, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [623.9001759718444, 662.4799532367093, 692.9099217101776, 737.5279820914612, 745.3440971944688, 14049.648758781035, 14963.476278330261, 15008.774868376982, 15021.849781502182, 15033.473828416008, 13071.934640326808, 13178.406971686272, 13329.666758331046, 13477.730445391086, 13526.32368381582, 781.3592853859838, 789.763071078677, 797.9985118676053, 801.1307731111722, 807.725373373056, 783.0603276068712, 788.1527358090048, 789.8183417813906, 790.0953408347439, 790.9564973926432, 812.3947658994028, 814.8818421328904, 815.4006804998321, 816.3428440105713, 818.9262966333029, 807.6446363599997, 808.2523665609317, 809.5062012172571, 811.6631597886899, 813.6297984417106, 607.5109536822856, 608.367298010069, 610.3042531514078, 610.4475449718012, 611.9020956646959, 12420.627381178489, 12433.813492797615, 12692.492300307977, 12698.492060317576, 12907.031972601999, 11345.0, 11509.48207330672, 11509.76980460386, 11696.88303116975, 11893.940530297294, 10139.290249682525, 10221.591136354537, 10290.176785857113, 10307.330023548446, 10451.738706532322, 3510.0, 3523.859045638171, 3534.717222622184, 3546.0, 3547.0777597824494, 98947.50526247395, 99156.0, 99166.0, 99222.0, 100774.0, 8578.742637720878, 8677.563150074295, 8898.555072246361, 8919.643214271486, 9074.682386116454, 18612.720809187875, 18636.347727829732, 18646.44060678182, 18652.720209196872, 18722.157502912403, 7294.0, 7503.62481875904, 7572.39420846331, 7652.502587331805, 7714.537127772352, 4018.6985976051915, 4152.958470415318, 4216.536181020086, 4337.436133302651, 4344.847930322424, 3345.063382252973, 3416.555847739778, 3421.8460169291943, 3461.6884480396884, 3465.86136554535, 617.7127635649445, 623.2863371439767, 624.54720327762, 630.6909614289057, 638.7955854126727, 576.907694768835, 629.4303655192059, 631.0849268560605, 639.9296077431479, 649.2890285137767, 7205.531640443354, 8157.493909911312, 8442.240198382175, 8478.703245386505, 8865.404227239158, 565.1656940443678, 594.8334466349429, 623.2738859228947, 646.9061986012002, 654.4698793976894, 4288.914221715549, 4441.089576636782, 4456.665750068758, 4470.307102399133, 4553.453585569687, 73593.63203184056, 74941.5011699763, 80910.5954470224, 83503.58248208721, 98912.0, 73821.15447113865, 76917.0, 78007.82988255181, 78009.82985255226, 81451.0, 73631.0, 74675.1331216713, 75055.1236219099, 76059.61970190113, 76838.0, 7328.267173282629, 9523.28832548921, 9655.53053490391, 9713.320067595201, 9767.9023209767, 47043.76478117587, 48268.0, 48578.757106214245, 49252.26121608107, 50235.0, 46600.7669961648, 58402.70798646149, 62271.6886415565, 64274.67862660657, 66580.66709666421, 12443.813342799684, 12517.812232816697, 12576.434060467118, 12799.936000319938, 13083.0, 14197.929010354883, 14266.928665356607, 14554.0, 14616.196109214095, 15121.243937803285, 13321.400536975665, 13472.663183420493, 13505.392257348247, 13682.179069255877, 13690.041697081508, 13034.08761386694, 13192.274424906722, 13228.603141905758, 13256.668583285109, 13356.131851429625, 12875.871241287467, 13019.479220831061, 13022.348882555645, 13089.018323625576, 13518.188908665512, 10047.648332308334, 10321.690349289533, 10360.896391035993, 10389.272750907365, 10803.243772935819, 10236.283460157718, 10244.948775256076, 10249.077583017564, 10265.794684106426, 10395.376277423378], "value": 6917.945281868833, "unit": "score"}], "name": "dromaeo_css", "value": 6923.284891442818, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 108.0, 113.0, 113.0, 112.0, 114.0, 114.0, 113.0, 114.0, 113.0], "value": 112.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [134.0, 110.0, 110.0, 142.0, 114.0, 113.0, 140.0, 142.0, 131.0, 109.0], "value": 124.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [186.0, 183.0, 182.0, 180.0, 186.0, 182.0, 183.0, 184.0, 179.0, 185.0], "value": 183.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 80.0, 59.0, 87.0, 63.0, 58.0, 58.0, 59.0, 91.0, 81.0], "value": 73.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 69.0, 87.0, 69.0, 70.0, 69.0, 83.0, 70.0, 70.0, 84.0], "value": 76.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 83.0, 87.0, 83.0, 87.0, 84.0, 87.0, 84.0, 87.0, 83.0], "value": 85.2, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 97.0, 97.0, 96.0, 112.0, 165.0, 108.0, 97.0, 96.0, 95.0], "value": 105.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 86.0, 86.0, 87.0, 87.0, 87.0, 88.0, 105.0, 138.0, 139.0], "value": 99.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 55.0, 54.0, 53.0, 54.0, 55.0, 53.0, 55.0, 55.0, 55.0], "value": 54.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 73.0, 71.0, 75.0, 72.0, 69.0, 66.0, 68.0, 69.0, 72.0], "value": 69.9, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 90.0, 87.0, 87.0, 87.0, 86.0, 102.0, 87.0, 87.0, 88.0], "value": 88.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [140.0, 150.0, 151.0, 143.0, 148.0, 148.0, 140.0, 138.0, 227.0, 150.0], "value": 153.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [171.0, 173.0, 172.0, 171.0, 173.0, 173.0, 173.0, 170.0, 178.0, 171.0], "value": 172.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 67.0, 68.0, 67.0, 69.0, 67.0, 66.0, 67.0, 66.0, 67.0], "value": 67.2, "unit": "score"}], "name": "kraken", "value": 1465.8, "alertThreshold": 2.0}]} 20:07:50 INFO - Return code: 0 20:07:50 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 20:07:50 INFO - # TBPL SUCCESS # 20:07:50 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 20:07:50 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 20:07:50 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 20:07:50 INFO - 2016-12-29 20:07:50.222 screenresolution[1657:9873] starting screenresolution argv=screenresolution get 20:07:50 INFO - 2016-12-29 20:07:50.224 screenresolution[1657:9873] Display 0: 1600x1200x32@60 20:07:50 INFO - 2016-12-29 20:07:50.230 screenresolution[1658:9876] starting screenresolution argv=screenresolution list 20:07:50 INFO - Available Modes on Display 0 20:07:50 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 20:07:50 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 20:07:50 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 20:07:50 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 20:07:50 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 20:07:50 INFO - Intel Iris: 20:07:50 INFO - Chipset Model: Intel Iris 20:07:50 INFO - Type: GPU 20:07:50 INFO - Bus: Built-In 20:07:50 INFO - VRAM (Dynamic, Max): 1536 MB 20:07:50 INFO - Vendor: Intel (0x8086) 20:07:50 INFO - Device ID: 0x0a2e 20:07:50 INFO - Revision ID: 0x0009 20:07:50 INFO - Displays: 20:07:50 INFO - Display: 20:07:50 INFO - Resolution: 1600 x 1200 @ 60 Hz 20:07:50 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 20:07:50 INFO - Display Serial Number: 1600x1200 60 20:07:50 INFO - Main Display: Yes 20:07:50 INFO - Mirror: Off 20:07:50 INFO - Online: Yes 20:07:50 INFO - Rotation: Supported 20:07:50 INFO - Return code: 0 20:07:50 INFO - Running post-action listener: _resource_record_post_action 20:07:50 INFO - [mozharness: 2016-12-30 04:07:50.470585Z] Finished run-tests step (success) 20:07:50 INFO - Running post-run listener: _resource_record_post_run 20:07:50 INFO - Total resource usage - Wall time: 1202s; CPU: 54.0%; Read bytes: 37639168; Write bytes: 550743552; Read time: 236; Write time: 1658 20:07:50 INFO - TinderboxPrint: CPU usage
53.9% 20:07:50 INFO - TinderboxPrint: I/O read bytes / time
37,639,168 / 236 20:07:50 INFO - TinderboxPrint: I/O write bytes / time
550,743,552 / 1,658 20:07:50 INFO - TinderboxPrint: CPU idle
2,218.7 (46.1%) 20:07:50 INFO - TinderboxPrint: CPU system
963.4 (20.0%) 20:07:50 INFO - TinderboxPrint: CPU user
1,627.5 (33.8%) 20:07:50 INFO - TinderboxPrint: Swap in / out
286,842,880 / 0 20:07:50 INFO - install - Wall time: 22s; CPU: 55.0%; Read bytes: 232077824; Write bytes: 223381504; Read time: 16449; Write time: 473 20:07:50 INFO - run-tests - Wall time: 1181s; CPU: 54.0%; Read bytes: 31394816; Write bytes: 297231872; Read time: 183; Write time: 1114 20:07:50 INFO - Running post-run listener: _upload_blobber_files 20:07:50 INFO - Blob upload gear active. 20:07:50 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 20:07:50 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 20:07:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 20:07:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 20:07:51 INFO - (blobuploader) - INFO - Open directory for files ... 20:07:51 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 20:07:51 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 20:07:51 INFO - (blobuploader) - INFO - Uploading, attempt #1. 20:07:52 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 20:07:52 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 20:07:52 INFO - (blobuploader) - INFO - Done attempting. 20:07:52 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 20:07:52 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 20:07:52 INFO - (blobuploader) - INFO - Uploading, attempt #1. 20:07:53 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 20:07:53 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 20:07:53 INFO - (blobuploader) - INFO - Done attempting. 20:07:53 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 20:07:53 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 20:07:53 INFO - (blobuploader) - INFO - Uploading, attempt #1. 20:07:54 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 20:07:54 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 20:07:54 INFO - (blobuploader) - INFO - Done attempting. 20:07:54 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 20:07:54 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 20:07:54 INFO - (blobuploader) - INFO - Uploading, attempt #1. 20:07:57 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 20:07:57 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 20:07:57 INFO - (blobuploader) - INFO - Done attempting. 20:07:57 INFO - (blobuploader) - INFO - Iteration through files over. 20:07:57 INFO - Return code: 0 20:07:57 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 20:07:57 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 20:07:57 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2ac050d43c10d684538e171fc0ab1cd9cd696cf7a3eca012583ca049d86b31bb806929c945f6d6a5942ca0f7417389c450a85958fdd654713a913462332e94ef", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6f9dd40bade4333e5907f95d9fd8ab9e21492a85be8b0f9a52faf052181b5ed4c617b715add4e0d68ae34ab357005981862db014bf39be43a2a8b5e85d8d33e9", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/76c02b10bf6f939fb2b72c798602c30692c602f89a60ddb9e84d8e85d879be4be719da887a7a158e2d8070a4de6fc6f40babe8915cf7664f8c6bd771b86e4c78", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ec5e199752a16dd1aea65a94fa57bc3798f7451661e457a916beaaa3f975b9bf4b56c228e10e3c1788fc433574f199ede515f83e8e7ba5f37fb9a6f969d0a94a"} 20:07:57 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 20:07:57 INFO - Writing to file /builds/slave/test/properties/blobber_files 20:07:57 INFO - Contents: 20:07:57 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2ac050d43c10d684538e171fc0ab1cd9cd696cf7a3eca012583ca049d86b31bb806929c945f6d6a5942ca0f7417389c450a85958fdd654713a913462332e94ef", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6f9dd40bade4333e5907f95d9fd8ab9e21492a85be8b0f9a52faf052181b5ed4c617b715add4e0d68ae34ab357005981862db014bf39be43a2a8b5e85d8d33e9", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/76c02b10bf6f939fb2b72c798602c30692c602f89a60ddb9e84d8e85d879be4be719da887a7a158e2d8070a4de6fc6f40babe8915cf7664f8c6bd771b86e4c78", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ec5e199752a16dd1aea65a94fa57bc3798f7451661e457a916beaaa3f975b9bf4b56c228e10e3c1788fc433574f199ede515f83e8e7ba5f37fb9a6f969d0a94a"} 20:07:57 INFO - Running post-run listener: copy_logs_to_upload_dir 20:07:57 INFO - Copying logs to upload dir... 20:07:57 INFO - mkdir: /builds/slave/test/build/upload/logs 20:07:57 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1267.722060 ========= master_lag: 0.14 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 7 secs) (at 2016-12-29 20:07:57.842574) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-29 20:07:57.846042) ========= 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.XAxEqqjmZN/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.YW7HwvDW5a/Listeners TMPDIR=/var/folders/9_/bq7njmms6gbgk1ygh6pll6r400000w/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/2ac050d43c10d684538e171fc0ab1cd9cd696cf7a3eca012583ca049d86b31bb806929c945f6d6a5942ca0f7417389c450a85958fdd654713a913462332e94ef", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6f9dd40bade4333e5907f95d9fd8ab9e21492a85be8b0f9a52faf052181b5ed4c617b715add4e0d68ae34ab357005981862db014bf39be43a2a8b5e85d8d33e9", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/76c02b10bf6f939fb2b72c798602c30692c602f89a60ddb9e84d8e85d879be4be719da887a7a158e2d8070a4de6fc6f40babe8915cf7664f8c6bd771b86e4c78", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ec5e199752a16dd1aea65a94fa57bc3798f7451661e457a916beaaa3f975b9bf4b56c228e10e3c1788fc433574f199ede515f83e8e7ba5f37fb9a6f969d0a94a"} build_url:https://queue.taskcluster.net/v1/task/Snmb8HRcQWOBKk1aw_4KVw/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010964 build_url: 'https://queue.taskcluster.net/v1/task/Snmb8HRcQWOBKk1aw_4KVw/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/2ac050d43c10d684538e171fc0ab1cd9cd696cf7a3eca012583ca049d86b31bb806929c945f6d6a5942ca0f7417389c450a85958fdd654713a913462332e94ef", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6f9dd40bade4333e5907f95d9fd8ab9e21492a85be8b0f9a52faf052181b5ed4c617b715add4e0d68ae34ab357005981862db014bf39be43a2a8b5e85d8d33e9", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/76c02b10bf6f939fb2b72c798602c30692c602f89a60ddb9e84d8e85d879be4be719da887a7a158e2d8070a4de6fc6f40babe8915cf7664f8c6bd771b86e4c78", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ec5e199752a16dd1aea65a94fa57bc3798f7451661e457a916beaaa3f975b9bf4b56c228e10e3c1788fc433574f199ede515f83e8e7ba5f37fb9a6f969d0a94a"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-29 20:07:57.885999) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-29 20:07:57.886421) ========= 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.XAxEqqjmZN/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.YW7HwvDW5a/Listeners TMPDIR=/var/folders/9_/bq7njmms6gbgk1ygh6pll6r400000w/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.005043 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-29 20:07:57.935558) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-29 20:07:57.935881) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-29 20:07:57.936206) ========= ========= Total master_lag: 0.28 =========