builder: mozilla-aurora_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0218 starttime: 1492763574.49 results: success (0) buildid: 20170421004017 builduid: 6d9d2290897b41d0b44cbe1d0aa78aa7 revision: 94b7e538af7dbb9a6567cad9125c2f5411e7fb80 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-04-21 01:32:54.493720) ========= master: http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-04-21 01:32:54.494169) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-04-21 01:32:54.494461) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-04-21 01:32:54.545479) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-21 01:32:54.545870) ========= 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.wNYqFqMEdq/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.AnpypnL2k5/Listeners TMPDIR=/var/folders/k4/xd58rgkx0dnbrdjb1pv1qrj800000w/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-04-21 01:32:54-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 19.1M=0.001s 2017-04-21 01:32:54 (19.1 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.154606 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-21 01:32:54.734283) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-04-21 01:32:54.734635) ========= 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.wNYqFqMEdq/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.AnpypnL2k5/Listeners TMPDIR=/var/folders/k4/xd58rgkx0dnbrdjb1pv1qrj800000w/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.027467 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-04-21 01:32:54.787056) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-04-21 01:32:54.787754) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 94b7e538af7dbb9a6567cad9125c2f5411e7fb80 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 94b7e538af7dbb9a6567cad9125c2f5411e7fb80 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.wNYqFqMEdq/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.AnpypnL2k5/Listeners TMPDIR=/var/folders/k4/xd58rgkx0dnbrdjb1pv1qrj800000w/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-04-21 01:32:54,893 truncating revision to first 12 chars 2017-04-21 01:32:54,893 Setting DEBUG logging. 2017-04-21 01:32:54,894 attempt 1/10 2017-04-21 01:32:54,894 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/94b7e538af7d?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-04-21 01:32:55,676 unpacking tar archive at: mozilla-aurora-94b7e538af7d/testing/mozharness/ program finished with exit code 0 elapsedTime=1.062071 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-04-21 01:32:55.873461) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-04-21 01:32:55.873764) ========= script_repo_revision: 94b7e538af7dbb9a6567cad9125c2f5411e7fb80 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-04-21 01:32:55.874141) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-04-21 01:32:55.874422) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-04-21 01:32:55.891712) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 26 secs) (at 2017-04-21 01:32:55.892132) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Aurora', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.wNYqFqMEdq/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.AnpypnL2k5/Listeners TMPDIR=/var/folders/k4/xd58rgkx0dnbrdjb1pv1qrj800000w/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 01:32:56 INFO - MultiFileLogger online at 20170421 01:32:56 in /builds/slave/test 01:32:56 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 01:32:56 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 01:32:56 INFO - {'append_to_log': False, 01:32:56 INFO - 'base_work_dir': '/builds/slave/test', 01:32:56 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 01:32:56 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 01:32:56 INFO - 'branch': 'Mozilla-Aurora', 01:32:56 INFO - 'buildbot_json_path': 'buildprops.json', 01:32:56 INFO - 'config_files': ('talos/mac_config.py',), 01:32:56 INFO - 'default_actions': ('clobber', 01:32:56 INFO - 'read-buildbot-config', 01:32:56 INFO - 'download-and-extract', 01:32:56 INFO - 'populate-webroot', 01:32:56 INFO - 'create-virtualenv', 01:32:56 INFO - 'install', 01:32:56 INFO - 'run-tests'), 01:32:56 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 01:32:56 INFO - 'download_minidump_stackwalk': True, 01:32:56 INFO - 'download_symbols': 'ondemand', 01:32:56 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 01:32:56 INFO - 'tooltool.py': '/tools/tooltool.py', 01:32:56 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 01:32:56 INFO - '/tools/misc-python/virtualenv.py')}, 01:32:56 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 01:32:56 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 01:32:56 INFO - 'gecko_profile': False, 01:32:56 INFO - 'gecko_profile_interval': 0, 01:32:56 INFO - 'installer_path': 'installer.exe', 01:32:56 INFO - 'log_level': 'info', 01:32:56 INFO - 'log_name': 'talos', 01:32:56 INFO - 'log_to_console': True, 01:32:56 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 01:32:56 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 01:32:56 INFO - 'opt_config_files': (), 01:32:56 INFO - 'pip_index': False, 01:32:56 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 01:32:56 INFO - 'cmd': ('bash', 01:32:56 INFO - '-c', 01:32:56 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 01:32:56 INFO - 'enabled': True, 01:32:56 INFO - 'halt_on_failure': False, 01:32:56 INFO - 'name': 'check_screen_resolution'},), 01:32:56 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 01:32:56 INFO - 'cmd': ('bash', 01:32:56 INFO - '-c', 01:32:56 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 01:32:56 INFO - 'enabled': True, 01:32:56 INFO - 'halt_on_failure': False, 01:32:56 INFO - 'name': 'check_screen_resolution'},), 01:32:56 INFO - 'run_cmd_checks_enabled': True, 01:32:56 INFO - 'suite': 'dromaeojs', 01:32:56 INFO - 'system_bits': '32', 01:32:56 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 01:32:56 INFO - 'title': 't-yosemite-r7-0218', 01:32:56 INFO - 'tooltool_cache': '/builds/tooltool_cache', 01:32:56 INFO - 'use_talos_json': True, 01:32:56 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 01:32:56 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 01:32:56 INFO - 'work_dir': 'build'} 01:32:56 INFO - [mozharness: 2017-04-21 08:32:56.106142Z] Running clobber step. 01:32:56 INFO - Running pre-action listener: _resource_record_pre_action 01:32:56 INFO - Running main action method: clobber 01:32:56 INFO - rmtree: /builds/slave/test/build 01:32:56 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 01:32:58 INFO - Running post-action listener: _resource_record_post_action 01:32:58 INFO - [mozharness: 2017-04-21 08:32:58.430937Z] Finished clobber step (success) 01:32:58 INFO - [mozharness: 2017-04-21 08:32:58.431215Z] Running read-buildbot-config step. 01:32:58 INFO - Running pre-action listener: _resource_record_pre_action 01:32:58 INFO - Running main action method: read_buildbot_config 01:32:58 INFO - Using buildbot properties: 01:32:58 INFO - { 01:32:58 INFO - "project": "", 01:32:58 INFO - "product": "firefox", 01:32:58 INFO - "script_repo_revision": "production", 01:32:58 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs", 01:32:58 INFO - "repository": "", 01:32:58 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs", 01:32:58 INFO - "buildid": "20170421004017", 01:32:58 INFO - "stage_platform": "macosx64", 01:32:58 INFO - "basedir": "/builds/slave/test", 01:32:58 INFO - "nightly_build": "True", 01:32:58 INFO - "buildnumber": 3, 01:32:58 INFO - "slavename": "t-yosemite-r7-0218", 01:32:58 INFO - "master": "http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/", 01:32:58 INFO - "platform": "yosemite_r7", 01:32:58 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 01:32:58 INFO - "branch": "mozilla-aurora", 01:32:58 INFO - "repo_path": "releases/mozilla-aurora", 01:32:58 INFO - "revision": "94b7e538af7dbb9a6567cad9125c2f5411e7fb80", 01:32:58 INFO - "pgo_build": "False", 01:32:58 INFO - "builduid": "6d9d2290897b41d0b44cbe1d0aa78aa7", 01:32:58 INFO - "slavebuilddir": "test" 01:32:58 INFO - } 01:32:58 INFO - Found installer url https://queue.taskcluster.net/v1/task/DD51djZRSZCceFCg4Q-gKg/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg. 01:32:58 INFO - Running post-action listener: _resource_record_post_action 01:32:58 INFO - [mozharness: 2017-04-21 08:32:58.435924Z] Finished read-buildbot-config step (success) 01:32:58 INFO - [mozharness: 2017-04-21 08:32:58.436064Z] Running download-and-extract step. 01:32:58 INFO - Running pre-action listener: _resource_record_pre_action 01:32:58 INFO - Running main action method: download_and_extract 01:32:58 INFO - mkdir: /builds/slave/test/build/tests 01:32:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:32:58 INFO - trying https://queue.taskcluster.net/v1/task/DD51djZRSZCceFCg4Q-gKg/artifacts/public/build/firefox-54.0a2.en-US.mac.test_packages.json 01:32:58 INFO - Downloading https://queue.taskcluster.net/v1/task/DD51djZRSZCceFCg4Q-gKg/artifacts/public/build/firefox-54.0a2.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-54.0a2.en-US.mac.test_packages.json 01:32:58 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/DD51djZRSZCceFCg4Q-gKg/artifacts/public/build/firefox-54.0a2.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-54.0a2.en-US.mac.test_packages.json'}, attempt #1 01:32:59 INFO - Downloaded 1199 bytes. 01:32:59 INFO - Reading from file /builds/slave/test/build/firefox-54.0a2.en-US.mac.test_packages.json 01:32:59 INFO - Using the following test package requirements: 01:32:59 INFO - {u'common': [u'firefox-54.0a2.en-US.mac.common.tests.zip'], 01:32:59 INFO - u'cppunittest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 01:32:59 INFO - u'firefox-54.0a2.en-US.mac.cppunittest.tests.zip'], 01:32:59 INFO - u'gtest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 01:32:59 INFO - u'firefox-54.0a2.en-US.mac.gtest.tests.zip'], 01:32:59 INFO - u'jittest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 01:32:59 INFO - u'jsshell-mac.zip'], 01:32:59 INFO - u'mochitest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 01:32:59 INFO - u'firefox-54.0a2.en-US.mac.mochitest.tests.zip'], 01:32:59 INFO - u'mozbase': [u'firefox-54.0a2.en-US.mac.common.tests.zip'], 01:32:59 INFO - u'reftest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 01:32:59 INFO - u'firefox-54.0a2.en-US.mac.reftest.tests.zip'], 01:32:59 INFO - u'talos': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 01:32:59 INFO - u'firefox-54.0a2.en-US.mac.talos.tests.zip'], 01:32:59 INFO - u'web-platform': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 01:32:59 INFO - u'firefox-54.0a2.en-US.mac.web-platform.tests.zip'], 01:32:59 INFO - u'xpcshell': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 01:32:59 INFO - u'firefox-54.0a2.en-US.mac.xpcshell.tests.zip']} 01:32:59 INFO - Downloading packages: [u'firefox-54.0a2.en-US.mac.common.tests.zip'] for test suite category: common 01:32:59 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/DD51djZRSZCceFCg4Q-gKg/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip 01:32:59 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/DD51djZRSZCceFCg4Q-gKg/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip'}, attempt #1 01:32:59 INFO - Fetch https://queue.taskcluster.net/v1/task/DD51djZRSZCceFCg4Q-gKg/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip into memory 01:33:00 INFO - Expected file size: 51791052 01:33:00 INFO - Obtained file size: 51791052 01:33:17 INFO - Downloading packages: [u'firefox-54.0a2.en-US.mac.common.tests.zip', u'firefox-54.0a2.en-US.mac.talos.tests.zip'] for test suite category: talos 01:33:17 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/DD51djZRSZCceFCg4Q-gKg/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip 01:33:17 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/DD51djZRSZCceFCg4Q-gKg/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip'}, attempt #1 01:33:17 INFO - Fetch https://queue.taskcluster.net/v1/task/DD51djZRSZCceFCg4Q-gKg/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip into memory 01:33:18 INFO - Expected file size: 51791052 01:33:18 INFO - Obtained file size: 51791052 01:33:33 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/DD51djZRSZCceFCg4Q-gKg/artifacts/public/build/firefox-54.0a2.en-US.mac.talos.tests.zip 01:33:33 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/DD51djZRSZCceFCg4Q-gKg/artifacts/public/build/firefox-54.0a2.en-US.mac.talos.tests.zip'}, attempt #1 01:33:33 INFO - Fetch https://queue.taskcluster.net/v1/task/DD51djZRSZCceFCg4Q-gKg/artifacts/public/build/firefox-54.0a2.en-US.mac.talos.tests.zip into memory 01:33:34 INFO - Expected file size: 14404424 01:33:34 INFO - Obtained file size: 14404424 01:33:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:33:34 INFO - trying https://queue.taskcluster.net/v1/task/DD51djZRSZCceFCg4Q-gKg/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg 01:33:34 INFO - Downloading https://queue.taskcluster.net/v1/task/DD51djZRSZCceFCg4Q-gKg/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-54.0a2.en-US.mac.dmg 01:33:34 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/DD51djZRSZCceFCg4Q-gKg/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-54.0a2.en-US.mac.dmg'}, attempt #1 01:33:37 INFO - Downloaded 74778982 bytes. 01:33:37 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/DD51djZRSZCceFCg4Q-gKg/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg 01:33:37 INFO - mkdir: /builds/slave/test/properties 01:33:37 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 01:33:37 INFO - Writing to file /builds/slave/test/properties/build_url 01:33:37 INFO - Contents: 01:33:37 INFO - build_url:https://queue.taskcluster.net/v1/task/DD51djZRSZCceFCg4Q-gKg/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg 01:33:37 INFO - Running post-action listener: _resource_record_post_action 01:33:37 INFO - Running post-action listener: set_extra_try_arguments 01:33:37 INFO - [mozharness: 2017-04-21 08:33:37.734757Z] Finished download-and-extract step (success) 01:33:37 INFO - [mozharness: 2017-04-21 08:33:37.734913Z] Running populate-webroot step. 01:33:37 INFO - Running pre-action listener: _resource_record_pre_action 01:33:37 INFO - Running main action method: populate_webroot 01:33:37 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 01:33:37 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 01:33:37 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 01:33:37 INFO - '--tppagecycles', 01:33:37 INFO - '7'], 01:33:37 INFO - 'tests': ['tsvgm']}}, 01:33:37 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 01:33:37 INFO - 'tests': ['tresize', 'tcanvasmark']}, 01:33:37 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 01:33:37 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 01:33:37 INFO - 'tests': ['dromaeo_css', 'kraken']}, 01:33:37 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 01:33:37 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 01:33:37 INFO - 'talos_options': ['--disable-e10s'], 01:33:37 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 01:33:37 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 01:33:37 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 01:33:37 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 01:33:37 INFO - 'talos_options': ['--disable-e10s'], 01:33:37 INFO - 'tests': ['damp', 'tps']}, 01:33:37 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 01:33:37 INFO - 'tests': ['damp', 'tps']}, 01:33:37 INFO - 'g3': {'talos_options': ['--disable-e10s'], 01:33:37 INFO - 'tests': ['dromaeo_dom']}, 01:33:37 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 01:33:37 INFO - 'g4': {'talos_options': ['--disable-e10s'], 01:33:37 INFO - 'tests': ['basic_compositor_video']}, 01:33:37 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 01:33:37 INFO - 'other': {'talos_options': ['--disable-e10s'], 01:33:37 INFO - 'tests': ['a11yr', 01:33:37 INFO - 'ts_paint', 01:33:37 INFO - 'tpaint', 01:33:37 INFO - 'sessionrestore', 01:33:37 INFO - 'sessionrestore_no_auto_restore', 01:33:37 INFO - 'tabpaint']}, 01:33:37 INFO - 'other-e10s': {'tests': ['a11yr', 01:33:37 INFO - 'ts_paint', 01:33:37 INFO - 'tpaint', 01:33:37 INFO - 'sessionrestore', 01:33:37 INFO - 'sessionrestore_no_auto_restore', 01:33:37 INFO - 'tabpaint']}, 01:33:37 INFO - 'other-e10s_l64': {'tests': ['a11yr', 01:33:37 INFO - 'ts_paint', 01:33:37 INFO - 'tpaint', 01:33:37 INFO - 'sessionrestore', 01:33:37 INFO - 'sessionrestore_no_auto_restore', 01:33:37 INFO - 'tabpaint']}, 01:33:37 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 01:33:37 INFO - 'ts_paint', 01:33:37 INFO - 'tpaint', 01:33:37 INFO - 'sessionrestore', 01:33:37 INFO - 'sessionrestore_no_auto_restore', 01:33:37 INFO - 'tabpaint']}, 01:33:37 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 01:33:37 INFO - 'tests': ['a11yr', 01:33:37 INFO - 'ts_paint', 01:33:37 INFO - 'tpaint', 01:33:37 INFO - 'sessionrestore', 01:33:37 INFO - 'sessionrestore_no_auto_restore', 01:33:37 INFO - 'tabpaint']}, 01:33:37 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 01:33:37 INFO - 'tests': ['a11yr', 01:33:37 INFO - 'ts_paint', 01:33:37 INFO - 'tpaint', 01:33:37 INFO - 'sessionrestore', 01:33:37 INFO - 'sessionrestore_no_auto_restore', 01:33:37 INFO - 'tabpaint']}, 01:33:37 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 01:33:37 INFO - 'tests': ['tsvgx', 01:33:37 INFO - 'tsvgr_opacity', 01:33:37 INFO - 'tart', 01:33:37 INFO - 'tscrollx', 01:33:37 INFO - 'cart', 01:33:37 INFO - 'tsvg_static']}, 01:33:37 INFO - 'svgr-e10s': {'tests': ['tsvgx', 01:33:37 INFO - 'tsvgr_opacity', 01:33:37 INFO - 'tart', 01:33:37 INFO - 'tscrollx', 01:33:37 INFO - 'cart', 01:33:37 INFO - 'tsvg_static']}, 01:33:37 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 01:33:37 INFO - 'talos_options': ['--disable-e10s'], 01:33:37 INFO - 'tests': ['tp5o']}, 01:33:37 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 01:33:37 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 01:33:37 INFO - 'talos_options': ['--disable-e10s', 01:33:37 INFO - '--xperf_path', 01:33:37 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 01:33:37 INFO - 'tests': ['tp5n']}, 01:33:37 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 01:33:37 INFO - 'talos_options': ['--xperf_path', 01:33:37 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 01:33:37 INFO - 'tests': ['tp5n']}}, 01:33:37 INFO - 'talos.zip': {'path': '', 01:33:37 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 01:33:37 INFO - Running post-action listener: _resource_record_post_action 01:33:37 INFO - [mozharness: 2017-04-21 08:33:37.753692Z] Finished populate-webroot step (success) 01:33:37 INFO - [mozharness: 2017-04-21 08:33:37.753810Z] Running create-virtualenv step. 01:33:37 INFO - Running pre-action listener: _resource_record_pre_action 01:33:37 INFO - Running main action method: create_virtualenv 01:33:37 INFO - Creating virtualenv /builds/slave/test/build/venv 01:33:37 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 01:33:37 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 01:33:37 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 01:33:37 INFO - Using real prefix '/tools/python27' 01:33:37 INFO - New python executable in /builds/slave/test/build/venv/bin/python 01:33:38 INFO - Installing distribute.............................................................................................................................................................................................done. 01:33:41 INFO - Installing pip.................done. 01:33:41 INFO - Return code: 0 01:33:41 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 01:33:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 01:33:41 INFO - Reading from file tmpfile_stdout 01:33:41 INFO - Output received: 01:33:41 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) 01:33:41 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 01:33: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')]} 01:33:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:33:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:33: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')]} 01:33:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:33:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:33:41 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 0x109ed3b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109ed6768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f82795742b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109cb1d30>, '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 0x109bd8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109ca6be8>, '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.wNYqFqMEdq/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.AnpypnL2k5/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/k4/xd58rgkx0dnbrdjb1pv1qrj800000w/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 01:33:41 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 01:33:41 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 01:33:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wNYqFqMEdq/Render', 01:33:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:33:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:33:41 INFO - 'HOME': '/Users/cltbld', 01:33:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:33:41 INFO - 'LOGNAME': 'cltbld', 01:33:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:33:41 INFO - 'MOZ_NO_REMOTE': '1', 01:33:41 INFO - 'NO_EM_RESTART': '1', 01:33:41 INFO - 'PAGER': '/bin/cat', 01:33:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:33:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:33:41 INFO - 'PWD': '/builds/slave/test', 01:33:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:33:41 INFO - 'SHELL': '/bin/bash', 01:33:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.AnpypnL2k5/Listeners', 01:33:41 INFO - 'TMPDIR': '/var/folders/k4/xd58rgkx0dnbrdjb1pv1qrj800000w/T/', 01:33:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:33:41 INFO - 'USER': 'cltbld', 01:33:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:33:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:33:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:33:41 INFO - 'XPC_FLAGS': '0x0', 01:33:41 INFO - 'XPC_SERVICE_NAME': '0', 01:33:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:33:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:33:42 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 01:33:42 INFO - Cleaning up... 01:33:42 INFO - Return code: 0 01:33:42 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 01:33:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:33:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:33:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:33:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:33:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:33:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:33:42 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 0x109ed3b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109ed6768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f82795742b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109cb1d30>, '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 0x109bd8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109ca6be8>, '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.wNYqFqMEdq/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.AnpypnL2k5/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/k4/xd58rgkx0dnbrdjb1pv1qrj800000w/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 01:33:42 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 01:33:42 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 01:33:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wNYqFqMEdq/Render', 01:33:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:33:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:33:42 INFO - 'HOME': '/Users/cltbld', 01:33:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:33:42 INFO - 'LOGNAME': 'cltbld', 01:33:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:33:42 INFO - 'MOZ_NO_REMOTE': '1', 01:33:42 INFO - 'NO_EM_RESTART': '1', 01:33:42 INFO - 'PAGER': '/bin/cat', 01:33:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:33:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:33:42 INFO - 'PWD': '/builds/slave/test', 01:33:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:33:42 INFO - 'SHELL': '/bin/bash', 01:33:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.AnpypnL2k5/Listeners', 01:33:42 INFO - 'TMPDIR': '/var/folders/k4/xd58rgkx0dnbrdjb1pv1qrj800000w/T/', 01:33:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:33:42 INFO - 'USER': 'cltbld', 01:33:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:33:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:33:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:33:42 INFO - 'XPC_FLAGS': '0x0', 01:33:42 INFO - 'XPC_SERVICE_NAME': '0', 01:33:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:33:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:33:42 INFO - Downloading/unpacking psutil>=3.1.1 01:33:42 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 01:33:42 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 01:33:42 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 01:33:42 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 01:33:42 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 01:33:42 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 01:33:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 01:33:46 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 01:33:46 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 01:33:46 INFO - Installing collected packages: psutil 01:33:46 INFO - Running setup.py install for psutil 01:33:46 INFO - building 'psutil._psutil_osx' extension 01:33:46 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 01:33:46 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 01:33:47 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 01:33:47 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 01:33:47 INFO - building 'psutil._psutil_posix' extension 01:33:47 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 01:33:47 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 01:33:47 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 01:33:47 INFO - ^ 01:33:47 INFO - 1 warning generated. 01:33:47 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 01:33:47 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 01:33:47 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 01:33:47 INFO - Successfully installed psutil 01:33:47 INFO - Cleaning up... 01:33:47 INFO - Return code: 0 01:33:47 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 01:33:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:33:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:33:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:33:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:33:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:33:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:33:47 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 0x109ed3b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109ed6768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f82795742b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109cb1d30>, '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 0x109bd8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109ca6be8>, '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.wNYqFqMEdq/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.AnpypnL2k5/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/k4/xd58rgkx0dnbrdjb1pv1qrj800000w/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 01:33:47 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 01:33:47 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 01:33:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wNYqFqMEdq/Render', 01:33:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:33:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:33:47 INFO - 'HOME': '/Users/cltbld', 01:33:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:33:47 INFO - 'LOGNAME': 'cltbld', 01:33:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:33:47 INFO - 'MOZ_NO_REMOTE': '1', 01:33:47 INFO - 'NO_EM_RESTART': '1', 01:33:47 INFO - 'PAGER': '/bin/cat', 01:33:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:33:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:33:47 INFO - 'PWD': '/builds/slave/test', 01:33:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:33:47 INFO - 'SHELL': '/bin/bash', 01:33:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.AnpypnL2k5/Listeners', 01:33:47 INFO - 'TMPDIR': '/var/folders/k4/xd58rgkx0dnbrdjb1pv1qrj800000w/T/', 01:33:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:33:47 INFO - 'USER': 'cltbld', 01:33:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:33:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:33:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:33:47 INFO - 'XPC_FLAGS': '0x0', 01:33:47 INFO - 'XPC_SERVICE_NAME': '0', 01:33:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:33:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:33:47 INFO - Downloading/unpacking mozsystemmonitor==0.3 01:33:47 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 01:33:47 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 01:33:47 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 01:33:47 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 01:33:47 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 01:33:47 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 01:33:50 INFO - Downloading mozsystemmonitor-0.3.tar.gz 01:33:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 01:33:50 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 01:33:50 INFO - Installing collected packages: mozsystemmonitor 01:33:50 INFO - Running setup.py install for mozsystemmonitor 01:33:50 INFO - Successfully installed mozsystemmonitor 01:33:50 INFO - Cleaning up... 01:33:50 INFO - Return code: 0 01:33:50 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 01:33:50 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')]} 01:33:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:33:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:33:50 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')]} 01:33:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:33:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:33:50 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 0x109ed3b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109ed6768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f82795742b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109cb1d30>, '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 0x109bd8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109ca6be8>, '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.wNYqFqMEdq/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.AnpypnL2k5/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/k4/xd58rgkx0dnbrdjb1pv1qrj800000w/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 01:33:50 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 01:33:50 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 01:33:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wNYqFqMEdq/Render', 01:33:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:33:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:33:50 INFO - 'HOME': '/Users/cltbld', 01:33:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:33:50 INFO - 'LOGNAME': 'cltbld', 01:33:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:33:50 INFO - 'MOZ_NO_REMOTE': '1', 01:33:50 INFO - 'NO_EM_RESTART': '1', 01:33:50 INFO - 'PAGER': '/bin/cat', 01:33:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:33:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:33:50 INFO - 'PWD': '/builds/slave/test', 01:33:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:33:50 INFO - 'SHELL': '/bin/bash', 01:33:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.AnpypnL2k5/Listeners', 01:33:50 INFO - 'TMPDIR': '/var/folders/k4/xd58rgkx0dnbrdjb1pv1qrj800000w/T/', 01:33:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:33:50 INFO - 'USER': 'cltbld', 01:33:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:33:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:33:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:33:50 INFO - 'XPC_FLAGS': '0x0', 01:33:50 INFO - 'XPC_SERVICE_NAME': '0', 01:33:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:33:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:33:50 INFO - Downloading/unpacking jsonschema==2.5.1 01:33:50 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 01:33:50 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 01:33:50 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 01:33:50 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 01:33:50 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 01:33:50 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 01:33:53 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 01:33:53 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 01:33:53 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 01:33:53 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 01:33:53 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 01:33:53 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 01:33:53 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 01:33:53 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 01:33:54 INFO - Downloading functools32-3.2.3-2.tar.gz 01:33:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 01:33:54 INFO - warning: no files found matching '*.txt' 01:33:54 INFO - no previously-included directories found matching 'build' 01:33:54 INFO - no previously-included directories found matching 'dist' 01:33:54 INFO - no previously-included directories found matching '.git*' 01:33:54 INFO - Installing collected packages: jsonschema, functools32 01:33:54 INFO - Running setup.py install for functools32 01:33:54 INFO - warning: no files found matching '*.txt' 01:33:54 INFO - no previously-included directories found matching 'build' 01:33:54 INFO - no previously-included directories found matching 'dist' 01:33:54 INFO - no previously-included directories found matching '.git*' 01:33:54 INFO - Successfully installed jsonschema functools32 01:33:54 INFO - Cleaning up... 01:33:54 INFO - Return code: 0 01:33:54 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 01:33:54 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')]} 01:33:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:33:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:33:54 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')]} 01:33:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:33:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:33:54 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 0x109ed3b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109ed6768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f82795742b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109cb1d30>, '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 0x109bd8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109ca6be8>, '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.wNYqFqMEdq/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.AnpypnL2k5/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/k4/xd58rgkx0dnbrdjb1pv1qrj800000w/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 01:33:54 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 01:33:54 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 01:33:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wNYqFqMEdq/Render', 01:33:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:33:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:33:54 INFO - 'HOME': '/Users/cltbld', 01:33:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:33:54 INFO - 'LOGNAME': 'cltbld', 01:33:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:33:54 INFO - 'MOZ_NO_REMOTE': '1', 01:33:54 INFO - 'NO_EM_RESTART': '1', 01:33:54 INFO - 'PAGER': '/bin/cat', 01:33:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:33:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:33:54 INFO - 'PWD': '/builds/slave/test', 01:33:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:33:54 INFO - 'SHELL': '/bin/bash', 01:33:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.AnpypnL2k5/Listeners', 01:33:54 INFO - 'TMPDIR': '/var/folders/k4/xd58rgkx0dnbrdjb1pv1qrj800000w/T/', 01:33:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:33:54 INFO - 'USER': 'cltbld', 01:33:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:33:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:33:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:33:54 INFO - 'XPC_FLAGS': '0x0', 01:33:54 INFO - 'XPC_SERVICE_NAME': '0', 01:33:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:33:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:33:54 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 01:33:54 INFO - Cleaning up... 01:33:54 INFO - Return code: 0 01:33:54 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 01:33:54 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')]} 01:33:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:33:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:33:54 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')]} 01:33:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:33:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:33:54 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 0x109ed3b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109ed6768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f82795742b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109cb1d30>, '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 0x109bd8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109ca6be8>, '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.wNYqFqMEdq/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.AnpypnL2k5/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/k4/xd58rgkx0dnbrdjb1pv1qrj800000w/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 01:33:54 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 01:33:54 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 01:33:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wNYqFqMEdq/Render', 01:33:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:33:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:33:54 INFO - 'HOME': '/Users/cltbld', 01:33:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:33:54 INFO - 'LOGNAME': 'cltbld', 01:33:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:33:54 INFO - 'MOZ_NO_REMOTE': '1', 01:33:54 INFO - 'NO_EM_RESTART': '1', 01:33:54 INFO - 'PAGER': '/bin/cat', 01:33:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:33:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:33:54 INFO - 'PWD': '/builds/slave/test', 01:33:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:33:54 INFO - 'SHELL': '/bin/bash', 01:33:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.AnpypnL2k5/Listeners', 01:33:54 INFO - 'TMPDIR': '/var/folders/k4/xd58rgkx0dnbrdjb1pv1qrj800000w/T/', 01:33:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:33:54 INFO - 'USER': 'cltbld', 01:33:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:33:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:33:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:33:54 INFO - 'XPC_FLAGS': '0x0', 01:33:54 INFO - 'XPC_SERVICE_NAME': '0', 01:33:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:33:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:33:54 INFO - Downloading/unpacking blobuploader==1.2.4 01:33:54 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 01:33:54 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 01:33:54 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 01:33:54 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 01:33:54 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 01:33:54 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 01:33:57 INFO - Downloading blobuploader-1.2.4.tar.gz 01:33:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 01:33:57 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 01:33: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 01:33: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 01:33: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 01:33: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 01:33: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 01:33: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 01:33:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 01:33:58 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 01:33:58 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 01:33:58 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 01:33:58 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 01:33:58 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 01:33:58 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 01:33:58 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 01:33:58 INFO - Downloading docopt-0.6.1.tar.gz 01:33:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 01:33:58 INFO - Installing collected packages: blobuploader, requests, docopt 01:33:58 INFO - Running setup.py install for blobuploader 01:33:58 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 01:33:59 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 01:33:59 INFO - Running setup.py install for requests 01:33:59 INFO - Running setup.py install for docopt 01:33:59 INFO - Successfully installed blobuploader requests docopt 01:33:59 INFO - Cleaning up... 01:33:59 INFO - Return code: 0 01:33:59 INFO - Installing None into virtualenv /builds/slave/test/build/venv 01:33:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:33:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:33:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:33:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:33:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:33:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:33:59 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 0x109ed3b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109ed6768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f82795742b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109cb1d30>, '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 0x109bd8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109ca6be8>, '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.wNYqFqMEdq/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.AnpypnL2k5/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/k4/xd58rgkx0dnbrdjb1pv1qrj800000w/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 01:33:59 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 01:33:59 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 01:33:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wNYqFqMEdq/Render', 01:33:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:33:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:33:59 INFO - 'HOME': '/Users/cltbld', 01:33:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:33:59 INFO - 'LOGNAME': 'cltbld', 01:33:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:33:59 INFO - 'MOZ_NO_REMOTE': '1', 01:33:59 INFO - 'NO_EM_RESTART': '1', 01:33:59 INFO - 'PAGER': '/bin/cat', 01:33:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:33:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:33:59 INFO - 'PWD': '/builds/slave/test', 01:33:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:33:59 INFO - 'SHELL': '/bin/bash', 01:33:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.AnpypnL2k5/Listeners', 01:33:59 INFO - 'TMPDIR': '/var/folders/k4/xd58rgkx0dnbrdjb1pv1qrj800000w/T/', 01:33:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:33:59 INFO - 'USER': 'cltbld', 01:33:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:33:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:33:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:33:59 INFO - 'XPC_FLAGS': '0x0', 01:33:59 INFO - 'XPC_SERVICE_NAME': '0', 01:33:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:34:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:34:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 01:34:00 INFO - Running setup.py (path:/var/folders/k4/xd58rgkx0dnbrdjb1pv1qrj800000w/T/pip-R_Dzp4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 01:34:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 01:34:00 INFO - Running setup.py (path:/var/folders/k4/xd58rgkx0dnbrdjb1pv1qrj800000w/T/pip-3A0lLS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 01:34:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 01:34:00 INFO - Running setup.py (path:/var/folders/k4/xd58rgkx0dnbrdjb1pv1qrj800000w/T/pip-liswXf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 01:34:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 01:34:00 INFO - Running setup.py (path:/var/folders/k4/xd58rgkx0dnbrdjb1pv1qrj800000w/T/pip-VdKnpy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 01:34:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 01:34:00 INFO - Running setup.py (path:/var/folders/k4/xd58rgkx0dnbrdjb1pv1qrj800000w/T/pip-bOzq2R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 01:34:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 01:34:00 INFO - Running setup.py (path:/var/folders/k4/xd58rgkx0dnbrdjb1pv1qrj800000w/T/pip-vGyI0L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 01:34:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 01:34:00 INFO - Running setup.py (path:/var/folders/k4/xd58rgkx0dnbrdjb1pv1qrj800000w/T/pip-GCkTTE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 01:34:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 01:34:00 INFO - Running setup.py (path:/var/folders/k4/xd58rgkx0dnbrdjb1pv1qrj800000w/T/pip-WIbK88-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 01:34:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 01:34:00 INFO - Running setup.py (path:/var/folders/k4/xd58rgkx0dnbrdjb1pv1qrj800000w/T/pip-WYij8s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 01:34:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 01:34:01 INFO - Running setup.py (path:/var/folders/k4/xd58rgkx0dnbrdjb1pv1qrj800000w/T/pip-yfGSWF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 01:34:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 01:34:01 INFO - Running setup.py (path:/var/folders/k4/xd58rgkx0dnbrdjb1pv1qrj800000w/T/pip-J1mKQo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 01:34:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 01:34:01 INFO - Running setup.py (path:/var/folders/k4/xd58rgkx0dnbrdjb1pv1qrj800000w/T/pip-bRyGze-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 01:34:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 01:34:01 INFO - Running setup.py (path:/var/folders/k4/xd58rgkx0dnbrdjb1pv1qrj800000w/T/pip-REbyJw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 01:34:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 01:34:01 INFO - Running setup.py (path:/var/folders/k4/xd58rgkx0dnbrdjb1pv1qrj800000w/T/pip-8f9PNg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 01:34:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 01:34:01 INFO - Running setup.py (path:/var/folders/k4/xd58rgkx0dnbrdjb1pv1qrj800000w/T/pip-hPvsXC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 01:34:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 01:34:01 INFO - Running setup.py (path:/var/folders/k4/xd58rgkx0dnbrdjb1pv1qrj800000w/T/pip-iUKvN2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 01:34:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 01:34:01 INFO - Running setup.py (path:/var/folders/k4/xd58rgkx0dnbrdjb1pv1qrj800000w/T/pip-4Khw3v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 01:34:01 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 01:34:01 INFO - Running setup.py install for manifestparser 01:34:01 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 01:34:01 INFO - Running setup.py install for mozcrash 01:34:02 INFO - Running setup.py install for mozdebug 01:34:02 INFO - Running setup.py install for mozdevice 01:34:02 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 01:34:02 INFO - Installing dm script to /builds/slave/test/build/venv/bin 01:34:02 INFO - Running setup.py install for mozfile 01:34:02 INFO - Running setup.py install for mozhttpd 01:34:02 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 01:34:02 INFO - Running setup.py install for mozinfo 01:34:02 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 01:34:02 INFO - Running setup.py install for mozInstall 01:34:03 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 01:34:03 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 01:34:03 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 01:34:03 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 01:34:03 INFO - Running setup.py install for mozleak 01:34:03 INFO - Running setup.py install for mozlog 01:34:03 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 01:34:03 INFO - Running setup.py install for moznetwork 01:34:03 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 01:34:03 INFO - Running setup.py install for mozprocess 01:34:03 INFO - Running setup.py install for mozprofile 01:34:03 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 01:34:03 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 01:34:03 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 01:34:03 INFO - Running setup.py install for mozrunner 01:34:03 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 01:34:04 INFO - Running setup.py install for mozscreenshot 01:34:04 INFO - Running setup.py install for moztest 01:34:04 INFO - Running setup.py install for mozversion 01:34:04 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 01:34:04 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 01:34:04 INFO - Cleaning up... 01:34:04 INFO - Return code: 0 01:34:04 INFO - Installing None into virtualenv /builds/slave/test/build/venv 01:34: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')]} 01:34:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:34:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:34: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')]} 01:34:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:34:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:34:04 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 0x109ed3b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109ed6768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f82795742b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109cb1d30>, '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 0x109bd8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109ca6be8>, '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.wNYqFqMEdq/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.AnpypnL2k5/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/k4/xd58rgkx0dnbrdjb1pv1qrj800000w/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 01:34:04 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 01:34:04 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 01:34:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wNYqFqMEdq/Render', 01:34:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:34:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:34:04 INFO - 'HOME': '/Users/cltbld', 01:34:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:34:04 INFO - 'LOGNAME': 'cltbld', 01:34:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:34:04 INFO - 'MOZ_NO_REMOTE': '1', 01:34:04 INFO - 'NO_EM_RESTART': '1', 01:34:04 INFO - 'PAGER': '/bin/cat', 01:34:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:34:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:34:04 INFO - 'PWD': '/builds/slave/test', 01:34:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:34:04 INFO - 'SHELL': '/bin/bash', 01:34:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.AnpypnL2k5/Listeners', 01:34:04 INFO - 'TMPDIR': '/var/folders/k4/xd58rgkx0dnbrdjb1pv1qrj800000w/T/', 01:34:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:34:04 INFO - 'USER': 'cltbld', 01:34:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:34:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:34:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:34:04 INFO - 'XPC_FLAGS': '0x0', 01:34:04 INFO - 'XPC_SERVICE_NAME': '0', 01:34:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:34:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:34:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 01:34:04 INFO - Running setup.py (path:/var/folders/k4/xd58rgkx0dnbrdjb1pv1qrj800000w/T/pip-m_1kYE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 01:34:04 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)) 01:34:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 01:34:04 INFO - Running setup.py (path:/var/folders/k4/xd58rgkx0dnbrdjb1pv1qrj800000w/T/pip-qSkYLB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 01:34:04 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)) 01:34:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 01:34:04 INFO - Running setup.py (path:/var/folders/k4/xd58rgkx0dnbrdjb1pv1qrj800000w/T/pip-FZ0gXd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 01:34:05 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)) 01:34:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 01:34:05 INFO - Running setup.py (path:/var/folders/k4/xd58rgkx0dnbrdjb1pv1qrj800000w/T/pip-fcD36a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 01:34:05 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)) 01:34:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 01:34:05 INFO - Running setup.py (path:/var/folders/k4/xd58rgkx0dnbrdjb1pv1qrj800000w/T/pip-wnQitr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 01:34:05 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)) 01:34:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 01:34:05 INFO - Running setup.py (path:/var/folders/k4/xd58rgkx0dnbrdjb1pv1qrj800000w/T/pip-DqopIX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 01:34:05 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)) 01:34:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 01:34:05 INFO - Running setup.py (path:/var/folders/k4/xd58rgkx0dnbrdjb1pv1qrj800000w/T/pip-Dk5gUq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 01:34:05 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)) 01:34:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 01:34:05 INFO - Running setup.py (path:/var/folders/k4/xd58rgkx0dnbrdjb1pv1qrj800000w/T/pip-l5V5QB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 01:34:05 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)) 01:34:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 01:34:05 INFO - Running setup.py (path:/var/folders/k4/xd58rgkx0dnbrdjb1pv1qrj800000w/T/pip-UiZF_u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 01:34:05 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)) 01:34:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 01:34:05 INFO - Running setup.py (path:/var/folders/k4/xd58rgkx0dnbrdjb1pv1qrj800000w/T/pip-H4SDAR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 01:34:05 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)) 01:34:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 01:34:05 INFO - Running setup.py (path:/var/folders/k4/xd58rgkx0dnbrdjb1pv1qrj800000w/T/pip-0SrfZb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 01:34:05 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)) 01:34:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 01:34:05 INFO - Running setup.py (path:/var/folders/k4/xd58rgkx0dnbrdjb1pv1qrj800000w/T/pip-vWyiGl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 01:34:06 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)) 01:34:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 01:34:06 INFO - Running setup.py (path:/var/folders/k4/xd58rgkx0dnbrdjb1pv1qrj800000w/T/pip-7qrxad-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 01:34:06 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)) 01:34:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 01:34:06 INFO - Running setup.py (path:/var/folders/k4/xd58rgkx0dnbrdjb1pv1qrj800000w/T/pip-HajRKD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 01:34:06 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)) 01:34:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 01:34:06 INFO - Running setup.py (path:/var/folders/k4/xd58rgkx0dnbrdjb1pv1qrj800000w/T/pip-aaY9ca-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 01:34:06 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)) 01:34:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 01:34:06 INFO - Running setup.py (path:/var/folders/k4/xd58rgkx0dnbrdjb1pv1qrj800000w/T/pip-Y9F6PA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 01:34:06 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)) 01:34:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 01:34:06 INFO - Running setup.py (path:/var/folders/k4/xd58rgkx0dnbrdjb1pv1qrj800000w/T/pip-i_MXul-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 01:34:06 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)) 01:34:06 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)) 01:34:06 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)) 01:34:06 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)) 01:34:06 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)) 01:34:06 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)) 01:34:06 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 01:34:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:34:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:34:06 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:34:06 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:34:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:34:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:34:09 INFO - Downloading blessings-1.6.tar.gz 01:34:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 01:34:09 INFO - Installing collected packages: blessings 01:34:09 INFO - Running setup.py install for blessings 01:34:09 INFO - Successfully installed blessings 01:34:09 INFO - Cleaning up... 01:34:09 INFO - Return code: 0 01:34:09 INFO - Done creating virtualenv /builds/slave/test/build/venv. 01:34:09 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 01:34:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 01:34:09 INFO - Reading from file tmpfile_stdout 01:34:09 INFO - Current package versions: 01:34:09 INFO - blessings == 1.6 01:34:09 INFO - blobuploader == 1.2.4 01:34:09 INFO - docopt == 0.6.1 01:34:09 INFO - functools32 == 3.2.3-2 01:34:09 INFO - jsonschema == 2.5.1 01:34:09 INFO - manifestparser == 1.1 01:34:09 INFO - mozInstall == 1.12 01:34:09 INFO - mozcrash == 1.0 01:34:09 INFO - mozdebug == 0.1 01:34:09 INFO - mozdevice == 0.49 01:34:09 INFO - mozfile == 1.2 01:34:09 INFO - mozhttpd == 0.7 01:34:09 INFO - mozinfo == 0.9 01:34:09 INFO - mozleak == 0.1 01:34:09 INFO - mozlog == 3.4 01:34:09 INFO - moznetwork == 0.27 01:34:09 INFO - mozprocess == 0.25 01:34:09 INFO - mozprofile == 0.28 01:34:09 INFO - mozrunner == 6.13 01:34:09 INFO - mozscreenshot == 0.1 01:34:09 INFO - mozsystemmonitor == 0.3 01:34:09 INFO - moztest == 0.8 01:34:09 INFO - mozversion == 1.4 01:34:09 INFO - psutil == 3.1.1 01:34:09 INFO - requests == 1.2.3 01:34:09 INFO - wsgiref == 0.1.2 01:34:09 INFO - Installing None into virtualenv /builds/slave/test/build/venv 01:34: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')]} 01:34:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:34:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:34: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')]} 01:34:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:34:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:34:09 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 0x109ed3b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109ed6768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f82795742b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109cb1d30>, '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 0x109bd8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109ca6be8>, '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.wNYqFqMEdq/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.AnpypnL2k5/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/k4/xd58rgkx0dnbrdjb1pv1qrj800000w/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 01:34:09 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 01:34:09 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 01:34:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wNYqFqMEdq/Render', 01:34:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:34:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:34:09 INFO - 'HOME': '/Users/cltbld', 01:34:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:34:09 INFO - 'LOGNAME': 'cltbld', 01:34:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:34:09 INFO - 'MOZ_NO_REMOTE': '1', 01:34:09 INFO - 'NO_EM_RESTART': '1', 01:34:09 INFO - 'PAGER': '/bin/cat', 01:34:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:34:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:34:09 INFO - 'PWD': '/builds/slave/test', 01:34:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:34:09 INFO - 'SHELL': '/bin/bash', 01:34:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.AnpypnL2k5/Listeners', 01:34:09 INFO - 'TMPDIR': '/var/folders/k4/xd58rgkx0dnbrdjb1pv1qrj800000w/T/', 01:34:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:34:09 INFO - 'USER': 'cltbld', 01:34:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:34:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:34:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:34:09 INFO - 'XPC_FLAGS': '0x0', 01:34:09 INFO - 'XPC_SERVICE_NAME': '0', 01:34:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:34:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:34:10 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)) 01:34:10 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)) 01:34:10 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)) 01:34:10 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)) 01:34:10 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)) 01:34:10 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)) 01:34:10 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)) 01:34:10 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)) 01:34:10 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)) 01:34:10 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)) 01:34:10 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)) 01:34:10 INFO - Cleaning up... 01:34:10 INFO - Return code: 0 01:34:10 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 01:34:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:34:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:34:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:34:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:34:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:34:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:34:10 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x109ed3b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109ed6768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f82795742b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109cb1d30>, '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 0x109bd8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109ca6be8>, '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.wNYqFqMEdq/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.AnpypnL2k5/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/k4/xd58rgkx0dnbrdjb1pv1qrj800000w/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 01:34:10 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 01:34:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 01:34:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wNYqFqMEdq/Render', 01:34:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:34:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:34:10 INFO - 'HOME': '/Users/cltbld', 01:34:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:34:10 INFO - 'LOGNAME': 'cltbld', 01:34:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:34:10 INFO - 'MOZ_NO_REMOTE': '1', 01:34:10 INFO - 'NO_EM_RESTART': '1', 01:34:10 INFO - 'PAGER': '/bin/cat', 01:34:10 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:34:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:34:10 INFO - 'PWD': '/builds/slave/test', 01:34:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:34:10 INFO - 'SHELL': '/bin/bash', 01:34:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.AnpypnL2k5/Listeners', 01:34:10 INFO - 'TMPDIR': '/var/folders/k4/xd58rgkx0dnbrdjb1pv1qrj800000w/T/', 01:34:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:34:10 INFO - 'USER': 'cltbld', 01:34:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:34:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:34:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:34:10 INFO - 'XPC_FLAGS': '0x0', 01:34:10 INFO - 'XPC_SERVICE_NAME': '0', 01:34:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:34:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:34:10 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 01:34:10 INFO - Cleaning up... 01:34:10 INFO - Return code: 0 01:34:10 INFO - Running post-action listener: _resource_record_post_action 01:34:10 INFO - Running post-action listener: _start_resource_monitoring 01:34:10 INFO - Starting resource monitoring. 01:34:10 INFO - [mozharness: 2017-04-21 08:34:10.430527Z] Finished create-virtualenv step (success) 01:34:10 INFO - [mozharness: 2017-04-21 08:34:10.431508Z] Running install step. 01:34:10 INFO - Running pre-action listener: _resource_record_pre_action 01:34:10 INFO - Running main action method: install 01:34:10 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 01:34:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 01:34:10 INFO - Reading from file tmpfile_stdout 01:34:10 INFO - Detecting whether we're running mozinstall >=1.0... 01:34:10 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 01:34:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 01:34:10 INFO - Reading from file tmpfile_stdout 01:34:10 INFO - Output received: 01:34:10 INFO - Usage: mozinstall [options] installer 01:34:10 INFO - Options: 01:34:10 INFO - -h, --help show this help message and exit 01:34:10 INFO - -d DEST, --destination=DEST 01:34:10 INFO - Directory to install application into. [default: 01:34:10 INFO - "/builds/slave/test"] 01:34:10 INFO - --app=APP Application being installed. [default: firefox] 01:34:10 INFO - mkdir: /builds/slave/test/build/application 01:34:10 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-54.0a2.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 01:34:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-54.0a2.en-US.mac.dmg --destination /builds/slave/test/build/application 01:34:30 INFO - Reading from file tmpfile_stdout 01:34:30 INFO - Output received: 01:34:30 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 01:34:30 INFO - Running post-action listener: _resource_record_post_action 01:34:30 INFO - [mozharness: 2017-04-21 08:34:30.926990Z] Finished install step (success) 01:34:30 INFO - [mozharness: 2017-04-21 08:34:30.927130Z] Running run-tests step. 01:34:30 INFO - Running pre-action listener: _resource_record_pre_action 01:34:30 INFO - Running main action method: run_tests 01:34:30 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 01:34:30 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 01:34:30 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 01:34:30 INFO - 2017-04-21 01:34:30.941 screenresolution[1636:9820] starting screenresolution argv=screenresolution get 01:34:30 INFO - 2017-04-21 01:34:30.944 screenresolution[1636:9820] Display 0: 1600x1200x32@60 01:34:30 INFO - 2017-04-21 01:34:30.949 screenresolution[1637:9822] starting screenresolution argv=screenresolution list 01:34:30 INFO - Available Modes on Display 0 01:34:30 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 01:34:30 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 01:34:30 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 01:34:30 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 01:34:31 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 01:34:31 INFO - Intel Iris: 01:34:31 INFO - Chipset Model: Intel Iris 01:34:31 INFO - Type: GPU 01:34:31 INFO - Bus: Built-In 01:34:31 INFO - VRAM (Dynamic, Max): 1536 MB 01:34:31 INFO - Vendor: Intel (0x8086) 01:34:31 INFO - Device ID: 0x0a2e 01:34:31 INFO - Revision ID: 0x0009 01:34:31 INFO - Displays: 01:34:31 INFO - Display: 01:34:31 INFO - Resolution: 1600 x 1200 @ 60 Hz 01:34:31 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 01:34:31 INFO - Display Serial Number: 1600x1200 60 01:34:31 INFO - Main Display: Yes 01:34:31 INFO - Mirror: Off 01:34:31 INFO - Online: Yes 01:34:31 INFO - Rotation: Supported 01:34:31 INFO - Return code: 0 01:34:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 01:34:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 01:34:31 INFO - Python 2.7.3 01:34:31 INFO - Return code: 0 01:34:31 INFO - grabbing minidump binary from tooltool 01:34:31 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')]} 01:34:31 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 0x109cb1d30>, '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 0x109bd8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109ca6be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 01:34:31 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 01:34:31 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 01:34:31 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 01:34:31 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 01:34:31 INFO - Return code: 0 01:34:31 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 01:34:31 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 01:34:31 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 01:34:31 INFO - ENV: RUST_BACKTRACE is now 1 01:34:31 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 01:34:31 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 01:34:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/DD51djZRSZCceFCg4Q-gKg/artifacts/public/build/firefox-54.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0218', '--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 01:34:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs --executablePath /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/DD51djZRSZCceFCg4Q-gKg/artifacts/public/build/firefox-54.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0218 --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 01:34:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wNYqFqMEdq/Render', 01:34:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:34:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:34:31 INFO - 'HOME': '/Users/cltbld', 01:34:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:34:31 INFO - 'LOGNAME': 'cltbld', 01:34:31 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 01:34:31 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 01:34:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:34:31 INFO - 'MOZ_NO_REMOTE': '1', 01:34:31 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 01:34:31 INFO - 'NO_EM_RESTART': '1', 01:34:31 INFO - 'PAGER': '/bin/cat', 01:34:31 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:34:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:34:31 INFO - 'PWD': '/builds/slave/test', 01:34:31 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 01:34:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:34:31 INFO - 'RUST_BACKTRACE': '1', 01:34:31 INFO - 'SHELL': '/bin/bash', 01:34:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.AnpypnL2k5/Listeners', 01:34:31 INFO - 'TMPDIR': '/var/folders/k4/xd58rgkx0dnbrdjb1pv1qrj800000w/T/', 01:34:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:34:31 INFO - 'USER': 'cltbld', 01:34:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:34:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:34:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:34:31 INFO - 'XPC_FLAGS': '0x0', 01:34:31 INFO - 'XPC_SERVICE_NAME': '0', 01:34:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:34:31 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/DD51djZRSZCceFCg4Q-gKg/artifacts/public/build/firefox-54.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0218', '--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 01:34:31 INFO - mozversion application_buildid: 20170421004017 01:34:31 INFO - mozversion application_changeset: 94b7e538af7dbb9a6567cad9125c2f5411e7fb80 01:34:31 INFO - mozversion application_display_name: FirefoxDeveloperEdition 01:34:31 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 01:34:31 INFO - mozversion application_name: Firefox 01:34:31 INFO - mozversion application_remotingname: firefox-dev 01:34:31 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 01:34:31 INFO - mozversion application_vendor: Mozilla 01:34:31 INFO - mozversion application_version: 54.0a2 01:34:31 INFO - mozversion platform_buildid: 20170421004017 01:34:31 INFO - mozversion platform_changeset: 94b7e538af7dbb9a6567cad9125c2f5411e7fb80 01:34:31 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 01:34:31 INFO - mozversion platform_version: 54.0a2 01:34:31 INFO - using testdate: 1492763671 01:34:31 INFO - actual date: 1492763671 01:34:31 INFO - starting webserver on 'localhost:49246' 01:34:31 INFO - SUITE-START | Running 2 tests 01:34:31 INFO - TEST-START | dromaeo_css 01:34:31 INFO - operating with platform_type : mac_ 01:34:31 INFO - Initialising browser for dromaeo_css test... 01:34:31 INFO - TEST-INFO | started process 1647 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/k4/xd58rgkx0dnbrdjb1pv1qrj800000w/T/tmpNbCPTd/profile http://localhost:49246/getInfo.html) 01:34:34 INFO - PROCESS | 1647 | __metrics Screen width/height:1600/1200 01:34:34 INFO - PROCESS | 1647 | colorDepth:24 01:34:34 INFO - PROCESS | 1647 | Browser inner width/height: 1024/689 01:34:34 INFO - PROCESS | 1647 | __metrics 01:34:34 INFO - PROCESS | 1647 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 01:34:34 INFO - PROCESS | 1647 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 01:34:35 INFO - TEST-INFO | 1647: exit 0 01:34:35 INFO - Browser initialized. 01:34:35 INFO - Running cycle 1/1 for dromaeo_css test... 01:34:35 INFO - TEST-INFO | started process 1652 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/k4/xd58rgkx0dnbrdjb1pv1qrj800000w/T/tmpNbCPTd/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 01:34:47 INFO - PROCESS | 1652 | RSS: Main: 215175168 01:34:47 INFO - PROCESS | 1652 | 01:37:51 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49246/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49246/tests/dromaeo/cssquery-ext.html) 01:37:53 INFO - PROCESS | 1652 | RSS: Main: 374169600 01:37:53 INFO - PROCESS | 1652 | 01:39:37 INFO - PROCESS | 1652 | ************************* 01:39:37 INFO - PROCESS | 1652 | A coding exception was thrown and uncaught in a Task. 01:39:37 INFO - PROCESS | 1652 | 01:39:37 ERROR - PROCESS | 1652 | Full message: TypeError: NetworkError when attempting to fetch resource. 01:39:37 INFO - PROCESS | 1652 | Full stack: 01:39:37 INFO - PROCESS | 1652 | ************************* 01:39:37 INFO - PROCESS | 1652 | ************************* 01:39:37 INFO - PROCESS | 1652 | A coding exception was thrown and uncaught in a Task. 01:39:37 INFO - PROCESS | 1652 | 01:39:37 ERROR - PROCESS | 1652 | Full message: TypeError: NetworkError when attempting to fetch resource. 01:39:37 INFO - PROCESS | 1652 | Full stack: 01:39:37 INFO - PROCESS | 1652 | ************************* 01:39:37 INFO - PROCESS | 1652 | ************************* 01:39:37 INFO - PROCESS | 1652 | A coding exception was thrown and uncaught in a Task. 01:39:37 INFO - PROCESS | 1652 | 01:39:37 ERROR - PROCESS | 1652 | Full message: TypeError: NetworkError when attempting to fetch resource. 01:39:37 INFO - PROCESS | 1652 | Full stack: 01:39:37 INFO - PROCESS | 1652 | ************************* 01:39:37 INFO - PROCESS | 1652 | ************************* 01:39:37 INFO - PROCESS | 1652 | A coding exception was thrown and uncaught in a Task. 01:39:37 INFO - PROCESS | 1652 | 01:39:37 ERROR - PROCESS | 1652 | Full message: TypeError: NetworkError when attempting to fetch resource. 01:39:37 INFO - PROCESS | 1652 | Full stack: 01:39:37 INFO - PROCESS | 1652 | ************************* 01:40:56 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49246/tests/dromaeo/cssquery-ext.html (next: http://localhost:49246/tests/dromaeo/cssquery-jquery.html) 01:40:56 INFO - PROCESS | 1652 | RSS: Main: 350777344 01:40:56 INFO - PROCESS | 1652 | 01:43:59 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49246/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49246/tests/dromaeo/cssquery-mootools.html) 01:43:59 INFO - PROCESS | 1652 | RSS: Main: 341770240 01:43:59 INFO - PROCESS | 1652 | 01:47:02 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49246/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49246/tests/dromaeo/cssquery-prototype.html) 01:47:02 INFO - PROCESS | 1652 | RSS: Main: 339353600 01:47:02 INFO - PROCESS | 1652 | 01:50:06 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49246/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49246/tests/dromaeo/cssquery-yui.html) 01:50:07 INFO - PROCESS | 1652 | RSS: Main: 323121152 01:50:07 INFO - PROCESS | 1652 | 01:53:10 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49246/tests/dromaeo/cssquery-yui.html (next: http://localhost:49246/tests/dromaeo/cssquery-dojo.html) 01:53:10 INFO - PROCESS | 1652 | RSS: Main: 418775040 01:53:10 INFO - PROCESS | 1652 | 01:53:10 INFO - PROCESS | 1652 | __start_tp_report 01:53:10 INFO - PROCESS | 1652 | _x_x_mozilla_page_load 01:53:10 INFO - PROCESS | 1652 | _x_x_mozilla_page_load_details 01:53:10 INFO - PROCESS | 1652 | |i|pagename|runs| 01:53:10 INFO - PROCESS | 1652 | |0;dojo.html;2619.873454414602;3147.725171305621;3349.7487688423375;3408.6420925802777;3430.9485357719623;5012.17299145641;5667.319921609403;5899.469047785704;5905.911411328822;5940.108983652454;4974.452810190876;5895.056790913454;5947.92937271291;5960.0761881908265;5979.850503737398;5331.866703332428;5344.5724342052545;5393.7303134843105;5404.08130617794;5450.400455949843;5241.213817927303;5268.683878967255;5440.809571664992;5455.563554915597;5501.4223506531835;5300.681959082448;5337;5368.355797304329;5373.059714549958;5379.058664733676;5364.812231571896;5371;5376.354837419534;5377.220303056074;5379.919301210485;5243.161094224944;5288.365396152486;5308.177232528964;5310.123829568117;5313.070212712799;5978.521718262572;5996.670183139926;6000.8499787505225;6002.6398416094735;6006.5495087868585;6145.969270153665;6157.815265542041;6170.475509581691;6172.938270617281;6233.34549872266;6444.130042444257;6447.903281450782;6449.742010319582;6451.709673064724;6465.4827613790785;6053.51571874249;6101.60339577926;6118.694065296718;6120.959436895739;6127.693615319261;6256.468200202943;6270.561060725797;6514.60912345256;6531.150950376421;6541.443977261986;6192.535559832941;6213.440790328892;6224.097505861625;6239.812805615838;6278.30938596754;6337.112804207414;6338.556301058974;6347.809565713036;6351.809445716636;6352.2694890087305;6254.9687251563455;6264.9976003839165;6265.028920517327;6273;6279.529035322369;6210.130581718563;6213;6254.906176407358;6281.183446152062;6289.0252010938375;5103.821366252163;5111.846644600668;5118;5121.743912804345;5145.253938178943;4548.272276435754;4550.226461501553;4553.544645535486;4560;4591.219492686251;5722.284714410699;5791.420857914259;5827.708614569255;5908.438698323653;5990.550708696778;6347.714352854133;6373.681315934185;6400.9039864402075;6401.935980640227;6408.7756928508215;5979.01346277868;5982.252218472691;5983.461488465972;5992.40075992406;6053.697315134314;5458.317710286215;5462.3445186577865;5622.606417550814;5623.0721930880945;5625.606207565513;6415.294317624965;6493.350664933469;6548;6594.505412094016;6612.404883560503;6404.4876409886165;6409.4872410206135;6418.518611104279;6424.421802037839;6433.678316084271;6459.806205813833;6593.736250549924;6604.0093985902495;6615.04081908111;6616.437602803816;6210.968945155245;6228.283747369111;6248.781292654735;6260.40526150015;6261.68691565429;6371.585846919936;6381.106645069787;6400.583962042452;6402.871942561216;6406.5515413920575;6107.450329470369;6217.595856269328;6240.500759939285;6324.272708638381;6340.714667839866;6107.3708791890585;6172.876542469216;6250.562460627894;6256.186695729526;6335.873282534231;6213.161223234805;6218.440340369389;6234.2830574484515;6243.219597550306;6245.6252624842655;6157.907631385622;6192.721327540181;6307.274663413767;6323.241211054703;6330.303666596578;5083.313752643346;5089.262057001788;5997.520198384032;6027.6986150693165;6050.788222412194 01:53:10 INFO - PROCESS | 1652 | |1;ext.html;755.7883792538088;784.1961988961315;806.7620052084636;875.6672464463503;885.0972008551282;27627.723722762792;27922.743476543554;28986.710132898665;39489.21021579567;39668.60331396685;40567;40810.795946020306;40816.18367632646;40832;40842.38736418949;9053.36626436148;9196.080391960784;9198.356115071947;9213.447193168398;9222.907770922306;7318.524295920774;7492.662830172655;9153.954230228874;10916.945415272912;11177.161712871526;11073.50169242386;11122.55509779608;11123.110151187926;11126.777464450706;11130.276532025433;11032;11055.391953442557;11075.335479871233;11095.944520277428;11112.666620001413;7312.96343518279;7458.440616953751;10689.198310126772;10830.133589312914;10884.564617415293;39695.41979771305;39976.80069597917;40018.999525011815;40112;40361;3852.757194683173;3860.961390386088;3878.398848178537;3892.1826416452577;3919.549251836047;26916.923323067054;27639.86180069107;28322.858385708147;28488.28779280514;29148.8542557288;2881.9048761470717;2904.7385735283715;3101.4882544380152;3186.410514054884;3204.951925721116;359682;359887;360303.19848400593;360924;361213.9999999974;50727.49272507302;51876.48123518792;51999.70000750011;52010;52130;53891.19163212555;55018.449815502136;55089.17366239509;55119.72440137854;55146.72426637841;11949.88050119505;14828.70342593142;14840.183789891444;14875.5537333878;14875.553733388017;12808.80786788199;12810.871891281155;12818.294993775431;12821.487140514462;12848.100632955604;10346.22403319754;10613.310134841211;10709.143268538499;12071.973882219934;12249.958753506051;5135.818761684834;5326.760295786699;5784.334801497797;5789.710514474344;5803.912941305884;890.6081324217324;939.220447028978;940.4169414962766;972.3825370889537;973.1047436358567;27995.440091198063;28291;29053.564196536645;29076.418471630448;29385.706142938725;790.7904405332591;1052.2686732720686;1094.2559059839386;1114.9944250278697;1135.1543100390263;13462.528811491551;13874.306284685725;14160.929195354163;14174.07868488545;14189.219592922489;296956;297678;298715;298720;298974;51432.714182145;51485;51585.484145158065;51586;51605.741971290656;1389.4372779024511;1447.6525633848012;1452.5932738833144;1455.577882414088;1458.4457905995653;25344;26338.86830565835;26445.94216231329;26500.807463663798;26577.46845063049;42671.3599296017;42819.92950176271;42895.7855210722;42943.35584966165;43070.56929430665;231134.6886531113;240264;257259;257876;257919;31677.683223167474;32093;32386.83806580952;32439.513407298437;32445.837770810995;38989;39916.60083399129;40023.59976400315;40060.79969600133;40380.394294085025;28211.57682634803;28588.999385021423;28823.42353152883;29135.271618209714;29386.265343365732;26572.601410978463;28088.719112809427;28116;28280.29299267535;29345.7065429343;33148;33951.320973580885;33953.66046339505;34025.319493609495;34070.65929340675;28684.85657571699;29065.854670727356;29361.85319073391;29389.412211756073;29468.705312946597;21018;21929.890350548147;22556;22969;23055.19306824253 01:53:10 INFO - PROCESS | 1652 | |2;jquery.html;379.8509508736073;389.4994931513006;406.240330581812;411.98970025749355;419.36884988092925;13221.669458263535;14531.854681453195;17749.91125044376;21401.46496337592;21863;22160;22480.10079596818;22833.54332913341;22914.65628015577;23045.884770576125;6874.278200788895;6902.5858448493;7022.801628330976;7041;7132.108486439195;6896.689648965808;7025.26234745352;7033.788986330418;7035.2612975637585;7191;7570.545767253955;7648.617569121521;7663.923360766376;7664.731734389297;7681.9231807682045;7058.894116588255;7355.521891077116;7444.8883266751045;7457.067866516685;7639.350655194316;6397.296297407282;7096.6451677416435;7233.204347521769;7265.346118849329;7500.8124796879965;25720;25979;26518.60222096651;26591.46817063648;26912.865435672695;3299.9275235548544;3315.270640459095;3387.170143314879;3462.186386199241;3497.7201823854284;14942.925285373612;16539.66920661592;16690.83309166905;16709.394661749207;17128.314867405414;2043.3052762060822;2085.270155445597;2085.436932028344;2091.4875855415366;2095.350441363182;164815.93512356817;167056.16471917683;170699;172084.4187337191;176805;15362.539123826304;19925.900370498344;20079.393648508085;20169;20190.769476883626;31650.841745791124;31826.681733182835;31846.363072738877;31853.52219716706;31887.36225275528;9871.30900836949;10041.246906482043;10251.231157663206;10288.794224115476;10487.528061237263;8565.914340856636;8588.312934965188;8749.912500875038;8751.56242187888;8754.124587541195;8968.820623587491;9334.253259739204;9428.62285508572;9451.810963780823;9482.38364506305;3496.9125771855397;3792.3742582474133;3972.960270397317;3974.6224108709625;3976.1252524444576;1008.2891561449161;1012.7063151685928;1015.5125539740966;1017.3234798858824;1053.6364954090827;20114.39656810298;20751.792482074987;21106;21141.471463213544;21510.677339839916;997.6258902911409;1007.2244371833647;1030.907218350352;1045.29442626227;1048.0724558765548;10332.915043920431;10795.352278863293;10837.615724540588;10891.83662245067;10918.07196388316;737145;739800;742393;748855;773022;148942;150448;161203;169022;170765;1270.879266469684;1273.9044571657162;1284.6017734502334;1307.7646023715631;1315.5592876386327;17934.5516362092;18069.457916262534;18113.184906679493;18279.90320580716;18283.177257023734;64610;64746.70506589748;65312.34687653253;66731;67214;91352;92151.07848921425;93421;94940;95818;10791.298565593212;11548.133889958455;11557.093079641765;11693.941530292293;11910.285382877055;12795.93602031984;12886.226826390071;12984.805227921399;13233.404496797475;13247.536336228186;11212;11335.886641133484;11598.88401115978;11605.013573846318;11664.358460284426;8956.373053886426;10395.63615273492;10503.369797812156;10691.358518488916;10718.464076796285;9661.178799801814;11617.941910290732;11633.011194048262;11720.94139529331;11830.112741544246;11508.94245528767;11546.538138474703;11775.587854425054;11793.41032948332;11898.286102833858;10481.580736770444;10694.839577406501;10937.726556835825;11274.098072154373;11319.773604528027 01:53:10 INFO - PROCESS | 1652 | |3;mootools.html;1495.2972103111533;1518.9544313670588;1544.7065057639047;1554.4326320892872;1565.639902822351;10617.41604211768;11101.777964440706;11115.277506962042;11125.332480051202;11126.833097503533;10711.839322410151;10815.67552973412;11076.723081922955;11145.72135696606;11148.832767508495;2281.121768119278;2281.7376001759762;2315.131825565413;2315.780000899912;2316.420894776306;2215.911363545464;2290.347251033456;2321.616933206019;2322.6167682332393;2328.511012687338;2246.4945387287894;2257.9096836126537;2282.7260728712577;2286.6112760830615;2304.562133194696;1938.4863011302016;2083.2187929526426;2106.220698341608;2282.315305408371;2292.908283668651;2281.7261928568514;2291.919782807594;2300.7124109486313;2309.3302942146756;2315.6063469210276;10943;11053.502592383362;11077.944610276978;11127.610533631365;11131.610393636183;5333.7599808008345;5490.725463726798;5493.23094766733;5499.972500137474;5501.9174712379345;3213.260949981494;3218.372417378612;3404.931901361984;3437;3455.378031954247;1220.7192345760525;1240.2744394529211;1808.5569035586234;1848.439910723644;1925.7111433284979;14968.47610333644;15575.610609734847;15718.52844414658;15721.292541835644;15802.920985395114;3745.4756334113245;4225.091605304874;4292.656587472995;4421.447319085114;4440.844570440019;11537.942310288563;11540.653780386601;11545.595904143483;11558.017568506602;11560.710982225344;5395.622306438511;5407.351117865803;5408.13469844823;5409.864753381197;5437.075697131497;4060.7360521566593;4966.6771659842;4970.453250142481;5017.7491125443585;5019.52314530119;3532.4877892705413;3576.8926932192076;3608.0619039050034;3628.4216365880925;3632.727545434103;899.347972719786;900.8153328567628;917.8072604752944;922.2483675804332;923.6490133749267;914.4284821986258;929.1312622697799;944.3342443577261;945.1965829045256;987.59015008772;5771.711414429262;6575.671216439255;6723.831904202434;6792.184937807396;6984.255947367871;946.7727745341206;1008.047395211518;1009.2934945538153;1012.6253286284121;1041.2034793383068;3276.6559511251453;4209.957900421018;4214.704970652087;4312.180685669713;4390.670699697472;13210.933945330213;13784.172949623055;13825.861741382458;13845.100068495516;13875.653108672162;13719.725605488034;13749.656258593415;13752.587422377144;13756.312184390741;13765.174089554459;7423.888641670379;8696.652133914698;8733.956330218309;9995.050470205319;10021.699349019395;6748.392644662004;6820.215675197416;6844.178698556006;6850.246472888078;7001.299870013162;11942.283462992249;12079.939600301943;12364.752704945671;12368.134230603771;12373.93813030929;12324;12461;12628.494860205488;12641.557545485864;12750;8289.33685305162;8581.613827377907;8583;8584.613692383733;8598.097199793932;10066.748331291776;10138.239632027775;10175.287729859136;10237.283390162915;10286.948565257126;8606.65573377083;8655.394122411371;8750.256228220418;8790.692325768567;8948.552572371485;7729.690812367668;8161.224683655043;8469.491830490191;8534.829303413773;8607.913920860712;7759.534427934342;7830.8825367620675;7833.960830195812;7924.722634707758;7928.722494712657;5854.882902342015;5892.145638882424;5902.4097590240635;6063.302720187058;6078.3921607838865;5912.526997840077;5923.022701254417;6068.271807383142;6096.420840020191;6250.968745156245 01:53:10 INFO - PROCESS | 1652 | |4;prototype.html;362.1760494874161;372.7614326830829;378.234075996108;378.4663624289752;382.75886191699226;4392.3631073494325;4459.620932220757;4594.782382668595;4845.055214233226;4973.378327709036;4314.611684948354;4424.911501769962;4940.258961155829;4981.476944920776;5029.5221953914315;3880.7014038067628;4234.259004674185;4252.255855225323;4268.338407546819;4269.996550810557;3646.5988741238443;3795.335816232162;3852.7110466715108;3860.0156959975166;4304.289792184286;3866.845326186949;4156.376543518466;4181.247375472413;4187.769672668002;4220.219259436999;4049.493813273341;4143.3370660694445;4151.107511884959;4188.706790524665;4211.525965911922;3273.7872038317673;3323.850426730803;3839.308924393608;3860.5946375630715;3907.7299877539913;4429.468463784334;4474.664400170001;4490;4909.656324057318;4995.975020124913;4822.493638168012;4830.855074347775;4845.248986407112;4864.854054378339;4876.926846097312;4517.932231016537;4672.696274742131;4723.740194289313;5299.125644268691;5445.453046706005;3681.5398075240596;3978.5241603196405;4283.91432171358;4321.178975994551;4328.718633288826;4979.427365852937;5135.201315283105;5400.459954004607;5568.777248909998;5728.971355143198;3669.449582562637;3722.3485889969147;4270.765107919032;4272.508661503967;4316.633086187647;3749.7375183736876;3840.807959602009;4084.591540845892;4423.549631287601;4479.641628669699;4216.810243539048;4538.818447262139;4773.594244489188;4866.544753225179;4883.877903052381;4820;4992.176290912032;5085.644004919694;5131.947950670178;5316.388615309212;1398.3916996106523;1516.3782849031843;1517.6357674158123;1540.4377402248099;1546.8089571030469;1020.4387586827364;1043.95824167034;1083.3229231730168;1153.7923173828792;1178.6581891251442;4698.788554515055;4751.859553707085;4881.585065269421;4891.483640071589;4906.215005599087;4844.273358996108;5643.065605884999;5671.574631902624;5676.801311954145;5753.309602847685;4428.645708343325;4681;4860.586850117709;5001.974990125026;5092.541671249605;2504.3864253258066;2581.4578938422765;2886;2910.9811565952173;2928.7949843511174;5056.280864506086;5702.210143479497;5824.941750582525;5848.356680765198;5907.645541267538;5122.747055619634;5195.610329225247;5275.366955965309;5546.306711661043;5627.296587926509;5468.343798744176;5656.971715141398;5802.230319752516;5848.590598658053;5897.941020589825;5134.589232861287;5223.720188553829;5270.578353731769;5582.581306402118;5635.690037048002;4509.526499717614;5066.36670416198;5072.112380333353;5199.792008319625;5471.85091130867;4910.656254062182;5354.759035843318;5386.007177344408;5538.723063846872;5781.82654520365;3880.3985382265796;3936.665383442325;3975.443437918636;4148.688848336326;4480.513315110014;5035.773390197377;5282.788688452419;5561.247182644617;5587.0502014656795;5651.463111004448;4618.51505591908;4762.809297675582;4981.950180498148;5401.918971215514;5425.403205647297;4543.7955292011275;5005.549500544968;5039.571636410946;5094.605535398357;5365.087935051129;4631.9536804631525;5114.4118426379955;5145.45972672864;5328.120860058047;5341.305630268041;4788.4253889533475;4910.386201724785;5134.535422997057;5472.589555783253;5521.889562208814;5104.702883702274;5326.777299733605;5330.173823057432;5340.317799893021;5348.78604855817 01:53:10 INFO - PROCESS | 1652 | |5;yui.html;719.5682590445737;739.9223081576434;754.0310700749538;807.6728924785463;829.2205326992627;13445.991550633717;15221.467248646299;16051.678966420679;16051.83948160521;16101.83898161018;14616.926915365408;14623.92688036561;14630.122192668447;14707.264636768146;14752.778708319383;914.7987442762582;929.8605209218604;951.46242373059;951.6288647427474;965.8696076029752;952.9761755956122;956.6842941829179;956.7177682583655;957.5020989085672;958.4249450329781;1006.2151521812998;1008.8284991551417;1009.0262896305064;1009.6466236817129;1013.8377859542475;840.5839109640737;998.7003898830321;1002.4386343647581;1002.5989604158395;1005.8893521712606;741.9517731347445;742.6806473216515;743.1824992508251;743.2864450127884;745.1207575061426;15781.684366312724;16105;16137.677246455005;16151.35394584227;16389.098599577017;13893.861061389358;13910.930445347709;14011.579652610439;14082.155070695791;14133.78799318011;11579.942100289529;11751.059915206763;11846.348450835201;11891.940540297242;12437.440315185748;3504.088936876405;3627.6735093841417;3759;3804.029972357059;3846.826892789803;117907.64184716258;120923;122009;122774;123376;10717.035466808025;10756.784864302826;10974.231803773659;11232.66302010941;11238.325700457835;22473.88763056174;22920.968556415002;22977;23013.53972920566;23078.96144673494;7665;7683.346915512132;7700.653470593837;7705.345045671181;7843;4255.914881702349;4334.869953901388;4389.275769497997;4482;4562.612177964844;3428.3314753623304;3517.718582513393;3519.8240087995496;3521.683048525645;3523.224890524081;612.7059011674421;617.478230894893;618.783425800966;619.0343064818807;626.1828314050117;658.5456035335603;672.3491441472752;681.5365551425078;692.5394612582576;714.125196634119;9957.402555846673;10105.897434618488;10149.64476243328;10365.378077315385;10376.948115259376;687.1479365586637;696.944244460442;711.5695004522189;712.6045044999971;713.5148099292531;4467.754273514988;4978.751062446863;4986.750662466862;4994.300797888299;4996.325496058058;102614.05327803394;105914;107536.92463075269;108559;111235.77528449385;73179;77358;90535.54732226297;94468;96228.51885740526;61736.69131654223;71723;77607;79587;80183.59908200422;10509.264351495627;10603.681889543326;10762.14094434802;10810.35137891729;10843.1867609928;44842.65472035844;46630.53369466397;47610.28584571165;48659;52064;63463.365366345744;63794;65064.67467662632;65340.67329663321;66250.66874665595;12129.454174562343;12187.025037997117;12256.816147757612;12280;12953.481860725466;13878.722425551634;14147.858521415066;14280.928595356956;14282.714345713235;14849.703005940037;12876.613701588967;12956.546520872102;13406.798898016732;13560.118592291865;13663.38514766818;12554.811677825022;13189;13222.140560863512;13879.236641984788;14266;12878.742425151631;12912.548060818202;13121.990170162342;13285.621986978933;13893.791593125909;7751.728689495841;9693.515324233618;10095.192384609361;10264.948675256876;10421.56088490781;11001.119910407308;11052.613158539412;11144.944275278573;11212.607558735404;11264.605738799102 01:53:10 INFO - PROCESS | 1652 | __end_tp_report 01:53:10 INFO - PROCESS | 1652 | __start_cc_report 01:53:10 INFO - PROCESS | 1652 | _x_x_mozilla_cycle_collect,1581 01:53:10 INFO - PROCESS | 1652 | __end_cc_report 01:53:10 INFO - PROCESS | 1652 | __startTimestamp1492764790661__endTimestamp 01:53:10 INFO - PROCESS | 1652 | 01:53:10 INFO - PROCESS | 1652 | ------- Summary: start ------- 01:53:10 INFO - PROCESS | 1652 | Number of tests: 6 01:53:10 INFO - PROCESS | 1652 | 01:53:10 INFO - PROCESS | 1652 | [#0] dojo.html Cycles:165 Average:5875.93 Median:6145.97 stddev:689.17 (11.2%) stddev-sans-first:642.20 01:53:10 INFO - PROCESS | 1652 | Values: 2619.9 3147.7 3349.7 3408.6 3430.9 5012.2 5667.3 5899.5 5905.9 5940.1 4974.5 5895.1 5947.9 5960.1 5979.9 5331.9 5344.6 5393.7 5404.1 5450.4 5241.2 5268.7 5440.8 5455.6 5501.4 5300.7 5337.0 5368.4 5373.1 5379.1 5364.8 5371.0 5376.4 5377.2 5379.9 5243.2 5288.4 5308.2 5310.1 5313.1 5978.5 5996.7 6000.8 6002.6 6006.5 6146.0 6157.8 6170.5 6172.9 6233.3 6444.1 6447.9 6449.7 6451.7 6465.5 6053.5 6101.6 6118.7 6121.0 6127.7 6256.5 6270.6 6514.6 6531.2 6541.4 6192.5 6213.4 6224.1 6239.8 6278.3 6337.1 6338.6 6347.8 6351.8 6352.3 6255.0 6265.0 6265.0 6273.0 6279.5 6210.1 6213.0 6254.9 6281.2 6289.0 5103.8 5111.8 5118.0 5121.7 5145.3 4548.3 4550.2 4553.5 4560.0 4591.2 5722.3 5791.4 5827.7 5908.4 5990.6 6347.7 6373.7 6400.9 6401.9 6408.8 5979.0 5982.3 5983.5 5992.4 6053.7 5458.3 5462.3 5622.6 5623.1 5625.6 6415.3 6493.4 6548.0 6594.5 6612.4 6404.5 6409.5 6418.5 6424.4 6433.7 6459.8 6593.7 6604.0 6615.0 6616.4 6211.0 6228.3 6248.8 6260.4 6261.7 6371.6 6381.1 6400.6 6402.9 6406.6 6107.5 6217.6 6240.5 6324.3 6340.7 6107.4 6172.9 6250.6 6256.2 6335.9 6213.2 6218.4 6234.3 6243.2 6245.6 6157.9 6192.7 6307.3 6323.2 6330.3 5083.3 5089.3 5997.5 6027.7 6050.8 01:53:10 INFO - PROCESS | 1652 | 01:53:10 INFO - PROCESS | 1652 | [#1] ext.html Cycles:180 Average:45514.49 Median:298196.50 stddev:80357.93 (26.9%) stddev-sans-first:80513.09 01:53:10 INFO - PROCESS | 1652 | Values: 755.8 784.2 806.8 875.7 885.1 27627.7 27922.7 28986.7 39489.2 39668.6 40567.0 40810.8 40816.2 40832.0 40842.4 9053.4 9196.1 9198.4 9213.4 9222.9 7318.5 7492.7 9154.0 10916.9 11177.2 11073.5 11122.6 11123.1 11126.8 11130.3 11032.0 11055.4 11075.3 11095.9 11112.7 7313.0 7458.4 10689.2 10830.1 10884.6 39695.4 39976.8 40019.0 40112.0 40361.0 3852.8 3861.0 3878.4 3892.2 3919.5 26916.9 27639.9 28322.9 28488.3 29148.9 2881.9 2904.7 3101.5 3186.4 3205.0 359682.0 359887.0 360303.2 360924.0 361214.0 50727.5 51876.5 51999.7 52010.0 52130.0 53891.2 55018.4 55089.2 55119.7 55146.7 11949.9 14828.7 14840.2 14875.6 14875.6 12808.8 12810.9 12818.3 12821.5 12848.1 10346.2 10613.3 10709.1 12072.0 12250.0 5135.8 5326.8 5784.3 5789.7 5803.9 890.6 939.2 940.4 972.4 973.1 27995.4 28291.0 29053.6 29076.4 29385.7 790.8 1052.3 1094.3 1115.0 1135.2 13462.5 13874.3 14160.9 14174.1 14189.2 296956.0 297678.0 298715.0 298720.0 298974.0 51432.7 51485.0 51585.5 51586.0 51605.7 1389.4 1447.7 1452.6 1455.6 1458.4 25344.0 26338.9 26445.9 26500.8 26577.5 42671.4 42819.9 42895.8 42943.4 43070.6 231134.7 240264.0 257259.0 257876.0 257919.0 31677.7 32093.0 32386.8 32439.5 32445.8 38989.0 39916.6 40023.6 40060.8 40380.4 28211.6 28589.0 28823.4 29135.3 29386.3 26572.6 28088.7 28116.0 28280.3 29345.7 33148.0 33951.3 33953.7 34025.3 34070.7 28684.9 29065.9 29361.9 29389.4 29468.7 21018.0 21929.9 22556.0 22969.0 23055.2 01:53:10 INFO - PROCESS | 1652 | 01:53:10 INFO - PROCESS | 1652 | [#2] jquery.html Cycles:180 Average:43873.92 Median:11600.68 stddev:125526.11 (1082.1%) stddev-sans-first:125835.76 01:53:10 INFO - PROCESS | 1652 | Values: 379.9 389.5 406.2 412.0 419.4 13221.7 14531.9 17749.9 21401.5 21863.0 22160.0 22480.1 22833.5 22914.7 23045.9 6874.3 6902.6 7022.8 7041.0 7132.1 6896.7 7025.3 7033.8 7035.3 7191.0 7570.5 7648.6 7663.9 7664.7 7681.9 7058.9 7355.5 7444.9 7457.1 7639.4 6397.3 7096.6 7233.2 7265.3 7500.8 25720.0 25979.0 26518.6 26591.5 26912.9 3299.9 3315.3 3387.2 3462.2 3497.7 14942.9 16539.7 16690.8 16709.4 17128.3 2043.3 2085.3 2085.4 2091.5 2095.4 164815.9 167056.2 170699.0 172084.4 176805.0 15362.5 19925.9 20079.4 20169.0 20190.8 31650.8 31826.7 31846.4 31853.5 31887.4 9871.3 10041.2 10251.2 10288.8 10487.5 8565.9 8588.3 8749.9 8751.6 8754.1 8968.8 9334.3 9428.6 9451.8 9482.4 3496.9 3792.4 3973.0 3974.6 3976.1 1008.3 1012.7 1015.5 1017.3 1053.6 20114.4 20751.8 21106.0 21141.5 21510.7 997.6 1007.2 1030.9 1045.3 1048.1 10332.9 10795.4 10837.6 10891.8 10918.1 737145.0 739800.0 742393.0 748855.0 773022.0 148942.0 150448.0 161203.0 169022.0 170765.0 1270.9 1273.9 1284.6 1307.8 1315.6 17934.6 18069.5 18113.2 18279.9 18283.2 64610.0 64746.7 65312.3 66731.0 67214.0 91352.0 92151.1 93421.0 94940.0 95818.0 10791.3 11548.1 11557.1 11693.9 11910.3 12795.9 12886.2 12984.8 13233.4 13247.5 11212.0 11335.9 11598.9 11605.0 11664.4 8956.4 10395.6 10503.4 10691.4 10718.5 9661.2 11617.9 11633.0 11720.9 11830.1 11508.9 11546.5 11775.6 11793.4 11898.3 10481.6 10694.8 10937.7 11274.1 11319.8 01:53:10 INFO - PROCESS | 1652 | 01:53:10 INFO - PROCESS | 1652 | [#3] mootools.html Cycles:180 Average:6630.07 Median:3215.82 stddev:4241.61 (131.9%) stddev-sans-first:4235.96 01:53:10 INFO - PROCESS | 1652 | Values: 1495.3 1519.0 1544.7 1554.4 1565.6 10617.4 11101.8 11115.3 11125.3 11126.8 10711.8 10815.7 11076.7 11145.7 11148.8 2281.1 2281.7 2315.1 2315.8 2316.4 2215.9 2290.3 2321.6 2322.6 2328.5 2246.5 2257.9 2282.7 2286.6 2304.6 1938.5 2083.2 2106.2 2282.3 2292.9 2281.7 2291.9 2300.7 2309.3 2315.6 10943.0 11053.5 11077.9 11127.6 11131.6 5333.8 5490.7 5493.2 5500.0 5501.9 3213.3 3218.4 3404.9 3437.0 3455.4 1220.7 1240.3 1808.6 1848.4 1925.7 14968.5 15575.6 15718.5 15721.3 15802.9 3745.5 4225.1 4292.7 4421.4 4440.8 11537.9 11540.7 11545.6 11558.0 11560.7 5395.6 5407.4 5408.1 5409.9 5437.1 4060.7 4966.7 4970.5 5017.7 5019.5 3532.5 3576.9 3608.1 3628.4 3632.7 899.3 900.8 917.8 922.2 923.6 914.4 929.1 944.3 945.2 987.6 5771.7 6575.7 6723.8 6792.2 6984.3 946.8 1008.0 1009.3 1012.6 1041.2 3276.7 4210.0 4214.7 4312.2 4390.7 13210.9 13784.2 13825.9 13845.1 13875.7 13719.7 13749.7 13752.6 13756.3 13765.2 7423.9 8696.7 8734.0 9995.1 10021.7 6748.4 6820.2 6844.2 6850.2 7001.3 11942.3 12079.9 12364.8 12368.1 12373.9 12324.0 12461.0 12628.5 12641.6 12750.0 8289.3 8581.6 8583.0 8584.6 8598.1 10066.7 10138.2 10175.3 10237.3 10286.9 8606.7 8655.4 8750.3 8790.7 8948.6 7729.7 8161.2 8469.5 8534.8 8607.9 7759.5 7830.9 7834.0 7924.7 7928.7 5854.9 5892.1 5902.4 6063.3 6078.4 5912.5 5923.0 6068.3 6096.4 6251.0 01:53:10 INFO - PROCESS | 1652 | 01:53:10 INFO - PROCESS | 1652 | [#4] prototype.html Cycles:180 Average:4438.62 Median:4826.67 stddev:1246.63 (25.8%) stddev-sans-first:1212.00 01:53:10 INFO - PROCESS | 1652 | Values: 362.2 372.8 378.2 378.5 382.8 4392.4 4459.6 4594.8 4845.1 4973.4 4314.6 4424.9 4940.3 4981.5 5029.5 3880.7 4234.3 4252.3 4268.3 4270.0 3646.6 3795.3 3852.7 3860.0 4304.3 3866.8 4156.4 4181.2 4187.8 4220.2 4049.5 4143.3 4151.1 4188.7 4211.5 3273.8 3323.9 3839.3 3860.6 3907.7 4429.5 4474.7 4490.0 4909.7 4996.0 4822.5 4830.9 4845.2 4864.9 4876.9 4517.9 4672.7 4723.7 5299.1 5445.5 3681.5 3978.5 4283.9 4321.2 4328.7 4979.4 5135.2 5400.5 5568.8 5729.0 3669.4 3722.3 4270.8 4272.5 4316.6 3749.7 3840.8 4084.6 4423.5 4479.6 4216.8 4538.8 4773.6 4866.5 4883.9 4820.0 4992.2 5085.6 5131.9 5316.4 1398.4 1516.4 1517.6 1540.4 1546.8 1020.4 1044.0 1083.3 1153.8 1178.7 4698.8 4751.9 4881.6 4891.5 4906.2 4844.3 5643.1 5671.6 5676.8 5753.3 4428.6 4681.0 4860.6 5002.0 5092.5 2504.4 2581.5 2886.0 2911.0 2928.8 5056.3 5702.2 5824.9 5848.4 5907.6 5122.7 5195.6 5275.4 5546.3 5627.3 5468.3 5657.0 5802.2 5848.6 5897.9 5134.6 5223.7 5270.6 5582.6 5635.7 4509.5 5066.4 5072.1 5199.8 5471.9 4910.7 5354.8 5386.0 5538.7 5781.8 3880.4 3936.7 3975.4 4148.7 4480.5 5035.8 5282.8 5561.2 5587.1 5651.5 4618.5 4762.8 4982.0 5401.9 5425.4 4543.8 5005.5 5039.6 5094.6 5365.1 4632.0 5114.4 5145.5 5328.1 5341.3 4788.4 4910.4 5134.5 5472.6 5521.9 5104.7 5326.8 5330.2 5340.3 5348.8 01:53:10 INFO - PROCESS | 1652 | 01:53:10 INFO - PROCESS | 1652 | [#5] yui.html Cycles:180 Average:20768.12 Median:22697.43 stddev:30571.91 (134.7%) stddev-sans-first:30620.61 01:53:10 INFO - PROCESS | 1652 | Values: 719.6 739.9 754.0 807.7 829.2 13446.0 15221.5 16051.7 16051.8 16101.8 14616.9 14623.9 14630.1 14707.3 14752.8 914.8 929.9 951.5 951.6 965.9 953.0 956.7 956.7 957.5 958.4 1006.2 1008.8 1009.0 1009.6 1013.8 840.6 998.7 1002.4 1002.6 1005.9 742.0 742.7 743.2 743.3 745.1 15781.7 16105.0 16137.7 16151.4 16389.1 13893.9 13910.9 14011.6 14082.2 14133.8 11579.9 11751.1 11846.3 11891.9 12437.4 3504.1 3627.7 3759.0 3804.0 3846.8 117907.6 120923.0 122009.0 122774.0 123376.0 10717.0 10756.8 10974.2 11232.7 11238.3 22473.9 22921.0 22977.0 23013.5 23079.0 7665.0 7683.3 7700.7 7705.3 7843.0 4255.9 4334.9 4389.3 4482.0 4562.6 3428.3 3517.7 3519.8 3521.7 3523.2 612.7 617.5 618.8 619.0 626.2 658.5 672.3 681.5 692.5 714.1 9957.4 10105.9 10149.6 10365.4 10376.9 687.1 696.9 711.6 712.6 713.5 4467.8 4978.8 4986.8 4994.3 4996.3 102614.1 105914.0 107536.9 108559.0 111235.8 73179.0 77358.0 90535.5 94468.0 96228.5 61736.7 71723.0 77607.0 79587.0 80183.6 10509.3 10603.7 10762.1 10810.4 10843.2 44842.7 46630.5 47610.3 48659.0 52064.0 63463.4 63794.0 65064.7 65340.7 66250.7 12129.5 12187.0 12256.8 12280.0 12953.5 13878.7 14147.9 14280.9 14282.7 14849.7 12876.6 12956.5 13406.8 13560.1 13663.4 12554.8 13189.0 13222.1 13879.2 14266.0 12878.7 12912.5 13122.0 13285.6 13893.8 7751.7 9693.5 10095.2 10264.9 10421.6 11001.1 11052.6 11144.9 11212.6 11264.6 01:53:10 INFO - PROCESS | 1652 | -------- Summary: end -------- 01:53:10 INFO - PROCESS | 1652 | 01:53:11 INFO - TEST-INFO | 1652: exit 0 01:53:11 INFO - TEST-OK | dromaeo_css | took 1119807ms 01:53:11 INFO - TEST-START | kraken 01:53:11 INFO - operating with platform_type : mac_ 01:53:11 INFO - Initialising browser for kraken test... 01:53:11 INFO - TEST-INFO | started process 1660 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/k4/xd58rgkx0dnbrdjb1pv1qrj800000w/T/tmpjq4N0P/profile http://localhost:49246/getInfo.html) 01:53:13 INFO - PROCESS | 1660 | __metrics Screen width/height:1600/1200 01:53:13 INFO - PROCESS | 1660 | colorDepth:24 01:53:13 INFO - PROCESS | 1660 | Browser inner width/height: 1024/689 01:53:13 INFO - PROCESS | 1660 | __metrics 01:53:13 INFO - PROCESS | 1660 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 01:53:13 INFO - PROCESS | 1660 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 01:53:14 INFO - TEST-INFO | 1660: exit 0 01:53:14 INFO - Browser initialized. 01:53:14 INFO - Running cycle 1/1 for kraken test... 01:53:14 INFO - TEST-INFO | started process 1662 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/k4/xd58rgkx0dnbrdjb1pv1qrj800000w/T/tmpjq4N0P/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 01:53:26 INFO - PROCESS | 1662 | RSS: Main: 214896640 01:53:26 INFO - PROCESS | 1662 | 01:53:29 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49246/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49246/tests/kraken/driver.html?testName=audio-beat-detection) 01:53:30 INFO - PROCESS | 1662 | RSS: Main: 267636736 01:53:30 INFO - PROCESS | 1662 | 01:53:33 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49246/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49246/tests/kraken/driver.html?testName=audio-dft) 01:53:33 INFO - PROCESS | 1662 | RSS: Main: 279060480 01:53:33 INFO - PROCESS | 1662 | 01:53:36 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49246/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49246/tests/kraken/driver.html?testName=audio-fft) 01:53:37 INFO - PROCESS | 1662 | RSS: Main: 376369152 01:53:37 INFO - PROCESS | 1662 | 01:53:39 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49246/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49246/tests/kraken/driver.html?testName=audio-oscillator) 01:53:40 INFO - PROCESS | 1662 | RSS: Main: 279285760 01:53:40 INFO - PROCESS | 1662 | 01:53:42 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49246/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49246/tests/kraken/driver.html?testName=imaging-gaussian-blur) 01:53:42 INFO - PROCESS | 1662 | RSS: Main: 281219072 01:53:42 INFO - PROCESS | 1662 | 01:53:45 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49246/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49246/tests/kraken/driver.html?testName=imaging-darkroom) 01:53:46 INFO - PROCESS | 1662 | RSS: Main: 321028096 01:53:46 INFO - PROCESS | 1662 | 01:53:49 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49246/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49246/tests/kraken/driver.html?testName=imaging-desaturate) 01:53:50 INFO - PROCESS | 1662 | RSS: Main: 320946176 01:53:50 INFO - PROCESS | 1662 | 01:53:53 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49246/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49246/tests/kraken/driver.html?testName=json-parse-financial) 01:53:53 INFO - PROCESS | 1662 | RSS: Main: 318578688 01:53:53 INFO - PROCESS | 1662 | 01:53:55 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49246/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49246/tests/kraken/driver.html?testName=json-stringify-tinderbox) 01:53:56 INFO - PROCESS | 1662 | RSS: Main: 296243200 01:53:56 INFO - PROCESS | 1662 | 01:53:58 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49246/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49246/tests/kraken/driver.html?testName=stanford-crypto-aes) 01:53:58 INFO - PROCESS | 1662 | RSS: Main: 298205184 01:53:58 INFO - PROCESS | 1662 | 01:54:01 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49246/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49246/tests/kraken/driver.html?testName=stanford-crypto-ccm) 01:54:01 INFO - PROCESS | 1662 | RSS: Main: 305364992 01:54:01 INFO - PROCESS | 1662 | 01:54:04 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49246/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49246/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 01:54:05 INFO - PROCESS | 1662 | RSS: Main: 319320064 01:54:05 INFO - PROCESS | 1662 | 01:54:08 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49246/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49246/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 01:54:09 INFO - PROCESS | 1662 | RSS: Main: 313417728 01:54:09 INFO - PROCESS | 1662 | 01:54:10 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49246/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49246/tests/kraken/driver.html?testName=ai-astar) 01:54:11 INFO - PROCESS | 1662 | RSS: Main: 357384192 01:54:11 INFO - PROCESS | 1662 | 01:54:11 INFO - PROCESS | 1662 | __start_tp_report 01:54:11 INFO - PROCESS | 1662 | _x_x_mozilla_page_load 01:54:11 INFO - PROCESS | 1662 | _x_x_mozilla_page_load_details 01:54:11 INFO - PROCESS | 1662 | |i|pagename|runs| 01:54:11 INFO - PROCESS | 1662 | |0;ai-astar;117;116;116;115;115;116;115;116;116;116 01:54:11 INFO - PROCESS | 1662 | |1;audio-beat-detection;172;142;110;146;112;110;109;110;132;110 01:54:11 INFO - PROCESS | 1662 | |2;audio-dft;185;187;184;184;185;181;184;181;183;182 01:54:11 INFO - PROCESS | 1662 | |3;audio-fft;83;59;61;92;65;61;61;75;101;70 01:54:11 INFO - PROCESS | 1662 | |4;audio-oscillator;89;71;89;68;69;67;83;70;70;83 01:54:11 INFO - PROCESS | 1662 | |5;imaging-gaussian-blur;89;87;88;88;88;88;89;88;89;88 01:54:11 INFO - PROCESS | 1662 | |6;imaging-darkroom;95;128;149;95;95;95;95;94;107;151 01:54:11 INFO - PROCESS | 1662 | |7;imaging-desaturate;126;129;83;85;83;84;86;86;84;83 01:54:11 INFO - PROCESS | 1662 | |8;json-parse-financial;59;59;66;81;99;97;100;101;63;66 01:54:11 INFO - PROCESS | 1662 | |9;json-stringify-tinderbox;59;63;56;65;61;59;59;58;57;58 01:54:11 INFO - PROCESS | 1662 | |10;stanford-crypto-aes;86;84;83;85;83;84;83;83;86;90 01:54:11 INFO - PROCESS | 1662 | |11;stanford-crypto-ccm;153;143;127;141;139;145;144;142;180;150 01:54:11 INFO - PROCESS | 1662 | |12;stanford-crypto-pbkdf2;169;170;163;168;168;218;204;167;167;165 01:54:11 INFO - PROCESS | 1662 | |13;stanford-crypto-sha256-iterative;66;67;65;70;76;79;80;79;73;65 01:54:11 INFO - PROCESS | 1662 | __end_tp_report 01:54:11 INFO - PROCESS | 1662 | __start_cc_report 01:54:11 INFO - PROCESS | 1662 | _x_x_mozilla_cycle_collect,2163 01:54:11 INFO - PROCESS | 1662 | __end_cc_report 01:54:11 INFO - PROCESS | 1662 | __startTimestamp1492764851219__endTimestamp 01:54:11 INFO - PROCESS | 1662 | 01:54:11 INFO - PROCESS | 1662 | ------- Summary: start ------- 01:54:11 INFO - PROCESS | 1662 | Number of tests: 14 01:54:11 INFO - PROCESS | 1662 | 01:54:11 INFO - PROCESS | 1662 | [#0] ai-astar Cycles:10 Average:115.80 Median:116.00 stddev:0.63 (0.5%) stddev-sans-first:0.50 01:54:11 INFO - PROCESS | 1662 | Values: 117.0 116.0 116.0 115.0 115.0 116.0 115.0 116.0 116.0 116.0 01:54:11 INFO - PROCESS | 1662 | 01:54:11 INFO - PROCESS | 1662 | [#1] audio-beat-detection Cycles:10 Average:125.30 Median:122.00 stddev:21.89 (17.9%) stddev-sans-first:15.37 01:54:11 INFO - PROCESS | 1662 | Values: 172.0 142.0 110.0 146.0 112.0 110.0 109.0 110.0 132.0 110.0 01:54:11 INFO - PROCESS | 1662 | 01:54:11 INFO - PROCESS | 1662 | [#2] audio-dft Cycles:10 Average:183.60 Median:184.00 stddev:1.90 (1.0%) stddev-sans-first:1.94 01:54:11 INFO - PROCESS | 1662 | Values: 185.0 187.0 184.0 184.0 185.0 181.0 184.0 181.0 183.0 182.0 01:54:11 INFO - PROCESS | 1662 | 01:54:11 INFO - PROCESS | 1662 | [#3] audio-fft Cycles:10 Average:72.80 Median:67.50 stddev:14.72 (21.8%) stddev-sans-first:15.14 01:54:11 INFO - PROCESS | 1662 | Values: 83.0 59.0 61.0 92.0 65.0 61.0 61.0 75.0 101.0 70.0 01:54:11 INFO - PROCESS | 1662 | 01:54:11 INFO - PROCESS | 1662 | [#4] audio-oscillator Cycles:10 Average:75.90 Median:77.00 stddev:8.99 (11.7%) stddev-sans-first:8.19 01:54:11 INFO - PROCESS | 1662 | Values: 89.0 71.0 89.0 68.0 69.0 67.0 83.0 70.0 70.0 83.0 01:54:11 INFO - PROCESS | 1662 | 01:54:11 INFO - PROCESS | 1662 | [#5] imaging-gaussian-blur Cycles:10 Average:88.20 Median:88.00 stddev:0.63 (0.7%) stddev-sans-first:0.60 01:54:11 INFO - PROCESS | 1662 | Values: 89.0 87.0 88.0 88.0 88.0 88.0 89.0 88.0 89.0 88.0 01:54:11 INFO - PROCESS | 1662 | 01:54:11 INFO - PROCESS | 1662 | [#6] imaging-darkroom Cycles:10 Average:110.40 Median:95.00 stddev:23.37 (24.6%) stddev-sans-first:24.11 01:54:11 INFO - PROCESS | 1662 | Values: 95.0 128.0 149.0 95.0 95.0 95.0 95.0 94.0 107.0 151.0 01:54:11 INFO - PROCESS | 1662 | 01:54:11 INFO - PROCESS | 1662 | [#7] imaging-desaturate Cycles:10 Average:92.90 Median:84.00 stddev:18.28 (21.8%) stddev-sans-first:14.96 01:54:11 INFO - PROCESS | 1662 | Values: 126.0 129.0 83.0 85.0 83.0 84.0 86.0 86.0 84.0 83.0 01:54:11 INFO - PROCESS | 1662 | 01:54:11 INFO - PROCESS | 1662 | [#8] json-parse-financial Cycles:10 Average:79.10 Median:66.00 stddev:18.40 (27.9%) stddev-sans-first:18.02 01:54:11 INFO - PROCESS | 1662 | Values: 59.0 59.0 66.0 81.0 99.0 97.0 100.0 101.0 63.0 66.0 01:54:11 INFO - PROCESS | 1662 | 01:54:11 INFO - PROCESS | 1662 | [#9] json-stringify-tinderbox Cycles:10 Average:59.50 Median:59.00 stddev:2.76 (4.7%) stddev-sans-first:2.92 01:54:11 INFO - PROCESS | 1662 | Values: 59.0 63.0 56.0 65.0 61.0 59.0 59.0 58.0 57.0 58.0 01:54:11 INFO - PROCESS | 1662 | 01:54:11 INFO - PROCESS | 1662 | [#10] stanford-crypto-aes Cycles:10 Average:84.70 Median:84.50 stddev:2.21 (2.6%) stddev-sans-first:2.30 01:54:11 INFO - PROCESS | 1662 | Values: 86.0 84.0 83.0 85.0 83.0 84.0 83.0 83.0 86.0 90.0 01:54:11 INFO - PROCESS | 1662 | 01:54:11 INFO - PROCESS | 1662 | [#11] stanford-crypto-ccm Cycles:10 Average:146.40 Median:144.50 stddev:13.68 (9.5%) stddev-sans-first:14.30 01:54:11 INFO - PROCESS | 1662 | Values: 153.0 143.0 127.0 141.0 139.0 145.0 144.0 142.0 180.0 150.0 01:54:11 INFO - PROCESS | 1662 | 01:54:11 INFO - PROCESS | 1662 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:175.90 Median:168.50 stddev:18.89 (11.2%) stddev-sans-first:19.87 01:54:11 INFO - PROCESS | 1662 | Values: 169.0 170.0 163.0 168.0 168.0 218.0 204.0 167.0 167.0 165.0 01:54:11 INFO - PROCESS | 1662 | 01:54:11 INFO - PROCESS | 1662 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:72.00 Median:74.50 stddev:6.16 (8.3%) stddev-sans-first:6.14 01:54:11 INFO - PROCESS | 1662 | Values: 66.0 67.0 65.0 70.0 76.0 79.0 80.0 79.0 73.0 65.0 01:54:11 INFO - PROCESS | 1662 | -------- Summary: end -------- 01:54:11 INFO - PROCESS | 1662 | 01:54:12 INFO - TEST-INFO | 1662: exit 0 01:54:12 INFO - TEST-OK | kraken | took 60795ms 01:54:12 INFO - SUITE-END | took 1180s 01:54:12 INFO - Completed test suite (00:19:40) 01:54:12 INFO - javascript benchmark 01:54:12 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": [], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7588.011820656283, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2619.873454414602, 3147.725171305621, 3349.7487688423375, 3408.6420925802777, 3430.9485357719623, 5012.17299145641, 5667.319921609403, 5899.469047785704, 5905.911411328822, 5940.108983652454, 4974.452810190876, 5895.056790913454, 5947.92937271291, 5960.0761881908265, 5979.850503737398, 5331.866703332428, 5344.5724342052545, 5393.7303134843105, 5404.08130617794, 5450.400455949843, 5241.213817927303, 5268.683878967255, 5440.809571664992, 5455.563554915597, 5501.4223506531835, 5300.681959082448, 5337.0, 5368.355797304329, 5373.059714549958, 5379.058664733676, 5364.812231571896, 5371.0, 5376.354837419534, 5377.220303056074, 5379.919301210485, 5243.161094224944, 5288.365396152486, 5308.177232528964, 5310.123829568117, 5313.070212712799, 5978.521718262572, 5996.670183139926, 6000.8499787505225, 6002.6398416094735, 6006.5495087868585, 6145.969270153665, 6157.815265542041, 6170.475509581691, 6172.938270617281, 6233.34549872266, 6444.130042444257, 6447.903281450782, 6449.742010319582, 6451.709673064724, 6465.4827613790785, 6053.51571874249, 6101.60339577926, 6118.694065296718, 6120.959436895739, 6127.693615319261, 6256.468200202943, 6270.561060725797, 6514.60912345256, 6531.150950376421, 6541.443977261986, 6192.535559832941, 6213.440790328892, 6224.097505861625, 6239.812805615838, 6278.30938596754, 6337.112804207414, 6338.556301058974, 6347.809565713036, 6351.809445716636, 6352.2694890087305, 6254.9687251563455, 6264.9976003839165, 6265.028920517327, 6273.0, 6279.529035322369, 6210.130581718563, 6213.0, 6254.906176407358, 6281.183446152062, 6289.0252010938375, 5103.821366252163, 5111.846644600668, 5118.0, 5121.743912804345, 5145.253938178943, 4548.272276435754, 4550.226461501553, 4553.544645535486, 4560.0, 4591.219492686251, 5722.284714410699, 5791.420857914259, 5827.708614569255, 5908.438698323653, 5990.550708696778, 6347.714352854133, 6373.681315934185, 6400.9039864402075, 6401.935980640227, 6408.7756928508215, 5979.01346277868, 5982.252218472691, 5983.461488465972, 5992.40075992406, 6053.697315134314, 5458.317710286215, 5462.3445186577865, 5622.606417550814, 5623.0721930880945, 5625.606207565513, 6415.294317624965, 6493.350664933469, 6548.0, 6594.505412094016, 6612.404883560503, 6404.4876409886165, 6409.4872410206135, 6418.518611104279, 6424.421802037839, 6433.678316084271, 6459.806205813833, 6593.736250549924, 6604.0093985902495, 6615.04081908111, 6616.437602803816, 6210.968945155245, 6228.283747369111, 6248.781292654735, 6260.40526150015, 6261.68691565429, 6371.585846919936, 6381.106645069787, 6400.583962042452, 6402.871942561216, 6406.5515413920575, 6107.450329470369, 6217.595856269328, 6240.500759939285, 6324.272708638381, 6340.714667839866, 6107.3708791890585, 6172.876542469216, 6250.562460627894, 6256.186695729526, 6335.873282534231, 6213.161223234805, 6218.440340369389, 6234.2830574484515, 6243.219597550306, 6245.6252624842655, 6157.907631385622, 6192.721327540181, 6307.274663413767, 6323.241211054703, 6330.303666596578, 5083.313752643346, 5089.262057001788, 5997.520198384032, 6027.6986150693165, 6050.788222412194], "value": 5828.2680280361965, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [755.7883792538088, 784.1961988961315, 806.7620052084636, 875.6672464463503, 885.0972008551282, 27627.723722762792, 27922.743476543554, 28986.710132898665, 39489.21021579567, 39668.60331396685, 40567.0, 40810.795946020306, 40816.18367632646, 40832.0, 40842.38736418949, 9053.36626436148, 9196.080391960784, 9198.356115071947, 9213.447193168398, 9222.907770922306, 7318.524295920774, 7492.662830172655, 9153.954230228874, 10916.945415272912, 11177.161712871526, 11073.50169242386, 11122.55509779608, 11123.110151187926, 11126.777464450706, 11130.276532025433, 11032.0, 11055.391953442557, 11075.335479871233, 11095.944520277428, 11112.666620001413, 7312.96343518279, 7458.440616953751, 10689.198310126772, 10830.133589312914, 10884.564617415293, 39695.41979771305, 39976.80069597917, 40018.999525011815, 40112.0, 40361.0, 3852.757194683173, 3860.961390386088, 3878.398848178537, 3892.1826416452577, 3919.549251836047, 26916.923323067054, 27639.86180069107, 28322.858385708147, 28488.28779280514, 29148.8542557288, 2881.9048761470717, 2904.7385735283715, 3101.4882544380152, 3186.410514054884, 3204.951925721116, 359682.0, 359887.0, 360303.19848400593, 360924.0, 361213.9999999974, 50727.49272507302, 51876.48123518792, 51999.70000750011, 52010.0, 52130.0, 53891.19163212555, 55018.449815502136, 55089.17366239509, 55119.72440137854, 55146.72426637841, 11949.88050119505, 14828.70342593142, 14840.183789891444, 14875.5537333878, 14875.553733388017, 12808.80786788199, 12810.871891281155, 12818.294993775431, 12821.487140514462, 12848.100632955604, 10346.22403319754, 10613.310134841211, 10709.143268538499, 12071.973882219934, 12249.958753506051, 5135.818761684834, 5326.760295786699, 5784.334801497797, 5789.710514474344, 5803.912941305884, 890.6081324217324, 939.220447028978, 940.4169414962766, 972.3825370889537, 973.1047436358567, 27995.440091198063, 28291.0, 29053.564196536645, 29076.418471630448, 29385.706142938725, 790.7904405332591, 1052.2686732720686, 1094.2559059839386, 1114.9944250278697, 1135.1543100390263, 13462.528811491551, 13874.306284685725, 14160.929195354163, 14174.07868488545, 14189.219592922489, 296956.0, 297678.0, 298715.0, 298720.0, 298974.0, 51432.714182145, 51485.0, 51585.484145158065, 51586.0, 51605.741971290656, 1389.4372779024511, 1447.6525633848012, 1452.5932738833144, 1455.577882414088, 1458.4457905995653, 25344.0, 26338.86830565835, 26445.94216231329, 26500.807463663798, 26577.46845063049, 42671.3599296017, 42819.92950176271, 42895.7855210722, 42943.35584966165, 43070.56929430665, 231134.6886531113, 240264.0, 257259.0, 257876.0, 257919.0, 31677.683223167474, 32093.0, 32386.83806580952, 32439.513407298437, 32445.837770810995, 38989.0, 39916.60083399129, 40023.59976400315, 40060.79969600133, 40380.394294085025, 28211.57682634803, 28588.999385021423, 28823.42353152883, 29135.271618209714, 29386.265343365732, 26572.601410978463, 28088.719112809427, 28116.0, 28280.29299267535, 29345.7065429343, 33148.0, 33951.320973580885, 33953.66046339505, 34025.319493609495, 34070.65929340675, 28684.85657571699, 29065.854670727356, 29361.85319073391, 29389.412211756073, 29468.705312946597, 21018.0, 21929.890350548147, 22556.0, 22969.0, 23055.19306824253], "value": 17752.44410229494, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [379.8509508736073, 389.4994931513006, 406.240330581812, 411.98970025749355, 419.36884988092925, 13221.669458263535, 14531.854681453195, 17749.91125044376, 21401.46496337592, 21863.0, 22160.0, 22480.10079596818, 22833.54332913341, 22914.65628015577, 23045.884770576125, 6874.278200788895, 6902.5858448493, 7022.801628330976, 7041.0, 7132.108486439195, 6896.689648965808, 7025.26234745352, 7033.788986330418, 7035.2612975637585, 7191.0, 7570.545767253955, 7648.617569121521, 7663.923360766376, 7664.731734389297, 7681.9231807682045, 7058.894116588255, 7355.521891077116, 7444.8883266751045, 7457.067866516685, 7639.350655194316, 6397.296297407282, 7096.6451677416435, 7233.204347521769, 7265.346118849329, 7500.8124796879965, 25720.0, 25979.0, 26518.60222096651, 26591.46817063648, 26912.865435672695, 3299.9275235548544, 3315.270640459095, 3387.170143314879, 3462.186386199241, 3497.7201823854284, 14942.925285373612, 16539.66920661592, 16690.83309166905, 16709.394661749207, 17128.314867405414, 2043.3052762060822, 2085.270155445597, 2085.436932028344, 2091.4875855415366, 2095.350441363182, 164815.93512356817, 167056.16471917683, 170699.0, 172084.4187337191, 176805.0, 15362.539123826304, 19925.900370498344, 20079.393648508085, 20169.0, 20190.769476883626, 31650.841745791124, 31826.681733182835, 31846.363072738877, 31853.52219716706, 31887.36225275528, 9871.30900836949, 10041.246906482043, 10251.231157663206, 10288.794224115476, 10487.528061237263, 8565.914340856636, 8588.312934965188, 8749.912500875038, 8751.56242187888, 8754.124587541195, 8968.820623587491, 9334.253259739204, 9428.62285508572, 9451.810963780823, 9482.38364506305, 3496.9125771855397, 3792.3742582474133, 3972.960270397317, 3974.6224108709625, 3976.1252524444576, 1008.2891561449161, 1012.7063151685928, 1015.5125539740966, 1017.3234798858824, 1053.6364954090827, 20114.39656810298, 20751.792482074987, 21106.0, 21141.471463213544, 21510.677339839916, 997.6258902911409, 1007.2244371833647, 1030.907218350352, 1045.29442626227, 1048.0724558765548, 10332.915043920431, 10795.352278863293, 10837.615724540588, 10891.83662245067, 10918.07196388316, 737145.0, 739800.0, 742393.0, 748855.0, 773022.0, 148942.0, 150448.0, 161203.0, 169022.0, 170765.0, 1270.879266469684, 1273.9044571657162, 1284.6017734502334, 1307.7646023715631, 1315.5592876386327, 17934.5516362092, 18069.457916262534, 18113.184906679493, 18279.90320580716, 18283.177257023734, 64610.0, 64746.70506589748, 65312.34687653253, 66731.0, 67214.0, 91352.0, 92151.07848921425, 93421.0, 94940.0, 95818.0, 10791.298565593212, 11548.133889958455, 11557.093079641765, 11693.941530292293, 11910.285382877055, 12795.93602031984, 12886.226826390071, 12984.805227921399, 13233.404496797475, 13247.536336228186, 11212.0, 11335.886641133484, 11598.88401115978, 11605.013573846318, 11664.358460284426, 8956.373053886426, 10395.63615273492, 10503.369797812156, 10691.358518488916, 10718.464076796285, 9661.178799801814, 11617.941910290732, 11633.011194048262, 11720.94139529331, 11830.112741544246, 11508.94245528767, 11546.538138474703, 11775.587854425054, 11793.41032948332, 11898.286102833858, 10481.580736770444, 10694.839577406501, 10937.726556835825, 11274.098072154373, 11319.773604528027], "value": 11751.647509175968, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1495.2972103111533, 1518.9544313670588, 1544.7065057639047, 1554.4326320892872, 1565.639902822351, 10617.41604211768, 11101.777964440706, 11115.277506962042, 11125.332480051202, 11126.833097503533, 10711.839322410151, 10815.67552973412, 11076.723081922955, 11145.72135696606, 11148.832767508495, 2281.121768119278, 2281.7376001759762, 2315.131825565413, 2315.780000899912, 2316.420894776306, 2215.911363545464, 2290.347251033456, 2321.616933206019, 2322.6167682332393, 2328.511012687338, 2246.4945387287894, 2257.9096836126537, 2282.7260728712577, 2286.6112760830615, 2304.562133194696, 1938.4863011302016, 2083.2187929526426, 2106.220698341608, 2282.315305408371, 2292.908283668651, 2281.7261928568514, 2291.919782807594, 2300.7124109486313, 2309.3302942146756, 2315.6063469210276, 10943.0, 11053.502592383362, 11077.944610276978, 11127.610533631365, 11131.610393636183, 5333.7599808008345, 5490.725463726798, 5493.23094766733, 5499.972500137474, 5501.9174712379345, 3213.260949981494, 3218.372417378612, 3404.931901361984, 3437.0, 3455.378031954247, 1220.7192345760525, 1240.2744394529211, 1808.5569035586234, 1848.439910723644, 1925.7111433284979, 14968.47610333644, 15575.610609734847, 15718.52844414658, 15721.292541835644, 15802.920985395114, 3745.4756334113245, 4225.091605304874, 4292.656587472995, 4421.447319085114, 4440.844570440019, 11537.942310288563, 11540.653780386601, 11545.595904143483, 11558.017568506602, 11560.710982225344, 5395.622306438511, 5407.351117865803, 5408.13469844823, 5409.864753381197, 5437.075697131497, 4060.7360521566593, 4966.6771659842, 4970.453250142481, 5017.7491125443585, 5019.52314530119, 3532.4877892705413, 3576.8926932192076, 3608.0619039050034, 3628.4216365880925, 3632.727545434103, 899.347972719786, 900.8153328567628, 917.8072604752944, 922.2483675804332, 923.6490133749267, 914.4284821986258, 929.1312622697799, 944.3342443577261, 945.1965829045256, 987.59015008772, 5771.711414429262, 6575.671216439255, 6723.831904202434, 6792.184937807396, 6984.255947367871, 946.7727745341206, 1008.047395211518, 1009.2934945538153, 1012.6253286284121, 1041.2034793383068, 3276.6559511251453, 4209.957900421018, 4214.704970652087, 4312.180685669713, 4390.670699697472, 13210.933945330213, 13784.172949623055, 13825.861741382458, 13845.100068495516, 13875.653108672162, 13719.725605488034, 13749.656258593415, 13752.587422377144, 13756.312184390741, 13765.174089554459, 7423.888641670379, 8696.652133914698, 8733.956330218309, 9995.050470205319, 10021.699349019395, 6748.392644662004, 6820.215675197416, 6844.178698556006, 6850.246472888078, 7001.299870013162, 11942.283462992249, 12079.939600301943, 12364.752704945671, 12368.134230603771, 12373.93813030929, 12324.0, 12461.0, 12628.494860205488, 12641.557545485864, 12750.0, 8289.33685305162, 8581.613827377907, 8583.0, 8584.613692383733, 8598.097199793932, 10066.748331291776, 10138.239632027775, 10175.287729859136, 10237.283390162915, 10286.948565257126, 8606.65573377083, 8655.394122411371, 8750.256228220418, 8790.692325768567, 8948.552572371485, 7729.690812367668, 8161.224683655043, 8469.491830490191, 8534.829303413773, 8607.913920860712, 7759.534427934342, 7830.8825367620675, 7833.960830195812, 7924.722634707758, 7928.722494712657, 5854.882902342015, 5892.145638882424, 5902.4097590240635, 6063.302720187058, 6078.3921607838865, 5912.526997840077, 5923.022701254417, 6068.271807383142, 6096.420840020191, 6250.968745156245], "value": 5035.66141709859, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [362.1760494874161, 372.7614326830829, 378.234075996108, 378.4663624289752, 382.75886191699226, 4392.3631073494325, 4459.620932220757, 4594.782382668595, 4845.055214233226, 4973.378327709036, 4314.611684948354, 4424.911501769962, 4940.258961155829, 4981.476944920776, 5029.5221953914315, 3880.7014038067628, 4234.259004674185, 4252.255855225323, 4268.338407546819, 4269.996550810557, 3646.5988741238443, 3795.335816232162, 3852.7110466715108, 3860.0156959975166, 4304.289792184286, 3866.845326186949, 4156.376543518466, 4181.247375472413, 4187.769672668002, 4220.219259436999, 4049.493813273341, 4143.3370660694445, 4151.107511884959, 4188.706790524665, 4211.525965911922, 3273.7872038317673, 3323.850426730803, 3839.308924393608, 3860.5946375630715, 3907.7299877539913, 4429.468463784334, 4474.664400170001, 4490.0, 4909.656324057318, 4995.975020124913, 4822.493638168012, 4830.855074347775, 4845.248986407112, 4864.854054378339, 4876.926846097312, 4517.932231016537, 4672.696274742131, 4723.740194289313, 5299.125644268691, 5445.453046706005, 3681.5398075240596, 3978.5241603196405, 4283.91432171358, 4321.178975994551, 4328.718633288826, 4979.427365852937, 5135.201315283105, 5400.459954004607, 5568.777248909998, 5728.971355143198, 3669.449582562637, 3722.3485889969147, 4270.765107919032, 4272.508661503967, 4316.633086187647, 3749.7375183736876, 3840.807959602009, 4084.591540845892, 4423.549631287601, 4479.641628669699, 4216.810243539048, 4538.818447262139, 4773.594244489188, 4866.544753225179, 4883.877903052381, 4820.0, 4992.176290912032, 5085.644004919694, 5131.947950670178, 5316.388615309212, 1398.3916996106523, 1516.3782849031843, 1517.6357674158123, 1540.4377402248099, 1546.8089571030469, 1020.4387586827364, 1043.95824167034, 1083.3229231730168, 1153.7923173828792, 1178.6581891251442, 4698.788554515055, 4751.859553707085, 4881.585065269421, 4891.483640071589, 4906.215005599087, 4844.273358996108, 5643.065605884999, 5671.574631902624, 5676.801311954145, 5753.309602847685, 4428.645708343325, 4681.0, 4860.586850117709, 5001.974990125026, 5092.541671249605, 2504.3864253258066, 2581.4578938422765, 2886.0, 2910.9811565952173, 2928.7949843511174, 5056.280864506086, 5702.210143479497, 5824.941750582525, 5848.356680765198, 5907.645541267538, 5122.747055619634, 5195.610329225247, 5275.366955965309, 5546.306711661043, 5627.296587926509, 5468.343798744176, 5656.971715141398, 5802.230319752516, 5848.590598658053, 5897.941020589825, 5134.589232861287, 5223.720188553829, 5270.578353731769, 5582.581306402118, 5635.690037048002, 4509.526499717614, 5066.36670416198, 5072.112380333353, 5199.792008319625, 5471.85091130867, 4910.656254062182, 5354.759035843318, 5386.007177344408, 5538.723063846872, 5781.82654520365, 3880.3985382265796, 3936.665383442325, 3975.443437918636, 4148.688848336326, 4480.513315110014, 5035.773390197377, 5282.788688452419, 5561.247182644617, 5587.0502014656795, 5651.463111004448, 4618.51505591908, 4762.809297675582, 4981.950180498148, 5401.918971215514, 5425.403205647297, 4543.7955292011275, 5005.549500544968, 5039.571636410946, 5094.605535398357, 5365.087935051129, 4631.9536804631525, 5114.4118426379955, 5145.45972672864, 5328.120860058047, 5341.305630268041, 4788.4253889533475, 4910.386201724785, 5134.535422997057, 5472.589555783253, 5521.889562208814, 5104.702883702274, 5326.777299733605, 5330.173823057432, 5340.317799893021, 5348.78604855817], "value": 4077.91696044566, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [719.5682590445737, 739.9223081576434, 754.0310700749538, 807.6728924785463, 829.2205326992627, 13445.991550633717, 15221.467248646299, 16051.678966420679, 16051.83948160521, 16101.83898161018, 14616.926915365408, 14623.92688036561, 14630.122192668447, 14707.264636768146, 14752.778708319383, 914.7987442762582, 929.8605209218604, 951.46242373059, 951.6288647427474, 965.8696076029752, 952.9761755956122, 956.6842941829179, 956.7177682583655, 957.5020989085672, 958.4249450329781, 1006.2151521812998, 1008.8284991551417, 1009.0262896305064, 1009.6466236817129, 1013.8377859542475, 840.5839109640737, 998.7003898830321, 1002.4386343647581, 1002.5989604158395, 1005.8893521712606, 741.9517731347445, 742.6806473216515, 743.1824992508251, 743.2864450127884, 745.1207575061426, 15781.684366312724, 16105.0, 16137.677246455005, 16151.35394584227, 16389.098599577017, 13893.861061389358, 13910.930445347709, 14011.579652610439, 14082.155070695791, 14133.78799318011, 11579.942100289529, 11751.059915206763, 11846.348450835201, 11891.940540297242, 12437.440315185748, 3504.088936876405, 3627.6735093841417, 3759.0, 3804.029972357059, 3846.826892789803, 117907.64184716258, 120923.0, 122009.0, 122774.0, 123376.0, 10717.035466808025, 10756.784864302826, 10974.231803773659, 11232.66302010941, 11238.325700457835, 22473.88763056174, 22920.968556415002, 22977.0, 23013.53972920566, 23078.96144673494, 7665.0, 7683.346915512132, 7700.653470593837, 7705.345045671181, 7843.0, 4255.914881702349, 4334.869953901388, 4389.275769497997, 4482.0, 4562.612177964844, 3428.3314753623304, 3517.718582513393, 3519.8240087995496, 3521.683048525645, 3523.224890524081, 612.7059011674421, 617.478230894893, 618.783425800966, 619.0343064818807, 626.1828314050117, 658.5456035335603, 672.3491441472752, 681.5365551425078, 692.5394612582576, 714.125196634119, 9957.402555846673, 10105.897434618488, 10149.64476243328, 10365.378077315385, 10376.948115259376, 687.1479365586637, 696.944244460442, 711.5695004522189, 712.6045044999971, 713.5148099292531, 4467.754273514988, 4978.751062446863, 4986.750662466862, 4994.300797888299, 4996.325496058058, 102614.05327803394, 105914.0, 107536.92463075269, 108559.0, 111235.77528449385, 73179.0, 77358.0, 90535.54732226297, 94468.0, 96228.51885740526, 61736.69131654223, 71723.0, 77607.0, 79587.0, 80183.59908200422, 10509.264351495627, 10603.681889543326, 10762.14094434802, 10810.35137891729, 10843.1867609928, 44842.65472035844, 46630.53369466397, 47610.28584571165, 48659.0, 52064.0, 63463.365366345744, 63794.0, 65064.67467662632, 65340.67329663321, 66250.66874665595, 12129.454174562343, 12187.025037997117, 12256.816147757612, 12280.0, 12953.481860725466, 13878.722425551634, 14147.858521415066, 14280.928595356956, 14282.714345713235, 14849.703005940037, 12876.613701588967, 12956.546520872102, 13406.798898016732, 13560.118592291865, 13663.38514766818, 12554.811677825022, 13189.0, 13222.140560863512, 13879.236641984788, 14266.0, 12878.742425151631, 12912.548060818202, 13121.990170162342, 13285.621986978933, 13893.791593125909, 7751.728689495841, 9693.515324233618, 10095.192384609361, 10264.948675256876, 10421.56088490781, 11001.119910407308, 11052.613158539412, 11144.944275278573, 11212.607558735404, 11264.605738799102], "value": 7644.244912380453, "unit": "score"}]}, {"extraOptions": [], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1482.5000000000002, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 116.0, 116.0, 115.0, 115.0, 116.0, 115.0, 116.0, 116.0, 116.0], "value": 115.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [172.0, 142.0, 110.0, 146.0, 112.0, 110.0, 109.0, 110.0, 132.0, 110.0], "value": 125.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [185.0, 187.0, 184.0, 184.0, 185.0, 181.0, 184.0, 181.0, 183.0, 182.0], "value": 183.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 59.0, 61.0, 92.0, 65.0, 61.0, 61.0, 75.0, 101.0, 70.0], "value": 72.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 71.0, 89.0, 68.0, 69.0, 67.0, 83.0, 70.0, 70.0, 83.0], "value": 75.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 87.0, 88.0, 88.0, 88.0, 88.0, 89.0, 88.0, 89.0, 88.0], "value": 88.2, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 128.0, 149.0, 95.0, 95.0, 95.0, 95.0, 94.0, 107.0, 151.0], "value": 110.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [126.0, 129.0, 83.0, 85.0, 83.0, 84.0, 86.0, 86.0, 84.0, 83.0], "value": 92.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 59.0, 66.0, 81.0, 99.0, 97.0, 100.0, 101.0, 63.0, 66.0], "value": 79.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 63.0, 56.0, 65.0, 61.0, 59.0, 59.0, 58.0, 57.0, 58.0], "value": 59.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 84.0, 83.0, 85.0, 83.0, 84.0, 83.0, 83.0, 86.0, 90.0], "value": 84.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [153.0, 143.0, 127.0, 141.0, 139.0, 145.0, 144.0, 142.0, 180.0, 150.0], "value": 146.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [169.0, 170.0, 163.0, 168.0, 168.0, 218.0, 204.0, 167.0, 167.0, 165.0], "value": 175.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 67.0, 65.0, 70.0, 76.0, 79.0, 80.0, 79.0, 73.0, 65.0], "value": 72.0, "unit": "score"}]}]} 01:54:12 INFO - Return code: 0 01:54:12 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 01:54:12 ERROR - # TBPL SUCCESS # 01:54:12 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 01:54:12 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 01:54:12 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 01:54:12 INFO - 2017-04-21 01:54:12.557 screenresolution[1666:12218] starting screenresolution argv=screenresolution get 01:54:12 INFO - 2017-04-21 01:54:12.560 screenresolution[1666:12218] Display 0: 1600x1200x32@60 01:54:12 INFO - 2017-04-21 01:54:12.567 screenresolution[1667:12221] starting screenresolution argv=screenresolution list 01:54:12 INFO - Available Modes on Display 0 01:54:12 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 01:54:12 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 01:54:12 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 01:54:12 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 01:54:12 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 01:54:12 INFO - Intel Iris: 01:54:12 INFO - Chipset Model: Intel Iris 01:54:12 INFO - Type: GPU 01:54:12 INFO - Bus: Built-In 01:54:12 INFO - VRAM (Dynamic, Max): 1536 MB 01:54:12 INFO - Vendor: Intel (0x8086) 01:54:12 INFO - Device ID: 0x0a2e 01:54:12 INFO - Revision ID: 0x0009 01:54:12 INFO - Displays: 01:54:12 INFO - Display: 01:54:12 INFO - Resolution: 1600 x 1200 @ 60 Hz 01:54:12 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 01:54:12 INFO - Display Serial Number: 1600x1200 60 01:54:12 INFO - Main Display: Yes 01:54:12 INFO - Mirror: Off 01:54:12 INFO - Online: Yes 01:54:12 INFO - Rotation: Supported 01:54:12 INFO - Return code: 0 01:54:12 INFO - Running post-action listener: _resource_record_post_action 01:54:12 INFO - [mozharness: 2017-04-21 08:54:12.801302Z] Finished run-tests step (success) 01:54:12 INFO - Running post-run listener: _resource_record_post_run 01:54:12 INFO - Total resource usage - Wall time: 1201s; CPU: 54.0%; Read bytes: 33016832; Write bytes: 509520896; Read time: 200; Write time: 1582 01:54:12 INFO - TinderboxPrint: CPU usage
53.6% 01:54:12 INFO - TinderboxPrint: I/O read bytes / time
33,016,832 / 200 01:54:12 INFO - TinderboxPrint: I/O write bytes / time
509,520,896 / 1,582 01:54:12 INFO - TinderboxPrint: CPU idle
2,227.6 (46.4%) 01:54:12 INFO - TinderboxPrint: CPU system
949.4 (19.8%) 01:54:12 INFO - TinderboxPrint: CPU user
1,628.7 (33.9%) 01:54:12 INFO - TinderboxPrint: Swap in / out
237,236,224 / 0 01:54:12 INFO - install - Wall time: 20s; CPU: 54.0%; Read bytes: 203907584; Write bytes: 199294976; Read time: 15671; Write time: 425 01:54:12 INFO - run-tests - Wall time: 1182s; CPU: 54.0%; Read bytes: 30985216; Write bytes: 299432960; Read time: 182; Write time: 1121 01:54:13 INFO - Running post-run listener: _upload_blobber_files 01:54:13 INFO - Blob upload gear active. 01:54:13 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 01:54:13 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 01:54:13 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 01:54:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 01:54:13 INFO - (blobuploader) - INFO - Open directory for files ... 01:54:13 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 01:54:13 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 01:54:13 INFO - (blobuploader) - INFO - Uploading, attempt #1. 01:54:14 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 01:54:14 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 01:54:14 INFO - (blobuploader) - INFO - Done attempting. 01:54:14 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 01:54:14 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 01:54:14 INFO - (blobuploader) - INFO - Uploading, attempt #1. 01:54:15 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 01:54:15 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 01:54:15 INFO - (blobuploader) - INFO - Done attempting. 01:54:15 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 01:54:15 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 01:54:15 INFO - (blobuploader) - INFO - Uploading, attempt #1. 01:54:16 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 01:54:16 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 01:54:16 INFO - (blobuploader) - INFO - Done attempting. 01:54:16 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 01:54:16 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 01:54:16 INFO - (blobuploader) - INFO - Uploading, attempt #1. 01:54:18 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 01:54:18 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 01:54:18 INFO - (blobuploader) - INFO - Done attempting. 01:54:18 INFO - (blobuploader) - INFO - Iteration through files over. 01:54:18 INFO - Return code: 0 01:54:18 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 01:54:18 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 01:54:18 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/99104a9173780898625b74d3a3ba8f4ea52612939392e1fa59ab2491556900eb106bb48e98ecab26bc6ff6645f007fc57fc5095d91ae6fdd7555594fde30023d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/39349e6d0b30709939f1a3f08272eca1dab8020689f43072881c9c74a71e85b319dad8aebbed508a540d3e9434ae35a46a91e4b6df583ebfc5fa404b6a75eb1b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/d1c4f73352728a11d1086d008b44fecdb1e266f43de4afcfd2b5e75f9fe17420857693682589bf2226be7eb6bd35095e72018915dd9602de4275d86123afae72", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/a87096235aa5609838209a67036c9b51bc646434468af1b1eb2430a855d721a46423bafcd22a1fedf1b55c9ddb4fc544471da85c93f8c26a01d7df5fbbe5174e"} 01:54:18 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 01:54:18 INFO - Writing to file /builds/slave/test/properties/blobber_files 01:54:18 INFO - Contents: 01:54:18 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/99104a9173780898625b74d3a3ba8f4ea52612939392e1fa59ab2491556900eb106bb48e98ecab26bc6ff6645f007fc57fc5095d91ae6fdd7555594fde30023d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/39349e6d0b30709939f1a3f08272eca1dab8020689f43072881c9c74a71e85b319dad8aebbed508a540d3e9434ae35a46a91e4b6df583ebfc5fa404b6a75eb1b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/d1c4f73352728a11d1086d008b44fecdb1e266f43de4afcfd2b5e75f9fe17420857693682589bf2226be7eb6bd35095e72018915dd9602de4275d86123afae72", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/a87096235aa5609838209a67036c9b51bc646434468af1b1eb2430a855d721a46423bafcd22a1fedf1b55c9ddb4fc544471da85c93f8c26a01d7df5fbbe5174e"} 01:54:18 INFO - Running post-run listener: copy_logs_to_upload_dir 01:54:18 INFO - Copying logs to upload dir... 01:54:18 INFO - mkdir: /builds/slave/test/build/upload/logs 01:54:18 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1282.830989 ========= master_lag: 3.95 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 26 secs) (at 2017-04-21 01:54:22.677712) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 1 secs) (at 2017-04-21 01:54:22.680764) ========= 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.wNYqFqMEdq/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.AnpypnL2k5/Listeners TMPDIR=/var/folders/k4/xd58rgkx0dnbrdjb1pv1qrj800000w/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/Mozilla-Aurora/sha512/99104a9173780898625b74d3a3ba8f4ea52612939392e1fa59ab2491556900eb106bb48e98ecab26bc6ff6645f007fc57fc5095d91ae6fdd7555594fde30023d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/39349e6d0b30709939f1a3f08272eca1dab8020689f43072881c9c74a71e85b319dad8aebbed508a540d3e9434ae35a46a91e4b6df583ebfc5fa404b6a75eb1b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/d1c4f73352728a11d1086d008b44fecdb1e266f43de4afcfd2b5e75f9fe17420857693682589bf2226be7eb6bd35095e72018915dd9602de4275d86123afae72", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/a87096235aa5609838209a67036c9b51bc646434468af1b1eb2430a855d721a46423bafcd22a1fedf1b55c9ddb4fc544471da85c93f8c26a01d7df5fbbe5174e"} build_url:https://queue.taskcluster.net/v1/task/DD51djZRSZCceFCg4Q-gKg/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011428 build_url: 'https://queue.taskcluster.net/v1/task/DD51djZRSZCceFCg4Q-gKg/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/99104a9173780898625b74d3a3ba8f4ea52612939392e1fa59ab2491556900eb106bb48e98ecab26bc6ff6645f007fc57fc5095d91ae6fdd7555594fde30023d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/39349e6d0b30709939f1a3f08272eca1dab8020689f43072881c9c74a71e85b319dad8aebbed508a540d3e9434ae35a46a91e4b6df583ebfc5fa404b6a75eb1b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/d1c4f73352728a11d1086d008b44fecdb1e266f43de4afcfd2b5e75f9fe17420857693682589bf2226be7eb6bd35095e72018915dd9602de4275d86123afae72", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/a87096235aa5609838209a67036c9b51bc646434468af1b1eb2430a855d721a46423bafcd22a1fedf1b55c9ddb4fc544471da85c93f8c26a01d7df5fbbe5174e"}' ========= master_lag: 1.61 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 1 secs) (at 2017-04-21 01:54:24.299274) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 1 secs) (at 2017-04-21 01:54:24.303684) ========= 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.wNYqFqMEdq/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.AnpypnL2k5/Listeners TMPDIR=/var/folders/k4/xd58rgkx0dnbrdjb1pv1qrj800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False program finished with exit code 0 elapsedTime=0.005165 ========= master_lag: 1.16 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 1 secs) (at 2017-04-21 01:54:25.464766) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-04-21 01:54:25.465051) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-04-21 01:54:25.474567) ========= ========= Total master_lag: 6.80 =========