builder: mozilla-aurora_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0381 starttime: 1489115357.1 results: success (0) buildid: 20170309171123 builduid: 69496f50b8334ca7a1ab1af78656478c revision: a7744be94bea4581220cfce7c48e22fb96ac2bf3 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-03-09 19:09:17.104866) ========= master: http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-03-09 19:09:17.105334) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-09 19:09:17.105607) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-09 19:09:17.131159) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-09 19:09:17.131487) ========= 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.aSomOB5hf6/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.ckkPjw05hu/Listeners TMPDIR=/var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2017-03-09 19:09:17-- 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% 10.8M=0.001s 2017-03-09 19:09:17 (10.8 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.122107 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-09 19:09:17.276601) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-09 19:09:17.279938) ========= 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.aSomOB5hf6/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.ckkPjw05hu/Listeners TMPDIR=/var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/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.020824 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-09 19:09:17.328625) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-03-09 19:09:17.332032) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev a7744be94bea4581220cfce7c48e22fb96ac2bf3 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev a7744be94bea4581220cfce7c48e22fb96ac2bf3 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.aSomOB5hf6/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.ckkPjw05hu/Listeners TMPDIR=/var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2017-03-09 19:09:17,425 truncating revision to first 12 chars 2017-03-09 19:09:17,425 Setting DEBUG logging. 2017-03-09 19:09:17,425 attempt 1/10 2017-03-09 19:09:17,425 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/a7744be94bea?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-03-09 19:09:18,753 unpacking tar archive at: mozilla-aurora-a7744be94bea/testing/mozharness/ program finished with exit code 0 elapsedTime=1.592858 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-03-09 19:09:18.950024) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-09 19:09:18.950342) ========= script_repo_revision: a7744be94bea4581220cfce7c48e22fb96ac2bf3 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-09 19:09:18.950730) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-09 19:09:18.951009) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-09 19:09:18.970560) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 22 secs) (at 2017-03-09 19:09:18.970911) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 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-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Aurora', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.aSomOB5hf6/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.ckkPjw05hu/Listeners TMPDIR=/var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/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:09:19 INFO - MultiFileLogger online at 20170309 19:09:19 in /builds/slave/test 19:09:19 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 19:09:19 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 19:09:19 INFO - {'append_to_log': False, 19:09:19 INFO - 'base_work_dir': '/builds/slave/test', 19:09:19 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 19:09:19 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 19:09:19 INFO - 'branch': 'Mozilla-Aurora', 19:09:19 INFO - 'buildbot_json_path': 'buildprops.json', 19:09:19 INFO - 'config_files': ('talos/mac_config.py',), 19:09:19 INFO - 'default_actions': ('clobber', 19:09:19 INFO - 'read-buildbot-config', 19:09:19 INFO - 'download-and-extract', 19:09:19 INFO - 'populate-webroot', 19:09:19 INFO - 'create-virtualenv', 19:09:19 INFO - 'install', 19:09:19 INFO - 'run-tests'), 19:09:19 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 19:09:19 INFO - 'download_minidump_stackwalk': True, 19:09:19 INFO - 'download_symbols': 'ondemand', 19:09:19 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 19:09:19 INFO - 'tooltool.py': '/tools/tooltool.py', 19:09:19 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 19:09:19 INFO - '/tools/misc-python/virtualenv.py')}, 19:09:19 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 19:09:19 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 19:09:19 INFO - 'gecko_profile': False, 19:09:19 INFO - 'gecko_profile_interval': 0, 19:09:19 INFO - 'installer_path': 'installer.exe', 19:09:19 INFO - 'log_level': 'info', 19:09:19 INFO - 'log_name': 'talos', 19:09:19 INFO - 'log_to_console': True, 19:09:19 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 19:09:19 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 19:09:19 INFO - 'opt_config_files': (), 19:09:19 INFO - 'pip_index': False, 19:09:19 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 19:09:19 INFO - 'cmd': ('bash', 19:09:19 INFO - '-c', 19:09:19 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 19:09:19 INFO - 'enabled': True, 19:09:19 INFO - 'halt_on_failure': False, 19:09:19 INFO - 'name': 'check_screen_resolution'},), 19:09:19 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 19:09:19 INFO - 'cmd': ('bash', 19:09:19 INFO - '-c', 19:09:19 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 19:09:19 INFO - 'enabled': True, 19:09:19 INFO - 'halt_on_failure': False, 19:09:19 INFO - 'name': 'check_screen_resolution'},), 19:09:19 INFO - 'run_cmd_checks_enabled': True, 19:09:19 INFO - 'suite': 'dromaeojs-e10s', 19:09:19 INFO - 'system_bits': '32', 19:09:19 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 19:09:19 INFO - 'title': 't-yosemite-r7-0381', 19:09:19 INFO - 'tooltool_cache': '/builds/tooltool_cache', 19:09:19 INFO - 'use_talos_json': True, 19:09:19 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 19:09:19 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 19:09:19 INFO - 'work_dir': 'build'} 19:09:19 INFO - [mozharness: 2017-03-10 03:09:19.186340Z] Running clobber step. 19:09:19 INFO - Running pre-action listener: _resource_record_pre_action 19:09:19 INFO - Running main action method: clobber 19:09:19 INFO - rmtree: /builds/slave/test/build 19:09:19 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 19:09:20 INFO - Running post-action listener: _resource_record_post_action 19:09:20 INFO - [mozharness: 2017-03-10 03:09:20.950641Z] Finished clobber step (success) 19:09:20 INFO - [mozharness: 2017-03-10 03:09:20.950812Z] Running read-buildbot-config step. 19:09:20 INFO - Running pre-action listener: _resource_record_pre_action 19:09:20 INFO - Running main action method: read_buildbot_config 19:09:20 INFO - Using buildbot properties: 19:09:20 INFO - { 19:09:20 INFO - "project": "", 19:09:20 INFO - "product": "firefox", 19:09:20 INFO - "script_repo_revision": "production", 19:09:20 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs-e10s", 19:09:20 INFO - "repository": "", 19:09:20 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs-e10s", 19:09:20 INFO - "buildid": "20170309171123", 19:09:20 INFO - "pgo_build": "False", 19:09:20 INFO - "basedir": "/builds/slave/test", 19:09:20 INFO - "buildnumber": 7, 19:09:20 INFO - "slavename": "t-yosemite-r7-0381", 19:09:20 INFO - "master": "http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/", 19:09:20 INFO - "platform": "yosemite_r7", 19:09:20 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 19:09:20 INFO - "branch": "mozilla-aurora", 19:09:20 INFO - "repo_path": "releases/mozilla-aurora", 19:09:20 INFO - "revision": "a7744be94bea4581220cfce7c48e22fb96ac2bf3", 19:09:20 INFO - "stage_platform": "macosx64", 19:09:20 INFO - "builduid": "69496f50b8334ca7a1ab1af78656478c", 19:09:20 INFO - "slavebuilddir": "test" 19:09:20 INFO - } 19:09:20 INFO - Found installer url https://queue.taskcluster.net/v1/task/EEpWLpp1SSab7xvVlSXnIA/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg. 19:09:20 INFO - Running post-action listener: _resource_record_post_action 19:09:20 INFO - [mozharness: 2017-03-10 03:09:20.954173Z] Finished read-buildbot-config step (success) 19:09:20 INFO - [mozharness: 2017-03-10 03:09:20.954301Z] Running download-and-extract step. 19:09:20 INFO - Running pre-action listener: _resource_record_pre_action 19:09:20 INFO - Running main action method: download_and_extract 19:09:20 INFO - mkdir: /builds/slave/test/build/tests 19:09:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:09:20 INFO - trying https://queue.taskcluster.net/v1/task/EEpWLpp1SSab7xvVlSXnIA/artifacts/public/build/firefox-54.0a2.en-US.mac.test_packages.json 19:09:20 INFO - Downloading https://queue.taskcluster.net/v1/task/EEpWLpp1SSab7xvVlSXnIA/artifacts/public/build/firefox-54.0a2.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-54.0a2.en-US.mac.test_packages.json 19:09:20 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/EEpWLpp1SSab7xvVlSXnIA/artifacts/public/build/firefox-54.0a2.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-54.0a2.en-US.mac.test_packages.json'}, attempt #1 19:09:22 INFO - Downloaded 1199 bytes. 19:09:22 INFO - Reading from file /builds/slave/test/build/firefox-54.0a2.en-US.mac.test_packages.json 19:09:22 INFO - Using the following test package requirements: 19:09:22 INFO - {u'common': [u'firefox-54.0a2.en-US.mac.common.tests.zip'], 19:09:22 INFO - u'cppunittest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 19:09:22 INFO - u'firefox-54.0a2.en-US.mac.cppunittest.tests.zip'], 19:09:22 INFO - u'gtest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 19:09:22 INFO - u'firefox-54.0a2.en-US.mac.gtest.tests.zip'], 19:09:22 INFO - u'jittest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 19:09:22 INFO - u'jsshell-mac.zip'], 19:09:22 INFO - u'mochitest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 19:09:22 INFO - u'firefox-54.0a2.en-US.mac.mochitest.tests.zip'], 19:09:22 INFO - u'mozbase': [u'firefox-54.0a2.en-US.mac.common.tests.zip'], 19:09:22 INFO - u'reftest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 19:09:22 INFO - u'firefox-54.0a2.en-US.mac.reftest.tests.zip'], 19:09:22 INFO - u'talos': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 19:09:22 INFO - u'firefox-54.0a2.en-US.mac.talos.tests.zip'], 19:09:22 INFO - u'web-platform': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 19:09:22 INFO - u'firefox-54.0a2.en-US.mac.web-platform.tests.zip'], 19:09:22 INFO - u'xpcshell': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 19:09:22 INFO - u'firefox-54.0a2.en-US.mac.xpcshell.tests.zip']} 19:09:22 INFO - Downloading packages: [u'firefox-54.0a2.en-US.mac.common.tests.zip'] for test suite category: common 19:09:22 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/EEpWLpp1SSab7xvVlSXnIA/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip 19:09:22 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/EEpWLpp1SSab7xvVlSXnIA/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip'}, attempt #1 19:09:22 INFO - Fetch https://queue.taskcluster.net/v1/task/EEpWLpp1SSab7xvVlSXnIA/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip into memory 19:09:23 INFO - Expected file size: 51777830 19:09:23 INFO - Obtained file size: 51777830 19:09:39 INFO - Downloading packages: [u'firefox-54.0a2.en-US.mac.common.tests.zip', u'firefox-54.0a2.en-US.mac.talos.tests.zip'] for test suite category: talos 19:09:39 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/EEpWLpp1SSab7xvVlSXnIA/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip 19:09:39 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/EEpWLpp1SSab7xvVlSXnIA/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip'}, attempt #1 19:09:39 INFO - Fetch https://queue.taskcluster.net/v1/task/EEpWLpp1SSab7xvVlSXnIA/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip into memory 19:09:41 INFO - Expected file size: 51777830 19:09:41 INFO - Obtained file size: 51777830 19:09:56 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/EEpWLpp1SSab7xvVlSXnIA/artifacts/public/build/firefox-54.0a2.en-US.mac.talos.tests.zip 19:09:56 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/EEpWLpp1SSab7xvVlSXnIA/artifacts/public/build/firefox-54.0a2.en-US.mac.talos.tests.zip'}, attempt #1 19:09:56 INFO - Fetch https://queue.taskcluster.net/v1/task/EEpWLpp1SSab7xvVlSXnIA/artifacts/public/build/firefox-54.0a2.en-US.mac.talos.tests.zip into memory 19:09:57 INFO - Expected file size: 14404424 19:09:57 INFO - Obtained file size: 14404424 19:09:58 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:09:58 INFO - trying https://queue.taskcluster.net/v1/task/EEpWLpp1SSab7xvVlSXnIA/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg 19:09:58 INFO - Downloading https://queue.taskcluster.net/v1/task/EEpWLpp1SSab7xvVlSXnIA/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-54.0a2.en-US.mac.dmg 19:09:58 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/EEpWLpp1SSab7xvVlSXnIA/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-54.0a2.en-US.mac.dmg'}, attempt #1 19:10:00 INFO - Downloaded 74618548 bytes. 19:10:00 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/EEpWLpp1SSab7xvVlSXnIA/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg 19:10:00 INFO - mkdir: /builds/slave/test/properties 19:10:00 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 19:10:00 INFO - Writing to file /builds/slave/test/properties/build_url 19:10:00 INFO - Contents: 19:10:00 INFO - build_url:https://queue.taskcluster.net/v1/task/EEpWLpp1SSab7xvVlSXnIA/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg 19:10:00 INFO - Running post-action listener: _resource_record_post_action 19:10:00 INFO - Running post-action listener: set_extra_try_arguments 19:10:00 INFO - [mozharness: 2017-03-10 03:10:00.890128Z] Finished download-and-extract step (success) 19:10:00 INFO - [mozharness: 2017-03-10 03:10:00.890282Z] Running populate-webroot step. 19:10:00 INFO - Running pre-action listener: _resource_record_pre_action 19:10:00 INFO - Running main action method: populate_webroot 19:10:00 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 19:10:00 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 19:10:00 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 19:10:00 INFO - '--tppagecycles', 19:10:00 INFO - '7'], 19:10:00 INFO - 'tests': ['tsvgm']}}, 19:10:00 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 19:10:00 INFO - 'tests': ['tresize', 'tcanvasmark']}, 19:10:00 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 19:10:00 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 19:10:00 INFO - 'tests': ['dromaeo_css', 'kraken']}, 19:10:00 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 19:10:00 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 19:10:00 INFO - 'talos_options': ['--disable-e10s'], 19:10:00 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 19:10:00 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 19:10:00 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 19:10:00 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 19:10:00 INFO - 'talos_options': ['--disable-e10s'], 19:10:00 INFO - 'tests': ['damp', 'tps']}, 19:10:00 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 19:10:00 INFO - 'tests': ['damp', 'tps']}, 19:10:00 INFO - 'g3': {'talos_options': ['--disable-e10s'], 19:10:00 INFO - 'tests': ['dromaeo_dom']}, 19:10:00 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 19:10:00 INFO - 'g4': {'talos_options': ['--disable-e10s'], 19:10:00 INFO - 'tests': ['basic_compositor_video']}, 19:10:00 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 19:10:00 INFO - 'other': {'talos_options': ['--disable-e10s'], 19:10:00 INFO - 'tests': ['a11yr', 19:10:00 INFO - 'ts_paint', 19:10:00 INFO - 'tpaint', 19:10:00 INFO - 'sessionrestore', 19:10:00 INFO - 'sessionrestore_no_auto_restore', 19:10:00 INFO - 'tabpaint']}, 19:10:00 INFO - 'other-e10s': {'tests': ['a11yr', 19:10:00 INFO - 'ts_paint', 19:10:00 INFO - 'tpaint', 19:10:00 INFO - 'sessionrestore', 19:10:00 INFO - 'sessionrestore_no_auto_restore', 19:10:00 INFO - 'tabpaint']}, 19:10:00 INFO - 'other-e10s_l64': {'tests': ['a11yr', 19:10:00 INFO - 'ts_paint', 19:10:00 INFO - 'tpaint', 19:10:00 INFO - 'sessionrestore', 19:10:00 INFO - 'sessionrestore_no_auto_restore', 19:10:00 INFO - 'tabpaint']}, 19:10:00 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 19:10:00 INFO - 'ts_paint', 19:10:00 INFO - 'tpaint', 19:10:00 INFO - 'sessionrestore', 19:10:00 INFO - 'sessionrestore_no_auto_restore', 19:10:00 INFO - 'tabpaint']}, 19:10:00 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 19:10:00 INFO - 'tests': ['a11yr', 19:10:00 INFO - 'ts_paint', 19:10:00 INFO - 'tpaint', 19:10:00 INFO - 'sessionrestore', 19:10:00 INFO - 'sessionrestore_no_auto_restore', 19:10:00 INFO - 'tabpaint']}, 19:10:00 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 19:10:00 INFO - 'tests': ['a11yr', 19:10:00 INFO - 'ts_paint', 19:10:00 INFO - 'tpaint', 19:10:00 INFO - 'sessionrestore', 19:10:00 INFO - 'sessionrestore_no_auto_restore', 19:10:00 INFO - 'tabpaint']}, 19:10:00 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 19:10:00 INFO - 'tests': ['tsvgx', 19:10:00 INFO - 'tsvgr_opacity', 19:10:00 INFO - 'tart', 19:10:00 INFO - 'tscrollx', 19:10:00 INFO - 'cart', 19:10:00 INFO - 'tsvg_static']}, 19:10:00 INFO - 'svgr-e10s': {'tests': ['tsvgx', 19:10:00 INFO - 'tsvgr_opacity', 19:10:00 INFO - 'tart', 19:10:00 INFO - 'tscrollx', 19:10:00 INFO - 'cart', 19:10:00 INFO - 'tsvg_static']}, 19:10:00 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 19:10:00 INFO - 'talos_options': ['--disable-e10s'], 19:10:00 INFO - 'tests': ['tp5o']}, 19:10:00 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 19:10:00 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 19:10:00 INFO - 'talos_options': ['--disable-e10s', 19:10:00 INFO - '--xperf_path', 19:10:00 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 19:10:00 INFO - 'tests': ['tp5n']}, 19:10:00 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 19:10:00 INFO - 'talos_options': ['--xperf_path', 19:10:00 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 19:10:00 INFO - 'tests': ['tp5n']}}, 19:10:00 INFO - 'talos.zip': {'path': '', 19:10:00 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 19:10:00 INFO - Running post-action listener: _resource_record_post_action 19:10:00 INFO - [mozharness: 2017-03-10 03:10:00.908818Z] Finished populate-webroot step (success) 19:10:00 INFO - [mozharness: 2017-03-10 03:10:00.908915Z] Running create-virtualenv step. 19:10:00 INFO - Running pre-action listener: _resource_record_pre_action 19:10:00 INFO - Running main action method: create_virtualenv 19:10:00 INFO - Creating virtualenv /builds/slave/test/build/venv 19:10:00 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:10:00 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 19:10:01 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 19:10:01 INFO - Using real prefix '/tools/python27' 19:10:01 INFO - New python executable in /builds/slave/test/build/venv/bin/python 19:10:01 INFO - Installing distribute.............................................................................................................................................................................................done. 19:10:04 INFO - Installing pip.................done. 19:10:04 INFO - Return code: 0 19:10:04 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 19:10:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 19:10:04 INFO - Reading from file tmpfile_stdout 19:10:04 INFO - Output received: 19:10:04 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:10:04 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 19:10:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:10:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:10:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:10:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:10:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:10:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:10:04 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 0x10a2b3b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a2b6768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8be8ca2150>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a091d30>, '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 0x109fb8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a086be8>, '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.aSomOB5hf6/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.ckkPjw05hu/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/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:10:04 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:10:04 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:10:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.aSomOB5hf6/Render', 19:10:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:10:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:10:04 INFO - 'HOME': '/Users/cltbld', 19:10:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:10:04 INFO - 'LOGNAME': 'cltbld', 19:10:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:10:04 INFO - 'MOZ_NO_REMOTE': '1', 19:10:04 INFO - 'NO_EM_RESTART': '1', 19:10:04 INFO - 'PAGER': '/bin/cat', 19:10:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:10:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:10:04 INFO - 'PWD': '/builds/slave/test', 19:10:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:10:04 INFO - 'SHELL': '/bin/bash', 19:10:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ckkPjw05hu/Listeners', 19:10:04 INFO - 'TMPDIR': '/var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/T/', 19:10:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:10:04 INFO - 'USER': 'cltbld', 19:10:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:10:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:10:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:10:04 INFO - 'XPC_FLAGS': '0x0', 19:10:04 INFO - 'XPC_SERVICE_NAME': '0', 19:10:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:10:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:10:05 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:10:05 INFO - Cleaning up... 19:10:05 INFO - Return code: 0 19:10:05 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 19:10:05 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:10:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:10:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:10:05 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:10:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:10:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:10:05 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 0x10a2b3b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a2b6768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8be8ca2150>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a091d30>, '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 0x109fb8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a086be8>, '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.aSomOB5hf6/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.ckkPjw05hu/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/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:10:05 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:10:05 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:10:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.aSomOB5hf6/Render', 19:10:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:10:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:10:05 INFO - 'HOME': '/Users/cltbld', 19:10:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:10:05 INFO - 'LOGNAME': 'cltbld', 19:10:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:10:05 INFO - 'MOZ_NO_REMOTE': '1', 19:10:05 INFO - 'NO_EM_RESTART': '1', 19:10:05 INFO - 'PAGER': '/bin/cat', 19:10:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:10:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:10:05 INFO - 'PWD': '/builds/slave/test', 19:10:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:10:05 INFO - 'SHELL': '/bin/bash', 19:10:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ckkPjw05hu/Listeners', 19:10:05 INFO - 'TMPDIR': '/var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/T/', 19:10:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:10:05 INFO - 'USER': 'cltbld', 19:10:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:10:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:10:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:10:05 INFO - 'XPC_FLAGS': '0x0', 19:10:05 INFO - 'XPC_SERVICE_NAME': '0', 19:10:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:10:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:10:05 INFO - Downloading/unpacking psutil>=3.1.1 19:10:05 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:10:05 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:10:05 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:10:05 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:10:05 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:10:05 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:10:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 19:10:08 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 19:10:08 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 19:10:08 INFO - Installing collected packages: psutil 19:10:08 INFO - Running setup.py install for psutil 19:10:09 INFO - building 'psutil._psutil_osx' extension 19:10:09 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:10:09 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:10:09 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:10:09 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:10:09 INFO - building 'psutil._psutil_posix' extension 19:10:09 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:10:09 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 19:10:09 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 19:10:09 INFO - ^ 19:10:09 INFO - 1 warning generated. 19:10:09 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:10:09 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 19:10:09 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 19:10:09 INFO - Successfully installed psutil 19:10:09 INFO - Cleaning up... 19:10:09 INFO - Return code: 0 19:10:09 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 19:10:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:10:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:10:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:10:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:10:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:10:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:10:09 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 0x10a2b3b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a2b6768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8be8ca2150>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a091d30>, '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 0x109fb8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a086be8>, '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.aSomOB5hf6/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.ckkPjw05hu/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/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:10:09 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:10:09 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:10:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.aSomOB5hf6/Render', 19:10:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:10:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:10:09 INFO - 'HOME': '/Users/cltbld', 19:10:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:10:09 INFO - 'LOGNAME': 'cltbld', 19:10:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:10:09 INFO - 'MOZ_NO_REMOTE': '1', 19:10:09 INFO - 'NO_EM_RESTART': '1', 19:10:09 INFO - 'PAGER': '/bin/cat', 19:10:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:10:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:10:09 INFO - 'PWD': '/builds/slave/test', 19:10:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:10:09 INFO - 'SHELL': '/bin/bash', 19:10:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ckkPjw05hu/Listeners', 19:10:09 INFO - 'TMPDIR': '/var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/T/', 19:10:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:10:09 INFO - 'USER': 'cltbld', 19:10:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:10:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:10:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:10:09 INFO - 'XPC_FLAGS': '0x0', 19:10:09 INFO - 'XPC_SERVICE_NAME': '0', 19:10:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:10:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:10:10 INFO - Downloading/unpacking mozsystemmonitor==0.3 19:10:10 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:10:10 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:10:10 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:10:10 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:10:10 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:10:10 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:10:12 INFO - Downloading mozsystemmonitor-0.3.tar.gz 19:10:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 19:10:12 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 19:10:12 INFO - Installing collected packages: mozsystemmonitor 19:10:12 INFO - Running setup.py install for mozsystemmonitor 19:10:12 INFO - Successfully installed mozsystemmonitor 19:10:12 INFO - Cleaning up... 19:10:13 INFO - Return code: 0 19:10:13 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 19:10:13 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:10:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:10:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:10:13 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:10:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:10:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:10:13 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 0x10a2b3b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a2b6768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8be8ca2150>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a091d30>, '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 0x109fb8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a086be8>, '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.aSomOB5hf6/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.ckkPjw05hu/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/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:10:13 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:10:13 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:10:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.aSomOB5hf6/Render', 19:10:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:10:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:10:13 INFO - 'HOME': '/Users/cltbld', 19:10:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:10:13 INFO - 'LOGNAME': 'cltbld', 19:10:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:10:13 INFO - 'MOZ_NO_REMOTE': '1', 19:10:13 INFO - 'NO_EM_RESTART': '1', 19:10:13 INFO - 'PAGER': '/bin/cat', 19:10:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:10:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:10:13 INFO - 'PWD': '/builds/slave/test', 19:10:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:10:13 INFO - 'SHELL': '/bin/bash', 19:10:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ckkPjw05hu/Listeners', 19:10:13 INFO - 'TMPDIR': '/var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/T/', 19:10:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:10:13 INFO - 'USER': 'cltbld', 19:10:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:10:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:10:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:10:13 INFO - 'XPC_FLAGS': '0x0', 19:10:13 INFO - 'XPC_SERVICE_NAME': '0', 19:10:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:10:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:10:14 INFO - Downloading/unpacking jsonschema==2.5.1 19:10:14 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:10:14 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:10:14 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:10:14 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:10:14 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:10:14 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:10:17 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 19:10:17 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 19:10:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:10:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:10:17 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:10:17 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:10:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:10:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:10:17 INFO - Downloading functools32-3.2.3-2.tar.gz 19:10:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 19:10:17 INFO - warning: no files found matching '*.txt' 19:10:17 INFO - no previously-included directories found matching 'build' 19:10:17 INFO - no previously-included directories found matching 'dist' 19:10:17 INFO - no previously-included directories found matching '.git*' 19:10:17 INFO - Installing collected packages: jsonschema, functools32 19:10:17 INFO - Running setup.py install for functools32 19:10:17 INFO - warning: no files found matching '*.txt' 19:10:17 INFO - no previously-included directories found matching 'build' 19:10:17 INFO - no previously-included directories found matching 'dist' 19:10:17 INFO - no previously-included directories found matching '.git*' 19:10:17 INFO - Successfully installed jsonschema functools32 19:10:17 INFO - Cleaning up... 19:10:18 INFO - Return code: 0 19:10:18 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 19:10: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:10:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:10:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:10: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:10:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:10:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:10:18 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10a2b3b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a2b6768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8be8ca2150>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a091d30>, '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 0x109fb8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a086be8>, '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.aSomOB5hf6/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.ckkPjw05hu/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/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:10:18 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 19:10:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 19:10:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.aSomOB5hf6/Render', 19:10:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:10:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:10:18 INFO - 'HOME': '/Users/cltbld', 19:10:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:10:18 INFO - 'LOGNAME': 'cltbld', 19:10:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:10:18 INFO - 'MOZ_NO_REMOTE': '1', 19:10:18 INFO - 'NO_EM_RESTART': '1', 19:10:18 INFO - 'PAGER': '/bin/cat', 19:10:18 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:10:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:10:18 INFO - 'PWD': '/builds/slave/test', 19:10:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:10:18 INFO - 'SHELL': '/bin/bash', 19:10:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ckkPjw05hu/Listeners', 19:10:18 INFO - 'TMPDIR': '/var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/T/', 19:10:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:10:18 INFO - 'USER': 'cltbld', 19:10:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:10:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:10:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:10:18 INFO - 'XPC_FLAGS': '0x0', 19:10:18 INFO - 'XPC_SERVICE_NAME': '0', 19:10:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:10:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:10:18 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 19:10:18 INFO - Cleaning up... 19:10:18 INFO - Return code: 0 19:10:18 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 19:10: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:10:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:10:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:10: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:10:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:10:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:10:18 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10a2b3b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a2b6768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8be8ca2150>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a091d30>, '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 0x109fb8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a086be8>, '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.aSomOB5hf6/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.ckkPjw05hu/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/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:10:18 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 19:10:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 19:10:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.aSomOB5hf6/Render', 19:10:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:10:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:10:18 INFO - 'HOME': '/Users/cltbld', 19:10:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:10:18 INFO - 'LOGNAME': 'cltbld', 19:10:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:10:18 INFO - 'MOZ_NO_REMOTE': '1', 19:10:18 INFO - 'NO_EM_RESTART': '1', 19:10:18 INFO - 'PAGER': '/bin/cat', 19:10:18 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:10:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:10:18 INFO - 'PWD': '/builds/slave/test', 19:10:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:10:18 INFO - 'SHELL': '/bin/bash', 19:10:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ckkPjw05hu/Listeners', 19:10:18 INFO - 'TMPDIR': '/var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/T/', 19:10:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:10:18 INFO - 'USER': 'cltbld', 19:10:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:10:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:10:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:10:18 INFO - 'XPC_FLAGS': '0x0', 19:10:18 INFO - 'XPC_SERVICE_NAME': '0', 19:10:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:10:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:10:18 INFO - Downloading/unpacking blobuploader==1.2.4 19:10:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:10:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:10:18 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:10:18 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:10:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:10:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:10:21 INFO - Downloading blobuploader-1.2.4.tar.gz 19:10:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 19:10:21 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 19:10:21 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:10:21 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:10:21 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:10:21 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:10:21 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:10:21 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:10:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 19:10:22 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 19:10:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:10:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:10:22 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:10:22 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:10:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:10:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:10:22 INFO - Downloading docopt-0.6.1.tar.gz 19:10:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 19:10:22 INFO - Installing collected packages: blobuploader, requests, docopt 19:10:22 INFO - Running setup.py install for blobuploader 19:10:22 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 19:10:22 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 19:10:22 INFO - Running setup.py install for requests 19:10:23 INFO - Running setup.py install for docopt 19:10:23 INFO - Successfully installed blobuploader requests docopt 19:10:23 INFO - Cleaning up... 19:10:23 INFO - Return code: 0 19:10:23 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:10:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:10:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:10:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:10:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:10:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:10:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:10:23 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 0x10a2b3b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a2b6768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8be8ca2150>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a091d30>, '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 0x109fb8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a086be8>, '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.aSomOB5hf6/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.ckkPjw05hu/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/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:10:23 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:10:23 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:10:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.aSomOB5hf6/Render', 19:10:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:10:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:10:23 INFO - 'HOME': '/Users/cltbld', 19:10:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:10:23 INFO - 'LOGNAME': 'cltbld', 19:10:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:10:23 INFO - 'MOZ_NO_REMOTE': '1', 19:10:23 INFO - 'NO_EM_RESTART': '1', 19:10:23 INFO - 'PAGER': '/bin/cat', 19:10:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:10:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:10:23 INFO - 'PWD': '/builds/slave/test', 19:10:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:10:23 INFO - 'SHELL': '/bin/bash', 19:10:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ckkPjw05hu/Listeners', 19:10:23 INFO - 'TMPDIR': '/var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/T/', 19:10:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:10:23 INFO - 'USER': 'cltbld', 19:10:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:10:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:10:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:10:23 INFO - 'XPC_FLAGS': '0x0', 19:10:23 INFO - 'XPC_SERVICE_NAME': '0', 19:10:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:10:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:10:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 19:10:23 INFO - Running setup.py (path:/var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/T/pip-V3QNM5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 19:10:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 19:10:23 INFO - Running setup.py (path:/var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/T/pip-AkPaZk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 19:10:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 19:10:23 INFO - Running setup.py (path:/var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/T/pip-jpIKpv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 19:10:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 19:10:24 INFO - Running setup.py (path:/var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/T/pip-EqTdF8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 19:10:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 19:10:24 INFO - Running setup.py (path:/var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/T/pip-7g97wn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 19:10:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 19:10:24 INFO - Running setup.py (path:/var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/T/pip-Cz9tUB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 19:10:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 19:10:24 INFO - Running setup.py (path:/var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/T/pip-vOwTJI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 19:10:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 19:10:24 INFO - Running setup.py (path:/var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/T/pip-NYSLDp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 19:10:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 19:10:24 INFO - Running setup.py (path:/var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/T/pip-yRnoR9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 19:10:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 19:10:24 INFO - Running setup.py (path:/var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/T/pip-6A1dCJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 19:10:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 19:10:24 INFO - Running setup.py (path:/var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/T/pip-fIDBM3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 19:10:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 19:10:24 INFO - Running setup.py (path:/var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/T/pip-4BDJ9V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 19:10:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 19:10:25 INFO - Running setup.py (path:/var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/T/pip-vokDYZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 19:10:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 19:10:25 INFO - Running setup.py (path:/var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/T/pip-HwQjnP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 19:10:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 19:10:25 INFO - Running setup.py (path:/var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/T/pip-bko2Je-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 19:10:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 19:10:25 INFO - Running setup.py (path:/var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/T/pip-HySoY6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 19:10:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 19:10:25 INFO - Running setup.py (path:/var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/T/pip-ixP5q_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 19:10:25 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 19:10:25 INFO - Running setup.py install for manifestparser 19:10:25 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 19:10:25 INFO - Running setup.py install for mozcrash 19:10:25 INFO - Running setup.py install for mozdebug 19:10:25 INFO - Running setup.py install for mozdevice 19:10:26 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 19:10:26 INFO - Installing dm script to /builds/slave/test/build/venv/bin 19:10:26 INFO - Running setup.py install for mozfile 19:10:26 INFO - Running setup.py install for mozhttpd 19:10:26 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 19:10:26 INFO - Running setup.py install for mozinfo 19:10:26 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 19:10:26 INFO - Running setup.py install for mozInstall 19:10:26 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 19:10:26 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 19:10:26 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 19:10:26 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 19:10:26 INFO - Running setup.py install for mozleak 19:10:26 INFO - Running setup.py install for mozlog 19:10:27 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 19:10:27 INFO - Running setup.py install for moznetwork 19:10:27 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 19:10:27 INFO - Running setup.py install for mozprocess 19:10:27 INFO - Running setup.py install for mozprofile 19:10:27 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 19:10:27 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 19:10:27 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 19:10:27 INFO - Running setup.py install for mozrunner 19:10:27 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 19:10:27 INFO - Running setup.py install for mozscreenshot 19:10:27 INFO - Running setup.py install for moztest 19:10:27 INFO - Running setup.py install for mozversion 19:10:28 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 19:10:28 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 19:10:28 INFO - Cleaning up... 19:10:28 INFO - Return code: 0 19:10:28 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:10:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:10:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:10:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:10:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:10:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:10:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:10:28 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 0x10a2b3b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a2b6768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8be8ca2150>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a091d30>, '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 0x109fb8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a086be8>, '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.aSomOB5hf6/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.ckkPjw05hu/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/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:10:28 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:10:28 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:10:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.aSomOB5hf6/Render', 19:10:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:10:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:10:28 INFO - 'HOME': '/Users/cltbld', 19:10:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:10:28 INFO - 'LOGNAME': 'cltbld', 19:10:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:10:28 INFO - 'MOZ_NO_REMOTE': '1', 19:10:28 INFO - 'NO_EM_RESTART': '1', 19:10:28 INFO - 'PAGER': '/bin/cat', 19:10:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:10:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:10:28 INFO - 'PWD': '/builds/slave/test', 19:10:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:10:28 INFO - 'SHELL': '/bin/bash', 19:10:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ckkPjw05hu/Listeners', 19:10:28 INFO - 'TMPDIR': '/var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/T/', 19:10:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:10:28 INFO - 'USER': 'cltbld', 19:10:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:10:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:10:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:10:28 INFO - 'XPC_FLAGS': '0x0', 19:10:28 INFO - 'XPC_SERVICE_NAME': '0', 19:10:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:10:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:10:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 19:10:28 INFO - Running setup.py (path:/var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/T/pip-XLuHRr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 19:10:28 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:10:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 19:10:28 INFO - Running setup.py (path:/var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/T/pip-iMxvzg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 19:10:28 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:10:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 19:10:28 INFO - Running setup.py (path:/var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/T/pip-ejfIBy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 19:10:28 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:10:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 19:10:28 INFO - Running setup.py (path:/var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/T/pip-GYuk52-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 19:10:28 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:10:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 19:10:28 INFO - Running setup.py (path:/var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/T/pip-G9Xh7h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 19:10:28 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:10:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 19:10:28 INFO - Running setup.py (path:/var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/T/pip-hrBok6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 19:10:29 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:10:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 19:10:29 INFO - Running setup.py (path:/var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/T/pip-AWkapQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 19:10:29 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:10:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 19:10:29 INFO - Running setup.py (path:/var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/T/pip-bwLoJR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 19:10:29 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:10:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 19:10:29 INFO - Running setup.py (path:/var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/T/pip-CVaNO4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 19:10:29 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:10:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 19:10:29 INFO - Running setup.py (path:/var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/T/pip-cfOVTc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 19:10:29 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:10:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 19:10:29 INFO - Running setup.py (path:/var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/T/pip-ZZatc4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 19:10:29 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:10:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 19:10:29 INFO - Running setup.py (path:/var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/T/pip-rodBUU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 19:10:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 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:10:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 19:10:29 INFO - Running setup.py (path:/var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/T/pip-VNx5aR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 19:10:29 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:10:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 19:10:29 INFO - Running setup.py (path:/var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/T/pip-OiJxrT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 19:10:29 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:10:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 19:10:29 INFO - Running setup.py (path:/var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/T/pip-4IHleT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 19:10:29 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:10:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 19:10:29 INFO - Running setup.py (path:/var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/T/pip-e5dWer-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 19:10:30 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 19:10:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 19:10:30 INFO - Running setup.py (path:/var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/T/pip-qkkGH_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 19:10:30 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:10:30 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:10:30 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:10:30 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:10:30 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:10:30 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:10:30 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 19:10:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:10:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:10:30 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:10:30 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:10:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:10:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:10:32 INFO - Downloading blessings-1.6.tar.gz 19:10:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 19:10:32 INFO - Installing collected packages: blessings 19:10:32 INFO - Running setup.py install for blessings 19:10:33 INFO - Successfully installed blessings 19:10:33 INFO - Cleaning up... 19:10:33 INFO - Return code: 0 19:10:33 INFO - Done creating virtualenv /builds/slave/test/build/venv. 19:10:33 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 19:10:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 19:10:33 INFO - Reading from file tmpfile_stdout 19:10:33 INFO - Current package versions: 19:10:33 INFO - blessings == 1.6 19:10:33 INFO - blobuploader == 1.2.4 19:10:33 INFO - docopt == 0.6.1 19:10:33 INFO - functools32 == 3.2.3-2 19:10:33 INFO - jsonschema == 2.5.1 19:10:33 INFO - manifestparser == 1.1 19:10:33 INFO - mozInstall == 1.12 19:10:33 INFO - mozcrash == 1.0 19:10:33 INFO - mozdebug == 0.1 19:10:33 INFO - mozdevice == 0.49 19:10:33 INFO - mozfile == 1.2 19:10:33 INFO - mozhttpd == 0.7 19:10:33 INFO - mozinfo == 0.9 19:10:33 INFO - mozleak == 0.1 19:10:33 INFO - mozlog == 3.4 19:10:33 INFO - moznetwork == 0.27 19:10:33 INFO - mozprocess == 0.25 19:10:33 INFO - mozprofile == 0.28 19:10:33 INFO - mozrunner == 6.13 19:10:33 INFO - mozscreenshot == 0.1 19:10:33 INFO - mozsystemmonitor == 0.3 19:10:33 INFO - moztest == 0.8 19:10:33 INFO - mozversion == 1.4 19:10:33 INFO - psutil == 3.1.1 19:10:33 INFO - requests == 1.2.3 19:10:33 INFO - wsgiref == 0.1.2 19:10:33 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:10:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:10:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:10:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:10:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:10:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:10:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:10:33 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 0x10a2b3b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a2b6768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8be8ca2150>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a091d30>, '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 0x109fb8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a086be8>, '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.aSomOB5hf6/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.ckkPjw05hu/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/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:10:33 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:10:33 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:10:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.aSomOB5hf6/Render', 19:10:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:10:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:10:33 INFO - 'HOME': '/Users/cltbld', 19:10:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:10:33 INFO - 'LOGNAME': 'cltbld', 19:10:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:10:33 INFO - 'MOZ_NO_REMOTE': '1', 19:10:33 INFO - 'NO_EM_RESTART': '1', 19:10:33 INFO - 'PAGER': '/bin/cat', 19:10:33 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:10:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:10:33 INFO - 'PWD': '/builds/slave/test', 19:10:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:10:33 INFO - 'SHELL': '/bin/bash', 19:10:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ckkPjw05hu/Listeners', 19:10:33 INFO - 'TMPDIR': '/var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/T/', 19:10:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:10:33 INFO - 'USER': 'cltbld', 19:10:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:10:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:10:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:10:33 INFO - 'XPC_FLAGS': '0x0', 19:10:33 INFO - 'XPC_SERVICE_NAME': '0', 19:10:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:10:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:10:33 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:10:33 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:10:33 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:10:33 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:10:33 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:10:33 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:10:33 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:10:33 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:10:33 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:10:33 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:10:33 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:10:33 INFO - Cleaning up... 19:10:33 INFO - Return code: 0 19:10:33 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 19:10:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:10:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:10:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:10:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:10:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:10:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:10:33 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 0x10a2b3b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a2b6768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8be8ca2150>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a091d30>, '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 0x109fb8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a086be8>, '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.aSomOB5hf6/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.ckkPjw05hu/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/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:10:33 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:10:33 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:10:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.aSomOB5hf6/Render', 19:10:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:10:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:10:33 INFO - 'HOME': '/Users/cltbld', 19:10:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:10:33 INFO - 'LOGNAME': 'cltbld', 19:10:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:10:33 INFO - 'MOZ_NO_REMOTE': '1', 19:10:33 INFO - 'NO_EM_RESTART': '1', 19:10:33 INFO - 'PAGER': '/bin/cat', 19:10:33 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:10:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:10:33 INFO - 'PWD': '/builds/slave/test', 19:10:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:10:33 INFO - 'SHELL': '/bin/bash', 19:10:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ckkPjw05hu/Listeners', 19:10:33 INFO - 'TMPDIR': '/var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/T/', 19:10:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:10:33 INFO - 'USER': 'cltbld', 19:10:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:10:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:10:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:10:33 INFO - 'XPC_FLAGS': '0x0', 19:10:33 INFO - 'XPC_SERVICE_NAME': '0', 19:10:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:10:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:10:34 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 19:10:34 INFO - Cleaning up... 19:10:34 INFO - Return code: 0 19:10:34 INFO - Running post-action listener: _resource_record_post_action 19:10:34 INFO - Running post-action listener: _start_resource_monitoring 19:10:34 INFO - Starting resource monitoring. 19:10:34 INFO - [mozharness: 2017-03-10 03:10:34.061497Z] Finished create-virtualenv step (success) 19:10:34 INFO - [mozharness: 2017-03-10 03:10:34.062521Z] Running install step. 19:10:34 INFO - Running pre-action listener: _resource_record_pre_action 19:10:34 INFO - Running main action method: install 19:10:34 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 19:10:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 19:10:34 INFO - Reading from file tmpfile_stdout 19:10:34 INFO - Detecting whether we're running mozinstall >=1.0... 19:10:34 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 19:10:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 19:10:34 INFO - Reading from file tmpfile_stdout 19:10:34 INFO - Output received: 19:10:34 INFO - Usage: mozinstall [options] installer 19:10:34 INFO - Options: 19:10:34 INFO - -h, --help show this help message and exit 19:10:34 INFO - -d DEST, --destination=DEST 19:10:34 INFO - Directory to install application into. [default: 19:10:34 INFO - "/builds/slave/test"] 19:10:34 INFO - --app=APP Application being installed. [default: firefox] 19:10:34 INFO - mkdir: /builds/slave/test/build/application 19:10:34 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-54.0a2.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 19:10:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-54.0a2.en-US.mac.dmg --destination /builds/slave/test/build/application 19:10:54 INFO - Reading from file tmpfile_stdout 19:10:54 INFO - Output received: 19:10:54 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 19:10:54 INFO - Running post-action listener: _resource_record_post_action 19:10:54 INFO - [mozharness: 2017-03-10 03:10:54.455766Z] Finished install step (success) 19:10:54 INFO - [mozharness: 2017-03-10 03:10:54.455902Z] Running run-tests step. 19:10:54 INFO - Running pre-action listener: _resource_record_pre_action 19:10:54 INFO - Running main action method: run_tests 19:10:54 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 19:10:54 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 19:10:54 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 19:10:54 INFO - 2017-03-09 19:10:54.469 screenresolution[1907:13874] starting screenresolution argv=screenresolution get 19:10:54 INFO - 2017-03-09 19:10:54.472 screenresolution[1907:13874] Display 0: 1600x1200x32@60 19:10:54 INFO - 2017-03-09 19:10:54.477 screenresolution[1908:13877] starting screenresolution argv=screenresolution list 19:10:54 INFO - Available Modes on Display 0 19:10:54 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 19:10:54 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 19:10:54 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 19:10:54 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 19:10:54 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 19:10:54 INFO - Intel Iris: 19:10:54 INFO - Chipset Model: Intel Iris 19:10:54 INFO - Type: GPU 19:10:54 INFO - Bus: Built-In 19:10:54 INFO - VRAM (Dynamic, Max): 1536 MB 19:10:54 INFO - Vendor: Intel (0x8086) 19:10:54 INFO - Device ID: 0x0a2e 19:10:54 INFO - Revision ID: 0x0009 19:10:54 INFO - Displays: 19:10:54 INFO - Display: 19:10:54 INFO - Resolution: 1600 x 1200 @ 60 Hz 19:10:54 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 19:10:54 INFO - Display Serial Number: 1600x1200 60 19:10:54 INFO - Main Display: Yes 19:10:54 INFO - Mirror: Off 19:10:54 INFO - Online: Yes 19:10:54 INFO - Rotation: Supported 19:10:54 INFO - Return code: 0 19:10:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 19:10:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 19:10:54 INFO - Python 2.7.3 19:10:54 INFO - Return code: 0 19:10:54 INFO - grabbing minidump binary from tooltool 19:10:54 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:10:54 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 0x10a091d30>, '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 0x109fb8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a086be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 19:10:54 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:10:54 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:10:54 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:10:54 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 19:10:54 INFO - Return code: 0 19:10:54 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 19:10:54 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 19:10:54 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 19:10:54 INFO - ENV: RUST_BACKTRACE is now 1 19:10:54 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 19:10:54 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 19:10:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/EEpWLpp1SSab7xvVlSXnIA/artifacts/public/build/firefox-54.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0381', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 19:10:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/EEpWLpp1SSab7xvVlSXnIA/artifacts/public/build/firefox-54.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0381 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 19:10:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.aSomOB5hf6/Render', 19:10:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:10:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:10:54 INFO - 'HOME': '/Users/cltbld', 19:10:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:10:54 INFO - 'LOGNAME': 'cltbld', 19:10:54 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 19:10:54 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 19:10:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:10:54 INFO - 'MOZ_NO_REMOTE': '1', 19:10:54 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 19:10:54 INFO - 'NO_EM_RESTART': '1', 19:10:54 INFO - 'PAGER': '/bin/cat', 19:10:54 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:10:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:10:54 INFO - 'PWD': '/builds/slave/test', 19:10:54 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 19:10:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:10:54 INFO - 'RUST_BACKTRACE': '1', 19:10:54 INFO - 'SHELL': '/bin/bash', 19:10:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ckkPjw05hu/Listeners', 19:10:54 INFO - 'TMPDIR': '/var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/T/', 19:10:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:10:54 INFO - 'USER': 'cltbld', 19:10:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:10:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:10:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:10:54 INFO - 'XPC_FLAGS': '0x0', 19:10:54 INFO - 'XPC_SERVICE_NAME': '0', 19:10:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:10:54 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/EEpWLpp1SSab7xvVlSXnIA/artifacts/public/build/firefox-54.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0381', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 19:10:55 INFO - mozversion application_buildid: 20170309171123 19:10:55 INFO - mozversion application_changeset: a7744be94bea4581220cfce7c48e22fb96ac2bf3 19:10:55 INFO - mozversion application_display_name: FirefoxDeveloperEdition 19:10:55 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 19:10:55 INFO - mozversion application_name: Firefox 19:10:55 INFO - mozversion application_remotingname: firefox-dev 19:10:55 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 19:10:55 INFO - mozversion application_vendor: Mozilla 19:10:55 INFO - mozversion application_version: 54.0a2 19:10:55 INFO - mozversion platform_buildid: 20170309171123 19:10:55 INFO - mozversion platform_changeset: a7744be94bea4581220cfce7c48e22fb96ac2bf3 19:10:55 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 19:10:55 INFO - mozversion platform_version: 54.0a2 19:10:55 INFO - using testdate: 1489115455 19:10:55 INFO - actual date: 1489115455 19:10:55 INFO - starting webserver on 'localhost:52071' 19:10:55 INFO - SUITE-START | Running 2 tests 19:10:55 INFO - TEST-START | dromaeo_css 19:10:55 INFO - operating with platform_type : mac_ 19:10:55 INFO - Initialising browser for dromaeo_css test... 19:10:55 INFO - TEST-INFO | started process 1918 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/T/tmp6W1JFt/profile http://localhost:52071/getInfo.html) 19:10:57 INFO - PROCESS | 1918 | 2017-03-09 19:10:57.865 plugin-container[1920:14044] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8f37, name = 'com.apple.tsm.portname' 19:10:57 INFO - PROCESS | 1918 | See /usr/include/servers/bootstrap_defs.h for the error codes. 19:10:57 INFO - PROCESS | 1918 | 2017-03-09 19:10:57.868 plugin-container[1920:14044] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x905b, name = 'com.apple.CFPasteboardClient' 19:10:57 INFO - PROCESS | 1918 | See /usr/include/servers/bootstrap_defs.h for the error codes. 19:10:57 INFO - PROCESS | 1918 | 2017-03-09 19:10:57.868 plugin-container[1920:14044] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 19:10:57 INFO - PROCESS | 1918 | __metrics Screen width/height:1600/1200 19:10:57 INFO - PROCESS | 1918 | colorDepth:24 19:10:57 INFO - PROCESS | 1918 | Browser inner width/height: 1024/689 19:10:57 INFO - PROCESS | 1918 | __metrics 19:10:58 ERROR - PROCESS | 1918 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3353: TypeError: this.tabs is undefined 19:10:58 INFO - TEST-INFO | 1918: exit 0 19:10:58 INFO - Browser initialized. 19:10:58 INFO - Running cycle 1/1 for dromaeo_css test... 19:10:58 INFO - TEST-INFO | started process 1921 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/T/tmp6W1JFt/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 19:11:01 INFO - PROCESS | 1921 | 2017-03-09 19:11:01.272 plugin-container[1922:14177] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x434b, name = 'com.apple.tsm.portname' 19:11:01 INFO - PROCESS | 1921 | See /usr/include/servers/bootstrap_defs.h for the error codes. 19:11:01 INFO - PROCESS | 1921 | 2017-03-09 19:11:01.273 plugin-container[1922:14177] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x900b, name = 'com.apple.CFPasteboardClient' 19:11:01 INFO - PROCESS | 1921 | See /usr/include/servers/bootstrap_defs.h for the error codes. 19:11:01 INFO - PROCESS | 1921 | 2017-03-09 19:11:01.273 plugin-container[1922:14177] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 19:11:11 INFO - PROCESS | 1921 | RSS: Main: 220102656 19:11:11 INFO - PROCESS | 1921 | 19:14:14 INFO - PROCESS | 1921 | Cycle 1(1): loaded http://localhost:52071/tests/dromaeo/cssquery-dojo.html (next: http://localhost:52071/tests/dromaeo/cssquery-ext.html) 19:14:16 INFO - PROCESS | 1921 | RSS: Main: 240304128 19:14:16 INFO - PROCESS | 1921 | 19:15:59 INFO - PROCESS | 1921 | ************************* 19:15:59 INFO - PROCESS | 1921 | A coding exception was thrown and uncaught in a Task. 19:15:59 INFO - PROCESS | 1921 | 19:15:59 ERROR - PROCESS | 1921 | Full message: TypeError: NetworkError when attempting to fetch resource. 19:15:59 INFO - PROCESS | 1921 | Full stack: 19:15:59 INFO - PROCESS | 1921 | ************************* 19:17:18 INFO - PROCESS | 1921 | Cycle 1(1): loaded http://localhost:52071/tests/dromaeo/cssquery-ext.html (next: http://localhost:52071/tests/dromaeo/cssquery-jquery.html) 19:17:19 INFO - PROCESS | 1921 | RSS: Main: 248614912 19:17:19 INFO - PROCESS | 1921 | 19:20:21 INFO - PROCESS | 1921 | Cycle 1(1): loaded http://localhost:52071/tests/dromaeo/cssquery-jquery.html (next: http://localhost:52071/tests/dromaeo/cssquery-mootools.html) 19:20:22 INFO - PROCESS | 1921 | RSS: Main: 255074304 19:20:22 INFO - PROCESS | 1921 | 19:23:24 INFO - PROCESS | 1921 | Cycle 1(1): loaded http://localhost:52071/tests/dromaeo/cssquery-mootools.html (next: http://localhost:52071/tests/dromaeo/cssquery-prototype.html) 19:23:24 INFO - PROCESS | 1921 | RSS: Main: 252153856 19:23:24 INFO - PROCESS | 1921 | 19:26:27 INFO - PROCESS | 1921 | Cycle 1(1): loaded http://localhost:52071/tests/dromaeo/cssquery-prototype.html (next: http://localhost:52071/tests/dromaeo/cssquery-yui.html) 19:26:28 INFO - PROCESS | 1921 | RSS: Main: 257191936 19:26:28 INFO - PROCESS | 1921 | 19:29:31 INFO - PROCESS | 1921 | Cycle 1(1): loaded http://localhost:52071/tests/dromaeo/cssquery-yui.html (next: http://localhost:52071/tests/dromaeo/cssquery-dojo.html) 19:29:31 INFO - PROCESS | 1921 | RSS: Main: 253456384 19:29:31 INFO - PROCESS | 1921 | 19:29:31 INFO - PROCESS | 1921 | __start_tp_report 19:29:31 INFO - PROCESS | 1921 | _x_x_mozilla_page_load 19:29:31 INFO - PROCESS | 1921 | _x_x_mozilla_page_load_details 19:29:31 INFO - PROCESS | 1921 | |i|pagename|runs| 19:29:31 INFO - PROCESS | 1921 | |0;dojo.html;3480.338735640228;3483.982580087099;3490.476428535718;3495.650434956505;3515.4551044588084;6067.575269731115;6068.211132552768;6071.453569178773;6107.083937409391;6122.41837025483;6066.454019138277;6087.3303936566945;6126.724297406617;6127.846803829895;6129.724162412691;5477.75350109244;5511.366192887809;5519.448055194469;5535.086710692731;5570.6657600543895;4223.61987421132;5516.613837031409;5579.38626751057;5589.832305030855;5605.411431799664;5384.6499977501335;5389.78440862365;5558.666480011192;5563.082091454905;5574.804881829137;5494.313210848644;5500.834974950758;5501.064818980783;5531.778728850841;5590.636608620467;4507.774611269456;5315.468453154654;5432.239486471897;5454.699991500466;5514.972425137848;4997.700137991696;5874.647521148702;6145.293291271516;6191.380861913818;6235.127082208494;6299.086632438317;6301.968490157566;6309.116723658692;6330.17707698001;6335.904961425582;6570;6637.933620663828;6649.168853893263;6668.19981602206;6710.16122984627;6194.318624951252;6197.380261973812;6234.0960560718895;6244.250689917239;6280.434760871543;6359.459445947054;6440;6526.934730652728;6629.105070815474;6763.7632682855865;6277.497800175975;6296.181496405438;6328.6519241442165;6334.493240540745;6337.841553961188;6477.902831457532;6510.967445162744;6520.967395162994;6550.6069635822005;6553.475721942234;6212.658303793335;6424.775132870327;6434.935650643527;6441.838954026093;6453.870922581616;6191.938080619227;6285.057241413733;6298.307186209513;6325.652089135142;6351.523635727339;5180.637355385087;5214.817481388209;5221.947780522222;5222.4255331913455;5235;4654.534546545318;4678.064387122589;4686.625069994392;4693.225617773098;4706.058788242297;6015.789447369409;6101.420365065312;6184.81445556634;6189.071639254148;6208.286047104551;6346.143270658495;6354.777582784581;6356.968215158895;6394.136791533084;6398.360163983658;6107.877842443127;6115.541334399938;6119.2350956130485;6126.6017708848785;6126.908096378558;5679.176519404663;5695.231143795535;5698.202251684767;5709.628874123252;5715.057015592464;6399.64801935898;6457.967710161419;6610.206775187008;6649.534532582673;6768.153980752406;6491;6507.739690412331;6541.247756508063;6554.082428459924;6570.605763654196;6668.933310667026;6769.830754231184;6777.457803375817;6784;6789.93210067893;6231.75072997075;6277.932751432176;6330.746770129327;6351.30135685065;6432.903506447501;6358.650274234777;6495.707693153725;6499.38255865692;6504.512161587994;6525.9021114684265;6306.84232894163;6326.145970293951;6355.809325720051;6369.49044076482;6369.554131210771;6218.284897236876;6256.968715156395;6262.279837818709;6288.308286088435;6342.873142537216;4668.696534725232;6161.045038019114;6278.152449419453;6297.527685423704;6321.8735625288155;6345;6349.4920406366455;6354.332795056637;6358.364163583605;6358.841028974312;6132.141500189888;6150.60021098645;6226.657533835501;6265.216847894013;6302.401271879165 19:29:31 INFO - PROCESS | 1921 | |1;ext.html;740.2412527159661;762.306301265848;811.3509192645881;827.652385997881;951.7382719752063;32409.83795081024;37704.43443348352;38982.8050859746;39410.408843867364;39411.40882886752;30685.69314306856;38084.61915380845;38163.61836381636;38209.808950955274;38258.61741382585;7859.528428294307;7983.401244906655;10266.486675666225;10607.681769546887;10659.467026648637;9376.343655944087;9472.579113133017;10399.220058495604;10422.530986105605;10503;10467.371957682526;10503.264771466;10527.420991845445;10535.63125290615;10548.736281592983;10465.895341046606;10492.10817080549;10504.212184086224;10509.789804203872;10522.842157367646;9115.680951166678;9882.258830587663;10229.693109206735;10276.588936442533;10295.845562316572;32838.83580582106;33055.50416743751;33205.66794332074;33311.50032749511;33401.6659833401;3290.7696461247724;3653.6711695947492;3743.1765011697385;3776.7922764247955;3782.583915769263;23805.880970595208;25757.484850303077;25940.61089083665;26011.73988260131;26067.869660651766;3018.139830148419;3055.7860949733526;3069.6623371429123;3153.1644114309725;3242.270489139948;358198;363686;364687;365337;368936.1553192271;43605;43743.78128109403;43779.562204378184;43781.56218437839;43899.34150987738;44606;45141.54858451374;45178.548214518094;45505.31742023872;45524;13782.517611883535;13867.306634668226;13889.513867014606;13890.305484725723;13926.30368481592;11799.823002654966;11986.34075125859;12011.279323240458;12013.699657508632;12021.939890300493;10068.798624027624;11604.24572402808;11723.179377443497;11788.587399440978;11800.586979455678;5356.384015838151;5660.320761508563;5662.433756624305;5664.348599910981;5686.118651609007;886.6143227695973;902.3367824648877;909.7680091576606;921.9078092190728;937.8593211018402;26897.327566810985;26913.192604221906;27138.914443422436;27320.59019114715;27445.58831617527;730.82825535999;1029.1355261580159;1035.4977835749655;1061.2995423020768;1165.9708507287216;10951.178661600412;14370.784438233435;14475.710485790225;14575.833933285312;14738.484153054591;324092;324325;324924;326284;334454;41850;42448;50509.98980020379;50613.74693126511;50768;1415.900886937925;1423.6156237815733;1436.7772995185762;1440.9567712968628;1450.3835869755524;23998.40003999914;27123.236989595614;28835.711642883303;28857.711422885503;29001.694923728905;41690.1661966765;41955;41995.74012779621;42003.52987645418;42140.578594213664;308966;311759;312394;313395;313462;22431;28852.278693032004;29088.8364465419;29598.852005739835;29640.851795740884;39072;39726.60273397229;39816.60183398129;39971;40049.79975100106;27700.861495692392;33172.50241246432;33512.32975340528;34344.14139646529;34664.306713866084;33055.173620659676;33100.84393651031;33650.15874603154;33980.98057058292;34278.82860585681;32359.676403235666;32898.67101328956;33188.50217246695;33573.32853342968;33694;28154.2961425966;29372.26569335698;29396.41207175802;29594.704052959194;29645.851770741006;18440.90779546094;19207.8079219206;23192.768072319737;23267;23297.068117275798 19:29:31 INFO - PROCESS | 1921 | |2;jquery.html;411.76323613922;424.7642558380099;426.4412711009548;433.28724248611053;437.732982880443;19647.803521964775;20408.489787755312;20472.078756455976;20577.971101444906;20956;20408.081636326362;21370.679439808377;21690.566188676257;22248.221312254074;22446.66330005047;6349;6594;6603.537752357337;6606.471482281406;6632.469402447792;4583.427071616048;5655.915161272585;6576.112224849632;6580.374864387875;6587.703553340088;5528.198411230369;6561.442277406426;6576.868462630745;6580.868382632344;7292.781216563511;7631.656575454118;7700.691972321157;7748.496347737436;7773.689052437924;7805.804854878616;6733.730650773963;7053.224145344008;7076.115485564304;7078.610676412795;7120.928790712079;20033.899830500755;20645.380638580868;21447.142114315408;25839.35401614956;25943.481130377288;2802.341449759305;3015.8039727417868;3276.393867134564;3287.8849240276477;3501.334746398202;15232.695346093016;15868.682626347409;15939.282732277075;16303.755443668355;16642.001479911123;2076.750789905221;2106.799854013866;2149.4518897681146;2163.102312540294;2364.2788949370433;141363;160983;166411.99999999878;169216;173671;16657.41699040552;20294.898525507277;20976.273084498916;21006.0547275373;21338;31972;32005.039848804572;32462.188445289055;32498.862539810994;32514.674853251163;10234.539445724811;10368.740781480523;10402.895971040343;10593.152547796159;10709.678709638723;7352.852942941218;8686.86969695455;8698.21716045559;8728.825423491622;8934.50860202682;9767.316287859923;9824.954264178128;9858.211343092535;9928.404295742279;10111.494425278854;4107.617581802548;4191.580841915845;4210.45264115663;4216.262154123016;4228.577142285808;1011.6307547745167;1012.9240306977006;1022.8823685276288;1025.2208321675432;1060.4697651174413;18937.905310473634;20287;20667;20673.218061948002;20837.68743468849;1020.1838529176629;1021.7445638590352;1022.8363461846069;1052.5158427123456;1060.0512541275668;9935.205183585296;10597.62908298206;10627.52176152075;10671.82609912909;10725.463726813785;759046;772291;778322;780011;783498;122751;125377.7462225366;151869;155543;156709;1174.2602160638744;1201.6995751062234;1243.8693937136832;1251.7121062155572;1253.8432695913011;19173.424797256106;19352;19922.90038549798;19928.800711993274;19957;61356;61782;62373.07455791692;63257;65137.674311630035;85488.14511854803;86154;87476.12523874934;87640.5617971906;88412.67380989419;8418.536980466133;10294.073533382032;11076.117060099641;11083.224174307721;11480.770384592428;9938.30431869791;11409.144314176305;12032.518699251932;12360.62918112422;12637.747245054863;10858.402787846428;10934.343939363664;11291.830622540832;11296.661100167008;11503;10726.249162558546;10795.190360723134;10857.239993200401;10875.184361172785;11012.063974562252;11134.997850193522;11164.609238676607;11190.160737944523;11283.943580282046;11384.37385943781;9534.95232523833;10894.182936279969;11049.281796683512;11479.885201147881;11482.081433485135;10422.583096676048;10450.947745261225;10457.843132353173;10568.313059651098;10597.28420852744 19:29:31 INFO - PROCESS | 1921 | |3;mootools.html;1313.5008696695256;1548.41934274647;1553.223388305847;1581.5571639940813;1598.944036958706;10711.571537138505;11039.39283339416;11269.26749761266;11283.830742538868;11380;10626.734331641714;10728.624498142566;10801.729956751085;10825.404602746847;10828.079613232865;2371.5494056129364;2416.0577374823833;2422.588160012793;2424.272718184546;2427.4538228898446;2342.16853017179;2359.0327965534134;2382.65451509531;2386.78518933296;2389.6773935518745;2013.8791672499624;2352.3178278299356;2362.492064206195;2373.8338316317863;2375.70303712036;2322.8954697038503;2341.0401735288533;2344.308429013445;2347.3427440316655;2347.9647805282934;2330.300909727092;2391.748866369041;2392.617181251009;2402.3753824005885;2403.399150212447;11001.394923279217;11183.608573699881;11197.776044479146;11255.043321317698;11258.986691197832;5731.312242530881;5919.585629005971;5922.289325280951;5942.257217847769;5944.138099975523;3443.0359499340225;3497.3879571075213;3537.0449978505917;3559.1635965548076;3594.7663401878795;1113.3542545323692;1123.4495097402296;1132.9206955513118;1151.6314779270638;1185.1052455396207;13695.452181912711;13854;14974.176420296988;15199.62000949974;15258.465953691679;3771.6794072503594;4612.884677883013;4746.1456937750845;4757.119932812441;4759.619230461555;10553;11822;11853;11888.643340699793;11967.461464234131;5801.448862358069;5803.709814509258;5805.88388232233;5813.09896965971;5827.35899051104;5105.953279577678;5264.894702105937;5278.366596008504;5336;5339.11904535755;3794.8102594870147;3900.8439662413753;3942.211557688474;3972.8410863565714;3979.960200397959;888.5646033443695;1022.9590816367262;1029.2075102171436;1030.6753372687579;1037.9377237365782;927.9189743948225;931.1386967055447;932.9953350233205;937.1097457415483;980.0444566547559;6385.393387628168;6414.967925160345;6546.541742078009;6672.699728512229;6755.932440675629;921.2307723051193;993.6273897288517;995.0994350113159;998.5356809083665;1015.6800607808517;4127.587241275848;4205.621494065487;4243.872683819491;4341.544137865541;4397.802098905557;12673.30296833683;13015.284159371136;13015.284159371136;13025.218486890817;13113.147645403018;10774.515146818412;12797.552085676956;12827.717228277097;12853.742925141632;12883.935580322226;8627.525486098199;9984.101430871257;10130.442825644514;10244.180465562737;10248.948755256177;6530.771423000172;6661.267260601233;6685.665716714242;6758;6787.626680532618;11869.347185904513;12145.514179432723;12145.514179432723;12150.696232594255;12175.269483830998;11497.310161390344;11856.940715296369;12002.039836813208;12431.813522797345;12596.622101336974;8998.82002359936;9425.622975081194;9429.057094290516;9437.952810235905;9438.811223775348;10238.18094552449;10262.43556604394;10343.586256549956;10405.479726013518;10446.529906154394;9227.35408521397;9274.582643780912;9292.303077269096;9334.20659243972;9590.42457452555;9004.909950900406;9047.276217902683;9110.954445227997;9142.040085790895;9289.117533834275;7812.257835505618;7848.568328741974;7894.763157105296;7930.524168549675;7955.960220198862;5853.975554277899;5863.677497737665;5919.993601087739;6068.6358818471035;6088.452039316306;5955.791547295824;6086.147939288591;6100.908486372797;6136.048912418582;6158.107074474266 19:29:31 INFO - PROCESS | 1921 | |4;prototype.html;307.35608899355844;365.384327408317;380.6098748782499;383.8809968909638;389.65320864430663;5153.81961631343;5161.380634323877;5269.841904742859;5359.445760729389;5461.0716178249695;4362.629176519991;4594;5143.562797162245;5210.843674689756;5242.292290540776;4087.9182416351728;4162.83532563067;4203.579642035803;4292.519080917079;4335.6965012449145;3294.6772525369556;3778.8110594470304;3919.4316824060493;4179.874603761892;4182.159849666137;3794.4118661607495;4106.77412742299;4186.602272784081;4281.914361712764;4313.784310784448;3514.2413205633457;3877.922441551153;4258.12576510854;4270.466191726035;4328.891777705551;3365.074604483762;3976.880693579168;3983.900402489932;3992.840286388541;3996.500437445319;4383.824647014148;4667.486576476585;5092.388913330387;5100.801311691749;5159.71621560814;4802.919343147799;4999.575036121934;5022.372203474566;5032.47159048302;5032.6477146599755;4609.539046095397;5362.624616276862;5467.726613669341;5536.003519366513;5640.86931752877;4164.646005089571;4411.691181617288;4437.689361744679;4459.687821852472;4488;5611.943880561183;5702.714864256812;5868.970655146697;5888.440598143169;5919.378465261172;3581.391163502211;4176.206520761046;4271.803894909431;4537.1833070047705;4587.4036375271;4232.724872883253;4453.532379100213;4635.466921304026;4643.930341044819;4655.790489427984;3795.8873723546426;4257.489101307801;4877.6585639004925;5023.346964894614;5025.899482010413;4332.415123958225;4710.95289047112;5277.9472205278225;5550.056490396642;5715.857103572361;1415.344047464469;1421.8862491000696;1464.392277204948;1481.5703446000755;1579.2470357902719;1007.6120693532896;1056.693558867935;1194.6595220362155;1197.5329621447643;1215.9817602735966;4333.609975102256;4689.577937985598;5059.443461219263;5229.607779416559;5263.526282634504;5201.505856943584;5280.630355875129;5837.832433513315;5875.618084824473;5884.734377264784;4834.7824347904425;5153.114256016643;5221.347331583552;5242.5281724644965;5246.69145575953;2565.3586603349163;2620.2794231586086;2865.9426811464073;3013.74383177428;3014.2065471044693;5747.453991870766;5868.053374058243;5910.468057874812;5950.702464876739;6067.908981365283;5146.794128234904;5451;5868.972929737364;6000.909986350208;6039.486643635252;5302.337207849019;5374.355077390738;5430.91853622196;5709.971450142806;5818;5642.3355110241755;5723.942760572341;5768.711564421678;5823.563232757476;5848.853778655568;5540.587150276654;5682.954136510935;5758.942410575841;5764.020116580107;5804.5356371491025;4923.135338759057;5619.297587801525;5669.922714684197;5894.793682221101;5933.2880054393745;4322.524522302481;4372.825086996611;4444.844430445049;4554.43069616298;4554.954450455453;5130.435652078194;5183.6263390200875;5213.348331458567;5556.805511807067;5780.786034932543;4725.40932383452;4855.417349918032;4905.8037678492465;5654.6889921054735;5663.575231857545;4619.376384188092;5225.498915726548;5280.392754833243;5635.182898479779;5669.4409037515015;4546.40896683429;5094.490550944876;5186.377634683711;5216.330774152387;5430.972845135749;4930.433000205022;5277.683338999673;5685.687287199244;5712.7714891403875;5745.862978655361;4837.637177211794;5312.884294298241;5364.785408583769;5486.765477767471;5551.944480555143 19:29:31 INFO - PROCESS | 1921 | |5;yui.html;702.7118881258685;714.9320814522619;775.9418043646727;817.860963636182;819.8425664636864;15236.61908452288;15590.688186236297;16089.356425742957;16256.837431625694;16302.266398012089;14872.405103795862;14886.553403397915;15054.096754194756;15096.698066038673;15101.697966040672;866.3805379153904;918.2470374293082;934.0192797562532;943.8584212368165;960.5149399553216;899.7615631857531;951.3578334624101;951.7097285327973;951.7620594851287;954.0983770337034;958.2573505533198;992.2012779712329;998.9900100999006;1007.9848802267935;1013.1034034879148;999.145730400508;1002.5638847101498;1003.0471052500154;1003.2926786615492;1008.1984822066474;735.6248313360171;745.9477836551443;747.7270796159418;747.9476436649437;750.2759836757555;16082.115483648395;16429.83570164295;16496.17519124039;16638.001719896845;16671.416500422427;13929.791053134211;14066.788998165035;14152.29238538069;14192.645183870383;14235.430582776677;11685.298882067103;11888.643340699793;11889.619810402006;11994.520219191221;12016.218945768496;3077.276839942612;3594.6405359463843;3617.5297211362617;3757.041954301663;3812.0850995761093;56847.71576142093;81243.5937820313;99665;107107.46446267796;107754.46122769336;10603.628872989562;10999.120070394349;11054.336739795637;11536.134789890792;11594.710132246593;21787.455313617287;21927.890360548416;22056.007479663454;22092.116315347528;22225.22211722614;7857.2928436441;7888.960555197187;7889.8816517752275;7963.641636126388;8158.224968627971;4003.753518923961;4328.307470804719;4354.521002689701;4383.715058521186;4425.756583387944;3466.9653303466644;3515.5078289039557;3528.770629909099;3544.4151714966742;3563.4476656118345;453.6529554890511;456.4157877916272;657.8815813153583;659.2188256915474;718.6489399928206;672.1530871102349;674.3795707948699;698.5180225644289;706.5795851468368;747.3498056690714;10081.243906707026;10651.30766500175;10953.464240102272;11437;11475.311481311148;570.2956848292356;577.2784019975031;595.4551585299489;644.9358558378619;703.2721133626774;3802.543694756647;4928.433230178503;4966.950330496648;5037.370328708911;5038;79098;84320;92531.07468925224;94401;98317.5084124589;75243.87134192992;75696.24303756891;76897;81066;84241.57879210565;71593.64203178951;71765;74029.25970740223;75288.62355688187;75527.62236188784;9769.46267955241;10835.241533092609;11019.393933333744;11091.279066860629;11257.380844053328;49500.50499494959;50578.494215058854;51681;52211.216831748316;52466.475335247684;61494;63275.36724632695;64800.35199647943;64812;65250.673746630964;12675.429605667583;12877.291748953523;12956.740865182455;13012.934935325262;13104.475820967436;14475.493357732428;14572.781408278672;14634.92682536623;14772.33524491422;14990.250487475365;13121.73756524845;13742.93128534351;13883.16700997903;14055.508057217949;14313.785293220819;13529.932350338186;13706.52027179044;14006.509772157926;14255.358508866919;14302.57092287233;13431.992600555193;13758.243296618783;13767.31163441844;13896.305184740924;14220.21788801626;11335.829962550404;11384.260023098472;11572.074234061087;12071.456784444897;12131.272123672608;11238.7255856207;11455.885441145481;11529.365884876412;11710;11851.525938962344 19:29:31 INFO - PROCESS | 1921 | __end_tp_report 19:29:31 INFO - PROCESS | 1921 | __start_cc_report 19:29:31 INFO - PROCESS | 1921 | _x_x_mozilla_cycle_collect,411 19:29:31 INFO - PROCESS | 1921 | __end_cc_report 19:29:31 INFO - PROCESS | 1921 | __startTimestamp1489116571760__endTimestamp 19:29:31 INFO - PROCESS | 1921 | 19:29:31 INFO - PROCESS | 1921 | ------- Summary: start ------- 19:29:31 INFO - PROCESS | 1921 | Number of tests: 6 19:29:31 INFO - PROCESS | 1921 | 19:29:31 INFO - PROCESS | 1921 | [#0] dojo.html Cycles:165 Average:5998.62 Median:6244.25 stddev:680.97 (10.9%) stddev-sans-first:653.77 19:29:31 INFO - PROCESS | 1921 | Values: 3480.3 3484.0 3490.5 3495.7 3515.5 6067.6 6068.2 6071.5 6107.1 6122.4 6066.5 6087.3 6126.7 6127.8 6129.7 5477.8 5511.4 5519.4 5535.1 5570.7 4223.6 5516.6 5579.4 5589.8 5605.4 5384.6 5389.8 5558.7 5563.1 5574.8 5494.3 5500.8 5501.1 5531.8 5590.6 4507.8 5315.5 5432.2 5454.7 5515.0 4997.7 5874.6 6145.3 6191.4 6235.1 6299.1 6302.0 6309.1 6330.2 6335.9 6570.0 6637.9 6649.2 6668.2 6710.2 6194.3 6197.4 6234.1 6244.3 6280.4 6359.5 6440.0 6526.9 6629.1 6763.8 6277.5 6296.2 6328.7 6334.5 6337.8 6477.9 6511.0 6521.0 6550.6 6553.5 6212.7 6424.8 6434.9 6441.8 6453.9 6191.9 6285.1 6298.3 6325.7 6351.5 5180.6 5214.8 5221.9 5222.4 5235.0 4654.5 4678.1 4686.6 4693.2 4706.1 6015.8 6101.4 6184.8 6189.1 6208.3 6346.1 6354.8 6357.0 6394.1 6398.4 6107.9 6115.5 6119.2 6126.6 6126.9 5679.2 5695.2 5698.2 5709.6 5715.1 6399.6 6458.0 6610.2 6649.5 6768.2 6491.0 6507.7 6541.2 6554.1 6570.6 6668.9 6769.8 6777.5 6784.0 6789.9 6231.8 6277.9 6330.7 6351.3 6432.9 6358.7 6495.7 6499.4 6504.5 6525.9 6306.8 6326.1 6355.8 6369.5 6369.6 6218.3 6257.0 6262.3 6288.3 6342.9 4668.7 6161.0 6278.2 6297.5 6321.9 6345.0 6349.5 6354.3 6358.4 6358.8 6132.1 6150.6 6226.7 6265.2 6302.4 19:29:31 INFO - PROCESS | 1921 | 19:29:31 INFO - PROCESS | 1921 | [#1] ext.html Cycles:180 Average:47351.40 Median:178250.92 stddev:88167.87 (49.5%) stddev-sans-first:88345.75 19:29:31 INFO - PROCESS | 1921 | Values: 740.2 762.3 811.4 827.7 951.7 32409.8 37704.4 38982.8 39410.4 39411.4 30685.7 38084.6 38163.6 38209.8 38258.6 7859.5 7983.4 10266.5 10607.7 10659.5 9376.3 9472.6 10399.2 10422.5 10503.0 10467.4 10503.3 10527.4 10535.6 10548.7 10465.9 10492.1 10504.2 10509.8 10522.8 9115.7 9882.3 10229.7 10276.6 10295.8 32838.8 33055.5 33205.7 33311.5 33401.7 3290.8 3653.7 3743.2 3776.8 3782.6 23805.9 25757.5 25940.6 26011.7 26067.9 3018.1 3055.8 3069.7 3153.2 3242.3 358198.0 363686.0 364687.0 365337.0 368936.2 43605.0 43743.8 43779.6 43781.6 43899.3 44606.0 45141.5 45178.5 45505.3 45524.0 13782.5 13867.3 13889.5 13890.3 13926.3 11799.8 11986.3 12011.3 12013.7 12021.9 10068.8 11604.2 11723.2 11788.6 11800.6 5356.4 5660.3 5662.4 5664.3 5686.1 886.6 902.3 909.8 921.9 937.9 26897.3 26913.2 27138.9 27320.6 27445.6 730.8 1029.1 1035.5 1061.3 1166.0 10951.2 14370.8 14475.7 14575.8 14738.5 324092.0 324325.0 324924.0 326284.0 334454.0 41850.0 42448.0 50510.0 50613.7 50768.0 1415.9 1423.6 1436.8 1441.0 1450.4 23998.4 27123.2 28835.7 28857.7 29001.7 41690.2 41955.0 41995.7 42003.5 42140.6 308966.0 311759.0 312394.0 313395.0 313462.0 22431.0 28852.3 29088.8 29598.9 29640.9 39072.0 39726.6 39816.6 39971.0 40049.8 27700.9 33172.5 33512.3 34344.1 34664.3 33055.2 33100.8 33650.2 33981.0 34278.8 32359.7 32898.7 33188.5 33573.3 33694.0 28154.3 29372.3 29396.4 29594.7 29645.9 18440.9 19207.8 23192.8 23267.0 23297.1 19:29:31 INFO - PROCESS | 1921 | 19:29:31 INFO - PROCESS | 1921 | [#2] jquery.html Cycles:180 Average:43487.56 Median:11457.22 stddev:129025.62 (1126.2%) stddev-sans-first:129347.03 19:29:31 INFO - PROCESS | 1921 | Values: 411.8 424.8 426.4 433.3 437.7 19647.8 20408.5 20472.1 20578.0 20956.0 20408.1 21370.7 21690.6 22248.2 22446.7 6349.0 6594.0 6603.5 6606.5 6632.5 4583.4 5655.9 6576.1 6580.4 6587.7 5528.2 6561.4 6576.9 6580.9 7292.8 7631.7 7700.7 7748.5 7773.7 7805.8 6733.7 7053.2 7076.1 7078.6 7120.9 20033.9 20645.4 21447.1 25839.4 25943.5 2802.3 3015.8 3276.4 3287.9 3501.3 15232.7 15868.7 15939.3 16303.8 16642.0 2076.8 2106.8 2149.5 2163.1 2364.3 141363.0 160983.0 166412.0 169216.0 173671.0 16657.4 20294.9 20976.3 21006.1 21338.0 31972.0 32005.0 32462.2 32498.9 32514.7 10234.5 10368.7 10402.9 10593.2 10709.7 7352.9 8686.9 8698.2 8728.8 8934.5 9767.3 9825.0 9858.2 9928.4 10111.5 4107.6 4191.6 4210.5 4216.3 4228.6 1011.6 1012.9 1022.9 1025.2 1060.5 18937.9 20287.0 20667.0 20673.2 20837.7 1020.2 1021.7 1022.8 1052.5 1060.1 9935.2 10597.6 10627.5 10671.8 10725.5 759046.0 772291.0 778322.0 780011.0 783498.0 122751.0 125377.7 151869.0 155543.0 156709.0 1174.3 1201.7 1243.9 1251.7 1253.8 19173.4 19352.0 19922.9 19928.8 19957.0 61356.0 61782.0 62373.1 63257.0 65137.7 85488.1 86154.0 87476.1 87640.6 88412.7 8418.5 10294.1 11076.1 11083.2 11480.8 9938.3 11409.1 12032.5 12360.6 12637.7 10858.4 10934.3 11291.8 11296.7 11503.0 10726.2 10795.2 10857.2 10875.2 11012.1 11135.0 11164.6 11190.2 11283.9 11384.4 9535.0 10894.2 11049.3 11479.9 11482.1 10422.6 10450.9 10457.8 10568.3 10597.3 19:29:31 INFO - PROCESS | 1921 | 19:29:31 INFO - PROCESS | 1921 | [#3] mootools.html Cycles:180 Average:6684.15 Median:2423.43 stddev:4103.39 (169.3%) stddev-sans-first:4095.06 19:29:31 INFO - PROCESS | 1921 | Values: 1313.5 1548.4 1553.2 1581.6 1598.9 10711.6 11039.4 11269.3 11283.8 11380.0 10626.7 10728.6 10801.7 10825.4 10828.1 2371.5 2416.1 2422.6 2424.3 2427.5 2342.2 2359.0 2382.7 2386.8 2389.7 2013.9 2352.3 2362.5 2373.8 2375.7 2322.9 2341.0 2344.3 2347.3 2348.0 2330.3 2391.7 2392.6 2402.4 2403.4 11001.4 11183.6 11197.8 11255.0 11259.0 5731.3 5919.6 5922.3 5942.3 5944.1 3443.0 3497.4 3537.0 3559.2 3594.8 1113.4 1123.4 1132.9 1151.6 1185.1 13695.5 13854.0 14974.2 15199.6 15258.5 3771.7 4612.9 4746.1 4757.1 4759.6 10553.0 11822.0 11853.0 11888.6 11967.5 5801.4 5803.7 5805.9 5813.1 5827.4 5106.0 5264.9 5278.4 5336.0 5339.1 3794.8 3900.8 3942.2 3972.8 3980.0 888.6 1023.0 1029.2 1030.7 1037.9 927.9 931.1 933.0 937.1 980.0 6385.4 6415.0 6546.5 6672.7 6755.9 921.2 993.6 995.1 998.5 1015.7 4127.6 4205.6 4243.9 4341.5 4397.8 12673.3 13015.3 13015.3 13025.2 13113.1 10774.5 12797.6 12827.7 12853.7 12883.9 8627.5 9984.1 10130.4 10244.2 10248.9 6530.8 6661.3 6685.7 6758.0 6787.6 11869.3 12145.5 12145.5 12150.7 12175.3 11497.3 11856.9 12002.0 12431.8 12596.6 8998.8 9425.6 9429.1 9438.0 9438.8 10238.2 10262.4 10343.6 10405.5 10446.5 9227.4 9274.6 9292.3 9334.2 9590.4 9004.9 9047.3 9111.0 9142.0 9289.1 7812.3 7848.6 7894.8 7930.5 7956.0 5854.0 5863.7 5920.0 6068.6 6088.5 5955.8 6086.1 6100.9 6136.0 6158.1 19:29:31 INFO - PROCESS | 1921 | 19:29:31 INFO - PROCESS | 1921 | [#4] prototype.html Cycles:180 Average:4610.91 Median:5029.19 stddev:1317.99 (26.2%) stddev-sans-first:1281.50 19:29:31 INFO - PROCESS | 1921 | Values: 307.4 365.4 380.6 383.9 389.7 5153.8 5161.4 5269.8 5359.4 5461.1 4362.6 4594.0 5143.6 5210.8 5242.3 4087.9 4162.8 4203.6 4292.5 4335.7 3294.7 3778.8 3919.4 4179.9 4182.2 3794.4 4106.8 4186.6 4281.9 4313.8 3514.2 3877.9 4258.1 4270.5 4328.9 3365.1 3976.9 3983.9 3992.8 3996.5 4383.8 4667.5 5092.4 5100.8 5159.7 4802.9 4999.6 5022.4 5032.5 5032.6 4609.5 5362.6 5467.7 5536.0 5640.9 4164.6 4411.7 4437.7 4459.7 4488.0 5611.9 5702.7 5869.0 5888.4 5919.4 3581.4 4176.2 4271.8 4537.2 4587.4 4232.7 4453.5 4635.5 4643.9 4655.8 3795.9 4257.5 4877.7 5023.3 5025.9 4332.4 4711.0 5277.9 5550.1 5715.9 1415.3 1421.9 1464.4 1481.6 1579.2 1007.6 1056.7 1194.7 1197.5 1216.0 4333.6 4689.6 5059.4 5229.6 5263.5 5201.5 5280.6 5837.8 5875.6 5884.7 4834.8 5153.1 5221.3 5242.5 5246.7 2565.4 2620.3 2865.9 3013.7 3014.2 5747.5 5868.1 5910.5 5950.7 6067.9 5146.8 5451.0 5869.0 6000.9 6039.5 5302.3 5374.4 5430.9 5710.0 5818.0 5642.3 5723.9 5768.7 5823.6 5848.9 5540.6 5683.0 5758.9 5764.0 5804.5 4923.1 5619.3 5669.9 5894.8 5933.3 4322.5 4372.8 4444.8 4554.4 4555.0 5130.4 5183.6 5213.3 5556.8 5780.8 4725.4 4855.4 4905.8 5654.7 5663.6 4619.4 5225.5 5280.4 5635.2 5669.4 4546.4 5094.5 5186.4 5216.3 5431.0 4930.4 5277.7 5685.7 5712.8 5745.9 4837.6 5312.9 5364.8 5486.8 5551.9 19:29:31 INFO - PROCESS | 1921 | 19:29:31 INFO - PROCESS | 1921 | [#5] yui.html Cycles:180 Average:19440.40 Median:3536.59 stddev:26271.09 (742.8%) stddev-sans-first:26307.11 19:29:31 INFO - PROCESS | 1921 | Values: 702.7 714.9 775.9 817.9 819.8 15236.6 15590.7 16089.4 16256.8 16302.3 14872.4 14886.6 15054.1 15096.7 15101.7 866.4 918.2 934.0 943.9 960.5 899.8 951.4 951.7 951.8 954.1 958.3 992.2 999.0 1008.0 1013.1 999.1 1002.6 1003.0 1003.3 1008.2 735.6 745.9 747.7 747.9 750.3 16082.1 16429.8 16496.2 16638.0 16671.4 13929.8 14066.8 14152.3 14192.6 14235.4 11685.3 11888.6 11889.6 11994.5 12016.2 3077.3 3594.6 3617.5 3757.0 3812.1 56847.7 81243.6 99665.0 107107.5 107754.5 10603.6 10999.1 11054.3 11536.1 11594.7 21787.5 21927.9 22056.0 22092.1 22225.2 7857.3 7889.0 7889.9 7963.6 8158.2 4003.8 4328.3 4354.5 4383.7 4425.8 3467.0 3515.5 3528.8 3544.4 3563.4 453.7 456.4 657.9 659.2 718.6 672.2 674.4 698.5 706.6 747.3 10081.2 10651.3 10953.5 11437.0 11475.3 570.3 577.3 595.5 644.9 703.3 3802.5 4928.4 4967.0 5037.4 5038.0 79098.0 84320.0 92531.1 94401.0 98317.5 75243.9 75696.2 76897.0 81066.0 84241.6 71593.6 71765.0 74029.3 75288.6 75527.6 9769.5 10835.2 11019.4 11091.3 11257.4 49500.5 50578.5 51681.0 52211.2 52466.5 61494.0 63275.4 64800.4 64812.0 65250.7 12675.4 12877.3 12956.7 13012.9 13104.5 14475.5 14572.8 14634.9 14772.3 14990.3 13121.7 13742.9 13883.2 14055.5 14313.8 13529.9 13706.5 14006.5 14255.4 14302.6 13432.0 13758.2 13767.3 13896.3 14220.2 11335.8 11384.3 11572.1 12071.5 12131.3 11238.7 11455.9 11529.4 11710.0 11851.5 19:29:31 INFO - PROCESS | 1921 | -------- Summary: end -------- 19:29:31 INFO - PROCESS | 1921 | 19:29:32 INFO - TEST-INFO | 1921: exit 0 19:29:32 INFO - TEST-OK | dromaeo_css | took 1117357ms 19:29:32 INFO - TEST-START | kraken 19:29:32 INFO - operating with platform_type : mac_ 19:29:32 INFO - Initialising browser for kraken test... 19:29:32 INFO - TEST-INFO | started process 1926 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/T/tmptM_REX/profile http://localhost:52071/getInfo.html) 19:29:34 INFO - PROCESS | 1926 | 2017-03-09 19:29:34.977 plugin-container[1927:16259] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8833, name = 'com.apple.tsm.portname' 19:29:34 INFO - PROCESS | 1926 | See /usr/include/servers/bootstrap_defs.h for the error codes. 19:29:34 INFO - PROCESS | 1926 | 2017-03-09 19:29:34.980 plugin-container[1927:16259] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8c0b, name = 'com.apple.CFPasteboardClient' 19:29:34 INFO - PROCESS | 1926 | See /usr/include/servers/bootstrap_defs.h for the error codes. 19:29:34 INFO - PROCESS | 1926 | 2017-03-09 19:29:34.980 plugin-container[1927:16259] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 19:29:35 INFO - PROCESS | 1926 | __metrics Screen width/height:1600/1200 19:29:35 INFO - PROCESS | 1926 | colorDepth:24 19:29:35 INFO - PROCESS | 1926 | Browser inner width/height: 1024/689 19:29:35 INFO - PROCESS | 1926 | __metrics 19:29:35 ERROR - PROCESS | 1926 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3353: TypeError: this.tabs is undefined 19:29:35 INFO - TEST-INFO | 1926: exit 0 19:29:35 INFO - Browser initialized. 19:29:35 INFO - Running cycle 1/1 for kraken test... 19:29:35 INFO - TEST-INFO | started process 1928 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/T/tmptM_REX/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 19:29:38 INFO - PROCESS | 1928 | 2017-03-09 19:29:38.301 plugin-container[1929:16389] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x433b, name = 'com.apple.tsm.portname' 19:29:38 INFO - PROCESS | 1928 | See /usr/include/servers/bootstrap_defs.h for the error codes. 19:29:38 INFO - PROCESS | 1928 | 2017-03-09 19:29:38.301 plugin-container[1929:16389] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x894b, name = 'com.apple.CFPasteboardClient' 19:29:38 INFO - PROCESS | 1928 | See /usr/include/servers/bootstrap_defs.h for the error codes. 19:29:38 INFO - PROCESS | 1928 | 2017-03-09 19:29:38.301 plugin-container[1929:16389] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 19:29:48 INFO - PROCESS | 1928 | RSS: Main: 220315648 19:29:48 INFO - PROCESS | 1928 | 19:29:51 INFO - PROCESS | 1928 | Cycle 1(1): loaded http://localhost:52071/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:52071/tests/kraken/driver.html?testName=audio-beat-detection) 19:29:52 INFO - PROCESS | 1928 | RSS: Main: 235053056 19:29:52 INFO - PROCESS | 1928 | 19:29:54 INFO - PROCESS | 1928 | Cycle 1(1): loaded http://localhost:52071/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:52071/tests/kraken/driver.html?testName=audio-dft) 19:29:55 INFO - PROCESS | 1928 | RSS: Main: 243236864 19:29:55 INFO - PROCESS | 1928 | 19:29:58 INFO - PROCESS | 1928 | Cycle 1(1): loaded http://localhost:52071/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:52071/tests/kraken/driver.html?testName=audio-fft) 19:29:59 INFO - PROCESS | 1928 | RSS: Main: 244899840 19:29:59 INFO - PROCESS | 1928 | 19:30:01 INFO - PROCESS | 1928 | Cycle 1(1): loaded http://localhost:52071/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:52071/tests/kraken/driver.html?testName=audio-oscillator) 19:30:02 INFO - PROCESS | 1928 | RSS: Main: 246587392 19:30:02 INFO - PROCESS | 1928 | 19:30:04 INFO - PROCESS | 1928 | Cycle 1(1): loaded http://localhost:52071/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:52071/tests/kraken/driver.html?testName=imaging-gaussian-blur) 19:30:04 INFO - PROCESS | 1928 | RSS: Main: 248242176 19:30:04 INFO - PROCESS | 1928 | 19:30:07 INFO - PROCESS | 1928 | Cycle 1(1): loaded http://localhost:52071/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:52071/tests/kraken/driver.html?testName=imaging-darkroom) 19:30:08 INFO - PROCESS | 1928 | RSS: Main: 252628992 19:30:08 INFO - PROCESS | 1928 | 19:30:11 INFO - PROCESS | 1928 | Cycle 1(1): loaded http://localhost:52071/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:52071/tests/kraken/driver.html?testName=imaging-desaturate) 19:30:12 INFO - PROCESS | 1928 | RSS: Main: 249626624 19:30:12 INFO - PROCESS | 1928 | 19:30:16 INFO - PROCESS | 1928 | Cycle 1(1): loaded http://localhost:52071/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:52071/tests/kraken/driver.html?testName=json-parse-financial) 19:30:16 INFO - PROCESS | 1928 | RSS: Main: 249425920 19:30:16 INFO - PROCESS | 1928 | 19:30:18 INFO - PROCESS | 1928 | Cycle 1(1): loaded http://localhost:52071/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:52071/tests/kraken/driver.html?testName=json-stringify-tinderbox) 19:30:19 INFO - PROCESS | 1928 | RSS: Main: 250753024 19:30:19 INFO - PROCESS | 1928 | 19:30:20 INFO - PROCESS | 1928 | Cycle 1(1): loaded http://localhost:52071/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:52071/tests/kraken/driver.html?testName=stanford-crypto-aes) 19:30:21 INFO - PROCESS | 1928 | RSS: Main: 251158528 19:30:21 INFO - PROCESS | 1928 | 19:30:23 INFO - PROCESS | 1928 | Cycle 1(1): loaded http://localhost:52071/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:52071/tests/kraken/driver.html?testName=stanford-crypto-ccm) 19:30:24 INFO - PROCESS | 1928 | RSS: Main: 251326464 19:30:24 INFO - PROCESS | 1928 | 19:30:27 INFO - PROCESS | 1928 | Cycle 1(1): loaded http://localhost:52071/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:52071/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 19:30:28 INFO - PROCESS | 1928 | RSS: Main: 251650048 19:30:28 INFO - PROCESS | 1928 | 19:30:31 INFO - PROCESS | 1928 | Cycle 1(1): loaded http://localhost:52071/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:52071/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 19:30:31 INFO - PROCESS | 1928 | RSS: Main: 251437056 19:30:31 INFO - PROCESS | 1928 | 19:30:33 INFO - PROCESS | 1928 | Cycle 1(1): loaded http://localhost:52071/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:52071/tests/kraken/driver.html?testName=ai-astar) 19:30:33 INFO - PROCESS | 1928 | RSS: Main: 254885888 19:30:33 INFO - PROCESS | 1928 | 19:30:33 INFO - PROCESS | 1928 | __start_tp_report 19:30:33 INFO - PROCESS | 1928 | _x_x_mozilla_page_load 19:30:33 INFO - PROCESS | 1928 | _x_x_mozilla_page_load_details 19:30:33 INFO - PROCESS | 1928 | |i|pagename|runs| 19:30:33 INFO - PROCESS | 1928 | |0;ai-astar;113;167;120;120;119;118;120;120;121;120 19:30:33 INFO - PROCESS | 1928 | |1;audio-beat-detection;131;115;94;173;144;143;113;142;165;113 19:30:33 INFO - PROCESS | 1928 | |2;audio-dft;198;199;240;282;205;187;284;284;226;203 19:30:33 INFO - PROCESS | 1928 | |3;audio-fft;81;60;61;85;70;78;86;70;74;62 19:30:33 INFO - PROCESS | 1928 | |4;audio-oscillator;86;70;83;72;75;69;82;73;94;70 19:30:33 INFO - PROCESS | 1928 | |5;imaging-gaussian-blur;88;88;92;89;89;88;91;88;90;89 19:30:33 INFO - PROCESS | 1928 | |6;imaging-darkroom;154;97;95;106;100;106;155;125;102;148 19:30:33 INFO - PROCESS | 1928 | |7;imaging-desaturate;90;146;142;94;90;82;87;150;140;114 19:30:33 INFO - PROCESS | 1928 | |8;json-parse-financial;65;68;67;66;71;70;69;71;90;65 19:30:33 INFO - PROCESS | 1928 | |9;json-stringify-tinderbox;58;65;58;65;63;58;57;59;59;60 19:30:33 INFO - PROCESS | 1928 | |10;stanford-crypto-aes;85;80;101;103;103;103;87;88;88;83 19:30:33 INFO - PROCESS | 1928 | |11;stanford-crypto-ccm;151;142;138;140;138;141;143;163;220;151 19:30:33 INFO - PROCESS | 1928 | |12;stanford-crypto-pbkdf2;169;206;179;172;168;169;171;170;231;217 19:30:33 INFO - PROCESS | 1928 | |13;stanford-crypto-sha256-iterative;67;68;65;66;66;67;70;70;68;65 19:30:33 INFO - PROCESS | 1928 | __end_tp_report 19:30:33 INFO - PROCESS | 1928 | __start_cc_report 19:30:33 INFO - PROCESS | 1928 | _x_x_mozilla_cycle_collect,998 19:30:33 INFO - PROCESS | 1928 | __end_cc_report 19:30:33 INFO - PROCESS | 1928 | __startTimestamp1489116633828__endTimestamp 19:30:33 INFO - PROCESS | 1928 | 19:30:33 INFO - PROCESS | 1928 | ------- Summary: start ------- 19:30:33 INFO - PROCESS | 1928 | Number of tests: 14 19:30:33 INFO - PROCESS | 1928 | 19:30:33 INFO - PROCESS | 1928 | [#0] ai-astar Cycles:10 Average:123.80 Median:120.00 stddev:15.35 (12.8%) stddev-sans-first:15.77 19:30:33 INFO - PROCESS | 1928 | Values: 113.0 167.0 120.0 120.0 119.0 118.0 120.0 120.0 121.0 120.0 19:30:33 INFO - PROCESS | 1928 | 19:30:33 INFO - PROCESS | 1928 | [#1] audio-beat-detection Cycles:10 Average:133.30 Median:143.50 stddev:24.89 (17.3%) stddev-sans-first:26.38 19:30:33 INFO - PROCESS | 1928 | Values: 131.0 115.0 94.0 173.0 144.0 143.0 113.0 142.0 165.0 113.0 19:30:33 INFO - PROCESS | 1928 | 19:30:33 INFO - PROCESS | 1928 | [#2] audio-dft Cycles:10 Average:230.80 Median:233.00 stddev:39.21 (16.8%) stddev-sans-first:39.75 19:30:33 INFO - PROCESS | 1928 | Values: 198.0 199.0 240.0 282.0 205.0 187.0 284.0 284.0 226.0 203.0 19:30:33 INFO - PROCESS | 1928 | 19:30:33 INFO - PROCESS | 1928 | [#3] audio-fft Cycles:10 Average:72.70 Median:76.00 stddev:9.74 (12.8%) stddev-sans-first:9.86 19:30:33 INFO - PROCESS | 1928 | Values: 81.0 60.0 61.0 85.0 70.0 78.0 86.0 70.0 74.0 62.0 19:30:33 INFO - PROCESS | 1928 | 19:30:33 INFO - PROCESS | 1928 | [#4] audio-oscillator Cycles:10 Average:77.40 Median:78.50 stddev:8.41 (10.7%) stddev-sans-first:8.32 19:30:33 INFO - PROCESS | 1928 | Values: 86.0 70.0 83.0 72.0 75.0 69.0 82.0 73.0 94.0 70.0 19:30:33 INFO - PROCESS | 1928 | 19:30:33 INFO - PROCESS | 1928 | [#5] imaging-gaussian-blur Cycles:10 Average:89.20 Median:89.00 stddev:1.40 (1.6%) stddev-sans-first:1.41 19:30:33 INFO - PROCESS | 1928 | Values: 88.0 88.0 92.0 89.0 89.0 88.0 91.0 88.0 90.0 89.0 19:30:33 INFO - PROCESS | 1928 | 19:30:33 INFO - PROCESS | 1928 | [#6] imaging-darkroom Cycles:10 Average:118.80 Median:151.00 stddev:24.60 (16.3%) stddev-sans-first:22.55 19:30:33 INFO - PROCESS | 1928 | Values: 154.0 97.0 95.0 106.0 100.0 106.0 155.0 125.0 102.0 148.0 19:30:33 INFO - PROCESS | 1928 | 19:30:33 INFO - PROCESS | 1928 | [#7] imaging-desaturate Cycles:10 Average:113.50 Median:84.50 stddev:28.05 (33.2%) stddev-sans-first:28.44 19:30:33 INFO - PROCESS | 1928 | Values: 90.0 146.0 142.0 94.0 90.0 82.0 87.0 150.0 140.0 114.0 19:30:33 INFO - PROCESS | 1928 | 19:30:33 INFO - PROCESS | 1928 | [#8] json-parse-financial Cycles:10 Average:70.20 Median:69.50 stddev:7.32 (10.5%) stddev-sans-first:7.51 19:30:33 INFO - PROCESS | 1928 | Values: 65.0 68.0 67.0 66.0 71.0 70.0 69.0 71.0 90.0 65.0 19:30:33 INFO - PROCESS | 1928 | 19:30:33 INFO - PROCESS | 1928 | [#9] json-stringify-tinderbox Cycles:10 Average:60.20 Median:59.50 stddev:3.01 (5.1%) stddev-sans-first:3.09 19:30:33 INFO - PROCESS | 1928 | Values: 58.0 65.0 58.0 65.0 63.0 58.0 57.0 59.0 59.0 60.0 19:30:33 INFO - PROCESS | 1928 | 19:30:33 INFO - PROCESS | 1928 | [#10] stanford-crypto-aes Cycles:10 Average:92.10 Median:84.00 stddev:9.28 (11.0%) stddev-sans-first:9.48 19:30:33 INFO - PROCESS | 1928 | Values: 85.0 80.0 101.0 103.0 103.0 103.0 87.0 88.0 88.0 83.0 19:30:33 INFO - PROCESS | 1928 | 19:30:33 INFO - PROCESS | 1928 | [#11] stanford-crypto-ccm Cycles:10 Average:152.70 Median:147.00 stddev:24.90 (16.9%) stddev-sans-first:26.40 19:30:33 INFO - PROCESS | 1928 | Values: 151.0 142.0 138.0 140.0 138.0 141.0 143.0 163.0 220.0 151.0 19:30:33 INFO - PROCESS | 1928 | 19:30:33 INFO - PROCESS | 1928 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:185.20 Median:175.50 stddev:23.59 (13.4%) stddev-sans-first:24.28 19:30:33 INFO - PROCESS | 1928 | Values: 169.0 206.0 179.0 172.0 168.0 169.0 171.0 170.0 231.0 217.0 19:30:33 INFO - PROCESS | 1928 | 19:30:33 INFO - PROCESS | 1928 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.20 Median:67.50 stddev:1.81 (2.7%) stddev-sans-first:1.92 19:30:33 INFO - PROCESS | 1928 | Values: 67.0 68.0 65.0 66.0 66.0 67.0 70.0 70.0 68.0 65.0 19:30:33 INFO - PROCESS | 1928 | -------- Summary: end -------- 19:30:33 INFO - PROCESS | 1928 | 19:30:34 INFO - TEST-INFO | 1928: exit 0 19:30:34 INFO - TEST-OK | kraken | took 62311ms 19:30:34 INFO - SUITE-END | took 1179s 19:30:35 INFO - Completed test suite (00:19:39) 19:30:35 INFO - javascript benchmark 19:30:35 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7629.229988326482, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3480.338735640228, 3483.982580087099, 3490.476428535718, 3495.650434956505, 3515.4551044588084, 6067.575269731115, 6068.211132552768, 6071.453569178773, 6107.083937409391, 6122.41837025483, 6066.454019138277, 6087.3303936566945, 6126.724297406617, 6127.846803829895, 6129.724162412691, 5477.75350109244, 5511.366192887809, 5519.448055194469, 5535.086710692731, 5570.6657600543895, 4223.61987421132, 5516.613837031409, 5579.38626751057, 5589.832305030855, 5605.411431799664, 5384.6499977501335, 5389.78440862365, 5558.666480011192, 5563.082091454905, 5574.804881829137, 5494.313210848644, 5500.834974950758, 5501.064818980783, 5531.778728850841, 5590.636608620467, 4507.774611269456, 5315.468453154654, 5432.239486471897, 5454.699991500466, 5514.972425137848, 4997.700137991696, 5874.647521148702, 6145.293291271516, 6191.380861913818, 6235.127082208494, 6299.086632438317, 6301.968490157566, 6309.116723658692, 6330.17707698001, 6335.904961425582, 6570.0, 6637.933620663828, 6649.168853893263, 6668.19981602206, 6710.16122984627, 6194.318624951252, 6197.380261973812, 6234.0960560718895, 6244.250689917239, 6280.434760871543, 6359.459445947054, 6440.0, 6526.934730652728, 6629.105070815474, 6763.7632682855865, 6277.497800175975, 6296.181496405438, 6328.6519241442165, 6334.493240540745, 6337.841553961188, 6477.902831457532, 6510.967445162744, 6520.967395162994, 6550.6069635822005, 6553.475721942234, 6212.658303793335, 6424.775132870327, 6434.935650643527, 6441.838954026093, 6453.870922581616, 6191.938080619227, 6285.057241413733, 6298.307186209513, 6325.652089135142, 6351.523635727339, 5180.637355385087, 5214.817481388209, 5221.947780522222, 5222.4255331913455, 5235.0, 4654.534546545318, 4678.064387122589, 4686.625069994392, 4693.225617773098, 4706.058788242297, 6015.789447369409, 6101.420365065312, 6184.81445556634, 6189.071639254148, 6208.286047104551, 6346.143270658495, 6354.777582784581, 6356.968215158895, 6394.136791533084, 6398.360163983658, 6107.877842443127, 6115.541334399938, 6119.2350956130485, 6126.6017708848785, 6126.908096378558, 5679.176519404663, 5695.231143795535, 5698.202251684767, 5709.628874123252, 5715.057015592464, 6399.64801935898, 6457.967710161419, 6610.206775187008, 6649.534532582673, 6768.153980752406, 6491.0, 6507.739690412331, 6541.247756508063, 6554.082428459924, 6570.605763654196, 6668.933310667026, 6769.830754231184, 6777.457803375817, 6784.0, 6789.93210067893, 6231.75072997075, 6277.932751432176, 6330.746770129327, 6351.30135685065, 6432.903506447501, 6358.650274234777, 6495.707693153725, 6499.38255865692, 6504.512161587994, 6525.9021114684265, 6306.84232894163, 6326.145970293951, 6355.809325720051, 6369.49044076482, 6369.554131210771, 6218.284897236876, 6256.968715156395, 6262.279837818709, 6288.308286088435, 6342.873142537216, 4668.696534725232, 6161.045038019114, 6278.152449419453, 6297.527685423704, 6321.8735625288155, 6345.0, 6349.4920406366455, 6354.332795056637, 6358.364163583605, 6358.841028974312, 6132.141500189888, 6150.60021098645, 6226.657533835501, 6265.216847894013, 6302.401271879165], "value": 5955.966794194695, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [740.2412527159661, 762.306301265848, 811.3509192645881, 827.652385997881, 951.7382719752063, 32409.83795081024, 37704.43443348352, 38982.8050859746, 39410.408843867364, 39411.40882886752, 30685.69314306856, 38084.61915380845, 38163.61836381636, 38209.808950955274, 38258.61741382585, 7859.528428294307, 7983.401244906655, 10266.486675666225, 10607.681769546887, 10659.467026648637, 9376.343655944087, 9472.579113133017, 10399.220058495604, 10422.530986105605, 10503.0, 10467.371957682526, 10503.264771466, 10527.420991845445, 10535.63125290615, 10548.736281592983, 10465.895341046606, 10492.10817080549, 10504.212184086224, 10509.789804203872, 10522.842157367646, 9115.680951166678, 9882.258830587663, 10229.693109206735, 10276.588936442533, 10295.845562316572, 32838.83580582106, 33055.50416743751, 33205.66794332074, 33311.50032749511, 33401.6659833401, 3290.7696461247724, 3653.6711695947492, 3743.1765011697385, 3776.7922764247955, 3782.583915769263, 23805.880970595208, 25757.484850303077, 25940.61089083665, 26011.73988260131, 26067.869660651766, 3018.139830148419, 3055.7860949733526, 3069.6623371429123, 3153.1644114309725, 3242.270489139948, 358198.0, 363686.0, 364687.0, 365337.0, 368936.1553192271, 43605.0, 43743.78128109403, 43779.562204378184, 43781.56218437839, 43899.34150987738, 44606.0, 45141.54858451374, 45178.548214518094, 45505.31742023872, 45524.0, 13782.517611883535, 13867.306634668226, 13889.513867014606, 13890.305484725723, 13926.30368481592, 11799.823002654966, 11986.34075125859, 12011.279323240458, 12013.699657508632, 12021.939890300493, 10068.798624027624, 11604.24572402808, 11723.179377443497, 11788.587399440978, 11800.586979455678, 5356.384015838151, 5660.320761508563, 5662.433756624305, 5664.348599910981, 5686.118651609007, 886.6143227695973, 902.3367824648877, 909.7680091576606, 921.9078092190728, 937.8593211018402, 26897.327566810985, 26913.192604221906, 27138.914443422436, 27320.59019114715, 27445.58831617527, 730.82825535999, 1029.1355261580159, 1035.4977835749655, 1061.2995423020768, 1165.9708507287216, 10951.178661600412, 14370.784438233435, 14475.710485790225, 14575.833933285312, 14738.484153054591, 324092.0, 324325.0, 324924.0, 326284.0, 334454.0, 41850.0, 42448.0, 50509.98980020379, 50613.74693126511, 50768.0, 1415.900886937925, 1423.6156237815733, 1436.7772995185762, 1440.9567712968628, 1450.3835869755524, 23998.40003999914, 27123.236989595614, 28835.711642883303, 28857.711422885503, 29001.694923728905, 41690.1661966765, 41955.0, 41995.74012779621, 42003.52987645418, 42140.578594213664, 308966.0, 311759.0, 312394.0, 313395.0, 313462.0, 22431.0, 28852.278693032004, 29088.8364465419, 29598.852005739835, 29640.851795740884, 39072.0, 39726.60273397229, 39816.60183398129, 39971.0, 40049.79975100106, 27700.861495692392, 33172.50241246432, 33512.32975340528, 34344.14139646529, 34664.306713866084, 33055.173620659676, 33100.84393651031, 33650.15874603154, 33980.98057058292, 34278.82860585681, 32359.676403235666, 32898.67101328956, 33188.50217246695, 33573.32853342968, 33694.0, 28154.2961425966, 29372.26569335698, 29396.41207175802, 29594.704052959194, 29645.851770741006, 18440.90779546094, 19207.8079219206, 23192.768072319737, 23267.0, 23297.068117275798], "value": 17535.76251006132, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [411.76323613922, 424.7642558380099, 426.4412711009548, 433.28724248611053, 437.732982880443, 19647.803521964775, 20408.489787755312, 20472.078756455976, 20577.971101444906, 20956.0, 20408.081636326362, 21370.679439808377, 21690.566188676257, 22248.221312254074, 22446.66330005047, 6349.0, 6594.0, 6603.537752357337, 6606.471482281406, 6632.469402447792, 4583.427071616048, 5655.915161272585, 6576.112224849632, 6580.374864387875, 6587.703553340088, 5528.198411230369, 6561.442277406426, 6576.868462630745, 6580.868382632344, 7292.781216563511, 7631.656575454118, 7700.691972321157, 7748.496347737436, 7773.689052437924, 7805.804854878616, 6733.730650773963, 7053.224145344008, 7076.115485564304, 7078.610676412795, 7120.928790712079, 20033.899830500755, 20645.380638580868, 21447.142114315408, 25839.35401614956, 25943.481130377288, 2802.341449759305, 3015.8039727417868, 3276.393867134564, 3287.8849240276477, 3501.334746398202, 15232.695346093016, 15868.682626347409, 15939.282732277075, 16303.755443668355, 16642.001479911123, 2076.750789905221, 2106.799854013866, 2149.4518897681146, 2163.102312540294, 2364.2788949370433, 141363.0, 160983.0, 166411.99999999878, 169216.0, 173671.0, 16657.41699040552, 20294.898525507277, 20976.273084498916, 21006.0547275373, 21338.0, 31972.0, 32005.039848804572, 32462.188445289055, 32498.862539810994, 32514.674853251163, 10234.539445724811, 10368.740781480523, 10402.895971040343, 10593.152547796159, 10709.678709638723, 7352.852942941218, 8686.86969695455, 8698.21716045559, 8728.825423491622, 8934.50860202682, 9767.316287859923, 9824.954264178128, 9858.211343092535, 9928.404295742279, 10111.494425278854, 4107.617581802548, 4191.580841915845, 4210.45264115663, 4216.262154123016, 4228.577142285808, 1011.6307547745167, 1012.9240306977006, 1022.8823685276288, 1025.2208321675432, 1060.4697651174413, 18937.905310473634, 20287.0, 20667.0, 20673.218061948002, 20837.68743468849, 1020.1838529176629, 1021.7445638590352, 1022.8363461846069, 1052.5158427123456, 1060.0512541275668, 9935.205183585296, 10597.62908298206, 10627.52176152075, 10671.82609912909, 10725.463726813785, 759046.0, 772291.0, 778322.0, 780011.0, 783498.0, 122751.0, 125377.7462225366, 151869.0, 155543.0, 156709.0, 1174.2602160638744, 1201.6995751062234, 1243.8693937136832, 1251.7121062155572, 1253.8432695913011, 19173.424797256106, 19352.0, 19922.90038549798, 19928.800711993274, 19957.0, 61356.0, 61782.0, 62373.07455791692, 63257.0, 65137.674311630035, 85488.14511854803, 86154.0, 87476.12523874934, 87640.5617971906, 88412.67380989419, 8418.536980466133, 10294.073533382032, 11076.117060099641, 11083.224174307721, 11480.770384592428, 9938.30431869791, 11409.144314176305, 12032.518699251932, 12360.62918112422, 12637.747245054863, 10858.402787846428, 10934.343939363664, 11291.830622540832, 11296.661100167008, 11503.0, 10726.249162558546, 10795.190360723134, 10857.239993200401, 10875.184361172785, 11012.063974562252, 11134.997850193522, 11164.609238676607, 11190.160737944523, 11283.943580282046, 11384.37385943781, 9534.95232523833, 10894.182936279969, 11049.281796683512, 11479.885201147881, 11482.081433485135, 10422.583096676048, 10450.947745261225, 10457.843132353173, 10568.313059651098, 10597.28420852744], "value": 11514.585997118142, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1313.5008696695256, 1548.41934274647, 1553.223388305847, 1581.5571639940813, 1598.944036958706, 10711.571537138505, 11039.39283339416, 11269.26749761266, 11283.830742538868, 11380.0, 10626.734331641714, 10728.624498142566, 10801.729956751085, 10825.404602746847, 10828.079613232865, 2371.5494056129364, 2416.0577374823833, 2422.588160012793, 2424.272718184546, 2427.4538228898446, 2342.16853017179, 2359.0327965534134, 2382.65451509531, 2386.78518933296, 2389.6773935518745, 2013.8791672499624, 2352.3178278299356, 2362.492064206195, 2373.8338316317863, 2375.70303712036, 2322.8954697038503, 2341.0401735288533, 2344.308429013445, 2347.3427440316655, 2347.9647805282934, 2330.300909727092, 2391.748866369041, 2392.617181251009, 2402.3753824005885, 2403.399150212447, 11001.394923279217, 11183.608573699881, 11197.776044479146, 11255.043321317698, 11258.986691197832, 5731.312242530881, 5919.585629005971, 5922.289325280951, 5942.257217847769, 5944.138099975523, 3443.0359499340225, 3497.3879571075213, 3537.0449978505917, 3559.1635965548076, 3594.7663401878795, 1113.3542545323692, 1123.4495097402296, 1132.9206955513118, 1151.6314779270638, 1185.1052455396207, 13695.452181912711, 13854.0, 14974.176420296988, 15199.62000949974, 15258.465953691679, 3771.6794072503594, 4612.884677883013, 4746.1456937750845, 4757.119932812441, 4759.619230461555, 10553.0, 11822.0, 11853.0, 11888.643340699793, 11967.461464234131, 5801.448862358069, 5803.709814509258, 5805.88388232233, 5813.09896965971, 5827.35899051104, 5105.953279577678, 5264.894702105937, 5278.366596008504, 5336.0, 5339.11904535755, 3794.8102594870147, 3900.8439662413753, 3942.211557688474, 3972.8410863565714, 3979.960200397959, 888.5646033443695, 1022.9590816367262, 1029.2075102171436, 1030.6753372687579, 1037.9377237365782, 927.9189743948225, 931.1386967055447, 932.9953350233205, 937.1097457415483, 980.0444566547559, 6385.393387628168, 6414.967925160345, 6546.541742078009, 6672.699728512229, 6755.932440675629, 921.2307723051193, 993.6273897288517, 995.0994350113159, 998.5356809083665, 1015.6800607808517, 4127.587241275848, 4205.621494065487, 4243.872683819491, 4341.544137865541, 4397.802098905557, 12673.30296833683, 13015.284159371136, 13015.284159371136, 13025.218486890817, 13113.147645403018, 10774.515146818412, 12797.552085676956, 12827.717228277097, 12853.742925141632, 12883.935580322226, 8627.525486098199, 9984.101430871257, 10130.442825644514, 10244.180465562737, 10248.948755256177, 6530.771423000172, 6661.267260601233, 6685.665716714242, 6758.0, 6787.626680532618, 11869.347185904513, 12145.514179432723, 12145.514179432723, 12150.696232594255, 12175.269483830998, 11497.310161390344, 11856.940715296369, 12002.039836813208, 12431.813522797345, 12596.622101336974, 8998.82002359936, 9425.622975081194, 9429.057094290516, 9437.952810235905, 9438.811223775348, 10238.18094552449, 10262.43556604394, 10343.586256549956, 10405.479726013518, 10446.529906154394, 9227.35408521397, 9274.582643780912, 9292.303077269096, 9334.20659243972, 9590.42457452555, 9004.909950900406, 9047.276217902683, 9110.954445227997, 9142.040085790895, 9289.117533834275, 7812.257835505618, 7848.568328741974, 7894.763157105296, 7930.524168549675, 7955.960220198862, 5853.975554277899, 5863.677497737665, 5919.993601087739, 6068.6358818471035, 6088.452039316306, 5955.791547295824, 6086.147939288591, 6100.908486372797, 6136.048912418582, 6158.107074474266], "value": 5106.090702568623, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [307.35608899355844, 365.384327408317, 380.6098748782499, 383.8809968909638, 389.65320864430663, 5153.81961631343, 5161.380634323877, 5269.841904742859, 5359.445760729389, 5461.0716178249695, 4362.629176519991, 4594.0, 5143.562797162245, 5210.843674689756, 5242.292290540776, 4087.9182416351728, 4162.83532563067, 4203.579642035803, 4292.519080917079, 4335.6965012449145, 3294.6772525369556, 3778.8110594470304, 3919.4316824060493, 4179.874603761892, 4182.159849666137, 3794.4118661607495, 4106.77412742299, 4186.602272784081, 4281.914361712764, 4313.784310784448, 3514.2413205633457, 3877.922441551153, 4258.12576510854, 4270.466191726035, 4328.891777705551, 3365.074604483762, 3976.880693579168, 3983.900402489932, 3992.840286388541, 3996.500437445319, 4383.824647014148, 4667.486576476585, 5092.388913330387, 5100.801311691749, 5159.71621560814, 4802.919343147799, 4999.575036121934, 5022.372203474566, 5032.47159048302, 5032.6477146599755, 4609.539046095397, 5362.624616276862, 5467.726613669341, 5536.003519366513, 5640.86931752877, 4164.646005089571, 4411.691181617288, 4437.689361744679, 4459.687821852472, 4488.0, 5611.943880561183, 5702.714864256812, 5868.970655146697, 5888.440598143169, 5919.378465261172, 3581.391163502211, 4176.206520761046, 4271.803894909431, 4537.1833070047705, 4587.4036375271, 4232.724872883253, 4453.532379100213, 4635.466921304026, 4643.930341044819, 4655.790489427984, 3795.8873723546426, 4257.489101307801, 4877.6585639004925, 5023.346964894614, 5025.899482010413, 4332.415123958225, 4710.95289047112, 5277.9472205278225, 5550.056490396642, 5715.857103572361, 1415.344047464469, 1421.8862491000696, 1464.392277204948, 1481.5703446000755, 1579.2470357902719, 1007.6120693532896, 1056.693558867935, 1194.6595220362155, 1197.5329621447643, 1215.9817602735966, 4333.609975102256, 4689.577937985598, 5059.443461219263, 5229.607779416559, 5263.526282634504, 5201.505856943584, 5280.630355875129, 5837.832433513315, 5875.618084824473, 5884.734377264784, 4834.7824347904425, 5153.114256016643, 5221.347331583552, 5242.5281724644965, 5246.69145575953, 2565.3586603349163, 2620.2794231586086, 2865.9426811464073, 3013.74383177428, 3014.2065471044693, 5747.453991870766, 5868.053374058243, 5910.468057874812, 5950.702464876739, 6067.908981365283, 5146.794128234904, 5451.0, 5868.972929737364, 6000.909986350208, 6039.486643635252, 5302.337207849019, 5374.355077390738, 5430.91853622196, 5709.971450142806, 5818.0, 5642.3355110241755, 5723.942760572341, 5768.711564421678, 5823.563232757476, 5848.853778655568, 5540.587150276654, 5682.954136510935, 5758.942410575841, 5764.020116580107, 5804.5356371491025, 4923.135338759057, 5619.297587801525, 5669.922714684197, 5894.793682221101, 5933.2880054393745, 4322.524522302481, 4372.825086996611, 4444.844430445049, 4554.43069616298, 4554.954450455453, 5130.435652078194, 5183.6263390200875, 5213.348331458567, 5556.805511807067, 5780.786034932543, 4725.40932383452, 4855.417349918032, 4905.8037678492465, 5654.6889921054735, 5663.575231857545, 4619.376384188092, 5225.498915726548, 5280.392754833243, 5635.182898479779, 5669.4409037515015, 4546.40896683429, 5094.490550944876, 5186.377634683711, 5216.330774152387, 5430.972845135749, 4930.433000205022, 5277.683338999673, 5685.687287199244, 5712.7714891403875, 5745.862978655361, 4837.637177211794, 5312.884294298241, 5364.785408583769, 5486.765477767471, 5551.944480555143], "value": 4221.7710978596315, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [702.7118881258685, 714.9320814522619, 775.9418043646727, 817.860963636182, 819.8425664636864, 15236.61908452288, 15590.688186236297, 16089.356425742957, 16256.837431625694, 16302.266398012089, 14872.405103795862, 14886.553403397915, 15054.096754194756, 15096.698066038673, 15101.697966040672, 866.3805379153904, 918.2470374293082, 934.0192797562532, 943.8584212368165, 960.5149399553216, 899.7615631857531, 951.3578334624101, 951.7097285327973, 951.7620594851287, 954.0983770337034, 958.2573505533198, 992.2012779712329, 998.9900100999006, 1007.9848802267935, 1013.1034034879148, 999.145730400508, 1002.5638847101498, 1003.0471052500154, 1003.2926786615492, 1008.1984822066474, 735.6248313360171, 745.9477836551443, 747.7270796159418, 747.9476436649437, 750.2759836757555, 16082.115483648395, 16429.83570164295, 16496.17519124039, 16638.001719896845, 16671.416500422427, 13929.791053134211, 14066.788998165035, 14152.29238538069, 14192.645183870383, 14235.430582776677, 11685.298882067103, 11888.643340699793, 11889.619810402006, 11994.520219191221, 12016.218945768496, 3077.276839942612, 3594.6405359463843, 3617.5297211362617, 3757.041954301663, 3812.0850995761093, 56847.71576142093, 81243.5937820313, 99665.0, 107107.46446267796, 107754.46122769336, 10603.628872989562, 10999.120070394349, 11054.336739795637, 11536.134789890792, 11594.710132246593, 21787.455313617287, 21927.890360548416, 22056.007479663454, 22092.116315347528, 22225.22211722614, 7857.2928436441, 7888.960555197187, 7889.8816517752275, 7963.641636126388, 8158.224968627971, 4003.753518923961, 4328.307470804719, 4354.521002689701, 4383.715058521186, 4425.756583387944, 3466.9653303466644, 3515.5078289039557, 3528.770629909099, 3544.4151714966742, 3563.4476656118345, 453.6529554890511, 456.4157877916272, 657.8815813153583, 659.2188256915474, 718.6489399928206, 672.1530871102349, 674.3795707948699, 698.5180225644289, 706.5795851468368, 747.3498056690714, 10081.243906707026, 10651.30766500175, 10953.464240102272, 11437.0, 11475.311481311148, 570.2956848292356, 577.2784019975031, 595.4551585299489, 644.9358558378619, 703.2721133626774, 3802.543694756647, 4928.433230178503, 4966.950330496648, 5037.370328708911, 5038.0, 79098.0, 84320.0, 92531.07468925224, 94401.0, 98317.5084124589, 75243.87134192992, 75696.24303756891, 76897.0, 81066.0, 84241.57879210565, 71593.64203178951, 71765.0, 74029.25970740223, 75288.62355688187, 75527.62236188784, 9769.46267955241, 10835.241533092609, 11019.393933333744, 11091.279066860629, 11257.380844053328, 49500.50499494959, 50578.494215058854, 51681.0, 52211.216831748316, 52466.475335247684, 61494.0, 63275.36724632695, 64800.35199647943, 64812.0, 65250.673746630964, 12675.429605667583, 12877.291748953523, 12956.740865182455, 13012.934935325262, 13104.475820967436, 14475.493357732428, 14572.781408278672, 14634.92682536623, 14772.33524491422, 14990.250487475365, 13121.73756524845, 13742.93128534351, 13883.16700997903, 14055.508057217949, 14313.785293220819, 13529.932350338186, 13706.52027179044, 14006.509772157926, 14255.358508866919, 14302.57092287233, 13431.992600555193, 13758.243296618783, 13767.31163441844, 13896.305184740924, 14220.21788801626, 11335.829962550404, 11384.260023098472, 11572.074234061087, 12071.456784444897, 12131.272123672608, 11238.7255856207, 11455.885441145481, 11529.365884876412, 11710.0, 11851.525938962344], "value": 7605.66021421207, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1587.1000000000001, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 167.0, 120.0, 120.0, 119.0, 118.0, 120.0, 120.0, 121.0, 120.0], "value": 123.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [131.0, 115.0, 94.0, 173.0, 144.0, 143.0, 113.0, 142.0, 165.0, 113.0], "value": 133.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [198.0, 199.0, 240.0, 282.0, 205.0, 187.0, 284.0, 284.0, 226.0, 203.0], "value": 230.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 60.0, 61.0, 85.0, 70.0, 78.0, 86.0, 70.0, 74.0, 62.0], "value": 72.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 70.0, 83.0, 72.0, 75.0, 69.0, 82.0, 73.0, 94.0, 70.0], "value": 77.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 88.0, 92.0, 89.0, 89.0, 88.0, 91.0, 88.0, 90.0, 89.0], "value": 89.2, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [154.0, 97.0, 95.0, 106.0, 100.0, 106.0, 155.0, 125.0, 102.0, 148.0], "value": 118.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 146.0, 142.0, 94.0, 90.0, 82.0, 87.0, 150.0, 140.0, 114.0], "value": 113.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 68.0, 67.0, 66.0, 71.0, 70.0, 69.0, 71.0, 90.0, 65.0], "value": 70.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 65.0, 58.0, 65.0, 63.0, 58.0, 57.0, 59.0, 59.0, 60.0], "value": 60.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 80.0, 101.0, 103.0, 103.0, 103.0, 87.0, 88.0, 88.0, 83.0], "value": 92.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [151.0, 142.0, 138.0, 140.0, 138.0, 141.0, 143.0, 163.0, 220.0, 151.0], "value": 152.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [169.0, 206.0, 179.0, 172.0, 168.0, 169.0, 171.0, 170.0, 231.0, 217.0], "value": 185.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 68.0, 65.0, 66.0, 66.0, 67.0, 70.0, 70.0, 68.0, 65.0], "value": 67.2, "unit": "score"}]}]} 19:30:35 INFO - Return code: 0 19:30:35 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 19:30:35 ERROR - # TBPL SUCCESS # 19:30:35 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 19:30:35 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 19:30:35 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 19:30:35 INFO - 2017-03-09 19:30:35.172 screenresolution[1934:16776] starting screenresolution argv=screenresolution get 19:30:35 INFO - 2017-03-09 19:30:35.175 screenresolution[1934:16776] Display 0: 1600x1200x32@60 19:30:35 INFO - 2017-03-09 19:30:35.181 screenresolution[1935:16779] starting screenresolution argv=screenresolution list 19:30:35 INFO - Available Modes on Display 0 19:30:35 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 19:30:35 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 19:30:35 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 19:30:35 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 19:30:35 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 19:30:35 INFO - Intel Iris: 19:30:35 INFO - Chipset Model: Intel Iris 19:30:35 INFO - Type: GPU 19:30:35 INFO - Bus: Built-In 19:30:35 INFO - VRAM (Dynamic, Max): 1536 MB 19:30:35 INFO - Vendor: Intel (0x8086) 19:30:35 INFO - Device ID: 0x0a2e 19:30:35 INFO - Revision ID: 0x0009 19:30:35 INFO - Displays: 19:30:35 INFO - Display: 19:30:35 INFO - Resolution: 1600 x 1200 @ 60 Hz 19:30:35 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 19:30:35 INFO - Display Serial Number: 1600x1200 60 19:30:35 INFO - Main Display: Yes 19:30:35 INFO - Mirror: Off 19:30:35 INFO - Online: Yes 19:30:35 INFO - Rotation: Supported 19:30:35 INFO - Return code: 0 19:30:35 INFO - Running post-action listener: _resource_record_post_action 19:30:35 INFO - [mozharness: 2017-03-10 03:30:35.417100Z] Finished run-tests step (success) 19:30:35 INFO - Running post-run listener: _resource_record_post_run 19:30:35 INFO - Total resource usage - Wall time: 1200s; CPU: 54.0%; Read bytes: 846848; Write bytes: 510767616; Read time: 24; Write time: 1647 19:30:35 INFO - TinderboxPrint: CPU usage
54.5% 19:30:35 INFO - TinderboxPrint: I/O read bytes / time
846,848 / 24 19:30:35 INFO - TinderboxPrint: I/O write bytes / time
510,767,616 / 1,647 19:30:35 INFO - TinderboxPrint: CPU idle
2,185.4 (45.5%) 19:30:35 INFO - TinderboxPrint: CPU system
963.7 (20.1%) 19:30:35 INFO - TinderboxPrint: CPU user
1,652.5 (34.4%) 19:30:35 INFO - TinderboxPrint: Swap in / out
209,444,864 / 0 19:30:35 INFO - install - Wall time: 20s; CPU: 54.0%; Read bytes: 203893248; Write bytes: 202962944; Read time: 15645; Write time: 452 19:30:35 INFO - run-tests - Wall time: 1181s; CPU: 54.0%; Read bytes: 809984; Write bytes: 296790528; Read time: 24; Write time: 1153 19:30:35 INFO - Running post-run listener: _upload_blobber_files 19:30:35 INFO - Blob upload gear active. 19:30:35 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 19:30:35 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 19:30:35 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', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 19:30:35 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 Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 19:30:36 INFO - (blobuploader) - INFO - Open directory for files ... 19:30:36 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 19:30:36 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:30:36 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:30:37 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 19:30:37 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:30:37 INFO - (blobuploader) - INFO - Done attempting. 19:30:37 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 19:30:37 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:30:37 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:30:38 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 19:30:38 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:30:38 INFO - (blobuploader) - INFO - Done attempting. 19:30:38 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 19:30:38 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:30:38 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:30:39 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 19:30:39 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:30:39 INFO - (blobuploader) - INFO - Done attempting. 19:30:39 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 19:30:39 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:30:39 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:30:41 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 19:30:41 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:30:41 INFO - (blobuploader) - INFO - Done attempting. 19:30:41 INFO - (blobuploader) - INFO - Iteration through files over. 19:30:41 INFO - Return code: 0 19:30:41 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 19:30:41 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 19:30:41 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/efad621c3248d34ff2f8e4c4b2aef9ed1d32bc2ca35a75fd0c9b03e6cfc3c56ee4ea7629a6ed6e3416e16aa19827288d98346b6c2c3101afaac18deee661134d", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/660cfb904981cd07115ac916ec87f7ff4ab9424a78784d12c3aeb11da355decd3cbacd9e2a0772799cd30bce4470fc49c927ac75504a000fde81db7d515a02da", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/6c5df300192ea17dd506e32e0a27958c68be3258422b8f87eec2c6765b099097fa582248889c21864dfcf8b11a7c0dcaeaa73220bb7ec93af4ad981015ea8c17", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/02186363116457986ef6d17dcdefd040efca29c41f0f7233a4ac47cb6dafd424ed1c4d186f5df0f60cfd204084f5c6cb8ec7ec9321e1061555d4c4089b129e6e"} 19:30:41 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 19:30:41 INFO - Writing to file /builds/slave/test/properties/blobber_files 19:30:41 INFO - Contents: 19:30:41 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/efad621c3248d34ff2f8e4c4b2aef9ed1d32bc2ca35a75fd0c9b03e6cfc3c56ee4ea7629a6ed6e3416e16aa19827288d98346b6c2c3101afaac18deee661134d", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/660cfb904981cd07115ac916ec87f7ff4ab9424a78784d12c3aeb11da355decd3cbacd9e2a0772799cd30bce4470fc49c927ac75504a000fde81db7d515a02da", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/6c5df300192ea17dd506e32e0a27958c68be3258422b8f87eec2c6765b099097fa582248889c21864dfcf8b11a7c0dcaeaa73220bb7ec93af4ad981015ea8c17", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/02186363116457986ef6d17dcdefd040efca29c41f0f7233a4ac47cb6dafd424ed1c4d186f5df0f60cfd204084f5c6cb8ec7ec9321e1061555d4c4089b129e6e"} 19:30:41 INFO - Running post-run listener: copy_logs_to_upload_dir 19:30:41 INFO - Copying logs to upload dir... 19:30:41 INFO - mkdir: /builds/slave/test/build/upload/logs 19:30:41 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1282.541481 ========= master_lag: 0.17 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 22 secs) (at 2017-03-09 19:30:41.681511) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-09 19:30:41.685391) ========= 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.aSomOB5hf6/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.ckkPjw05hu/Listeners TMPDIR=/var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/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-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/efad621c3248d34ff2f8e4c4b2aef9ed1d32bc2ca35a75fd0c9b03e6cfc3c56ee4ea7629a6ed6e3416e16aa19827288d98346b6c2c3101afaac18deee661134d", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/660cfb904981cd07115ac916ec87f7ff4ab9424a78784d12c3aeb11da355decd3cbacd9e2a0772799cd30bce4470fc49c927ac75504a000fde81db7d515a02da", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/6c5df300192ea17dd506e32e0a27958c68be3258422b8f87eec2c6765b099097fa582248889c21864dfcf8b11a7c0dcaeaa73220bb7ec93af4ad981015ea8c17", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/02186363116457986ef6d17dcdefd040efca29c41f0f7233a4ac47cb6dafd424ed1c4d186f5df0f60cfd204084f5c6cb8ec7ec9321e1061555d4c4089b129e6e"} build_url:https://queue.taskcluster.net/v1/task/EEpWLpp1SSab7xvVlSXnIA/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011271 build_url: 'https://queue.taskcluster.net/v1/task/EEpWLpp1SSab7xvVlSXnIA/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/efad621c3248d34ff2f8e4c4b2aef9ed1d32bc2ca35a75fd0c9b03e6cfc3c56ee4ea7629a6ed6e3416e16aa19827288d98346b6c2c3101afaac18deee661134d", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/660cfb904981cd07115ac916ec87f7ff4ab9424a78784d12c3aeb11da355decd3cbacd9e2a0772799cd30bce4470fc49c927ac75504a000fde81db7d515a02da", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/6c5df300192ea17dd506e32e0a27958c68be3258422b8f87eec2c6765b099097fa582248889c21864dfcf8b11a7c0dcaeaa73220bb7ec93af4ad981015ea8c17", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/02186363116457986ef6d17dcdefd040efca29c41f0f7233a4ac47cb6dafd424ed1c4d186f5df0f60cfd204084f5c6cb8ec7ec9321e1061555d4c4089b129e6e"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-09 19:30:41.724635) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-09 19:30:41.724992) ========= 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.aSomOB5hf6/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.ckkPjw05hu/Listeners TMPDIR=/var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/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.005380 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-09 19:30:41.773474) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-09 19:30:41.773795) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-09 19:30:41.774116) ========= ========= Total master_lag: 0.32 =========