builder: mozilla-central_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0360 starttime: 1481030214.04 results: success (0) buildid: 20161206030203 builduid: 5ba23cb5a5bb4d779d9a0024601083cd revision: 8103c612b79c2587ea4ca1b0a9f9f82db4b185b8 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-12-06 05:16:54.044804) ========= master: http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-12-06 05:16:54.045298) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-06 05:16:54.045754) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-06 05:16:54.073422) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-06 05:16:54.073824) ========= 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.h1al2HKYZY/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.GdXX81PhtH/Listeners TMPDIR=/var/folders/0_/1mzt9_fn191_pm4dgt9z87g000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-12-06 05:16:54-- 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% 21.2M=0.001s 2016-12-06 05:16:54 (21.2 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.616877 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-06 05:16:54.715656) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-06 05:16:54.716036) ========= 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.h1al2HKYZY/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.GdXX81PhtH/Listeners TMPDIR=/var/folders/0_/1mzt9_fn191_pm4dgt9z87g000000w/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.024687 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-06 05:16:54.765367) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-12-06 05:16:54.765762) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 8103c612b79c2587ea4ca1b0a9f9f82db4b185b8 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev 8103c612b79c2587ea4ca1b0a9f9f82db4b185b8 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.h1al2HKYZY/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.GdXX81PhtH/Listeners TMPDIR=/var/folders/0_/1mzt9_fn191_pm4dgt9z87g000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-12-06 05:16:54,864 truncating revision to first 12 chars 2016-12-06 05:16:54,864 Setting DEBUG logging. 2016-12-06 05:16:54,864 attempt 1/10 2016-12-06 05:16:54,864 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/8103c612b79c?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-12-06 05:16:56,119 unpacking tar archive at: mozilla-central-8103c612b79c/testing/mozharness/ program finished with exit code 0 elapsedTime=1.526166 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-12-06 05:16:56.314911) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-06 05:16:56.315196) ========= script_repo_revision: 8103c612b79c2587ea4ca1b0a9f9f82db4b185b8 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-06 05:16:56.315595) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-06 05:16:56.315857) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-06 05:16:56.336665) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 18 secs) (at 2016-12-06 05:16:56.337073) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.h1al2HKYZY/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.GdXX81PhtH/Listeners TMPDIR=/var/folders/0_/1mzt9_fn191_pm4dgt9z87g000000w/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 05:16:56 INFO - MultiFileLogger online at 20161206 05:16:56 in /builds/slave/test 05:16:56 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 05:16:56 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 05:16:56 INFO - {'append_to_log': False, 05:16:56 INFO - 'base_work_dir': '/builds/slave/test', 05:16:56 INFO - 'blob_upload_branch': 'Firefox', 05:16:56 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 05:16:56 INFO - 'branch': 'Firefox', 05:16:56 INFO - 'buildbot_json_path': 'buildprops.json', 05:16:56 INFO - 'config_files': ('talos/mac_config.py',), 05:16:56 INFO - 'default_actions': ('clobber', 05:16:56 INFO - 'read-buildbot-config', 05:16:56 INFO - 'download-and-extract', 05:16:56 INFO - 'populate-webroot', 05:16:56 INFO - 'create-virtualenv', 05:16:56 INFO - 'install', 05:16:56 INFO - 'run-tests'), 05:16:56 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 05:16:56 INFO - 'download_minidump_stackwalk': True, 05:16:56 INFO - 'download_symbols': 'ondemand', 05:16:56 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 05:16:56 INFO - 'tooltool.py': '/tools/tooltool.py', 05:16:56 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 05:16:56 INFO - '/tools/misc-python/virtualenv.py')}, 05:16:56 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 05:16:56 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 05:16:56 INFO - 'installer_path': 'installer.exe', 05:16:56 INFO - 'log_level': 'info', 05:16:56 INFO - 'log_name': 'talos', 05:16:56 INFO - 'log_to_console': True, 05:16:56 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 05:16:56 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 05:16:56 INFO - 'opt_config_files': (), 05:16:56 INFO - 'pip_index': False, 05:16:56 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:16:56 INFO - 'cmd': ('bash', 05:16:56 INFO - '-c', 05:16:56 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:16:56 INFO - 'enabled': True, 05:16:56 INFO - 'halt_on_failure': False, 05:16:56 INFO - 'name': 'check_screen_resolution'},), 05:16:56 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:16:56 INFO - 'cmd': ('bash', 05:16:56 INFO - '-c', 05:16:56 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:16:56 INFO - 'enabled': True, 05:16:56 INFO - 'halt_on_failure': False, 05:16:56 INFO - 'name': 'check_screen_resolution'},), 05:16:56 INFO - 'run_cmd_checks_enabled': True, 05:16:56 INFO - 'sps_profile': False, 05:16:56 INFO - 'sps_profile_interval': 0, 05:16:56 INFO - 'suite': 'dromaeojs', 05:16:56 INFO - 'system_bits': '32', 05:16:56 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 05:16:56 INFO - 'title': 't-yosemite-r7-0360', 05:16:56 INFO - 'tooltool_cache': '/builds/tooltool_cache', 05:16:56 INFO - 'use_talos_json': True, 05:16:56 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 05:16:56 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 05:16:56 INFO - 'work_dir': 'build'} 05:16:56 INFO - [mozharness: 2016-12-06 13:16:56.543604Z] Running clobber step. 05:16:56 INFO - Running pre-action listener: _resource_record_pre_action 05:16:56 INFO - Running main action method: clobber 05:16:56 INFO - rmtree: /builds/slave/test/build 05:16:56 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 05:16:58 INFO - Running post-action listener: _resource_record_post_action 05:16:58 INFO - [mozharness: 2016-12-06 13:16:58.821751Z] Finished clobber step (success) 05:16:58 INFO - [mozharness: 2016-12-06 13:16:58.821884Z] Running read-buildbot-config step. 05:16:58 INFO - Running pre-action listener: _resource_record_pre_action 05:16:58 INFO - Running main action method: read_buildbot_config 05:16:58 INFO - Using buildbot properties: 05:16:58 INFO - { 05:16:58 INFO - "project": "", 05:16:58 INFO - "product": "firefox", 05:16:58 INFO - "script_repo_revision": "production", 05:16:58 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs", 05:16:58 INFO - "repository": "", 05:16:58 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs", 05:16:58 INFO - "buildid": "20161206030203", 05:16:58 INFO - "stage_platform": "macosx64", 05:16:58 INFO - "basedir": "/builds/slave/test", 05:16:58 INFO - "nightly_build": "True", 05:16:58 INFO - "buildnumber": 23, 05:16:58 INFO - "slavename": "t-yosemite-r7-0360", 05:16:58 INFO - "master": "http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/", 05:16:58 INFO - "platform": "yosemite_r7", 05:16:58 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 05:16:58 INFO - "branch": "mozilla-central", 05:16:58 INFO - "repo_path": "mozilla-central", 05:16:58 INFO - "revision": "8103c612b79c2587ea4ca1b0a9f9f82db4b185b8", 05:16:58 INFO - "pgo_build": "False", 05:16:58 INFO - "builduid": "5ba23cb5a5bb4d779d9a0024601083cd", 05:16:58 INFO - "slavebuilddir": "test" 05:16:58 INFO - } 05:16:58 INFO - Found installer url https://queue.taskcluster.net/v1/task/Pq-E40jKTUm8YOp9A8gcKQ/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg. 05:16:58 INFO - Running post-action listener: _resource_record_post_action 05:16:58 INFO - [mozharness: 2016-12-06 13:16:58.825172Z] Finished read-buildbot-config step (success) 05:16:58 INFO - [mozharness: 2016-12-06 13:16:58.825302Z] Running download-and-extract step. 05:16:58 INFO - Running pre-action listener: _resource_record_pre_action 05:16:58 INFO - Running main action method: download_and_extract 05:16:58 INFO - mkdir: /builds/slave/test/build/tests 05:16:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:16:58 INFO - trying https://queue.taskcluster.net/v1/task/Pq-E40jKTUm8YOp9A8gcKQ/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json 05:16:58 INFO - Downloading https://queue.taskcluster.net/v1/task/Pq-E40jKTUm8YOp9A8gcKQ/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json 05:16:58 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Pq-E40jKTUm8YOp9A8gcKQ/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json'}, attempt #1 05:16:59 INFO - Downloaded 1199 bytes. 05:16:59 INFO - Reading from file /builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json 05:16:59 INFO - Using the following test package requirements: 05:16:59 INFO - {u'common': [u'firefox-53.0a1.en-US.mac.common.tests.zip'], 05:16:59 INFO - u'cppunittest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 05:16:59 INFO - u'firefox-53.0a1.en-US.mac.cppunittest.tests.zip'], 05:16:59 INFO - u'gtest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 05:16:59 INFO - u'firefox-53.0a1.en-US.mac.gtest.tests.zip'], 05:16:59 INFO - u'jittest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 05:16:59 INFO - u'jsshell-mac.zip'], 05:16:59 INFO - u'mochitest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 05:16:59 INFO - u'firefox-53.0a1.en-US.mac.mochitest.tests.zip'], 05:16:59 INFO - u'mozbase': [u'firefox-53.0a1.en-US.mac.common.tests.zip'], 05:16:59 INFO - u'reftest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 05:16:59 INFO - u'firefox-53.0a1.en-US.mac.reftest.tests.zip'], 05:16:59 INFO - u'talos': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 05:16:59 INFO - u'firefox-53.0a1.en-US.mac.talos.tests.zip'], 05:16:59 INFO - u'web-platform': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 05:16:59 INFO - u'firefox-53.0a1.en-US.mac.web-platform.tests.zip'], 05:16:59 INFO - u'xpcshell': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 05:16:59 INFO - u'firefox-53.0a1.en-US.mac.xpcshell.tests.zip']} 05:16:59 INFO - Downloading packages: [u'firefox-53.0a1.en-US.mac.common.tests.zip'] for test suite category: common 05:16:59 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Pq-E40jKTUm8YOp9A8gcKQ/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip 05:16:59 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Pq-E40jKTUm8YOp9A8gcKQ/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip'}, attempt #1 05:16:59 INFO - Fetch https://queue.taskcluster.net/v1/task/Pq-E40jKTUm8YOp9A8gcKQ/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip into memory 05:17:01 INFO - Expected file size: 16283859 05:17:01 INFO - Obtained file size: 16283859 05:17:08 INFO - Downloading packages: [u'firefox-53.0a1.en-US.mac.common.tests.zip', u'firefox-53.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 05:17:08 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Pq-E40jKTUm8YOp9A8gcKQ/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip 05:17:08 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Pq-E40jKTUm8YOp9A8gcKQ/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip'}, attempt #1 05:17:08 INFO - Fetch https://queue.taskcluster.net/v1/task/Pq-E40jKTUm8YOp9A8gcKQ/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip into memory 05:17:09 INFO - Expected file size: 16283859 05:17:09 INFO - Obtained file size: 16283859 05:17:15 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Pq-E40jKTUm8YOp9A8gcKQ/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip 05:17:15 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Pq-E40jKTUm8YOp9A8gcKQ/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip'}, attempt #1 05:17:15 INFO - Fetch https://queue.taskcluster.net/v1/task/Pq-E40jKTUm8YOp9A8gcKQ/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip into memory 05:17:17 INFO - Expected file size: 14409734 05:17:17 INFO - Obtained file size: 14409734 05:17:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:17:17 INFO - trying https://queue.taskcluster.net/v1/task/Pq-E40jKTUm8YOp9A8gcKQ/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 05:17:17 INFO - Downloading https://queue.taskcluster.net/v1/task/Pq-E40jKTUm8YOp9A8gcKQ/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg 05:17:17 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Pq-E40jKTUm8YOp9A8gcKQ/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg'}, attempt #1 05:17:20 INFO - Downloaded 107708272 bytes. 05:17:20 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Pq-E40jKTUm8YOp9A8gcKQ/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 05:17:20 INFO - mkdir: /builds/slave/test/properties 05:17:20 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 05:17:20 INFO - Writing to file /builds/slave/test/properties/build_url 05:17:20 INFO - Contents: 05:17:20 INFO - build_url:https://queue.taskcluster.net/v1/task/Pq-E40jKTUm8YOp9A8gcKQ/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 05:17:21 INFO - Running post-action listener: _resource_record_post_action 05:17:21 INFO - Running post-action listener: set_extra_try_arguments 05:17:21 INFO - [mozharness: 2016-12-06 13:17:21.031840Z] Finished download-and-extract step (success) 05:17:21 INFO - [mozharness: 2016-12-06 13:17:21.032001Z] Running populate-webroot step. 05:17:21 INFO - Running pre-action listener: _resource_record_pre_action 05:17:21 INFO - Running main action method: populate_webroot 05:17:21 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 05:17:21 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 05:17:21 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 05:17:21 INFO - '--tppagecycles', 05:17:21 INFO - '7'], 05:17:21 INFO - 'tests': ['tsvgm']}}, 05:17:21 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 05:17:21 INFO - 'tests': ['tresize', 'tcanvasmark']}, 05:17:21 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 05:17:21 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 05:17:21 INFO - 'tests': ['dromaeo_css', 'kraken']}, 05:17:21 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 05:17:21 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 05:17:21 INFO - 'talos_options': ['--disable-e10s'], 05:17:21 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:17:21 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 05:17:21 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:17:21 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 05:17:21 INFO - 'talos_options': ['--disable-e10s'], 05:17:21 INFO - 'tests': ['damp', 'tps']}, 05:17:21 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 05:17:21 INFO - 'tests': ['damp', 'tps']}, 05:17:21 INFO - 'g3': {'talos_options': ['--disable-e10s'], 05:17:21 INFO - 'tests': ['dromaeo_dom']}, 05:17:21 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 05:17:21 INFO - 'g4': {'talos_options': ['--disable-e10s'], 05:17:21 INFO - 'tests': ['basic_compositor_video']}, 05:17:21 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 05:17:21 INFO - 'other': {'talos_options': ['--disable-e10s'], 05:17:21 INFO - 'tests': ['a11yr', 05:17:21 INFO - 'ts_paint', 05:17:21 INFO - 'tpaint', 05:17:21 INFO - 'sessionrestore', 05:17:21 INFO - 'sessionrestore_no_auto_restore', 05:17:21 INFO - 'tabpaint']}, 05:17:21 INFO - 'other-e10s': {'tests': ['a11yr', 05:17:21 INFO - 'ts_paint', 05:17:21 INFO - 'tpaint', 05:17:21 INFO - 'sessionrestore', 05:17:21 INFO - 'sessionrestore_no_auto_restore', 05:17:21 INFO - 'tabpaint']}, 05:17:21 INFO - 'other-e10s_l64': {'tests': ['a11yr', 05:17:21 INFO - 'ts_paint', 05:17:21 INFO - 'tpaint', 05:17:21 INFO - 'sessionrestore', 05:17:21 INFO - 'sessionrestore_no_auto_restore', 05:17:21 INFO - 'tabpaint']}, 05:17:21 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 05:17:21 INFO - 'ts_paint', 05:17:21 INFO - 'tpaint', 05:17:21 INFO - 'sessionrestore', 05:17:21 INFO - 'sessionrestore_no_auto_restore', 05:17:21 INFO - 'tabpaint']}, 05:17:21 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 05:17:21 INFO - 'tests': ['a11yr', 05:17:21 INFO - 'ts_paint', 05:17:21 INFO - 'tpaint', 05:17:21 INFO - 'sessionrestore', 05:17:21 INFO - 'sessionrestore_no_auto_restore', 05:17:21 INFO - 'tabpaint']}, 05:17:21 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 05:17:21 INFO - 'tests': ['a11yr', 05:17:21 INFO - 'ts_paint', 05:17:21 INFO - 'tpaint', 05:17:21 INFO - 'sessionrestore', 05:17:21 INFO - 'sessionrestore_no_auto_restore', 05:17:21 INFO - 'tabpaint']}, 05:17:21 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 05:17:21 INFO - 'tests': ['tsvgx', 05:17:21 INFO - 'tsvgr_opacity', 05:17:21 INFO - 'tart', 05:17:21 INFO - 'tscrollx', 05:17:21 INFO - 'cart', 05:17:21 INFO - 'tsvg_static']}, 05:17:21 INFO - 'svgr-e10s': {'tests': ['tsvgx', 05:17:21 INFO - 'tsvgr_opacity', 05:17:21 INFO - 'tart', 05:17:21 INFO - 'tscrollx', 05:17:21 INFO - 'cart', 05:17:21 INFO - 'tsvg_static']}, 05:17:21 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 05:17:21 INFO - 'talos_options': ['--disable-e10s'], 05:17:21 INFO - 'tests': ['tp5o']}, 05:17:21 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 05:17:21 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 05:17:21 INFO - 'talos_options': ['--disable-e10s', 05:17:21 INFO - '--xperf_path', 05:17:21 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:17:21 INFO - 'tests': ['tp5n']}, 05:17:21 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 05:17:21 INFO - 'talos_options': ['--xperf_path', 05:17:21 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:17:21 INFO - 'tests': ['tp5n']}}, 05:17:21 INFO - 'talos.zip': {'path': '', 05:17:21 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 05:17:21 INFO - Running post-action listener: _resource_record_post_action 05:17:21 INFO - [mozharness: 2016-12-06 13:17:21.050284Z] Finished populate-webroot step (success) 05:17:21 INFO - [mozharness: 2016-12-06 13:17:21.050429Z] Running create-virtualenv step. 05:17:21 INFO - Running pre-action listener: _resource_record_pre_action 05:17:21 INFO - Running main action method: create_virtualenv 05:17:21 INFO - Creating virtualenv /builds/slave/test/build/venv 05:17:21 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 05:17:21 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 05:17:21 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 05:17:21 INFO - Using real prefix '/tools/python27' 05:17:21 INFO - New python executable in /builds/slave/test/build/venv/bin/python 05:17:21 INFO - Installing distribute.............................................................................................................................................................................................done. 05:17:24 INFO - Installing pip.................done. 05:17:24 INFO - Return code: 0 05:17:24 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 05:17:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 05:17:25 INFO - Reading from file tmpfile_stdout 05:17:25 INFO - Output received: 05:17:25 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) 05:17:25 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 05:17:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:17:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:17:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:17:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:17:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:17:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:17:25 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10f41a3f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f390570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8590c61330>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f22fd30>, '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 0x10f096db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f227b20>, '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.h1al2HKYZY/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.GdXX81PhtH/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0_/1mzt9_fn191_pm4dgt9z87g000000w/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 05:17:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 05:17:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 05:17:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.h1al2HKYZY/Render', 05:17:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:17:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:17:25 INFO - 'HOME': '/Users/cltbld', 05:17:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:17:25 INFO - 'LOGNAME': 'cltbld', 05:17:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:17:25 INFO - 'MOZ_NO_REMOTE': '1', 05:17:25 INFO - 'NO_EM_RESTART': '1', 05:17:25 INFO - 'PAGER': '/bin/cat', 05:17:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:17:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:17:25 INFO - 'PWD': '/builds/slave/test', 05:17:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:17:25 INFO - 'SHELL': '/bin/bash', 05:17:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.GdXX81PhtH/Listeners', 05:17:25 INFO - 'TMPDIR': '/var/folders/0_/1mzt9_fn191_pm4dgt9z87g000000w/T/', 05:17:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:17:25 INFO - 'USER': 'cltbld', 05:17:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:17:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:17:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:17:25 INFO - 'XPC_FLAGS': '0x0', 05:17:25 INFO - 'XPC_SERVICE_NAME': '0', 05:17:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:17:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:17:25 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 05:17:25 INFO - Cleaning up... 05:17:25 INFO - Return code: 0 05:17:25 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 05:17:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:17:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:17:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:17:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:17:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:17:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:17:25 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10f41a3f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f390570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8590c61330>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f22fd30>, '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 0x10f096db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f227b20>, '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.h1al2HKYZY/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.GdXX81PhtH/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0_/1mzt9_fn191_pm4dgt9z87g000000w/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 05:17:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 05:17:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 05:17:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.h1al2HKYZY/Render', 05:17:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:17:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:17:25 INFO - 'HOME': '/Users/cltbld', 05:17:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:17:25 INFO - 'LOGNAME': 'cltbld', 05:17:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:17:25 INFO - 'MOZ_NO_REMOTE': '1', 05:17:25 INFO - 'NO_EM_RESTART': '1', 05:17:25 INFO - 'PAGER': '/bin/cat', 05:17:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:17:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:17:25 INFO - 'PWD': '/builds/slave/test', 05:17:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:17:25 INFO - 'SHELL': '/bin/bash', 05:17:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.GdXX81PhtH/Listeners', 05:17:25 INFO - 'TMPDIR': '/var/folders/0_/1mzt9_fn191_pm4dgt9z87g000000w/T/', 05:17:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:17:25 INFO - 'USER': 'cltbld', 05:17:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:17:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:17:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:17:25 INFO - 'XPC_FLAGS': '0x0', 05:17:25 INFO - 'XPC_SERVICE_NAME': '0', 05:17:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:17:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:17:25 INFO - Downloading/unpacking psutil>=3.1.1 05:17:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:17:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:17:25 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:17:25 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:17:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:17:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:17:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 05:17:29 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:17:29 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:17:29 INFO - Installing collected packages: psutil 05:17:29 INFO - Running setup.py install for psutil 05:17:29 INFO - building 'psutil._psutil_osx' extension 05:17:29 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 05:17:30 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 05:17:30 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 05:17:30 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 05:17:30 INFO - building 'psutil._psutil_posix' extension 05:17:30 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 05:17:30 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 05:17:30 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 05:17:30 INFO - ^ 05:17:30 INFO - 1 warning generated. 05:17:30 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 05:17:30 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:17:30 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:17:30 INFO - Successfully installed psutil 05:17:30 INFO - Cleaning up... 05:17:30 INFO - Return code: 0 05:17:30 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 05:17:30 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')]} 05:17:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:17:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:17:30 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')]} 05:17:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:17:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:17:30 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 0x10f41a3f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f390570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8590c61330>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f22fd30>, '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 0x10f096db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f227b20>, '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.h1al2HKYZY/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.GdXX81PhtH/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0_/1mzt9_fn191_pm4dgt9z87g000000w/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 05:17:30 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 05:17:30 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 05:17:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.h1al2HKYZY/Render', 05:17:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:17:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:17:30 INFO - 'HOME': '/Users/cltbld', 05:17:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:17:30 INFO - 'LOGNAME': 'cltbld', 05:17:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:17:30 INFO - 'MOZ_NO_REMOTE': '1', 05:17:30 INFO - 'NO_EM_RESTART': '1', 05:17:30 INFO - 'PAGER': '/bin/cat', 05:17:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:17:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:17:30 INFO - 'PWD': '/builds/slave/test', 05:17:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:17:30 INFO - 'SHELL': '/bin/bash', 05:17:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.GdXX81PhtH/Listeners', 05:17:30 INFO - 'TMPDIR': '/var/folders/0_/1mzt9_fn191_pm4dgt9z87g000000w/T/', 05:17:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:17:30 INFO - 'USER': 'cltbld', 05:17:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:17:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:17:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:17:30 INFO - 'XPC_FLAGS': '0x0', 05:17:30 INFO - 'XPC_SERVICE_NAME': '0', 05:17:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:17:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:17:30 INFO - Downloading/unpacking mozsystemmonitor==0.3 05:17: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 05:17: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 05:17: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 05:17: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 05:17: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 05:17: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 05:17:33 INFO - Downloading mozsystemmonitor-0.3.tar.gz 05:17:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 05:17:33 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 05:17:33 INFO - Installing collected packages: mozsystemmonitor 05:17:33 INFO - Running setup.py install for mozsystemmonitor 05:17:33 INFO - Successfully installed mozsystemmonitor 05:17:33 INFO - Cleaning up... 05:17:33 INFO - Return code: 0 05:17:33 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 05:17:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:17:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:17:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:17:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:17:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:17:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:17:33 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10f41a3f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f390570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8590c61330>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f22fd30>, '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 0x10f096db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f227b20>, '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.h1al2HKYZY/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.GdXX81PhtH/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0_/1mzt9_fn191_pm4dgt9z87g000000w/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 05:17:33 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 05:17:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 05:17:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.h1al2HKYZY/Render', 05:17:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:17:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:17:33 INFO - 'HOME': '/Users/cltbld', 05:17:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:17:33 INFO - 'LOGNAME': 'cltbld', 05:17:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:17:33 INFO - 'MOZ_NO_REMOTE': '1', 05:17:33 INFO - 'NO_EM_RESTART': '1', 05:17:33 INFO - 'PAGER': '/bin/cat', 05:17:33 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:17:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:17:33 INFO - 'PWD': '/builds/slave/test', 05:17:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:17:33 INFO - 'SHELL': '/bin/bash', 05:17:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.GdXX81PhtH/Listeners', 05:17:33 INFO - 'TMPDIR': '/var/folders/0_/1mzt9_fn191_pm4dgt9z87g000000w/T/', 05:17:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:17:33 INFO - 'USER': 'cltbld', 05:17:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:17:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:17:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:17:33 INFO - 'XPC_FLAGS': '0x0', 05:17:33 INFO - 'XPC_SERVICE_NAME': '0', 05:17:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:17:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:17:34 INFO - Downloading/unpacking jsonschema==2.5.1 05:17:34 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 05:17:34 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 05:17:34 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 05:17:34 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 05:17:34 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 05:17:34 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 05:17:36 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 05:17:36 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 05:17:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:17:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:17:36 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:17:36 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:17:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:17:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:17:37 INFO - Downloading functools32-3.2.3-2.tar.gz 05:17:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 05:17:37 INFO - warning: no files found matching '*.txt' 05:17:37 INFO - no previously-included directories found matching 'build' 05:17:37 INFO - no previously-included directories found matching 'dist' 05:17:37 INFO - no previously-included directories found matching '.git*' 05:17:37 INFO - Installing collected packages: jsonschema, functools32 05:17:37 INFO - Running setup.py install for functools32 05:17:37 INFO - warning: no files found matching '*.txt' 05:17:37 INFO - no previously-included directories found matching 'build' 05:17:37 INFO - no previously-included directories found matching 'dist' 05:17:37 INFO - no previously-included directories found matching '.git*' 05:17:37 INFO - Successfully installed jsonschema functools32 05:17:37 INFO - Cleaning up... 05:17:37 INFO - Return code: 0 05:17:37 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 05:17: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')]} 05:17:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:17:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:17: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')]} 05:17:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:17:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:17:37 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 0x10f41a3f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f390570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8590c61330>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f22fd30>, '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 0x10f096db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f227b20>, '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.h1al2HKYZY/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.GdXX81PhtH/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0_/1mzt9_fn191_pm4dgt9z87g000000w/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 05:17:37 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 05:17:37 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 05:17:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.h1al2HKYZY/Render', 05:17:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:17:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:17:37 INFO - 'HOME': '/Users/cltbld', 05:17:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:17:37 INFO - 'LOGNAME': 'cltbld', 05:17:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:17:37 INFO - 'MOZ_NO_REMOTE': '1', 05:17:37 INFO - 'NO_EM_RESTART': '1', 05:17:37 INFO - 'PAGER': '/bin/cat', 05:17:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:17:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:17:37 INFO - 'PWD': '/builds/slave/test', 05:17:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:17:37 INFO - 'SHELL': '/bin/bash', 05:17:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.GdXX81PhtH/Listeners', 05:17:37 INFO - 'TMPDIR': '/var/folders/0_/1mzt9_fn191_pm4dgt9z87g000000w/T/', 05:17:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:17:37 INFO - 'USER': 'cltbld', 05:17:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:17:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:17:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:17:37 INFO - 'XPC_FLAGS': '0x0', 05:17:37 INFO - 'XPC_SERVICE_NAME': '0', 05:17:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:17:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:17:37 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 05:17:37 INFO - Cleaning up... 05:17:37 INFO - Return code: 0 05:17:37 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 05:17: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')]} 05:17:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:17:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:17: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')]} 05:17:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:17:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:17:37 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 0x10f41a3f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f390570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8590c61330>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f22fd30>, '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 0x10f096db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f227b20>, '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.h1al2HKYZY/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.GdXX81PhtH/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0_/1mzt9_fn191_pm4dgt9z87g000000w/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 05:17:37 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 05:17:37 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 05:17:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.h1al2HKYZY/Render', 05:17:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:17:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:17:37 INFO - 'HOME': '/Users/cltbld', 05:17:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:17:37 INFO - 'LOGNAME': 'cltbld', 05:17:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:17:37 INFO - 'MOZ_NO_REMOTE': '1', 05:17:37 INFO - 'NO_EM_RESTART': '1', 05:17:37 INFO - 'PAGER': '/bin/cat', 05:17:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:17:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:17:37 INFO - 'PWD': '/builds/slave/test', 05:17:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:17:37 INFO - 'SHELL': '/bin/bash', 05:17:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.GdXX81PhtH/Listeners', 05:17:37 INFO - 'TMPDIR': '/var/folders/0_/1mzt9_fn191_pm4dgt9z87g000000w/T/', 05:17:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:17:37 INFO - 'USER': 'cltbld', 05:17:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:17:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:17:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:17:37 INFO - 'XPC_FLAGS': '0x0', 05:17:37 INFO - 'XPC_SERVICE_NAME': '0', 05:17:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:17:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:17:38 INFO - Downloading/unpacking blobuploader==1.2.4 05:17:38 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 05:17:38 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 05:17:38 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 05:17:38 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 05:17:38 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 05:17:38 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 05:17:40 INFO - Downloading blobuploader-1.2.4.tar.gz 05:17:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 05:17:40 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 05:17:40 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 05:17:40 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 05:17:40 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 05:17:40 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 05:17:40 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 05:17:40 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 05:17:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 05:17:41 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 05:17:41 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 05:17:41 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 05:17:41 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 05:17:41 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 05:17:41 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 05:17:41 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 05:17:41 INFO - Downloading docopt-0.6.1.tar.gz 05:17:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 05:17:41 INFO - Installing collected packages: blobuploader, requests, docopt 05:17:41 INFO - Running setup.py install for blobuploader 05:17:41 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 05:17:41 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 05:17:41 INFO - Running setup.py install for requests 05:17:42 INFO - Running setup.py install for docopt 05:17:42 INFO - Successfully installed blobuploader requests docopt 05:17:42 INFO - Cleaning up... 05:17:42 INFO - Return code: 0 05:17:42 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:17: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')]} 05:17:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:17:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:17: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')]} 05:17:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:17:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:17:42 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 0x10f41a3f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f390570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8590c61330>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f22fd30>, '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 0x10f096db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f227b20>, '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.h1al2HKYZY/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.GdXX81PhtH/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0_/1mzt9_fn191_pm4dgt9z87g000000w/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 05:17:42 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 05:17:42 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 05:17:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.h1al2HKYZY/Render', 05:17:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:17:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:17:42 INFO - 'HOME': '/Users/cltbld', 05:17:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:17:42 INFO - 'LOGNAME': 'cltbld', 05:17:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:17:42 INFO - 'MOZ_NO_REMOTE': '1', 05:17:42 INFO - 'NO_EM_RESTART': '1', 05:17:42 INFO - 'PAGER': '/bin/cat', 05:17:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:17:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:17:42 INFO - 'PWD': '/builds/slave/test', 05:17:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:17:42 INFO - 'SHELL': '/bin/bash', 05:17:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.GdXX81PhtH/Listeners', 05:17:42 INFO - 'TMPDIR': '/var/folders/0_/1mzt9_fn191_pm4dgt9z87g000000w/T/', 05:17:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:17:42 INFO - 'USER': 'cltbld', 05:17:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:17:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:17:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:17:42 INFO - 'XPC_FLAGS': '0x0', 05:17:42 INFO - 'XPC_SERVICE_NAME': '0', 05:17:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:17:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:17:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:17:43 INFO - Running setup.py (path:/var/folders/0_/1mzt9_fn191_pm4dgt9z87g000000w/T/pip-Xpgsfv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:17:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:17:43 INFO - Running setup.py (path:/var/folders/0_/1mzt9_fn191_pm4dgt9z87g000000w/T/pip-pXtcnA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:17:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:17:43 INFO - Running setup.py (path:/var/folders/0_/1mzt9_fn191_pm4dgt9z87g000000w/T/pip-1nC9OS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:17:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:17:43 INFO - Running setup.py (path:/var/folders/0_/1mzt9_fn191_pm4dgt9z87g000000w/T/pip-HDgUZJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:17:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:17:43 INFO - Running setup.py (path:/var/folders/0_/1mzt9_fn191_pm4dgt9z87g000000w/T/pip-zH4QOd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:17:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:17:43 INFO - Running setup.py (path:/var/folders/0_/1mzt9_fn191_pm4dgt9z87g000000w/T/pip-OvrfwY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:17:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:17:43 INFO - Running setup.py (path:/var/folders/0_/1mzt9_fn191_pm4dgt9z87g000000w/T/pip-5_6pg3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:17:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:17:43 INFO - Running setup.py (path:/var/folders/0_/1mzt9_fn191_pm4dgt9z87g000000w/T/pip-1nDkbK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:17:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:17:43 INFO - Running setup.py (path:/var/folders/0_/1mzt9_fn191_pm4dgt9z87g000000w/T/pip-C8Yp0D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:17:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:17:43 INFO - Running setup.py (path:/var/folders/0_/1mzt9_fn191_pm4dgt9z87g000000w/T/pip-JqgVQK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:17:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:17:44 INFO - Running setup.py (path:/var/folders/0_/1mzt9_fn191_pm4dgt9z87g000000w/T/pip-DKMCMx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:17:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:17:44 INFO - Running setup.py (path:/var/folders/0_/1mzt9_fn191_pm4dgt9z87g000000w/T/pip-cpw2k1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:17:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:17:44 INFO - Running setup.py (path:/var/folders/0_/1mzt9_fn191_pm4dgt9z87g000000w/T/pip-FOd4Uf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:17:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:17:44 INFO - Running setup.py (path:/var/folders/0_/1mzt9_fn191_pm4dgt9z87g000000w/T/pip-7wF2WN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:17:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:17:44 INFO - Running setup.py (path:/var/folders/0_/1mzt9_fn191_pm4dgt9z87g000000w/T/pip-BHx3jn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:17:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:17:44 INFO - Running setup.py (path:/var/folders/0_/1mzt9_fn191_pm4dgt9z87g000000w/T/pip-LBgdA9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:17:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:17:44 INFO - Running setup.py (path:/var/folders/0_/1mzt9_fn191_pm4dgt9z87g000000w/T/pip-1ZrN1n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:17:44 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 05:17:44 INFO - Running setup.py install for manifestparser 05:17:44 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 05:17:44 INFO - Running setup.py install for mozcrash 05:17:45 INFO - Running setup.py install for mozdebug 05:17:45 INFO - Running setup.py install for mozdevice 05:17:45 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 05:17:45 INFO - Installing dm script to /builds/slave/test/build/venv/bin 05:17:45 INFO - Running setup.py install for mozfile 05:17:45 INFO - Running setup.py install for mozhttpd 05:17:45 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 05:17:45 INFO - Running setup.py install for mozinfo 05:17:45 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 05:17:45 INFO - Running setup.py install for mozInstall 05:17:45 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 05:17:45 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 05:17:45 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 05:17:45 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 05:17:45 INFO - Running setup.py install for mozleak 05:17:46 INFO - Running setup.py install for mozlog 05:17:46 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 05:17:46 INFO - Running setup.py install for moznetwork 05:17:46 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 05:17:46 INFO - Running setup.py install for mozprocess 05:17:46 INFO - Running setup.py install for mozprofile 05:17:46 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 05:17:46 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 05:17:46 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 05:17:46 INFO - Running setup.py install for mozrunner 05:17:46 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 05:17:46 INFO - Running setup.py install for mozscreenshot 05:17:47 INFO - Running setup.py install for moztest 05:17:47 INFO - Running setup.py install for mozversion 05:17:47 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 05:17:47 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 05:17:47 INFO - Cleaning up... 05:17:47 INFO - Return code: 0 05:17:47 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:17:47 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')]} 05:17:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:17:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:17:47 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')]} 05:17:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:17:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:17:47 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 0x10f41a3f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f390570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8590c61330>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f22fd30>, '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 0x10f096db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f227b20>, '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.h1al2HKYZY/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.GdXX81PhtH/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0_/1mzt9_fn191_pm4dgt9z87g000000w/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 05:17:47 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 05:17:47 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 05:17:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.h1al2HKYZY/Render', 05:17:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:17:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:17:47 INFO - 'HOME': '/Users/cltbld', 05:17:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:17:47 INFO - 'LOGNAME': 'cltbld', 05:17:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:17:47 INFO - 'MOZ_NO_REMOTE': '1', 05:17:47 INFO - 'NO_EM_RESTART': '1', 05:17:47 INFO - 'PAGER': '/bin/cat', 05:17:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:17:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:17:47 INFO - 'PWD': '/builds/slave/test', 05:17:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:17:47 INFO - 'SHELL': '/bin/bash', 05:17:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.GdXX81PhtH/Listeners', 05:17:47 INFO - 'TMPDIR': '/var/folders/0_/1mzt9_fn191_pm4dgt9z87g000000w/T/', 05:17:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:17:47 INFO - 'USER': 'cltbld', 05:17:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:17:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:17:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:17:47 INFO - 'XPC_FLAGS': '0x0', 05:17:47 INFO - 'XPC_SERVICE_NAME': '0', 05:17:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:17:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:17:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:17:47 INFO - Running setup.py (path:/var/folders/0_/1mzt9_fn191_pm4dgt9z87g000000w/T/pip-JDgslY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:17:47 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)) 05:17:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:17:47 INFO - Running setup.py (path:/var/folders/0_/1mzt9_fn191_pm4dgt9z87g000000w/T/pip-UA4xke-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:17:47 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)) 05:17:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:17:47 INFO - Running setup.py (path:/var/folders/0_/1mzt9_fn191_pm4dgt9z87g000000w/T/pip-rJU0Po-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:17:47 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)) 05:17:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:17:47 INFO - Running setup.py (path:/var/folders/0_/1mzt9_fn191_pm4dgt9z87g000000w/T/pip-IDsy25-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:17:48 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)) 05:17:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:17:48 INFO - Running setup.py (path:/var/folders/0_/1mzt9_fn191_pm4dgt9z87g000000w/T/pip-LGUVOo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:17:48 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)) 05:17:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:17:48 INFO - Running setup.py (path:/var/folders/0_/1mzt9_fn191_pm4dgt9z87g000000w/T/pip-LaXmhb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:17:48 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)) 05:17:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:17:48 INFO - Running setup.py (path:/var/folders/0_/1mzt9_fn191_pm4dgt9z87g000000w/T/pip-8N_BAV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:17:48 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)) 05:17:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:17:48 INFO - Running setup.py (path:/var/folders/0_/1mzt9_fn191_pm4dgt9z87g000000w/T/pip-BhSbAg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:17:48 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)) 05:17:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:17:48 INFO - Running setup.py (path:/var/folders/0_/1mzt9_fn191_pm4dgt9z87g000000w/T/pip-2mYnmT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:17:48 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)) 05:17:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:17:48 INFO - Running setup.py (path:/var/folders/0_/1mzt9_fn191_pm4dgt9z87g000000w/T/pip-5AKKPL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:17:48 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)) 05:17:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:17:48 INFO - Running setup.py (path:/var/folders/0_/1mzt9_fn191_pm4dgt9z87g000000w/T/pip-xLcjbD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:17:48 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)) 05:17:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:17:48 INFO - Running setup.py (path:/var/folders/0_/1mzt9_fn191_pm4dgt9z87g000000w/T/pip-MRdwd6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:17:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 05:17:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:17:48 INFO - Running setup.py (path:/var/folders/0_/1mzt9_fn191_pm4dgt9z87g000000w/T/pip-mI3tjj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:17:48 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)) 05:17:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:17:48 INFO - Running setup.py (path:/var/folders/0_/1mzt9_fn191_pm4dgt9z87g000000w/T/pip-tRQLkY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:17:49 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)) 05:17:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:17:49 INFO - Running setup.py (path:/var/folders/0_/1mzt9_fn191_pm4dgt9z87g000000w/T/pip-RDAD4h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:17:49 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)) 05:17:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:17:49 INFO - Running setup.py (path:/var/folders/0_/1mzt9_fn191_pm4dgt9z87g000000w/T/pip-EkiII4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:17:49 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 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)) 05:17:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:17:49 INFO - Running setup.py (path:/var/folders/0_/1mzt9_fn191_pm4dgt9z87g000000w/T/pip-6WO__6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:17:49 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)) 05:17:49 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)) 05:17:49 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)) 05:17:49 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)) 05:17:49 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)) 05:17:49 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)) 05:17:49 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:17:49 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 05:17:49 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 05:17:49 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 05:17:49 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 05:17:49 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 05:17:49 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 05:17:51 INFO - Downloading blessings-1.6.tar.gz 05:17:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 05:17:52 INFO - Installing collected packages: blessings 05:17:52 INFO - Running setup.py install for blessings 05:17:52 INFO - Successfully installed blessings 05:17:52 INFO - Cleaning up... 05:17:52 INFO - Return code: 0 05:17:52 INFO - Done creating virtualenv /builds/slave/test/build/venv. 05:17:52 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:17:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:17:52 INFO - Reading from file tmpfile_stdout 05:17:52 INFO - Current package versions: 05:17:52 INFO - blessings == 1.6 05:17:52 INFO - blobuploader == 1.2.4 05:17:52 INFO - docopt == 0.6.1 05:17:52 INFO - functools32 == 3.2.3-2 05:17:52 INFO - jsonschema == 2.5.1 05:17:52 INFO - manifestparser == 1.1 05:17:52 INFO - mozInstall == 1.12 05:17:52 INFO - mozcrash == 1.0 05:17:52 INFO - mozdebug == 0.1 05:17:52 INFO - mozdevice == 0.49 05:17:52 INFO - mozfile == 1.2 05:17:52 INFO - mozhttpd == 0.7 05:17:52 INFO - mozinfo == 0.9 05:17:52 INFO - mozleak == 0.1 05:17:52 INFO - mozlog == 3.4 05:17:52 INFO - moznetwork == 0.27 05:17:52 INFO - mozprocess == 0.23 05:17:52 INFO - mozprofile == 0.28 05:17:52 INFO - mozrunner == 6.13 05:17:52 INFO - mozscreenshot == 0.1 05:17:52 INFO - mozsystemmonitor == 0.3 05:17:52 INFO - moztest == 0.7 05:17:52 INFO - mozversion == 1.4 05:17:52 INFO - psutil == 3.1.1 05:17:52 INFO - requests == 1.2.3 05:17:52 INFO - wsgiref == 0.1.2 05:17:52 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:17:52 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')]} 05:17:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:17:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:17:52 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')]} 05:17:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:17:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:17:52 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 0x10f41a3f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f390570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8590c61330>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f22fd30>, '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 0x10f096db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f227b20>, '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.h1al2HKYZY/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.GdXX81PhtH/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0_/1mzt9_fn191_pm4dgt9z87g000000w/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 05:17:52 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 05:17:52 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 05:17:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.h1al2HKYZY/Render', 05:17:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:17:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:17:52 INFO - 'HOME': '/Users/cltbld', 05:17:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:17:52 INFO - 'LOGNAME': 'cltbld', 05:17:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:17:52 INFO - 'MOZ_NO_REMOTE': '1', 05:17:52 INFO - 'NO_EM_RESTART': '1', 05:17:52 INFO - 'PAGER': '/bin/cat', 05:17:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:17:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:17:52 INFO - 'PWD': '/builds/slave/test', 05:17:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:17:52 INFO - 'SHELL': '/bin/bash', 05:17:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.GdXX81PhtH/Listeners', 05:17:52 INFO - 'TMPDIR': '/var/folders/0_/1mzt9_fn191_pm4dgt9z87g000000w/T/', 05:17:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:17:52 INFO - 'USER': 'cltbld', 05:17:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:17:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:17:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:17:52 INFO - 'XPC_FLAGS': '0x0', 05:17:52 INFO - 'XPC_SERVICE_NAME': '0', 05:17:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:17:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:17:52 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)) 05:17:52 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)) 05:17:52 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)) 05:17:52 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)) 05:17:52 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)) 05:17:52 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)) 05:17:52 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)) 05:17:52 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)) 05:17:52 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)) 05:17:52 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)) 05:17:52 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)) 05:17:52 INFO - Cleaning up... 05:17:52 INFO - Return code: 0 05:17:52 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 05:17:52 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')]} 05:17:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:17:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:17:52 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')]} 05:17:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:17:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:17:52 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 0x10f41a3f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f390570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8590c61330>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f22fd30>, '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 0x10f096db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f227b20>, '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.h1al2HKYZY/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.GdXX81PhtH/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0_/1mzt9_fn191_pm4dgt9z87g000000w/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 05:17:52 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 05:17:52 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 05:17:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.h1al2HKYZY/Render', 05:17:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:17:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:17:52 INFO - 'HOME': '/Users/cltbld', 05:17:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:17:52 INFO - 'LOGNAME': 'cltbld', 05:17:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:17:52 INFO - 'MOZ_NO_REMOTE': '1', 05:17:52 INFO - 'NO_EM_RESTART': '1', 05:17:52 INFO - 'PAGER': '/bin/cat', 05:17:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:17:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:17:52 INFO - 'PWD': '/builds/slave/test', 05:17:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:17:52 INFO - 'SHELL': '/bin/bash', 05:17:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.GdXX81PhtH/Listeners', 05:17:52 INFO - 'TMPDIR': '/var/folders/0_/1mzt9_fn191_pm4dgt9z87g000000w/T/', 05:17:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:17:52 INFO - 'USER': 'cltbld', 05:17:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:17:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:17:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:17:52 INFO - 'XPC_FLAGS': '0x0', 05:17:52 INFO - 'XPC_SERVICE_NAME': '0', 05:17:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:17:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:17:53 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 05:17:53 INFO - Cleaning up... 05:17:53 INFO - Return code: 0 05:17:53 INFO - Running post-action listener: _resource_record_post_action 05:17:53 INFO - Running post-action listener: _start_resource_monitoring 05:17:53 INFO - Starting resource monitoring. 05:17:53 INFO - [mozharness: 2016-12-06 13:17:53.184131Z] Finished create-virtualenv step (success) 05:17:53 INFO - [mozharness: 2016-12-06 13:17:53.185134Z] Running install step. 05:17:53 INFO - Running pre-action listener: _resource_record_pre_action 05:17:53 INFO - Running main action method: install 05:17:53 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:17:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:17:53 INFO - Reading from file tmpfile_stdout 05:17:53 INFO - Detecting whether we're running mozinstall >=1.0... 05:17:53 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 05:17:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 05:17:53 INFO - Reading from file tmpfile_stdout 05:17:53 INFO - Output received: 05:17:53 INFO - Usage: mozinstall [options] installer 05:17:53 INFO - Options: 05:17:53 INFO - -h, --help show this help message and exit 05:17:53 INFO - -d DEST, --destination=DEST 05:17:53 INFO - Directory to install application into. [default: 05:17:53 INFO - "/builds/slave/test"] 05:17:53 INFO - --app=APP Application being installed. [default: firefox] 05:17:53 INFO - mkdir: /builds/slave/test/build/application 05:17:53 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 05:17:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 05:18:26 INFO - Reading from file tmpfile_stdout 05:18:26 INFO - Output received: 05:18:26 INFO - /builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox 05:18:26 INFO - Running post-action listener: _resource_record_post_action 05:18:26 INFO - [mozharness: 2016-12-06 13:18:26.012897Z] Finished install step (success) 05:18:26 INFO - [mozharness: 2016-12-06 13:18:26.013033Z] Running run-tests step. 05:18:26 INFO - Running pre-action listener: _resource_record_pre_action 05:18:26 INFO - Running main action method: run_tests 05:18:26 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:18:26 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:18:26 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:18:26 INFO - 2016-12-06 05:18:26.027 screenresolution[1662:12467] starting screenresolution argv=screenresolution get 05:18:26 INFO - 2016-12-06 05:18:26.030 screenresolution[1662:12467] Display 0: 1600x1200x32@60 05:18:26 INFO - 2016-12-06 05:18:26.035 screenresolution[1663:12469] starting screenresolution argv=screenresolution list 05:18:26 INFO - Available Modes on Display 0 05:18:26 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:18:26 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:18:26 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:18:26 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:18:26 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:18:26 INFO - Intel Iris: 05:18:26 INFO - Chipset Model: Intel Iris 05:18:26 INFO - Type: GPU 05:18:26 INFO - Bus: Built-In 05:18:26 INFO - VRAM (Dynamic, Max): 1536 MB 05:18:26 INFO - Vendor: Intel (0x8086) 05:18:26 INFO - Device ID: 0x0a2e 05:18:26 INFO - Revision ID: 0x0009 05:18:26 INFO - Displays: 05:18:26 INFO - Display: 05:18:26 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:18:26 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:18:26 INFO - Display Serial Number: 1600x1200 60 05:18:26 INFO - Main Display: Yes 05:18:26 INFO - Mirror: Off 05:18:26 INFO - Online: Yes 05:18:26 INFO - Rotation: Supported 05:18:26 INFO - Return code: 0 05:18:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 05:18:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 05:18:26 INFO - Python 2.7.3 05:18:26 INFO - Return code: 0 05:18:26 INFO - grabbing minidump binary from tooltool 05:18:26 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')]} 05:18:26 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 0x10f22fd30>, '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 0x10f096db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f227b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 05:18:26 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 05:18:26 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 05:18:26 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 05:18:26 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 05:18:26 INFO - Return code: 0 05:18:26 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 05:18:26 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 05:18:26 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 05:18:26 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 05:18:26 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 05:18:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Pq-E40jKTUm8YOp9A8gcKQ/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0360', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 05:18:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs --executablePath /builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/Pq-E40jKTUm8YOp9A8gcKQ/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0360 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 05:18:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.h1al2HKYZY/Render', 05:18:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:18:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:18:26 INFO - 'HOME': '/Users/cltbld', 05:18:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:18:26 INFO - 'LOGNAME': 'cltbld', 05:18:26 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 05:18:26 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 05:18:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:18:26 INFO - 'MOZ_NO_REMOTE': '1', 05:18:26 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 05:18:26 INFO - 'NO_EM_RESTART': '1', 05:18:26 INFO - 'PAGER': '/bin/cat', 05:18:26 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:18:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:18:26 INFO - 'PWD': '/builds/slave/test', 05:18:26 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 05:18:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:18:26 INFO - 'SHELL': '/bin/bash', 05:18:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.GdXX81PhtH/Listeners', 05:18:26 INFO - 'TMPDIR': '/var/folders/0_/1mzt9_fn191_pm4dgt9z87g000000w/T/', 05:18:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:18:26 INFO - 'USER': 'cltbld', 05:18:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:18:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:18:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:18:26 INFO - 'XPC_FLAGS': '0x0', 05:18:26 INFO - 'XPC_SERVICE_NAME': '0', 05:18:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:18:26 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Pq-E40jKTUm8YOp9A8gcKQ/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0360', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 05:18:26 INFO - mozversion application_buildid: 20161206030203 05:18:26 INFO - mozversion application_changeset: 8103c612b79c2587ea4ca1b0a9f9f82db4b185b8 05:18:26 INFO - mozversion application_display_name: Nightly 05:18:26 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 05:18:26 INFO - mozversion application_name: Firefox 05:18:26 INFO - mozversion application_remotingname: firefox 05:18:26 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 05:18:26 INFO - mozversion application_vendor: Mozilla 05:18:26 INFO - mozversion application_version: 53.0a1 05:18:26 INFO - mozversion platform_buildid: 20161206030203 05:18:26 INFO - mozversion platform_changeset: 8103c612b79c2587ea4ca1b0a9f9f82db4b185b8 05:18:26 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 05:18:26 INFO - mozversion platform_version: 53.0a1 05:18:26 INFO - using testdate: 1481030306 05:18:26 INFO - actual date: 1481030306 05:18:26 INFO - starting webserver on 'localhost:49243' 05:18:26 INFO - SUITE-START | Running 2 tests 05:18:26 INFO - TEST-START | dromaeo_css 05:18:26 INFO - operating with platform_type : mac_ 05:18:26 INFO - Initialising browser for dromaeo_css test... 05:18:26 INFO - TEST-INFO | started process 1673 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/0_/1mzt9_fn191_pm4dgt9z87g000000w/T/tmprNXBKh/profile http://localhost:49243/getInfo.html) 05:18:29 INFO - PROCESS | 1673 | __metrics Screen width/height:1600/1200 05:18:29 INFO - PROCESS | 1673 | colorDepth:24 05:18:29 INFO - PROCESS | 1673 | Browser inner width/height: 1024/689 05:18:29 INFO - PROCESS | 1673 | __metrics 05:18:29 INFO - PROCESS | 1673 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 05:18:29 INFO - PROCESS | 1673 | JavaScript error: resource:///modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 05:18:30 INFO - TEST-INFO | 1673: exit 0 05:18:30 INFO - Browser initialized. 05:18:30 INFO - Running cycle 1/1 for dromaeo_css test... 05:18:30 INFO - TEST-INFO | started process 1677 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/0_/1mzt9_fn191_pm4dgt9z87g000000w/T/tmprNXBKh/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:18:42 INFO - PROCESS | 1677 | RSS: Main: 242921472 05:18:42 INFO - PROCESS | 1677 | 05:19:33 INFO - PROCESS | 1677 | 1481030373962 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 05:21:46 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49243/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49243/tests/dromaeo/cssquery-ext.html) 05:21:48 INFO - PROCESS | 1677 | RSS: Main: 440184832 05:21:48 INFO - PROCESS | 1677 | 05:24:52 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49243/tests/dromaeo/cssquery-ext.html (next: http://localhost:49243/tests/dromaeo/cssquery-jquery.html) 05:24:52 INFO - PROCESS | 1677 | RSS: Main: 369283072 05:24:52 INFO - PROCESS | 1677 | 05:27:55 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49243/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49243/tests/dromaeo/cssquery-mootools.html) 05:27:55 INFO - PROCESS | 1677 | RSS: Main: 336330752 05:27:55 INFO - PROCESS | 1677 | 05:30:58 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49243/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49243/tests/dromaeo/cssquery-prototype.html) 05:30:58 INFO - PROCESS | 1677 | RSS: Main: 343834624 05:30:58 INFO - PROCESS | 1677 | 05:34:02 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49243/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49243/tests/dromaeo/cssquery-yui.html) 05:34:02 INFO - PROCESS | 1677 | RSS: Main: 328261632 05:34:02 INFO - PROCESS | 1677 | 05:37:06 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49243/tests/dromaeo/cssquery-yui.html (next: http://localhost:49243/tests/dromaeo/cssquery-dojo.html) 05:37:06 INFO - PROCESS | 1677 | RSS: Main: 392777728 05:37:06 INFO - PROCESS | 1677 | 05:37:06 INFO - PROCESS | 1677 | __start_tp_report 05:37:06 INFO - PROCESS | 1677 | _x_x_mozilla_page_load 05:37:06 INFO - PROCESS | 1677 | _x_x_mozilla_page_load_details 05:37:06 INFO - PROCESS | 1677 | |i|pagename|runs| 05:37:06 INFO - PROCESS | 1677 | |0;dojo.html;3294.752893532985;3320.8339583020843;3326.5509156263897;3378.831058447077;3396.575428071491;5514.972425137874;5516.889662206753;5602.495775380215;5604.103343465046;5615.185798059284;5580.497755202041;5615.326160860693;5640.74616642251;5645.096784514479;5699.800506982246;4838.830640927568;4860.270959356108;5150.201718733602;5198.09033419152;5237.240600112981;4182.309918863384;4477.9104417911785;5226.294450249225;5253.028189784902;5268.631195816294;4781.760911954406;5224.582033437316;5238.816641417551;5270.051390749663;5325.254464374991;5109.412417571953;5138.665986710889;5147.459516750762;5184.403793563751;5248.0553500369915;5099.031184075014;5151;5165.302684137631;5169.172932330846;5239.659422137549;5564;5584.776608935637;5619.6066275360745;5623.775048998035;5643.351014633328;6177.135201071854;6183.907241391383;6197.752089916398;6206.2862770781485;6209.161763162006;6105.32841387447;6116.5183814016455;6129.664444256052;6145.2178868128185;6196.87606247877;5746.88506229873;5765.538756899438;5766.221560089418;5776.653400795965;5815.854603634901;6103.42017508336;6245.406686364788;6249.562530622904;6252.562320637512;6273.937260627426;5851.180834683148;5920.763169473259;5927.170196172539;6126.08108783686;6141.416565426277;6117.174181485531;6130.948521380002;6136.733532366159;6178.104174894615;6183.598066125687;6103.328633850273;6134.9693251534345;6136.9079463808075;6142.662153581507;6154.169187159676;6080.391960803884;6106.908271785864;6120.287065634178;6137.938620613737;6155.938440615537;4864;4877.292792545132;4883.145449546315;4922.90154196914;4930.580900623487;4380.956190438119;4390.780460976938;4414.3157810539415;4435.489918659396;4445;5710.885782284414;5719.542436605062;5730.255066841284;5731.312242530923;5753.712314384347;6006.309274433496;6115.388461153938;6117.796956032686;6139.3553676863285;6200.100985357189;5589.636673616202;5598.748056337475;5651.886962260814;5689.800856969986;5700.6009579329875;4459.219636563588;5260.868913183684;5313.681179129265;5319;5329.626926115135;5534.584906131976;6176.81469555914;6229.4393504584805;6260.467860231931;6271.059341098689;6117.309816958451;6128.778133445034;6140.166841500503;6164.7842325518395;6197.752089916353;5411.242426060433;6316.242050953915;6360.872782544416;6376.713047912763;6380;5939.287285525765;6128.540359473147;6146.661933593696;6169;6173.753049878134;6097.878042439038;6105.732692890381;6127.830981140938;6132.816015519542;6209.192804935329;5996.610220335664;6101.450869421773;6107.667664879142;6132.78535251264;6134.283518479682;6093;6096.847578810565;6100.664463454375;6101.938980610137;6106.2061931948565;5631.662100273997;5743;5743.310802703702;5800.042992906123;5802.622829516068;5003.699778013331;5943.554233432596;5983.282006159288;5998.70006499682;6000.639961602318;5792.94207057924;5800.216970708901;5815.127730840239;5836.328822185503;5849.473547380756 05:37:06 INFO - PROCESS | 1677 | |1;ext.html;448.80476992508255;505.65362726532305;517.0125061133236;538.3754844380517;538.5664540045267;37173.070673233145;39218.803905980465;39417.60582394179;41036;41076.79461602688;40364;40548;40693.7965310173;40706.59293407065;40791.776246712645;8358.164183581654;9192.172704456581;9347.859782103274;9355.064493550624;9359.157675809176;8423.831523369528;11015.614453494129;11253.212275140744;11275.548978040868;11453.31280123191;11188.049015833663;11228.887711122867;11248.100151987821;11254.718632034224;11263.380514071723;8421.284190843786;11052.668419947375;11149.944250278697;11163.888361116366;11193.440327983568;8441.95779021107;10835.404052777094;11121.666350009513;11177.66467005991;11323.547058117665;37966;38010.669626562936;38770.44918203269;38915.416268755995;39190.60809391898;3316.651751566075;3398.728101751854;3423.623401425841;3587.928241435183;3736;28520.85916563335;29525.114246572637;29661.03389661038;29750.553741693893;30545.541816872767;3153.4008538377634;3166.255929856482;3202.791818531788;3291.5720956275773;3294.901152965415;344460;353608.23195884115;356582;357606.9999999974;357791;51708.74145629323;51925.36522443468;52013.47986520162;52135;52139.73930130401;57547.712261439265;57748;57753;57801.843963120504;57826.42173578295;12870.871291287154;14778.852211477963;15270.541883743506;15337.239296926437;15362.923185384225;10814.134869210444;11079.944600276947;11114.277571957797;11979.640610781691;12070.577529786591;11630.418479076012;13516.5269215577;13922.025458217828;14708.779368309484;14822.332995015251;6993.825154371079;7038.894416583756;7059.152901651834;7131.714731410685;7134.32223938725;774.7056118674867;935.5602866652663;961.423146112341;985.3151111599102;1030.953607087683;23256;27873.58189627157;28127.71872281251;28619.57070643942;29077.854610726812;925.7037747920601;998.7053819123377;1062.2404980438869;1066.7546464313252;1136.3011747775224;11616.361100139404;12727.681807954876;13957.860421395657;14067.296635168404;15137.318820653098;835979;847573;847601;848770.561471924;848929;55291;55743.72128139334;55886;56082.439175607724;56095.43904560902;1326.6484381638872;1360.7890776929592;1371.8079468874366;1373.4368908747365;1379.875811176999;27543.586846196922;27626.861865690542;27750.861245693643;28339;28497.857510713144;47191;47259;47493.575192744276;47530.28704569503;47776.80557986078;572093;572958;574936;577758;577955;22046.88976555107;22629.8868505663;22667.773322267225;24823.627645585693;24825.882835272096;42223;42500.5749942509;43217.783911080245;44003.11993760171;44984.32523512084;33105.23395598256;33862.81480148183;34111.4883276756;34920;36093.27813443769;35227.29545409129;35652.108697281736;36144.638553614124;36566;37302.8134859324;34936.12659683528;35512.46731298981;35550.11124721903;35828;36167.81916090403;29455;29725;31495;31664.366712666077;31693.20766980844;20671;22762.65856012194;23136.768632313462;23322.76677233206;23365 05:37:06 INFO - PROCESS | 1677 | |2;jquery.html;348.66353968420503;368.3553780883454;373.3950999381003;383.26222022606487;404.35909084101695;11280.323180609159;14316.141031538114;14675.339609717561;19430.514237144078;19909.3031743889;18403.07984600768;18562.44312670619;19408.708869366972;19614.705779413285;19618.313359032436;5240.79036838526;5518.503334699876;5529.861753456175;5555.8888822223535;5561.138023606348;4661.673682842203;5496.917546236809;6116;6141.938580614204;6151.231096112986;6100.72546735398;6120.816375508742;6121.816345509619;6131.233595800525;6134.9079763803575;5387.272718183034;5387.757550910218;5454.4545545445535;5457.072297709359;5462.890742185134;5076.416212135578;5293.100172970604;5294.788208471657;5298.629095963284;5299.470052994709;24494.63258051131;24873.62689559658;24901.501969960682;25221.991120355164;25285;2057.6296266671993;2098.517341011576;2145.765068017082;2147.3987283560627;2184.311941738363;9635.762581521429;9804.754881128029;9875.506224688737;10354.741131471697;10445.112165465944;1799.5231263715125;1872.4757068020976;1885.1422602715868;1906.3518403742794;1930.8165724256314;120484;123997;125759;127594.72405275921;128008;10675.946620267005;11449.54201831934;11497;11538.88461115395;11732;14589.270536473347;14610.63473413152;14611.561653150422;14619.342129604192;14628;8075.3539716822515;8364.874526882102;8376.246137847624;8398.244158025807;8491.532965686823;7019.508634395544;7941.324987376022;8151.918480815235;8190.140035296327;8292.87560686578;4462.486814016364;4553.33976573395;4585.312203169551;4621.907561848744;4646.97676511622;1562.5390509799636;1764.6647137043922;1919.3186418821117;1927.8361339286037;1933.1397528100142;682.4301708073715;710.6127160697432;711.9038929744522;788.9566073865992;972.7519482531909;14630.561083167522;20428.0807363669;20667.79332206689;20729.27447539359;20851.687224691337;862.254150160107;870.8998465176459;883.2845395229884;886.7162507997508;889.6396959231522;10629.46852657364;10996.23026388161;11055.170862185207;11066.225364224589;11107.222494425312;556025;556827;556946;563874;567255;96517.03482965221;105038.47480762548;123563;128319;128728.35635821761;821.4660470694097;921.7141489107053;955.5556666150273;955.9330846840794;961.8893827209821;14272.14367137975;18532.444026679223;19163.070406776795;19431.61136777285;19942.60114797725;50126.49873501218;52378.738106309225;53190;53493.46506535041;53502.46497534975;66141.09460297192;68273.63452730818;69096;69434;73476.63261683658;7072.434205263464;7336.449766267445;7663.31030207284;7868.606569671608;8383.371247156365;11594;11966.521339146337;12235.877641223475;12566.371681416076;13159.342032898125;10760.246782725448;11006.927436848417;11178.441077945907;11182.832257516306;11307.547698091985;10868.836967445337;11277.02652035874;11294.378809165575;11392.145589081018;11433.485493152995;10494.947525262325;10555.841662374916;11053.447327633747;11325.433728313716;11544.018758405293;7762.61186940662;7945.8410831784195;8155.79610509742;8203.876941845996;8335.208155225244;8082.959585202037;10612.734681633021;10699.251052426567;11024.062954648945;11215.831762523732 05:37:06 INFO - PROCESS | 1677 | |3;mootools.html;1456.300975531745;1474.1302631447443;1642.7700121982916;1782.349442453505;1831.2308830291286;7962.960185199073;8351.331893448525;8378.874316885252;8379.497230166195;8394.160583941602;7404.518706284101;7652.8852067218895;7669.079710434742;7942.364610831134;8190.631421586028;2252.538229662919;2313.0747700919683;2313.3060081975423;2317.40906068953;2317.6755254264417;2303.4126297794123;2328.1734984080663;2328.348062542491;2349.5888219561593;2352.2355234548754;2279.2706333973133;2283.908643654252;2312.5259321839067;2318.5362927414585;2322.941926451835;2264.841461097724;2288.199130304397;2290.4731911660247;2291.667708182304;2299.8850057497143;2269.704938358003;2281.178775640768;2307.896144673494;2312.4681323295736;2316.154603569702;6472.061551075068;7387.891816227609;7978.800529986739;8192.959035204785;8326.66693332266;4869.342638743759;5026.019926114409;5029.899402011976;5038.722870242135;5045.268436076785;2800.341919648881;3237.1548217516024;3468.1849765305133;3529.1530032791943;3536.840842162109;1037.2635428845529;1068.8236440987228;1078.9136869050458;1081.9675409737642;1096.276457538021;10687.14502839771;10772.838407423895;10800.027997480185;10855.51150198243;10863.945680271707;4452.443444569429;4577.977110114428;4581.541845815392;4691.648126390534;4705.788239529229;7835.804104897423;9027.729168124966;9234.445933244027;9306.581203845708;9337.346385753068;5166.43169251382;5168.8449346519665;5172.120739474299;5178.482151784791;5190.662606930538;4215.3044747616295;4481.126180394857;4619.1685496610935;4691.39011928454;4726.409198850144;3710.7588006779474;3739.364308067635;3809.5238095238096;3828.8468461261236;3838.9424158637644;1095.5289225632896;1223.5411720604773;1266.7783137951005;1271.2182008064974;1279.1877158004734;788.9605519723991;876.5660997806;887.0963736334703;933.4762358682059;937.4515908193647;5465.6447330923365;5527.806526771544;5592.7762889484775;5630.887382252414;5800.854978625568;897.8742975983367;941.6092321686424;945.2343601682659;991.945443000642;1023.5138309303021;3770.132869440044;3771.8114094295174;3802.6957843372466;3898.2398432305995;3900.395438707005;9705.514724263758;10021.248406369552;10028.448435336126;10162.898371016343;10189.847152292721;9710.902890971;9990.850137247948;10071.14395276395;10095.040971107734;10103.968809357311;7346.853062938711;7732.420068494773;7850.8429831403055;7864.567448790372;7875.566843823644;4954;5643.52030077448;5720.628159169641;5792.94207057924;5797.565182611237;9022.323325750725;9187.862182067141;9302.813943721223;9378.67174648884;9493.47785871756;9241.58412871409;9288.535573221177;9482.525873706149;9530.142287194185;9581.712548623553;6123.8162855114415;6544.705488253136;6752.189737231563;6757;7058.54119482232;7616.276453736886;7830.647620856961;7865.134835168013;7942.761717148494;7954.522728636301;6890.138732658418;7002.579845209304;7017.543859649107;7068.257832927468;7159.8926016110845;5871.354151043296;6866.828329291808;6962.860742785218;6999.265077166824;7038.753643622448;6410;6595.439387651912;6675.499337549606;6691.631960242234;6698.464122870061;4789.114013907439;4800.5199480051715;4833.95166048335;4869.6347773917805;4973.179425394914;4169.937450938299;4694.483606803317;4740.6207503398955;4808.471068182567;4931.0630980113665 05:37:06 INFO - PROCESS | 1677 | |4;prototype.html;300.6722672287207;306.32913918518454;309.84507746126934;316.0423915535927;319.66914243757714;4723.8110475581025;5228.764705588248;5305.628605997582;5330.840074797758;5367.382750983638;4939.728314942677;4999.875003124924;5070.442251352358;5116.2069879168785;5116.539511443969;3234.3369609230053;3542.8405721742583;3969.6427321541055;3997.42101869762;4023.8994025149314;3354.5639066921267;4023.859164929228;4024.798760062;4037.555868854424;4062.0860306431114;3672.6878215351726;3893.415987601854;4050.129222217223;4068.9389659155136;4069.532003819554;3337.7997320160625;3362.9831850840833;3744.4008958566496;4045.7572545647063;4089.8159582818694;2932.340223449728;3245.6592057833823;3545.556805399325;3546.368670411614;3572.7141828653644;4913.26301054841;5119.923201151985;5130.256112863613;5164.2253661950635;5228.738563071869;4454.443194600675;4634.725450501105;4660.254359302496;4668.486466488684;4677.625789936831;5555.638883472561;5558.388577256539;5580.665160090408;5619.775208991636;5677.630953987978;3753.7987843889964;3777.2823163598828;3939.330313846653;3962.326404511211;3985.362342025291;5159.14874045782;5585.039928837722;5765.942340576583;5767.788764359531;5840.211571437886;3930.508686414198;3942.704297177666;3947.4276229947072;3949.6445319921345;3966.643002129822;4000.6199411055904;4020.798960051986;4026.8255142223456;4027.5903433797935;4056.290149223874;4142.647874930604;4485.529019452975;4616.30755386688;4664.906701865943;4690.929636055462;4774.734695305747;4960.801567937314;5259.552938000236;5545.61180717354;5547.25112109868;1242.2981015726086;1297;1299.616613099138;1305.106002388367;1306.2097431054265;741.8664640364786;753.9623018849036;869.5174178331087;895.2077411490748;899.487292243415;4329.415528903558;4603.516630753723;4773.498782627774;4808.7595620219545;4914.754262286871;4669.813207471731;5193.545807173998;5204.271402003723;5482.997411889343;5486.807961721321;4522.8240657429305;4544.795484203219;4654.720716757005;4764;4957.702537847741;2489.751024897496;2500.612405077216;2591.4062851346284;2786.732036923195;2800.901968431095;4646;5489.698066606375;5519.558435325164;5543.833684989457;5573.749181286852;4543.704659197141;4968.577670898014;4969.906620543475;5395.7841686332895;5445;4910.53349931763;4948.628852836124;5280.683159010472;5467.371252305956;5490.69801160932;4695.5539223773685;5270.947290527046;5276.472352764693;5311.920321195108;5347.304850369427;4423.646108311263;4967.751612419437;5041.949580504148;5091.752520632321;5283.445238250082;4716.36394508559;4934.28452874324;5061.240813877947;5542.72286385671;5553.306241596352;3367.393869103585;3519.9648003519637;3548.73384496169;3595.352836489457;3747.981260093682;5159.277701121771;5332.920006199987;5595.860103497445;5725.6850873202375;5735.254416925773;4467.5085740567865;5569.777208911598;5581.665100094007;5582.665040097607;5587.994161050888;5203.921941170961;5248.291480650063;5542.836004439113;5577.079781836114;5578.219049333178;4971.980743947554;5218.633981082105;5308.522232999048;5459.235707001102;5464.852381000034;4461.2415889298245;5203.765830537559;5318.361796584435;5446.782128714807;5451.727413629382;5080.339555857715;5306.28365170697;5411.404745478073;5422.5119739223655;5463.098588732814 05:37:06 INFO - PROCESS | 1677 | |5;yui.html;663.6615326183646;733.5781925392897;771.317384115058;785.4266385538555;785.9528428294307;14647.56057318281;16571;17487.387941422054;17531.999999999985;17784.555386115353;15977.600559985976;16028.118453485054;16147.757783633255;16318.347266109342;16465.91767041166;819.3649921310987;843.0136740014168;844.3709436469808;845.045099038086;845.7082306604211;639.0573903492358;672.0557616548757;675.3246753246754;850.4132148817328;851.9275861551745;786.3159051625095;835.6824406725436;874.9606267717968;888.1296329596998;892.2594246775193;881.0748713850463;881.5856547422701;882.6248844241172;885.0618344554729;885.1414128295576;497.6335093095214;518.5773594520454;617.0096994324135;646.3827045171896;655.9376859198369;14710.55868323952;15256.923715381463;15280.083195008336;15593.688126237525;15595.688086238211;13867.306634668226;14018.929905350407;14090.154590724487;14092.718145637133;14097.929510352486;11803;11858.3477908715;11914.46384912681;12013.279203247834;12164.2701437914;3650.34293827111;3659.4693769403552;3709.0727318170457;3790.0524868782804;3838.654521093115;111006;111269.44365278202;118155.227671585;119298.40350798277;120333.99999999913;10709.625163119254;10715.785684286271;10824.26189201511;10902.18233632465;11017.779644407066;20246;20729.170833166503;20997;21742.130314787548;21799.128034878744;7749.112496941806;7853.214678532216;7867.6459559319965;7898.091719452336;8048.0342358917305;4230.302000169938;4230.957690423056;4301.459814133186;4306.935395969063;4317.309230523101;3485.19840436701;3599.046252743025;3639.253952939641;3647.6352364763843;3649.671529562299;635.1552435261092;646.2890820097855;649.6881496881429;650.2489624483729;695.7564852301755;548.2872266054113;564.2439131563642;577.1631134854478;613.6931534232883;633.8193614819754;8925.955370223237;9270.23747616574;9414.95292523533;9438.339316247797;9578.56896439662;558.8016254229772;576.3659974028535;579.2556564814194;582.670791003082;621.6518749500204;4381.539938306496;4672.602828759525;4687.086018226413;4748.169070412664;4748.691335063209;93305.5334723322;93863.53068234616;97280;97856.02143978469;102167;78369.21630783733;82964;85960.57019714861;94202;98415.85643230216;64295.03557446736;74013.62993185107;76643.46713065708;77676;82852;8834.116588341372;9562.66530671451;9574.95212523933;9667.936526982166;9799.284578696117;53097.93804123973;54221.45778542164;56091.15863262136;56732;56753.14870277032;69378.65310673616;78203;80571.79142312988;80720;82718.17281827105;12575.726753561015;12886.871131288568;12960.416781245056;13119.344032798132;13328.866711332763;14184.29078546048;14532.927335363256;14612.904032197415;14641.121532708072;15237.3905043797;13424.342463988301;13565.253911034593;13574.389152487964;13593.59219223425;14292.071015383966;11503.654890353302;13386;13922.234277114856;14102.506412275521;14141.575752727435;12928.870711292766;13324;13327.400266988203;13516.797248041485;13606.319684015958;9555.283353748358;10868.619598314337;10938.289011214563;11226.735147002682;11562.479688413874;11190.496427660937;11342.376169310437;11474.77050459003;11515;11531.884681153417 05:37:06 INFO - PROCESS | 1677 | __end_tp_report 05:37:06 INFO - PROCESS | 1677 | __start_cc_report 05:37:06 INFO - PROCESS | 1677 | _x_x_mozilla_cycle_collect,2192 05:37:06 INFO - PROCESS | 1677 | __end_cc_report 05:37:06 INFO - PROCESS | 1677 | __startTimestamp1481031426667__endTimestamp 05:37:06 INFO - PROCESS | 1677 | 05:37:06 INFO - PROCESS | 1677 | ------- Summary: start ------- 05:37:06 INFO - PROCESS | 1677 | Number of tests: 6 05:37:06 INFO - PROCESS | 1677 | 05:37:06 INFO - PROCESS | 1677 | [#0] dojo.html Cycles:165 Average:5650.42 Median:5800.22 stddev:646.53 (11.1%) stddev-sans-first:621.55 05:37:06 INFO - PROCESS | 1677 | Values: 3294.8 3320.8 3326.6 3378.8 3396.6 5515.0 5516.9 5602.5 5604.1 5615.2 5580.5 5615.3 5640.7 5645.1 5699.8 4838.8 4860.3 5150.2 5198.1 5237.2 4182.3 4477.9 5226.3 5253.0 5268.6 4781.8 5224.6 5238.8 5270.1 5325.3 5109.4 5138.7 5147.5 5184.4 5248.1 5099.0 5151.0 5165.3 5169.2 5239.7 5564.0 5584.8 5619.6 5623.8 5643.4 6177.1 6183.9 6197.8 6206.3 6209.2 6105.3 6116.5 6129.7 6145.2 6196.9 5746.9 5765.5 5766.2 5776.7 5815.9 6103.4 6245.4 6249.6 6252.6 6273.9 5851.2 5920.8 5927.2 6126.1 6141.4 6117.2 6130.9 6136.7 6178.1 6183.6 6103.3 6135.0 6136.9 6142.7 6154.2 6080.4 6106.9 6120.3 6137.9 6155.9 4864.0 4877.3 4883.1 4922.9 4930.6 4381.0 4390.8 4414.3 4435.5 4445.0 5710.9 5719.5 5730.3 5731.3 5753.7 6006.3 6115.4 6117.8 6139.4 6200.1 5589.6 5598.7 5651.9 5689.8 5700.6 4459.2 5260.9 5313.7 5319.0 5329.6 5534.6 6176.8 6229.4 6260.5 6271.1 6117.3 6128.8 6140.2 6164.8 6197.8 5411.2 6316.2 6360.9 6376.7 6380.0 5939.3 6128.5 6146.7 6169.0 6173.8 6097.9 6105.7 6127.8 6132.8 6209.2 5996.6 6101.5 6107.7 6132.8 6134.3 6093.0 6096.8 6100.7 6101.9 6106.2 5631.7 5743.0 5743.3 5800.0 5802.6 5003.7 5943.6 5983.3 5998.7 6000.6 5792.9 5800.2 5815.1 5836.3 5849.5 05:37:06 INFO - PROCESS | 1677 | 05:37:06 INFO - PROCESS | 1677 | [#1] ext.html Cycles:180 Average:70591.06 Median:194417.76 stddev:168764.16 (86.8%) stddev-sans-first:169155.42 05:37:06 INFO - PROCESS | 1677 | Values: 448.8 505.7 517.0 538.4 538.6 37173.1 39218.8 39417.6 41036.0 41076.8 40364.0 40548.0 40693.8 40706.6 40791.8 8358.2 9192.2 9347.9 9355.1 9359.2 8423.8 11015.6 11253.2 11275.5 11453.3 11188.0 11228.9 11248.1 11254.7 11263.4 8421.3 11052.7 11149.9 11163.9 11193.4 8442.0 10835.4 11121.7 11177.7 11323.5 37966.0 38010.7 38770.4 38915.4 39190.6 3316.7 3398.7 3423.6 3587.9 3736.0 28520.9 29525.1 29661.0 29750.6 30545.5 3153.4 3166.3 3202.8 3291.6 3294.9 344460.0 353608.2 356582.0 357607.0 357791.0 51708.7 51925.4 52013.5 52135.0 52139.7 57547.7 57748.0 57753.0 57801.8 57826.4 12870.9 14778.9 15270.5 15337.2 15362.9 10814.1 11079.9 11114.3 11979.6 12070.6 11630.4 13516.5 13922.0 14708.8 14822.3 6993.8 7038.9 7059.2 7131.7 7134.3 774.7 935.6 961.4 985.3 1031.0 23256.0 27873.6 28127.7 28619.6 29077.9 925.7 998.7 1062.2 1066.8 1136.3 11616.4 12727.7 13957.9 14067.3 15137.3 835979.0 847573.0 847601.0 848770.6 848929.0 55291.0 55743.7 55886.0 56082.4 56095.4 1326.6 1360.8 1371.8 1373.4 1379.9 27543.6 27626.9 27750.9 28339.0 28497.9 47191.0 47259.0 47493.6 47530.3 47776.8 572093.0 572958.0 574936.0 577758.0 577955.0 22046.9 22629.9 22667.8 24823.6 24825.9 42223.0 42500.6 43217.8 44003.1 44984.3 33105.2 33862.8 34111.5 34920.0 36093.3 35227.3 35652.1 36144.6 36566.0 37302.8 34936.1 35512.5 35550.1 35828.0 36167.8 29455.0 29725.0 31495.0 31664.4 31693.2 20671.0 22762.7 23136.8 23322.8 23365.0 05:37:06 INFO - PROCESS | 1677 | 05:37:06 INFO - PROCESS | 1677 | [#2] jquery.html Cycles:180 Average:33267.96 Median:358.51 stddev:93739.40 (26147.0%) stddev-sans-first:93969.78 05:37:06 INFO - PROCESS | 1677 | Values: 348.7 368.4 373.4 383.3 404.4 11280.3 14316.1 14675.3 19430.5 19909.3 18403.1 18562.4 19408.7 19614.7 19618.3 5240.8 5518.5 5529.9 5555.9 5561.1 4661.7 5496.9 6116.0 6141.9 6151.2 6100.7 6120.8 6121.8 6131.2 6134.9 5387.3 5387.8 5454.5 5457.1 5462.9 5076.4 5293.1 5294.8 5298.6 5299.5 24494.6 24873.6 24901.5 25222.0 25285.0 2057.6 2098.5 2145.8 2147.4 2184.3 9635.8 9804.8 9875.5 10354.7 10445.1 1799.5 1872.5 1885.1 1906.4 1930.8 120484.0 123997.0 125759.0 127594.7 128008.0 10675.9 11449.5 11497.0 11538.9 11732.0 14589.3 14610.6 14611.6 14619.3 14628.0 8075.4 8364.9 8376.2 8398.2 8491.5 7019.5 7941.3 8151.9 8190.1 8292.9 4462.5 4553.3 4585.3 4621.9 4647.0 1562.5 1764.7 1919.3 1927.8 1933.1 682.4 710.6 711.9 789.0 972.8 14630.6 20428.1 20667.8 20729.3 20851.7 862.3 870.9 883.3 886.7 889.6 10629.5 10996.2 11055.2 11066.2 11107.2 556025.0 556827.0 556946.0 563874.0 567255.0 96517.0 105038.5 123563.0 128319.0 128728.4 821.5 921.7 955.6 955.9 961.9 14272.1 18532.4 19163.1 19431.6 19942.6 50126.5 52378.7 53190.0 53493.5 53502.5 66141.1 68273.6 69096.0 69434.0 73476.6 7072.4 7336.4 7663.3 7868.6 8383.4 11594.0 11966.5 12235.9 12566.4 13159.3 10760.2 11006.9 11178.4 11182.8 11307.5 10868.8 11277.0 11294.4 11392.1 11433.5 10494.9 10555.8 11053.4 11325.4 11544.0 7762.6 7945.8 8155.8 8203.9 8335.2 8083.0 10612.7 10699.3 11024.1 11215.8 05:37:06 INFO - PROCESS | 1677 | 05:37:06 INFO - PROCESS | 1677 | [#3] mootools.html Cycles:180 Average:5363.30 Median:5027.96 stddev:2920.44 (58.1%) stddev-sans-first:2913.87 05:37:06 INFO - PROCESS | 1677 | Values: 1456.3 1474.1 1642.8 1782.3 1831.2 7963.0 8351.3 8378.9 8379.5 8394.2 7404.5 7652.9 7669.1 7942.4 8190.6 2252.5 2313.1 2313.3 2317.4 2317.7 2303.4 2328.2 2328.3 2349.6 2352.2 2279.3 2283.9 2312.5 2318.5 2322.9 2264.8 2288.2 2290.5 2291.7 2299.9 2269.7 2281.2 2307.9 2312.5 2316.2 6472.1 7387.9 7978.8 8193.0 8326.7 4869.3 5026.0 5029.9 5038.7 5045.3 2800.3 3237.2 3468.2 3529.2 3536.8 1037.3 1068.8 1078.9 1082.0 1096.3 10687.1 10772.8 10800.0 10855.5 10863.9 4452.4 4578.0 4581.5 4691.6 4705.8 7835.8 9027.7 9234.4 9306.6 9337.3 5166.4 5168.8 5172.1 5178.5 5190.7 4215.3 4481.1 4619.2 4691.4 4726.4 3710.8 3739.4 3809.5 3828.8 3838.9 1095.5 1223.5 1266.8 1271.2 1279.2 789.0 876.6 887.1 933.5 937.5 5465.6 5527.8 5592.8 5630.9 5800.9 897.9 941.6 945.2 991.9 1023.5 3770.1 3771.8 3802.7 3898.2 3900.4 9705.5 10021.2 10028.4 10162.9 10189.8 9710.9 9990.9 10071.1 10095.0 10104.0 7346.9 7732.4 7850.8 7864.6 7875.6 4954.0 5643.5 5720.6 5792.9 5797.6 9022.3 9187.9 9302.8 9378.7 9493.5 9241.6 9288.5 9482.5 9530.1 9581.7 6123.8 6544.7 6752.2 6757.0 7058.5 7616.3 7830.6 7865.1 7942.8 7954.5 6890.1 7002.6 7017.5 7068.3 7159.9 5871.4 6866.8 6962.9 6999.3 7038.8 6410.0 6595.4 6675.5 6691.6 6698.5 4789.1 4800.5 4834.0 4869.6 4973.2 4169.9 4694.5 4740.6 4808.5 4931.1 05:37:06 INFO - PROCESS | 1677 | 05:37:06 INFO - PROCESS | 1677 | [#4] prototype.html Cycles:180 Average:4381.11 Median:4924.52 stddev:1325.07 (26.9%) stddev-sans-first:1292.91 05:37:06 INFO - PROCESS | 1677 | Values: 300.7 306.3 309.8 316.0 319.7 4723.8 5228.8 5305.6 5330.8 5367.4 4939.7 4999.9 5070.4 5116.2 5116.5 3234.3 3542.8 3969.6 3997.4 4023.9 3354.6 4023.9 4024.8 4037.6 4062.1 3672.7 3893.4 4050.1 4068.9 4069.5 3337.8 3363.0 3744.4 4045.8 4089.8 2932.3 3245.7 3545.6 3546.4 3572.7 4913.3 5119.9 5130.3 5164.2 5228.7 4454.4 4634.7 4660.3 4668.5 4677.6 5555.6 5558.4 5580.7 5619.8 5677.6 3753.8 3777.3 3939.3 3962.3 3985.4 5159.1 5585.0 5765.9 5767.8 5840.2 3930.5 3942.7 3947.4 3949.6 3966.6 4000.6 4020.8 4026.8 4027.6 4056.3 4142.6 4485.5 4616.3 4664.9 4690.9 4774.7 4960.8 5259.6 5545.6 5547.3 1242.3 1297.0 1299.6 1305.1 1306.2 741.9 754.0 869.5 895.2 899.5 4329.4 4603.5 4773.5 4808.8 4914.8 4669.8 5193.5 5204.3 5483.0 5486.8 4522.8 4544.8 4654.7 4764.0 4957.7 2489.8 2500.6 2591.4 2786.7 2800.9 4646.0 5489.7 5519.6 5543.8 5573.7 4543.7 4968.6 4969.9 5395.8 5445.0 4910.5 4948.6 5280.7 5467.4 5490.7 4695.6 5270.9 5276.5 5311.9 5347.3 4423.6 4967.8 5041.9 5091.8 5283.4 4716.4 4934.3 5061.2 5542.7 5553.3 3367.4 3520.0 3548.7 3595.4 3748.0 5159.3 5332.9 5595.9 5725.7 5735.3 4467.5 5569.8 5581.7 5582.7 5588.0 5203.9 5248.3 5542.8 5577.1 5578.2 4972.0 5218.6 5308.5 5459.2 5464.9 4461.2 5203.8 5318.4 5446.8 5451.7 5080.3 5306.3 5411.4 5422.5 5463.1 05:37:06 INFO - PROCESS | 1677 | 05:37:06 INFO - PROCESS | 1677 | [#5] yui.html Cycles:180 Average:20989.30 Median:4527.07 stddev:30306.22 (669.4%) stddev-sans-first:30352.81 05:37:06 INFO - PROCESS | 1677 | Values: 663.7 733.6 771.3 785.4 786.0 14647.6 16571.0 17487.4 17532.0 17784.6 15977.6 16028.1 16147.8 16318.3 16465.9 819.4 843.0 844.4 845.0 845.7 639.1 672.1 675.3 850.4 851.9 786.3 835.7 875.0 888.1 892.3 881.1 881.6 882.6 885.1 885.1 497.6 518.6 617.0 646.4 655.9 14710.6 15256.9 15280.1 15593.7 15595.7 13867.3 14018.9 14090.2 14092.7 14097.9 11803.0 11858.3 11914.5 12013.3 12164.3 3650.3 3659.5 3709.1 3790.1 3838.7 111006.0 111269.4 118155.2 119298.4 120334.0 10709.6 10715.8 10824.3 10902.2 11017.8 20246.0 20729.2 20997.0 21742.1 21799.1 7749.1 7853.2 7867.6 7898.1 8048.0 4230.3 4231.0 4301.5 4306.9 4317.3 3485.2 3599.0 3639.3 3647.6 3649.7 635.2 646.3 649.7 650.2 695.8 548.3 564.2 577.2 613.7 633.8 8926.0 9270.2 9415.0 9438.3 9578.6 558.8 576.4 579.3 582.7 621.7 4381.5 4672.6 4687.1 4748.2 4748.7 93305.5 93863.5 97280.0 97856.0 102167.0 78369.2 82964.0 85960.6 94202.0 98415.9 64295.0 74013.6 76643.5 77676.0 82852.0 8834.1 9562.7 9575.0 9667.9 9799.3 53097.9 54221.5 56091.2 56732.0 56753.1 69378.7 78203.0 80571.8 80720.0 82718.2 12575.7 12886.9 12960.4 13119.3 13328.9 14184.3 14532.9 14612.9 14641.1 15237.4 13424.3 13565.3 13574.4 13593.6 14292.1 11503.7 13386.0 13922.2 14102.5 14141.6 12928.9 13324.0 13327.4 13516.8 13606.3 9555.3 10868.6 10938.3 11226.7 11562.5 11190.5 11342.4 11474.8 11515.0 11531.9 05:37:06 INFO - PROCESS | 1677 | -------- Summary: end -------- 05:37:06 INFO - PROCESS | 1677 | 05:37:07 INFO - TEST-INFO | 1677: exit 0 05:37:07 INFO - TEST-OK | dromaeo_css | took 1120709ms 05:37:07 INFO - TEST-START | kraken 05:37:07 INFO - operating with platform_type : mac_ 05:37:07 INFO - Initialising browser for kraken test... 05:37:07 INFO - TEST-INFO | started process 1684 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/0_/1mzt9_fn191_pm4dgt9z87g000000w/T/tmpmuM2OT/profile http://localhost:49243/getInfo.html) 05:37:10 INFO - PROCESS | 1684 | __metrics Screen width/height:1600/1200 05:37:10 INFO - PROCESS | 1684 | colorDepth:24 05:37:10 INFO - PROCESS | 1684 | Browser inner width/height: 1024/689 05:37:10 INFO - PROCESS | 1684 | __metrics 05:37:10 INFO - PROCESS | 1684 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 05:37:10 INFO - PROCESS | 1684 | JavaScript error: resource:///modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 05:37:11 INFO - TEST-INFO | 1684: exit 0 05:37:11 INFO - Browser initialized. 05:37:11 INFO - Running cycle 1/1 for kraken test... 05:37:11 INFO - TEST-INFO | started process 1686 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/0_/1mzt9_fn191_pm4dgt9z87g000000w/T/tmpmuM2OT/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:37:23 INFO - PROCESS | 1686 | RSS: Main: 242814976 05:37:23 INFO - PROCESS | 1686 | 05:37:26 INFO - PROCESS | 1686 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49243/tests/kraken/driver.html?testName=audio-beat-detection) 05:37:27 INFO - PROCESS | 1686 | RSS: Main: 293797888 05:37:27 INFO - PROCESS | 1686 | 05:37:29 INFO - PROCESS | 1686 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49243/tests/kraken/driver.html?testName=audio-dft) 05:37:30 INFO - PROCESS | 1686 | RSS: Main: 294711296 05:37:30 INFO - PROCESS | 1686 | 05:37:33 INFO - PROCESS | 1686 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49243/tests/kraken/driver.html?testName=audio-fft) 05:37:34 INFO - PROCESS | 1686 | RSS: Main: 386646016 05:37:34 INFO - PROCESS | 1686 | 05:37:36 INFO - PROCESS | 1686 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49243/tests/kraken/driver.html?testName=audio-oscillator) 05:37:37 INFO - PROCESS | 1686 | RSS: Main: 291491840 05:37:37 INFO - PROCESS | 1686 | 05:37:39 INFO - PROCESS | 1686 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49243/tests/kraken/driver.html?testName=imaging-gaussian-blur) 05:37:39 INFO - PROCESS | 1686 | RSS: Main: 293486592 05:37:39 INFO - PROCESS | 1686 | 05:37:42 INFO - PROCESS | 1686 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49243/tests/kraken/driver.html?testName=imaging-darkroom) 05:37:43 INFO - PROCESS | 1686 | RSS: Main: 336977920 05:37:43 INFO - PROCESS | 1686 | 05:37:46 INFO - PROCESS | 1686 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49243/tests/kraken/driver.html?testName=imaging-desaturate) 05:37:46 INFO - PROCESS | 1686 | RSS: Main: 337567744 05:37:46 INFO - PROCESS | 1686 | 05:37:49 INFO - PROCESS | 1686 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49243/tests/kraken/driver.html?testName=json-parse-financial) 05:37:50 INFO - PROCESS | 1686 | RSS: Main: 335654912 05:37:50 INFO - PROCESS | 1686 | 05:37:51 INFO - PROCESS | 1686 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49243/tests/kraken/driver.html?testName=json-stringify-tinderbox) 05:37:52 INFO - PROCESS | 1686 | RSS: Main: 309166080 05:37:52 INFO - PROCESS | 1686 | 05:37:54 INFO - PROCESS | 1686 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49243/tests/kraken/driver.html?testName=stanford-crypto-aes) 05:37:55 INFO - PROCESS | 1686 | RSS: Main: 312758272 05:37:55 INFO - PROCESS | 1686 | 05:37:57 INFO - PROCESS | 1686 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49243/tests/kraken/driver.html?testName=stanford-crypto-ccm) 05:37:57 INFO - PROCESS | 1686 | RSS: Main: 314834944 05:37:57 INFO - PROCESS | 1686 | 05:38:00 INFO - PROCESS | 1686 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49243/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 05:38:01 INFO - PROCESS | 1686 | RSS: Main: 331935744 05:38:01 INFO - PROCESS | 1686 | 05:38:04 INFO - PROCESS | 1686 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49243/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 05:38:05 INFO - PROCESS | 1686 | RSS: Main: 325554176 05:38:05 INFO - PROCESS | 1686 | 05:38:07 INFO - PROCESS | 1686 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49243/tests/kraken/driver.html?testName=ai-astar) 05:38:07 INFO - PROCESS | 1686 | RSS: Main: 369541120 05:38:07 INFO - PROCESS | 1686 | 05:38:07 INFO - PROCESS | 1686 | __start_tp_report 05:38:07 INFO - PROCESS | 1686 | _x_x_mozilla_page_load 05:38:07 INFO - PROCESS | 1686 | _x_x_mozilla_page_load_details 05:38:07 INFO - PROCESS | 1686 | |i|pagename|runs| 05:38:07 INFO - PROCESS | 1686 | |0;ai-astar;111;111;111;120;166;161;111;111;111;111 05:38:07 INFO - PROCESS | 1686 | |1;audio-beat-detection;151;117;114;152;115;115;114;112;135;114 05:38:07 INFO - PROCESS | 1686 | |2;audio-dft;176;182;188;230;181;179;192;266;182;183 05:38:07 INFO - PROCESS | 1686 | |3;audio-fft;79;59;62;86;61;59;61;77;75;58 05:38:07 INFO - PROCESS | 1686 | |4;audio-oscillator;91;69;86;70;68;69;81;68;69;83 05:38:07 INFO - PROCESS | 1686 | |5;imaging-gaussian-blur;87;84;87;84;84;83;87;84;87;91 05:38:07 INFO - PROCESS | 1686 | |6;imaging-darkroom;92;106;150;109;93;92;92;93;92;93 05:38:07 INFO - PROCESS | 1686 | |7;imaging-desaturate;84;84;84;84;86;85;85;84;85;81 05:38:07 INFO - PROCESS | 1686 | |8;json-parse-financial;56;54;53;53;54;56;55;53;52;54 05:38:07 INFO - PROCESS | 1686 | |9;json-stringify-tinderbox;64;67;72;74;72;68;69;72;69;69 05:38:07 INFO - PROCESS | 1686 | |10;stanford-crypto-aes;89;88;86;85;87;86;87;88;88;88 05:38:07 INFO - PROCESS | 1686 | |11;stanford-crypto-ccm;151;150;151;151;153;156;151;147;197;163 05:38:07 INFO - PROCESS | 1686 | |12;stanford-crypto-pbkdf2;175;175;211;238;174;170;174;175;180;171 05:38:07 INFO - PROCESS | 1686 | |13;stanford-crypto-sha256-iterative;69;70;68;70;70;70;68;69;69;69 05:38:07 INFO - PROCESS | 1686 | __end_tp_report 05:38:07 INFO - PROCESS | 1686 | __start_cc_report 05:38:07 INFO - PROCESS | 1686 | _x_x_mozilla_cycle_collect,2021 05:38:07 INFO - PROCESS | 1686 | __end_cc_report 05:38:07 INFO - PROCESS | 1686 | __startTimestamp1481031487297__endTimestamp 05:38:07 INFO - PROCESS | 1686 | 05:38:07 INFO - PROCESS | 1686 | ------- Summary: start ------- 05:38:07 INFO - PROCESS | 1686 | Number of tests: 14 05:38:07 INFO - PROCESS | 1686 | 05:38:07 INFO - PROCESS | 1686 | [#0] ai-astar Cycles:10 Average:122.40 Median:111.00 stddev:21.87 (19.7%) stddev-sans-first:22.81 05:38:07 INFO - PROCESS | 1686 | Values: 111.0 111.0 111.0 120.0 166.0 161.0 111.0 111.0 111.0 111.0 05:38:07 INFO - PROCESS | 1686 | 05:38:07 INFO - PROCESS | 1686 | [#1] audio-beat-detection Cycles:10 Average:123.90 Median:116.00 stddev:15.95 (13.7%) stddev-sans-first:13.57 05:38:07 INFO - PROCESS | 1686 | Values: 151.0 117.0 114.0 152.0 115.0 115.0 114.0 112.0 135.0 114.0 05:38:07 INFO - PROCESS | 1686 | 05:38:07 INFO - PROCESS | 1686 | [#2] audio-dft Cycles:10 Average:195.90 Median:185.50 stddev:29.08 (15.7%) stddev-sans-first:29.94 05:38:07 INFO - PROCESS | 1686 | Values: 176.0 182.0 188.0 230.0 181.0 179.0 192.0 266.0 182.0 183.0 05:38:07 INFO - PROCESS | 1686 | 05:38:07 INFO - PROCESS | 1686 | [#3] audio-fft Cycles:10 Average:67.70 Median:68.50 stddev:10.38 (15.2%) stddev-sans-first:10.17 05:38:07 INFO - PROCESS | 1686 | Values: 79.0 59.0 62.0 86.0 61.0 59.0 61.0 77.0 75.0 58.0 05:38:07 INFO - PROCESS | 1686 | 05:38:07 INFO - PROCESS | 1686 | [#4] audio-oscillator Cycles:10 Average:75.40 Median:75.50 stddev:8.86 (11.7%) stddev-sans-first:7.38 05:38:07 INFO - PROCESS | 1686 | Values: 91.0 69.0 86.0 70.0 68.0 69.0 81.0 68.0 69.0 83.0 05:38:07 INFO - PROCESS | 1686 | 05:38:07 INFO - PROCESS | 1686 | [#5] imaging-gaussian-blur Cycles:10 Average:85.80 Median:87.00 stddev:2.44 (2.8%) stddev-sans-first:2.55 05:38:07 INFO - PROCESS | 1686 | Values: 87.0 84.0 87.0 84.0 84.0 83.0 87.0 84.0 87.0 91.0 05:38:07 INFO - PROCESS | 1686 | 05:38:07 INFO - PROCESS | 1686 | [#6] imaging-darkroom Cycles:10 Average:101.20 Median:92.00 stddev:18.27 (19.9%) stddev-sans-first:19.08 05:38:07 INFO - PROCESS | 1686 | Values: 92.0 106.0 150.0 109.0 93.0 92.0 92.0 93.0 92.0 93.0 05:38:07 INFO - PROCESS | 1686 | 05:38:07 INFO - PROCESS | 1686 | [#7] imaging-desaturate Cycles:10 Average:84.20 Median:84.50 stddev:1.32 (1.6%) stddev-sans-first:1.39 05:38:07 INFO - PROCESS | 1686 | Values: 84.0 84.0 84.0 84.0 86.0 85.0 85.0 84.0 85.0 81.0 05:38:07 INFO - PROCESS | 1686 | 05:38:07 INFO - PROCESS | 1686 | [#8] json-parse-financial Cycles:10 Average:54.00 Median:54.00 stddev:1.33 (2.5%) stddev-sans-first:1.20 05:38:07 INFO - PROCESS | 1686 | Values: 56.0 54.0 53.0 53.0 54.0 56.0 55.0 53.0 52.0 54.0 05:38:07 INFO - PROCESS | 1686 | 05:38:07 INFO - PROCESS | 1686 | [#9] json-stringify-tinderbox Cycles:10 Average:69.60 Median:70.50 stddev:2.95 (4.2%) stddev-sans-first:2.33 05:38:07 INFO - PROCESS | 1686 | Values: 64.0 67.0 72.0 74.0 72.0 68.0 69.0 72.0 69.0 69.0 05:38:07 INFO - PROCESS | 1686 | 05:38:07 INFO - PROCESS | 1686 | [#10] stanford-crypto-aes Cycles:10 Average:87.20 Median:88.00 stddev:1.23 (1.4%) stddev-sans-first:1.12 05:38:07 INFO - PROCESS | 1686 | Values: 89.0 88.0 86.0 85.0 87.0 86.0 87.0 88.0 88.0 88.0 05:38:07 INFO - PROCESS | 1686 | 05:38:07 INFO - PROCESS | 1686 | [#11] stanford-crypto-ccm Cycles:10 Average:157.00 Median:152.00 stddev:14.70 (9.7%) stddev-sans-first:15.44 05:38:07 INFO - PROCESS | 1686 | Values: 151.0 150.0 151.0 151.0 153.0 156.0 151.0 147.0 197.0 163.0 05:38:07 INFO - PROCESS | 1686 | 05:38:07 INFO - PROCESS | 1686 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:184.30 Median:175.00 stddev:22.28 (12.7%) stddev-sans-first:23.38 05:38:07 INFO - PROCESS | 1686 | Values: 175.0 175.0 211.0 238.0 174.0 170.0 174.0 175.0 180.0 171.0 05:38:07 INFO - PROCESS | 1686 | 05:38:07 INFO - PROCESS | 1686 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:69.20 Median:69.50 stddev:0.79 (1.1%) stddev-sans-first:0.83 05:38:07 INFO - PROCESS | 1686 | Values: 69.0 70.0 68.0 70.0 70.0 70.0 68.0 69.0 69.0 69.0 05:38:07 INFO - PROCESS | 1686 | -------- Summary: end -------- 05:38:07 INFO - PROCESS | 1686 | 05:38:08 INFO - TEST-INFO | 1686: exit 0 05:38:08 INFO - TEST-OK | kraken | took 61237ms 05:38:08 INFO - SUITE-END | took 1181s 05:38:08 INFO - Completed test suite (00:19:42) 05:38:08 INFO - javascript benchmark 05:38:09 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3294.752893532985, 3320.8339583020843, 3326.5509156263897, 3378.831058447077, 3396.575428071491, 5514.972425137874, 5516.889662206753, 5602.495775380215, 5604.103343465046, 5615.185798059284, 5580.497755202041, 5615.326160860693, 5640.74616642251, 5645.096784514479, 5699.800506982246, 4838.830640927568, 4860.270959356108, 5150.201718733602, 5198.09033419152, 5237.240600112981, 4182.309918863384, 4477.9104417911785, 5226.294450249225, 5253.028189784902, 5268.631195816294, 4781.760911954406, 5224.582033437316, 5238.816641417551, 5270.051390749663, 5325.254464374991, 5109.412417571953, 5138.665986710889, 5147.459516750762, 5184.403793563751, 5248.0553500369915, 5099.031184075014, 5151.0, 5165.302684137631, 5169.172932330846, 5239.659422137549, 5564.0, 5584.776608935637, 5619.6066275360745, 5623.775048998035, 5643.351014633328, 6177.135201071854, 6183.907241391383, 6197.752089916398, 6206.2862770781485, 6209.161763162006, 6105.32841387447, 6116.5183814016455, 6129.664444256052, 6145.2178868128185, 6196.87606247877, 5746.88506229873, 5765.538756899438, 5766.221560089418, 5776.653400795965, 5815.854603634901, 6103.42017508336, 6245.406686364788, 6249.562530622904, 6252.562320637512, 6273.937260627426, 5851.180834683148, 5920.763169473259, 5927.170196172539, 6126.08108783686, 6141.416565426277, 6117.174181485531, 6130.948521380002, 6136.733532366159, 6178.104174894615, 6183.598066125687, 6103.328633850273, 6134.9693251534345, 6136.9079463808075, 6142.662153581507, 6154.169187159676, 6080.391960803884, 6106.908271785864, 6120.287065634178, 6137.938620613737, 6155.938440615537, 4864.0, 4877.292792545132, 4883.145449546315, 4922.90154196914, 4930.580900623487, 4380.956190438119, 4390.780460976938, 4414.3157810539415, 4435.489918659396, 4445.0, 5710.885782284414, 5719.542436605062, 5730.255066841284, 5731.312242530923, 5753.712314384347, 6006.309274433496, 6115.388461153938, 6117.796956032686, 6139.3553676863285, 6200.100985357189, 5589.636673616202, 5598.748056337475, 5651.886962260814, 5689.800856969986, 5700.6009579329875, 4459.219636563588, 5260.868913183684, 5313.681179129265, 5319.0, 5329.626926115135, 5534.584906131976, 6176.81469555914, 6229.4393504584805, 6260.467860231931, 6271.059341098689, 6117.309816958451, 6128.778133445034, 6140.166841500503, 6164.7842325518395, 6197.752089916353, 5411.242426060433, 6316.242050953915, 6360.872782544416, 6376.713047912763, 6380.0, 5939.287285525765, 6128.540359473147, 6146.661933593696, 6169.0, 6173.753049878134, 6097.878042439038, 6105.732692890381, 6127.830981140938, 6132.816015519542, 6209.192804935329, 5996.610220335664, 6101.450869421773, 6107.667664879142, 6132.78535251264, 6134.283518479682, 6093.0, 6096.847578810565, 6100.664463454375, 6101.938980610137, 6106.2061931948565, 5631.662100273997, 5743.0, 5743.310802703702, 5800.042992906123, 5802.622829516068, 5003.699778013331, 5943.554233432596, 5983.282006159288, 5998.70006499682, 6000.639961602318, 5792.94207057924, 5800.216970708901, 5815.127730840239, 5836.328822185503, 5849.473547380756], "value": 5609.607174467327, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [448.80476992508255, 505.65362726532305, 517.0125061133236, 538.3754844380517, 538.5664540045267, 37173.070673233145, 39218.803905980465, 39417.60582394179, 41036.0, 41076.79461602688, 40364.0, 40548.0, 40693.7965310173, 40706.59293407065, 40791.776246712645, 8358.164183581654, 9192.172704456581, 9347.859782103274, 9355.064493550624, 9359.157675809176, 8423.831523369528, 11015.614453494129, 11253.212275140744, 11275.548978040868, 11453.31280123191, 11188.049015833663, 11228.887711122867, 11248.100151987821, 11254.718632034224, 11263.380514071723, 8421.284190843786, 11052.668419947375, 11149.944250278697, 11163.888361116366, 11193.440327983568, 8441.95779021107, 10835.404052777094, 11121.666350009513, 11177.66467005991, 11323.547058117665, 37966.0, 38010.669626562936, 38770.44918203269, 38915.416268755995, 39190.60809391898, 3316.651751566075, 3398.728101751854, 3423.623401425841, 3587.928241435183, 3736.0, 28520.85916563335, 29525.114246572637, 29661.03389661038, 29750.553741693893, 30545.541816872767, 3153.4008538377634, 3166.255929856482, 3202.791818531788, 3291.5720956275773, 3294.901152965415, 344460.0, 353608.23195884115, 356582.0, 357606.9999999974, 357791.0, 51708.74145629323, 51925.36522443468, 52013.47986520162, 52135.0, 52139.73930130401, 57547.712261439265, 57748.0, 57753.0, 57801.843963120504, 57826.42173578295, 12870.871291287154, 14778.852211477963, 15270.541883743506, 15337.239296926437, 15362.923185384225, 10814.134869210444, 11079.944600276947, 11114.277571957797, 11979.640610781691, 12070.577529786591, 11630.418479076012, 13516.5269215577, 13922.025458217828, 14708.779368309484, 14822.332995015251, 6993.825154371079, 7038.894416583756, 7059.152901651834, 7131.714731410685, 7134.32223938725, 774.7056118674867, 935.5602866652663, 961.423146112341, 985.3151111599102, 1030.953607087683, 23256.0, 27873.58189627157, 28127.71872281251, 28619.57070643942, 29077.854610726812, 925.7037747920601, 998.7053819123377, 1062.2404980438869, 1066.7546464313252, 1136.3011747775224, 11616.361100139404, 12727.681807954876, 13957.860421395657, 14067.296635168404, 15137.318820653098, 835979.0, 847573.0, 847601.0, 848770.561471924, 848929.0, 55291.0, 55743.72128139334, 55886.0, 56082.439175607724, 56095.43904560902, 1326.6484381638872, 1360.7890776929592, 1371.8079468874366, 1373.4368908747365, 1379.875811176999, 27543.586846196922, 27626.861865690542, 27750.861245693643, 28339.0, 28497.857510713144, 47191.0, 47259.0, 47493.575192744276, 47530.28704569503, 47776.80557986078, 572093.0, 572958.0, 574936.0, 577758.0, 577955.0, 22046.88976555107, 22629.8868505663, 22667.773322267225, 24823.627645585693, 24825.882835272096, 42223.0, 42500.5749942509, 43217.783911080245, 44003.11993760171, 44984.32523512084, 33105.23395598256, 33862.81480148183, 34111.4883276756, 34920.0, 36093.27813443769, 35227.29545409129, 35652.108697281736, 36144.638553614124, 36566.0, 37302.8134859324, 34936.12659683528, 35512.46731298981, 35550.11124721903, 35828.0, 36167.81916090403, 29455.0, 29725.0, 31495.0, 31664.366712666077, 31693.20766980844, 20671.0, 22762.65856012194, 23136.768632313462, 23322.76677233206, 23365.0], "value": 19074.272736788676, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [348.66353968420503, 368.3553780883454, 373.3950999381003, 383.26222022606487, 404.35909084101695, 11280.323180609159, 14316.141031538114, 14675.339609717561, 19430.514237144078, 19909.3031743889, 18403.07984600768, 18562.44312670619, 19408.708869366972, 19614.705779413285, 19618.313359032436, 5240.79036838526, 5518.503334699876, 5529.861753456175, 5555.8888822223535, 5561.138023606348, 4661.673682842203, 5496.917546236809, 6116.0, 6141.938580614204, 6151.231096112986, 6100.72546735398, 6120.816375508742, 6121.816345509619, 6131.233595800525, 6134.9079763803575, 5387.272718183034, 5387.757550910218, 5454.4545545445535, 5457.072297709359, 5462.890742185134, 5076.416212135578, 5293.100172970604, 5294.788208471657, 5298.629095963284, 5299.470052994709, 24494.63258051131, 24873.62689559658, 24901.501969960682, 25221.991120355164, 25285.0, 2057.6296266671993, 2098.517341011576, 2145.765068017082, 2147.3987283560627, 2184.311941738363, 9635.762581521429, 9804.754881128029, 9875.506224688737, 10354.741131471697, 10445.112165465944, 1799.5231263715125, 1872.4757068020976, 1885.1422602715868, 1906.3518403742794, 1930.8165724256314, 120484.0, 123997.0, 125759.0, 127594.72405275921, 128008.0, 10675.946620267005, 11449.54201831934, 11497.0, 11538.88461115395, 11732.0, 14589.270536473347, 14610.63473413152, 14611.561653150422, 14619.342129604192, 14628.0, 8075.3539716822515, 8364.874526882102, 8376.246137847624, 8398.244158025807, 8491.532965686823, 7019.508634395544, 7941.324987376022, 8151.918480815235, 8190.140035296327, 8292.87560686578, 4462.486814016364, 4553.33976573395, 4585.312203169551, 4621.907561848744, 4646.97676511622, 1562.5390509799636, 1764.6647137043922, 1919.3186418821117, 1927.8361339286037, 1933.1397528100142, 682.4301708073715, 710.6127160697432, 711.9038929744522, 788.9566073865992, 972.7519482531909, 14630.561083167522, 20428.0807363669, 20667.79332206689, 20729.27447539359, 20851.687224691337, 862.254150160107, 870.8998465176459, 883.2845395229884, 886.7162507997508, 889.6396959231522, 10629.46852657364, 10996.23026388161, 11055.170862185207, 11066.225364224589, 11107.222494425312, 556025.0, 556827.0, 556946.0, 563874.0, 567255.0, 96517.03482965221, 105038.47480762548, 123563.0, 128319.0, 128728.35635821761, 821.4660470694097, 921.7141489107053, 955.5556666150273, 955.9330846840794, 961.8893827209821, 14272.14367137975, 18532.444026679223, 19163.070406776795, 19431.61136777285, 19942.60114797725, 50126.49873501218, 52378.738106309225, 53190.0, 53493.46506535041, 53502.46497534975, 66141.09460297192, 68273.63452730818, 69096.0, 69434.0, 73476.63261683658, 7072.434205263464, 7336.449766267445, 7663.31030207284, 7868.606569671608, 8383.371247156365, 11594.0, 11966.521339146337, 12235.877641223475, 12566.371681416076, 13159.342032898125, 10760.246782725448, 11006.927436848417, 11178.441077945907, 11182.832257516306, 11307.547698091985, 10868.836967445337, 11277.02652035874, 11294.378809165575, 11392.145589081018, 11433.485493152995, 10494.947525262325, 10555.841662374916, 11053.447327633747, 11325.433728313716, 11544.018758405293, 7762.61186940662, 7945.8410831784195, 8155.79610509742, 8203.876941845996, 8335.208155225244, 8082.959585202037, 10612.734681633021, 10699.251052426567, 11024.062954648945, 11215.831762523732], "value": 9175.696636128974, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1456.300975531745, 1474.1302631447443, 1642.7700121982916, 1782.349442453505, 1831.2308830291286, 7962.960185199073, 8351.331893448525, 8378.874316885252, 8379.497230166195, 8394.160583941602, 7404.518706284101, 7652.8852067218895, 7669.079710434742, 7942.364610831134, 8190.631421586028, 2252.538229662919, 2313.0747700919683, 2313.3060081975423, 2317.40906068953, 2317.6755254264417, 2303.4126297794123, 2328.1734984080663, 2328.348062542491, 2349.5888219561593, 2352.2355234548754, 2279.2706333973133, 2283.908643654252, 2312.5259321839067, 2318.5362927414585, 2322.941926451835, 2264.841461097724, 2288.199130304397, 2290.4731911660247, 2291.667708182304, 2299.8850057497143, 2269.704938358003, 2281.178775640768, 2307.896144673494, 2312.4681323295736, 2316.154603569702, 6472.061551075068, 7387.891816227609, 7978.800529986739, 8192.959035204785, 8326.66693332266, 4869.342638743759, 5026.019926114409, 5029.899402011976, 5038.722870242135, 5045.268436076785, 2800.341919648881, 3237.1548217516024, 3468.1849765305133, 3529.1530032791943, 3536.840842162109, 1037.2635428845529, 1068.8236440987228, 1078.9136869050458, 1081.9675409737642, 1096.276457538021, 10687.14502839771, 10772.838407423895, 10800.027997480185, 10855.51150198243, 10863.945680271707, 4452.443444569429, 4577.977110114428, 4581.541845815392, 4691.648126390534, 4705.788239529229, 7835.804104897423, 9027.729168124966, 9234.445933244027, 9306.581203845708, 9337.346385753068, 5166.43169251382, 5168.8449346519665, 5172.120739474299, 5178.482151784791, 5190.662606930538, 4215.3044747616295, 4481.126180394857, 4619.1685496610935, 4691.39011928454, 4726.409198850144, 3710.7588006779474, 3739.364308067635, 3809.5238095238096, 3828.8468461261236, 3838.9424158637644, 1095.5289225632896, 1223.5411720604773, 1266.7783137951005, 1271.2182008064974, 1279.1877158004734, 788.9605519723991, 876.5660997806, 887.0963736334703, 933.4762358682059, 937.4515908193647, 5465.6447330923365, 5527.806526771544, 5592.7762889484775, 5630.887382252414, 5800.854978625568, 897.8742975983367, 941.6092321686424, 945.2343601682659, 991.945443000642, 1023.5138309303021, 3770.132869440044, 3771.8114094295174, 3802.6957843372466, 3898.2398432305995, 3900.395438707005, 9705.514724263758, 10021.248406369552, 10028.448435336126, 10162.898371016343, 10189.847152292721, 9710.902890971, 9990.850137247948, 10071.14395276395, 10095.040971107734, 10103.968809357311, 7346.853062938711, 7732.420068494773, 7850.8429831403055, 7864.567448790372, 7875.566843823644, 4954.0, 5643.52030077448, 5720.628159169641, 5792.94207057924, 5797.565182611237, 9022.323325750725, 9187.862182067141, 9302.813943721223, 9378.67174648884, 9493.47785871756, 9241.58412871409, 9288.535573221177, 9482.525873706149, 9530.142287194185, 9581.712548623553, 6123.8162855114415, 6544.705488253136, 6752.189737231563, 6757.0, 7058.54119482232, 7616.276453736886, 7830.647620856961, 7865.134835168013, 7942.761717148494, 7954.522728636301, 6890.138732658418, 7002.579845209304, 7017.543859649107, 7068.257832927468, 7159.8926016110845, 5871.354151043296, 6866.828329291808, 6962.860742785218, 6999.265077166824, 7038.753643622448, 6410.0, 6595.439387651912, 6675.499337549606, 6691.631960242234, 6698.464122870061, 4789.114013907439, 4800.5199480051715, 4833.95166048335, 4869.6347773917805, 4973.179425394914, 4169.937450938299, 4694.483606803317, 4740.6207503398955, 4808.471068182567, 4931.0630980113665], "value": 4372.412625011694, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [300.6722672287207, 306.32913918518454, 309.84507746126934, 316.0423915535927, 319.66914243757714, 4723.8110475581025, 5228.764705588248, 5305.628605997582, 5330.840074797758, 5367.382750983638, 4939.728314942677, 4999.875003124924, 5070.442251352358, 5116.2069879168785, 5116.539511443969, 3234.3369609230053, 3542.8405721742583, 3969.6427321541055, 3997.42101869762, 4023.8994025149314, 3354.5639066921267, 4023.859164929228, 4024.798760062, 4037.555868854424, 4062.0860306431114, 3672.6878215351726, 3893.415987601854, 4050.129222217223, 4068.9389659155136, 4069.532003819554, 3337.7997320160625, 3362.9831850840833, 3744.4008958566496, 4045.7572545647063, 4089.8159582818694, 2932.340223449728, 3245.6592057833823, 3545.556805399325, 3546.368670411614, 3572.7141828653644, 4913.26301054841, 5119.923201151985, 5130.256112863613, 5164.2253661950635, 5228.738563071869, 4454.443194600675, 4634.725450501105, 4660.254359302496, 4668.486466488684, 4677.625789936831, 5555.638883472561, 5558.388577256539, 5580.665160090408, 5619.775208991636, 5677.630953987978, 3753.7987843889964, 3777.2823163598828, 3939.330313846653, 3962.326404511211, 3985.362342025291, 5159.14874045782, 5585.039928837722, 5765.942340576583, 5767.788764359531, 5840.211571437886, 3930.508686414198, 3942.704297177666, 3947.4276229947072, 3949.6445319921345, 3966.643002129822, 4000.6199411055904, 4020.798960051986, 4026.8255142223456, 4027.5903433797935, 4056.290149223874, 4142.647874930604, 4485.529019452975, 4616.30755386688, 4664.906701865943, 4690.929636055462, 4774.734695305747, 4960.801567937314, 5259.552938000236, 5545.61180717354, 5547.25112109868, 1242.2981015726086, 1297.0, 1299.616613099138, 1305.106002388367, 1306.2097431054265, 741.8664640364786, 753.9623018849036, 869.5174178331087, 895.2077411490748, 899.487292243415, 4329.415528903558, 4603.516630753723, 4773.498782627774, 4808.7595620219545, 4914.754262286871, 4669.813207471731, 5193.545807173998, 5204.271402003723, 5482.997411889343, 5486.807961721321, 4522.8240657429305, 4544.795484203219, 4654.720716757005, 4764.0, 4957.702537847741, 2489.751024897496, 2500.612405077216, 2591.4062851346284, 2786.732036923195, 2800.901968431095, 4646.0, 5489.698066606375, 5519.558435325164, 5543.833684989457, 5573.749181286852, 4543.704659197141, 4968.577670898014, 4969.906620543475, 5395.7841686332895, 5445.0, 4910.53349931763, 4948.628852836124, 5280.683159010472, 5467.371252305956, 5490.69801160932, 4695.5539223773685, 5270.947290527046, 5276.472352764693, 5311.920321195108, 5347.304850369427, 4423.646108311263, 4967.751612419437, 5041.949580504148, 5091.752520632321, 5283.445238250082, 4716.36394508559, 4934.28452874324, 5061.240813877947, 5542.72286385671, 5553.306241596352, 3367.393869103585, 3519.9648003519637, 3548.73384496169, 3595.352836489457, 3747.981260093682, 5159.277701121771, 5332.920006199987, 5595.860103497445, 5725.6850873202375, 5735.254416925773, 4467.5085740567865, 5569.777208911598, 5581.665100094007, 5582.665040097607, 5587.994161050888, 5203.921941170961, 5248.291480650063, 5542.836004439113, 5577.079781836114, 5578.219049333178, 4971.980743947554, 5218.633981082105, 5308.522232999048, 5459.235707001102, 5464.852381000034, 4461.2415889298245, 5203.765830537559, 5318.361796584435, 5446.782128714807, 5451.727413629382, 5080.339555857715, 5306.28365170697, 5411.404745478073, 5422.5119739223655, 5463.098588732814], "value": 3958.1551688633745, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [663.6615326183646, 733.5781925392897, 771.317384115058, 785.4266385538555, 785.9528428294307, 14647.56057318281, 16571.0, 17487.387941422054, 17531.999999999985, 17784.555386115353, 15977.600559985976, 16028.118453485054, 16147.757783633255, 16318.347266109342, 16465.91767041166, 819.3649921310987, 843.0136740014168, 844.3709436469808, 845.045099038086, 845.7082306604211, 639.0573903492358, 672.0557616548757, 675.3246753246754, 850.4132148817328, 851.9275861551745, 786.3159051625095, 835.6824406725436, 874.9606267717968, 888.1296329596998, 892.2594246775193, 881.0748713850463, 881.5856547422701, 882.6248844241172, 885.0618344554729, 885.1414128295576, 497.6335093095214, 518.5773594520454, 617.0096994324135, 646.3827045171896, 655.9376859198369, 14710.55868323952, 15256.923715381463, 15280.083195008336, 15593.688126237525, 15595.688086238211, 13867.306634668226, 14018.929905350407, 14090.154590724487, 14092.718145637133, 14097.929510352486, 11803.0, 11858.3477908715, 11914.46384912681, 12013.279203247834, 12164.2701437914, 3650.34293827111, 3659.4693769403552, 3709.0727318170457, 3790.0524868782804, 3838.654521093115, 111006.0, 111269.44365278202, 118155.227671585, 119298.40350798277, 120333.99999999913, 10709.625163119254, 10715.785684286271, 10824.26189201511, 10902.18233632465, 11017.779644407066, 20246.0, 20729.170833166503, 20997.0, 21742.130314787548, 21799.128034878744, 7749.112496941806, 7853.214678532216, 7867.6459559319965, 7898.091719452336, 8048.0342358917305, 4230.302000169938, 4230.957690423056, 4301.459814133186, 4306.935395969063, 4317.309230523101, 3485.19840436701, 3599.046252743025, 3639.253952939641, 3647.6352364763843, 3649.671529562299, 635.1552435261092, 646.2890820097855, 649.6881496881429, 650.2489624483729, 695.7564852301755, 548.2872266054113, 564.2439131563642, 577.1631134854478, 613.6931534232883, 633.8193614819754, 8925.955370223237, 9270.23747616574, 9414.95292523533, 9438.339316247797, 9578.56896439662, 558.8016254229772, 576.3659974028535, 579.2556564814194, 582.670791003082, 621.6518749500204, 4381.539938306496, 4672.602828759525, 4687.086018226413, 4748.169070412664, 4748.691335063209, 93305.5334723322, 93863.53068234616, 97280.0, 97856.02143978469, 102167.0, 78369.21630783733, 82964.0, 85960.57019714861, 94202.0, 98415.85643230216, 64295.03557446736, 74013.62993185107, 76643.46713065708, 77676.0, 82852.0, 8834.116588341372, 9562.66530671451, 9574.95212523933, 9667.936526982166, 9799.284578696117, 53097.93804123973, 54221.45778542164, 56091.15863262136, 56732.0, 56753.14870277032, 69378.65310673616, 78203.0, 80571.79142312988, 80720.0, 82718.17281827105, 12575.726753561015, 12886.871131288568, 12960.416781245056, 13119.344032798132, 13328.866711332763, 14184.29078546048, 14532.927335363256, 14612.904032197415, 14641.121532708072, 15237.3905043797, 13424.342463988301, 13565.253911034593, 13574.389152487964, 13593.59219223425, 14292.071015383966, 11503.654890353302, 13386.0, 13922.234277114856, 14102.506412275521, 14141.575752727435, 12928.870711292766, 13324.0, 13327.400266988203, 13516.797248041485, 13606.319684015958, 9555.283353748358, 10868.619598314337, 10938.289011214563, 11226.735147002682, 11562.479688413874, 11190.496427660937, 11342.376169310437, 11474.77050459003, 11515.0, 11531.884681153417], "value": 7455.775983883973, "unit": "score"}], "name": "dromaeo_css", "value": 7086.996774826805, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [111.0, 111.0, 111.0, 120.0, 166.0, 161.0, 111.0, 111.0, 111.0, 111.0], "value": 122.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [151.0, 117.0, 114.0, 152.0, 115.0, 115.0, 114.0, 112.0, 135.0, 114.0], "value": 123.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [176.0, 182.0, 188.0, 230.0, 181.0, 179.0, 192.0, 266.0, 182.0, 183.0], "value": 195.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 59.0, 62.0, 86.0, 61.0, 59.0, 61.0, 77.0, 75.0, 58.0], "value": 67.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 69.0, 86.0, 70.0, 68.0, 69.0, 81.0, 68.0, 69.0, 83.0], "value": 75.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 84.0, 87.0, 84.0, 84.0, 83.0, 87.0, 84.0, 87.0, 91.0], "value": 85.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 106.0, 150.0, 109.0, 93.0, 92.0, 92.0, 93.0, 92.0, 93.0], "value": 101.2, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 84.0, 84.0, 84.0, 86.0, 85.0, 85.0, 84.0, 85.0, 81.0], "value": 84.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 54.0, 53.0, 53.0, 54.0, 56.0, 55.0, 53.0, 52.0, 54.0], "value": 54.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 67.0, 72.0, 74.0, 72.0, 68.0, 69.0, 72.0, 69.0, 69.0], "value": 69.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 88.0, 86.0, 85.0, 87.0, 86.0, 87.0, 88.0, 88.0, 88.0], "value": 87.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [151.0, 150.0, 151.0, 151.0, 153.0, 156.0, 151.0, 147.0, 197.0, 163.0], "value": 157.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [175.0, 175.0, 211.0, 238.0, 174.0, 170.0, 174.0, 175.0, 180.0, 171.0], "value": 184.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 70.0, 68.0, 70.0, 70.0, 70.0, 68.0, 69.0, 69.0, 69.0], "value": 69.2, "unit": "score"}], "name": "kraken", "value": 1477.8000000000002, "alertThreshold": 2.0}]} 05:38:09 INFO - Return code: 0 05:38:09 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 05:38:09 INFO - # TBPL SUCCESS # 05:38:09 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:38:09 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:38:09 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:38:09 INFO - 2016-12-06 05:38:09.145 screenresolution[1690:14802] starting screenresolution argv=screenresolution get 05:38:09 INFO - 2016-12-06 05:38:09.147 screenresolution[1690:14802] Display 0: 1600x1200x32@60 05:38:09 INFO - 2016-12-06 05:38:09.153 screenresolution[1691:14805] starting screenresolution argv=screenresolution list 05:38:09 INFO - Available Modes on Display 0 05:38:09 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:38:09 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:38:09 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:38:09 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:38:09 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:38:09 INFO - Intel Iris: 05:38:09 INFO - Chipset Model: Intel Iris 05:38:09 INFO - Type: GPU 05:38:09 INFO - Bus: Built-In 05:38:09 INFO - VRAM (Dynamic, Max): 1536 MB 05:38:09 INFO - Vendor: Intel (0x8086) 05:38:09 INFO - Device ID: 0x0a2e 05:38:09 INFO - Revision ID: 0x0009 05:38:09 INFO - Displays: 05:38:09 INFO - Display: 05:38:09 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:38:09 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:38:09 INFO - Display Serial Number: 1600x1200 60 05:38:09 INFO - Main Display: Yes 05:38:09 INFO - Mirror: Off 05:38:09 INFO - Online: Yes 05:38:09 INFO - Rotation: Supported 05:38:09 INFO - Return code: 0 05:38:09 INFO - Running post-action listener: _resource_record_post_action 05:38:09 INFO - [mozharness: 2016-12-06 13:38:09.390111Z] Finished run-tests step (success) 05:38:09 INFO - Running post-run listener: _resource_record_post_run 05:38:09 INFO - Total resource usage - Wall time: 1215s; CPU: 54.0%; Read bytes: 30923776; Write bytes: 697231360; Read time: 191; Write time: 1976 05:38:09 INFO - TinderboxPrint: CPU usage
53.6% 05:38:09 INFO - TinderboxPrint: I/O read bytes / time
30,923,776 / 191 05:38:09 INFO - TinderboxPrint: I/O write bytes / time
697,231,360 / 1,976 05:38:09 INFO - TinderboxPrint: CPU idle
2,258.0 (46.4%) 05:38:09 INFO - TinderboxPrint: CPU system
967.4 (19.9%) 05:38:09 INFO - TinderboxPrint: CPU user
1,636.3 (33.7%) 05:38:09 INFO - TinderboxPrint: Swap in / out
410,955,776 / 0 05:38:09 INFO - install - Wall time: 33s; CPU: 54.0%; Read bytes: 367000064; Write bytes: 370226176; Read time: 27944; Write time: 775 05:38:09 INFO - run-tests - Wall time: 1183s; CPU: 54.0%; Read bytes: 30034944; Write bytes: 308524032; Read time: 178; Write time: 1149 05:38:09 INFO - Running post-run listener: _upload_blobber_files 05:38:09 INFO - Blob upload gear active. 05:38:09 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 05:38:09 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 05:38:09 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', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 05:38:09 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 Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 05:38:10 INFO - (blobuploader) - INFO - Open directory for files ... 05:38:10 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 05:38:10 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:38:10 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:38:11 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 05:38:11 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:38:11 INFO - (blobuploader) - INFO - Done attempting. 05:38:11 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 05:38:11 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:38:11 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:38:12 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 05:38:12 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:38:12 INFO - (blobuploader) - INFO - Done attempting. 05:38:12 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 05:38:12 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:38:12 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:38:14 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 05:38:14 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:38:14 INFO - (blobuploader) - INFO - Done attempting. 05:38:14 INFO - (blobuploader) - INFO - Iteration through files over. 05:38:14 INFO - Return code: 0 05:38:14 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 05:38:14 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 05:38:14 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b62f980be52509a18582db5ef73e5ccb2b558cd0442cc78ed65a7dcb6f6fc97b7a609371273804528e0cd67b54bffa89bbbc48d0af9ea3b0b7b52fe66a350e4f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f65026a50355f9f8269d788af36e2a4dc6abac781a74ea27ad95c2c64add03deb343ddabef0566f66cc108ad39663e7cfbe43021f6dba39ab40b60db2793288b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7e544f79707820c0702eb55c3f540662e235a1e4cd816e1b69101467a0b2644f1bfe8eff42aff9b1b5e99166b05259a77c7500e723fc3995a835d6660244c881"} 05:38:14 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 05:38:14 INFO - Writing to file /builds/slave/test/properties/blobber_files 05:38:14 INFO - Contents: 05:38:14 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b62f980be52509a18582db5ef73e5ccb2b558cd0442cc78ed65a7dcb6f6fc97b7a609371273804528e0cd67b54bffa89bbbc48d0af9ea3b0b7b52fe66a350e4f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f65026a50355f9f8269d788af36e2a4dc6abac781a74ea27ad95c2c64add03deb343ddabef0566f66cc108ad39663e7cfbe43021f6dba39ab40b60db2793288b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7e544f79707820c0702eb55c3f540662e235a1e4cd816e1b69101467a0b2644f1bfe8eff42aff9b1b5e99166b05259a77c7500e723fc3995a835d6660244c881"} 05:38:14 INFO - Running post-run listener: copy_logs_to_upload_dir 05:38:14 INFO - Copying logs to upload dir... 05:38:14 INFO - mkdir: /builds/slave/test/build/upload/logs 05:38:14 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1278.307221 ========= master_lag: 0.28 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 18 secs) (at 2016-12-06 05:38:14.925384) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-06 05:38:14.928816) ========= 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.h1al2HKYZY/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.GdXX81PhtH/Listeners TMPDIR=/var/folders/0_/1mzt9_fn191_pm4dgt9z87g000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b62f980be52509a18582db5ef73e5ccb2b558cd0442cc78ed65a7dcb6f6fc97b7a609371273804528e0cd67b54bffa89bbbc48d0af9ea3b0b7b52fe66a350e4f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f65026a50355f9f8269d788af36e2a4dc6abac781a74ea27ad95c2c64add03deb343ddabef0566f66cc108ad39663e7cfbe43021f6dba39ab40b60db2793288b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7e544f79707820c0702eb55c3f540662e235a1e4cd816e1b69101467a0b2644f1bfe8eff42aff9b1b5e99166b05259a77c7500e723fc3995a835d6660244c881"} build_url:https://queue.taskcluster.net/v1/task/Pq-E40jKTUm8YOp9A8gcKQ/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011166 build_url: 'https://queue.taskcluster.net/v1/task/Pq-E40jKTUm8YOp9A8gcKQ/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b62f980be52509a18582db5ef73e5ccb2b558cd0442cc78ed65a7dcb6f6fc97b7a609371273804528e0cd67b54bffa89bbbc48d0af9ea3b0b7b52fe66a350e4f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f65026a50355f9f8269d788af36e2a4dc6abac781a74ea27ad95c2c64add03deb343ddabef0566f66cc108ad39663e7cfbe43021f6dba39ab40b60db2793288b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7e544f79707820c0702eb55c3f540662e235a1e4cd816e1b69101467a0b2644f1bfe8eff42aff9b1b5e99166b05259a77c7500e723fc3995a835d6660244c881"}' ========= master_lag: 0.06 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-06 05:38:14.995763) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 3 secs) (at 2016-12-06 05:38:14.996080) ========= 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.h1al2HKYZY/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.GdXX81PhtH/Listeners TMPDIR=/var/folders/0_/1mzt9_fn191_pm4dgt9z87g000000w/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.005165 ========= master_lag: 3.24 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 3 secs) (at 2016-12-06 05:38:18.239926) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-06 05:38:18.240259) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-06 05:38:18.241773) ========= ========= Total master_lag: 3.65 =========