builder: mozilla-central_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0214 starttime: 1482465295.59 results: success (0) buildid: 20161222183754 builduid: dee82441c0744801b44dbb935098a21e revision: 2785aaf276ba29fb2e1f5607d90d441fee42efb4 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-12-22 19:54:55.592380) ========= master: http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-12-22 19:54:55.592939) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-22 19:54:55.593264) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-22 19:54:55.620543) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-22 19:54:55.620920) ========= 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.PvqjkwyGji/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.lic5rMQcuM/Listeners TMPDIR=/var/folders/m5/r6dgwqsj10g93g673s3k_wyc00000w/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-22 19:54:55-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 18.8M=0.001s 2016-12-22 19:54:56 (18.8 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.635297 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-22 19:54:56.280759) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-22 19:54:56.281139) ========= 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.PvqjkwyGji/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.lic5rMQcuM/Listeners TMPDIR=/var/folders/m5/r6dgwqsj10g93g673s3k_wyc00000w/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.025416 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-22 19:54:56.330049) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-22 19:54:56.330431) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 2785aaf276ba29fb2e1f5607d90d441fee42efb4 --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 2785aaf276ba29fb2e1f5607d90d441fee42efb4 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.PvqjkwyGji/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.lic5rMQcuM/Listeners TMPDIR=/var/folders/m5/r6dgwqsj10g93g673s3k_wyc00000w/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-22 19:54:56,427 truncating revision to first 12 chars 2016-12-22 19:54:56,427 Setting DEBUG logging. 2016-12-22 19:54:56,427 attempt 1/10 2016-12-22 19:54:56,427 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/2785aaf276ba?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-12-22 19:54:57,112 unpacking tar archive at: mozilla-central-2785aaf276ba/testing/mozharness/ program finished with exit code 0 elapsedTime=0.956717 ========= master_lag: 0.04 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-22 19:54:57.326434) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-22 19:54:57.326789) ========= script_repo_revision: 2785aaf276ba29fb2e1f5607d90d441fee42efb4 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-22 19:54:57.327269) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-22 19:54:57.327590) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-22 19:54:57.347811) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 5 secs) (at 2016-12-22 19:54:57.348181) ========= /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.PvqjkwyGji/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.lic5rMQcuM/Listeners TMPDIR=/var/folders/m5/r6dgwqsj10g93g673s3k_wyc00000w/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:54:57 INFO - MultiFileLogger online at 20161222 19:54:57 in /builds/slave/test 19:54:57 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:54:57 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 19:54:57 INFO - {'append_to_log': False, 19:54:57 INFO - 'base_work_dir': '/builds/slave/test', 19:54:57 INFO - 'blob_upload_branch': 'Firefox', 19:54:57 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 19:54:57 INFO - 'branch': 'Firefox', 19:54:57 INFO - 'buildbot_json_path': 'buildprops.json', 19:54:57 INFO - 'config_files': ('talos/mac_config.py',), 19:54:57 INFO - 'default_actions': ('clobber', 19:54:57 INFO - 'read-buildbot-config', 19:54:57 INFO - 'download-and-extract', 19:54:57 INFO - 'populate-webroot', 19:54:57 INFO - 'create-virtualenv', 19:54:57 INFO - 'install', 19:54:57 INFO - 'run-tests'), 19:54:57 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 19:54:57 INFO - 'download_minidump_stackwalk': True, 19:54:57 INFO - 'download_symbols': 'ondemand', 19:54:57 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 19:54:57 INFO - 'tooltool.py': '/tools/tooltool.py', 19:54:57 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 19:54:57 INFO - '/tools/misc-python/virtualenv.py')}, 19:54:57 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 19:54:57 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 19:54:57 INFO - 'installer_path': 'installer.exe', 19:54:57 INFO - 'log_level': 'info', 19:54:57 INFO - 'log_name': 'talos', 19:54:57 INFO - 'log_to_console': True, 19:54:57 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 19:54:57 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 19:54:57 INFO - 'opt_config_files': (), 19:54:57 INFO - 'pip_index': False, 19:54:57 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 19:54:57 INFO - 'cmd': ('bash', 19:54:57 INFO - '-c', 19:54:57 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 19:54:57 INFO - 'enabled': True, 19:54:57 INFO - 'halt_on_failure': False, 19:54:57 INFO - 'name': 'check_screen_resolution'},), 19:54:57 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 19:54:57 INFO - 'cmd': ('bash', 19:54:57 INFO - '-c', 19:54:57 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 19:54:57 INFO - 'enabled': True, 19:54:57 INFO - 'halt_on_failure': False, 19:54:57 INFO - 'name': 'check_screen_resolution'},), 19:54:57 INFO - 'run_cmd_checks_enabled': True, 19:54:57 INFO - 'sps_profile': False, 19:54:57 INFO - 'sps_profile_interval': 0, 19:54:57 INFO - 'suite': 'dromaeojs', 19:54:57 INFO - 'system_bits': '32', 19:54:57 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 19:54:57 INFO - 'title': 't-yosemite-r7-0214', 19:54:57 INFO - 'tooltool_cache': '/builds/tooltool_cache', 19:54:57 INFO - 'use_talos_json': True, 19:54:57 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 19:54:57 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 19:54:57 INFO - 'work_dir': 'build'} 19:54:57 INFO - [mozharness: 2016-12-23 03:54:57.549976Z] Running clobber step. 19:54:57 INFO - Running pre-action listener: _resource_record_pre_action 19:54:57 INFO - Running main action method: clobber 19:54:57 INFO - rmtree: /builds/slave/test/build 19:54:57 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 19:54:59 INFO - Running post-action listener: _resource_record_post_action 19:54:59 INFO - [mozharness: 2016-12-23 03:54:59.972295Z] Finished clobber step (success) 19:54:59 INFO - [mozharness: 2016-12-23 03:54:59.972527Z] Running read-buildbot-config step. 19:54:59 INFO - Running pre-action listener: _resource_record_pre_action 19:54:59 INFO - Running main action method: read_buildbot_config 19:54:59 INFO - Using buildbot properties: 19:54:59 INFO - { 19:54:59 INFO - "project": "", 19:54:59 INFO - "product": "firefox", 19:54:59 INFO - "script_repo_revision": "production", 19:54:59 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs", 19:54:59 INFO - "repository": "", 19:54:59 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs", 19:54:59 INFO - "buildid": "20161222183754", 19:54:59 INFO - "pgo_build": "False", 19:54:59 INFO - "basedir": "/builds/slave/test", 19:54:59 INFO - "buildnumber": 12, 19:54:59 INFO - "slavename": "t-yosemite-r7-0214", 19:54:59 INFO - "master": "http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/", 19:54:59 INFO - "platform": "yosemite_r7", 19:54:59 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 19:54:59 INFO - "branch": "mozilla-central", 19:54:59 INFO - "repo_path": "mozilla-central", 19:54:59 INFO - "revision": "2785aaf276ba29fb2e1f5607d90d441fee42efb4", 19:54:59 INFO - "stage_platform": "macosx64", 19:54:59 INFO - "builduid": "dee82441c0744801b44dbb935098a21e", 19:54:59 INFO - "slavebuilddir": "test" 19:54:59 INFO - } 19:54:59 INFO - Found installer url https://queue.taskcluster.net/v1/task/L6HAsoCJSeOLxQK9LQEUjw/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg. 19:54:59 INFO - Running post-action listener: _resource_record_post_action 19:54:59 INFO - [mozharness: 2016-12-23 03:54:59.978231Z] Finished read-buildbot-config step (success) 19:54:59 INFO - [mozharness: 2016-12-23 03:54:59.978437Z] Running download-and-extract step. 19:54:59 INFO - Running pre-action listener: _resource_record_pre_action 19:54:59 INFO - Running main action method: download_and_extract 19:54:59 INFO - mkdir: /builds/slave/test/build/tests 19:54:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:54:59 INFO - trying https://queue.taskcluster.net/v1/task/L6HAsoCJSeOLxQK9LQEUjw/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json 19:54:59 INFO - Downloading https://queue.taskcluster.net/v1/task/L6HAsoCJSeOLxQK9LQEUjw/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:54:59 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/L6HAsoCJSeOLxQK9LQEUjw/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:55:00 INFO - Downloaded 1199 bytes. 19:55:00 INFO - Reading from file /builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json 19:55:00 INFO - Using the following test package requirements: 19:55:00 INFO - {u'common': [u'firefox-53.0a1.en-US.mac.common.tests.zip'], 19:55:00 INFO - u'cppunittest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 19:55:00 INFO - u'firefox-53.0a1.en-US.mac.cppunittest.tests.zip'], 19:55:00 INFO - u'gtest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 19:55:00 INFO - u'firefox-53.0a1.en-US.mac.gtest.tests.zip'], 19:55:00 INFO - u'jittest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 19:55:00 INFO - u'jsshell-mac.zip'], 19:55:00 INFO - u'mochitest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 19:55:00 INFO - u'firefox-53.0a1.en-US.mac.mochitest.tests.zip'], 19:55:00 INFO - u'mozbase': [u'firefox-53.0a1.en-US.mac.common.tests.zip'], 19:55:00 INFO - u'reftest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 19:55:00 INFO - u'firefox-53.0a1.en-US.mac.reftest.tests.zip'], 19:55:00 INFO - u'talos': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 19:55:00 INFO - u'firefox-53.0a1.en-US.mac.talos.tests.zip'], 19:55:00 INFO - u'web-platform': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 19:55:00 INFO - u'firefox-53.0a1.en-US.mac.web-platform.tests.zip'], 19:55:00 INFO - u'xpcshell': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 19:55:00 INFO - u'firefox-53.0a1.en-US.mac.xpcshell.tests.zip']} 19:55:00 INFO - Downloading packages: [u'firefox-53.0a1.en-US.mac.common.tests.zip'] for test suite category: common 19:55:00 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/L6HAsoCJSeOLxQK9LQEUjw/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip 19:55:00 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/L6HAsoCJSeOLxQK9LQEUjw/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip'}, attempt #1 19:55:00 INFO - Fetch https://queue.taskcluster.net/v1/task/L6HAsoCJSeOLxQK9LQEUjw/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip into memory 19:55:01 INFO - Expected file size: 16289480 19:55:01 INFO - Obtained file size: 16289480 19:55:08 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:55:08 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/L6HAsoCJSeOLxQK9LQEUjw/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip 19:55:08 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/L6HAsoCJSeOLxQK9LQEUjw/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip'}, attempt #1 19:55:08 INFO - Fetch https://queue.taskcluster.net/v1/task/L6HAsoCJSeOLxQK9LQEUjw/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip into memory 19:55:09 INFO - Expected file size: 16289480 19:55:09 INFO - Obtained file size: 16289480 19:55:15 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/L6HAsoCJSeOLxQK9LQEUjw/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip 19:55:15 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/L6HAsoCJSeOLxQK9LQEUjw/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip'}, attempt #1 19:55:15 INFO - Fetch https://queue.taskcluster.net/v1/task/L6HAsoCJSeOLxQK9LQEUjw/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip into memory 19:55:18 INFO - Expected file size: 14409776 19:55:18 INFO - Obtained file size: 14409776 19:55: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:55:18 INFO - trying https://queue.taskcluster.net/v1/task/L6HAsoCJSeOLxQK9LQEUjw/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 19:55:18 INFO - Downloading https://queue.taskcluster.net/v1/task/L6HAsoCJSeOLxQK9LQEUjw/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg 19:55:18 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/L6HAsoCJSeOLxQK9LQEUjw/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:55:20 INFO - Downloaded 69691622 bytes. 19:55:20 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/L6HAsoCJSeOLxQK9LQEUjw/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 19:55:20 INFO - mkdir: /builds/slave/test/properties 19:55:20 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 19:55:20 INFO - Writing to file /builds/slave/test/properties/build_url 19:55:20 INFO - Contents: 19:55:20 INFO - build_url:https://queue.taskcluster.net/v1/task/L6HAsoCJSeOLxQK9LQEUjw/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 19:55:21 INFO - Running post-action listener: _resource_record_post_action 19:55:21 INFO - Running post-action listener: set_extra_try_arguments 19:55:21 INFO - [mozharness: 2016-12-23 03:55:21.413754Z] Finished download-and-extract step (success) 19:55:21 INFO - [mozharness: 2016-12-23 03:55:21.413889Z] Running populate-webroot step. 19:55:21 INFO - Running pre-action listener: _resource_record_pre_action 19:55:21 INFO - Running main action method: populate_webroot 19:55:21 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 19:55:21 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 19:55:21 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 19:55:21 INFO - '--tppagecycles', 19:55:21 INFO - '7'], 19:55:21 INFO - 'tests': ['tsvgm']}}, 19:55:21 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 19:55:21 INFO - 'tests': ['tresize', 'tcanvasmark']}, 19:55:21 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 19:55:21 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 19:55:21 INFO - 'tests': ['dromaeo_css', 'kraken']}, 19:55:21 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 19:55:21 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 19:55:21 INFO - 'talos_options': ['--disable-e10s'], 19:55:21 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 19:55:21 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 19:55:21 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 19:55:21 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 19:55:21 INFO - 'talos_options': ['--disable-e10s'], 19:55:21 INFO - 'tests': ['damp', 'tps']}, 19:55:21 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 19:55:21 INFO - 'tests': ['damp', 'tps']}, 19:55:21 INFO - 'g3': {'talos_options': ['--disable-e10s'], 19:55:21 INFO - 'tests': ['dromaeo_dom']}, 19:55:21 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 19:55:21 INFO - 'g4': {'talos_options': ['--disable-e10s'], 19:55:21 INFO - 'tests': ['basic_compositor_video']}, 19:55:21 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 19:55:21 INFO - 'other': {'talos_options': ['--disable-e10s'], 19:55:21 INFO - 'tests': ['a11yr', 19:55:21 INFO - 'ts_paint', 19:55:21 INFO - 'tpaint', 19:55:21 INFO - 'sessionrestore', 19:55:21 INFO - 'sessionrestore_no_auto_restore', 19:55:21 INFO - 'tabpaint']}, 19:55:21 INFO - 'other-e10s': {'tests': ['a11yr', 19:55:21 INFO - 'ts_paint', 19:55:21 INFO - 'tpaint', 19:55:21 INFO - 'sessionrestore', 19:55:21 INFO - 'sessionrestore_no_auto_restore', 19:55:21 INFO - 'tabpaint']}, 19:55:21 INFO - 'other-e10s_l64': {'tests': ['a11yr', 19:55:21 INFO - 'ts_paint', 19:55:21 INFO - 'tpaint', 19:55:21 INFO - 'sessionrestore', 19:55:21 INFO - 'sessionrestore_no_auto_restore', 19:55:21 INFO - 'tabpaint']}, 19:55:21 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 19:55:21 INFO - 'ts_paint', 19:55:21 INFO - 'tpaint', 19:55:21 INFO - 'sessionrestore', 19:55:21 INFO - 'sessionrestore_no_auto_restore', 19:55:21 INFO - 'tabpaint']}, 19:55:21 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 19:55:21 INFO - 'tests': ['a11yr', 19:55:21 INFO - 'ts_paint', 19:55:21 INFO - 'tpaint', 19:55:21 INFO - 'sessionrestore', 19:55:21 INFO - 'sessionrestore_no_auto_restore', 19:55:21 INFO - 'tabpaint']}, 19:55:21 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 19:55:21 INFO - 'tests': ['a11yr', 19:55:21 INFO - 'ts_paint', 19:55:21 INFO - 'tpaint', 19:55:21 INFO - 'sessionrestore', 19:55:21 INFO - 'sessionrestore_no_auto_restore', 19:55:21 INFO - 'tabpaint']}, 19:55:21 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 19:55:21 INFO - 'tests': ['tsvgx', 19:55:21 INFO - 'tsvgr_opacity', 19:55:21 INFO - 'tart', 19:55:21 INFO - 'tscrollx', 19:55:21 INFO - 'cart', 19:55:21 INFO - 'tsvg_static']}, 19:55:21 INFO - 'svgr-e10s': {'tests': ['tsvgx', 19:55:21 INFO - 'tsvgr_opacity', 19:55:21 INFO - 'tart', 19:55:21 INFO - 'tscrollx', 19:55:21 INFO - 'cart', 19:55:21 INFO - 'tsvg_static']}, 19:55:21 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 19:55:21 INFO - 'talos_options': ['--disable-e10s'], 19:55:21 INFO - 'tests': ['tp5o']}, 19:55:21 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 19:55:21 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 19:55:21 INFO - 'talos_options': ['--disable-e10s', 19:55:21 INFO - '--xperf_path', 19:55:21 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 19:55:21 INFO - 'tests': ['tp5n']}, 19:55:21 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 19:55:21 INFO - 'talos_options': ['--xperf_path', 19:55:21 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 19:55:21 INFO - 'tests': ['tp5n']}}, 19:55:21 INFO - 'talos.zip': {'path': '', 19:55:21 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 19:55:21 INFO - Running post-action listener: _resource_record_post_action 19:55:21 INFO - [mozharness: 2016-12-23 03:55:21.432391Z] Finished populate-webroot step (success) 19:55:21 INFO - [mozharness: 2016-12-23 03:55:21.432483Z] Running create-virtualenv step. 19:55:21 INFO - Running pre-action listener: _resource_record_pre_action 19:55:21 INFO - Running main action method: create_virtualenv 19:55:21 INFO - Creating virtualenv /builds/slave/test/build/venv 19:55:21 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:55:21 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 19:55:21 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 19:55:21 INFO - Using real prefix '/tools/python27' 19:55:21 INFO - New python executable in /builds/slave/test/build/venv/bin/python 19:55:22 INFO - Installing distribute.............................................................................................................................................................................................done. 19:55:25 INFO - Installing pip.................done. 19:55:25 INFO - Return code: 0 19:55:25 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 19:55:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 19:55:25 INFO - Reading from file tmpfile_stdout 19:55:25 INFO - Output received: 19:55:25 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:55:25 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 19:55:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:55:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:55:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:55:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:55:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:55:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:55:25 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x107d4ab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107d4d768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc889cb7040>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107b28d30>, '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 0x107a4fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107b1dbe8>, '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.PvqjkwyGji/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.lic5rMQcuM/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/m5/r6dgwqsj10g93g673s3k_wyc00000w/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:55:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 19:55:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 19:55:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.PvqjkwyGji/Render', 19:55:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:55:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:55:25 INFO - 'HOME': '/Users/cltbld', 19:55:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:55:25 INFO - 'LOGNAME': 'cltbld', 19:55:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:55:25 INFO - 'MOZ_NO_REMOTE': '1', 19:55:25 INFO - 'NO_EM_RESTART': '1', 19:55:25 INFO - 'PAGER': '/bin/cat', 19:55:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:55:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:55:25 INFO - 'PWD': '/builds/slave/test', 19:55:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:55:25 INFO - 'SHELL': '/bin/bash', 19:55:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.lic5rMQcuM/Listeners', 19:55:25 INFO - 'TMPDIR': '/var/folders/m5/r6dgwqsj10g93g673s3k_wyc00000w/T/', 19:55:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:55:25 INFO - 'USER': 'cltbld', 19:55:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:55:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:55:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:55:25 INFO - 'XPC_FLAGS': '0x0', 19:55:25 INFO - 'XPC_SERVICE_NAME': '0', 19:55:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:55:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:55:25 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:55:25 INFO - Cleaning up... 19:55:25 INFO - Return code: 0 19:55:25 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 19:55:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:55:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:55:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:55:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:55:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:55:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:55:25 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x107d4ab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107d4d768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc889cb7040>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107b28d30>, '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 0x107a4fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107b1dbe8>, '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.PvqjkwyGji/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.lic5rMQcuM/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/m5/r6dgwqsj10g93g673s3k_wyc00000w/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:55:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 19:55:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 19:55:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.PvqjkwyGji/Render', 19:55:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:55:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:55:25 INFO - 'HOME': '/Users/cltbld', 19:55:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:55:25 INFO - 'LOGNAME': 'cltbld', 19:55:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:55:25 INFO - 'MOZ_NO_REMOTE': '1', 19:55:25 INFO - 'NO_EM_RESTART': '1', 19:55:25 INFO - 'PAGER': '/bin/cat', 19:55:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:55:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:55:25 INFO - 'PWD': '/builds/slave/test', 19:55:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:55:25 INFO - 'SHELL': '/bin/bash', 19:55:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.lic5rMQcuM/Listeners', 19:55:25 INFO - 'TMPDIR': '/var/folders/m5/r6dgwqsj10g93g673s3k_wyc00000w/T/', 19:55:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:55:25 INFO - 'USER': 'cltbld', 19:55:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:55:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:55:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:55:25 INFO - 'XPC_FLAGS': '0x0', 19:55:25 INFO - 'XPC_SERVICE_NAME': '0', 19:55:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:55:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:55:25 INFO - Downloading/unpacking psutil>=3.1.1 19:55:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:55:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:55:25 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:55:25 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:55:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:55:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:55:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 19:55:30 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 19:55:30 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 19:55:30 INFO - Installing collected packages: psutil 19:55:30 INFO - Running setup.py install for psutil 19:55:30 INFO - building 'psutil._psutil_osx' extension 19:55:30 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:55:30 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:55:30 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:55:30 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:55:30 INFO - building 'psutil._psutil_posix' extension 19:55:30 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:55:30 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 19:55:30 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 19:55:30 INFO - ^ 19:55:30 INFO - 1 warning generated. 19:55:30 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:55:31 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 19:55:31 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 19:55:31 INFO - Successfully installed psutil 19:55:31 INFO - Cleaning up... 19:55:31 INFO - Return code: 0 19:55:31 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 19:55:31 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:55:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:55:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:55:31 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:55:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:55:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:55:31 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 0x107d4ab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107d4d768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc889cb7040>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107b28d30>, '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 0x107a4fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107b1dbe8>, '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.PvqjkwyGji/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.lic5rMQcuM/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/m5/r6dgwqsj10g93g673s3k_wyc00000w/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:55:31 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:55:31 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:55:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.PvqjkwyGji/Render', 19:55:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:55:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:55:31 INFO - 'HOME': '/Users/cltbld', 19:55:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:55:31 INFO - 'LOGNAME': 'cltbld', 19:55:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:55:31 INFO - 'MOZ_NO_REMOTE': '1', 19:55:31 INFO - 'NO_EM_RESTART': '1', 19:55:31 INFO - 'PAGER': '/bin/cat', 19:55:31 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:55:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:55:31 INFO - 'PWD': '/builds/slave/test', 19:55:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:55:31 INFO - 'SHELL': '/bin/bash', 19:55:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.lic5rMQcuM/Listeners', 19:55:31 INFO - 'TMPDIR': '/var/folders/m5/r6dgwqsj10g93g673s3k_wyc00000w/T/', 19:55:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:55:31 INFO - 'USER': 'cltbld', 19:55:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:55:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:55:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:55:31 INFO - 'XPC_FLAGS': '0x0', 19:55:31 INFO - 'XPC_SERVICE_NAME': '0', 19:55:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:55:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:55:31 INFO - Downloading/unpacking mozsystemmonitor==0.3 19:55:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:55:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:55:31 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:55:31 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:55:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:55:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:55:34 INFO - Downloading mozsystemmonitor-0.3.tar.gz 19:55:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 19:55:34 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 19:55:34 INFO - Installing collected packages: mozsystemmonitor 19:55:34 INFO - Running setup.py install for mozsystemmonitor 19:55:34 INFO - Successfully installed mozsystemmonitor 19:55:34 INFO - Cleaning up... 19:55:34 INFO - Return code: 0 19:55:34 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 19:55:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:55:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:55:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:55:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:55:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:55:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:55:34 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 0x107d4ab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107d4d768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc889cb7040>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107b28d30>, '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 0x107a4fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107b1dbe8>, '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.PvqjkwyGji/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.lic5rMQcuM/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/m5/r6dgwqsj10g93g673s3k_wyc00000w/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:55:34 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:55:34 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:55:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.PvqjkwyGji/Render', 19:55:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:55:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:55:34 INFO - 'HOME': '/Users/cltbld', 19:55:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:55:34 INFO - 'LOGNAME': 'cltbld', 19:55:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:55:34 INFO - 'MOZ_NO_REMOTE': '1', 19:55:34 INFO - 'NO_EM_RESTART': '1', 19:55:34 INFO - 'PAGER': '/bin/cat', 19:55:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:55:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:55:34 INFO - 'PWD': '/builds/slave/test', 19:55:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:55:34 INFO - 'SHELL': '/bin/bash', 19:55:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.lic5rMQcuM/Listeners', 19:55:34 INFO - 'TMPDIR': '/var/folders/m5/r6dgwqsj10g93g673s3k_wyc00000w/T/', 19:55:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:55:34 INFO - 'USER': 'cltbld', 19:55:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:55:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:55:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:55:34 INFO - 'XPC_FLAGS': '0x0', 19:55:34 INFO - 'XPC_SERVICE_NAME': '0', 19:55:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:55:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:55:34 INFO - Downloading/unpacking jsonschema==2.5.1 19:55: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:55: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:55: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:55: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:55: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:55: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:55:37 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 19:55:37 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 19:55:37 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:55:37 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:55:37 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:55:37 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:55:37 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:55:37 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:55:37 INFO - Downloading functools32-3.2.3-2.tar.gz 19:55:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 19:55:37 INFO - warning: no files found matching '*.txt' 19:55:37 INFO - no previously-included directories found matching 'build' 19:55:37 INFO - no previously-included directories found matching 'dist' 19:55:37 INFO - no previously-included directories found matching '.git*' 19:55:37 INFO - Installing collected packages: jsonschema, functools32 19:55:37 INFO - Running setup.py install for functools32 19:55:38 INFO - warning: no files found matching '*.txt' 19:55:38 INFO - no previously-included directories found matching 'build' 19:55:38 INFO - no previously-included directories found matching 'dist' 19:55:38 INFO - no previously-included directories found matching '.git*' 19:55:38 INFO - Successfully installed jsonschema functools32 19:55:38 INFO - Cleaning up... 19:55:38 INFO - Return code: 0 19:55:38 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 19:55:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:55:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:55:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:55:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:55:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:55:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:55:38 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 0x107d4ab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107d4d768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc889cb7040>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107b28d30>, '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 0x107a4fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107b1dbe8>, '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.PvqjkwyGji/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.lic5rMQcuM/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/m5/r6dgwqsj10g93g673s3k_wyc00000w/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:55:38 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:55:38 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:55:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.PvqjkwyGji/Render', 19:55:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:55:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:55:38 INFO - 'HOME': '/Users/cltbld', 19:55:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:55:38 INFO - 'LOGNAME': 'cltbld', 19:55:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:55:38 INFO - 'MOZ_NO_REMOTE': '1', 19:55:38 INFO - 'NO_EM_RESTART': '1', 19:55:38 INFO - 'PAGER': '/bin/cat', 19:55:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:55:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:55:38 INFO - 'PWD': '/builds/slave/test', 19:55:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:55:38 INFO - 'SHELL': '/bin/bash', 19:55:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.lic5rMQcuM/Listeners', 19:55:38 INFO - 'TMPDIR': '/var/folders/m5/r6dgwqsj10g93g673s3k_wyc00000w/T/', 19:55:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:55:38 INFO - 'USER': 'cltbld', 19:55:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:55:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:55:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:55:38 INFO - 'XPC_FLAGS': '0x0', 19:55:38 INFO - 'XPC_SERVICE_NAME': '0', 19:55:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:55:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:55:38 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 19:55:38 INFO - Cleaning up... 19:55:38 INFO - Return code: 0 19:55:38 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 19:55:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:55:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:55:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:55:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:55:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:55:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:55:38 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 0x107d4ab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107d4d768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc889cb7040>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107b28d30>, '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 0x107a4fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107b1dbe8>, '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.PvqjkwyGji/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.lic5rMQcuM/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/m5/r6dgwqsj10g93g673s3k_wyc00000w/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:55:38 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:55:38 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:55:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.PvqjkwyGji/Render', 19:55:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:55:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:55:38 INFO - 'HOME': '/Users/cltbld', 19:55:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:55:38 INFO - 'LOGNAME': 'cltbld', 19:55:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:55:38 INFO - 'MOZ_NO_REMOTE': '1', 19:55:38 INFO - 'NO_EM_RESTART': '1', 19:55:38 INFO - 'PAGER': '/bin/cat', 19:55:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:55:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:55:38 INFO - 'PWD': '/builds/slave/test', 19:55:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:55:38 INFO - 'SHELL': '/bin/bash', 19:55:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.lic5rMQcuM/Listeners', 19:55:38 INFO - 'TMPDIR': '/var/folders/m5/r6dgwqsj10g93g673s3k_wyc00000w/T/', 19:55:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:55:38 INFO - 'USER': 'cltbld', 19:55:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:55:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:55:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:55:38 INFO - 'XPC_FLAGS': '0x0', 19:55:38 INFO - 'XPC_SERVICE_NAME': '0', 19:55:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:55:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:55:38 INFO - Downloading/unpacking blobuploader==1.2.4 19:55:38 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:55:38 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:55:38 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:55:38 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:55:38 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:55:38 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:55:41 INFO - Downloading blobuploader-1.2.4.tar.gz 19:55:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 19:55:41 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 19:55:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:55:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:55:41 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:55:41 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:55:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:55:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:55:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 19:55:42 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 19:55:42 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:55:42 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:55:42 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:55:42 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:55:42 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:55:42 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:55:42 INFO - Downloading docopt-0.6.1.tar.gz 19:55:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 19:55:42 INFO - Installing collected packages: blobuploader, requests, docopt 19:55:42 INFO - Running setup.py install for blobuploader 19:55:42 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 19:55:42 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 19:55:42 INFO - Running setup.py install for requests 19:55:43 INFO - Running setup.py install for docopt 19:55:43 INFO - Successfully installed blobuploader requests docopt 19:55:43 INFO - Cleaning up... 19:55:43 INFO - Return code: 0 19:55:43 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:55:43 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:55:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:55:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:55:43 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:55:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:55:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:55:43 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 0x107d4ab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107d4d768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc889cb7040>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107b28d30>, '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 0x107a4fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107b1dbe8>, '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.PvqjkwyGji/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.lic5rMQcuM/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/m5/r6dgwqsj10g93g673s3k_wyc00000w/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:55:43 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:55:43 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:55:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.PvqjkwyGji/Render', 19:55:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:55:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:55:43 INFO - 'HOME': '/Users/cltbld', 19:55:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:55:43 INFO - 'LOGNAME': 'cltbld', 19:55:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:55:43 INFO - 'MOZ_NO_REMOTE': '1', 19:55:43 INFO - 'NO_EM_RESTART': '1', 19:55:43 INFO - 'PAGER': '/bin/cat', 19:55:43 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:55:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:55:43 INFO - 'PWD': '/builds/slave/test', 19:55:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:55:43 INFO - 'SHELL': '/bin/bash', 19:55:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.lic5rMQcuM/Listeners', 19:55:43 INFO - 'TMPDIR': '/var/folders/m5/r6dgwqsj10g93g673s3k_wyc00000w/T/', 19:55:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:55:43 INFO - 'USER': 'cltbld', 19:55:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:55:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:55:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:55:43 INFO - 'XPC_FLAGS': '0x0', 19:55:43 INFO - 'XPC_SERVICE_NAME': '0', 19:55:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:55:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:55:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 19:55:43 INFO - Running setup.py (path:/var/folders/m5/r6dgwqsj10g93g673s3k_wyc00000w/T/pip-WVNQie-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 19:55:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 19:55:43 INFO - Running setup.py (path:/var/folders/m5/r6dgwqsj10g93g673s3k_wyc00000w/T/pip-INXbuv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 19:55:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 19:55:44 INFO - Running setup.py (path:/var/folders/m5/r6dgwqsj10g93g673s3k_wyc00000w/T/pip-yaSjiQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 19:55:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 19:55:44 INFO - Running setup.py (path:/var/folders/m5/r6dgwqsj10g93g673s3k_wyc00000w/T/pip-CA2RlZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 19:55:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 19:55:44 INFO - Running setup.py (path:/var/folders/m5/r6dgwqsj10g93g673s3k_wyc00000w/T/pip-PA5xO4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 19:55:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 19:55:44 INFO - Running setup.py (path:/var/folders/m5/r6dgwqsj10g93g673s3k_wyc00000w/T/pip-u91Fbj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 19:55:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 19:55:44 INFO - Running setup.py (path:/var/folders/m5/r6dgwqsj10g93g673s3k_wyc00000w/T/pip-oSxheK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 19:55:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 19:55:44 INFO - Running setup.py (path:/var/folders/m5/r6dgwqsj10g93g673s3k_wyc00000w/T/pip-oL7C5S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 19:55:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 19:55:44 INFO - Running setup.py (path:/var/folders/m5/r6dgwqsj10g93g673s3k_wyc00000w/T/pip-mTkgvz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 19:55:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 19:55:44 INFO - Running setup.py (path:/var/folders/m5/r6dgwqsj10g93g673s3k_wyc00000w/T/pip-oMryNR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 19:55:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 19:55:44 INFO - Running setup.py (path:/var/folders/m5/r6dgwqsj10g93g673s3k_wyc00000w/T/pip-H4Th36-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 19:55:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 19:55:45 INFO - Running setup.py (path:/var/folders/m5/r6dgwqsj10g93g673s3k_wyc00000w/T/pip-0_HrtY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 19:55:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 19:55:45 INFO - Running setup.py (path:/var/folders/m5/r6dgwqsj10g93g673s3k_wyc00000w/T/pip-nghuDp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 19:55:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 19:55:45 INFO - Running setup.py (path:/var/folders/m5/r6dgwqsj10g93g673s3k_wyc00000w/T/pip-lUpWhD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 19:55:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 19:55:45 INFO - Running setup.py (path:/var/folders/m5/r6dgwqsj10g93g673s3k_wyc00000w/T/pip-OutZsg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 19:55:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 19:55:45 INFO - Running setup.py (path:/var/folders/m5/r6dgwqsj10g93g673s3k_wyc00000w/T/pip-Vc_h80-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 19:55:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 19:55:45 INFO - Running setup.py (path:/var/folders/m5/r6dgwqsj10g93g673s3k_wyc00000w/T/pip-aHruUg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 19:55:45 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 19:55:45 INFO - Running setup.py install for manifestparser 19:55:45 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 19:55:45 INFO - Running setup.py install for mozcrash 19:55:45 INFO - Running setup.py install for mozdebug 19:55:46 INFO - Running setup.py install for mozdevice 19:55:46 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 19:55:46 INFO - Installing dm script to /builds/slave/test/build/venv/bin 19:55:46 INFO - Running setup.py install for mozfile 19:55:46 INFO - Running setup.py install for mozhttpd 19:55:46 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 19:55:46 INFO - Running setup.py install for mozinfo 19:55:46 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 19:55:46 INFO - Running setup.py install for mozInstall 19:55:46 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 19:55:46 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 19:55:46 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 19:55:46 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 19:55:46 INFO - Running setup.py install for mozleak 19:55:46 INFO - Running setup.py install for mozlog 19:55:47 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 19:55:47 INFO - Running setup.py install for moznetwork 19:55:47 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 19:55:47 INFO - Running setup.py install for mozprocess 19:55:47 INFO - Running setup.py install for mozprofile 19:55:47 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 19:55:47 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 19:55:47 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 19:55:47 INFO - Running setup.py install for mozrunner 19:55:47 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 19:55:47 INFO - Running setup.py install for mozscreenshot 19:55:47 INFO - Running setup.py install for moztest 19:55:48 INFO - Running setup.py install for mozversion 19:55:48 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 19:55:48 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 19:55:48 INFO - Cleaning up... 19:55:48 INFO - Return code: 0 19:55:48 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:55:48 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:55:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:55:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:55:48 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:55:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:55:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:55:48 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 0x107d4ab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107d4d768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc889cb7040>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107b28d30>, '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 0x107a4fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107b1dbe8>, '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.PvqjkwyGji/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.lic5rMQcuM/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/m5/r6dgwqsj10g93g673s3k_wyc00000w/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:55:48 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:55:48 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:55:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.PvqjkwyGji/Render', 19:55:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:55:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:55:48 INFO - 'HOME': '/Users/cltbld', 19:55:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:55:48 INFO - 'LOGNAME': 'cltbld', 19:55:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:55:48 INFO - 'MOZ_NO_REMOTE': '1', 19:55:48 INFO - 'NO_EM_RESTART': '1', 19:55:48 INFO - 'PAGER': '/bin/cat', 19:55:48 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:55:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:55:48 INFO - 'PWD': '/builds/slave/test', 19:55:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:55:48 INFO - 'SHELL': '/bin/bash', 19:55:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.lic5rMQcuM/Listeners', 19:55:48 INFO - 'TMPDIR': '/var/folders/m5/r6dgwqsj10g93g673s3k_wyc00000w/T/', 19:55:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:55:48 INFO - 'USER': 'cltbld', 19:55:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:55:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:55:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:55:48 INFO - 'XPC_FLAGS': '0x0', 19:55:48 INFO - 'XPC_SERVICE_NAME': '0', 19:55:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:55:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:55:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 19:55:48 INFO - Running setup.py (path:/var/folders/m5/r6dgwqsj10g93g673s3k_wyc00000w/T/pip-gblrnW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 19:55:48 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:55:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 19:55:48 INFO - Running setup.py (path:/var/folders/m5/r6dgwqsj10g93g673s3k_wyc00000w/T/pip-XTTQIi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 19:55:48 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:55:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 19:55:48 INFO - Running setup.py (path:/var/folders/m5/r6dgwqsj10g93g673s3k_wyc00000w/T/pip-jLybuG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 19:55:48 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:55:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 19:55:48 INFO - Running setup.py (path:/var/folders/m5/r6dgwqsj10g93g673s3k_wyc00000w/T/pip-Dzp2z3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 19:55:48 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:55:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 19:55:48 INFO - Running setup.py (path:/var/folders/m5/r6dgwqsj10g93g673s3k_wyc00000w/T/pip-a4CjzP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 19:55:49 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:55:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 19:55:49 INFO - Running setup.py (path:/var/folders/m5/r6dgwqsj10g93g673s3k_wyc00000w/T/pip-nNCVb3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 19:55:49 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:55:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 19:55:49 INFO - Running setup.py (path:/var/folders/m5/r6dgwqsj10g93g673s3k_wyc00000w/T/pip-UhtUu_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 19:55:49 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:55:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 19:55:49 INFO - Running setup.py (path:/var/folders/m5/r6dgwqsj10g93g673s3k_wyc00000w/T/pip-Typ_qa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 19:55:49 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:55:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 19:55:49 INFO - Running setup.py (path:/var/folders/m5/r6dgwqsj10g93g673s3k_wyc00000w/T/pip-P7i7IK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 19:55:49 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:55:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 19:55:49 INFO - Running setup.py (path:/var/folders/m5/r6dgwqsj10g93g673s3k_wyc00000w/T/pip-KCKxM4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 19:55:49 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:55:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 19:55:49 INFO - Running setup.py (path:/var/folders/m5/r6dgwqsj10g93g673s3k_wyc00000w/T/pip-T0XudH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 19:55:49 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:55:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 19:55:49 INFO - Running setup.py (path:/var/folders/m5/r6dgwqsj10g93g673s3k_wyc00000w/T/pip-ty7Yw5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 19:55:49 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:55:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 19:55:49 INFO - Running setup.py (path:/var/folders/m5/r6dgwqsj10g93g673s3k_wyc00000w/T/pip-7BtBVo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 19:55:49 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:55:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 19:55:49 INFO - Running setup.py (path:/var/folders/m5/r6dgwqsj10g93g673s3k_wyc00000w/T/pip-wH2xi4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 19:55:50 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:55:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 19:55:50 INFO - Running setup.py (path:/var/folders/m5/r6dgwqsj10g93g673s3k_wyc00000w/T/pip-5AQSQY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 19:55:50 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:55:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 19:55:50 INFO - Running setup.py (path:/var/folders/m5/r6dgwqsj10g93g673s3k_wyc00000w/T/pip-FnqwOx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 19:55:50 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 19:55:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 19:55:50 INFO - Running setup.py (path:/var/folders/m5/r6dgwqsj10g93g673s3k_wyc00000w/T/pip-EnV2BP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 19:55:50 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:55:50 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:55:50 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:55:50 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:55:50 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:55:50 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:55:50 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 19:55:50 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:55:50 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:55:50 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:55:50 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:55:50 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:55:50 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:55:52 INFO - Downloading blessings-1.6.tar.gz 19:55:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 19:55:53 INFO - Installing collected packages: blessings 19:55:53 INFO - Running setup.py install for blessings 19:55:53 INFO - Successfully installed blessings 19:55:53 INFO - Cleaning up... 19:55:53 INFO - Return code: 0 19:55:53 INFO - Done creating virtualenv /builds/slave/test/build/venv. 19:55:53 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 19:55:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 19:55:53 INFO - Reading from file tmpfile_stdout 19:55:53 INFO - Current package versions: 19:55:53 INFO - blessings == 1.6 19:55:53 INFO - blobuploader == 1.2.4 19:55:53 INFO - docopt == 0.6.1 19:55:53 INFO - functools32 == 3.2.3-2 19:55:53 INFO - jsonschema == 2.5.1 19:55:53 INFO - manifestparser == 1.1 19:55:53 INFO - mozInstall == 1.12 19:55:53 INFO - mozcrash == 1.0 19:55:53 INFO - mozdebug == 0.1 19:55:53 INFO - mozdevice == 0.49 19:55:53 INFO - mozfile == 1.2 19:55:53 INFO - mozhttpd == 0.7 19:55:53 INFO - mozinfo == 0.9 19:55:53 INFO - mozleak == 0.1 19:55:53 INFO - mozlog == 3.4 19:55:53 INFO - moznetwork == 0.27 19:55:53 INFO - mozprocess == 0.23 19:55:53 INFO - mozprofile == 0.28 19:55:53 INFO - mozrunner == 6.13 19:55:53 INFO - mozscreenshot == 0.1 19:55:53 INFO - mozsystemmonitor == 0.3 19:55:53 INFO - moztest == 0.7 19:55:53 INFO - mozversion == 1.4 19:55:53 INFO - psutil == 3.1.1 19:55:53 INFO - requests == 1.2.3 19:55:53 INFO - wsgiref == 0.1.2 19:55:53 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:55:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:55:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:55:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:55:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:55:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:55:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:55:53 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 0x107d4ab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107d4d768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc889cb7040>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107b28d30>, '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 0x107a4fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107b1dbe8>, '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.PvqjkwyGji/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.lic5rMQcuM/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/m5/r6dgwqsj10g93g673s3k_wyc00000w/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:55:53 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:55:53 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:55:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.PvqjkwyGji/Render', 19:55:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:55:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:55:53 INFO - 'HOME': '/Users/cltbld', 19:55:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:55:53 INFO - 'LOGNAME': 'cltbld', 19:55:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:55:53 INFO - 'MOZ_NO_REMOTE': '1', 19:55:53 INFO - 'NO_EM_RESTART': '1', 19:55:53 INFO - 'PAGER': '/bin/cat', 19:55:53 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:55:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:55:53 INFO - 'PWD': '/builds/slave/test', 19:55:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:55:53 INFO - 'SHELL': '/bin/bash', 19:55:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.lic5rMQcuM/Listeners', 19:55:53 INFO - 'TMPDIR': '/var/folders/m5/r6dgwqsj10g93g673s3k_wyc00000w/T/', 19:55:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:55:53 INFO - 'USER': 'cltbld', 19:55:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:55:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:55:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:55:53 INFO - 'XPC_FLAGS': '0x0', 19:55:53 INFO - 'XPC_SERVICE_NAME': '0', 19:55:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:55:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:55:53 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:55:53 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:55:53 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:55:53 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:55:53 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:55:53 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:55:53 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:55:53 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:55:53 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:55:53 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:55:53 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:55:53 INFO - Cleaning up... 19:55:53 INFO - Return code: 0 19:55:53 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 19:55:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:55:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:55:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:55:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:55:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:55:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:55:53 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 0x107d4ab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107d4d768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc889cb7040>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107b28d30>, '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 0x107a4fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107b1dbe8>, '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.PvqjkwyGji/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.lic5rMQcuM/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/m5/r6dgwqsj10g93g673s3k_wyc00000w/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:55:53 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:55:53 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:55:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.PvqjkwyGji/Render', 19:55:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:55:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:55:53 INFO - 'HOME': '/Users/cltbld', 19:55:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:55:53 INFO - 'LOGNAME': 'cltbld', 19:55:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:55:53 INFO - 'MOZ_NO_REMOTE': '1', 19:55:53 INFO - 'NO_EM_RESTART': '1', 19:55:53 INFO - 'PAGER': '/bin/cat', 19:55:53 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:55:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:55:53 INFO - 'PWD': '/builds/slave/test', 19:55:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:55:53 INFO - 'SHELL': '/bin/bash', 19:55:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.lic5rMQcuM/Listeners', 19:55:53 INFO - 'TMPDIR': '/var/folders/m5/r6dgwqsj10g93g673s3k_wyc00000w/T/', 19:55:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:55:53 INFO - 'USER': 'cltbld', 19:55:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:55:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:55:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:55:53 INFO - 'XPC_FLAGS': '0x0', 19:55:53 INFO - 'XPC_SERVICE_NAME': '0', 19:55:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:55:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:55:54 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 19:55:54 INFO - Cleaning up... 19:55:54 INFO - Return code: 0 19:55:54 INFO - Running post-action listener: _resource_record_post_action 19:55:54 INFO - Running post-action listener: _start_resource_monitoring 19:55:54 INFO - Starting resource monitoring. 19:55:54 INFO - [mozharness: 2016-12-23 03:55:54.218437Z] Finished create-virtualenv step (success) 19:55:54 INFO - [mozharness: 2016-12-23 03:55:54.219471Z] Running install step. 19:55:54 INFO - Running pre-action listener: _resource_record_pre_action 19:55:54 INFO - Running main action method: install 19:55:54 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 19:55:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 19:55:54 INFO - Reading from file tmpfile_stdout 19:55:54 INFO - Detecting whether we're running mozinstall >=1.0... 19:55:54 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 19:55:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 19:55:54 INFO - Reading from file tmpfile_stdout 19:55:54 INFO - Output received: 19:55:54 INFO - Usage: mozinstall [options] installer 19:55:54 INFO - Options: 19:55:54 INFO - -h, --help show this help message and exit 19:55:54 INFO - -d DEST, --destination=DEST 19:55:54 INFO - Directory to install application into. [default: 19:55:54 INFO - "/builds/slave/test"] 19:55:54 INFO - --app=APP Application being installed. [default: firefox] 19:55:54 INFO - mkdir: /builds/slave/test/build/application 19:55:54 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:55:54 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:56:16 INFO - Reading from file tmpfile_stdout 19:56:16 INFO - Output received: 19:56:16 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 19:56:16 INFO - Running post-action listener: _resource_record_post_action 19:56:16 INFO - [mozharness: 2016-12-23 03:56:16.219626Z] Finished install step (success) 19:56:16 INFO - [mozharness: 2016-12-23 03:56:16.219764Z] Running run-tests step. 19:56:16 INFO - Running pre-action listener: _resource_record_pre_action 19:56:16 INFO - Running main action method: run_tests 19:56:16 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 19:56:16 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 19:56:16 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 19:56:16 INFO - 2016-12-22 19:56:16.234 screenresolution[1634:9211] starting screenresolution argv=screenresolution get 19:56:16 INFO - 2016-12-22 19:56:16.236 screenresolution[1634:9211] Display 0: 1600x1200x32@60 19:56:16 INFO - 2016-12-22 19:56:16.242 screenresolution[1635:9214] starting screenresolution argv=screenresolution list 19:56:16 INFO - Available Modes on Display 0 19:56:16 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 19:56:16 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 19:56:16 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 19:56:16 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 19:56:16 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 19:56:16 INFO - Intel Iris: 19:56:16 INFO - Chipset Model: Intel Iris 19:56:16 INFO - Type: GPU 19:56:16 INFO - Bus: Built-In 19:56:16 INFO - VRAM (Dynamic, Max): 1536 MB 19:56:16 INFO - Vendor: Intel (0x8086) 19:56:16 INFO - Device ID: 0x0a2e 19:56:16 INFO - Revision ID: 0x0009 19:56:16 INFO - Displays: 19:56:16 INFO - Display: 19:56:16 INFO - Resolution: 1600 x 1200 @ 60 Hz 19:56:16 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 19:56:16 INFO - Display Serial Number: 1600x1200 60 19:56:16 INFO - Main Display: Yes 19:56:16 INFO - Mirror: Off 19:56:16 INFO - Online: Yes 19:56:16 INFO - Rotation: Supported 19:56:16 INFO - Return code: 0 19:56:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 19:56:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 19:56:16 INFO - Python 2.7.3 19:56:16 INFO - Return code: 0 19:56:16 INFO - grabbing minidump binary from tooltool 19:56:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:56:16 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 0x107b28d30>, '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 0x107a4fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107b1dbe8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 19:56:16 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:56:16 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:56:16 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:56:16 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 19:56:16 INFO - Return code: 0 19:56:16 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 19:56:16 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 19:56:16 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 19:56:16 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 19:56:16 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 19:56:16 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/L6HAsoCJSeOLxQK9LQEUjw/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0214', '--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:56:16 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/L6HAsoCJSeOLxQK9LQEUjw/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0214 --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:56:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.PvqjkwyGji/Render', 19:56:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:56:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:56:16 INFO - 'HOME': '/Users/cltbld', 19:56:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:56:16 INFO - 'LOGNAME': 'cltbld', 19:56:16 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 19:56:16 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 19:56:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:56:16 INFO - 'MOZ_NO_REMOTE': '1', 19:56:16 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 19:56:16 INFO - 'NO_EM_RESTART': '1', 19:56:16 INFO - 'PAGER': '/bin/cat', 19:56:16 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:56:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:56:16 INFO - 'PWD': '/builds/slave/test', 19:56:16 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 19:56:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:56:16 INFO - 'SHELL': '/bin/bash', 19:56:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.lic5rMQcuM/Listeners', 19:56:16 INFO - 'TMPDIR': '/var/folders/m5/r6dgwqsj10g93g673s3k_wyc00000w/T/', 19:56:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:56:16 INFO - 'USER': 'cltbld', 19:56:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:56:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:56:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:56:16 INFO - 'XPC_FLAGS': '0x0', 19:56:16 INFO - 'XPC_SERVICE_NAME': '0', 19:56:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:56:16 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/L6HAsoCJSeOLxQK9LQEUjw/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0214', '--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:56:16 INFO - mozversion application_buildid: 20161222183754 19:56:16 INFO - mozversion application_changeset: 2785aaf276ba29fb2e1f5607d90d441fee42efb4 19:56:16 INFO - mozversion application_display_name: Nightly 19:56:16 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 19:56:16 INFO - mozversion application_name: Firefox 19:56:16 INFO - mozversion application_remotingname: firefox 19:56:16 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 19:56:16 INFO - mozversion application_vendor: Mozilla 19:56:16 INFO - mozversion application_version: 53.0a1 19:56:16 INFO - mozversion platform_buildid: 20161222183754 19:56:16 INFO - mozversion platform_changeset: 2785aaf276ba29fb2e1f5607d90d441fee42efb4 19:56:16 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 19:56:16 INFO - mozversion platform_version: 53.0a1 19:56:16 INFO - using testdate: 1482465376 19:56:16 INFO - actual date: 1482465376 19:56:16 INFO - starting webserver on 'localhost:49242' 19:56:16 INFO - SUITE-START | Running 2 tests 19:56:16 INFO - TEST-START | dromaeo_css 19:56:16 INFO - operating with platform_type : mac_ 19:56:16 INFO - Initialising browser for dromaeo_css test... 19:56:16 INFO - TEST-INFO | started process 1645 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/m5/r6dgwqsj10g93g673s3k_wyc00000w/T/tmpSIWApP/profile http://localhost:49242/getInfo.html) 19:56:17 INFO - PROCESS | 1645 | 1482465377373 addons.xpi WARN Exception running bootstrap method install on shield-recipe-client@mozilla.org: [Exception... "Component returned failure code: 0x8000ffff (NS_ERROR_UNEXPECTED) [nsIPrefBranch.getBoolPref]" nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js :: this.install :: line 38" data: no] Stack trace: this.install()@resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js:38 < callBootstrapMethod()@resource://gre/modules/addons/XPIProvider.jsm:4979 < processFileChanges()@resource://gre/modules/addons/XPIProvider.jsm -> resource://gre/modules/addons/XPIProviderUtils.js:2097 < checkForChanges()@resource://gre/modules/addons/XPIProvider.jsm:3825 < startup()@resource://gre/modules/addons/XPIProvider.jsm:2829 < callProvider()@resource://gre/modules/AddonManager.jsm:264 < _startProvider()@resource://gre/modules/AddonManager.jsm:771 < startup()@resource://gre/modules/AddonManager.jsm:957 < startup()@resource://gre/modules/AddonManager.jsm:2923 < observe()@resource://gre/components/addonManager.js:65 19:56:18 INFO - PROCESS | 1645 | JavaScript error: chrome://global/content/browser-content.js, line 1772: ReferenceError: event is not defined 19:56:18 INFO - PROCESS | 1645 | JavaScript error: chrome://global/content/browser-content.js, line 1772: ReferenceError: event is not defined 19:56:19 INFO - PROCESS | 1645 | JavaScript error: chrome://global/content/browser-content.js, line 1772: ReferenceError: event is not defined 19:56:19 INFO - PROCESS | 1645 | __metrics Screen width/height:1600/1200 19:56:19 INFO - PROCESS | 1645 | colorDepth:24 19:56:19 INFO - PROCESS | 1645 | Browser inner width/height: 1024/689 19:56:19 INFO - PROCESS | 1645 | __metrics 19:56:19 INFO - PROCESS | 1645 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 19:56:19 INFO - PROCESS | 1645 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 19:56:19 INFO - TEST-INFO | 1645: exit 0 19:56:19 INFO - Browser initialized. 19:56:19 INFO - Running cycle 1/1 for dromaeo_css test... 19:56:19 INFO - TEST-INFO | started process 1648 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/m5/r6dgwqsj10g93g673s3k_wyc00000w/T/tmpSIWApP/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 19:56:31 INFO - PROCESS | 1648 | RSS: Main: 235450368 19:56:31 INFO - PROCESS | 1648 | 19:57:23 INFO - PROCESS | 1648 | 1482465443272 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 19:59:37 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49242/tests/dromaeo/cssquery-ext.html) 19:59:38 INFO - PROCESS | 1648 | RSS: Main: 361132032 19:59:38 INFO - PROCESS | 1648 | 20:01:20 INFO - PROCESS | 1648 | ************************* 20:01:20 INFO - PROCESS | 1648 | A coding exception was thrown and uncaught in a Task. 20:01:20 INFO - PROCESS | 1648 | 20:01:20 INFO - PROCESS | 1648 | Full message: ReferenceError: fetch is not defined 20:01:20 INFO - PROCESS | 1648 | Full stack: apiCall@resource://shield-recipe-client/lib/NormandyApi.jsm:37:5 20:01:20 INFO - PROCESS | 1648 | get@resource://shield-recipe-client/lib/NormandyApi.jsm:44:12 20:01:20 INFO - PROCESS | 1648 | this.NormandyApi.fetchRecipes<@resource://shield-recipe-client/lib/NormandyApi.jsm:52:34 20:01:20 INFO - PROCESS | 1648 | TaskImpl_run@resource://gre/modules/Task.jsm:319:42 20:01:20 INFO - PROCESS | 1648 | TaskImpl@resource://gre/modules/Task.jsm:277:3 20:01:20 INFO - PROCESS | 1648 | asyncFunction@resource://gre/modules/Task.jsm:252:14 20:01:20 INFO - PROCESS | 1648 | this.RecipeRunner.start<@resource://shield-recipe-client/lib/RecipeRunner.jsm:64:23 20:01:20 INFO - PROCESS | 1648 | TaskImpl_run@resource://gre/modules/Task.jsm:319:42 20:01:20 INFO - PROCESS | 1648 | TaskImpl@resource://gre/modules/Task.jsm:277:3 20:01:20 INFO - PROCESS | 1648 | asyncFunction@resource://gre/modules/Task.jsm:252:14 20:01:20 INFO - PROCESS | 1648 | setTimeout_timer@resource://gre/modules/Timer.jsm:30:5 20:01:20 INFO - PROCESS | 1648 | 20:01:20 INFO - PROCESS | 1648 | ************************* 20:02:41 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-ext.html (next: http://localhost:49242/tests/dromaeo/cssquery-jquery.html) 20:02:41 INFO - PROCESS | 1648 | RSS: Main: 368361472 20:02:41 INFO - PROCESS | 1648 | 20:05:44 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49242/tests/dromaeo/cssquery-mootools.html) 20:05:44 INFO - PROCESS | 1648 | RSS: Main: 346148864 20:05:44 INFO - PROCESS | 1648 | 20:08:47 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49242/tests/dromaeo/cssquery-prototype.html) 20:08:47 INFO - PROCESS | 1648 | RSS: Main: 346980352 20:08:47 INFO - PROCESS | 1648 | 20:11:51 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49242/tests/dromaeo/cssquery-yui.html) 20:11:51 INFO - PROCESS | 1648 | RSS: Main: 333484032 20:11:51 INFO - PROCESS | 1648 | 20:14:55 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-yui.html (next: http://localhost:49242/tests/dromaeo/cssquery-dojo.html) 20:14:55 INFO - PROCESS | 1648 | RSS: Main: 453656576 20:14:55 INFO - PROCESS | 1648 | 20:14:55 INFO - PROCESS | 1648 | __start_tp_report 20:14:55 INFO - PROCESS | 1648 | _x_x_mozilla_page_load 20:14:55 INFO - PROCESS | 1648 | _x_x_mozilla_page_load_details 20:14:55 INFO - PROCESS | 1648 | |i|pagename|runs| 20:14:55 INFO - PROCESS | 1648 | |0;dojo.html;3363.663633636638;3367.8989630311094;3386.3565922474736;3407.761456698032;3427.108951672566;5558.888822223563;5649.8587535311635;5658.009848276551;5729.28383952006;5742.196092547041;5656.095024796034;5669.433056694318;5680.772769089232;5687.7156142192825;5702.1446782982575;5133.974330128344;5139.537441630253;5156.278121063054;5210.088234558956;5243.449437809032;5151.74241287936;5267.841964741045;5269.578433725312;5271.077561426754;5276.34045744282;4276;5197.558207552363;5239;5260.395054568736;5303.363596368441;5118.309028281172;5160.329157209576;5169.896602067937;5185;5212.452692467275;5157;5207.973960130175;5243.318368612056;5262.60530460217;5276.709780962084;5645.520130788888;5691.146328050784;5698.544116470673;5732.570057245724;5738.713064346766;6133.601315914451;6264.248290205159;6283.403076707706;6286.937130628727;6289.811305660837;6145.477634401037;6218.409251121137;6222.844428889269;6259.655718935457;6263.843403914893;5782.508486778587;5782.739776710058;5787.102999035156;5795.043817770063;5813;6099.875452227012;6234.189555357865;6252.843678907972;6265.373462653698;6280.748770049238;5148.9485105149215;5967.403259673998;6043.697815109227;6044.153818465418;6145.10895920098;5168.3797944246935;6249.625022498664;6259.405356491126;6263.029230469285;6275.278342990523;6071.635701857903;6107.97783795876;6214.8135555933395;6226.875462490725;6230;6137.604186954709;6143.095640351506;6199.225096862892;6204.224471941007;6222.222222222223;4886.438059623189;4931;4940.604751619861;4944.307796908436;4945.010997800454;3763.7929913854455;4366.214081465303;4466.285394336891;4482.955170448254;4484.215262329079;5761.423857614205;5781.71091445426;5782.942170578241;5788.623739456922;5791.8552036199435;6133.76314253068;6179.876402471926;6207.379262073757;6210.347913469111;6291.811245662638;5753.85615359611;5835.037218858925;5861.150133230658;5869.7945571904775;5870.618409803349;5348.91976620351;5402.189671549299;5405.837824865261;5406.405295417501;5409.621326507186;6256.687165641609;6291.905621415591;6292.307846136921;6303.527235457267;6311.2426508819235;6195;6208.9689551551955;6236.688165591793;6243.125962365181;6252.843678907881;6286.905696414649;6306.779262725782;6307.211598550181;6317.336679648571;6321.589096708699;6162.445379915829;6174.166487524127;6197.070439434121;6204.100405441276;6207.099970504161;6140.969295153495;6162.691865406801;6174.44430001302;6197.5351848610635;6290.779822706183;5935.0503919372695;6136.673645080297;6144.723487442986;6145.508359331154;6151.354107818794;6115.209918062534;6123.069106197142;6128.686460748315;6130.979101201984;6145.600535965148;5789.8552536186935;5844.415558444121;5907.3797251288;5927.288725352812;5960.612560183574;6121.818815678178;6140.688304616564;6160.075988601746;6169.56813023084;6177.444030037318;5794.884102317846;5851.64890106595;5969.313528944053;5986.281646202309;6020.548458865515 20:14:55 INFO - PROCESS | 1648 | |1;ext.html;434.558922693466;478.2969035517789;489.93385892904445;494.4659767451152;496.43406516571093;30356.241093972632;38710.80644596776;40959.38560921588;41003;41074.99999999996;42242.7887860561;42640.78679606605;42748.717538473895;42824.57175428245;42896.57103428965;8389.748307550783;8600.526971016592;9295.349325547197;9495.52522373882;9509.33434659574;9253.167214950687;11429.828552571718;11435.313881167114;11591.072714182845;11616.419179041057;11347;11393.145514086435;11418.257813242113;11426.257293275952;11441.942790286037;11250.437478126101;11311.15166362522;11311.83032254517;11315.321080735182;11330.67721117169;9456.912455067686;11137.220394572383;11243.60647377338;11250.437478126061;11260.493277802518;39475.60524394748;39718.80140599279;39746;39784.80107599472;39793.40309895354;3037.635483741943;3250.122466933938;3315.088350703552;3408.9659103409144;3596.82015899204;30452.086437406913;30625.081247562608;30893.845530772203;30902.53646195309;32075.679243207735;2580.5355036093497;3170.7304879085304;3200.807951522916;3214.051854702845;3254.886078987248;370109;499652.9999999964;501462;502198;503531.99999999633;63619.68190159019;63806.68096659487;64604.35395646077;65793;65870;70123;70666.29333706597;70788;70809;70855;15916.76124858128;16323.918380408022;16472.83527164737;16480.67038659244;16501.752473712902;11344.149188810872;11546.134039947037;11568.479418426192;12276.754464910651;12507.624771256877;19770.802291976896;20492.38522844317;20585.382438526867;20605.690914636292;20640.48398790042;6953.687084081229;7214.567125972459;8329.500229986099;8352.12302708219;8446.282066024456;853.6585365853708;927.9814403711888;930.2976252928995;963.633819148733;1032.2413026425572;26446.735532644812;27224.047158349364;28643.57034644482;28826.279343016173;29788.851055744584;1030.9845352319721;1075.9724463137684;1085.3433672628105;1096.2490693874722;1113.8663360396642;15452;15485.922570387302;15544.145072020921;15666.530004099895;15925.840741592436;564057;571564;613268;621330;622082;59209.70395147997;59943;59996.50008749816;60009.69995149996;60197.79604407975;1339.9665008374868;1356.6811799227173;1369.116919586841;1369.5206677662939;1376.2980879751399;28757.56863647089;29374.41251174922;29504.557431638113;29961.250968725955;30166.49167541658;51491.48508514867;51899;51918.96162076813;51981.7400912993;52045.21932170945;544817;547620;548062;550179;551726;34059;36368;37283.25433491369;40713.778586642446;41264.587354127274;42276;42497;42514;42563.7871810639;44932.550674492835;35814.74648387294;35958.8202058988;36950;37945.051373715876;38790;36150.63849361473;37145.81427092847;37458.812705936296;38326.80836595799;38409.807950960065;34108.82945585256;34801.78193763206;35204.76783312572;36704.265914681026;36731;28306.15081547557;30898.69101308958;31082.844585776926;31272.68727312789;31716;23462.648060278767;23570.882145589847;23806.961426165664;23919.760802392448;23968 20:14:55 INFO - PROCESS | 1648 | |2;jquery.html;368.474923234391;370.1468115992636;376.7777011563178;390.81241004317945;402.3020060195562;18609.06954652267;20316.69524957127;20572.69140962885;20750;20780.792192078094;15955.521334359988;20247.39257822264;20696.172153113894;20760.85815280159;20928.709614529023;5681.375048744645;5708.08670612702;5828.0767346775465;5831.912521312183;5956.910646340287;4090.9386359204636;5773.509251713609;5892.057270836667;5894.646321220719;5896.675682837442;4227.556106608808;4284.378765079062;4285.592868677486;4303.956960430402;4346.913061738764;6388.64862432566;6470.805875823733;6484.610923344614;6486.708098135548;6487.481001519891;5444.265024221759;5566.359868615121;5633.774649014034;5656.274836174965;5666.121751128581;25164.496710065698;25213.495730085295;25254.621180682305;25268.747312527008;25529.489410211878;2091.4144039668918;2196.5716685246384;2214.5460180662926;2296.977030229693;2908.720163128238;14561.78157327641;14901.254937253203;15205.619859503491;15320.310586023656;15592.922035389864;1621.6351320952808;1727.6890159771235;1787.3297513432462;1813.7914139873951;1826.5616252099533;100204;102886;103756;105315.47342263239;105631;9957.352772069793;10173.643922462827;10403.739906502398;10533.104686101768;10652.893471065345;12767.914727248102;12811.231326120462;13017.284049377186;13041.673958151123;13056.804147937788;7980.3615710743;8028.397870159762;8174.836503269901;8248.628811703487;8254.876176857351;8097.433179677484;8100.648308620504;8168.7957801055445;8321.04308004575;8321.334293256525;5168.3797944246935;5435.266239057755;6554.442872355808;6566.376194261537;6685.364890335411;1768.8584913206912;1807.3132209760206;1808.8372046515876;1819.0631824610336;1821.1440622907212;887.8224355129;906.5965645287783;925.7778133247972;1008.1682611845257;1008.369768894441;17580;19544.51138721515;21943;21985.23051693183;22108;955.4171955107518;956.2350119904049;965.3387429610739;976.4141515090888;1025.9791507450134;11033.503492342863;11300.982911538002;11368.602098926498;11462.656120316404;11559.884401156049;535017;539284;540435;550522;551252.093488314;92037;106598;108667;110417.79164416666;112508;994.3735446668552;997.2670087485692;1051.7475805806705;1054.5887104029434;1055.2454994678687;19541.70687439718;19728.408147755592;20031.79491281388;20040.098195581526;20062.598748025248;46838.531614683416;47630.14219146862;48602.02795944132;49283.507164929324;50794.73013174552;63395;64672;64709.67645161744;66213.66893165503;69245.65377173082;6377.808665740035;6742.8651426972165;7823.139454659869;7905.169957154416;8027.759167224993;10122.746931326776;11522.423878806194;12321.137520373488;12331.69170770738;12420.62738117858;10297.3821570706;10373.636922707668;10684.25210235276;10748.516316765608;10830.729231769268;10355.94822025885;10372.325798823053;10453.163746900382;10816.891831081903;10920.18098642589;10545.31455455393;10694.893051069701;10999.780004399707;11198;11331.036861866834;7623;7770.067591889009;7776.650050747617;7800.4929679572915;8051.597420129087;10258.589656413958;10483.790324193626;10567.947160264457;10991.230613856953;11054.834177487504 20:14:55 INFO - PROCESS | 1648 | |3;mootools.html;1442.1491320121127;1492.1420183394544;1680.9579760505985;1815.745795588617;1874.4751469588512;7873.960630196848;8277.17228277172;8278.379121565884;8280.130586288436;8291.336693064555;7189.42484601232;7339.743108991186;7540.472166948316;7789.805254868631;8004.359651227888;2248.123231939541;2287.954240915181;2289.977100228993;2294.265834932822;2295.8163346932206;2270.8751018693965;2300.7354154272307;2304.930852074432;2307.8038366738765;2310.214527060799;2240.2749882590397;2250.324902529235;2276.5333106713083;2280.144945645383;2284.657301404786;1735.0110437050823;2064.762552306489;2246.8876556172127;2277.6355783074628;2280.395695140772;2263.5359751250953;2299.4366380236784;2299.5056062946546;2300.4823914619246;2308.0652335804025;8174.100848906615;8178.795530111735;8275.586220688941;8314.584270786436;8317.96025496813;4312.978435107804;4928.67963582366;5009.949900500985;5106;5116.2069879168785;3095.1952492351925;3249.772515923886;3285.0309158798214;3290.8683652653867;3294.983525082383;989.2827699917574;1021.8058568871891;1052.5211028981794;1059.099765199582;1060.3531845574194;10579.153667706641;10597.682069537927;10599.947000265027;10618.150547956146;10629.681109566804;4381.932647147009;4429.800658970354;4589.495155532889;4597.816087356535;4611.354410382549;8352.206540378655;8356;8400.075991640915;8413.368997325226;8415.158484151536;4947.975260123749;4960.404751429852;4962.652614317035;4964.05682920244;4976.278439626234;4475.641948644101;4566.49768525462;4582.266837306015;4634.490206077328;4665.766711664403;3389.084947064279;3440.9655903440644;3464.566929133858;3478.826058697055;3514.3147086318436;1077.983830242547;1089.4062735809002;1089.760252744395;1096.3421946831836;1104.1553211792825;743.9479236453396;816.7672213419147;870.4603146049363;891.5364010714392;933.7945651956559;5203.739813009334;5435.048866448435;5594.888102238014;5637.26715526987;5788.652680839079;868.9956550217208;914.3371055984358;919.7721042408459;925.9166774873414;966.709987003896;3524.7708898921487;3639.0720366306423;3691.464737613031;3724;3792.715546334036;9489.66786162481;9735.756606084762;9783.461909594898;9815.11663950248;9834.704958851245;9790.5594248259;9795.951020244853;9796.706098817047;9797.559109840075;9823.066808653166;6582.913819219871;7273.6726847292;7512.060992375953;7679.577623230776;7681.846363072707;5326.174442961347;5336.599755018312;5373.462653734595;5427.701476418677;5463.153211252262;8939.418937769024;8950.104989500998;9055;9058.728238152866;9087.000429952832;7241.855162896818;8855.424397414148;9135.360524763202;9144.679936202467;9254;6515.609063456208;6748.493862960199;6833.384995350442;6928.792136235921;7030;7728.381729461742;7784.104827944859;7806.4925779824125;7815.413843961612;7881.13307536182;6923.826904327432;6946.722131114699;6946.79159625212;7011.1586609607175;7031.753888613874;5965.045592705146;6123.479504242189;6786.253512113763;6907.13660792401;6928.757493487906;6050.273967123973;6266.874662506815;6269.435750782452;6270.184875966278;6280.089387038763;4565.018521017997;4676.018036212434;4717.551832575899;4779.211430113992;4806.927896081632;4594.0582180653555;4610.976945115253;4621.30680397943;4656.278276867091;4805.27920811881 20:14:55 INFO - PROCESS | 1648 | |4;prototype.html;290.4611944842317;349.5438452819072;361.7521997431759;365.7202240286183;366.079310534007;4662.696924699896;4805.774527495492;5059.810944428056;5304.389995150554;5328;5006.524380183877;5069.721165335905;5225.1072950651815;5270.393904700961;5276.683398996063;3794.601566835484;4152.4428339372735;4164.708470407073;4171.912784539434;4174.165166966612;3716.2010167813914;4091.774952377618;4171.20747058058;4171.937420938689;4172.499300083994;3566.5185199997927;4137.13120244749;4141.337386018237;4141.813618387165;4152.37714342848;3427.845746941381;4103;4126.163857083614;4131.380292956051;4144.33690609501;3066.4054691560264;3210.5344725014975;3397.2695870387984;3655.798931058791;3657.1771351445977;4565.972656152372;4877.487863774323;4932.432770231433;5153.484651534854;5198.766055527472;3769.586405098088;4243.7480943121545;4517.254652982287;4561.4491073035;4569.954300456986;5561.860953476155;5562.582806289504;5566.66600003997;5568.470995255444;5587;3174.206448387903;3309.5035744638258;3444.9827750861086;3725.3666876631037;3783.621637836222;4900.926486102711;5804.6359105610145;5832.912506312408;5856.925114314976;5887.4406931341455;3803.67668748154;3824.9481392617254;3828.2152158807935;3833.9041523962123;3834.827432765532;3565.286942611488;3635.927281454356;3820.7898565578603;3866.7293289469467;3882.6410756235655;3560.857565697395;4168.478940132483;4273.850415235452;4509.684322097487;4525.366448697119;4842.95157048425;4926.729029903389;5374.596905232123;5429.457054294618;5547.583931205176;1078.883355726819;1184.7867383870814;1207.3299318878107;1215.9027277817754;1237.984852421006;835.788107244498;845.9280961118251;854.9145085491401;863.5121156546656;863.9157856889564;4183.535762483168;4358.975640724428;4547.363369128325;4875.19559272716;5085;4498.034024223978;4958.479359667255;5069.062223488666;5143.7685304161405;5418.485243901823;4250.36244563318;4861.392325959255;4923.818283611908;4962.851114466572;5043.52086551777;2553.923382298534;2581.7409766454352;2584.3926677230834;2827.5579454478366;2880.6975267597018;4910.361652985089;4963.081829861487;5566.916496252559;5654.370717616246;5673.780137270506;4760.857174284777;4995.877576514678;5042.067217564762;5348.331458567679;5587.469190426903;5428.267183930277;5458.543655355106;5556.694381809039;5650.26546548946;5663.592972986465;4674.5325467452985;5147.3308469898675;5367.51692347675;5370.946290537045;5421.79810646752;4621.701817309934;4801.199550168687;5292.914952434665;5316.30887984575;5341.439148889311;4456.479381288875;5078.974605127099;5505.449455054462;5587.385387607441;5660.603757736919;3447.9827600861836;3503.684668379756;3574.4459608760685;3618.0593045807755;3626.655467730561;4511.353356025093;4768.546988036131;5206.271122042841;5397.087111843882;5432.56539476833;4642.9089164045745;5073.416557095981;5091;5268.947310526999;5281.234221038005;4688.171613070973;4961.776720047535;5158.613104017289;5257.369115706139;5542.8337149885565;4567.908641827078;5085.7622646035625;5272.024675434904;5425.457454254543;5503.807366742145;5023.924641130313;5465.890682186255;5559.8308963890195;5600.7199640017025;5624.409437009055;4642.53574642533;4791.425028996543;5113.463086375877;5520.613557050968;5537.972310138423 20:14:55 INFO - PROCESS | 1648 | |5;yui.html;620.5190976992832;680.4794332335763;681.9829504262395;722.4076257468878;731.4843035659857;14820.703585928275;15578.221088945564;15581.298841552129;15608.453704120358;15650.53048408548;14152.858471415282;14231.715365692706;14237.074590151658;14238.430462781476;14278.64303392413;677.2076670295766;699.0597646165899;701.7052837808133;709.9503034787567;868.8175483148515;694.8054544727485;698.6646409723336;699.6326928362624;854.2055888024136;882.7307671160294;771.5717776633966;930.2465003347311;939.3800091939321;949.0746522140927;951.6241084771511;934.2152591822834;936.2369668719972;937.0676177203692;944.6646440513622;945.645382981382;534.4308311648122;551.5008916930185;706.1173533083645;706.8798304288283;707.723987644819;13907.79138312926;14423.567292981123;14488.927555362156;14825.77761333581;14827.925860370737;13713.314334283346;14114.294285285694;14300.92849535756;14317.856821431757;14336.784948225784;11605.129615278887;11777.882221177764;11923.821142682868;11950.701232469171;12161.756764864653;3668.816559172031;3721.6092310307567;3894.6689531389866;3928.921421571581;3941.2708648899757;94654.52672736661;96323;96471.03528964691;97941;98324;10383.428911409794;11027.944860275647;11076.833847492295;11151.498182581803;11219.55121795119;22887.885560572424;23235;23244.3026709199;23245;23273.301800946;6823.55646882951;7789.104253010976;7835.686572537149;7846.058472983164;7860.371170306362;4217.177650358212;4315.374270730789;4326.502452218036;4332.891677708082;4334.089841133335;3452.896413107611;3517.683408493248;3532.593751718534;3565.94651080234;3574.410222313289;618.2086928731231;628.6605233174126;628.8396458902951;640.4972096903908;647.1037612906159;571.2488078177183;653.9378759017885;656.6815094679076;665.381195488191;684.9691763870637;8556.957215213884;8993.595288212045;9138.862917056249;9293.67472138472;9421.293402994803;597.7429705226795;645.3256347117274;667.6494840208832;675.0886303490249;680.3434685528489;4344.45694288214;4485.192665320208;4529.614982726505;4543.7728113594185;4611.538846115429;76547.23452765512;93162.53418732862;96373;98157.50921245349;101778.96442071117;81186.59406702929;81607;82054;84609.15390846012;84764.57617711995;74855;76344.85482717764;76827;77071.61464192643;77239;8936.151065648754;8941.597628106621;8960.551972401485;8992;9238.630454781733;50835;51435.88512918883;52553.7372313136;53335.733321334694;53972.73013634907;53857;68612.97080543695;71195.64402177956;72791;74916;11784.469698863742;12039.578614748441;12285.754284914074;12287.262764234174;12517.248965062125;12732.299723515296;12933.288669123289;13187.538436155073;13364.465421383036;13386.464541418234;11968;12024.458899349725;12209.877901220874;12275.877241227474;12606.369681516071;11796.4691588877;11979.166666666515;12247.69380765488;12352.62942111738;12410.937945310216;11652.825207621723;12043.458044387848;12257.203281786655;12400.31798251105;12417.75164496687;9910.30627856043;9953.850692239477;10147.594096236067;10308.175345972455;10385.896141038493;10212.438315892698;10362.585496580052;10369.377837329785;10404.167666586503;10473.477004754997 20:14:55 INFO - PROCESS | 1648 | __end_tp_report 20:14:55 INFO - PROCESS | 1648 | __start_cc_report 20:14:55 INFO - PROCESS | 1648 | _x_x_mozilla_cycle_collect,1251 20:14:55 INFO - PROCESS | 1648 | __end_cc_report 20:14:55 INFO - PROCESS | 1648 | __startTimestamp1482466495859__endTimestamp 20:14:55 INFO - PROCESS | 1648 | 20:14:55 INFO - PROCESS | 1648 | ------- Summary: start ------- 20:14:55 INFO - PROCESS | 1648 | Number of tests: 6 20:14:55 INFO - PROCESS | 1648 | 20:14:55 INFO - PROCESS | 1648 | [#0] dojo.html Cycles:165 Average:5725.42 Median:5927.29 stddev:648.48 (10.9%) stddev-sans-first:623.44 20:14:55 INFO - PROCESS | 1648 | Values: 3363.7 3367.9 3386.4 3407.8 3427.1 5558.9 5649.9 5658.0 5729.3 5742.2 5656.1 5669.4 5680.8 5687.7 5702.1 5134.0 5139.5 5156.3 5210.1 5243.4 5151.7 5267.8 5269.6 5271.1 5276.3 4276.0 5197.6 5239.0 5260.4 5303.4 5118.3 5160.3 5169.9 5185.0 5212.5 5157.0 5208.0 5243.3 5262.6 5276.7 5645.5 5691.1 5698.5 5732.6 5738.7 6133.6 6264.2 6283.4 6286.9 6289.8 6145.5 6218.4 6222.8 6259.7 6263.8 5782.5 5782.7 5787.1 5795.0 5813.0 6099.9 6234.2 6252.8 6265.4 6280.7 5148.9 5967.4 6043.7 6044.2 6145.1 5168.4 6249.6 6259.4 6263.0 6275.3 6071.6 6108.0 6214.8 6226.9 6230.0 6137.6 6143.1 6199.2 6204.2 6222.2 4886.4 4931.0 4940.6 4944.3 4945.0 3763.8 4366.2 4466.3 4483.0 4484.2 5761.4 5781.7 5782.9 5788.6 5791.9 6133.8 6179.9 6207.4 6210.3 6291.8 5753.9 5835.0 5861.2 5869.8 5870.6 5348.9 5402.2 5405.8 5406.4 5409.6 6256.7 6291.9 6292.3 6303.5 6311.2 6195.0 6209.0 6236.7 6243.1 6252.8 6286.9 6306.8 6307.2 6317.3 6321.6 6162.4 6174.2 6197.1 6204.1 6207.1 6141.0 6162.7 6174.4 6197.5 6290.8 5935.1 6136.7 6144.7 6145.5 6151.4 6115.2 6123.1 6128.7 6131.0 6145.6 5789.9 5844.4 5907.4 5927.3 5960.6 6121.8 6140.7 6160.1 6169.6 6177.4 5794.9 5851.6 5969.3 5986.3 6020.5 20:14:55 INFO - PROCESS | 1648 | 20:14:55 INFO - PROCESS | 1648 | [#1] ext.html Cycles:180 Average:68345.25 Median:19873.73 stddev:145106.74 (730.1%) stddev-sans-first:145424.22 20:14:55 INFO - PROCESS | 1648 | Values: 434.6 478.3 489.9 494.5 496.4 30356.2 38710.8 40959.4 41003.0 41075.0 42242.8 42640.8 42748.7 42824.6 42896.6 8389.7 8600.5 9295.3 9495.5 9509.3 9253.2 11429.8 11435.3 11591.1 11616.4 11347.0 11393.1 11418.3 11426.3 11441.9 11250.4 11311.2 11311.8 11315.3 11330.7 9456.9 11137.2 11243.6 11250.4 11260.5 39475.6 39718.8 39746.0 39784.8 39793.4 3037.6 3250.1 3315.1 3409.0 3596.8 30452.1 30625.1 30893.8 30902.5 32075.7 2580.5 3170.7 3200.8 3214.1 3254.9 370109.0 499653.0 501462.0 502198.0 503532.0 63619.7 63806.7 64604.4 65793.0 65870.0 70123.0 70666.3 70788.0 70809.0 70855.0 15916.8 16323.9 16472.8 16480.7 16501.8 11344.1 11546.1 11568.5 12276.8 12507.6 19770.8 20492.4 20585.4 20605.7 20640.5 6953.7 7214.6 8329.5 8352.1 8446.3 853.7 928.0 930.3 963.6 1032.2 26446.7 27224.0 28643.6 28826.3 29788.9 1031.0 1076.0 1085.3 1096.2 1113.9 15452.0 15485.9 15544.1 15666.5 15925.8 564057.0 571564.0 613268.0 621330.0 622082.0 59209.7 59943.0 59996.5 60009.7 60197.8 1340.0 1356.7 1369.1 1369.5 1376.3 28757.6 29374.4 29504.6 29961.3 30166.5 51491.5 51899.0 51919.0 51981.7 52045.2 544817.0 547620.0 548062.0 550179.0 551726.0 34059.0 36368.0 37283.3 40713.8 41264.6 42276.0 42497.0 42514.0 42563.8 44932.6 35814.7 35958.8 36950.0 37945.1 38790.0 36150.6 37145.8 37458.8 38326.8 38409.8 34108.8 34801.8 35204.8 36704.3 36731.0 28306.2 30898.7 31082.8 31272.7 31716.0 23462.6 23570.9 23807.0 23919.8 23968.0 20:14:55 INFO - PROCESS | 1648 | 20:14:55 INFO - PROCESS | 1648 | [#2] jquery.html Cycles:180 Average:31991.28 Median:12161.27 stddev:90163.30 (741.4%) stddev-sans-first:90384.97 20:14:55 INFO - PROCESS | 1648 | Values: 368.5 370.1 376.8 390.8 402.3 18609.1 20316.7 20572.7 20750.0 20780.8 15955.5 20247.4 20696.2 20760.9 20928.7 5681.4 5708.1 5828.1 5831.9 5956.9 4090.9 5773.5 5892.1 5894.6 5896.7 4227.6 4284.4 4285.6 4304.0 4346.9 6388.6 6470.8 6484.6 6486.7 6487.5 5444.3 5566.4 5633.8 5656.3 5666.1 25164.5 25213.5 25254.6 25268.7 25529.5 2091.4 2196.6 2214.5 2297.0 2908.7 14561.8 14901.3 15205.6 15320.3 15592.9 1621.6 1727.7 1787.3 1813.8 1826.6 100204.0 102886.0 103756.0 105315.5 105631.0 9957.4 10173.6 10403.7 10533.1 10652.9 12767.9 12811.2 13017.3 13041.7 13056.8 7980.4 8028.4 8174.8 8248.6 8254.9 8097.4 8100.6 8168.8 8321.0 8321.3 5168.4 5435.3 6554.4 6566.4 6685.4 1768.9 1807.3 1808.8 1819.1 1821.1 887.8 906.6 925.8 1008.2 1008.4 17580.0 19544.5 21943.0 21985.2 22108.0 955.4 956.2 965.3 976.4 1026.0 11033.5 11301.0 11368.6 11462.7 11559.9 535017.0 539284.0 540435.0 550522.0 551252.1 92037.0 106598.0 108667.0 110417.8 112508.0 994.4 997.3 1051.7 1054.6 1055.2 19541.7 19728.4 20031.8 20040.1 20062.6 46838.5 47630.1 48602.0 49283.5 50794.7 63395.0 64672.0 64709.7 66213.7 69245.7 6377.8 6742.9 7823.1 7905.2 8027.8 10122.7 11522.4 12321.1 12331.7 12420.6 10297.4 10373.6 10684.3 10748.5 10830.7 10355.9 10372.3 10453.2 10816.9 10920.2 10545.3 10694.9 10999.8 11198.0 11331.0 7623.0 7770.1 7776.7 7800.5 8051.6 10258.6 10483.8 10567.9 10991.2 11054.8 20:14:55 INFO - PROCESS | 1648 | 20:14:55 INFO - PROCESS | 1648 | [#3] mootools.html Cycles:180 Average:5234.52 Median:5331.39 stddev:2853.97 (53.5%) stddev-sans-first:2847.75 20:14:55 INFO - PROCESS | 1648 | Values: 1442.1 1492.1 1681.0 1815.7 1874.5 7874.0 8277.2 8278.4 8280.1 8291.3 7189.4 7339.7 7540.5 7789.8 8004.4 2248.1 2288.0 2290.0 2294.3 2295.8 2270.9 2300.7 2304.9 2307.8 2310.2 2240.3 2250.3 2276.5 2280.1 2284.7 1735.0 2064.8 2246.9 2277.6 2280.4 2263.5 2299.4 2299.5 2300.5 2308.1 8174.1 8178.8 8275.6 8314.6 8318.0 4313.0 4928.7 5009.9 5106.0 5116.2 3095.2 3249.8 3285.0 3290.9 3295.0 989.3 1021.8 1052.5 1059.1 1060.4 10579.2 10597.7 10599.9 10618.2 10629.7 4381.9 4429.8 4589.5 4597.8 4611.4 8352.2 8356.0 8400.1 8413.4 8415.2 4948.0 4960.4 4962.7 4964.1 4976.3 4475.6 4566.5 4582.3 4634.5 4665.8 3389.1 3441.0 3464.6 3478.8 3514.3 1078.0 1089.4 1089.8 1096.3 1104.2 743.9 816.8 870.5 891.5 933.8 5203.7 5435.0 5594.9 5637.3 5788.7 869.0 914.3 919.8 925.9 966.7 3524.8 3639.1 3691.5 3724.0 3792.7 9489.7 9735.8 9783.5 9815.1 9834.7 9790.6 9796.0 9796.7 9797.6 9823.1 6582.9 7273.7 7512.1 7679.6 7681.8 5326.2 5336.6 5373.5 5427.7 5463.2 8939.4 8950.1 9055.0 9058.7 9087.0 7241.9 8855.4 9135.4 9144.7 9254.0 6515.6 6748.5 6833.4 6928.8 7030.0 7728.4 7784.1 7806.5 7815.4 7881.1 6923.8 6946.7 6946.8 7011.2 7031.8 5965.0 6123.5 6786.3 6907.1 6928.8 6050.3 6266.9 6269.4 6270.2 6280.1 4565.0 4676.0 4717.6 4779.2 4806.9 4594.1 4611.0 4621.3 4656.3 4805.3 20:14:55 INFO - PROCESS | 1648 | 20:14:55 INFO - PROCESS | 1648 | [#4] prototype.html Cycles:180 Average:4344.93 Median:4852.17 stddev:1317.49 (27.2%) stddev-sans-first:1285.56 20:14:55 INFO - PROCESS | 1648 | Values: 290.5 349.5 361.8 365.7 366.1 4662.7 4805.8 5059.8 5304.4 5328.0 5006.5 5069.7 5225.1 5270.4 5276.7 3794.6 4152.4 4164.7 4171.9 4174.2 3716.2 4091.8 4171.2 4171.9 4172.5 3566.5 4137.1 4141.3 4141.8 4152.4 3427.8 4103.0 4126.2 4131.4 4144.3 3066.4 3210.5 3397.3 3655.8 3657.2 4566.0 4877.5 4932.4 5153.5 5198.8 3769.6 4243.7 4517.3 4561.4 4570.0 5561.9 5562.6 5566.7 5568.5 5587.0 3174.2 3309.5 3445.0 3725.4 3783.6 4900.9 5804.6 5832.9 5856.9 5887.4 3803.7 3824.9 3828.2 3833.9 3834.8 3565.3 3635.9 3820.8 3866.7 3882.6 3560.9 4168.5 4273.9 4509.7 4525.4 4843.0 4926.7 5374.6 5429.5 5547.6 1078.9 1184.8 1207.3 1215.9 1238.0 835.8 845.9 854.9 863.5 863.9 4183.5 4359.0 4547.4 4875.2 5085.0 4498.0 4958.5 5069.1 5143.8 5418.5 4250.4 4861.4 4923.8 4962.9 5043.5 2553.9 2581.7 2584.4 2827.6 2880.7 4910.4 4963.1 5566.9 5654.4 5673.8 4760.9 4995.9 5042.1 5348.3 5587.5 5428.3 5458.5 5556.7 5650.3 5663.6 4674.5 5147.3 5367.5 5370.9 5421.8 4621.7 4801.2 5292.9 5316.3 5341.4 4456.5 5079.0 5505.4 5587.4 5660.6 3448.0 3503.7 3574.4 3618.1 3626.7 4511.4 4768.5 5206.3 5397.1 5432.6 4642.9 5073.4 5091.0 5268.9 5281.2 4688.2 4961.8 5158.6 5257.4 5542.8 4567.9 5085.8 5272.0 5425.5 5503.8 5023.9 5465.9 5559.8 5600.7 5624.4 4642.5 4791.4 5113.5 5520.6 5538.0 20:14:55 INFO - PROCESS | 1648 | 20:14:55 INFO - PROCESS | 1648 | [#5] yui.html Cycles:180 Average:19587.69 Median:51994.81 stddev:27672.01 (53.2%) stddev-sans-first:27712.98 20:14:55 INFO - PROCESS | 1648 | Values: 620.5 680.5 682.0 722.4 731.5 14820.7 15578.2 15581.3 15608.5 15650.5 14152.9 14231.7 14237.1 14238.4 14278.6 677.2 699.1 701.7 710.0 868.8 694.8 698.7 699.6 854.2 882.7 771.6 930.2 939.4 949.1 951.6 934.2 936.2 937.1 944.7 945.6 534.4 551.5 706.1 706.9 707.7 13907.8 14423.6 14488.9 14825.8 14827.9 13713.3 14114.3 14300.9 14317.9 14336.8 11605.1 11777.9 11923.8 11950.7 12161.8 3668.8 3721.6 3894.7 3928.9 3941.3 94654.5 96323.0 96471.0 97941.0 98324.0 10383.4 11027.9 11076.8 11151.5 11219.6 22887.9 23235.0 23244.3 23245.0 23273.3 6823.6 7789.1 7835.7 7846.1 7860.4 4217.2 4315.4 4326.5 4332.9 4334.1 3452.9 3517.7 3532.6 3565.9 3574.4 618.2 628.7 628.8 640.5 647.1 571.2 653.9 656.7 665.4 685.0 8557.0 8993.6 9138.9 9293.7 9421.3 597.7 645.3 667.6 675.1 680.3 4344.5 4485.2 4529.6 4543.8 4611.5 76547.2 93162.5 96373.0 98157.5 101779.0 81186.6 81607.0 82054.0 84609.2 84764.6 74855.0 76344.9 76827.0 77071.6 77239.0 8936.2 8941.6 8960.6 8992.0 9238.6 50835.0 51435.9 52553.7 53335.7 53972.7 53857.0 68613.0 71195.6 72791.0 74916.0 11784.5 12039.6 12285.8 12287.3 12517.2 12732.3 12933.3 13187.5 13364.5 13386.5 11968.0 12024.5 12209.9 12275.9 12606.4 11796.5 11979.2 12247.7 12352.6 12410.9 11652.8 12043.5 12257.2 12400.3 12417.8 9910.3 9953.9 10147.6 10308.2 10385.9 10212.4 10362.6 10369.4 10404.2 10473.5 20:14:55 INFO - PROCESS | 1648 | -------- Summary: end -------- 20:14:55 INFO - PROCESS | 1648 | 20:14:56 INFO - TEST-INFO | 1648: exit 0 20:14:56 INFO - TEST-OK | dromaeo_css | took 1119686ms 20:14:56 INFO - TEST-START | kraken 20:14:56 INFO - operating with platform_type : mac_ 20:14:56 INFO - Initialising browser for kraken test... 20:14:56 INFO - TEST-INFO | started process 1653 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/m5/r6dgwqsj10g93g673s3k_wyc00000w/T/tmp8SZ2Tr/profile http://localhost:49242/getInfo.html) 20:14:57 INFO - PROCESS | 1653 | 1482466497008 addons.xpi WARN Exception running bootstrap method install on shield-recipe-client@mozilla.org: [Exception... "Component returned failure code: 0x8000ffff (NS_ERROR_UNEXPECTED) [nsIPrefBranch.getBoolPref]" nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js :: this.install :: line 38" data: no] Stack trace: this.install()@resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js:38 < callBootstrapMethod()@resource://gre/modules/addons/XPIProvider.jsm:4979 < processFileChanges()@resource://gre/modules/addons/XPIProvider.jsm -> resource://gre/modules/addons/XPIProviderUtils.js:2097 < checkForChanges()@resource://gre/modules/addons/XPIProvider.jsm:3825 < startup()@resource://gre/modules/addons/XPIProvider.jsm:2829 < callProvider()@resource://gre/modules/AddonManager.jsm:264 < _startProvider()@resource://gre/modules/AddonManager.jsm:771 < startup()@resource://gre/modules/AddonManager.jsm:957 < startup()@resource://gre/modules/AddonManager.jsm:2923 < observe()@resource://gre/components/addonManager.js:65 20:14:58 INFO - PROCESS | 1653 | JavaScript error: chrome://global/content/browser-content.js, line 1772: ReferenceError: event is not defined 20:14:58 INFO - PROCESS | 1653 | JavaScript error: chrome://global/content/browser-content.js, line 1772: ReferenceError: event is not defined 20:14:58 INFO - PROCESS | 1653 | JavaScript error: chrome://global/content/browser-content.js, line 1772: ReferenceError: event is not defined 20:14:58 INFO - PROCESS | 1653 | __metrics Screen width/height:1600/1200 20:14:58 INFO - PROCESS | 1653 | colorDepth:24 20:14:58 INFO - PROCESS | 1653 | Browser inner width/height: 1024/689 20:14:58 INFO - PROCESS | 1653 | __metrics 20:14:58 INFO - PROCESS | 1653 | 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:14:58 INFO - PROCESS | 1653 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 20:14:59 INFO - TEST-INFO | 1653: exit 0 20:14:59 INFO - Browser initialized. 20:14:59 INFO - Running cycle 1/1 for kraken test... 20:14:59 INFO - TEST-INFO | started process 1654 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/m5/r6dgwqsj10g93g673s3k_wyc00000w/T/tmp8SZ2Tr/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 20:15:11 INFO - PROCESS | 1654 | RSS: Main: 227852288 20:15:11 INFO - PROCESS | 1654 | 20:15:14 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49242/tests/kraken/driver.html?testName=audio-beat-detection) 20:15:15 INFO - PROCESS | 1654 | RSS: Main: 288186368 20:15:15 INFO - PROCESS | 1654 | 20:15:17 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49242/tests/kraken/driver.html?testName=audio-dft) 20:15:18 INFO - PROCESS | 1654 | RSS: Main: 289869824 20:15:18 INFO - PROCESS | 1654 | 20:15:21 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49242/tests/kraken/driver.html?testName=audio-fft) 20:15:22 INFO - PROCESS | 1654 | RSS: Main: 380841984 20:15:22 INFO - PROCESS | 1654 | 20:15:24 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49242/tests/kraken/driver.html?testName=audio-oscillator) 20:15:25 INFO - PROCESS | 1654 | RSS: Main: 284925952 20:15:25 INFO - PROCESS | 1654 | 20:15:27 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49242/tests/kraken/driver.html?testName=imaging-gaussian-blur) 20:15:27 INFO - PROCESS | 1654 | RSS: Main: 289243136 20:15:27 INFO - PROCESS | 1654 | 20:15:30 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49242/tests/kraken/driver.html?testName=imaging-darkroom) 20:15:31 INFO - PROCESS | 1654 | RSS: Main: 331923456 20:15:31 INFO - PROCESS | 1654 | 20:15:33 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49242/tests/kraken/driver.html?testName=imaging-desaturate) 20:15:34 INFO - PROCESS | 1654 | RSS: Main: 331997184 20:15:34 INFO - PROCESS | 1654 | 20:15:37 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49242/tests/kraken/driver.html?testName=json-parse-financial) 20:15:38 INFO - PROCESS | 1654 | RSS: Main: 330391552 20:15:38 INFO - PROCESS | 1654 | 20:15:39 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49242/tests/kraken/driver.html?testName=json-stringify-tinderbox) 20:15:40 INFO - PROCESS | 1654 | RSS: Main: 305393664 20:15:40 INFO - PROCESS | 1654 | 20:15:42 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-aes) 20:15:42 INFO - PROCESS | 1654 | RSS: Main: 306868224 20:15:42 INFO - PROCESS | 1654 | 20:15:44 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-ccm) 20:15:45 INFO - PROCESS | 1654 | RSS: Main: 315236352 20:15:45 INFO - PROCESS | 1654 | 20:15:48 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 20:15:49 INFO - PROCESS | 1654 | RSS: Main: 328204288 20:15:49 INFO - PROCESS | 1654 | 20:15:52 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 20:15:52 INFO - PROCESS | 1654 | RSS: Main: 322117632 20:15:52 INFO - PROCESS | 1654 | 20:15:54 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49242/tests/kraken/driver.html?testName=ai-astar) 20:15:54 INFO - PROCESS | 1654 | RSS: Main: 366018560 20:15:54 INFO - PROCESS | 1654 | 20:15:54 INFO - PROCESS | 1654 | __start_tp_report 20:15:54 INFO - PROCESS | 1654 | _x_x_mozilla_page_load 20:15:54 INFO - PROCESS | 1654 | _x_x_mozilla_page_load_details 20:15:54 INFO - PROCESS | 1654 | |i|pagename|runs| 20:15:54 INFO - PROCESS | 1654 | |0;ai-astar;111;111;111;113;114;111;110;113;112;113 20:15:54 INFO - PROCESS | 1654 | |1;audio-beat-detection;143;109;108;145;111;109;107;109;128;114 20:15:54 INFO - PROCESS | 1654 | |2;audio-dft;184;182;184;184;184;187;179;184;183;182 20:15:54 INFO - PROCESS | 1654 | |3;audio-fft;81;59;58;90;59;58;59;49;87;75 20:15:54 INFO - PROCESS | 1654 | |4;audio-oscillator;87;71;83;70;70;68;82;70;70;108 20:15:54 INFO - PROCESS | 1654 | |5;imaging-gaussian-blur;88;84;87;85;87;84;102;111;88;89 20:15:54 INFO - PROCESS | 1654 | |6;imaging-darkroom;93;93;93;96;93;94;93;96;96;93 20:15:54 INFO - PROCESS | 1654 | |7;imaging-desaturate;87;84;85;88;88;84;85;87;88;88 20:15:54 INFO - PROCESS | 1654 | |8;json-parse-financial;56;53;55;53;56;54;57;56;55;55 20:15:54 INFO - PROCESS | 1654 | |9;json-stringify-tinderbox;64;72;74;73;74;68;69;69;70;72 20:15:54 INFO - PROCESS | 1654 | |10;stanford-crypto-aes;90;89;90;87;91;90;87;91;87;88 20:15:54 INFO - PROCESS | 1654 | |11;stanford-crypto-ccm;143;137;144;132;139;149;149;152;184;151 20:15:54 INFO - PROCESS | 1654 | |12;stanford-crypto-pbkdf2;173;171;171;178;171;171;170;170;169;171 20:15:54 INFO - PROCESS | 1654 | |13;stanford-crypto-sha256-iterative;68;67;66;66;75;88;75;66;67;66 20:15:54 INFO - PROCESS | 1654 | __end_tp_report 20:15:54 INFO - PROCESS | 1654 | __start_cc_report 20:15:54 INFO - PROCESS | 1654 | _x_x_mozilla_cycle_collect,2006 20:15:54 INFO - PROCESS | 1654 | __end_cc_report 20:15:54 INFO - PROCESS | 1654 | __startTimestamp1482466554818__endTimestamp 20:15:54 INFO - PROCESS | 1654 | 20:15:54 INFO - PROCESS | 1654 | ------- Summary: start ------- 20:15:54 INFO - PROCESS | 1654 | Number of tests: 14 20:15:54 INFO - PROCESS | 1654 | 20:15:54 INFO - PROCESS | 1654 | [#0] ai-astar Cycles:10 Average:111.90 Median:112.50 stddev:1.29 (1.1%) stddev-sans-first:1.32 20:15:54 INFO - PROCESS | 1654 | Values: 111.0 111.0 111.0 113.0 114.0 111.0 110.0 113.0 112.0 113.0 20:15:54 INFO - PROCESS | 1654 | 20:15:54 INFO - PROCESS | 1654 | [#1] audio-beat-detection Cycles:10 Average:118.30 Median:112.50 stddev:14.84 (13.2%) stddev-sans-first:12.77 20:15:54 INFO - PROCESS | 1654 | Values: 143.0 109.0 108.0 145.0 111.0 109.0 107.0 109.0 128.0 114.0 20:15:54 INFO - PROCESS | 1654 | 20:15:54 INFO - PROCESS | 1654 | [#2] audio-dft Cycles:10 Average:183.30 Median:184.00 stddev:2.06 (1.1%) stddev-sans-first:2.17 20:15:54 INFO - PROCESS | 1654 | Values: 184.0 182.0 184.0 184.0 184.0 187.0 179.0 184.0 183.0 182.0 20:15:54 INFO - PROCESS | 1654 | 20:15:54 INFO - PROCESS | 1654 | [#3] audio-fft Cycles:10 Average:67.50 Median:67.00 stddev:14.39 (21.5%) stddev-sans-first:14.41 20:15:54 INFO - PROCESS | 1654 | Values: 81.0 59.0 58.0 90.0 59.0 58.0 59.0 49.0 87.0 75.0 20:15:54 INFO - PROCESS | 1654 | 20:15:54 INFO - PROCESS | 1654 | [#4] audio-oscillator Cycles:10 Average:77.90 Median:70.50 stddev:12.59 (17.9%) stddev-sans-first:12.92 20:15:54 INFO - PROCESS | 1654 | Values: 87.0 71.0 83.0 70.0 70.0 68.0 82.0 70.0 70.0 108.0 20:15:54 INFO - PROCESS | 1654 | 20:15:54 INFO - PROCESS | 1654 | [#5] imaging-gaussian-blur Cycles:10 Average:90.50 Median:87.00 stddev:8.86 (10.2%) stddev-sans-first:9.35 20:15:54 INFO - PROCESS | 1654 | Values: 88.0 84.0 87.0 85.0 87.0 84.0 102.0 111.0 88.0 89.0 20:15:54 INFO - PROCESS | 1654 | 20:15:54 INFO - PROCESS | 1654 | [#6] imaging-darkroom Cycles:10 Average:94.00 Median:93.50 stddev:1.41 (1.5%) stddev-sans-first:1.45 20:15:54 INFO - PROCESS | 1654 | Values: 93.0 93.0 93.0 96.0 93.0 94.0 93.0 96.0 96.0 93.0 20:15:54 INFO - PROCESS | 1654 | 20:15:54 INFO - PROCESS | 1654 | [#7] imaging-desaturate Cycles:10 Average:86.40 Median:87.50 stddev:1.71 (2.0%) stddev-sans-first:1.80 20:15:54 INFO - PROCESS | 1654 | Values: 87.0 84.0 85.0 88.0 88.0 84.0 85.0 87.0 88.0 88.0 20:15:54 INFO - PROCESS | 1654 | 20:15:54 INFO - PROCESS | 1654 | [#8] json-parse-financial Cycles:10 Average:55.00 Median:55.50 stddev:1.33 (2.4%) stddev-sans-first:1.36 20:15:54 INFO - PROCESS | 1654 | Values: 56.0 53.0 55.0 53.0 56.0 54.0 57.0 56.0 55.0 55.0 20:15:54 INFO - PROCESS | 1654 | 20:15:54 INFO - PROCESS | 1654 | [#9] json-stringify-tinderbox Cycles:10 Average:70.50 Median:72.00 stddev:3.14 (4.4%) stddev-sans-first:2.28 20:15:54 INFO - PROCESS | 1654 | Values: 64.0 72.0 74.0 73.0 74.0 68.0 69.0 69.0 70.0 72.0 20:15:54 INFO - PROCESS | 1654 | 20:15:54 INFO - PROCESS | 1654 | [#10] stanford-crypto-aes Cycles:10 Average:89.00 Median:90.00 stddev:1.63 (1.8%) stddev-sans-first:1.69 20:15:54 INFO - PROCESS | 1654 | Values: 90.0 89.0 90.0 87.0 91.0 90.0 87.0 91.0 87.0 88.0 20:15:54 INFO - PROCESS | 1654 | 20:15:54 INFO - PROCESS | 1654 | [#11] stanford-crypto-ccm Cycles:10 Average:148.00 Median:149.00 stddev:14.23 (9.5%) stddev-sans-first:14.98 20:15:54 INFO - PROCESS | 1654 | Values: 143.0 137.0 144.0 132.0 139.0 149.0 149.0 152.0 184.0 151.0 20:15:54 INFO - PROCESS | 1654 | 20:15:54 INFO - PROCESS | 1654 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:171.50 Median:171.00 stddev:2.51 (1.5%) stddev-sans-first:2.60 20:15:54 INFO - PROCESS | 1654 | Values: 173.0 171.0 171.0 178.0 171.0 171.0 170.0 170.0 169.0 171.0 20:15:54 INFO - PROCESS | 1654 | 20:15:54 INFO - PROCESS | 1654 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:70.40 Median:67.50 stddev:7.14 (10.6%) stddev-sans-first:7.52 20:15:54 INFO - PROCESS | 1654 | Values: 68.0 67.0 66.0 66.0 75.0 88.0 75.0 66.0 67.0 66.0 20:15:54 INFO - PROCESS | 1654 | -------- Summary: end -------- 20:15:54 INFO - PROCESS | 1654 | 20:15:55 INFO - TEST-INFO | 1654: exit 0 20:15:55 INFO - TEST-OK | kraken | took 59222ms 20:15:55 INFO - SUITE-END | took 1178s 20:15:55 INFO - Completed test suite (00:19:39) 20:15:55 INFO - javascript benchmark 20:15:56 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3363.663633636638, 3367.8989630311094, 3386.3565922474736, 3407.761456698032, 3427.108951672566, 5558.888822223563, 5649.8587535311635, 5658.009848276551, 5729.28383952006, 5742.196092547041, 5656.095024796034, 5669.433056694318, 5680.772769089232, 5687.7156142192825, 5702.1446782982575, 5133.974330128344, 5139.537441630253, 5156.278121063054, 5210.088234558956, 5243.449437809032, 5151.74241287936, 5267.841964741045, 5269.578433725312, 5271.077561426754, 5276.34045744282, 4276.0, 5197.558207552363, 5239.0, 5260.395054568736, 5303.363596368441, 5118.309028281172, 5160.329157209576, 5169.896602067937, 5185.0, 5212.452692467275, 5157.0, 5207.973960130175, 5243.318368612056, 5262.60530460217, 5276.709780962084, 5645.520130788888, 5691.146328050784, 5698.544116470673, 5732.570057245724, 5738.713064346766, 6133.601315914451, 6264.248290205159, 6283.403076707706, 6286.937130628727, 6289.811305660837, 6145.477634401037, 6218.409251121137, 6222.844428889269, 6259.655718935457, 6263.843403914893, 5782.508486778587, 5782.739776710058, 5787.102999035156, 5795.043817770063, 5813.0, 6099.875452227012, 6234.189555357865, 6252.843678907972, 6265.373462653698, 6280.748770049238, 5148.9485105149215, 5967.403259673998, 6043.697815109227, 6044.153818465418, 6145.10895920098, 5168.3797944246935, 6249.625022498664, 6259.405356491126, 6263.029230469285, 6275.278342990523, 6071.635701857903, 6107.97783795876, 6214.8135555933395, 6226.875462490725, 6230.0, 6137.604186954709, 6143.095640351506, 6199.225096862892, 6204.224471941007, 6222.222222222223, 4886.438059623189, 4931.0, 4940.604751619861, 4944.307796908436, 4945.010997800454, 3763.7929913854455, 4366.214081465303, 4466.285394336891, 4482.955170448254, 4484.215262329079, 5761.423857614205, 5781.71091445426, 5782.942170578241, 5788.623739456922, 5791.8552036199435, 6133.76314253068, 6179.876402471926, 6207.379262073757, 6210.347913469111, 6291.811245662638, 5753.85615359611, 5835.037218858925, 5861.150133230658, 5869.7945571904775, 5870.618409803349, 5348.91976620351, 5402.189671549299, 5405.837824865261, 5406.405295417501, 5409.621326507186, 6256.687165641609, 6291.905621415591, 6292.307846136921, 6303.527235457267, 6311.2426508819235, 6195.0, 6208.9689551551955, 6236.688165591793, 6243.125962365181, 6252.843678907881, 6286.905696414649, 6306.779262725782, 6307.211598550181, 6317.336679648571, 6321.589096708699, 6162.445379915829, 6174.166487524127, 6197.070439434121, 6204.100405441276, 6207.099970504161, 6140.969295153495, 6162.691865406801, 6174.44430001302, 6197.5351848610635, 6290.779822706183, 5935.0503919372695, 6136.673645080297, 6144.723487442986, 6145.508359331154, 6151.354107818794, 6115.209918062534, 6123.069106197142, 6128.686460748315, 6130.979101201984, 6145.600535965148, 5789.8552536186935, 5844.415558444121, 5907.3797251288, 5927.288725352812, 5960.612560183574, 6121.818815678178, 6140.688304616564, 6160.075988601746, 6169.56813023084, 6177.444030037318, 5794.884102317846, 5851.64890106595, 5969.313528944053, 5986.281646202309, 6020.548458865515], "value": 5683.906131213469, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [434.558922693466, 478.2969035517789, 489.93385892904445, 494.4659767451152, 496.43406516571093, 30356.241093972632, 38710.80644596776, 40959.38560921588, 41003.0, 41074.99999999996, 42242.7887860561, 42640.78679606605, 42748.717538473895, 42824.57175428245, 42896.57103428965, 8389.748307550783, 8600.526971016592, 9295.349325547197, 9495.52522373882, 9509.33434659574, 9253.167214950687, 11429.828552571718, 11435.313881167114, 11591.072714182845, 11616.419179041057, 11347.0, 11393.145514086435, 11418.257813242113, 11426.257293275952, 11441.942790286037, 11250.437478126101, 11311.15166362522, 11311.83032254517, 11315.321080735182, 11330.67721117169, 9456.912455067686, 11137.220394572383, 11243.60647377338, 11250.437478126061, 11260.493277802518, 39475.60524394748, 39718.80140599279, 39746.0, 39784.80107599472, 39793.40309895354, 3037.635483741943, 3250.122466933938, 3315.088350703552, 3408.9659103409144, 3596.82015899204, 30452.086437406913, 30625.081247562608, 30893.845530772203, 30902.53646195309, 32075.679243207735, 2580.5355036093497, 3170.7304879085304, 3200.807951522916, 3214.051854702845, 3254.886078987248, 370109.0, 499652.9999999964, 501462.0, 502198.0, 503531.99999999633, 63619.68190159019, 63806.68096659487, 64604.35395646077, 65793.0, 65870.0, 70123.0, 70666.29333706597, 70788.0, 70809.0, 70855.0, 15916.76124858128, 16323.918380408022, 16472.83527164737, 16480.67038659244, 16501.752473712902, 11344.149188810872, 11546.134039947037, 11568.479418426192, 12276.754464910651, 12507.624771256877, 19770.802291976896, 20492.38522844317, 20585.382438526867, 20605.690914636292, 20640.48398790042, 6953.687084081229, 7214.567125972459, 8329.500229986099, 8352.12302708219, 8446.282066024456, 853.6585365853708, 927.9814403711888, 930.2976252928995, 963.633819148733, 1032.2413026425572, 26446.735532644812, 27224.047158349364, 28643.57034644482, 28826.279343016173, 29788.851055744584, 1030.9845352319721, 1075.9724463137684, 1085.3433672628105, 1096.2490693874722, 1113.8663360396642, 15452.0, 15485.922570387302, 15544.145072020921, 15666.530004099895, 15925.840741592436, 564057.0, 571564.0, 613268.0, 621330.0, 622082.0, 59209.70395147997, 59943.0, 59996.50008749816, 60009.69995149996, 60197.79604407975, 1339.9665008374868, 1356.6811799227173, 1369.116919586841, 1369.5206677662939, 1376.2980879751399, 28757.56863647089, 29374.41251174922, 29504.557431638113, 29961.250968725955, 30166.49167541658, 51491.48508514867, 51899.0, 51918.96162076813, 51981.7400912993, 52045.21932170945, 544817.0, 547620.0, 548062.0, 550179.0, 551726.0, 34059.0, 36368.0, 37283.25433491369, 40713.778586642446, 41264.587354127274, 42276.0, 42497.0, 42514.0, 42563.7871810639, 44932.550674492835, 35814.74648387294, 35958.8202058988, 36950.0, 37945.051373715876, 38790.0, 36150.63849361473, 37145.81427092847, 37458.812705936296, 38326.80836595799, 38409.807950960065, 34108.82945585256, 34801.78193763206, 35204.76783312572, 36704.265914681026, 36731.0, 28306.15081547557, 30898.69101308958, 31082.844585776926, 31272.68727312789, 31716.0, 23462.648060278767, 23570.882145589847, 23806.961426165664, 23919.760802392448, 23968.0], "value": 20218.21486513911, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [368.474923234391, 370.1468115992636, 376.7777011563178, 390.81241004317945, 402.3020060195562, 18609.06954652267, 20316.69524957127, 20572.69140962885, 20750.0, 20780.792192078094, 15955.521334359988, 20247.39257822264, 20696.172153113894, 20760.85815280159, 20928.709614529023, 5681.375048744645, 5708.08670612702, 5828.0767346775465, 5831.912521312183, 5956.910646340287, 4090.9386359204636, 5773.509251713609, 5892.057270836667, 5894.646321220719, 5896.675682837442, 4227.556106608808, 4284.378765079062, 4285.592868677486, 4303.956960430402, 4346.913061738764, 6388.64862432566, 6470.805875823733, 6484.610923344614, 6486.708098135548, 6487.481001519891, 5444.265024221759, 5566.359868615121, 5633.774649014034, 5656.274836174965, 5666.121751128581, 25164.496710065698, 25213.495730085295, 25254.621180682305, 25268.747312527008, 25529.489410211878, 2091.4144039668918, 2196.5716685246384, 2214.5460180662926, 2296.977030229693, 2908.720163128238, 14561.78157327641, 14901.254937253203, 15205.619859503491, 15320.310586023656, 15592.922035389864, 1621.6351320952808, 1727.6890159771235, 1787.3297513432462, 1813.7914139873951, 1826.5616252099533, 100204.0, 102886.0, 103756.0, 105315.47342263239, 105631.0, 9957.352772069793, 10173.643922462827, 10403.739906502398, 10533.104686101768, 10652.893471065345, 12767.914727248102, 12811.231326120462, 13017.284049377186, 13041.673958151123, 13056.804147937788, 7980.3615710743, 8028.397870159762, 8174.836503269901, 8248.628811703487, 8254.876176857351, 8097.433179677484, 8100.648308620504, 8168.7957801055445, 8321.04308004575, 8321.334293256525, 5168.3797944246935, 5435.266239057755, 6554.442872355808, 6566.376194261537, 6685.364890335411, 1768.8584913206912, 1807.3132209760206, 1808.8372046515876, 1819.0631824610336, 1821.1440622907212, 887.8224355129, 906.5965645287783, 925.7778133247972, 1008.1682611845257, 1008.369768894441, 17580.0, 19544.51138721515, 21943.0, 21985.23051693183, 22108.0, 955.4171955107518, 956.2350119904049, 965.3387429610739, 976.4141515090888, 1025.9791507450134, 11033.503492342863, 11300.982911538002, 11368.602098926498, 11462.656120316404, 11559.884401156049, 535017.0, 539284.0, 540435.0, 550522.0, 551252.093488314, 92037.0, 106598.0, 108667.0, 110417.79164416666, 112508.0, 994.3735446668552, 997.2670087485692, 1051.7475805806705, 1054.5887104029434, 1055.2454994678687, 19541.70687439718, 19728.408147755592, 20031.79491281388, 20040.098195581526, 20062.598748025248, 46838.531614683416, 47630.14219146862, 48602.02795944132, 49283.507164929324, 50794.73013174552, 63395.0, 64672.0, 64709.67645161744, 66213.66893165503, 69245.65377173082, 6377.808665740035, 6742.8651426972165, 7823.139454659869, 7905.169957154416, 8027.759167224993, 10122.746931326776, 11522.423878806194, 12321.137520373488, 12331.69170770738, 12420.62738117858, 10297.3821570706, 10373.636922707668, 10684.25210235276, 10748.516316765608, 10830.729231769268, 10355.94822025885, 10372.325798823053, 10453.163746900382, 10816.891831081903, 10920.18098642589, 10545.31455455393, 10694.893051069701, 10999.780004399707, 11198.0, 11331.036861866834, 7623.0, 7770.067591889009, 7776.650050747617, 7800.4929679572915, 8051.597420129087, 10258.589656413958, 10483.790324193626, 10567.947160264457, 10991.230613856953, 11054.834177487504], "value": 9345.32994015918, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1442.1491320121127, 1492.1420183394544, 1680.9579760505985, 1815.745795588617, 1874.4751469588512, 7873.960630196848, 8277.17228277172, 8278.379121565884, 8280.130586288436, 8291.336693064555, 7189.42484601232, 7339.743108991186, 7540.472166948316, 7789.805254868631, 8004.359651227888, 2248.123231939541, 2287.954240915181, 2289.977100228993, 2294.265834932822, 2295.8163346932206, 2270.8751018693965, 2300.7354154272307, 2304.930852074432, 2307.8038366738765, 2310.214527060799, 2240.2749882590397, 2250.324902529235, 2276.5333106713083, 2280.144945645383, 2284.657301404786, 1735.0110437050823, 2064.762552306489, 2246.8876556172127, 2277.6355783074628, 2280.395695140772, 2263.5359751250953, 2299.4366380236784, 2299.5056062946546, 2300.4823914619246, 2308.0652335804025, 8174.100848906615, 8178.795530111735, 8275.586220688941, 8314.584270786436, 8317.96025496813, 4312.978435107804, 4928.67963582366, 5009.949900500985, 5106.0, 5116.2069879168785, 3095.1952492351925, 3249.772515923886, 3285.0309158798214, 3290.8683652653867, 3294.983525082383, 989.2827699917574, 1021.8058568871891, 1052.5211028981794, 1059.099765199582, 1060.3531845574194, 10579.153667706641, 10597.682069537927, 10599.947000265027, 10618.150547956146, 10629.681109566804, 4381.932647147009, 4429.800658970354, 4589.495155532889, 4597.816087356535, 4611.354410382549, 8352.206540378655, 8356.0, 8400.075991640915, 8413.368997325226, 8415.158484151536, 4947.975260123749, 4960.404751429852, 4962.652614317035, 4964.05682920244, 4976.278439626234, 4475.641948644101, 4566.49768525462, 4582.266837306015, 4634.490206077328, 4665.766711664403, 3389.084947064279, 3440.9655903440644, 3464.566929133858, 3478.826058697055, 3514.3147086318436, 1077.983830242547, 1089.4062735809002, 1089.760252744395, 1096.3421946831836, 1104.1553211792825, 743.9479236453396, 816.7672213419147, 870.4603146049363, 891.5364010714392, 933.7945651956559, 5203.739813009334, 5435.048866448435, 5594.888102238014, 5637.26715526987, 5788.652680839079, 868.9956550217208, 914.3371055984358, 919.7721042408459, 925.9166774873414, 966.709987003896, 3524.7708898921487, 3639.0720366306423, 3691.464737613031, 3724.0, 3792.715546334036, 9489.66786162481, 9735.756606084762, 9783.461909594898, 9815.11663950248, 9834.704958851245, 9790.5594248259, 9795.951020244853, 9796.706098817047, 9797.559109840075, 9823.066808653166, 6582.913819219871, 7273.6726847292, 7512.060992375953, 7679.577623230776, 7681.846363072707, 5326.174442961347, 5336.599755018312, 5373.462653734595, 5427.701476418677, 5463.153211252262, 8939.418937769024, 8950.104989500998, 9055.0, 9058.728238152866, 9087.000429952832, 7241.855162896818, 8855.424397414148, 9135.360524763202, 9144.679936202467, 9254.0, 6515.609063456208, 6748.493862960199, 6833.384995350442, 6928.792136235921, 7030.0, 7728.381729461742, 7784.104827944859, 7806.4925779824125, 7815.413843961612, 7881.13307536182, 6923.826904327432, 6946.722131114699, 6946.79159625212, 7011.1586609607175, 7031.753888613874, 5965.045592705146, 6123.479504242189, 6786.253512113763, 6907.13660792401, 6928.757493487906, 6050.273967123973, 6266.874662506815, 6269.435750782452, 6270.184875966278, 6280.089387038763, 4565.018521017997, 4676.018036212434, 4717.551832575899, 4779.211430113992, 4806.927896081632, 4594.0582180653555, 4610.976945115253, 4621.30680397943, 4656.278276867091, 4805.27920811881], "value": 4255.61183737608, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [290.4611944842317, 349.5438452819072, 361.7521997431759, 365.7202240286183, 366.079310534007, 4662.696924699896, 4805.774527495492, 5059.810944428056, 5304.389995150554, 5328.0, 5006.524380183877, 5069.721165335905, 5225.1072950651815, 5270.393904700961, 5276.683398996063, 3794.601566835484, 4152.4428339372735, 4164.708470407073, 4171.912784539434, 4174.165166966612, 3716.2010167813914, 4091.774952377618, 4171.20747058058, 4171.937420938689, 4172.499300083994, 3566.5185199997927, 4137.13120244749, 4141.337386018237, 4141.813618387165, 4152.37714342848, 3427.845746941381, 4103.0, 4126.163857083614, 4131.380292956051, 4144.33690609501, 3066.4054691560264, 3210.5344725014975, 3397.2695870387984, 3655.798931058791, 3657.1771351445977, 4565.972656152372, 4877.487863774323, 4932.432770231433, 5153.484651534854, 5198.766055527472, 3769.586405098088, 4243.7480943121545, 4517.254652982287, 4561.4491073035, 4569.954300456986, 5561.860953476155, 5562.582806289504, 5566.66600003997, 5568.470995255444, 5587.0, 3174.206448387903, 3309.5035744638258, 3444.9827750861086, 3725.3666876631037, 3783.621637836222, 4900.926486102711, 5804.6359105610145, 5832.912506312408, 5856.925114314976, 5887.4406931341455, 3803.67668748154, 3824.9481392617254, 3828.2152158807935, 3833.9041523962123, 3834.827432765532, 3565.286942611488, 3635.927281454356, 3820.7898565578603, 3866.7293289469467, 3882.6410756235655, 3560.857565697395, 4168.478940132483, 4273.850415235452, 4509.684322097487, 4525.366448697119, 4842.95157048425, 4926.729029903389, 5374.596905232123, 5429.457054294618, 5547.583931205176, 1078.883355726819, 1184.7867383870814, 1207.3299318878107, 1215.9027277817754, 1237.984852421006, 835.788107244498, 845.9280961118251, 854.9145085491401, 863.5121156546656, 863.9157856889564, 4183.535762483168, 4358.975640724428, 4547.363369128325, 4875.19559272716, 5085.0, 4498.034024223978, 4958.479359667255, 5069.062223488666, 5143.7685304161405, 5418.485243901823, 4250.36244563318, 4861.392325959255, 4923.818283611908, 4962.851114466572, 5043.52086551777, 2553.923382298534, 2581.7409766454352, 2584.3926677230834, 2827.5579454478366, 2880.6975267597018, 4910.361652985089, 4963.081829861487, 5566.916496252559, 5654.370717616246, 5673.780137270506, 4760.857174284777, 4995.877576514678, 5042.067217564762, 5348.331458567679, 5587.469190426903, 5428.267183930277, 5458.543655355106, 5556.694381809039, 5650.26546548946, 5663.592972986465, 4674.5325467452985, 5147.3308469898675, 5367.51692347675, 5370.946290537045, 5421.79810646752, 4621.701817309934, 4801.199550168687, 5292.914952434665, 5316.30887984575, 5341.439148889311, 4456.479381288875, 5078.974605127099, 5505.449455054462, 5587.385387607441, 5660.603757736919, 3447.9827600861836, 3503.684668379756, 3574.4459608760685, 3618.0593045807755, 3626.655467730561, 4511.353356025093, 4768.546988036131, 5206.271122042841, 5397.087111843882, 5432.56539476833, 4642.9089164045745, 5073.416557095981, 5091.0, 5268.947310526999, 5281.234221038005, 4688.171613070973, 4961.776720047535, 5158.613104017289, 5257.369115706139, 5542.8337149885565, 4567.908641827078, 5085.7622646035625, 5272.024675434904, 5425.457454254543, 5503.807366742145, 5023.924641130313, 5465.890682186255, 5559.8308963890195, 5600.7199640017025, 5624.409437009055, 4642.53574642533, 4791.425028996543, 5113.463086375877, 5520.613557050968, 5537.972310138423], "value": 3935.7758759179587, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [620.5190976992832, 680.4794332335763, 681.9829504262395, 722.4076257468878, 731.4843035659857, 14820.703585928275, 15578.221088945564, 15581.298841552129, 15608.453704120358, 15650.53048408548, 14152.858471415282, 14231.715365692706, 14237.074590151658, 14238.430462781476, 14278.64303392413, 677.2076670295766, 699.0597646165899, 701.7052837808133, 709.9503034787567, 868.8175483148515, 694.8054544727485, 698.6646409723336, 699.6326928362624, 854.2055888024136, 882.7307671160294, 771.5717776633966, 930.2465003347311, 939.3800091939321, 949.0746522140927, 951.6241084771511, 934.2152591822834, 936.2369668719972, 937.0676177203692, 944.6646440513622, 945.645382981382, 534.4308311648122, 551.5008916930185, 706.1173533083645, 706.8798304288283, 707.723987644819, 13907.79138312926, 14423.567292981123, 14488.927555362156, 14825.77761333581, 14827.925860370737, 13713.314334283346, 14114.294285285694, 14300.92849535756, 14317.856821431757, 14336.784948225784, 11605.129615278887, 11777.882221177764, 11923.821142682868, 11950.701232469171, 12161.756764864653, 3668.816559172031, 3721.6092310307567, 3894.6689531389866, 3928.921421571581, 3941.2708648899757, 94654.52672736661, 96323.0, 96471.03528964691, 97941.0, 98324.0, 10383.428911409794, 11027.944860275647, 11076.833847492295, 11151.498182581803, 11219.55121795119, 22887.885560572424, 23235.0, 23244.3026709199, 23245.0, 23273.301800946, 6823.55646882951, 7789.104253010976, 7835.686572537149, 7846.058472983164, 7860.371170306362, 4217.177650358212, 4315.374270730789, 4326.502452218036, 4332.891677708082, 4334.089841133335, 3452.896413107611, 3517.683408493248, 3532.593751718534, 3565.94651080234, 3574.410222313289, 618.2086928731231, 628.6605233174126, 628.8396458902951, 640.4972096903908, 647.1037612906159, 571.2488078177183, 653.9378759017885, 656.6815094679076, 665.381195488191, 684.9691763870637, 8556.957215213884, 8993.595288212045, 9138.862917056249, 9293.67472138472, 9421.293402994803, 597.7429705226795, 645.3256347117274, 667.6494840208832, 675.0886303490249, 680.3434685528489, 4344.45694288214, 4485.192665320208, 4529.614982726505, 4543.7728113594185, 4611.538846115429, 76547.23452765512, 93162.53418732862, 96373.0, 98157.50921245349, 101778.96442071117, 81186.59406702929, 81607.0, 82054.0, 84609.15390846012, 84764.57617711995, 74855.0, 76344.85482717764, 76827.0, 77071.61464192643, 77239.0, 8936.151065648754, 8941.597628106621, 8960.551972401485, 8992.0, 9238.630454781733, 50835.0, 51435.88512918883, 52553.7372313136, 53335.733321334694, 53972.73013634907, 53857.0, 68612.97080543695, 71195.64402177956, 72791.0, 74916.0, 11784.469698863742, 12039.578614748441, 12285.754284914074, 12287.262764234174, 12517.248965062125, 12732.299723515296, 12933.288669123289, 13187.538436155073, 13364.465421383036, 13386.464541418234, 11968.0, 12024.458899349725, 12209.877901220874, 12275.877241227474, 12606.369681516071, 11796.4691588877, 11979.166666666515, 12247.69380765488, 12352.62942111738, 12410.937945310216, 11652.825207621723, 12043.458044387848, 12257.203281786655, 12400.31798251105, 12417.75164496687, 9910.30627856043, 9953.850692239477, 10147.594096236067, 10308.175345972455, 10385.896141038493, 10212.438315892698, 10362.585496580052, 10369.377837329785, 10404.167666586503, 10473.477004754997], "value": 7214.737455259078, "unit": "score"}], "name": "dromaeo_css", "value": 7115.542150602661, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [111.0, 111.0, 111.0, 113.0, 114.0, 111.0, 110.0, 113.0, 112.0, 113.0], "value": 111.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [143.0, 109.0, 108.0, 145.0, 111.0, 109.0, 107.0, 109.0, 128.0, 114.0], "value": 118.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [184.0, 182.0, 184.0, 184.0, 184.0, 187.0, 179.0, 184.0, 183.0, 182.0], "value": 183.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 59.0, 58.0, 90.0, 59.0, 58.0, 59.0, 49.0, 87.0, 75.0], "value": 67.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 71.0, 83.0, 70.0, 70.0, 68.0, 82.0, 70.0, 70.0, 108.0], "value": 77.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 84.0, 87.0, 85.0, 87.0, 84.0, 102.0, 111.0, 88.0, 89.0], "value": 90.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 93.0, 93.0, 96.0, 93.0, 94.0, 93.0, 96.0, 96.0, 93.0], "value": 94.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 84.0, 85.0, 88.0, 88.0, 84.0, 85.0, 87.0, 88.0, 88.0], "value": 86.4, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 53.0, 55.0, 53.0, 56.0, 54.0, 57.0, 56.0, 55.0, 55.0], "value": 55.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 72.0, 74.0, 73.0, 74.0, 68.0, 69.0, 69.0, 70.0, 72.0], "value": 70.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 89.0, 90.0, 87.0, 91.0, 90.0, 87.0, 91.0, 87.0, 88.0], "value": 89.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [143.0, 137.0, 144.0, 132.0, 139.0, 149.0, 149.0, 152.0, 184.0, 151.0], "value": 148.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [173.0, 171.0, 171.0, 178.0, 171.0, 171.0, 170.0, 170.0, 169.0, 171.0], "value": 171.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 67.0, 66.0, 66.0, 75.0, 88.0, 75.0, 66.0, 67.0, 66.0], "value": 70.4, "unit": "score"}], "name": "kraken", "value": 1434.2, "alertThreshold": 2.0}]} 20:15:56 INFO - Return code: 0 20:15:56 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 20:15:56 INFO - # TBPL SUCCESS # 20:15:56 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 20:15:56 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 20:15:56 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 20:15:56 INFO - 2016-12-22 20:15:56.144 screenresolution[1658:11318] starting screenresolution argv=screenresolution get 20:15:56 INFO - 2016-12-22 20:15:56.146 screenresolution[1658:11318] Display 0: 1600x1200x32@60 20:15:56 INFO - 2016-12-22 20:15:56.153 screenresolution[1659:11321] starting screenresolution argv=screenresolution list 20:15:56 INFO - Available Modes on Display 0 20:15:56 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 20:15:56 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 20:15:56 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 20:15:56 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 20:15:56 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 20:15:56 INFO - Intel Iris: 20:15:56 INFO - Chipset Model: Intel Iris 20:15:56 INFO - Type: GPU 20:15:56 INFO - Bus: Built-In 20:15:56 INFO - VRAM (Dynamic, Max): 1536 MB 20:15:56 INFO - Vendor: Intel (0x8086) 20:15:56 INFO - Device ID: 0x0a2e 20:15:56 INFO - Revision ID: 0x0009 20:15:56 INFO - Displays: 20:15:56 INFO - Display: 20:15:56 INFO - Resolution: 1600 x 1200 @ 60 Hz 20:15:56 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 20:15:56 INFO - Display Serial Number: 1600x1200 60 20:15:56 INFO - Main Display: Yes 20:15:56 INFO - Mirror: Off 20:15:56 INFO - Online: Yes 20:15:56 INFO - Rotation: Supported 20:15:56 INFO - Return code: 0 20:15:56 INFO - Running post-action listener: _resource_record_post_action 20:15:56 INFO - [mozharness: 2016-12-23 04:15:56.390125Z] Finished run-tests step (success) 20:15:56 INFO - Running post-run listener: _resource_record_post_run 20:15:56 INFO - Total resource usage - Wall time: 1201s; CPU: 54.0%; Read bytes: 32197632; Write bytes: 553628160; Read time: 194; Write time: 1680 20:15:56 INFO - TinderboxPrint: CPU usage
53.7% 20:15:56 INFO - TinderboxPrint: I/O read bytes / time
32,197,632 / 194 20:15:56 INFO - TinderboxPrint: I/O write bytes / time
553,628,160 / 1,680 20:15:56 INFO - TinderboxPrint: CPU idle
2,224.9 (46.3%) 20:15:56 INFO - TinderboxPrint: CPU system
960.0 (20.0%) 20:15:56 INFO - TinderboxPrint: CPU user
1,620.8 (33.7%) 20:15:56 INFO - TinderboxPrint: Swap in / out
277,397,504 / 0 20:15:56 INFO - install - Wall time: 22s; CPU: 54.0%; Read bytes: 226914816; Write bytes: 223614464; Read time: 16288; Write time: 497 20:15:56 INFO - run-tests - Wall time: 1180s; CPU: 54.0%; Read bytes: 31271936; Write bytes: 299711488; Read time: 181; Write time: 1116 20:15:56 INFO - Running post-run listener: _upload_blobber_files 20:15:56 INFO - Blob upload gear active. 20:15:56 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 20:15:56 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:15:56 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:15:56 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:15:57 INFO - (blobuploader) - INFO - Open directory for files ... 20:15:57 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 20:15:57 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 20:15:57 INFO - (blobuploader) - INFO - Uploading, attempt #1. 20:15:58 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 20:15:58 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 20:15:58 INFO - (blobuploader) - INFO - Done attempting. 20:15:58 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 20:15:58 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 20:15:58 INFO - (blobuploader) - INFO - Uploading, attempt #1. 20:15:59 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 20:15:59 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 20:15:59 INFO - (blobuploader) - INFO - Done attempting. 20:15:59 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 20:15:59 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 20:15:59 INFO - (blobuploader) - INFO - Uploading, attempt #1. 20:16:00 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 20:16:00 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 20:16:00 INFO - (blobuploader) - INFO - Done attempting. 20:16:00 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 20:16:00 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 20:16:00 INFO - (blobuploader) - INFO - Uploading, attempt #1. 20:16:02 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 20:16:02 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 20:16:02 INFO - (blobuploader) - INFO - Done attempting. 20:16:02 INFO - (blobuploader) - INFO - Iteration through files over. 20:16:02 INFO - Return code: 0 20:16:02 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 20:16:02 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 20:16:02 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/564a2b01bc19bc092ef7e5e5706021fee2b87b2d4ec7885e5bc934a6965cfba6f359ba73e3cd5dcfeffe5553fde4f2644e9aa00db7a6e1a379e1db60d59df3e0", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a32ed92a225b28145975395464f0fd58406784fc2dcedad3e27692451ca529e0ab0c4b740a7941748526ebfe47d474ccd0033661261f0445e1cf9017fdca6d06", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ce01d954cade19c7be7f0dbb79278a463dbc772066b738690ac9975e4b6439954895d1dd743e4f49293ae704f56f41720e9e5ffc019872159cf76e3244be4f7e", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a5ab080b88d09e92286ff8aaa39a2bfe3a1d55ffc5276ca86e5857cbae21b5cd19f3413b84021bb7c1a49ba1c36e36c6b744ce796ad69a582b70ebe38bc882d2"} 20:16:02 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 20:16:02 INFO - Writing to file /builds/slave/test/properties/blobber_files 20:16:02 INFO - Contents: 20:16:02 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/564a2b01bc19bc092ef7e5e5706021fee2b87b2d4ec7885e5bc934a6965cfba6f359ba73e3cd5dcfeffe5553fde4f2644e9aa00db7a6e1a379e1db60d59df3e0", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a32ed92a225b28145975395464f0fd58406784fc2dcedad3e27692451ca529e0ab0c4b740a7941748526ebfe47d474ccd0033661261f0445e1cf9017fdca6d06", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ce01d954cade19c7be7f0dbb79278a463dbc772066b738690ac9975e4b6439954895d1dd743e4f49293ae704f56f41720e9e5ffc019872159cf76e3244be4f7e", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a5ab080b88d09e92286ff8aaa39a2bfe3a1d55ffc5276ca86e5857cbae21b5cd19f3413b84021bb7c1a49ba1c36e36c6b744ce796ad69a582b70ebe38bc882d2"} 20:16:02 INFO - Running post-run listener: copy_logs_to_upload_dir 20:16:02 INFO - Copying logs to upload dir... 20:16:02 INFO - mkdir: /builds/slave/test/build/upload/logs 20:16:02 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1265.545069 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 5 secs) (at 2016-12-22 20:16:02.938090) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-22 20:16:02.941324) ========= 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.PvqjkwyGji/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.lic5rMQcuM/Listeners TMPDIR=/var/folders/m5/r6dgwqsj10g93g673s3k_wyc00000w/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/564a2b01bc19bc092ef7e5e5706021fee2b87b2d4ec7885e5bc934a6965cfba6f359ba73e3cd5dcfeffe5553fde4f2644e9aa00db7a6e1a379e1db60d59df3e0", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a32ed92a225b28145975395464f0fd58406784fc2dcedad3e27692451ca529e0ab0c4b740a7941748526ebfe47d474ccd0033661261f0445e1cf9017fdca6d06", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ce01d954cade19c7be7f0dbb79278a463dbc772066b738690ac9975e4b6439954895d1dd743e4f49293ae704f56f41720e9e5ffc019872159cf76e3244be4f7e", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a5ab080b88d09e92286ff8aaa39a2bfe3a1d55ffc5276ca86e5857cbae21b5cd19f3413b84021bb7c1a49ba1c36e36c6b744ce796ad69a582b70ebe38bc882d2"} build_url:https://queue.taskcluster.net/v1/task/L6HAsoCJSeOLxQK9LQEUjw/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011360 build_url: 'https://queue.taskcluster.net/v1/task/L6HAsoCJSeOLxQK9LQEUjw/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/564a2b01bc19bc092ef7e5e5706021fee2b87b2d4ec7885e5bc934a6965cfba6f359ba73e3cd5dcfeffe5553fde4f2644e9aa00db7a6e1a379e1db60d59df3e0", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a32ed92a225b28145975395464f0fd58406784fc2dcedad3e27692451ca529e0ab0c4b740a7941748526ebfe47d474ccd0033661261f0445e1cf9017fdca6d06", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ce01d954cade19c7be7f0dbb79278a463dbc772066b738690ac9975e4b6439954895d1dd743e4f49293ae704f56f41720e9e5ffc019872159cf76e3244be4f7e", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a5ab080b88d09e92286ff8aaa39a2bfe3a1d55ffc5276ca86e5857cbae21b5cd19f3413b84021bb7c1a49ba1c36e36c6b744ce796ad69a582b70ebe38bc882d2"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-22 20:16:02.980952) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-22 20:16:02.981620) ========= 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.PvqjkwyGji/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.lic5rMQcuM/Listeners TMPDIR=/var/folders/m5/r6dgwqsj10g93g673s3k_wyc00000w/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.005417 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-22 20:16:03.033642) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-22 20:16:03.034438) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-22 20:16:03.035209) ========= ========= Total master_lag: 0.21 =========