builder: mozilla-aurora_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0248 starttime: 1490176757.86 results: success (0) buildid: 20170322020638 builduid: 5e0be0c06ab7434a9d7dd2237a4e59bf revision: f024c36fad38f458a8561d6e245c2936f42215d9 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-03-22 02:59:17.861890) ========= master: http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-03-22 02:59:17.862318) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-22 02:59:17.862596) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-22 02:59:17.887180) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-22 02:59:17.887485) ========= 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.KNRQsa1F3W/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.mXFqTmDUa3/Listeners TMPDIR=/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2017-03-22 02:59:17-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 8.10M=0.001s 2017-03-22 02:59:18 (8.10 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.165156 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-22 02:59:18.076191) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-22 02:59:18.076566) ========= 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.KNRQsa1F3W/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.mXFqTmDUa3/Listeners TMPDIR=/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/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.022394 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-22 02:59:18.125629) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 3 secs) (at 2017-03-22 02:59:18.125994) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev f024c36fad38f458a8561d6e245c2936f42215d9 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev f024c36fad38f458a8561d6e245c2936f42215d9 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.KNRQsa1F3W/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.mXFqTmDUa3/Listeners TMPDIR=/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2017-03-22 02:59:18,218 truncating revision to first 12 chars 2017-03-22 02:59:18,218 Setting DEBUG logging. 2017-03-22 02:59:18,218 attempt 1/10 2017-03-22 02:59:18,218 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/f024c36fad38?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-03-22 02:59:19,784 unpacking tar archive at: mozilla-aurora-f024c36fad38/testing/mozharness/ program finished with exit code 0 elapsedTime=1.827407 ========= master_lag: 1.54 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 3 secs) (at 2017-03-22 02:59:21.492716) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-22 02:59:21.493061) ========= script_repo_revision: f024c36fad38f458a8561d6e245c2936f42215d9 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-22 02:59:21.493460) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-22 02:59:21.493742) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-22 02:59:21.517791) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 24 secs) (at 2017-03-22 02:59:21.518144) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Aurora', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.KNRQsa1F3W/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.mXFqTmDUa3/Listeners TMPDIR=/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/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 02:59:21 INFO - MultiFileLogger online at 20170322 02:59:21 in /builds/slave/test 02:59:21 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 02:59:21 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 02:59:21 INFO - {'append_to_log': False, 02:59:21 INFO - 'base_work_dir': '/builds/slave/test', 02:59:21 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 02:59:21 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 02:59:21 INFO - 'branch': 'Mozilla-Aurora', 02:59:21 INFO - 'buildbot_json_path': 'buildprops.json', 02:59:21 INFO - 'config_files': ('talos/mac_config.py',), 02:59:21 INFO - 'default_actions': ('clobber', 02:59:21 INFO - 'read-buildbot-config', 02:59:21 INFO - 'download-and-extract', 02:59:21 INFO - 'populate-webroot', 02:59:21 INFO - 'create-virtualenv', 02:59:21 INFO - 'install', 02:59:21 INFO - 'run-tests'), 02:59:21 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 02:59:21 INFO - 'download_minidump_stackwalk': True, 02:59:21 INFO - 'download_symbols': 'ondemand', 02:59:21 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 02:59:21 INFO - 'tooltool.py': '/tools/tooltool.py', 02:59:21 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 02:59:21 INFO - '/tools/misc-python/virtualenv.py')}, 02:59:21 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 02:59:21 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 02:59:21 INFO - 'gecko_profile': False, 02:59:21 INFO - 'gecko_profile_interval': 0, 02:59:21 INFO - 'installer_path': 'installer.exe', 02:59:21 INFO - 'log_level': 'info', 02:59:21 INFO - 'log_name': 'talos', 02:59:21 INFO - 'log_to_console': True, 02:59:21 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 02:59:21 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 02:59:21 INFO - 'opt_config_files': (), 02:59:21 INFO - 'pip_index': False, 02:59:21 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 02:59:21 INFO - 'cmd': ('bash', 02:59:21 INFO - '-c', 02:59:21 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 02:59:21 INFO - 'enabled': True, 02:59:21 INFO - 'halt_on_failure': False, 02:59:21 INFO - 'name': 'check_screen_resolution'},), 02:59:21 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 02:59:21 INFO - 'cmd': ('bash', 02:59:21 INFO - '-c', 02:59:21 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 02:59:21 INFO - 'enabled': True, 02:59:21 INFO - 'halt_on_failure': False, 02:59:21 INFO - 'name': 'check_screen_resolution'},), 02:59:21 INFO - 'run_cmd_checks_enabled': True, 02:59:21 INFO - 'suite': 'dromaeojs-e10s', 02:59:21 INFO - 'system_bits': '32', 02:59:21 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 02:59:21 INFO - 'title': 't-yosemite-r7-0248', 02:59:21 INFO - 'tooltool_cache': '/builds/tooltool_cache', 02:59:21 INFO - 'use_talos_json': True, 02:59:21 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 02:59:21 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 02:59:21 INFO - 'work_dir': 'build'} 02:59:21 INFO - [mozharness: 2017-03-22 09:59:21.731051Z] Running clobber step. 02:59:21 INFO - Running pre-action listener: _resource_record_pre_action 02:59:21 INFO - Running main action method: clobber 02:59:21 INFO - rmtree: /builds/slave/test/build 02:59:21 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 02:59:21 INFO - Running post-action listener: _resource_record_post_action 02:59:21 INFO - [mozharness: 2017-03-22 09:59:21.888158Z] Finished clobber step (success) 02:59:21 INFO - [mozharness: 2017-03-22 09:59:21.888351Z] Running read-buildbot-config step. 02:59:21 INFO - Running pre-action listener: _resource_record_pre_action 02:59:21 INFO - Running main action method: read_buildbot_config 02:59:21 INFO - Using buildbot properties: 02:59:21 INFO - { 02:59:21 INFO - "project": "", 02:59:21 INFO - "product": "firefox", 02:59:21 INFO - "script_repo_revision": "production", 02:59:21 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs-e10s", 02:59:21 INFO - "repository": "", 02:59:21 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs-e10s", 02:59:21 INFO - "buildid": "20170322020638", 02:59:21 INFO - "pgo_build": "False", 02:59:21 INFO - "basedir": "/builds/slave/test", 02:59:21 INFO - "buildnumber": 24, 02:59:21 INFO - "slavename": "t-yosemite-r7-0248", 02:59:21 INFO - "master": "http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/", 02:59:21 INFO - "platform": "yosemite_r7", 02:59:21 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 02:59:21 INFO - "branch": "mozilla-aurora", 02:59:21 INFO - "repo_path": "releases/mozilla-aurora", 02:59:21 INFO - "revision": "f024c36fad38f458a8561d6e245c2936f42215d9", 02:59:21 INFO - "stage_platform": "macosx64", 02:59:21 INFO - "builduid": "5e0be0c06ab7434a9d7dd2237a4e59bf", 02:59:21 INFO - "slavebuilddir": "test" 02:59:21 INFO - } 02:59:21 INFO - Found installer url https://queue.taskcluster.net/v1/task/NF7XhXjMRceWKAJ10VwVOg/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg. 02:59:21 INFO - Running post-action listener: _resource_record_post_action 02:59:21 INFO - [mozharness: 2017-03-22 09:59:21.891767Z] Finished read-buildbot-config step (success) 02:59:21 INFO - [mozharness: 2017-03-22 09:59:21.891917Z] Running download-and-extract step. 02:59:21 INFO - Running pre-action listener: _resource_record_pre_action 02:59:21 INFO - Running main action method: download_and_extract 02:59:21 INFO - mkdir: /builds/slave/test/build/tests 02:59:21 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')]} 02:59:21 INFO - trying https://queue.taskcluster.net/v1/task/NF7XhXjMRceWKAJ10VwVOg/artifacts/public/build/firefox-54.0a2.en-US.mac.test_packages.json 02:59:21 INFO - Downloading https://queue.taskcluster.net/v1/task/NF7XhXjMRceWKAJ10VwVOg/artifacts/public/build/firefox-54.0a2.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-54.0a2.en-US.mac.test_packages.json 02:59:21 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/NF7XhXjMRceWKAJ10VwVOg/artifacts/public/build/firefox-54.0a2.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-54.0a2.en-US.mac.test_packages.json'}, attempt #1 02:59:23 INFO - Downloaded 1199 bytes. 02:59:23 INFO - Reading from file /builds/slave/test/build/firefox-54.0a2.en-US.mac.test_packages.json 02:59:23 INFO - Using the following test package requirements: 02:59:23 INFO - {u'common': [u'firefox-54.0a2.en-US.mac.common.tests.zip'], 02:59:23 INFO - u'cppunittest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 02:59:23 INFO - u'firefox-54.0a2.en-US.mac.cppunittest.tests.zip'], 02:59:23 INFO - u'gtest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 02:59:23 INFO - u'firefox-54.0a2.en-US.mac.gtest.tests.zip'], 02:59:23 INFO - u'jittest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 02:59:23 INFO - u'jsshell-mac.zip'], 02:59:23 INFO - u'mochitest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 02:59:23 INFO - u'firefox-54.0a2.en-US.mac.mochitest.tests.zip'], 02:59:23 INFO - u'mozbase': [u'firefox-54.0a2.en-US.mac.common.tests.zip'], 02:59:23 INFO - u'reftest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 02:59:23 INFO - u'firefox-54.0a2.en-US.mac.reftest.tests.zip'], 02:59:23 INFO - u'talos': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 02:59:23 INFO - u'firefox-54.0a2.en-US.mac.talos.tests.zip'], 02:59:23 INFO - u'web-platform': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 02:59:23 INFO - u'firefox-54.0a2.en-US.mac.web-platform.tests.zip'], 02:59:23 INFO - u'xpcshell': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 02:59:23 INFO - u'firefox-54.0a2.en-US.mac.xpcshell.tests.zip']} 02:59:23 INFO - Downloading packages: [u'firefox-54.0a2.en-US.mac.common.tests.zip'] for test suite category: common 02:59:23 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/NF7XhXjMRceWKAJ10VwVOg/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip 02:59:23 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/NF7XhXjMRceWKAJ10VwVOg/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip'}, attempt #1 02:59:23 INFO - Fetch https://queue.taskcluster.net/v1/task/NF7XhXjMRceWKAJ10VwVOg/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip into memory 02:59:24 INFO - Expected file size: 51782010 02:59:24 INFO - Obtained file size: 51782010 02:59:40 INFO - Downloading packages: [u'firefox-54.0a2.en-US.mac.common.tests.zip', u'firefox-54.0a2.en-US.mac.talos.tests.zip'] for test suite category: talos 02:59:40 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/NF7XhXjMRceWKAJ10VwVOg/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip 02:59:40 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/NF7XhXjMRceWKAJ10VwVOg/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip'}, attempt #1 02:59:40 INFO - Fetch https://queue.taskcluster.net/v1/task/NF7XhXjMRceWKAJ10VwVOg/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip into memory 02:59:42 INFO - Expected file size: 51782010 02:59:42 INFO - Obtained file size: 51782010 02:59:57 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/NF7XhXjMRceWKAJ10VwVOg/artifacts/public/build/firefox-54.0a2.en-US.mac.talos.tests.zip 02:59:57 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/NF7XhXjMRceWKAJ10VwVOg/artifacts/public/build/firefox-54.0a2.en-US.mac.talos.tests.zip'}, attempt #1 02:59:57 INFO - Fetch https://queue.taskcluster.net/v1/task/NF7XhXjMRceWKAJ10VwVOg/artifacts/public/build/firefox-54.0a2.en-US.mac.talos.tests.zip into memory 02:59:58 INFO - Expected file size: 14404438 02:59:58 INFO - Obtained file size: 14404438 02:59:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:59:59 INFO - trying https://queue.taskcluster.net/v1/task/NF7XhXjMRceWKAJ10VwVOg/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg 02:59:59 INFO - Downloading https://queue.taskcluster.net/v1/task/NF7XhXjMRceWKAJ10VwVOg/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-54.0a2.en-US.mac.dmg 02:59:59 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/NF7XhXjMRceWKAJ10VwVOg/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-54.0a2.en-US.mac.dmg'}, attempt #1 03:00:01 INFO - Downloaded 74710343 bytes. 03:00:01 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/NF7XhXjMRceWKAJ10VwVOg/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg 03:00:01 INFO - mkdir: /builds/slave/test/properties 03:00:01 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 03:00:01 INFO - Writing to file /builds/slave/test/properties/build_url 03:00:01 INFO - Contents: 03:00:01 INFO - build_url:https://queue.taskcluster.net/v1/task/NF7XhXjMRceWKAJ10VwVOg/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg 03:00:01 INFO - Running post-action listener: _resource_record_post_action 03:00:01 INFO - Running post-action listener: set_extra_try_arguments 03:00:01 INFO - [mozharness: 2017-03-22 10:00:01.836449Z] Finished download-and-extract step (success) 03:00:01 INFO - [mozharness: 2017-03-22 10:00:01.836568Z] Running populate-webroot step. 03:00:01 INFO - Running pre-action listener: _resource_record_pre_action 03:00:01 INFO - Running main action method: populate_webroot 03:00:01 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 03:00:01 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 03:00:01 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 03:00:01 INFO - '--tppagecycles', 03:00:01 INFO - '7'], 03:00:01 INFO - 'tests': ['tsvgm']}}, 03:00:01 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 03:00:01 INFO - 'tests': ['tresize', 'tcanvasmark']}, 03:00:01 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 03:00:01 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 03:00:01 INFO - 'tests': ['dromaeo_css', 'kraken']}, 03:00:01 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 03:00:01 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 03:00:01 INFO - 'talos_options': ['--disable-e10s'], 03:00:01 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:00:01 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 03:00:01 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:00:01 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 03:00:01 INFO - 'talos_options': ['--disable-e10s'], 03:00:01 INFO - 'tests': ['damp', 'tps']}, 03:00:01 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 03:00:01 INFO - 'tests': ['damp', 'tps']}, 03:00:01 INFO - 'g3': {'talos_options': ['--disable-e10s'], 03:00:01 INFO - 'tests': ['dromaeo_dom']}, 03:00:01 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 03:00:01 INFO - 'g4': {'talos_options': ['--disable-e10s'], 03:00:01 INFO - 'tests': ['basic_compositor_video']}, 03:00:01 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 03:00:01 INFO - 'other': {'talos_options': ['--disable-e10s'], 03:00:01 INFO - 'tests': ['a11yr', 03:00:01 INFO - 'ts_paint', 03:00:01 INFO - 'tpaint', 03:00:01 INFO - 'sessionrestore', 03:00:01 INFO - 'sessionrestore_no_auto_restore', 03:00:01 INFO - 'tabpaint']}, 03:00:01 INFO - 'other-e10s': {'tests': ['a11yr', 03:00:01 INFO - 'ts_paint', 03:00:01 INFO - 'tpaint', 03:00:01 INFO - 'sessionrestore', 03:00:01 INFO - 'sessionrestore_no_auto_restore', 03:00:01 INFO - 'tabpaint']}, 03:00:01 INFO - 'other-e10s_l64': {'tests': ['a11yr', 03:00:01 INFO - 'ts_paint', 03:00:01 INFO - 'tpaint', 03:00:01 INFO - 'sessionrestore', 03:00:01 INFO - 'sessionrestore_no_auto_restore', 03:00:01 INFO - 'tabpaint']}, 03:00:01 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 03:00:01 INFO - 'ts_paint', 03:00:01 INFO - 'tpaint', 03:00:01 INFO - 'sessionrestore', 03:00:01 INFO - 'sessionrestore_no_auto_restore', 03:00:01 INFO - 'tabpaint']}, 03:00:01 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 03:00:01 INFO - 'tests': ['a11yr', 03:00:01 INFO - 'ts_paint', 03:00:01 INFO - 'tpaint', 03:00:01 INFO - 'sessionrestore', 03:00:01 INFO - 'sessionrestore_no_auto_restore', 03:00:01 INFO - 'tabpaint']}, 03:00:01 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 03:00:01 INFO - 'tests': ['a11yr', 03:00:01 INFO - 'ts_paint', 03:00:01 INFO - 'tpaint', 03:00:01 INFO - 'sessionrestore', 03:00:01 INFO - 'sessionrestore_no_auto_restore', 03:00:01 INFO - 'tabpaint']}, 03:00:01 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 03:00:01 INFO - 'tests': ['tsvgx', 03:00:01 INFO - 'tsvgr_opacity', 03:00:01 INFO - 'tart', 03:00:01 INFO - 'tscrollx', 03:00:01 INFO - 'cart', 03:00:01 INFO - 'tsvg_static']}, 03:00:01 INFO - 'svgr-e10s': {'tests': ['tsvgx', 03:00:01 INFO - 'tsvgr_opacity', 03:00:01 INFO - 'tart', 03:00:01 INFO - 'tscrollx', 03:00:01 INFO - 'cart', 03:00:01 INFO - 'tsvg_static']}, 03:00:01 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 03:00:01 INFO - 'talos_options': ['--disable-e10s'], 03:00:01 INFO - 'tests': ['tp5o']}, 03:00:01 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 03:00:01 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 03:00:01 INFO - 'talos_options': ['--disable-e10s', 03:00:01 INFO - '--xperf_path', 03:00:01 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:00:01 INFO - 'tests': ['tp5n']}, 03:00:01 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 03:00:01 INFO - 'talos_options': ['--xperf_path', 03:00:01 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:00:01 INFO - 'tests': ['tp5n']}}, 03:00:01 INFO - 'talos.zip': {'path': '', 03:00:01 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 03:00:01 INFO - Running post-action listener: _resource_record_post_action 03:00:01 INFO - [mozharness: 2017-03-22 10:00:01.855125Z] Finished populate-webroot step (success) 03:00:01 INFO - [mozharness: 2017-03-22 10:00:01.855252Z] Running create-virtualenv step. 03:00:01 INFO - Running pre-action listener: _resource_record_pre_action 03:00:01 INFO - Running main action method: create_virtualenv 03:00:01 INFO - Creating virtualenv /builds/slave/test/build/venv 03:00:01 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 03:00:01 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 03:00:01 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 03:00:01 INFO - Using real prefix '/tools/python27' 03:00:01 INFO - New python executable in /builds/slave/test/build/venv/bin/python 03:00:02 INFO - Installing distribute.............................................................................................................................................................................................done. 03:00:05 INFO - Installing pip.................done. 03:00:05 INFO - Return code: 0 03:00:05 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 03:00:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 03:00:05 INFO - Reading from file tmpfile_stdout 03:00:05 INFO - Output received: 03:00:05 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) 03:00:05 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 03:00: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')]} 03:00:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:00:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:00: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')]} 03:00:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:00:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:00: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', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x107a9ab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107a9d768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8dcbdcea50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107878d30>, '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 0x10779fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10786dbe8>, '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.KNRQsa1F3W/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.mXFqTmDUa3/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/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 03:00: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', 'pip>=1.5'] in /builds/slave/test/build 03:00: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 pip>=1.5 03:00:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.KNRQsa1F3W/Render', 03:00:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:00:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:00:05 INFO - 'HOME': '/Users/cltbld', 03:00:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:00:05 INFO - 'LOGNAME': 'cltbld', 03:00:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:00:05 INFO - 'MOZ_NO_REMOTE': '1', 03:00:05 INFO - 'NO_EM_RESTART': '1', 03:00:05 INFO - 'PAGER': '/bin/cat', 03:00:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:00:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:00:05 INFO - 'PWD': '/builds/slave/test', 03:00:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:00:05 INFO - 'SHELL': '/bin/bash', 03:00:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mXFqTmDUa3/Listeners', 03:00:05 INFO - 'TMPDIR': '/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/T/', 03:00:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:00:05 INFO - 'USER': 'cltbld', 03:00:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:00:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:00:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:00:05 INFO - 'XPC_FLAGS': '0x0', 03:00:05 INFO - 'XPC_SERVICE_NAME': '0', 03:00:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:00:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:00:06 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 03:00:06 INFO - Cleaning up... 03:00:06 INFO - Return code: 0 03:00:06 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 03:00:06 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')]} 03:00:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:00:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:00:06 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')]} 03:00:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:00:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:00:06 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 0x107a9ab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107a9d768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8dcbdcea50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107878d30>, '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 0x10779fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10786dbe8>, '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.KNRQsa1F3W/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.mXFqTmDUa3/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/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 03:00:06 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 03:00:06 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 03:00:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.KNRQsa1F3W/Render', 03:00:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:00:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:00:06 INFO - 'HOME': '/Users/cltbld', 03:00:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:00:06 INFO - 'LOGNAME': 'cltbld', 03:00:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:00:06 INFO - 'MOZ_NO_REMOTE': '1', 03:00:06 INFO - 'NO_EM_RESTART': '1', 03:00:06 INFO - 'PAGER': '/bin/cat', 03:00:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:00:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:00:06 INFO - 'PWD': '/builds/slave/test', 03:00:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:00:06 INFO - 'SHELL': '/bin/bash', 03:00:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mXFqTmDUa3/Listeners', 03:00:06 INFO - 'TMPDIR': '/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/T/', 03:00:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:00:06 INFO - 'USER': 'cltbld', 03:00:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:00:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:00:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:00:06 INFO - 'XPC_FLAGS': '0x0', 03:00:06 INFO - 'XPC_SERVICE_NAME': '0', 03:00:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:00:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:00:06 INFO - Downloading/unpacking psutil>=3.1.1 03:00:06 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 03:00:06 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 03:00:06 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 03:00:06 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 03:00:06 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 03:00:06 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 03:00:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 03:00:09 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:00:09 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:00:09 INFO - Installing collected packages: psutil 03:00:09 INFO - Running setup.py install for psutil 03:00:10 INFO - building 'psutil._psutil_osx' extension 03:00:10 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 03:00:10 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 03:00:10 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 03:00:10 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 03:00:10 INFO - building 'psutil._psutil_posix' extension 03:00:10 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 03:00:10 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 03:00:10 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 03:00:10 INFO - ^ 03:00:10 INFO - 1 warning generated. 03:00:10 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 03:00:10 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:00:10 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:00:10 INFO - Successfully installed psutil 03:00:10 INFO - Cleaning up... 03:00:10 INFO - Return code: 0 03:00:10 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 03:00:10 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')]} 03:00:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:00:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:00:10 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')]} 03:00:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:00:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:00:10 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 0x107a9ab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107a9d768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8dcbdcea50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107878d30>, '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 0x10779fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10786dbe8>, '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.KNRQsa1F3W/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.mXFqTmDUa3/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/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 03:00:10 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 03:00:10 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 03:00:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.KNRQsa1F3W/Render', 03:00:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:00:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:00:10 INFO - 'HOME': '/Users/cltbld', 03:00:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:00:10 INFO - 'LOGNAME': 'cltbld', 03:00:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:00:10 INFO - 'MOZ_NO_REMOTE': '1', 03:00:10 INFO - 'NO_EM_RESTART': '1', 03:00:10 INFO - 'PAGER': '/bin/cat', 03:00:10 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:00:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:00:10 INFO - 'PWD': '/builds/slave/test', 03:00:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:00:10 INFO - 'SHELL': '/bin/bash', 03:00:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mXFqTmDUa3/Listeners', 03:00:10 INFO - 'TMPDIR': '/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/T/', 03:00:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:00:10 INFO - 'USER': 'cltbld', 03:00:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:00:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:00:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:00:10 INFO - 'XPC_FLAGS': '0x0', 03:00:10 INFO - 'XPC_SERVICE_NAME': '0', 03:00:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:00:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:00:11 INFO - Downloading/unpacking mozsystemmonitor==0.3 03:00: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 03:00: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 03:00: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 03:00: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 03:00: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 03:00: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 03:00:13 INFO - Downloading mozsystemmonitor-0.3.tar.gz 03:00:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 03:00:13 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 03:00:13 INFO - Installing collected packages: mozsystemmonitor 03:00:13 INFO - Running setup.py install for mozsystemmonitor 03:00:13 INFO - Successfully installed mozsystemmonitor 03:00:13 INFO - Cleaning up... 03:00:14 INFO - Return code: 0 03:00:14 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 03:00:14 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')]} 03:00:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:00:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:00:14 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')]} 03:00:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:00:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:00:15 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 0x107a9ab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107a9d768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8dcbdcea50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107878d30>, '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 0x10779fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10786dbe8>, '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.KNRQsa1F3W/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.mXFqTmDUa3/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/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 03:00:15 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 03:00:15 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 03:00:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.KNRQsa1F3W/Render', 03:00:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:00:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:00:15 INFO - 'HOME': '/Users/cltbld', 03:00:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:00:15 INFO - 'LOGNAME': 'cltbld', 03:00:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:00:15 INFO - 'MOZ_NO_REMOTE': '1', 03:00:15 INFO - 'NO_EM_RESTART': '1', 03:00:15 INFO - 'PAGER': '/bin/cat', 03:00:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:00:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:00:15 INFO - 'PWD': '/builds/slave/test', 03:00:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:00:15 INFO - 'SHELL': '/bin/bash', 03:00:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mXFqTmDUa3/Listeners', 03:00:15 INFO - 'TMPDIR': '/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/T/', 03:00:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:00:15 INFO - 'USER': 'cltbld', 03:00:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:00:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:00:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:00:15 INFO - 'XPC_FLAGS': '0x0', 03:00:15 INFO - 'XPC_SERVICE_NAME': '0', 03:00:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:00:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:00:15 INFO - Downloading/unpacking jsonschema==2.5.1 03:00: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 03:00: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 03:00: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 03:00: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 03:00: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 03:00: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 03:00:18 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 03:00:18 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 03:00:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:00:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:00:18 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:00:18 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:00:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:00:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:00:18 INFO - Downloading functools32-3.2.3-2.tar.gz 03:00:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 03:00:18 INFO - warning: no files found matching '*.txt' 03:00:18 INFO - no previously-included directories found matching 'build' 03:00:18 INFO - no previously-included directories found matching 'dist' 03:00:18 INFO - no previously-included directories found matching '.git*' 03:00:18 INFO - Installing collected packages: jsonschema, functools32 03:00:18 INFO - Running setup.py install for functools32 03:00:19 INFO - warning: no files found matching '*.txt' 03:00:19 INFO - no previously-included directories found matching 'build' 03:00:19 INFO - no previously-included directories found matching 'dist' 03:00:19 INFO - no previously-included directories found matching '.git*' 03:00:19 INFO - Successfully installed jsonschema functools32 03:00:19 INFO - Cleaning up... 03:00:19 INFO - Return code: 0 03:00:19 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 03:00:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:00:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:00:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:00:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:00:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:00:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:00:19 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x107a9ab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107a9d768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8dcbdcea50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107878d30>, '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 0x10779fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10786dbe8>, '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.KNRQsa1F3W/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.mXFqTmDUa3/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/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 03:00:19 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 03:00:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 03:00:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.KNRQsa1F3W/Render', 03:00:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:00:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:00:19 INFO - 'HOME': '/Users/cltbld', 03:00:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:00:19 INFO - 'LOGNAME': 'cltbld', 03:00:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:00:19 INFO - 'MOZ_NO_REMOTE': '1', 03:00:19 INFO - 'NO_EM_RESTART': '1', 03:00:19 INFO - 'PAGER': '/bin/cat', 03:00:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:00:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:00:19 INFO - 'PWD': '/builds/slave/test', 03:00:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:00:19 INFO - 'SHELL': '/bin/bash', 03:00:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mXFqTmDUa3/Listeners', 03:00:19 INFO - 'TMPDIR': '/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/T/', 03:00:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:00:19 INFO - 'USER': 'cltbld', 03:00:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:00:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:00:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:00:19 INFO - 'XPC_FLAGS': '0x0', 03:00:19 INFO - 'XPC_SERVICE_NAME': '0', 03:00:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:00:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:00:19 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 03:00:19 INFO - Cleaning up... 03:00:19 INFO - Return code: 0 03:00:19 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 03:00:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:00:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:00:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:00:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:00:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:00:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:00:19 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x107a9ab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107a9d768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8dcbdcea50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107878d30>, '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 0x10779fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10786dbe8>, '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.KNRQsa1F3W/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.mXFqTmDUa3/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/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 03:00:19 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 03:00:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 03:00:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.KNRQsa1F3W/Render', 03:00:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:00:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:00:19 INFO - 'HOME': '/Users/cltbld', 03:00:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:00:19 INFO - 'LOGNAME': 'cltbld', 03:00:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:00:19 INFO - 'MOZ_NO_REMOTE': '1', 03:00:19 INFO - 'NO_EM_RESTART': '1', 03:00:19 INFO - 'PAGER': '/bin/cat', 03:00:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:00:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:00:19 INFO - 'PWD': '/builds/slave/test', 03:00:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:00:19 INFO - 'SHELL': '/bin/bash', 03:00:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mXFqTmDUa3/Listeners', 03:00:19 INFO - 'TMPDIR': '/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/T/', 03:00:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:00:19 INFO - 'USER': 'cltbld', 03:00:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:00:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:00:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:00:19 INFO - 'XPC_FLAGS': '0x0', 03:00:19 INFO - 'XPC_SERVICE_NAME': '0', 03:00:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:00:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:00:19 INFO - Downloading/unpacking blobuploader==1.2.4 03:00:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:00:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:00:19 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:00:19 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:00:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:00:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:00:22 INFO - Downloading blobuploader-1.2.4.tar.gz 03:00:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 03:00:22 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 03:00:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:00:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:00:22 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:00:22 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:00:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:00:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:00:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 03:00:23 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 03:00:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:00:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:00:23 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:00:23 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:00:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:00:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:00:23 INFO - Downloading docopt-0.6.1.tar.gz 03:00:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 03:00:23 INFO - Installing collected packages: blobuploader, requests, docopt 03:00:23 INFO - Running setup.py install for blobuploader 03:00:23 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 03:00:23 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 03:00:23 INFO - Running setup.py install for requests 03:00:24 INFO - Running setup.py install for docopt 03:00:24 INFO - Successfully installed blobuploader requests docopt 03:00:24 INFO - Cleaning up... 03:00:24 INFO - Return code: 0 03:00:24 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:00:24 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')]} 03:00:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:00:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:00:24 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')]} 03:00:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:00:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:00:24 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 0x107a9ab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107a9d768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8dcbdcea50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107878d30>, '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 0x10779fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10786dbe8>, '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.KNRQsa1F3W/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.mXFqTmDUa3/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/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 03:00:24 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 03:00:24 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 03:00:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.KNRQsa1F3W/Render', 03:00:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:00:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:00:24 INFO - 'HOME': '/Users/cltbld', 03:00:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:00:24 INFO - 'LOGNAME': 'cltbld', 03:00:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:00:24 INFO - 'MOZ_NO_REMOTE': '1', 03:00:24 INFO - 'NO_EM_RESTART': '1', 03:00:24 INFO - 'PAGER': '/bin/cat', 03:00:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:00:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:00:24 INFO - 'PWD': '/builds/slave/test', 03:00:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:00:24 INFO - 'SHELL': '/bin/bash', 03:00:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mXFqTmDUa3/Listeners', 03:00:24 INFO - 'TMPDIR': '/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/T/', 03:00:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:00:24 INFO - 'USER': 'cltbld', 03:00:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:00:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:00:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:00:24 INFO - 'XPC_FLAGS': '0x0', 03:00:24 INFO - 'XPC_SERVICE_NAME': '0', 03:00:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:00:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:00:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:00:24 INFO - Running setup.py (path:/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/T/pip-oiZKRc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:00:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:00:24 INFO - Running setup.py (path:/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/T/pip-jQWwz5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:00:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:00:24 INFO - Running setup.py (path:/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/T/pip-EG7Lr4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:00:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:00:25 INFO - Running setup.py (path:/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/T/pip-E0o0Z1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:00:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:00:25 INFO - Running setup.py (path:/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/T/pip-DOKoAg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:00:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:00:25 INFO - Running setup.py (path:/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/T/pip-0EHpZA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:00:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:00:25 INFO - Running setup.py (path:/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/T/pip-xdpPqR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:00:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:00:25 INFO - Running setup.py (path:/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/T/pip-DQxTWn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:00:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:00:25 INFO - Running setup.py (path:/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/T/pip-qoZdWx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:00:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:00:25 INFO - Running setup.py (path:/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/T/pip-9UsDmp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:00:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:00:25 INFO - Running setup.py (path:/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/T/pip-Pzpxgs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:00:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:00:25 INFO - Running setup.py (path:/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/T/pip-ADBi57-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:00:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:00:26 INFO - Running setup.py (path:/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/T/pip-cDU4hS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:00:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:00:26 INFO - Running setup.py (path:/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/T/pip-MLDm97-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:00:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:00:26 INFO - Running setup.py (path:/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/T/pip-GIm2ws-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:00:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:00:26 INFO - Running setup.py (path:/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/T/pip-CcvE6c-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:00:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:00:26 INFO - Running setup.py (path:/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/T/pip-_1cQp4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:00:26 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 03:00:26 INFO - Running setup.py install for manifestparser 03:00:26 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 03:00:26 INFO - Running setup.py install for mozcrash 03:00:26 INFO - Running setup.py install for mozdebug 03:00:26 INFO - Running setup.py install for mozdevice 03:00:27 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 03:00:27 INFO - Installing dm script to /builds/slave/test/build/venv/bin 03:00:27 INFO - Running setup.py install for mozfile 03:00:27 INFO - Running setup.py install for mozhttpd 03:00:27 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 03:00:27 INFO - Running setup.py install for mozinfo 03:00:27 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 03:00:27 INFO - Running setup.py install for mozInstall 03:00:27 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 03:00:27 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 03:00:27 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 03:00:27 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 03:00:27 INFO - Running setup.py install for mozleak 03:00:27 INFO - Running setup.py install for mozlog 03:00:28 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 03:00:28 INFO - Running setup.py install for moznetwork 03:00:28 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 03:00:28 INFO - Running setup.py install for mozprocess 03:00:28 INFO - Running setup.py install for mozprofile 03:00:28 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 03:00:28 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 03:00:28 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 03:00:28 INFO - Running setup.py install for mozrunner 03:00:28 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 03:00:28 INFO - Running setup.py install for mozscreenshot 03:00:28 INFO - Running setup.py install for moztest 03:00:29 INFO - Running setup.py install for mozversion 03:00:29 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 03:00:29 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 03:00:29 INFO - Cleaning up... 03:00:29 INFO - Return code: 0 03:00:29 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:00:29 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')]} 03:00:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:00:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:00:29 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')]} 03:00:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:00:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:00:29 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 0x107a9ab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107a9d768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8dcbdcea50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107878d30>, '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 0x10779fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10786dbe8>, '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.KNRQsa1F3W/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.mXFqTmDUa3/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/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 03:00:29 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 03:00:29 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 03:00:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.KNRQsa1F3W/Render', 03:00:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:00:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:00:29 INFO - 'HOME': '/Users/cltbld', 03:00:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:00:29 INFO - 'LOGNAME': 'cltbld', 03:00:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:00:29 INFO - 'MOZ_NO_REMOTE': '1', 03:00:29 INFO - 'NO_EM_RESTART': '1', 03:00:29 INFO - 'PAGER': '/bin/cat', 03:00:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:00:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:00:29 INFO - 'PWD': '/builds/slave/test', 03:00:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:00:29 INFO - 'SHELL': '/bin/bash', 03:00:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mXFqTmDUa3/Listeners', 03:00:29 INFO - 'TMPDIR': '/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/T/', 03:00:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:00:29 INFO - 'USER': 'cltbld', 03:00:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:00:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:00:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:00:29 INFO - 'XPC_FLAGS': '0x0', 03:00:29 INFO - 'XPC_SERVICE_NAME': '0', 03:00:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:00:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:00:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:00:29 INFO - Running setup.py (path:/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/T/pip-XVHQ95-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:00:29 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)) 03:00:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:00:29 INFO - Running setup.py (path:/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/T/pip-EdyDg7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:00:29 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)) 03:00:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:00:29 INFO - Running setup.py (path:/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/T/pip-9Pghws-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:00:29 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)) 03:00:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:00:29 INFO - Running setup.py (path:/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/T/pip-vy1vot-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:00:29 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)) 03:00:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:00:29 INFO - Running setup.py (path:/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/T/pip-KlgqmM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:00:30 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)) 03:00:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:00:30 INFO - Running setup.py (path:/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/T/pip-S8xnO8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:00:30 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)) 03:00:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:00:30 INFO - Running setup.py (path:/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/T/pip-pq8RQ5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:00:30 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)) 03:00:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:00:30 INFO - Running setup.py (path:/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/T/pip-dsAFUf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:00:30 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)) 03:00:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:00:30 INFO - Running setup.py (path:/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/T/pip-dCaHL2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:00:30 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)) 03:00:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:00:30 INFO - Running setup.py (path:/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/T/pip-XynMW4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:00:30 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)) 03:00:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:00:30 INFO - Running setup.py (path:/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/T/pip-WEwED6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:00:30 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)) 03:00:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:00:30 INFO - Running setup.py (path:/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/T/pip-AjcGKg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:00:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 03:00:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:00:30 INFO - Running setup.py (path:/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/T/pip-ifrUSk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:00:30 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)) 03:00:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:00:30 INFO - Running setup.py (path:/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/T/pip-VQygOY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:00:31 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)) 03:00:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:00:31 INFO - Running setup.py (path:/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/T/pip-XtuguX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:00:31 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)) 03:00:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:00:31 INFO - Running setup.py (path:/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/T/pip-P5jxcD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:00:31 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)) 03:00:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:00:31 INFO - Running setup.py (path:/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/T/pip-W4m_g3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:00:31 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)) 03:00:31 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)) 03:00:31 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)) 03:00:31 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)) 03:00:31 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)) 03:00:31 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)) 03:00:31 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 03:00:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:00:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:00:31 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:00:31 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:00:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:00:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:00:33 INFO - Downloading blessings-1.6.tar.gz 03:00:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 03:00:34 INFO - Installing collected packages: blessings 03:00:34 INFO - Running setup.py install for blessings 03:00:34 INFO - Successfully installed blessings 03:00:34 INFO - Cleaning up... 03:00:34 INFO - Return code: 0 03:00:34 INFO - Done creating virtualenv /builds/slave/test/build/venv. 03:00:34 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:00:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:00:34 INFO - Reading from file tmpfile_stdout 03:00:34 INFO - Current package versions: 03:00:34 INFO - blessings == 1.6 03:00:34 INFO - blobuploader == 1.2.4 03:00:34 INFO - docopt == 0.6.1 03:00:34 INFO - functools32 == 3.2.3-2 03:00:34 INFO - jsonschema == 2.5.1 03:00:34 INFO - manifestparser == 1.1 03:00:34 INFO - mozInstall == 1.12 03:00:34 INFO - mozcrash == 1.0 03:00:34 INFO - mozdebug == 0.1 03:00:34 INFO - mozdevice == 0.49 03:00:34 INFO - mozfile == 1.2 03:00:34 INFO - mozhttpd == 0.7 03:00:34 INFO - mozinfo == 0.9 03:00:34 INFO - mozleak == 0.1 03:00:34 INFO - mozlog == 3.4 03:00:34 INFO - moznetwork == 0.27 03:00:34 INFO - mozprocess == 0.25 03:00:34 INFO - mozprofile == 0.28 03:00:34 INFO - mozrunner == 6.13 03:00:34 INFO - mozscreenshot == 0.1 03:00:34 INFO - mozsystemmonitor == 0.3 03:00:34 INFO - moztest == 0.8 03:00:34 INFO - mozversion == 1.4 03:00:34 INFO - psutil == 3.1.1 03:00:34 INFO - requests == 1.2.3 03:00:34 INFO - wsgiref == 0.1.2 03:00:34 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:00:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:00:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:00:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:00:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:00:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:00:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:00:34 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 0x107a9ab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107a9d768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8dcbdcea50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107878d30>, '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 0x10779fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10786dbe8>, '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.KNRQsa1F3W/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.mXFqTmDUa3/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/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 03:00:34 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 03:00:34 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 03:00:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.KNRQsa1F3W/Render', 03:00:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:00:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:00:34 INFO - 'HOME': '/Users/cltbld', 03:00:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:00:34 INFO - 'LOGNAME': 'cltbld', 03:00:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:00:34 INFO - 'MOZ_NO_REMOTE': '1', 03:00:34 INFO - 'NO_EM_RESTART': '1', 03:00:34 INFO - 'PAGER': '/bin/cat', 03:00:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:00:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:00:34 INFO - 'PWD': '/builds/slave/test', 03:00:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:00:34 INFO - 'SHELL': '/bin/bash', 03:00:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mXFqTmDUa3/Listeners', 03:00:34 INFO - 'TMPDIR': '/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/T/', 03:00:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:00:34 INFO - 'USER': 'cltbld', 03:00:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:00:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:00:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:00:34 INFO - 'XPC_FLAGS': '0x0', 03:00:34 INFO - 'XPC_SERVICE_NAME': '0', 03:00:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:00:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:00:34 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)) 03:00:34 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)) 03:00:34 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)) 03:00:34 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)) 03:00:34 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)) 03:00:34 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)) 03:00:34 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)) 03:00:34 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)) 03:00:34 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)) 03:00:34 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)) 03:00:34 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)) 03:00:34 INFO - Cleaning up... 03:00:34 INFO - Return code: 0 03:00:34 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 03:00:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:00:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:00:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:00:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:00:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:00:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:00:34 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x107a9ab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107a9d768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8dcbdcea50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107878d30>, '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 0x10779fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10786dbe8>, '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.KNRQsa1F3W/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.mXFqTmDUa3/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/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 03:00:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 03:00:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 03:00:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.KNRQsa1F3W/Render', 03:00:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:00:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:00:34 INFO - 'HOME': '/Users/cltbld', 03:00:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:00:34 INFO - 'LOGNAME': 'cltbld', 03:00:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:00:34 INFO - 'MOZ_NO_REMOTE': '1', 03:00:34 INFO - 'NO_EM_RESTART': '1', 03:00:34 INFO - 'PAGER': '/bin/cat', 03:00:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:00:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:00:34 INFO - 'PWD': '/builds/slave/test', 03:00:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:00:34 INFO - 'SHELL': '/bin/bash', 03:00:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mXFqTmDUa3/Listeners', 03:00:34 INFO - 'TMPDIR': '/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/T/', 03:00:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:00:34 INFO - 'USER': 'cltbld', 03:00:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:00:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:00:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:00:34 INFO - 'XPC_FLAGS': '0x0', 03:00:34 INFO - 'XPC_SERVICE_NAME': '0', 03:00:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:00:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:00:35 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 03:00:35 INFO - Cleaning up... 03:00:35 INFO - Return code: 0 03:00:35 INFO - Running post-action listener: _resource_record_post_action 03:00:35 INFO - Running post-action listener: _start_resource_monitoring 03:00:35 INFO - Starting resource monitoring. 03:00:35 INFO - [mozharness: 2017-03-22 10:00:35.232717Z] Finished create-virtualenv step (success) 03:00:35 INFO - [mozharness: 2017-03-22 10:00:35.233718Z] Running install step. 03:00:35 INFO - Running pre-action listener: _resource_record_pre_action 03:00:35 INFO - Running main action method: install 03:00:35 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:00:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:00:35 INFO - Reading from file tmpfile_stdout 03:00:35 INFO - Detecting whether we're running mozinstall >=1.0... 03:00:35 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 03:00:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 03:00:35 INFO - Reading from file tmpfile_stdout 03:00:35 INFO - Output received: 03:00:35 INFO - Usage: mozinstall [options] installer 03:00:35 INFO - Options: 03:00:35 INFO - -h, --help show this help message and exit 03:00:35 INFO - -d DEST, --destination=DEST 03:00:35 INFO - Directory to install application into. [default: 03:00:35 INFO - "/builds/slave/test"] 03:00:35 INFO - --app=APP Application being installed. [default: firefox] 03:00:35 INFO - mkdir: /builds/slave/test/build/application 03:00:35 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-54.0a2.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 03:00:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-54.0a2.en-US.mac.dmg --destination /builds/slave/test/build/application 03:00:55 INFO - Reading from file tmpfile_stdout 03:00:55 INFO - Output received: 03:00:55 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 03:00:55 INFO - Running post-action listener: _resource_record_post_action 03:00:55 INFO - [mozharness: 2017-03-22 10:00:55.881315Z] Finished install step (success) 03:00:55 INFO - [mozharness: 2017-03-22 10:00:55.881470Z] Running run-tests step. 03:00:55 INFO - Running pre-action listener: _resource_record_pre_action 03:00:55 INFO - Running main action method: run_tests 03:00:55 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 03:00:55 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 03:00:55 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 03:00:55 INFO - 2017-03-22 03:00:55.896 screenresolution[1882:8993] starting screenresolution argv=screenresolution get 03:00:55 INFO - 2017-03-22 03:00:55.899 screenresolution[1882:8993] Display 0: 1600x1200x32@60 03:00:55 INFO - 2017-03-22 03:00:55.904 screenresolution[1883:8996] starting screenresolution argv=screenresolution list 03:00:55 INFO - Available Modes on Display 0 03:00:55 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 03:00:55 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 03:00:55 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 03:00:55 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 03:00:56 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 03:00:56 INFO - Intel Iris: 03:00:56 INFO - Chipset Model: Intel Iris 03:00:56 INFO - Type: GPU 03:00:56 INFO - Bus: Built-In 03:00:56 INFO - VRAM (Dynamic, Max): 1536 MB 03:00:56 INFO - Vendor: Intel (0x8086) 03:00:56 INFO - Device ID: 0x0a2e 03:00:56 INFO - Revision ID: 0x0009 03:00:56 INFO - Displays: 03:00:56 INFO - Display: 03:00:56 INFO - Resolution: 1600 x 1200 @ 60 Hz 03:00:56 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 03:00:56 INFO - Display Serial Number: 1600x1200 60 03:00:56 INFO - Main Display: Yes 03:00:56 INFO - Mirror: Off 03:00:56 INFO - Online: Yes 03:00:56 INFO - Rotation: Supported 03:00:56 INFO - Return code: 0 03:00:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 03:00:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 03:00:56 INFO - Python 2.7.3 03:00:56 INFO - Return code: 0 03:00:56 INFO - grabbing minidump binary from tooltool 03:00:56 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')]} 03:00:56 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 0x107878d30>, '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 0x10779fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10786dbe8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 03:00:56 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 03:00:56 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 03:00:56 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 03:00:56 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 03:00:56 INFO - Return code: 0 03:00:56 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 03:00:56 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 03:00:56 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 03:00:56 INFO - ENV: RUST_BACKTRACE is now 1 03:00:56 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 03:00:56 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 03:00:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/NF7XhXjMRceWKAJ10VwVOg/artifacts/public/build/firefox-54.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0248', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 03:00:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/NF7XhXjMRceWKAJ10VwVOg/artifacts/public/build/firefox-54.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0248 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 03:00:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.KNRQsa1F3W/Render', 03:00:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:00:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:00:56 INFO - 'HOME': '/Users/cltbld', 03:00:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:00:56 INFO - 'LOGNAME': 'cltbld', 03:00:56 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 03:00:56 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 03:00:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:00:56 INFO - 'MOZ_NO_REMOTE': '1', 03:00:56 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 03:00:56 INFO - 'NO_EM_RESTART': '1', 03:00:56 INFO - 'PAGER': '/bin/cat', 03:00:56 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:00:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:00:56 INFO - 'PWD': '/builds/slave/test', 03:00:56 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 03:00:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:00:56 INFO - 'RUST_BACKTRACE': '1', 03:00:56 INFO - 'SHELL': '/bin/bash', 03:00:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mXFqTmDUa3/Listeners', 03:00:56 INFO - 'TMPDIR': '/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/T/', 03:00:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:00:56 INFO - 'USER': 'cltbld', 03:00:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:00:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:00:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:00:56 INFO - 'XPC_FLAGS': '0x0', 03:00:56 INFO - 'XPC_SERVICE_NAME': '0', 03:00:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:00:56 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/NF7XhXjMRceWKAJ10VwVOg/artifacts/public/build/firefox-54.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0248', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 03:00:56 INFO - mozversion application_buildid: 20170322020638 03:00:56 INFO - mozversion application_changeset: f024c36fad38f458a8561d6e245c2936f42215d9 03:00:56 INFO - mozversion application_display_name: FirefoxDeveloperEdition 03:00:56 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 03:00:56 INFO - mozversion application_name: Firefox 03:00:56 INFO - mozversion application_remotingname: firefox-dev 03:00:56 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 03:00:56 INFO - mozversion application_vendor: Mozilla 03:00:56 INFO - mozversion application_version: 54.0a2 03:00:56 INFO - mozversion platform_buildid: 20170322020638 03:00:56 INFO - mozversion platform_changeset: f024c36fad38f458a8561d6e245c2936f42215d9 03:00:56 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 03:00:56 INFO - mozversion platform_version: 54.0a2 03:00:56 INFO - using testdate: 1490176856 03:00:56 INFO - actual date: 1490176856 03:00:56 INFO - starting webserver on 'localhost:49373' 03:00:56 INFO - SUITE-START | Running 2 tests 03:00:56 INFO - TEST-START | dromaeo_css 03:00:56 INFO - operating with platform_type : mac_ 03:00:56 INFO - Initialising browser for dromaeo_css test... 03:00:56 INFO - TEST-INFO | started process 1893 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/T/tmpC1vrmm/profile http://localhost:49373/getInfo.html) 03:00:59 INFO - PROCESS | 1893 | 2017-03-22 03:00:59.463 plugin-container[1895:9159] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4b4b, name = 'com.apple.tsm.portname' 03:00:59 INFO - PROCESS | 1893 | See /usr/include/servers/bootstrap_defs.h for the error codes. 03:00:59 INFO - PROCESS | 1893 | 2017-03-22 03:00:59.465 plugin-container[1895:9159] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x960b, name = 'com.apple.CFPasteboardClient' 03:00:59 INFO - PROCESS | 1893 | See /usr/include/servers/bootstrap_defs.h for the error codes. 03:00:59 INFO - PROCESS | 1893 | 2017-03-22 03:00:59.466 plugin-container[1895:9159] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 03:00:59 INFO - PROCESS | 1893 | __metrics Screen width/height:1600/1200 03:00:59 INFO - PROCESS | 1893 | colorDepth:24 03:00:59 INFO - PROCESS | 1893 | Browser inner width/height: 1024/689 03:00:59 INFO - PROCESS | 1893 | __metrics 03:00:59 ERROR - PROCESS | 1893 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3353: TypeError: this.tabs is undefined 03:01:00 INFO - TEST-INFO | 1893: exit 0 03:01:00 INFO - Browser initialized. 03:01:00 INFO - Running cycle 1/1 for dromaeo_css test... 03:01:00 INFO - TEST-INFO | started process 1896 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/T/tmpC1vrmm/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 03:01:03 INFO - PROCESS | 1896 | 2017-03-22 03:01:03.320 plugin-container[1897:9284] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4747, name = 'com.apple.tsm.portname' 03:01:03 INFO - PROCESS | 1896 | See /usr/include/servers/bootstrap_defs.h for the error codes. 03:01:03 INFO - PROCESS | 1896 | 2017-03-22 03:01:03.320 plugin-container[1897:9284] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x900b, name = 'com.apple.CFPasteboardClient' 03:01:03 INFO - PROCESS | 1896 | See /usr/include/servers/bootstrap_defs.h for the error codes. 03:01:03 INFO - PROCESS | 1896 | 2017-03-22 03:01:03.320 plugin-container[1897:9284] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 03:01:12 INFO - PROCESS | 1896 | RSS: Main: 220893184 03:01:12 INFO - PROCESS | 1896 | 03:04:16 INFO - PROCESS | 1896 | Cycle 1(1): loaded http://localhost:49373/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49373/tests/dromaeo/cssquery-ext.html) 03:04:18 INFO - PROCESS | 1896 | RSS: Main: 238387200 03:04:18 INFO - PROCESS | 1896 | 03:06:00 INFO - PROCESS | 1896 | ************************* 03:06:00 INFO - PROCESS | 1896 | A coding exception was thrown and uncaught in a Task. 03:06:00 INFO - PROCESS | 1896 | 03:06:00 ERROR - PROCESS | 1896 | Full message: TypeError: NetworkError when attempting to fetch resource. 03:06:00 INFO - PROCESS | 1896 | Full stack: 03:06:00 INFO - PROCESS | 1896 | ************************* 03:07:20 INFO - PROCESS | 1896 | Cycle 1(1): loaded http://localhost:49373/tests/dromaeo/cssquery-ext.html (next: http://localhost:49373/tests/dromaeo/cssquery-jquery.html) 03:07:21 INFO - PROCESS | 1896 | RSS: Main: 248586240 03:07:21 INFO - PROCESS | 1896 | 03:10:23 INFO - PROCESS | 1896 | Cycle 1(1): loaded http://localhost:49373/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49373/tests/dromaeo/cssquery-mootools.html) 03:10:24 INFO - PROCESS | 1896 | RSS: Main: 255971328 03:10:24 INFO - PROCESS | 1896 | 03:13:26 INFO - PROCESS | 1896 | Cycle 1(1): loaded http://localhost:49373/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49373/tests/dromaeo/cssquery-prototype.html) 03:13:26 INFO - PROCESS | 1896 | RSS: Main: 253120512 03:13:26 INFO - PROCESS | 1896 | 03:16:29 INFO - PROCESS | 1896 | Cycle 1(1): loaded http://localhost:49373/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49373/tests/dromaeo/cssquery-yui.html) 03:16:30 INFO - PROCESS | 1896 | RSS: Main: 258244608 03:16:30 INFO - PROCESS | 1896 | 03:19:33 INFO - PROCESS | 1896 | Cycle 1(1): loaded http://localhost:49373/tests/dromaeo/cssquery-yui.html (next: http://localhost:49373/tests/dromaeo/cssquery-dojo.html) 03:19:33 INFO - PROCESS | 1896 | RSS: Main: 254312448 03:19:33 INFO - PROCESS | 1896 | 03:19:33 INFO - PROCESS | 1896 | __start_tp_report 03:19:33 INFO - PROCESS | 1896 | _x_x_mozilla_page_load 03:19:33 INFO - PROCESS | 1896 | _x_x_mozilla_page_load_details 03:19:33 INFO - PROCESS | 1896 | |i|pagename|runs| 03:19:33 INFO - PROCESS | 1896 | |0;dojo.html;3464.3764122457956;3471.87848425305;3478.8608455661774;3485.6165821759587;3516.2088530080737;5989.88020239595;6033.818985430438;6077.3922607739205;6106.511479081674;6126.23422072241;6086;6135.72389242484;6153.969230153843;6157.722902469389;6162.198914141155;5479.506844384005;5521.972390138044;5538.196961440589;5588.105903055512;5604.663720176782;5539.473749993744;5573;5573.219749235111;5576.470235327637;5584.832455026335;5351.357837059559;5394.109971854639;5582.330120385559;5602.187682785993;5637.492625663689;5512.062949298629;5524.226608274825;5538.113901775697;5559.972200139014;5582.302212223472;5410.1343784994215;5513;5529.806456774034;5532.585056120807;5547.72261386933;5862.325832529271;6124.081387791867;6124.510039196898;6184.8763024739255;6226.128342032119;6270.623762574214;6331.8417039573915;6360.52296077796;6396.648184349859;6421.036844473319;5185.533302002837;6416.844967905775;6545.9018114728315;6552.213734351861;6563.671816409208;6209.87580248397;6224.657643829542;6227.532935029891;6230.750769969196;6237.875242495215;6239.78160764371;6418.422341989243;6430.3891130342545;6587.604743715392;6589.9341006590275;6349.52378571609;6369.936300636934;6376.7130479128555;6393.6483493408305;6400.583962042452;6390.9360906391275;6454.128692626436;6459.354064593597;6474.967625161844;6477.4494167996245;6222.284437289679;6239.1577137085915;6283.748650053946;6364.554481186273;6396.488280937514;6253.593516421418;6379.075034120026;6417.903731444032;6438.195225596801;6442.259140198843;5109.489051094935;5157.406898206679;5227.3988491323225;5238.266642670029;5243;4583.083383323282;4587.3348364486965;4615.3999980003055;4645.841971435546;4708.387909571734;5967.1347654589845;6020.608660437058;6178.752849886044;6200.658963756947;6203.937960620427;6303.086052522359;6320.241571011508;6416.518761092843;6438.195225596801;6441.355864413615;6229.626222426669;6235.282942461584;6242.09489624002;6261.279952805395;6264.655443950535;5782.017057100303;5796.855078623068;5811.389804070596;5822.243108395882;5836.9124463133085;5888.528917686489;6349.523785715997;6550.312217217314;6567.573107747981;6786.083878676414;5527.336719593674;6410.391012853772;6472.805815825533;6485.643289619116;6512.283648798533;6667.19993600771;6756.69594868242;6771.559848609824;6773.119494465688;6786.796396108125;6252.187215661935;6259.405356491126;6314.873702526015;6398.936010639834;6425.421712045938;6416.743330266737;6472.935270647234;6508;6516.315786842313;6517.283098859027;5385.676859388449;6110.297315808739;6336.683165841598;6339.985602303609;6366.713497892513;6191.442770150708;6240.812775616739;6275.435210831047;6303.432691057827;6358.682065896594;6120.44915957566;6134.375358695336;6207.534434917309;6269;6277.529185311211;6344.302126766144;6354.777582784581;6358.936410635834;6363.3000369958345;6368.554201206057;6043.335233124265;6126.326104128631;6144.986077297196;6246.1255424239735;6290.9056364155485 03:19:33 INFO - PROCESS | 1896 | |1;ext.html;739.8261408568987;741.1661880384568;821.7863355527561;915.8855143107112;959.8704174936381;32215.838920805392;37766.811165944164;40260;40281.59718402815;40387.79806100973;33029;38952;39149.80425097878;39162;39263;5222.791088356461;5296.973515132419;10611.204159688015;10940.288881222696;11063.446827658625;10742.409167495785;10824.891751082507;10852.294600850959;10856.131509479263;10872.728181795439;9203.4477931324;10854.565817367296;10879.347239165636;10899.727506812314;10917.563297468132;9196.586153623102;10821.729456763565;10835.83746243807;10859.782804343908;10878.836817447745;10556.524956376981;10574.894251057545;10606.15150787935;10625.256232063759;10657.893421065844;33824.32351352959;34178.48732269018;34316;34350.14124646879;34396.6560334396;3229.6931791479774;3620.8732694355836;3741.5136032315627;3745.494358261627;3805.1058001369656;25193;25365.239042828744;26200.60699089515;26466.867665661743;27038.45923081547;3044.1476386611785;3075.8000729952705;3164.7942883712717;3268.7711860169798;3284.3431313737346;269953;299362;334725;340790;343370;41833.163336733094;45080.3237951431;45162.32256516155;45164.322535162;45165.54834451614;45853.85365365892;45922;45959;46082;46110.76944615255;12024.038076953822;13286.468541258435;13739.96950228737;13792.241426721426;13827.446902124004;11680.948714615726;11910.523579056913;11934.641960741192;11937.403129843473;11959.940200298943;10126.69619911389;12033.518659253707;12058.216215946111;12069.939650301692;12145.69635759113;5511.724413779295;5589.329280486368;5589.80435684749;5598.916016259759;5601.467860553241;970.8834939807268;973.5813600151861;993.5529011944653;1002.9097381235724;1018.2770233134557;26524.734752652614;26833.731662683513;27131.186064417703;27216.319592010357;27231.774570144393;909.4770506958525;972.2562239886493;1056.762228498597;1089.3463921646949;1103.2442776697987;11745.941270293593;13439.865601343861;13689.59885272663;13985.3706583204;14031.78952315716;438688;602094;614205;615665;617655;41596.3361465538;42145;49122.75438622855;49167.50832491629;49597.504024959286;1376.1124074971588;1392.4778208171936;1410.26666133612;1411.5483045425572;1412.2726795700223;27346;27473.86263068672;27977.72022279751;28056.43887122287;28387.574186386806;41660.58339416567;41747.582524174366;41858.744237672916;41861.79069104635;42071;365316;459739.70130149135;459975;460010;462142;30469;30758.384832303676;30797.538036929913;30811.383772324876;30815.845920770254;38151.80924095362;38341.04147396337;39252.607473924894;39276;39362.40956385714;27306;27402;32560;32684;32701.836490817393;32314.515282271255;32373.866914657872;32771.836140820094;32790.5081423774;33306.500402494465;32811.179720506225;32843.68625254963;32879.34241315208;32895.67104328926;33911.32177356489;26851.865740671954;28539.28651783722;29958;30151.849240753654;30233.848830755705;19295.517612059248;20827.229685476905;23738.287851364486;23892.641610376206;23904.52190956206 03:19:33 INFO - PROCESS | 1896 | |2;jquery.html;359.45901418365366;407.3929844531648;408.11031950348246;408.51794882039184;449.17127899026303;17713.64572708545;20077.498062548424;20289.695654565156;21368.25211117613;21414;20487.897560512214;20685.870508765423;20967.84676842773;21248.787512124913;21382.572348553018;6262.655553944543;6462.967685161568;6476;6517.543771935966;6524.282328943812;5612.69130197841;6350;6445.903311450332;6897.379235868771;7269.927300727004;7343.853122937538;7373.336399724024;7375.815604609874;7382.741604043859;7384.409247260233;7081.398081163107;7270.6001169935635;7330;7331;7475.02824632797;6948.305169483011;7024.824379390505;7091.503594748369;7120.038794762744;7136.464765142583;23948.760512394827;24122.758772412402;24990.750092499024;25074.373140671447;25313.98744050236;2782.8330300182056;2830.985845070782;2883.18656441045;2895.4643390972738;2933.48663983802;14619.122852628876;14701.926490367587;14920.62698432537;15134.545963621109;15329.770053449207;1974.4570243183095;2019.9798002020086;2021.929232476871;2025.2202901882881;2028.3103744726716;165248.17375913163;166713;174565;177558;178560;17796.288148474174;18700.25198992052;20192.39422817318;20198.495037624176;20341.288054918008;30849.69150308513;31268.530972035438;31443.213919652193;31458;31486.740530378986;9981.401115933068;10131.392116473035;10283.331583447052;10284.28010039305;10380.532876020598;8540.572971351408;8552.700655477029;8568.186022327869;8712.259457946146;8723.563821809012;9115.817683646423;9215.44707317563;9282.582283797245;9301.581428835718;9325.860112098324;3901.31726947783;3935.2916475034767;3950.1309711863346;3959.4456776051375;3967.3652215645357;865.4634126841398;985.8915519292872;1031.4687935713114;1031.5512751952924;1067.7063807452857;17027.97832130076;19771.901140494494;19786.10962506662;19908.90045549763;20094.598108037757;881.2861582118504;961.8890181839986;1009.6466236817055;1013.2501948558014;1046.7487802927394;7662.3487003604205;8027.357811375076;9732.659356922615;10272.897271027194;10428.270021098602;683248;691030;711876;734647;754316;118779.40610297066;124569;146222;155417;162186;1170.5025364220173;1193.480835836414;1218.3908045977012;1266.866978967195;1283.5700040486354;18382.72425913639;18862.905685471484;19020.524486887938;19165.32921347746;19216.327428539935;56078;64359;64806.67596661986;65511.68976620345;65883.67058164679;77104.84342734751;90075.54962225146;90358.54820725854;91892.54053729688;92677.5366123192;10734.355938643706;11145.944270278596;11211.66365009051;11614.535418583162;11651.475683594428;12101.818472723093;12112.878871211527;12171.878281217429;12242.081843861568;12501.874981250072;10791.56833726642;10886.836697449386;10935.726606834893;11057.668269951915;11077.003069723763;9149.954250228706;10490.05589496949;10641.201909856947;10658.520366583367;10984.450777461256;10281.383117013003;10671;10719.732006699895;10780.245382823126;10890.455477225949;11023.83464248053;11097.33415995043;11167.832482512606;11188.552457901593;11478;9238.907610923805;9412.858807117762;9557.426554406758;10540;10633.096186824207 03:19:33 INFO - PROCESS | 1896 | |3;mootools.html;1514.931828067737;1547.8761699064069;1556.7742677311783;1564.6166689161157;1569.6703692224633;10755.83866242007;10982.780344393108;11185.888141118587;11468.25456345338;11497.080233581315;11257.943710281457;11441.199116061878;11478;11482.196246262765;11517.36654484003;2389;2426.9757302427015;2436.0864675746593;2443.328084776692;2444.804415646744;2392.5693375192545;2415.6497307890345;2431.2098567965395;2435.585950388438;2439.5730747119273;2382.9991403610525;2403.639454081884;2408.1330720940446;2413.070967677439;2415.722191947922;2363.1847012780736;2386.05750728463;2387.7970372518357;2397.7482364351667;2401.639754036891;2329.126577533425;2346.5189636124555;2401.075585899441;2402.25530085674;2406.602910519762;11195.888041119648;11239.438028098562;11253.324800511913;11262.380569068699;11273.041791447737;5819.098039803837;5945.791897283618;5989.401059894019;6002.519798416116;6007.068904319837;3202.823844688541;3445.3109378124223;3608.8195590220384;3625.6374362563797;3656.926861462756;1034.5396298647106;1182.7634473105413;1231.7536492701406;1233.444949772597;1258.8489381274214;13128.343582820917;13599.04806663534;13813.723725525533;15496.380144794195;15630.609234769108;4584.839530616479;4616.030633566988;4797.904041919142;4807.18277892759;4810.735409552508;11680.824787628193;11800.822987655192;11838.526458941718;11848.94075529634;11908.702282443008;5899.7050147492455;5917.704114794329;5922.111683247547;5938.584299099108;5942.465178133989;4499.775011249424;4540.886477838015;5232.3721153461065;5270.446603106695;5352;3870.1679138985255;3897.590752970954;3935;3957.6438120569287;4003.6797056236014;914.826183025223;1034.591336422109;1046.0742243114898;1050.3855244681795;1059.0627294844119;788.7712563356677;921.9585118669676;930.7673081729567;947.8199142162968;992.5880759484877;6550.443212327005;6560.704768285439;6594.142761440982;6656.134702488645;6658.067870498134;920.7928216151446;966.3139171188394;978.2760757039739;985.2856678907734;989.618996686281;3548.822558872046;4208.9789551052045;4262.104957958863;4421.38100665907;4432.3351497275025;13514.797278040838;13955.930220349037;13975.860241397659;13978.86021139796;13982;13489.2580908051;13544.661383465493;13848.307584620728;13933.721325573635;14055.367508462145;8782.560871956377;10332.845007324897;10344.793104138025;10551.683449496528;10632.734181645521;6313.526485513513;6383.39357761012;6651.13535240435;6941.167059952838;6973.895391569232;11956.641300760992;12463.31451770125;12467.064970127096;12573.874261257637;12608.684782880502;12426.06804489685;12573.30846803398;12745.17156384832;12804.551840685532;12900.870991290343;9383.624655013997;9385.436873787594;9449.48027858447;9535.713928582154;9540.522973851419;10243.38539687621;10311.845322320021;10335.966403359604;10442.530086146293;10448.42533660656;8186.672533098847;8628.52543110135;9230.492322922304;9337.253019758538;9501.57242924084;8811.647534098563;8971.955140224258;9020;9051.683191088281;9055.185033346766;7264.309890560388;7471.701131954661;7923.326517246099;7953.32396746283;8031.638576264199;5902.704864756831;5956.463918247378;5975.671338076448;5999;6117.510599152146;5325.547328477122;5682.6306290091015;5873.911891321548;5943.762249509971;6122 03:19:33 INFO - PROCESS | 1896 | |4;prototype.html;305.2810630964078;371.88657459474865;378.68947463080275;382.2679568626081;386.04646523087973;4466.464024317078;4768.78540465679;5091.414487333957;5095.388553373596;5143.485651434859;4357.474883790672;4684.297355396684;5059.443461219263;5110.335656364668;5143.562797162245;4237.152569486099;4283.357496375545;4304.074623955848;4347.869563913087;4356.847510337139;3488.633693462188;3927.803609819512;4051.554329023805;4099.569545197756;4350.195213885441;3760.8119594020327;3940.074082590589;4117.8970525736795;4345.456817897762;4349.499807522144;3780.773153610792;4102.338552886086;4220.7467551946675;4277.395976508825;4328.848490302824;3463.8268086595567;3683.5027271318563;3816.2749077675153;3938.2714197873483;3956.724883192176;4746.430428348585;4911.557959783637;4987.750612469362;5002.774875130592;5056.544910958031;4591.334256532784;4966.404031516241;4990.604509835263;5004.399472063339;5010.173321401964;4591.563801438858;5160.09076641645;5336.692510334954;5441.700706461143;5516.200150978086;3871.728978971473;4202.92825329539;4295.52749197588;4424.668149888771;4465.303184789792;4638.605718513931;5254.579633629301;5467.179464156547;5468.45716741717;5857.560682948796;3783.9972407312084;4312.439382880206;4482.5069242383315;4505.977470112629;4542.410825829312;4130.008797888545;4168.7707176105605;4441.9117316257725;4509.1658043262105;4570.702904311209;4214.704970652025;4492;4887.240593386378;4952.653314268039;5000.374953130859;5269.55208807248;5509.3664228614225;5509.889802204014;5553.916986187654;5559.665680236714;1386.8266466691664;1460.7729507213992;1476.5422718957157;1542.6760380320259;1547.1258738812535;961.6392804182144;1072.126217133034;1088.6026600290836;1108.6230681568306;1171.226990186473;4351.608355248043;4830.589399900978;4930.778114984834;5048.949510504922;5099.260607211933;5370.221317908881;5374.420278266454;5384.730763461811;5702.45826646468;5722.77108915647;4224.301999332916;4695.389599352063;4942.678725882806;5122.410922743858;5149.536541711264;2953.8818447262297;2967.6438827340858;2971.870689138114;2980.195347256272;2985.4178435205363;5014.974925125351;5446.591505637093;5635.943640563624;5837.6497410155525;5928.9465925048635;4713.67004309695;5148.794048238104;5201.921971170435;5551.944480555224;5835.562332825055;4906.06784710911;5022.522860328263;5037.891663833951;5194.818181363634;5825.417458254141;5032.496750325085;5085.059264036166;5157.922631160461;5756.769729210952;5778.566607504538;4817.7350245736825;4874.000829829947;5540.390557038642;5679.233303503974;5694.369544809974;5175.896482070262;5243.344581927259;5753.798617048383;5938.268396537468;6008.519318454601;4482.551744825621;4497.26855160772;4499.820007199675;4502.694218676547;4515.096980603958;5157.7679004444135;5232.895342093213;5285.180796976475;5706.885862282814;5733.226014487991;4768.713877167381;4873.342098816614;4984.651074424755;5194.506521880416;5503.285991090863;4755.667103302874;5123.820666276662;5268;5373.785048598169;5537.363203231519;4647.651426142985;4842.95157048425;4923.104604727278;5019.623528235324;5191.845384165529;4706.411698537683;5019.472955339783;5206.817761378486;5547.750351234124;5561.694106824001;4443.755903268829;4816.590589799906;5297.682139071668;5317.8404647860625;5321.3880403754065 03:19:33 INFO - PROCESS | 1896 | |5;yui.html;591.3057258599684;707.978760637181;731.1920328037519;773.6054612147803;847.724489540899;15071.924640376796;16250;16257.918710406446;16286.918565407157;16292.10393428361;14171.433142674307;14398.280085995711;14554.053986490871;14565.41738330468;14570.927145364285;920.3235621817976;940.7742141885966;941.7080704981444;946.394307643109;949.1268033409245;900.455224589124;928.299134153713;943.0710749911332;945.1682519382967;948.7485816258697;1004.6985904228702;1006.0392325329329;1006.7533454303706;1007.098646711193;1010.4139599032543;996.3872218585554;999.585172153559;1001.0240015984393;1001.8747656542932;1002.2433063037433;740.0859937976595;740.2375553180233;740.829609189884;741.5180132913596;741.8738814401564;15655.452059177875;15815.762763558556;16106.275217615119;16557.503274901774;16858.747118793228;13933.512327068605;14104.365303561364;14140.92929535356;14151.575452736435;14252.786208206886;11531.132461365083;11748.845337660716;11785;11812.173147879652;11888.762224755457;3390.491426286052;3567.4648802679544;3603.207294395229;3638.872639457606;3750.606186350421;93948;94900.52549737276;107418.46290768497;108118;108293.45853270762;10124.645637402655;10237.692869213935;10590.629327973484;10740.516676749565;10810.405427701551;22230.221942231943;22420.88789556042;22449.21427750021;22546.77453225447;22558.0976760931;7233.493655444068;7248.565086094851;7299.5255308404785;7350.338986420645;7357.190709022003;3522.454019626962;4268.509121451011;4345.435093437833;4355.412019377407;4395.912081758347;3352.7317814575263;3423.8288085595623;3432.2449061206494;3443.810590417501;3444.7071998879874;623.233422889848;624.9625022498665;625.3652542669195;626.3392121311969;627.3538255596743;620.0777589472599;647.8315637934171;647.834802125455;655.5771527364918;683.8256244657581;9775.51122443875;10059;10079.193664506822;10134.341267817568;10184.6435374763;555.2504119438726;560.2436362005143;606.6784604159802;674.7166190200128;683.135833171039;4424.292113261927;4930.9506904930495;4968.72672003036;5070.721110338966;5077.289179514871;94338;97268.0273197273;97776;98634;98813.50593246988;78908.6054569735;79773;80749.59625201837;81637.59181204055;82599;75878;77428.61285693536;77569.83645245325;77882.6105869467;78524.21475785169;9746.463944482837;9906.25703072258;9922.255830812574;9933.900660993297;9954.153896918842;38059;47162.76418617885;47462.525374747194;47636.2854557175;48829.0234195307;50215.74892125516;50815.49184508107;64510;65009.67495162494;65543.67228163828;12821.679458013625;12889.8066529004;12974.805377919527;12997.480100795863;13163;12074.973627241783;14531.854681453051;14735.55793326202;15132.848671513144;15772.60568485797;13525.391357389139;13629.659258518617;13835.861641383457;13872.514461993782;14201.644958876112;11437.59968401102;13125.73748525005;13784;13791.310434478437;13932.373043213283;10786.460676965964;13281.933590331986;13689.45242190341;13943.16341019542;14266.286685665467;10529.947350263199;10907.600327927843;10934.890651093387;11025.503852326825;11296;9729.562169702238;10369.896301037195;10484.947575262075;10498.422586757802;10510.737231569272 03:19:33 INFO - PROCESS | 1896 | __end_tp_report 03:19:33 INFO - PROCESS | 1896 | __start_cc_report 03:19:33 INFO - PROCESS | 1896 | _x_x_mozilla_cycle_collect,411 03:19:33 INFO - PROCESS | 1896 | __end_cc_report 03:19:33 INFO - PROCESS | 1896 | __startTimestamp1490177973320__endTimestamp 03:19:33 INFO - PROCESS | 1896 | 03:19:33 INFO - PROCESS | 1896 | ------- Summary: start ------- 03:19:33 INFO - PROCESS | 1896 | Number of tests: 6 03:19:33 INFO - PROCESS | 1896 | 03:19:33 INFO - PROCESS | 1896 | [#0] dojo.html Cycles:165 Average:6001.38 Median:6230.75 stddev:641.55 (10.3%) stddev-sans-first:611.87 03:19:33 INFO - PROCESS | 1896 | Values: 3464.4 3471.9 3478.9 3485.6 3516.2 5989.9 6033.8 6077.4 6106.5 6126.2 6086.0 6135.7 6154.0 6157.7 6162.2 5479.5 5522.0 5538.2 5588.1 5604.7 5539.5 5573.0 5573.2 5576.5 5584.8 5351.4 5394.1 5582.3 5602.2 5637.5 5512.1 5524.2 5538.1 5560.0 5582.3 5410.1 5513.0 5529.8 5532.6 5547.7 5862.3 6124.1 6124.5 6184.9 6226.1 6270.6 6331.8 6360.5 6396.6 6421.0 5185.5 6416.8 6545.9 6552.2 6563.7 6209.9 6224.7 6227.5 6230.8 6237.9 6239.8 6418.4 6430.4 6587.6 6589.9 6349.5 6369.9 6376.7 6393.6 6400.6 6390.9 6454.1 6459.4 6475.0 6477.4 6222.3 6239.2 6283.7 6364.6 6396.5 6253.6 6379.1 6417.9 6438.2 6442.3 5109.5 5157.4 5227.4 5238.3 5243.0 4583.1 4587.3 4615.4 4645.8 4708.4 5967.1 6020.6 6178.8 6200.7 6203.9 6303.1 6320.2 6416.5 6438.2 6441.4 6229.6 6235.3 6242.1 6261.3 6264.7 5782.0 5796.9 5811.4 5822.2 5836.9 5888.5 6349.5 6550.3 6567.6 6786.1 5527.3 6410.4 6472.8 6485.6 6512.3 6667.2 6756.7 6771.6 6773.1 6786.8 6252.2 6259.4 6314.9 6398.9 6425.4 6416.7 6472.9 6508.0 6516.3 6517.3 5385.7 6110.3 6336.7 6340.0 6366.7 6191.4 6240.8 6275.4 6303.4 6358.7 6120.4 6134.4 6207.5 6269.0 6277.5 6344.3 6354.8 6358.9 6363.3 6368.6 6043.3 6126.3 6145.0 6246.1 6290.9 03:19:33 INFO - PROCESS | 1896 | 03:19:33 INFO - PROCESS | 1896 | [#1] ext.html Cycles:180 Average:56722.36 Median:32344.19 stddev:123175.62 (380.8%) stddev-sans-first:123449.44 03:19:33 INFO - PROCESS | 1896 | Values: 739.8 741.2 821.8 915.9 959.9 32215.8 37766.8 40260.0 40281.6 40387.8 33029.0 38952.0 39149.8 39162.0 39263.0 5222.8 5297.0 10611.2 10940.3 11063.4 10742.4 10824.9 10852.3 10856.1 10872.7 9203.4 10854.6 10879.3 10899.7 10917.6 9196.6 10821.7 10835.8 10859.8 10878.8 10556.5 10574.9 10606.2 10625.3 10657.9 33824.3 34178.5 34316.0 34350.1 34396.7 3229.7 3620.9 3741.5 3745.5 3805.1 25193.0 25365.2 26200.6 26466.9 27038.5 3044.1 3075.8 3164.8 3268.8 3284.3 269953.0 299362.0 334725.0 340790.0 343370.0 41833.2 45080.3 45162.3 45164.3 45165.5 45853.9 45922.0 45959.0 46082.0 46110.8 12024.0 13286.5 13740.0 13792.2 13827.4 11680.9 11910.5 11934.6 11937.4 11959.9 10126.7 12033.5 12058.2 12069.9 12145.7 5511.7 5589.3 5589.8 5598.9 5601.5 970.9 973.6 993.6 1002.9 1018.3 26524.7 26833.7 27131.2 27216.3 27231.8 909.5 972.3 1056.8 1089.3 1103.2 11745.9 13439.9 13689.6 13985.4 14031.8 438688.0 602094.0 614205.0 615665.0 617655.0 41596.3 42145.0 49122.8 49167.5 49597.5 1376.1 1392.5 1410.3 1411.5 1412.3 27346.0 27473.9 27977.7 28056.4 28387.6 41660.6 41747.6 41858.7 41861.8 42071.0 365316.0 459739.7 459975.0 460010.0 462142.0 30469.0 30758.4 30797.5 30811.4 30815.8 38151.8 38341.0 39252.6 39276.0 39362.4 27306.0 27402.0 32560.0 32684.0 32701.8 32314.5 32373.9 32771.8 32790.5 33306.5 32811.2 32843.7 32879.3 32895.7 33911.3 26851.9 28539.3 29958.0 30151.8 30233.8 19295.5 20827.2 23738.3 23892.6 23904.5 03:19:33 INFO - PROCESS | 1896 | 03:19:33 INFO - PROCESS | 1896 | [#2] jquery.html Cycles:180 Average:41985.95 Median:21375.41 stddev:120021.61 (561.5%) stddev-sans-first:120317.60 03:19:33 INFO - PROCESS | 1896 | Values: 359.5 407.4 408.1 408.5 449.2 17713.6 20077.5 20289.7 21368.3 21414.0 20487.9 20685.9 20967.8 21248.8 21382.6 6262.7 6463.0 6476.0 6517.5 6524.3 5612.7 6350.0 6445.9 6897.4 7269.9 7343.9 7373.3 7375.8 7382.7 7384.4 7081.4 7270.6 7330.0 7331.0 7475.0 6948.3 7024.8 7091.5 7120.0 7136.5 23948.8 24122.8 24990.8 25074.4 25314.0 2782.8 2831.0 2883.2 2895.5 2933.5 14619.1 14701.9 14920.6 15134.5 15329.8 1974.5 2020.0 2021.9 2025.2 2028.3 165248.2 166713.0 174565.0 177558.0 178560.0 17796.3 18700.3 20192.4 20198.5 20341.3 30849.7 31268.5 31443.2 31458.0 31486.7 9981.4 10131.4 10283.3 10284.3 10380.5 8540.6 8552.7 8568.2 8712.3 8723.6 9115.8 9215.4 9282.6 9301.6 9325.9 3901.3 3935.3 3950.1 3959.4 3967.4 865.5 985.9 1031.5 1031.6 1067.7 17028.0 19771.9 19786.1 19908.9 20094.6 881.3 961.9 1009.6 1013.3 1046.7 7662.3 8027.4 9732.7 10272.9 10428.3 683248.0 691030.0 711876.0 734647.0 754316.0 118779.4 124569.0 146222.0 155417.0 162186.0 1170.5 1193.5 1218.4 1266.9 1283.6 18382.7 18862.9 19020.5 19165.3 19216.3 56078.0 64359.0 64806.7 65511.7 65883.7 77104.8 90075.5 90358.5 91892.5 92677.5 10734.4 11145.9 11211.7 11614.5 11651.5 12101.8 12112.9 12171.9 12242.1 12501.9 10791.6 10886.8 10935.7 11057.7 11077.0 9150.0 10490.1 10641.2 10658.5 10984.5 10281.4 10671.0 10719.7 10780.2 10890.5 11023.8 11097.3 11167.8 11188.6 11478.0 9238.9 9412.9 9557.4 10540.0 10633.1 03:19:33 INFO - PROCESS | 1896 | 03:19:33 INFO - PROCESS | 1896 | [#3] mootools.html Cycles:180 Average:6785.97 Median:2441.45 stddev:4246.52 (173.9%) stddev-sans-first:4239.96 03:19:33 INFO - PROCESS | 1896 | Values: 1514.9 1547.9 1556.8 1564.6 1569.7 10755.8 10982.8 11185.9 11468.3 11497.1 11257.9 11441.2 11478.0 11482.2 11517.4 2389.0 2427.0 2436.1 2443.3 2444.8 2392.6 2415.6 2431.2 2435.6 2439.6 2383.0 2403.6 2408.1 2413.1 2415.7 2363.2 2386.1 2387.8 2397.7 2401.6 2329.1 2346.5 2401.1 2402.3 2406.6 11195.9 11239.4 11253.3 11262.4 11273.0 5819.1 5945.8 5989.4 6002.5 6007.1 3202.8 3445.3 3608.8 3625.6 3656.9 1034.5 1182.8 1231.8 1233.4 1258.8 13128.3 13599.0 13813.7 15496.4 15630.6 4584.8 4616.0 4797.9 4807.2 4810.7 11680.8 11800.8 11838.5 11848.9 11908.7 5899.7 5917.7 5922.1 5938.6 5942.5 4499.8 4540.9 5232.4 5270.4 5352.0 3870.2 3897.6 3935.0 3957.6 4003.7 914.8 1034.6 1046.1 1050.4 1059.1 788.8 922.0 930.8 947.8 992.6 6550.4 6560.7 6594.1 6656.1 6658.1 920.8 966.3 978.3 985.3 989.6 3548.8 4209.0 4262.1 4421.4 4432.3 13514.8 13955.9 13975.9 13978.9 13982.0 13489.3 13544.7 13848.3 13933.7 14055.4 8782.6 10332.8 10344.8 10551.7 10632.7 6313.5 6383.4 6651.1 6941.2 6973.9 11956.6 12463.3 12467.1 12573.9 12608.7 12426.1 12573.3 12745.2 12804.6 12900.9 9383.6 9385.4 9449.5 9535.7 9540.5 10243.4 10311.8 10336.0 10442.5 10448.4 8186.7 8628.5 9230.5 9337.3 9501.6 8811.6 8972.0 9020.0 9051.7 9055.2 7264.3 7471.7 7923.3 7953.3 8031.6 5902.7 5956.5 5975.7 5999.0 6117.5 5325.5 5682.6 5873.9 5943.8 6122.0 03:19:33 INFO - PROCESS | 1896 | 03:19:33 INFO - PROCESS | 1896 | [#4] prototype.html Cycles:180 Average:4512.54 Median:4908.81 stddev:1245.73 (25.4%) stddev-sans-first:1208.53 03:19:33 INFO - PROCESS | 1896 | Values: 305.3 371.9 378.7 382.3 386.0 4466.5 4768.8 5091.4 5095.4 5143.5 4357.5 4684.3 5059.4 5110.3 5143.6 4237.2 4283.4 4304.1 4347.9 4356.8 3488.6 3927.8 4051.6 4099.6 4350.2 3760.8 3940.1 4117.9 4345.5 4349.5 3780.8 4102.3 4220.7 4277.4 4328.8 3463.8 3683.5 3816.3 3938.3 3956.7 4746.4 4911.6 4987.8 5002.8 5056.5 4591.3 4966.4 4990.6 5004.4 5010.2 4591.6 5160.1 5336.7 5441.7 5516.2 3871.7 4202.9 4295.5 4424.7 4465.3 4638.6 5254.6 5467.2 5468.5 5857.6 3784.0 4312.4 4482.5 4506.0 4542.4 4130.0 4168.8 4441.9 4509.2 4570.7 4214.7 4492.0 4887.2 4952.7 5000.4 5269.6 5509.4 5509.9 5553.9 5559.7 1386.8 1460.8 1476.5 1542.7 1547.1 961.6 1072.1 1088.6 1108.6 1171.2 4351.6 4830.6 4930.8 5048.9 5099.3 5370.2 5374.4 5384.7 5702.5 5722.8 4224.3 4695.4 4942.7 5122.4 5149.5 2953.9 2967.6 2971.9 2980.2 2985.4 5015.0 5446.6 5635.9 5837.6 5928.9 4713.7 5148.8 5201.9 5551.9 5835.6 4906.1 5022.5 5037.9 5194.8 5825.4 5032.5 5085.1 5157.9 5756.8 5778.6 4817.7 4874.0 5540.4 5679.2 5694.4 5175.9 5243.3 5753.8 5938.3 6008.5 4482.6 4497.3 4499.8 4502.7 4515.1 5157.8 5232.9 5285.2 5706.9 5733.2 4768.7 4873.3 4984.7 5194.5 5503.3 4755.7 5123.8 5268.0 5373.8 5537.4 4647.7 4843.0 4923.1 5019.6 5191.8 4706.4 5019.5 5206.8 5547.8 5561.7 4443.8 4816.6 5297.7 5317.8 5321.4 03:19:33 INFO - PROCESS | 1896 | 03:19:33 INFO - PROCESS | 1896 | [#5] yui.html Cycles:180 Average:19669.28 Median:3544.96 stddev:27694.03 (781.2%) stddev-sans-first:27734.67 03:19:33 INFO - PROCESS | 1896 | Values: 591.3 708.0 731.2 773.6 847.7 15071.9 16250.0 16257.9 16286.9 16292.1 14171.4 14398.3 14554.1 14565.4 14570.9 920.3 940.8 941.7 946.4 949.1 900.5 928.3 943.1 945.2 948.7 1004.7 1006.0 1006.8 1007.1 1010.4 996.4 999.6 1001.0 1001.9 1002.2 740.1 740.2 740.8 741.5 741.9 15655.5 15815.8 16106.3 16557.5 16858.7 13933.5 14104.4 14140.9 14151.6 14252.8 11531.1 11748.8 11785.0 11812.2 11888.8 3390.5 3567.5 3603.2 3638.9 3750.6 93948.0 94900.5 107418.5 108118.0 108293.5 10124.6 10237.7 10590.6 10740.5 10810.4 22230.2 22420.9 22449.2 22546.8 22558.1 7233.5 7248.6 7299.5 7350.3 7357.2 3522.5 4268.5 4345.4 4355.4 4395.9 3352.7 3423.8 3432.2 3443.8 3444.7 623.2 625.0 625.4 626.3 627.4 620.1 647.8 647.8 655.6 683.8 9775.5 10059.0 10079.2 10134.3 10184.6 555.3 560.2 606.7 674.7 683.1 4424.3 4931.0 4968.7 5070.7 5077.3 94338.0 97268.0 97776.0 98634.0 98813.5 78908.6 79773.0 80749.6 81637.6 82599.0 75878.0 77428.6 77569.8 77882.6 78524.2 9746.5 9906.3 9922.3 9933.9 9954.2 38059.0 47162.8 47462.5 47636.3 48829.0 50215.7 50815.5 64510.0 65009.7 65543.7 12821.7 12889.8 12974.8 12997.5 13163.0 12075.0 14531.9 14735.6 15132.8 15772.6 13525.4 13629.7 13835.9 13872.5 14201.6 11437.6 13125.7 13784.0 13791.3 13932.4 10786.5 13281.9 13689.5 13943.2 14266.3 10529.9 10907.6 10934.9 11025.5 11296.0 9729.6 10369.9 10484.9 10498.4 10510.7 03:19:33 INFO - PROCESS | 1896 | -------- Summary: end -------- 03:19:33 INFO - PROCESS | 1896 | 03:19:33 INFO - TEST-INFO | 1896: exit 0 03:19:34 INFO - TEST-OK | dromaeo_css | took 1117517ms 03:19:34 INFO - TEST-START | kraken 03:19:34 INFO - operating with platform_type : mac_ 03:19:34 INFO - Initialising browser for kraken test... 03:19:34 INFO - TEST-INFO | started process 1903 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/T/tmpGzkJYR/profile http://localhost:49373/getInfo.html) 03:19:37 INFO - PROCESS | 1903 | 2017-03-22 03:19:37.014 plugin-container[1904:11646] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x893f, name = 'com.apple.tsm.portname' 03:19:37 INFO - PROCESS | 1903 | See /usr/include/servers/bootstrap_defs.h for the error codes. 03:19:37 INFO - PROCESS | 1903 | 2017-03-22 03:19:37.017 plugin-container[1904:11646] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9013, name = 'com.apple.CFPasteboardClient' 03:19:37 INFO - PROCESS | 1903 | See /usr/include/servers/bootstrap_defs.h for the error codes. 03:19:37 INFO - PROCESS | 1903 | 2017-03-22 03:19:37.017 plugin-container[1904:11646] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 03:19:37 INFO - PROCESS | 1903 | __metrics Screen width/height:1600/1200 03:19:37 INFO - PROCESS | 1903 | colorDepth:24 03:19:37 INFO - PROCESS | 1903 | Browser inner width/height: 1024/689 03:19:37 INFO - PROCESS | 1903 | __metrics 03:19:37 ERROR - PROCESS | 1903 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3353: TypeError: this.tabs is undefined 03:19:37 INFO - TEST-INFO | 1903: exit 0 03:19:37 INFO - Browser initialized. 03:19:37 INFO - Running cycle 1/1 for kraken test... 03:19:37 INFO - TEST-INFO | started process 1905 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/T/tmpGzkJYR/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 03:19:40 INFO - PROCESS | 1905 | 2017-03-22 03:19:40.550 plugin-container[1906:11782] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4747, name = 'com.apple.tsm.portname' 03:19:40 INFO - PROCESS | 1905 | See /usr/include/servers/bootstrap_defs.h for the error codes. 03:19:40 INFO - PROCESS | 1905 | 2017-03-22 03:19:40.550 plugin-container[1906:11782] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x900b, name = 'com.apple.CFPasteboardClient' 03:19:40 INFO - PROCESS | 1905 | See /usr/include/servers/bootstrap_defs.h for the error codes. 03:19:40 INFO - PROCESS | 1905 | 2017-03-22 03:19:40.550 plugin-container[1906:11782] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 03:19:50 INFO - PROCESS | 1905 | RSS: Main: 221138944 03:19:50 INFO - PROCESS | 1905 | 03:19:53 INFO - PROCESS | 1905 | Cycle 1(1): loaded http://localhost:49373/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49373/tests/kraken/driver.html?testName=audio-beat-detection) 03:19:54 INFO - PROCESS | 1905 | RSS: Main: 235417600 03:19:54 INFO - PROCESS | 1905 | 03:19:56 INFO - PROCESS | 1905 | Cycle 1(1): loaded http://localhost:49373/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49373/tests/kraken/driver.html?testName=audio-dft) 03:19:57 INFO - PROCESS | 1905 | RSS: Main: 243699712 03:19:57 INFO - PROCESS | 1905 | 03:20:00 INFO - PROCESS | 1905 | Cycle 1(1): loaded http://localhost:49373/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49373/tests/kraken/driver.html?testName=audio-fft) 03:20:01 INFO - PROCESS | 1905 | RSS: Main: 247255040 03:20:01 INFO - PROCESS | 1905 | 03:20:03 INFO - PROCESS | 1905 | Cycle 1(1): loaded http://localhost:49373/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49373/tests/kraken/driver.html?testName=audio-oscillator) 03:20:04 INFO - PROCESS | 1905 | RSS: Main: 248684544 03:20:04 INFO - PROCESS | 1905 | 03:20:06 INFO - PROCESS | 1905 | Cycle 1(1): loaded http://localhost:49373/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49373/tests/kraken/driver.html?testName=imaging-gaussian-blur) 03:20:06 INFO - PROCESS | 1905 | RSS: Main: 249061376 03:20:06 INFO - PROCESS | 1905 | 03:20:09 INFO - PROCESS | 1905 | Cycle 1(1): loaded http://localhost:49373/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49373/tests/kraken/driver.html?testName=imaging-darkroom) 03:20:10 INFO - PROCESS | 1905 | RSS: Main: 252837888 03:20:10 INFO - PROCESS | 1905 | 03:20:13 INFO - PROCESS | 1905 | Cycle 1(1): loaded http://localhost:49373/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49373/tests/kraken/driver.html?testName=imaging-desaturate) 03:20:14 INFO - PROCESS | 1905 | RSS: Main: 252301312 03:20:14 INFO - PROCESS | 1905 | 03:20:17 INFO - PROCESS | 1905 | Cycle 1(1): loaded http://localhost:49373/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49373/tests/kraken/driver.html?testName=json-parse-financial) 03:20:17 INFO - PROCESS | 1905 | RSS: Main: 252444672 03:20:17 INFO - PROCESS | 1905 | 03:20:19 INFO - PROCESS | 1905 | Cycle 1(1): loaded http://localhost:49373/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49373/tests/kraken/driver.html?testName=json-stringify-tinderbox) 03:20:20 INFO - PROCESS | 1905 | RSS: Main: 251658240 03:20:20 INFO - PROCESS | 1905 | 03:20:21 INFO - PROCESS | 1905 | Cycle 1(1): loaded http://localhost:49373/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49373/tests/kraken/driver.html?testName=stanford-crypto-aes) 03:20:22 INFO - PROCESS | 1905 | RSS: Main: 251568128 03:20:22 INFO - PROCESS | 1905 | 03:20:24 INFO - PROCESS | 1905 | Cycle 1(1): loaded http://localhost:49373/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49373/tests/kraken/driver.html?testName=stanford-crypto-ccm) 03:20:25 INFO - PROCESS | 1905 | RSS: Main: 251686912 03:20:25 INFO - PROCESS | 1905 | 03:20:28 INFO - PROCESS | 1905 | Cycle 1(1): loaded http://localhost:49373/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49373/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 03:20:29 INFO - PROCESS | 1905 | RSS: Main: 252051456 03:20:29 INFO - PROCESS | 1905 | 03:20:31 INFO - PROCESS | 1905 | Cycle 1(1): loaded http://localhost:49373/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49373/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 03:20:32 INFO - PROCESS | 1905 | RSS: Main: 251838464 03:20:32 INFO - PROCESS | 1905 | 03:20:34 INFO - PROCESS | 1905 | Cycle 1(1): loaded http://localhost:49373/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49373/tests/kraken/driver.html?testName=ai-astar) 03:20:34 INFO - PROCESS | 1905 | RSS: Main: 253505536 03:20:34 INFO - PROCESS | 1905 | 03:20:34 INFO - PROCESS | 1905 | __start_tp_report 03:20:34 INFO - PROCESS | 1905 | _x_x_mozilla_page_load 03:20:34 INFO - PROCESS | 1905 | _x_x_mozilla_page_load_details 03:20:34 INFO - PROCESS | 1905 | |i|pagename|runs| 03:20:34 INFO - PROCESS | 1905 | |0;ai-astar;106;117;118;121;118;118;118;117;117;119 03:20:34 INFO - PROCESS | 1905 | |1;audio-beat-detection;151;110;113;145;114;116;110;132;124;138 03:20:34 INFO - PROCESS | 1905 | |2;audio-dft;205;199;192;198;198;281;214;200;198;198 03:20:34 INFO - PROCESS | 1905 | |3;audio-fft;92;58;80;112;52;73;84;87;64;77 03:20:34 INFO - PROCESS | 1905 | |4;audio-oscillator;89;73;87;70;71;72;82;69;72;83 03:20:34 INFO - PROCESS | 1905 | |5;imaging-gaussian-blur;93;93;81;134;123;92;92;92;93;86 03:20:34 INFO - PROCESS | 1905 | |6;imaging-darkroom;98;99;99;125;154;99;98;99;98;147 03:20:34 INFO - PROCESS | 1905 | |7;imaging-desaturate;91;96;92;93;91;89;87;93;142;137 03:20:34 INFO - PROCESS | 1905 | |8;json-parse-financial;66;65;67;68;69;69;69;69;70;70 03:20:34 INFO - PROCESS | 1905 | |9;json-stringify-tinderbox;62;56;61;64;59;61;59;61;59;60 03:20:34 INFO - PROCESS | 1905 | |10;stanford-crypto-aes;89;85;84;89;90;91;83;89;91;111 03:20:34 INFO - PROCESS | 1905 | |11;stanford-crypto-ccm;140;146;151;140;140;163;143;136;150;149 03:20:34 INFO - PROCESS | 1905 | |12;stanford-crypto-pbkdf2;170;173;169;168;170;170;169;168;168;165 03:20:34 INFO - PROCESS | 1905 | |13;stanford-crypto-sha256-iterative;67;68;71;67;67;67;66;67;67;65 03:20:34 INFO - PROCESS | 1905 | __end_tp_report 03:20:34 INFO - PROCESS | 1905 | __start_cc_report 03:20:34 INFO - PROCESS | 1905 | _x_x_mozilla_cycle_collect,1048 03:20:34 INFO - PROCESS | 1905 | __end_cc_report 03:20:34 INFO - PROCESS | 1905 | __startTimestamp1490178034484__endTimestamp 03:20:34 INFO - PROCESS | 1905 | 03:20:34 INFO - PROCESS | 1905 | ------- Summary: start ------- 03:20:34 INFO - PROCESS | 1905 | Number of tests: 14 03:20:34 INFO - PROCESS | 1905 | 03:20:34 INFO - PROCESS | 1905 | [#0] ai-astar Cycles:10 Average:116.90 Median:118.00 stddev:4.01 (3.4%) stddev-sans-first:1.27 03:20:34 INFO - PROCESS | 1905 | Values: 106.0 117.0 118.0 121.0 118.0 118.0 118.0 117.0 117.0 119.0 03:20:34 INFO - PROCESS | 1905 | 03:20:34 INFO - PROCESS | 1905 | [#1] audio-beat-detection Cycles:10 Average:125.30 Median:128.00 stddev:15.24 (11.9%) stddev-sans-first:13.02 03:20:34 INFO - PROCESS | 1905 | Values: 151.0 110.0 113.0 145.0 114.0 116.0 110.0 132.0 124.0 138.0 03:20:34 INFO - PROCESS | 1905 | 03:20:34 INFO - PROCESS | 1905 | [#2] audio-dft Cycles:10 Average:208.30 Median:199.50 stddev:26.19 (13.1%) stddev-sans-first:27.75 03:20:34 INFO - PROCESS | 1905 | Values: 205.0 199.0 192.0 198.0 198.0 281.0 214.0 200.0 198.0 198.0 03:20:34 INFO - PROCESS | 1905 | 03:20:34 INFO - PROCESS | 1905 | [#3] audio-fft Cycles:10 Average:77.90 Median:78.50 stddev:17.55 (22.4%) stddev-sans-first:17.85 03:20:34 INFO - PROCESS | 1905 | Values: 92.0 58.0 80.0 112.0 52.0 73.0 84.0 87.0 64.0 77.0 03:20:34 INFO - PROCESS | 1905 | 03:20:34 INFO - PROCESS | 1905 | [#4] audio-oscillator Cycles:10 Average:76.80 Median:77.50 stddev:7.60 (9.8%) stddev-sans-first:6.65 03:20:34 INFO - PROCESS | 1905 | Values: 89.0 73.0 87.0 70.0 71.0 72.0 82.0 69.0 72.0 83.0 03:20:34 INFO - PROCESS | 1905 | 03:20:34 INFO - PROCESS | 1905 | [#5] imaging-gaussian-blur Cycles:10 Average:97.90 Median:92.00 stddev:16.79 (18.2%) stddev-sans-first:17.71 03:20:34 INFO - PROCESS | 1905 | Values: 93.0 93.0 81.0 134.0 123.0 92.0 92.0 92.0 93.0 86.0 03:20:34 INFO - PROCESS | 1905 | 03:20:34 INFO - PROCESS | 1905 | [#6] imaging-darkroom Cycles:10 Average:111.60 Median:98.50 stddev:22.16 (22.5%) stddev-sans-first:22.95 03:20:34 INFO - PROCESS | 1905 | Values: 98.0 99.0 99.0 125.0 154.0 99.0 98.0 99.0 98.0 147.0 03:20:34 INFO - PROCESS | 1905 | 03:20:34 INFO - PROCESS | 1905 | [#7] imaging-desaturate Cycles:10 Average:101.10 Median:91.50 stddev:20.41 (22.3%) stddev-sans-first:21.32 03:20:34 INFO - PROCESS | 1905 | Values: 91.0 96.0 92.0 93.0 91.0 89.0 87.0 93.0 142.0 137.0 03:20:34 INFO - PROCESS | 1905 | 03:20:34 INFO - PROCESS | 1905 | [#8] json-parse-financial Cycles:10 Average:68.20 Median:69.00 stddev:1.69 (2.4%) stddev-sans-first:1.59 03:20:34 INFO - PROCESS | 1905 | Values: 66.0 65.0 67.0 68.0 69.0 69.0 69.0 69.0 70.0 70.0 03:20:34 INFO - PROCESS | 1905 | 03:20:34 INFO - PROCESS | 1905 | [#9] json-stringify-tinderbox Cycles:10 Average:60.20 Median:61.00 stddev:2.15 (3.5%) stddev-sans-first:2.18 03:20:34 INFO - PROCESS | 1905 | Values: 62.0 56.0 61.0 64.0 59.0 61.0 59.0 61.0 59.0 60.0 03:20:34 INFO - PROCESS | 1905 | 03:20:34 INFO - PROCESS | 1905 | [#10] stanford-crypto-aes Cycles:10 Average:90.20 Median:89.00 stddev:7.86 (8.8%) stddev-sans-first:8.32 03:20:34 INFO - PROCESS | 1905 | Values: 89.0 85.0 84.0 89.0 90.0 91.0 83.0 89.0 91.0 111.0 03:20:34 INFO - PROCESS | 1905 | 03:20:34 INFO - PROCESS | 1905 | [#11] stanford-crypto-ccm Cycles:10 Average:145.80 Median:147.50 stddev:7.86 (5.3%) stddev-sans-first:8.05 03:20:34 INFO - PROCESS | 1905 | Values: 140.0 146.0 151.0 140.0 140.0 163.0 143.0 136.0 150.0 149.0 03:20:34 INFO - PROCESS | 1905 | 03:20:34 INFO - PROCESS | 1905 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:169.00 Median:169.50 stddev:2.05 (1.2%) stddev-sans-first:2.15 03:20:34 INFO - PROCESS | 1905 | Values: 170.0 173.0 169.0 168.0 170.0 170.0 169.0 168.0 168.0 165.0 03:20:34 INFO - PROCESS | 1905 | 03:20:34 INFO - PROCESS | 1905 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.20 Median:67.00 stddev:1.55 (2.3%) stddev-sans-first:1.64 03:20:34 INFO - PROCESS | 1905 | Values: 67.0 68.0 71.0 67.0 67.0 67.0 66.0 67.0 67.0 65.0 03:20:34 INFO - PROCESS | 1905 | -------- Summary: end -------- 03:20:34 INFO - PROCESS | 1905 | 03:20:35 INFO - TEST-INFO | 1905: exit 0 03:20:35 INFO - TEST-OK | kraken | took 61372ms 03:20:35 INFO - SUITE-END | took 1178s 03:20:35 INFO - Completed test suite (00:19:39) 03:20:35 INFO - javascript benchmark 03:20:35 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7610.32812933873, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3464.3764122457956, 3471.87848425305, 3478.8608455661774, 3485.6165821759587, 3516.2088530080737, 5989.88020239595, 6033.818985430438, 6077.3922607739205, 6106.511479081674, 6126.23422072241, 6086.0, 6135.72389242484, 6153.969230153843, 6157.722902469389, 6162.198914141155, 5479.506844384005, 5521.972390138044, 5538.196961440589, 5588.105903055512, 5604.663720176782, 5539.473749993744, 5573.0, 5573.219749235111, 5576.470235327637, 5584.832455026335, 5351.357837059559, 5394.109971854639, 5582.330120385559, 5602.187682785993, 5637.492625663689, 5512.062949298629, 5524.226608274825, 5538.113901775697, 5559.972200139014, 5582.302212223472, 5410.1343784994215, 5513.0, 5529.806456774034, 5532.585056120807, 5547.72261386933, 5862.325832529271, 6124.081387791867, 6124.510039196898, 6184.8763024739255, 6226.128342032119, 6270.623762574214, 6331.8417039573915, 6360.52296077796, 6396.648184349859, 6421.036844473319, 5185.533302002837, 6416.844967905775, 6545.9018114728315, 6552.213734351861, 6563.671816409208, 6209.87580248397, 6224.657643829542, 6227.532935029891, 6230.750769969196, 6237.875242495215, 6239.78160764371, 6418.422341989243, 6430.3891130342545, 6587.604743715392, 6589.9341006590275, 6349.52378571609, 6369.936300636934, 6376.7130479128555, 6393.6483493408305, 6400.583962042452, 6390.9360906391275, 6454.128692626436, 6459.354064593597, 6474.967625161844, 6477.4494167996245, 6222.284437289679, 6239.1577137085915, 6283.748650053946, 6364.554481186273, 6396.488280937514, 6253.593516421418, 6379.075034120026, 6417.903731444032, 6438.195225596801, 6442.259140198843, 5109.489051094935, 5157.406898206679, 5227.3988491323225, 5238.266642670029, 5243.0, 4583.083383323282, 4587.3348364486965, 4615.3999980003055, 4645.841971435546, 4708.387909571734, 5967.1347654589845, 6020.608660437058, 6178.752849886044, 6200.658963756947, 6203.937960620427, 6303.086052522359, 6320.241571011508, 6416.518761092843, 6438.195225596801, 6441.355864413615, 6229.626222426669, 6235.282942461584, 6242.09489624002, 6261.279952805395, 6264.655443950535, 5782.017057100303, 5796.855078623068, 5811.389804070596, 5822.243108395882, 5836.9124463133085, 5888.528917686489, 6349.523785715997, 6550.312217217314, 6567.573107747981, 6786.083878676414, 5527.336719593674, 6410.391012853772, 6472.805815825533, 6485.643289619116, 6512.283648798533, 6667.19993600771, 6756.69594868242, 6771.559848609824, 6773.119494465688, 6786.796396108125, 6252.187215661935, 6259.405356491126, 6314.873702526015, 6398.936010639834, 6425.421712045938, 6416.743330266737, 6472.935270647234, 6508.0, 6516.315786842313, 6517.283098859027, 5385.676859388449, 6110.297315808739, 6336.683165841598, 6339.985602303609, 6366.713497892513, 6191.442770150708, 6240.812775616739, 6275.435210831047, 6303.432691057827, 6358.682065896594, 6120.44915957566, 6134.375358695336, 6207.534434917309, 6269.0, 6277.529185311211, 6344.302126766144, 6354.777582784581, 6358.936410635834, 6363.3000369958345, 6368.554201206057, 6043.335233124265, 6126.326104128631, 6144.986077297196, 6246.1255424239735, 6290.9056364155485], "value": 5961.893398473163, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [739.8261408568987, 741.1661880384568, 821.7863355527561, 915.8855143107112, 959.8704174936381, 32215.838920805392, 37766.811165944164, 40260.0, 40281.59718402815, 40387.79806100973, 33029.0, 38952.0, 39149.80425097878, 39162.0, 39263.0, 5222.791088356461, 5296.973515132419, 10611.204159688015, 10940.288881222696, 11063.446827658625, 10742.409167495785, 10824.891751082507, 10852.294600850959, 10856.131509479263, 10872.728181795439, 9203.4477931324, 10854.565817367296, 10879.347239165636, 10899.727506812314, 10917.563297468132, 9196.586153623102, 10821.729456763565, 10835.83746243807, 10859.782804343908, 10878.836817447745, 10556.524956376981, 10574.894251057545, 10606.15150787935, 10625.256232063759, 10657.893421065844, 33824.32351352959, 34178.48732269018, 34316.0, 34350.14124646879, 34396.6560334396, 3229.6931791479774, 3620.8732694355836, 3741.5136032315627, 3745.494358261627, 3805.1058001369656, 25193.0, 25365.239042828744, 26200.60699089515, 26466.867665661743, 27038.45923081547, 3044.1476386611785, 3075.8000729952705, 3164.7942883712717, 3268.7711860169798, 3284.3431313737346, 269953.0, 299362.0, 334725.0, 340790.0, 343370.0, 41833.163336733094, 45080.3237951431, 45162.32256516155, 45164.322535162, 45165.54834451614, 45853.85365365892, 45922.0, 45959.0, 46082.0, 46110.76944615255, 12024.038076953822, 13286.468541258435, 13739.96950228737, 13792.241426721426, 13827.446902124004, 11680.948714615726, 11910.523579056913, 11934.641960741192, 11937.403129843473, 11959.940200298943, 10126.69619911389, 12033.518659253707, 12058.216215946111, 12069.939650301692, 12145.69635759113, 5511.724413779295, 5589.329280486368, 5589.80435684749, 5598.916016259759, 5601.467860553241, 970.8834939807268, 973.5813600151861, 993.5529011944653, 1002.9097381235724, 1018.2770233134557, 26524.734752652614, 26833.731662683513, 27131.186064417703, 27216.319592010357, 27231.774570144393, 909.4770506958525, 972.2562239886493, 1056.762228498597, 1089.3463921646949, 1103.2442776697987, 11745.941270293593, 13439.865601343861, 13689.59885272663, 13985.3706583204, 14031.78952315716, 438688.0, 602094.0, 614205.0, 615665.0, 617655.0, 41596.3361465538, 42145.0, 49122.75438622855, 49167.50832491629, 49597.504024959286, 1376.1124074971588, 1392.4778208171936, 1410.26666133612, 1411.5483045425572, 1412.2726795700223, 27346.0, 27473.86263068672, 27977.72022279751, 28056.43887122287, 28387.574186386806, 41660.58339416567, 41747.582524174366, 41858.744237672916, 41861.79069104635, 42071.0, 365316.0, 459739.70130149135, 459975.0, 460010.0, 462142.0, 30469.0, 30758.384832303676, 30797.538036929913, 30811.383772324876, 30815.845920770254, 38151.80924095362, 38341.04147396337, 39252.607473924894, 39276.0, 39362.40956385714, 27306.0, 27402.0, 32560.0, 32684.0, 32701.836490817393, 32314.515282271255, 32373.866914657872, 32771.836140820094, 32790.5081423774, 33306.500402494465, 32811.179720506225, 32843.68625254963, 32879.34241315208, 32895.67104328926, 33911.32177356489, 26851.865740671954, 28539.28651783722, 29958.0, 30151.849240753654, 30233.848830755705, 19295.517612059248, 20827.229685476905, 23738.287851364486, 23892.641610376206, 23904.52190956206], "value": 18038.160040691142, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [359.45901418365366, 407.3929844531648, 408.11031950348246, 408.51794882039184, 449.17127899026303, 17713.64572708545, 20077.498062548424, 20289.695654565156, 21368.25211117613, 21414.0, 20487.897560512214, 20685.870508765423, 20967.84676842773, 21248.787512124913, 21382.572348553018, 6262.655553944543, 6462.967685161568, 6476.0, 6517.543771935966, 6524.282328943812, 5612.69130197841, 6350.0, 6445.903311450332, 6897.379235868771, 7269.927300727004, 7343.853122937538, 7373.336399724024, 7375.815604609874, 7382.741604043859, 7384.409247260233, 7081.398081163107, 7270.6001169935635, 7330.0, 7331.0, 7475.02824632797, 6948.305169483011, 7024.824379390505, 7091.503594748369, 7120.038794762744, 7136.464765142583, 23948.760512394827, 24122.758772412402, 24990.750092499024, 25074.373140671447, 25313.98744050236, 2782.8330300182056, 2830.985845070782, 2883.18656441045, 2895.4643390972738, 2933.48663983802, 14619.122852628876, 14701.926490367587, 14920.62698432537, 15134.545963621109, 15329.770053449207, 1974.4570243183095, 2019.9798002020086, 2021.929232476871, 2025.2202901882881, 2028.3103744726716, 165248.17375913163, 166713.0, 174565.0, 177558.0, 178560.0, 17796.288148474174, 18700.25198992052, 20192.39422817318, 20198.495037624176, 20341.288054918008, 30849.69150308513, 31268.530972035438, 31443.213919652193, 31458.0, 31486.740530378986, 9981.401115933068, 10131.392116473035, 10283.331583447052, 10284.28010039305, 10380.532876020598, 8540.572971351408, 8552.700655477029, 8568.186022327869, 8712.259457946146, 8723.563821809012, 9115.817683646423, 9215.44707317563, 9282.582283797245, 9301.581428835718, 9325.860112098324, 3901.31726947783, 3935.2916475034767, 3950.1309711863346, 3959.4456776051375, 3967.3652215645357, 865.4634126841398, 985.8915519292872, 1031.4687935713114, 1031.5512751952924, 1067.7063807452857, 17027.97832130076, 19771.901140494494, 19786.10962506662, 19908.90045549763, 20094.598108037757, 881.2861582118504, 961.8890181839986, 1009.6466236817055, 1013.2501948558014, 1046.7487802927394, 7662.3487003604205, 8027.357811375076, 9732.659356922615, 10272.897271027194, 10428.270021098602, 683248.0, 691030.0, 711876.0, 734647.0, 754316.0, 118779.40610297066, 124569.0, 146222.0, 155417.0, 162186.0, 1170.5025364220173, 1193.480835836414, 1218.3908045977012, 1266.866978967195, 1283.5700040486354, 18382.72425913639, 18862.905685471484, 19020.524486887938, 19165.32921347746, 19216.327428539935, 56078.0, 64359.0, 64806.67596661986, 65511.68976620345, 65883.67058164679, 77104.84342734751, 90075.54962225146, 90358.54820725854, 91892.54053729688, 92677.5366123192, 10734.355938643706, 11145.944270278596, 11211.66365009051, 11614.535418583162, 11651.475683594428, 12101.818472723093, 12112.878871211527, 12171.878281217429, 12242.081843861568, 12501.874981250072, 10791.56833726642, 10886.836697449386, 10935.726606834893, 11057.668269951915, 11077.003069723763, 9149.954250228706, 10490.05589496949, 10641.201909856947, 10658.520366583367, 10984.450777461256, 10281.383117013003, 10671.0, 10719.732006699895, 10780.245382823126, 10890.455477225949, 11023.83464248053, 11097.33415995043, 11167.832482512606, 11188.552457901593, 11478.0, 9238.907610923805, 9412.858807117762, 9557.426554406758, 10540.0, 10633.096186824207], "value": 11309.36588973737, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1514.931828067737, 1547.8761699064069, 1556.7742677311783, 1564.6166689161157, 1569.6703692224633, 10755.83866242007, 10982.780344393108, 11185.888141118587, 11468.25456345338, 11497.080233581315, 11257.943710281457, 11441.199116061878, 11478.0, 11482.196246262765, 11517.36654484003, 2389.0, 2426.9757302427015, 2436.0864675746593, 2443.328084776692, 2444.804415646744, 2392.5693375192545, 2415.6497307890345, 2431.2098567965395, 2435.585950388438, 2439.5730747119273, 2382.9991403610525, 2403.639454081884, 2408.1330720940446, 2413.070967677439, 2415.722191947922, 2363.1847012780736, 2386.05750728463, 2387.7970372518357, 2397.7482364351667, 2401.639754036891, 2329.126577533425, 2346.5189636124555, 2401.075585899441, 2402.25530085674, 2406.602910519762, 11195.888041119648, 11239.438028098562, 11253.324800511913, 11262.380569068699, 11273.041791447737, 5819.098039803837, 5945.791897283618, 5989.401059894019, 6002.519798416116, 6007.068904319837, 3202.823844688541, 3445.3109378124223, 3608.8195590220384, 3625.6374362563797, 3656.926861462756, 1034.5396298647106, 1182.7634473105413, 1231.7536492701406, 1233.444949772597, 1258.8489381274214, 13128.343582820917, 13599.04806663534, 13813.723725525533, 15496.380144794195, 15630.609234769108, 4584.839530616479, 4616.030633566988, 4797.904041919142, 4807.18277892759, 4810.735409552508, 11680.824787628193, 11800.822987655192, 11838.526458941718, 11848.94075529634, 11908.702282443008, 5899.7050147492455, 5917.704114794329, 5922.111683247547, 5938.584299099108, 5942.465178133989, 4499.775011249424, 4540.886477838015, 5232.3721153461065, 5270.446603106695, 5352.0, 3870.1679138985255, 3897.590752970954, 3935.0, 3957.6438120569287, 4003.6797056236014, 914.826183025223, 1034.591336422109, 1046.0742243114898, 1050.3855244681795, 1059.0627294844119, 788.7712563356677, 921.9585118669676, 930.7673081729567, 947.8199142162968, 992.5880759484877, 6550.443212327005, 6560.704768285439, 6594.142761440982, 6656.134702488645, 6658.067870498134, 920.7928216151446, 966.3139171188394, 978.2760757039739, 985.2856678907734, 989.618996686281, 3548.822558872046, 4208.9789551052045, 4262.104957958863, 4421.38100665907, 4432.3351497275025, 13514.797278040838, 13955.930220349037, 13975.860241397659, 13978.86021139796, 13982.0, 13489.2580908051, 13544.661383465493, 13848.307584620728, 13933.721325573635, 14055.367508462145, 8782.560871956377, 10332.845007324897, 10344.793104138025, 10551.683449496528, 10632.734181645521, 6313.526485513513, 6383.39357761012, 6651.13535240435, 6941.167059952838, 6973.895391569232, 11956.641300760992, 12463.31451770125, 12467.064970127096, 12573.874261257637, 12608.684782880502, 12426.06804489685, 12573.30846803398, 12745.17156384832, 12804.551840685532, 12900.870991290343, 9383.624655013997, 9385.436873787594, 9449.48027858447, 9535.713928582154, 9540.522973851419, 10243.38539687621, 10311.845322320021, 10335.966403359604, 10442.530086146293, 10448.42533660656, 8186.672533098847, 8628.52543110135, 9230.492322922304, 9337.253019758538, 9501.57242924084, 8811.647534098563, 8971.955140224258, 9020.0, 9051.683191088281, 9055.185033346766, 7264.309890560388, 7471.701131954661, 7923.326517246099, 7953.32396746283, 8031.638576264199, 5902.704864756831, 5956.463918247378, 5975.671338076448, 5999.0, 6117.510599152146, 5325.547328477122, 5682.6306290091015, 5873.911891321548, 5943.762249509971, 6122.0], "value": 5157.222636497141, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [305.2810630964078, 371.88657459474865, 378.68947463080275, 382.2679568626081, 386.04646523087973, 4466.464024317078, 4768.78540465679, 5091.414487333957, 5095.388553373596, 5143.485651434859, 4357.474883790672, 4684.297355396684, 5059.443461219263, 5110.335656364668, 5143.562797162245, 4237.152569486099, 4283.357496375545, 4304.074623955848, 4347.869563913087, 4356.847510337139, 3488.633693462188, 3927.803609819512, 4051.554329023805, 4099.569545197756, 4350.195213885441, 3760.8119594020327, 3940.074082590589, 4117.8970525736795, 4345.456817897762, 4349.499807522144, 3780.773153610792, 4102.338552886086, 4220.7467551946675, 4277.395976508825, 4328.848490302824, 3463.8268086595567, 3683.5027271318563, 3816.2749077675153, 3938.2714197873483, 3956.724883192176, 4746.430428348585, 4911.557959783637, 4987.750612469362, 5002.774875130592, 5056.544910958031, 4591.334256532784, 4966.404031516241, 4990.604509835263, 5004.399472063339, 5010.173321401964, 4591.563801438858, 5160.09076641645, 5336.692510334954, 5441.700706461143, 5516.200150978086, 3871.728978971473, 4202.92825329539, 4295.52749197588, 4424.668149888771, 4465.303184789792, 4638.605718513931, 5254.579633629301, 5467.179464156547, 5468.45716741717, 5857.560682948796, 3783.9972407312084, 4312.439382880206, 4482.5069242383315, 4505.977470112629, 4542.410825829312, 4130.008797888545, 4168.7707176105605, 4441.9117316257725, 4509.1658043262105, 4570.702904311209, 4214.704970652025, 4492.0, 4887.240593386378, 4952.653314268039, 5000.374953130859, 5269.55208807248, 5509.3664228614225, 5509.889802204014, 5553.916986187654, 5559.665680236714, 1386.8266466691664, 1460.7729507213992, 1476.5422718957157, 1542.6760380320259, 1547.1258738812535, 961.6392804182144, 1072.126217133034, 1088.6026600290836, 1108.6230681568306, 1171.226990186473, 4351.608355248043, 4830.589399900978, 4930.778114984834, 5048.949510504922, 5099.260607211933, 5370.221317908881, 5374.420278266454, 5384.730763461811, 5702.45826646468, 5722.77108915647, 4224.301999332916, 4695.389599352063, 4942.678725882806, 5122.410922743858, 5149.536541711264, 2953.8818447262297, 2967.6438827340858, 2971.870689138114, 2980.195347256272, 2985.4178435205363, 5014.974925125351, 5446.591505637093, 5635.943640563624, 5837.6497410155525, 5928.9465925048635, 4713.67004309695, 5148.794048238104, 5201.921971170435, 5551.944480555224, 5835.562332825055, 4906.06784710911, 5022.522860328263, 5037.891663833951, 5194.818181363634, 5825.417458254141, 5032.496750325085, 5085.059264036166, 5157.922631160461, 5756.769729210952, 5778.566607504538, 4817.7350245736825, 4874.000829829947, 5540.390557038642, 5679.233303503974, 5694.369544809974, 5175.896482070262, 5243.344581927259, 5753.798617048383, 5938.268396537468, 6008.519318454601, 4482.551744825621, 4497.26855160772, 4499.820007199675, 4502.694218676547, 4515.096980603958, 5157.7679004444135, 5232.895342093213, 5285.180796976475, 5706.885862282814, 5733.226014487991, 4768.713877167381, 4873.342098816614, 4984.651074424755, 5194.506521880416, 5503.285991090863, 4755.667103302874, 5123.820666276662, 5268.0, 5373.785048598169, 5537.363203231519, 4647.651426142985, 4842.95157048425, 4923.104604727278, 5019.623528235324, 5191.845384165529, 4706.411698537683, 5019.472955339783, 5206.817761378486, 5547.750351234124, 5561.694106824001, 4443.755903268829, 4816.590589799906, 5297.682139071668, 5317.8404647860625, 5321.3880403754065], "value": 4144.400531335752, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [591.3057258599684, 707.978760637181, 731.1920328037519, 773.6054612147803, 847.724489540899, 15071.924640376796, 16250.0, 16257.918710406446, 16286.918565407157, 16292.10393428361, 14171.433142674307, 14398.280085995711, 14554.053986490871, 14565.41738330468, 14570.927145364285, 920.3235621817976, 940.7742141885966, 941.7080704981444, 946.394307643109, 949.1268033409245, 900.455224589124, 928.299134153713, 943.0710749911332, 945.1682519382967, 948.7485816258697, 1004.6985904228702, 1006.0392325329329, 1006.7533454303706, 1007.098646711193, 1010.4139599032543, 996.3872218585554, 999.585172153559, 1001.0240015984393, 1001.8747656542932, 1002.2433063037433, 740.0859937976595, 740.2375553180233, 740.829609189884, 741.5180132913596, 741.8738814401564, 15655.452059177875, 15815.762763558556, 16106.275217615119, 16557.503274901774, 16858.747118793228, 13933.512327068605, 14104.365303561364, 14140.92929535356, 14151.575452736435, 14252.786208206886, 11531.132461365083, 11748.845337660716, 11785.0, 11812.173147879652, 11888.762224755457, 3390.491426286052, 3567.4648802679544, 3603.207294395229, 3638.872639457606, 3750.606186350421, 93948.0, 94900.52549737276, 107418.46290768497, 108118.0, 108293.45853270762, 10124.645637402655, 10237.692869213935, 10590.629327973484, 10740.516676749565, 10810.405427701551, 22230.221942231943, 22420.88789556042, 22449.21427750021, 22546.77453225447, 22558.0976760931, 7233.493655444068, 7248.565086094851, 7299.5255308404785, 7350.338986420645, 7357.190709022003, 3522.454019626962, 4268.509121451011, 4345.435093437833, 4355.412019377407, 4395.912081758347, 3352.7317814575263, 3423.8288085595623, 3432.2449061206494, 3443.810590417501, 3444.7071998879874, 623.233422889848, 624.9625022498665, 625.3652542669195, 626.3392121311969, 627.3538255596743, 620.0777589472599, 647.8315637934171, 647.834802125455, 655.5771527364918, 683.8256244657581, 9775.51122443875, 10059.0, 10079.193664506822, 10134.341267817568, 10184.6435374763, 555.2504119438726, 560.2436362005143, 606.6784604159802, 674.7166190200128, 683.135833171039, 4424.292113261927, 4930.9506904930495, 4968.72672003036, 5070.721110338966, 5077.289179514871, 94338.0, 97268.0273197273, 97776.0, 98634.0, 98813.50593246988, 78908.6054569735, 79773.0, 80749.59625201837, 81637.59181204055, 82599.0, 75878.0, 77428.61285693536, 77569.83645245325, 77882.6105869467, 78524.21475785169, 9746.463944482837, 9906.25703072258, 9922.255830812574, 9933.900660993297, 9954.153896918842, 38059.0, 47162.76418617885, 47462.525374747194, 47636.2854557175, 48829.0234195307, 50215.74892125516, 50815.49184508107, 64510.0, 65009.67495162494, 65543.67228163828, 12821.679458013625, 12889.8066529004, 12974.805377919527, 12997.480100795863, 13163.0, 12074.973627241783, 14531.854681453051, 14735.55793326202, 15132.848671513144, 15772.60568485797, 13525.391357389139, 13629.659258518617, 13835.861641383457, 13872.514461993782, 14201.644958876112, 11437.59968401102, 13125.73748525005, 13784.0, 13791.310434478437, 13932.373043213283, 10786.460676965964, 13281.933590331986, 13689.45242190341, 13943.16341019542, 14266.286685665467, 10529.947350263199, 10907.600327927843, 10934.890651093387, 11025.503852326825, 11296.0, 9729.562169702238, 10369.896301037195, 10484.947575262075, 10498.422586757802, 10510.737231569272], "value": 7472.87925358797, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1516.4, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [106.0, 117.0, 118.0, 121.0, 118.0, 118.0, 118.0, 117.0, 117.0, 119.0], "value": 116.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [151.0, 110.0, 113.0, 145.0, 114.0, 116.0, 110.0, 132.0, 124.0, 138.0], "value": 125.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [205.0, 199.0, 192.0, 198.0, 198.0, 281.0, 214.0, 200.0, 198.0, 198.0], "value": 208.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 58.0, 80.0, 112.0, 52.0, 73.0, 84.0, 87.0, 64.0, 77.0], "value": 77.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 73.0, 87.0, 70.0, 71.0, 72.0, 82.0, 69.0, 72.0, 83.0], "value": 76.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 93.0, 81.0, 134.0, 123.0, 92.0, 92.0, 92.0, 93.0, 86.0], "value": 97.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [98.0, 99.0, 99.0, 125.0, 154.0, 99.0, 98.0, 99.0, 98.0, 147.0], "value": 111.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 96.0, 92.0, 93.0, 91.0, 89.0, 87.0, 93.0, 142.0, 137.0], "value": 101.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 65.0, 67.0, 68.0, 69.0, 69.0, 69.0, 69.0, 70.0, 70.0], "value": 68.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 56.0, 61.0, 64.0, 59.0, 61.0, 59.0, 61.0, 59.0, 60.0], "value": 60.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 85.0, 84.0, 89.0, 90.0, 91.0, 83.0, 89.0, 91.0, 111.0], "value": 90.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [140.0, 146.0, 151.0, 140.0, 140.0, 163.0, 143.0, 136.0, 150.0, 149.0], "value": 145.8, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [170.0, 173.0, 169.0, 168.0, 170.0, 170.0, 169.0, 168.0, 168.0, 165.0], "value": 169.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 68.0, 71.0, 67.0, 67.0, 67.0, 66.0, 67.0, 67.0, 65.0], "value": 67.2, "unit": "score"}]}]} 03:20:35 INFO - Return code: 0 03:20:35 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 03:20:35 ERROR - # TBPL SUCCESS # 03:20:35 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 03:20:35 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 03:20:35 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 03:20:35 INFO - 2017-03-22 03:20:35.855 screenresolution[1910:12161] starting screenresolution argv=screenresolution get 03:20:35 INFO - 2017-03-22 03:20:35.858 screenresolution[1910:12161] Display 0: 1600x1200x32@60 03:20:35 INFO - 2017-03-22 03:20:35.864 screenresolution[1911:12164] starting screenresolution argv=screenresolution list 03:20:35 INFO - Available Modes on Display 0 03:20:35 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 03:20:35 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 03:20:35 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 03:20:35 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 03:20:36 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 03:20:36 INFO - Intel Iris: 03:20:36 INFO - Chipset Model: Intel Iris 03:20:36 INFO - Type: GPU 03:20:36 INFO - Bus: Built-In 03:20:36 INFO - VRAM (Dynamic, Max): 1536 MB 03:20:36 INFO - Vendor: Intel (0x8086) 03:20:36 INFO - Device ID: 0x0a2e 03:20:36 INFO - Revision ID: 0x0009 03:20:36 INFO - Displays: 03:20:36 INFO - Display: 03:20:36 INFO - Resolution: 1600 x 1200 @ 60 Hz 03:20:36 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 03:20:36 INFO - Display Serial Number: 1600x1200 60 03:20:36 INFO - Main Display: Yes 03:20:36 INFO - Mirror: Off 03:20:36 INFO - Online: Yes 03:20:36 INFO - Rotation: Supported 03:20:36 INFO - Return code: 0 03:20:36 INFO - Running post-action listener: _resource_record_post_action 03:20:36 INFO - [mozharness: 2017-03-22 10:20:36.099164Z] Finished run-tests step (success) 03:20:36 INFO - Running post-run listener: _resource_record_post_run 03:20:36 INFO - Total resource usage - Wall time: 1200s; CPU: 55.0%; Read bytes: 3241472; Write bytes: 512311296; Read time: 28; Write time: 1617 03:20:36 INFO - TinderboxPrint: CPU usage
54.7% 03:20:36 INFO - TinderboxPrint: I/O read bytes / time
3,241,472 / 28 03:20:36 INFO - TinderboxPrint: I/O write bytes / time
512,311,296 / 1,617 03:20:36 INFO - TinderboxPrint: CPU idle
2,176.5 (45.3%) 03:20:36 INFO - TinderboxPrint: CPU system
968.0 (20.2%) 03:20:36 INFO - TinderboxPrint: CPU user
1,657.2 (34.5%) 03:20:36 INFO - TinderboxPrint: Swap in / out
212,299,776 / 0 03:20:36 INFO - install - Wall time: 21s; CPU: 54.0%; Read bytes: 202025472; Write bytes: 199847936; Read time: 15601; Write time: 421 03:20:36 INFO - run-tests - Wall time: 1180s; CPU: 55.0%; Read bytes: 2373120; Write bytes: 301694976; Read time: 25; Write time: 1160 03:20:36 INFO - Running post-run listener: _upload_blobber_files 03:20:36 INFO - Blob upload gear active. 03:20:36 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 03:20:36 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 03:20:36 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 03:20:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 03:20:36 INFO - (blobuploader) - INFO - Open directory for files ... 03:20:36 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 03:20:36 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:20:36 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:20:40 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 03:20:40 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:20:40 INFO - (blobuploader) - INFO - Done attempting. 03:20:40 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 03:20:40 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:20:40 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:20:42 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 03:20:42 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:20:42 INFO - (blobuploader) - INFO - Done attempting. 03:20:42 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 03:20:42 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:20:42 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:20:43 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 03:20:43 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:20:43 INFO - (blobuploader) - INFO - Done attempting. 03:20:43 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 03:20:43 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:20:43 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:20:45 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 03:20:45 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:20:45 INFO - (blobuploader) - INFO - Done attempting. 03:20:45 INFO - (blobuploader) - INFO - Iteration through files over. 03:20:45 INFO - Return code: 0 03:20:45 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 03:20:45 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 03:20:45 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/a4485fbe543cb46a52193f93aa5cb169a3868108dbf513d02d5c680074d5c3e03fddfe4436fb37731c804482c0026b7c9f970db8c232ab7f842ded225b0a60b7", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/b5cbd134056ce91dd4fd4a2219e85795fea6a373b5f8bfb7ee2985fd5a5acf48209efb27ae3ef2429c1eba8b2deeeee0be01ac56bbe08f813a67313d5ff69e1f", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e7e0e486b43810148aad119a8702b3d70e777bd02d223dfd586b07396676b9ecfced219dceebeb24a8da3cc12c49ecab73afd361c246f4535c6e50e0a942d985", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/d7fe47f578350bf73d6ef3fd6df11b9eb0cff08ecc2967a0eb8d125ada053b4480f1ff57ce8450f5f1ffdb824bae67b8991c170ccce6ca8ec0996215a77a05c0"} 03:20:45 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 03:20:45 INFO - Writing to file /builds/slave/test/properties/blobber_files 03:20:45 INFO - Contents: 03:20:45 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/a4485fbe543cb46a52193f93aa5cb169a3868108dbf513d02d5c680074d5c3e03fddfe4436fb37731c804482c0026b7c9f970db8c232ab7f842ded225b0a60b7", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/b5cbd134056ce91dd4fd4a2219e85795fea6a373b5f8bfb7ee2985fd5a5acf48209efb27ae3ef2429c1eba8b2deeeee0be01ac56bbe08f813a67313d5ff69e1f", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e7e0e486b43810148aad119a8702b3d70e777bd02d223dfd586b07396676b9ecfced219dceebeb24a8da3cc12c49ecab73afd361c246f4535c6e50e0a942d985", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/d7fe47f578350bf73d6ef3fd6df11b9eb0cff08ecc2967a0eb8d125ada053b4480f1ff57ce8450f5f1ffdb824bae67b8991c170ccce6ca8ec0996215a77a05c0"} 03:20:45 INFO - Running post-run listener: copy_logs_to_upload_dir 03:20:45 INFO - Copying logs to upload dir... 03:20:45 INFO - mkdir: /builds/slave/test/build/upload/logs 03:20:45 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1283.992127 ========= master_lag: 0.11 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 24 secs) (at 2017-03-22 03:20:45.618040) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-22 03:20:45.624353) ========= 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.KNRQsa1F3W/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.mXFqTmDUa3/Listeners TMPDIR=/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/a4485fbe543cb46a52193f93aa5cb169a3868108dbf513d02d5c680074d5c3e03fddfe4436fb37731c804482c0026b7c9f970db8c232ab7f842ded225b0a60b7", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/b5cbd134056ce91dd4fd4a2219e85795fea6a373b5f8bfb7ee2985fd5a5acf48209efb27ae3ef2429c1eba8b2deeeee0be01ac56bbe08f813a67313d5ff69e1f", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e7e0e486b43810148aad119a8702b3d70e777bd02d223dfd586b07396676b9ecfced219dceebeb24a8da3cc12c49ecab73afd361c246f4535c6e50e0a942d985", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/d7fe47f578350bf73d6ef3fd6df11b9eb0cff08ecc2967a0eb8d125ada053b4480f1ff57ce8450f5f1ffdb824bae67b8991c170ccce6ca8ec0996215a77a05c0"} build_url:https://queue.taskcluster.net/v1/task/NF7XhXjMRceWKAJ10VwVOg/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011191 build_url: 'https://queue.taskcluster.net/v1/task/NF7XhXjMRceWKAJ10VwVOg/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/a4485fbe543cb46a52193f93aa5cb169a3868108dbf513d02d5c680074d5c3e03fddfe4436fb37731c804482c0026b7c9f970db8c232ab7f842ded225b0a60b7", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/b5cbd134056ce91dd4fd4a2219e85795fea6a373b5f8bfb7ee2985fd5a5acf48209efb27ae3ef2429c1eba8b2deeeee0be01ac56bbe08f813a67313d5ff69e1f", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e7e0e486b43810148aad119a8702b3d70e777bd02d223dfd586b07396676b9ecfced219dceebeb24a8da3cc12c49ecab73afd361c246f4535c6e50e0a942d985", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/d7fe47f578350bf73d6ef3fd6df11b9eb0cff08ecc2967a0eb8d125ada053b4480f1ff57ce8450f5f1ffdb824bae67b8991c170ccce6ca8ec0996215a77a05c0"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-22 03:20:45.664297) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-22 03:20:45.664673) ========= 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.KNRQsa1F3W/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.mXFqTmDUa3/Listeners TMPDIR=/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/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.005200 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-22 03:20:45.713653) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-22 03:20:45.713997) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-22 03:20:45.714342) ========= ========= Total master_lag: 1.77 =========