builder: mozilla-aurora_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0332 starttime: 1492036994.98 results: success (0) buildid: 20170412135108 builduid: 5d60753d2cc745d8a08b661245ee5c65 revision: cef7b5c139ef710202d2e95ec7b9ab84b9e9b2a2 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-04-12 15:43:14.981779) ========= master: http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-04-12 15:43:14.982230) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-04-12 15:43:14.982532) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-04-12 15:43:15.013791) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-12 15:43:15.014475) ========= 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.rzPqNHbdQT/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.Vrd4veNHwd/Listeners TMPDIR=/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/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-12 15:43:15-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 159M=0s 2017-04-12 15:43:15 (159 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.147366 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-12 15:43:15.190073) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-04-12 15:43:15.190790) ========= 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.rzPqNHbdQT/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.Vrd4veNHwd/Listeners TMPDIR=/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/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.041242 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-04-12 15:43:15.255663) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-04-12 15:43:15.256179) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev cef7b5c139ef710202d2e95ec7b9ab84b9e9b2a2 --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 cef7b5c139ef710202d2e95ec7b9ab84b9e9b2a2 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.rzPqNHbdQT/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.Vrd4veNHwd/Listeners TMPDIR=/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/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-12 15:43:15,388 truncating revision to first 12 chars 2017-04-12 15:43:15,388 Setting DEBUG logging. 2017-04-12 15:43:15,388 attempt 1/10 2017-04-12 15:43:15,389 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/cef7b5c139ef?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-04-12 15:43:17,255 unpacking tar archive at: mozilla-aurora-cef7b5c139ef/testing/mozharness/ program finished with exit code 0 elapsedTime=2.137959 ========= master_lag: 0.11 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-04-12 15:43:17.503246) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-04-12 15:43:17.503946) ========= script_repo_revision: cef7b5c139ef710202d2e95ec7b9ab84b9e9b2a2 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-04-12 15:43:17.505107) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-04-12 15:43:17.505789) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-04-12 15:43:17.526765) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 25 secs) (at 2017-04-12 15:43:17.527479) ========= /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.rzPqNHbdQT/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.Vrd4veNHwd/Listeners TMPDIR=/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/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 15:43:17 INFO - MultiFileLogger online at 20170412 15:43:17 in /builds/slave/test 15:43:17 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 15:43:17 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 15:43:17 INFO - {'append_to_log': False, 15:43:17 INFO - 'base_work_dir': '/builds/slave/test', 15:43:17 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 15:43:17 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 15:43:17 INFO - 'branch': 'Mozilla-Aurora', 15:43:17 INFO - 'buildbot_json_path': 'buildprops.json', 15:43:17 INFO - 'config_files': ('talos/mac_config.py',), 15:43:17 INFO - 'default_actions': ('clobber', 15:43:17 INFO - 'read-buildbot-config', 15:43:17 INFO - 'download-and-extract', 15:43:17 INFO - 'populate-webroot', 15:43:17 INFO - 'create-virtualenv', 15:43:17 INFO - 'install', 15:43:17 INFO - 'run-tests'), 15:43:17 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 15:43:17 INFO - 'download_minidump_stackwalk': True, 15:43:17 INFO - 'download_symbols': 'ondemand', 15:43:17 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 15:43:17 INFO - 'tooltool.py': '/tools/tooltool.py', 15:43:17 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 15:43:17 INFO - '/tools/misc-python/virtualenv.py')}, 15:43:17 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 15:43:17 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 15:43:17 INFO - 'gecko_profile': False, 15:43:17 INFO - 'gecko_profile_interval': 0, 15:43:17 INFO - 'installer_path': 'installer.exe', 15:43:17 INFO - 'log_level': 'info', 15:43:17 INFO - 'log_name': 'talos', 15:43:17 INFO - 'log_to_console': True, 15:43:17 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 15:43:17 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 15:43:17 INFO - 'opt_config_files': (), 15:43:17 INFO - 'pip_index': False, 15:43:17 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 15:43:17 INFO - 'cmd': ('bash', 15:43:17 INFO - '-c', 15:43:17 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 15:43:17 INFO - 'enabled': True, 15:43:17 INFO - 'halt_on_failure': False, 15:43:17 INFO - 'name': 'check_screen_resolution'},), 15:43:17 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 15:43:17 INFO - 'cmd': ('bash', 15:43:17 INFO - '-c', 15:43:17 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 15:43:17 INFO - 'enabled': True, 15:43:17 INFO - 'halt_on_failure': False, 15:43:17 INFO - 'name': 'check_screen_resolution'},), 15:43:17 INFO - 'run_cmd_checks_enabled': True, 15:43:17 INFO - 'suite': 'dromaeojs', 15:43:17 INFO - 'system_bits': '32', 15:43:17 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 15:43:17 INFO - 'title': 't-yosemite-r7-0332', 15:43:17 INFO - 'tooltool_cache': '/builds/tooltool_cache', 15:43:17 INFO - 'use_talos_json': True, 15:43:17 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 15:43:17 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 15:43:17 INFO - 'work_dir': 'build'} 15:43:17 INFO - [mozharness: 2017-04-12 22:43:17.767502Z] Running clobber step. 15:43:17 INFO - Running pre-action listener: _resource_record_pre_action 15:43:17 INFO - Running main action method: clobber 15:43:17 INFO - rmtree: /builds/slave/test/build 15:43:17 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 15:43:20 INFO - Running post-action listener: _resource_record_post_action 15:43:20 INFO - [mozharness: 2017-04-12 22:43:20.229926Z] Finished clobber step (success) 15:43:20 INFO - [mozharness: 2017-04-12 22:43:20.230102Z] Running read-buildbot-config step. 15:43:20 INFO - Running pre-action listener: _resource_record_pre_action 15:43:20 INFO - Running main action method: read_buildbot_config 15:43:20 INFO - Using buildbot properties: 15:43:20 INFO - { 15:43:20 INFO - "project": "", 15:43:20 INFO - "product": "firefox", 15:43:20 INFO - "script_repo_revision": "production", 15:43:20 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs", 15:43:20 INFO - "repository": "", 15:43:20 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs", 15:43:20 INFO - "buildid": "20170412135108", 15:43:20 INFO - "pgo_build": "False", 15:43:20 INFO - "basedir": "/builds/slave/test", 15:43:20 INFO - "buildnumber": 15, 15:43:20 INFO - "slavename": "t-yosemite-r7-0332", 15:43:20 INFO - "master": "http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/", 15:43:20 INFO - "platform": "yosemite_r7", 15:43:20 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 15:43:20 INFO - "branch": "mozilla-aurora", 15:43:20 INFO - "repo_path": "releases/mozilla-aurora", 15:43:20 INFO - "revision": "cef7b5c139ef710202d2e95ec7b9ab84b9e9b2a2", 15:43:20 INFO - "stage_platform": "macosx64", 15:43:20 INFO - "builduid": "5d60753d2cc745d8a08b661245ee5c65", 15:43:20 INFO - "slavebuilddir": "test" 15:43:20 INFO - } 15:43:20 INFO - Found installer url https://queue.taskcluster.net/v1/task/M21nbwsWR0ihuc3xFXN83A/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg. 15:43:20 INFO - Running post-action listener: _resource_record_post_action 15:43:20 INFO - [mozharness: 2017-04-12 22:43:20.233292Z] Finished read-buildbot-config step (success) 15:43:20 INFO - [mozharness: 2017-04-12 22:43:20.233387Z] Running download-and-extract step. 15:43:20 INFO - Running pre-action listener: _resource_record_pre_action 15:43:20 INFO - Running main action method: download_and_extract 15:43:20 INFO - mkdir: /builds/slave/test/build/tests 15:43:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:43:20 INFO - trying https://queue.taskcluster.net/v1/task/M21nbwsWR0ihuc3xFXN83A/artifacts/public/build/firefox-54.0a2.en-US.mac.test_packages.json 15:43:20 INFO - Downloading https://queue.taskcluster.net/v1/task/M21nbwsWR0ihuc3xFXN83A/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 15:43:20 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/M21nbwsWR0ihuc3xFXN83A/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 15:43:21 INFO - Downloaded 1199 bytes. 15:43:21 INFO - Reading from file /builds/slave/test/build/firefox-54.0a2.en-US.mac.test_packages.json 15:43:21 INFO - Using the following test package requirements: 15:43:21 INFO - {u'common': [u'firefox-54.0a2.en-US.mac.common.tests.zip'], 15:43:21 INFO - u'cppunittest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 15:43:21 INFO - u'firefox-54.0a2.en-US.mac.cppunittest.tests.zip'], 15:43:21 INFO - u'gtest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 15:43:21 INFO - u'firefox-54.0a2.en-US.mac.gtest.tests.zip'], 15:43:21 INFO - u'jittest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 15:43:21 INFO - u'jsshell-mac.zip'], 15:43:21 INFO - u'mochitest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 15:43:21 INFO - u'firefox-54.0a2.en-US.mac.mochitest.tests.zip'], 15:43:21 INFO - u'mozbase': [u'firefox-54.0a2.en-US.mac.common.tests.zip'], 15:43:21 INFO - u'reftest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 15:43:21 INFO - u'firefox-54.0a2.en-US.mac.reftest.tests.zip'], 15:43:21 INFO - u'talos': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 15:43:21 INFO - u'firefox-54.0a2.en-US.mac.talos.tests.zip'], 15:43:21 INFO - u'web-platform': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 15:43:21 INFO - u'firefox-54.0a2.en-US.mac.web-platform.tests.zip'], 15:43:21 INFO - u'xpcshell': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 15:43:21 INFO - u'firefox-54.0a2.en-US.mac.xpcshell.tests.zip']} 15:43:21 INFO - Downloading packages: [u'firefox-54.0a2.en-US.mac.common.tests.zip'] for test suite category: common 15:43:21 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/M21nbwsWR0ihuc3xFXN83A/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip 15:43:21 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/M21nbwsWR0ihuc3xFXN83A/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip'}, attempt #1 15:43:21 INFO - Fetch https://queue.taskcluster.net/v1/task/M21nbwsWR0ihuc3xFXN83A/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip into memory 15:43:23 INFO - Expected file size: 51791364 15:43:23 INFO - Obtained file size: 51791364 15:43:39 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 15:43:39 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/M21nbwsWR0ihuc3xFXN83A/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip 15:43:39 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/M21nbwsWR0ihuc3xFXN83A/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip'}, attempt #1 15:43:39 INFO - Fetch https://queue.taskcluster.net/v1/task/M21nbwsWR0ihuc3xFXN83A/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip into memory 15:43:41 INFO - Expected file size: 51791364 15:43:41 INFO - Obtained file size: 51791364 15:43:56 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/M21nbwsWR0ihuc3xFXN83A/artifacts/public/build/firefox-54.0a2.en-US.mac.talos.tests.zip 15:43:56 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/M21nbwsWR0ihuc3xFXN83A/artifacts/public/build/firefox-54.0a2.en-US.mac.talos.tests.zip'}, attempt #1 15:43:56 INFO - Fetch https://queue.taskcluster.net/v1/task/M21nbwsWR0ihuc3xFXN83A/artifacts/public/build/firefox-54.0a2.en-US.mac.talos.tests.zip into memory 15:43:57 INFO - Expected file size: 14404424 15:43:57 INFO - Obtained file size: 14404424 15:43:57 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')]} 15:43:57 INFO - trying https://queue.taskcluster.net/v1/task/M21nbwsWR0ihuc3xFXN83A/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg 15:43:57 INFO - Downloading https://queue.taskcluster.net/v1/task/M21nbwsWR0ihuc3xFXN83A/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-54.0a2.en-US.mac.dmg 15:43:57 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/M21nbwsWR0ihuc3xFXN83A/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 15:43:59 INFO - Downloaded 74727420 bytes. 15:43:59 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/M21nbwsWR0ihuc3xFXN83A/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg 15:43:59 INFO - mkdir: /builds/slave/test/properties 15:43:59 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 15:43:59 INFO - Writing to file /builds/slave/test/properties/build_url 15:43:59 INFO - Contents: 15:43:59 INFO - build_url:https://queue.taskcluster.net/v1/task/M21nbwsWR0ihuc3xFXN83A/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg 15:44:00 INFO - Running post-action listener: _resource_record_post_action 15:44:00 INFO - Running post-action listener: set_extra_try_arguments 15:44:00 INFO - [mozharness: 2017-04-12 22:44:00.674798Z] Finished download-and-extract step (success) 15:44:00 INFO - [mozharness: 2017-04-12 22:44:00.674959Z] Running populate-webroot step. 15:44:00 INFO - Running pre-action listener: _resource_record_pre_action 15:44:00 INFO - Running main action method: populate_webroot 15:44:00 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 15:44:00 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 15:44:00 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 15:44:00 INFO - '--tppagecycles', 15:44:00 INFO - '7'], 15:44:00 INFO - 'tests': ['tsvgm']}}, 15:44:00 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 15:44:00 INFO - 'tests': ['tresize', 'tcanvasmark']}, 15:44:00 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 15:44:00 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 15:44:00 INFO - 'tests': ['dromaeo_css', 'kraken']}, 15:44:00 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 15:44:00 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 15:44:00 INFO - 'talos_options': ['--disable-e10s'], 15:44:00 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:44:00 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 15:44:00 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:44:00 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 15:44:00 INFO - 'talos_options': ['--disable-e10s'], 15:44:00 INFO - 'tests': ['damp', 'tps']}, 15:44:00 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 15:44:00 INFO - 'tests': ['damp', 'tps']}, 15:44:00 INFO - 'g3': {'talos_options': ['--disable-e10s'], 15:44:00 INFO - 'tests': ['dromaeo_dom']}, 15:44:00 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 15:44:00 INFO - 'g4': {'talos_options': ['--disable-e10s'], 15:44:00 INFO - 'tests': ['basic_compositor_video']}, 15:44:00 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 15:44:00 INFO - 'other': {'talos_options': ['--disable-e10s'], 15:44:00 INFO - 'tests': ['a11yr', 15:44:00 INFO - 'ts_paint', 15:44:00 INFO - 'tpaint', 15:44:00 INFO - 'sessionrestore', 15:44:00 INFO - 'sessionrestore_no_auto_restore', 15:44:00 INFO - 'tabpaint']}, 15:44:00 INFO - 'other-e10s': {'tests': ['a11yr', 15:44:00 INFO - 'ts_paint', 15:44:00 INFO - 'tpaint', 15:44:00 INFO - 'sessionrestore', 15:44:00 INFO - 'sessionrestore_no_auto_restore', 15:44:00 INFO - 'tabpaint']}, 15:44:00 INFO - 'other-e10s_l64': {'tests': ['a11yr', 15:44:00 INFO - 'ts_paint', 15:44:00 INFO - 'tpaint', 15:44:00 INFO - 'sessionrestore', 15:44:00 INFO - 'sessionrestore_no_auto_restore', 15:44:00 INFO - 'tabpaint']}, 15:44:00 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 15:44:00 INFO - 'ts_paint', 15:44:00 INFO - 'tpaint', 15:44:00 INFO - 'sessionrestore', 15:44:00 INFO - 'sessionrestore_no_auto_restore', 15:44:00 INFO - 'tabpaint']}, 15:44:00 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 15:44:00 INFO - 'tests': ['a11yr', 15:44:00 INFO - 'ts_paint', 15:44:00 INFO - 'tpaint', 15:44:00 INFO - 'sessionrestore', 15:44:00 INFO - 'sessionrestore_no_auto_restore', 15:44:00 INFO - 'tabpaint']}, 15:44:00 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 15:44:00 INFO - 'tests': ['a11yr', 15:44:00 INFO - 'ts_paint', 15:44:00 INFO - 'tpaint', 15:44:00 INFO - 'sessionrestore', 15:44:00 INFO - 'sessionrestore_no_auto_restore', 15:44:00 INFO - 'tabpaint']}, 15:44:00 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 15:44:00 INFO - 'tests': ['tsvgx', 15:44:00 INFO - 'tsvgr_opacity', 15:44:00 INFO - 'tart', 15:44:00 INFO - 'tscrollx', 15:44:00 INFO - 'cart', 15:44:00 INFO - 'tsvg_static']}, 15:44:00 INFO - 'svgr-e10s': {'tests': ['tsvgx', 15:44:00 INFO - 'tsvgr_opacity', 15:44:00 INFO - 'tart', 15:44:00 INFO - 'tscrollx', 15:44:00 INFO - 'cart', 15:44:00 INFO - 'tsvg_static']}, 15:44:00 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 15:44:00 INFO - 'talos_options': ['--disable-e10s'], 15:44:00 INFO - 'tests': ['tp5o']}, 15:44:00 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 15:44:00 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 15:44:00 INFO - 'talos_options': ['--disable-e10s', 15:44:00 INFO - '--xperf_path', 15:44:00 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:44:00 INFO - 'tests': ['tp5n']}, 15:44:00 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 15:44:00 INFO - 'talos_options': ['--xperf_path', 15:44:00 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:44:00 INFO - 'tests': ['tp5n']}}, 15:44:00 INFO - 'talos.zip': {'path': '', 15:44:00 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 15:44:00 INFO - Running post-action listener: _resource_record_post_action 15:44:00 INFO - [mozharness: 2017-04-12 22:44:00.693482Z] Finished populate-webroot step (success) 15:44:00 INFO - [mozharness: 2017-04-12 22:44:00.693612Z] Running create-virtualenv step. 15:44:00 INFO - Running pre-action listener: _resource_record_pre_action 15:44:00 INFO - Running main action method: create_virtualenv 15:44:00 INFO - Creating virtualenv /builds/slave/test/build/venv 15:44:00 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 15:44:00 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 15:44:00 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 15:44:00 INFO - Using real prefix '/tools/python27' 15:44:00 INFO - New python executable in /builds/slave/test/build/venv/bin/python 15:44:01 INFO - Installing distribute.............................................................................................................................................................................................done. 15:44:04 INFO - Installing pip.................done. 15:44:04 INFO - Return code: 0 15:44:04 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 15:44:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 15:44:04 INFO - Reading from file tmpfile_stdout 15:44:04 INFO - Output received: 15:44:04 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) 15:44:04 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 15:44: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')]} 15:44:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:44:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:44: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')]} 15:44:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:44:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:44:04 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10489cb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10489f768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9a51cc2180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10467ad30>, '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 0x1045a1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10466fbe8>, '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.rzPqNHbdQT/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.Vrd4veNHwd/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/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 15:44:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 15:44:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 15:44:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rzPqNHbdQT/Render', 15:44:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:44:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:44:04 INFO - 'HOME': '/Users/cltbld', 15:44:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:44:04 INFO - 'LOGNAME': 'cltbld', 15:44:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:44:04 INFO - 'MOZ_NO_REMOTE': '1', 15:44:04 INFO - 'NO_EM_RESTART': '1', 15:44:04 INFO - 'PAGER': '/bin/cat', 15:44:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:44:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:44:04 INFO - 'PWD': '/builds/slave/test', 15:44:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:44:04 INFO - 'SHELL': '/bin/bash', 15:44:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Vrd4veNHwd/Listeners', 15:44:04 INFO - 'TMPDIR': '/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/', 15:44:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:44:04 INFO - 'USER': 'cltbld', 15:44:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:44:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:44:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:44:04 INFO - 'XPC_FLAGS': '0x0', 15:44:04 INFO - 'XPC_SERVICE_NAME': '0', 15:44:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:44:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:44:04 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 15:44:04 INFO - Cleaning up... 15:44:04 INFO - Return code: 0 15:44:04 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 15:44: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')]} 15:44:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:44:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:44: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')]} 15:44:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:44:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:44:04 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10489cb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10489f768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9a51cc2180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10467ad30>, '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 0x1045a1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10466fbe8>, '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.rzPqNHbdQT/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.Vrd4veNHwd/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/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 15:44:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 15:44:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 15:44:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rzPqNHbdQT/Render', 15:44:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:44:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:44:04 INFO - 'HOME': '/Users/cltbld', 15:44:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:44:04 INFO - 'LOGNAME': 'cltbld', 15:44:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:44:04 INFO - 'MOZ_NO_REMOTE': '1', 15:44:04 INFO - 'NO_EM_RESTART': '1', 15:44:04 INFO - 'PAGER': '/bin/cat', 15:44:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:44:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:44:04 INFO - 'PWD': '/builds/slave/test', 15:44:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:44:04 INFO - 'SHELL': '/bin/bash', 15:44:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Vrd4veNHwd/Listeners', 15:44:04 INFO - 'TMPDIR': '/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/', 15:44:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:44:04 INFO - 'USER': 'cltbld', 15:44:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:44:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:44:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:44:04 INFO - 'XPC_FLAGS': '0x0', 15:44:04 INFO - 'XPC_SERVICE_NAME': '0', 15:44:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:44:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:44:05 INFO - Downloading/unpacking psutil>=3.1.1 15:44:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:44:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:44:05 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:44:05 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:44:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:44:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:44:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 15:44:08 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:44:08 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:44:08 INFO - Installing collected packages: psutil 15:44:08 INFO - Running setup.py install for psutil 15:44:08 INFO - building 'psutil._psutil_osx' extension 15:44:08 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 15:44:09 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 15:44:09 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 15:44:09 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 15:44:09 INFO - building 'psutil._psutil_posix' extension 15:44:09 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 15:44:09 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 15:44:09 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 15:44:09 INFO - ^ 15:44:09 INFO - 1 warning generated. 15:44:09 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 15:44:09 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:44:09 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:44:09 INFO - Successfully installed psutil 15:44:09 INFO - Cleaning up... 15:44:09 INFO - Return code: 0 15:44:09 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 15:44: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')]} 15:44:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:44:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:44: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')]} 15:44:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:44:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:44: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', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10489cb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10489f768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9a51cc2180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10467ad30>, '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 0x1045a1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10466fbe8>, '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.rzPqNHbdQT/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.Vrd4veNHwd/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/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 15:44: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', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 15:44: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 mozsystemmonitor==0.3 15:44:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rzPqNHbdQT/Render', 15:44:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:44:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:44:10 INFO - 'HOME': '/Users/cltbld', 15:44:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:44:10 INFO - 'LOGNAME': 'cltbld', 15:44:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:44:10 INFO - 'MOZ_NO_REMOTE': '1', 15:44:10 INFO - 'NO_EM_RESTART': '1', 15:44:10 INFO - 'PAGER': '/bin/cat', 15:44:10 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:44:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:44:10 INFO - 'PWD': '/builds/slave/test', 15:44:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:44:10 INFO - 'SHELL': '/bin/bash', 15:44:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Vrd4veNHwd/Listeners', 15:44:10 INFO - 'TMPDIR': '/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/', 15:44:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:44:10 INFO - 'USER': 'cltbld', 15:44:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:44:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:44:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:44:10 INFO - 'XPC_FLAGS': '0x0', 15:44:10 INFO - 'XPC_SERVICE_NAME': '0', 15:44:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:44:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:44:10 INFO - Downloading/unpacking mozsystemmonitor==0.3 15:44:10 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 15:44:10 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 15:44:10 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 15:44:10 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 15:44:10 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 15:44:10 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 15:44:14 INFO - Downloading mozsystemmonitor-0.3.tar.gz 15:44:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 15:44:14 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 15:44:14 INFO - Installing collected packages: mozsystemmonitor 15:44:14 INFO - Running setup.py install for mozsystemmonitor 15:44:15 INFO - Successfully installed mozsystemmonitor 15:44:15 INFO - Cleaning up... 15:44:15 INFO - Return code: 0 15:44:15 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 15:44:15 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')]} 15:44:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:44:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:44:15 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')]} 15:44:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:44:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:44:15 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 0x10489cb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10489f768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9a51cc2180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10467ad30>, '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 0x1045a1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10466fbe8>, '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.rzPqNHbdQT/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.Vrd4veNHwd/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/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 15:44:15 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 15:44:15 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 15:44:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rzPqNHbdQT/Render', 15:44:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:44:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:44:15 INFO - 'HOME': '/Users/cltbld', 15:44:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:44:15 INFO - 'LOGNAME': 'cltbld', 15:44:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:44:15 INFO - 'MOZ_NO_REMOTE': '1', 15:44:15 INFO - 'NO_EM_RESTART': '1', 15:44:15 INFO - 'PAGER': '/bin/cat', 15:44:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:44:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:44:15 INFO - 'PWD': '/builds/slave/test', 15:44:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:44:15 INFO - 'SHELL': '/bin/bash', 15:44:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Vrd4veNHwd/Listeners', 15:44:15 INFO - 'TMPDIR': '/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/', 15:44:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:44:15 INFO - 'USER': 'cltbld', 15:44:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:44:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:44:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:44:15 INFO - 'XPC_FLAGS': '0x0', 15:44:15 INFO - 'XPC_SERVICE_NAME': '0', 15:44:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:44:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:44:15 INFO - Downloading/unpacking jsonschema==2.5.1 15:44:15 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 15:44:15 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 15:44:15 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 15:44:15 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 15:44:15 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 15:44:15 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 15:44:17 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 15:44:17 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 15:44:17 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 15:44:17 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 15:44:17 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 15:44:17 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 15:44:17 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 15:44:17 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 15:44:18 INFO - Downloading functools32-3.2.3-2.tar.gz 15:44:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 15:44:18 INFO - warning: no files found matching '*.txt' 15:44:18 INFO - no previously-included directories found matching 'build' 15:44:18 INFO - no previously-included directories found matching 'dist' 15:44:18 INFO - no previously-included directories found matching '.git*' 15:44:18 INFO - Installing collected packages: jsonschema, functools32 15:44:18 INFO - Running setup.py install for functools32 15:44:18 INFO - warning: no files found matching '*.txt' 15:44:18 INFO - no previously-included directories found matching 'build' 15:44:18 INFO - no previously-included directories found matching 'dist' 15:44:18 INFO - no previously-included directories found matching '.git*' 15:44:18 INFO - Successfully installed jsonschema functools32 15:44:18 INFO - Cleaning up... 15:44:18 INFO - Return code: 0 15:44:18 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 15:44:18 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')]} 15:44:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:44:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:44:18 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')]} 15:44:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:44:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:44:18 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 0x10489cb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10489f768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9a51cc2180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10467ad30>, '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 0x1045a1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10466fbe8>, '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.rzPqNHbdQT/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.Vrd4veNHwd/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/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 15:44:18 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 15:44:18 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 15:44:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rzPqNHbdQT/Render', 15:44:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:44:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:44:18 INFO - 'HOME': '/Users/cltbld', 15:44:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:44:18 INFO - 'LOGNAME': 'cltbld', 15:44:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:44:18 INFO - 'MOZ_NO_REMOTE': '1', 15:44:18 INFO - 'NO_EM_RESTART': '1', 15:44:18 INFO - 'PAGER': '/bin/cat', 15:44:18 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:44:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:44:18 INFO - 'PWD': '/builds/slave/test', 15:44:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:44:18 INFO - 'SHELL': '/bin/bash', 15:44:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Vrd4veNHwd/Listeners', 15:44:18 INFO - 'TMPDIR': '/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/', 15:44:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:44:18 INFO - 'USER': 'cltbld', 15:44:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:44:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:44:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:44:18 INFO - 'XPC_FLAGS': '0x0', 15:44:18 INFO - 'XPC_SERVICE_NAME': '0', 15:44:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:44:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:44:19 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 15:44:19 INFO - Cleaning up... 15:44:19 INFO - Return code: 0 15:44:19 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 15:44:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:44:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:44:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:44:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:44:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:44:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:44:19 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 0x10489cb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10489f768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9a51cc2180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10467ad30>, '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 0x1045a1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10466fbe8>, '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.rzPqNHbdQT/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.Vrd4veNHwd/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/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 15:44:19 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 15:44:19 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 15:44:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rzPqNHbdQT/Render', 15:44:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:44:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:44:19 INFO - 'HOME': '/Users/cltbld', 15:44:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:44:19 INFO - 'LOGNAME': 'cltbld', 15:44:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:44:19 INFO - 'MOZ_NO_REMOTE': '1', 15:44:19 INFO - 'NO_EM_RESTART': '1', 15:44:19 INFO - 'PAGER': '/bin/cat', 15:44:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:44:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:44:19 INFO - 'PWD': '/builds/slave/test', 15:44:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:44:19 INFO - 'SHELL': '/bin/bash', 15:44:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Vrd4veNHwd/Listeners', 15:44:19 INFO - 'TMPDIR': '/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/', 15:44:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:44:19 INFO - 'USER': 'cltbld', 15:44:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:44:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:44:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:44:19 INFO - 'XPC_FLAGS': '0x0', 15:44:19 INFO - 'XPC_SERVICE_NAME': '0', 15:44:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:44:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:44:19 INFO - Downloading/unpacking blobuploader==1.2.4 15:44:19 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 15:44:19 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 15:44:19 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 15:44:19 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 15:44:19 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 15:44:19 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 15:44:21 INFO - Downloading blobuploader-1.2.4.tar.gz 15:44:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 15:44:22 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 15:44:22 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 15:44:22 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 15:44:22 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 15:44:22 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 15:44:22 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 15:44:22 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 15:44:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 15:44:22 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 15:44:22 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 15:44:22 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 15:44:22 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 15:44:22 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 15:44:22 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 15:44:22 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 15:44:23 INFO - Downloading docopt-0.6.1.tar.gz 15:44:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 15:44:23 INFO - Installing collected packages: blobuploader, requests, docopt 15:44:23 INFO - Running setup.py install for blobuploader 15:44:23 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 15:44:23 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 15:44:23 INFO - Running setup.py install for requests 15:44:23 INFO - Running setup.py install for docopt 15:44:23 INFO - Successfully installed blobuploader requests docopt 15:44:23 INFO - Cleaning up... 15:44:24 INFO - Return code: 0 15:44:24 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:44:24 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')]} 15:44:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:44:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:44:24 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')]} 15:44:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:44:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:44:24 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 0x10489cb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10489f768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9a51cc2180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10467ad30>, '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 0x1045a1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10466fbe8>, '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.rzPqNHbdQT/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.Vrd4veNHwd/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/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 15:44:24 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 15:44:24 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 15:44:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rzPqNHbdQT/Render', 15:44:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:44:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:44:24 INFO - 'HOME': '/Users/cltbld', 15:44:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:44:24 INFO - 'LOGNAME': 'cltbld', 15:44:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:44:24 INFO - 'MOZ_NO_REMOTE': '1', 15:44:24 INFO - 'NO_EM_RESTART': '1', 15:44:24 INFO - 'PAGER': '/bin/cat', 15:44:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:44:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:44:24 INFO - 'PWD': '/builds/slave/test', 15:44:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:44:24 INFO - 'SHELL': '/bin/bash', 15:44:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Vrd4veNHwd/Listeners', 15:44:24 INFO - 'TMPDIR': '/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/', 15:44:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:44:24 INFO - 'USER': 'cltbld', 15:44:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:44:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:44:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:44:24 INFO - 'XPC_FLAGS': '0x0', 15:44:24 INFO - 'XPC_SERVICE_NAME': '0', 15:44:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:44:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:44:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:44:24 INFO - Running setup.py (path:/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/pip-W229q7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:44:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:44:24 INFO - Running setup.py (path:/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/pip-TyN1vr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:44:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:44:24 INFO - Running setup.py (path:/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/pip-AFQEJC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:44:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:44:24 INFO - Running setup.py (path:/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/pip-FcUJ8o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:44:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:44:24 INFO - Running setup.py (path:/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/pip-iNCqdR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:44:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:44:24 INFO - Running setup.py (path:/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/pip-DVPDKA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:44:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:44:24 INFO - Running setup.py (path:/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/pip-4v_qAQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:44:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:44:25 INFO - Running setup.py (path:/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/pip-JAXsuz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:44:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:44:25 INFO - Running setup.py (path:/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/pip-OZHP28-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:44:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:44:25 INFO - Running setup.py (path:/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/pip-LN4tqw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:44:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:44:25 INFO - Running setup.py (path:/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/pip-bvwcw3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:44:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:44:25 INFO - Running setup.py (path:/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/pip-ng5FjR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:44:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:44:25 INFO - Running setup.py (path:/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/pip-vInTQ2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:44:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:44:25 INFO - Running setup.py (path:/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/pip-Aww7fZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:44:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:44:25 INFO - Running setup.py (path:/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/pip-bvbMxH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:44:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:44:25 INFO - Running setup.py (path:/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/pip-GKW_iw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:44:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:44:26 INFO - Running setup.py (path:/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/pip-hTBqYn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:44:26 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 15:44:26 INFO - Running setup.py install for manifestparser 15:44:26 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 15:44:26 INFO - Running setup.py install for mozcrash 15:44:26 INFO - Running setup.py install for mozdebug 15:44:26 INFO - Running setup.py install for mozdevice 15:44:26 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 15:44:26 INFO - Installing dm script to /builds/slave/test/build/venv/bin 15:44:26 INFO - Running setup.py install for mozfile 15:44:26 INFO - Running setup.py install for mozhttpd 15:44:27 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 15:44:27 INFO - Running setup.py install for mozinfo 15:44:27 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 15:44:27 INFO - Running setup.py install for mozInstall 15:44:27 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 15:44:27 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 15:44:27 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 15:44:27 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 15:44:27 INFO - Running setup.py install for mozleak 15:44:27 INFO - Running setup.py install for mozlog 15:44:27 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 15:44:27 INFO - Running setup.py install for moznetwork 15:44:27 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 15:44:27 INFO - Running setup.py install for mozprocess 15:44:27 INFO - Running setup.py install for mozprofile 15:44:28 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 15:44:28 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 15:44:28 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 15:44:28 INFO - Running setup.py install for mozrunner 15:44:28 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 15:44:28 INFO - Running setup.py install for mozscreenshot 15:44:28 INFO - Running setup.py install for moztest 15:44:28 INFO - Running setup.py install for mozversion 15:44:28 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 15:44:28 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 15:44:28 INFO - Cleaning up... 15:44:28 INFO - Return code: 0 15:44:28 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:44:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:44:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:44:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:44:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:44:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:44:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:44:28 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 0x10489cb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10489f768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9a51cc2180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10467ad30>, '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 0x1045a1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10466fbe8>, '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.rzPqNHbdQT/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.Vrd4veNHwd/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/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 15:44:28 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 15:44:28 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 15:44:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rzPqNHbdQT/Render', 15:44:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:44:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:44:28 INFO - 'HOME': '/Users/cltbld', 15:44:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:44:28 INFO - 'LOGNAME': 'cltbld', 15:44:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:44:28 INFO - 'MOZ_NO_REMOTE': '1', 15:44:28 INFO - 'NO_EM_RESTART': '1', 15:44:28 INFO - 'PAGER': '/bin/cat', 15:44:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:44:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:44:28 INFO - 'PWD': '/builds/slave/test', 15:44:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:44:28 INFO - 'SHELL': '/bin/bash', 15:44:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Vrd4veNHwd/Listeners', 15:44:28 INFO - 'TMPDIR': '/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/', 15:44:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:44:28 INFO - 'USER': 'cltbld', 15:44:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:44:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:44:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:44:28 INFO - 'XPC_FLAGS': '0x0', 15:44:28 INFO - 'XPC_SERVICE_NAME': '0', 15:44:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:44:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:44:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:44:29 INFO - Running setup.py (path:/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/pip-M8kSAd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:44:29 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)) 15:44:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:44:29 INFO - Running setup.py (path:/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/pip-R3IGmt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:44:29 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)) 15:44:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:44:29 INFO - Running setup.py (path:/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/pip-gfEw0D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:44:29 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)) 15:44:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:44:29 INFO - Running setup.py (path:/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/pip-QkIbCB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:44:29 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)) 15:44:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:44:29 INFO - Running setup.py (path:/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/pip-lg210a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:44:29 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)) 15:44:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:44:29 INFO - Running setup.py (path:/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/pip-l8_VVY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:44:29 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)) 15:44:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:44:29 INFO - Running setup.py (path:/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/pip-Nc_FiM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:44:29 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)) 15:44:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:44:29 INFO - Running setup.py (path:/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/pip-s3T638-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:44:29 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)) 15:44:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:44:29 INFO - Running setup.py (path:/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/pip-yJYKCU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:44:29 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)) 15:44:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:44:29 INFO - Running setup.py (path:/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/pip-CpdHFN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:44:30 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)) 15:44:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:44:30 INFO - Running setup.py (path:/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/pip-yfYup_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:44:30 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)) 15:44:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:44:30 INFO - Running setup.py (path:/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/pip-YUR4Xb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:44:30 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)) 15:44:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:44:30 INFO - Running setup.py (path:/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/pip-4nh5nq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:44:30 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)) 15:44:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:44:30 INFO - Running setup.py (path:/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/pip-QCUB0f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:44:30 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)) 15:44:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:44:30 INFO - Running setup.py (path:/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/pip-1nvjko-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:44:30 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)) 15:44:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:44:30 INFO - Running setup.py (path:/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/pip-i8OjYH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:44:30 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)) 15:44:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:44:30 INFO - Running setup.py (path:/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/pip-WKApH_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:44:30 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)) 15:44:30 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)) 15:44:30 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)) 15:44:30 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)) 15:44:30 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)) 15:44:30 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)) 15:44:30 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 15:44:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:44:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:44:30 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:44:30 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:44:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:44:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:44:33 INFO - Downloading blessings-1.6.tar.gz 15:44:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 15:44:33 INFO - Installing collected packages: blessings 15:44:33 INFO - Running setup.py install for blessings 15:44:33 INFO - Successfully installed blessings 15:44:33 INFO - Cleaning up... 15:44:33 INFO - Return code: 0 15:44:33 INFO - Done creating virtualenv /builds/slave/test/build/venv. 15:44:33 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:44:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:44:34 INFO - Reading from file tmpfile_stdout 15:44:34 INFO - Current package versions: 15:44:34 INFO - blessings == 1.6 15:44:34 INFO - blobuploader == 1.2.4 15:44:34 INFO - docopt == 0.6.1 15:44:34 INFO - functools32 == 3.2.3-2 15:44:34 INFO - jsonschema == 2.5.1 15:44:34 INFO - manifestparser == 1.1 15:44:34 INFO - mozInstall == 1.12 15:44:34 INFO - mozcrash == 1.0 15:44:34 INFO - mozdebug == 0.1 15:44:34 INFO - mozdevice == 0.49 15:44:34 INFO - mozfile == 1.2 15:44:34 INFO - mozhttpd == 0.7 15:44:34 INFO - mozinfo == 0.9 15:44:34 INFO - mozleak == 0.1 15:44:34 INFO - mozlog == 3.4 15:44:34 INFO - moznetwork == 0.27 15:44:34 INFO - mozprocess == 0.25 15:44:34 INFO - mozprofile == 0.28 15:44:34 INFO - mozrunner == 6.13 15:44:34 INFO - mozscreenshot == 0.1 15:44:34 INFO - mozsystemmonitor == 0.3 15:44:34 INFO - moztest == 0.8 15:44:34 INFO - mozversion == 1.4 15:44:34 INFO - psutil == 3.1.1 15:44:34 INFO - requests == 1.2.3 15:44:34 INFO - wsgiref == 0.1.2 15:44:34 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:44: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')]} 15:44:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:44:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:44: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')]} 15:44:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:44:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:44:34 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 0x10489cb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10489f768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9a51cc2180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10467ad30>, '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 0x1045a1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10466fbe8>, '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.rzPqNHbdQT/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.Vrd4veNHwd/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/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 15:44:34 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 15:44:34 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 15:44:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rzPqNHbdQT/Render', 15:44:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:44:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:44:34 INFO - 'HOME': '/Users/cltbld', 15:44:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:44:34 INFO - 'LOGNAME': 'cltbld', 15:44:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:44:34 INFO - 'MOZ_NO_REMOTE': '1', 15:44:34 INFO - 'NO_EM_RESTART': '1', 15:44:34 INFO - 'PAGER': '/bin/cat', 15:44:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:44:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:44:34 INFO - 'PWD': '/builds/slave/test', 15:44:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:44:34 INFO - 'SHELL': '/bin/bash', 15:44:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Vrd4veNHwd/Listeners', 15:44:34 INFO - 'TMPDIR': '/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/', 15:44:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:44:34 INFO - 'USER': 'cltbld', 15:44:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:44:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:44:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:44:34 INFO - 'XPC_FLAGS': '0x0', 15:44:34 INFO - 'XPC_SERVICE_NAME': '0', 15:44:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:44:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:44:34 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)) 15:44:34 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)) 15:44:34 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)) 15:44:34 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)) 15:44:34 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)) 15:44:34 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)) 15:44:34 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)) 15:44:34 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)) 15:44:34 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)) 15:44:34 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)) 15:44:34 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)) 15:44:34 INFO - Cleaning up... 15:44:34 INFO - Return code: 0 15:44:34 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 15:44: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')]} 15:44:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:44:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:44: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')]} 15:44:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:44:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:44:34 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 0x10489cb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10489f768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9a51cc2180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10467ad30>, '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 0x1045a1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10466fbe8>, '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.rzPqNHbdQT/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.Vrd4veNHwd/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/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 15:44:34 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 15:44:34 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 15:44:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rzPqNHbdQT/Render', 15:44:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:44:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:44:34 INFO - 'HOME': '/Users/cltbld', 15:44:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:44:34 INFO - 'LOGNAME': 'cltbld', 15:44:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:44:34 INFO - 'MOZ_NO_REMOTE': '1', 15:44:34 INFO - 'NO_EM_RESTART': '1', 15:44:34 INFO - 'PAGER': '/bin/cat', 15:44:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:44:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:44:34 INFO - 'PWD': '/builds/slave/test', 15:44:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:44:34 INFO - 'SHELL': '/bin/bash', 15:44:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Vrd4veNHwd/Listeners', 15:44:34 INFO - 'TMPDIR': '/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/', 15:44:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:44:34 INFO - 'USER': 'cltbld', 15:44:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:44:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:44:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:44:34 INFO - 'XPC_FLAGS': '0x0', 15:44:34 INFO - 'XPC_SERVICE_NAME': '0', 15:44:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:44:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:44:34 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 15:44:34 INFO - Cleaning up... 15:44:34 INFO - Return code: 0 15:44:34 INFO - Running post-action listener: _resource_record_post_action 15:44:34 INFO - Running post-action listener: _start_resource_monitoring 15:44:34 INFO - Starting resource monitoring. 15:44:34 INFO - [mozharness: 2017-04-12 22:44:34.591714Z] Finished create-virtualenv step (success) 15:44:34 INFO - [mozharness: 2017-04-12 22:44:34.592753Z] Running install step. 15:44:34 INFO - Running pre-action listener: _resource_record_pre_action 15:44:34 INFO - Running main action method: install 15:44:34 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:44:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:44:34 INFO - Reading from file tmpfile_stdout 15:44:34 INFO - Detecting whether we're running mozinstall >=1.0... 15:44:34 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 15:44:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 15:44:34 INFO - Reading from file tmpfile_stdout 15:44:34 INFO - Output received: 15:44:34 INFO - Usage: mozinstall [options] installer 15:44:34 INFO - Options: 15:44:34 INFO - -h, --help show this help message and exit 15:44:34 INFO - -d DEST, --destination=DEST 15:44:34 INFO - Directory to install application into. [default: 15:44:34 INFO - "/builds/slave/test"] 15:44:34 INFO - --app=APP Application being installed. [default: firefox] 15:44:34 INFO - mkdir: /builds/slave/test/build/application 15:44:34 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'] 15:44:34 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 15:44:54 INFO - Reading from file tmpfile_stdout 15:44:54 INFO - Output received: 15:44:54 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 15:44:54 INFO - Running post-action listener: _resource_record_post_action 15:44:54 INFO - [mozharness: 2017-04-12 22:44:54.895468Z] Finished install step (success) 15:44:54 INFO - [mozharness: 2017-04-12 22:44:54.895631Z] Running run-tests step. 15:44:54 INFO - Running pre-action listener: _resource_record_pre_action 15:44:54 INFO - Running main action method: run_tests 15:44:54 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 15:44:54 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 15:44:54 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 15:44:54 INFO - 2017-04-12 15:44:54.910 screenresolution[1624:7590] starting screenresolution argv=screenresolution get 15:44:54 INFO - 2017-04-12 15:44:54.912 screenresolution[1624:7590] Display 0: 1600x1200x32@60 15:44:54 INFO - 2017-04-12 15:44:54.918 screenresolution[1625:7593] starting screenresolution argv=screenresolution list 15:44:54 INFO - Available Modes on Display 0 15:44:54 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 15:44:54 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 15:44:54 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 15:44:54 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 15:44:55 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 15:44:55 INFO - Intel Iris: 15:44:55 INFO - Chipset Model: Intel Iris 15:44:55 INFO - Type: GPU 15:44:55 INFO - Bus: Built-In 15:44:55 INFO - VRAM (Dynamic, Max): 1536 MB 15:44:55 INFO - Vendor: Intel (0x8086) 15:44:55 INFO - Device ID: 0x0a2e 15:44:55 INFO - Revision ID: 0x0009 15:44:55 INFO - Displays: 15:44:55 INFO - Display: 15:44:55 INFO - Resolution: 1600 x 1200 @ 60 Hz 15:44:55 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 15:44:55 INFO - Display Serial Number: 1600x1200 60 15:44:55 INFO - Main Display: Yes 15:44:55 INFO - Mirror: Off 15:44:55 INFO - Online: Yes 15:44:55 INFO - Rotation: Supported 15:44:55 INFO - Return code: 0 15:44:55 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 15:44:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 15:44:55 INFO - Python 2.7.3 15:44:55 INFO - Return code: 0 15:44:55 INFO - grabbing minidump binary from tooltool 15:44:55 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')]} 15:44:55 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 0x10467ad30>, '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 0x1045a1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10466fbe8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 15:44:55 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 15:44:55 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 15:44:55 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 15:44:55 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 15:44:55 INFO - Return code: 0 15:44:55 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 15:44:55 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 15:44:55 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 15:44:55 INFO - ENV: RUST_BACKTRACE is now 1 15:44:55 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 15:44:55 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 15:44:55 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/M21nbwsWR0ihuc3xFXN83A/artifacts/public/build/firefox-54.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0332', '--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 15:44:55 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/M21nbwsWR0ihuc3xFXN83A/artifacts/public/build/firefox-54.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0332 --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 15:44:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rzPqNHbdQT/Render', 15:44:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:44:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:44:55 INFO - 'HOME': '/Users/cltbld', 15:44:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:44:55 INFO - 'LOGNAME': 'cltbld', 15:44:55 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 15:44:55 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 15:44:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:44:55 INFO - 'MOZ_NO_REMOTE': '1', 15:44:55 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 15:44:55 INFO - 'NO_EM_RESTART': '1', 15:44:55 INFO - 'PAGER': '/bin/cat', 15:44:55 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:44:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:44:55 INFO - 'PWD': '/builds/slave/test', 15:44:55 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 15:44:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:44:55 INFO - 'RUST_BACKTRACE': '1', 15:44:55 INFO - 'SHELL': '/bin/bash', 15:44:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Vrd4veNHwd/Listeners', 15:44:55 INFO - 'TMPDIR': '/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/', 15:44:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:44:55 INFO - 'USER': 'cltbld', 15:44:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:44:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:44:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:44:55 INFO - 'XPC_FLAGS': '0x0', 15:44:55 INFO - 'XPC_SERVICE_NAME': '0', 15:44:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:44:55 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/M21nbwsWR0ihuc3xFXN83A/artifacts/public/build/firefox-54.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0332', '--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 15:44:55 INFO - mozversion application_buildid: 20170412135108 15:44:55 INFO - mozversion application_changeset: cef7b5c139ef710202d2e95ec7b9ab84b9e9b2a2 15:44:55 INFO - mozversion application_display_name: FirefoxDeveloperEdition 15:44:55 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 15:44:55 INFO - mozversion application_name: Firefox 15:44:55 INFO - mozversion application_remotingname: firefox-dev 15:44:55 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 15:44:55 INFO - mozversion application_vendor: Mozilla 15:44:55 INFO - mozversion application_version: 54.0a2 15:44:55 INFO - mozversion platform_buildid: 20170412135108 15:44:55 INFO - mozversion platform_changeset: cef7b5c139ef710202d2e95ec7b9ab84b9e9b2a2 15:44:55 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 15:44:55 INFO - mozversion platform_version: 54.0a2 15:44:55 INFO - using testdate: 1492037095 15:44:55 INFO - actual date: 1492037095 15:44:55 INFO - starting webserver on 'localhost:49247' 15:44:55 INFO - SUITE-START | Running 2 tests 15:44:55 INFO - TEST-START | dromaeo_css 15:44:55 INFO - operating with platform_type : mac_ 15:44:55 INFO - Initialising browser for dromaeo_css test... 15:44:55 INFO - TEST-INFO | started process 1635 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/tmpwkxYrc/profile http://localhost:49247/getInfo.html) 15:44:58 INFO - PROCESS | 1635 | __metrics Screen width/height:1600/1200 15:44:58 INFO - PROCESS | 1635 | colorDepth:24 15:44:58 INFO - PROCESS | 1635 | Browser inner width/height: 1024/689 15:44:58 INFO - PROCESS | 1635 | __metrics 15:44:58 INFO - PROCESS | 1635 | 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" 15:44:58 INFO - PROCESS | 1635 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 15:44:59 INFO - TEST-INFO | 1635: exit 0 15:44:59 INFO - Browser initialized. 15:44:59 INFO - Running cycle 1/1 for dromaeo_css test... 15:44:59 INFO - TEST-INFO | started process 1637 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/tmpwkxYrc/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 15:45:11 INFO - PROCESS | 1637 | RSS: Main: 215011328 15:45:11 INFO - PROCESS | 1637 | 15:48:15 INFO - PROCESS | 1637 | Cycle 1(1): loaded http://localhost:49247/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49247/tests/dromaeo/cssquery-ext.html) 15:48:17 INFO - PROCESS | 1637 | RSS: Main: 395055104 15:48:17 INFO - PROCESS | 1637 | 15:50:01 INFO - PROCESS | 1637 | ************************* 15:50:01 INFO - PROCESS | 1637 | A coding exception was thrown and uncaught in a Task. 15:50:01 INFO - PROCESS | 1637 | 15:50:01 ERROR - PROCESS | 1637 | Full message: TypeError: NetworkError when attempting to fetch resource. 15:50:01 INFO - PROCESS | 1637 | Full stack: 15:50:01 INFO - PROCESS | 1637 | ************************* 15:50:01 INFO - PROCESS | 1637 | ************************* 15:50:01 INFO - PROCESS | 1637 | A coding exception was thrown and uncaught in a Task. 15:50:01 INFO - PROCESS | 1637 | 15:50:01 ERROR - PROCESS | 1637 | Full message: TypeError: NetworkError when attempting to fetch resource. 15:50:01 INFO - PROCESS | 1637 | Full stack: 15:50:01 INFO - PROCESS | 1637 | ************************* 15:50:01 INFO - PROCESS | 1637 | ************************* 15:50:01 INFO - PROCESS | 1637 | A coding exception was thrown and uncaught in a Task. 15:50:01 INFO - PROCESS | 1637 | 15:50:01 ERROR - PROCESS | 1637 | Full message: TypeError: NetworkError when attempting to fetch resource. 15:50:01 INFO - PROCESS | 1637 | Full stack: 15:50:01 INFO - PROCESS | 1637 | ************************* 15:50:01 INFO - PROCESS | 1637 | ************************* 15:50:01 INFO - PROCESS | 1637 | A coding exception was thrown and uncaught in a Task. 15:50:01 INFO - PROCESS | 1637 | 15:50:01 ERROR - PROCESS | 1637 | Full message: TypeError: NetworkError when attempting to fetch resource. 15:50:01 INFO - PROCESS | 1637 | Full stack: 15:50:01 INFO - PROCESS | 1637 | ************************* 15:51:20 INFO - PROCESS | 1637 | Cycle 1(1): loaded http://localhost:49247/tests/dromaeo/cssquery-ext.html (next: http://localhost:49247/tests/dromaeo/cssquery-jquery.html) 15:51:20 INFO - PROCESS | 1637 | RSS: Main: 349155328 15:51:20 INFO - PROCESS | 1637 | 15:54:23 INFO - PROCESS | 1637 | Cycle 1(1): loaded http://localhost:49247/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49247/tests/dromaeo/cssquery-mootools.html) 15:54:23 INFO - PROCESS | 1637 | RSS: Main: 339963904 15:54:23 INFO - PROCESS | 1637 | 15:57:26 INFO - PROCESS | 1637 | Cycle 1(1): loaded http://localhost:49247/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49247/tests/dromaeo/cssquery-prototype.html) 15:57:27 INFO - PROCESS | 1637 | RSS: Main: 320061440 15:57:27 INFO - PROCESS | 1637 | 16:00:30 INFO - PROCESS | 1637 | Cycle 1(1): loaded http://localhost:49247/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49247/tests/dromaeo/cssquery-yui.html) 16:00:31 INFO - PROCESS | 1637 | RSS: Main: 320811008 16:00:31 INFO - PROCESS | 1637 | 16:03:34 INFO - PROCESS | 1637 | Cycle 1(1): loaded http://localhost:49247/tests/dromaeo/cssquery-yui.html (next: http://localhost:49247/tests/dromaeo/cssquery-dojo.html) 16:03:34 INFO - PROCESS | 1637 | RSS: Main: 428023808 16:03:34 INFO - PROCESS | 1637 | 16:03:34 INFO - PROCESS | 1637 | __start_tp_report 16:03:34 INFO - PROCESS | 1637 | _x_x_mozilla_page_load 16:03:34 INFO - PROCESS | 1637 | _x_x_mozilla_page_load_details 16:03:34 INFO - PROCESS | 1637 | |i|pagename|runs| 16:03:34 INFO - PROCESS | 1637 | |0;dojo.html;2833.249188964924;3348.6818752218533;3381.898543043708;3420.9144771380697;3450.9137271568206;4876.951230487694;5944.197533332998;5970.522358211343;5977;5986.670733109677;5887.558433117522;5953.613015154012;5954.285485741717;5970.492508136803;5978;5307.787688492465;5360.624756267063;5364.624476286662;5402.243685883979;5424.945750542503;5246.895062098756;5274;5319.175527793198;5439.619226654136;5450.5639548836;5358.705271210082;5398.163284690879;5415.810446634368;5443.1835224716215;5464.153056276284;5418.485243901823;5425.077736784717;5425.9186112208345;5431.972840135813;5442.727863606804;5185.507376799198;5307.442718514578;5383.246345511631;5385.057614917413;5433.945660543383;5808.796692115756;5818.767249310023;5828.358880523139;5924.170616113747;6005.099235114724;6351.460125889305;6414.166158399425;6438.517111216677;6441.710123044474;6446.742130314782;6449.742010319582;6492.610443373413;6498.707558159894;6503.739850405978;6506.869862602722;5829.533637309005;5877.471027607536;6056.364081771439;6146.323904370515;6160.1375807387;6383.776567820104;6386.616802991835;6488.610683358919;6549.214094308713;6622.635755033423;6328.272248691459;6338.619682819046;6341.746330146742;6343.0485427186295;6350.491960643138;6356.459700925473;6434.034894765729;6455.580387274812;6460.612363258219;6461.7738379157445;6237.220347456568;6240.063990401476;6291.370862913764;6350.904736428957;6366.299707032223;6251.906221406683;6359.427651511293;6364.7135878885565;6365.14070600463;6373.8725225496155;5069.670471419346;5118.488151184852;5123.359580052494;5136.5890728741615;5153.123968925292;4460.977695111504;4464.1071785643;4550.904161381005;4563.995920897397;4584.381108550369;4903.975480122576;5960.37416071315;6005.189299444606;6062.605930614496;6066.969665151734;6389.520785941073;6420.967895160495;6428.067930150196;6433.613983161025;6458.128152699325;6008.759649614054;6009.098635204667;6036.758529658764;6037;6045.939540604626;5453.890922181614;5469.480399362054;5564.721763911788;5600.747966341524;5643.746031428595;6621.404073633396;6635.834104147339;6666.266710661726;6678.532502724763;6689.2976237496305;6424.550281480439;6438.195225596801;6451.0968464415955;6455.2576453708425;6456.386643268882;5465.7813687453645;6352.237731472253;6380.744770209325;6600.53796234259;6707.228668702966;5275.551578115903;6269.717862696099;6309.305976342691;6335.90496142549;6353.809385718436;5634.267545219095;6282.308946016026;6322.652254126067;6351.96824015877;6380.266269379081;6160.630362178284;6229.813105606839;6305.96847015762;6315.684215789284;6318.05229215603;5572.359178694502;6264.342244064308;6289.150964619717;6303.401176888372;6305.558610897193;5283.392409872914;6063.514918806397;6064.818055458343;6072.666003369857;6078.756849725962;6212.347703491068;6224.533160013108;6234.937650623436;6263.4362907338555;6277.843053923506;6255.687215639291;6256.593321434274;6264.68676566179;6278.497720182466;6294.653794041188 16:03:34 INFO - PROCESS | 1637 | |1;ext.html;789.6801795272914;811.5901469757771;856.503228127686;881.2729498164016;884;38695.613043869555;39380.60619393809;39492.81521554351;39501.8024909875;39593.80203098984;32392.838035809847;40509.797451012775;40542.7972860136;40632.796836015776;40644.18711625766;7209.963950180242;9011.639534418615;9171;9173.128552787508;9190.264778817713;7207.5675459472595;10934.125269978422;10965.232433729641;11071.058959988412;11096.00135987761;10902.890971090306;10927;10946.945265273702;10960;10980.780384392347;10878.347299162037;10924.2899211551;10925;10936.726581835439;10939.671809845717;7124.14510258767;7290.088738907637;10445.895541044569;10714.46427678613;10894.67315980522;38242.617573824464;38245.85262442104;38354.89048102353;38381.61618383809;38539.61460385389;3770.2722809106003;3835.5780864104927;3845.7348295021156;3848.1149335652676;3871.55477120132;27003.1899043029;27450.313742156406;28767.849286028533;28811.42377152466;28837;3020.9546856797165;3049.344390955933;3059.7552195824283;3208.679132086796;3276.410246155691;350004;351213;351706.99999999744;353048;353642;51976.700582484984;52209.69475763136;52209.738951305;52273.47726522762;52318;54215.45784542183;54299.18551221735;54330.45669543254;54370;54420.727896360266;14507.202103884176;14609.853901461061;14617.269136543344;14627.488037918834;14646.194459304841;12677.239365638092;12679.619611411672;12688.04839637031;12690.111692181643;12692.365381730877;8028.718994835152;8736.8689469658;9574.04259574051;11538.78842721519;11802.232854864405;4573.474050484237;5378.650387724785;5409.6213265071065;5430.647007944471;5470.56235501159;1071.3571856885962;1085.137315833914;1093.8960798724108;1104.718296834302;1143.1712008793559;21945.341639750834;22510.66234006491;26375.868120659274;26968.460630787275;26991.460170796476;1149.9137564682683;1172.3200543684643;1179.0920990837178;1194.5460724924474;1203.939803009846;13292;13619.319034048258;13920.930395348161;13930.303484825718;13961.86038139626;412774.9361253175;415733;416170.83829161926;416519;417678;53340;53423.732881335345;53456.46543534593;53504.19743703848;53910.65223369369;1392.394308475796;1457.1839769728774;1459.160982435104;1461.8391976882747;1472.0578829549097;20830.583388332452;27819;30315.69684303217;31011.534826977164;31025.534616980312;50398;50704.23943640775;50764;50789.238161428344;50846;464098;464160.67919660185;464442;464523;467209;28263.15210543687;28785.712142878303;29890;29909.850450747606;33620.327593448485;36544.63455365413;37334.439983400815;37883.62116378801;37929.81035094917;38210.04474888039;27452.17643470699;27506.724932750418;28089.859550702116;28320.575191371732;28836.423271534033;26988.460230795667;27852.72147278501;27923.581146282417;28102.2974425641;28154.71845281603;31105.37789244157;31161.688383115878;31637.8418107908;31789.523157152198;31796.728130874504;27074.72925270801;27373.58939615947;27939.16182514528;28033.159005229874;28415.857920710263;21605.78394216038;22356.204147364602;22475.5504889898;22640.207592734176;22695 16:03:34 INFO - PROCESS | 1637 | |2;jquery.html;391.745365512417;394.2784703991695;415.27327177439463;430.52642093696926;464.11354313261677;12927.741445171103;14650.926745366272;20812.687809682866;20869.791302086993;21405;22226.88886555565;22443.887780561075;22498.98754556045;23378;23678.88160559195;6121.632702037869;6289.685515724218;6722;6792.286809884965;6800.965995170017;5475.972620136894;6203.782867599635;6548.246951600556;6667.733290668368;6673.399394054534;6699.732010719566;6700.430463410592;6710.261871194165;6714.362135597111;6718.563293385939;6490.643014634194;6610.140681711371;6633.767818126343;6688.5986840789465;6691.899621505681;6437.806865794034;6499.252585952629;6535.607863528204;6549.9520076787485;6550.246721627026;23988.88005559961;24282.514349712907;24921.875390622932;27317.317067073483;28027.29931751702;3012.3523442459978;3013.969860301391;3014.3669829335868;3026.3493348929865;3051;7338.229485903957;7371.594562299071;7371.852562948765;15404.614884627861;16183.352665893468;1708.0349602474687;1973.516488460322;2011.1251605551631;2028.4421784009369;2042.2137477071285;106140.46929765302;125827.11259331118;131906;143927;146988.79516807257;16693.165341733107;17101.657966840594;20557.280495182596;21765;22431.654100754007;36478.905632831054;36865;37247.88256352314;37360.81319593439;37380.62619373826;10313.174946004301;10525.684229473129;10745.677629671123;10778.784424311627;10925.962033606795;8729.301655867515;8734.995475520269;8764.912350876537;8929.374943753875;8934.28525717941;10189;10197.286189966631;10306.587736490457;10419.843702344471;10457.477126143662;3738.8035828534607;4186.874393768192;4201.264778663722;4273.18809426208;4284.1645879053285;797.57728403946;912.9543522823832;913.1690161952724;923.7875288683506;929.1451864284874;21747.89126054391;22152.113915443202;22323.776762232494;22363.65818050922;22381.664275035888;939.8778158839444;956.5791051937125;960.9855852162223;968.6851773173747;978.4373984958677;10869;11676.824847627293;11837.408129593658;11875.406229688653;11921.165518413627;736690;747103;758587;765672;765697;131228;133706.66293337138;134000;134049;140665;1257.7421628566121;1275.4706796679275;1295.2940647347218;1299.909006369545;1300.1419063418098;18872.867627942367;19906.60186796285;19913;19931;19983.60032799365;45517.08965820731;56720.14919776124;58412.70793646004;59055;59127.70436147792;69042.61914761575;72440;76082.61958690171;78041;80514.79227811705;7637.656305466378;9338.39300445496;11905;12218.26690398579;12433.751324973631;13189.538366157138;13292.601221963356;13294.933525332312;13744.65638359049;13754.105983111065;11453;11671.824922625998;11741.29552226869;11926.403679816149;11952.34262115592;7937.32532734724;10650.254482186172;10711.25021248505;10713.089387402159;10879.564817407216;11643.417829108681;11772.05823534098;11791.469383877919;11797.469113889723;11884.76230475403;12019.338936358585;12140.089493288215;12220.755584888075;12258;12259.632211033684;11367.772644546898;11404.600838970597;11488;11607.419629018346;11794.29234245948 16:03:34 INFO - PROCESS | 1637 | |3;mootools.html;1337.9197248165117;1545.8917875748702;1562.406285611468;1575.9448419305322;1577.574055005149;9177.770555736102;9593.760155996104;9695.224382049428;9696.078872507118;9706.272029597772;9527.761805954855;10560.735981600465;10903.01872831445;11046.889531104687;11071.335719856814;2354.19957214547;2394.0543485323287;2394.174009966559;2395.209580838319;2398.256540472459;2256.8307376946714;2372.7627237276306;2384.2727967969763;2399.1602938971396;2399.4841109161525;1987.46338488608;2080.282302605606;2080.583883223354;2083.510375061867;2086.7182930304443;2054.27073388947;2292.793648571628;2395.964060539093;2404.5671779079757;2406.446517301013;2145.9785402145935;2249.055396733376;2367.112332875172;2375.5961486547158;2391.4619210677633;9266.397684150508;11101.44492775358;11168.66494005181;11215.439228038565;11240.325580465198;4929.580985616227;5712.971435142839;5842.036064049426;5845.620034697731;5872.354041055481;3477.878274260389;3567.126054116732;3589.546233775325;3659.5791483979415;3663.761855479412;1198.5565340823864;1200.3938011304235;1210.5278941212935;1214.3806658604087;1238.969025774354;15470;15484;15607.843921560867;15609.687806243925;15631.609209769733;4647.847863592371;4823.807047718052;4853.635977301717;4880.853574392774;4915.827946021872;12781.744365112645;12786.552470663668;12801.103922725506;12807.743845123045;12810.80783788244;5792.189093526909;5825.533957283407;5865.589408741347;5906.5570082244;5918;5168.457311982263;5226.608004399609;5270.868228294246;5286.07493688603;5289.6032797539565;3937.3897045957924;4004.939925901114;4025.8662908658457;4081.877543673635;4093.201825643971;1123.1407972900738;1124.4771181400688;1129.170060005895;1136.5283407385841;1144.164759725405;820.0897004325194;945.3477100800563;950.0594411532533;959.1080295325414;999.4702807512031;5927.940720592739;6548.3124271950755;6626.734930602818;6761.391474767294;6820.829479263058;1026.645807196516;1042.6976176908763;1054.0882136951482;1056.7199692081604;1114.6767437443214;4147.543770185277;4164.875053748392;4261.936070958938;4441.733495990251;4480.305552639346;13444.395002224728;14086.788698169536;14092.084014539023;14095.154290742588;14102.576922692335;13804.17174969505;13816.792748108786;13861.930690346484;14053.648658783612;14122.71754564903;10098.889122196553;10121.392716437038;10160.847587286196;10342.37945723244;10358.482075896325;5526.91709624364;6523.054157147092;6646.83382915431;6650.667466626746;6792.117024786944;11957;12338.13633045714;12347;12485.56300529477;12501.56244531437;10315.587376504856;12438.502459901865;12454.937725311316;12586.559470418491;12647.304398258184;9234.307426942873;9309.348345616012;9340.81318373615;9401.200897923753;9453.054694530492;10149.695509134737;10247.487625618838;10359;10362.689119326433;10371.222158338003;8775.956120219358;8807.779805504913;8817.955910220408;8871.955640221757;8949.105089490999;7576.62116894142;8322.791930201793;8647.56762161902;8681.045085040767;8683.609237584451;7874.291313781787;7894.881576776238;8010.519368837889;8026.558539280396;8028.157043510348;5602.243697101002;5911.379305173067;5923.970380148072;5996.040633498619;6047.123167140652;5808.593398462236;5902.586818922634;5907.645541267538;6115.724792384264;6245.843853903689 16:03:34 INFO - PROCESS | 1637 | |4;prototype.html;366.1395720057864;372.64226342711;373.54054512949057;386.15432203474387;387.82547853465945;4409.625181859542;4588.54114588541;4983.277424773406;5186.974065129673;5273.024490469261;4227.408162857202;4778.450478195009;4948.406191257045;5061.519155680205;5080.0347933892535;4091.24312002279;4106.1462283712835;4310.870673879781;4375.365571992059;4412.492563355223;3781.5840257571645;3877.864274750384;3938.1490699371393;4224.091820258644;4231.280682284004;3608.350496910555;4208.305629571117;4259.723117997336;4299;4308.784560771965;4273.974246180926;4294.871153865373;4295.957040429587;4300.440942677432;4321.570390349885;3389.6101948275773;3436;3823.866164684223;3867.2652196082654;3925.941110883339;4418.5802348776815;4504.684672072956;4521.937344723987;4826.300186472977;4995.353392023843;4685.554872287127;4881.219004959225;4892.9510704892855;4943.456219815817;4948.554630083274;5221.605956128509;5456.836294911159;5513.917291240635;5519.75805443776;5520.475554822286;3626.6373362663785;3771.585125636178;4352.7823608819435;4385.868423947287;4397.626201772853;5155.819546315936;5278.434154456368;5492.368377636583;5679.403662615448;5814.767409303623;3608.6391360863704;3812.6187381262207;4235.8093885775215;4253.361995700608;4301.741895486281;3913.96086039136;4074.7351422157467;4201.285781417106;4310.051788606502;4595.862124136282;4883.926741098886;4886.755662216875;4912.312276281324;4932.755596203719;4983.9252411213865;4734.905301893943;5225.204515584144;5405.7837686492885;5437.429069947678;5491.780328786804;1258.5406326690693;1454.4909281751513;1477.6527516033723;1489.0321291160833;1537.2621141851973;1103.332483847277;1139.629620373382;1152.9192956493132;1162.7848847963153;1170.011340417346;4488.618467430239;5077.283653245496;5104.443144840785;5109.5437800226755;5119.590432765444;4893.486183950705;5045.974770126126;5496.74633400304;5593.720313984284;5681.545476361881;4887.486813884562;4900.2359150705715;4928;5069.417017043087;5124.026434977342;2650.9204723858315;2679.4641071785722;2934.662513810939;2947.5283954567167;2958.704129587024;5552.167174923794;5811.476967072984;5879.735411906389;5933.6736479493175;5953.374895635982;4704.411948506437;4940.580050695659;5070.172020124782;5206.297149884793;5562.330081560874;5393.5954803389905;5422.875382763251;5446.1558458439;5494.148406996922;5549.861253468695;4469.949561852897;5224.320838290998;5329.935287209947;5564.721763911869;5568.939492387843;4856.465788763163;5543.307086614173;5550.666959982414;5566.970110529565;5586.357568879631;5087.287779710918;5427.530473634566;5571.554275658019;5663.017943719665;5788.942110578841;3975.741576797499;4305.8901138441315;4336.9349459757495;4349.086691794759;4353.608175264241;4930.348333308382;4933.753312334297;5177.870553236199;5420.777481232676;5466.699331536644;4671.486136524912;4901.534354236342;5076.771545280545;5315.441878602691;5422.728863556886;4561.521040290721;4956.132676781621;5056.64603477753;5243.737813109253;5464.9180262296895;4741.155306815883;4962.379702537183;5190.55880250183;5345.305110335631;5374.838754837361;4282.63597594208;5359.839204823861;5526.309211348582;5548.334199896039;5570.470805273492;5009.9479109385975;5175.48988740453;5214.765335559833;5423.511883930465;5448.373437054789 16:03:34 INFO - PROCESS | 1637 | |5;yui.html;716.6810769207705;737.3327138939262;773.3735674103976;828.4697793412217;835.669910385398;13325.733485330287;15109;15948.28232729527;15973.920130399347;16032.278547465363;14034.649133771636;14116.57650270491;14168.716625667481;14212.431502739904;14215;917.1470532404877;929.1498279074639;959.1894848852743;969.4619486185164;985.7486340983075;980.4460479828874;982.3712823792742;983.2576404814355;984.8522721591747;986.5905649155592;1026.240581969341;1030.6907927621721;1031.9019693129142;1033.1166852341253;1033.565902321027;1022.161827301613;1022.5807418958266;1023.3092662452815;1024.119257438606;1024.7540590258284;761.9961900190464;762.5882023707192;763.4999075605488;763.5609524523421;768.9038870141233;13203.339833008311;15782.684346313124;16105.033697978042;16152.596185095466;16639.584010399714;13311.267880266581;14844.109353438827;14998.62503437412;15002.24988750558;15004.699906001819;10801.351918884813;11198.608048718337;11390.03184729299;11768.293902365886;11973.40132993347;3676.283124790667;3751.924961500782;3768.171002379472;3799.772013679188;3943.4084887266854;104307;108606.45696771465;116870.41564792207;119036;120583;10243.94878025605;10769.407682577377;11624.186306958602;11923.880761192277;12057;25847.61228581573;25937.832797524155;26097.478050438884;26110.086146984766;26137.869310653325;6812;8003.839923201504;8069.515829050276;8080.19198080199;8281.751547453587;4230.640395566411;4308.224519586442;4332.176886391576;4400.251957167289;4481.932771008437;3574.696150827157;3581.76718513301;3645.453182022665;3648.1426864686778;3666.651668091527;573.8335882594084;641.4163111568544;641.4547634510628;658.1772784019975;663.2405895250008;652.546480196259;673.3233100733731;681.3629256645053;691.4330249195712;710.4458522352573;8372.958135209285;9610.06589106874;9763.658271960447;9998.900010999942;10541.631042913461;654.6693919570681;679.8482801931831;692.1901375390805;706.5654622407183;728.2826415980255;4756;4843.571146511717;4864.738597337021;4892.804287828518;4897.461279259279;77255.61372193103;91231.54384228037;94256;95448;96861;82744;84520.57739711262;86472.13527864766;89375.55312223398;90078.54960725286;55983.160252596244;70202;77993;79594.20405795868;79970.6001469989;9485.288603354915;9494.857577136487;9502.667406640734;9536.284778641491;9562.282828788007;50330;50449.49550504448;50540.98918021692;51384.48615513797;51538.22692659538;64506;64554;66602.66698666669;67127.66436167787;67332.65334693181;11627.418629068681;11807.527698891949;11882.227655202385;12064.336461494351;12220.51117955272;12311.876881231432;12413.503459861866;12663.430145643284;12902.935485322514;13487.595372138852;11711.472983715923;12235;12238.571649992207;12280.81578776337;12517.93741031289;11720;12148;12148.757024859276;12537.749245014866;12551.93724031374;11187.21689481761;11506.94246528762;11516.790736972498;12011.459484323052;12229.266244025386;8580.399372043898;8707.564621769014;8782.91217087821;8840.911590884009;8940.910590894007;9797.608095676092;10176.418568166468;10232.386056836614;10289.9485502572;10404.635837745642 16:03:34 INFO - PROCESS | 1637 | __end_tp_report 16:03:34 INFO - PROCESS | 1637 | __start_cc_report 16:03:34 INFO - PROCESS | 1637 | _x_x_mozilla_cycle_collect,2092 16:03:34 INFO - PROCESS | 1637 | __end_cc_report 16:03:34 INFO - PROCESS | 1637 | __startTimestamp1492038214864__endTimestamp 16:03:34 INFO - PROCESS | 1637 | 16:03:34 INFO - PROCESS | 1637 | ------- Summary: start ------- 16:03:34 INFO - PROCESS | 1637 | Number of tests: 6 16:03:34 INFO - PROCESS | 1637 | 16:03:34 INFO - PROCESS | 1637 | [#0] dojo.html Cycles:165 Average:5896.38 Median:6146.32 stddev:691.94 (11.3%) stddev-sans-first:651.00 16:03:34 INFO - PROCESS | 1637 | Values: 2833.2 3348.7 3381.9 3420.9 3450.9 4877.0 5944.2 5970.5 5977.0 5986.7 5887.6 5953.6 5954.3 5970.5 5978.0 5307.8 5360.6 5364.6 5402.2 5424.9 5246.9 5274.0 5319.2 5439.6 5450.6 5358.7 5398.2 5415.8 5443.2 5464.2 5418.5 5425.1 5425.9 5432.0 5442.7 5185.5 5307.4 5383.2 5385.1 5433.9 5808.8 5818.8 5828.4 5924.2 6005.1 6351.5 6414.2 6438.5 6441.7 6446.7 6449.7 6492.6 6498.7 6503.7 6506.9 5829.5 5877.5 6056.4 6146.3 6160.1 6383.8 6386.6 6488.6 6549.2 6622.6 6328.3 6338.6 6341.7 6343.0 6350.5 6356.5 6434.0 6455.6 6460.6 6461.8 6237.2 6240.1 6291.4 6350.9 6366.3 6251.9 6359.4 6364.7 6365.1 6373.9 5069.7 5118.5 5123.4 5136.6 5153.1 4461.0 4464.1 4550.9 4564.0 4584.4 4904.0 5960.4 6005.2 6062.6 6067.0 6389.5 6421.0 6428.1 6433.6 6458.1 6008.8 6009.1 6036.8 6037.0 6045.9 5453.9 5469.5 5564.7 5600.7 5643.7 6621.4 6635.8 6666.3 6678.5 6689.3 6424.6 6438.2 6451.1 6455.3 6456.4 5465.8 6352.2 6380.7 6600.5 6707.2 5275.6 6269.7 6309.3 6335.9 6353.8 5634.3 6282.3 6322.7 6352.0 6380.3 6160.6 6229.8 6306.0 6315.7 6318.1 5572.4 6264.3 6289.2 6303.4 6305.6 5283.4 6063.5 6064.8 6072.7 6078.8 6212.3 6224.5 6234.9 6263.4 6277.8 6255.7 6256.6 6264.7 6278.5 6294.7 16:03:34 INFO - PROCESS | 1637 | 16:03:34 INFO - PROCESS | 1637 | [#1] ext.html Cycles:180 Average:54565.40 Median:31065.46 stddev:109713.65 (353.2%) stddev-sans-first:109947.14 16:03:34 INFO - PROCESS | 1637 | Values: 789.7 811.6 856.5 881.3 884.0 38695.6 39380.6 39492.8 39501.8 39593.8 32392.8 40509.8 40542.8 40632.8 40644.2 7210.0 9011.6 9171.0 9173.1 9190.3 7207.6 10934.1 10965.2 11071.1 11096.0 10902.9 10927.0 10946.9 10960.0 10980.8 10878.3 10924.3 10925.0 10936.7 10939.7 7124.1 7290.1 10445.9 10714.5 10894.7 38242.6 38245.9 38354.9 38381.6 38539.6 3770.3 3835.6 3845.7 3848.1 3871.6 27003.2 27450.3 28767.8 28811.4 28837.0 3021.0 3049.3 3059.8 3208.7 3276.4 350004.0 351213.0 351707.0 353048.0 353642.0 51976.7 52209.7 52209.7 52273.5 52318.0 54215.5 54299.2 54330.5 54370.0 54420.7 14507.2 14609.9 14617.3 14627.5 14646.2 12677.2 12679.6 12688.0 12690.1 12692.4 8028.7 8736.9 9574.0 11538.8 11802.2 4573.5 5378.7 5409.6 5430.6 5470.6 1071.4 1085.1 1093.9 1104.7 1143.2 21945.3 22510.7 26375.9 26968.5 26991.5 1149.9 1172.3 1179.1 1194.5 1203.9 13292.0 13619.3 13920.9 13930.3 13961.9 412774.9 415733.0 416170.8 416519.0 417678.0 53340.0 53423.7 53456.5 53504.2 53910.7 1392.4 1457.2 1459.2 1461.8 1472.1 20830.6 27819.0 30315.7 31011.5 31025.5 50398.0 50704.2 50764.0 50789.2 50846.0 464098.0 464160.7 464442.0 464523.0 467209.0 28263.2 28785.7 29890.0 29909.9 33620.3 36544.6 37334.4 37883.6 37929.8 38210.0 27452.2 27506.7 28089.9 28320.6 28836.4 26988.5 27852.7 27923.6 28102.3 28154.7 31105.4 31161.7 31637.8 31789.5 31796.7 27074.7 27373.6 27939.2 28033.2 28415.9 21605.8 22356.2 22475.6 22640.2 22695.0 16:03:34 INFO - PROCESS | 1637 | 16:03:34 INFO - PROCESS | 1637 | [#2] jquery.html Cycles:180 Average:41553.47 Median:22471.44 stddev:124835.69 (555.5%) stddev-sans-first:125147.63 16:03:34 INFO - PROCESS | 1637 | Values: 391.7 394.3 415.3 430.5 464.1 12927.7 14650.9 20812.7 20869.8 21405.0 22226.9 22443.9 22499.0 23378.0 23678.9 6121.6 6289.7 6722.0 6792.3 6801.0 5476.0 6203.8 6548.2 6667.7 6673.4 6699.7 6700.4 6710.3 6714.4 6718.6 6490.6 6610.1 6633.8 6688.6 6691.9 6437.8 6499.3 6535.6 6550.0 6550.2 23988.9 24282.5 24921.9 27317.3 28027.3 3012.4 3014.0 3014.4 3026.3 3051.0 7338.2 7371.6 7371.9 15404.6 16183.4 1708.0 1973.5 2011.1 2028.4 2042.2 106140.5 125827.1 131906.0 143927.0 146988.8 16693.2 17101.7 20557.3 21765.0 22431.7 36478.9 36865.0 37247.9 37360.8 37380.6 10313.2 10525.7 10745.7 10778.8 10926.0 8729.3 8735.0 8764.9 8929.4 8934.3 10189.0 10197.3 10306.6 10419.8 10457.5 3738.8 4186.9 4201.3 4273.2 4284.2 797.6 913.0 913.2 923.8 929.1 21747.9 22152.1 22323.8 22363.7 22381.7 939.9 956.6 961.0 968.7 978.4 10869.0 11676.8 11837.4 11875.4 11921.2 736690.0 747103.0 758587.0 765672.0 765697.0 131228.0 133706.7 134000.0 134049.0 140665.0 1257.7 1275.5 1295.3 1299.9 1300.1 18872.9 19906.6 19913.0 19931.0 19983.6 45517.1 56720.1 58412.7 59055.0 59127.7 69042.6 72440.0 76082.6 78041.0 80514.8 7637.7 9338.4 11905.0 12218.3 12433.8 13189.5 13292.6 13294.9 13744.7 13754.1 11453.0 11671.8 11741.3 11926.4 11952.3 7937.3 10650.3 10711.3 10713.1 10879.6 11643.4 11772.1 11791.5 11797.5 11884.8 12019.3 12140.1 12220.8 12258.0 12259.6 11367.8 11404.6 11488.0 11607.4 11794.3 16:03:34 INFO - PROCESS | 1637 | 16:03:34 INFO - PROCESS | 1637 | [#3] mootools.html Cycles:180 Average:6732.17 Median:2402.03 stddev:4253.25 (177.1%) stddev-sans-first:4245.86 16:03:34 INFO - PROCESS | 1637 | Values: 1337.9 1545.9 1562.4 1575.9 1577.6 9177.8 9593.8 9695.2 9696.1 9706.3 9527.8 10560.7 10903.0 11046.9 11071.3 2354.2 2394.1 2394.2 2395.2 2398.3 2256.8 2372.8 2384.3 2399.2 2399.5 1987.5 2080.3 2080.6 2083.5 2086.7 2054.3 2292.8 2396.0 2404.6 2406.4 2146.0 2249.1 2367.1 2375.6 2391.5 9266.4 11101.4 11168.7 11215.4 11240.3 4929.6 5713.0 5842.0 5845.6 5872.4 3477.9 3567.1 3589.5 3659.6 3663.8 1198.6 1200.4 1210.5 1214.4 1239.0 15470.0 15484.0 15607.8 15609.7 15631.6 4647.8 4823.8 4853.6 4880.9 4915.8 12781.7 12786.6 12801.1 12807.7 12810.8 5792.2 5825.5 5865.6 5906.6 5918.0 5168.5 5226.6 5270.9 5286.1 5289.6 3937.4 4004.9 4025.9 4081.9 4093.2 1123.1 1124.5 1129.2 1136.5 1144.2 820.1 945.3 950.1 959.1 999.5 5927.9 6548.3 6626.7 6761.4 6820.8 1026.6 1042.7 1054.1 1056.7 1114.7 4147.5 4164.9 4261.9 4441.7 4480.3 13444.4 14086.8 14092.1 14095.2 14102.6 13804.2 13816.8 13861.9 14053.6 14122.7 10098.9 10121.4 10160.8 10342.4 10358.5 5526.9 6523.1 6646.8 6650.7 6792.1 11957.0 12338.1 12347.0 12485.6 12501.6 10315.6 12438.5 12454.9 12586.6 12647.3 9234.3 9309.3 9340.8 9401.2 9453.1 10149.7 10247.5 10359.0 10362.7 10371.2 8776.0 8807.8 8818.0 8872.0 8949.1 7576.6 8322.8 8647.6 8681.0 8683.6 7874.3 7894.9 8010.5 8026.6 8028.2 5602.2 5911.4 5924.0 5996.0 6047.1 5808.6 5902.6 5907.6 6115.7 6245.8 16:03:34 INFO - PROCESS | 1637 | 16:03:34 INFO - PROCESS | 1637 | [#4] prototype.html Cycles:180 Average:4499.16 Median:4906.92 stddev:1253.91 (25.6%) stddev-sans-first:1218.45 16:03:34 INFO - PROCESS | 1637 | Values: 366.1 372.6 373.5 386.2 387.8 4409.6 4588.5 4983.3 5187.0 5273.0 4227.4 4778.5 4948.4 5061.5 5080.0 4091.2 4106.1 4310.9 4375.4 4412.5 3781.6 3877.9 3938.1 4224.1 4231.3 3608.4 4208.3 4259.7 4299.0 4308.8 4274.0 4294.9 4296.0 4300.4 4321.6 3389.6 3436.0 3823.9 3867.3 3925.9 4418.6 4504.7 4521.9 4826.3 4995.4 4685.6 4881.2 4893.0 4943.5 4948.6 5221.6 5456.8 5513.9 5519.8 5520.5 3626.6 3771.6 4352.8 4385.9 4397.6 5155.8 5278.4 5492.4 5679.4 5814.8 3608.6 3812.6 4235.8 4253.4 4301.7 3914.0 4074.7 4201.3 4310.1 4595.9 4883.9 4886.8 4912.3 4932.8 4983.9 4734.9 5225.2 5405.8 5437.4 5491.8 1258.5 1454.5 1477.7 1489.0 1537.3 1103.3 1139.6 1152.9 1162.8 1170.0 4488.6 5077.3 5104.4 5109.5 5119.6 4893.5 5046.0 5496.7 5593.7 5681.5 4887.5 4900.2 4928.0 5069.4 5124.0 2650.9 2679.5 2934.7 2947.5 2958.7 5552.2 5811.5 5879.7 5933.7 5953.4 4704.4 4940.6 5070.2 5206.3 5562.3 5393.6 5422.9 5446.2 5494.1 5549.9 4469.9 5224.3 5329.9 5564.7 5568.9 4856.5 5543.3 5550.7 5567.0 5586.4 5087.3 5427.5 5571.6 5663.0 5788.9 3975.7 4305.9 4336.9 4349.1 4353.6 4930.3 4933.8 5177.9 5420.8 5466.7 4671.5 4901.5 5076.8 5315.4 5422.7 4561.5 4956.1 5056.6 5243.7 5464.9 4741.2 4962.4 5190.6 5345.3 5374.8 4282.6 5359.8 5526.3 5548.3 5570.5 5009.9 5175.5 5214.8 5423.5 5448.4 16:03:34 INFO - PROCESS | 1637 | 16:03:34 INFO - PROCESS | 1637 | [#5] yui.html Cycles:180 Average:20013.25 Median:4269.43 stddev:28867.22 (676.1%) stddev-sans-first:28911.84 16:03:34 INFO - PROCESS | 1637 | Values: 716.7 737.3 773.4 828.5 835.7 13325.7 15109.0 15948.3 15973.9 16032.3 14034.6 14116.6 14168.7 14212.4 14215.0 917.1 929.1 959.2 969.5 985.7 980.4 982.4 983.3 984.9 986.6 1026.2 1030.7 1031.9 1033.1 1033.6 1022.2 1022.6 1023.3 1024.1 1024.8 762.0 762.6 763.5 763.6 768.9 13203.3 15782.7 16105.0 16152.6 16639.6 13311.3 14844.1 14998.6 15002.2 15004.7 10801.4 11198.6 11390.0 11768.3 11973.4 3676.3 3751.9 3768.2 3799.8 3943.4 104307.0 108606.5 116870.4 119036.0 120583.0 10243.9 10769.4 11624.2 11923.9 12057.0 25847.6 25937.8 26097.5 26110.1 26137.9 6812.0 8003.8 8069.5 8080.2 8281.8 4230.6 4308.2 4332.2 4400.3 4481.9 3574.7 3581.8 3645.5 3648.1 3666.7 573.8 641.4 641.5 658.2 663.2 652.5 673.3 681.4 691.4 710.4 8373.0 9610.1 9763.7 9998.9 10541.6 654.7 679.8 692.2 706.6 728.3 4756.0 4843.6 4864.7 4892.8 4897.5 77255.6 91231.5 94256.0 95448.0 96861.0 82744.0 84520.6 86472.1 89375.6 90078.5 55983.2 70202.0 77993.0 79594.2 79970.6 9485.3 9494.9 9502.7 9536.3 9562.3 50330.0 50449.5 50541.0 51384.5 51538.2 64506.0 64554.0 66602.7 67127.7 67332.7 11627.4 11807.5 11882.2 12064.3 12220.5 12311.9 12413.5 12663.4 12902.9 13487.6 11711.5 12235.0 12238.6 12280.8 12517.9 11720.0 12148.0 12148.8 12537.7 12551.9 11187.2 11506.9 11516.8 12011.5 12229.3 8580.4 8707.6 8782.9 8840.9 8940.9 9797.6 10176.4 10232.4 10289.9 10404.6 16:03:34 INFO - PROCESS | 1637 | -------- Summary: end -------- 16:03:34 INFO - PROCESS | 1637 | 16:03:35 INFO - TEST-INFO | 1637: exit 0 16:03:35 INFO - TEST-OK | dromaeo_css | took 1120031ms 16:03:35 INFO - TEST-START | kraken 16:03:35 INFO - operating with platform_type : mac_ 16:03:35 INFO - Initialising browser for kraken test... 16:03:35 INFO - TEST-INFO | started process 1646 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/tmpdY61r8/profile http://localhost:49247/getInfo.html) 16:03:37 INFO - PROCESS | 1646 | __metrics Screen width/height:1600/1200 16:03:37 INFO - PROCESS | 1646 | colorDepth:24 16:03:37 INFO - PROCESS | 1646 | Browser inner width/height: 1024/689 16:03:37 INFO - PROCESS | 1646 | __metrics 16:03:38 INFO - PROCESS | 1646 | 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" 16:03:38 INFO - PROCESS | 1646 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 16:03:38 INFO - TEST-INFO | 1646: exit 0 16:03:38 INFO - Browser initialized. 16:03:38 INFO - Running cycle 1/1 for kraken test... 16:03:38 INFO - TEST-INFO | started process 1647 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/tmpdY61r8/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:03:50 INFO - PROCESS | 1647 | RSS: Main: 214822912 16:03:50 INFO - PROCESS | 1647 | 16:03:53 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49247/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49247/tests/kraken/driver.html?testName=audio-beat-detection) 16:03:54 INFO - PROCESS | 1647 | RSS: Main: 267513856 16:03:54 INFO - PROCESS | 1647 | 16:03:57 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49247/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49247/tests/kraken/driver.html?testName=audio-dft) 16:03:58 INFO - PROCESS | 1647 | RSS: Main: 278867968 16:03:58 INFO - PROCESS | 1647 | 16:04:01 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49247/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49247/tests/kraken/driver.html?testName=audio-fft) 16:04:01 INFO - PROCESS | 1647 | RSS: Main: 375361536 16:04:01 INFO - PROCESS | 1647 | 16:04:03 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49247/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49247/tests/kraken/driver.html?testName=audio-oscillator) 16:04:04 INFO - PROCESS | 1647 | RSS: Main: 278913024 16:04:04 INFO - PROCESS | 1647 | 16:04:06 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49247/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49247/tests/kraken/driver.html?testName=imaging-gaussian-blur) 16:04:07 INFO - PROCESS | 1647 | RSS: Main: 280727552 16:04:07 INFO - PROCESS | 1647 | 16:04:09 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49247/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49247/tests/kraken/driver.html?testName=imaging-darkroom) 16:04:10 INFO - PROCESS | 1647 | RSS: Main: 320163840 16:04:10 INFO - PROCESS | 1647 | 16:04:13 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49247/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49247/tests/kraken/driver.html?testName=imaging-desaturate) 16:04:14 INFO - PROCESS | 1647 | RSS: Main: 320299008 16:04:14 INFO - PROCESS | 1647 | 16:04:17 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49247/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49247/tests/kraken/driver.html?testName=json-parse-financial) 16:04:17 INFO - PROCESS | 1647 | RSS: Main: 319148032 16:04:17 INFO - PROCESS | 1647 | 16:04:19 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49247/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49247/tests/kraken/driver.html?testName=json-stringify-tinderbox) 16:04:20 INFO - PROCESS | 1647 | RSS: Main: 294432768 16:04:20 INFO - PROCESS | 1647 | 16:04:21 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49247/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49247/tests/kraken/driver.html?testName=stanford-crypto-aes) 16:04:22 INFO - PROCESS | 1647 | RSS: Main: 300568576 16:04:22 INFO - PROCESS | 1647 | 16:04:24 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49247/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49247/tests/kraken/driver.html?testName=stanford-crypto-ccm) 16:04:25 INFO - PROCESS | 1647 | RSS: Main: 303546368 16:04:25 INFO - PROCESS | 1647 | 16:04:28 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49247/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49247/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 16:04:29 INFO - PROCESS | 1647 | RSS: Main: 321658880 16:04:29 INFO - PROCESS | 1647 | 16:04:31 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49247/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49247/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 16:04:32 INFO - PROCESS | 1647 | RSS: Main: 314458112 16:04:32 INFO - PROCESS | 1647 | 16:04:34 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49247/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49247/tests/kraken/driver.html?testName=ai-astar) 16:04:34 INFO - PROCESS | 1647 | RSS: Main: 358957056 16:04:34 INFO - PROCESS | 1647 | 16:04:34 INFO - PROCESS | 1647 | __start_tp_report 16:04:34 INFO - PROCESS | 1647 | _x_x_mozilla_page_load 16:04:34 INFO - PROCESS | 1647 | _x_x_mozilla_page_load_details 16:04:34 INFO - PROCESS | 1647 | |i|pagename|runs| 16:04:34 INFO - PROCESS | 1647 | |0;ai-astar;115;115;115;115;115;115;118;163;169;114 16:04:34 INFO - PROCESS | 1647 | |1;audio-beat-detection;143;108;106;162;104;114;135;136;135;138 16:04:34 INFO - PROCESS | 1647 | |2;audio-dft;185;184;186;184;186;186;186;183;185;186 16:04:34 INFO - PROCESS | 1647 | |3;audio-fft;84;63;62;91;63;63;61;58;75;61 16:04:34 INFO - PROCESS | 1647 | |4;audio-oscillator;90;70;89;70;70;69;84;69;70;84 16:04:34 INFO - PROCESS | 1647 | |5;imaging-gaussian-blur;86;85;86;86;85;85;86;86;85;86 16:04:34 INFO - PROCESS | 1647 | |6;imaging-darkroom;92;108;151;93;93;94;94;94;94;93 16:04:34 INFO - PROCESS | 1647 | |7;imaging-desaturate;83;85;87;86;83;84;86;84;84;86 16:04:34 INFO - PROCESS | 1647 | |8;json-parse-financial;66;64;60;62;64;65;66;64;63;64 16:04:34 INFO - PROCESS | 1647 | |9;json-stringify-tinderbox;60;55;61;63;63;61;59;59;57;59 16:04:34 INFO - PROCESS | 1647 | |10;stanford-crypto-aes;84;86;83;84;83;89;85;84;83;90 16:04:34 INFO - PROCESS | 1647 | |11;stanford-crypto-ccm;137;136;135;137;131;140;156;140;210;142 16:04:34 INFO - PROCESS | 1647 | |12;stanford-crypto-pbkdf2;165;168;164;165;161;162;165;165;167;164 16:04:34 INFO - PROCESS | 1647 | |13;stanford-crypto-sha256-iterative;65;66;67;64;75;80;78;67;65;64 16:04:34 INFO - PROCESS | 1647 | __end_tp_report 16:04:34 INFO - PROCESS | 1647 | __start_cc_report 16:04:34 INFO - PROCESS | 1647 | _x_x_mozilla_cycle_collect,2147 16:04:34 INFO - PROCESS | 1647 | __end_cc_report 16:04:34 INFO - PROCESS | 1647 | __startTimestamp1492038274646__endTimestamp 16:04:34 INFO - PROCESS | 1647 | 16:04:34 INFO - PROCESS | 1647 | ------- Summary: start ------- 16:04:34 INFO - PROCESS | 1647 | Number of tests: 14 16:04:34 INFO - PROCESS | 1647 | 16:04:34 INFO - PROCESS | 1647 | [#0] ai-astar Cycles:10 Average:125.40 Median:115.00 stddev:21.47 (18.7%) stddev-sans-first:22.44 16:04:34 INFO - PROCESS | 1647 | Values: 115.0 115.0 115.0 115.0 115.0 115.0 118.0 163.0 169.0 114.0 16:04:34 INFO - PROCESS | 1647 | 16:04:34 INFO - PROCESS | 1647 | [#1] audio-beat-detection Cycles:10 Average:128.10 Median:135.50 stddev:19.15 (14.1%) stddev-sans-first:19.53 16:04:34 INFO - PROCESS | 1647 | Values: 143.0 108.0 106.0 162.0 104.0 114.0 135.0 136.0 135.0 138.0 16:04:34 INFO - PROCESS | 1647 | 16:04:34 INFO - PROCESS | 1647 | [#2] audio-dft Cycles:10 Average:185.10 Median:186.00 stddev:1.10 (0.6%) stddev-sans-first:1.17 16:04:34 INFO - PROCESS | 1647 | Values: 185.0 184.0 186.0 184.0 186.0 186.0 186.0 183.0 185.0 186.0 16:04:34 INFO - PROCESS | 1647 | 16:04:34 INFO - PROCESS | 1647 | [#3] audio-fft Cycles:10 Average:68.10 Median:63.00 stddev:11.27 (17.9%) stddev-sans-first:10.38 16:04:34 INFO - PROCESS | 1647 | Values: 84.0 63.0 62.0 91.0 63.0 63.0 61.0 58.0 75.0 61.0 16:04:34 INFO - PROCESS | 1647 | 16:04:34 INFO - PROCESS | 1647 | [#4] audio-oscillator Cycles:10 Average:76.50 Median:77.00 stddev:9.02 (11.7%) stddev-sans-first:8.14 16:04:34 INFO - PROCESS | 1647 | Values: 90.0 70.0 89.0 70.0 70.0 69.0 84.0 69.0 70.0 84.0 16:04:34 INFO - PROCESS | 1647 | 16:04:34 INFO - PROCESS | 1647 | [#5] imaging-gaussian-blur Cycles:10 Average:85.60 Median:86.00 stddev:0.52 (0.6%) stddev-sans-first:0.53 16:04:34 INFO - PROCESS | 1647 | Values: 86.0 85.0 86.0 86.0 85.0 85.0 86.0 86.0 85.0 86.0 16:04:34 INFO - PROCESS | 1647 | 16:04:34 INFO - PROCESS | 1647 | [#6] imaging-darkroom Cycles:10 Average:100.60 Median:93.50 stddev:18.31 (19.6%) stddev-sans-first:19.15 16:04:34 INFO - PROCESS | 1647 | Values: 92.0 108.0 151.0 93.0 93.0 94.0 94.0 94.0 94.0 93.0 16:04:34 INFO - PROCESS | 1647 | 16:04:34 INFO - PROCESS | 1647 | [#7] imaging-desaturate Cycles:10 Average:84.80 Median:85.50 stddev:1.40 (1.6%) stddev-sans-first:1.32 16:04:34 INFO - PROCESS | 1647 | Values: 83.0 85.0 87.0 86.0 83.0 84.0 86.0 84.0 84.0 86.0 16:04:34 INFO - PROCESS | 1647 | 16:04:34 INFO - PROCESS | 1647 | [#8] json-parse-financial Cycles:10 Average:63.80 Median:64.00 stddev:1.81 (2.8%) stddev-sans-first:1.74 16:04:34 INFO - PROCESS | 1647 | Values: 66.0 64.0 60.0 62.0 64.0 65.0 66.0 64.0 63.0 64.0 16:04:34 INFO - PROCESS | 1647 | 16:04:34 INFO - PROCESS | 1647 | [#9] json-stringify-tinderbox Cycles:10 Average:59.70 Median:60.50 stddev:2.50 (4.1%) stddev-sans-first:2.65 16:04:34 INFO - PROCESS | 1647 | Values: 60.0 55.0 61.0 63.0 63.0 61.0 59.0 59.0 57.0 59.0 16:04:34 INFO - PROCESS | 1647 | 16:04:34 INFO - PROCESS | 1647 | [#10] stanford-crypto-aes Cycles:10 Average:85.10 Median:84.50 stddev:2.51 (3.0%) stddev-sans-first:2.64 16:04:34 INFO - PROCESS | 1647 | Values: 84.0 86.0 83.0 84.0 83.0 89.0 85.0 84.0 83.0 90.0 16:04:34 INFO - PROCESS | 1647 | 16:04:34 INFO - PROCESS | 1647 | [#11] stanford-crypto-ccm Cycles:10 Average:146.40 Median:140.00 stddev:23.31 (16.7%) stddev-sans-first:24.48 16:04:34 INFO - PROCESS | 1647 | Values: 137.0 136.0 135.0 137.0 131.0 140.0 156.0 140.0 210.0 142.0 16:04:34 INFO - PROCESS | 1647 | 16:04:34 INFO - PROCESS | 1647 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:164.60 Median:165.00 stddev:2.07 (1.3%) stddev-sans-first:2.19 16:04:34 INFO - PROCESS | 1647 | Values: 165.0 168.0 164.0 165.0 161.0 162.0 165.0 165.0 167.0 164.0 16:04:34 INFO - PROCESS | 1647 | 16:04:34 INFO - PROCESS | 1647 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:69.10 Median:67.00 stddev:6.12 (9.1%) stddev-sans-first:6.31 16:04:34 INFO - PROCESS | 1647 | Values: 65.0 66.0 67.0 64.0 75.0 80.0 78.0 67.0 65.0 64.0 16:04:34 INFO - PROCESS | 1647 | -------- Summary: end -------- 16:04:34 INFO - PROCESS | 1647 | 16:04:35 INFO - TEST-INFO | 1647: exit 0 16:04:35 INFO - TEST-OK | kraken | took 60069ms 16:04:35 INFO - SUITE-END | took 1180s 16:04:35 INFO - Completed test suite (00:19:40) 16:04:35 INFO - javascript benchmark 16:04:35 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": [], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7599.904674044854, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2833.249188964924, 3348.6818752218533, 3381.898543043708, 3420.9144771380697, 3450.9137271568206, 4876.951230487694, 5944.197533332998, 5970.522358211343, 5977.0, 5986.670733109677, 5887.558433117522, 5953.613015154012, 5954.285485741717, 5970.492508136803, 5978.0, 5307.787688492465, 5360.624756267063, 5364.624476286662, 5402.243685883979, 5424.945750542503, 5246.895062098756, 5274.0, 5319.175527793198, 5439.619226654136, 5450.5639548836, 5358.705271210082, 5398.163284690879, 5415.810446634368, 5443.1835224716215, 5464.153056276284, 5418.485243901823, 5425.077736784717, 5425.9186112208345, 5431.972840135813, 5442.727863606804, 5185.507376799198, 5307.442718514578, 5383.246345511631, 5385.057614917413, 5433.945660543383, 5808.796692115756, 5818.767249310023, 5828.358880523139, 5924.170616113747, 6005.099235114724, 6351.460125889305, 6414.166158399425, 6438.517111216677, 6441.710123044474, 6446.742130314782, 6449.742010319582, 6492.610443373413, 6498.707558159894, 6503.739850405978, 6506.869862602722, 5829.533637309005, 5877.471027607536, 6056.364081771439, 6146.323904370515, 6160.1375807387, 6383.776567820104, 6386.616802991835, 6488.610683358919, 6549.214094308713, 6622.635755033423, 6328.272248691459, 6338.619682819046, 6341.746330146742, 6343.0485427186295, 6350.491960643138, 6356.459700925473, 6434.034894765729, 6455.580387274812, 6460.612363258219, 6461.7738379157445, 6237.220347456568, 6240.063990401476, 6291.370862913764, 6350.904736428957, 6366.299707032223, 6251.906221406683, 6359.427651511293, 6364.7135878885565, 6365.14070600463, 6373.8725225496155, 5069.670471419346, 5118.488151184852, 5123.359580052494, 5136.5890728741615, 5153.123968925292, 4460.977695111504, 4464.1071785643, 4550.904161381005, 4563.995920897397, 4584.381108550369, 4903.975480122576, 5960.37416071315, 6005.189299444606, 6062.605930614496, 6066.969665151734, 6389.520785941073, 6420.967895160495, 6428.067930150196, 6433.613983161025, 6458.128152699325, 6008.759649614054, 6009.098635204667, 6036.758529658764, 6037.0, 6045.939540604626, 5453.890922181614, 5469.480399362054, 5564.721763911788, 5600.747966341524, 5643.746031428595, 6621.404073633396, 6635.834104147339, 6666.266710661726, 6678.532502724763, 6689.2976237496305, 6424.550281480439, 6438.195225596801, 6451.0968464415955, 6455.2576453708425, 6456.386643268882, 5465.7813687453645, 6352.237731472253, 6380.744770209325, 6600.53796234259, 6707.228668702966, 5275.551578115903, 6269.717862696099, 6309.305976342691, 6335.90496142549, 6353.809385718436, 5634.267545219095, 6282.308946016026, 6322.652254126067, 6351.96824015877, 6380.266269379081, 6160.630362178284, 6229.813105606839, 6305.96847015762, 6315.684215789284, 6318.05229215603, 5572.359178694502, 6264.342244064308, 6289.150964619717, 6303.401176888372, 6305.558610897193, 5283.392409872914, 6063.514918806397, 6064.818055458343, 6072.666003369857, 6078.756849725962, 6212.347703491068, 6224.533160013108, 6234.937650623436, 6263.4362907338555, 6277.843053923506, 6255.687215639291, 6256.593321434274, 6264.68676566179, 6278.497720182466, 6294.653794041188], "value": 5850.565242368839, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [789.6801795272914, 811.5901469757771, 856.503228127686, 881.2729498164016, 884.0, 38695.613043869555, 39380.60619393809, 39492.81521554351, 39501.8024909875, 39593.80203098984, 32392.838035809847, 40509.797451012775, 40542.7972860136, 40632.796836015776, 40644.18711625766, 7209.963950180242, 9011.639534418615, 9171.0, 9173.128552787508, 9190.264778817713, 7207.5675459472595, 10934.125269978422, 10965.232433729641, 11071.058959988412, 11096.00135987761, 10902.890971090306, 10927.0, 10946.945265273702, 10960.0, 10980.780384392347, 10878.347299162037, 10924.2899211551, 10925.0, 10936.726581835439, 10939.671809845717, 7124.14510258767, 7290.088738907637, 10445.895541044569, 10714.46427678613, 10894.67315980522, 38242.617573824464, 38245.85262442104, 38354.89048102353, 38381.61618383809, 38539.61460385389, 3770.2722809106003, 3835.5780864104927, 3845.7348295021156, 3848.1149335652676, 3871.55477120132, 27003.1899043029, 27450.313742156406, 28767.849286028533, 28811.42377152466, 28837.0, 3020.9546856797165, 3049.344390955933, 3059.7552195824283, 3208.679132086796, 3276.410246155691, 350004.0, 351213.0, 351706.99999999744, 353048.0, 353642.0, 51976.700582484984, 52209.69475763136, 52209.738951305, 52273.47726522762, 52318.0, 54215.45784542183, 54299.18551221735, 54330.45669543254, 54370.0, 54420.727896360266, 14507.202103884176, 14609.853901461061, 14617.269136543344, 14627.488037918834, 14646.194459304841, 12677.239365638092, 12679.619611411672, 12688.04839637031, 12690.111692181643, 12692.365381730877, 8028.718994835152, 8736.8689469658, 9574.04259574051, 11538.78842721519, 11802.232854864405, 4573.474050484237, 5378.650387724785, 5409.6213265071065, 5430.647007944471, 5470.56235501159, 1071.3571856885962, 1085.137315833914, 1093.8960798724108, 1104.718296834302, 1143.1712008793559, 21945.341639750834, 22510.66234006491, 26375.868120659274, 26968.460630787275, 26991.460170796476, 1149.9137564682683, 1172.3200543684643, 1179.0920990837178, 1194.5460724924474, 1203.939803009846, 13292.0, 13619.319034048258, 13920.930395348161, 13930.303484825718, 13961.86038139626, 412774.9361253175, 415733.0, 416170.83829161926, 416519.0, 417678.0, 53340.0, 53423.732881335345, 53456.46543534593, 53504.19743703848, 53910.65223369369, 1392.394308475796, 1457.1839769728774, 1459.160982435104, 1461.8391976882747, 1472.0578829549097, 20830.583388332452, 27819.0, 30315.69684303217, 31011.534826977164, 31025.534616980312, 50398.0, 50704.23943640775, 50764.0, 50789.238161428344, 50846.0, 464098.0, 464160.67919660185, 464442.0, 464523.0, 467209.0, 28263.15210543687, 28785.712142878303, 29890.0, 29909.850450747606, 33620.327593448485, 36544.63455365413, 37334.439983400815, 37883.62116378801, 37929.81035094917, 38210.04474888039, 27452.17643470699, 27506.724932750418, 28089.859550702116, 28320.575191371732, 28836.423271534033, 26988.460230795667, 27852.72147278501, 27923.581146282417, 28102.2974425641, 28154.71845281603, 31105.37789244157, 31161.688383115878, 31637.8418107908, 31789.523157152198, 31796.728130874504, 27074.72925270801, 27373.58939615947, 27939.16182514528, 28033.159005229874, 28415.857920710263, 21605.78394216038, 22356.204147364602, 22475.5504889898, 22640.207592734176, 22695.0], "value": 18272.118984862802, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [391.745365512417, 394.2784703991695, 415.27327177439463, 430.52642093696926, 464.11354313261677, 12927.741445171103, 14650.926745366272, 20812.687809682866, 20869.791302086993, 21405.0, 22226.88886555565, 22443.887780561075, 22498.98754556045, 23378.0, 23678.88160559195, 6121.632702037869, 6289.685515724218, 6722.0, 6792.286809884965, 6800.965995170017, 5475.972620136894, 6203.782867599635, 6548.246951600556, 6667.733290668368, 6673.399394054534, 6699.732010719566, 6700.430463410592, 6710.261871194165, 6714.362135597111, 6718.563293385939, 6490.643014634194, 6610.140681711371, 6633.767818126343, 6688.5986840789465, 6691.899621505681, 6437.806865794034, 6499.252585952629, 6535.607863528204, 6549.9520076787485, 6550.246721627026, 23988.88005559961, 24282.514349712907, 24921.875390622932, 27317.317067073483, 28027.29931751702, 3012.3523442459978, 3013.969860301391, 3014.3669829335868, 3026.3493348929865, 3051.0, 7338.229485903957, 7371.594562299071, 7371.852562948765, 15404.614884627861, 16183.352665893468, 1708.0349602474687, 1973.516488460322, 2011.1251605551631, 2028.4421784009369, 2042.2137477071285, 106140.46929765302, 125827.11259331118, 131906.0, 143927.0, 146988.79516807257, 16693.165341733107, 17101.657966840594, 20557.280495182596, 21765.0, 22431.654100754007, 36478.905632831054, 36865.0, 37247.88256352314, 37360.81319593439, 37380.62619373826, 10313.174946004301, 10525.684229473129, 10745.677629671123, 10778.784424311627, 10925.962033606795, 8729.301655867515, 8734.995475520269, 8764.912350876537, 8929.374943753875, 8934.28525717941, 10189.0, 10197.286189966631, 10306.587736490457, 10419.843702344471, 10457.477126143662, 3738.8035828534607, 4186.874393768192, 4201.264778663722, 4273.18809426208, 4284.1645879053285, 797.57728403946, 912.9543522823832, 913.1690161952724, 923.7875288683506, 929.1451864284874, 21747.89126054391, 22152.113915443202, 22323.776762232494, 22363.65818050922, 22381.664275035888, 939.8778158839444, 956.5791051937125, 960.9855852162223, 968.6851773173747, 978.4373984958677, 10869.0, 11676.824847627293, 11837.408129593658, 11875.406229688653, 11921.165518413627, 736690.0, 747103.0, 758587.0, 765672.0, 765697.0, 131228.0, 133706.66293337138, 134000.0, 134049.0, 140665.0, 1257.7421628566121, 1275.4706796679275, 1295.2940647347218, 1299.909006369545, 1300.1419063418098, 18872.867627942367, 19906.60186796285, 19913.0, 19931.0, 19983.60032799365, 45517.08965820731, 56720.14919776124, 58412.70793646004, 59055.0, 59127.70436147792, 69042.61914761575, 72440.0, 76082.61958690171, 78041.0, 80514.79227811705, 7637.656305466378, 9338.39300445496, 11905.0, 12218.26690398579, 12433.751324973631, 13189.538366157138, 13292.601221963356, 13294.933525332312, 13744.65638359049, 13754.105983111065, 11453.0, 11671.824922625998, 11741.29552226869, 11926.403679816149, 11952.34262115592, 7937.32532734724, 10650.254482186172, 10711.25021248505, 10713.089387402159, 10879.564817407216, 11643.417829108681, 11772.05823534098, 11791.469383877919, 11797.469113889723, 11884.76230475403, 12019.338936358585, 12140.089493288215, 12220.755584888075, 12258.0, 12259.632211033684, 11367.772644546898, 11404.600838970597, 11488.0, 11607.419629018346, 11794.29234245948], "value": 11329.359166270508, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1337.9197248165117, 1545.8917875748702, 1562.406285611468, 1575.9448419305322, 1577.574055005149, 9177.770555736102, 9593.760155996104, 9695.224382049428, 9696.078872507118, 9706.272029597772, 9527.761805954855, 10560.735981600465, 10903.01872831445, 11046.889531104687, 11071.335719856814, 2354.19957214547, 2394.0543485323287, 2394.174009966559, 2395.209580838319, 2398.256540472459, 2256.8307376946714, 2372.7627237276306, 2384.2727967969763, 2399.1602938971396, 2399.4841109161525, 1987.46338488608, 2080.282302605606, 2080.583883223354, 2083.510375061867, 2086.7182930304443, 2054.27073388947, 2292.793648571628, 2395.964060539093, 2404.5671779079757, 2406.446517301013, 2145.9785402145935, 2249.055396733376, 2367.112332875172, 2375.5961486547158, 2391.4619210677633, 9266.397684150508, 11101.44492775358, 11168.66494005181, 11215.439228038565, 11240.325580465198, 4929.580985616227, 5712.971435142839, 5842.036064049426, 5845.620034697731, 5872.354041055481, 3477.878274260389, 3567.126054116732, 3589.546233775325, 3659.5791483979415, 3663.761855479412, 1198.5565340823864, 1200.3938011304235, 1210.5278941212935, 1214.3806658604087, 1238.969025774354, 15470.0, 15484.0, 15607.843921560867, 15609.687806243925, 15631.609209769733, 4647.847863592371, 4823.807047718052, 4853.635977301717, 4880.853574392774, 4915.827946021872, 12781.744365112645, 12786.552470663668, 12801.103922725506, 12807.743845123045, 12810.80783788244, 5792.189093526909, 5825.533957283407, 5865.589408741347, 5906.5570082244, 5918.0, 5168.457311982263, 5226.608004399609, 5270.868228294246, 5286.07493688603, 5289.6032797539565, 3937.3897045957924, 4004.939925901114, 4025.8662908658457, 4081.877543673635, 4093.201825643971, 1123.1407972900738, 1124.4771181400688, 1129.170060005895, 1136.5283407385841, 1144.164759725405, 820.0897004325194, 945.3477100800563, 950.0594411532533, 959.1080295325414, 999.4702807512031, 5927.940720592739, 6548.3124271950755, 6626.734930602818, 6761.391474767294, 6820.829479263058, 1026.645807196516, 1042.6976176908763, 1054.0882136951482, 1056.7199692081604, 1114.6767437443214, 4147.543770185277, 4164.875053748392, 4261.936070958938, 4441.733495990251, 4480.305552639346, 13444.395002224728, 14086.788698169536, 14092.084014539023, 14095.154290742588, 14102.576922692335, 13804.17174969505, 13816.792748108786, 13861.930690346484, 14053.648658783612, 14122.71754564903, 10098.889122196553, 10121.392716437038, 10160.847587286196, 10342.37945723244, 10358.482075896325, 5526.91709624364, 6523.054157147092, 6646.83382915431, 6650.667466626746, 6792.117024786944, 11957.0, 12338.13633045714, 12347.0, 12485.56300529477, 12501.56244531437, 10315.587376504856, 12438.502459901865, 12454.937725311316, 12586.559470418491, 12647.304398258184, 9234.307426942873, 9309.348345616012, 9340.81318373615, 9401.200897923753, 9453.054694530492, 10149.695509134737, 10247.487625618838, 10359.0, 10362.689119326433, 10371.222158338003, 8775.956120219358, 8807.779805504913, 8817.955910220408, 8871.955640221757, 8949.105089490999, 7576.62116894142, 8322.791930201793, 8647.56762161902, 8681.045085040767, 8683.609237584451, 7874.291313781787, 7894.881576776238, 8010.519368837889, 8026.558539280396, 8028.157043510348, 5602.243697101002, 5911.379305173067, 5923.970380148072, 5996.040633498619, 6047.123167140652, 5808.593398462236, 5902.586818922634, 5907.645541267538, 6115.724792384264, 6245.843853903689], "value": 5129.6890227013455, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [366.1395720057864, 372.64226342711, 373.54054512949057, 386.15432203474387, 387.82547853465945, 4409.625181859542, 4588.54114588541, 4983.277424773406, 5186.974065129673, 5273.024490469261, 4227.408162857202, 4778.450478195009, 4948.406191257045, 5061.519155680205, 5080.0347933892535, 4091.24312002279, 4106.1462283712835, 4310.870673879781, 4375.365571992059, 4412.492563355223, 3781.5840257571645, 3877.864274750384, 3938.1490699371393, 4224.091820258644, 4231.280682284004, 3608.350496910555, 4208.305629571117, 4259.723117997336, 4299.0, 4308.784560771965, 4273.974246180926, 4294.871153865373, 4295.957040429587, 4300.440942677432, 4321.570390349885, 3389.6101948275773, 3436.0, 3823.866164684223, 3867.2652196082654, 3925.941110883339, 4418.5802348776815, 4504.684672072956, 4521.937344723987, 4826.300186472977, 4995.353392023843, 4685.554872287127, 4881.219004959225, 4892.9510704892855, 4943.456219815817, 4948.554630083274, 5221.605956128509, 5456.836294911159, 5513.917291240635, 5519.75805443776, 5520.475554822286, 3626.6373362663785, 3771.585125636178, 4352.7823608819435, 4385.868423947287, 4397.626201772853, 5155.819546315936, 5278.434154456368, 5492.368377636583, 5679.403662615448, 5814.767409303623, 3608.6391360863704, 3812.6187381262207, 4235.8093885775215, 4253.361995700608, 4301.741895486281, 3913.96086039136, 4074.7351422157467, 4201.285781417106, 4310.051788606502, 4595.862124136282, 4883.926741098886, 4886.755662216875, 4912.312276281324, 4932.755596203719, 4983.9252411213865, 4734.905301893943, 5225.204515584144, 5405.7837686492885, 5437.429069947678, 5491.780328786804, 1258.5406326690693, 1454.4909281751513, 1477.6527516033723, 1489.0321291160833, 1537.2621141851973, 1103.332483847277, 1139.629620373382, 1152.9192956493132, 1162.7848847963153, 1170.011340417346, 4488.618467430239, 5077.283653245496, 5104.443144840785, 5109.5437800226755, 5119.590432765444, 4893.486183950705, 5045.974770126126, 5496.74633400304, 5593.720313984284, 5681.545476361881, 4887.486813884562, 4900.2359150705715, 4928.0, 5069.417017043087, 5124.026434977342, 2650.9204723858315, 2679.4641071785722, 2934.662513810939, 2947.5283954567167, 2958.704129587024, 5552.167174923794, 5811.476967072984, 5879.735411906389, 5933.6736479493175, 5953.374895635982, 4704.411948506437, 4940.580050695659, 5070.172020124782, 5206.297149884793, 5562.330081560874, 5393.5954803389905, 5422.875382763251, 5446.1558458439, 5494.148406996922, 5549.861253468695, 4469.949561852897, 5224.320838290998, 5329.935287209947, 5564.721763911869, 5568.939492387843, 4856.465788763163, 5543.307086614173, 5550.666959982414, 5566.970110529565, 5586.357568879631, 5087.287779710918, 5427.530473634566, 5571.554275658019, 5663.017943719665, 5788.942110578841, 3975.741576797499, 4305.8901138441315, 4336.9349459757495, 4349.086691794759, 4353.608175264241, 4930.348333308382, 4933.753312334297, 5177.870553236199, 5420.777481232676, 5466.699331536644, 4671.486136524912, 4901.534354236342, 5076.771545280545, 5315.441878602691, 5422.728863556886, 4561.521040290721, 4956.132676781621, 5056.64603477753, 5243.737813109253, 5464.9180262296895, 4741.155306815883, 4962.379702537183, 5190.55880250183, 5345.305110335631, 5374.838754837361, 4282.63597594208, 5359.839204823861, 5526.309211348582, 5548.334199896039, 5570.470805273492, 5009.9479109385975, 5175.48988740453, 5214.765335559833, 5423.511883930465, 5448.373437054789], "value": 4134.122054928432, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [716.6810769207705, 737.3327138939262, 773.3735674103976, 828.4697793412217, 835.669910385398, 13325.733485330287, 15109.0, 15948.28232729527, 15973.920130399347, 16032.278547465363, 14034.649133771636, 14116.57650270491, 14168.716625667481, 14212.431502739904, 14215.0, 917.1470532404877, 929.1498279074639, 959.1894848852743, 969.4619486185164, 985.7486340983075, 980.4460479828874, 982.3712823792742, 983.2576404814355, 984.8522721591747, 986.5905649155592, 1026.240581969341, 1030.6907927621721, 1031.9019693129142, 1033.1166852341253, 1033.565902321027, 1022.161827301613, 1022.5807418958266, 1023.3092662452815, 1024.119257438606, 1024.7540590258284, 761.9961900190464, 762.5882023707192, 763.4999075605488, 763.5609524523421, 768.9038870141233, 13203.339833008311, 15782.684346313124, 16105.033697978042, 16152.596185095466, 16639.584010399714, 13311.267880266581, 14844.109353438827, 14998.62503437412, 15002.24988750558, 15004.699906001819, 10801.351918884813, 11198.608048718337, 11390.03184729299, 11768.293902365886, 11973.40132993347, 3676.283124790667, 3751.924961500782, 3768.171002379472, 3799.772013679188, 3943.4084887266854, 104307.0, 108606.45696771465, 116870.41564792207, 119036.0, 120583.0, 10243.94878025605, 10769.407682577377, 11624.186306958602, 11923.880761192277, 12057.0, 25847.61228581573, 25937.832797524155, 26097.478050438884, 26110.086146984766, 26137.869310653325, 6812.0, 8003.839923201504, 8069.515829050276, 8080.19198080199, 8281.751547453587, 4230.640395566411, 4308.224519586442, 4332.176886391576, 4400.251957167289, 4481.932771008437, 3574.696150827157, 3581.76718513301, 3645.453182022665, 3648.1426864686778, 3666.651668091527, 573.8335882594084, 641.4163111568544, 641.4547634510628, 658.1772784019975, 663.2405895250008, 652.546480196259, 673.3233100733731, 681.3629256645053, 691.4330249195712, 710.4458522352573, 8372.958135209285, 9610.06589106874, 9763.658271960447, 9998.900010999942, 10541.631042913461, 654.6693919570681, 679.8482801931831, 692.1901375390805, 706.5654622407183, 728.2826415980255, 4756.0, 4843.571146511717, 4864.738597337021, 4892.804287828518, 4897.461279259279, 77255.61372193103, 91231.54384228037, 94256.0, 95448.0, 96861.0, 82744.0, 84520.57739711262, 86472.13527864766, 89375.55312223398, 90078.54960725286, 55983.160252596244, 70202.0, 77993.0, 79594.20405795868, 79970.6001469989, 9485.288603354915, 9494.857577136487, 9502.667406640734, 9536.284778641491, 9562.282828788007, 50330.0, 50449.49550504448, 50540.98918021692, 51384.48615513797, 51538.22692659538, 64506.0, 64554.0, 66602.66698666669, 67127.66436167787, 67332.65334693181, 11627.418629068681, 11807.527698891949, 11882.227655202385, 12064.336461494351, 12220.51117955272, 12311.876881231432, 12413.503459861866, 12663.430145643284, 12902.935485322514, 13487.595372138852, 11711.472983715923, 12235.0, 12238.571649992207, 12280.81578776337, 12517.93741031289, 11720.0, 12148.0, 12148.757024859276, 12537.749245014866, 12551.93724031374, 11187.21689481761, 11506.94246528762, 11516.790736972498, 12011.459484323052, 12229.266244025386, 8580.399372043898, 8707.564621769014, 8782.91217087821, 8840.911590884009, 8940.910590894007, 9797.608095676092, 10176.418568166468, 10232.386056836614, 10289.9485502572, 10404.635837745642], "value": 7501.38383114468, "unit": "score"}]}, {"extraOptions": [], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1442.8999999999999, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [115.0, 115.0, 115.0, 115.0, 115.0, 115.0, 118.0, 163.0, 169.0, 114.0], "value": 125.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [143.0, 108.0, 106.0, 162.0, 104.0, 114.0, 135.0, 136.0, 135.0, 138.0], "value": 128.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [185.0, 184.0, 186.0, 184.0, 186.0, 186.0, 186.0, 183.0, 185.0, 186.0], "value": 185.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 63.0, 62.0, 91.0, 63.0, 63.0, 61.0, 58.0, 75.0, 61.0], "value": 68.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 70.0, 89.0, 70.0, 70.0, 69.0, 84.0, 69.0, 70.0, 84.0], "value": 76.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 85.0, 86.0, 86.0, 85.0, 85.0, 86.0, 86.0, 85.0, 86.0], "value": 85.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 108.0, 151.0, 93.0, 93.0, 94.0, 94.0, 94.0, 94.0, 93.0], "value": 100.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 85.0, 87.0, 86.0, 83.0, 84.0, 86.0, 84.0, 84.0, 86.0], "value": 84.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 64.0, 60.0, 62.0, 64.0, 65.0, 66.0, 64.0, 63.0, 64.0], "value": 63.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 55.0, 61.0, 63.0, 63.0, 61.0, 59.0, 59.0, 57.0, 59.0], "value": 59.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 86.0, 83.0, 84.0, 83.0, 89.0, 85.0, 84.0, 83.0, 90.0], "value": 85.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [137.0, 136.0, 135.0, 137.0, 131.0, 140.0, 156.0, 140.0, 210.0, 142.0], "value": 146.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [165.0, 168.0, 164.0, 165.0, 161.0, 162.0, 165.0, 165.0, 167.0, 164.0], "value": 164.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 66.0, 67.0, 64.0, 75.0, 80.0, 78.0, 67.0, 65.0, 64.0], "value": 69.1, "unit": "score"}]}]} 16:04:35 INFO - Return code: 0 16:04:35 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 16:04:35 ERROR - # TBPL SUCCESS # 16:04:35 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 16:04:35 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 16:04:35 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 16:04:35 INFO - 2017-04-12 16:04:35.966 screenresolution[1651:10001] starting screenresolution argv=screenresolution get 16:04:35 INFO - 2017-04-12 16:04:35.969 screenresolution[1651:10001] Display 0: 1600x1200x32@60 16:04:35 INFO - 2017-04-12 16:04:35.975 screenresolution[1652:10004] starting screenresolution argv=screenresolution list 16:04:35 INFO - Available Modes on Display 0 16:04:35 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 16:04:35 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 16:04:35 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 16:04:35 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 16:04:36 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 16:04:36 INFO - Intel Iris: 16:04:36 INFO - Chipset Model: Intel Iris 16:04:36 INFO - Type: GPU 16:04:36 INFO - Bus: Built-In 16:04:36 INFO - VRAM (Dynamic, Max): 1536 MB 16:04:36 INFO - Vendor: Intel (0x8086) 16:04:36 INFO - Device ID: 0x0a2e 16:04:36 INFO - Revision ID: 0x0009 16:04:36 INFO - Displays: 16:04:36 INFO - Display: 16:04:36 INFO - Resolution: 1600 x 1200 @ 60 Hz 16:04:36 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 16:04:36 INFO - Display Serial Number: 1600x1200 60 16:04:36 INFO - Main Display: Yes 16:04:36 INFO - Mirror: Off 16:04:36 INFO - Online: Yes 16:04:36 INFO - Rotation: Supported 16:04:36 INFO - Return code: 0 16:04:36 INFO - Running post-action listener: _resource_record_post_action 16:04:36 INFO - [mozharness: 2017-04-12 23:04:36.211964Z] Finished run-tests step (success) 16:04:36 INFO - Running post-run listener: _resource_record_post_run 16:04:36 INFO - Total resource usage - Wall time: 1201s; CPU: 54.0%; Read bytes: 38192128; Write bytes: 509010944; Read time: 234; Write time: 1565 16:04:36 INFO - TinderboxPrint: CPU usage
53.8% 16:04:36 INFO - TinderboxPrint: I/O read bytes / time
38,192,128 / 234 16:04:36 INFO - TinderboxPrint: I/O write bytes / time
509,010,944 / 1,565 16:04:36 INFO - TinderboxPrint: CPU idle
2,219.4 (46.2%) 16:04:36 INFO - TinderboxPrint: CPU system
956.9 (19.9%) 16:04:36 INFO - TinderboxPrint: CPU user
1,629.4 (33.9%) 16:04:36 INFO - TinderboxPrint: Swap in / out
246,226,944 / 0 16:04:36 INFO - install - Wall time: 20s; CPU: 54.0%; Read bytes: 206014976; Write bytes: 201316352; Read time: 15658; Write time: 441 16:04:36 INFO - run-tests - Wall time: 1181s; CPU: 54.0%; Read bytes: 36074496; Write bytes: 296516608; Read time: 215; Write time: 1084 16:04:36 INFO - Running post-run listener: _upload_blobber_files 16:04:36 INFO - Blob upload gear active. 16:04:36 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 16:04:36 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 16:04:36 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'] 16:04:36 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 16:04:37 INFO - (blobuploader) - INFO - Open directory for files ... 16:04:37 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 16:04:37 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:04:37 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:04:38 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 16:04:38 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:04:38 INFO - (blobuploader) - INFO - Done attempting. 16:04:38 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 16:04:38 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:04:38 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:04:40 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 16:04:40 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:04:40 INFO - (blobuploader) - INFO - Done attempting. 16:04:40 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 16:04:40 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:04:40 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:04:41 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 16:04:41 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:04:41 INFO - (blobuploader) - INFO - Done attempting. 16:04:41 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 16:04:41 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:04:41 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:04:43 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 16:04:43 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:04:43 INFO - (blobuploader) - INFO - Done attempting. 16:04:43 INFO - (blobuploader) - INFO - Iteration through files over. 16:04:43 INFO - Return code: 0 16:04:43 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 16:04:43 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 16:04:43 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/67d549d3e98f7a7bfd75b8e6a3474ecc8c414b59fd6f3405a12c4fbf736660691067cfa75ce9a35ab3cab634064cfe372b9b55b3e4e641250572110690b85539", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e13b9854af7120cf458f7e034e7d49a596fa005cd1215d55ff09b25aa4fb231a3209ad5436e6f6fca411b2fddeb722f7d8f7e591f6b153b24a311c238ebcbd4e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/6aee578742437c1c5adc2717874c181fd577f1ecfbf7a08cc4b262a65f62a8a898c55f7660476571854b2f55321984771858795dec6fd3c916d6bfc7424a9810", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/d3fd842f4b0c2e6ebf639b943f76c60c4d0cc4f91bc2de2e27904b9b0e93fcea1ceb57a52a8589c99f761bcf8030b35da99ea0930b0d4890579751b85523ed96"} 16:04:43 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 16:04:43 INFO - Writing to file /builds/slave/test/properties/blobber_files 16:04:43 INFO - Contents: 16:04:43 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/67d549d3e98f7a7bfd75b8e6a3474ecc8c414b59fd6f3405a12c4fbf736660691067cfa75ce9a35ab3cab634064cfe372b9b55b3e4e641250572110690b85539", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e13b9854af7120cf458f7e034e7d49a596fa005cd1215d55ff09b25aa4fb231a3209ad5436e6f6fca411b2fddeb722f7d8f7e591f6b153b24a311c238ebcbd4e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/6aee578742437c1c5adc2717874c181fd577f1ecfbf7a08cc4b262a65f62a8a898c55f7660476571854b2f55321984771858795dec6fd3c916d6bfc7424a9810", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/d3fd842f4b0c2e6ebf639b943f76c60c4d0cc4f91bc2de2e27904b9b0e93fcea1ceb57a52a8589c99f761bcf8030b35da99ea0930b0d4890579751b85523ed96"} 16:04:43 INFO - Running post-run listener: copy_logs_to_upload_dir 16:04:43 INFO - Copying logs to upload dir... 16:04:43 INFO - mkdir: /builds/slave/test/build/upload/logs 16:04:43 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1285.557674 ========= master_lag: 0.06 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 25 secs) (at 2017-04-12 16:04:43.149317) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-04-12 16:04:43.152416) ========= 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.rzPqNHbdQT/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.Vrd4veNHwd/Listeners TMPDIR=/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/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/67d549d3e98f7a7bfd75b8e6a3474ecc8c414b59fd6f3405a12c4fbf736660691067cfa75ce9a35ab3cab634064cfe372b9b55b3e4e641250572110690b85539", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e13b9854af7120cf458f7e034e7d49a596fa005cd1215d55ff09b25aa4fb231a3209ad5436e6f6fca411b2fddeb722f7d8f7e591f6b153b24a311c238ebcbd4e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/6aee578742437c1c5adc2717874c181fd577f1ecfbf7a08cc4b262a65f62a8a898c55f7660476571854b2f55321984771858795dec6fd3c916d6bfc7424a9810", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/d3fd842f4b0c2e6ebf639b943f76c60c4d0cc4f91bc2de2e27904b9b0e93fcea1ceb57a52a8589c99f761bcf8030b35da99ea0930b0d4890579751b85523ed96"} build_url:https://queue.taskcluster.net/v1/task/M21nbwsWR0ihuc3xFXN83A/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011235 build_url: 'https://queue.taskcluster.net/v1/task/M21nbwsWR0ihuc3xFXN83A/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/67d549d3e98f7a7bfd75b8e6a3474ecc8c414b59fd6f3405a12c4fbf736660691067cfa75ce9a35ab3cab634064cfe372b9b55b3e4e641250572110690b85539", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e13b9854af7120cf458f7e034e7d49a596fa005cd1215d55ff09b25aa4fb231a3209ad5436e6f6fca411b2fddeb722f7d8f7e591f6b153b24a311c238ebcbd4e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/6aee578742437c1c5adc2717874c181fd577f1ecfbf7a08cc4b262a65f62a8a898c55f7660476571854b2f55321984771858795dec6fd3c916d6bfc7424a9810", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/d3fd842f4b0c2e6ebf639b943f76c60c4d0cc4f91bc2de2e27904b9b0e93fcea1ceb57a52a8589c99f761bcf8030b35da99ea0930b0d4890579751b85523ed96"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-04-12 16:04:43.187897) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-04-12 16:04:43.188247) ========= 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.rzPqNHbdQT/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.Vrd4veNHwd/Listeners TMPDIR=/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/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.005241 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-04-12 16:04:43.237339) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-04-12 16:04:43.237660) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-04-12 16:04:43.238009) ========= ========= Total master_lag: 0.29 =========