builder: graphics_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0324 starttime: 1483124970.83 results: success (0) buildid: 20161230093919 builduid: faa3eef4d0af4db7b1b2bc72612d210f revision: a00632dc133aa17fbb42197911b8ce7833bf9386 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-12-30 11:09:30.835200) ========= master: http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-12-30 11:09:30.835652) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-30 11:09:30.835926) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-30 11:09:31.024975) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-30 11:09:31.025255) ========= 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.9XD77iYQHa/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.fl2iYrNvHO/Listeners TMPDIR=/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-12-30 11:09:31-- 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.2M=0.001s 2016-12-30 11:09:31 (10.2 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.598229 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-30 11:09:31.647065) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-30 11:09:31.647364) ========= 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.9XD77iYQHa/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.fl2iYrNvHO/Listeners TMPDIR=/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/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.019390 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-30 11:09:31.696654) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-12-30 11:09:31.697030) ========= bash -c 'python archiver_client.py mozharness --repo projects/graphics --rev a00632dc133aa17fbb42197911b8ce7833bf9386 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo projects/graphics --rev a00632dc133aa17fbb42197911b8ce7833bf9386 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.9XD77iYQHa/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.fl2iYrNvHO/Listeners TMPDIR=/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-12-30 11:09:31,779 truncating revision to first 12 chars 2016-12-30 11:09:31,779 Setting DEBUG logging. 2016-12-30 11:09:31,779 attempt 1/10 2016-12-30 11:09:31,779 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/projects/graphics/a00632dc133a?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-12-30 11:09:33,909 unpacking tar archive at: graphics-a00632dc133a/testing/mozharness/ program finished with exit code 0 elapsedTime=2.385147 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-12-30 11:09:34.105472) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-30 11:09:34.108846) ========= script_repo_revision: a00632dc133aa17fbb42197911b8ce7833bf9386 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-30 11:09:34.109265) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-30 11:09:34.109532) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-30 11:09:34.129279) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 2 secs) (at 2016-12-30 11:09:34.129663) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Graphics --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Graphics in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Graphics', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Graphics'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.9XD77iYQHa/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.fl2iYrNvHO/Listeners TMPDIR=/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/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 11:09:34 INFO - MultiFileLogger online at 20161230 11:09:34 in /builds/slave/test 11:09:34 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Graphics --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Graphics 11:09:34 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 11:09:34 INFO - {'append_to_log': False, 11:09:34 INFO - 'base_work_dir': '/builds/slave/test', 11:09:34 INFO - 'blob_upload_branch': 'Graphics', 11:09:34 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 11:09:34 INFO - 'branch': 'Graphics', 11:09:34 INFO - 'buildbot_json_path': 'buildprops.json', 11:09:34 INFO - 'config_files': ('talos/mac_config.py',), 11:09:34 INFO - 'default_actions': ('clobber', 11:09:34 INFO - 'read-buildbot-config', 11:09:34 INFO - 'download-and-extract', 11:09:34 INFO - 'populate-webroot', 11:09:34 INFO - 'create-virtualenv', 11:09:34 INFO - 'install', 11:09:34 INFO - 'run-tests'), 11:09:34 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 11:09:34 INFO - 'download_minidump_stackwalk': True, 11:09:34 INFO - 'download_symbols': 'ondemand', 11:09:34 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 11:09:34 INFO - 'tooltool.py': '/tools/tooltool.py', 11:09:34 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 11:09:34 INFO - '/tools/misc-python/virtualenv.py')}, 11:09:34 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 11:09:34 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 11:09:34 INFO - 'installer_path': 'installer.exe', 11:09:34 INFO - 'log_level': 'info', 11:09:34 INFO - 'log_name': 'talos', 11:09:34 INFO - 'log_to_console': True, 11:09:34 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 11:09:34 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 11:09:34 INFO - 'opt_config_files': (), 11:09:34 INFO - 'pip_index': False, 11:09:34 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 11:09:34 INFO - 'cmd': ('bash', 11:09:34 INFO - '-c', 11:09:34 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 11:09:34 INFO - 'enabled': True, 11:09:34 INFO - 'halt_on_failure': False, 11:09:34 INFO - 'name': 'check_screen_resolution'},), 11:09:34 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 11:09:34 INFO - 'cmd': ('bash', 11:09:34 INFO - '-c', 11:09:34 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 11:09:34 INFO - 'enabled': True, 11:09:34 INFO - 'halt_on_failure': False, 11:09:34 INFO - 'name': 'check_screen_resolution'},), 11:09:34 INFO - 'run_cmd_checks_enabled': True, 11:09:34 INFO - 'sps_profile': False, 11:09:34 INFO - 'sps_profile_interval': 0, 11:09:34 INFO - 'suite': 'dromaeojs', 11:09:34 INFO - 'system_bits': '32', 11:09:34 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 11:09:34 INFO - 'title': 't-yosemite-r7-0324', 11:09:34 INFO - 'tooltool_cache': '/builds/tooltool_cache', 11:09:34 INFO - 'use_talos_json': True, 11:09:34 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 11:09:34 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 11:09:34 INFO - 'work_dir': 'build'} 11:09:34 INFO - [mozharness: 2016-12-30 19:09:34.334240Z] Running clobber step. 11:09:34 INFO - Running pre-action listener: _resource_record_pre_action 11:09:34 INFO - Running main action method: clobber 11:09:34 INFO - rmtree: /builds/slave/test/build 11:09:34 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 11:09:35 INFO - Running post-action listener: _resource_record_post_action 11:09:35 INFO - [mozharness: 2016-12-30 19:09:35.802883Z] Finished clobber step (success) 11:09:35 INFO - [mozharness: 2016-12-30 19:09:35.803051Z] Running read-buildbot-config step. 11:09:35 INFO - Running pre-action listener: _resource_record_pre_action 11:09:35 INFO - Running main action method: read_buildbot_config 11:09:35 INFO - Using buildbot properties: 11:09:35 INFO - { 11:09:35 INFO - "project": "", 11:09:35 INFO - "product": "firefox", 11:09:35 INFO - "script_repo_revision": "default", 11:09:35 INFO - "builddir": "graphics_yosemite_r7_test-dromaeojs", 11:09:35 INFO - "repository": "", 11:09:35 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 graphics talos dromaeojs", 11:09:35 INFO - "buildid": "20161230093919", 11:09:35 INFO - "pgo_build": "False", 11:09:35 INFO - "basedir": "/builds/slave/test", 11:09:35 INFO - "buildnumber": 17, 11:09:35 INFO - "slavename": "t-yosemite-r7-0324", 11:09:35 INFO - "master": "http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/", 11:09:35 INFO - "platform": "yosemite_r7", 11:09:35 INFO - "scheduler": "tests-graphics-macosx64-talos", 11:09:35 INFO - "branch": "graphics", 11:09:35 INFO - "repo_path": "projects/graphics", 11:09:35 INFO - "revision": "a00632dc133aa17fbb42197911b8ce7833bf9386", 11:09:35 INFO - "stage_platform": "macosx64", 11:09:35 INFO - "builduid": "faa3eef4d0af4db7b1b2bc72612d210f", 11:09:35 INFO - "slavebuilddir": "test" 11:09:35 INFO - } 11:09:35 INFO - Found installer url https://queue.taskcluster.net/v1/task/W-c36Vo3SSGPQ21dWGq2Jw/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg. 11:09:35 INFO - Running post-action listener: _resource_record_post_action 11:09:35 INFO - [mozharness: 2016-12-30 19:09:35.806244Z] Finished read-buildbot-config step (success) 11:09:35 INFO - [mozharness: 2016-12-30 19:09:35.806347Z] Running download-and-extract step. 11:09:35 INFO - Running pre-action listener: _resource_record_pre_action 11:09:35 INFO - Running main action method: download_and_extract 11:09:35 INFO - mkdir: /builds/slave/test/build/tests 11:09:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:09:35 INFO - trying https://queue.taskcluster.net/v1/task/W-c36Vo3SSGPQ21dWGq2Jw/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json 11:09:35 INFO - Downloading https://queue.taskcluster.net/v1/task/W-c36Vo3SSGPQ21dWGq2Jw/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json 11:09:35 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/W-c36Vo3SSGPQ21dWGq2Jw/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json'}, attempt #1 11:09:37 INFO - Downloaded 1199 bytes. 11:09:37 INFO - Reading from file /builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json 11:09:37 INFO - Using the following test package requirements: 11:09:37 INFO - {u'common': [u'firefox-53.0a1.en-US.mac.common.tests.zip'], 11:09:37 INFO - u'cppunittest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 11:09:37 INFO - u'firefox-53.0a1.en-US.mac.cppunittest.tests.zip'], 11:09:37 INFO - u'gtest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 11:09:37 INFO - u'firefox-53.0a1.en-US.mac.gtest.tests.zip'], 11:09:37 INFO - u'jittest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 11:09:37 INFO - u'jsshell-mac.zip'], 11:09:37 INFO - u'mochitest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 11:09:37 INFO - u'firefox-53.0a1.en-US.mac.mochitest.tests.zip'], 11:09:37 INFO - u'mozbase': [u'firefox-53.0a1.en-US.mac.common.tests.zip'], 11:09:37 INFO - u'reftest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 11:09:37 INFO - u'firefox-53.0a1.en-US.mac.reftest.tests.zip'], 11:09:37 INFO - u'talos': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 11:09:37 INFO - u'firefox-53.0a1.en-US.mac.talos.tests.zip'], 11:09:37 INFO - u'web-platform': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 11:09:37 INFO - u'firefox-53.0a1.en-US.mac.web-platform.tests.zip'], 11:09:37 INFO - u'xpcshell': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 11:09:37 INFO - u'firefox-53.0a1.en-US.mac.xpcshell.tests.zip']} 11:09:37 INFO - Downloading packages: [u'firefox-53.0a1.en-US.mac.common.tests.zip'] for test suite category: common 11:09:37 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/W-c36Vo3SSGPQ21dWGq2Jw/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip 11:09:37 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/W-c36Vo3SSGPQ21dWGq2Jw/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip'}, attempt #1 11:09:37 INFO - Fetch https://queue.taskcluster.net/v1/task/W-c36Vo3SSGPQ21dWGq2Jw/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip into memory 11:09:38 INFO - Expected file size: 16299175 11:09:38 INFO - Obtained file size: 16299175 11:09:44 INFO - Downloading packages: [u'firefox-53.0a1.en-US.mac.common.tests.zip', u'firefox-53.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 11:09:44 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/W-c36Vo3SSGPQ21dWGq2Jw/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip 11:09:44 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/W-c36Vo3SSGPQ21dWGq2Jw/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip'}, attempt #1 11:09:44 INFO - Fetch https://queue.taskcluster.net/v1/task/W-c36Vo3SSGPQ21dWGq2Jw/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip into memory 11:09:45 INFO - Expected file size: 16299175 11:09:45 INFO - Obtained file size: 16299175 11:09:52 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/W-c36Vo3SSGPQ21dWGq2Jw/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip 11:09:52 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/W-c36Vo3SSGPQ21dWGq2Jw/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip'}, attempt #1 11:09:52 INFO - Fetch https://queue.taskcluster.net/v1/task/W-c36Vo3SSGPQ21dWGq2Jw/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip into memory 11:09:53 INFO - Expected file size: 14409776 11:09:53 INFO - Obtained file size: 14409776 11:09:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:09:53 INFO - trying https://queue.taskcluster.net/v1/task/W-c36Vo3SSGPQ21dWGq2Jw/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 11:09:53 INFO - Downloading https://queue.taskcluster.net/v1/task/W-c36Vo3SSGPQ21dWGq2Jw/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg 11:09:53 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/W-c36Vo3SSGPQ21dWGq2Jw/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg'}, attempt #1 11:09:55 INFO - Downloaded 69762867 bytes. 11:09:55 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/W-c36Vo3SSGPQ21dWGq2Jw/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 11:09:55 INFO - mkdir: /builds/slave/test/properties 11:09:55 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 11:09:55 INFO - Writing to file /builds/slave/test/properties/build_url 11:09:55 INFO - Contents: 11:09:55 INFO - build_url:https://queue.taskcluster.net/v1/task/W-c36Vo3SSGPQ21dWGq2Jw/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 11:09:56 INFO - Running post-action listener: _resource_record_post_action 11:09:56 INFO - Running post-action listener: set_extra_try_arguments 11:09:56 INFO - [mozharness: 2016-12-30 19:09:56.439339Z] Finished download-and-extract step (success) 11:09:56 INFO - [mozharness: 2016-12-30 19:09:56.439480Z] Running populate-webroot step. 11:09:56 INFO - Running pre-action listener: _resource_record_pre_action 11:09:56 INFO - Running main action method: populate_webroot 11:09:56 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 11:09:56 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 11:09:56 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 11:09:56 INFO - '--tppagecycles', 11:09:56 INFO - '7'], 11:09:56 INFO - 'tests': ['tsvgm']}}, 11:09:56 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 11:09:56 INFO - 'tests': ['tresize', 'tcanvasmark']}, 11:09:56 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 11:09:56 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 11:09:56 INFO - 'tests': ['dromaeo_css', 'kraken']}, 11:09:56 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 11:09:56 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 11:09:56 INFO - 'talos_options': ['--disable-e10s'], 11:09:56 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 11:09:56 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 11:09:56 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 11:09:56 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 11:09:56 INFO - 'talos_options': ['--disable-e10s'], 11:09:56 INFO - 'tests': ['damp', 'tps']}, 11:09:56 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 11:09:56 INFO - 'tests': ['damp', 'tps']}, 11:09:56 INFO - 'g3': {'talos_options': ['--disable-e10s'], 11:09:56 INFO - 'tests': ['dromaeo_dom']}, 11:09:56 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 11:09:56 INFO - 'g4': {'talos_options': ['--disable-e10s'], 11:09:56 INFO - 'tests': ['basic_compositor_video']}, 11:09:56 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 11:09:56 INFO - 'other': {'talos_options': ['--disable-e10s'], 11:09:56 INFO - 'tests': ['a11yr', 11:09:56 INFO - 'ts_paint', 11:09:56 INFO - 'tpaint', 11:09:56 INFO - 'sessionrestore', 11:09:56 INFO - 'sessionrestore_no_auto_restore', 11:09:56 INFO - 'tabpaint']}, 11:09:56 INFO - 'other-e10s': {'tests': ['a11yr', 11:09:56 INFO - 'ts_paint', 11:09:56 INFO - 'tpaint', 11:09:56 INFO - 'sessionrestore', 11:09:56 INFO - 'sessionrestore_no_auto_restore', 11:09:56 INFO - 'tabpaint']}, 11:09:56 INFO - 'other-e10s_l64': {'tests': ['a11yr', 11:09:56 INFO - 'ts_paint', 11:09:56 INFO - 'tpaint', 11:09:56 INFO - 'sessionrestore', 11:09:56 INFO - 'sessionrestore_no_auto_restore', 11:09:56 INFO - 'tabpaint']}, 11:09:56 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 11:09:56 INFO - 'ts_paint', 11:09:56 INFO - 'tpaint', 11:09:56 INFO - 'sessionrestore', 11:09:56 INFO - 'sessionrestore_no_auto_restore', 11:09:56 INFO - 'tabpaint']}, 11:09:56 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 11:09:56 INFO - 'tests': ['a11yr', 11:09:56 INFO - 'ts_paint', 11:09:56 INFO - 'tpaint', 11:09:56 INFO - 'sessionrestore', 11:09:56 INFO - 'sessionrestore_no_auto_restore', 11:09:56 INFO - 'tabpaint']}, 11:09:56 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 11:09:56 INFO - 'tests': ['a11yr', 11:09:56 INFO - 'ts_paint', 11:09:56 INFO - 'tpaint', 11:09:56 INFO - 'sessionrestore', 11:09:56 INFO - 'sessionrestore_no_auto_restore', 11:09:56 INFO - 'tabpaint']}, 11:09:56 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 11:09:56 INFO - 'tests': ['tsvgx', 11:09:56 INFO - 'tsvgr_opacity', 11:09:56 INFO - 'tart', 11:09:56 INFO - 'tscrollx', 11:09:56 INFO - 'cart', 11:09:56 INFO - 'tsvg_static']}, 11:09:56 INFO - 'svgr-e10s': {'tests': ['tsvgx', 11:09:56 INFO - 'tsvgr_opacity', 11:09:56 INFO - 'tart', 11:09:56 INFO - 'tscrollx', 11:09:56 INFO - 'cart', 11:09:56 INFO - 'tsvg_static']}, 11:09:56 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 11:09:56 INFO - 'talos_options': ['--disable-e10s'], 11:09:56 INFO - 'tests': ['tp5o']}, 11:09:56 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 11:09:56 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 11:09:56 INFO - 'talos_options': ['--disable-e10s', 11:09:56 INFO - '--xperf_path', 11:09:56 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 11:09:56 INFO - 'tests': ['tp5n']}, 11:09:56 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 11:09:56 INFO - 'talos_options': ['--xperf_path', 11:09:56 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 11:09:56 INFO - 'tests': ['tp5n']}}, 11:09:56 INFO - 'talos.zip': {'path': '', 11:09:56 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 11:09:56 INFO - Running post-action listener: _resource_record_post_action 11:09:56 INFO - [mozharness: 2016-12-30 19:09:56.459249Z] Finished populate-webroot step (success) 11:09:56 INFO - [mozharness: 2016-12-30 19:09:56.459360Z] Running create-virtualenv step. 11:09:56 INFO - Running pre-action listener: _resource_record_pre_action 11:09:56 INFO - Running main action method: create_virtualenv 11:09:56 INFO - Creating virtualenv /builds/slave/test/build/venv 11:09:56 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 11:09:56 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 11:09:56 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 11:09:56 INFO - Using real prefix '/tools/python27' 11:09:56 INFO - New python executable in /builds/slave/test/build/venv/bin/python 11:09:57 INFO - Installing distribute.............................................................................................................................................................................................done. 11:10:00 INFO - Installing pip.................done. 11:10:00 INFO - Return code: 0 11:10:00 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 11:10:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 11:10:00 INFO - Reading from file tmpfile_stdout 11:10:00 INFO - Output received: 11:10:00 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) 11:10:00 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 11:10:00 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')]} 11:10:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:10:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:10:00 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')]} 11:10:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:10:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:10:00 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 0x10dd15b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dd18768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffa89c63f70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10daf3d30>, '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 0x10da1adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10dae8be8>, '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.9XD77iYQHa/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.fl2iYrNvHO/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/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 11:10:00 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 11:10:00 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 11:10:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.9XD77iYQHa/Render', 11:10:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:10:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:10:00 INFO - 'HOME': '/Users/cltbld', 11:10:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:10:00 INFO - 'LOGNAME': 'cltbld', 11:10:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:10:00 INFO - 'MOZ_NO_REMOTE': '1', 11:10:00 INFO - 'NO_EM_RESTART': '1', 11:10:00 INFO - 'PAGER': '/bin/cat', 11:10:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:10:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:10:00 INFO - 'PWD': '/builds/slave/test', 11:10:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:10:00 INFO - 'SHELL': '/bin/bash', 11:10:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.fl2iYrNvHO/Listeners', 11:10:00 INFO - 'TMPDIR': '/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/T/', 11:10:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:10:00 INFO - 'USER': 'cltbld', 11:10:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:10:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:10:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:10:00 INFO - 'XPC_FLAGS': '0x0', 11:10:00 INFO - 'XPC_SERVICE_NAME': '0', 11:10:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:10:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:10:00 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 11:10:00 INFO - Cleaning up... 11:10:00 INFO - Return code: 0 11:10:00 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 11:10:00 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')]} 11:10:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:10:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:10:00 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')]} 11:10:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:10:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:10:00 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 0x10dd15b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dd18768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffa89c63f70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10daf3d30>, '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 0x10da1adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10dae8be8>, '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.9XD77iYQHa/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.fl2iYrNvHO/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/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 11:10:00 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 11:10:00 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 11:10:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.9XD77iYQHa/Render', 11:10:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:10:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:10:00 INFO - 'HOME': '/Users/cltbld', 11:10:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:10:00 INFO - 'LOGNAME': 'cltbld', 11:10:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:10:00 INFO - 'MOZ_NO_REMOTE': '1', 11:10:00 INFO - 'NO_EM_RESTART': '1', 11:10:00 INFO - 'PAGER': '/bin/cat', 11:10:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:10:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:10:00 INFO - 'PWD': '/builds/slave/test', 11:10:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:10:00 INFO - 'SHELL': '/bin/bash', 11:10:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.fl2iYrNvHO/Listeners', 11:10:00 INFO - 'TMPDIR': '/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/T/', 11:10:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:10:00 INFO - 'USER': 'cltbld', 11:10:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:10:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:10:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:10:00 INFO - 'XPC_FLAGS': '0x0', 11:10:00 INFO - 'XPC_SERVICE_NAME': '0', 11:10:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:10:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:10:00 INFO - Downloading/unpacking psutil>=3.1.1 11:10:00 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 11:10:00 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 11:10:00 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 11:10:00 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 11:10:00 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 11:10:00 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 11:10:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 11:10:04 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 11:10:04 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 11:10:04 INFO - Installing collected packages: psutil 11:10:04 INFO - Running setup.py install for psutil 11:10:04 INFO - building 'psutil._psutil_osx' extension 11:10:04 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 11:10:05 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 11:10:05 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 11:10:05 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 11:10:05 INFO - building 'psutil._psutil_posix' extension 11:10:05 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 11:10:05 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 11:10:05 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 11:10:05 INFO - ^ 11:10:05 INFO - 1 warning generated. 11:10:05 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 11:10:05 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 11:10:05 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 11:10:05 INFO - Successfully installed psutil 11:10:05 INFO - Cleaning up... 11:10:05 INFO - Return code: 0 11:10:05 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 11: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')]} 11:10:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:10:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11: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')]} 11:10:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:10:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11: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', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10dd15b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dd18768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffa89c63f70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10daf3d30>, '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 0x10da1adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10dae8be8>, '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.9XD77iYQHa/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.fl2iYrNvHO/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/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 11: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', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 11: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 mozsystemmonitor==0.3 11:10:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.9XD77iYQHa/Render', 11:10:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:10:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:10:05 INFO - 'HOME': '/Users/cltbld', 11:10:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:10:05 INFO - 'LOGNAME': 'cltbld', 11:10:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:10:05 INFO - 'MOZ_NO_REMOTE': '1', 11:10:05 INFO - 'NO_EM_RESTART': '1', 11:10:05 INFO - 'PAGER': '/bin/cat', 11:10:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:10:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:10:05 INFO - 'PWD': '/builds/slave/test', 11:10:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:10:05 INFO - 'SHELL': '/bin/bash', 11:10:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.fl2iYrNvHO/Listeners', 11:10:05 INFO - 'TMPDIR': '/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/T/', 11:10:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:10:05 INFO - 'USER': 'cltbld', 11:10:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:10:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:10:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:10:05 INFO - 'XPC_FLAGS': '0x0', 11:10:05 INFO - 'XPC_SERVICE_NAME': '0', 11:10:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:10:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:10:05 INFO - Downloading/unpacking mozsystemmonitor==0.3 11: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 11: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 11: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 11: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 11: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 11: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 11:10:08 INFO - Downloading mozsystemmonitor-0.3.tar.gz 11:10:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 11:10:08 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 11:10:08 INFO - Installing collected packages: mozsystemmonitor 11:10:08 INFO - Running setup.py install for mozsystemmonitor 11:10:08 INFO - Successfully installed mozsystemmonitor 11:10:08 INFO - Cleaning up... 11:10:08 INFO - Return code: 0 11:10:08 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 11:10:08 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')]} 11:10:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:10:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:10:08 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')]} 11:10:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:10:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:10:08 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 0x10dd15b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dd18768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffa89c63f70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10daf3d30>, '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 0x10da1adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10dae8be8>, '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.9XD77iYQHa/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.fl2iYrNvHO/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/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 11:10:08 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 11:10:08 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 11:10:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.9XD77iYQHa/Render', 11:10:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:10:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:10:08 INFO - 'HOME': '/Users/cltbld', 11:10:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:10:08 INFO - 'LOGNAME': 'cltbld', 11:10:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:10:08 INFO - 'MOZ_NO_REMOTE': '1', 11:10:08 INFO - 'NO_EM_RESTART': '1', 11:10:08 INFO - 'PAGER': '/bin/cat', 11:10:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:10:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:10:08 INFO - 'PWD': '/builds/slave/test', 11:10:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:10:08 INFO - 'SHELL': '/bin/bash', 11:10:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.fl2iYrNvHO/Listeners', 11:10:08 INFO - 'TMPDIR': '/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/T/', 11:10:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:10:08 INFO - 'USER': 'cltbld', 11:10:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:10:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:10:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:10:08 INFO - 'XPC_FLAGS': '0x0', 11:10:08 INFO - 'XPC_SERVICE_NAME': '0', 11:10:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:10:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:10:09 INFO - Downloading/unpacking jsonschema==2.5.1 11:10:09 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 11:10:09 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 11:10:09 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 11:10:09 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 11:10:09 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 11:10:09 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 11:10:11 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 11:10:11 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 11:10:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:10:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:10:11 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:10:11 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:10:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:10:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:10:12 INFO - Downloading functools32-3.2.3-2.tar.gz 11:10:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 11:10:12 INFO - warning: no files found matching '*.txt' 11:10:12 INFO - no previously-included directories found matching 'build' 11:10:12 INFO - no previously-included directories found matching 'dist' 11:10:12 INFO - no previously-included directories found matching '.git*' 11:10:12 INFO - Installing collected packages: jsonschema, functools32 11:10:12 INFO - Running setup.py install for functools32 11:10:12 INFO - warning: no files found matching '*.txt' 11:10:12 INFO - no previously-included directories found matching 'build' 11:10:12 INFO - no previously-included directories found matching 'dist' 11:10:12 INFO - no previously-included directories found matching '.git*' 11:10:12 INFO - Successfully installed jsonschema functools32 11:10:12 INFO - Cleaning up... 11:10:12 INFO - Return code: 0 11:10:12 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 11:10:12 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')]} 11:10:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:10:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:10:12 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')]} 11:10:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:10:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:10:12 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 0x10dd15b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dd18768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffa89c63f70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10daf3d30>, '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 0x10da1adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10dae8be8>, '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.9XD77iYQHa/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.fl2iYrNvHO/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/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 11:10:12 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 11:10:12 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 11:10:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.9XD77iYQHa/Render', 11:10:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:10:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:10:12 INFO - 'HOME': '/Users/cltbld', 11:10:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:10:12 INFO - 'LOGNAME': 'cltbld', 11:10:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:10:12 INFO - 'MOZ_NO_REMOTE': '1', 11:10:12 INFO - 'NO_EM_RESTART': '1', 11:10:12 INFO - 'PAGER': '/bin/cat', 11:10:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:10:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:10:12 INFO - 'PWD': '/builds/slave/test', 11:10:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:10:12 INFO - 'SHELL': '/bin/bash', 11:10:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.fl2iYrNvHO/Listeners', 11:10:12 INFO - 'TMPDIR': '/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/T/', 11:10:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:10:12 INFO - 'USER': 'cltbld', 11:10:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:10:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:10:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:10:12 INFO - 'XPC_FLAGS': '0x0', 11:10:12 INFO - 'XPC_SERVICE_NAME': '0', 11:10:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:10:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:10:12 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 11:10:12 INFO - Cleaning up... 11:10:12 INFO - Return code: 0 11:10:12 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 11:10:12 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')]} 11:10:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:10:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:10:12 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')]} 11:10:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:10:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:10:12 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 0x10dd15b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dd18768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffa89c63f70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10daf3d30>, '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 0x10da1adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10dae8be8>, '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.9XD77iYQHa/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.fl2iYrNvHO/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/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 11:10:12 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 11:10:12 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 11:10:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.9XD77iYQHa/Render', 11:10:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:10:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:10:12 INFO - 'HOME': '/Users/cltbld', 11:10:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:10:12 INFO - 'LOGNAME': 'cltbld', 11:10:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:10:12 INFO - 'MOZ_NO_REMOTE': '1', 11:10:12 INFO - 'NO_EM_RESTART': '1', 11:10:12 INFO - 'PAGER': '/bin/cat', 11:10:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:10:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:10:12 INFO - 'PWD': '/builds/slave/test', 11:10:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:10:12 INFO - 'SHELL': '/bin/bash', 11:10:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.fl2iYrNvHO/Listeners', 11:10:12 INFO - 'TMPDIR': '/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/T/', 11:10:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:10:12 INFO - 'USER': 'cltbld', 11:10:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:10:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:10:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:10:12 INFO - 'XPC_FLAGS': '0x0', 11:10:12 INFO - 'XPC_SERVICE_NAME': '0', 11:10:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:10:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:10:13 INFO - Downloading/unpacking blobuploader==1.2.4 11:10:13 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 11:10:13 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 11:10:13 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 11:10:13 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 11:10:13 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 11:10:13 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 11:10:15 INFO - Downloading blobuploader-1.2.4.tar.gz 11:10:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 11:10:15 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 11:10:15 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 11:10:15 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 11:10:15 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 11:10:15 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 11:10:15 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 11:10:15 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 11:10:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 11:10:16 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 11:10:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:10:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:10:16 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:10:16 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:10:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:10:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:10:16 INFO - Downloading docopt-0.6.1.tar.gz 11:10:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 11:10:17 INFO - Installing collected packages: blobuploader, requests, docopt 11:10:17 INFO - Running setup.py install for blobuploader 11:10:17 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 11:10:17 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 11:10:17 INFO - Running setup.py install for requests 11:10:17 INFO - Running setup.py install for docopt 11:10:17 INFO - Successfully installed blobuploader requests docopt 11:10:17 INFO - Cleaning up... 11:10:17 INFO - Return code: 0 11:10:17 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:10:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:10:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:10:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:10:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:10:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:10:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:10:17 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 0x10dd15b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dd18768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffa89c63f70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10daf3d30>, '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 0x10da1adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10dae8be8>, '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.9XD77iYQHa/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.fl2iYrNvHO/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/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 11:10:17 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 11:10:17 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 11:10:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.9XD77iYQHa/Render', 11:10:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:10:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:10:17 INFO - 'HOME': '/Users/cltbld', 11:10:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:10:17 INFO - 'LOGNAME': 'cltbld', 11:10:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:10:17 INFO - 'MOZ_NO_REMOTE': '1', 11:10:17 INFO - 'NO_EM_RESTART': '1', 11:10:17 INFO - 'PAGER': '/bin/cat', 11:10:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:10:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:10:17 INFO - 'PWD': '/builds/slave/test', 11:10:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:10:17 INFO - 'SHELL': '/bin/bash', 11:10:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.fl2iYrNvHO/Listeners', 11:10:17 INFO - 'TMPDIR': '/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/T/', 11:10:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:10:17 INFO - 'USER': 'cltbld', 11:10:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:10:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:10:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:10:17 INFO - 'XPC_FLAGS': '0x0', 11:10:17 INFO - 'XPC_SERVICE_NAME': '0', 11:10:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:10:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:10:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 11:10:18 INFO - Running setup.py (path:/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/T/pip-m0HhpM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 11:10:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 11:10:18 INFO - Running setup.py (path:/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/T/pip-68ikn5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 11:10:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 11:10:18 INFO - Running setup.py (path:/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/T/pip-GlVhHR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 11:10:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 11:10:18 INFO - Running setup.py (path:/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/T/pip-l2lL0O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 11:10:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 11:10:18 INFO - Running setup.py (path:/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/T/pip-LOCpcz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 11:10:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 11:10:18 INFO - Running setup.py (path:/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/T/pip-AFAeLW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 11:10:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 11:10:18 INFO - Running setup.py (path:/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/T/pip-I_3LhC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 11:10:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 11:10:18 INFO - Running setup.py (path:/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/T/pip-bNpBYv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 11:10:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 11:10:19 INFO - Running setup.py (path:/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/T/pip-duaGin-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 11:10:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 11:10:19 INFO - Running setup.py (path:/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/T/pip-XWgv0r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 11:10:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 11:10:19 INFO - Running setup.py (path:/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/T/pip-6Qgmqh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 11:10:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 11:10:19 INFO - Running setup.py (path:/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/T/pip-ceyUA9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 11:10:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 11:10:19 INFO - Running setup.py (path:/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/T/pip-auYncW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 11:10:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 11:10:19 INFO - Running setup.py (path:/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/T/pip-0OWUrR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 11:10:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 11:10:19 INFO - Running setup.py (path:/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/T/pip-maTWKP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 11:10:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 11:10:19 INFO - Running setup.py (path:/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/T/pip-8o5XB5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 11:10:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 11:10:19 INFO - Running setup.py (path:/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/T/pip-ZgErL0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 11:10:19 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 11:10:19 INFO - Running setup.py install for manifestparser 11:10:20 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 11:10:20 INFO - Running setup.py install for mozcrash 11:10:20 INFO - Running setup.py install for mozdebug 11:10:20 INFO - Running setup.py install for mozdevice 11:10:20 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 11:10:20 INFO - Installing dm script to /builds/slave/test/build/venv/bin 11:10:20 INFO - Running setup.py install for mozfile 11:10:20 INFO - Running setup.py install for mozhttpd 11:10:20 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 11:10:20 INFO - Running setup.py install for mozinfo 11:10:21 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 11:10:21 INFO - Running setup.py install for mozInstall 11:10:21 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 11:10:21 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 11:10:21 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 11:10:21 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 11:10:21 INFO - Running setup.py install for mozleak 11:10:21 INFO - Running setup.py install for mozlog 11:10:21 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 11:10:21 INFO - Running setup.py install for moznetwork 11:10:21 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 11:10:21 INFO - Running setup.py install for mozprocess 11:10:21 INFO - Running setup.py install for mozprofile 11:10:21 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 11:10:21 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 11:10:21 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 11:10:21 INFO - Running setup.py install for mozrunner 11:10:22 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 11:10:22 INFO - Running setup.py install for mozscreenshot 11:10:22 INFO - Running setup.py install for moztest 11:10:22 INFO - Running setup.py install for mozversion 11:10:22 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 11:10:22 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 11:10:22 INFO - Cleaning up... 11:10:22 INFO - Return code: 0 11:10:22 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:10:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:10:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:10:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:10:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:10:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:10:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:10:22 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 0x10dd15b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dd18768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffa89c63f70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10daf3d30>, '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 0x10da1adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10dae8be8>, '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.9XD77iYQHa/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.fl2iYrNvHO/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/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 11:10:22 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 11:10:22 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 11:10:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.9XD77iYQHa/Render', 11:10:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:10:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:10:22 INFO - 'HOME': '/Users/cltbld', 11:10:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:10:22 INFO - 'LOGNAME': 'cltbld', 11:10:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:10:22 INFO - 'MOZ_NO_REMOTE': '1', 11:10:22 INFO - 'NO_EM_RESTART': '1', 11:10:22 INFO - 'PAGER': '/bin/cat', 11:10:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:10:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:10:22 INFO - 'PWD': '/builds/slave/test', 11:10:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:10:22 INFO - 'SHELL': '/bin/bash', 11:10:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.fl2iYrNvHO/Listeners', 11:10:22 INFO - 'TMPDIR': '/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/T/', 11:10:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:10:22 INFO - 'USER': 'cltbld', 11:10:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:10:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:10:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:10:22 INFO - 'XPC_FLAGS': '0x0', 11:10:22 INFO - 'XPC_SERVICE_NAME': '0', 11:10:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:10:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:10:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 11:10:22 INFO - Running setup.py (path:/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/T/pip-B4nCW4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 11:10:23 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)) 11:10:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 11:10:23 INFO - Running setup.py (path:/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/T/pip-Ga9zWM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 11:10:23 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)) 11:10:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 11:10:23 INFO - Running setup.py (path:/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/T/pip-Y0xQdS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 11:10:23 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)) 11:10:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 11:10:23 INFO - Running setup.py (path:/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/T/pip-6Y4lsb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 11:10:23 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)) 11:10:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 11:10:23 INFO - Running setup.py (path:/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/T/pip-jpmmlJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 11:10:23 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)) 11:10:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 11:10:23 INFO - Running setup.py (path:/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/T/pip-TGUo9W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 11:10:23 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)) 11:10:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 11:10:23 INFO - Running setup.py (path:/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/T/pip-YfK0NN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 11:10:23 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)) 11:10:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 11:10:23 INFO - Running setup.py (path:/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/T/pip-72eatY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 11:10:23 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)) 11:10:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 11:10:23 INFO - Running setup.py (path:/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/T/pip-w_A_xF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 11:10:23 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)) 11:10:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 11:10:23 INFO - Running setup.py (path:/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/T/pip-BVjQXu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 11:10:23 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)) 11:10:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 11:10:23 INFO - Running setup.py (path:/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/T/pip-Yk_Oso-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 11:10:24 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)) 11:10:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 11:10:24 INFO - Running setup.py (path:/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/T/pip-MEexrP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 11:10:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 11:10:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 11:10:24 INFO - Running setup.py (path:/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/T/pip-Vbk2f8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 11:10:24 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)) 11:10:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 11:10:24 INFO - Running setup.py (path:/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/T/pip-4d8wSf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 11:10:24 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)) 11:10:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 11:10:24 INFO - Running setup.py (path:/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/T/pip-ZzU8aF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 11:10:24 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)) 11:10:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 11:10:24 INFO - Running setup.py (path:/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/T/pip-J3d7co-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 11:10:24 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)) 11:10:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 11:10:24 INFO - Running setup.py (path:/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/T/pip-tbqShf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 11:10:24 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)) 11:10:24 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)) 11:10:24 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)) 11:10:24 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)) 11:10:24 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)) 11:10:24 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)) 11:10:24 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 11:10:24 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 11:10:24 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 11:10:24 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 11:10:24 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 11:10:24 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 11:10:24 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 11:10:27 INFO - Downloading blessings-1.6.tar.gz 11:10:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 11:10:27 INFO - Installing collected packages: blessings 11:10:27 INFO - Running setup.py install for blessings 11:10:27 INFO - Successfully installed blessings 11:10:27 INFO - Cleaning up... 11:10:27 INFO - Return code: 0 11:10:27 INFO - Done creating virtualenv /builds/slave/test/build/venv. 11:10:27 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 11:10:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 11:10:28 INFO - Reading from file tmpfile_stdout 11:10:28 INFO - Current package versions: 11:10:28 INFO - blessings == 1.6 11:10:28 INFO - blobuploader == 1.2.4 11:10:28 INFO - docopt == 0.6.1 11:10:28 INFO - functools32 == 3.2.3-2 11:10:28 INFO - jsonschema == 2.5.1 11:10:28 INFO - manifestparser == 1.1 11:10:28 INFO - mozInstall == 1.12 11:10:28 INFO - mozcrash == 1.0 11:10:28 INFO - mozdebug == 0.1 11:10:28 INFO - mozdevice == 0.49 11:10:28 INFO - mozfile == 1.2 11:10:28 INFO - mozhttpd == 0.7 11:10:28 INFO - mozinfo == 0.9 11:10:28 INFO - mozleak == 0.1 11:10:28 INFO - mozlog == 3.4 11:10:28 INFO - moznetwork == 0.27 11:10:28 INFO - mozprocess == 0.23 11:10:28 INFO - mozprofile == 0.28 11:10:28 INFO - mozrunner == 6.13 11:10:28 INFO - mozscreenshot == 0.1 11:10:28 INFO - mozsystemmonitor == 0.3 11:10:28 INFO - moztest == 0.8 11:10:28 INFO - mozversion == 1.4 11:10:28 INFO - psutil == 3.1.1 11:10:28 INFO - requests == 1.2.3 11:10:28 INFO - wsgiref == 0.1.2 11:10:28 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11: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')]} 11:10:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:10:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11: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')]} 11:10:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:10:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11: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/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 0x10dd15b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dd18768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffa89c63f70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10daf3d30>, '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 0x10da1adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10dae8be8>, '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.9XD77iYQHa/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.fl2iYrNvHO/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/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 11:10:28 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 11:10:28 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 11:10:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.9XD77iYQHa/Render', 11:10:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:10:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:10:28 INFO - 'HOME': '/Users/cltbld', 11:10:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:10:28 INFO - 'LOGNAME': 'cltbld', 11:10:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:10:28 INFO - 'MOZ_NO_REMOTE': '1', 11:10:28 INFO - 'NO_EM_RESTART': '1', 11:10:28 INFO - 'PAGER': '/bin/cat', 11:10:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:10:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:10:28 INFO - 'PWD': '/builds/slave/test', 11:10:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:10:28 INFO - 'SHELL': '/bin/bash', 11:10:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.fl2iYrNvHO/Listeners', 11:10:28 INFO - 'TMPDIR': '/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/T/', 11:10:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:10:28 INFO - 'USER': 'cltbld', 11:10:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:10:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:10:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:10:28 INFO - 'XPC_FLAGS': '0x0', 11:10:28 INFO - 'XPC_SERVICE_NAME': '0', 11:10:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:10:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:10:28 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)) 11:10:28 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)) 11:10:28 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)) 11:10:28 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)) 11:10:28 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)) 11:10:28 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)) 11:10:28 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)) 11:10:28 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)) 11:10:28 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)) 11:10:28 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)) 11:10:28 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)) 11:10:28 INFO - Cleaning up... 11:10:28 INFO - Return code: 0 11:10:28 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 11: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')]} 11:10:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:10:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11: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')]} 11:10:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:10:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:10:28 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 0x10dd15b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dd18768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffa89c63f70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10daf3d30>, '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 0x10da1adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10dae8be8>, '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.9XD77iYQHa/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.fl2iYrNvHO/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/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 11:10:28 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 11:10:28 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 11:10:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.9XD77iYQHa/Render', 11:10:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:10:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:10:28 INFO - 'HOME': '/Users/cltbld', 11:10:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:10:28 INFO - 'LOGNAME': 'cltbld', 11:10:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:10:28 INFO - 'MOZ_NO_REMOTE': '1', 11:10:28 INFO - 'NO_EM_RESTART': '1', 11:10:28 INFO - 'PAGER': '/bin/cat', 11:10:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:10:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:10:28 INFO - 'PWD': '/builds/slave/test', 11:10:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:10:28 INFO - 'SHELL': '/bin/bash', 11:10:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.fl2iYrNvHO/Listeners', 11:10:28 INFO - 'TMPDIR': '/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/T/', 11:10:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:10:28 INFO - 'USER': 'cltbld', 11:10:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:10:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:10:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:10:28 INFO - 'XPC_FLAGS': '0x0', 11:10:28 INFO - 'XPC_SERVICE_NAME': '0', 11:10:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:10:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:10:28 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 11:10:28 INFO - Cleaning up... 11:10:28 INFO - Return code: 0 11:10:28 INFO - Running post-action listener: _resource_record_post_action 11:10:28 INFO - Running post-action listener: _start_resource_monitoring 11:10:28 INFO - Starting resource monitoring. 11:10:28 INFO - [mozharness: 2016-12-30 19:10:28.586547Z] Finished create-virtualenv step (success) 11:10:28 INFO - [mozharness: 2016-12-30 19:10:28.587501Z] Running install step. 11:10:28 INFO - Running pre-action listener: _resource_record_pre_action 11:10:28 INFO - Running main action method: install 11:10:28 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 11:10:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 11:10:28 INFO - Reading from file tmpfile_stdout 11:10:28 INFO - Detecting whether we're running mozinstall >=1.0... 11:10:28 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 11:10:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 11:10:28 INFO - Reading from file tmpfile_stdout 11:10:28 INFO - Output received: 11:10:28 INFO - Usage: mozinstall [options] installer 11:10:28 INFO - Options: 11:10:28 INFO - -h, --help show this help message and exit 11:10:28 INFO - -d DEST, --destination=DEST 11:10:28 INFO - Directory to install application into. [default: 11:10:28 INFO - "/builds/slave/test"] 11:10:28 INFO - --app=APP Application being installed. [default: firefox] 11:10:28 INFO - mkdir: /builds/slave/test/build/application 11:10:28 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 11:10:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 11:10:50 INFO - Reading from file tmpfile_stdout 11:10:50 INFO - Output received: 11:10:50 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 11:10:50 INFO - Running post-action listener: _resource_record_post_action 11:10:50 INFO - [mozharness: 2016-12-30 19:10:50.564127Z] Finished install step (success) 11:10:50 INFO - [mozharness: 2016-12-30 19:10:50.564257Z] Running run-tests step. 11:10:50 INFO - Running pre-action listener: _resource_record_pre_action 11:10:50 INFO - Running main action method: run_tests 11:10:50 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 11:10:50 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 11:10:50 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 11:10:50 INFO - 2016-12-30 11:10:50.579 screenresolution[1904:18049] starting screenresolution argv=screenresolution get 11:10:50 INFO - 2016-12-30 11:10:50.581 screenresolution[1904:18049] Display 0: 1600x1200x32@60 11:10:50 INFO - 2016-12-30 11:10:50.587 screenresolution[1905:18052] starting screenresolution argv=screenresolution list 11:10:50 INFO - Available Modes on Display 0 11:10:50 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 11:10:50 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 11:10:50 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 11:10:50 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 11:10:50 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 11:10:50 INFO - Intel Iris: 11:10:50 INFO - Chipset Model: Intel Iris 11:10:50 INFO - Type: GPU 11:10:50 INFO - Bus: Built-In 11:10:50 INFO - VRAM (Dynamic, Max): 1536 MB 11:10:50 INFO - Vendor: Intel (0x8086) 11:10:50 INFO - Device ID: 0x0a2e 11:10:50 INFO - Revision ID: 0x0009 11:10:50 INFO - Displays: 11:10:50 INFO - Display: 11:10:50 INFO - Resolution: 1600 x 1200 @ 60 Hz 11:10:50 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 11:10:50 INFO - Display Serial Number: 1600x1200 60 11:10:50 INFO - Main Display: Yes 11:10:50 INFO - Mirror: Off 11:10:50 INFO - Online: Yes 11:10:50 INFO - Rotation: Supported 11:10:50 INFO - Return code: 0 11:10:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 11:10:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 11:10:50 INFO - Python 2.7.3 11:10:50 INFO - Return code: 0 11:10:50 INFO - grabbing minidump binary from tooltool 11:10:50 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')]} 11:10:50 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 0x10daf3d30>, '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 0x10da1adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10dae8be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 11:10:50 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 11:10:50 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 11:10:50 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 11:10:50 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 11:10:50 INFO - Return code: 0 11:10:50 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 11:10:50 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 11:10:50 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 11:10:50 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 11:10:50 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 11:10:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Graphics', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/W-c36Vo3SSGPQ21dWGq2Jw/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0324', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 11:10:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Graphics --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/W-c36Vo3SSGPQ21dWGq2Jw/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0324 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 11:10:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.9XD77iYQHa/Render', 11:10:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:10:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:10:50 INFO - 'HOME': '/Users/cltbld', 11:10:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:10:50 INFO - 'LOGNAME': 'cltbld', 11:10:50 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 11:10:50 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 11:10:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:10:50 INFO - 'MOZ_NO_REMOTE': '1', 11:10:50 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 11:10:50 INFO - 'NO_EM_RESTART': '1', 11:10:50 INFO - 'PAGER': '/bin/cat', 11:10:50 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:10:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:10:50 INFO - 'PWD': '/builds/slave/test', 11:10:50 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 11:10:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:10:50 INFO - 'SHELL': '/bin/bash', 11:10:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.fl2iYrNvHO/Listeners', 11:10:50 INFO - 'TMPDIR': '/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/T/', 11:10:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:10:50 INFO - 'USER': 'cltbld', 11:10:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:10:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:10:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:10:50 INFO - 'XPC_FLAGS': '0x0', 11:10:50 INFO - 'XPC_SERVICE_NAME': '0', 11:10:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:10:50 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Graphics', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/W-c36Vo3SSGPQ21dWGq2Jw/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0324', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 11:10:51 INFO - mozversion application_buildid: 20161230093919 11:10:51 INFO - mozversion application_changeset: a00632dc133aa17fbb42197911b8ce7833bf9386 11:10:51 INFO - mozversion application_display_name: Nightly 11:10:51 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 11:10:51 INFO - mozversion application_name: Firefox 11:10:51 INFO - mozversion application_remotingname: firefox 11:10:51 INFO - mozversion application_repository: https://hg.mozilla.org/projects/graphics 11:10:51 INFO - mozversion application_vendor: Mozilla 11:10:51 INFO - mozversion application_version: 53.0a1 11:10:51 INFO - mozversion platform_buildid: 20161230093919 11:10:51 INFO - mozversion platform_changeset: a00632dc133aa17fbb42197911b8ce7833bf9386 11:10:51 INFO - mozversion platform_repository: https://hg.mozilla.org/projects/graphics 11:10:51 INFO - mozversion platform_version: 53.0a1 11:10:51 INFO - using testdate: 1483125051 11:10:51 INFO - actual date: 1483125051 11:10:51 INFO - starting webserver on 'localhost:53224' 11:10:51 INFO - SUITE-START | Running 2 tests 11:10:51 INFO - TEST-START | dromaeo_css 11:10:51 INFO - operating with platform_type : mac_ 11:10:51 INFO - Initialising browser for dromaeo_css test... 11:10:51 INFO - TEST-INFO | started process 1915 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/T/tmpvQ6Sd_/profile http://localhost:53224/getInfo.html) 11:10:51 INFO - PROCESS | 1915 | 1483125051675 addons.xpi WARN Exception running bootstrap method install on shield-recipe-client@mozilla.org: [Exception... "Component returned failure code: 0x8000ffff (NS_ERROR_UNEXPECTED) [nsIPrefBranch.getBoolPref]" nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js :: this.install :: line 38" data: no] Stack trace: this.install()@resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js:38 < callBootstrapMethod()@resource://gre/modules/addons/XPIProvider.jsm:4986 < processFileChanges()@resource://gre/modules/addons/XPIProvider.jsm -> resource://gre/modules/addons/XPIProviderUtils.js:2097 < checkForChanges()@resource://gre/modules/addons/XPIProvider.jsm:3827 < startup()@resource://gre/modules/addons/XPIProvider.jsm:2831 < callProvider()@resource://gre/modules/AddonManager.jsm:264 < _startProvider()@resource://gre/modules/AddonManager.jsm:771 < startup()@resource://gre/modules/AddonManager.jsm:957 < startup()@resource://gre/modules/AddonManager.jsm:2923 < observe()@resource://gre/components/addonManager.js:65 11:10:53 INFO - PROCESS | 1915 | __metrics Screen width/height:1600/1200 11:10:53 INFO - PROCESS | 1915 | colorDepth:24 11:10:53 INFO - PROCESS | 1915 | Browser inner width/height: 1024/689 11:10:53 INFO - PROCESS | 1915 | __metrics 11:10:53 INFO - PROCESS | 1915 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 11:10:53 INFO - PROCESS | 1915 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 11:10:54 INFO - TEST-INFO | 1915: exit 0 11:10:54 INFO - Browser initialized. 11:10:54 INFO - Running cycle 1/1 for dromaeo_css test... 11:10:54 INFO - TEST-INFO | started process 1917 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/T/tmpvQ6Sd_/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 11:11:06 INFO - PROCESS | 1917 | RSS: Main: 233795584 11:11:06 INFO - PROCESS | 1917 | 11:14:10 INFO - PROCESS | 1917 | Cycle 1(1): loaded http://localhost:53224/tests/dromaeo/cssquery-dojo.html (next: http://localhost:53224/tests/dromaeo/cssquery-ext.html) 11:14:12 INFO - PROCESS | 1917 | RSS: Main: 403984384 11:14:12 INFO - PROCESS | 1917 | 11:15:55 INFO - PROCESS | 1917 | ************************* 11:15:55 INFO - PROCESS | 1917 | A coding exception was thrown and uncaught in a Task. 11:15:55 INFO - PROCESS | 1917 | 11:15:55 INFO - PROCESS | 1917 | Full message: ReferenceError: fetch is not defined 11:15:55 INFO - PROCESS | 1917 | Full stack: apiCall@resource://shield-recipe-client/lib/NormandyApi.jsm:37:5 11:15:55 INFO - PROCESS | 1917 | get@resource://shield-recipe-client/lib/NormandyApi.jsm:44:12 11:15:55 INFO - PROCESS | 1917 | this.NormandyApi.fetchRecipes<@resource://shield-recipe-client/lib/NormandyApi.jsm:52:34 11:15:55 INFO - PROCESS | 1917 | TaskImpl_run@resource://gre/modules/Task.jsm:319:42 11:15:55 INFO - PROCESS | 1917 | TaskImpl@resource://gre/modules/Task.jsm:277:3 11:15:55 INFO - PROCESS | 1917 | asyncFunction@resource://gre/modules/Task.jsm:252:14 11:15:55 INFO - PROCESS | 1917 | this.RecipeRunner.start<@resource://shield-recipe-client/lib/RecipeRunner.jsm:64:23 11:15:55 INFO - PROCESS | 1917 | TaskImpl_run@resource://gre/modules/Task.jsm:319:42 11:15:55 INFO - PROCESS | 1917 | TaskImpl@resource://gre/modules/Task.jsm:277:3 11:15:55 INFO - PROCESS | 1917 | asyncFunction@resource://gre/modules/Task.jsm:252:14 11:15:55 INFO - PROCESS | 1917 | setTimeout_timer@resource://gre/modules/Timer.jsm:30:5 11:15:55 INFO - PROCESS | 1917 | 11:15:55 INFO - PROCESS | 1917 | ************************* 11:17:15 INFO - PROCESS | 1917 | Cycle 1(1): loaded http://localhost:53224/tests/dromaeo/cssquery-ext.html (next: http://localhost:53224/tests/dromaeo/cssquery-jquery.html) 11:17:15 INFO - PROCESS | 1917 | RSS: Main: 367996928 11:17:15 INFO - PROCESS | 1917 | 11:20:18 INFO - PROCESS | 1917 | Cycle 1(1): loaded http://localhost:53224/tests/dromaeo/cssquery-jquery.html (next: http://localhost:53224/tests/dromaeo/cssquery-mootools.html) 11:20:19 INFO - PROCESS | 1917 | RSS: Main: 335347712 11:20:19 INFO - PROCESS | 1917 | 11:23:21 INFO - PROCESS | 1917 | Cycle 1(1): loaded http://localhost:53224/tests/dromaeo/cssquery-mootools.html (next: http://localhost:53224/tests/dromaeo/cssquery-prototype.html) 11:23:22 INFO - PROCESS | 1917 | RSS: Main: 329900032 11:23:22 INFO - PROCESS | 1917 | 11:26:26 INFO - PROCESS | 1917 | Cycle 1(1): loaded http://localhost:53224/tests/dromaeo/cssquery-prototype.html (next: http://localhost:53224/tests/dromaeo/cssquery-yui.html) 11:26:26 INFO - PROCESS | 1917 | RSS: Main: 326090752 11:26:26 INFO - PROCESS | 1917 | 11:29:29 INFO - PROCESS | 1917 | Cycle 1(1): loaded http://localhost:53224/tests/dromaeo/cssquery-yui.html (next: http://localhost:53224/tests/dromaeo/cssquery-dojo.html) 11:29:30 INFO - PROCESS | 1917 | RSS: Main: 461631488 11:29:30 INFO - PROCESS | 1917 | 11:29:30 INFO - PROCESS | 1917 | __start_tp_report 11:29:30 INFO - PROCESS | 1917 | _x_x_mozilla_page_load 11:29:30 INFO - PROCESS | 1917 | _x_x_mozilla_page_load_details 11:29:30 INFO - PROCESS | 1917 | |i|pagename|runs| 11:29:30 INFO - PROCESS | 1917 | |0;dojo.html;3284.2774589590294;3294.407006738787;3312.4037673218827;3326.168457885529;3369.81466019369;4662.5803677669;5507.862303442416;5593.440655934404;5637.548996080319;5656.264685590872;5608.327000759904;5632.098864181732;5665.320161580606;5685.317761868581;5691.08942569189;4674.831292176956;5101.260317253996;5164.999999999991;5210.010098081371;5242.187460943541;5228.764705588239;5278.287431196778;5281.524662780349;5308.256844041837;5332.253484512172;5127.410347809993;5237.502437268454;5265.8946821063555;5323.467653234684;5328.413874473805;5193.350831146106;5241.711705856176;5246.816361427332;5249.107651699221;5310.150375939869;5073.949260507385;5074.416442109168;5132.230165475171;5158.587313014949;5182.663126896778;5586.748596313176;5641.746121424505;5661.547076233891;5671.2060311556415;5740.167675687044;5110.770015349318;6282.465990390822;6305.558610897239;6310.116583678288;6321.1150438938585;6128.819915423286;6265.59273647216;6336;6360;6379.489640828722;4770.427548694179;5723.998300297431;5758.971205144031;5776.768929242868;5784.768609255667;6221.47117495018;6254.74981000764;6283.59156654816;6319.905201421982;6343.27052388972;6100.557439056541;6150.876982460415;6153.753849846045;6165.475934545524;6199.659018753921;6310.7791227306825;6317.936820631735;6321.557490975588;6322.9051564226575;6327.651979141191;6073.544484163705;6133;6230.6573138476915;6234.937650623527;6249.937500624936;6142.266691322318;6182.28903676083;6257.186565746515;6268.592541484789;6319.431251187415;4921.926171107436;4940.505949405103;4941.752912354368;4942.8764280893265;4969.751512424365;4424.446944131983;4431.534688857688;4435.6229720473475;4447.288433850568;4465.955340446619;5572.60991730575;5616.1014237721765;5621.409751976065;5718.056520674042;5745.1669507921115;6277.780277690259;6321.589096708699;6328.620282782956;6332.398422149889;6359.077933699587;5682.261306030272;5694.60137790359;5805.796797112081;5807.303123625192;5828.300603927556;5274.4198138204765;5288.497592728685;5334.333208348957;5422.91865622016;5424.403315635277;6309.526785491198;6315.652639104802;6340.080688300079;6346.3019067903415;6353.841153971003;6302.747890084529;6304.810855674337;6316.747330106744;6322.051692246016;6325.177726895474;6282.277538083179;6300.21247344082;6356.904646430215;6366.140571023038;6372.968135159294;6119.624418755179;6121.387861213843;6127.953177891068;6167.5682702210415;6247.875042499216;6084.087386892002;6326.082718005955;6326.398992095744;6328.810135695937;6337.746490140527;4727.361806156263;5799.9130013049;5991.101334799815;6017.488513476404;6018.849528761816;5870.882582348244;6095.573309868267;6113.786017489367;6118.816435506942;6121.785737499166;5798.420157984167;5824.5631577631;5832.94167058324;5837.445442682938;5838.532917366516;5479.753411096431;5991.760329586942;6032.849178770566;6051.636901785907;6117.0824376342125;5901.970490147522;5918.911216331845;5920.8815823684145;5932.228810254639;5958.731857066529 11:29:30 INFO - PROCESS | 1917 | |1;ext.html;491.1846335083762;494.3696786597088;496.92297693857455;502.3068165931015;504.56355252706396;29578.408431831376;39062.60937390625;40178.7991060045;40240;42165.57834421655;40974.59025409745;41341.79329103358;41360;41424.79287603558;41572.34842425212;9181.311401644887;9370.390924589914;9373.906260937405;9374.85937710935;9391.859122113174;8430.452020618672;8686.826263474726;11099.944500277486;11357.943210283978;11378.544858205703;11323.263987840764;11366.829497557543;11378.260413073165;11386.316820990767;11407;8503.489790612583;11139.610113646024;11226.438678066064;11251.774964500664;11303.717407064849;8462.66149354025;8597.785055373603;8621.439606425562;8621.439606425562;10968.780624387548;37901.052473688105;39699.40450893239;39743.60256397457;39751.80744577639;39923.40114898279;3981.163955569334;3998.540167880702;4016.598340165989;4054.8580799671868;4126.236646220459;23740;29371.70628293711;29911;30529.08412747621;30531.69468305311;3115.704008119225;3263.673632636741;3263.7552183586326;3264.1513206566465;3308.123347312964;418661.9066904677;419159;419398;420555;420818.9999999969;58094.7095264521;58537.4146258532;58539.70730146322;58752.11871821926;58800.82398352009;60033;60074.79850402968;60101.69949150314;60194;60296.69851650714;15120.924395378173;15318.310676019606;15799.842001579837;15815.762763558556;15852.92073539648;10970.561177552969;11281;12332.013438924865;12431.689207769878;12471.376431178405;14612.634684132981;16039.43861964849;16326.265318060716;16464.341426342813;16766.74849877253;5936.643801371932;6348.888944434705;6562.114114594468;6700.731970721116;6756.628385438749;830.6078493937542;850.6639877248452;855.6320782063648;863.922266947991;865.2040123086775;28772.136835894955;28802.567961480596;29266;29731.810727571086;29801;1032.9380237185792;1038.5586125896464;1057.460695045517;1084.1922767538235;1126.0822429719751;14992.775108373165;15007.474738384104;15407.537773866801;15417.922910385376;15446.304916278794;587169;591403;593280;595011;597766;57758.8448231033;57816.71091644515;57898.42101579015;57998.42001580015;58029.709851450476;1335.8730920562532;1376.1742954227384;1397.5388121919946;1400.4188261871209;1401.7266633006466;27904;28709;28916.27709307284;29406.852965735034;29579.964701236215;48429.273560897316;48887.755561221966;48986.265206022654;49066.26400604065;49161;504946;507926;508376;509138;509622;37322.06694832651;38444.42333365058;38792;38800.41799372955;38819.6118038816;44003.559964399945;44656.77671611621;44810.55189448194;44956.550434496545;45242.54757452384;29921;36852;37152.62847371492;37641;37744.056398590255;36780;37107.6289237115;37686.434703480016;38229.85310440691;38345;35804.10489737673;35890.4616430759;36699.44950825686;36821.26357472889;36826.26347473089;32234.83882580666;32928.506072408454;33598;33650;33792.66207337895;23951.28146155618;23966.88016559906;24207.273781786575;24485.877570612032;24859.875700621382 11:29:30 INFO - PROCESS | 1917 | |2;jquery.html;350.37283262959306;370.6330732574751;372.20534159569326;379.23962455277166;399.88803135122174;14662.63343416415;14963.326650300736;15441.613959651;16566.585835354123;20177.697334539993;19424.125914333854;19832.70250946237;20396.8980155099;20637.864917429535;20751;5617.859553511164;5852.619579727304;5860.062390017577;5882.941170588282;5895;4395.549593309415;5759.1937128802;5769.509591684712;5792.855178620526;5796;5722.914156287659;5723.341815691187;5726.828195154131;5728.341240757304;5737.913931291034;7105.218425973138;7449.180590135081;7506.662200201004;7519.774406767806;7614.2766437188375;6963.7562685305775;7185.281471852825;7228.855422891513;7261.3101755333155;7333.339999400079;28114.297142571395;28218.435631287466;28227.294317642223;28344.724487398118;28386.574201386997;2390.964135537968;2978.8212707237417;2990.5833532838465;2993.5659329397117;3016.457037733207;16144.434944776876;16187.514374568782;16293.429729959395;16827.91586042062;17118;1456.9198694071822;1974.6840505519049;1982.9405117846488;1994.411648563677;1998.9300374486822;132363.33818330846;134344;135903;138242.61757382398;143636;16805.83194168067;17693.469195924143;18680.719789203173;20697.379078627666;21164.682529762067;33377.165570860925;34834;34834.30331393358;34927.82536087354;34981.65018349835;8024.398170137263;10319.277650564389;10527.420991845369;10611.31026483276;10738.677839664822;7500.774976750707;8132.6746930122135;8564.229219370287;8607.48355098696;8749.25631321332;9437.90562094384;9684.258611015988;9790.363626364264;9796.95101524502;9807.166390856857;3665.890023299305;3756.9812150939615;3768.246350729865;3777.206786574795;3789.6589306962505;933.5472295936465;968.7626531499686;981.65642025292;992.3053862296422;1010.4291075542295;20572.897135514526;20810.47973800667;20812.687809682866;20889.79110208909;20894.268700595407;999.9450030248261;1014.974625634365;1025.5436330832856;1032.5353590884133;1035.4822588705647;10184.338018028804;10881.401522916158;10885.510152043176;10904;10944.288621239593;455440;461615;524604;526258;530323;133859;136166;136249.3187534056;138232.30883845515;139385.30307348398;962.6101428921298;1004.2719028704131;1036.409246729357;1037.3412882819464;1039.8336266197373;17377.13114344303;17519;18285.725714114033;18993.905030474758;19237.519062023563;43805.34291985686;45357.54642453533;51416.74291628518;51623.741881291855;53581.73209133929;74888;75147.62426187834;75645.62177189298;76130.23869761454;76940;8095.352371810359;8129.715459958873;8215.178482151738;8297.460665056751;8313.625886835229;11197.38414387184;12873.678158046123;12907.870921290667;13067.477300907858;13137.211767293993;9096.363254572381;10794.622188223375;10949.178811589321;11116.055135313589;11161.274517156684;10929.016388525071;11112.277701949348;11169.832452513056;11252.66242012741;11400.657980260605;9005;11378.715532111764;11485.770284594428;11510.712232194262;11570;6984.196817365864;7359;7924.20757924203;7945.523268603903;8273.089960104264;10050.497475126069;10651.840222396826;10702.571897124339;11248.94375528117;11311.717207069889 11:29:30 INFO - PROCESS | 1917 | |3;mootools.html;1381.689119948012;1386.8335799704034;1401.0472878442654;1403.2492616450202;1406.9859301406987;7943.880841787371;8344.958275208615;8375.953005874273;8417.873731894026;8422.241998220159;7377.446691498132;7656.3109320161175;7670.348020418257;8019.077806052306;8172.346212303017;2230.2863083813145;2259.7401298850677;2266.8073213776765;2270.375646697155;2273.0453209651987;2227.9554408911813;2275.2036787124457;2276.499170182557;2310.1914329984456;2362.444825466023;1884.9151788169497;1950.4831219726784;2213;2232.9888350558226;2285.3486756274465;1888.6600411925847;2251.7410497792716;2265.410993141795;2274.920377786786;2275.8634481931053;1913.8373238274764;2271.681964524968;2295.8278129140213;2301.735300440454;2301.8043466305385;8258;8312.085670576293;8316;8346.791330216733;8365.749027529184;5065.341505604248;5237.47625237477;5238.68567885928;5240.34495688039;5247.658902171384;2766.845439314773;3456.740744444177;3474.1488335357744;3497.527833742463;3508.9298214035575;1159.8144296912537;1212.8483939507562;1246.1401632873287;1249.8312727781724;1255.121415009497;10446.216533759998;10724.946375268151;10737.83893241602;10759.977802108788;10874.510647020903;4251.681123915657;4265.91468170641;4387.210302145644;4390.495093064338;4450.265706158512;8516.829663406697;8588.269997050196;8677.956610216908;8728.607212675444;8757.12428757132;5115.2071428928575;5116.692998420107;5118.718470484159;5131.461196574381;5136.178211486144;4277.550857159953;4439.267520859086;4636.374089497942;4776.498467660846;4812.5668689818085;3612.5303710517464;3670.210904655512;3729.272791805572;3758.210775737126;3779.055236190952;988.5255077562811;1136.7612801311652;1144.5593446523148;1145.2212495503138;1164.0978241862456;687.4191308344441;759.5480688990042;882.298572634757;898.8786513820681;923.9076092390707;5332.520073193354;5405.945940540544;5621.0444224481935;5708.514776244066;5732.3407808101765;746.488655271141;838.8028813228887;884.8407286688458;886.7738726624798;893.5934149961752;3146.606674165729;3392.6768560261517;3643.6720695137565;3766.8304926278383;3794.3739283018544;9468.337216394924;9527.571259293349;9929.404235745878;10055.39667619945;10116.898831011596;9791.804163916682;10021.699349019542;10067.69796906094;10078.798424031478;10080.495975201211;7171.713131474787;7425.331720145213;7456.067991501062;7599.620018999028;7857.2928436441;5346.786128554814;5603.523700485504;5658.575606829422;5668.149777533403;5915.58590898633;8485;8676.826463470821;9001.729948101567;9309.813803723751;9426.575804088696;8950.657401389844;9434.29242806806;9443.244540436886;9506.524673766422;9577.760555986157;6566.409023187936;6609.900851487136;6793.796186114425;6799.184097908283;7269.927300726925;7429.405647548075;7523.88714169277;7557.319841214097;7832.138464768985;7891.763247102596;7036.331548503089;7161.4986950912935;7162.641867906688;7229.204787473479;7294.635268236673;6962.1993470749485;6991.755288564876;7040.225575186623;7062.576245425291;7115.679794409368;6227.688615569294;6350.301466838551;6370.968145159244;6384.329645387354;6437.291897891322;4744.359511465908;4780.235162374003;4787.664863459524;4811.08589368019;4896.338994235733;4054.8175332109536;4711.31685905535;4741.431028276629;4778.307145463958;4969.602431805519 11:29:30 INFO - PROCESS | 1917 | |4;prototype.html;347.0836990345488;353.36394489918155;357.11969993964874;368.5724559510968;370.65343903450275;4585.541445855417;5020.221865610832;5025.346704928364;5174.534291913727;5208.192730126827;4808.893954390493;5017.698938063719;5146.897062058757;5151.407588127367;5187.221916712495;3145.5124455709342;3302.9669703302898;4063.5326937402283;4088.7751173685583;4132.462779838632;3246.8051916884942;3667.5782285037294;4082.9591704082877;4128.50457945047;4132.793360331986;3286.901392958215;3925.60743925608;3939.8621048263317;3947.7631342119566;3953.446517487554;3592.46113083037;3758.3610786166146;3900.609939006105;3923.8822835314986;3926.587708290617;2843.5876797864403;3393.7794043387103;3418.504316874064;3423.0928803866996;3429.2969941162;4591;4696.342512048316;4848.878778030542;5037.748112594392;5059.974700126476;3674.8713795017047;3752.9061773455883;3993.3211354069877;4319.034839148211;4506.571875671806;4595.2188128018315;4777.474477807473;4930.630202734774;5159.535641792257;5429.239906413105;3264.298175892171;3380.9857042887033;3617.981910090459;3694.063219740313;3743.8128093595483;4501.7974191161475;4692.788824502905;4759.904801903977;5508.862278443031;5657.405972372924;3172.920676983094;3755.3615885299564;3757.755745876564;3769.7738135711943;3772.5472943246436;3577.928441431157;3628.854845806138;3802.8098595070687;3822.808859557011;3867.9363175127064;4192.622663960258;4407.449068866392;4412.669049821212;4459.636392711868;4468.9106217876115;5282.573705099679;5426.75579598919;5452.6455780374945;5470.753816078286;5481.342238931274;1179.475133565555;1205.7467931734259;1214.518287689018;1214.5809695654984;1229.8585662648732;772.2393442459173;804.0381469215097;834.4242472693821;845.2266176448603;852.3224036890687;4217.536071032184;4589.613401031445;4886.731229782325;4898.779554920037;4907.263910413467;4926.729029903318;4935.407751069823;4958.42978057528;5206.609504287194;5299.920501192485;4491.1915855145735;4561.738357074708;4789.928151077736;4805.951940480551;4816.638752093607;2758.1449750577385;2766.7371599697994;2773.55623100303;2776.7088303938363;2782.8729364607366;4942.418426103613;5123.513266239701;5167;5177.275181474596;5408.269883565747;4818.542238487338;5367.892642147135;5381.228667236376;5391.622586418913;5405.243265942771;4374.934375984363;4941.703497790144;5094.5160209780015;5197.246399272084;5384.811531596375;4596.521828348678;4633.374494443207;4654.9301760472945;4896.996115796324;5142.100132476877;4713.410823647044;4807.538572227991;5095.0574143783515;5255.500727430888;5317.2555842181355;4900.407367605614;5287.391949925808;5429.918551221655;5439.403703870224;5489.451054894478;3189.553462515296;3545.751797374159;3556.7729133449952;3576.856925722942;3577.338192434408;5574.386817450159;5575.553955683617;5608.971955140199;5626.634268772516;5657.43425657431;5071.776070647239;5078.415982162099;5182.870428239174;5474.616776825584;5493.2034854946605;4586.72479651222;5185.144451165515;5437;5440.612643775812;5460.836174914759;4654.325122857234;4696.718196908197;4900.093482705568;5152.922706159336;5425.240466334637;5404.756785944721;5435.211894275387;5504.843982763665;5531.917021244765;5564.888702225851;5007.874803129951;5225.320708308048;5297.284866542967;5384.6769193848495;5448.940539298248 11:29:30 INFO - PROCESS | 1917 | |5;yui.html;698.5005720909548;705.8694141583808;744.8249661329587;781.1914668318291;824.5052968219068;14779.778303325458;15613.65795130731;15741.134237616927;15875;16196.838031619709;14074.29628518575;14252.643683907907;14373;14603.561893143196;14603.78094328586;853.0658928473327;873.6811063961671;878.0692465986043;878.165742544582;880.9251213646847;671.8085345676483;696.2863065357998;716.6667499612679;721.3363705391026;877.8112705768259;932.0958670090001;935.4995077633467;935.7613808478828;940.9576569054375;945.9527023648825;937.1518775508176;938.4697645830153;938.9061093890624;939.0421769794839;939.3283802081545;544.0669252232401;561.7331767410489;671.0203103468941;674.8549061951704;687.1719577908608;15012.47456339023;15072.020318679251;15088.321025553767;15105.244737763069;15107.697846043018;13255.204687718768;13278.535251266256;13387.665308367272;13402.731945361136;13403.664908377368;12185.268883866996;12312.13815032948;12405.255684658949;12548.874511254953;12869.613911582574;4277.337012763027;4278.272693642089;4368.956310436887;4369.518145623972;4468.843590474317;92001.53999229961;92655.53672231662;92873.53563232141;94167.52916235375;95662;10347.741306467398;10374.58501659925;10397.896021039844;10536.157107431387;10999.945000274947;16336.673266534603;18598.44204673862;20190.697139542917;20741.792582073987;21020.26429074975;7396.593187374639;7430.814229644194;7451.515651482637;7485.812854678677;7544.283293087148;4114.67919492389;4192.22443847883;4230.238557059759;4254.659627229814;4336.869893903188;3379.665032312272;3390.9491357629654;3447.3277710846146;3488.494168345576;3490.424080026767;636.1697984130661;636.9426751592379;637.996810015947;641.8202903187115;644.9935500645028;578.8986927287708;591.1398914579277;616.722474886303;623.7504998000836;625.3996163682824;8304.211099945496;8809.647614095364;8817.29461643067;8862.76192804988;8901.243394311556;610.5970059760537;628.1676778268812;629.9118123462719;639.3446717114995;644.8033349828254;4339.284018137035;4807.206810876236;4851.320815085891;4911.361523001987;4967.503249675004;84490;100796.49601752091;104223.47888260511;104641;108259;93303.53348233351;93759.53120234492;95710;96026.0397396017;97722;83348.91627709234;83438.58280708558;83601;83916.16083839205;84009;9674;9690.806183876424;9720.465374404475;9724.513774311397;9728.708138755848;48247.793805155154;50093.74953125211;50729;50736.97052117874;51471.74264128805;58779.118313226194;67017.66491167514;70506;70923;72942.63528682322;12566.937165314115;12871.871281287067;12988.545400910923;12990.285534295326;13410.664733381744;14563.912052327354;14742.557723268319;14781;14914.701705966037;15371.69340606061;13409.396577153857;13428.19430834153;13541.661458463617;13771.586852394445;14091.647708807363;13624.795628065785;13797.10318829273;13855.584332470042;13863.37614807316;13931.512397066053;13301.944076688049;13458.798118028042;13556.457741690221;13637.454501819817;14190.929045354707;11013.889861101286;11264.267822591506;11343.829842552534;11444.533041458424;11589.24669896454;10715.839262410915;10952.45237738094;10985.285956412808;11026;11039.613613523488 11:29:30 INFO - PROCESS | 1917 | __end_tp_report 11:29:30 INFO - PROCESS | 1917 | __start_cc_report 11:29:30 INFO - PROCESS | 1917 | _x_x_mozilla_cycle_collect,1743 11:29:30 INFO - PROCESS | 1917 | __end_cc_report 11:29:30 INFO - PROCESS | 1917 | __startTimestamp1483126170226__endTimestamp 11:29:30 INFO - PROCESS | 1917 | 11:29:30 INFO - PROCESS | 1917 | ------- Summary: start ------- 11:29:30 INFO - PROCESS | 1917 | Number of tests: 6 11:29:30 INFO - PROCESS | 1917 | 11:29:30 INFO - PROCESS | 1917 | [#0] dojo.html Cycles:165 Average:5712.07 Median:5837.45 stddev:667.42 (11.4%) stddev-sans-first:641.72 11:29:30 INFO - PROCESS | 1917 | Values: 3284.3 3294.4 3312.4 3326.2 3369.8 4662.6 5507.9 5593.4 5637.5 5656.3 5608.3 5632.1 5665.3 5685.3 5691.1 4674.8 5101.3 5165.0 5210.0 5242.2 5228.8 5278.3 5281.5 5308.3 5332.3 5127.4 5237.5 5265.9 5323.5 5328.4 5193.4 5241.7 5246.8 5249.1 5310.2 5073.9 5074.4 5132.2 5158.6 5182.7 5586.7 5641.7 5661.5 5671.2 5740.2 5110.8 6282.5 6305.6 6310.1 6321.1 6128.8 6265.6 6336.0 6360.0 6379.5 4770.4 5724.0 5759.0 5776.8 5784.8 6221.5 6254.7 6283.6 6319.9 6343.3 6100.6 6150.9 6153.8 6165.5 6199.7 6310.8 6317.9 6321.6 6322.9 6327.7 6073.5 6133.0 6230.7 6234.9 6249.9 6142.3 6182.3 6257.2 6268.6 6319.4 4921.9 4940.5 4941.8 4942.9 4969.8 4424.4 4431.5 4435.6 4447.3 4466.0 5572.6 5616.1 5621.4 5718.1 5745.2 6277.8 6321.6 6328.6 6332.4 6359.1 5682.3 5694.6 5805.8 5807.3 5828.3 5274.4 5288.5 5334.3 5422.9 5424.4 6309.5 6315.7 6340.1 6346.3 6353.8 6302.7 6304.8 6316.7 6322.1 6325.2 6282.3 6300.2 6356.9 6366.1 6373.0 6119.6 6121.4 6128.0 6167.6 6247.9 6084.1 6326.1 6326.4 6328.8 6337.7 4727.4 5799.9 5991.1 6017.5 6018.8 5870.9 6095.6 6113.8 6118.8 6121.8 5798.4 5824.6 5832.9 5837.4 5838.5 5479.8 5991.8 6032.8 6051.6 6117.1 5902.0 5918.9 5920.9 5932.2 5958.7 11:29:30 INFO - PROCESS | 1917 | 11:29:30 INFO - PROCESS | 1917 | [#1] ext.html Cycles:180 Average:64713.27 Median:38394.71 stddev:136372.46 (355.2%) stddev-sans-first:136669.78 11:29:30 INFO - PROCESS | 1917 | Values: 491.2 494.4 496.9 502.3 504.6 29578.4 39062.6 40178.8 40240.0 42165.6 40974.6 41341.8 41360.0 41424.8 41572.3 9181.3 9370.4 9373.9 9374.9 9391.9 8430.5 8686.8 11099.9 11357.9 11378.5 11323.3 11366.8 11378.3 11386.3 11407.0 8503.5 11139.6 11226.4 11251.8 11303.7 8462.7 8597.8 8621.4 8621.4 10968.8 37901.1 39699.4 39743.6 39751.8 39923.4 3981.2 3998.5 4016.6 4054.9 4126.2 23740.0 29371.7 29911.0 30529.1 30531.7 3115.7 3263.7 3263.8 3264.2 3308.1 418661.9 419159.0 419398.0 420555.0 420819.0 58094.7 58537.4 58539.7 58752.1 58800.8 60033.0 60074.8 60101.7 60194.0 60296.7 15120.9 15318.3 15799.8 15815.8 15852.9 10970.6 11281.0 12332.0 12431.7 12471.4 14612.6 16039.4 16326.3 16464.3 16766.7 5936.6 6348.9 6562.1 6700.7 6756.6 830.6 850.7 855.6 863.9 865.2 28772.1 28802.6 29266.0 29731.8 29801.0 1032.9 1038.6 1057.5 1084.2 1126.1 14992.8 15007.5 15407.5 15417.9 15446.3 587169.0 591403.0 593280.0 595011.0 597766.0 57758.8 57816.7 57898.4 57998.4 58029.7 1335.9 1376.2 1397.5 1400.4 1401.7 27904.0 28709.0 28916.3 29406.9 29580.0 48429.3 48887.8 48986.3 49066.3 49161.0 504946.0 507926.0 508376.0 509138.0 509622.0 37322.1 38444.4 38792.0 38800.4 38819.6 44003.6 44656.8 44810.6 44956.6 45242.5 29921.0 36852.0 37152.6 37641.0 37744.1 36780.0 37107.6 37686.4 38229.9 38345.0 35804.1 35890.5 36699.4 36821.3 36826.3 32234.8 32928.5 33598.0 33650.0 33792.7 23951.3 23966.9 24207.3 24485.9 24859.9 11:29:30 INFO - PROCESS | 1917 | 11:29:30 INFO - PROCESS | 1917 | [#2] jquery.html Cycles:180 Average:34077.16 Median:28365.65 stddev:85232.89 (300.5%) stddev-sans-first:85434.37 11:29:30 INFO - PROCESS | 1917 | Values: 350.4 370.6 372.2 379.2 399.9 14662.6 14963.3 15441.6 16566.6 20177.7 19424.1 19832.7 20396.9 20637.9 20751.0 5617.9 5852.6 5860.1 5882.9 5895.0 4395.5 5759.2 5769.5 5792.9 5796.0 5722.9 5723.3 5726.8 5728.3 5737.9 7105.2 7449.2 7506.7 7519.8 7614.3 6963.8 7185.3 7228.9 7261.3 7333.3 28114.3 28218.4 28227.3 28344.7 28386.6 2391.0 2978.8 2990.6 2993.6 3016.5 16144.4 16187.5 16293.4 16827.9 17118.0 1456.9 1974.7 1982.9 1994.4 1998.9 132363.3 134344.0 135903.0 138242.6 143636.0 16805.8 17693.5 18680.7 20697.4 21164.7 33377.2 34834.0 34834.3 34927.8 34981.7 8024.4 10319.3 10527.4 10611.3 10738.7 7500.8 8132.7 8564.2 8607.5 8749.3 9437.9 9684.3 9790.4 9797.0 9807.2 3665.9 3757.0 3768.2 3777.2 3789.7 933.5 968.8 981.7 992.3 1010.4 20572.9 20810.5 20812.7 20889.8 20894.3 999.9 1015.0 1025.5 1032.5 1035.5 10184.3 10881.4 10885.5 10904.0 10944.3 455440.0 461615.0 524604.0 526258.0 530323.0 133859.0 136166.0 136249.3 138232.3 139385.3 962.6 1004.3 1036.4 1037.3 1039.8 17377.1 17519.0 18285.7 18993.9 19237.5 43805.3 45357.5 51416.7 51623.7 53581.7 74888.0 75147.6 75645.6 76130.2 76940.0 8095.4 8129.7 8215.2 8297.5 8313.6 11197.4 12873.7 12907.9 13067.5 13137.2 9096.4 10794.6 10949.2 11116.1 11161.3 10929.0 11112.3 11169.8 11252.7 11400.7 9005.0 11378.7 11485.8 11510.7 11570.0 6984.2 7359.0 7924.2 7945.5 8273.1 10050.5 10651.8 10702.6 11248.9 11311.7 11:29:30 INFO - PROCESS | 1917 | 11:29:30 INFO - PROCESS | 1917 | [#3] mootools.html Cycles:180 Average:5319.00 Median:5186.83 stddev:2930.00 (56.5%) stddev-sans-first:2923.28 11:29:30 INFO - PROCESS | 1917 | Values: 1381.7 1386.8 1401.0 1403.2 1407.0 7943.9 8345.0 8376.0 8417.9 8422.2 7377.4 7656.3 7670.3 8019.1 8172.3 2230.3 2259.7 2266.8 2270.4 2273.0 2228.0 2275.2 2276.5 2310.2 2362.4 1884.9 1950.5 2213.0 2233.0 2285.3 1888.7 2251.7 2265.4 2274.9 2275.9 1913.8 2271.7 2295.8 2301.7 2301.8 8258.0 8312.1 8316.0 8346.8 8365.7 5065.3 5237.5 5238.7 5240.3 5247.7 2766.8 3456.7 3474.1 3497.5 3508.9 1159.8 1212.8 1246.1 1249.8 1255.1 10446.2 10724.9 10737.8 10760.0 10874.5 4251.7 4265.9 4387.2 4390.5 4450.3 8516.8 8588.3 8678.0 8728.6 8757.1 5115.2 5116.7 5118.7 5131.5 5136.2 4277.6 4439.3 4636.4 4776.5 4812.6 3612.5 3670.2 3729.3 3758.2 3779.1 988.5 1136.8 1144.6 1145.2 1164.1 687.4 759.5 882.3 898.9 923.9 5332.5 5405.9 5621.0 5708.5 5732.3 746.5 838.8 884.8 886.8 893.6 3146.6 3392.7 3643.7 3766.8 3794.4 9468.3 9527.6 9929.4 10055.4 10116.9 9791.8 10021.7 10067.7 10078.8 10080.5 7171.7 7425.3 7456.1 7599.6 7857.3 5346.8 5603.5 5658.6 5668.1 5915.6 8485.0 8676.8 9001.7 9309.8 9426.6 8950.7 9434.3 9443.2 9506.5 9577.8 6566.4 6609.9 6793.8 6799.2 7269.9 7429.4 7523.9 7557.3 7832.1 7891.8 7036.3 7161.5 7162.6 7229.2 7294.6 6962.2 6991.8 7040.2 7062.6 7115.7 6227.7 6350.3 6371.0 6384.3 6437.3 4744.4 4780.2 4787.7 4811.1 4896.3 4054.8 4711.3 4741.4 4778.3 4969.6 11:29:30 INFO - PROCESS | 1917 | 11:29:30 INFO - PROCESS | 1917 | [#4] prototype.html Cycles:180 Average:4270.17 Median:4797.94 stddev:1295.68 (27.0%) stddev-sans-first:1265.41 11:29:30 INFO - PROCESS | 1917 | Values: 347.1 353.4 357.1 368.6 370.7 4585.5 5020.2 5025.3 5174.5 5208.2 4808.9 5017.7 5146.9 5151.4 5187.2 3145.5 3303.0 4063.5 4088.8 4132.5 3246.8 3667.6 4083.0 4128.5 4132.8 3286.9 3925.6 3939.9 3947.8 3953.4 3592.5 3758.4 3900.6 3923.9 3926.6 2843.6 3393.8 3418.5 3423.1 3429.3 4591.0 4696.3 4848.9 5037.7 5060.0 3674.9 3752.9 3993.3 4319.0 4506.6 4595.2 4777.5 4930.6 5159.5 5429.2 3264.3 3381.0 3618.0 3694.1 3743.8 4501.8 4692.8 4759.9 5508.9 5657.4 3172.9 3755.4 3757.8 3769.8 3772.5 3577.9 3628.9 3802.8 3822.8 3867.9 4192.6 4407.4 4412.7 4459.6 4468.9 5282.6 5426.8 5452.6 5470.8 5481.3 1179.5 1205.7 1214.5 1214.6 1229.9 772.2 804.0 834.4 845.2 852.3 4217.5 4589.6 4886.7 4898.8 4907.3 4926.7 4935.4 4958.4 5206.6 5299.9 4491.2 4561.7 4789.9 4806.0 4816.6 2758.1 2766.7 2773.6 2776.7 2782.9 4942.4 5123.5 5167.0 5177.3 5408.3 4818.5 5367.9 5381.2 5391.6 5405.2 4374.9 4941.7 5094.5 5197.2 5384.8 4596.5 4633.4 4654.9 4897.0 5142.1 4713.4 4807.5 5095.1 5255.5 5317.3 4900.4 5287.4 5429.9 5439.4 5489.5 3189.6 3545.8 3556.8 3576.9 3577.3 5574.4 5575.6 5609.0 5626.6 5657.4 5071.8 5078.4 5182.9 5474.6 5493.2 4586.7 5185.1 5437.0 5440.6 5460.8 4654.3 4696.7 4900.1 5152.9 5425.2 5404.8 5435.2 5504.8 5531.9 5564.9 5007.9 5225.3 5297.3 5384.7 5448.9 11:29:30 INFO - PROCESS | 1917 | 11:29:30 INFO - PROCESS | 1917 | [#5] yui.html Cycles:180 Average:20352.23 Median:26527.50 stddev:29075.39 (109.6%) stddev-sans-first:29119.50 11:29:30 INFO - PROCESS | 1917 | Values: 698.5 705.9 744.8 781.2 824.5 14779.8 15613.7 15741.1 15875.0 16196.8 14074.3 14252.6 14373.0 14603.6 14603.8 853.1 873.7 878.1 878.2 880.9 671.8 696.3 716.7 721.3 877.8 932.1 935.5 935.8 941.0 946.0 937.2 938.5 938.9 939.0 939.3 544.1 561.7 671.0 674.9 687.2 15012.5 15072.0 15088.3 15105.2 15107.7 13255.2 13278.5 13387.7 13402.7 13403.7 12185.3 12312.1 12405.3 12548.9 12869.6 4277.3 4278.3 4369.0 4369.5 4468.8 92001.5 92655.5 92873.5 94167.5 95662.0 10347.7 10374.6 10397.9 10536.2 10999.9 16336.7 18598.4 20190.7 20741.8 21020.3 7396.6 7430.8 7451.5 7485.8 7544.3 4114.7 4192.2 4230.2 4254.7 4336.9 3379.7 3390.9 3447.3 3488.5 3490.4 636.2 636.9 638.0 641.8 645.0 578.9 591.1 616.7 623.8 625.4 8304.2 8809.6 8817.3 8862.8 8901.2 610.6 628.2 629.9 639.3 644.8 4339.3 4807.2 4851.3 4911.4 4967.5 84490.0 100796.5 104223.5 104641.0 108259.0 93303.5 93759.5 95710.0 96026.0 97722.0 83348.9 83438.6 83601.0 83916.2 84009.0 9674.0 9690.8 9720.5 9724.5 9728.7 48247.8 50093.7 50729.0 50737.0 51471.7 58779.1 67017.7 70506.0 70923.0 72942.6 12566.9 12871.9 12988.5 12990.3 13410.7 14563.9 14742.6 14781.0 14914.7 15371.7 13409.4 13428.2 13541.7 13771.6 14091.6 13624.8 13797.1 13855.6 13863.4 13931.5 13301.9 13458.8 13556.5 13637.5 14190.9 11013.9 11264.3 11343.8 11444.5 11589.2 10715.8 10952.5 10985.3 11026.0 11039.6 11:29:30 INFO - PROCESS | 1917 | -------- Summary: end -------- 11:29:30 INFO - PROCESS | 1917 | 11:29:30 INFO - TEST-INFO | 1917: exit 0 11:29:30 INFO - TEST-OK | dromaeo_css | took 1119696ms 11:29:30 INFO - TEST-START | kraken 11:29:30 INFO - operating with platform_type : mac_ 11:29:30 INFO - Initialising browser for kraken test... 11:29:30 INFO - TEST-INFO | started process 1920 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/T/tmpVpw6zZ/profile http://localhost:53224/getInfo.html) 11:29:31 INFO - PROCESS | 1920 | 1483126171362 addons.xpi WARN Exception running bootstrap method install on shield-recipe-client@mozilla.org: [Exception... "Component returned failure code: 0x8000ffff (NS_ERROR_UNEXPECTED) [nsIPrefBranch.getBoolPref]" nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js :: this.install :: line 38" data: no] Stack trace: this.install()@resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js:38 < callBootstrapMethod()@resource://gre/modules/addons/XPIProvider.jsm:4986 < processFileChanges()@resource://gre/modules/addons/XPIProvider.jsm -> resource://gre/modules/addons/XPIProviderUtils.js:2097 < checkForChanges()@resource://gre/modules/addons/XPIProvider.jsm:3827 < startup()@resource://gre/modules/addons/XPIProvider.jsm:2831 < callProvider()@resource://gre/modules/AddonManager.jsm:264 < _startProvider()@resource://gre/modules/AddonManager.jsm:771 < startup()@resource://gre/modules/AddonManager.jsm:957 < startup()@resource://gre/modules/AddonManager.jsm:2923 < observe()@resource://gre/components/addonManager.js:65 11:29:33 INFO - PROCESS | 1920 | __metrics Screen width/height:1600/1200 11:29:33 INFO - PROCESS | 1920 | colorDepth:24 11:29:33 INFO - PROCESS | 1920 | Browser inner width/height: 1024/689 11:29:33 INFO - PROCESS | 1920 | __metrics 11:29:33 INFO - PROCESS | 1920 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 11:29:33 INFO - PROCESS | 1920 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 11:29:34 INFO - TEST-INFO | 1920: exit 0 11:29:34 INFO - Browser initialized. 11:29:34 INFO - Running cycle 1/1 for kraken test... 11:29:34 INFO - TEST-INFO | started process 1921 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/T/tmpVpw6zZ/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 11:29:46 INFO - PROCESS | 1921 | RSS: Main: 225837056 11:29:46 INFO - PROCESS | 1921 | 11:29:48 INFO - PROCESS | 1921 | Cycle 1(1): loaded http://localhost:53224/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:53224/tests/kraken/driver.html?testName=audio-beat-detection) 11:29:49 INFO - PROCESS | 1921 | RSS: Main: 285593600 11:29:49 INFO - PROCESS | 1921 | 11:29:52 INFO - PROCESS | 1921 | Cycle 1(1): loaded http://localhost:53224/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:53224/tests/kraken/driver.html?testName=audio-dft) 11:29:52 INFO - PROCESS | 1921 | RSS: Main: 287473664 11:29:52 INFO - PROCESS | 1921 | 11:29:56 INFO - PROCESS | 1921 | Cycle 1(1): loaded http://localhost:53224/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:53224/tests/kraken/driver.html?testName=audio-fft) 11:29:56 INFO - PROCESS | 1921 | RSS: Main: 379154432 11:29:56 INFO - PROCESS | 1921 | 11:29:58 INFO - PROCESS | 1921 | Cycle 1(1): loaded http://localhost:53224/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:53224/tests/kraken/driver.html?testName=audio-oscillator) 11:29:59 INFO - PROCESS | 1921 | RSS: Main: 282726400 11:29:59 INFO - PROCESS | 1921 | 11:30:01 INFO - PROCESS | 1921 | Cycle 1(1): loaded http://localhost:53224/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:53224/tests/kraken/driver.html?testName=imaging-gaussian-blur) 11:30:02 INFO - PROCESS | 1921 | RSS: Main: 286474240 11:30:02 INFO - PROCESS | 1921 | 11:30:04 INFO - PROCESS | 1921 | Cycle 1(1): loaded http://localhost:53224/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:53224/tests/kraken/driver.html?testName=imaging-darkroom) 11:30:05 INFO - PROCESS | 1921 | RSS: Main: 327970816 11:30:05 INFO - PROCESS | 1921 | 11:30:08 INFO - PROCESS | 1921 | Cycle 1(1): loaded http://localhost:53224/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:53224/tests/kraken/driver.html?testName=imaging-desaturate) 11:30:08 INFO - PROCESS | 1921 | RSS: Main: 331186176 11:30:08 INFO - PROCESS | 1921 | 11:30:11 INFO - PROCESS | 1921 | Cycle 1(1): loaded http://localhost:53224/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:53224/tests/kraken/driver.html?testName=json-parse-financial) 11:30:12 INFO - PROCESS | 1921 | RSS: Main: 329482240 11:30:12 INFO - PROCESS | 1921 | 11:30:14 INFO - PROCESS | 1921 | Cycle 1(1): loaded http://localhost:53224/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:53224/tests/kraken/driver.html?testName=json-stringify-tinderbox) 11:30:14 INFO - PROCESS | 1921 | RSS: Main: 303558656 11:30:14 INFO - PROCESS | 1921 | 11:30:16 INFO - PROCESS | 1921 | Cycle 1(1): loaded http://localhost:53224/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:53224/tests/kraken/driver.html?testName=stanford-crypto-aes) 11:30:17 INFO - PROCESS | 1921 | RSS: Main: 306204672 11:30:17 INFO - PROCESS | 1921 | 11:30:19 INFO - PROCESS | 1921 | Cycle 1(1): loaded http://localhost:53224/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:53224/tests/kraken/driver.html?testName=stanford-crypto-ccm) 11:30:20 INFO - PROCESS | 1921 | RSS: Main: 307494912 11:30:20 INFO - PROCESS | 1921 | 11:30:23 INFO - PROCESS | 1921 | Cycle 1(1): loaded http://localhost:53224/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:53224/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 11:30:23 INFO - PROCESS | 1921 | RSS: Main: 317743104 11:30:23 INFO - PROCESS | 1921 | 11:30:26 INFO - PROCESS | 1921 | Cycle 1(1): loaded http://localhost:53224/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:53224/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 11:30:27 INFO - PROCESS | 1921 | RSS: Main: 316477440 11:30:27 INFO - PROCESS | 1921 | 11:30:29 INFO - PROCESS | 1921 | Cycle 1(1): loaded http://localhost:53224/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:53224/tests/kraken/driver.html?testName=ai-astar) 11:30:29 INFO - PROCESS | 1921 | RSS: Main: 360243200 11:30:29 INFO - PROCESS | 1921 | 11:30:29 INFO - PROCESS | 1921 | __start_tp_report 11:30:29 INFO - PROCESS | 1921 | _x_x_mozilla_page_load 11:30:29 INFO - PROCESS | 1921 | _x_x_mozilla_page_load_details 11:30:29 INFO - PROCESS | 1921 | |i|pagename|runs| 11:30:29 INFO - PROCESS | 1921 | |0;ai-astar;107;113;114;113;112;113;112;113;113;112 11:30:29 INFO - PROCESS | 1921 | |1;audio-beat-detection;141;112;109;147;112;110;111;109;126;109 11:30:29 INFO - PROCESS | 1921 | |2;audio-dft;184;180;184;184;183;182;180;181;185;232 11:30:29 INFO - PROCESS | 1921 | |3;audio-fft;81;60;58;90;60;59;58;59;74;59 11:30:29 INFO - PROCESS | 1921 | |4;audio-oscillator;90;70;87;68;69;70;83;69;68;83 11:30:29 INFO - PROCESS | 1921 | |5;imaging-gaussian-blur;87;85;87;84;86;84;88;87;88;84 11:30:29 INFO - PROCESS | 1921 | |6;imaging-darkroom;96;95;96;97;95;96;96;96;96;95 11:30:29 INFO - PROCESS | 1921 | |7;imaging-desaturate;84;84;84;100;132;130;87;86;93;87 11:30:29 INFO - PROCESS | 1921 | |8;json-parse-financial;53;52;54;56;55;54;56;56;56;55 11:30:29 INFO - PROCESS | 1921 | |9;json-stringify-tinderbox;66;77;66;68;76;71;72;73;72;72 11:30:29 INFO - PROCESS | 1921 | |10;stanford-crypto-aes;88;87;88;87;88;87;86;86;87;88 11:30:29 INFO - PROCESS | 1921 | |11;stanford-crypto-ccm;152;160;149;146;146;129;144;155;262;164 11:30:29 INFO - PROCESS | 1921 | |12;stanford-crypto-pbkdf2;179;172;171;173;173;173;172;171;172;171 11:30:29 INFO - PROCESS | 1921 | |13;stanford-crypto-sha256-iterative;68;67;82;87;69;68;68;66;66;66 11:30:29 INFO - PROCESS | 1921 | __end_tp_report 11:30:29 INFO - PROCESS | 1921 | __start_cc_report 11:30:29 INFO - PROCESS | 1921 | _x_x_mozilla_cycle_collect,1940 11:30:29 INFO - PROCESS | 1921 | __end_cc_report 11:30:29 INFO - PROCESS | 1921 | __startTimestamp1483126229453__endTimestamp 11:30:29 INFO - PROCESS | 1921 | 11:30:29 INFO - PROCESS | 1921 | ------- Summary: start ------- 11:30:29 INFO - PROCESS | 1921 | Number of tests: 14 11:30:29 INFO - PROCESS | 1921 | 11:30:29 INFO - PROCESS | 1921 | [#0] ai-astar Cycles:10 Average:112.20 Median:113.00 stddev:1.93 (1.7%) stddev-sans-first:0.67 11:30:29 INFO - PROCESS | 1921 | Values: 107.0 113.0 114.0 113.0 112.0 113.0 112.0 113.0 113.0 112.0 11:30:29 INFO - PROCESS | 1921 | 11:30:29 INFO - PROCESS | 1921 | [#1] audio-beat-detection Cycles:10 Average:118.60 Median:112.00 stddev:14.37 (12.8%) stddev-sans-first:12.75 11:30:29 INFO - PROCESS | 1921 | Values: 141.0 112.0 109.0 147.0 112.0 110.0 111.0 109.0 126.0 109.0 11:30:29 INFO - PROCESS | 1921 | 11:30:29 INFO - PROCESS | 1921 | [#2] audio-dft Cycles:10 Average:187.50 Median:184.00 stddev:15.74 (8.6%) stddev-sans-first:16.64 11:30:29 INFO - PROCESS | 1921 | Values: 184.0 180.0 184.0 184.0 183.0 182.0 180.0 181.0 185.0 232.0 11:30:29 INFO - PROCESS | 1921 | 11:30:29 INFO - PROCESS | 1921 | [#3] audio-fft Cycles:10 Average:65.80 Median:60.00 stddev:11.60 (19.3%) stddev-sans-first:10.93 11:30:29 INFO - PROCESS | 1921 | Values: 81.0 60.0 58.0 90.0 60.0 59.0 58.0 59.0 74.0 59.0 11:30:29 INFO - PROCESS | 1921 | 11:30:29 INFO - PROCESS | 1921 | [#4] audio-oscillator Cycles:10 Average:75.70 Median:76.50 stddev:8.90 (11.6%) stddev-sans-first:7.79 11:30:29 INFO - PROCESS | 1921 | Values: 90.0 70.0 87.0 68.0 69.0 70.0 83.0 69.0 68.0 83.0 11:30:29 INFO - PROCESS | 1921 | 11:30:29 INFO - PROCESS | 1921 | [#5] imaging-gaussian-blur Cycles:10 Average:86.00 Median:87.00 stddev:1.63 (1.9%) stddev-sans-first:1.69 11:30:29 INFO - PROCESS | 1921 | Values: 87.0 85.0 87.0 84.0 86.0 84.0 88.0 87.0 88.0 84.0 11:30:29 INFO - PROCESS | 1921 | 11:30:29 INFO - PROCESS | 1921 | [#6] imaging-darkroom Cycles:10 Average:95.80 Median:96.00 stddev:0.63 (0.7%) stddev-sans-first:0.67 11:30:29 INFO - PROCESS | 1921 | Values: 96.0 95.0 96.0 97.0 95.0 96.0 96.0 96.0 96.0 95.0 11:30:29 INFO - PROCESS | 1921 | 11:30:29 INFO - PROCESS | 1921 | [#7] imaging-desaturate Cycles:10 Average:96.70 Median:85.00 stddev:18.76 (22.1%) stddev-sans-first:19.32 11:30:29 INFO - PROCESS | 1921 | Values: 84.0 84.0 84.0 100.0 132.0 130.0 87.0 86.0 93.0 87.0 11:30:29 INFO - PROCESS | 1921 | 11:30:29 INFO - PROCESS | 1921 | [#8] json-parse-financial Cycles:10 Average:54.70 Median:55.50 stddev:1.42 (2.6%) stddev-sans-first:1.36 11:30:29 INFO - PROCESS | 1921 | Values: 53.0 52.0 54.0 56.0 55.0 54.0 56.0 56.0 56.0 55.0 11:30:29 INFO - PROCESS | 1921 | 11:30:29 INFO - PROCESS | 1921 | [#9] json-stringify-tinderbox Cycles:10 Average:71.30 Median:72.00 stddev:3.74 (5.2%) stddev-sans-first:3.44 11:30:29 INFO - PROCESS | 1921 | Values: 66.0 77.0 66.0 68.0 76.0 71.0 72.0 73.0 72.0 72.0 11:30:29 INFO - PROCESS | 1921 | 11:30:29 INFO - PROCESS | 1921 | [#10] stanford-crypto-aes Cycles:10 Average:87.20 Median:87.50 stddev:0.79 (0.9%) stddev-sans-first:0.78 11:30:29 INFO - PROCESS | 1921 | Values: 88.0 87.0 88.0 87.0 88.0 87.0 86.0 86.0 87.0 88.0 11:30:29 INFO - PROCESS | 1921 | 11:30:29 INFO - PROCESS | 1921 | [#11] stanford-crypto-ccm Cycles:10 Average:160.70 Median:153.50 stddev:36.87 (24.0%) stddev-sans-first:38.97 11:30:29 INFO - PROCESS | 1921 | Values: 152.0 160.0 149.0 146.0 146.0 129.0 144.0 155.0 262.0 164.0 11:30:29 INFO - PROCESS | 1921 | 11:30:29 INFO - PROCESS | 1921 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:172.70 Median:172.50 stddev:2.36 (1.4%) stddev-sans-first:0.87 11:30:29 INFO - PROCESS | 1921 | Values: 179.0 172.0 171.0 173.0 173.0 173.0 172.0 171.0 172.0 171.0 11:30:29 INFO - PROCESS | 1921 | 11:30:29 INFO - PROCESS | 1921 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:70.70 Median:68.00 stddev:7.44 (10.9%) stddev-sans-first:7.83 11:30:29 INFO - PROCESS | 1921 | Values: 68.0 67.0 82.0 87.0 69.0 68.0 68.0 66.0 66.0 66.0 11:30:29 INFO - PROCESS | 1921 | -------- Summary: end -------- 11:30:29 INFO - PROCESS | 1921 | 11:30:30 INFO - TEST-INFO | 1921: exit 0 11:30:30 INFO - TEST-OK | kraken | took 59509ms 11:30:30 INFO - SUITE-END | took 1179s 11:30:30 INFO - Completed test suite (00:19:39) 11:30:30 INFO - javascript benchmark 11:30:30 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3284.2774589590294, 3294.407006738787, 3312.4037673218827, 3326.168457885529, 3369.81466019369, 4662.5803677669, 5507.862303442416, 5593.440655934404, 5637.548996080319, 5656.264685590872, 5608.327000759904, 5632.098864181732, 5665.320161580606, 5685.317761868581, 5691.08942569189, 4674.831292176956, 5101.260317253996, 5164.999999999991, 5210.010098081371, 5242.187460943541, 5228.764705588239, 5278.287431196778, 5281.524662780349, 5308.256844041837, 5332.253484512172, 5127.410347809993, 5237.502437268454, 5265.8946821063555, 5323.467653234684, 5328.413874473805, 5193.350831146106, 5241.711705856176, 5246.816361427332, 5249.107651699221, 5310.150375939869, 5073.949260507385, 5074.416442109168, 5132.230165475171, 5158.587313014949, 5182.663126896778, 5586.748596313176, 5641.746121424505, 5661.547076233891, 5671.2060311556415, 5740.167675687044, 5110.770015349318, 6282.465990390822, 6305.558610897239, 6310.116583678288, 6321.1150438938585, 6128.819915423286, 6265.59273647216, 6336.0, 6360.0, 6379.489640828722, 4770.427548694179, 5723.998300297431, 5758.971205144031, 5776.768929242868, 5784.768609255667, 6221.47117495018, 6254.74981000764, 6283.59156654816, 6319.905201421982, 6343.27052388972, 6100.557439056541, 6150.876982460415, 6153.753849846045, 6165.475934545524, 6199.659018753921, 6310.7791227306825, 6317.936820631735, 6321.557490975588, 6322.9051564226575, 6327.651979141191, 6073.544484163705, 6133.0, 6230.6573138476915, 6234.937650623527, 6249.937500624936, 6142.266691322318, 6182.28903676083, 6257.186565746515, 6268.592541484789, 6319.431251187415, 4921.926171107436, 4940.505949405103, 4941.752912354368, 4942.8764280893265, 4969.751512424365, 4424.446944131983, 4431.534688857688, 4435.6229720473475, 4447.288433850568, 4465.955340446619, 5572.60991730575, 5616.1014237721765, 5621.409751976065, 5718.056520674042, 5745.1669507921115, 6277.780277690259, 6321.589096708699, 6328.620282782956, 6332.398422149889, 6359.077933699587, 5682.261306030272, 5694.60137790359, 5805.796797112081, 5807.303123625192, 5828.300603927556, 5274.4198138204765, 5288.497592728685, 5334.333208348957, 5422.91865622016, 5424.403315635277, 6309.526785491198, 6315.652639104802, 6340.080688300079, 6346.3019067903415, 6353.841153971003, 6302.747890084529, 6304.810855674337, 6316.747330106744, 6322.051692246016, 6325.177726895474, 6282.277538083179, 6300.21247344082, 6356.904646430215, 6366.140571023038, 6372.968135159294, 6119.624418755179, 6121.387861213843, 6127.953177891068, 6167.5682702210415, 6247.875042499216, 6084.087386892002, 6326.082718005955, 6326.398992095744, 6328.810135695937, 6337.746490140527, 4727.361806156263, 5799.9130013049, 5991.101334799815, 6017.488513476404, 6018.849528761816, 5870.882582348244, 6095.573309868267, 6113.786017489367, 6118.816435506942, 6121.785737499166, 5798.420157984167, 5824.5631577631, 5832.94167058324, 5837.445442682938, 5838.532917366516, 5479.753411096431, 5991.760329586942, 6032.849178770566, 6051.636901785907, 6117.0824376342125, 5901.970490147522, 5918.911216331845, 5920.8815823684145, 5932.228810254639, 5958.731857066529], "value": 5669.165523275232, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [491.1846335083762, 494.3696786597088, 496.92297693857455, 502.3068165931015, 504.56355252706396, 29578.408431831376, 39062.60937390625, 40178.7991060045, 40240.0, 42165.57834421655, 40974.59025409745, 41341.79329103358, 41360.0, 41424.79287603558, 41572.34842425212, 9181.311401644887, 9370.390924589914, 9373.906260937405, 9374.85937710935, 9391.859122113174, 8430.452020618672, 8686.826263474726, 11099.944500277486, 11357.943210283978, 11378.544858205703, 11323.263987840764, 11366.829497557543, 11378.260413073165, 11386.316820990767, 11407.0, 8503.489790612583, 11139.610113646024, 11226.438678066064, 11251.774964500664, 11303.717407064849, 8462.66149354025, 8597.785055373603, 8621.439606425562, 8621.439606425562, 10968.780624387548, 37901.052473688105, 39699.40450893239, 39743.60256397457, 39751.80744577639, 39923.40114898279, 3981.163955569334, 3998.540167880702, 4016.598340165989, 4054.8580799671868, 4126.236646220459, 23740.0, 29371.70628293711, 29911.0, 30529.08412747621, 30531.69468305311, 3115.704008119225, 3263.673632636741, 3263.7552183586326, 3264.1513206566465, 3308.123347312964, 418661.9066904677, 419159.0, 419398.0, 420555.0, 420818.9999999969, 58094.7095264521, 58537.4146258532, 58539.70730146322, 58752.11871821926, 58800.82398352009, 60033.0, 60074.79850402968, 60101.69949150314, 60194.0, 60296.69851650714, 15120.924395378173, 15318.310676019606, 15799.842001579837, 15815.762763558556, 15852.92073539648, 10970.561177552969, 11281.0, 12332.013438924865, 12431.689207769878, 12471.376431178405, 14612.634684132981, 16039.43861964849, 16326.265318060716, 16464.341426342813, 16766.74849877253, 5936.643801371932, 6348.888944434705, 6562.114114594468, 6700.731970721116, 6756.628385438749, 830.6078493937542, 850.6639877248452, 855.6320782063648, 863.922266947991, 865.2040123086775, 28772.136835894955, 28802.567961480596, 29266.0, 29731.810727571086, 29801.0, 1032.9380237185792, 1038.5586125896464, 1057.460695045517, 1084.1922767538235, 1126.0822429719751, 14992.775108373165, 15007.474738384104, 15407.537773866801, 15417.922910385376, 15446.304916278794, 587169.0, 591403.0, 593280.0, 595011.0, 597766.0, 57758.8448231033, 57816.71091644515, 57898.42101579015, 57998.42001580015, 58029.709851450476, 1335.8730920562532, 1376.1742954227384, 1397.5388121919946, 1400.4188261871209, 1401.7266633006466, 27904.0, 28709.0, 28916.27709307284, 29406.852965735034, 29579.964701236215, 48429.273560897316, 48887.755561221966, 48986.265206022654, 49066.26400604065, 49161.0, 504946.0, 507926.0, 508376.0, 509138.0, 509622.0, 37322.06694832651, 38444.42333365058, 38792.0, 38800.41799372955, 38819.6118038816, 44003.559964399945, 44656.77671611621, 44810.55189448194, 44956.550434496545, 45242.54757452384, 29921.0, 36852.0, 37152.62847371492, 37641.0, 37744.056398590255, 36780.0, 37107.6289237115, 37686.434703480016, 38229.85310440691, 38345.0, 35804.10489737673, 35890.4616430759, 36699.44950825686, 36821.26357472889, 36826.26347473089, 32234.83882580666, 32928.506072408454, 33598.0, 33650.0, 33792.66207337895, 23951.28146155618, 23966.88016559906, 24207.273781786575, 24485.877570612032, 24859.875700621382], "value": 19597.495959605516, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [350.37283262959306, 370.6330732574751, 372.20534159569326, 379.23962455277166, 399.88803135122174, 14662.63343416415, 14963.326650300736, 15441.613959651, 16566.585835354123, 20177.697334539993, 19424.125914333854, 19832.70250946237, 20396.8980155099, 20637.864917429535, 20751.0, 5617.859553511164, 5852.619579727304, 5860.062390017577, 5882.941170588282, 5895.0, 4395.549593309415, 5759.1937128802, 5769.509591684712, 5792.855178620526, 5796.0, 5722.914156287659, 5723.341815691187, 5726.828195154131, 5728.341240757304, 5737.913931291034, 7105.218425973138, 7449.180590135081, 7506.662200201004, 7519.774406767806, 7614.2766437188375, 6963.7562685305775, 7185.281471852825, 7228.855422891513, 7261.3101755333155, 7333.339999400079, 28114.297142571395, 28218.435631287466, 28227.294317642223, 28344.724487398118, 28386.574201386997, 2390.964135537968, 2978.8212707237417, 2990.5833532838465, 2993.5659329397117, 3016.457037733207, 16144.434944776876, 16187.514374568782, 16293.429729959395, 16827.91586042062, 17118.0, 1456.9198694071822, 1974.6840505519049, 1982.9405117846488, 1994.411648563677, 1998.9300374486822, 132363.33818330846, 134344.0, 135903.0, 138242.61757382398, 143636.0, 16805.83194168067, 17693.469195924143, 18680.719789203173, 20697.379078627666, 21164.682529762067, 33377.165570860925, 34834.0, 34834.30331393358, 34927.82536087354, 34981.65018349835, 8024.398170137263, 10319.277650564389, 10527.420991845369, 10611.31026483276, 10738.677839664822, 7500.774976750707, 8132.6746930122135, 8564.229219370287, 8607.48355098696, 8749.25631321332, 9437.90562094384, 9684.258611015988, 9790.363626364264, 9796.95101524502, 9807.166390856857, 3665.890023299305, 3756.9812150939615, 3768.246350729865, 3777.206786574795, 3789.6589306962505, 933.5472295936465, 968.7626531499686, 981.65642025292, 992.3053862296422, 1010.4291075542295, 20572.897135514526, 20810.47973800667, 20812.687809682866, 20889.79110208909, 20894.268700595407, 999.9450030248261, 1014.974625634365, 1025.5436330832856, 1032.5353590884133, 1035.4822588705647, 10184.338018028804, 10881.401522916158, 10885.510152043176, 10904.0, 10944.288621239593, 455440.0, 461615.0, 524604.0, 526258.0, 530323.0, 133859.0, 136166.0, 136249.3187534056, 138232.30883845515, 139385.30307348398, 962.6101428921298, 1004.2719028704131, 1036.409246729357, 1037.3412882819464, 1039.8336266197373, 17377.13114344303, 17519.0, 18285.725714114033, 18993.905030474758, 19237.519062023563, 43805.34291985686, 45357.54642453533, 51416.74291628518, 51623.741881291855, 53581.73209133929, 74888.0, 75147.62426187834, 75645.62177189298, 76130.23869761454, 76940.0, 8095.352371810359, 8129.715459958873, 8215.178482151738, 8297.460665056751, 8313.625886835229, 11197.38414387184, 12873.678158046123, 12907.870921290667, 13067.477300907858, 13137.211767293993, 9096.363254572381, 10794.622188223375, 10949.178811589321, 11116.055135313589, 11161.274517156684, 10929.016388525071, 11112.277701949348, 11169.832452513056, 11252.66242012741, 11400.657980260605, 9005.0, 11378.715532111764, 11485.770284594428, 11510.712232194262, 11570.0, 6984.196817365864, 7359.0, 7924.20757924203, 7945.523268603903, 8273.089960104264, 10050.497475126069, 10651.840222396826, 10702.571897124339, 11248.94375528117, 11311.717207069889], "value": 10661.288293606654, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1381.689119948012, 1386.8335799704034, 1401.0472878442654, 1403.2492616450202, 1406.9859301406987, 7943.880841787371, 8344.958275208615, 8375.953005874273, 8417.873731894026, 8422.241998220159, 7377.446691498132, 7656.3109320161175, 7670.348020418257, 8019.077806052306, 8172.346212303017, 2230.2863083813145, 2259.7401298850677, 2266.8073213776765, 2270.375646697155, 2273.0453209651987, 2227.9554408911813, 2275.2036787124457, 2276.499170182557, 2310.1914329984456, 2362.444825466023, 1884.9151788169497, 1950.4831219726784, 2213.0, 2232.9888350558226, 2285.3486756274465, 1888.6600411925847, 2251.7410497792716, 2265.410993141795, 2274.920377786786, 2275.8634481931053, 1913.8373238274764, 2271.681964524968, 2295.8278129140213, 2301.735300440454, 2301.8043466305385, 8258.0, 8312.085670576293, 8316.0, 8346.791330216733, 8365.749027529184, 5065.341505604248, 5237.47625237477, 5238.68567885928, 5240.34495688039, 5247.658902171384, 2766.845439314773, 3456.740744444177, 3474.1488335357744, 3497.527833742463, 3508.9298214035575, 1159.8144296912537, 1212.8483939507562, 1246.1401632873287, 1249.8312727781724, 1255.121415009497, 10446.216533759998, 10724.946375268151, 10737.83893241602, 10759.977802108788, 10874.510647020903, 4251.681123915657, 4265.91468170641, 4387.210302145644, 4390.495093064338, 4450.265706158512, 8516.829663406697, 8588.269997050196, 8677.956610216908, 8728.607212675444, 8757.12428757132, 5115.2071428928575, 5116.692998420107, 5118.718470484159, 5131.461196574381, 5136.178211486144, 4277.550857159953, 4439.267520859086, 4636.374089497942, 4776.498467660846, 4812.5668689818085, 3612.5303710517464, 3670.210904655512, 3729.272791805572, 3758.210775737126, 3779.055236190952, 988.5255077562811, 1136.7612801311652, 1144.5593446523148, 1145.2212495503138, 1164.0978241862456, 687.4191308344441, 759.5480688990042, 882.298572634757, 898.8786513820681, 923.9076092390707, 5332.520073193354, 5405.945940540544, 5621.0444224481935, 5708.514776244066, 5732.3407808101765, 746.488655271141, 838.8028813228887, 884.8407286688458, 886.7738726624798, 893.5934149961752, 3146.606674165729, 3392.6768560261517, 3643.6720695137565, 3766.8304926278383, 3794.3739283018544, 9468.337216394924, 9527.571259293349, 9929.404235745878, 10055.39667619945, 10116.898831011596, 9791.804163916682, 10021.699349019542, 10067.69796906094, 10078.798424031478, 10080.495975201211, 7171.713131474787, 7425.331720145213, 7456.067991501062, 7599.620018999028, 7857.2928436441, 5346.786128554814, 5603.523700485504, 5658.575606829422, 5668.149777533403, 5915.58590898633, 8485.0, 8676.826463470821, 9001.729948101567, 9309.813803723751, 9426.575804088696, 8950.657401389844, 9434.29242806806, 9443.244540436886, 9506.524673766422, 9577.760555986157, 6566.409023187936, 6609.900851487136, 6793.796186114425, 6799.184097908283, 7269.927300726925, 7429.405647548075, 7523.88714169277, 7557.319841214097, 7832.138464768985, 7891.763247102596, 7036.331548503089, 7161.4986950912935, 7162.641867906688, 7229.204787473479, 7294.635268236673, 6962.1993470749485, 6991.755288564876, 7040.225575186623, 7062.576245425291, 7115.679794409368, 6227.688615569294, 6350.301466838551, 6370.968145159244, 6384.329645387354, 6437.291897891322, 4744.359511465908, 4780.235162374003, 4787.664863459524, 4811.08589368019, 4896.338994235733, 4054.8175332109536, 4711.31685905535, 4741.431028276629, 4778.307145463958, 4969.602431805519], "value": 4294.249696832134, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [347.0836990345488, 353.36394489918155, 357.11969993964874, 368.5724559510968, 370.65343903450275, 4585.541445855417, 5020.221865610832, 5025.346704928364, 5174.534291913727, 5208.192730126827, 4808.893954390493, 5017.698938063719, 5146.897062058757, 5151.407588127367, 5187.221916712495, 3145.5124455709342, 3302.9669703302898, 4063.5326937402283, 4088.7751173685583, 4132.462779838632, 3246.8051916884942, 3667.5782285037294, 4082.9591704082877, 4128.50457945047, 4132.793360331986, 3286.901392958215, 3925.60743925608, 3939.8621048263317, 3947.7631342119566, 3953.446517487554, 3592.46113083037, 3758.3610786166146, 3900.609939006105, 3923.8822835314986, 3926.587708290617, 2843.5876797864403, 3393.7794043387103, 3418.504316874064, 3423.0928803866996, 3429.2969941162, 4591.0, 4696.342512048316, 4848.878778030542, 5037.748112594392, 5059.974700126476, 3674.8713795017047, 3752.9061773455883, 3993.3211354069877, 4319.034839148211, 4506.571875671806, 4595.2188128018315, 4777.474477807473, 4930.630202734774, 5159.535641792257, 5429.239906413105, 3264.298175892171, 3380.9857042887033, 3617.981910090459, 3694.063219740313, 3743.8128093595483, 4501.7974191161475, 4692.788824502905, 4759.904801903977, 5508.862278443031, 5657.405972372924, 3172.920676983094, 3755.3615885299564, 3757.755745876564, 3769.7738135711943, 3772.5472943246436, 3577.928441431157, 3628.854845806138, 3802.8098595070687, 3822.808859557011, 3867.9363175127064, 4192.622663960258, 4407.449068866392, 4412.669049821212, 4459.636392711868, 4468.9106217876115, 5282.573705099679, 5426.75579598919, 5452.6455780374945, 5470.753816078286, 5481.342238931274, 1179.475133565555, 1205.7467931734259, 1214.518287689018, 1214.5809695654984, 1229.8585662648732, 772.2393442459173, 804.0381469215097, 834.4242472693821, 845.2266176448603, 852.3224036890687, 4217.536071032184, 4589.613401031445, 4886.731229782325, 4898.779554920037, 4907.263910413467, 4926.729029903318, 4935.407751069823, 4958.42978057528, 5206.609504287194, 5299.920501192485, 4491.1915855145735, 4561.738357074708, 4789.928151077736, 4805.951940480551, 4816.638752093607, 2758.1449750577385, 2766.7371599697994, 2773.55623100303, 2776.7088303938363, 2782.8729364607366, 4942.418426103613, 5123.513266239701, 5167.0, 5177.275181474596, 5408.269883565747, 4818.542238487338, 5367.892642147135, 5381.228667236376, 5391.622586418913, 5405.243265942771, 4374.934375984363, 4941.703497790144, 5094.5160209780015, 5197.246399272084, 5384.811531596375, 4596.521828348678, 4633.374494443207, 4654.9301760472945, 4896.996115796324, 5142.100132476877, 4713.410823647044, 4807.538572227991, 5095.0574143783515, 5255.500727430888, 5317.2555842181355, 4900.407367605614, 5287.391949925808, 5429.918551221655, 5439.403703870224, 5489.451054894478, 3189.553462515296, 3545.751797374159, 3556.7729133449952, 3576.856925722942, 3577.338192434408, 5574.386817450159, 5575.553955683617, 5608.971955140199, 5626.634268772516, 5657.43425657431, 5071.776070647239, 5078.415982162099, 5182.870428239174, 5474.616776825584, 5493.2034854946605, 4586.72479651222, 5185.144451165515, 5437.0, 5440.612643775812, 5460.836174914759, 4654.325122857234, 4696.718196908197, 4900.093482705568, 5152.922706159336, 5425.240466334637, 5404.756785944721, 5435.211894275387, 5504.843982763665, 5531.917021244765, 5564.888702225851, 5007.874803129951, 5225.320708308048, 5297.284866542967, 5384.6769193848495, 5448.940539298248], "value": 3872.9684726822875, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [698.5005720909548, 705.8694141583808, 744.8249661329587, 781.1914668318291, 824.5052968219068, 14779.778303325458, 15613.65795130731, 15741.134237616927, 15875.0, 16196.838031619709, 14074.29628518575, 14252.643683907907, 14373.0, 14603.561893143196, 14603.78094328586, 853.0658928473327, 873.6811063961671, 878.0692465986043, 878.165742544582, 880.9251213646847, 671.8085345676483, 696.2863065357998, 716.6667499612679, 721.3363705391026, 877.8112705768259, 932.0958670090001, 935.4995077633467, 935.7613808478828, 940.9576569054375, 945.9527023648825, 937.1518775508176, 938.4697645830153, 938.9061093890624, 939.0421769794839, 939.3283802081545, 544.0669252232401, 561.7331767410489, 671.0203103468941, 674.8549061951704, 687.1719577908608, 15012.47456339023, 15072.020318679251, 15088.321025553767, 15105.244737763069, 15107.697846043018, 13255.204687718768, 13278.535251266256, 13387.665308367272, 13402.731945361136, 13403.664908377368, 12185.268883866996, 12312.13815032948, 12405.255684658949, 12548.874511254953, 12869.613911582574, 4277.337012763027, 4278.272693642089, 4368.956310436887, 4369.518145623972, 4468.843590474317, 92001.53999229961, 92655.53672231662, 92873.53563232141, 94167.52916235375, 95662.0, 10347.741306467398, 10374.58501659925, 10397.896021039844, 10536.157107431387, 10999.945000274947, 16336.673266534603, 18598.44204673862, 20190.697139542917, 20741.792582073987, 21020.26429074975, 7396.593187374639, 7430.814229644194, 7451.515651482637, 7485.812854678677, 7544.283293087148, 4114.67919492389, 4192.22443847883, 4230.238557059759, 4254.659627229814, 4336.869893903188, 3379.665032312272, 3390.9491357629654, 3447.3277710846146, 3488.494168345576, 3490.424080026767, 636.1697984130661, 636.9426751592379, 637.996810015947, 641.8202903187115, 644.9935500645028, 578.8986927287708, 591.1398914579277, 616.722474886303, 623.7504998000836, 625.3996163682824, 8304.211099945496, 8809.647614095364, 8817.29461643067, 8862.76192804988, 8901.243394311556, 610.5970059760537, 628.1676778268812, 629.9118123462719, 639.3446717114995, 644.8033349828254, 4339.284018137035, 4807.206810876236, 4851.320815085891, 4911.361523001987, 4967.503249675004, 84490.0, 100796.49601752091, 104223.47888260511, 104641.0, 108259.0, 93303.53348233351, 93759.53120234492, 95710.0, 96026.0397396017, 97722.0, 83348.91627709234, 83438.58280708558, 83601.0, 83916.16083839205, 84009.0, 9674.0, 9690.806183876424, 9720.465374404475, 9724.513774311397, 9728.708138755848, 48247.793805155154, 50093.74953125211, 50729.0, 50736.97052117874, 51471.74264128805, 58779.118313226194, 67017.66491167514, 70506.0, 70923.0, 72942.63528682322, 12566.937165314115, 12871.871281287067, 12988.545400910923, 12990.285534295326, 13410.664733381744, 14563.912052327354, 14742.557723268319, 14781.0, 14914.701705966037, 15371.69340606061, 13409.396577153857, 13428.19430834153, 13541.661458463617, 13771.586852394445, 14091.647708807363, 13624.795628065785, 13797.10318829273, 13855.584332470042, 13863.37614807316, 13931.512397066053, 13301.944076688049, 13458.798118028042, 13556.457741690221, 13637.454501819817, 14190.929045354707, 11013.889861101286, 11264.267822591506, 11343.829842552534, 11444.533041458424, 11589.24669896454, 10715.839262410915, 10952.45237738094, 10985.285956412808, 11026.0, 11039.613613523488], "value": 7431.7450409620005, "unit": "score"}], "name": "dromaeo_css", "value": 7259.955457546506, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [107.0, 113.0, 114.0, 113.0, 112.0, 113.0, 112.0, 113.0, 113.0, 112.0], "value": 112.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [141.0, 112.0, 109.0, 147.0, 112.0, 110.0, 111.0, 109.0, 126.0, 109.0], "value": 118.6, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [184.0, 180.0, 184.0, 184.0, 183.0, 182.0, 180.0, 181.0, 185.0, 232.0], "value": 187.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 60.0, 58.0, 90.0, 60.0, 59.0, 58.0, 59.0, 74.0, 59.0], "value": 65.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 70.0, 87.0, 68.0, 69.0, 70.0, 83.0, 69.0, 68.0, 83.0], "value": 75.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 85.0, 87.0, 84.0, 86.0, 84.0, 88.0, 87.0, 88.0, 84.0], "value": 86.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 95.0, 96.0, 97.0, 95.0, 96.0, 96.0, 96.0, 96.0, 95.0], "value": 95.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 84.0, 84.0, 100.0, 132.0, 130.0, 87.0, 86.0, 93.0, 87.0], "value": 96.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [53.0, 52.0, 54.0, 56.0, 55.0, 54.0, 56.0, 56.0, 56.0, 55.0], "value": 54.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 77.0, 66.0, 68.0, 76.0, 71.0, 72.0, 73.0, 72.0, 72.0], "value": 71.3, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 87.0, 88.0, 87.0, 88.0, 87.0, 86.0, 86.0, 87.0, 88.0], "value": 87.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [152.0, 160.0, 149.0, 146.0, 146.0, 129.0, 144.0, 155.0, 262.0, 164.0], "value": 160.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [179.0, 172.0, 171.0, 173.0, 173.0, 173.0, 172.0, 171.0, 172.0, 171.0], "value": 172.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 67.0, 82.0, 87.0, 69.0, 68.0, 68.0, 66.0, 66.0, 66.0], "value": 70.7, "unit": "score"}], "name": "kraken", "value": 1455.6000000000001, "alertThreshold": 2.0}]} 11:30:30 INFO - Return code: 0 11:30:30 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 11:30:30 INFO - # TBPL SUCCESS # 11:30:30 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 11:30:30 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 11:30:30 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 11:30:30 INFO - 2016-12-30 11:30:30.776 screenresolution[1926:20199] starting screenresolution argv=screenresolution get 11:30:30 INFO - 2016-12-30 11:30:30.781 screenresolution[1926:20199] Display 0: 1600x1200x32@60 11:30:30 INFO - 2016-12-30 11:30:30.787 screenresolution[1927:20202] starting screenresolution argv=screenresolution list 11:30:30 INFO - Available Modes on Display 0 11:30:30 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 11:30:30 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 11:30:30 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 11:30:30 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 11:30:31 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 11:30:31 INFO - Intel Iris: 11:30:31 INFO - Chipset Model: Intel Iris 11:30:31 INFO - Type: GPU 11:30:31 INFO - Bus: Built-In 11:30:31 INFO - VRAM (Dynamic, Max): 1536 MB 11:30:31 INFO - Vendor: Intel (0x8086) 11:30:31 INFO - Device ID: 0x0a2e 11:30:31 INFO - Revision ID: 0x0009 11:30:31 INFO - Displays: 11:30:31 INFO - Display: 11:30:31 INFO - Resolution: 1600 x 1200 @ 60 Hz 11:30:31 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 11:30:31 INFO - Display Serial Number: 1600x1200 60 11:30:31 INFO - Main Display: Yes 11:30:31 INFO - Mirror: Off 11:30:31 INFO - Online: Yes 11:30:31 INFO - Rotation: Supported 11:30:31 INFO - Return code: 0 11:30:31 INFO - Running post-action listener: _resource_record_post_action 11:30:31 INFO - [mozharness: 2016-12-30 19:30:31.023008Z] Finished run-tests step (success) 11:30:31 INFO - Running post-run listener: _resource_record_post_run 11:30:31 INFO - Total resource usage - Wall time: 1201s; CPU: 54.0%; Read bytes: 507904; Write bytes: 549359104; Read time: 4; Write time: 1660 11:30:31 INFO - TinderboxPrint: CPU usage
53.7% 11:30:31 INFO - TinderboxPrint: I/O read bytes / time
507,904 / 4 11:30:31 INFO - TinderboxPrint: I/O write bytes / time
549,359,104 / 1,660 11:30:31 INFO - TinderboxPrint: CPU idle
2,224.3 (46.3%) 11:30:31 INFO - TinderboxPrint: CPU system
961.6 (20.0%) 11:30:31 INFO - TinderboxPrint: CPU user
1,619.8 (33.7%) 11:30:31 INFO - TinderboxPrint: Swap in / out
249,724,928 / 0 11:30:31 INFO - install - Wall time: 22s; CPU: 54.0%; Read bytes: 228983296; Write bytes: 223707648; Read time: 16546; Write time: 490 11:30:31 INFO - run-tests - Wall time: 1180s; CPU: 54.0%; Read bytes: 507904; Write bytes: 296512512; Read time: 4; Write time: 1102 11:30:31 INFO - Running post-run listener: _upload_blobber_files 11:30:31 INFO - Blob upload gear active. 11:30:31 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 11:30:31 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 11:30:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Graphics', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 11:30:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Graphics -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 11:30:31 INFO - (blobuploader) - INFO - Open directory for files ... 11:30:31 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 11:30:31 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:30:31 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:30:32 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 11:30:32 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:30:32 INFO - (blobuploader) - INFO - Done attempting. 11:30:32 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 11:30:32 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:30:32 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:30:33 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 11:30:33 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:30:33 INFO - (blobuploader) - INFO - Done attempting. 11:30:33 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 11:30:33 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:30:33 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:30:34 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 11:30:34 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:30:34 INFO - (blobuploader) - INFO - Done attempting. 11:30:34 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 11:30:34 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:30:34 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:30:36 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 11:30:36 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:30:36 INFO - (blobuploader) - INFO - Done attempting. 11:30:36 INFO - (blobuploader) - INFO - Iteration through files over. 11:30:36 INFO - Return code: 0 11:30:36 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 11:30:36 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 11:30:36 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/ad909826a441011856311c12aa820502dcaf0775ea85d2df0f252937159f5f979b3cfff900e59272102ffbcb994a95a4abcf6543e2cd4c82b132ddce6e8ffdb3", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/541be0a8e20c59dd444969349a02386e7193b8e492bf6a8dc1575df144a69f8db6f778019c2e5642439eedc3ab788e16bbe3d2c48c606c217e2fa11bf2d1016c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/f7960e9773287e998702a795acdc162852cc3ef3ba7d7f7561fce00f76ad2f218d901ac91971c08fffa077078198b1f2a8a7582ac5db9c1d6b3743ca13a761af", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/11915d1b8d2ba10ac89207279c5d6a558fa2e8e6aed242ebe473d50bc9eeb90c297789b4d4910e778c3c3ee1b59f00f3e82eb34b64aebd05b3e7f4f6178f7f8b"} 11:30:36 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 11:30:36 INFO - Writing to file /builds/slave/test/properties/blobber_files 11:30:36 INFO - Contents: 11:30:36 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/ad909826a441011856311c12aa820502dcaf0775ea85d2df0f252937159f5f979b3cfff900e59272102ffbcb994a95a4abcf6543e2cd4c82b132ddce6e8ffdb3", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/541be0a8e20c59dd444969349a02386e7193b8e492bf6a8dc1575df144a69f8db6f778019c2e5642439eedc3ab788e16bbe3d2c48c606c217e2fa11bf2d1016c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/f7960e9773287e998702a795acdc162852cc3ef3ba7d7f7561fce00f76ad2f218d901ac91971c08fffa077078198b1f2a8a7582ac5db9c1d6b3743ca13a761af", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/11915d1b8d2ba10ac89207279c5d6a558fa2e8e6aed242ebe473d50bc9eeb90c297789b4d4910e778c3c3ee1b59f00f3e82eb34b64aebd05b3e7f4f6178f7f8b"} 11:30:36 INFO - Running post-run listener: copy_logs_to_upload_dir 11:30:36 INFO - Copying logs to upload dir... 11:30:36 INFO - mkdir: /builds/slave/test/build/upload/logs 11:30:36 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1262.713501 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 2 secs) (at 2016-12-30 11:30:36.882036) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-30 11:30:36.885232) ========= 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.9XD77iYQHa/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.fl2iYrNvHO/Listeners TMPDIR=/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/ad909826a441011856311c12aa820502dcaf0775ea85d2df0f252937159f5f979b3cfff900e59272102ffbcb994a95a4abcf6543e2cd4c82b132ddce6e8ffdb3", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/541be0a8e20c59dd444969349a02386e7193b8e492bf6a8dc1575df144a69f8db6f778019c2e5642439eedc3ab788e16bbe3d2c48c606c217e2fa11bf2d1016c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/f7960e9773287e998702a795acdc162852cc3ef3ba7d7f7561fce00f76ad2f218d901ac91971c08fffa077078198b1f2a8a7582ac5db9c1d6b3743ca13a761af", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/11915d1b8d2ba10ac89207279c5d6a558fa2e8e6aed242ebe473d50bc9eeb90c297789b4d4910e778c3c3ee1b59f00f3e82eb34b64aebd05b3e7f4f6178f7f8b"} build_url:https://queue.taskcluster.net/v1/task/W-c36Vo3SSGPQ21dWGq2Jw/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011231 build_url: 'https://queue.taskcluster.net/v1/task/W-c36Vo3SSGPQ21dWGq2Jw/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/ad909826a441011856311c12aa820502dcaf0775ea85d2df0f252937159f5f979b3cfff900e59272102ffbcb994a95a4abcf6543e2cd4c82b132ddce6e8ffdb3", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/541be0a8e20c59dd444969349a02386e7193b8e492bf6a8dc1575df144a69f8db6f778019c2e5642439eedc3ab788e16bbe3d2c48c606c217e2fa11bf2d1016c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/f7960e9773287e998702a795acdc162852cc3ef3ba7d7f7561fce00f76ad2f218d901ac91971c08fffa077078198b1f2a8a7582ac5db9c1d6b3743ca13a761af", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/11915d1b8d2ba10ac89207279c5d6a558fa2e8e6aed242ebe473d50bc9eeb90c297789b4d4910e778c3c3ee1b59f00f3e82eb34b64aebd05b3e7f4f6178f7f8b"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-30 11:30:36.919745) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-30 11:30:36.920082) ========= 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.9XD77iYQHa/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.fl2iYrNvHO/Listeners TMPDIR=/var/folders/k0/k2bv9k_d1hj2csl792zsg9v800000w/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.005090 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-30 11:30:36.968417) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-30 11:30:36.968729) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-30 11:30:36.969078) ========= ========= Total master_lag: 0.18 =========