builder: mozilla-aurora_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0067 starttime: 1489166907.0 results: success (0) buildid: 20170310074623 builduid: 5dad902c01284117b59e0a152bb6a280 revision: 5b0ad641d3214345e7bb7745fdd7bde0cfc6643d ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-03-10 09:28:26.998684) ========= master: http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-03-10 09:28:26.999119) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-10 09:28:26.999398) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-10 09:28:27.027162) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-10 09:28:27.027445) ========= 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.2ni27nkc8x/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.jzIZyM7Dbn/Listeners TMPDIR=/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/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-10 09:28:27-- 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.42M=0.001s 2017-03-10 09:28:27 (9.42 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.081975 ========= master_lag: 0.28 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-10 09:28:27.386460) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-10 09:28:27.386883) ========= 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.2ni27nkc8x/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.jzIZyM7Dbn/Listeners TMPDIR=/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/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.027493 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-10 09:28:27.437889) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-10 09:28:27.438228) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 5b0ad641d3214345e7bb7745fdd7bde0cfc6643d --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 5b0ad641d3214345e7bb7745fdd7bde0cfc6643d --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.2ni27nkc8x/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.jzIZyM7Dbn/Listeners TMPDIR=/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/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-10 09:28:27,541 truncating revision to first 12 chars 2017-03-10 09:28:27,541 Setting DEBUG logging. 2017-03-10 09:28:27,541 attempt 1/10 2017-03-10 09:28:27,541 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/5b0ad641d321?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-03-10 09:28:27,849 unpacking tar archive at: mozilla-aurora-5b0ad641d321/testing/mozharness/ program finished with exit code 0 elapsedTime=0.580706 ========= master_lag: 0.21 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-10 09:28:28.225690) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-10 09:28:28.226010) ========= script_repo_revision: 5b0ad641d3214345e7bb7745fdd7bde0cfc6643d ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-10 09:28:28.226390) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-10 09:28:28.226668) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-10 09:28:28.246368) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 22 secs) (at 2017-03-10 09:28:28.246650) ========= /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.2ni27nkc8x/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.jzIZyM7Dbn/Listeners TMPDIR=/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/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:28:28 INFO - MultiFileLogger online at 20170310 09:28:28 in /builds/slave/test 09:28:28 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:28:28 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 09:28:28 INFO - {'append_to_log': False, 09:28:28 INFO - 'base_work_dir': '/builds/slave/test', 09:28:28 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 09:28:28 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 09:28:28 INFO - 'branch': 'Mozilla-Aurora', 09:28:28 INFO - 'buildbot_json_path': 'buildprops.json', 09:28:28 INFO - 'config_files': ('talos/mac_config.py',), 09:28:28 INFO - 'default_actions': ('clobber', 09:28:28 INFO - 'read-buildbot-config', 09:28:28 INFO - 'download-and-extract', 09:28:28 INFO - 'populate-webroot', 09:28:28 INFO - 'create-virtualenv', 09:28:28 INFO - 'install', 09:28:28 INFO - 'run-tests'), 09:28:28 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:28:28 INFO - 'download_minidump_stackwalk': True, 09:28:28 INFO - 'download_symbols': 'ondemand', 09:28:28 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 09:28:28 INFO - 'tooltool.py': '/tools/tooltool.py', 09:28:28 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 09:28:28 INFO - '/tools/misc-python/virtualenv.py')}, 09:28:28 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:28:28 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:28:28 INFO - 'gecko_profile': False, 09:28:28 INFO - 'gecko_profile_interval': 0, 09:28:28 INFO - 'installer_path': 'installer.exe', 09:28:28 INFO - 'log_level': 'info', 09:28:28 INFO - 'log_name': 'talos', 09:28:28 INFO - 'log_to_console': True, 09:28:28 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 09:28:28 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 09:28:28 INFO - 'opt_config_files': (), 09:28:28 INFO - 'pip_index': False, 09:28:28 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:28:28 INFO - 'cmd': ('bash', 09:28:28 INFO - '-c', 09:28:28 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:28:28 INFO - 'enabled': True, 09:28:28 INFO - 'halt_on_failure': False, 09:28:28 INFO - 'name': 'check_screen_resolution'},), 09:28:28 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:28:28 INFO - 'cmd': ('bash', 09:28:28 INFO - '-c', 09:28:28 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:28:28 INFO - 'enabled': True, 09:28:28 INFO - 'halt_on_failure': False, 09:28:28 INFO - 'name': 'check_screen_resolution'},), 09:28:28 INFO - 'run_cmd_checks_enabled': True, 09:28:28 INFO - 'suite': 'dromaeojs-e10s', 09:28:28 INFO - 'system_bits': '32', 09:28:28 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:28:28 INFO - 'title': 't-yosemite-r7-0067', 09:28:28 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:28:28 INFO - 'use_talos_json': True, 09:28:28 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 09:28:28 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:28:28 INFO - 'work_dir': 'build'} 09:28:28 INFO - [mozharness: 2017-03-10 17:28:28.466355Z] Running clobber step. 09:28:28 INFO - Running pre-action listener: _resource_record_pre_action 09:28:28 INFO - Running main action method: clobber 09:28:28 INFO - rmtree: /builds/slave/test/build 09:28:28 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 09:28:29 INFO - Running post-action listener: _resource_record_post_action 09:28:29 INFO - [mozharness: 2017-03-10 17:28:29.871838Z] Finished clobber step (success) 09:28:29 INFO - [mozharness: 2017-03-10 17:28:29.871948Z] Running read-buildbot-config step. 09:28:29 INFO - Running pre-action listener: _resource_record_pre_action 09:28:29 INFO - Running main action method: read_buildbot_config 09:28:29 INFO - Using buildbot properties: 09:28:29 INFO - { 09:28:29 INFO - "project": "", 09:28:29 INFO - "product": "firefox", 09:28:29 INFO - "script_repo_revision": "production", 09:28:29 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs-e10s", 09:28:29 INFO - "repository": "", 09:28:29 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs-e10s", 09:28:29 INFO - "buildid": "20170310074623", 09:28:29 INFO - "pgo_build": "False", 09:28:29 INFO - "basedir": "/builds/slave/test", 09:28:29 INFO - "buildnumber": 62, 09:28:29 INFO - "slavename": "t-yosemite-r7-0067", 09:28:29 INFO - "master": "http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/", 09:28:29 INFO - "platform": "yosemite_r7", 09:28:29 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 09:28:29 INFO - "branch": "mozilla-aurora", 09:28:29 INFO - "repo_path": "releases/mozilla-aurora", 09:28:29 INFO - "revision": "5b0ad641d3214345e7bb7745fdd7bde0cfc6643d", 09:28:29 INFO - "stage_platform": "macosx64", 09:28:29 INFO - "builduid": "5dad902c01284117b59e0a152bb6a280", 09:28:29 INFO - "slavebuilddir": "test" 09:28:29 INFO - } 09:28:29 INFO - Found installer url https://queue.taskcluster.net/v1/task/B4BfDL3PQOyOjUohjVfCtQ/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg. 09:28:29 INFO - Running post-action listener: _resource_record_post_action 09:28:29 INFO - [mozharness: 2017-03-10 17:28:29.875406Z] Finished read-buildbot-config step (success) 09:28:29 INFO - [mozharness: 2017-03-10 17:28:29.875547Z] Running download-and-extract step. 09:28:29 INFO - Running pre-action listener: _resource_record_pre_action 09:28:29 INFO - Running main action method: download_and_extract 09:28:29 INFO - mkdir: /builds/slave/test/build/tests 09:28:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:28:29 INFO - trying https://queue.taskcluster.net/v1/task/B4BfDL3PQOyOjUohjVfCtQ/artifacts/public/build/firefox-54.0a2.en-US.mac.test_packages.json 09:28:29 INFO - Downloading https://queue.taskcluster.net/v1/task/B4BfDL3PQOyOjUohjVfCtQ/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:28:29 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/B4BfDL3PQOyOjUohjVfCtQ/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:28:31 INFO - Downloaded 1199 bytes. 09:28:31 INFO - Reading from file /builds/slave/test/build/firefox-54.0a2.en-US.mac.test_packages.json 09:28:31 INFO - Using the following test package requirements: 09:28:31 INFO - {u'common': [u'firefox-54.0a2.en-US.mac.common.tests.zip'], 09:28:31 INFO - u'cppunittest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 09:28:31 INFO - u'firefox-54.0a2.en-US.mac.cppunittest.tests.zip'], 09:28:31 INFO - u'gtest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 09:28:31 INFO - u'firefox-54.0a2.en-US.mac.gtest.tests.zip'], 09:28:31 INFO - u'jittest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 09:28:31 INFO - u'jsshell-mac.zip'], 09:28:31 INFO - u'mochitest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 09:28:31 INFO - u'firefox-54.0a2.en-US.mac.mochitest.tests.zip'], 09:28:31 INFO - u'mozbase': [u'firefox-54.0a2.en-US.mac.common.tests.zip'], 09:28:31 INFO - u'reftest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 09:28:31 INFO - u'firefox-54.0a2.en-US.mac.reftest.tests.zip'], 09:28:31 INFO - u'talos': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 09:28:31 INFO - u'firefox-54.0a2.en-US.mac.talos.tests.zip'], 09:28:31 INFO - u'web-platform': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 09:28:31 INFO - u'firefox-54.0a2.en-US.mac.web-platform.tests.zip'], 09:28:31 INFO - u'xpcshell': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 09:28:31 INFO - u'firefox-54.0a2.en-US.mac.xpcshell.tests.zip']} 09:28:31 INFO - Downloading packages: [u'firefox-54.0a2.en-US.mac.common.tests.zip'] for test suite category: common 09:28:31 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/B4BfDL3PQOyOjUohjVfCtQ/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip 09:28:31 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/B4BfDL3PQOyOjUohjVfCtQ/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip'}, attempt #1 09:28:31 INFO - Fetch https://queue.taskcluster.net/v1/task/B4BfDL3PQOyOjUohjVfCtQ/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip into memory 09:28:32 INFO - Expected file size: 51777975 09:28:32 INFO - Obtained file size: 51777975 09:28:48 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:28:48 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/B4BfDL3PQOyOjUohjVfCtQ/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip 09:28:48 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/B4BfDL3PQOyOjUohjVfCtQ/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip'}, attempt #1 09:28:48 INFO - Fetch https://queue.taskcluster.net/v1/task/B4BfDL3PQOyOjUohjVfCtQ/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip into memory 09:28:51 INFO - Expected file size: 51777975 09:28:51 INFO - Obtained file size: 51777975 09:29:06 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/B4BfDL3PQOyOjUohjVfCtQ/artifacts/public/build/firefox-54.0a2.en-US.mac.talos.tests.zip 09:29:06 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/B4BfDL3PQOyOjUohjVfCtQ/artifacts/public/build/firefox-54.0a2.en-US.mac.talos.tests.zip'}, attempt #1 09:29:06 INFO - Fetch https://queue.taskcluster.net/v1/task/B4BfDL3PQOyOjUohjVfCtQ/artifacts/public/build/firefox-54.0a2.en-US.mac.talos.tests.zip into memory 09:29:06 INFO - Expected file size: 14404424 09:29:06 INFO - Obtained file size: 14404424 09:29:07 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:29:07 INFO - trying https://queue.taskcluster.net/v1/task/B4BfDL3PQOyOjUohjVfCtQ/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg 09:29:07 INFO - Downloading https://queue.taskcluster.net/v1/task/B4BfDL3PQOyOjUohjVfCtQ/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-54.0a2.en-US.mac.dmg 09:29:07 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/B4BfDL3PQOyOjUohjVfCtQ/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:29:09 INFO - Downloaded 74619474 bytes. 09:29:09 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/B4BfDL3PQOyOjUohjVfCtQ/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg 09:29:09 INFO - mkdir: /builds/slave/test/properties 09:29:09 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 09:29:09 INFO - Writing to file /builds/slave/test/properties/build_url 09:29:09 INFO - Contents: 09:29:09 INFO - build_url:https://queue.taskcluster.net/v1/task/B4BfDL3PQOyOjUohjVfCtQ/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg 09:29:09 INFO - Running post-action listener: _resource_record_post_action 09:29:09 INFO - Running post-action listener: set_extra_try_arguments 09:29:09 INFO - [mozharness: 2017-03-10 17:29:09.890329Z] Finished download-and-extract step (success) 09:29:09 INFO - [mozharness: 2017-03-10 17:29:09.890454Z] Running populate-webroot step. 09:29:09 INFO - Running pre-action listener: _resource_record_pre_action 09:29:09 INFO - Running main action method: populate_webroot 09:29:09 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:29:09 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:29:09 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:29:09 INFO - '--tppagecycles', 09:29:09 INFO - '7'], 09:29:09 INFO - 'tests': ['tsvgm']}}, 09:29:09 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 09:29:09 INFO - 'tests': ['tresize', 'tcanvasmark']}, 09:29:09 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 09:29:09 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 09:29:09 INFO - 'tests': ['dromaeo_css', 'kraken']}, 09:29:09 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 09:29:09 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 09:29:09 INFO - 'talos_options': ['--disable-e10s'], 09:29:09 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:29:09 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 09:29:09 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:29:09 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 09:29:09 INFO - 'talos_options': ['--disable-e10s'], 09:29:09 INFO - 'tests': ['damp', 'tps']}, 09:29:09 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 09:29:09 INFO - 'tests': ['damp', 'tps']}, 09:29:09 INFO - 'g3': {'talos_options': ['--disable-e10s'], 09:29:09 INFO - 'tests': ['dromaeo_dom']}, 09:29:09 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:29:09 INFO - 'g4': {'talos_options': ['--disable-e10s'], 09:29:09 INFO - 'tests': ['basic_compositor_video']}, 09:29:09 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 09:29:09 INFO - 'other': {'talos_options': ['--disable-e10s'], 09:29:09 INFO - 'tests': ['a11yr', 09:29:09 INFO - 'ts_paint', 09:29:09 INFO - 'tpaint', 09:29:09 INFO - 'sessionrestore', 09:29:09 INFO - 'sessionrestore_no_auto_restore', 09:29:09 INFO - 'tabpaint']}, 09:29:09 INFO - 'other-e10s': {'tests': ['a11yr', 09:29:09 INFO - 'ts_paint', 09:29:09 INFO - 'tpaint', 09:29:09 INFO - 'sessionrestore', 09:29:09 INFO - 'sessionrestore_no_auto_restore', 09:29:09 INFO - 'tabpaint']}, 09:29:09 INFO - 'other-e10s_l64': {'tests': ['a11yr', 09:29:09 INFO - 'ts_paint', 09:29:09 INFO - 'tpaint', 09:29:09 INFO - 'sessionrestore', 09:29:09 INFO - 'sessionrestore_no_auto_restore', 09:29:09 INFO - 'tabpaint']}, 09:29:09 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 09:29:09 INFO - 'ts_paint', 09:29:09 INFO - 'tpaint', 09:29:09 INFO - 'sessionrestore', 09:29:09 INFO - 'sessionrestore_no_auto_restore', 09:29:09 INFO - 'tabpaint']}, 09:29:09 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 09:29:09 INFO - 'tests': ['a11yr', 09:29:09 INFO - 'ts_paint', 09:29:09 INFO - 'tpaint', 09:29:09 INFO - 'sessionrestore', 09:29:09 INFO - 'sessionrestore_no_auto_restore', 09:29:09 INFO - 'tabpaint']}, 09:29:09 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 09:29:09 INFO - 'tests': ['a11yr', 09:29:09 INFO - 'ts_paint', 09:29:09 INFO - 'tpaint', 09:29:09 INFO - 'sessionrestore', 09:29:09 INFO - 'sessionrestore_no_auto_restore', 09:29:09 INFO - 'tabpaint']}, 09:29:09 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 09:29:09 INFO - 'tests': ['tsvgx', 09:29:09 INFO - 'tsvgr_opacity', 09:29:09 INFO - 'tart', 09:29:09 INFO - 'tscrollx', 09:29:09 INFO - 'cart', 09:29:09 INFO - 'tsvg_static']}, 09:29:09 INFO - 'svgr-e10s': {'tests': ['tsvgx', 09:29:09 INFO - 'tsvgr_opacity', 09:29:09 INFO - 'tart', 09:29:09 INFO - 'tscrollx', 09:29:09 INFO - 'cart', 09:29:09 INFO - 'tsvg_static']}, 09:29:09 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 09:29:09 INFO - 'talos_options': ['--disable-e10s'], 09:29:09 INFO - 'tests': ['tp5o']}, 09:29:09 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 09:29:09 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 09:29:09 INFO - 'talos_options': ['--disable-e10s', 09:29:09 INFO - '--xperf_path', 09:29:09 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:29:09 INFO - 'tests': ['tp5n']}, 09:29:09 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 09:29:09 INFO - 'talos_options': ['--xperf_path', 09:29:09 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:29:09 INFO - 'tests': ['tp5n']}}, 09:29:09 INFO - 'talos.zip': {'path': '', 09:29:09 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:29:09 INFO - Running post-action listener: _resource_record_post_action 09:29:09 INFO - [mozharness: 2017-03-10 17:29:09.908704Z] Finished populate-webroot step (success) 09:29:09 INFO - [mozharness: 2017-03-10 17:29:09.908819Z] Running create-virtualenv step. 09:29:09 INFO - Running pre-action listener: _resource_record_pre_action 09:29:09 INFO - Running main action method: create_virtualenv 09:29:09 INFO - Creating virtualenv /builds/slave/test/build/venv 09:29:09 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:29:09 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 09:29:10 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:29:10 INFO - Using real prefix '/tools/python27' 09:29:10 INFO - New python executable in /builds/slave/test/build/venv/bin/python 09:29:10 INFO - Installing distribute.............................................................................................................................................................................................done. 09:29:13 INFO - Installing pip.................done. 09:29:13 INFO - Return code: 0 09:29:13 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 09:29:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 09:29:13 INFO - Reading from file tmpfile_stdout 09:29:13 INFO - Output received: 09:29:13 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:29:13 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 09:29:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:29:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:29:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:29:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:29:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:29:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:29:13 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10f1c8b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f1cb768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc4ea4b4690>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10efa6d30>, '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 0x10eecddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ef9bbe8>, '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.2ni27nkc8x/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.jzIZyM7Dbn/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/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:29:13 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 09:29:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 09:29:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.2ni27nkc8x/Render', 09:29:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:29:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:29:13 INFO - 'HOME': '/Users/cltbld', 09:29:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:29:13 INFO - 'LOGNAME': 'cltbld', 09:29:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:29:13 INFO - 'MOZ_NO_REMOTE': '1', 09:29:13 INFO - 'NO_EM_RESTART': '1', 09:29:13 INFO - 'PAGER': '/bin/cat', 09:29:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:29:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:29:13 INFO - 'PWD': '/builds/slave/test', 09:29:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:29:13 INFO - 'SHELL': '/bin/bash', 09:29:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.jzIZyM7Dbn/Listeners', 09:29:13 INFO - 'TMPDIR': '/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/', 09:29:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:29:13 INFO - 'USER': 'cltbld', 09:29:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:29:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:29:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:29:13 INFO - 'XPC_FLAGS': '0x0', 09:29:13 INFO - 'XPC_SERVICE_NAME': '0', 09:29:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:29:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:29:14 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:29:14 INFO - Cleaning up... 09:29:14 INFO - Return code: 0 09:29:14 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 09:29:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:29:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:29:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:29:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:29:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:29:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:29:14 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 0x10f1c8b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f1cb768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc4ea4b4690>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10efa6d30>, '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 0x10eecddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ef9bbe8>, '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.2ni27nkc8x/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.jzIZyM7Dbn/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/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:29:14 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:29:14 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:29:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.2ni27nkc8x/Render', 09:29:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:29:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:29:14 INFO - 'HOME': '/Users/cltbld', 09:29:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:29:14 INFO - 'LOGNAME': 'cltbld', 09:29:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:29:14 INFO - 'MOZ_NO_REMOTE': '1', 09:29:14 INFO - 'NO_EM_RESTART': '1', 09:29:14 INFO - 'PAGER': '/bin/cat', 09:29:14 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:29:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:29:14 INFO - 'PWD': '/builds/slave/test', 09:29:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:29:14 INFO - 'SHELL': '/bin/bash', 09:29:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.jzIZyM7Dbn/Listeners', 09:29:14 INFO - 'TMPDIR': '/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/', 09:29:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:29:14 INFO - 'USER': 'cltbld', 09:29:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:29:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:29:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:29:14 INFO - 'XPC_FLAGS': '0x0', 09:29:14 INFO - 'XPC_SERVICE_NAME': '0', 09:29:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:29:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:29:14 INFO - Downloading/unpacking psutil>=3.1.1 09:29:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:29:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:29:14 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:29:14 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:29:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:29:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:29:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 09:29:18 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:29:18 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:29:18 INFO - Installing collected packages: psutil 09:29:18 INFO - Running setup.py install for psutil 09:29:18 INFO - building 'psutil._psutil_osx' extension 09:29:18 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:29:18 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:29:18 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:29:18 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:29:18 INFO - building 'psutil._psutil_posix' extension 09:29:18 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:29:19 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 09:29:19 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 09:29:19 INFO - ^ 09:29:19 INFO - 1 warning generated. 09:29:19 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:29:19 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:29:19 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:29:19 INFO - Successfully installed psutil 09:29:19 INFO - Cleaning up... 09:29:19 INFO - Return code: 0 09:29:19 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 09:29:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:29:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:29:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:29:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:29:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:29:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:29: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', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10f1c8b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f1cb768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc4ea4b4690>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10efa6d30>, '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 0x10eecddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ef9bbe8>, '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.2ni27nkc8x/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.jzIZyM7Dbn/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/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:29: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', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 09:29: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 mozsystemmonitor==0.3 09:29:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.2ni27nkc8x/Render', 09:29:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:29:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:29:20 INFO - 'HOME': '/Users/cltbld', 09:29:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:29:20 INFO - 'LOGNAME': 'cltbld', 09:29:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:29:20 INFO - 'MOZ_NO_REMOTE': '1', 09:29:20 INFO - 'NO_EM_RESTART': '1', 09:29:20 INFO - 'PAGER': '/bin/cat', 09:29:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:29:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:29:20 INFO - 'PWD': '/builds/slave/test', 09:29:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:29:20 INFO - 'SHELL': '/bin/bash', 09:29:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.jzIZyM7Dbn/Listeners', 09:29:20 INFO - 'TMPDIR': '/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/', 09:29:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:29:20 INFO - 'USER': 'cltbld', 09:29:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:29:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:29:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:29:20 INFO - 'XPC_FLAGS': '0x0', 09:29:20 INFO - 'XPC_SERVICE_NAME': '0', 09:29:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:29:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:29:20 INFO - Downloading/unpacking mozsystemmonitor==0.3 09:29: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:29: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:29: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:29: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:29: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:29: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:29:23 INFO - Downloading mozsystemmonitor-0.3.tar.gz 09:29:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:29:23 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 09:29:23 INFO - Installing collected packages: mozsystemmonitor 09:29:23 INFO - Running setup.py install for mozsystemmonitor 09:29:23 INFO - Successfully installed mozsystemmonitor 09:29:23 INFO - Cleaning up... 09:29:23 INFO - Return code: 0 09:29:23 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 09:29:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:29:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:29:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:29:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:29:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:29:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:29:23 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 0x10f1c8b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f1cb768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc4ea4b4690>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10efa6d30>, '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 0x10eecddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ef9bbe8>, '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.2ni27nkc8x/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.jzIZyM7Dbn/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/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:29:23 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:29:23 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:29:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.2ni27nkc8x/Render', 09:29:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:29:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:29:23 INFO - 'HOME': '/Users/cltbld', 09:29:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:29:23 INFO - 'LOGNAME': 'cltbld', 09:29:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:29:23 INFO - 'MOZ_NO_REMOTE': '1', 09:29:23 INFO - 'NO_EM_RESTART': '1', 09:29:23 INFO - 'PAGER': '/bin/cat', 09:29:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:29:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:29:23 INFO - 'PWD': '/builds/slave/test', 09:29:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:29:23 INFO - 'SHELL': '/bin/bash', 09:29:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.jzIZyM7Dbn/Listeners', 09:29:23 INFO - 'TMPDIR': '/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/', 09:29:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:29:23 INFO - 'USER': 'cltbld', 09:29:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:29:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:29:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:29:23 INFO - 'XPC_FLAGS': '0x0', 09:29:23 INFO - 'XPC_SERVICE_NAME': '0', 09:29:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:29:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:29:23 INFO - Downloading/unpacking jsonschema==2.5.1 09:29:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:29:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:29:23 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:29:23 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:29:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:29:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:29:26 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:29:26 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 09:29:26 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:29:26 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:29:26 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:29:26 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:29:26 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:29:26 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:29:26 INFO - Downloading functools32-3.2.3-2.tar.gz 09:29:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 09:29:27 INFO - warning: no files found matching '*.txt' 09:29:27 INFO - no previously-included directories found matching 'build' 09:29:27 INFO - no previously-included directories found matching 'dist' 09:29:27 INFO - no previously-included directories found matching '.git*' 09:29:27 INFO - Installing collected packages: jsonschema, functools32 09:29:27 INFO - Running setup.py install for functools32 09:29:27 INFO - warning: no files found matching '*.txt' 09:29:27 INFO - no previously-included directories found matching 'build' 09:29:27 INFO - no previously-included directories found matching 'dist' 09:29:27 INFO - no previously-included directories found matching '.git*' 09:29:27 INFO - Successfully installed jsonschema functools32 09:29:27 INFO - Cleaning up... 09:29:27 INFO - Return code: 0 09:29:27 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 09:29:27 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:29:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:29:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:29:27 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:29:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:29:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:29:27 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 0x10f1c8b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f1cb768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc4ea4b4690>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10efa6d30>, '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 0x10eecddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ef9bbe8>, '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.2ni27nkc8x/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.jzIZyM7Dbn/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/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:29:27 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:29:27 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:29:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.2ni27nkc8x/Render', 09:29:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:29:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:29:27 INFO - 'HOME': '/Users/cltbld', 09:29:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:29:27 INFO - 'LOGNAME': 'cltbld', 09:29:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:29:27 INFO - 'MOZ_NO_REMOTE': '1', 09:29:27 INFO - 'NO_EM_RESTART': '1', 09:29:27 INFO - 'PAGER': '/bin/cat', 09:29:27 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:29:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:29:27 INFO - 'PWD': '/builds/slave/test', 09:29:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:29:27 INFO - 'SHELL': '/bin/bash', 09:29:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.jzIZyM7Dbn/Listeners', 09:29:27 INFO - 'TMPDIR': '/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/', 09:29:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:29:27 INFO - 'USER': 'cltbld', 09:29:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:29:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:29:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:29:27 INFO - 'XPC_FLAGS': '0x0', 09:29:27 INFO - 'XPC_SERVICE_NAME': '0', 09:29:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:29:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:29:27 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 09:29:27 INFO - Cleaning up... 09:29:27 INFO - Return code: 0 09:29:27 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 09:29:27 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:29:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:29:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:29:27 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:29:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:29:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:29:27 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 0x10f1c8b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f1cb768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc4ea4b4690>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10efa6d30>, '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 0x10eecddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ef9bbe8>, '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.2ni27nkc8x/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.jzIZyM7Dbn/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/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:29:27 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:29:27 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:29:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.2ni27nkc8x/Render', 09:29:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:29:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:29:27 INFO - 'HOME': '/Users/cltbld', 09:29:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:29:27 INFO - 'LOGNAME': 'cltbld', 09:29:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:29:27 INFO - 'MOZ_NO_REMOTE': '1', 09:29:27 INFO - 'NO_EM_RESTART': '1', 09:29:27 INFO - 'PAGER': '/bin/cat', 09:29:27 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:29:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:29:27 INFO - 'PWD': '/builds/slave/test', 09:29:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:29:27 INFO - 'SHELL': '/bin/bash', 09:29:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.jzIZyM7Dbn/Listeners', 09:29:27 INFO - 'TMPDIR': '/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/', 09:29:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:29:27 INFO - 'USER': 'cltbld', 09:29:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:29:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:29:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:29:27 INFO - 'XPC_FLAGS': '0x0', 09:29:27 INFO - 'XPC_SERVICE_NAME': '0', 09:29:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:29:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:29:27 INFO - Downloading/unpacking blobuploader==1.2.4 09:29:27 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:29:27 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:29:27 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:29:27 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:29:27 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:29:27 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:29:30 INFO - Downloading blobuploader-1.2.4.tar.gz 09:29:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:29:30 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:29:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:29:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:29:30 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:29:30 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:29:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:29:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:29:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 09:29:31 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:29:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:29:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:29:31 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:29:31 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:29:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:29:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:29:31 INFO - Downloading docopt-0.6.1.tar.gz 09:29:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 09:29:31 INFO - Installing collected packages: blobuploader, requests, docopt 09:29:31 INFO - Running setup.py install for blobuploader 09:29:31 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:29:31 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 09:29:31 INFO - Running setup.py install for requests 09:29:32 INFO - Running setup.py install for docopt 09:29:32 INFO - Successfully installed blobuploader requests docopt 09:29:32 INFO - Cleaning up... 09:29:32 INFO - Return code: 0 09:29:32 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:29:32 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:29:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:29:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:29:32 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:29:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:29:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:29:32 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 0x10f1c8b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f1cb768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc4ea4b4690>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10efa6d30>, '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 0x10eecddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ef9bbe8>, '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.2ni27nkc8x/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.jzIZyM7Dbn/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/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:29:32 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:29:32 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:29:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.2ni27nkc8x/Render', 09:29:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:29:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:29:32 INFO - 'HOME': '/Users/cltbld', 09:29:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:29:32 INFO - 'LOGNAME': 'cltbld', 09:29:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:29:32 INFO - 'MOZ_NO_REMOTE': '1', 09:29:32 INFO - 'NO_EM_RESTART': '1', 09:29:32 INFO - 'PAGER': '/bin/cat', 09:29:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:29:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:29:32 INFO - 'PWD': '/builds/slave/test', 09:29:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:29:32 INFO - 'SHELL': '/bin/bash', 09:29:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.jzIZyM7Dbn/Listeners', 09:29:32 INFO - 'TMPDIR': '/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/', 09:29:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:29:32 INFO - 'USER': 'cltbld', 09:29:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:29:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:29:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:29:32 INFO - 'XPC_FLAGS': '0x0', 09:29:32 INFO - 'XPC_SERVICE_NAME': '0', 09:29:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:29:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:29:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:29:32 INFO - Running setup.py (path:/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/pip-TbOl6c-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:29:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:29:33 INFO - Running setup.py (path:/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/pip-4DBfE4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:29:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:29:33 INFO - Running setup.py (path:/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/pip-vLxAsK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:29:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:29:33 INFO - Running setup.py (path:/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/pip-yFN2TY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:29:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:29:33 INFO - Running setup.py (path:/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/pip-6enj2I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:29:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:29:33 INFO - Running setup.py (path:/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/pip-IvV_xX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:29:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:29:33 INFO - Running setup.py (path:/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/pip-5iJsct-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:29:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:29:33 INFO - Running setup.py (path:/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/pip-E65rEh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:29:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:29:33 INFO - Running setup.py (path:/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/pip-24Lv5q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:29:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:29:33 INFO - Running setup.py (path:/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/pip-5o826N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:29:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:29:33 INFO - Running setup.py (path:/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/pip-9H21Lg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:29:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:29:34 INFO - Running setup.py (path:/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/pip-DswqyB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:29:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:29:34 INFO - Running setup.py (path:/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/pip-sbP4OU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:29:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:29:34 INFO - Running setup.py (path:/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/pip-DWXQkh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:29:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:29:34 INFO - Running setup.py (path:/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/pip-rrxz8T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:29:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:29:34 INFO - Running setup.py (path:/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/pip-Th_V2S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:29:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:29:34 INFO - Running setup.py (path:/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/pip-l_v88z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:29:34 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:29:34 INFO - Running setup.py install for manifestparser 09:29:34 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 09:29:34 INFO - Running setup.py install for mozcrash 09:29:35 INFO - Running setup.py install for mozdebug 09:29:35 INFO - Running setup.py install for mozdevice 09:29:35 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 09:29:35 INFO - Installing dm script to /builds/slave/test/build/venv/bin 09:29:35 INFO - Running setup.py install for mozfile 09:29:35 INFO - Running setup.py install for mozhttpd 09:29:35 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 09:29:35 INFO - Running setup.py install for mozinfo 09:29:35 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 09:29:35 INFO - Running setup.py install for mozInstall 09:29:35 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 09:29:35 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 09:29:35 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 09:29:35 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 09:29:35 INFO - Running setup.py install for mozleak 09:29:36 INFO - Running setup.py install for mozlog 09:29:36 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 09:29:36 INFO - Running setup.py install for moznetwork 09:29:36 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 09:29:36 INFO - Running setup.py install for mozprocess 09:29:36 INFO - Running setup.py install for mozprofile 09:29:36 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 09:29:36 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 09:29:36 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 09:29:36 INFO - Running setup.py install for mozrunner 09:29:36 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 09:29:36 INFO - Running setup.py install for mozscreenshot 09:29:37 INFO - Running setup.py install for moztest 09:29:37 INFO - Running setup.py install for mozversion 09:29:37 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 09:29:37 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:29:37 INFO - Cleaning up... 09:29:37 INFO - Return code: 0 09:29:37 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:29:37 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:29:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:29:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:29:37 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:29:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:29:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:29:37 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 0x10f1c8b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f1cb768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc4ea4b4690>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10efa6d30>, '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 0x10eecddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ef9bbe8>, '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.2ni27nkc8x/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.jzIZyM7Dbn/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/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:29:37 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:29:37 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:29:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.2ni27nkc8x/Render', 09:29:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:29:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:29:37 INFO - 'HOME': '/Users/cltbld', 09:29:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:29:37 INFO - 'LOGNAME': 'cltbld', 09:29:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:29:37 INFO - 'MOZ_NO_REMOTE': '1', 09:29:37 INFO - 'NO_EM_RESTART': '1', 09:29:37 INFO - 'PAGER': '/bin/cat', 09:29:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:29:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:29:37 INFO - 'PWD': '/builds/slave/test', 09:29:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:29:37 INFO - 'SHELL': '/bin/bash', 09:29:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.jzIZyM7Dbn/Listeners', 09:29:37 INFO - 'TMPDIR': '/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/', 09:29:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:29:37 INFO - 'USER': 'cltbld', 09:29:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:29:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:29:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:29:37 INFO - 'XPC_FLAGS': '0x0', 09:29:37 INFO - 'XPC_SERVICE_NAME': '0', 09:29:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:29:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:29:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:29:37 INFO - Running setup.py (path:/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/pip-BgLQsb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:29:37 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:29:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:29:37 INFO - Running setup.py (path:/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/pip-5MEq6s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:29:37 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:29:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:29:37 INFO - Running setup.py (path:/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/pip-DjvNNH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:29:37 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:29:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:29:37 INFO - Running setup.py (path:/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/pip-Fr8kVw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:29:38 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:29:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:29:38 INFO - Running setup.py (path:/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/pip-wVX9PF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:29:38 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:29:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:29:38 INFO - Running setup.py (path:/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/pip-2cMERR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:29:38 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:29:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:29:38 INFO - Running setup.py (path:/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/pip-WtkKCw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:29:38 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:29:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:29:38 INFO - Running setup.py (path:/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/pip-NKhgau-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:29:38 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:29:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:29:38 INFO - Running setup.py (path:/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/pip-OyAxyt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:29:38 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:29:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:29:38 INFO - Running setup.py (path:/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/pip-DCeAlC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:29:38 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:29:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:29:38 INFO - Running setup.py (path:/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/pip-Dy6r1W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:29:38 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:29:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:29:38 INFO - Running setup.py (path:/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/pip-r2e5I_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:29:38 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:29:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:29:38 INFO - Running setup.py (path:/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/pip-g3iLzr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:29:38 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:29:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:29:39 INFO - Running setup.py (path:/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/pip-ucGz7P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:29:39 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:29:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:29:39 INFO - Running setup.py (path:/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/pip-n6VRKB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:29:39 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:29:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:29:39 INFO - Running setup.py (path:/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/pip-P9liPI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:29:39 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:29:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:29:39 INFO - Running setup.py (path:/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/pip-DB7ZaD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:29:39 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:29:39 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:29:39 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:29:39 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:29:39 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:29:39 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:29:39 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:29:39 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:29:39 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:29:39 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:29:39 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:29:39 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:29:39 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:29:41 INFO - Downloading blessings-1.6.tar.gz 09:29:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 09:29:42 INFO - Installing collected packages: blessings 09:29:42 INFO - Running setup.py install for blessings 09:29:42 INFO - Successfully installed blessings 09:29:42 INFO - Cleaning up... 09:29:42 INFO - Return code: 0 09:29:42 INFO - Done creating virtualenv /builds/slave/test/build/venv. 09:29:42 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:29:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:29:42 INFO - Reading from file tmpfile_stdout 09:29:42 INFO - Current package versions: 09:29:42 INFO - blessings == 1.6 09:29:42 INFO - blobuploader == 1.2.4 09:29:42 INFO - docopt == 0.6.1 09:29:42 INFO - functools32 == 3.2.3-2 09:29:42 INFO - jsonschema == 2.5.1 09:29:42 INFO - manifestparser == 1.1 09:29:42 INFO - mozInstall == 1.12 09:29:42 INFO - mozcrash == 1.0 09:29:42 INFO - mozdebug == 0.1 09:29:42 INFO - mozdevice == 0.49 09:29:42 INFO - mozfile == 1.2 09:29:42 INFO - mozhttpd == 0.7 09:29:42 INFO - mozinfo == 0.9 09:29:42 INFO - mozleak == 0.1 09:29:42 INFO - mozlog == 3.4 09:29:42 INFO - moznetwork == 0.27 09:29:42 INFO - mozprocess == 0.25 09:29:42 INFO - mozprofile == 0.28 09:29:42 INFO - mozrunner == 6.13 09:29:42 INFO - mozscreenshot == 0.1 09:29:42 INFO - mozsystemmonitor == 0.3 09:29:42 INFO - moztest == 0.8 09:29:42 INFO - mozversion == 1.4 09:29:42 INFO - psutil == 3.1.1 09:29:42 INFO - requests == 1.2.3 09:29:42 INFO - wsgiref == 0.1.2 09:29:42 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:29:42 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:29:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:29:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:29:42 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:29:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:29:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:29:42 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 0x10f1c8b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f1cb768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc4ea4b4690>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10efa6d30>, '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 0x10eecddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ef9bbe8>, '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.2ni27nkc8x/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.jzIZyM7Dbn/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/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:29:42 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:29:42 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:29:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.2ni27nkc8x/Render', 09:29:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:29:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:29:42 INFO - 'HOME': '/Users/cltbld', 09:29:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:29:42 INFO - 'LOGNAME': 'cltbld', 09:29:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:29:42 INFO - 'MOZ_NO_REMOTE': '1', 09:29:42 INFO - 'NO_EM_RESTART': '1', 09:29:42 INFO - 'PAGER': '/bin/cat', 09:29:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:29:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:29:42 INFO - 'PWD': '/builds/slave/test', 09:29:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:29:42 INFO - 'SHELL': '/bin/bash', 09:29:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.jzIZyM7Dbn/Listeners', 09:29:42 INFO - 'TMPDIR': '/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/', 09:29:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:29:42 INFO - 'USER': 'cltbld', 09:29:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:29:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:29:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:29:42 INFO - 'XPC_FLAGS': '0x0', 09:29:42 INFO - 'XPC_SERVICE_NAME': '0', 09:29:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:29:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:29:42 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:29:42 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:29:42 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:29:42 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:29:42 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:29:42 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:29:42 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:29:42 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:29:42 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:29:42 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:29:42 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:29:42 INFO - Cleaning up... 09:29:43 INFO - Return code: 0 09:29:43 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 09:29: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:29:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:29:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:29: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:29:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:29:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:29:43 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 0x10f1c8b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f1cb768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc4ea4b4690>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10efa6d30>, '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 0x10eecddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ef9bbe8>, '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.2ni27nkc8x/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.jzIZyM7Dbn/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/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:29:43 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:29:43 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:29:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.2ni27nkc8x/Render', 09:29:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:29:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:29:43 INFO - 'HOME': '/Users/cltbld', 09:29:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:29:43 INFO - 'LOGNAME': 'cltbld', 09:29:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:29:43 INFO - 'MOZ_NO_REMOTE': '1', 09:29:43 INFO - 'NO_EM_RESTART': '1', 09:29:43 INFO - 'PAGER': '/bin/cat', 09:29:43 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:29:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:29:43 INFO - 'PWD': '/builds/slave/test', 09:29:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:29:43 INFO - 'SHELL': '/bin/bash', 09:29:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.jzIZyM7Dbn/Listeners', 09:29:43 INFO - 'TMPDIR': '/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/', 09:29:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:29:43 INFO - 'USER': 'cltbld', 09:29:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:29:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:29:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:29:43 INFO - 'XPC_FLAGS': '0x0', 09:29:43 INFO - 'XPC_SERVICE_NAME': '0', 09:29:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:29:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:29:43 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 09:29:43 INFO - Cleaning up... 09:29:43 INFO - Return code: 0 09:29:43 INFO - Running post-action listener: _resource_record_post_action 09:29:43 INFO - Running post-action listener: _start_resource_monitoring 09:29:43 INFO - Starting resource monitoring. 09:29:43 INFO - [mozharness: 2017-03-10 17:29:43.295381Z] Finished create-virtualenv step (success) 09:29:43 INFO - [mozharness: 2017-03-10 17:29:43.296350Z] Running install step. 09:29:43 INFO - Running pre-action listener: _resource_record_pre_action 09:29:43 INFO - Running main action method: install 09:29:43 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:29:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:29:43 INFO - Reading from file tmpfile_stdout 09:29:43 INFO - Detecting whether we're running mozinstall >=1.0... 09:29:43 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 09:29:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 09:29:43 INFO - Reading from file tmpfile_stdout 09:29:43 INFO - Output received: 09:29:43 INFO - Usage: mozinstall [options] installer 09:29:43 INFO - Options: 09:29:43 INFO - -h, --help show this help message and exit 09:29:43 INFO - -d DEST, --destination=DEST 09:29:43 INFO - Directory to install application into. [default: 09:29:43 INFO - "/builds/slave/test"] 09:29:43 INFO - --app=APP Application being installed. [default: firefox] 09:29:43 INFO - mkdir: /builds/slave/test/build/application 09:29:43 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:29:43 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:30:03 INFO - Reading from file tmpfile_stdout 09:30:03 INFO - Output received: 09:30:03 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 09:30:03 INFO - Running post-action listener: _resource_record_post_action 09:30:03 INFO - [mozharness: 2017-03-10 17:30:03.738715Z] Finished install step (success) 09:30:03 INFO - [mozharness: 2017-03-10 17:30:03.738848Z] Running run-tests step. 09:30:03 INFO - Running pre-action listener: _resource_record_pre_action 09:30:03 INFO - Running main action method: run_tests 09:30:03 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:30:03 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:30:03 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:30:03 INFO - 2017-03-10 09:30:03.753 screenresolution[1627:7450] starting screenresolution argv=screenresolution get 09:30:03 INFO - 2017-03-10 09:30:03.756 screenresolution[1627:7450] Display 0: 1600x1200x32@60 09:30:03 INFO - 2017-03-10 09:30:03.761 screenresolution[1628:7452] starting screenresolution argv=screenresolution list 09:30:03 INFO - Available Modes on Display 0 09:30:03 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:30:03 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:30:03 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:30:03 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:30:03 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:30:03 INFO - Intel Iris: 09:30:03 INFO - Chipset Model: Intel Iris 09:30:03 INFO - Type: GPU 09:30:03 INFO - Bus: Built-In 09:30:03 INFO - VRAM (Dynamic, Max): 1536 MB 09:30:03 INFO - Vendor: Intel (0x8086) 09:30:03 INFO - Device ID: 0x0a2e 09:30:03 INFO - Revision ID: 0x0009 09:30:03 INFO - Displays: 09:30:03 INFO - Display: 09:30:03 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:30:03 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:30:03 INFO - Display Serial Number: 1600x1200 60 09:30:03 INFO - Main Display: Yes 09:30:03 INFO - Mirror: Off 09:30:03 INFO - Online: Yes 09:30:03 INFO - Rotation: Supported 09:30:03 INFO - Return code: 0 09:30:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 09:30:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 09:30:03 INFO - Python 2.7.3 09:30:03 INFO - Return code: 0 09:30:03 INFO - grabbing minidump binary from tooltool 09:30:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:30:04 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 0x10efa6d30>, '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 0x10eecddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ef9bbe8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 09:30:04 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:30:04 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:30:04 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:30:04 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:30:04 INFO - Return code: 0 09:30:04 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 09:30:04 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 09:30:04 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 09:30:04 INFO - ENV: RUST_BACKTRACE is now 1 09:30:04 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 09:30:04 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 09:30:04 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/B4BfDL3PQOyOjUohjVfCtQ/artifacts/public/build/firefox-54.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0067', '--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:30:04 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/B4BfDL3PQOyOjUohjVfCtQ/artifacts/public/build/firefox-54.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0067 --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:30:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.2ni27nkc8x/Render', 09:30:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:30:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:30:04 INFO - 'HOME': '/Users/cltbld', 09:30:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:30:04 INFO - 'LOGNAME': 'cltbld', 09:30:04 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:30:04 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 09:30:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:30:04 INFO - 'MOZ_NO_REMOTE': '1', 09:30:04 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:30:04 INFO - 'NO_EM_RESTART': '1', 09:30:04 INFO - 'PAGER': '/bin/cat', 09:30:04 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:30:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:30:04 INFO - 'PWD': '/builds/slave/test', 09:30:04 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:30:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:30:04 INFO - 'RUST_BACKTRACE': '1', 09:30:04 INFO - 'SHELL': '/bin/bash', 09:30:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.jzIZyM7Dbn/Listeners', 09:30:04 INFO - 'TMPDIR': '/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/', 09:30:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:30:04 INFO - 'USER': 'cltbld', 09:30:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:30:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:30:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:30:04 INFO - 'XPC_FLAGS': '0x0', 09:30:04 INFO - 'XPC_SERVICE_NAME': '0', 09:30:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:30:04 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/B4BfDL3PQOyOjUohjVfCtQ/artifacts/public/build/firefox-54.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0067', '--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:30:04 INFO - mozversion application_buildid: 20170310074623 09:30:04 INFO - mozversion application_changeset: 5b0ad641d3214345e7bb7745fdd7bde0cfc6643d 09:30:04 INFO - mozversion application_display_name: FirefoxDeveloperEdition 09:30:04 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:30:04 INFO - mozversion application_name: Firefox 09:30:04 INFO - mozversion application_remotingname: firefox-dev 09:30:04 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 09:30:04 INFO - mozversion application_vendor: Mozilla 09:30:04 INFO - mozversion application_version: 54.0a2 09:30:04 INFO - mozversion platform_buildid: 20170310074623 09:30:04 INFO - mozversion platform_changeset: 5b0ad641d3214345e7bb7745fdd7bde0cfc6643d 09:30:04 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 09:30:04 INFO - mozversion platform_version: 54.0a2 09:30:04 INFO - using testdate: 1489167004 09:30:04 INFO - actual date: 1489167004 09:30:04 INFO - starting webserver on 'localhost:49237' 09:30:04 INFO - SUITE-START | Running 2 tests 09:30:04 INFO - TEST-START | dromaeo_css 09:30:04 INFO - operating with platform_type : mac_ 09:30:04 INFO - Initialising browser for dromaeo_css test... 09:30:04 INFO - TEST-INFO | started process 1638 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/tmp3kswxq/profile http://localhost:49237/getInfo.html) 09:30:07 INFO - PROCESS | 1638 | 2017-03-10 09:30:07.065 plugin-container[1641:7634] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4b3b, name = 'com.apple.tsm.portname' 09:30:07 INFO - PROCESS | 1638 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:30:07 INFO - PROCESS | 1638 | 2017-03-10 09:30:07.068 plugin-container[1641:7634] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x960b, name = 'com.apple.CFPasteboardClient' 09:30:07 INFO - PROCESS | 1638 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:30:07 INFO - PROCESS | 1638 | 2017-03-10 09:30:07.069 plugin-container[1641:7634] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 09:30:07 INFO - PROCESS | 1638 | __metrics Screen width/height:1600/1200 09:30:07 INFO - PROCESS | 1638 | colorDepth:24 09:30:07 INFO - PROCESS | 1638 | Browser inner width/height: 1024/689 09:30:07 INFO - PROCESS | 1638 | __metrics 09:30:07 ERROR - PROCESS | 1638 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3353: TypeError: this.tabs is undefined 09:30:07 INFO - TEST-INFO | 1638: exit 0 09:30:07 INFO - Browser initialized. 09:30:07 INFO - Running cycle 1/1 for dromaeo_css test... 09:30:08 INFO - TEST-INFO | started process 1642 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/tmp3kswxq/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:30:10 INFO - PROCESS | 1642 | 2017-03-10 09:30:10.494 plugin-container[1643:7768] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4347, name = 'com.apple.tsm.portname' 09:30:10 INFO - PROCESS | 1642 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:30:10 INFO - PROCESS | 1642 | 2017-03-10 09:30:10.495 plugin-container[1643:7768] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x900b, name = 'com.apple.CFPasteboardClient' 09:30:10 INFO - PROCESS | 1642 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:30:10 INFO - PROCESS | 1642 | 2017-03-10 09:30:10.495 plugin-container[1643:7768] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 09:30:20 INFO - PROCESS | 1642 | RSS: Main: 219901952 09:30:20 INFO - PROCESS | 1642 | 09:33:23 INFO - PROCESS | 1642 | Cycle 1(1): loaded http://localhost:49237/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49237/tests/dromaeo/cssquery-ext.html) 09:33:25 INFO - PROCESS | 1642 | RSS: Main: 238723072 09:33:25 INFO - PROCESS | 1642 | 09:35:08 INFO - PROCESS | 1642 | ************************* 09:35:08 INFO - PROCESS | 1642 | A coding exception was thrown and uncaught in a Task. 09:35:08 INFO - PROCESS | 1642 | 09:35:08 ERROR - PROCESS | 1642 | Full message: TypeError: NetworkError when attempting to fetch resource. 09:35:08 INFO - PROCESS | 1642 | Full stack: 09:35:08 INFO - PROCESS | 1642 | ************************* 09:36:27 INFO - PROCESS | 1642 | Cycle 1(1): loaded http://localhost:49237/tests/dromaeo/cssquery-ext.html (next: http://localhost:49237/tests/dromaeo/cssquery-jquery.html) 09:36:28 INFO - PROCESS | 1642 | RSS: Main: 248762368 09:36:28 INFO - PROCESS | 1642 | 09:39:30 INFO - PROCESS | 1642 | Cycle 1(1): loaded http://localhost:49237/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49237/tests/dromaeo/cssquery-mootools.html) 09:39:30 INFO - PROCESS | 1642 | RSS: Main: 256135168 09:39:30 INFO - PROCESS | 1642 | 09:42:32 INFO - PROCESS | 1642 | Cycle 1(1): loaded http://localhost:49237/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49237/tests/dromaeo/cssquery-prototype.html) 09:42:33 INFO - PROCESS | 1642 | RSS: Main: 252309504 09:42:33 INFO - PROCESS | 1642 | 09:45:36 INFO - PROCESS | 1642 | Cycle 1(1): loaded http://localhost:49237/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49237/tests/dromaeo/cssquery-yui.html) 09:45:37 INFO - PROCESS | 1642 | RSS: Main: 257327104 09:45:37 INFO - PROCESS | 1642 | 09:48:39 INFO - PROCESS | 1642 | Cycle 1(1): loaded http://localhost:49237/tests/dromaeo/cssquery-yui.html (next: http://localhost:49237/tests/dromaeo/cssquery-dojo.html) 09:48:40 INFO - PROCESS | 1642 | RSS: Main: 253640704 09:48:40 INFO - PROCESS | 1642 | 09:48:40 INFO - PROCESS | 1642 | __start_tp_report 09:48:40 INFO - PROCESS | 1642 | _x_x_mozilla_page_load 09:48:40 INFO - PROCESS | 1642 | _x_x_mozilla_page_load_details 09:48:40 INFO - PROCESS | 1642 | |i|pagename|runs| 09:48:40 INFO - PROCESS | 1642 | |0;dojo.html;2955.926101847454;3495.283466889288;3508.614052454228;3536.451849963255;3544.273423948091;4934.531219534148;6118.755249790009;6174.259088909326;6186.56694031418;6187.659678717669;4954.603631709464;5126.538611524962;5139.7430128493515;6122.602030868002;6147.907781383272;4608.493065762764;5500.862478438031;5517.2551705519745;5573.247611572447;5641.956238095965;5665.943340566603;5675.233843431146;5681;5693.914591281134;5727.341355744101;5410.91883621746;5437.972810135944;5531.778728850841;5664.801731939383;5671.6597004179675;5115.795368185268;5517.841253336764;5587.944120558824;5679.432056794329;5692.260006199168;4642.419697537808;5465.9180112298345;5540.667559946376;5562.3047119110115;5591.217229587861;5356.732163391854;5834;5932.703364831785;6155.538334624921;6244.843878903019;5478.753456094445;6332.145160403333;6336.556441049082;6379.936200637981;6395.232572091332;6632.071509988606;6658.766943156967;6662.267150613428;6666.100076489661;6673.39939405451;6194.690265486708;6224.439800417894;6273.6235825850135;6276.843078923018;6293.213348331458;5392.299001129828;5557.194206839986;5854.356020837705;6505.186851643544;6622.212002759228;6412.551121421549;6427.646479443582;6438.8390290243115;6447.097406363019;6470.22357317125;6512.837179070561;6553.984132459476;6566.244881838554;6589.011648252705;6618.90071648916;6273.96863015682;6308.0537919311555;6386.840328991812;6425.293217746044;6492.993585994179;5441.83674489766;6247.500199983991;6409;6490.772822951269;6541.443977261986;5172.482751724798;5238;5255.947440525622;5259.474052594786;5268.473152684701;4649.767511624473;4653.325267836145;4682.1103990241745;4716.551927566876;4747.098051370229;4506.481754598198;5924.14099955504;6037.09443583466;6236.126942228092;6244.2819075805955;5481.725913704299;6454.128692626529;6458.354164583504;6519.022146677941;6570.934290657128;6168.938310616836;6176.073588961692;6196.81409557714;6221.3778622138325;6228.968855155696;5179.300794392784;5811.622244554175;5825.446582574648;5829.387914269026;5842.561807864342;6157.876842463215;6649.235337936006;6688.3646053624825;6811.386975172259;6897.689603967733;6537.70580323877;6622.90065649006;6626.5030122741955;6628.5691430056895;6639.435647969976;5630.831075067755;6676.332366763285;6812.284710105368;6825.522213445011;6844.452443804584;4747.430308363018;6129.48104857405;6373.044043393528;6380.9042864358;6395.1046853441485;6577;6606.768763093269;6616.206055273398;6619.768308109193;6638.601683898982;6293.874122517433;6350;6357.427831495188;6363.3636636336;6434.903476447951;6235.968820156052;6296.150019747459;6310.7791227306825;6314.715837787218;6387.233531976192;5419;6235.657038862906;6303.275123360872;6306.117143599808;6320.683965801783;6248.218972628421;6339.366063393623;6362.6818659067785;6382.074599183;6408.263049749338;6220.409061139185;6222.315545289924;6235.844103897439;6278.780242691484;6283.183186185766 09:48:40 INFO - PROCESS | 1642 | |1;ext.html;742.7039815521915;794.8291117409758;820.573301883021;958.4009993753908;965.9661911833088;35599.64400355999;36391.63608363919;37589.62410375896;37676;37744.43383349248;36934.44598331021;37028.44457333142;37215.441768373494;37258;37291.06772330686;7738.883916741253;7866.977292951937;8947.597358118901;10233.488325583729;10639.414832184228;10457.372557646528;10568.471576421187;10570.78858422831;10572;10601.098906592948;10524.579016839318;10526.578936842518;10529.420881851494;10588.894111058868;10597.894021059768;10490.842637360445;10528.368297902112;10567.682969510926;10576;10590.152787776959;9244.168024877727;9992.700218993441;10325.84511232332;10365.585376585002;10375.584976600927;32431.51352729711;32780.344393112246;32940.67059329424;32955.50566741501;33035.834820825985;3680.595134535199;3746.868859589901;3752.155764952891;3754.4931434256573;3791.50710407648;18454.630907381776;24576.508469830682;24576.754232457624;25545.361365965815;25943.35141621456;3050.2069461940055;3082.2910730532103;3138.1056398926426;3210.9036728897936;3232.949291480278;337948;338765;339798;340097;341511.29244353867;42311.57688423138;42527.787361063;42807.71576852699;42850.57149428528;43023.569764302585;43999.78000109979;44141.77929110334;44182.77908610436;44240.77879610581;44339.55660443419;12643.683907902192;12823.871761282267;12826.679333016562;12834.29411382383;12838.229706217657;9128.634854605742;10875.129989600813;10896.727581810519;10901.400422976812;10911.345319280868;11264.774704506028;11532.481038353295;11543.942280288546;11618.302901825917;11629.53481860733;5655.773769049274;5714.828555143353;5753;5760.567957403212;5772.307323121252;1102.8511150994673;1132.7281452451516;1137.806572882612;1145.8223975283845;1187.192708957917;23564.528709425715;26066.609000865003;26150.86924565365;26374.604380934303;26494.47011059768;1030.1964467715195;1223.1193540650718;1237.9442925068392;1251.161721646499;1254.9309787961568;13351;13447.394867230998;13505.729885402434;13516.93241533786;13520.661983450493;396613;420903;436691;440760;441630;54568.45431545713;54572.72713636486;54611.45388546143;54641.453585463634;54749.178762318596;1371.732512160119;1409.8942579306593;1425.8146440962817;1459.0224549551826;1469.2359972814077;21740.45648858791;25425.491490169723;25645;26356.868215659568;26760.33099172536;37885.242295154494;38126.618733812305;38176;38195.618043820316;38256.23487530289;393920;395344.02327988175;396466;396599;397028;28354;28447.857760711064;28494.145175644764;36885.81557092197;38070.809645951595;45618.77190614026;46955.06089878252;47535.52464475449;47879.803004925154;47882.521174787806;36663.816680917495;37700.43449348317;38398;39016;39066.02334941536;37821;37870.62129378671;38506.074696265634;39125.02187445223;39518;33401.99794006184;35037;37430.812845936685;38296.42555361616;38532;32074;33918.78152349431;34217.65782342145;34234.315313693085;34583.96248112561;25779.871100645127;25843.96624135089;25864.353391165372;25997.350066247738;26327.34181645474 09:48:40 INFO - PROCESS | 1642 | |2;jquery.html;352.2356486424459;410.8110269276133;421.2438672582714;426.996558088492;450.1110307143392;19758.209671613135;20148.992550372495;20355.796442035575;20790.376288711326;21059.894700526496;21164.682529762067;21283.787162128374;21434.24980125696;21964.780352196474;22068.33794986149;5788.536917046647;6536.771213007558;6714.932850671504;6735.797926062226;6758.391744742972;5799.536037117042;6258.906116408258;6478.676066196695;6524.836879078037;6641.435477984377;5830.804685039578;6019.157317975465;6499.545031847772;6522.119513865615;6527.249366322862;6268.65522396268;6614.768483103092;6685.39831415175;6711.127553418049;6712.899306510406;6116.602420842631;6355.713992870332;6420.775272865474;6539.673016349164;6574.145361103074;23778.881105594533;25087.498250034896;25390.492190156278;25967.220983370527;26034.828432720384;2858.299716569433;2928.824270543774;2934.5598160275918;2938.6620538638117;2957.9704202958123;14961.850381496155;15704.450344237897;15745.291461884242;15940.123293218869;15950;1967.5868067705799;1968.2225520919158;1975.2888959974398;2000.3198912369865;2008.9095990680455;154149;157838;163498;163499.9999999988;165249;20757.065932033096;20909.013643703824;21027.68458473124;21113.894430527747;21182.15271389127;31215;31660.208494787814;31740;31757.8412107938;31824.84087579547;9920.107190352759;9985.251106167067;10147.934466881019;10151.289409741396;10237.283390162765;8319.084900660922;8323.8335233295;8345.290650294794;8500;8518.23335899772;7723.845523089507;9592.616295348247;9625.51872406377;9654.806903861883;9682.419054856731;3872.2255548890334;3957.8614748483665;3977.54258260298;3988.4814974053766;3997.4603428537357;962.7929995051097;974.7904200596905;979.8873129590046;985.6353149334649;1021.1677482851452;13367.532136375376;13373.130746501447;13575.253361065235;13715.657108572366;13743.037987340791;988.4958671077802;991.6677912899115;1011.1304278320638;1018.2770233134557;1021.1422405179536;6493.123428337209;6795.286494918061;6946.166460024729;7008.964955175192;7092.148942126978;740421;756303;768116;769460;779067;121644;125514;131908;143872;147141;1051.8948105189572;1070.673444599389;1106.833974903771;1252.5490823303603;1292.877176668234;15412.152331621868;15556.844431555539;17411.651766964336;18825.435236942914;20048.198072077535;51130;65061.02408463782;65397.346026539126;65721.34278657344;68772.656136719;75701.62149189219;86270.7059394097;92796.53601731948;93329.5333523328;93608;11507.194496385173;11731.824022639837;11764.58823941158;12035.458404371999;12083.39583020828;10910.945445272722;13213.009024323022;13254.138480998705;13395.531156409479;13618.727625447635;11488.253263537843;11541.884581154416;11557.826632600685;11640.126990475917;11812.704682382666;11267.605633802777;11278.323300601991;11352.772944540899;11667.824982625438;11885.5840045598;11686.94156529212;11776.823347649963;11860;11929.522819087138;11962.188885612048;11677.182597218112;11702.35637039971;11760;11960.880391195977;12058.939705301418;11466.541338346706;11479.368634724837;11700.94691477771;11800.291982481078;11819.527218911491 09:48:40 INFO - PROCESS | 1642 | |3;mootools.html;1495.312156408053;1552.6351307442756;1592.2755146408385;1601.9439319623812;1614.402671011726;9397.29520285978;9575.856362154556;10695.732606684836;11271.6618501445;11292.491837867296;10405.583776648944;10798.5680572777;10802.5678972841;10820.621278255263;10822.567097316098;2375.4417711837705;2428.0287884846025;2431.416460049584;2433.245693834917;2434.3427274635837;2382.940426489334;2388.0925248405583;2413.75862413759;2416.347586151738;2421.1162925532144;2379.452725873042;2399.7120345558556;2416.4804567018086;2420.995286955911;2422.0917156066475;2360.4570948681726;2375.8455700379504;2377.2036367816804;2381.3213234228247;2381.6784734060852;2335.941601459977;2354.2937118864274;2356.2106694257445;2360.6931098957198;2397.9040838366445;10990.285631433931;11042.613508527164;11073.889261107446;11105.666829995112;11159.944200279027;5761.769529218826;5826.980278451254;5935.347111817697;5946.494547963428;5952.940470595282;3450.413429716954;3616.8914932552066;3642.0530662028063;3661.3775658137915;3716.8699095531797;1207.4023358437541;1211.9697007574794;1216.5377156680493;1247.6070037938111;1260.521002019236;14970.17664028478;15046.849531504764;15249.695006099815;15361.231938403034;15381.384744610203;4425.712328698624;4862.07620552094;4864.124457597595;4979.57673597741;4999.350084488993;10996.450177491093;11383.86161383855;12722.173058751152;12812.42344094518;12836.229826210458;5056.671316364498;5831.300243970751;5920.111983202468;5935.851603709942;5936.881262374728;4387.561243875651;4903.901921961541;5069.543741063322;5196.194589838581;5327.413984461706;3669.94495082574;4086.0977766233177;4214.641755450821;4252.532221455637;4257.659387249013;1149.6665966869514;1166.311875993151;1177.1230433327225;1180.3449085757486;1180.5926955200614;795.8965334506477;798.6645608844183;813.8819871118654;815.1644564321617;820.782492639451;6345.764744086147;6537.477001839841;6687.197536295578;6807.65961701913;6973.302669733088;957.5882370580578;985.1626117799813;992.0674565908023;1035.9067683908484;1075.3386667199773;4131.690123240769;4177.624013838769;4200.87397378073;4205.1589682063095;4319.632831209383;13062.34688265583;13669.248191349572;13690.38393272305;13712.93143534276;13721.588352349445;12688.302143382203;13364.665883352995;13508;13508.392122354519;13521.323933803467;9937.701868943943;10210.336328138646;10223.335483193718;10233.94883025595;10255.743606409751;6396.10454536356;6467.870642587216;6468.8059358219325;6556.475481961527;6614.6361950093205;9866.309358344846;11733.76532469363;12146.696332591757;12237.326947018;12255.264684118982;12204.328761917825;12298.815517767061;12331.890129888354;12343.75312493763;12354.752904941672;7988.84022319562;8098.75703728889;8211.753647390588;9008;9201.585928633362;10395.32430392022;10406.11548018427;10422.114120299859;10523.05292523646;10526.578936842441;9136.862947055932;9205.631774728936;9251.444913305224;9295.349325547148;9333.300002499704;8994.820103598022;9091.227245683926;9130.77173070652;9152.862707059267;9169.587368568296;7558.281963213486;7719.112302085332;7737.1102323233545;7738.92261077382;7844.921550784419;5562.3047119110115;5710.771569137354;5790.449907258803;5963;5998.280206375263;5799;5870.706464676835;5881.94118058831;5904.202932604216;6051.667158306335 09:48:40 INFO - PROCESS | 1642 | |4;prototype.html;330.49599360974497;367.0493422036924;379.3702453926481;379.4213823918524;390.04439124145847;4258.382534532491;4665.9300110498325;5103.030424219401;5118.5905127589795;5159.45825688302;4438.956845141389;5081.3759478874235;5181.8131848912835;5260.947390526094;5327.549327309433;3574.785512869223;4416.911661766779;4421.187313201588;4443.644508439334;4461.241588929874;4399.142167277382;4461.866144015685;4486.416765820439;4500.572445617661;4519.751413672247;4057.8376864925517;4190.344813798548;4488.192125417424;4499.785058034328;4505.256632655608;3522.7846392994375;4078.632923036911;4361.43301370823;4453.910921781546;4491.708038977472;3609.1698909251018;4047.5142982841953;4060.8375664973364;4072.490938632671;4080.9387859182134;4921.901561968776;4975.15915616028;5221.73891305437;5260.710660913648;5266.92099618506;4852.636052296092;4883.755812209375;5083.43286640248;5113.468832927688;5121.539061484485;5679.744411501492;5717.026666466807;5719.6854173020465;5754.424557544254;5758.193852860602;3990;4075.1677584079075;4386.078923426085;4396.538363471854;4565.885852853672;5165.948340516585;5388.811391601275;5732.5987180897355;5738.397468265855;6019.638821670714;3919.2161567686576;4503.842365517191;4564.520725323793;4688.976555117202;4733.219018861849;4619.376384188159;4763.309320148559;4778.085367925562;4784.378030855966;4788.353572267769;4983.9252411213865;5043.36957880265;5119.59043276537;5132.409772876093;5167.870803229949;4567.566081222279;4913.606911447076;5024.974875125674;5106.412762532356;5664.745086471119;1373.9450421983208;1381.2886363522794;1419.2548911821377;1625.7886474758207;1631.8776091793163;1021.8211812932707;1111.1666250312267;1218.4882349413267;1218.5438401110764;1239.0954603139758;4854.975725121351;5348.8662783429945;5374.435684253175;5375.704336261465;5376.542993845489;5065.936153407825;5354.9196762048605;5646.57650676201;5933.58464907461;6027.57806953509;4231.793938727448;4758.691359876062;5236;5325.6272060956135;5364.554976698654;2772.3900741837165;2807.1578526441986;3040.650325212629;3063.816171029701;3079.5842561254394;5707;6021.367756385516;6043.758249670052;6064.120702498113;6209.565330426917;5181.974090129525;5897.64614123154;5920.109491056426;5939.346671866091;6031.849203769941;5280.60395470349;5575.686090272491;5618.69097199658;6192.721327540272;6232.719527621177;5190.948090519047;5826.135177127147;5853.326867410302;5939.940600594112;5981.3420523743225;5391.919121213264;5522.696251706195;5627.971860140837;5733.713314334183;5880.647161170344;4942.382202224722;5281.8131895560955;5821.650700957956;5921.940780592139;5933.270695480128;4043.8584649537124;4149.481314835645;4205.719538759772;4334.068175342317;4606.424196975378;5242.921356179737;5252.737363131751;5393.487618676219;5400.4059553448305;5499.862503437446;5022.296878436949;5308.734563271898;5615.591760468013;5708.286464191976;5740;4697.953020469751;4848.424757702644;5701.8859622806485;5739.569532285179;5773.971130144322;5219.8255392536375;5367.35591728995;5462.432656651367;5480.479354461319;5542.61201715876;4965.503449655005;5243.5018673227505;5693.160813308655;5727.627704199213;5734.197212390185;4625.196173492273;5108.182690769459;5156.355455568054;5184.637075404837;5601.691906945157 09:48:40 INFO - PROCESS | 1642 | |5;yui.html;714.5926821711627;723.4284914917216;766.9961650191748;827.1025936858508;835.9498430094193;14805.925970370146;15234.162121083336;15768.975016623912;15772.211389430511;15777.684446311066;15256.923715381436;15391.69216615667;15468.381264749425;15477.535673929771;15543.689126217469;903.9638414463413;920.1902325953151;942.5664194470518;948.4214629076258;949.5252373813094;939.6288466055904;942.5475771629588;947.7725345916965;950.2208189284789;955.99142904236;981.4847205217246;995.7958618483229;999.215615741643;999.860019597257;1000.3847633705292;974.2790335152007;992.072412294507;993.9652112176075;996.1035068438428;996.7607774134228;740.0157790139102;740.3854800515559;740.5815714121504;740.7111226621622;742.858856817203;16068.59828504285;16239.756403653955;16455.177241138015;16565.751513727304;16589.50231493057;13849;14044.719105617933;14131.858681413158;14161.504347347896;14164.29178541079;11613.477393517313;11797.469113889896;11805.291682499077;11823.645290641294;11847.229930054518;3569.01851990702;3584.731145164123;3622.492851000862;3732.962670373289;3807.562130355017;91089;100575;108320.20879269188;110187;112134.43932780284;10289.897101028893;10673.57305707763;10771.730706732395;10779.838302425469;10824.94587527073;18277.817221827878;22259.554808903733;22439;22467.43831404228;22511.32466026022;7635.427342949301;7647.311741943252;7659.080910290801;7723.53658780475;7767.766966990999;4276.5082015568605;4302.7848607569495;4344.956550434518;4408.162449134654;4475.664325175625;3379.915502112467;3450.516927630134;3457.135716070982;3477.8434970425888;3483.9129021774656;525.2357819372803;619.8295468746131;641.7850020243177;642.8071578526424;643.3020173112226;692.2005084127841;713.8001359619315;715.1811176203322;721.3363705391052;725.1407082607127;9526.142647161789;10459.947700261602;10492.84893625436;10536.525856336484;10640.25518213733;565.1833101168771;703.2615753458848;703.391566295151;712.5261700968902;714.2892821642499;4708.788104535304;4994.2534740361425;5021.5229553192385;5035.7410647338165;5036.546710796046;105930;107826;109097;109878;110409.44795275973;82697.75953360704;89663.55168224247;93552.53223733838;96565;97648;74107;74513;81438.59280703677;82431;84041;9692.563834627317;9707;9794.314397992071;9875.308728388944;9878.901210987799;46750.766246168554;51187.488125118274;51795;53132;54137;68803.31196687969;69062.30937690558;70146.64926675538;70378.29621703923;70383.64808175927;11025.283356581796;11169.273997190157;11243.613279403633;11557.190996630492;11948.34284114382;11780;11853;12438.564650237198;12484.500619975099;12991;11612.6516204514;11632.941835290769;11681.299122052704;11907.047436205256;11972.401379930794;11688.006519445942;11827.231229970024;11974.341411222467;12109.69725756828;12269.325187114795;11287.98408143271;11616.593419230625;11617.535298587962;11829.526818927146;12058.819117713418;10674.786504270027;10875.238733288594;11177.776444471228;11240.83138752903;11299.09607231403;9726.629057967042;11508.36703981289;11543.191976561897;11653.067754579444;11889.108316876442 09:48:40 INFO - PROCESS | 1642 | __end_tp_report 09:48:40 INFO - PROCESS | 1642 | __start_cc_report 09:48:40 INFO - PROCESS | 1642 | _x_x_mozilla_cycle_collect,413 09:48:40 INFO - PROCESS | 1642 | __end_cc_report 09:48:40 INFO - PROCESS | 1642 | __startTimestamp1489168120138__endTimestamp 09:48:40 INFO - PROCESS | 1642 | 09:48:40 INFO - PROCESS | 1642 | ------- Summary: start ------- 09:48:40 INFO - PROCESS | 1642 | Number of tests: 6 09:48:40 INFO - PROCESS | 1642 | 09:48:40 INFO - PROCESS | 1642 | [#0] dojo.html Cycles:165 Average:5958.30 Median:6235.66 stddev:719.84 (11.5%) stddev-sans-first:682.43 09:48:40 INFO - PROCESS | 1642 | Values: 2955.9 3495.3 3508.6 3536.5 3544.3 4934.5 6118.8 6174.3 6186.6 6187.7 4954.6 5126.5 5139.7 6122.6 6147.9 4608.5 5500.9 5517.3 5573.2 5642.0 5665.9 5675.2 5681.0 5693.9 5727.3 5410.9 5438.0 5531.8 5664.8 5671.7 5115.8 5517.8 5587.9 5679.4 5692.3 4642.4 5465.9 5540.7 5562.3 5591.2 5356.7 5834.0 5932.7 6155.5 6244.8 5478.8 6332.1 6336.6 6379.9 6395.2 6632.1 6658.8 6662.3 6666.1 6673.4 6194.7 6224.4 6273.6 6276.8 6293.2 5392.3 5557.2 5854.4 6505.2 6622.2 6412.6 6427.6 6438.8 6447.1 6470.2 6512.8 6554.0 6566.2 6589.0 6618.9 6274.0 6308.1 6386.8 6425.3 6493.0 5441.8 6247.5 6409.0 6490.8 6541.4 5172.5 5238.0 5255.9 5259.5 5268.5 4649.8 4653.3 4682.1 4716.6 4747.1 4506.5 5924.1 6037.1 6236.1 6244.3 5481.7 6454.1 6458.4 6519.0 6570.9 6168.9 6176.1 6196.8 6221.4 6229.0 5179.3 5811.6 5825.4 5829.4 5842.6 6157.9 6649.2 6688.4 6811.4 6897.7 6537.7 6622.9 6626.5 6628.6 6639.4 5630.8 6676.3 6812.3 6825.5 6844.5 4747.4 6129.5 6373.0 6380.9 6395.1 6577.0 6606.8 6616.2 6619.8 6638.6 6293.9 6350.0 6357.4 6363.4 6434.9 6236.0 6296.2 6310.8 6314.7 6387.2 5419.0 6235.7 6303.3 6306.1 6320.7 6248.2 6339.4 6362.7 6382.1 6408.3 6220.4 6222.3 6235.8 6278.8 6283.2 09:48:40 INFO - PROCESS | 1642 | 09:48:40 INFO - PROCESS | 1642 | [#1] ext.html Cycles:180 Average:52577.94 Median:186341.89 stddev:103013.02 (55.3%) stddev-sans-first:103228.48 09:48:40 INFO - PROCESS | 1642 | Values: 742.7 794.8 820.6 958.4 966.0 35599.6 36391.6 37589.6 37676.0 37744.4 36934.4 37028.4 37215.4 37258.0 37291.1 7738.9 7867.0 8947.6 10233.5 10639.4 10457.4 10568.5 10570.8 10572.0 10601.1 10524.6 10526.6 10529.4 10588.9 10597.9 10490.8 10528.4 10567.7 10576.0 10590.2 9244.2 9992.7 10325.8 10365.6 10375.6 32431.5 32780.3 32940.7 32955.5 33035.8 3680.6 3746.9 3752.2 3754.5 3791.5 18454.6 24576.5 24576.8 25545.4 25943.4 3050.2 3082.3 3138.1 3210.9 3232.9 337948.0 338765.0 339798.0 340097.0 341511.3 42311.6 42527.8 42807.7 42850.6 43023.6 43999.8 44141.8 44182.8 44240.8 44339.6 12643.7 12823.9 12826.7 12834.3 12838.2 9128.6 10875.1 10896.7 10901.4 10911.3 11264.8 11532.5 11543.9 11618.3 11629.5 5655.8 5714.8 5753.0 5760.6 5772.3 1102.9 1132.7 1137.8 1145.8 1187.2 23564.5 26066.6 26150.9 26374.6 26494.5 1030.2 1223.1 1237.9 1251.2 1254.9 13351.0 13447.4 13505.7 13516.9 13520.7 396613.0 420903.0 436691.0 440760.0 441630.0 54568.5 54572.7 54611.5 54641.5 54749.2 1371.7 1409.9 1425.8 1459.0 1469.2 21740.5 25425.5 25645.0 26356.9 26760.3 37885.2 38126.6 38176.0 38195.6 38256.2 393920.0 395344.0 396466.0 396599.0 397028.0 28354.0 28447.9 28494.1 36885.8 38070.8 45618.8 46955.1 47535.5 47879.8 47882.5 36663.8 37700.4 38398.0 39016.0 39066.0 37821.0 37870.6 38506.1 39125.0 39518.0 33402.0 35037.0 37430.8 38296.4 38532.0 32074.0 33918.8 34217.7 34234.3 34584.0 25779.9 25844.0 25864.4 25997.4 26327.3 09:48:40 INFO - PROCESS | 1642 | 09:48:40 INFO - PROCESS | 1642 | [#2] jquery.html Cycles:180 Average:42720.82 Median:22016.56 stddev:126977.57 (576.7%) stddev-sans-first:127293.92 09:48:40 INFO - PROCESS | 1642 | Values: 352.2 410.8 421.2 427.0 450.1 19758.2 20149.0 20355.8 20790.4 21059.9 21164.7 21283.8 21434.2 21964.8 22068.3 5788.5 6536.8 6714.9 6735.8 6758.4 5799.5 6258.9 6478.7 6524.8 6641.4 5830.8 6019.2 6499.5 6522.1 6527.2 6268.7 6614.8 6685.4 6711.1 6712.9 6116.6 6355.7 6420.8 6539.7 6574.1 23778.9 25087.5 25390.5 25967.2 26034.8 2858.3 2928.8 2934.6 2938.7 2958.0 14961.9 15704.5 15745.3 15940.1 15950.0 1967.6 1968.2 1975.3 2000.3 2008.9 154149.0 157838.0 163498.0 163500.0 165249.0 20757.1 20909.0 21027.7 21113.9 21182.2 31215.0 31660.2 31740.0 31757.8 31824.8 9920.1 9985.3 10147.9 10151.3 10237.3 8319.1 8323.8 8345.3 8500.0 8518.2 7723.8 9592.6 9625.5 9654.8 9682.4 3872.2 3957.9 3977.5 3988.5 3997.5 962.8 974.8 979.9 985.6 1021.2 13367.5 13373.1 13575.3 13715.7 13743.0 988.5 991.7 1011.1 1018.3 1021.1 6493.1 6795.3 6946.2 7009.0 7092.1 740421.0 756303.0 768116.0 769460.0 779067.0 121644.0 125514.0 131908.0 143872.0 147141.0 1051.9 1070.7 1106.8 1252.5 1292.9 15412.2 15556.8 17411.7 18825.4 20048.2 51130.0 65061.0 65397.3 65721.3 68772.7 75701.6 86270.7 92796.5 93329.5 93608.0 11507.2 11731.8 11764.6 12035.5 12083.4 10910.9 13213.0 13254.1 13395.5 13618.7 11488.3 11541.9 11557.8 11640.1 11812.7 11267.6 11278.3 11352.8 11667.8 11885.6 11686.9 11776.8 11860.0 11929.5 11962.2 11677.2 11702.4 11760.0 11960.9 12058.9 11466.5 11479.4 11700.9 11800.3 11819.5 09:48:40 INFO - PROCESS | 1642 | 09:48:40 INFO - PROCESS | 1642 | [#3] mootools.html Cycles:180 Average:6711.89 Median:2432.33 stddev:4170.83 (171.5%) stddev-sans-first:4164.11 09:48:40 INFO - PROCESS | 1642 | Values: 1495.3 1552.6 1592.3 1601.9 1614.4 9397.3 9575.9 10695.7 11271.7 11292.5 10405.6 10798.6 10802.6 10820.6 10822.6 2375.4 2428.0 2431.4 2433.2 2434.3 2382.9 2388.1 2413.8 2416.3 2421.1 2379.5 2399.7 2416.5 2421.0 2422.1 2360.5 2375.8 2377.2 2381.3 2381.7 2335.9 2354.3 2356.2 2360.7 2397.9 10990.3 11042.6 11073.9 11105.7 11159.9 5761.8 5827.0 5935.3 5946.5 5952.9 3450.4 3616.9 3642.1 3661.4 3716.9 1207.4 1212.0 1216.5 1247.6 1260.5 14970.2 15046.8 15249.7 15361.2 15381.4 4425.7 4862.1 4864.1 4979.6 4999.4 10996.5 11383.9 12722.2 12812.4 12836.2 5056.7 5831.3 5920.1 5935.9 5936.9 4387.6 4903.9 5069.5 5196.2 5327.4 3669.9 4086.1 4214.6 4252.5 4257.7 1149.7 1166.3 1177.1 1180.3 1180.6 795.9 798.7 813.9 815.2 820.8 6345.8 6537.5 6687.2 6807.7 6973.3 957.6 985.2 992.1 1035.9 1075.3 4131.7 4177.6 4200.9 4205.2 4319.6 13062.3 13669.2 13690.4 13712.9 13721.6 12688.3 13364.7 13508.0 13508.4 13521.3 9937.7 10210.3 10223.3 10233.9 10255.7 6396.1 6467.9 6468.8 6556.5 6614.6 9866.3 11733.8 12146.7 12237.3 12255.3 12204.3 12298.8 12331.9 12343.8 12354.8 7988.8 8098.8 8211.8 9008.0 9201.6 10395.3 10406.1 10422.1 10523.1 10526.6 9136.9 9205.6 9251.4 9295.3 9333.3 8994.8 9091.2 9130.8 9152.9 9169.6 7558.3 7719.1 7737.1 7738.9 7844.9 5562.3 5710.8 5790.4 5963.0 5998.3 5799.0 5870.7 5881.9 5904.2 6051.7 09:48:40 INFO - PROCESS | 1642 | 09:48:40 INFO - PROCESS | 1642 | [#4] prototype.html Cycles:180 Average:4698.62 Median:5107.30 stddev:1316.69 (25.8%) stddev-sans-first:1278.91 09:48:40 INFO - PROCESS | 1642 | Values: 330.5 367.0 379.4 379.4 390.0 4258.4 4665.9 5103.0 5118.6 5159.5 4439.0 5081.4 5181.8 5260.9 5327.5 3574.8 4416.9 4421.2 4443.6 4461.2 4399.1 4461.9 4486.4 4500.6 4519.8 4057.8 4190.3 4488.2 4499.8 4505.3 3522.8 4078.6 4361.4 4453.9 4491.7 3609.2 4047.5 4060.8 4072.5 4080.9 4921.9 4975.2 5221.7 5260.7 5266.9 4852.6 4883.8 5083.4 5113.5 5121.5 5679.7 5717.0 5719.7 5754.4 5758.2 3990.0 4075.2 4386.1 4396.5 4565.9 5165.9 5388.8 5732.6 5738.4 6019.6 3919.2 4503.8 4564.5 4689.0 4733.2 4619.4 4763.3 4778.1 4784.4 4788.4 4983.9 5043.4 5119.6 5132.4 5167.9 4567.6 4913.6 5025.0 5106.4 5664.7 1373.9 1381.3 1419.3 1625.8 1631.9 1021.8 1111.2 1218.5 1218.5 1239.1 4855.0 5348.9 5374.4 5375.7 5376.5 5065.9 5354.9 5646.6 5933.6 6027.6 4231.8 4758.7 5236.0 5325.6 5364.6 2772.4 2807.2 3040.7 3063.8 3079.6 5707.0 6021.4 6043.8 6064.1 6209.6 5182.0 5897.6 5920.1 5939.3 6031.8 5280.6 5575.7 5618.7 6192.7 6232.7 5190.9 5826.1 5853.3 5939.9 5981.3 5391.9 5522.7 5628.0 5733.7 5880.6 4942.4 5281.8 5821.7 5921.9 5933.3 4043.9 4149.5 4205.7 4334.1 4606.4 5242.9 5252.7 5393.5 5400.4 5499.9 5022.3 5308.7 5615.6 5708.3 5740.0 4698.0 4848.4 5701.9 5739.6 5774.0 5219.8 5367.4 5462.4 5480.5 5542.6 4965.5 5243.5 5693.2 5727.6 5734.2 4625.2 5108.2 5156.4 5184.6 5601.7 09:48:40 INFO - PROCESS | 1642 | 09:48:40 INFO - PROCESS | 1642 | [#5] yui.html Cycles:180 Average:20667.47 Median:3677.73 stddev:30208.46 (821.4%) stddev-sans-first:30256.05 09:48:40 INFO - PROCESS | 1642 | Values: 714.6 723.4 767.0 827.1 835.9 14805.9 15234.2 15769.0 15772.2 15777.7 15256.9 15391.7 15468.4 15477.5 15543.7 904.0 920.2 942.6 948.4 949.5 939.6 942.5 947.8 950.2 956.0 981.5 995.8 999.2 999.9 1000.4 974.3 992.1 994.0 996.1 996.8 740.0 740.4 740.6 740.7 742.9 16068.6 16239.8 16455.2 16565.8 16589.5 13849.0 14044.7 14131.9 14161.5 14164.3 11613.5 11797.5 11805.3 11823.6 11847.2 3569.0 3584.7 3622.5 3733.0 3807.6 91089.0 100575.0 108320.2 110187.0 112134.4 10289.9 10673.6 10771.7 10779.8 10824.9 18277.8 22259.6 22439.0 22467.4 22511.3 7635.4 7647.3 7659.1 7723.5 7767.8 4276.5 4302.8 4345.0 4408.2 4475.7 3379.9 3450.5 3457.1 3477.8 3483.9 525.2 619.8 641.8 642.8 643.3 692.2 713.8 715.2 721.3 725.1 9526.1 10459.9 10492.8 10536.5 10640.3 565.2 703.3 703.4 712.5 714.3 4708.8 4994.3 5021.5 5035.7 5036.5 105930.0 107826.0 109097.0 109878.0 110409.4 82697.8 89663.6 93552.5 96565.0 97648.0 74107.0 74513.0 81438.6 82431.0 84041.0 9692.6 9707.0 9794.3 9875.3 9878.9 46750.8 51187.5 51795.0 53132.0 54137.0 68803.3 69062.3 70146.6 70378.3 70383.6 11025.3 11169.3 11243.6 11557.2 11948.3 11780.0 11853.0 12438.6 12484.5 12991.0 11612.7 11632.9 11681.3 11907.0 11972.4 11688.0 11827.2 11974.3 12109.7 12269.3 11288.0 11616.6 11617.5 11829.5 12058.8 10674.8 10875.2 11177.8 11240.8 11299.1 9726.6 11508.4 11543.2 11653.1 11889.1 09:48:40 INFO - PROCESS | 1642 | -------- Summary: end -------- 09:48:40 INFO - PROCESS | 1642 | 09:48:40 INFO - TEST-INFO | 1642: exit 0 09:48:40 INFO - TEST-OK | dromaeo_css | took 1116442ms 09:48:40 INFO - TEST-START | kraken 09:48:40 INFO - operating with platform_type : mac_ 09:48:40 INFO - Initialising browser for kraken test... 09:48:40 INFO - TEST-INFO | started process 1654 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/tmpXmYLcQ/profile http://localhost:49237/getInfo.html) 09:48:43 INFO - PROCESS | 1654 | 2017-03-10 09:48:43.643 plugin-container[1655:10172] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4747, name = 'com.apple.tsm.portname' 09:48:43 INFO - PROCESS | 1654 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:48:43 INFO - PROCESS | 1654 | 2017-03-10 09:48:43.646 plugin-container[1655:10172] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x910b, name = 'com.apple.CFPasteboardClient' 09:48:43 INFO - PROCESS | 1654 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:48:43 INFO - PROCESS | 1654 | 2017-03-10 09:48:43.646 plugin-container[1655:10172] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 09:48:43 INFO - PROCESS | 1654 | __metrics Screen width/height:1600/1200 09:48:43 INFO - PROCESS | 1654 | colorDepth:24 09:48:43 INFO - PROCESS | 1654 | Browser inner width/height: 1024/689 09:48:43 INFO - PROCESS | 1654 | __metrics 09:48:43 ERROR - PROCESS | 1654 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3353: TypeError: this.tabs is undefined 09:48:44 INFO - TEST-INFO | 1654: exit 0 09:48:44 INFO - Browser initialized. 09:48:44 INFO - Running cycle 1/1 for kraken test... 09:48:44 INFO - TEST-INFO | started process 1656 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/tmpXmYLcQ/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:48:47 INFO - PROCESS | 1656 | 2017-03-10 09:48:47.253 plugin-container[1657:10300] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4647, name = 'com.apple.tsm.portname' 09:48:47 INFO - PROCESS | 1656 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:48:47 INFO - PROCESS | 1656 | 2017-03-10 09:48:47.254 plugin-container[1657:10300] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8f0b, name = 'com.apple.CFPasteboardClient' 09:48:47 INFO - PROCESS | 1656 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:48:47 INFO - PROCESS | 1656 | 2017-03-10 09:48:47.254 plugin-container[1657:10300] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 09:48:56 INFO - PROCESS | 1656 | RSS: Main: 220295168 09:48:56 INFO - PROCESS | 1656 | 09:49:00 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49237/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49237/tests/kraken/driver.html?testName=audio-beat-detection) 09:49:01 INFO - PROCESS | 1656 | RSS: Main: 234749952 09:49:01 INFO - PROCESS | 1656 | 09:49:03 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49237/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49237/tests/kraken/driver.html?testName=audio-dft) 09:49:04 INFO - PROCESS | 1656 | RSS: Main: 242933760 09:49:04 INFO - PROCESS | 1656 | 09:49:08 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49237/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49237/tests/kraken/driver.html?testName=audio-fft) 09:49:08 INFO - PROCESS | 1656 | RSS: Main: 244551680 09:49:08 INFO - PROCESS | 1656 | 09:49:10 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49237/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49237/tests/kraken/driver.html?testName=audio-oscillator) 09:49:11 INFO - PROCESS | 1656 | RSS: Main: 246317056 09:49:11 INFO - PROCESS | 1656 | 09:49:13 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49237/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49237/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:49:14 INFO - PROCESS | 1656 | RSS: Main: 247963648 09:49:14 INFO - PROCESS | 1656 | 09:49:17 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49237/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49237/tests/kraken/driver.html?testName=imaging-darkroom) 09:49:17 INFO - PROCESS | 1656 | RSS: Main: 252403712 09:49:17 INFO - PROCESS | 1656 | 09:49:21 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49237/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49237/tests/kraken/driver.html?testName=imaging-desaturate) 09:49:21 INFO - PROCESS | 1656 | RSS: Main: 249319424 09:49:21 INFO - PROCESS | 1656 | 09:49:25 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49237/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49237/tests/kraken/driver.html?testName=json-parse-financial) 09:49:25 INFO - PROCESS | 1656 | RSS: Main: 250437632 09:49:25 INFO - PROCESS | 1656 | 09:49:27 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49237/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49237/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:49:27 INFO - PROCESS | 1656 | RSS: Main: 250630144 09:49:27 INFO - PROCESS | 1656 | 09:49:29 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49237/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49237/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:49:30 INFO - PROCESS | 1656 | RSS: Main: 250638336 09:49:30 INFO - PROCESS | 1656 | 09:49:32 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49237/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49237/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:49:33 INFO - PROCESS | 1656 | RSS: Main: 250941440 09:49:33 INFO - PROCESS | 1656 | 09:49:36 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49237/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49237/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:49:36 INFO - PROCESS | 1656 | RSS: Main: 251260928 09:49:36 INFO - PROCESS | 1656 | 09:49:40 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49237/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49237/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:49:40 INFO - PROCESS | 1656 | RSS: Main: 250912768 09:49:40 INFO - PROCESS | 1656 | 09:49:42 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49237/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49237/tests/kraken/driver.html?testName=ai-astar) 09:49:42 INFO - PROCESS | 1656 | RSS: Main: 254271488 09:49:42 INFO - PROCESS | 1656 | 09:49:42 INFO - PROCESS | 1656 | __start_tp_report 09:49:42 INFO - PROCESS | 1656 | _x_x_mozilla_page_load 09:49:42 INFO - PROCESS | 1656 | _x_x_mozilla_page_load_details 09:49:42 INFO - PROCESS | 1656 | |i|pagename|runs| 09:49:42 INFO - PROCESS | 1656 | |0;ai-astar;151;180;158;123;123;121;121;120;120;120 09:49:42 INFO - PROCESS | 1656 | |1;audio-beat-detection;145;118;101;182;101;145;103;144;123;145 09:49:42 INFO - PROCESS | 1656 | |2;audio-dft;205;285;210;289;279;198;196;200;203;263 09:49:42 INFO - PROCESS | 1656 | |3;audio-fft;80;58;84;112;83;65;62;61;75;61 09:49:42 INFO - PROCESS | 1656 | |4;audio-oscillator;88;70;84;73;72;72;84;71;70;86 09:49:42 INFO - PROCESS | 1656 | |5;imaging-gaussian-blur;89;88;107;90;88;88;89;89;88;91 09:49:42 INFO - PROCESS | 1656 | |6;imaging-darkroom;99;126;110;153;97;98;98;113;152;133 09:49:42 INFO - PROCESS | 1656 | |7;imaging-desaturate;89;90;94;104;147;132;90;89;91;91 09:49:42 INFO - PROCESS | 1656 | |8;json-parse-financial;67;66;68;68;70;68;70;70;69;70 09:49:42 INFO - PROCESS | 1656 | |9;json-stringify-tinderbox;59;64;59;70;64;65;65;73;60;60 09:49:42 INFO - PROCESS | 1656 | |10;stanford-crypto-aes;84;84;83;91;103;93;83;87;82;87 09:49:42 INFO - PROCESS | 1656 | |11;stanford-crypto-ccm;139;139;137;139;136;142;146;142;185;143 09:49:42 INFO - PROCESS | 1656 | |12;stanford-crypto-pbkdf2;218;215;166;168;167;194;218;196;172;220 09:49:42 INFO - PROCESS | 1656 | |13;stanford-crypto-sha256-iterative;64;67;64;68;80;82;79;87;82;68 09:49:42 INFO - PROCESS | 1656 | __end_tp_report 09:49:42 INFO - PROCESS | 1656 | __start_cc_report 09:49:42 INFO - PROCESS | 1656 | _x_x_mozilla_cycle_collect,1005 09:49:42 INFO - PROCESS | 1656 | __end_cc_report 09:49:42 INFO - PROCESS | 1656 | __startTimestamp1489168182719__endTimestamp 09:49:42 INFO - PROCESS | 1656 | 09:49:42 INFO - PROCESS | 1656 | ------- Summary: start ------- 09:49:42 INFO - PROCESS | 1656 | Number of tests: 14 09:49:42 INFO - PROCESS | 1656 | 09:49:42 INFO - PROCESS | 1656 | [#0] ai-astar Cycles:10 Average:133.70 Median:123.00 stddev:21.47 (17.5%) stddev-sans-first:21.84 09:49:42 INFO - PROCESS | 1656 | Values: 151.0 180.0 158.0 123.0 123.0 121.0 121.0 120.0 120.0 120.0 09:49:42 INFO - PROCESS | 1656 | 09:49:42 INFO - PROCESS | 1656 | [#1] audio-beat-detection Cycles:10 Average:130.70 Median:144.50 stddev:26.19 (18.1%) stddev-sans-first:27.26 09:49:42 INFO - PROCESS | 1656 | Values: 145.0 118.0 101.0 182.0 101.0 145.0 103.0 144.0 123.0 145.0 09:49:42 INFO - PROCESS | 1656 | 09:49:42 INFO - PROCESS | 1656 | [#2] audio-dft Cycles:10 Average:232.80 Median:236.50 stddev:40.49 (17.1%) stddev-sans-first:41.67 09:49:42 INFO - PROCESS | 1656 | Values: 205.0 285.0 210.0 289.0 279.0 198.0 196.0 200.0 203.0 263.0 09:49:42 INFO - PROCESS | 1656 | 09:49:42 INFO - PROCESS | 1656 | [#3] audio-fft Cycles:10 Average:74.10 Median:70.00 stddev:16.60 (23.7%) stddev-sans-first:17.47 09:49:42 INFO - PROCESS | 1656 | Values: 80.0 58.0 84.0 112.0 83.0 65.0 62.0 61.0 75.0 61.0 09:49:42 INFO - PROCESS | 1656 | 09:49:42 INFO - PROCESS | 1656 | [#4] audio-oscillator Cycles:10 Average:77.00 Median:78.50 stddev:7.45 (9.5%) stddev-sans-first:6.76 09:49:42 INFO - PROCESS | 1656 | Values: 88.0 70.0 84.0 73.0 72.0 72.0 84.0 71.0 70.0 86.0 09:49:42 INFO - PROCESS | 1656 | 09:49:42 INFO - PROCESS | 1656 | [#5] imaging-gaussian-blur Cycles:10 Average:90.70 Median:89.00 stddev:5.81 (6.5%) stddev-sans-first:6.13 09:49:42 INFO - PROCESS | 1656 | Values: 89.0 88.0 107.0 90.0 88.0 88.0 89.0 89.0 88.0 91.0 09:49:42 INFO - PROCESS | 1656 | 09:49:42 INFO - PROCESS | 1656 | [#6] imaging-darkroom Cycles:10 Average:117.90 Median:125.00 stddev:22.01 (17.6%) stddev-sans-first:22.26 09:49:42 INFO - PROCESS | 1656 | Values: 99.0 126.0 110.0 153.0 97.0 98.0 98.0 113.0 152.0 133.0 09:49:42 INFO - PROCESS | 1656 | 09:49:42 INFO - PROCESS | 1656 | [#7] imaging-desaturate Cycles:10 Average:101.70 Median:90.00 stddev:20.71 (23.0%) stddev-sans-first:21.45 09:49:42 INFO - PROCESS | 1656 | Values: 89.0 90.0 94.0 104.0 147.0 132.0 90.0 89.0 91.0 91.0 09:49:42 INFO - PROCESS | 1656 | 09:49:42 INFO - PROCESS | 1656 | [#8] json-parse-financial Cycles:10 Average:68.60 Median:69.50 stddev:1.43 (2.1%) stddev-sans-first:1.39 09:49:42 INFO - PROCESS | 1656 | Values: 67.0 66.0 68.0 68.0 70.0 68.0 70.0 70.0 69.0 70.0 09:49:42 INFO - PROCESS | 1656 | 09:49:42 INFO - PROCESS | 1656 | [#9] json-stringify-tinderbox Cycles:10 Average:63.90 Median:64.50 stddev:4.72 (7.3%) stddev-sans-first:4.67 09:49:42 INFO - PROCESS | 1656 | Values: 59.0 64.0 59.0 70.0 64.0 65.0 65.0 73.0 60.0 60.0 09:49:42 INFO - PROCESS | 1656 | 09:49:42 INFO - PROCESS | 1656 | [#10] stanford-crypto-aes Cycles:10 Average:87.70 Median:85.50 stddev:6.48 (7.6%) stddev-sans-first:6.74 09:49:42 INFO - PROCESS | 1656 | Values: 84.0 84.0 83.0 91.0 103.0 93.0 83.0 87.0 82.0 87.0 09:49:42 INFO - PROCESS | 1656 | 09:49:42 INFO - PROCESS | 1656 | [#11] stanford-crypto-ccm Cycles:10 Average:144.80 Median:142.00 stddev:14.44 (10.2%) stddev-sans-first:15.16 09:49:42 INFO - PROCESS | 1656 | Values: 139.0 139.0 137.0 139.0 136.0 142.0 146.0 142.0 185.0 143.0 09:49:42 INFO - PROCESS | 1656 | 09:49:42 INFO - PROCESS | 1656 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:193.40 Median:205.50 stddev:23.43 (11.4%) stddev-sans-first:23.10 09:49:42 INFO - PROCESS | 1656 | Values: 218.0 215.0 166.0 168.0 167.0 194.0 218.0 196.0 172.0 220.0 09:49:42 INFO - PROCESS | 1656 | 09:49:42 INFO - PROCESS | 1656 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:74.10 Median:79.50 stddev:8.69 (10.9%) stddev-sans-first:8.41 09:49:42 INFO - PROCESS | 1656 | Values: 64.0 67.0 64.0 68.0 80.0 82.0 79.0 87.0 82.0 68.0 09:49:42 INFO - PROCESS | 1656 | -------- Summary: end -------- 09:49:42 INFO - PROCESS | 1656 | 09:49:43 INFO - TEST-INFO | 1656: exit 0 09:49:43 INFO - TEST-OK | kraken | took 62833ms 09:49:43 INFO - SUITE-END | took 1179s 09:49:43 INFO - Completed test suite (00:19:39) 09:49:43 INFO - javascript benchmark 09:49:43 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7660.430922551734, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2955.926101847454, 3495.283466889288, 3508.614052454228, 3536.451849963255, 3544.273423948091, 4934.531219534148, 6118.755249790009, 6174.259088909326, 6186.56694031418, 6187.659678717669, 4954.603631709464, 5126.538611524962, 5139.7430128493515, 6122.602030868002, 6147.907781383272, 4608.493065762764, 5500.862478438031, 5517.2551705519745, 5573.247611572447, 5641.956238095965, 5665.943340566603, 5675.233843431146, 5681.0, 5693.914591281134, 5727.341355744101, 5410.91883621746, 5437.972810135944, 5531.778728850841, 5664.801731939383, 5671.6597004179675, 5115.795368185268, 5517.841253336764, 5587.944120558824, 5679.432056794329, 5692.260006199168, 4642.419697537808, 5465.9180112298345, 5540.667559946376, 5562.3047119110115, 5591.217229587861, 5356.732163391854, 5834.0, 5932.703364831785, 6155.538334624921, 6244.843878903019, 5478.753456094445, 6332.145160403333, 6336.556441049082, 6379.936200637981, 6395.232572091332, 6632.071509988606, 6658.766943156967, 6662.267150613428, 6666.100076489661, 6673.39939405451, 6194.690265486708, 6224.439800417894, 6273.6235825850135, 6276.843078923018, 6293.213348331458, 5392.299001129828, 5557.194206839986, 5854.356020837705, 6505.186851643544, 6622.212002759228, 6412.551121421549, 6427.646479443582, 6438.8390290243115, 6447.097406363019, 6470.22357317125, 6512.837179070561, 6553.984132459476, 6566.244881838554, 6589.011648252705, 6618.90071648916, 6273.96863015682, 6308.0537919311555, 6386.840328991812, 6425.293217746044, 6492.993585994179, 5441.83674489766, 6247.500199983991, 6409.0, 6490.772822951269, 6541.443977261986, 5172.482751724798, 5238.0, 5255.947440525622, 5259.474052594786, 5268.473152684701, 4649.767511624473, 4653.325267836145, 4682.1103990241745, 4716.551927566876, 4747.098051370229, 4506.481754598198, 5924.14099955504, 6037.09443583466, 6236.126942228092, 6244.2819075805955, 5481.725913704299, 6454.128692626529, 6458.354164583504, 6519.022146677941, 6570.934290657128, 6168.938310616836, 6176.073588961692, 6196.81409557714, 6221.3778622138325, 6228.968855155696, 5179.300794392784, 5811.622244554175, 5825.446582574648, 5829.387914269026, 5842.561807864342, 6157.876842463215, 6649.235337936006, 6688.3646053624825, 6811.386975172259, 6897.689603967733, 6537.70580323877, 6622.90065649006, 6626.5030122741955, 6628.5691430056895, 6639.435647969976, 5630.831075067755, 6676.332366763285, 6812.284710105368, 6825.522213445011, 6844.452443804584, 4747.430308363018, 6129.48104857405, 6373.044043393528, 6380.9042864358, 6395.1046853441485, 6577.0, 6606.768763093269, 6616.206055273398, 6619.768308109193, 6638.601683898982, 6293.874122517433, 6350.0, 6357.427831495188, 6363.3636636336, 6434.903476447951, 6235.968820156052, 6296.150019747459, 6310.7791227306825, 6314.715837787218, 6387.233531976192, 5419.0, 6235.657038862906, 6303.275123360872, 6306.117143599808, 6320.683965801783, 6248.218972628421, 6339.366063393623, 6362.6818659067785, 6382.074599183, 6408.263049749338, 6220.409061139185, 6222.315545289924, 6235.844103897439, 6278.780242691484, 6283.183186185766], "value": 5915.452508861117, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [742.7039815521915, 794.8291117409758, 820.573301883021, 958.4009993753908, 965.9661911833088, 35599.64400355999, 36391.63608363919, 37589.62410375896, 37676.0, 37744.43383349248, 36934.44598331021, 37028.44457333142, 37215.441768373494, 37258.0, 37291.06772330686, 7738.883916741253, 7866.977292951937, 8947.597358118901, 10233.488325583729, 10639.414832184228, 10457.372557646528, 10568.471576421187, 10570.78858422831, 10572.0, 10601.098906592948, 10524.579016839318, 10526.578936842518, 10529.420881851494, 10588.894111058868, 10597.894021059768, 10490.842637360445, 10528.368297902112, 10567.682969510926, 10576.0, 10590.152787776959, 9244.168024877727, 9992.700218993441, 10325.84511232332, 10365.585376585002, 10375.584976600927, 32431.51352729711, 32780.344393112246, 32940.67059329424, 32955.50566741501, 33035.834820825985, 3680.595134535199, 3746.868859589901, 3752.155764952891, 3754.4931434256573, 3791.50710407648, 18454.630907381776, 24576.508469830682, 24576.754232457624, 25545.361365965815, 25943.35141621456, 3050.2069461940055, 3082.2910730532103, 3138.1056398926426, 3210.9036728897936, 3232.949291480278, 337948.0, 338765.0, 339798.0, 340097.0, 341511.29244353867, 42311.57688423138, 42527.787361063, 42807.71576852699, 42850.57149428528, 43023.569764302585, 43999.78000109979, 44141.77929110334, 44182.77908610436, 44240.77879610581, 44339.55660443419, 12643.683907902192, 12823.871761282267, 12826.679333016562, 12834.29411382383, 12838.229706217657, 9128.634854605742, 10875.129989600813, 10896.727581810519, 10901.400422976812, 10911.345319280868, 11264.774704506028, 11532.481038353295, 11543.942280288546, 11618.302901825917, 11629.53481860733, 5655.773769049274, 5714.828555143353, 5753.0, 5760.567957403212, 5772.307323121252, 1102.8511150994673, 1132.7281452451516, 1137.806572882612, 1145.8223975283845, 1187.192708957917, 23564.528709425715, 26066.609000865003, 26150.86924565365, 26374.604380934303, 26494.47011059768, 1030.1964467715195, 1223.1193540650718, 1237.9442925068392, 1251.161721646499, 1254.9309787961568, 13351.0, 13447.394867230998, 13505.729885402434, 13516.93241533786, 13520.661983450493, 396613.0, 420903.0, 436691.0, 440760.0, 441630.0, 54568.45431545713, 54572.72713636486, 54611.45388546143, 54641.453585463634, 54749.178762318596, 1371.732512160119, 1409.8942579306593, 1425.8146440962817, 1459.0224549551826, 1469.2359972814077, 21740.45648858791, 25425.491490169723, 25645.0, 26356.868215659568, 26760.33099172536, 37885.242295154494, 38126.618733812305, 38176.0, 38195.618043820316, 38256.23487530289, 393920.0, 395344.02327988175, 396466.0, 396599.0, 397028.0, 28354.0, 28447.857760711064, 28494.145175644764, 36885.81557092197, 38070.809645951595, 45618.77190614026, 46955.06089878252, 47535.52464475449, 47879.803004925154, 47882.521174787806, 36663.816680917495, 37700.43449348317, 38398.0, 39016.0, 39066.02334941536, 37821.0, 37870.62129378671, 38506.074696265634, 39125.02187445223, 39518.0, 33401.99794006184, 35037.0, 37430.812845936685, 38296.42555361616, 38532.0, 32074.0, 33918.78152349431, 34217.65782342145, 34234.315313693085, 34583.96248112561, 25779.871100645127, 25843.96624135089, 25864.353391165372, 25997.350066247738, 26327.34181645474], "value": 18309.146399495243, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [352.2356486424459, 410.8110269276133, 421.2438672582714, 426.996558088492, 450.1110307143392, 19758.209671613135, 20148.992550372495, 20355.796442035575, 20790.376288711326, 21059.894700526496, 21164.682529762067, 21283.787162128374, 21434.24980125696, 21964.780352196474, 22068.33794986149, 5788.536917046647, 6536.771213007558, 6714.932850671504, 6735.797926062226, 6758.391744742972, 5799.536037117042, 6258.906116408258, 6478.676066196695, 6524.836879078037, 6641.435477984377, 5830.804685039578, 6019.157317975465, 6499.545031847772, 6522.119513865615, 6527.249366322862, 6268.65522396268, 6614.768483103092, 6685.39831415175, 6711.127553418049, 6712.899306510406, 6116.602420842631, 6355.713992870332, 6420.775272865474, 6539.673016349164, 6574.145361103074, 23778.881105594533, 25087.498250034896, 25390.492190156278, 25967.220983370527, 26034.828432720384, 2858.299716569433, 2928.824270543774, 2934.5598160275918, 2938.6620538638117, 2957.9704202958123, 14961.850381496155, 15704.450344237897, 15745.291461884242, 15940.123293218869, 15950.0, 1967.5868067705799, 1968.2225520919158, 1975.2888959974398, 2000.3198912369865, 2008.9095990680455, 154149.0, 157838.0, 163498.0, 163499.9999999988, 165249.0, 20757.065932033096, 20909.013643703824, 21027.68458473124, 21113.894430527747, 21182.15271389127, 31215.0, 31660.208494787814, 31740.0, 31757.8412107938, 31824.84087579547, 9920.107190352759, 9985.251106167067, 10147.934466881019, 10151.289409741396, 10237.283390162765, 8319.084900660922, 8323.8335233295, 8345.290650294794, 8500.0, 8518.23335899772, 7723.845523089507, 9592.616295348247, 9625.51872406377, 9654.806903861883, 9682.419054856731, 3872.2255548890334, 3957.8614748483665, 3977.54258260298, 3988.4814974053766, 3997.4603428537357, 962.7929995051097, 974.7904200596905, 979.8873129590046, 985.6353149334649, 1021.1677482851452, 13367.532136375376, 13373.130746501447, 13575.253361065235, 13715.657108572366, 13743.037987340791, 988.4958671077802, 991.6677912899115, 1011.1304278320638, 1018.2770233134557, 1021.1422405179536, 6493.123428337209, 6795.286494918061, 6946.166460024729, 7008.964955175192, 7092.148942126978, 740421.0, 756303.0, 768116.0, 769460.0, 779067.0, 121644.0, 125514.0, 131908.0, 143872.0, 147141.0, 1051.8948105189572, 1070.673444599389, 1106.833974903771, 1252.5490823303603, 1292.877176668234, 15412.152331621868, 15556.844431555539, 17411.651766964336, 18825.435236942914, 20048.198072077535, 51130.0, 65061.02408463782, 65397.346026539126, 65721.34278657344, 68772.656136719, 75701.62149189219, 86270.7059394097, 92796.53601731948, 93329.5333523328, 93608.0, 11507.194496385173, 11731.824022639837, 11764.58823941158, 12035.458404371999, 12083.39583020828, 10910.945445272722, 13213.009024323022, 13254.138480998705, 13395.531156409479, 13618.727625447635, 11488.253263537843, 11541.884581154416, 11557.826632600685, 11640.126990475917, 11812.704682382666, 11267.605633802777, 11278.323300601991, 11352.772944540899, 11667.824982625438, 11885.5840045598, 11686.94156529212, 11776.823347649963, 11860.0, 11929.522819087138, 11962.188885612048, 11677.182597218112, 11702.35637039971, 11760.0, 11960.880391195977, 12058.939705301418, 11466.541338346706, 11479.368634724837, 11700.94691477771, 11800.291982481078, 11819.527218911491], "value": 11169.344361236524, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1495.312156408053, 1552.6351307442756, 1592.2755146408385, 1601.9439319623812, 1614.402671011726, 9397.29520285978, 9575.856362154556, 10695.732606684836, 11271.6618501445, 11292.491837867296, 10405.583776648944, 10798.5680572777, 10802.5678972841, 10820.621278255263, 10822.567097316098, 2375.4417711837705, 2428.0287884846025, 2431.416460049584, 2433.245693834917, 2434.3427274635837, 2382.940426489334, 2388.0925248405583, 2413.75862413759, 2416.347586151738, 2421.1162925532144, 2379.452725873042, 2399.7120345558556, 2416.4804567018086, 2420.995286955911, 2422.0917156066475, 2360.4570948681726, 2375.8455700379504, 2377.2036367816804, 2381.3213234228247, 2381.6784734060852, 2335.941601459977, 2354.2937118864274, 2356.2106694257445, 2360.6931098957198, 2397.9040838366445, 10990.285631433931, 11042.613508527164, 11073.889261107446, 11105.666829995112, 11159.944200279027, 5761.769529218826, 5826.980278451254, 5935.347111817697, 5946.494547963428, 5952.940470595282, 3450.413429716954, 3616.8914932552066, 3642.0530662028063, 3661.3775658137915, 3716.8699095531797, 1207.4023358437541, 1211.9697007574794, 1216.5377156680493, 1247.6070037938111, 1260.521002019236, 14970.17664028478, 15046.849531504764, 15249.695006099815, 15361.231938403034, 15381.384744610203, 4425.712328698624, 4862.07620552094, 4864.124457597595, 4979.57673597741, 4999.350084488993, 10996.450177491093, 11383.86161383855, 12722.173058751152, 12812.42344094518, 12836.229826210458, 5056.671316364498, 5831.300243970751, 5920.111983202468, 5935.851603709942, 5936.881262374728, 4387.561243875651, 4903.901921961541, 5069.543741063322, 5196.194589838581, 5327.413984461706, 3669.94495082574, 4086.0977766233177, 4214.641755450821, 4252.532221455637, 4257.659387249013, 1149.6665966869514, 1166.311875993151, 1177.1230433327225, 1180.3449085757486, 1180.5926955200614, 795.8965334506477, 798.6645608844183, 813.8819871118654, 815.1644564321617, 820.782492639451, 6345.764744086147, 6537.477001839841, 6687.197536295578, 6807.65961701913, 6973.302669733088, 957.5882370580578, 985.1626117799813, 992.0674565908023, 1035.9067683908484, 1075.3386667199773, 4131.690123240769, 4177.624013838769, 4200.87397378073, 4205.1589682063095, 4319.632831209383, 13062.34688265583, 13669.248191349572, 13690.38393272305, 13712.93143534276, 13721.588352349445, 12688.302143382203, 13364.665883352995, 13508.0, 13508.392122354519, 13521.323933803467, 9937.701868943943, 10210.336328138646, 10223.335483193718, 10233.94883025595, 10255.743606409751, 6396.10454536356, 6467.870642587216, 6468.8059358219325, 6556.475481961527, 6614.6361950093205, 9866.309358344846, 11733.76532469363, 12146.696332591757, 12237.326947018, 12255.264684118982, 12204.328761917825, 12298.815517767061, 12331.890129888354, 12343.75312493763, 12354.752904941672, 7988.84022319562, 8098.75703728889, 8211.753647390588, 9008.0, 9201.585928633362, 10395.32430392022, 10406.11548018427, 10422.114120299859, 10523.05292523646, 10526.578936842441, 9136.862947055932, 9205.631774728936, 9251.444913305224, 9295.349325547148, 9333.300002499704, 8994.820103598022, 9091.227245683926, 9130.77173070652, 9152.862707059267, 9169.587368568296, 7558.281963213486, 7719.112302085332, 7737.1102323233545, 7738.92261077382, 7844.921550784419, 5562.3047119110115, 5710.771569137354, 5790.449907258803, 5963.0, 5998.280206375263, 5799.0, 5870.706464676835, 5881.94118058831, 5904.202932604216, 6051.667158306335], "value": 5135.615335857676, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [330.49599360974497, 367.0493422036924, 379.3702453926481, 379.4213823918524, 390.04439124145847, 4258.382534532491, 4665.9300110498325, 5103.030424219401, 5118.5905127589795, 5159.45825688302, 4438.956845141389, 5081.3759478874235, 5181.8131848912835, 5260.947390526094, 5327.549327309433, 3574.785512869223, 4416.911661766779, 4421.187313201588, 4443.644508439334, 4461.241588929874, 4399.142167277382, 4461.866144015685, 4486.416765820439, 4500.572445617661, 4519.751413672247, 4057.8376864925517, 4190.344813798548, 4488.192125417424, 4499.785058034328, 4505.256632655608, 3522.7846392994375, 4078.632923036911, 4361.43301370823, 4453.910921781546, 4491.708038977472, 3609.1698909251018, 4047.5142982841953, 4060.8375664973364, 4072.490938632671, 4080.9387859182134, 4921.901561968776, 4975.15915616028, 5221.73891305437, 5260.710660913648, 5266.92099618506, 4852.636052296092, 4883.755812209375, 5083.43286640248, 5113.468832927688, 5121.539061484485, 5679.744411501492, 5717.026666466807, 5719.6854173020465, 5754.424557544254, 5758.193852860602, 3990.0, 4075.1677584079075, 4386.078923426085, 4396.538363471854, 4565.885852853672, 5165.948340516585, 5388.811391601275, 5732.5987180897355, 5738.397468265855, 6019.638821670714, 3919.2161567686576, 4503.842365517191, 4564.520725323793, 4688.976555117202, 4733.219018861849, 4619.376384188159, 4763.309320148559, 4778.085367925562, 4784.378030855966, 4788.353572267769, 4983.9252411213865, 5043.36957880265, 5119.59043276537, 5132.409772876093, 5167.870803229949, 4567.566081222279, 4913.606911447076, 5024.974875125674, 5106.412762532356, 5664.745086471119, 1373.9450421983208, 1381.2886363522794, 1419.2548911821377, 1625.7886474758207, 1631.8776091793163, 1021.8211812932707, 1111.1666250312267, 1218.4882349413267, 1218.5438401110764, 1239.0954603139758, 4854.975725121351, 5348.8662783429945, 5374.435684253175, 5375.704336261465, 5376.542993845489, 5065.936153407825, 5354.9196762048605, 5646.57650676201, 5933.58464907461, 6027.57806953509, 4231.793938727448, 4758.691359876062, 5236.0, 5325.6272060956135, 5364.554976698654, 2772.3900741837165, 2807.1578526441986, 3040.650325212629, 3063.816171029701, 3079.5842561254394, 5707.0, 6021.367756385516, 6043.758249670052, 6064.120702498113, 6209.565330426917, 5181.974090129525, 5897.64614123154, 5920.109491056426, 5939.346671866091, 6031.849203769941, 5280.60395470349, 5575.686090272491, 5618.69097199658, 6192.721327540272, 6232.719527621177, 5190.948090519047, 5826.135177127147, 5853.326867410302, 5939.940600594112, 5981.3420523743225, 5391.919121213264, 5522.696251706195, 5627.971860140837, 5733.713314334183, 5880.647161170344, 4942.382202224722, 5281.8131895560955, 5821.650700957956, 5921.940780592139, 5933.270695480128, 4043.8584649537124, 4149.481314835645, 4205.719538759772, 4334.068175342317, 4606.424196975378, 5242.921356179737, 5252.737363131751, 5393.487618676219, 5400.4059553448305, 5499.862503437446, 5022.296878436949, 5308.734563271898, 5615.591760468013, 5708.286464191976, 5740.0, 4697.953020469751, 4848.424757702644, 5701.8859622806485, 5739.569532285179, 5773.971130144322, 5219.8255392536375, 5367.35591728995, 5462.432656651367, 5480.479354461319, 5542.61201715876, 4965.503449655005, 5243.5018673227505, 5693.160813308655, 5727.627704199213, 5734.197212390185, 4625.196173492273, 5108.182690769459, 5156.355455568054, 5184.637075404837, 5601.691906945157], "value": 4307.052771751986, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [714.5926821711627, 723.4284914917216, 766.9961650191748, 827.1025936858508, 835.9498430094193, 14805.925970370146, 15234.162121083336, 15768.975016623912, 15772.211389430511, 15777.684446311066, 15256.923715381436, 15391.69216615667, 15468.381264749425, 15477.535673929771, 15543.689126217469, 903.9638414463413, 920.1902325953151, 942.5664194470518, 948.4214629076258, 949.5252373813094, 939.6288466055904, 942.5475771629588, 947.7725345916965, 950.2208189284789, 955.99142904236, 981.4847205217246, 995.7958618483229, 999.215615741643, 999.860019597257, 1000.3847633705292, 974.2790335152007, 992.072412294507, 993.9652112176075, 996.1035068438428, 996.7607774134228, 740.0157790139102, 740.3854800515559, 740.5815714121504, 740.7111226621622, 742.858856817203, 16068.59828504285, 16239.756403653955, 16455.177241138015, 16565.751513727304, 16589.50231493057, 13849.0, 14044.719105617933, 14131.858681413158, 14161.504347347896, 14164.29178541079, 11613.477393517313, 11797.469113889896, 11805.291682499077, 11823.645290641294, 11847.229930054518, 3569.01851990702, 3584.731145164123, 3622.492851000862, 3732.962670373289, 3807.562130355017, 91089.0, 100575.0, 108320.20879269188, 110187.0, 112134.43932780284, 10289.897101028893, 10673.57305707763, 10771.730706732395, 10779.838302425469, 10824.94587527073, 18277.817221827878, 22259.554808903733, 22439.0, 22467.43831404228, 22511.32466026022, 7635.427342949301, 7647.311741943252, 7659.080910290801, 7723.53658780475, 7767.766966990999, 4276.5082015568605, 4302.7848607569495, 4344.956550434518, 4408.162449134654, 4475.664325175625, 3379.915502112467, 3450.516927630134, 3457.135716070982, 3477.8434970425888, 3483.9129021774656, 525.2357819372803, 619.8295468746131, 641.7850020243177, 642.8071578526424, 643.3020173112226, 692.2005084127841, 713.8001359619315, 715.1811176203322, 721.3363705391052, 725.1407082607127, 9526.142647161789, 10459.947700261602, 10492.84893625436, 10536.525856336484, 10640.25518213733, 565.1833101168771, 703.2615753458848, 703.391566295151, 712.5261700968902, 714.2892821642499, 4708.788104535304, 4994.2534740361425, 5021.5229553192385, 5035.7410647338165, 5036.546710796046, 105930.0, 107826.0, 109097.0, 109878.0, 110409.44795275973, 82697.75953360704, 89663.55168224247, 93552.53223733838, 96565.0, 97648.0, 74107.0, 74513.0, 81438.59280703677, 82431.0, 84041.0, 9692.563834627317, 9707.0, 9794.314397992071, 9875.308728388944, 9878.901210987799, 46750.766246168554, 51187.488125118274, 51795.0, 53132.0, 54137.0, 68803.31196687969, 69062.30937690558, 70146.64926675538, 70378.29621703923, 70383.64808175927, 11025.283356581796, 11169.273997190157, 11243.613279403633, 11557.190996630492, 11948.34284114382, 11780.0, 11853.0, 12438.564650237198, 12484.500619975099, 12991.0, 11612.6516204514, 11632.941835290769, 11681.299122052704, 11907.047436205256, 11972.401379930794, 11688.006519445942, 11827.231229970024, 11974.341411222467, 12109.69725756828, 12269.325187114795, 11287.98408143271, 11616.593419230625, 11617.535298587962, 11829.526818927146, 12058.819117713418, 10674.786504270027, 10875.238733288594, 11177.776444471228, 11240.83138752903, 11299.09607231403, 9726.629057967042, 11508.36703981289, 11543.191976561897, 11653.067754579444, 11889.108316876442], "value": 7551.339792088496, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1591.1000000000001, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [151.0, 180.0, 158.0, 123.0, 123.0, 121.0, 121.0, 120.0, 120.0, 120.0], "value": 133.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 118.0, 101.0, 182.0, 101.0, 145.0, 103.0, 144.0, 123.0, 145.0], "value": 130.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [205.0, 285.0, 210.0, 289.0, 279.0, 198.0, 196.0, 200.0, 203.0, 263.0], "value": 232.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 58.0, 84.0, 112.0, 83.0, 65.0, 62.0, 61.0, 75.0, 61.0], "value": 74.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 70.0, 84.0, 73.0, 72.0, 72.0, 84.0, 71.0, 70.0, 86.0], "value": 77.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 88.0, 107.0, 90.0, 88.0, 88.0, 89.0, 89.0, 88.0, 91.0], "value": 90.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [99.0, 126.0, 110.0, 153.0, 97.0, 98.0, 98.0, 113.0, 152.0, 133.0], "value": 117.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 90.0, 94.0, 104.0, 147.0, 132.0, 90.0, 89.0, 91.0, 91.0], "value": 101.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 66.0, 68.0, 68.0, 70.0, 68.0, 70.0, 70.0, 69.0, 70.0], "value": 68.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 64.0, 59.0, 70.0, 64.0, 65.0, 65.0, 73.0, 60.0, 60.0], "value": 63.9, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 84.0, 83.0, 91.0, 103.0, 93.0, 83.0, 87.0, 82.0, 87.0], "value": 87.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [139.0, 139.0, 137.0, 139.0, 136.0, 142.0, 146.0, 142.0, 185.0, 143.0], "value": 144.8, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [218.0, 215.0, 166.0, 168.0, 167.0, 194.0, 218.0, 196.0, 172.0, 220.0], "value": 193.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 67.0, 64.0, 68.0, 80.0, 82.0, 79.0, 87.0, 82.0, 68.0], "value": 74.1, "unit": "score"}]}]} 09:49:43 INFO - Return code: 0 09:49:44 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 09:49:44 ERROR - # TBPL SUCCESS # 09:49:44 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:49:44 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:49:44 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:49:44 INFO - 2017-03-10 09:49:44.055 screenresolution[1661:10679] starting screenresolution argv=screenresolution get 09:49:44 INFO - 2017-03-10 09:49:44.058 screenresolution[1661:10679] Display 0: 1600x1200x32@60 09:49:44 INFO - 2017-03-10 09:49:44.064 screenresolution[1662:10682] starting screenresolution argv=screenresolution list 09:49:44 INFO - Available Modes on Display 0 09:49:44 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:49:44 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:49:44 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:49:44 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:49:44 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:49:44 INFO - Intel Iris: 09:49:44 INFO - Chipset Model: Intel Iris 09:49:44 INFO - Type: GPU 09:49:44 INFO - Bus: Built-In 09:49:44 INFO - VRAM (Dynamic, Max): 1536 MB 09:49:44 INFO - Vendor: Intel (0x8086) 09:49:44 INFO - Device ID: 0x0a2e 09:49:44 INFO - Revision ID: 0x0009 09:49:44 INFO - Displays: 09:49:44 INFO - Display: 09:49:44 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:49:44 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:49:44 INFO - Display Serial Number: 1600x1200 60 09:49:44 INFO - Main Display: Yes 09:49:44 INFO - Mirror: Off 09:49:44 INFO - Online: Yes 09:49:44 INFO - Rotation: Supported 09:49:44 INFO - Return code: 0 09:49:44 INFO - Running post-action listener: _resource_record_post_action 09:49:44 INFO - [mozharness: 2017-03-10 17:49:44.299752Z] Finished run-tests step (success) 09:49:44 INFO - Running post-run listener: _resource_record_post_run 09:49:44 INFO - Total resource usage - Wall time: 1200s; CPU: 55.0%; Read bytes: 41396736; Write bytes: 513873408; Read time: 408; Write time: 1821 09:49:44 INFO - TinderboxPrint: CPU usage
54.7% 09:49:44 INFO - TinderboxPrint: I/O read bytes / time
41,396,736 / 408 09:49:44 INFO - TinderboxPrint: I/O write bytes / time
513,873,408 / 1,821 09:49:44 INFO - TinderboxPrint: CPU idle
2,177.2 (45.3%) 09:49:44 INFO - TinderboxPrint: CPU system
965.2 (20.1%) 09:49:44 INFO - TinderboxPrint: CPU user
1,659.1 (34.6%) 09:49:44 INFO - TinderboxPrint: Swap in / out
248,832,000 / 0 09:49:44 INFO - install - Wall time: 20s; CPU: 54.0%; Read bytes: 204220928; Write bytes: 202245632; Read time: 15700; Write time: 440 09:49:44 INFO - run-tests - Wall time: 1181s; CPU: 55.0%; Read bytes: 39324160; Write bytes: 300797952; Read time: 385; Write time: 1347 09:49:44 INFO - Running post-run listener: _upload_blobber_files 09:49:44 INFO - Blob upload gear active. 09:49:44 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:49:44 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:49:44 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:49:44 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:49:45 INFO - (blobuploader) - INFO - Open directory for files ... 09:49:45 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 09:49:45 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:49:45 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:49:46 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 09:49:46 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:49:46 INFO - (blobuploader) - INFO - Done attempting. 09:49:46 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 09:49:46 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:49:46 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:49:47 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 09:49:47 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:49:47 INFO - (blobuploader) - INFO - Done attempting. 09:49:47 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 09:49:47 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:49:47 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:49:48 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 09:49:48 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:49:48 INFO - (blobuploader) - INFO - Done attempting. 09:49:48 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 09:49:48 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:49:48 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:49:50 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 09:49:50 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:49:50 INFO - (blobuploader) - INFO - Done attempting. 09:49:50 INFO - (blobuploader) - INFO - Iteration through files over. 09:49:50 INFO - Return code: 0 09:49:50 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:49:50 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:49:50 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/704efb39c59cc171836536c5fcc9d8ccd9f0a656befa7ec3102532e89ccc8e2fd2884f447992390076d5e629686bb7827185354a8e590beeae5310443ed0b059", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/fdb80ee4ac938d13b6a28c5017047f03ce997cc08f8f45f18b1c603697ebaadbe1128397f8e6aa8c1cd1823db18385a0888f8a5301396d5fbddda110ee4f4b5d", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/08d32720f3a38560ee1343693060ead48a0446949ed1bd7f4c5ca49469d023d3d2020b8582644c84c268acdcfa126e7599bdd6146ddb0a7450521c083a7a5032", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/57ec147d27fa3a9f4ffd5e982010f7ebd27cb3b263dad2fdc77df07c8234500a87adfef953136da95b345ebf9bfd8377ab7aa24e0b3ca8a917ddc6436c00483e"} 09:49:50 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:49:50 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:49:50 INFO - Contents: 09:49:50 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/704efb39c59cc171836536c5fcc9d8ccd9f0a656befa7ec3102532e89ccc8e2fd2884f447992390076d5e629686bb7827185354a8e590beeae5310443ed0b059", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/fdb80ee4ac938d13b6a28c5017047f03ce997cc08f8f45f18b1c603697ebaadbe1128397f8e6aa8c1cd1823db18385a0888f8a5301396d5fbddda110ee4f4b5d", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/08d32720f3a38560ee1343693060ead48a0446949ed1bd7f4c5ca49469d023d3d2020b8582644c84c268acdcfa126e7599bdd6146ddb0a7450521c083a7a5032", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/57ec147d27fa3a9f4ffd5e982010f7ebd27cb3b263dad2fdc77df07c8234500a87adfef953136da95b345ebf9bfd8377ab7aa24e0b3ca8a917ddc6436c00483e"} 09:49:50 INFO - Running post-run listener: copy_logs_to_upload_dir 09:49:50 INFO - Copying logs to upload dir... 09:49:50 INFO - mkdir: /builds/slave/test/build/upload/logs 09:49:50 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1282.384059 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 22 secs) (at 2017-03-10 09:49:50.670023) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-10 09:49:50.673094) ========= 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.2ni27nkc8x/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.jzIZyM7Dbn/Listeners TMPDIR=/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/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/704efb39c59cc171836536c5fcc9d8ccd9f0a656befa7ec3102532e89ccc8e2fd2884f447992390076d5e629686bb7827185354a8e590beeae5310443ed0b059", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/fdb80ee4ac938d13b6a28c5017047f03ce997cc08f8f45f18b1c603697ebaadbe1128397f8e6aa8c1cd1823db18385a0888f8a5301396d5fbddda110ee4f4b5d", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/08d32720f3a38560ee1343693060ead48a0446949ed1bd7f4c5ca49469d023d3d2020b8582644c84c268acdcfa126e7599bdd6146ddb0a7450521c083a7a5032", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/57ec147d27fa3a9f4ffd5e982010f7ebd27cb3b263dad2fdc77df07c8234500a87adfef953136da95b345ebf9bfd8377ab7aa24e0b3ca8a917ddc6436c00483e"} build_url:https://queue.taskcluster.net/v1/task/B4BfDL3PQOyOjUohjVfCtQ/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011424 build_url: 'https://queue.taskcluster.net/v1/task/B4BfDL3PQOyOjUohjVfCtQ/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/704efb39c59cc171836536c5fcc9d8ccd9f0a656befa7ec3102532e89ccc8e2fd2884f447992390076d5e629686bb7827185354a8e590beeae5310443ed0b059", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/fdb80ee4ac938d13b6a28c5017047f03ce997cc08f8f45f18b1c603697ebaadbe1128397f8e6aa8c1cd1823db18385a0888f8a5301396d5fbddda110ee4f4b5d", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/08d32720f3a38560ee1343693060ead48a0446949ed1bd7f4c5ca49469d023d3d2020b8582644c84c268acdcfa126e7599bdd6146ddb0a7450521c083a7a5032", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/57ec147d27fa3a9f4ffd5e982010f7ebd27cb3b263dad2fdc77df07c8234500a87adfef953136da95b345ebf9bfd8377ab7aa24e0b3ca8a917ddc6436c00483e"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-10 09:49:50.707495) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-10 09:49:50.707799) ========= 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.2ni27nkc8x/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.jzIZyM7Dbn/Listeners TMPDIR=/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/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.004978 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-10 09:49:50.756256) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-10 09:49:50.756541) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-10 09:49:50.756851) ========= ========= Total master_lag: 0.61 =========