builder: mozilla-aurora_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0373 starttime: 1488993991.77 results: success (0) buildid: 20170308073723 builduid: 0c2d28d0d38c4fe7a9627d3cedbe2f85 revision: ae315069c70eb5fe7da4dd2b158b3eee3ca9b94a ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-03-08 09:26:31.774182) ========= master: http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-03-08 09:26:31.774656) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-08 09:26:31.774968) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-08 09:26:31.799997) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-08 09:26:31.800389) ========= 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.HS2ehUAfQ5/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.uQahpadQ75/Listeners TMPDIR=/var/folders/w3/rmzpg_rj3zjdrsx94grbz4kr00000w/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-08 09:26:32-- 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% 9.87M=0.001s 2017-03-08 09:26:32 (9.87 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.116950 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-08 09:26:31.940700) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-08 09:26:31.941028) ========= 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.HS2ehUAfQ5/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.uQahpadQ75/Listeners TMPDIR=/var/folders/w3/rmzpg_rj3zjdrsx94grbz4kr00000w/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.021012 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-08 09:26:31.990898) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-03-08 09:26:31.991242) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev ae315069c70eb5fe7da4dd2b158b3eee3ca9b94a --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 ae315069c70eb5fe7da4dd2b158b3eee3ca9b94a --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.HS2ehUAfQ5/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.uQahpadQ75/Listeners TMPDIR=/var/folders/w3/rmzpg_rj3zjdrsx94grbz4kr00000w/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-08 09:26:32,271 truncating revision to first 12 chars 2017-03-08 09:26:32,271 Setting DEBUG logging. 2017-03-08 09:26:32,271 attempt 1/10 2017-03-08 09:26:32,271 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/ae315069c70e?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-03-08 09:26:33,372 unpacking tar archive at: mozilla-aurora-ae315069c70e/testing/mozharness/ program finished with exit code 0 elapsedTime=1.376764 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-03-08 09:26:33.391653) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-08 09:26:33.391985) ========= script_repo_revision: ae315069c70eb5fe7da4dd2b158b3eee3ca9b94a ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-08 09:26:33.392426) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-08 09:26:33.392689) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-08 09:26:33.412293) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 23 secs) (at 2017-03-08 09:26:33.412575) ========= /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.HS2ehUAfQ5/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.uQahpadQ75/Listeners TMPDIR=/var/folders/w3/rmzpg_rj3zjdrsx94grbz4kr00000w/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 09:26:33 INFO - MultiFileLogger online at 20170308 09:26:33 in /builds/slave/test 09:26:33 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 09:26:33 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 09:26:33 INFO - {'append_to_log': False, 09:26:33 INFO - 'base_work_dir': '/builds/slave/test', 09:26:33 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 09:26:33 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 09:26:33 INFO - 'branch': 'Mozilla-Aurora', 09:26:33 INFO - 'buildbot_json_path': 'buildprops.json', 09:26:33 INFO - 'config_files': ('talos/mac_config.py',), 09:26:33 INFO - 'default_actions': ('clobber', 09:26:33 INFO - 'read-buildbot-config', 09:26:33 INFO - 'download-and-extract', 09:26:33 INFO - 'populate-webroot', 09:26:33 INFO - 'create-virtualenv', 09:26:33 INFO - 'install', 09:26:33 INFO - 'run-tests'), 09:26:33 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:26:33 INFO - 'download_minidump_stackwalk': True, 09:26:33 INFO - 'download_symbols': 'ondemand', 09:26:33 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 09:26:33 INFO - 'tooltool.py': '/tools/tooltool.py', 09:26:33 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 09:26:33 INFO - '/tools/misc-python/virtualenv.py')}, 09:26:33 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:26:33 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:26:33 INFO - 'gecko_profile': False, 09:26:33 INFO - 'gecko_profile_interval': 0, 09:26:33 INFO - 'installer_path': 'installer.exe', 09:26:33 INFO - 'log_level': 'info', 09:26:33 INFO - 'log_name': 'talos', 09:26:33 INFO - 'log_to_console': True, 09:26:33 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 09:26:33 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 09:26:33 INFO - 'opt_config_files': (), 09:26:33 INFO - 'pip_index': False, 09:26:33 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:26:33 INFO - 'cmd': ('bash', 09:26:33 INFO - '-c', 09:26:33 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:26:33 INFO - 'enabled': True, 09:26:33 INFO - 'halt_on_failure': False, 09:26:33 INFO - 'name': 'check_screen_resolution'},), 09:26:33 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:26:33 INFO - 'cmd': ('bash', 09:26:33 INFO - '-c', 09:26:33 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:26:33 INFO - 'enabled': True, 09:26:33 INFO - 'halt_on_failure': False, 09:26:33 INFO - 'name': 'check_screen_resolution'},), 09:26:33 INFO - 'run_cmd_checks_enabled': True, 09:26:33 INFO - 'suite': 'dromaeojs-e10s', 09:26:33 INFO - 'system_bits': '32', 09:26:33 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:26:33 INFO - 'title': 't-yosemite-r7-0373', 09:26:33 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:26:33 INFO - 'use_talos_json': True, 09:26:33 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 09:26:33 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:26:33 INFO - 'work_dir': 'build'} 09:26:33 INFO - [mozharness: 2017-03-08 17:26:33.812730Z] Running clobber step. 09:26:33 INFO - Running pre-action listener: _resource_record_pre_action 09:26:33 INFO - Running main action method: clobber 09:26:33 INFO - rmtree: /builds/slave/test/build 09:26:33 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 09:26:35 INFO - Running post-action listener: _resource_record_post_action 09:26:35 INFO - [mozharness: 2017-03-08 17:26:35.530196Z] Finished clobber step (success) 09:26:35 INFO - [mozharness: 2017-03-08 17:26:35.530347Z] Running read-buildbot-config step. 09:26:35 INFO - Running pre-action listener: _resource_record_pre_action 09:26:35 INFO - Running main action method: read_buildbot_config 09:26:35 INFO - Using buildbot properties: 09:26:35 INFO - { 09:26:35 INFO - "project": "", 09:26:35 INFO - "product": "firefox", 09:26:35 INFO - "script_repo_revision": "production", 09:26:35 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs-e10s", 09:26:35 INFO - "repository": "", 09:26:35 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs-e10s", 09:26:35 INFO - "buildid": "20170308073723", 09:26:35 INFO - "pgo_build": "False", 09:26:35 INFO - "basedir": "/builds/slave/test", 09:26:35 INFO - "buildnumber": 5, 09:26:35 INFO - "slavename": "t-yosemite-r7-0373", 09:26:35 INFO - "master": "http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/", 09:26:35 INFO - "platform": "yosemite_r7", 09:26:35 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 09:26:35 INFO - "branch": "mozilla-aurora", 09:26:35 INFO - "repo_path": "releases/mozilla-aurora", 09:26:35 INFO - "revision": "ae315069c70eb5fe7da4dd2b158b3eee3ca9b94a", 09:26:35 INFO - "stage_platform": "macosx64", 09:26:35 INFO - "builduid": "0c2d28d0d38c4fe7a9627d3cedbe2f85", 09:26:35 INFO - "slavebuilddir": "test" 09:26:35 INFO - } 09:26:35 INFO - Found installer url https://queue.taskcluster.net/v1/task/fzptBtX2To2PHoHNb6yOPA/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg. 09:26:35 INFO - Running post-action listener: _resource_record_post_action 09:26:35 INFO - [mozharness: 2017-03-08 17:26:35.534588Z] Finished read-buildbot-config step (success) 09:26:35 INFO - [mozharness: 2017-03-08 17:26:35.534744Z] Running download-and-extract step. 09:26:35 INFO - Running pre-action listener: _resource_record_pre_action 09:26:35 INFO - Running main action method: download_and_extract 09:26:35 INFO - mkdir: /builds/slave/test/build/tests 09:26:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:26:35 INFO - trying https://queue.taskcluster.net/v1/task/fzptBtX2To2PHoHNb6yOPA/artifacts/public/build/firefox-54.0a2.en-US.mac.test_packages.json 09:26:35 INFO - Downloading https://queue.taskcluster.net/v1/task/fzptBtX2To2PHoHNb6yOPA/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 09:26:35 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/fzptBtX2To2PHoHNb6yOPA/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 09:26:36 INFO - Downloaded 1199 bytes. 09:26:36 INFO - Reading from file /builds/slave/test/build/firefox-54.0a2.en-US.mac.test_packages.json 09:26:36 INFO - Using the following test package requirements: 09:26:36 INFO - {u'common': [u'firefox-54.0a2.en-US.mac.common.tests.zip'], 09:26:36 INFO - u'cppunittest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 09:26:36 INFO - u'firefox-54.0a2.en-US.mac.cppunittest.tests.zip'], 09:26:36 INFO - u'gtest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 09:26:36 INFO - u'firefox-54.0a2.en-US.mac.gtest.tests.zip'], 09:26:36 INFO - u'jittest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 09:26:36 INFO - u'jsshell-mac.zip'], 09:26:36 INFO - u'mochitest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 09:26:36 INFO - u'firefox-54.0a2.en-US.mac.mochitest.tests.zip'], 09:26:36 INFO - u'mozbase': [u'firefox-54.0a2.en-US.mac.common.tests.zip'], 09:26:36 INFO - u'reftest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 09:26:36 INFO - u'firefox-54.0a2.en-US.mac.reftest.tests.zip'], 09:26:36 INFO - u'talos': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 09:26:36 INFO - u'firefox-54.0a2.en-US.mac.talos.tests.zip'], 09:26:36 INFO - u'web-platform': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 09:26:36 INFO - u'firefox-54.0a2.en-US.mac.web-platform.tests.zip'], 09:26:36 INFO - u'xpcshell': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 09:26:36 INFO - u'firefox-54.0a2.en-US.mac.xpcshell.tests.zip']} 09:26:36 INFO - Downloading packages: [u'firefox-54.0a2.en-US.mac.common.tests.zip'] for test suite category: common 09:26:36 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/fzptBtX2To2PHoHNb6yOPA/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip 09:26:36 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/fzptBtX2To2PHoHNb6yOPA/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip'}, attempt #1 09:26:36 INFO - Fetch https://queue.taskcluster.net/v1/task/fzptBtX2To2PHoHNb6yOPA/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip into memory 09:26:38 INFO - Expected file size: 51785990 09:26:38 INFO - Obtained file size: 51785990 09:26:54 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 09:26:54 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/fzptBtX2To2PHoHNb6yOPA/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip 09:26:54 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/fzptBtX2To2PHoHNb6yOPA/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip'}, attempt #1 09:26:54 INFO - Fetch https://queue.taskcluster.net/v1/task/fzptBtX2To2PHoHNb6yOPA/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip into memory 09:26:55 INFO - Expected file size: 51785990 09:26:55 INFO - Obtained file size: 51785990 09:27:11 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/fzptBtX2To2PHoHNb6yOPA/artifacts/public/build/firefox-54.0a2.en-US.mac.talos.tests.zip 09:27:11 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/fzptBtX2To2PHoHNb6yOPA/artifacts/public/build/firefox-54.0a2.en-US.mac.talos.tests.zip'}, attempt #1 09:27:11 INFO - Fetch https://queue.taskcluster.net/v1/task/fzptBtX2To2PHoHNb6yOPA/artifacts/public/build/firefox-54.0a2.en-US.mac.talos.tests.zip into memory 09:27:12 INFO - Expected file size: 14404424 09:27:12 INFO - Obtained file size: 14404424 09:27:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:27:12 INFO - trying https://queue.taskcluster.net/v1/task/fzptBtX2To2PHoHNb6yOPA/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg 09:27:12 INFO - Downloading https://queue.taskcluster.net/v1/task/fzptBtX2To2PHoHNb6yOPA/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-54.0a2.en-US.mac.dmg 09:27:12 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/fzptBtX2To2PHoHNb6yOPA/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 09:27:15 INFO - Downloaded 74609069 bytes. 09:27:15 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/fzptBtX2To2PHoHNb6yOPA/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg 09:27:15 INFO - mkdir: /builds/slave/test/properties 09:27:15 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 09:27:15 INFO - Writing to file /builds/slave/test/properties/build_url 09:27:15 INFO - Contents: 09:27:15 INFO - build_url:https://queue.taskcluster.net/v1/task/fzptBtX2To2PHoHNb6yOPA/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg 09:27:16 INFO - Running post-action listener: _resource_record_post_action 09:27:16 INFO - Running post-action listener: set_extra_try_arguments 09:27:16 INFO - [mozharness: 2017-03-08 17:27:16.177050Z] Finished download-and-extract step (success) 09:27:16 INFO - [mozharness: 2017-03-08 17:27:16.177174Z] Running populate-webroot step. 09:27:16 INFO - Running pre-action listener: _resource_record_pre_action 09:27:16 INFO - Running main action method: populate_webroot 09:27:16 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:27:16 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:27:16 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:27:16 INFO - '--tppagecycles', 09:27:16 INFO - '7'], 09:27:16 INFO - 'tests': ['tsvgm']}}, 09:27:16 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 09:27:16 INFO - 'tests': ['tresize', 'tcanvasmark']}, 09:27:16 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 09:27:16 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 09:27:16 INFO - 'tests': ['dromaeo_css', 'kraken']}, 09:27:16 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 09:27:16 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 09:27:16 INFO - 'talos_options': ['--disable-e10s'], 09:27:16 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:27:16 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 09:27:16 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:27:16 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 09:27:16 INFO - 'talos_options': ['--disable-e10s'], 09:27:16 INFO - 'tests': ['damp', 'tps']}, 09:27:16 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 09:27:16 INFO - 'tests': ['damp', 'tps']}, 09:27:16 INFO - 'g3': {'talos_options': ['--disable-e10s'], 09:27:16 INFO - 'tests': ['dromaeo_dom']}, 09:27:16 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:27:16 INFO - 'g4': {'talos_options': ['--disable-e10s'], 09:27:16 INFO - 'tests': ['basic_compositor_video']}, 09:27:16 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 09:27:16 INFO - 'other': {'talos_options': ['--disable-e10s'], 09:27:16 INFO - 'tests': ['a11yr', 09:27:16 INFO - 'ts_paint', 09:27:16 INFO - 'tpaint', 09:27:16 INFO - 'sessionrestore', 09:27:16 INFO - 'sessionrestore_no_auto_restore', 09:27:16 INFO - 'tabpaint']}, 09:27:16 INFO - 'other-e10s': {'tests': ['a11yr', 09:27:16 INFO - 'ts_paint', 09:27:16 INFO - 'tpaint', 09:27:16 INFO - 'sessionrestore', 09:27:16 INFO - 'sessionrestore_no_auto_restore', 09:27:16 INFO - 'tabpaint']}, 09:27:16 INFO - 'other-e10s_l64': {'tests': ['a11yr', 09:27:16 INFO - 'ts_paint', 09:27:16 INFO - 'tpaint', 09:27:16 INFO - 'sessionrestore', 09:27:16 INFO - 'sessionrestore_no_auto_restore', 09:27:16 INFO - 'tabpaint']}, 09:27:16 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 09:27:16 INFO - 'ts_paint', 09:27:16 INFO - 'tpaint', 09:27:16 INFO - 'sessionrestore', 09:27:16 INFO - 'sessionrestore_no_auto_restore', 09:27:16 INFO - 'tabpaint']}, 09:27:16 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 09:27:16 INFO - 'tests': ['a11yr', 09:27:16 INFO - 'ts_paint', 09:27:16 INFO - 'tpaint', 09:27:16 INFO - 'sessionrestore', 09:27:16 INFO - 'sessionrestore_no_auto_restore', 09:27:16 INFO - 'tabpaint']}, 09:27:16 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 09:27:16 INFO - 'tests': ['a11yr', 09:27:16 INFO - 'ts_paint', 09:27:16 INFO - 'tpaint', 09:27:16 INFO - 'sessionrestore', 09:27:16 INFO - 'sessionrestore_no_auto_restore', 09:27:16 INFO - 'tabpaint']}, 09:27:16 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 09:27:16 INFO - 'tests': ['tsvgx', 09:27:16 INFO - 'tsvgr_opacity', 09:27:16 INFO - 'tart', 09:27:16 INFO - 'tscrollx', 09:27:16 INFO - 'cart', 09:27:16 INFO - 'tsvg_static']}, 09:27:16 INFO - 'svgr-e10s': {'tests': ['tsvgx', 09:27:16 INFO - 'tsvgr_opacity', 09:27:16 INFO - 'tart', 09:27:16 INFO - 'tscrollx', 09:27:16 INFO - 'cart', 09:27:16 INFO - 'tsvg_static']}, 09:27:16 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 09:27:16 INFO - 'talos_options': ['--disable-e10s'], 09:27:16 INFO - 'tests': ['tp5o']}, 09:27:16 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 09:27:16 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 09:27:16 INFO - 'talos_options': ['--disable-e10s', 09:27:16 INFO - '--xperf_path', 09:27:16 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:27:16 INFO - 'tests': ['tp5n']}, 09:27:16 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 09:27:16 INFO - 'talos_options': ['--xperf_path', 09:27:16 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:27:16 INFO - 'tests': ['tp5n']}}, 09:27:16 INFO - 'talos.zip': {'path': '', 09:27:16 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:27:16 INFO - Running post-action listener: _resource_record_post_action 09:27:16 INFO - [mozharness: 2017-03-08 17:27:16.196732Z] Finished populate-webroot step (success) 09:27:16 INFO - [mozharness: 2017-03-08 17:27:16.196843Z] Running create-virtualenv step. 09:27:16 INFO - Running pre-action listener: _resource_record_pre_action 09:27:16 INFO - Running main action method: create_virtualenv 09:27:16 INFO - Creating virtualenv /builds/slave/test/build/venv 09:27:16 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 09:27:16 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 09:27:16 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:27:16 INFO - Using real prefix '/tools/python27' 09:27:16 INFO - New python executable in /builds/slave/test/build/venv/bin/python 09:27:17 INFO - Installing distribute.............................................................................................................................................................................................done. 09:27:19 INFO - Installing pip.................done. 09:27:19 INFO - Return code: 0 09:27:19 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 09:27:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 09:27:20 INFO - Reading from file tmpfile_stdout 09:27:20 INFO - Output received: 09:27:20 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) 09:27:20 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 09:27:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:27:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:27:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:27:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:27:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:27:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:27:20 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 0x107edcb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107edf768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f900bceadc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107cbad30>, '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 0x107be1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107cafbe8>, '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.HS2ehUAfQ5/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.uQahpadQ75/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/w3/rmzpg_rj3zjdrsx94grbz4kr00000w/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 09:27:20 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 09:27:20 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 09:27:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HS2ehUAfQ5/Render', 09:27:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:27:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:27:20 INFO - 'HOME': '/Users/cltbld', 09:27:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:27:20 INFO - 'LOGNAME': 'cltbld', 09:27:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:27:20 INFO - 'MOZ_NO_REMOTE': '1', 09:27:20 INFO - 'NO_EM_RESTART': '1', 09:27:20 INFO - 'PAGER': '/bin/cat', 09:27:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:27:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:27:20 INFO - 'PWD': '/builds/slave/test', 09:27:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:27:20 INFO - 'SHELL': '/bin/bash', 09:27:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.uQahpadQ75/Listeners', 09:27:20 INFO - 'TMPDIR': '/var/folders/w3/rmzpg_rj3zjdrsx94grbz4kr00000w/T/', 09:27:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:27:20 INFO - 'USER': 'cltbld', 09:27:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:27:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:27:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:27:20 INFO - 'XPC_FLAGS': '0x0', 09:27:20 INFO - 'XPC_SERVICE_NAME': '0', 09:27:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:27:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:27:20 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 09:27:20 INFO - Cleaning up... 09:27:20 INFO - Return code: 0 09:27:20 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 09:27:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:27:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:27:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:27:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:27:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:27:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:27:20 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 0x107edcb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107edf768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f900bceadc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107cbad30>, '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 0x107be1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107cafbe8>, '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.HS2ehUAfQ5/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.uQahpadQ75/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/w3/rmzpg_rj3zjdrsx94grbz4kr00000w/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 09:27:20 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 09:27:20 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 09:27:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HS2ehUAfQ5/Render', 09:27:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:27:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:27:20 INFO - 'HOME': '/Users/cltbld', 09:27:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:27:20 INFO - 'LOGNAME': 'cltbld', 09:27:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:27:20 INFO - 'MOZ_NO_REMOTE': '1', 09:27:20 INFO - 'NO_EM_RESTART': '1', 09:27:20 INFO - 'PAGER': '/bin/cat', 09:27:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:27:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:27:20 INFO - 'PWD': '/builds/slave/test', 09:27:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:27:20 INFO - 'SHELL': '/bin/bash', 09:27:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.uQahpadQ75/Listeners', 09:27:20 INFO - 'TMPDIR': '/var/folders/w3/rmzpg_rj3zjdrsx94grbz4kr00000w/T/', 09:27:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:27:20 INFO - 'USER': 'cltbld', 09:27:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:27:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:27:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:27:20 INFO - 'XPC_FLAGS': '0x0', 09:27:20 INFO - 'XPC_SERVICE_NAME': '0', 09:27:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:27:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:27:20 INFO - Downloading/unpacking psutil>=3.1.1 09:27:20 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 09:27:20 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 09:27:20 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 09:27:20 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 09:27:20 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 09:27:20 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 09:27:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 09:27:24 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:27:24 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:27:24 INFO - Installing collected packages: psutil 09:27:24 INFO - Running setup.py install for psutil 09:27:24 INFO - building 'psutil._psutil_osx' extension 09:27:24 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 09:27:24 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 09:27:24 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 09:27:24 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 09:27:24 INFO - building 'psutil._psutil_posix' extension 09:27:24 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 09:27:24 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 09:27:24 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 09:27:24 INFO - ^ 09:27:24 INFO - 1 warning generated. 09:27:24 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 09:27:24 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:27:24 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:27:24 INFO - Successfully installed psutil 09:27:24 INFO - Cleaning up... 09:27:25 INFO - Return code: 0 09:27:25 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 09:27:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:27:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:27:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:27:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:27:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:27:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:27:25 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x107edcb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107edf768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f900bceadc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107cbad30>, '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 0x107be1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107cafbe8>, '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.HS2ehUAfQ5/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.uQahpadQ75/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/w3/rmzpg_rj3zjdrsx94grbz4kr00000w/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 09:27:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 09:27:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 09:27:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HS2ehUAfQ5/Render', 09:27:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:27:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:27:25 INFO - 'HOME': '/Users/cltbld', 09:27:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:27:25 INFO - 'LOGNAME': 'cltbld', 09:27:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:27:25 INFO - 'MOZ_NO_REMOTE': '1', 09:27:25 INFO - 'NO_EM_RESTART': '1', 09:27:25 INFO - 'PAGER': '/bin/cat', 09:27:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:27:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:27:25 INFO - 'PWD': '/builds/slave/test', 09:27:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:27:25 INFO - 'SHELL': '/bin/bash', 09:27:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.uQahpadQ75/Listeners', 09:27:25 INFO - 'TMPDIR': '/var/folders/w3/rmzpg_rj3zjdrsx94grbz4kr00000w/T/', 09:27:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:27:25 INFO - 'USER': 'cltbld', 09:27:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:27:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:27:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:27:25 INFO - 'XPC_FLAGS': '0x0', 09:27:25 INFO - 'XPC_SERVICE_NAME': '0', 09:27:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:27:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:27:25 INFO - Downloading/unpacking mozsystemmonitor==0.3 09:27:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:27:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:27:25 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:27:25 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:27:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:27:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:27:27 INFO - Downloading mozsystemmonitor-0.3.tar.gz 09:27:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:27:27 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 09:27:27 INFO - Installing collected packages: mozsystemmonitor 09:27:27 INFO - Running setup.py install for mozsystemmonitor 09:27:28 INFO - Successfully installed mozsystemmonitor 09:27:28 INFO - Cleaning up... 09:27:28 INFO - Return code: 0 09:27:28 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 09:27:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:27:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:27:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:27:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:27:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:27:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:27:29 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 0x107edcb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107edf768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f900bceadc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107cbad30>, '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 0x107be1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107cafbe8>, '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.HS2ehUAfQ5/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.uQahpadQ75/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/w3/rmzpg_rj3zjdrsx94grbz4kr00000w/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 09:27:29 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 09:27:29 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 09:27:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HS2ehUAfQ5/Render', 09:27:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:27:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:27:29 INFO - 'HOME': '/Users/cltbld', 09:27:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:27:29 INFO - 'LOGNAME': 'cltbld', 09:27:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:27:29 INFO - 'MOZ_NO_REMOTE': '1', 09:27:29 INFO - 'NO_EM_RESTART': '1', 09:27:29 INFO - 'PAGER': '/bin/cat', 09:27:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:27:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:27:29 INFO - 'PWD': '/builds/slave/test', 09:27:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:27:29 INFO - 'SHELL': '/bin/bash', 09:27:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.uQahpadQ75/Listeners', 09:27:29 INFO - 'TMPDIR': '/var/folders/w3/rmzpg_rj3zjdrsx94grbz4kr00000w/T/', 09:27:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:27:29 INFO - 'USER': 'cltbld', 09:27:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:27:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:27:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:27:29 INFO - 'XPC_FLAGS': '0x0', 09:27:29 INFO - 'XPC_SERVICE_NAME': '0', 09:27:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:27:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:27:29 INFO - Downloading/unpacking jsonschema==2.5.1 09:27:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:27:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:27:29 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:27:29 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:27:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:27:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:27:32 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:27:32 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 09:27:32 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 09:27:32 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 09:27:32 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 09:27:32 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 09:27:32 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 09:27:32 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 09:27:32 INFO - Downloading functools32-3.2.3-2.tar.gz 09:27:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 09:27:33 INFO - warning: no files found matching '*.txt' 09:27:33 INFO - no previously-included directories found matching 'build' 09:27:33 INFO - no previously-included directories found matching 'dist' 09:27:33 INFO - no previously-included directories found matching '.git*' 09:27:33 INFO - Installing collected packages: jsonschema, functools32 09:27:33 INFO - Running setup.py install for functools32 09:27:33 INFO - warning: no files found matching '*.txt' 09:27:33 INFO - no previously-included directories found matching 'build' 09:27:33 INFO - no previously-included directories found matching 'dist' 09:27:33 INFO - no previously-included directories found matching '.git*' 09:27:33 INFO - Successfully installed jsonschema functools32 09:27:33 INFO - Cleaning up... 09:27:33 INFO - Return code: 0 09:27:33 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 09:27:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:27:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:27:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:27:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:27:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:27:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:27:33 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x107edcb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107edf768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f900bceadc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107cbad30>, '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 0x107be1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107cafbe8>, '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.HS2ehUAfQ5/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.uQahpadQ75/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/w3/rmzpg_rj3zjdrsx94grbz4kr00000w/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 09:27:33 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 09:27:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 09:27:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HS2ehUAfQ5/Render', 09:27:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:27:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:27:33 INFO - 'HOME': '/Users/cltbld', 09:27:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:27:33 INFO - 'LOGNAME': 'cltbld', 09:27:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:27:33 INFO - 'MOZ_NO_REMOTE': '1', 09:27:33 INFO - 'NO_EM_RESTART': '1', 09:27:33 INFO - 'PAGER': '/bin/cat', 09:27:33 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:27:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:27:33 INFO - 'PWD': '/builds/slave/test', 09:27:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:27:33 INFO - 'SHELL': '/bin/bash', 09:27:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.uQahpadQ75/Listeners', 09:27:33 INFO - 'TMPDIR': '/var/folders/w3/rmzpg_rj3zjdrsx94grbz4kr00000w/T/', 09:27:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:27:33 INFO - 'USER': 'cltbld', 09:27:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:27:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:27:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:27:33 INFO - 'XPC_FLAGS': '0x0', 09:27:33 INFO - 'XPC_SERVICE_NAME': '0', 09:27:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:27:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:27:33 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 09:27:33 INFO - Cleaning up... 09:27:33 INFO - Return code: 0 09:27:33 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 09:27:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:27:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:27:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:27:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:27:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:27:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:27:33 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x107edcb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107edf768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f900bceadc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107cbad30>, '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 0x107be1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107cafbe8>, '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.HS2ehUAfQ5/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.uQahpadQ75/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/w3/rmzpg_rj3zjdrsx94grbz4kr00000w/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 09:27:33 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 09:27:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 09:27:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HS2ehUAfQ5/Render', 09:27:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:27:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:27:33 INFO - 'HOME': '/Users/cltbld', 09:27:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:27:33 INFO - 'LOGNAME': 'cltbld', 09:27:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:27:33 INFO - 'MOZ_NO_REMOTE': '1', 09:27:33 INFO - 'NO_EM_RESTART': '1', 09:27:33 INFO - 'PAGER': '/bin/cat', 09:27:33 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:27:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:27:33 INFO - 'PWD': '/builds/slave/test', 09:27:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:27:33 INFO - 'SHELL': '/bin/bash', 09:27:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.uQahpadQ75/Listeners', 09:27:33 INFO - 'TMPDIR': '/var/folders/w3/rmzpg_rj3zjdrsx94grbz4kr00000w/T/', 09:27:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:27:33 INFO - 'USER': 'cltbld', 09:27:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:27:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:27:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:27:33 INFO - 'XPC_FLAGS': '0x0', 09:27:33 INFO - 'XPC_SERVICE_NAME': '0', 09:27:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:27:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:27:33 INFO - Downloading/unpacking blobuploader==1.2.4 09:27:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:27:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:27:33 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:27:33 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:27:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:27:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:27:36 INFO - Downloading blobuploader-1.2.4.tar.gz 09:27:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:27:36 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:27:36 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 09:27:36 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 09:27:36 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 09:27:36 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 09:27:36 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 09:27:36 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 09:27:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 09:27:37 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:27:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:27:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:27:37 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:27:37 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:27:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:27:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:27:37 INFO - Downloading docopt-0.6.1.tar.gz 09:27:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 09:27:37 INFO - Installing collected packages: blobuploader, requests, docopt 09:27:37 INFO - Running setup.py install for blobuploader 09:27:37 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:27:37 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 09:27:37 INFO - Running setup.py install for requests 09:27:38 INFO - Running setup.py install for docopt 09:27:38 INFO - Successfully installed blobuploader requests docopt 09:27:38 INFO - Cleaning up... 09:27:38 INFO - Return code: 0 09:27:38 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:27:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:27:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:27:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:27:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:27:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:27:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:27:38 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 0x107edcb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107edf768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f900bceadc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107cbad30>, '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 0x107be1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107cafbe8>, '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.HS2ehUAfQ5/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.uQahpadQ75/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/w3/rmzpg_rj3zjdrsx94grbz4kr00000w/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 09:27:38 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 09:27:38 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 09:27:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HS2ehUAfQ5/Render', 09:27:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:27:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:27:38 INFO - 'HOME': '/Users/cltbld', 09:27:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:27:38 INFO - 'LOGNAME': 'cltbld', 09:27:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:27:38 INFO - 'MOZ_NO_REMOTE': '1', 09:27:38 INFO - 'NO_EM_RESTART': '1', 09:27:38 INFO - 'PAGER': '/bin/cat', 09:27:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:27:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:27:38 INFO - 'PWD': '/builds/slave/test', 09:27:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:27:38 INFO - 'SHELL': '/bin/bash', 09:27:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.uQahpadQ75/Listeners', 09:27:38 INFO - 'TMPDIR': '/var/folders/w3/rmzpg_rj3zjdrsx94grbz4kr00000w/T/', 09:27:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:27:38 INFO - 'USER': 'cltbld', 09:27:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:27:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:27:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:27:38 INFO - 'XPC_FLAGS': '0x0', 09:27:38 INFO - 'XPC_SERVICE_NAME': '0', 09:27:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:27:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:27:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:27:38 INFO - Running setup.py (path:/var/folders/w3/rmzpg_rj3zjdrsx94grbz4kr00000w/T/pip-BbHkpz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:27:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:27:39 INFO - Running setup.py (path:/var/folders/w3/rmzpg_rj3zjdrsx94grbz4kr00000w/T/pip-td1tZU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:27:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:27:39 INFO - Running setup.py (path:/var/folders/w3/rmzpg_rj3zjdrsx94grbz4kr00000w/T/pip-R0MllN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:27:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:27:39 INFO - Running setup.py (path:/var/folders/w3/rmzpg_rj3zjdrsx94grbz4kr00000w/T/pip-6Up93W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:27:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:27:39 INFO - Running setup.py (path:/var/folders/w3/rmzpg_rj3zjdrsx94grbz4kr00000w/T/pip-Nxx5wi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:27:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:27:39 INFO - Running setup.py (path:/var/folders/w3/rmzpg_rj3zjdrsx94grbz4kr00000w/T/pip-vu0TFi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:27:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:27:39 INFO - Running setup.py (path:/var/folders/w3/rmzpg_rj3zjdrsx94grbz4kr00000w/T/pip-qvnCQV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:27:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:27:39 INFO - Running setup.py (path:/var/folders/w3/rmzpg_rj3zjdrsx94grbz4kr00000w/T/pip-unOcKR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:27:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:27:39 INFO - Running setup.py (path:/var/folders/w3/rmzpg_rj3zjdrsx94grbz4kr00000w/T/pip-YUWAA_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:27:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:27:39 INFO - Running setup.py (path:/var/folders/w3/rmzpg_rj3zjdrsx94grbz4kr00000w/T/pip-0nAWB1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:27:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:27:39 INFO - Running setup.py (path:/var/folders/w3/rmzpg_rj3zjdrsx94grbz4kr00000w/T/pip-D0dVAK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:27:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:27:40 INFO - Running setup.py (path:/var/folders/w3/rmzpg_rj3zjdrsx94grbz4kr00000w/T/pip-uXYiX4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:27:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:27:40 INFO - Running setup.py (path:/var/folders/w3/rmzpg_rj3zjdrsx94grbz4kr00000w/T/pip-wi0IE9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:27:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:27:40 INFO - Running setup.py (path:/var/folders/w3/rmzpg_rj3zjdrsx94grbz4kr00000w/T/pip-exqmYD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:27:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:27:40 INFO - Running setup.py (path:/var/folders/w3/rmzpg_rj3zjdrsx94grbz4kr00000w/T/pip-tPAMIZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:27:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:27:40 INFO - Running setup.py (path:/var/folders/w3/rmzpg_rj3zjdrsx94grbz4kr00000w/T/pip-5g6L8Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:27:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:27:40 INFO - Running setup.py (path:/var/folders/w3/rmzpg_rj3zjdrsx94grbz4kr00000w/T/pip-sR3ouJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:27:40 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:27:40 INFO - Running setup.py install for manifestparser 09:27:40 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 09:27:40 INFO - Running setup.py install for mozcrash 09:27:41 INFO - Running setup.py install for mozdebug 09:27:41 INFO - Running setup.py install for mozdevice 09:27:41 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 09:27:41 INFO - Installing dm script to /builds/slave/test/build/venv/bin 09:27:41 INFO - Running setup.py install for mozfile 09:27:41 INFO - Running setup.py install for mozhttpd 09:27:41 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 09:27:41 INFO - Running setup.py install for mozinfo 09:27:41 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 09:27:41 INFO - Running setup.py install for mozInstall 09:27:41 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 09:27:41 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 09:27:41 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 09:27:41 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 09:27:41 INFO - Running setup.py install for mozleak 09:27:42 INFO - Running setup.py install for mozlog 09:27:42 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 09:27:42 INFO - Running setup.py install for moznetwork 09:27:42 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 09:27:42 INFO - Running setup.py install for mozprocess 09:27:42 INFO - Running setup.py install for mozprofile 09:27:42 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 09:27:42 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 09:27:42 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 09:27:42 INFO - Running setup.py install for mozrunner 09:27:42 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 09:27:42 INFO - Running setup.py install for mozscreenshot 09:27:43 INFO - Running setup.py install for moztest 09:27:43 INFO - Running setup.py install for mozversion 09:27:43 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 09:27:43 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:27:43 INFO - Cleaning up... 09:27:43 INFO - Return code: 0 09:27:43 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:27:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:27:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:27:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:27:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:27:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:27:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:27:43 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 0x107edcb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107edf768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f900bceadc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107cbad30>, '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 0x107be1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107cafbe8>, '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.HS2ehUAfQ5/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.uQahpadQ75/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/w3/rmzpg_rj3zjdrsx94grbz4kr00000w/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 09:27:43 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 09:27:43 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 09:27:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HS2ehUAfQ5/Render', 09:27:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:27:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:27:43 INFO - 'HOME': '/Users/cltbld', 09:27:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:27:43 INFO - 'LOGNAME': 'cltbld', 09:27:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:27:43 INFO - 'MOZ_NO_REMOTE': '1', 09:27:43 INFO - 'NO_EM_RESTART': '1', 09:27:43 INFO - 'PAGER': '/bin/cat', 09:27:43 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:27:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:27:43 INFO - 'PWD': '/builds/slave/test', 09:27:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:27:43 INFO - 'SHELL': '/bin/bash', 09:27:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.uQahpadQ75/Listeners', 09:27:43 INFO - 'TMPDIR': '/var/folders/w3/rmzpg_rj3zjdrsx94grbz4kr00000w/T/', 09:27:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:27:43 INFO - 'USER': 'cltbld', 09:27:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:27:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:27:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:27:43 INFO - 'XPC_FLAGS': '0x0', 09:27:43 INFO - 'XPC_SERVICE_NAME': '0', 09:27:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:27:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:27:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:27:43 INFO - Running setup.py (path:/var/folders/w3/rmzpg_rj3zjdrsx94grbz4kr00000w/T/pip-eiJFeE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:27:43 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)) 09:27:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:27:43 INFO - Running setup.py (path:/var/folders/w3/rmzpg_rj3zjdrsx94grbz4kr00000w/T/pip-f8OqJj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:27:43 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)) 09:27:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:27:43 INFO - Running setup.py (path:/var/folders/w3/rmzpg_rj3zjdrsx94grbz4kr00000w/T/pip-28Ty4J-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:27:43 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)) 09:27:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:27:43 INFO - Running setup.py (path:/var/folders/w3/rmzpg_rj3zjdrsx94grbz4kr00000w/T/pip-lsns3G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:27:44 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)) 09:27:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:27:44 INFO - Running setup.py (path:/var/folders/w3/rmzpg_rj3zjdrsx94grbz4kr00000w/T/pip-YIWugq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:27:44 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)) 09:27:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:27:44 INFO - Running setup.py (path:/var/folders/w3/rmzpg_rj3zjdrsx94grbz4kr00000w/T/pip-edJIaZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:27:44 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)) 09:27:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:27:44 INFO - Running setup.py (path:/var/folders/w3/rmzpg_rj3zjdrsx94grbz4kr00000w/T/pip-sHvB8l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:27:44 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)) 09:27:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:27:44 INFO - Running setup.py (path:/var/folders/w3/rmzpg_rj3zjdrsx94grbz4kr00000w/T/pip-ct_4YD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:27:44 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)) 09:27:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:27:44 INFO - Running setup.py (path:/var/folders/w3/rmzpg_rj3zjdrsx94grbz4kr00000w/T/pip-KGTX2S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:27:44 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)) 09:27:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:27:44 INFO - Running setup.py (path:/var/folders/w3/rmzpg_rj3zjdrsx94grbz4kr00000w/T/pip-BkSGde-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:27:44 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)) 09:27:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:27:44 INFO - Running setup.py (path:/var/folders/w3/rmzpg_rj3zjdrsx94grbz4kr00000w/T/pip-RPKsyR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:27:44 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)) 09:27:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:27:44 INFO - Running setup.py (path:/var/folders/w3/rmzpg_rj3zjdrsx94grbz4kr00000w/T/pip-kKeJDY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:27:44 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)) 09:27:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:27:44 INFO - Running setup.py (path:/var/folders/w3/rmzpg_rj3zjdrsx94grbz4kr00000w/T/pip-xWRxAk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:27:44 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)) 09:27:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:27:44 INFO - Running setup.py (path:/var/folders/w3/rmzpg_rj3zjdrsx94grbz4kr00000w/T/pip-Y66fLW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:27:45 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)) 09:27:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:27:45 INFO - Running setup.py (path:/var/folders/w3/rmzpg_rj3zjdrsx94grbz4kr00000w/T/pip-AxvItM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:27:45 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)) 09:27:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:27:45 INFO - Running setup.py (path:/var/folders/w3/rmzpg_rj3zjdrsx94grbz4kr00000w/T/pip-hxdmzf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:27:45 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)) 09:27:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:27:45 INFO - Running setup.py (path:/var/folders/w3/rmzpg_rj3zjdrsx94grbz4kr00000w/T/pip-c2loPo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:27:45 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)) 09:27:45 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)) 09:27:45 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)) 09:27:45 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)) 09:27:45 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)) 09:27:45 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)) 09:27:45 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:27:45 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 09:27:45 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 09:27:45 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 09:27:45 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 09:27:45 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 09:27:45 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 09:27:47 INFO - Downloading blessings-1.6.tar.gz 09:27:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 09:27:48 INFO - Installing collected packages: blessings 09:27:48 INFO - Running setup.py install for blessings 09:27:48 INFO - Successfully installed blessings 09:27:48 INFO - Cleaning up... 09:27:48 INFO - Return code: 0 09:27:48 INFO - Done creating virtualenv /builds/slave/test/build/venv. 09:27:48 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:27:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:27:48 INFO - Reading from file tmpfile_stdout 09:27:48 INFO - Current package versions: 09:27:48 INFO - blessings == 1.6 09:27:48 INFO - blobuploader == 1.2.4 09:27:48 INFO - docopt == 0.6.1 09:27:48 INFO - functools32 == 3.2.3-2 09:27:48 INFO - jsonschema == 2.5.1 09:27:48 INFO - manifestparser == 1.1 09:27:48 INFO - mozInstall == 1.12 09:27:48 INFO - mozcrash == 1.0 09:27:48 INFO - mozdebug == 0.1 09:27:48 INFO - mozdevice == 0.49 09:27:48 INFO - mozfile == 1.2 09:27:48 INFO - mozhttpd == 0.7 09:27:48 INFO - mozinfo == 0.9 09:27:48 INFO - mozleak == 0.1 09:27:48 INFO - mozlog == 3.4 09:27:48 INFO - moznetwork == 0.27 09:27:48 INFO - mozprocess == 0.25 09:27:48 INFO - mozprofile == 0.28 09:27:48 INFO - mozrunner == 6.13 09:27:48 INFO - mozscreenshot == 0.1 09:27:48 INFO - mozsystemmonitor == 0.3 09:27:48 INFO - moztest == 0.8 09:27:48 INFO - mozversion == 1.4 09:27:48 INFO - psutil == 3.1.1 09:27:48 INFO - requests == 1.2.3 09:27:48 INFO - wsgiref == 0.1.2 09:27:48 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:27:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:27:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:27:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:27:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:27:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:27:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:27:48 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 0x107edcb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107edf768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f900bceadc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107cbad30>, '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 0x107be1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107cafbe8>, '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.HS2ehUAfQ5/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.uQahpadQ75/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/w3/rmzpg_rj3zjdrsx94grbz4kr00000w/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 09:27:48 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 09:27:48 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 09:27:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HS2ehUAfQ5/Render', 09:27:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:27:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:27:48 INFO - 'HOME': '/Users/cltbld', 09:27:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:27:48 INFO - 'LOGNAME': 'cltbld', 09:27:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:27:48 INFO - 'MOZ_NO_REMOTE': '1', 09:27:48 INFO - 'NO_EM_RESTART': '1', 09:27:48 INFO - 'PAGER': '/bin/cat', 09:27:48 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:27:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:27:48 INFO - 'PWD': '/builds/slave/test', 09:27:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:27:48 INFO - 'SHELL': '/bin/bash', 09:27:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.uQahpadQ75/Listeners', 09:27:48 INFO - 'TMPDIR': '/var/folders/w3/rmzpg_rj3zjdrsx94grbz4kr00000w/T/', 09:27:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:27:48 INFO - 'USER': 'cltbld', 09:27:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:27:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:27:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:27:48 INFO - 'XPC_FLAGS': '0x0', 09:27:48 INFO - 'XPC_SERVICE_NAME': '0', 09:27:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:27:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:27:48 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)) 09:27:48 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)) 09:27:48 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)) 09:27:48 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)) 09:27:48 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)) 09:27:48 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)) 09:27:48 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)) 09:27:48 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)) 09:27:48 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)) 09:27:48 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)) 09:27:48 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)) 09:27:48 INFO - Cleaning up... 09:27:48 INFO - Return code: 0 09:27:48 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 09:27:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:27:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:27:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:27:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:27:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:27:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:27:48 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 0x107edcb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107edf768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f900bceadc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107cbad30>, '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 0x107be1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107cafbe8>, '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.HS2ehUAfQ5/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.uQahpadQ75/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/w3/rmzpg_rj3zjdrsx94grbz4kr00000w/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 09:27:48 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 09:27:48 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 09:27:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HS2ehUAfQ5/Render', 09:27:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:27:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:27:48 INFO - 'HOME': '/Users/cltbld', 09:27:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:27:48 INFO - 'LOGNAME': 'cltbld', 09:27:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:27:48 INFO - 'MOZ_NO_REMOTE': '1', 09:27:48 INFO - 'NO_EM_RESTART': '1', 09:27:48 INFO - 'PAGER': '/bin/cat', 09:27:48 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:27:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:27:48 INFO - 'PWD': '/builds/slave/test', 09:27:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:27:48 INFO - 'SHELL': '/bin/bash', 09:27:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.uQahpadQ75/Listeners', 09:27:48 INFO - 'TMPDIR': '/var/folders/w3/rmzpg_rj3zjdrsx94grbz4kr00000w/T/', 09:27:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:27:48 INFO - 'USER': 'cltbld', 09:27:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:27:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:27:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:27:48 INFO - 'XPC_FLAGS': '0x0', 09:27:48 INFO - 'XPC_SERVICE_NAME': '0', 09:27:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:27:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:27:49 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 09:27:49 INFO - Cleaning up... 09:27:49 INFO - Return code: 0 09:27:49 INFO - Running post-action listener: _resource_record_post_action 09:27:49 INFO - Running post-action listener: _start_resource_monitoring 09:27:49 INFO - Starting resource monitoring. 09:27:49 INFO - [mozharness: 2017-03-08 17:27:49.172828Z] Finished create-virtualenv step (success) 09:27:49 INFO - [mozharness: 2017-03-08 17:27:49.173820Z] Running install step. 09:27:49 INFO - Running pre-action listener: _resource_record_pre_action 09:27:49 INFO - Running main action method: install 09:27:49 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:27:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:27:49 INFO - Reading from file tmpfile_stdout 09:27:49 INFO - Detecting whether we're running mozinstall >=1.0... 09:27:49 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 09:27:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 09:27:49 INFO - Reading from file tmpfile_stdout 09:27:49 INFO - Output received: 09:27:49 INFO - Usage: mozinstall [options] installer 09:27:49 INFO - Options: 09:27:49 INFO - -h, --help show this help message and exit 09:27:49 INFO - -d DEST, --destination=DEST 09:27:49 INFO - Directory to install application into. [default: 09:27:49 INFO - "/builds/slave/test"] 09:27:49 INFO - --app=APP Application being installed. [default: firefox] 09:27:49 INFO - mkdir: /builds/slave/test/build/application 09:27:49 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'] 09:27:49 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 09:28:09 INFO - Reading from file tmpfile_stdout 09:28:09 INFO - Output received: 09:28:09 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 09:28:09 INFO - Running post-action listener: _resource_record_post_action 09:28:09 INFO - [mozharness: 2017-03-08 17:28:09.744660Z] Finished install step (success) 09:28:09 INFO - [mozharness: 2017-03-08 17:28:09.744816Z] Running run-tests step. 09:28:09 INFO - Running pre-action listener: _resource_record_pre_action 09:28:09 INFO - Running main action method: run_tests 09:28:09 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:28:09 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:28:09 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:28:09 INFO - 2017-03-08 09:28:09.759 screenresolution[2068:15497] starting screenresolution argv=screenresolution get 09:28:09 INFO - 2017-03-08 09:28:09.762 screenresolution[2068:15497] Display 0: 1600x1200x32@60 09:28:09 INFO - 2017-03-08 09:28:09.768 screenresolution[2069:15500] starting screenresolution argv=screenresolution list 09:28:09 INFO - Available Modes on Display 0 09:28:09 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:28:09 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:28:09 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:28:09 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:28:09 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:28:09 INFO - Intel Iris: 09:28:09 INFO - Chipset Model: Intel Iris 09:28:09 INFO - Type: GPU 09:28:09 INFO - Bus: Built-In 09:28:09 INFO - VRAM (Dynamic, Max): 1536 MB 09:28:09 INFO - Vendor: Intel (0x8086) 09:28:09 INFO - Device ID: 0x0a2e 09:28:09 INFO - Revision ID: 0x0009 09:28:09 INFO - Displays: 09:28:09 INFO - Display: 09:28:09 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:28:09 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:28:09 INFO - Display Serial Number: 1600x1200 60 09:28:09 INFO - Main Display: Yes 09:28:09 INFO - Mirror: Off 09:28:09 INFO - Online: Yes 09:28:09 INFO - Rotation: Supported 09:28:10 INFO - Return code: 0 09:28:10 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 09:28:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 09:28:10 INFO - Python 2.7.3 09:28:10 INFO - Return code: 0 09:28:10 INFO - grabbing minidump binary from tooltool 09:28: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')]} 09:28:10 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 0x107cbad30>, '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 0x107be1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107cafbe8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 09:28:10 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 09:28:10 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 09:28:10 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 09:28:10 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:28:10 INFO - Return code: 0 09:28:10 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 09:28:10 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 09:28:10 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 09:28:10 INFO - ENV: RUST_BACKTRACE is now 1 09:28:10 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 09:28:10 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 09:28:10 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/fzptBtX2To2PHoHNb6yOPA/artifacts/public/build/firefox-54.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0373', '--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 09:28:10 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/fzptBtX2To2PHoHNb6yOPA/artifacts/public/build/firefox-54.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0373 --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 09:28:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HS2ehUAfQ5/Render', 09:28:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:28:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:28:10 INFO - 'HOME': '/Users/cltbld', 09:28:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:28:10 INFO - 'LOGNAME': 'cltbld', 09:28:10 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:28:10 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 09:28:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:28:10 INFO - 'MOZ_NO_REMOTE': '1', 09:28:10 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:28:10 INFO - 'NO_EM_RESTART': '1', 09:28:10 INFO - 'PAGER': '/bin/cat', 09:28:10 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:28:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:28:10 INFO - 'PWD': '/builds/slave/test', 09:28:10 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:28:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:28:10 INFO - 'RUST_BACKTRACE': '1', 09:28:10 INFO - 'SHELL': '/bin/bash', 09:28:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.uQahpadQ75/Listeners', 09:28:10 INFO - 'TMPDIR': '/var/folders/w3/rmzpg_rj3zjdrsx94grbz4kr00000w/T/', 09:28:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:28:10 INFO - 'USER': 'cltbld', 09:28:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:28:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:28:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:28:10 INFO - 'XPC_FLAGS': '0x0', 09:28:10 INFO - 'XPC_SERVICE_NAME': '0', 09:28:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:28:10 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/fzptBtX2To2PHoHNb6yOPA/artifacts/public/build/firefox-54.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0373', '--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 09:28:10 INFO - mozversion application_buildid: 20170308073723 09:28:10 INFO - mozversion application_changeset: ae315069c70eb5fe7da4dd2b158b3eee3ca9b94a 09:28:10 INFO - mozversion application_display_name: FirefoxDeveloperEdition 09:28:10 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:28:10 INFO - mozversion application_name: Firefox 09:28:10 INFO - mozversion application_remotingname: firefox-dev 09:28:10 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 09:28:10 INFO - mozversion application_vendor: Mozilla 09:28:10 INFO - mozversion application_version: 54.0a2 09:28:10 INFO - mozversion platform_buildid: 20170308073723 09:28:10 INFO - mozversion platform_changeset: ae315069c70eb5fe7da4dd2b158b3eee3ca9b94a 09:28:10 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 09:28:10 INFO - mozversion platform_version: 54.0a2 09:28:10 INFO - using testdate: 1488994090 09:28:10 INFO - actual date: 1488994090 09:28:10 INFO - starting webserver on 'localhost:53002' 09:28:10 INFO - SUITE-START | Running 2 tests 09:28:10 INFO - TEST-START | dromaeo_css 09:28:10 INFO - operating with platform_type : mac_ 09:28:10 INFO - Initialising browser for dromaeo_css test... 09:28:10 INFO - TEST-INFO | started process 2079 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/w3/rmzpg_rj3zjdrsx94grbz4kr00000w/T/tmpqoj5y9/profile http://localhost:53002/getInfo.html) 09:28:12 INFO - PROCESS | 2079 | 2017-03-08 09:28:12.953 plugin-container[2081:15666] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4b3b, name = 'com.apple.tsm.portname' 09:28:12 INFO - PROCESS | 2079 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:28:12 INFO - PROCESS | 2079 | 2017-03-08 09:28:12.956 plugin-container[2081:15666] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x491b, name = 'com.apple.CFPasteboardClient' 09:28:12 INFO - PROCESS | 2079 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:28:12 INFO - PROCESS | 2079 | 2017-03-08 09:28:12.956 plugin-container[2081:15666] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 09:28:13 INFO - PROCESS | 2079 | __metrics Screen width/height:1600/1200 09:28:13 INFO - PROCESS | 2079 | colorDepth:24 09:28:13 INFO - PROCESS | 2079 | Browser inner width/height: 1024/689 09:28:13 INFO - PROCESS | 2079 | __metrics 09:28:13 ERROR - PROCESS | 2079 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3353: TypeError: this.tabs is undefined 09:28:13 INFO - TEST-INFO | 2079: exit 0 09:28:13 INFO - Browser initialized. 09:28:13 INFO - Running cycle 1/1 for dromaeo_css test... 09:28:13 INFO - TEST-INFO | started process 2082 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/w3/rmzpg_rj3zjdrsx94grbz4kr00000w/T/tmpqoj5y9/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:28:16 INFO - PROCESS | 2082 | 2017-03-08 09:28:16.578 plugin-container[2083:15798] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4347, name = 'com.apple.tsm.portname' 09:28:16 INFO - PROCESS | 2082 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:28:16 INFO - PROCESS | 2082 | 2017-03-08 09:28:16.579 plugin-container[2083:15798] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x461b, name = 'com.apple.CFPasteboardClient' 09:28:16 INFO - PROCESS | 2082 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:28:16 INFO - PROCESS | 2082 | 2017-03-08 09:28:16.579 plugin-container[2083:15798] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 09:28:26 INFO - PROCESS | 2082 | RSS: Main: 220139520 09:28:26 INFO - PROCESS | 2082 | 09:31:30 INFO - PROCESS | 2082 | Cycle 1(1): loaded http://localhost:53002/tests/dromaeo/cssquery-dojo.html (next: http://localhost:53002/tests/dromaeo/cssquery-ext.html) 09:31:32 INFO - PROCESS | 2082 | RSS: Main: 244695040 09:31:32 INFO - PROCESS | 2082 | 09:33:14 INFO - PROCESS | 2082 | ************************* 09:33:14 INFO - PROCESS | 2082 | A coding exception was thrown and uncaught in a Task. 09:33:14 INFO - PROCESS | 2082 | 09:33:14 ERROR - PROCESS | 2082 | Full message: TypeError: NetworkError when attempting to fetch resource. 09:33:14 INFO - PROCESS | 2082 | Full stack: 09:33:14 INFO - PROCESS | 2082 | ************************* 09:34:34 INFO - PROCESS | 2082 | Cycle 1(1): loaded http://localhost:53002/tests/dromaeo/cssquery-ext.html (next: http://localhost:53002/tests/dromaeo/cssquery-jquery.html) 09:34:35 INFO - PROCESS | 2082 | RSS: Main: 249114624 09:34:35 INFO - PROCESS | 2082 | 09:37:37 INFO - PROCESS | 2082 | Cycle 1(1): loaded http://localhost:53002/tests/dromaeo/cssquery-jquery.html (next: http://localhost:53002/tests/dromaeo/cssquery-mootools.html) 09:37:37 INFO - PROCESS | 2082 | RSS: Main: 257486848 09:37:37 INFO - PROCESS | 2082 | 09:40:39 INFO - PROCESS | 2082 | Cycle 1(1): loaded http://localhost:53002/tests/dromaeo/cssquery-mootools.html (next: http://localhost:53002/tests/dromaeo/cssquery-prototype.html) 09:40:40 INFO - PROCESS | 2082 | RSS: Main: 253665280 09:40:40 INFO - PROCESS | 2082 | 09:43:43 INFO - PROCESS | 2082 | Cycle 1(1): loaded http://localhost:53002/tests/dromaeo/cssquery-prototype.html (next: http://localhost:53002/tests/dromaeo/cssquery-yui.html) 09:43:43 INFO - PROCESS | 2082 | RSS: Main: 259592192 09:43:43 INFO - PROCESS | 2082 | 09:46:46 INFO - PROCESS | 2082 | Cycle 1(1): loaded http://localhost:53002/tests/dromaeo/cssquery-yui.html (next: http://localhost:53002/tests/dromaeo/cssquery-dojo.html) 09:46:47 INFO - PROCESS | 2082 | RSS: Main: 256057344 09:46:47 INFO - PROCESS | 2082 | 09:46:47 INFO - PROCESS | 2082 | __start_tp_report 09:46:47 INFO - PROCESS | 2082 | _x_x_mozilla_page_load 09:46:47 INFO - PROCESS | 2082 | _x_x_mozilla_page_load_details 09:46:47 INFO - PROCESS | 2082 | |i|pagename|runs| 09:46:47 INFO - PROCESS | 2082 | |0;dojo.html;3105.7360124389425;3478.1652403423186;3494.895153145403;3498.2249229801314;3499.072745722384;5018.297438358623;6072.423119803617;6117.357677443865;6134.969325153379;6139.7851075212375;6069.33237343893;6108.114323423112;6120.173776540166;6127.35662755411;6148.8155355339295;5425.186222066692;5435.076037073707;5497.120460726284;5533.47431993962;5620.522255608278;5566.55467562596;5574.414686457924;5574.47042530961;5578.748956296956;5579.69311687859;5004.774785134678;5321.414644389114;5325.334333208349;5555.861103472425;5562.9443705562835;5525.696086715229;5530.087535556669;5555.916661250044;5572.3870374259;5620.381758006616;5385.219143224211;5399.028174928511;5496.917546236809;5509.559235261169;5559.054960656698;5459.617826752129;5775.740091695884;5834.183214349994;6050.152978583002;6132.50939924805;6276.089966954766;6312.905306420407;6327.715252813635;6333.651649159295;6357.65032923189;6516.93483065168;6522.445592124675;6523.804285871432;6531.0856480092825;6538.476921846241;5560.527355174855;6249.312575616678;6269;6286.87426251477;6318.810435686937;6304.117423560705;6333.746650134035;6469.385408386196;6522.673866306665;6573.079768832367;5036.7733451994745;5737.77048918047;6358.904616430757;6366.872662546816;6375.3624637536805;5309.33633295838;6321;6456.225252969673;6460.612363258126;6466;6270.717817698214;6291.150694656254;6313.558050936482;6382.20222472191;6392.84017899547;6165.32181460039;6231.563790534619;6450.41946224842;6480.287168411471;6498.772542960974;5106.744662766847;5145.279660847485;5155.994581056733;5181.689098654093;5193.948060519347;4626.282926146453;4643.141018911512;4650.930236046462;4682.993156471307;4715.292706094045;5920.970395147997;5937;6085.543584231112;6206.906896396557;6242.313345531988;6401.9039714404325;6528.21661400635;6531.412172904366;6535.803925882136;6537.280899101095;6203.4416902479;6214.223222097238;6215.440610345091;6221.5333849961435;6248.28144763349;5368.275282836845;5428.050091234098;5744.798932037359;5753.683547404933;5761.452661997104;6307.323408931906;6548.377904099103;6580.605163690194;6596.637184954873;6619.106420633153;6407.775727849503;6513.250976137805;6518.152640156749;6523.217213934168;6559.540832141704;6717.227518835201;6734.528582999143;6740.898886516804;6743.055972163803;6745.797626071226;6281.026440901667;6293.433590976834;6319.557630965788;6354.968225158845;6371.96814015927;4929;6460.644664543495;6488.870222595616;6495.1556297681;6535.705893234721;6237.563370564017;6329.335419781041;6337.2395312562785;6347.71435285404;6396.584222025554;6209.441150296495;6272.623642581277;6281.371862813682;6287.874242515216;6341.334159913143;5402.486763757594;6257.43683068526;6338.524610654312;6354.968225158845;6395.840103997251;5293.894122117459;6220.409061139185;6223.906641400292;6312.684365781784;6378;6261.592996455216;6280.623162610258;6288.27684816252;6299.181106456314;6318.462930650762 09:46:47 INFO - PROCESS | 2082 | |1;ext.html;728.1189760389927;741.7515132430635;817.7628487738556;930.2929773372239;935.8315503209428;31988.200294992635;37565;38332.0416989575;39975.80012099935;39975.80072597827;28812;36841.52633894641;36961.63038369615;36974.260514789756;36999.63000369995;7718.073831140257;7849.882251766199;10379.740506487322;10699.518521666505;10854.67435976922;10481.73795655111;10564.366138031704;10596.046355827973;10606.098481629071;10623.893761062367;10560.683179504627;10574.259801813876;10584.947075264612;10605.575776968912;10620;10506.054455099002;10540.9986051325;10559.894401056006;10573.524191411405;10576.841347379795;10415.531301091469;10442.477876106164;10473.423961682105;10476.161907047494;10517.211209159344;33273.16817079568;33378.99863004114;33499.49750753741;33564;33699.49450758241;3055.618047744032;3615.222727113683;3628.983884512341;3632.20091579852;3695.113172838526;19374.903125484423;24765.752342476524;24785.5042899141;25506.59713715745;26268.949242030296;3023.425549145655;3026.6821983691616;3126.796758210709;3184.8885289014775;3199.1842080269384;344079.2796036029;346666;347084;347665;367420;43194.568054319054;43737.50099991993;43867.12265754667;43885.12229755451;43982.56017439849;45068.77465612716;45347.319790203175;45574.54425455703;45632.08735825265;45659.77170114128;13622.523211687543;13803.930980345034;13931.442742290195;13944.372503237175;13957.651058723613;11793.705157371134;11986.220895641929;11991.880081199077;12005.91946724799;12007.639770806889;11287.717807054725;11799.823002654966;11800.704982375337;11843.58547450835;11893.464794084288;5587.8603034923635;5614.242077319591;5618.213450116987;5674.319081710221;5681.375048744635;923.0999775219108;1081.221520505235;1109.4619109731773;1125.3697929159696;1133.659902029388;26488.205353839414;26589.734102659113;26937;26943.730562694123;27039.864800676263;946.5551190940247;961.9038096190325;1018.4449475036123;1147.7991351513451;1263.3620021888887;8005.199480051948;8182.549959752271;8316.859154441101;8334.374921880883;8410.158984101541;376273.1186344051;419248;423936;424616;425243;56715;57110.143347849815;57191;57261;57411;1375.2711063136553;1386.632542376251;1402.52314213168;1430.6780974280912;1435.770276755735;22775.430614234778;24814.875925620257;26390.86804565965;27055.45889082247;27324.863375682995;39799.403008955465;40096.7995160034;40105.79947100246;40174.79912600418;40262.39606405848;497021;500889;503626;504447;505222;26922.730772692023;29427.85286073556;29784.255393615334;29822.55266170966;29826.80692772265;33357.99926002224;36261.81869090637;37172;37605;37684.62315376921;30766.769329227474;31023.53464697986;31204.531932020585;31787.364252715277;32262.516062258615;30736;31035.379292413574;31133.53299700461;31514;31697;30117;30856.69143308628;31304.843475782476;31445.842770786916;31516;27343.179704608894;28518.287042824097;28522.857385713767;28573.857130714212;28627;22255.888720556293;22270.77729222687;22310.442238944157;22323.218687345863;22355 09:46:47 INFO - PROCESS | 2082 | |2;jquery.html;390.2896727955123;392.87428023032635;409.61291579457406;425.75731832855274;456.4203461603763;19580.804191958076;20161.697574536392;20958;21013.369598912057;21127.260545880898;17234.741478877826;20963.475913102142;21513.67729484059;22246;22429.214977475793;6539.378759017897;6808.2851300613465;6809.489288303373;6848.657567121649;6862.416694580967;6480.935190648081;6661.966690166542;6681.465482761391;6785.423239024689;6801.591904485722;6609.900851487231;6752.5948443093575;6774.491913106512;6790.388865002149;6795.864082718343;5914.61554998928;6389.105525226472;6535.281119076898;6664.633445160538;6671.566348187353;5431.266778984826;6505.447037001813;6536.803895883131;6540.738370465176;6553.213614366259;26194.73805261942;26200.213993580222;26430.471390572082;26453.603195952077;26522.46955060888;3294.4893541500865;3341.281624450755;3383.983080084584;3400.6769356911054;3423.4693622488553;15914.283857226452;16075.955062920873;16131.96337457192;16621.584460388465;17206.483805485856;2035.918563257468;2092.5396412789164;2111.3032699209225;2117.1637203304763;2119.9682004769943;137605;138081;139500;175681.18869301697;183170.99999999866;19413.51466213356;20566.69149962752;20762.339012878932;20791.168353266003;20796.688049679266;25180.11869584556;27597.724022759514;27878.581821272695;31953.201169970936;32090.839545802122;10285.43430111336;10413.11488523484;10550.894491055145;10609.787804243872;10659.4137322448;7100.538464999759;8634.956825215833;8719.258862996716;8898.911010889939;8911.643534258686;7742.303192712683;7954.522728636301;8512.658544142532;9332.253419726405;9646.421214727;3328.951380315193;3467.8612855486003;3553.4542474023883;3649.6532829381167;3972.2254160439015;897.264243320471;912.5346073502561;1002.6139936124645;1015.4110615842793;1053.7892421515728;21760.564788704138;22831.771682283295;23642.64536031961;23698.644520332207;23842.046318147426;920.2729843423754;926.4904302633524;933.883264591926;960.2653969713175;961.7739831139677;6974.197967233833;6990.895136572955;7073.92926070743;7167.104111986002;7182.604956727326;774480;783398;790949;799294;807197;142509.5749042517;157055.42944570637;163204;163760;170514;1085.0072183951595;1259.9055070869538;1281.2632736176736;1387.8334599848083;1388.2156581531403;10328.793424131625;12575.622731318075;12577.434015469144;12786.104972651825;12904.032197585406;51244.487555125466;64639;65768.34231657622;66024;66768;69571.65214173897;80157.59921200357;91207;94319;95431.05085830437;10402.323848949794;10457.424841633783;11696.239744416585;11784.76430471403;12167.75664486723;11022.944885275523;13134.60596182116;13242;13509.662258443617;13675.589732308046;11446.313221206754;11653.126015549038;11706.941465292619;11725.706857328634;11831.704207394883;11248;11306.4912078955;11415.258008229439;11495.0229230513;11637;9676.516174191403;9728.513574321114;11678.824817627572;11827.940860295643;11881.1089168311;11776.941115294369;11828.822567661664;11885.563459490611;11907.285562866256;11963.401829908644;11184.72038199019;11300.039496642878;11482;11614.88385116138;11705.824412633987 09:46:47 INFO - PROCESS | 2082 | |3;mootools.html;1487.0854424528918;1496.1546726099753;1516.264611663343;1528.1671489038476;1546.829848716641;10670.35977841329;11053.834192487118;11269.774604507915;11299.604513842007;11452.141089418285;11076.778464430707;11263;11264.605738799144;11276.830847537272;11295.209335346508;2389.307100940725;2434.866082365469;2449.632555116729;2452.1172377943926;2454.8036157107476;2354.682117914077;2395.6406539019113;2396.2212281008656;2404.6272827711646;2408.759124087595;2165.3828658832235;2185.0822654485155;2186.180182431588;2190.9452263693374;2197.890105494727;2217.866927984326;2224.9888750556224;2227.676986836908;2229.208630936019;2236.5079682469996;2278.168468508999;2383.845050071757;2388.605880029793;2388.7014123234594;2389.486260454011;11109.277896936754;11205.159613028974;11212.607558735404;11260.943695281552;11318.377489238088;5712.514746165985;5770.826875193751;5817.680027598481;5917.644941303535;5932.317783454915;3405.591329040506;3505.8772942946875;3699.26014797039;3700.1119731264303;3700.7409481336317;1139.1627154041773;1176.2472017908544;1201.4533387308754;1203.8984329338646;1227.9631611051682;13679.931600341935;13915.930420347935;15015.474458393905;15608.765868511982;15618.76571851423;4558.088382323548;4627.930581041287;4787.54518320759;4806.615470762331;4817.13291607514;11455.255408398427;11470.713232169095;11540.422978851024;11548.711282217844;11556.94221528887;5631.802886898939;5668.744906479218;5696.943030569724;5698.515626171739;5703.914441283384;4360.585744354281;5161.767720452589;5166.638335316492;5214.504622060898;5223.2948551945765;3808.447775072654;3856.5564960029396;3881.4177873318663;3899.1811719539214;3940.5271367435516;1024.9897501025043;1033.49358814182;1041.505284989639;1048.910842578374;1058.2010582010532;750.2497502497503;757.9090509138829;773.860705073081;782.6791015683542;793.5479368750473;4949.7030178189425;5977.402259773988;6033.125196846433;6068.575199735976;6185.134081228632;951.1392190068;972.1396564040875;972.9367591106698;986.2208855004466;1003.0922015575916;3105.627324721048;3911.9608803911597;3967.6358386951197;4034.1931613677384;4151.543330233671;13649.317534123255;13697.657558561115;14036.166383213073;14049.648758781112;14197.148171109766;13954.651133721738;13985;14018.509352172625;14155.787663185061;14173.574792756233;10196.796064078677;10245.436500992368;10271.383716977005;10305.278630495794;10351.844722329171;6257.655828929453;6345.4923606112325;6454.257760357619;6482.060101285381;6482.157319548617;12172.817407739069;12312.445939932546;12379;12397.814032789696;12484.625461236177;12671.80992285135;12701;12726.300053497147;12910;13031.609051728463;7705.576193309422;7919.762407127795;8428.957855210685;8606;8627.6980305689;9784.070513301225;9958.954309797657;10004.599816007278;10019.148372388428;10043.648472303434;7075.327843854826;9065.68270110543;9085.818283634158;9174.862377064483;9339.392939458914;8860.424072435271;8877.689280875138;8904.643814247616;8996;9162.221211197122;7812.92187078122;7817.374610031297;7833.295003449717;7928.484648497829;7935.0874649413745;5724.255846739898;5772.711364431678;5781.479666830005;5955.970220148872;6011.6693581853415;5780.768769249183;5839.386864379179;5926.851828704317;5998.280206375263;6130.111133885651 09:46:47 INFO - PROCESS | 2082 | |4;prototype.html;332.44979558830136;366.12496134239154;378.0737193875007;380.6421963354445;391.45196724585566;4285.571442855713;4625.861224163272;5160.974195129023;5256.947430525694;5412.4587541245855;4058.6753059755224;4792.976035119828;5101.260317253996;5194.558462530689;5217.1218361389865;4455.502430591986;4471.731696098244;4508.120916421299;4526.216376928199;4573.908521829561;4244.745315281078;4485.299627807056;4516.4418275695;4526.4794548627;4540.205464043779;3318.369509793132;3554.5734511858614;3852.672522835562;4165.58344165584;4419.381286619876;3671.81640917953;3706.3307325747587;3879.8254078566533;4437.711548749337;4493.123840851035;3538.4515400113023;3797.9240415191816;4215.473065866767;4241.406203131533;4273.166732487166;4448.421705178338;4798.592119669797;5046.520580544842;5072.594192464594;5144.897102057937;4249.893752656178;4397.868063958054;4658.487566367697;4921.606271498307;4964.851054468336;4626.450139203371;5520.751566179531;5522.751476183581;5541.196526503675;5676.091825307973;3659.3413185626578;3733.2640322250286;4117.8970525736795;4495.505494395614;4495.976810435284;5606.467385598362;5713.571482138815;5752.8274151775095;5911.733971971272;5938.901303258867;4509.1658043262105;4512.4133862597655;4536.841210557681;4538.977305113453;4544.545545445429;3936.8622098226424;3954.683625309968;4098.569650186747;4587.233914942771;4793.976030119897;4347.34789781529;4547.317902314613;4991.176455884738;5010.49895010496;5051.368578927634;4593.540645935446;5156.714367772987;5581.246531718166;5630.4369563043365;5642.887142257132;1568.8704133024205;1569.8101148897224;1578.4396539228637;1588.5385445390268;1589.0148108173007;998.201438848918;1070.8809294287332;1170.7541416302502;1192.4633914738401;1211.4306276050418;4484.8430304939175;4519.07358991406;5117.948820511747;5142.814303920819;5160.354955630546;5244.292020577173;5465.496988328162;5495.917561236584;5742.856428589235;5794.188813566104;4453.19842428366;4796.088743138793;4956.702597844141;5096.796128154832;5257.526822585909;2978.7765917555835;2980.7764417668764;2991.5662228976676;3064.310530130703;3064.4637188491924;4969.428515720666;5114.156164232934;5845.298564172326;5914.053751399755;5917.585768996215;4791.041791641685;5424.860779236405;5427;5912.822615321547;5929.673867937305;5270.84041510867;5668.943310566841;5724;5781.768729250783;5833.79581714638;5580.804671836629;5634.536731162524;5667.829965101054;5814.273215848019;5912.970435147797;5049.823256186016;5621;5623.3533143689;5642.463965923231;5671.631343962629;5293.602979776455;5678.602497825278;5743.339515955551;5802.47777700009;5907.793227237026;4577.313402989578;4631.467381251199;4633.328167415639;4652.488226295172;4677.246032737723;5492.297387809792;5506.284183056177;5524.933661817729;5630.577706671934;5805.274340707411;4864.708117512961;5268.578513718817;5333.853221557384;5551.694656793915;5645.971770141287;4807.471178170328;4906.945006823407;5329.014132385521;5348.609361566098;5414.214938833926;4481.574250446202;4522.8643140705835;5109.821156259513;5163.432022477449;5271.551918087006;5196.792128314825;5256.6320357574605;5441.374241962225;5458.727063646881;5585.357683866408;5123.308353372247;5293.523582877559;5325.600579956596;5386.8687575609565;5502.284702988586 09:46:47 INFO - PROCESS | 2082 | |5;yui.html;702.6521871673523;710.0201721624164;770.4337312075623;811.1037303779323;827.9751607451776;13427;13972.790408143861;13973.091749036306;13998.93000534996;14697.779533306995;16381.672366552692;16529.752053719174;16559.172041397942;16574.41989530367;16609.50171494854;921.4056933278048;942.006183476432;951.0014484790977;952.485657744817;956.2636769687336;899.9055099214554;930.7952250504878;952.5570609666504;953.7806304549957;956.3449037409362;1003.5283416794096;1006.3056491021208;1010.9949450252739;1011.4386515483903;1012.4532752313742;1003.0671475527756;1005.7737009172987;1009.5053423822275;1010.2978429991159;1010.5755582655303;733.3253406865697;747.2676776758752;747.8616455955665;747.9551226926402;748.1321666866409;15682.215889205494;16054.1170235637;16094.919525402414;16558.172091395383;16784.328626854913;14006.369713362923;14152.716945661132;14190.148591084464;14194.85805141956;14237.430502779876;11799.646010619696;11949.22330048544;11956.40217989097;11961.820572691417;11976.58081967127;3551.8934431966823;3590.9281814363826;3616.692581130607;3652.214773823641;3696.8706095286807;118653.40673296664;120009;123297;126220.1066983996;128225;9318.86021709675;10533.789324213472;10859.294145880493;10990.395528245863;11281.887181128248;18694.25222991065;22589.548209035725;23128.074877005067;23215.767842321362;23215.767842321362;6171.691415429211;6241.34465881085;6271.529635277281;6309.400606942425;6406.199225096863;4239.300515414983;4240.575942405735;4336.544662810423;4380.7152535085115;4508.661850361236;3164.7942883712944;3198.296374797541;3225.145336485833;3243.3351163011525;3254.153919980822;553.698234462219;558.617347117226;559.0858945623934;560.9859753506195;571.894199573072;642.6497558830449;673.6968364236113;675.1290834823121;689.9586024838526;697.8290318871909;9099.590518426687;9720.708378748648;10356.948215258875;10360.082099515736;10577;587.122252232906;671.5802623360399;695.8260434891278;702.2696395748467;703.802935178151;3911.3741801311653;4999.350084488993;5016.247562865599;5019.899602008012;5061.316722242524;105811;106775.39836902452;106943.46528267309;107788;108281;66599;68642.65678671675;69589.33130805414;80536.59731701305;84480;75503.62248188833;76081.6195919028;79697;80998;81111.5944420286;10912.290701104403;11087.279326843729;11150.553977840795;11281.21031527785;11433.885661143615;42910.141797163255;45622.77188614169;49133.50866491289;51568.22647660213;52153.217701733745;59881.70059149677;65924;68706;70737.64631176811;73099.53800924058;12950.417231224428;13040.282784446566;13119.344032798514;13175.14361566495;13212.735745285232;12345.629631111082;14815.407383704962;15105.773413398589;15220.7683575312;15823.36706531772;13896.16623002623;13964.4414223434;14033.29833508341;14358.210298433687;14509.564713058626;13871.722565548835;13890.610938906433;13936;14097.731204191672;14496.420143194155;12765.489380424679;13482.797758033834;13607.86392136066;13657.453701852211;13856.376463059389;8862.64549418016;11108.38903860263;11253.66239012831;11404.144689148181;11517.82723259135;9237.769055773659;10895.529103571234;11542;11560.826587601025;11572.6528204154 09:46:47 INFO - PROCESS | 2082 | __end_tp_report 09:46:47 INFO - PROCESS | 2082 | __start_cc_report 09:46:47 INFO - PROCESS | 2082 | _x_x_mozilla_cycle_collect,411 09:46:47 INFO - PROCESS | 2082 | __end_cc_report 09:46:47 INFO - PROCESS | 2082 | __startTimestamp1488995207032__endTimestamp 09:46:47 INFO - PROCESS | 2082 | 09:46:47 INFO - PROCESS | 2082 | ------- Summary: start ------- 09:46:47 INFO - PROCESS | 2082 | Number of tests: 6 09:46:47 INFO - PROCESS | 2082 | 09:46:47 INFO - PROCESS | 2082 | [#0] dojo.html Cycles:165 Average:5960.19 Median:6248.28 stddev:677.22 (10.8%) stddev-sans-first:641.20 09:46:47 INFO - PROCESS | 2082 | Values: 3105.7 3478.2 3494.9 3498.2 3499.1 5018.3 6072.4 6117.4 6135.0 6139.8 6069.3 6108.1 6120.2 6127.4 6148.8 5425.2 5435.1 5497.1 5533.5 5620.5 5566.6 5574.4 5574.5 5578.7 5579.7 5004.8 5321.4 5325.3 5555.9 5562.9 5525.7 5530.1 5555.9 5572.4 5620.4 5385.2 5399.0 5496.9 5509.6 5559.1 5459.6 5775.7 5834.2 6050.2 6132.5 6276.1 6312.9 6327.7 6333.7 6357.7 6516.9 6522.4 6523.8 6531.1 6538.5 5560.5 6249.3 6269.0 6286.9 6318.8 6304.1 6333.7 6469.4 6522.7 6573.1 5036.8 5737.8 6358.9 6366.9 6375.4 5309.3 6321.0 6456.2 6460.6 6466.0 6270.7 6291.2 6313.6 6382.2 6392.8 6165.3 6231.6 6450.4 6480.3 6498.8 5106.7 5145.3 5156.0 5181.7 5193.9 4626.3 4643.1 4650.9 4683.0 4715.3 5921.0 5937.0 6085.5 6206.9 6242.3 6401.9 6528.2 6531.4 6535.8 6537.3 6203.4 6214.2 6215.4 6221.5 6248.3 5368.3 5428.1 5744.8 5753.7 5761.5 6307.3 6548.4 6580.6 6596.6 6619.1 6407.8 6513.3 6518.2 6523.2 6559.5 6717.2 6734.5 6740.9 6743.1 6745.8 6281.0 6293.4 6319.6 6355.0 6372.0 4929.0 6460.6 6488.9 6495.2 6535.7 6237.6 6329.3 6337.2 6347.7 6396.6 6209.4 6272.6 6281.4 6287.9 6341.3 5402.5 6257.4 6338.5 6355.0 6395.8 5293.9 6220.4 6223.9 6312.7 6378.0 6261.6 6280.6 6288.3 6299.2 6318.5 09:46:47 INFO - PROCESS | 2082 | 09:46:47 INFO - PROCESS | 2082 | [#1] ext.html Cycles:180 Average:54446.46 Median:31169.03 stddev:113639.41 (364.6%) stddev-sans-first:113886.62 09:46:47 INFO - PROCESS | 2082 | Values: 728.1 741.8 817.8 930.3 935.8 31988.2 37565.0 38332.0 39975.8 39975.8 28812.0 36841.5 36961.6 36974.3 36999.6 7718.1 7849.9 10379.7 10699.5 10854.7 10481.7 10564.4 10596.0 10606.1 10623.9 10560.7 10574.3 10584.9 10605.6 10620.0 10506.1 10541.0 10559.9 10573.5 10576.8 10415.5 10442.5 10473.4 10476.2 10517.2 33273.2 33379.0 33499.5 33564.0 33699.5 3055.6 3615.2 3629.0 3632.2 3695.1 19374.9 24765.8 24785.5 25506.6 26268.9 3023.4 3026.7 3126.8 3184.9 3199.2 344079.3 346666.0 347084.0 347665.0 367420.0 43194.6 43737.5 43867.1 43885.1 43982.6 45068.8 45347.3 45574.5 45632.1 45659.8 13622.5 13803.9 13931.4 13944.4 13957.7 11793.7 11986.2 11991.9 12005.9 12007.6 11287.7 11799.8 11800.7 11843.6 11893.5 5587.9 5614.2 5618.2 5674.3 5681.4 923.1 1081.2 1109.5 1125.4 1133.7 26488.2 26589.7 26937.0 26943.7 27039.9 946.6 961.9 1018.4 1147.8 1263.4 8005.2 8182.5 8316.9 8334.4 8410.2 376273.1 419248.0 423936.0 424616.0 425243.0 56715.0 57110.1 57191.0 57261.0 57411.0 1375.3 1386.6 1402.5 1430.7 1435.8 22775.4 24814.9 26390.9 27055.5 27324.9 39799.4 40096.8 40105.8 40174.8 40262.4 497021.0 500889.0 503626.0 504447.0 505222.0 26922.7 29427.9 29784.3 29822.6 29826.8 33358.0 36261.8 37172.0 37605.0 37684.6 30766.8 31023.5 31204.5 31787.4 32262.5 30736.0 31035.4 31133.5 31514.0 31697.0 30117.0 30856.7 31304.8 31445.8 31516.0 27343.2 28518.3 28522.9 28573.9 28627.0 22255.9 22270.8 22310.4 22323.2 22355.0 09:46:47 INFO - PROCESS | 2082 | 09:46:47 INFO - PROCESS | 2082 | [#2] jquery.html Cycles:180 Average:44033.06 Median:24511.08 stddev:131895.05 (538.1%) stddev-sans-first:132224.34 09:46:47 INFO - PROCESS | 2082 | Values: 390.3 392.9 409.6 425.8 456.4 19580.8 20161.7 20958.0 21013.4 21127.3 17234.7 20963.5 21513.7 22246.0 22429.2 6539.4 6808.3 6809.5 6848.7 6862.4 6480.9 6662.0 6681.5 6785.4 6801.6 6609.9 6752.6 6774.5 6790.4 6795.9 5914.6 6389.1 6535.3 6664.6 6671.6 5431.3 6505.4 6536.8 6540.7 6553.2 26194.7 26200.2 26430.5 26453.6 26522.5 3294.5 3341.3 3384.0 3400.7 3423.5 15914.3 16076.0 16132.0 16621.6 17206.5 2035.9 2092.5 2111.3 2117.2 2120.0 137605.0 138081.0 139500.0 175681.2 183171.0 19413.5 20566.7 20762.3 20791.2 20796.7 25180.1 27597.7 27878.6 31953.2 32090.8 10285.4 10413.1 10550.9 10609.8 10659.4 7100.5 8635.0 8719.3 8898.9 8911.6 7742.3 7954.5 8512.7 9332.3 9646.4 3329.0 3467.9 3553.5 3649.7 3972.2 897.3 912.5 1002.6 1015.4 1053.8 21760.6 22831.8 23642.6 23698.6 23842.0 920.3 926.5 933.9 960.3 961.8 6974.2 6990.9 7073.9 7167.1 7182.6 774480.0 783398.0 790949.0 799294.0 807197.0 142509.6 157055.4 163204.0 163760.0 170514.0 1085.0 1259.9 1281.3 1387.8 1388.2 10328.8 12575.6 12577.4 12786.1 12904.0 51244.5 64639.0 65768.3 66024.0 66768.0 69571.7 80157.6 91207.0 94319.0 95431.1 10402.3 10457.4 11696.2 11784.8 12167.8 11022.9 13134.6 13242.0 13509.7 13675.6 11446.3 11653.1 11706.9 11725.7 11831.7 11248.0 11306.5 11415.3 11495.0 11637.0 9676.5 9728.5 11678.8 11827.9 11881.1 11776.9 11828.8 11885.6 11907.3 11963.4 11184.7 11300.0 11482.0 11614.9 11705.8 09:46:47 INFO - PROCESS | 2082 | 09:46:47 INFO - PROCESS | 2082 | [#3] mootools.html Cycles:180 Average:6688.83 Median:2442.25 stddev:4270.58 (174.9%) stddev-sans-first:4264.68 09:46:47 INFO - PROCESS | 2082 | Values: 1487.1 1496.2 1516.3 1528.2 1546.8 10670.4 11053.8 11269.8 11299.6 11452.1 11076.8 11263.0 11264.6 11276.8 11295.2 2389.3 2434.9 2449.6 2452.1 2454.8 2354.7 2395.6 2396.2 2404.6 2408.8 2165.4 2185.1 2186.2 2190.9 2197.9 2217.9 2225.0 2227.7 2229.2 2236.5 2278.2 2383.8 2388.6 2388.7 2389.5 11109.3 11205.2 11212.6 11260.9 11318.4 5712.5 5770.8 5817.7 5917.6 5932.3 3405.6 3505.9 3699.3 3700.1 3700.7 1139.2 1176.2 1201.5 1203.9 1228.0 13679.9 13915.9 15015.5 15608.8 15618.8 4558.1 4627.9 4787.5 4806.6 4817.1 11455.3 11470.7 11540.4 11548.7 11556.9 5631.8 5668.7 5696.9 5698.5 5703.9 4360.6 5161.8 5166.6 5214.5 5223.3 3808.4 3856.6 3881.4 3899.2 3940.5 1025.0 1033.5 1041.5 1048.9 1058.2 750.2 757.9 773.9 782.7 793.5 4949.7 5977.4 6033.1 6068.6 6185.1 951.1 972.1 972.9 986.2 1003.1 3105.6 3912.0 3967.6 4034.2 4151.5 13649.3 13697.7 14036.2 14049.6 14197.1 13954.7 13985.0 14018.5 14155.8 14173.6 10196.8 10245.4 10271.4 10305.3 10351.8 6257.7 6345.5 6454.3 6482.1 6482.2 12172.8 12312.4 12379.0 12397.8 12484.6 12671.8 12701.0 12726.3 12910.0 13031.6 7705.6 7919.8 8429.0 8606.0 8627.7 9784.1 9959.0 10004.6 10019.1 10043.6 7075.3 9065.7 9085.8 9174.9 9339.4 8860.4 8877.7 8904.6 8996.0 9162.2 7812.9 7817.4 7833.3 7928.5 7935.1 5724.3 5772.7 5781.5 5956.0 6011.7 5780.8 5839.4 5926.9 5998.3 6130.1 09:46:47 INFO - PROCESS | 2082 | 09:46:47 INFO - PROCESS | 2082 | [#4] prototype.html Cycles:180 Average:4606.61 Median:4980.30 stddev:1282.49 (25.8%) stddev-sans-first:1245.32 09:46:47 INFO - PROCESS | 2082 | Values: 332.4 366.1 378.1 380.6 391.5 4285.6 4625.9 5161.0 5256.9 5412.5 4058.7 4793.0 5101.3 5194.6 5217.1 4455.5 4471.7 4508.1 4526.2 4573.9 4244.7 4485.3 4516.4 4526.5 4540.2 3318.4 3554.6 3852.7 4165.6 4419.4 3671.8 3706.3 3879.8 4437.7 4493.1 3538.5 3797.9 4215.5 4241.4 4273.2 4448.4 4798.6 5046.5 5072.6 5144.9 4249.9 4397.9 4658.5 4921.6 4964.9 4626.5 5520.8 5522.8 5541.2 5676.1 3659.3 3733.3 4117.9 4495.5 4496.0 5606.5 5713.6 5752.8 5911.7 5938.9 4509.2 4512.4 4536.8 4539.0 4544.5 3936.9 3954.7 4098.6 4587.2 4794.0 4347.3 4547.3 4991.2 5010.5 5051.4 4593.5 5156.7 5581.2 5630.4 5642.9 1568.9 1569.8 1578.4 1588.5 1589.0 998.2 1070.9 1170.8 1192.5 1211.4 4484.8 4519.1 5117.9 5142.8 5160.4 5244.3 5465.5 5495.9 5742.9 5794.2 4453.2 4796.1 4956.7 5096.8 5257.5 2978.8 2980.8 2991.6 3064.3 3064.5 4969.4 5114.2 5845.3 5914.1 5917.6 4791.0 5424.9 5427.0 5912.8 5929.7 5270.8 5668.9 5724.0 5781.8 5833.8 5580.8 5634.5 5667.8 5814.3 5913.0 5049.8 5621.0 5623.4 5642.5 5671.6 5293.6 5678.6 5743.3 5802.5 5907.8 4577.3 4631.5 4633.3 4652.5 4677.2 5492.3 5506.3 5524.9 5630.6 5805.3 4864.7 5268.6 5333.9 5551.7 5646.0 4807.5 4906.9 5329.0 5348.6 5414.2 4481.6 4522.9 5109.8 5163.4 5271.6 5196.8 5256.6 5441.4 5458.7 5585.4 5123.3 5293.5 5325.6 5386.9 5502.3 09:46:47 INFO - PROCESS | 2082 | 09:46:47 INFO - PROCESS | 2082 | [#5] yui.html Cycles:180 Average:20739.99 Median:20641.90 stddev:30402.56 (147.3%) stddev-sans-first:30450.62 09:46:47 INFO - PROCESS | 2082 | Values: 702.7 710.0 770.4 811.1 828.0 13427.0 13972.8 13973.1 13998.9 14697.8 16381.7 16529.8 16559.2 16574.4 16609.5 921.4 942.0 951.0 952.5 956.3 899.9 930.8 952.6 953.8 956.3 1003.5 1006.3 1011.0 1011.4 1012.5 1003.1 1005.8 1009.5 1010.3 1010.6 733.3 747.3 747.9 748.0 748.1 15682.2 16054.1 16094.9 16558.2 16784.3 14006.4 14152.7 14190.1 14194.9 14237.4 11799.6 11949.2 11956.4 11961.8 11976.6 3551.9 3590.9 3616.7 3652.2 3696.9 118653.4 120009.0 123297.0 126220.1 128225.0 9318.9 10533.8 10859.3 10990.4 11281.9 18694.3 22589.5 23128.1 23215.8 23215.8 6171.7 6241.3 6271.5 6309.4 6406.2 4239.3 4240.6 4336.5 4380.7 4508.7 3164.8 3198.3 3225.1 3243.3 3254.2 553.7 558.6 559.1 561.0 571.9 642.6 673.7 675.1 690.0 697.8 9099.6 9720.7 10356.9 10360.1 10577.0 587.1 671.6 695.8 702.3 703.8 3911.4 4999.4 5016.2 5019.9 5061.3 105811.0 106775.4 106943.5 107788.0 108281.0 66599.0 68642.7 69589.3 80536.6 84480.0 75503.6 76081.6 79697.0 80998.0 81111.6 10912.3 11087.3 11150.6 11281.2 11433.9 42910.1 45622.8 49133.5 51568.2 52153.2 59881.7 65924.0 68706.0 70737.6 73099.5 12950.4 13040.3 13119.3 13175.1 13212.7 12345.6 14815.4 15105.8 15220.8 15823.4 13896.2 13964.4 14033.3 14358.2 14509.6 13871.7 13890.6 13936.0 14097.7 14496.4 12765.5 13482.8 13607.9 13657.5 13856.4 8862.6 11108.4 11253.7 11404.1 11517.8 9237.8 10895.5 11542.0 11560.8 11572.7 09:46:47 INFO - PROCESS | 2082 | -------- Summary: end -------- 09:46:47 INFO - PROCESS | 2082 | 09:46:47 INFO - TEST-INFO | 2082: exit 0 09:46:47 INFO - TEST-OK | dromaeo_css | took 1117494ms 09:46:47 INFO - TEST-START | kraken 09:46:47 INFO - operating with platform_type : mac_ 09:46:47 INFO - Initialising browser for kraken test... 09:46:47 INFO - TEST-INFO | started process 2200 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/w3/rmzpg_rj3zjdrsx94grbz4kr00000w/T/tmpbMy_mR/profile http://localhost:53002/getInfo.html) 09:46:50 INFO - PROCESS | 2200 | 2017-03-08 09:46:50.699 plugin-container[2201:18141] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8c4b, name = 'com.apple.tsm.portname' 09:46:50 INFO - PROCESS | 2200 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:46:50 INFO - PROCESS | 2200 | 2017-03-08 09:46:50.705 plugin-container[2201:18141] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8c4f, name = 'com.apple.CFPasteboardClient' 09:46:50 INFO - PROCESS | 2200 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:46:50 INFO - PROCESS | 2200 | 2017-03-08 09:46:50.705 plugin-container[2201:18141] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 09:46:50 INFO - PROCESS | 2200 | __metrics Screen width/height:1600/1200 09:46:50 INFO - PROCESS | 2200 | colorDepth:24 09:46:50 INFO - PROCESS | 2200 | Browser inner width/height: 1024/689 09:46:50 INFO - PROCESS | 2200 | __metrics 09:46:50 ERROR - PROCESS | 2200 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3353: TypeError: this.tabs is undefined 09:46:51 INFO - TEST-INFO | 2200: exit 0 09:46:51 INFO - Browser initialized. 09:46:51 INFO - Running cycle 1/1 for kraken test... 09:46:51 INFO - TEST-INFO | started process 2203 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/w3/rmzpg_rj3zjdrsx94grbz4kr00000w/T/tmpbMy_mR/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:46:54 INFO - PROCESS | 2203 | 2017-03-08 09:46:54.131 plugin-container[2204:18267] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8833, name = 'com.apple.tsm.portname' 09:46:54 INFO - PROCESS | 2203 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:46:54 INFO - PROCESS | 2203 | 2017-03-08 09:46:54.132 plugin-container[2204:18267] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8b57, name = 'com.apple.CFPasteboardClient' 09:46:54 INFO - PROCESS | 2203 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:46:54 INFO - PROCESS | 2203 | 2017-03-08 09:46:54.132 plugin-container[2204:18267] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 09:47:03 INFO - PROCESS | 2203 | RSS: Main: 220356608 09:47:03 INFO - PROCESS | 2203 | 09:47:06 INFO - PROCESS | 2203 | Cycle 1(1): loaded http://localhost:53002/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:53002/tests/kraken/driver.html?testName=audio-beat-detection) 09:47:07 INFO - PROCESS | 2203 | RSS: Main: 235089920 09:47:07 INFO - PROCESS | 2203 | 09:47:10 INFO - PROCESS | 2203 | Cycle 1(1): loaded http://localhost:53002/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:53002/tests/kraken/driver.html?testName=audio-dft) 09:47:11 INFO - PROCESS | 2203 | RSS: Main: 243490816 09:47:11 INFO - PROCESS | 2203 | 09:47:14 INFO - PROCESS | 2203 | Cycle 1(1): loaded http://localhost:53002/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:53002/tests/kraken/driver.html?testName=audio-fft) 09:47:15 INFO - PROCESS | 2203 | RSS: Main: 244903936 09:47:15 INFO - PROCESS | 2203 | 09:47:17 INFO - PROCESS | 2203 | Cycle 1(1): loaded http://localhost:53002/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:53002/tests/kraken/driver.html?testName=audio-oscillator) 09:47:17 INFO - PROCESS | 2203 | RSS: Main: 247664640 09:47:17 INFO - PROCESS | 2203 | 09:47:20 INFO - PROCESS | 2203 | Cycle 1(1): loaded http://localhost:53002/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:53002/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:47:20 INFO - PROCESS | 2203 | RSS: Main: 248016896 09:47:20 INFO - PROCESS | 2203 | 09:47:23 INFO - PROCESS | 2203 | Cycle 1(1): loaded http://localhost:53002/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:53002/tests/kraken/driver.html?testName=imaging-darkroom) 09:47:24 INFO - PROCESS | 2203 | RSS: Main: 252497920 09:47:24 INFO - PROCESS | 2203 | 09:47:27 INFO - PROCESS | 2203 | Cycle 1(1): loaded http://localhost:53002/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:53002/tests/kraken/driver.html?testName=imaging-desaturate) 09:47:28 INFO - PROCESS | 2203 | RSS: Main: 251842560 09:47:28 INFO - PROCESS | 2203 | 09:47:31 INFO - PROCESS | 2203 | Cycle 1(1): loaded http://localhost:53002/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:53002/tests/kraken/driver.html?testName=json-parse-financial) 09:47:31 INFO - PROCESS | 2203 | RSS: Main: 251965440 09:47:31 INFO - PROCESS | 2203 | 09:47:33 INFO - PROCESS | 2203 | Cycle 1(1): loaded http://localhost:53002/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:53002/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:47:34 INFO - PROCESS | 2203 | RSS: Main: 251518976 09:47:34 INFO - PROCESS | 2203 | 09:47:36 INFO - PROCESS | 2203 | Cycle 1(1): loaded http://localhost:53002/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:53002/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:47:36 INFO - PROCESS | 2203 | RSS: Main: 251445248 09:47:36 INFO - PROCESS | 2203 | 09:47:39 INFO - PROCESS | 2203 | Cycle 1(1): loaded http://localhost:53002/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:53002/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:47:39 INFO - PROCESS | 2203 | RSS: Main: 251473920 09:47:39 INFO - PROCESS | 2203 | 09:47:42 INFO - PROCESS | 2203 | Cycle 1(1): loaded http://localhost:53002/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:53002/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:47:43 INFO - PROCESS | 2203 | RSS: Main: 251846656 09:47:43 INFO - PROCESS | 2203 | 09:47:46 INFO - PROCESS | 2203 | Cycle 1(1): loaded http://localhost:53002/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:53002/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:47:47 INFO - PROCESS | 2203 | RSS: Main: 251691008 09:47:47 INFO - PROCESS | 2203 | 09:47:48 INFO - PROCESS | 2203 | Cycle 1(1): loaded http://localhost:53002/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:53002/tests/kraken/driver.html?testName=ai-astar) 09:47:49 INFO - PROCESS | 2203 | RSS: Main: 253546496 09:47:49 INFO - PROCESS | 2203 | 09:47:49 INFO - PROCESS | 2203 | __start_tp_report 09:47:49 INFO - PROCESS | 2203 | _x_x_mozilla_page_load 09:47:49 INFO - PROCESS | 2203 | _x_x_mozilla_page_load_details 09:47:49 INFO - PROCESS | 2203 | |i|pagename|runs| 09:47:49 INFO - PROCESS | 2203 | |0;ai-astar;108;119;122;122;119;123;122;120;121;121 09:47:49 INFO - PROCESS | 2203 | |1;audio-beat-detection;142;113;112;147;112;131;115;96;158;143 09:47:49 INFO - PROCESS | 2203 | |2;audio-dft;241;260;200;196;200;203;195;200;193;291 09:47:49 INFO - PROCESS | 2203 | |3;audio-fft;83;64;62;91;61;65;80;81;102;79 09:47:49 INFO - PROCESS | 2203 | |4;audio-oscillator;83;71;84;73;71;71;89;98;101;89 09:47:49 INFO - PROCESS | 2203 | |5;imaging-gaussian-blur;101;138;100;88;90;89;91;89;91;89 09:47:49 INFO - PROCESS | 2203 | |6;imaging-darkroom;96;98;96;97;100;95;97;96;96;107 09:47:49 INFO - PROCESS | 2203 | |7;imaging-desaturate;90;91;92;91;102;95;94;91;90;91 09:47:49 INFO - PROCESS | 2203 | |8;json-parse-financial;67;66;64;67;70;70;69;74;108;97 09:47:49 INFO - PROCESS | 2203 | |9;json-stringify-tinderbox;59;65;70;60;67;65;59;60;60;63 09:47:49 INFO - PROCESS | 2203 | |10;stanford-crypto-aes;97;94;105;88;89;85;87;98;103;84 09:47:49 INFO - PROCESS | 2203 | |11;stanford-crypto-ccm;152;143;137;154;147;158;154;159;201;178 09:47:49 INFO - PROCESS | 2203 | |12;stanford-crypto-pbkdf2;180;169;170;168;168;177;172;174;192;156 09:47:49 INFO - PROCESS | 2203 | |13;stanford-crypto-sha256-iterative;65;68;65;66;67;66;66;67;66;65 09:47:49 INFO - PROCESS | 2203 | __end_tp_report 09:47:49 INFO - PROCESS | 2203 | __start_cc_report 09:47:49 INFO - PROCESS | 2203 | _x_x_mozilla_cycle_collect,1022 09:47:49 INFO - PROCESS | 2203 | __end_cc_report 09:47:49 INFO - PROCESS | 2203 | __startTimestamp1488995269080__endTimestamp 09:47:49 INFO - PROCESS | 2203 | 09:47:49 INFO - PROCESS | 2203 | ------- Summary: start ------- 09:47:49 INFO - PROCESS | 2203 | Number of tests: 14 09:47:49 INFO - PROCESS | 2203 | 09:47:49 INFO - PROCESS | 2203 | [#0] ai-astar Cycles:10 Average:119.70 Median:121.50 stddev:4.32 (3.6%) stddev-sans-first:1.41 09:47:49 INFO - PROCESS | 2203 | Values: 108.0 119.0 122.0 122.0 119.0 123.0 122.0 120.0 121.0 121.0 09:47:49 INFO - PROCESS | 2203 | 09:47:49 INFO - PROCESS | 2203 | [#1] audio-beat-detection Cycles:10 Average:126.90 Median:142.50 stddev:20.02 (14.1%) stddev-sans-first:20.48 09:47:49 INFO - PROCESS | 2203 | Values: 142.0 113.0 112.0 147.0 112.0 131.0 115.0 96.0 158.0 143.0 09:47:49 INFO - PROCESS | 2203 | 09:47:49 INFO - PROCESS | 2203 | [#2] audio-dft Cycles:10 Average:217.90 Median:201.50 stddev:34.09 (16.9%) stddev-sans-first:35.11 09:47:49 INFO - PROCESS | 2203 | Values: 241.0 260.0 200.0 196.0 200.0 203.0 195.0 200.0 193.0 291.0 09:47:49 INFO - PROCESS | 2203 | 09:47:49 INFO - PROCESS | 2203 | [#3] audio-fft Cycles:10 Average:76.80 Median:79.50 stddev:13.66 (17.2%) stddev-sans-first:14.30 09:47:49 INFO - PROCESS | 2203 | Values: 83.0 64.0 62.0 91.0 61.0 65.0 80.0 81.0 102.0 79.0 09:47:49 INFO - PROCESS | 2203 | 09:47:49 INFO - PROCESS | 2203 | [#4] audio-oscillator Cycles:10 Average:83.00 Median:83.50 stddev:11.32 (13.6%) stddev-sans-first:12.01 09:47:49 INFO - PROCESS | 2203 | Values: 83.0 71.0 84.0 73.0 71.0 71.0 89.0 98.0 101.0 89.0 09:47:49 INFO - PROCESS | 2203 | 09:47:49 INFO - PROCESS | 2203 | [#5] imaging-gaussian-blur Cycles:10 Average:96.60 Median:89.00 stddev:15.27 (17.2%) stddev-sans-first:16.11 09:47:49 INFO - PROCESS | 2203 | Values: 101.0 138.0 100.0 88.0 90.0 89.0 91.0 89.0 91.0 89.0 09:47:49 INFO - PROCESS | 2203 | 09:47:49 INFO - PROCESS | 2203 | [#6] imaging-darkroom Cycles:10 Average:97.80 Median:96.00 stddev:3.52 (3.7%) stddev-sans-first:3.67 09:47:49 INFO - PROCESS | 2203 | Values: 96.0 98.0 96.0 97.0 100.0 95.0 97.0 96.0 96.0 107.0 09:47:49 INFO - PROCESS | 2203 | 09:47:49 INFO - PROCESS | 2203 | [#7] imaging-desaturate Cycles:10 Average:92.70 Median:91.00 stddev:3.65 (4.0%) stddev-sans-first:3.74 09:47:49 INFO - PROCESS | 2203 | Values: 90.0 91.0 92.0 91.0 102.0 95.0 94.0 91.0 90.0 91.0 09:47:49 INFO - PROCESS | 2203 | 09:47:49 INFO - PROCESS | 2203 | [#8] json-parse-financial Cycles:10 Average:75.20 Median:69.50 stddev:14.87 (21.4%) stddev-sans-first:15.47 09:47:49 INFO - PROCESS | 2203 | Values: 67.0 66.0 64.0 67.0 70.0 70.0 69.0 74.0 108.0 97.0 09:47:49 INFO - PROCESS | 2203 | 09:47:49 INFO - PROCESS | 2203 | [#9] json-stringify-tinderbox Cycles:10 Average:62.80 Median:64.00 stddev:3.82 (6.0%) stddev-sans-first:3.80 09:47:49 INFO - PROCESS | 2203 | Values: 59.0 65.0 70.0 60.0 67.0 65.0 59.0 60.0 60.0 63.0 09:47:49 INFO - PROCESS | 2203 | 09:47:49 INFO - PROCESS | 2203 | [#10] stanford-crypto-aes Cycles:10 Average:93.00 Median:88.50 stddev:7.51 (8.5%) stddev-sans-first:7.83 09:47:49 INFO - PROCESS | 2203 | Values: 97.0 94.0 105.0 88.0 89.0 85.0 87.0 98.0 103.0 84.0 09:47:49 INFO - PROCESS | 2203 | 09:47:49 INFO - PROCESS | 2203 | [#11] stanford-crypto-ccm Cycles:10 Average:158.30 Median:156.00 stddev:18.57 (11.9%) stddev-sans-first:19.56 09:47:49 INFO - PROCESS | 2203 | Values: 152.0 143.0 137.0 154.0 147.0 158.0 154.0 159.0 201.0 178.0 09:47:49 INFO - PROCESS | 2203 | 09:47:49 INFO - PROCESS | 2203 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:172.60 Median:173.00 stddev:9.37 (5.4%) stddev-sans-first:9.55 09:47:49 INFO - PROCESS | 2203 | Values: 180.0 169.0 170.0 168.0 168.0 177.0 172.0 174.0 192.0 156.0 09:47:49 INFO - PROCESS | 2203 | 09:47:49 INFO - PROCESS | 2203 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.10 Median:66.00 stddev:0.99 (1.5%) stddev-sans-first:0.97 09:47:49 INFO - PROCESS | 2203 | Values: 65.0 68.0 65.0 66.0 67.0 66.0 66.0 67.0 66.0 65.0 09:47:49 INFO - PROCESS | 2203 | -------- Summary: end -------- 09:47:49 INFO - PROCESS | 2203 | 09:47:49 INFO - TEST-INFO | 2203: exit 0 09:47:50 INFO - TEST-OK | kraken | took 62164ms 09:47:50 INFO - SUITE-END | took 1179s 09:47:50 INFO - Completed test suite (00:19:39) 09:47:50 INFO - javascript benchmark 09:47:50 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7592.992973048327, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3105.7360124389425, 3478.1652403423186, 3494.895153145403, 3498.2249229801314, 3499.072745722384, 5018.297438358623, 6072.423119803617, 6117.357677443865, 6134.969325153379, 6139.7851075212375, 6069.33237343893, 6108.114323423112, 6120.173776540166, 6127.35662755411, 6148.8155355339295, 5425.186222066692, 5435.076037073707, 5497.120460726284, 5533.47431993962, 5620.522255608278, 5566.55467562596, 5574.414686457924, 5574.47042530961, 5578.748956296956, 5579.69311687859, 5004.774785134678, 5321.414644389114, 5325.334333208349, 5555.861103472425, 5562.9443705562835, 5525.696086715229, 5530.087535556669, 5555.916661250044, 5572.3870374259, 5620.381758006616, 5385.219143224211, 5399.028174928511, 5496.917546236809, 5509.559235261169, 5559.054960656698, 5459.617826752129, 5775.740091695884, 5834.183214349994, 6050.152978583002, 6132.50939924805, 6276.089966954766, 6312.905306420407, 6327.715252813635, 6333.651649159295, 6357.65032923189, 6516.93483065168, 6522.445592124675, 6523.804285871432, 6531.0856480092825, 6538.476921846241, 5560.527355174855, 6249.312575616678, 6269.0, 6286.87426251477, 6318.810435686937, 6304.117423560705, 6333.746650134035, 6469.385408386196, 6522.673866306665, 6573.079768832367, 5036.7733451994745, 5737.77048918047, 6358.904616430757, 6366.872662546816, 6375.3624637536805, 5309.33633295838, 6321.0, 6456.225252969673, 6460.612363258126, 6466.0, 6270.717817698214, 6291.150694656254, 6313.558050936482, 6382.20222472191, 6392.84017899547, 6165.32181460039, 6231.563790534619, 6450.41946224842, 6480.287168411471, 6498.772542960974, 5106.744662766847, 5145.279660847485, 5155.994581056733, 5181.689098654093, 5193.948060519347, 4626.282926146453, 4643.141018911512, 4650.930236046462, 4682.993156471307, 4715.292706094045, 5920.970395147997, 5937.0, 6085.543584231112, 6206.906896396557, 6242.313345531988, 6401.9039714404325, 6528.21661400635, 6531.412172904366, 6535.803925882136, 6537.280899101095, 6203.4416902479, 6214.223222097238, 6215.440610345091, 6221.5333849961435, 6248.28144763349, 5368.275282836845, 5428.050091234098, 5744.798932037359, 5753.683547404933, 5761.452661997104, 6307.323408931906, 6548.377904099103, 6580.605163690194, 6596.637184954873, 6619.106420633153, 6407.775727849503, 6513.250976137805, 6518.152640156749, 6523.217213934168, 6559.540832141704, 6717.227518835201, 6734.528582999143, 6740.898886516804, 6743.055972163803, 6745.797626071226, 6281.026440901667, 6293.433590976834, 6319.557630965788, 6354.968225158845, 6371.96814015927, 4929.0, 6460.644664543495, 6488.870222595616, 6495.1556297681, 6535.705893234721, 6237.563370564017, 6329.335419781041, 6337.2395312562785, 6347.71435285404, 6396.584222025554, 6209.441150296495, 6272.623642581277, 6281.371862813682, 6287.874242515216, 6341.334159913143, 5402.486763757594, 6257.43683068526, 6338.524610654312, 6354.968225158845, 6395.840103997251, 5293.894122117459, 6220.409061139185, 6223.906641400292, 6312.684365781784, 6378.0, 6261.592996455216, 6280.623162610258, 6288.27684816252, 6299.181106456314, 6318.462930650762], "value": 5918.770813997755, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [728.1189760389927, 741.7515132430635, 817.7628487738556, 930.2929773372239, 935.8315503209428, 31988.200294992635, 37565.0, 38332.0416989575, 39975.80012099935, 39975.80072597827, 28812.0, 36841.52633894641, 36961.63038369615, 36974.260514789756, 36999.63000369995, 7718.073831140257, 7849.882251766199, 10379.740506487322, 10699.518521666505, 10854.67435976922, 10481.73795655111, 10564.366138031704, 10596.046355827973, 10606.098481629071, 10623.893761062367, 10560.683179504627, 10574.259801813876, 10584.947075264612, 10605.575776968912, 10620.0, 10506.054455099002, 10540.9986051325, 10559.894401056006, 10573.524191411405, 10576.841347379795, 10415.531301091469, 10442.477876106164, 10473.423961682105, 10476.161907047494, 10517.211209159344, 33273.16817079568, 33378.99863004114, 33499.49750753741, 33564.0, 33699.49450758241, 3055.618047744032, 3615.222727113683, 3628.983884512341, 3632.20091579852, 3695.113172838526, 19374.903125484423, 24765.752342476524, 24785.5042899141, 25506.59713715745, 26268.949242030296, 3023.425549145655, 3026.6821983691616, 3126.796758210709, 3184.8885289014775, 3199.1842080269384, 344079.2796036029, 346666.0, 347084.0, 347665.0, 367420.0, 43194.568054319054, 43737.50099991993, 43867.12265754667, 43885.12229755451, 43982.56017439849, 45068.77465612716, 45347.319790203175, 45574.54425455703, 45632.08735825265, 45659.77170114128, 13622.523211687543, 13803.930980345034, 13931.442742290195, 13944.372503237175, 13957.651058723613, 11793.705157371134, 11986.220895641929, 11991.880081199077, 12005.91946724799, 12007.639770806889, 11287.717807054725, 11799.823002654966, 11800.704982375337, 11843.58547450835, 11893.464794084288, 5587.8603034923635, 5614.242077319591, 5618.213450116987, 5674.319081710221, 5681.375048744635, 923.0999775219108, 1081.221520505235, 1109.4619109731773, 1125.3697929159696, 1133.659902029388, 26488.205353839414, 26589.734102659113, 26937.0, 26943.730562694123, 27039.864800676263, 946.5551190940247, 961.9038096190325, 1018.4449475036123, 1147.7991351513451, 1263.3620021888887, 8005.199480051948, 8182.549959752271, 8316.859154441101, 8334.374921880883, 8410.158984101541, 376273.1186344051, 419248.0, 423936.0, 424616.0, 425243.0, 56715.0, 57110.143347849815, 57191.0, 57261.0, 57411.0, 1375.2711063136553, 1386.632542376251, 1402.52314213168, 1430.6780974280912, 1435.770276755735, 22775.430614234778, 24814.875925620257, 26390.86804565965, 27055.45889082247, 27324.863375682995, 39799.403008955465, 40096.7995160034, 40105.79947100246, 40174.79912600418, 40262.39606405848, 497021.0, 500889.0, 503626.0, 504447.0, 505222.0, 26922.730772692023, 29427.85286073556, 29784.255393615334, 29822.55266170966, 29826.80692772265, 33357.99926002224, 36261.81869090637, 37172.0, 37605.0, 37684.62315376921, 30766.769329227474, 31023.53464697986, 31204.531932020585, 31787.364252715277, 32262.516062258615, 30736.0, 31035.379292413574, 31133.53299700461, 31514.0, 31697.0, 30117.0, 30856.69143308628, 31304.843475782476, 31445.842770786916, 31516.0, 27343.179704608894, 28518.287042824097, 28522.857385713767, 28573.857130714212, 28627.0, 22255.888720556293, 22270.77729222687, 22310.442238944157, 22323.218687345863, 22355.0], "value": 17662.10562313638, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [390.2896727955123, 392.87428023032635, 409.61291579457406, 425.75731832855274, 456.4203461603763, 19580.804191958076, 20161.697574536392, 20958.0, 21013.369598912057, 21127.260545880898, 17234.741478877826, 20963.475913102142, 21513.67729484059, 22246.0, 22429.214977475793, 6539.378759017897, 6808.2851300613465, 6809.489288303373, 6848.657567121649, 6862.416694580967, 6480.935190648081, 6661.966690166542, 6681.465482761391, 6785.423239024689, 6801.591904485722, 6609.900851487231, 6752.5948443093575, 6774.491913106512, 6790.388865002149, 6795.864082718343, 5914.61554998928, 6389.105525226472, 6535.281119076898, 6664.633445160538, 6671.566348187353, 5431.266778984826, 6505.447037001813, 6536.803895883131, 6540.738370465176, 6553.213614366259, 26194.73805261942, 26200.213993580222, 26430.471390572082, 26453.603195952077, 26522.46955060888, 3294.4893541500865, 3341.281624450755, 3383.983080084584, 3400.6769356911054, 3423.4693622488553, 15914.283857226452, 16075.955062920873, 16131.96337457192, 16621.584460388465, 17206.483805485856, 2035.918563257468, 2092.5396412789164, 2111.3032699209225, 2117.1637203304763, 2119.9682004769943, 137605.0, 138081.0, 139500.0, 175681.18869301697, 183170.99999999866, 19413.51466213356, 20566.69149962752, 20762.339012878932, 20791.168353266003, 20796.688049679266, 25180.11869584556, 27597.724022759514, 27878.581821272695, 31953.201169970936, 32090.839545802122, 10285.43430111336, 10413.11488523484, 10550.894491055145, 10609.787804243872, 10659.4137322448, 7100.538464999759, 8634.956825215833, 8719.258862996716, 8898.911010889939, 8911.643534258686, 7742.303192712683, 7954.522728636301, 8512.658544142532, 9332.253419726405, 9646.421214727, 3328.951380315193, 3467.8612855486003, 3553.4542474023883, 3649.6532829381167, 3972.2254160439015, 897.264243320471, 912.5346073502561, 1002.6139936124645, 1015.4110615842793, 1053.7892421515728, 21760.564788704138, 22831.771682283295, 23642.64536031961, 23698.644520332207, 23842.046318147426, 920.2729843423754, 926.4904302633524, 933.883264591926, 960.2653969713175, 961.7739831139677, 6974.197967233833, 6990.895136572955, 7073.92926070743, 7167.104111986002, 7182.604956727326, 774480.0, 783398.0, 790949.0, 799294.0, 807197.0, 142509.5749042517, 157055.42944570637, 163204.0, 163760.0, 170514.0, 1085.0072183951595, 1259.9055070869538, 1281.2632736176736, 1387.8334599848083, 1388.2156581531403, 10328.793424131625, 12575.622731318075, 12577.434015469144, 12786.104972651825, 12904.032197585406, 51244.487555125466, 64639.0, 65768.34231657622, 66024.0, 66768.0, 69571.65214173897, 80157.59921200357, 91207.0, 94319.0, 95431.05085830437, 10402.323848949794, 10457.424841633783, 11696.239744416585, 11784.76430471403, 12167.75664486723, 11022.944885275523, 13134.60596182116, 13242.0, 13509.662258443617, 13675.589732308046, 11446.313221206754, 11653.126015549038, 11706.941465292619, 11725.706857328634, 11831.704207394883, 11248.0, 11306.4912078955, 11415.258008229439, 11495.0229230513, 11637.0, 9676.516174191403, 9728.513574321114, 11678.824817627572, 11827.940860295643, 11881.1089168311, 11776.941115294369, 11828.822567661664, 11885.563459490611, 11907.285562866256, 11963.401829908644, 11184.72038199019, 11300.039496642878, 11482.0, 11614.88385116138, 11705.824412633987], "value": 11294.526370991785, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1487.0854424528918, 1496.1546726099753, 1516.264611663343, 1528.1671489038476, 1546.829848716641, 10670.35977841329, 11053.834192487118, 11269.774604507915, 11299.604513842007, 11452.141089418285, 11076.778464430707, 11263.0, 11264.605738799144, 11276.830847537272, 11295.209335346508, 2389.307100940725, 2434.866082365469, 2449.632555116729, 2452.1172377943926, 2454.8036157107476, 2354.682117914077, 2395.6406539019113, 2396.2212281008656, 2404.6272827711646, 2408.759124087595, 2165.3828658832235, 2185.0822654485155, 2186.180182431588, 2190.9452263693374, 2197.890105494727, 2217.866927984326, 2224.9888750556224, 2227.676986836908, 2229.208630936019, 2236.5079682469996, 2278.168468508999, 2383.845050071757, 2388.605880029793, 2388.7014123234594, 2389.486260454011, 11109.277896936754, 11205.159613028974, 11212.607558735404, 11260.943695281552, 11318.377489238088, 5712.514746165985, 5770.826875193751, 5817.680027598481, 5917.644941303535, 5932.317783454915, 3405.591329040506, 3505.8772942946875, 3699.26014797039, 3700.1119731264303, 3700.7409481336317, 1139.1627154041773, 1176.2472017908544, 1201.4533387308754, 1203.8984329338646, 1227.9631611051682, 13679.931600341935, 13915.930420347935, 15015.474458393905, 15608.765868511982, 15618.76571851423, 4558.088382323548, 4627.930581041287, 4787.54518320759, 4806.615470762331, 4817.13291607514, 11455.255408398427, 11470.713232169095, 11540.422978851024, 11548.711282217844, 11556.94221528887, 5631.802886898939, 5668.744906479218, 5696.943030569724, 5698.515626171739, 5703.914441283384, 4360.585744354281, 5161.767720452589, 5166.638335316492, 5214.504622060898, 5223.2948551945765, 3808.447775072654, 3856.5564960029396, 3881.4177873318663, 3899.1811719539214, 3940.5271367435516, 1024.9897501025043, 1033.49358814182, 1041.505284989639, 1048.910842578374, 1058.2010582010532, 750.2497502497503, 757.9090509138829, 773.860705073081, 782.6791015683542, 793.5479368750473, 4949.7030178189425, 5977.402259773988, 6033.125196846433, 6068.575199735976, 6185.134081228632, 951.1392190068, 972.1396564040875, 972.9367591106698, 986.2208855004466, 1003.0922015575916, 3105.627324721048, 3911.9608803911597, 3967.6358386951197, 4034.1931613677384, 4151.543330233671, 13649.317534123255, 13697.657558561115, 14036.166383213073, 14049.648758781112, 14197.148171109766, 13954.651133721738, 13985.0, 14018.509352172625, 14155.787663185061, 14173.574792756233, 10196.796064078677, 10245.436500992368, 10271.383716977005, 10305.278630495794, 10351.844722329171, 6257.655828929453, 6345.4923606112325, 6454.257760357619, 6482.060101285381, 6482.157319548617, 12172.817407739069, 12312.445939932546, 12379.0, 12397.814032789696, 12484.625461236177, 12671.80992285135, 12701.0, 12726.300053497147, 12910.0, 13031.609051728463, 7705.576193309422, 7919.762407127795, 8428.957855210685, 8606.0, 8627.6980305689, 9784.070513301225, 9958.954309797657, 10004.599816007278, 10019.148372388428, 10043.648472303434, 7075.327843854826, 9065.68270110543, 9085.818283634158, 9174.862377064483, 9339.392939458914, 8860.424072435271, 8877.689280875138, 8904.643814247616, 8996.0, 9162.221211197122, 7812.92187078122, 7817.374610031297, 7833.295003449717, 7928.484648497829, 7935.0874649413745, 5724.255846739898, 5772.711364431678, 5781.479666830005, 5955.970220148872, 6011.6693581853415, 5780.768769249183, 5839.386864379179, 5926.851828704317, 5998.280206375263, 6130.111133885651], "value": 5044.891046725713, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [332.44979558830136, 366.12496134239154, 378.0737193875007, 380.6421963354445, 391.45196724585566, 4285.571442855713, 4625.861224163272, 5160.974195129023, 5256.947430525694, 5412.4587541245855, 4058.6753059755224, 4792.976035119828, 5101.260317253996, 5194.558462530689, 5217.1218361389865, 4455.502430591986, 4471.731696098244, 4508.120916421299, 4526.216376928199, 4573.908521829561, 4244.745315281078, 4485.299627807056, 4516.4418275695, 4526.4794548627, 4540.205464043779, 3318.369509793132, 3554.5734511858614, 3852.672522835562, 4165.58344165584, 4419.381286619876, 3671.81640917953, 3706.3307325747587, 3879.8254078566533, 4437.711548749337, 4493.123840851035, 3538.4515400113023, 3797.9240415191816, 4215.473065866767, 4241.406203131533, 4273.166732487166, 4448.421705178338, 4798.592119669797, 5046.520580544842, 5072.594192464594, 5144.897102057937, 4249.893752656178, 4397.868063958054, 4658.487566367697, 4921.606271498307, 4964.851054468336, 4626.450139203371, 5520.751566179531, 5522.751476183581, 5541.196526503675, 5676.091825307973, 3659.3413185626578, 3733.2640322250286, 4117.8970525736795, 4495.505494395614, 4495.976810435284, 5606.467385598362, 5713.571482138815, 5752.8274151775095, 5911.733971971272, 5938.901303258867, 4509.1658043262105, 4512.4133862597655, 4536.841210557681, 4538.977305113453, 4544.545545445429, 3936.8622098226424, 3954.683625309968, 4098.569650186747, 4587.233914942771, 4793.976030119897, 4347.34789781529, 4547.317902314613, 4991.176455884738, 5010.49895010496, 5051.368578927634, 4593.540645935446, 5156.714367772987, 5581.246531718166, 5630.4369563043365, 5642.887142257132, 1568.8704133024205, 1569.8101148897224, 1578.4396539228637, 1588.5385445390268, 1589.0148108173007, 998.201438848918, 1070.8809294287332, 1170.7541416302502, 1192.4633914738401, 1211.4306276050418, 4484.8430304939175, 4519.07358991406, 5117.948820511747, 5142.814303920819, 5160.354955630546, 5244.292020577173, 5465.496988328162, 5495.917561236584, 5742.856428589235, 5794.188813566104, 4453.19842428366, 4796.088743138793, 4956.702597844141, 5096.796128154832, 5257.526822585909, 2978.7765917555835, 2980.7764417668764, 2991.5662228976676, 3064.310530130703, 3064.4637188491924, 4969.428515720666, 5114.156164232934, 5845.298564172326, 5914.053751399755, 5917.585768996215, 4791.041791641685, 5424.860779236405, 5427.0, 5912.822615321547, 5929.673867937305, 5270.84041510867, 5668.943310566841, 5724.0, 5781.768729250783, 5833.79581714638, 5580.804671836629, 5634.536731162524, 5667.829965101054, 5814.273215848019, 5912.970435147797, 5049.823256186016, 5621.0, 5623.3533143689, 5642.463965923231, 5671.631343962629, 5293.602979776455, 5678.602497825278, 5743.339515955551, 5802.47777700009, 5907.793227237026, 4577.313402989578, 4631.467381251199, 4633.328167415639, 4652.488226295172, 4677.246032737723, 5492.297387809792, 5506.284183056177, 5524.933661817729, 5630.577706671934, 5805.274340707411, 4864.708117512961, 5268.578513718817, 5333.853221557384, 5551.694656793915, 5645.971770141287, 4807.471178170328, 4906.945006823407, 5329.014132385521, 5348.609361566098, 5414.214938833926, 4481.574250446202, 4522.8643140705835, 5109.821156259513, 5163.432022477449, 5271.551918087006, 5196.792128314825, 5256.6320357574605, 5441.374241962225, 5458.727063646881, 5585.357683866408, 5123.308353372247, 5293.523582877559, 5325.600579956596, 5386.8687575609565, 5502.284702988586], "value": 4231.849616978241, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [702.6521871673523, 710.0201721624164, 770.4337312075623, 811.1037303779323, 827.9751607451776, 13427.0, 13972.790408143861, 13973.091749036306, 13998.93000534996, 14697.779533306995, 16381.672366552692, 16529.752053719174, 16559.172041397942, 16574.41989530367, 16609.50171494854, 921.4056933278048, 942.006183476432, 951.0014484790977, 952.485657744817, 956.2636769687336, 899.9055099214554, 930.7952250504878, 952.5570609666504, 953.7806304549957, 956.3449037409362, 1003.5283416794096, 1006.3056491021208, 1010.9949450252739, 1011.4386515483903, 1012.4532752313742, 1003.0671475527756, 1005.7737009172987, 1009.5053423822275, 1010.2978429991159, 1010.5755582655303, 733.3253406865697, 747.2676776758752, 747.8616455955665, 747.9551226926402, 748.1321666866409, 15682.215889205494, 16054.1170235637, 16094.919525402414, 16558.172091395383, 16784.328626854913, 14006.369713362923, 14152.716945661132, 14190.148591084464, 14194.85805141956, 14237.430502779876, 11799.646010619696, 11949.22330048544, 11956.40217989097, 11961.820572691417, 11976.58081967127, 3551.8934431966823, 3590.9281814363826, 3616.692581130607, 3652.214773823641, 3696.8706095286807, 118653.40673296664, 120009.0, 123297.0, 126220.1066983996, 128225.0, 9318.86021709675, 10533.789324213472, 10859.294145880493, 10990.395528245863, 11281.887181128248, 18694.25222991065, 22589.548209035725, 23128.074877005067, 23215.767842321362, 23215.767842321362, 6171.691415429211, 6241.34465881085, 6271.529635277281, 6309.400606942425, 6406.199225096863, 4239.300515414983, 4240.575942405735, 4336.544662810423, 4380.7152535085115, 4508.661850361236, 3164.7942883712944, 3198.296374797541, 3225.145336485833, 3243.3351163011525, 3254.153919980822, 553.698234462219, 558.617347117226, 559.0858945623934, 560.9859753506195, 571.894199573072, 642.6497558830449, 673.6968364236113, 675.1290834823121, 689.9586024838526, 697.8290318871909, 9099.590518426687, 9720.708378748648, 10356.948215258875, 10360.082099515736, 10577.0, 587.122252232906, 671.5802623360399, 695.8260434891278, 702.2696395748467, 703.802935178151, 3911.3741801311653, 4999.350084488993, 5016.247562865599, 5019.899602008012, 5061.316722242524, 105811.0, 106775.39836902452, 106943.46528267309, 107788.0, 108281.0, 66599.0, 68642.65678671675, 69589.33130805414, 80536.59731701305, 84480.0, 75503.62248188833, 76081.6195919028, 79697.0, 80998.0, 81111.5944420286, 10912.290701104403, 11087.279326843729, 11150.553977840795, 11281.21031527785, 11433.885661143615, 42910.141797163255, 45622.77188614169, 49133.50866491289, 51568.22647660213, 52153.217701733745, 59881.70059149677, 65924.0, 68706.0, 70737.64631176811, 73099.53800924058, 12950.417231224428, 13040.282784446566, 13119.344032798514, 13175.14361566495, 13212.735745285232, 12345.629631111082, 14815.407383704962, 15105.773413398589, 15220.7683575312, 15823.36706531772, 13896.16623002623, 13964.4414223434, 14033.29833508341, 14358.210298433687, 14509.564713058626, 13871.722565548835, 13890.610938906433, 13936.0, 14097.731204191672, 14496.420143194155, 12765.489380424679, 13482.797758033834, 13607.86392136066, 13657.453701852211, 13856.376463059389, 8862.64549418016, 11108.38903860263, 11253.66239012831, 11404.144689148181, 11517.82723259135, 9237.769055773659, 10895.529103571234, 11542.0, 11560.826587601025, 11572.6528204154], "value": 7601.772664387386, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1539.3999999999999, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [108.0, 119.0, 122.0, 122.0, 119.0, 123.0, 122.0, 120.0, 121.0, 121.0], "value": 119.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [142.0, 113.0, 112.0, 147.0, 112.0, 131.0, 115.0, 96.0, 158.0, 143.0], "value": 126.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [241.0, 260.0, 200.0, 196.0, 200.0, 203.0, 195.0, 200.0, 193.0, 291.0], "value": 217.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 64.0, 62.0, 91.0, 61.0, 65.0, 80.0, 81.0, 102.0, 79.0], "value": 76.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 71.0, 84.0, 73.0, 71.0, 71.0, 89.0, 98.0, 101.0, 89.0], "value": 83.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [101.0, 138.0, 100.0, 88.0, 90.0, 89.0, 91.0, 89.0, 91.0, 89.0], "value": 96.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 98.0, 96.0, 97.0, 100.0, 95.0, 97.0, 96.0, 96.0, 107.0], "value": 97.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 91.0, 92.0, 91.0, 102.0, 95.0, 94.0, 91.0, 90.0, 91.0], "value": 92.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 66.0, 64.0, 67.0, 70.0, 70.0, 69.0, 74.0, 108.0, 97.0], "value": 75.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 65.0, 70.0, 60.0, 67.0, 65.0, 59.0, 60.0, 60.0, 63.0], "value": 62.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [97.0, 94.0, 105.0, 88.0, 89.0, 85.0, 87.0, 98.0, 103.0, 84.0], "value": 93.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [152.0, 143.0, 137.0, 154.0, 147.0, 158.0, 154.0, 159.0, 201.0, 178.0], "value": 158.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [180.0, 169.0, 170.0, 168.0, 168.0, 177.0, 172.0, 174.0, 192.0, 156.0], "value": 172.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 68.0, 65.0, 66.0, 67.0, 66.0, 66.0, 67.0, 66.0, 65.0], "value": 66.1, "unit": "score"}]}]} 09:47:50 INFO - Return code: 0 09:47:50 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 09:47:50 ERROR - # TBPL SUCCESS # 09:47:50 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:47:50 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:47:50 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:47:50 INFO - 2017-03-08 09:47:50.406 screenresolution[2214:18651] starting screenresolution argv=screenresolution get 09:47:50 INFO - 2017-03-08 09:47:50.408 screenresolution[2214:18651] Display 0: 1600x1200x32@60 09:47:50 INFO - 2017-03-08 09:47:50.414 screenresolution[2215:18654] starting screenresolution argv=screenresolution list 09:47:50 INFO - Available Modes on Display 0 09:47:50 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:47:50 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:47:50 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:47:50 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:47:50 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:47:50 INFO - Intel Iris: 09:47:50 INFO - Chipset Model: Intel Iris 09:47:50 INFO - Type: GPU 09:47:50 INFO - Bus: Built-In 09:47:50 INFO - VRAM (Dynamic, Max): 1536 MB 09:47:50 INFO - Vendor: Intel (0x8086) 09:47:50 INFO - Device ID: 0x0a2e 09:47:50 INFO - Revision ID: 0x0009 09:47:50 INFO - Displays: 09:47:50 INFO - Display: 09:47:50 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:47:50 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:47:50 INFO - Display Serial Number: 1600x1200 60 09:47:50 INFO - Main Display: Yes 09:47:50 INFO - Mirror: Off 09:47:50 INFO - Online: Yes 09:47:50 INFO - Rotation: Supported 09:47:50 INFO - Return code: 0 09:47:50 INFO - Running post-action listener: _resource_record_post_action 09:47:50 INFO - [mozharness: 2017-03-08 17:47:50.650428Z] Finished run-tests step (success) 09:47:50 INFO - Running post-run listener: _resource_record_post_run 09:47:50 INFO - Total resource usage - Wall time: 1201s; CPU: 55.0%; Read bytes: 499712; Write bytes: 515443200; Read time: 9; Write time: 1684 09:47:50 INFO - TinderboxPrint: CPU usage
54.6% 09:47:50 INFO - TinderboxPrint: I/O read bytes / time
499,712 / 9 09:47:50 INFO - TinderboxPrint: I/O write bytes / time
515,443,200 / 1,684 09:47:50 INFO - TinderboxPrint: CPU idle
2,181.9 (45.4%) 09:47:50 INFO - TinderboxPrint: CPU system
970.2 (20.2%) 09:47:50 INFO - TinderboxPrint: CPU user
1,653.5 (34.4%) 09:47:50 INFO - TinderboxPrint: Swap in / out
209,432,576 / 0 09:47:50 INFO - install - Wall time: 21s; CPU: 54.0%; Read bytes: 201777664; Write bytes: 199557120; Read time: 15580; Write time: 444 09:47:50 INFO - run-tests - Wall time: 1181s; CPU: 55.0%; Read bytes: 499712; Write bytes: 305129984; Read time: 9; Write time: 1207 09:47:51 INFO - Running post-run listener: _upload_blobber_files 09:47:51 INFO - Blob upload gear active. 09:47:51 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:47:51 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 09:47:51 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'] 09:47:51 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 09:47:51 INFO - (blobuploader) - INFO - Open directory for files ... 09:47:51 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 09:47:51 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:47:51 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:47:52 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 09:47:52 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:47:52 INFO - (blobuploader) - INFO - Done attempting. 09:47:52 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 09:47:52 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:47:52 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:47:53 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 09:47:53 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:47:53 INFO - (blobuploader) - INFO - Done attempting. 09:47:53 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 09:47:53 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:47:53 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:47:54 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 09:47:54 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:47:54 INFO - (blobuploader) - INFO - Done attempting. 09:47:54 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 09:47:54 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:47:54 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:47:56 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 09:47:56 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:47:56 INFO - (blobuploader) - INFO - Done attempting. 09:47:56 INFO - (blobuploader) - INFO - Iteration through files over. 09:47:56 INFO - Return code: 0 09:47:56 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:47:56 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:47:56 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/7d64dfc9dc2627b2df5afb0c1d36621b2644fa21dd01101c656d3d8004fe173f6fc8b44d19b307bae464e4fc57b610891e22d1fc0cad235d504473667ecd6458", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/6548db57509f6c21890aa5f20aa8e580c70c14aad777837e8903fe6ff5a324edc93fdbfff442ee20441fc73370e1768b8b590e24125de9e070203ff63d2fbe4c", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/8fcfbecb38ccf4eeaa315e5d44357766582d38e2dc3b77d76062b070ef1375c615c81d8faf08c59c12154189798e6fd04b805e5dd6dbd70e6f59358237dad374", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/efbea9bae01698f2ee0cbedee33b1b64730f9b05e9bd3e5a9be2e4ee183ec1b13059b0495f37b6393599cdf757d9d7f4cbbb2438dd2040d2f52bfbce7c97dbf0"} 09:47:56 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:47:56 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:47:56 INFO - Contents: 09:47:56 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/7d64dfc9dc2627b2df5afb0c1d36621b2644fa21dd01101c656d3d8004fe173f6fc8b44d19b307bae464e4fc57b610891e22d1fc0cad235d504473667ecd6458", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/6548db57509f6c21890aa5f20aa8e580c70c14aad777837e8903fe6ff5a324edc93fdbfff442ee20441fc73370e1768b8b590e24125de9e070203ff63d2fbe4c", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/8fcfbecb38ccf4eeaa315e5d44357766582d38e2dc3b77d76062b070ef1375c615c81d8faf08c59c12154189798e6fd04b805e5dd6dbd70e6f59358237dad374", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/efbea9bae01698f2ee0cbedee33b1b64730f9b05e9bd3e5a9be2e4ee183ec1b13059b0495f37b6393599cdf757d9d7f4cbbb2438dd2040d2f52bfbce7c97dbf0"} 09:47:56 INFO - Running post-run listener: copy_logs_to_upload_dir 09:47:56 INFO - Copying logs to upload dir... 09:47:56 INFO - mkdir: /builds/slave/test/build/upload/logs 09:47:56 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1283.096966 ========= master_lag: 0.45 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 23 secs) (at 2017-03-08 09:47:56.955611) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-08 09:47:56.959461) ========= 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.HS2ehUAfQ5/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.uQahpadQ75/Listeners TMPDIR=/var/folders/w3/rmzpg_rj3zjdrsx94grbz4kr00000w/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/7d64dfc9dc2627b2df5afb0c1d36621b2644fa21dd01101c656d3d8004fe173f6fc8b44d19b307bae464e4fc57b610891e22d1fc0cad235d504473667ecd6458", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/6548db57509f6c21890aa5f20aa8e580c70c14aad777837e8903fe6ff5a324edc93fdbfff442ee20441fc73370e1768b8b590e24125de9e070203ff63d2fbe4c", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/8fcfbecb38ccf4eeaa315e5d44357766582d38e2dc3b77d76062b070ef1375c615c81d8faf08c59c12154189798e6fd04b805e5dd6dbd70e6f59358237dad374", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/efbea9bae01698f2ee0cbedee33b1b64730f9b05e9bd3e5a9be2e4ee183ec1b13059b0495f37b6393599cdf757d9d7f4cbbb2438dd2040d2f52bfbce7c97dbf0"} build_url:https://queue.taskcluster.net/v1/task/fzptBtX2To2PHoHNb6yOPA/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011312 build_url: 'https://queue.taskcluster.net/v1/task/fzptBtX2To2PHoHNb6yOPA/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/7d64dfc9dc2627b2df5afb0c1d36621b2644fa21dd01101c656d3d8004fe173f6fc8b44d19b307bae464e4fc57b610891e22d1fc0cad235d504473667ecd6458", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/6548db57509f6c21890aa5f20aa8e580c70c14aad777837e8903fe6ff5a324edc93fdbfff442ee20441fc73370e1768b8b590e24125de9e070203ff63d2fbe4c", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/8fcfbecb38ccf4eeaa315e5d44357766582d38e2dc3b77d76062b070ef1375c615c81d8faf08c59c12154189798e6fd04b805e5dd6dbd70e6f59358237dad374", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/efbea9bae01698f2ee0cbedee33b1b64730f9b05e9bd3e5a9be2e4ee183ec1b13059b0495f37b6393599cdf757d9d7f4cbbb2438dd2040d2f52bfbce7c97dbf0"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-08 09:47:57.003054) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-08 09:47:57.003355) ========= 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.HS2ehUAfQ5/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.uQahpadQ75/Listeners TMPDIR=/var/folders/w3/rmzpg_rj3zjdrsx94grbz4kr00000w/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.005395 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-08 09:47:57.051869) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-08 09:47:57.052187) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-08 09:47:57.052531) ========= ========= Total master_lag: 0.60 =========