builder: mozilla-central_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0111 starttime: 1489420920.13 results: success (0) buildid: 20170313072445 builduid: ad41c4f65eb94f29b1ed0d4ffd4f0bf4 revision: 8d9fd089cabd435590250c5e15b781c06a96f0a1 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-03-13 09:02:00.134296) ========= master: http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-03-13 09:02:00.134770) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-13 09:02:00.135044) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-13 09:02:00.160777) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-13 09:02:00.161190) ========= 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.xQdOQDhDM8/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.cE9aYYpqHB/Listeners TMPDIR=/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2017-03-13 09:02:00-- 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% 70.4M=0s 2017-03-13 09:02:00 (70.4 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.133627 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-13 09:02:00.318326) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-13 09:02:00.318678) ========= 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.xQdOQDhDM8/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.cE9aYYpqHB/Listeners TMPDIR=/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/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.020808 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-13 09:02:00.367298) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 4 secs) (at 2017-03-13 09:02:00.367661) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 8d9fd089cabd435590250c5e15b781c06a96f0a1 --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 8d9fd089cabd435590250c5e15b781c06a96f0a1 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.xQdOQDhDM8/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.cE9aYYpqHB/Listeners TMPDIR=/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2017-03-13 09:02:00,452 truncating revision to first 12 chars 2017-03-13 09:02:00,453 Setting DEBUG logging. 2017-03-13 09:02:00,453 attempt 1/10 2017-03-13 09:02:00,453 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/8d9fd089cabd?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-03-13 09:02:02,114 unpacking tar archive at: mozilla-central-8d9fd089cabd/testing/mozharness/ program finished with exit code 0 elapsedTime=1.921731 ========= master_lag: 2.69 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 4 secs) (at 2017-03-13 09:02:04.982068) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-13 09:02:04.982575) ========= script_repo_revision: 8d9fd089cabd435590250c5e15b781c06a96f0a1 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-13 09:02:04.983122) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-13 09:02:04.983510) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-13 09:02:05.099252) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 29 secs) (at 2017-03-13 09:02:05.099764) ========= /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.xQdOQDhDM8/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.cE9aYYpqHB/Listeners TMPDIR=/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 09:02:05 INFO - MultiFileLogger online at 20170313 09:02:05 in /builds/slave/test 09:02:05 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 09:02:05 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 09:02:05 INFO - {'append_to_log': False, 09:02:05 INFO - 'base_work_dir': '/builds/slave/test', 09:02:05 INFO - 'blob_upload_branch': 'Firefox', 09:02:05 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 09:02:05 INFO - 'branch': 'Firefox', 09:02:05 INFO - 'buildbot_json_path': 'buildprops.json', 09:02:05 INFO - 'config_files': ('talos/mac_config.py',), 09:02:05 INFO - 'default_actions': ('clobber', 09:02:05 INFO - 'read-buildbot-config', 09:02:05 INFO - 'download-and-extract', 09:02:05 INFO - 'populate-webroot', 09:02:05 INFO - 'create-virtualenv', 09:02:05 INFO - 'install', 09:02:05 INFO - 'run-tests'), 09:02:05 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:02:05 INFO - 'download_minidump_stackwalk': True, 09:02:05 INFO - 'download_symbols': 'ondemand', 09:02:05 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 09:02:05 INFO - 'tooltool.py': '/tools/tooltool.py', 09:02:05 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 09:02:05 INFO - '/tools/misc-python/virtualenv.py')}, 09:02:05 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:02:05 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:02:05 INFO - 'gecko_profile': False, 09:02:05 INFO - 'gecko_profile_interval': 0, 09:02:05 INFO - 'installer_path': 'installer.exe', 09:02:05 INFO - 'log_level': 'info', 09:02:05 INFO - 'log_name': 'talos', 09:02:05 INFO - 'log_to_console': True, 09:02:05 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 09:02:05 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 09:02:05 INFO - 'opt_config_files': (), 09:02:05 INFO - 'pip_index': False, 09:02:05 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:02:05 INFO - 'cmd': ('bash', 09:02:05 INFO - '-c', 09:02:05 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:02:05 INFO - 'enabled': True, 09:02:05 INFO - 'halt_on_failure': False, 09:02:05 INFO - 'name': 'check_screen_resolution'},), 09:02:05 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:02:05 INFO - 'cmd': ('bash', 09:02:05 INFO - '-c', 09:02:05 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:02:05 INFO - 'enabled': True, 09:02:05 INFO - 'halt_on_failure': False, 09:02:05 INFO - 'name': 'check_screen_resolution'},), 09:02:05 INFO - 'run_cmd_checks_enabled': True, 09:02:05 INFO - 'suite': 'dromaeojs', 09:02:05 INFO - 'system_bits': '32', 09:02:05 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:02:05 INFO - 'title': 't-yosemite-r7-0111', 09:02:05 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:02:05 INFO - 'use_talos_json': True, 09:02:05 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 09:02:05 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:02:05 INFO - 'work_dir': 'build'} 09:02:05 INFO - [mozharness: 2017-03-13 16:02:05.308847Z] Running clobber step. 09:02:05 INFO - Running pre-action listener: _resource_record_pre_action 09:02:05 INFO - Running main action method: clobber 09:02:05 INFO - rmtree: /builds/slave/test/build 09:02:05 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 09:02:07 INFO - Running post-action listener: _resource_record_post_action 09:02:07 INFO - [mozharness: 2017-03-13 16:02:07.423340Z] Finished clobber step (success) 09:02:07 INFO - [mozharness: 2017-03-13 16:02:07.423486Z] Running read-buildbot-config step. 09:02:07 INFO - Running pre-action listener: _resource_record_pre_action 09:02:07 INFO - Running main action method: read_buildbot_config 09:02:07 INFO - Using buildbot properties: 09:02:07 INFO - { 09:02:07 INFO - "project": "", 09:02:07 INFO - "product": "firefox", 09:02:07 INFO - "script_repo_revision": "production", 09:02:07 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs", 09:02:07 INFO - "repository": "", 09:02:07 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs", 09:02:07 INFO - "buildid": "20170313072445", 09:02:07 INFO - "pgo_build": "False", 09:02:07 INFO - "basedir": "/builds/slave/test", 09:02:07 INFO - "buildnumber": 67, 09:02:07 INFO - "slavename": "t-yosemite-r7-0111", 09:02:07 INFO - "master": "http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/", 09:02:07 INFO - "platform": "yosemite_r7", 09:02:07 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 09:02:07 INFO - "branch": "mozilla-central", 09:02:07 INFO - "repo_path": "mozilla-central", 09:02:07 INFO - "revision": "8d9fd089cabd435590250c5e15b781c06a96f0a1", 09:02:07 INFO - "stage_platform": "macosx64", 09:02:07 INFO - "builduid": "ad41c4f65eb94f29b1ed0d4ffd4f0bf4", 09:02:07 INFO - "slavebuilddir": "test" 09:02:07 INFO - } 09:02:07 INFO - Found installer url https://queue.taskcluster.net/v1/task/UjpqHGdGR7-sSi1Op6VhNA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg. 09:02:07 INFO - Running post-action listener: _resource_record_post_action 09:02:07 INFO - [mozharness: 2017-03-13 16:02:07.426839Z] Finished read-buildbot-config step (success) 09:02:07 INFO - [mozharness: 2017-03-13 16:02:07.426974Z] Running download-and-extract step. 09:02:07 INFO - Running pre-action listener: _resource_record_pre_action 09:02:07 INFO - Running main action method: download_and_extract 09:02:07 INFO - mkdir: /builds/slave/test/build/tests 09:02:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:02:07 INFO - trying https://queue.taskcluster.net/v1/task/UjpqHGdGR7-sSi1Op6VhNA/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json 09:02:07 INFO - Downloading https://queue.taskcluster.net/v1/task/UjpqHGdGR7-sSi1Op6VhNA/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 09:02:07 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/UjpqHGdGR7-sSi1Op6VhNA/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json'}, attempt #1 09:02:08 INFO - Downloaded 1199 bytes. 09:02:08 INFO - Reading from file /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 09:02:08 INFO - Using the following test package requirements: 09:02:08 INFO - {u'common': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 09:02:08 INFO - u'cppunittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 09:02:08 INFO - u'firefox-55.0a1.en-US.mac.cppunittest.tests.zip'], 09:02:08 INFO - u'gtest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 09:02:08 INFO - u'firefox-55.0a1.en-US.mac.gtest.tests.zip'], 09:02:08 INFO - u'jittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 09:02:08 INFO - u'jsshell-mac.zip'], 09:02:08 INFO - u'mochitest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 09:02:08 INFO - u'firefox-55.0a1.en-US.mac.mochitest.tests.zip'], 09:02:08 INFO - u'mozbase': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 09:02:08 INFO - u'reftest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 09:02:08 INFO - u'firefox-55.0a1.en-US.mac.reftest.tests.zip'], 09:02:08 INFO - u'talos': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 09:02:08 INFO - u'firefox-55.0a1.en-US.mac.talos.tests.zip'], 09:02:08 INFO - u'web-platform': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 09:02:08 INFO - u'firefox-55.0a1.en-US.mac.web-platform.tests.zip'], 09:02:08 INFO - u'xpcshell': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 09:02:08 INFO - u'firefox-55.0a1.en-US.mac.xpcshell.tests.zip']} 09:02:08 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip'] for test suite category: common 09:02:08 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/UjpqHGdGR7-sSi1Op6VhNA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 09:02:08 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/UjpqHGdGR7-sSi1Op6VhNA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 09:02:08 INFO - Fetch https://queue.taskcluster.net/v1/task/UjpqHGdGR7-sSi1Op6VhNA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 09:02:10 INFO - Expected file size: 52470959 09:02:10 INFO - Obtained file size: 52470959 09:02:26 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip', u'firefox-55.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 09:02:26 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/UjpqHGdGR7-sSi1Op6VhNA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 09:02:26 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/UjpqHGdGR7-sSi1Op6VhNA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 09:02:26 INFO - Fetch https://queue.taskcluster.net/v1/task/UjpqHGdGR7-sSi1Op6VhNA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 09:02:27 INFO - Expected file size: 52470959 09:02:27 INFO - Obtained file size: 52470959 09:02:43 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/UjpqHGdGR7-sSi1Op6VhNA/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip 09:02:43 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/UjpqHGdGR7-sSi1Op6VhNA/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip'}, attempt #1 09:02:43 INFO - Fetch https://queue.taskcluster.net/v1/task/UjpqHGdGR7-sSi1Op6VhNA/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip into memory 09:02:44 INFO - Expected file size: 14404438 09:02:44 INFO - Obtained file size: 14404438 09:02:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:02:44 INFO - trying https://queue.taskcluster.net/v1/task/UjpqHGdGR7-sSi1Op6VhNA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 09:02:44 INFO - Downloading https://queue.taskcluster.net/v1/task/UjpqHGdGR7-sSi1Op6VhNA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg 09:02:44 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/UjpqHGdGR7-sSi1Op6VhNA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg'}, attempt #1 09:02:46 INFO - Downloaded 74901835 bytes. 09:02:46 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/UjpqHGdGR7-sSi1Op6VhNA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 09:02:46 INFO - mkdir: /builds/slave/test/properties 09:02:46 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 09:02:46 INFO - Writing to file /builds/slave/test/properties/build_url 09:02:46 INFO - Contents: 09:02:46 INFO - build_url:https://queue.taskcluster.net/v1/task/UjpqHGdGR7-sSi1Op6VhNA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 09:02:47 INFO - Running post-action listener: _resource_record_post_action 09:02:47 INFO - Running post-action listener: set_extra_try_arguments 09:02:47 INFO - [mozharness: 2017-03-13 16:02:47.307916Z] Finished download-and-extract step (success) 09:02:47 INFO - [mozharness: 2017-03-13 16:02:47.308050Z] Running populate-webroot step. 09:02:47 INFO - Running pre-action listener: _resource_record_pre_action 09:02:47 INFO - Running main action method: populate_webroot 09:02:47 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:02:47 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:02:47 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:02:47 INFO - '--tppagecycles', 09:02:47 INFO - '7'], 09:02:47 INFO - 'tests': ['tsvgm']}}, 09:02:47 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 09:02:47 INFO - 'tests': ['tresize', 'tcanvasmark']}, 09:02:47 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 09:02:47 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 09:02:47 INFO - 'tests': ['dromaeo_css', 'kraken']}, 09:02:47 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 09:02:47 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 09:02:47 INFO - 'talos_options': ['--disable-e10s'], 09:02:47 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:02:47 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 09:02:47 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:02:47 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 09:02:47 INFO - 'talos_options': ['--disable-e10s'], 09:02:47 INFO - 'tests': ['damp', 'tps']}, 09:02:47 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 09:02:47 INFO - 'tests': ['damp', 'tps']}, 09:02:47 INFO - 'g3': {'talos_options': ['--disable-e10s'], 09:02:47 INFO - 'tests': ['dromaeo_dom']}, 09:02:47 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:02:47 INFO - 'g4': {'talos_options': ['--disable-e10s'], 09:02:47 INFO - 'tests': ['basic_compositor_video']}, 09:02:47 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 09:02:47 INFO - 'other': {'talos_options': ['--disable-e10s'], 09:02:47 INFO - 'tests': ['a11yr', 09:02:47 INFO - 'ts_paint', 09:02:47 INFO - 'tpaint', 09:02:47 INFO - 'sessionrestore', 09:02:47 INFO - 'sessionrestore_no_auto_restore', 09:02:47 INFO - 'tabpaint']}, 09:02:47 INFO - 'other-e10s': {'tests': ['a11yr', 09:02:47 INFO - 'ts_paint', 09:02:47 INFO - 'tpaint', 09:02:47 INFO - 'sessionrestore', 09:02:47 INFO - 'sessionrestore_no_auto_restore', 09:02:47 INFO - 'tabpaint']}, 09:02:47 INFO - 'other-e10s_l64': {'tests': ['a11yr', 09:02:47 INFO - 'ts_paint', 09:02:47 INFO - 'tpaint', 09:02:47 INFO - 'sessionrestore', 09:02:47 INFO - 'sessionrestore_no_auto_restore', 09:02:47 INFO - 'tabpaint']}, 09:02:47 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 09:02:47 INFO - 'ts_paint', 09:02:47 INFO - 'tpaint', 09:02:47 INFO - 'sessionrestore', 09:02:47 INFO - 'sessionrestore_no_auto_restore', 09:02:47 INFO - 'tabpaint']}, 09:02:47 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 09:02:47 INFO - 'tests': ['a11yr', 09:02:47 INFO - 'ts_paint', 09:02:47 INFO - 'tpaint', 09:02:47 INFO - 'sessionrestore', 09:02:47 INFO - 'sessionrestore_no_auto_restore', 09:02:47 INFO - 'tabpaint']}, 09:02:47 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 09:02:47 INFO - 'tests': ['a11yr', 09:02:47 INFO - 'ts_paint', 09:02:47 INFO - 'tpaint', 09:02:47 INFO - 'sessionrestore', 09:02:47 INFO - 'sessionrestore_no_auto_restore', 09:02:47 INFO - 'tabpaint']}, 09:02:47 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 09:02:47 INFO - 'tests': ['tsvgx', 09:02:47 INFO - 'tsvgr_opacity', 09:02:47 INFO - 'tart', 09:02:47 INFO - 'tscrollx', 09:02:47 INFO - 'cart', 09:02:47 INFO - 'tsvg_static']}, 09:02:47 INFO - 'svgr-e10s': {'tests': ['tsvgx', 09:02:47 INFO - 'tsvgr_opacity', 09:02:47 INFO - 'tart', 09:02:47 INFO - 'tscrollx', 09:02:47 INFO - 'cart', 09:02:47 INFO - 'tsvg_static']}, 09:02:47 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 09:02:47 INFO - 'talos_options': ['--disable-e10s'], 09:02:47 INFO - 'tests': ['tp5o']}, 09:02:47 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 09:02:47 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 09:02:47 INFO - 'talos_options': ['--disable-e10s', 09:02:47 INFO - '--xperf_path', 09:02:47 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:02:47 INFO - 'tests': ['tp5n']}, 09:02:47 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 09:02:47 INFO - 'talos_options': ['--xperf_path', 09:02:47 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:02:47 INFO - 'tests': ['tp5n']}}, 09:02:47 INFO - 'talos.zip': {'path': '', 09:02:47 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:02:47 INFO - Running post-action listener: _resource_record_post_action 09:02:47 INFO - [mozharness: 2017-03-13 16:02:47.326485Z] Finished populate-webroot step (success) 09:02:47 INFO - [mozharness: 2017-03-13 16:02:47.326622Z] Running create-virtualenv step. 09:02:47 INFO - Running pre-action listener: _resource_record_pre_action 09:02:47 INFO - Running main action method: create_virtualenv 09:02:47 INFO - Creating virtualenv /builds/slave/test/build/venv 09:02:47 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 09:02:47 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 09:02:47 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:02:47 INFO - Using real prefix '/tools/python27' 09:02:47 INFO - New python executable in /builds/slave/test/build/venv/bin/python 09:02:48 INFO - Installing distribute.............................................................................................................................................................................................done. 09:02:51 INFO - Installing pip.................done. 09:02:51 INFO - Return code: 0 09:02:51 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 09:02:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 09:02:51 INFO - Reading from file tmpfile_stdout 09:02:51 INFO - Output received: 09:02:51 INFO - pip 1.5.5 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 09:02:51 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 09:02:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:02:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:02:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:02:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:02:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:02:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:02:51 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 0x1020f5b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1020f8768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdcb8c92450>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101ed3d30>, '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 0x101dfadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101ec8be8>, '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.xQdOQDhDM8/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.cE9aYYpqHB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:02:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 09:02:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 09:02:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xQdOQDhDM8/Render', 09:02:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:02:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:02:51 INFO - 'HOME': '/Users/cltbld', 09:02:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:02:51 INFO - 'LOGNAME': 'cltbld', 09:02:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:02:51 INFO - 'MOZ_NO_REMOTE': '1', 09:02:51 INFO - 'NO_EM_RESTART': '1', 09:02:51 INFO - 'PAGER': '/bin/cat', 09:02:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:02:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:02:51 INFO - 'PWD': '/builds/slave/test', 09:02:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:02:51 INFO - 'SHELL': '/bin/bash', 09:02:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cE9aYYpqHB/Listeners', 09:02:51 INFO - 'TMPDIR': '/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/', 09:02:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:02:51 INFO - 'USER': 'cltbld', 09:02:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:02:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:02:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:02:51 INFO - 'XPC_FLAGS': '0x0', 09:02:51 INFO - 'XPC_SERVICE_NAME': '0', 09:02:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:02:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:02:51 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 09:02:51 INFO - Cleaning up... 09:02:51 INFO - Return code: 0 09:02:51 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 09:02:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:02:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:02:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:02:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:02:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:02:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:02:51 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 0x1020f5b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1020f8768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdcb8c92450>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101ed3d30>, '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 0x101dfadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101ec8be8>, '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.xQdOQDhDM8/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.cE9aYYpqHB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:02:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 09:02:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 09:02:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xQdOQDhDM8/Render', 09:02:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:02:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:02:51 INFO - 'HOME': '/Users/cltbld', 09:02:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:02:51 INFO - 'LOGNAME': 'cltbld', 09:02:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:02:51 INFO - 'MOZ_NO_REMOTE': '1', 09:02:51 INFO - 'NO_EM_RESTART': '1', 09:02:51 INFO - 'PAGER': '/bin/cat', 09:02:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:02:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:02:51 INFO - 'PWD': '/builds/slave/test', 09:02:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:02:51 INFO - 'SHELL': '/bin/bash', 09:02:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cE9aYYpqHB/Listeners', 09:02:51 INFO - 'TMPDIR': '/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/', 09:02:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:02:51 INFO - 'USER': 'cltbld', 09:02:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:02:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:02:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:02:51 INFO - 'XPC_FLAGS': '0x0', 09:02:51 INFO - 'XPC_SERVICE_NAME': '0', 09:02:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:02:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:02:51 INFO - Downloading/unpacking psutil>=3.1.1 09:02:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:02:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:02:51 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:02:51 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:02:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:02:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:02:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 09:02:55 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:02:55 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:02:55 INFO - Installing collected packages: psutil 09:02:55 INFO - Running setup.py install for psutil 09:02:55 INFO - building 'psutil._psutil_osx' extension 09:02:55 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 09:02:55 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 09:02:55 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 09:02:55 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 09:02:55 INFO - building 'psutil._psutil_posix' extension 09:02:55 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 09:02:55 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 09:02:55 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 09:02:55 INFO - ^ 09:02:55 INFO - 1 warning generated. 09:02:55 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 09:02:56 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:02:56 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:02:56 INFO - Successfully installed psutil 09:02:56 INFO - Cleaning up... 09:02:56 INFO - Return code: 0 09:02:56 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 09:02:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:02:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:02:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:02:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:02:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:02:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:02:57 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 0x1020f5b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1020f8768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdcb8c92450>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101ed3d30>, '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 0x101dfadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101ec8be8>, '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.xQdOQDhDM8/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.cE9aYYpqHB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:02:57 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 09:02:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 09:02:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xQdOQDhDM8/Render', 09:02:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:02:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:02:57 INFO - 'HOME': '/Users/cltbld', 09:02:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:02:57 INFO - 'LOGNAME': 'cltbld', 09:02:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:02:57 INFO - 'MOZ_NO_REMOTE': '1', 09:02:57 INFO - 'NO_EM_RESTART': '1', 09:02:57 INFO - 'PAGER': '/bin/cat', 09:02:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:02:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:02:57 INFO - 'PWD': '/builds/slave/test', 09:02:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:02:57 INFO - 'SHELL': '/bin/bash', 09:02:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cE9aYYpqHB/Listeners', 09:02:57 INFO - 'TMPDIR': '/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/', 09:02:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:02:57 INFO - 'USER': 'cltbld', 09:02:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:02:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:02:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:02:57 INFO - 'XPC_FLAGS': '0x0', 09:02:57 INFO - 'XPC_SERVICE_NAME': '0', 09:02:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:02:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:02:57 INFO - Downloading/unpacking mozsystemmonitor==0.3 09:02:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:02:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:02:57 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:02:57 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:02:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:02:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:03:00 INFO - Downloading mozsystemmonitor-0.3.tar.gz 09:03:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:03:00 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 09:03:00 INFO - Installing collected packages: mozsystemmonitor 09:03:00 INFO - Running setup.py install for mozsystemmonitor 09:03:00 INFO - Successfully installed mozsystemmonitor 09:03:00 INFO - Cleaning up... 09:03:00 INFO - Return code: 0 09:03:00 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 09:03:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:03:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:03:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:03:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:03:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:03:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:03:00 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1020f5b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1020f8768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdcb8c92450>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101ed3d30>, '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 0x101dfadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101ec8be8>, '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.xQdOQDhDM8/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.cE9aYYpqHB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:03:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 09:03:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 09:03:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xQdOQDhDM8/Render', 09:03:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:03:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:03:00 INFO - 'HOME': '/Users/cltbld', 09:03:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:03:00 INFO - 'LOGNAME': 'cltbld', 09:03:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:03:00 INFO - 'MOZ_NO_REMOTE': '1', 09:03:00 INFO - 'NO_EM_RESTART': '1', 09:03:00 INFO - 'PAGER': '/bin/cat', 09:03:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:03:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:03:00 INFO - 'PWD': '/builds/slave/test', 09:03:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:03:00 INFO - 'SHELL': '/bin/bash', 09:03:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cE9aYYpqHB/Listeners', 09:03:00 INFO - 'TMPDIR': '/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/', 09:03:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:03:00 INFO - 'USER': 'cltbld', 09:03:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:03:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:03:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:03:00 INFO - 'XPC_FLAGS': '0x0', 09:03:00 INFO - 'XPC_SERVICE_NAME': '0', 09:03:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:03:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:03:01 INFO - Downloading/unpacking jsonschema==2.5.1 09:03:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:03:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:03:01 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:03:01 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:03:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:03:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:03:03 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:03:03 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 09:03:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:03:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:03:03 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:03:03 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:03:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:03:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:03:04 INFO - Downloading functools32-3.2.3-2.tar.gz 09:03:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 09:03:04 INFO - warning: no files found matching '*.txt' 09:03:04 INFO - no previously-included directories found matching 'build' 09:03:04 INFO - no previously-included directories found matching 'dist' 09:03:04 INFO - no previously-included directories found matching '.git*' 09:03:04 INFO - Installing collected packages: jsonschema, functools32 09:03:04 INFO - Running setup.py install for functools32 09:03:04 INFO - warning: no files found matching '*.txt' 09:03:04 INFO - no previously-included directories found matching 'build' 09:03:04 INFO - no previously-included directories found matching 'dist' 09:03:04 INFO - no previously-included directories found matching '.git*' 09:03:04 INFO - Successfully installed jsonschema functools32 09:03:04 INFO - Cleaning up... 09:03:04 INFO - Return code: 0 09:03:04 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 09:03:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:03:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:03:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:03:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:03:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:03:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:03:04 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 0x1020f5b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1020f8768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdcb8c92450>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101ed3d30>, '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 0x101dfadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101ec8be8>, '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.xQdOQDhDM8/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.cE9aYYpqHB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:03:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 09:03:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 09:03:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xQdOQDhDM8/Render', 09:03:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:03:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:03:04 INFO - 'HOME': '/Users/cltbld', 09:03:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:03:04 INFO - 'LOGNAME': 'cltbld', 09:03:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:03:04 INFO - 'MOZ_NO_REMOTE': '1', 09:03:04 INFO - 'NO_EM_RESTART': '1', 09:03:04 INFO - 'PAGER': '/bin/cat', 09:03:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:03:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:03:04 INFO - 'PWD': '/builds/slave/test', 09:03:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:03:04 INFO - 'SHELL': '/bin/bash', 09:03:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cE9aYYpqHB/Listeners', 09:03:04 INFO - 'TMPDIR': '/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/', 09:03:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:03:04 INFO - 'USER': 'cltbld', 09:03:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:03:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:03:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:03:04 INFO - 'XPC_FLAGS': '0x0', 09:03:04 INFO - 'XPC_SERVICE_NAME': '0', 09:03:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:03:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:03:04 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 09:03:04 INFO - Cleaning up... 09:03:04 INFO - Return code: 0 09:03:04 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 09:03:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:03:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:03:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:03:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:03:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:03:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:03:04 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 0x1020f5b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1020f8768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdcb8c92450>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101ed3d30>, '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 0x101dfadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101ec8be8>, '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.xQdOQDhDM8/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.cE9aYYpqHB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:03:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 09:03:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 09:03:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xQdOQDhDM8/Render', 09:03:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:03:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:03:04 INFO - 'HOME': '/Users/cltbld', 09:03:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:03:04 INFO - 'LOGNAME': 'cltbld', 09:03:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:03:04 INFO - 'MOZ_NO_REMOTE': '1', 09:03:04 INFO - 'NO_EM_RESTART': '1', 09:03:04 INFO - 'PAGER': '/bin/cat', 09:03:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:03:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:03:04 INFO - 'PWD': '/builds/slave/test', 09:03:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:03:04 INFO - 'SHELL': '/bin/bash', 09:03:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cE9aYYpqHB/Listeners', 09:03:04 INFO - 'TMPDIR': '/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/', 09:03:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:03:04 INFO - 'USER': 'cltbld', 09:03:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:03:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:03:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:03:04 INFO - 'XPC_FLAGS': '0x0', 09:03:04 INFO - 'XPC_SERVICE_NAME': '0', 09:03:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:03:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:03:05 INFO - Downloading/unpacking blobuploader==1.2.4 09:03:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:03:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:03:05 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:03:05 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:03:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:03:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:03:07 INFO - Downloading blobuploader-1.2.4.tar.gz 09:03:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:03:07 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:03:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:03:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:03:07 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:03:07 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:03:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:03:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:03:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 09:03:08 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:03:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:03:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:03:08 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:03:08 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:03:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:03:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:03:08 INFO - Downloading docopt-0.6.1.tar.gz 09:03:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 09:03:09 INFO - Installing collected packages: blobuploader, requests, docopt 09:03:09 INFO - Running setup.py install for blobuploader 09:03:09 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:03:09 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 09:03:09 INFO - Running setup.py install for requests 09:03:09 INFO - Running setup.py install for docopt 09:03:09 INFO - Successfully installed blobuploader requests docopt 09:03:09 INFO - Cleaning up... 09:03:09 INFO - Return code: 0 09:03:09 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:03:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:03:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:03:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:03:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:03:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:03:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:03:09 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 0x1020f5b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1020f8768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdcb8c92450>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101ed3d30>, '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 0x101dfadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101ec8be8>, '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.xQdOQDhDM8/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.cE9aYYpqHB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:03:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 09:03:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:03:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xQdOQDhDM8/Render', 09:03:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:03:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:03:09 INFO - 'HOME': '/Users/cltbld', 09:03:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:03:09 INFO - 'LOGNAME': 'cltbld', 09:03:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:03:09 INFO - 'MOZ_NO_REMOTE': '1', 09:03:09 INFO - 'NO_EM_RESTART': '1', 09:03:09 INFO - 'PAGER': '/bin/cat', 09:03:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:03:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:03:09 INFO - 'PWD': '/builds/slave/test', 09:03:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:03:09 INFO - 'SHELL': '/bin/bash', 09:03:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cE9aYYpqHB/Listeners', 09:03:09 INFO - 'TMPDIR': '/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/', 09:03:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:03:09 INFO - 'USER': 'cltbld', 09:03:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:03:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:03:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:03:09 INFO - 'XPC_FLAGS': '0x0', 09:03:09 INFO - 'XPC_SERVICE_NAME': '0', 09:03:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:03:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:03:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:03:10 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-QYpsx8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:03:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:03:10 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-ymOII8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:03:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:03:10 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-e0wcIK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:03:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:03:10 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-IGzbsf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:03:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:03:10 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-swSlvo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:03:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:03:10 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-m7D6KU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:03:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:03:10 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-M8LpPM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:03:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:03:10 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-WHAbqN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:03:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:03:11 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-loxcgJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:03:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:03:11 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-RGxq8t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:03:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:03:11 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-wxAFBm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:03:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:03:11 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-YYlUnW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:03:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:03:11 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-zqubcX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:03:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:03:11 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-4HIE_u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:03:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:03:11 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-WROD9I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:03:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:03:11 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-F9PH1X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:03:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:03:11 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-lAxmp4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:03:11 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:03:11 INFO - Running setup.py install for manifestparser 09:03:12 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 09:03:12 INFO - Running setup.py install for mozcrash 09:03:12 INFO - Running setup.py install for mozdebug 09:03:12 INFO - Running setup.py install for mozdevice 09:03:12 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 09:03:12 INFO - Installing dm script to /builds/slave/test/build/venv/bin 09:03:12 INFO - Running setup.py install for mozfile 09:03:12 INFO - Running setup.py install for mozhttpd 09:03:12 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 09:03:12 INFO - Running setup.py install for mozinfo 09:03:13 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 09:03:13 INFO - Running setup.py install for mozInstall 09:03:13 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 09:03:13 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 09:03:13 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 09:03:13 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 09:03:13 INFO - Running setup.py install for mozleak 09:03:13 INFO - Running setup.py install for mozlog 09:03:13 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 09:03:13 INFO - Running setup.py install for moznetwork 09:03:13 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 09:03:13 INFO - Running setup.py install for mozprocess 09:03:13 INFO - Running setup.py install for mozprofile 09:03:13 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 09:03:13 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 09:03:13 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 09:03:13 INFO - Running setup.py install for mozrunner 09:03:14 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 09:03:14 INFO - Running setup.py install for mozscreenshot 09:03:14 INFO - Running setup.py install for moztest 09:03:14 INFO - Running setup.py install for mozversion 09:03:14 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 09:03:14 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:03:14 INFO - Cleaning up... 09:03:14 INFO - Return code: 0 09:03:14 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:03:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:03:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:03:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:03:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:03:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:03:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:03:14 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 0x1020f5b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1020f8768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdcb8c92450>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101ed3d30>, '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 0x101dfadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101ec8be8>, '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.xQdOQDhDM8/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.cE9aYYpqHB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:03:14 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 09:03:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:03:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xQdOQDhDM8/Render', 09:03:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:03:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:03:14 INFO - 'HOME': '/Users/cltbld', 09:03:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:03:14 INFO - 'LOGNAME': 'cltbld', 09:03:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:03:14 INFO - 'MOZ_NO_REMOTE': '1', 09:03:14 INFO - 'NO_EM_RESTART': '1', 09:03:14 INFO - 'PAGER': '/bin/cat', 09:03:14 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:03:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:03:14 INFO - 'PWD': '/builds/slave/test', 09:03:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:03:14 INFO - 'SHELL': '/bin/bash', 09:03:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cE9aYYpqHB/Listeners', 09:03:14 INFO - 'TMPDIR': '/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/', 09:03:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:03:14 INFO - 'USER': 'cltbld', 09:03:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:03:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:03:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:03:14 INFO - 'XPC_FLAGS': '0x0', 09:03:14 INFO - 'XPC_SERVICE_NAME': '0', 09:03:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:03:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:03:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:03:14 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-j6dJ4l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:03:14 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 09:03:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:03:14 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-cfxsTK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:03:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:03:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:03:15 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-KVUGP0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:03:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 09:03:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:03:15 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-ilv6RJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:03:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:03:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:03:15 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-af4wPs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:03:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 09:03:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:03:15 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-DLgCaF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:03:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 09:03:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:03:15 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-dxjO7W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:03:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 09:03:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:03:15 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-gCfpZu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:03:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 09:03:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:03:15 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-LOtn4v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:03:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 09:03:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:03:15 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-vumFgx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:03:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:03:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:03:15 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-4NI7Rm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:03:16 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 09:03:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:03:16 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-afMU6P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:03:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 09:03:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:03:16 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-jkCbw_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:03:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 09:03:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:03:16 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-yOhsXE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:03:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 09:03:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:03:16 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-wzcAbA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:03:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 09:03:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:03:16 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-IXz97w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:03:16 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 09:03:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:03:16 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-wmoNqX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:03:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 09:03:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:03:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:03:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 09:03:16 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:03:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:03:16 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:03:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:03:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:03:16 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:03:16 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:03:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:03:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:03:19 INFO - Downloading blessings-1.6.tar.gz 09:03:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 09:03:19 INFO - Installing collected packages: blessings 09:03:19 INFO - Running setup.py install for blessings 09:03:19 INFO - Successfully installed blessings 09:03:19 INFO - Cleaning up... 09:03:19 INFO - Return code: 0 09:03:19 INFO - Done creating virtualenv /builds/slave/test/build/venv. 09:03:19 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:03:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:03:19 INFO - Reading from file tmpfile_stdout 09:03:19 INFO - Current package versions: 09:03:19 INFO - blessings == 1.6 09:03:19 INFO - blobuploader == 1.2.4 09:03:19 INFO - docopt == 0.6.1 09:03:19 INFO - functools32 == 3.2.3-2 09:03:19 INFO - jsonschema == 2.5.1 09:03:19 INFO - manifestparser == 1.1 09:03:19 INFO - mozInstall == 1.12 09:03:19 INFO - mozcrash == 1.0 09:03:19 INFO - mozdebug == 0.1 09:03:19 INFO - mozdevice == 0.49 09:03:19 INFO - mozfile == 1.2 09:03:19 INFO - mozhttpd == 0.7 09:03:19 INFO - mozinfo == 0.9 09:03:19 INFO - mozleak == 0.1 09:03:19 INFO - mozlog == 3.4 09:03:19 INFO - moznetwork == 0.27 09:03:19 INFO - mozprocess == 0.25 09:03:19 INFO - mozprofile == 0.28 09:03:19 INFO - mozrunner == 6.13 09:03:19 INFO - mozscreenshot == 0.1 09:03:19 INFO - mozsystemmonitor == 0.3 09:03:19 INFO - moztest == 0.8 09:03:19 INFO - mozversion == 1.4 09:03:19 INFO - psutil == 3.1.1 09:03:19 INFO - requests == 1.2.3 09:03:19 INFO - wsgiref == 0.1.2 09:03:19 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:03:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:03:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:03:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:03:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:03:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:03:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:03:19 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 0x1020f5b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1020f8768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdcb8c92450>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101ed3d30>, '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 0x101dfadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101ec8be8>, '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.xQdOQDhDM8/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.cE9aYYpqHB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:03:19 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 09:03:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:03:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xQdOQDhDM8/Render', 09:03:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:03:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:03:19 INFO - 'HOME': '/Users/cltbld', 09:03:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:03:19 INFO - 'LOGNAME': 'cltbld', 09:03:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:03:19 INFO - 'MOZ_NO_REMOTE': '1', 09:03:19 INFO - 'NO_EM_RESTART': '1', 09:03:19 INFO - 'PAGER': '/bin/cat', 09:03:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:03:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:03:19 INFO - 'PWD': '/builds/slave/test', 09:03:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:03:19 INFO - 'SHELL': '/bin/bash', 09:03:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cE9aYYpqHB/Listeners', 09:03:19 INFO - 'TMPDIR': '/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/', 09:03:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:03:19 INFO - 'USER': 'cltbld', 09:03:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:03:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:03:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:03:19 INFO - 'XPC_FLAGS': '0x0', 09:03:19 INFO - 'XPC_SERVICE_NAME': '0', 09:03:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:03:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:03:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 09:03:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 09:03:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 09:03:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 09:03:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 09:03:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 09:03:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 09:03:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 09:03:20 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 09:03:20 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 09:03:20 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 09:03:20 INFO - Cleaning up... 09:03:20 INFO - Return code: 0 09:03:20 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 09:03:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:03:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:03:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:03:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:03:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:03:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:03:20 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1020f5b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1020f8768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdcb8c92450>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101ed3d30>, '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 0x101dfadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101ec8be8>, '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.xQdOQDhDM8/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.cE9aYYpqHB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:03:20 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 09:03:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 09:03:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xQdOQDhDM8/Render', 09:03:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:03:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:03:20 INFO - 'HOME': '/Users/cltbld', 09:03:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:03:20 INFO - 'LOGNAME': 'cltbld', 09:03:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:03:20 INFO - 'MOZ_NO_REMOTE': '1', 09:03:20 INFO - 'NO_EM_RESTART': '1', 09:03:20 INFO - 'PAGER': '/bin/cat', 09:03:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:03:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:03:20 INFO - 'PWD': '/builds/slave/test', 09:03:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:03:20 INFO - 'SHELL': '/bin/bash', 09:03:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cE9aYYpqHB/Listeners', 09:03:20 INFO - 'TMPDIR': '/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/', 09:03:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:03:20 INFO - 'USER': 'cltbld', 09:03:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:03:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:03:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:03:20 INFO - 'XPC_FLAGS': '0x0', 09:03:20 INFO - 'XPC_SERVICE_NAME': '0', 09:03:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:03:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:03:20 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 09:03:20 INFO - Cleaning up... 09:03:20 INFO - Return code: 0 09:03:20 INFO - Running post-action listener: _resource_record_post_action 09:03:20 INFO - Running post-action listener: _start_resource_monitoring 09:03:20 INFO - Starting resource monitoring. 09:03:20 INFO - [mozharness: 2017-03-13 16:03:20.521155Z] Finished create-virtualenv step (success) 09:03:20 INFO - [mozharness: 2017-03-13 16:03:20.522113Z] Running install step. 09:03:20 INFO - Running pre-action listener: _resource_record_pre_action 09:03:20 INFO - Running main action method: install 09:03:20 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:03:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:03:20 INFO - Reading from file tmpfile_stdout 09:03:20 INFO - Detecting whether we're running mozinstall >=1.0... 09:03:20 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 09:03:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 09:03:20 INFO - Reading from file tmpfile_stdout 09:03:20 INFO - Output received: 09:03:20 INFO - Usage: mozinstall [options] installer 09:03:20 INFO - Options: 09:03:20 INFO - -h, --help show this help message and exit 09:03:20 INFO - -d DEST, --destination=DEST 09:03:20 INFO - Directory to install application into. [default: 09:03:20 INFO - "/builds/slave/test"] 09:03:20 INFO - --app=APP Application being installed. [default: firefox] 09:03:20 INFO - mkdir: /builds/slave/test/build/application 09:03:20 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 09:03:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 09:03:41 INFO - Reading from file tmpfile_stdout 09:03:41 INFO - Output received: 09:03:41 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 09:03:41 INFO - Running post-action listener: _resource_record_post_action 09:03:41 INFO - [mozharness: 2017-03-13 16:03:41.447178Z] Finished install step (success) 09:03:41 INFO - [mozharness: 2017-03-13 16:03:41.447303Z] Running run-tests step. 09:03:41 INFO - Running pre-action listener: _resource_record_pre_action 09:03:41 INFO - Running main action method: run_tests 09:03:41 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:03:41 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:03:41 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:03:41 INFO - 2017-03-13 09:03:41.462 screenresolution[1997:15893] starting screenresolution argv=screenresolution get 09:03:41 INFO - 2017-03-13 09:03:41.464 screenresolution[1997:15893] Display 0: 1600x1200x32@60 09:03:41 INFO - 2017-03-13 09:03:41.470 screenresolution[1998:15896] starting screenresolution argv=screenresolution list 09:03:41 INFO - Available Modes on Display 0 09:03:41 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:03:41 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:03:41 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:03:41 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:03:41 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:03:41 INFO - Intel Iris: 09:03:41 INFO - Chipset Model: Intel Iris 09:03:41 INFO - Type: GPU 09:03:41 INFO - Bus: Built-In 09:03:41 INFO - VRAM (Dynamic, Max): 1536 MB 09:03:41 INFO - Vendor: Intel (0x8086) 09:03:41 INFO - Device ID: 0x0a2e 09:03:41 INFO - Revision ID: 0x0009 09:03:41 INFO - Displays: 09:03:41 INFO - Display: 09:03:41 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:03:41 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:03:41 INFO - Display Serial Number: 1600x1200 60 09:03:41 INFO - Main Display: Yes 09:03:41 INFO - Mirror: Off 09:03:41 INFO - Online: Yes 09:03:41 INFO - Rotation: Supported 09:03:41 INFO - Return code: 0 09:03:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 09:03:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 09:03:41 INFO - Python 2.7.3 09:03:41 INFO - Return code: 0 09:03:41 INFO - grabbing minidump binary from tooltool 09:03:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:03:41 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 0x101ed3d30>, '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 0x101dfadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101ec8be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 09:03:41 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 09:03:41 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 09:03:41 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 09:03:41 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:03:41 INFO - Return code: 0 09:03:41 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 09:03:41 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 09:03:41 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 09:03:41 INFO - ENV: RUST_BACKTRACE is now 1 09:03:41 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 09:03:41 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 09:03:41 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/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/UjpqHGdGR7-sSi1Op6VhNA/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0111', '--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 09:03:41 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/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/UjpqHGdGR7-sSi1Op6VhNA/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0111 --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 09:03:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xQdOQDhDM8/Render', 09:03:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:03:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:03:41 INFO - 'HOME': '/Users/cltbld', 09:03:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:03:41 INFO - 'LOGNAME': 'cltbld', 09:03:41 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:03:41 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 09:03:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:03:41 INFO - 'MOZ_NO_REMOTE': '1', 09:03:41 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:03:41 INFO - 'NO_EM_RESTART': '1', 09:03:41 INFO - 'PAGER': '/bin/cat', 09:03:41 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:03:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:03:41 INFO - 'PWD': '/builds/slave/test', 09:03:41 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:03:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:03:41 INFO - 'RUST_BACKTRACE': '1', 09:03:41 INFO - 'SHELL': '/bin/bash', 09:03:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cE9aYYpqHB/Listeners', 09:03:41 INFO - 'TMPDIR': '/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/', 09:03:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:03:41 INFO - 'USER': 'cltbld', 09:03:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:03:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:03:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:03:41 INFO - 'XPC_FLAGS': '0x0', 09:03:41 INFO - 'XPC_SERVICE_NAME': '0', 09:03:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:03:41 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/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/UjpqHGdGR7-sSi1Op6VhNA/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0111', '--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 09:03:42 INFO - mozversion application_buildid: 20170313072445 09:03:42 INFO - mozversion application_changeset: 8d9fd089cabd435590250c5e15b781c06a96f0a1 09:03:42 INFO - mozversion application_display_name: Nightly 09:03:42 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:03:42 INFO - mozversion application_name: Firefox 09:03:42 INFO - mozversion application_remotingname: firefox 09:03:42 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 09:03:42 INFO - mozversion application_vendor: Mozilla 09:03:42 INFO - mozversion application_version: 55.0a1 09:03:42 INFO - mozversion platform_buildid: 20170313072445 09:03:42 INFO - mozversion platform_changeset: 8d9fd089cabd435590250c5e15b781c06a96f0a1 09:03:42 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 09:03:42 INFO - mozversion platform_version: 55.0a1 09:03:42 INFO - using testdate: 1489421022 09:03:42 INFO - actual date: 1489421022 09:03:42 INFO - starting webserver on 'localhost:51259' 09:03:42 INFO - SUITE-START | Running 2 tests 09:03:42 INFO - TEST-START | dromaeo_css 09:03:42 INFO - operating with platform_type : mac_ 09:03:42 INFO - Initialising browser for dromaeo_css test... 09:03:42 INFO - TEST-INFO | started process 2008 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/tmpJgb8ZQ/profile http://localhost:51259/getInfo.html) 09:03:44 INFO - PID 2008 | __metrics Screen width/height:1600/1200 09:03:44 INFO - PID 2008 | colorDepth:24 09:03:44 INFO - PID 2008 | Browser inner width/height: 1024/689 09:03:44 INFO - PID 2008 | __metrics 09:03:44 INFO - PID 2008 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 673: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 09:03:44 INFO - PID 2008 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:03:45 INFO - TEST-INFO | 2008: exit 0 09:03:45 INFO - Browser initialized. 09:03:45 INFO - Running cycle 1/1 for dromaeo_css test... 09:03:45 INFO - TEST-INFO | started process 2010 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/tmpJgb8ZQ/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:03:57 INFO - PID 2010 | RSS: Main: 232562688 09:03:57 INFO - PID 2010 | 09:07:04 INFO - PID 2010 | Cycle 1(1): loaded http://localhost:51259/tests/dromaeo/cssquery-dojo.html (next: http://localhost:51259/tests/dromaeo/cssquery-ext.html) 09:07:06 INFO - PID 2010 | RSS: Main: 452071424 09:07:06 INFO - PID 2010 | 09:08:47 INFO - PID 2010 | ************************* 09:08:47 INFO - PID 2010 | A coding exception was thrown and uncaught in a Task. 09:08:47 INFO - PID 2010 | 09:08:47 ERROR - PID 2010 | Full message: TypeError: NetworkError when attempting to fetch resource. 09:08:47 INFO - PID 2010 | Full stack: 09:08:47 INFO - PID 2010 | ************************* 09:10:09 INFO - PID 2010 | Cycle 1(1): loaded http://localhost:51259/tests/dromaeo/cssquery-ext.html (next: http://localhost:51259/tests/dromaeo/cssquery-jquery.html) 09:10:10 INFO - PID 2010 | RSS: Main: 364859392 09:10:10 INFO - PID 2010 | 09:13:13 INFO - PID 2010 | Cycle 1(1): loaded http://localhost:51259/tests/dromaeo/cssquery-jquery.html (next: http://localhost:51259/tests/dromaeo/cssquery-mootools.html) 09:13:13 INFO - PID 2010 | RSS: Main: 359755776 09:13:13 INFO - PID 2010 | 09:16:16 INFO - PID 2010 | Cycle 1(1): loaded http://localhost:51259/tests/dromaeo/cssquery-mootools.html (next: http://localhost:51259/tests/dromaeo/cssquery-prototype.html) 09:16:16 INFO - PID 2010 | RSS: Main: 340959232 09:16:16 INFO - PID 2010 | 09:19:20 INFO - PID 2010 | Cycle 1(1): loaded http://localhost:51259/tests/dromaeo/cssquery-prototype.html (next: http://localhost:51259/tests/dromaeo/cssquery-yui.html) 09:19:21 INFO - PID 2010 | RSS: Main: 338681856 09:19:21 INFO - PID 2010 | 09:22:24 INFO - PID 2010 | Cycle 1(1): loaded http://localhost:51259/tests/dromaeo/cssquery-yui.html (next: http://localhost:51259/tests/dromaeo/cssquery-dojo.html) 09:22:25 INFO - PID 2010 | RSS: Main: 496975872 09:22:25 INFO - PID 2010 | 09:22:25 INFO - PID 2010 | __start_tp_report 09:22:25 INFO - PID 2010 | _x_x_mozilla_page_load 09:22:25 INFO - PID 2010 | _x_x_mozilla_page_load_details 09:22:25 INFO - PID 2010 | |i|pagename|runs| 09:22:25 INFO - PID 2010 | |0;dojo.html;3308.569885914831;3318.983405082976;3339.933201335974;3350.8492117854694;3390.2371966307583;5502.80740174094;5609.691466969315;5623.887522249553;5675.574331925101;5690.715464226788;5603.635763675358;5631.239782629344;5707.229524014257;5723.198752174689;5731.054376027961;5077.619178561623;5091.669041512299;5125.256837758541;5128.461511541291;5152.304438900756;4262.403263543107;4271.487421509408;5230.607704422165;5234.790608375661;5242.842714718546;5058.367704036996;5113.872153196163;5128.769205385729;5142.408623008365;5225.399079105913;5119.923201151985;5133.922991155136;5135.435102138762;5139.434662187156;5146.279520867081;5061.215511595708;5070.112730272188;5095.949040509621;5106.923396149024;5108.974455127701;5549.639273447213;5556.833295001116;5578.721063946827;5583.664980101166;5653.632513886584;6117.593182542236;6123.170895756613;6153.230846144232;6154.907676384858;6158.599691020069;6089.604175728564;6103.428585615342;6121.889865756803;6124.418180272867;6131.070842498486;5816.82549523515;5839.64962102271;5847.94152058474;5897.351291357947;5912.497437717756;6254.437100660872;6259.499240060784;6262.373762623793;6265.122882796412;6276.372362763778;5994.910076348859;6086.482648974798;6107.908381374283;6111.84720381994;6128.693565321716;6116.571839971249;6118.3269840317525;6124.785632502841;6142.262928448615;6142.754289828356;6096.774513597423;6099.104240589599;6119.3197658210165;6127.5404344674325;6130.570860039755;6009.06859436788;6099.329073801879;6108.6759897029115;6122.255819630095;6143.784967526115;4911.705297682151;4927.113119638428;4971.776270067856;5002.424721157041;5014.52362025607;4376.299792033259;4378.627816635558;4380.189664912002;4386.407834942242;4405.493368262691;5688.516476099495;5829.446202610745;5879.176915231871;5913.083472061838;5915.58590898633;6088.756449742049;6097.359777223416;6104.511639068864;6108.877822443526;6116.633002019804;5646.068398714248;5744.0522313817755;5877;5902.970485147547;5921.319048309413;5334.253204551366;5353.4646535346155;5366.597505187126;5375.75809088592;5388.380336261302;4965.751712414292;6160.907586386297;6161.815145545641;6169.074638804036;6172.506199503939;6113.324281714407;6193.256809182927;6198.907016394848;6211.503079753699;6233.906491402723;6277.372262773686;6278.246610406598;6278.372162783685;6291.622502649856;6295.653739044396;5965.642061476326;6143.508519318354;6149.7847575334645;6152.907706384318;6210.658413787104;6114.510272098727;6114.996965385453;6134.40597271149;6196.628202307876;6204.348543403058;6062.090686397076;6073.180120683651;6077.118817771487;6080.331163572092;6122.234720659918;6040.154378386941;6049.0926361046195;6051.818445446467;6069.180660610761;6085.300190478151;5697.316322041382;5750.424957504216;5761.107028410603;5767.884642307215;5908;5905.881882362414;5907.025340818716;5917.171595976481;5924.4667979882015;5929.940700592939;5756.963746525666;5761.625494342854;5771.59598828078;5785.652860828363;5786 09:22:25 INFO - PID 2010 | |1;ext.html;758.924107589241;778.1867947994343;844.721241990143;853.0999795216045;856.4647095565272;26507.867460662696;26975.999999999975;27005.324866878338;27081.593776093374;36057;37503;37947;37992.620073799255;38015.619843801556;38065.23869522615;8711.390202685814;8959.104089591054;8965.32760042996;8967.103289671046;8974.102589741038;7074.964625176867;7257.491975561713;8781.56092195391;8881.822363552725;8891.06643802401;7311.67097480612;7345.522541034842;7372.152202496701;7376.926230737705;10452.686419407391;10614.522346494427;10668.14654827612;10683.465826708634;10691.572337106507;10703.625373111903;10101.989801019912;10528.052475277185;10694.679159625224;10697.358158510515;10711.357318560911;25386.365340866443;25577;37867.81066094652;38009.0497737556;38060.23879522394;2951.4539810135193;3728.2170744143764;3753.549574051104;3756.7745935243975;3758.586555478895;25301.114460993776;25499.087568432442;25553.616695749577;25574.360640983938;25727.742722572908;3057.5719399284117;3104.30153215527;3109.6473034229957;3139.1838122088193;3140.7016333448287;301187;302013;302936.9999999978;303244.9999999978;303487;46206.30690539644;46517.767411163404;46635.6009319721;46674.533254667695;46722.065558688635;37490.25019499595;37690.62309376926;47289.5271047292;48461.75769121202;48661.75669121702;11573.942130289295;11727.648170554898;13864.514741983981;13979.86020139806;13983.230922299168;9542.045795420541;11281.379524126252;11380.601678941197;11389.373584452935;11399.373034483016;12147.514099435924;12227.449764760608;12229.309551898568;12276.508939642314;12408;5148.819791307286;5218.556422704037;5226.97386513065;5245;5302.030862716891;1099.5656715597295;1125.5554056147776;1132.043423307304;1157.2887798713377;1169.7426566155432;25981.870090649427;26110.955561777697;26129.608055879176;26278.474430511284;27118.5932211017;1217.8782121787751;1234.3581337704522;1267.245988636778;1270.0347735720918;1278.360819590205;13352.599422017354;14505.492307769178;14598.854011459962;14976.775348369783;15004.474843380429;268200;361358.38641613774;363270;364751;367010;41625;51040;51188.464346069675;51192;51207.7439612807;1349.3657980749033;1410.1116296732991;1415.7310111078862;1418.9219592922489;1437.525616546537;27849;27861.721382785912;28034.2991425216;28491.857540712994;28566.42867142687;43192.56807431886;43316.78341608272;43345.78327108344;43347;43448.78275608602;290512;295451;327285;333388;333941.33029334695;29780.553291701075;30318.393632126794;30446.695533044385;30451.390972180874;30466.695333046388;37795.05512362213;40865.38701919533;41046.9738256546;41291;41338.58661413468;31715.84142079275;31901;33235;34620.653793461744;34689.30621387609;26847.060352887554;33153.005409837744;33219;34599;34620;29447.26381840471;33459.163520912174;33620.495692564145;33706.325873482885;34273.31453370869;28086.578701319904;29615.25961850971;29739.10782676523;30002.84998574993;30131.62805742935;22550.32349029532;23216.883915580314;23317.76682233156;23382.532349352576;23389.41526461852 09:22:25 INFO - PID 2010 | |2;jquery.html;364.67361711268416;382.1325590908636;393.64768532163714;417.9164167166567;428.60996493191203;13700.451981920723;17389.913050434745;20947;22185.667214991747;22476.550468990612;21483.785162148375;21832.345029649096;22414.88792556035;22616.321510354675;22710;5772.971135144318;6281.403266689657;6469.935300647004;6560.311167327433;6566.704498297588;6207.968960155193;6501.869962600746;6526.184226971629;6542.50931180161;6544.247411547662;6532.183477065367;6547.3125321841;7156.212816590171;7457.0305860238095;7536.133344665352;7160.856782864365;7410.740624078158;7412.295831895961;7486.326230639242;7492.700291988314;7266.781996540112;7277.126744790606;7277.854442911165;7278.708851645928;7295.379892709126;26417;27546.173614791387;28006.719932800614;28331;28399.85800071007;2775.34779326858;2809.5223811951814;2838.2904273931517;2900.6809250982374;2933.7066293370704;16016.679666406722;16394.344226230936;16879.40922067734;16882.240299186567;17029.06340151291;1529.915854627995;2027.807358300959;2028.1785876719953;2030.6344857925567;2064.256867527689;148747;150147;150847;153302.46697532994;155767.2211638946;15441.922790386201;18094.366697165537;19906.203751849764;20548.38354849327;20969.895150524455;33841.32317353639;33875.661243387745;33999.15002124967;34017.48973765395;34039.48940765841;8387.245147936594;8921.55392230386;9291.721248362424;9299.39553929006;9781.755456113511;7172.569645821268;7491.250874912465;7521.548707077483;8145.511269323741;8593.52735599548;8996.820063598821;10209.234307426972;10276.588936442608;10321.690349289533;10384.961503849705;3691.427828686558;4018.564127670858;4148.336266197454;4172.290710579209;4183.163367326547;965.2567523007245;1028.3624153025173;1047.460557812727;1072.4423299884172;1073.044989958937;19233;23033;23326;23435.94538245783;23482.412939676302;1017.2167431078028;1050.847627094067;1051.2115913065202;1083.5286650307141;1091.7925594137248;10946.179036572288;11181.944090279496;11191.384473854016;12065.939670301592;12191.817122743167;651013.174011951;667010;678286;687005;689340;115638;148464;148724;149012;150080.2495987535;1191.1304747534348;1208.1542919956066;1238.3127364312713;1240.5472002719123;1241.2180326394493;19366.903165484084;20251;20280.391588252376;20292.695609566163;20293.49266268355;37770.43344349782;47038;54821;56214;58046.4195358041;83589.65457385726;83765;86083;86743;88906.55546722225;11243.60647377338;11340.773184536429;11485.770284594428;11564.074874010228;11608.24546404481;12841;12864.035197360048;13085.41115649817;13206.207627542379;13298;11207.551697931991;11246.04408625276;11439.42802859837;11469.885301146882;11483.023942964608;11142.498587563741;11278.165423755961;11309.943450282695;11425.771484570429;11593.304401735923;11370.431478426211;11634;11687.532498700297;11746.236494627821;11813.468393922121;10907.78184436291;11023.669289921316;11401.030912372209;11438.771224575628;11784.764304713686;9728.951355243462;11112.666620001413;11119.388433636228;11468.426578670866;11505.942470287595 09:22:25 INFO - PID 2010 | |3;mootools.html;1524.5959820647538;1570.9057456552607;1600.59985003749;1606.349428481465;1652.017049855336;10167.339122957002;10594;10596.774677317773;10603.893961060377;10620.99100585445;8505.149485051492;10800.891991080087;10805.783884322309;10805.891941080587;11018.559257629686;2308.8730119843444;2346.8826558672085;2347.4600841806396;2349.119080344871;2350.1656911796235;2283.748787633359;2320.0487800002;2322.071171531384;2322.105989194164;2324;1934.487360849376;2299.7815207555254;2312.5952958232324;2324.2213858357472;2325.988370058147;2277.4761804784994;2291.209532711212;2295.059025799414;2298.3909264044873;2301.2750983440164;1870.738096666454;2254.211026140854;2315.2822624986306;2316;2318.1654604342425;10945.56217751289;11027.724306892393;11033.668989930315;11056.778864422666;11075.446227688582;5370.275012873251;5595.076812325961;5599.888002239933;5601.775928962837;5607.271054762896;3325.667433256679;3392.6098498672477;3661.9816900915325;3670.3576874046935;3676.8529258829612;1172.302480024384;1188.1504724122208;1218.3603608105727;1218.9268643881308;1228.8648248692637;13465.86534134656;13887.58337249884;13916.860831391657;15287.541373758804;15336.539903802905;4101.958980410217;4207.789610519461;4219.767912764827;4473.642108631302;4666.743329116931;11385;11404.657860264206;11556.075513958864;11557.190996630155;11568.710782230512;5614.6631202128;5632.690202038927;5634.098544232903;5642.097264437671;5642.181883626933;4829.637777166727;5116.565091967224;5182.792688292426;5187.844364669066;5195.090859099642;3074.8776696505615;3785.37541305687;3818.0836599215986;3827.3876179811095;3874.767513949172;1033.106362996003;1034.818906691326;1036.8392899100652;1050.3487837540622;1051.3271506236015;832.4339449174498;882.993387538207;922.524899676667;965.5944503308627;1000.1948431512702;5127.384713834289;6534.150560427114;6675.566088204251;6801.965990170018;6802.285759995229;851.535912927456;878.9384743067924;997.7904640025531;1000.0949141027381;1046.931949423285;4206.326987681956;4226;4378.868633940987;4391.429114215131;4395.846145384896;13096.869031309565;13601.659958500919;13616;13625.659358515919;13660.180389176681;11142.721431964104;13053;13212.273324967027;13509;13535.526256580972;9905.306628535933;9929.80140397188;9937.950310248547;9939.353941993748;10096.242781791396;6436.227652681709;6619.404253617197;6619.801405957829;6636.303188165173;6651.501137414616;11904.107191960464;11943.641690749291;11957.342346171044;12061.396930153633;12247.75504489923;10590.523426445674;11237.752449509968;12314.81527777102;12493.875061249271;12618.936905315415;7050.25972272904;8230.547319897463;8257.38069644791;8314.916850831656;8358.582070896553;10011.599536018477;10045.146162576262;10179.134773544332;10179.7455063624;10234.181265498593;9076.319276054455;9084.772880678036;9106.271498280254;9156.908430915872;9192.448453092837;7365.9631701841145;8769.517676527852;8944.821103577762;8969.327300452622;8991;7884.881726773988;7941.682332706627;7965.800854978672;8008.399370047387;8023.598820058857;5863.384344643921;5868.23712917318;6025.246844144482;6085.330613632408;6109.20580324555;4788.329633851334;5888.793892213752;6019.367966363468;6020.458158765732;6102.420270074336 09:22:25 INFO - PID 2010 | |4;prototype.html;381.8835255247149;390.15920690911076;390.38514339914644;390.4143784323515;394.6389054015575;5143.279940808286;5560.972195139018;5653.943460565398;5654.575906806996;5811.651300921937;5530.364008139065;5599.300087489064;5623.831285061455;5661.547076233902;5666.781641946981;3834.5620392352866;4136.9450790048495;4423.513413524526;4536.773161341936;4670.532946705336;4362.650987920974;4694.788734506938;4698.412698412699;4731.9763401182945;4773.045390921812;3828.25349056934;4682.578567928886;4704.576588107069;4715.528447155291;4730.858074257778;3874.9031274218087;4577.7482238476705;4649.581537661593;4690.742009189493;4733.928991065137;4049.5343035550995;4247.193033323659;4261.637760790337;4267.935980960288;4302.311630139163;5480.26016487777;5490.478404551601;5535.833924982257;5540.584456165804;5542.279503664538;4354.259775838115;5395.271638328814;5422.186671999192;5430.42980487051;5463.153211252262;5281.86795330116;5317.521423071942;5997.920374332618;6000.419819477623;6021.608595441282;4176.352665336878;4723.858284251478;4802.111609352281;4825.927611085836;4828.324034635154;5531.63679177039;6386.68066596673;6447.097406363113;6466.256380516254;6482.6434546099945;4746.243889760742;4928.975355123202;4938.827141050046;4959.181735013683;4966.975165124223;4708.246680531099;5064.240363945438;5065.898682026339;5080.695158290514;5082.237664350377;5335.599830012765;5431.728413579305;5441.564674826004;5451.318585176853;5452.5365343946205;5273.6572122811895;5910.911336329958;6073.0221704783435;6085.148079268906;6140.723667434976;1467.611083062989;1653.1568899861156;1716.6266986410822;1728.87897847152;1763.3211213682569;1258.2576279994846;1269.4604792962955;1281.5130250504935;1285.0362228328754;1286.9613911582667;4980.003999200175;5213.556847667991;5766.769329226868;5787.334456537552;5790.915270502615;5344.198370244417;5380.488853558905;5475.370332411823;6264.809686159615;6276.623402595767;5562.527185189304;5725.370209276976;5763.971180144073;5789.652620842763;5801.129830525455;3262.9347413051605;3294.9835250823594;3304.5538852255117;3307.7188438982957;3326.120741780886;5510.393856675762;6102.9930061540235;6201.248244811914;6289.654069026156;6513.381228783258;5614.1859430382365;5850.53195744348;5961;6303.3066362700065;6326;5398.78404863801;6400.327965563642;6404.615723056539;6410.294867564564;6423.807285781434;5460.3618914326335;5624.690642014565;6085.939140608537;6224.564280500503;6274;5124.9743751281;6093.177421048279;6116.327204007466;6142.600730952474;6161.199044124414;5172.689638621544;5312.043832110257;6550.181227346582;6552.443042341454;6562.704678289584;4118.814653340547;4187.979060104802;4759.262869052702;4950.77721502526;4972.279019542074;6227.439530442282;6283.497320214463;6335.4931605472375;6344.9048264277;6433.324500927335;4984.675996060245;5679.6876171810945;6103.877922441615;6135;6167.907481387872;4774.474807771212;6054.57916277302;6063.757449701962;6074.686972249576;6090.878182436415;4935.729564400416;4938.666560028844;5569.637973531708;5954.523638108854;5974.253218347707;4955.547004232928;5907.852303692442;6007.339192688888;6021.187139736079;6029.306629737461;5881.2942446906645;5892.823215303548;5896.941030589811;5912.231409916683;5914.467697907209 09:22:25 INFO - PID 2010 | |5;yui.html;644.4779728419983;679.7858674517527;726.1866709285601;819.6680344460493;821.8931538899942;14958.77561836572;15810;15886.285117169726;15895.920520397396;15947.92026039871;14553.854461455383;14559.490417835377;14575.92712036441;14695.77956330656;14755.852441475608;930.0652843891896;950.4867371619317;954.5179684259439;996.1035068438428;998.6804222648756;960.8222478841436;972.1687956796943;977.0669011094424;978.3298440568198;978.8972157923422;1025.4616326428647;1026.420072658949;1026.9986762906144;1029.2229366828035;1029.6396261308519;1016.8576399304103;1018.5518371916407;1018.6791160784403;1020.6376736258595;1020.7550187954874;755.3315315945373;757.4962649837906;757.9317861392447;759.6961215513784;759.9164091949881;15708;16132.516024519166;16174.676506469923;16178;16469.91765041167;12015.93992030043;14454.205018723966;14460.710785784331;14552.781708274384;14645.853541464556;11517.243091590784;11796.823047654292;11819.231749936227;12144.392780360946;12382.566610168691;3608.8376023078763;3681.849190580741;3723.590405055442;3831.980840095782;3834.5973672764517;73581;93798;97360;101366;105406.47296763543;9654.469004204695;11469.827952580717;11644.475998580085;11939.641810745692;12046.15676902626;23810.52378952411;24065.63901541478;24212.636810447857;24344.26967190987;24373.63439548408;7849.254320839511;7890.842183156419;7892.408069394818;7969.960150199212;8255.25702686761;3950.802459876995;3968.1270120573427;3968.7420317679257;3982.3230050891416;3992.061865461614;3473.6700013498676;3523.0311664292526;3553.8578456861437;3555.8755443559353;3562.0738607962116;649.3862751063604;651.5732195412013;652.1717418877998;654.7479220500046;664.7905909638448;636.7612145445459;651.5308977536166;672.6266921858318;694.2467422846167;703.5110598134349;9025.864612030824;9103.135202155785;9802.607895684092;10027.749306267402;10106.444145572064;634.2072409488139;649.5810202419412;665.9234188068338;674.689642764324;700.56890169677;4920.803167873317;5217.616942284826;5365.785368585213;5427.810026649048;5574.08027675429;76069.61965190139;87149.56425217833;96961;97823;99120.50439747899;49571.50428495741;56517;66172;84079;87772.1222787764;56464;65573.67213163999;66306;67593.32406675968;67824;8750;9021.278297736035;9711.708648740549;10612.310199836986;10823.296485728717;38990.4151437723;44408.55591444044;45822.31266531072;48465;49862.002759945324;58475.70762146162;58953.41046589479;59547.70226148841;59654;59758.40241597528;13165.451514171402;13303.268320242092;13372.93313533426;13537.120087194695;13959.581212563638;14135.575932722035;14277.500287489887;14745.631359216106;15227.314770835117;15511.146886921328;13540.458381664623;13561.75370253229;13752.243626600633;13809.51666691661;13922.373493192628;12990.675233119247;13104.606861794162;13420.865791342352;13613.455461781417;14186.432542698176;12934.288614126313;13217.867821322048;13228.537001204912;13465;14010.159390436607;10845.250736877573;10977.560897564008;11004.919811531112;11119.44402779874;11975;10201.591936322464;10630.627928022483;10967.275464521856;11300.265482743594;11349.092072634005 09:22:25 INFO - PID 2010 | __end_tp_report 09:22:25 INFO - PID 2010 | __start_cc_report 09:22:25 INFO - PID 2010 | _x_x_mozilla_cycle_collect,2592 09:22:25 INFO - PID 2010 | __end_cc_report 09:22:25 INFO - PID 2010 | __startTimestamp1489422145144__endTimestamp 09:22:25 INFO - PID 2010 | 09:22:25 INFO - PID 2010 | ------- Summary: start ------- 09:22:25 INFO - PID 2010 | Number of tests: 6 09:22:25 INFO - PID 2010 | 09:22:25 INFO - PID 2010 | [#0] dojo.html Cycles:165 Average:5670.38 Median:5907.03 stddev:640.34 (10.8%) stddev-sans-first:614.92 09:22:25 INFO - PID 2010 | Values: 3308.6 3319.0 3339.9 3350.8 3390.2 5502.8 5609.7 5623.9 5675.6 5690.7 5603.6 5631.2 5707.2 5723.2 5731.1 5077.6 5091.7 5125.3 5128.5 5152.3 4262.4 4271.5 5230.6 5234.8 5242.8 5058.4 5113.9 5128.8 5142.4 5225.4 5119.9 5133.9 5135.4 5139.4 5146.3 5061.2 5070.1 5095.9 5106.9 5109.0 5549.6 5556.8 5578.7 5583.7 5653.6 6117.6 6123.2 6153.2 6154.9 6158.6 6089.6 6103.4 6121.9 6124.4 6131.1 5816.8 5839.6 5847.9 5897.4 5912.5 6254.4 6259.5 6262.4 6265.1 6276.4 5994.9 6086.5 6107.9 6111.8 6128.7 6116.6 6118.3 6124.8 6142.3 6142.8 6096.8 6099.1 6119.3 6127.5 6130.6 6009.1 6099.3 6108.7 6122.3 6143.8 4911.7 4927.1 4971.8 5002.4 5014.5 4376.3 4378.6 4380.2 4386.4 4405.5 5688.5 5829.4 5879.2 5913.1 5915.6 6088.8 6097.4 6104.5 6108.9 6116.6 5646.1 5744.1 5877.0 5903.0 5921.3 5334.3 5353.5 5366.6 5375.8 5388.4 4965.8 6160.9 6161.8 6169.1 6172.5 6113.3 6193.3 6198.9 6211.5 6233.9 6277.4 6278.2 6278.4 6291.6 6295.7 5965.6 6143.5 6149.8 6152.9 6210.7 6114.5 6115.0 6134.4 6196.6 6204.3 6062.1 6073.2 6077.1 6080.3 6122.2 6040.2 6049.1 6051.8 6069.2 6085.3 5697.3 5750.4 5761.1 5767.9 5908.0 5905.9 5907.0 5917.2 5924.5 5929.9 5757.0 5761.6 5771.6 5785.7 5786.0 09:22:25 INFO - PID 2010 | 09:22:25 INFO - PID 2010 | [#1] ext.html Cycles:180 Average:46165.24 Median:30459.04 stddev:84877.57 (278.7%) stddev-sans-first:85047.21 09:22:25 INFO - PID 2010 | Values: 758.9 778.2 844.7 853.1 856.5 26507.9 26976.0 27005.3 27081.6 36057.0 37503.0 37947.0 37992.6 38015.6 38065.2 8711.4 8959.1 8965.3 8967.1 8974.1 7075.0 7257.5 8781.6 8881.8 8891.1 7311.7 7345.5 7372.2 7376.9 10452.7 10614.5 10668.1 10683.5 10691.6 10703.6 10102.0 10528.1 10694.7 10697.4 10711.4 25386.4 25577.0 37867.8 38009.0 38060.2 2951.5 3728.2 3753.5 3756.8 3758.6 25301.1 25499.1 25553.6 25574.4 25727.7 3057.6 3104.3 3109.6 3139.2 3140.7 301187.0 302013.0 302937.0 303245.0 303487.0 46206.3 46517.8 46635.6 46674.5 46722.1 37490.3 37690.6 47289.5 48461.8 48661.8 11573.9 11727.6 13864.5 13979.9 13983.2 9542.0 11281.4 11380.6 11389.4 11399.4 12147.5 12227.4 12229.3 12276.5 12408.0 5148.8 5218.6 5227.0 5245.0 5302.0 1099.6 1125.6 1132.0 1157.3 1169.7 25981.9 26111.0 26129.6 26278.5 27118.6 1217.9 1234.4 1267.2 1270.0 1278.4 13352.6 14505.5 14598.9 14976.8 15004.5 268200.0 361358.4 363270.0 364751.0 367010.0 41625.0 51040.0 51188.5 51192.0 51207.7 1349.4 1410.1 1415.7 1418.9 1437.5 27849.0 27861.7 28034.3 28491.9 28566.4 43192.6 43316.8 43345.8 43347.0 43448.8 290512.0 295451.0 327285.0 333388.0 333941.3 29780.6 30318.4 30446.7 30451.4 30466.7 37795.1 40865.4 41047.0 41291.0 41338.6 31715.8 31901.0 33235.0 34620.7 34689.3 26847.1 33153.0 33219.0 34599.0 34620.0 29447.3 33459.2 33620.5 33706.3 34273.3 28086.6 29615.3 29739.1 30002.8 30131.6 22550.3 23216.9 23317.8 23382.5 23389.4 09:22:25 INFO - PID 2010 | 09:22:25 INFO - PID 2010 | [#2] jquery.html Cycles:180 Average:40373.88 Median:21226.84 stddev:112854.63 (531.7%) stddev-sans-first:113131.23 09:22:25 INFO - PID 2010 | Values: 364.7 382.1 393.6 417.9 428.6 13700.5 17389.9 20947.0 22185.7 22476.6 21483.8 21832.3 22414.9 22616.3 22710.0 5773.0 6281.4 6469.9 6560.3 6566.7 6208.0 6501.9 6526.2 6542.5 6544.2 6532.2 6547.3 7156.2 7457.0 7536.1 7160.9 7410.7 7412.3 7486.3 7492.7 7266.8 7277.1 7277.9 7278.7 7295.4 26417.0 27546.2 28006.7 28331.0 28399.9 2775.3 2809.5 2838.3 2900.7 2933.7 16016.7 16394.3 16879.4 16882.2 17029.1 1529.9 2027.8 2028.2 2030.6 2064.3 148747.0 150147.0 150847.0 153302.5 155767.2 15441.9 18094.4 19906.2 20548.4 20969.9 33841.3 33875.7 33999.2 34017.5 34039.5 8387.2 8921.6 9291.7 9299.4 9781.8 7172.6 7491.3 7521.5 8145.5 8593.5 8996.8 10209.2 10276.6 10321.7 10385.0 3691.4 4018.6 4148.3 4172.3 4183.2 965.3 1028.4 1047.5 1072.4 1073.0 19233.0 23033.0 23326.0 23435.9 23482.4 1017.2 1050.8 1051.2 1083.5 1091.8 10946.2 11181.9 11191.4 12065.9 12191.8 651013.2 667010.0 678286.0 687005.0 689340.0 115638.0 148464.0 148724.0 149012.0 150080.2 1191.1 1208.2 1238.3 1240.5 1241.2 19366.9 20251.0 20280.4 20292.7 20293.5 37770.4 47038.0 54821.0 56214.0 58046.4 83589.7 83765.0 86083.0 86743.0 88906.6 11243.6 11340.8 11485.8 11564.1 11608.2 12841.0 12864.0 13085.4 13206.2 13298.0 11207.6 11246.0 11439.4 11469.9 11483.0 11142.5 11278.2 11309.9 11425.8 11593.3 11370.4 11634.0 11687.5 11746.2 11813.5 10907.8 11023.7 11401.0 11438.8 11784.8 9729.0 11112.7 11119.4 11468.4 11505.9 09:22:25 INFO - PID 2010 | 09:22:25 INFO - PID 2010 | [#3] mootools.html Cycles:180 Average:6553.77 Median:2348.29 stddev:4083.47 (173.9%) stddev-sans-first:4077.44 09:22:25 INFO - PID 2010 | Values: 1524.6 1570.9 1600.6 1606.3 1652.0 10167.3 10594.0 10596.8 10603.9 10621.0 8505.1 10800.9 10805.8 10805.9 11018.6 2308.9 2346.9 2347.5 2349.1 2350.2 2283.7 2320.0 2322.1 2322.1 2324.0 1934.5 2299.8 2312.6 2324.2 2326.0 2277.5 2291.2 2295.1 2298.4 2301.3 1870.7 2254.2 2315.3 2316.0 2318.2 10945.6 11027.7 11033.7 11056.8 11075.4 5370.3 5595.1 5599.9 5601.8 5607.3 3325.7 3392.6 3662.0 3670.4 3676.9 1172.3 1188.2 1218.4 1218.9 1228.9 13465.9 13887.6 13916.9 15287.5 15336.5 4102.0 4207.8 4219.8 4473.6 4666.7 11385.0 11404.7 11556.1 11557.2 11568.7 5614.7 5632.7 5634.1 5642.1 5642.2 4829.6 5116.6 5182.8 5187.8 5195.1 3074.9 3785.4 3818.1 3827.4 3874.8 1033.1 1034.8 1036.8 1050.3 1051.3 832.4 883.0 922.5 965.6 1000.2 5127.4 6534.2 6675.6 6802.0 6802.3 851.5 878.9 997.8 1000.1 1046.9 4206.3 4226.0 4378.9 4391.4 4395.8 13096.9 13601.7 13616.0 13625.7 13660.2 11142.7 13053.0 13212.3 13509.0 13535.5 9905.3 9929.8 9938.0 9939.4 10096.2 6436.2 6619.4 6619.8 6636.3 6651.5 11904.1 11943.6 11957.3 12061.4 12247.8 10590.5 11237.8 12314.8 12493.9 12618.9 7050.3 8230.5 8257.4 8314.9 8358.6 10011.6 10045.1 10179.1 10179.7 10234.2 9076.3 9084.8 9106.3 9156.9 9192.4 7366.0 8769.5 8944.8 8969.3 8991.0 7884.9 7941.7 7965.8 8008.4 8023.6 5863.4 5868.2 6025.2 6085.3 6109.2 4788.3 5888.8 6019.4 6020.5 6102.4 09:22:25 INFO - PID 2010 | 09:22:25 INFO - PID 2010 | [#4] prototype.html Cycles:180 Average:5044.17 Median:5456.45 stddev:1409.32 (25.8%) stddev-sans-first:1369.14 09:22:25 INFO - PID 2010 | Values: 381.9 390.2 390.4 390.4 394.6 5143.3 5561.0 5653.9 5654.6 5811.7 5530.4 5599.3 5623.8 5661.5 5666.8 3834.6 4136.9 4423.5 4536.8 4670.5 4362.7 4694.8 4698.4 4732.0 4773.0 3828.3 4682.6 4704.6 4715.5 4730.9 3874.9 4577.7 4649.6 4690.7 4733.9 4049.5 4247.2 4261.6 4267.9 4302.3 5480.3 5490.5 5535.8 5540.6 5542.3 4354.3 5395.3 5422.2 5430.4 5463.2 5281.9 5317.5 5997.9 6000.4 6021.6 4176.4 4723.9 4802.1 4825.9 4828.3 5531.6 6386.7 6447.1 6466.3 6482.6 4746.2 4929.0 4938.8 4959.2 4967.0 4708.2 5064.2 5065.9 5080.7 5082.2 5335.6 5431.7 5441.6 5451.3 5452.5 5273.7 5910.9 6073.0 6085.1 6140.7 1467.6 1653.2 1716.6 1728.9 1763.3 1258.3 1269.5 1281.5 1285.0 1287.0 4980.0 5213.6 5766.8 5787.3 5790.9 5344.2 5380.5 5475.4 6264.8 6276.6 5562.5 5725.4 5764.0 5789.7 5801.1 3262.9 3295.0 3304.6 3307.7 3326.1 5510.4 6103.0 6201.2 6289.7 6513.4 5614.2 5850.5 5961.0 6303.3 6326.0 5398.8 6400.3 6404.6 6410.3 6423.8 5460.4 5624.7 6085.9 6224.6 6274.0 5125.0 6093.2 6116.3 6142.6 6161.2 5172.7 5312.0 6550.2 6552.4 6562.7 4118.8 4188.0 4759.3 4950.8 4972.3 6227.4 6283.5 6335.5 6344.9 6433.3 4984.7 5679.7 6103.9 6135.0 6167.9 4774.5 6054.6 6063.8 6074.7 6090.9 4935.7 4938.7 5569.6 5954.5 5974.3 4955.5 5907.9 6007.3 6021.2 6029.3 5881.3 5892.8 5896.9 5912.2 5914.5 09:22:25 INFO - PID 2010 | 09:22:25 INFO - PID 2010 | [#5] yui.html Cycles:180 Average:18729.96 Median:3645.34 stddev:25285.65 (693.6%) stddev-sans-first:25320.11 09:22:25 INFO - PID 2010 | Values: 644.5 679.8 726.2 819.7 821.9 14958.8 15810.0 15886.3 15895.9 15947.9 14553.9 14559.5 14575.9 14695.8 14755.9 930.1 950.5 954.5 996.1 998.7 960.8 972.2 977.1 978.3 978.9 1025.5 1026.4 1027.0 1029.2 1029.6 1016.9 1018.6 1018.7 1020.6 1020.8 755.3 757.5 757.9 759.7 759.9 15708.0 16132.5 16174.7 16178.0 16469.9 12015.9 14454.2 14460.7 14552.8 14645.9 11517.2 11796.8 11819.2 12144.4 12382.6 3608.8 3681.8 3723.6 3832.0 3834.6 73581.0 93798.0 97360.0 101366.0 105406.5 9654.5 11469.8 11644.5 11939.6 12046.2 23810.5 24065.6 24212.6 24344.3 24373.6 7849.3 7890.8 7892.4 7970.0 8255.3 3950.8 3968.1 3968.7 3982.3 3992.1 3473.7 3523.0 3553.9 3555.9 3562.1 649.4 651.6 652.2 654.7 664.8 636.8 651.5 672.6 694.2 703.5 9025.9 9103.1 9802.6 10027.7 10106.4 634.2 649.6 665.9 674.7 700.6 4920.8 5217.6 5365.8 5427.8 5574.1 76069.6 87149.6 96961.0 97823.0 99120.5 49571.5 56517.0 66172.0 84079.0 87772.1 56464.0 65573.7 66306.0 67593.3 67824.0 8750.0 9021.3 9711.7 10612.3 10823.3 38990.4 44408.6 45822.3 48465.0 49862.0 58475.7 58953.4 59547.7 59654.0 59758.4 13165.5 13303.3 13372.9 13537.1 13959.6 14135.6 14277.5 14745.6 15227.3 15511.1 13540.5 13561.8 13752.2 13809.5 13922.4 12990.7 13104.6 13420.9 13613.5 14186.4 12934.3 13217.9 13228.5 13465.0 14010.2 10845.3 10977.6 11004.9 11119.4 11975.0 10201.6 10630.6 10967.3 11300.3 11349.1 09:22:25 INFO - PID 2010 | -------- Summary: end -------- 09:22:25 INFO - PID 2010 | 09:22:25 INFO - TEST-INFO | 2010: exit 0 09:22:25 INFO - TEST-OK | dromaeo_css | took 1123812ms 09:22:25 INFO - TEST-START | kraken 09:22:25 INFO - operating with platform_type : mac_ 09:22:25 INFO - Initialising browser for kraken test... 09:22:25 INFO - TEST-INFO | started process 2015 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/tmpKLreD7/profile http://localhost:51259/getInfo.html) 09:22:28 INFO - PID 2015 | __metrics Screen width/height:1600/1200 09:22:28 INFO - PID 2015 | colorDepth:24 09:22:28 INFO - PID 2015 | Browser inner width/height: 1024/689 09:22:28 INFO - PID 2015 | __metrics 09:22:28 INFO - PID 2015 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 673: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 09:22:28 INFO - PID 2015 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:22:29 INFO - TEST-INFO | 2015: exit 0 09:22:29 INFO - Browser initialized. 09:22:29 INFO - Running cycle 1/1 for kraken test... 09:22:29 INFO - TEST-INFO | started process 2016 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/tmpKLreD7/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:22:41 INFO - PID 2016 | RSS: Main: 232439808 09:22:41 INFO - PID 2016 | 09:22:44 INFO - PID 2016 | Cycle 1(1): loaded http://localhost:51259/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:51259/tests/kraken/driver.html?testName=audio-beat-detection) 09:22:45 INFO - PID 2016 | RSS: Main: 285048832 09:22:45 INFO - PID 2016 | 09:22:47 INFO - PID 2016 | Cycle 1(1): loaded http://localhost:51259/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:51259/tests/kraken/driver.html?testName=audio-dft) 09:22:48 INFO - PID 2016 | RSS: Main: 284635136 09:22:48 INFO - PID 2016 | 09:22:51 INFO - PID 2016 | Cycle 1(1): loaded http://localhost:51259/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:51259/tests/kraken/driver.html?testName=audio-fft) 09:22:52 INFO - PID 2016 | RSS: Main: 379158528 09:22:52 INFO - PID 2016 | 09:22:54 INFO - PID 2016 | Cycle 1(1): loaded http://localhost:51259/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:51259/tests/kraken/driver.html?testName=audio-oscillator) 09:22:55 INFO - PID 2016 | RSS: Main: 282533888 09:22:55 INFO - PID 2016 | 09:22:57 INFO - PID 2016 | Cycle 1(1): loaded http://localhost:51259/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:51259/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:22:58 INFO - PID 2016 | RSS: Main: 286371840 09:22:58 INFO - PID 2016 | 09:23:00 INFO - PID 2016 | Cycle 1(1): loaded http://localhost:51259/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:51259/tests/kraken/driver.html?testName=imaging-darkroom) 09:23:01 INFO - PID 2016 | RSS: Main: 329388032 09:23:01 INFO - PID 2016 | 09:23:04 INFO - PID 2016 | Cycle 1(1): loaded http://localhost:51259/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:51259/tests/kraken/driver.html?testName=imaging-desaturate) 09:23:05 INFO - PID 2016 | RSS: Main: 340168704 09:23:05 INFO - PID 2016 | 09:23:08 INFO - PID 2016 | Cycle 1(1): loaded http://localhost:51259/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:51259/tests/kraken/driver.html?testName=json-parse-financial) 09:23:08 INFO - PID 2016 | RSS: Main: 348753920 09:23:08 INFO - PID 2016 | 09:23:10 INFO - PID 2016 | Cycle 1(1): loaded http://localhost:51259/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:51259/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:23:11 INFO - PID 2016 | RSS: Main: 301998080 09:23:11 INFO - PID 2016 | 09:23:12 INFO - PID 2016 | Cycle 1(1): loaded http://localhost:51259/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:51259/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:23:13 INFO - PID 2016 | RSS: Main: 307376128 09:23:13 INFO - PID 2016 | 09:23:15 INFO - PID 2016 | Cycle 1(1): loaded http://localhost:51259/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:51259/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:23:16 INFO - PID 2016 | RSS: Main: 308428800 09:23:16 INFO - PID 2016 | 09:23:19 INFO - PID 2016 | Cycle 1(1): loaded http://localhost:51259/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:51259/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:23:20 INFO - PID 2016 | RSS: Main: 327020544 09:23:20 INFO - PID 2016 | 09:23:23 INFO - PID 2016 | Cycle 1(1): loaded http://localhost:51259/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:51259/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:23:23 INFO - PID 2016 | RSS: Main: 321298432 09:23:23 INFO - PID 2016 | 09:23:25 INFO - PID 2016 | Cycle 1(1): loaded http://localhost:51259/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:51259/tests/kraken/driver.html?testName=ai-astar) 09:23:25 INFO - PID 2016 | RSS: Main: 382976000 09:23:25 INFO - PID 2016 | 09:23:25 INFO - PID 2016 | __start_tp_report 09:23:25 INFO - PID 2016 | _x_x_mozilla_page_load 09:23:25 INFO - PID 2016 | _x_x_mozilla_page_load_details 09:23:25 INFO - PID 2016 | |i|pagename|runs| 09:23:25 INFO - PID 2016 | |0;ai-astar;113;114;114;112;111;114;115;114;115;114 09:23:25 INFO - PID 2016 | |1;audio-beat-detection;131;113;111;149;112;110;109;111;136;112 09:23:25 INFO - PID 2016 | |2;audio-dft;186;201;264;185;180;182;186;185;182;187 09:23:25 INFO - PID 2016 | |3;audio-fft;82;59;76;124;87;58;62;58;79;58 09:23:25 INFO - PID 2016 | |4;audio-oscillator;88;70;91;70;71;69;85;71;93;119 09:23:25 INFO - PID 2016 | |5;imaging-gaussian-blur;93;87;87;86;87;87;87;87;87;85 09:23:25 INFO - PID 2016 | |6;imaging-darkroom;94;94;94;94;94;95;116;150;133;93 09:23:25 INFO - PID 2016 | |7;imaging-desaturate;86;83;86;86;83;86;86;82;83;86 09:23:25 INFO - PID 2016 | |8;json-parse-financial;64;63;62;62;61;63;62;63;62;63 09:23:25 INFO - PID 2016 | |9;json-stringify-tinderbox;54;55;63;70;63;63;59;58;57;58 09:23:25 INFO - PID 2016 | |10;stanford-crypto-aes;85;82;84;83;82;82;80;83;81;84 09:23:25 INFO - PID 2016 | |11;stanford-crypto-ccm;143;153;149;140;144;124;139;140;201;140 09:23:25 INFO - PID 2016 | |12;stanford-crypto-pbkdf2;189;185;185;187;184;232;193;184;185;184 09:23:25 INFO - PID 2016 | |13;stanford-crypto-sha256-iterative;67;68;65;67;66;68;68;68;67;69 09:23:25 INFO - PID 2016 | __end_tp_report 09:23:25 INFO - PID 2016 | __start_cc_report 09:23:25 INFO - PID 2016 | _x_x_mozilla_cycle_collect,2099 09:23:25 INFO - PID 2016 | __end_cc_report 09:23:25 INFO - PID 2016 | __startTimestamp1489422205874__endTimestamp 09:23:25 INFO - PID 2016 | 09:23:25 INFO - PID 2016 | ------- Summary: start ------- 09:23:25 INFO - PID 2016 | Number of tests: 14 09:23:25 INFO - PID 2016 | 09:23:25 INFO - PID 2016 | [#0] ai-astar Cycles:10 Average:113.60 Median:114.00 stddev:1.26 (1.1%) stddev-sans-first:1.32 09:23:25 INFO - PID 2016 | Values: 113.0 114.0 114.0 112.0 111.0 114.0 115.0 114.0 115.0 114.0 09:23:25 INFO - PID 2016 | 09:23:25 INFO - PID 2016 | [#1] audio-beat-detection Cycles:10 Average:119.40 Median:112.50 stddev:14.04 (12.5%) stddev-sans-first:14.25 09:23:25 INFO - PID 2016 | Values: 131.0 113.0 111.0 149.0 112.0 110.0 109.0 111.0 136.0 112.0 09:23:25 INFO - PID 2016 | 09:23:25 INFO - PID 2016 | [#2] audio-dft Cycles:10 Average:193.80 Median:186.00 stddev:25.32 (13.6%) stddev-sans-first:26.70 09:23:25 INFO - PID 2016 | Values: 186.0 201.0 264.0 185.0 180.0 182.0 186.0 185.0 182.0 187.0 09:23:25 INFO - PID 2016 | 09:23:25 INFO - PID 2016 | [#3] audio-fft Cycles:10 Average:74.30 Median:69.00 stddev:20.81 (30.2%) stddev-sans-first:21.89 09:23:25 INFO - PID 2016 | Values: 82.0 59.0 76.0 124.0 87.0 58.0 62.0 58.0 79.0 58.0 09:23:25 INFO - PID 2016 | 09:23:25 INFO - PID 2016 | [#4] audio-oscillator Cycles:10 Average:82.70 Median:78.00 stddev:16.02 (20.5%) stddev-sans-first:16.88 09:23:25 INFO - PID 2016 | Values: 88.0 70.0 91.0 70.0 71.0 69.0 85.0 71.0 93.0 119.0 09:23:25 INFO - PID 2016 | 09:23:25 INFO - PID 2016 | [#5] imaging-gaussian-blur Cycles:10 Average:87.30 Median:87.00 stddev:2.11 (2.4%) stddev-sans-first:0.71 09:23:25 INFO - PID 2016 | Values: 93.0 87.0 87.0 86.0 87.0 87.0 87.0 87.0 87.0 85.0 09:23:25 INFO - PID 2016 | 09:23:25 INFO - PID 2016 | [#6] imaging-darkroom Cycles:10 Average:105.70 Median:94.00 stddev:20.48 (21.8%) stddev-sans-first:21.28 09:23:25 INFO - PID 2016 | Values: 94.0 94.0 94.0 94.0 94.0 95.0 116.0 150.0 133.0 93.0 09:23:25 INFO - PID 2016 | 09:23:25 INFO - PID 2016 | [#7] imaging-desaturate Cycles:10 Average:84.70 Median:86.00 stddev:1.70 (2.0%) stddev-sans-first:1.74 09:23:25 INFO - PID 2016 | Values: 86.0 83.0 86.0 86.0 83.0 86.0 86.0 82.0 83.0 86.0 09:23:25 INFO - PID 2016 | 09:23:25 INFO - PID 2016 | [#8] json-parse-financial Cycles:10 Average:62.50 Median:63.00 stddev:0.85 (1.3%) stddev-sans-first:0.71 09:23:25 INFO - PID 2016 | Values: 64.0 63.0 62.0 62.0 61.0 63.0 62.0 63.0 62.0 63.0 09:23:25 INFO - PID 2016 | 09:23:25 INFO - PID 2016 | [#9] json-stringify-tinderbox Cycles:10 Average:60.00 Median:61.00 stddev:4.78 (7.8%) stddev-sans-first:4.56 09:23:25 INFO - PID 2016 | Values: 54.0 55.0 63.0 70.0 63.0 63.0 59.0 58.0 57.0 58.0 09:23:25 INFO - PID 2016 | 09:23:25 INFO - PID 2016 | [#10] stanford-crypto-aes Cycles:10 Average:82.60 Median:83.00 stddev:1.51 (1.8%) stddev-sans-first:1.32 09:23:25 INFO - PID 2016 | Values: 85.0 82.0 84.0 83.0 82.0 82.0 80.0 83.0 81.0 84.0 09:23:25 INFO - PID 2016 | 09:23:25 INFO - PID 2016 | [#11] stanford-crypto-ccm Cycles:10 Average:147.30 Median:143.50 stddev:20.33 (14.2%) stddev-sans-first:21.50 09:23:25 INFO - PID 2016 | Values: 143.0 153.0 149.0 140.0 144.0 124.0 139.0 140.0 201.0 140.0 09:23:25 INFO - PID 2016 | 09:23:25 INFO - PID 2016 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:190.80 Median:186.00 stddev:14.76 (7.9%) stddev-sans-first:15.64 09:23:25 INFO - PID 2016 | Values: 189.0 185.0 185.0 187.0 184.0 232.0 193.0 184.0 185.0 184.0 09:23:25 INFO - PID 2016 | 09:23:25 INFO - PID 2016 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.30 Median:68.00 stddev:1.16 (1.7%) stddev-sans-first:1.22 09:23:25 INFO - PID 2016 | Values: 67.0 68.0 65.0 67.0 66.0 68.0 68.0 68.0 67.0 69.0 09:23:25 INFO - PID 2016 | -------- Summary: end -------- 09:23:25 INFO - PID 2016 | 09:23:26 INFO - TEST-INFO | 2016: exit 0 09:23:26 INFO - TEST-OK | kraken | took 60962ms 09:23:26 INFO - SUITE-END | took 1184s 09:23:27 INFO - Completed test suite (00:19:44) 09:23:27 INFO - javascript benchmark 09:23:27 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": [], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7626.323574238275, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3308.569885914831, 3318.983405082976, 3339.933201335974, 3350.8492117854694, 3390.2371966307583, 5502.80740174094, 5609.691466969315, 5623.887522249553, 5675.574331925101, 5690.715464226788, 5603.635763675358, 5631.239782629344, 5707.229524014257, 5723.198752174689, 5731.054376027961, 5077.619178561623, 5091.669041512299, 5125.256837758541, 5128.461511541291, 5152.304438900756, 4262.403263543107, 4271.487421509408, 5230.607704422165, 5234.790608375661, 5242.842714718546, 5058.367704036996, 5113.872153196163, 5128.769205385729, 5142.408623008365, 5225.399079105913, 5119.923201151985, 5133.922991155136, 5135.435102138762, 5139.434662187156, 5146.279520867081, 5061.215511595708, 5070.112730272188, 5095.949040509621, 5106.923396149024, 5108.974455127701, 5549.639273447213, 5556.833295001116, 5578.721063946827, 5583.664980101166, 5653.632513886584, 6117.593182542236, 6123.170895756613, 6153.230846144232, 6154.907676384858, 6158.599691020069, 6089.604175728564, 6103.428585615342, 6121.889865756803, 6124.418180272867, 6131.070842498486, 5816.82549523515, 5839.64962102271, 5847.94152058474, 5897.351291357947, 5912.497437717756, 6254.437100660872, 6259.499240060784, 6262.373762623793, 6265.122882796412, 6276.372362763778, 5994.910076348859, 6086.482648974798, 6107.908381374283, 6111.84720381994, 6128.693565321716, 6116.571839971249, 6118.3269840317525, 6124.785632502841, 6142.262928448615, 6142.754289828356, 6096.774513597423, 6099.104240589599, 6119.3197658210165, 6127.5404344674325, 6130.570860039755, 6009.06859436788, 6099.329073801879, 6108.6759897029115, 6122.255819630095, 6143.784967526115, 4911.705297682151, 4927.113119638428, 4971.776270067856, 5002.424721157041, 5014.52362025607, 4376.299792033259, 4378.627816635558, 4380.189664912002, 4386.407834942242, 4405.493368262691, 5688.516476099495, 5829.446202610745, 5879.176915231871, 5913.083472061838, 5915.58590898633, 6088.756449742049, 6097.359777223416, 6104.511639068864, 6108.877822443526, 6116.633002019804, 5646.068398714248, 5744.0522313817755, 5877.0, 5902.970485147547, 5921.319048309413, 5334.253204551366, 5353.4646535346155, 5366.597505187126, 5375.75809088592, 5388.380336261302, 4965.751712414292, 6160.907586386297, 6161.815145545641, 6169.074638804036, 6172.506199503939, 6113.324281714407, 6193.256809182927, 6198.907016394848, 6211.503079753699, 6233.906491402723, 6277.372262773686, 6278.246610406598, 6278.372162783685, 6291.622502649856, 6295.653739044396, 5965.642061476326, 6143.508519318354, 6149.7847575334645, 6152.907706384318, 6210.658413787104, 6114.510272098727, 6114.996965385453, 6134.40597271149, 6196.628202307876, 6204.348543403058, 6062.090686397076, 6073.180120683651, 6077.118817771487, 6080.331163572092, 6122.234720659918, 6040.154378386941, 6049.0926361046195, 6051.818445446467, 6069.180660610761, 6085.300190478151, 5697.316322041382, 5750.424957504216, 5761.107028410603, 5767.884642307215, 5908.0, 5905.881882362414, 5907.025340818716, 5917.171595976481, 5924.4667979882015, 5929.940700592939, 5756.963746525666, 5761.625494342854, 5771.59598828078, 5785.652860828363, 5786.0], "value": 5629.272258876609, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [758.924107589241, 778.1867947994343, 844.721241990143, 853.0999795216045, 856.4647095565272, 26507.867460662696, 26975.999999999975, 27005.324866878338, 27081.593776093374, 36057.0, 37503.0, 37947.0, 37992.620073799255, 38015.619843801556, 38065.23869522615, 8711.390202685814, 8959.104089591054, 8965.32760042996, 8967.103289671046, 8974.102589741038, 7074.964625176867, 7257.491975561713, 8781.56092195391, 8881.822363552725, 8891.06643802401, 7311.67097480612, 7345.522541034842, 7372.152202496701, 7376.926230737705, 10452.686419407391, 10614.522346494427, 10668.14654827612, 10683.465826708634, 10691.572337106507, 10703.625373111903, 10101.989801019912, 10528.052475277185, 10694.679159625224, 10697.358158510515, 10711.357318560911, 25386.365340866443, 25577.0, 37867.81066094652, 38009.0497737556, 38060.23879522394, 2951.4539810135193, 3728.2170744143764, 3753.549574051104, 3756.7745935243975, 3758.586555478895, 25301.114460993776, 25499.087568432442, 25553.616695749577, 25574.360640983938, 25727.742722572908, 3057.5719399284117, 3104.30153215527, 3109.6473034229957, 3139.1838122088193, 3140.7016333448287, 301187.0, 302013.0, 302936.9999999978, 303244.9999999978, 303487.0, 46206.30690539644, 46517.767411163404, 46635.6009319721, 46674.533254667695, 46722.065558688635, 37490.25019499595, 37690.62309376926, 47289.5271047292, 48461.75769121202, 48661.75669121702, 11573.942130289295, 11727.648170554898, 13864.514741983981, 13979.86020139806, 13983.230922299168, 9542.045795420541, 11281.379524126252, 11380.601678941197, 11389.373584452935, 11399.373034483016, 12147.514099435924, 12227.449764760608, 12229.309551898568, 12276.508939642314, 12408.0, 5148.819791307286, 5218.556422704037, 5226.97386513065, 5245.0, 5302.030862716891, 1099.5656715597295, 1125.5554056147776, 1132.043423307304, 1157.2887798713377, 1169.7426566155432, 25981.870090649427, 26110.955561777697, 26129.608055879176, 26278.474430511284, 27118.5932211017, 1217.8782121787751, 1234.3581337704522, 1267.245988636778, 1270.0347735720918, 1278.360819590205, 13352.599422017354, 14505.492307769178, 14598.854011459962, 14976.775348369783, 15004.474843380429, 268200.0, 361358.38641613774, 363270.0, 364751.0, 367010.0, 41625.0, 51040.0, 51188.464346069675, 51192.0, 51207.7439612807, 1349.3657980749033, 1410.1116296732991, 1415.7310111078862, 1418.9219592922489, 1437.525616546537, 27849.0, 27861.721382785912, 28034.2991425216, 28491.857540712994, 28566.42867142687, 43192.56807431886, 43316.78341608272, 43345.78327108344, 43347.0, 43448.78275608602, 290512.0, 295451.0, 327285.0, 333388.0, 333941.33029334695, 29780.553291701075, 30318.393632126794, 30446.695533044385, 30451.390972180874, 30466.695333046388, 37795.05512362213, 40865.38701919533, 41046.9738256546, 41291.0, 41338.58661413468, 31715.84142079275, 31901.0, 33235.0, 34620.653793461744, 34689.30621387609, 26847.060352887554, 33153.005409837744, 33219.0, 34599.0, 34620.0, 29447.26381840471, 33459.163520912174, 33620.495692564145, 33706.325873482885, 34273.31453370869, 28086.578701319904, 29615.25961850971, 29739.10782676523, 30002.84998574993, 30131.62805742935, 22550.32349029532, 23216.883915580314, 23317.76682233156, 23382.532349352576, 23389.41526461852], "value": 17409.487772473254, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [364.67361711268416, 382.1325590908636, 393.64768532163714, 417.9164167166567, 428.60996493191203, 13700.451981920723, 17389.913050434745, 20947.0, 22185.667214991747, 22476.550468990612, 21483.785162148375, 21832.345029649096, 22414.88792556035, 22616.321510354675, 22710.0, 5772.971135144318, 6281.403266689657, 6469.935300647004, 6560.311167327433, 6566.704498297588, 6207.968960155193, 6501.869962600746, 6526.184226971629, 6542.50931180161, 6544.247411547662, 6532.183477065367, 6547.3125321841, 7156.212816590171, 7457.0305860238095, 7536.133344665352, 7160.856782864365, 7410.740624078158, 7412.295831895961, 7486.326230639242, 7492.700291988314, 7266.781996540112, 7277.126744790606, 7277.854442911165, 7278.708851645928, 7295.379892709126, 26417.0, 27546.173614791387, 28006.719932800614, 28331.0, 28399.85800071007, 2775.34779326858, 2809.5223811951814, 2838.2904273931517, 2900.6809250982374, 2933.7066293370704, 16016.679666406722, 16394.344226230936, 16879.40922067734, 16882.240299186567, 17029.06340151291, 1529.915854627995, 2027.807358300959, 2028.1785876719953, 2030.6344857925567, 2064.256867527689, 148747.0, 150147.0, 150847.0, 153302.46697532994, 155767.2211638946, 15441.922790386201, 18094.366697165537, 19906.203751849764, 20548.38354849327, 20969.895150524455, 33841.32317353639, 33875.661243387745, 33999.15002124967, 34017.48973765395, 34039.48940765841, 8387.245147936594, 8921.55392230386, 9291.721248362424, 9299.39553929006, 9781.755456113511, 7172.569645821268, 7491.250874912465, 7521.548707077483, 8145.511269323741, 8593.52735599548, 8996.820063598821, 10209.234307426972, 10276.588936442608, 10321.690349289533, 10384.961503849705, 3691.427828686558, 4018.564127670858, 4148.336266197454, 4172.290710579209, 4183.163367326547, 965.2567523007245, 1028.3624153025173, 1047.460557812727, 1072.4423299884172, 1073.044989958937, 19233.0, 23033.0, 23326.0, 23435.94538245783, 23482.412939676302, 1017.2167431078028, 1050.847627094067, 1051.2115913065202, 1083.5286650307141, 1091.7925594137248, 10946.179036572288, 11181.944090279496, 11191.384473854016, 12065.939670301592, 12191.817122743167, 651013.174011951, 667010.0, 678286.0, 687005.0, 689340.0, 115638.0, 148464.0, 148724.0, 149012.0, 150080.2495987535, 1191.1304747534348, 1208.1542919956066, 1238.3127364312713, 1240.5472002719123, 1241.2180326394493, 19366.903165484084, 20251.0, 20280.391588252376, 20292.695609566163, 20293.49266268355, 37770.43344349782, 47038.0, 54821.0, 56214.0, 58046.4195358041, 83589.65457385726, 83765.0, 86083.0, 86743.0, 88906.55546722225, 11243.60647377338, 11340.773184536429, 11485.770284594428, 11564.074874010228, 11608.24546404481, 12841.0, 12864.035197360048, 13085.41115649817, 13206.207627542379, 13298.0, 11207.551697931991, 11246.04408625276, 11439.42802859837, 11469.885301146882, 11483.023942964608, 11142.498587563741, 11278.165423755961, 11309.943450282695, 11425.771484570429, 11593.304401735923, 11370.431478426211, 11634.0, 11687.532498700297, 11746.236494627821, 11813.468393922121, 10907.78184436291, 11023.669289921316, 11401.030912372209, 11438.771224575628, 11784.764304713686, 9728.951355243462, 11112.666620001413, 11119.388433636228, 11468.426578670866, 11505.942470287595], "value": 11507.705845905883, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1524.5959820647538, 1570.9057456552607, 1600.59985003749, 1606.349428481465, 1652.017049855336, 10167.339122957002, 10594.0, 10596.774677317773, 10603.893961060377, 10620.99100585445, 8505.149485051492, 10800.891991080087, 10805.783884322309, 10805.891941080587, 11018.559257629686, 2308.8730119843444, 2346.8826558672085, 2347.4600841806396, 2349.119080344871, 2350.1656911796235, 2283.748787633359, 2320.0487800002, 2322.071171531384, 2322.105989194164, 2324.0, 1934.487360849376, 2299.7815207555254, 2312.5952958232324, 2324.2213858357472, 2325.988370058147, 2277.4761804784994, 2291.209532711212, 2295.059025799414, 2298.3909264044873, 2301.2750983440164, 1870.738096666454, 2254.211026140854, 2315.2822624986306, 2316.0, 2318.1654604342425, 10945.56217751289, 11027.724306892393, 11033.668989930315, 11056.778864422666, 11075.446227688582, 5370.275012873251, 5595.076812325961, 5599.888002239933, 5601.775928962837, 5607.271054762896, 3325.667433256679, 3392.6098498672477, 3661.9816900915325, 3670.3576874046935, 3676.8529258829612, 1172.302480024384, 1188.1504724122208, 1218.3603608105727, 1218.9268643881308, 1228.8648248692637, 13465.86534134656, 13887.58337249884, 13916.860831391657, 15287.541373758804, 15336.539903802905, 4101.958980410217, 4207.789610519461, 4219.767912764827, 4473.642108631302, 4666.743329116931, 11385.0, 11404.657860264206, 11556.075513958864, 11557.190996630155, 11568.710782230512, 5614.6631202128, 5632.690202038927, 5634.098544232903, 5642.097264437671, 5642.181883626933, 4829.637777166727, 5116.565091967224, 5182.792688292426, 5187.844364669066, 5195.090859099642, 3074.8776696505615, 3785.37541305687, 3818.0836599215986, 3827.3876179811095, 3874.767513949172, 1033.106362996003, 1034.818906691326, 1036.8392899100652, 1050.3487837540622, 1051.3271506236015, 832.4339449174498, 882.993387538207, 922.524899676667, 965.5944503308627, 1000.1948431512702, 5127.384713834289, 6534.150560427114, 6675.566088204251, 6801.965990170018, 6802.285759995229, 851.535912927456, 878.9384743067924, 997.7904640025531, 1000.0949141027381, 1046.931949423285, 4206.326987681956, 4226.0, 4378.868633940987, 4391.429114215131, 4395.846145384896, 13096.869031309565, 13601.659958500919, 13616.0, 13625.659358515919, 13660.180389176681, 11142.721431964104, 13053.0, 13212.273324967027, 13509.0, 13535.526256580972, 9905.306628535933, 9929.80140397188, 9937.950310248547, 9939.353941993748, 10096.242781791396, 6436.227652681709, 6619.404253617197, 6619.801405957829, 6636.303188165173, 6651.501137414616, 11904.107191960464, 11943.641690749291, 11957.342346171044, 12061.396930153633, 12247.75504489923, 10590.523426445674, 11237.752449509968, 12314.81527777102, 12493.875061249271, 12618.936905315415, 7050.25972272904, 8230.547319897463, 8257.38069644791, 8314.916850831656, 8358.582070896553, 10011.599536018477, 10045.146162576262, 10179.134773544332, 10179.7455063624, 10234.181265498593, 9076.319276054455, 9084.772880678036, 9106.271498280254, 9156.908430915872, 9192.448453092837, 7365.9631701841145, 8769.517676527852, 8944.821103577762, 8969.327300452622, 8991.0, 7884.881726773988, 7941.682332706627, 7965.800854978672, 8008.399370047387, 8023.598820058857, 5863.384344643921, 5868.23712917318, 6025.246844144482, 6085.330613632408, 6109.20580324555, 4788.329633851334, 5888.793892213752, 6019.367966363468, 6020.458158765732, 6102.420270074336], "value": 5008.128814491341, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [381.8835255247149, 390.15920690911076, 390.38514339914644, 390.4143784323515, 394.6389054015575, 5143.279940808286, 5560.972195139018, 5653.943460565398, 5654.575906806996, 5811.651300921937, 5530.364008139065, 5599.300087489064, 5623.831285061455, 5661.547076233902, 5666.781641946981, 3834.5620392352866, 4136.9450790048495, 4423.513413524526, 4536.773161341936, 4670.532946705336, 4362.650987920974, 4694.788734506938, 4698.412698412699, 4731.9763401182945, 4773.045390921812, 3828.25349056934, 4682.578567928886, 4704.576588107069, 4715.528447155291, 4730.858074257778, 3874.9031274218087, 4577.7482238476705, 4649.581537661593, 4690.742009189493, 4733.928991065137, 4049.5343035550995, 4247.193033323659, 4261.637760790337, 4267.935980960288, 4302.311630139163, 5480.26016487777, 5490.478404551601, 5535.833924982257, 5540.584456165804, 5542.279503664538, 4354.259775838115, 5395.271638328814, 5422.186671999192, 5430.42980487051, 5463.153211252262, 5281.86795330116, 5317.521423071942, 5997.920374332618, 6000.419819477623, 6021.608595441282, 4176.352665336878, 4723.858284251478, 4802.111609352281, 4825.927611085836, 4828.324034635154, 5531.63679177039, 6386.68066596673, 6447.097406363113, 6466.256380516254, 6482.6434546099945, 4746.243889760742, 4928.975355123202, 4938.827141050046, 4959.181735013683, 4966.975165124223, 4708.246680531099, 5064.240363945438, 5065.898682026339, 5080.695158290514, 5082.237664350377, 5335.599830012765, 5431.728413579305, 5441.564674826004, 5451.318585176853, 5452.5365343946205, 5273.6572122811895, 5910.911336329958, 6073.0221704783435, 6085.148079268906, 6140.723667434976, 1467.611083062989, 1653.1568899861156, 1716.6266986410822, 1728.87897847152, 1763.3211213682569, 1258.2576279994846, 1269.4604792962955, 1281.5130250504935, 1285.0362228328754, 1286.9613911582667, 4980.003999200175, 5213.556847667991, 5766.769329226868, 5787.334456537552, 5790.915270502615, 5344.198370244417, 5380.488853558905, 5475.370332411823, 6264.809686159615, 6276.623402595767, 5562.527185189304, 5725.370209276976, 5763.971180144073, 5789.652620842763, 5801.129830525455, 3262.9347413051605, 3294.9835250823594, 3304.5538852255117, 3307.7188438982957, 3326.120741780886, 5510.393856675762, 6102.9930061540235, 6201.248244811914, 6289.654069026156, 6513.381228783258, 5614.1859430382365, 5850.53195744348, 5961.0, 6303.3066362700065, 6326.0, 5398.78404863801, 6400.327965563642, 6404.615723056539, 6410.294867564564, 6423.807285781434, 5460.3618914326335, 5624.690642014565, 6085.939140608537, 6224.564280500503, 6274.0, 5124.9743751281, 6093.177421048279, 6116.327204007466, 6142.600730952474, 6161.199044124414, 5172.689638621544, 5312.043832110257, 6550.181227346582, 6552.443042341454, 6562.704678289584, 4118.814653340547, 4187.979060104802, 4759.262869052702, 4950.77721502526, 4972.279019542074, 6227.439530442282, 6283.497320214463, 6335.4931605472375, 6344.9048264277, 6433.324500927335, 4984.675996060245, 5679.6876171810945, 6103.877922441615, 6135.0, 6167.907481387872, 4774.474807771212, 6054.57916277302, 6063.757449701962, 6074.686972249576, 6090.878182436415, 4935.729564400416, 4938.666560028844, 5569.637973531708, 5954.523638108854, 5974.253218347707, 4955.547004232928, 5907.852303692442, 6007.339192688888, 6021.187139736079, 6029.306629737461, 5881.2942446906645, 5892.823215303548, 5896.941030589811, 5912.231409916683, 5914.467697907209], "value": 4628.802460919255, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [644.4779728419983, 679.7858674517527, 726.1866709285601, 819.6680344460493, 821.8931538899942, 14958.77561836572, 15810.0, 15886.285117169726, 15895.920520397396, 15947.92026039871, 14553.854461455383, 14559.490417835377, 14575.92712036441, 14695.77956330656, 14755.852441475608, 930.0652843891896, 950.4867371619317, 954.5179684259439, 996.1035068438428, 998.6804222648756, 960.8222478841436, 972.1687956796943, 977.0669011094424, 978.3298440568198, 978.8972157923422, 1025.4616326428647, 1026.420072658949, 1026.9986762906144, 1029.2229366828035, 1029.6396261308519, 1016.8576399304103, 1018.5518371916407, 1018.6791160784403, 1020.6376736258595, 1020.7550187954874, 755.3315315945373, 757.4962649837906, 757.9317861392447, 759.6961215513784, 759.9164091949881, 15708.0, 16132.516024519166, 16174.676506469923, 16178.0, 16469.91765041167, 12015.93992030043, 14454.205018723966, 14460.710785784331, 14552.781708274384, 14645.853541464556, 11517.243091590784, 11796.823047654292, 11819.231749936227, 12144.392780360946, 12382.566610168691, 3608.8376023078763, 3681.849190580741, 3723.590405055442, 3831.980840095782, 3834.5973672764517, 73581.0, 93798.0, 97360.0, 101366.0, 105406.47296763543, 9654.469004204695, 11469.827952580717, 11644.475998580085, 11939.641810745692, 12046.15676902626, 23810.52378952411, 24065.63901541478, 24212.636810447857, 24344.26967190987, 24373.63439548408, 7849.254320839511, 7890.842183156419, 7892.408069394818, 7969.960150199212, 8255.25702686761, 3950.802459876995, 3968.1270120573427, 3968.7420317679257, 3982.3230050891416, 3992.061865461614, 3473.6700013498676, 3523.0311664292526, 3553.8578456861437, 3555.8755443559353, 3562.0738607962116, 649.3862751063604, 651.5732195412013, 652.1717418877998, 654.7479220500046, 664.7905909638448, 636.7612145445459, 651.5308977536166, 672.6266921858318, 694.2467422846167, 703.5110598134349, 9025.864612030824, 9103.135202155785, 9802.607895684092, 10027.749306267402, 10106.444145572064, 634.2072409488139, 649.5810202419412, 665.9234188068338, 674.689642764324, 700.56890169677, 4920.803167873317, 5217.616942284826, 5365.785368585213, 5427.810026649048, 5574.08027675429, 76069.61965190139, 87149.56425217833, 96961.0, 97823.0, 99120.50439747899, 49571.50428495741, 56517.0, 66172.0, 84079.0, 87772.1222787764, 56464.0, 65573.67213163999, 66306.0, 67593.32406675968, 67824.0, 8750.0, 9021.278297736035, 9711.708648740549, 10612.310199836986, 10823.296485728717, 38990.4151437723, 44408.55591444044, 45822.31266531072, 48465.0, 49862.002759945324, 58475.70762146162, 58953.41046589479, 59547.70226148841, 59654.0, 59758.40241597528, 13165.451514171402, 13303.268320242092, 13372.93313533426, 13537.120087194695, 13959.581212563638, 14135.575932722035, 14277.500287489887, 14745.631359216106, 15227.314770835117, 15511.146886921328, 13540.458381664623, 13561.75370253229, 13752.243626600633, 13809.51666691661, 13922.373493192628, 12990.675233119247, 13104.606861794162, 13420.865791342352, 13613.455461781417, 14186.432542698176, 12934.288614126313, 13217.867821322048, 13228.537001204912, 13465.0, 14010.159390436607, 10845.250736877573, 10977.560897564008, 11004.919811531112, 11119.44402779874, 11975.0, 10201.591936322464, 10630.627928022483, 10967.275464521856, 11300.265482743594, 11349.092072634005], "value": 7524.6070697378445, "unit": "score"}]}, {"extraOptions": [], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1472.0, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 114.0, 114.0, 112.0, 111.0, 114.0, 115.0, 114.0, 115.0, 114.0], "value": 113.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [131.0, 113.0, 111.0, 149.0, 112.0, 110.0, 109.0, 111.0, 136.0, 112.0], "value": 119.4, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [186.0, 201.0, 264.0, 185.0, 180.0, 182.0, 186.0, 185.0, 182.0, 187.0], "value": 193.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 59.0, 76.0, 124.0, 87.0, 58.0, 62.0, 58.0, 79.0, 58.0], "value": 74.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 70.0, 91.0, 70.0, 71.0, 69.0, 85.0, 71.0, 93.0, 119.0], "value": 82.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 87.0, 87.0, 86.0, 87.0, 87.0, 87.0, 87.0, 87.0, 85.0], "value": 87.3, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 94.0, 94.0, 94.0, 95.0, 116.0, 150.0, 133.0, 93.0], "value": 105.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 83.0, 86.0, 86.0, 83.0, 86.0, 86.0, 82.0, 83.0, 86.0], "value": 84.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 63.0, 62.0, 62.0, 61.0, 63.0, 62.0, 63.0, 62.0, 63.0], "value": 62.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 55.0, 63.0, 70.0, 63.0, 63.0, 59.0, 58.0, 57.0, 58.0], "value": 60.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 82.0, 84.0, 83.0, 82.0, 82.0, 80.0, 83.0, 81.0, 84.0], "value": 82.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [143.0, 153.0, 149.0, 140.0, 144.0, 124.0, 139.0, 140.0, 201.0, 140.0], "value": 147.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [189.0, 185.0, 185.0, 187.0, 184.0, 232.0, 193.0, 184.0, 185.0, 184.0], "value": 190.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 68.0, 65.0, 67.0, 66.0, 68.0, 68.0, 68.0, 67.0, 69.0], "value": 67.3, "unit": "score"}]}]} 09:23:27 INFO - Return code: 0 09:23:27 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 09:23:27 ERROR - # TBPL SUCCESS # 09:23:27 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:23:27 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:23:27 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:23:27 INFO - 2017-03-13 09:23:27.199 screenresolution[2020:18430] starting screenresolution argv=screenresolution get 09:23:27 INFO - 2017-03-13 09:23:27.202 screenresolution[2020:18430] Display 0: 1600x1200x32@60 09:23:27 INFO - 2017-03-13 09:23:27.209 screenresolution[2021:18433] starting screenresolution argv=screenresolution list 09:23:27 INFO - Available Modes on Display 0 09:23:27 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:23:27 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:23:27 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:23:27 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:23:27 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:23:27 INFO - Intel Iris: 09:23:27 INFO - Chipset Model: Intel Iris 09:23:27 INFO - Type: GPU 09:23:27 INFO - Bus: Built-In 09:23:27 INFO - VRAM (Dynamic, Max): 1536 MB 09:23:27 INFO - Vendor: Intel (0x8086) 09:23:27 INFO - Device ID: 0x0a2e 09:23:27 INFO - Revision ID: 0x0009 09:23:27 INFO - Displays: 09:23:27 INFO - Display: 09:23:27 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:23:27 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:23:27 INFO - Display Serial Number: 1600x1200 60 09:23:27 INFO - Main Display: Yes 09:23:27 INFO - Mirror: Off 09:23:27 INFO - Online: Yes 09:23:27 INFO - Rotation: Supported 09:23:27 INFO - Return code: 0 09:23:27 INFO - Running post-action listener: _resource_record_post_action 09:23:27 INFO - [mozharness: 2017-03-13 16:23:27.454624Z] Finished run-tests step (success) 09:23:27 INFO - Running post-run listener: _resource_record_post_run 09:23:27 INFO - Total resource usage - Wall time: 1206s; CPU: 53.0%; Read bytes: 864256; Write bytes: 512491520; Read time: 12; Write time: 1722 09:23:27 INFO - TinderboxPrint: CPU usage
53.4% 09:23:27 INFO - TinderboxPrint: I/O read bytes / time
864,256 / 12 09:23:27 INFO - TinderboxPrint: I/O write bytes / time
512,491,520 / 1,722 09:23:27 INFO - TinderboxPrint: CPU idle
2,247.2 (46.6%) 09:23:27 INFO - TinderboxPrint: CPU system
961.7 (19.9%) 09:23:27 INFO - TinderboxPrint: CPU user
1,616.7 (33.5%) 09:23:27 INFO - TinderboxPrint: Swap in / out
210,825,216 / 0 09:23:27 INFO - install - Wall time: 21s; CPU: 54.0%; Read bytes: 198554112; Write bytes: 194029056; Read time: 15470; Write time: 472 09:23:27 INFO - run-tests - Wall time: 1186s; CPU: 53.0%; Read bytes: 864256; Write bytes: 298633728; Read time: 12; Write time: 1190 09:23:27 INFO - Running post-run listener: _upload_blobber_files 09:23:27 INFO - Blob upload gear active. 09:23:27 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:23:27 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 09:23:27 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'] 09:23:27 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 09:23:28 INFO - (blobuploader) - INFO - Open directory for files ... 09:23:28 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 09:23:28 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:23:28 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:23:29 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 09:23:29 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:23:29 INFO - (blobuploader) - INFO - Done attempting. 09:23:29 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 09:23:29 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:23:29 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:23:30 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 09:23:30 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:23:30 INFO - (blobuploader) - INFO - Done attempting. 09:23:30 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 09:23:30 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:23:30 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:23:31 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 09:23:31 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:23:31 INFO - (blobuploader) - INFO - Done attempting. 09:23:31 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 09:23:31 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:23:31 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:23:34 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 09:23:34 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:23:34 INFO - (blobuploader) - INFO - Done attempting. 09:23:34 INFO - (blobuploader) - INFO - Iteration through files over. 09:23:34 INFO - Return code: 0 09:23:34 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:23:34 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:23:34 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/002474918a87b7d339cd062042be988ccd9b90246f2fe308c7f892bdcc418bb8ab2feabb447e67d29286dbc4a31bb5a64d1ef9a43b04e5d9730528ff331efd90", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9900b72a7f5438dddc0cd9816dc665a8a137fb0baa5dcc5dab2faa74e8caa812e5a9ab3fada976604960aec22d967710abe438e5418a4e59ca3710a0980954a4", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/bcc4f83a1236fd5dd5e9e9a3b995831bf5e48edefe21f64e11977e4eb484094239fa647da2594fa2c82bd0b7b8e351d9367fbca8a0882a64120547aead5da8ac", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/25be116c282691ec634cfe2be3a8f24b84c4c5a60df720297a1f9699d103bef1ae1c0ccf8c2eb142fac0bd407e3fb2346efd20c148708a59eadedb747d20f162"} 09:23:34 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:23:34 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:23:34 INFO - Contents: 09:23:34 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/002474918a87b7d339cd062042be988ccd9b90246f2fe308c7f892bdcc418bb8ab2feabb447e67d29286dbc4a31bb5a64d1ef9a43b04e5d9730528ff331efd90", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9900b72a7f5438dddc0cd9816dc665a8a137fb0baa5dcc5dab2faa74e8caa812e5a9ab3fada976604960aec22d967710abe438e5418a4e59ca3710a0980954a4", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/bcc4f83a1236fd5dd5e9e9a3b995831bf5e48edefe21f64e11977e4eb484094239fa647da2594fa2c82bd0b7b8e351d9367fbca8a0882a64120547aead5da8ac", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/25be116c282691ec634cfe2be3a8f24b84c4c5a60df720297a1f9699d103bef1ae1c0ccf8c2eb142fac0bd407e3fb2346efd20c148708a59eadedb747d20f162"} 09:23:34 INFO - Running post-run listener: copy_logs_to_upload_dir 09:23:34 INFO - Copying logs to upload dir... 09:23:34 INFO - mkdir: /builds/slave/test/build/upload/logs 09:23:34 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1289.352842 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 29 secs) (at 2017-03-13 09:23:34.491631) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-13 09:23:34.494833) ========= 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.xQdOQDhDM8/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.cE9aYYpqHB/Listeners TMPDIR=/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/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/002474918a87b7d339cd062042be988ccd9b90246f2fe308c7f892bdcc418bb8ab2feabb447e67d29286dbc4a31bb5a64d1ef9a43b04e5d9730528ff331efd90", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9900b72a7f5438dddc0cd9816dc665a8a137fb0baa5dcc5dab2faa74e8caa812e5a9ab3fada976604960aec22d967710abe438e5418a4e59ca3710a0980954a4", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/bcc4f83a1236fd5dd5e9e9a3b995831bf5e48edefe21f64e11977e4eb484094239fa647da2594fa2c82bd0b7b8e351d9367fbca8a0882a64120547aead5da8ac", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/25be116c282691ec634cfe2be3a8f24b84c4c5a60df720297a1f9699d103bef1ae1c0ccf8c2eb142fac0bd407e3fb2346efd20c148708a59eadedb747d20f162"} build_url:https://queue.taskcluster.net/v1/task/UjpqHGdGR7-sSi1Op6VhNA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011182 build_url: 'https://queue.taskcluster.net/v1/task/UjpqHGdGR7-sSi1Op6VhNA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/002474918a87b7d339cd062042be988ccd9b90246f2fe308c7f892bdcc418bb8ab2feabb447e67d29286dbc4a31bb5a64d1ef9a43b04e5d9730528ff331efd90", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9900b72a7f5438dddc0cd9816dc665a8a137fb0baa5dcc5dab2faa74e8caa812e5a9ab3fada976604960aec22d967710abe438e5418a4e59ca3710a0980954a4", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/bcc4f83a1236fd5dd5e9e9a3b995831bf5e48edefe21f64e11977e4eb484094239fa647da2594fa2c82bd0b7b8e351d9367fbca8a0882a64120547aead5da8ac", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/25be116c282691ec634cfe2be3a8f24b84c4c5a60df720297a1f9699d103bef1ae1c0ccf8c2eb142fac0bd407e3fb2346efd20c148708a59eadedb747d20f162"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-13 09:23:34.529914) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-13 09:23:34.530258) ========= 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.xQdOQDhDM8/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.cE9aYYpqHB/Listeners TMPDIR=/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/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.004643 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-13 09:23:34.578438) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-13 09:23:34.578751) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-13 09:23:34.579204) ========= ========= Total master_lag: 2.85 =========