builder: mozilla-aurora_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0154 starttime: 1492507856.32 results: success (0) buildid: 20170418004027 builduid: 77a6948fbfb946df9b65835e53c654b9 revision: 105e456d811b8c4616de688e9afdcf0af620b80b ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-04-18 02:30:56.324619) ========= master: http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-04-18 02:30:56.325159) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-04-18 02:30:56.325463) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-04-18 02:30:56.352566) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-18 02:30:56.352935) ========= 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.BtJ7vr7dLN/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.5ZNk8XZnAd/Listeners TMPDIR=/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/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-18 02:30:56-- 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% 9.04M=0.001s 2017-04-18 02:30:56 (9.04 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.108309 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-18 02:30:56.485740) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-04-18 02:30:56.486180) ========= 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.BtJ7vr7dLN/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.5ZNk8XZnAd/Listeners TMPDIR=/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/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.040378 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-04-18 02:30:56.548560) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-04-18 02:30:56.548931) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 105e456d811b8c4616de688e9afdcf0af620b80b --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 105e456d811b8c4616de688e9afdcf0af620b80b --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.BtJ7vr7dLN/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.5ZNk8XZnAd/Listeners TMPDIR=/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/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-18 02:30:56,664 truncating revision to first 12 chars 2017-04-18 02:30:56,665 Setting DEBUG logging. 2017-04-18 02:30:56,665 attempt 1/10 2017-04-18 02:30:56,665 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/105e456d811b?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-04-18 02:30:57,759 unpacking tar archive at: mozilla-aurora-105e456d811b/testing/mozharness/ program finished with exit code 0 elapsedTime=1.367532 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-04-18 02:30:57.940563) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-04-18 02:30:57.940925) ========= script_repo_revision: 105e456d811b8c4616de688e9afdcf0af620b80b ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-04-18 02:30:57.941339) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-04-18 02:30:57.941634) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-04-18 02:30:57.961454) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 24 secs) (at 2017-04-18 02:30:57.961772) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Aurora', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.BtJ7vr7dLN/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.5ZNk8XZnAd/Listeners TMPDIR=/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/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 02:30:58 INFO - MultiFileLogger online at 20170418 02:30:58 in /builds/slave/test 02:30:58 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 02:30:58 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 02:30:58 INFO - {'append_to_log': False, 02:30:58 INFO - 'base_work_dir': '/builds/slave/test', 02:30:58 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 02:30:58 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 02:30:58 INFO - 'branch': 'Mozilla-Aurora', 02:30:58 INFO - 'buildbot_json_path': 'buildprops.json', 02:30:58 INFO - 'config_files': ('talos/mac_config.py',), 02:30:58 INFO - 'default_actions': ('clobber', 02:30:58 INFO - 'read-buildbot-config', 02:30:58 INFO - 'download-and-extract', 02:30:58 INFO - 'populate-webroot', 02:30:58 INFO - 'create-virtualenv', 02:30:58 INFO - 'install', 02:30:58 INFO - 'run-tests'), 02:30:58 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 02:30:58 INFO - 'download_minidump_stackwalk': True, 02:30:58 INFO - 'download_symbols': 'ondemand', 02:30:58 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 02:30:58 INFO - 'tooltool.py': '/tools/tooltool.py', 02:30:58 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 02:30:58 INFO - '/tools/misc-python/virtualenv.py')}, 02:30:58 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 02:30:58 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 02:30:58 INFO - 'gecko_profile': False, 02:30:58 INFO - 'gecko_profile_interval': 0, 02:30:58 INFO - 'installer_path': 'installer.exe', 02:30:58 INFO - 'log_level': 'info', 02:30:58 INFO - 'log_name': 'talos', 02:30:58 INFO - 'log_to_console': True, 02:30:58 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 02:30:58 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 02:30:58 INFO - 'opt_config_files': (), 02:30:58 INFO - 'pip_index': False, 02:30:58 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 02:30:58 INFO - 'cmd': ('bash', 02:30:58 INFO - '-c', 02:30:58 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 02:30:58 INFO - 'enabled': True, 02:30:58 INFO - 'halt_on_failure': False, 02:30:58 INFO - 'name': 'check_screen_resolution'},), 02:30:58 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 02:30:58 INFO - 'cmd': ('bash', 02:30:58 INFO - '-c', 02:30:58 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 02:30:58 INFO - 'enabled': True, 02:30:58 INFO - 'halt_on_failure': False, 02:30:58 INFO - 'name': 'check_screen_resolution'},), 02:30:58 INFO - 'run_cmd_checks_enabled': True, 02:30:58 INFO - 'suite': 'dromaeojs-e10s', 02:30:58 INFO - 'system_bits': '32', 02:30:58 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 02:30:58 INFO - 'title': 't-yosemite-r7-0154', 02:30:58 INFO - 'tooltool_cache': '/builds/tooltool_cache', 02:30:58 INFO - 'use_talos_json': True, 02:30:58 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 02:30:58 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 02:30:58 INFO - 'work_dir': 'build'} 02:30:58 INFO - [mozharness: 2017-04-18 09:30:58.194148Z] Running clobber step. 02:30:58 INFO - Running pre-action listener: _resource_record_pre_action 02:30:58 INFO - Running main action method: clobber 02:30:58 INFO - rmtree: /builds/slave/test/build 02:30:58 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 02:31:00 INFO - Running post-action listener: _resource_record_post_action 02:31:00 INFO - [mozharness: 2017-04-18 09:31:00.718145Z] Finished clobber step (success) 02:31:00 INFO - [mozharness: 2017-04-18 09:31:00.718279Z] Running read-buildbot-config step. 02:31:00 INFO - Running pre-action listener: _resource_record_pre_action 02:31:00 INFO - Running main action method: read_buildbot_config 02:31:00 INFO - Using buildbot properties: 02:31:00 INFO - { 02:31:00 INFO - "project": "", 02:31:00 INFO - "product": "firefox", 02:31:00 INFO - "script_repo_revision": "production", 02:31:00 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs-e10s", 02:31:00 INFO - "repository": "", 02:31:00 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs-e10s", 02:31:00 INFO - "buildid": "20170418004027", 02:31:00 INFO - "stage_platform": "macosx64", 02:31:00 INFO - "basedir": "/builds/slave/test", 02:31:00 INFO - "nightly_build": "True", 02:31:00 INFO - "buildnumber": 22, 02:31:00 INFO - "slavename": "t-yosemite-r7-0154", 02:31:00 INFO - "master": "http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/", 02:31:00 INFO - "platform": "yosemite_r7", 02:31:00 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 02:31:00 INFO - "branch": "mozilla-aurora", 02:31:00 INFO - "repo_path": "releases/mozilla-aurora", 02:31:00 INFO - "revision": "105e456d811b8c4616de688e9afdcf0af620b80b", 02:31:00 INFO - "pgo_build": "False", 02:31:00 INFO - "builduid": "77a6948fbfb946df9b65835e53c654b9", 02:31:00 INFO - "slavebuilddir": "test" 02:31:00 INFO - } 02:31:00 INFO - Found installer url https://queue.taskcluster.net/v1/task/ebjpA2HvR3u8s21cSOswGw/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg. 02:31:00 INFO - Running post-action listener: _resource_record_post_action 02:31:00 INFO - [mozharness: 2017-04-18 09:31:00.721705Z] Finished read-buildbot-config step (success) 02:31:00 INFO - [mozharness: 2017-04-18 09:31:00.721840Z] Running download-and-extract step. 02:31:00 INFO - Running pre-action listener: _resource_record_pre_action 02:31:00 INFO - Running main action method: download_and_extract 02:31:00 INFO - mkdir: /builds/slave/test/build/tests 02:31:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:31:00 INFO - trying https://queue.taskcluster.net/v1/task/ebjpA2HvR3u8s21cSOswGw/artifacts/public/build/firefox-54.0a2.en-US.mac.test_packages.json 02:31:00 INFO - Downloading https://queue.taskcluster.net/v1/task/ebjpA2HvR3u8s21cSOswGw/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 02:31:00 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/ebjpA2HvR3u8s21cSOswGw/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 02:31:02 INFO - Downloaded 1199 bytes. 02:31:02 INFO - Reading from file /builds/slave/test/build/firefox-54.0a2.en-US.mac.test_packages.json 02:31:02 INFO - Using the following test package requirements: 02:31:02 INFO - {u'common': [u'firefox-54.0a2.en-US.mac.common.tests.zip'], 02:31:02 INFO - u'cppunittest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 02:31:02 INFO - u'firefox-54.0a2.en-US.mac.cppunittest.tests.zip'], 02:31:02 INFO - u'gtest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 02:31:02 INFO - u'firefox-54.0a2.en-US.mac.gtest.tests.zip'], 02:31:02 INFO - u'jittest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 02:31:02 INFO - u'jsshell-mac.zip'], 02:31:02 INFO - u'mochitest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 02:31:02 INFO - u'firefox-54.0a2.en-US.mac.mochitest.tests.zip'], 02:31:02 INFO - u'mozbase': [u'firefox-54.0a2.en-US.mac.common.tests.zip'], 02:31:02 INFO - u'reftest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 02:31:02 INFO - u'firefox-54.0a2.en-US.mac.reftest.tests.zip'], 02:31:02 INFO - u'talos': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 02:31:02 INFO - u'firefox-54.0a2.en-US.mac.talos.tests.zip'], 02:31:02 INFO - u'web-platform': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 02:31:02 INFO - u'firefox-54.0a2.en-US.mac.web-platform.tests.zip'], 02:31:02 INFO - u'xpcshell': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 02:31:02 INFO - u'firefox-54.0a2.en-US.mac.xpcshell.tests.zip']} 02:31:02 INFO - Downloading packages: [u'firefox-54.0a2.en-US.mac.common.tests.zip'] for test suite category: common 02:31:02 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/ebjpA2HvR3u8s21cSOswGw/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip 02:31:02 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/ebjpA2HvR3u8s21cSOswGw/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip'}, attempt #1 02:31:02 INFO - Fetch https://queue.taskcluster.net/v1/task/ebjpA2HvR3u8s21cSOswGw/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip into memory 02:31:03 INFO - Expected file size: 51791327 02:31:03 INFO - Obtained file size: 51791327 02:31:19 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 02:31:19 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/ebjpA2HvR3u8s21cSOswGw/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip 02:31:19 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/ebjpA2HvR3u8s21cSOswGw/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip'}, attempt #1 02:31:19 INFO - Fetch https://queue.taskcluster.net/v1/task/ebjpA2HvR3u8s21cSOswGw/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip into memory 02:31:21 INFO - Expected file size: 51791327 02:31:21 INFO - Obtained file size: 51791327 02:31:36 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/ebjpA2HvR3u8s21cSOswGw/artifacts/public/build/firefox-54.0a2.en-US.mac.talos.tests.zip 02:31:36 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/ebjpA2HvR3u8s21cSOswGw/artifacts/public/build/firefox-54.0a2.en-US.mac.talos.tests.zip'}, attempt #1 02:31:36 INFO - Fetch https://queue.taskcluster.net/v1/task/ebjpA2HvR3u8s21cSOswGw/artifacts/public/build/firefox-54.0a2.en-US.mac.talos.tests.zip into memory 02:31:37 INFO - Expected file size: 14404424 02:31:37 INFO - Obtained file size: 14404424 02:31:38 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')]} 02:31:38 INFO - trying https://queue.taskcluster.net/v1/task/ebjpA2HvR3u8s21cSOswGw/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg 02:31:38 INFO - Downloading https://queue.taskcluster.net/v1/task/ebjpA2HvR3u8s21cSOswGw/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-54.0a2.en-US.mac.dmg 02:31:38 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/ebjpA2HvR3u8s21cSOswGw/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 02:31:40 INFO - Downloaded 74780312 bytes. 02:31:40 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/ebjpA2HvR3u8s21cSOswGw/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg 02:31:40 INFO - mkdir: /builds/slave/test/properties 02:31:40 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 02:31:40 INFO - Writing to file /builds/slave/test/properties/build_url 02:31:40 INFO - Contents: 02:31:40 INFO - build_url:https://queue.taskcluster.net/v1/task/ebjpA2HvR3u8s21cSOswGw/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg 02:31:40 INFO - Running post-action listener: _resource_record_post_action 02:31:40 INFO - Running post-action listener: set_extra_try_arguments 02:31:40 INFO - [mozharness: 2017-04-18 09:31:40.911125Z] Finished download-and-extract step (success) 02:31:40 INFO - [mozharness: 2017-04-18 09:31:40.911242Z] Running populate-webroot step. 02:31:40 INFO - Running pre-action listener: _resource_record_pre_action 02:31:40 INFO - Running main action method: populate_webroot 02:31:40 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 02:31:40 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 02:31:40 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 02:31:40 INFO - '--tppagecycles', 02:31:40 INFO - '7'], 02:31:40 INFO - 'tests': ['tsvgm']}}, 02:31:40 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 02:31:40 INFO - 'tests': ['tresize', 'tcanvasmark']}, 02:31:40 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 02:31:40 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 02:31:40 INFO - 'tests': ['dromaeo_css', 'kraken']}, 02:31:40 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 02:31:40 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 02:31:40 INFO - 'talos_options': ['--disable-e10s'], 02:31:40 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 02:31:40 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 02:31:40 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 02:31:40 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 02:31:40 INFO - 'talos_options': ['--disable-e10s'], 02:31:40 INFO - 'tests': ['damp', 'tps']}, 02:31:40 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 02:31:40 INFO - 'tests': ['damp', 'tps']}, 02:31:40 INFO - 'g3': {'talos_options': ['--disable-e10s'], 02:31:40 INFO - 'tests': ['dromaeo_dom']}, 02:31:40 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 02:31:40 INFO - 'g4': {'talos_options': ['--disable-e10s'], 02:31:40 INFO - 'tests': ['basic_compositor_video']}, 02:31:40 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 02:31:40 INFO - 'other': {'talos_options': ['--disable-e10s'], 02:31:40 INFO - 'tests': ['a11yr', 02:31:40 INFO - 'ts_paint', 02:31:40 INFO - 'tpaint', 02:31:40 INFO - 'sessionrestore', 02:31:40 INFO - 'sessionrestore_no_auto_restore', 02:31:40 INFO - 'tabpaint']}, 02:31:40 INFO - 'other-e10s': {'tests': ['a11yr', 02:31:40 INFO - 'ts_paint', 02:31:40 INFO - 'tpaint', 02:31:40 INFO - 'sessionrestore', 02:31:40 INFO - 'sessionrestore_no_auto_restore', 02:31:40 INFO - 'tabpaint']}, 02:31:40 INFO - 'other-e10s_l64': {'tests': ['a11yr', 02:31:40 INFO - 'ts_paint', 02:31:40 INFO - 'tpaint', 02:31:40 INFO - 'sessionrestore', 02:31:40 INFO - 'sessionrestore_no_auto_restore', 02:31:40 INFO - 'tabpaint']}, 02:31:40 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 02:31:40 INFO - 'ts_paint', 02:31:40 INFO - 'tpaint', 02:31:40 INFO - 'sessionrestore', 02:31:40 INFO - 'sessionrestore_no_auto_restore', 02:31:40 INFO - 'tabpaint']}, 02:31:40 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 02:31:40 INFO - 'tests': ['a11yr', 02:31:40 INFO - 'ts_paint', 02:31:40 INFO - 'tpaint', 02:31:40 INFO - 'sessionrestore', 02:31:40 INFO - 'sessionrestore_no_auto_restore', 02:31:40 INFO - 'tabpaint']}, 02:31:40 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 02:31:40 INFO - 'tests': ['a11yr', 02:31:40 INFO - 'ts_paint', 02:31:40 INFO - 'tpaint', 02:31:40 INFO - 'sessionrestore', 02:31:40 INFO - 'sessionrestore_no_auto_restore', 02:31:40 INFO - 'tabpaint']}, 02:31:40 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 02:31:40 INFO - 'tests': ['tsvgx', 02:31:40 INFO - 'tsvgr_opacity', 02:31:40 INFO - 'tart', 02:31:40 INFO - 'tscrollx', 02:31:40 INFO - 'cart', 02:31:40 INFO - 'tsvg_static']}, 02:31:40 INFO - 'svgr-e10s': {'tests': ['tsvgx', 02:31:40 INFO - 'tsvgr_opacity', 02:31:40 INFO - 'tart', 02:31:40 INFO - 'tscrollx', 02:31:40 INFO - 'cart', 02:31:40 INFO - 'tsvg_static']}, 02:31:40 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 02:31:40 INFO - 'talos_options': ['--disable-e10s'], 02:31:40 INFO - 'tests': ['tp5o']}, 02:31:40 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 02:31:40 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 02:31:40 INFO - 'talos_options': ['--disable-e10s', 02:31:40 INFO - '--xperf_path', 02:31:40 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 02:31:40 INFO - 'tests': ['tp5n']}, 02:31:40 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 02:31:40 INFO - 'talos_options': ['--xperf_path', 02:31:40 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 02:31:40 INFO - 'tests': ['tp5n']}}, 02:31:40 INFO - 'talos.zip': {'path': '', 02:31:40 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 02:31:40 INFO - Running post-action listener: _resource_record_post_action 02:31:40 INFO - [mozharness: 2017-04-18 09:31:40.929829Z] Finished populate-webroot step (success) 02:31:40 INFO - [mozharness: 2017-04-18 09:31:40.929939Z] Running create-virtualenv step. 02:31:40 INFO - Running pre-action listener: _resource_record_pre_action 02:31:40 INFO - Running main action method: create_virtualenv 02:31:40 INFO - Creating virtualenv /builds/slave/test/build/venv 02:31:40 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 02:31:40 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 02:31:41 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 02:31:41 INFO - Using real prefix '/tools/python27' 02:31:41 INFO - New python executable in /builds/slave/test/build/venv/bin/python 02:31:41 INFO - Installing distribute.............................................................................................................................................................................................done. 02:31:44 INFO - Installing pip.................done. 02:31:44 INFO - Return code: 0 02:31:44 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 02:31:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 02:31:45 INFO - Reading from file tmpfile_stdout 02:31:45 INFO - Output received: 02:31:45 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) 02:31:45 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 02:31:45 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')]} 02:31:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:31:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:31:45 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')]} 02:31:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:31:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:31:45 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 0x10ace1b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ace4768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fde13458360>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aabfd30>, '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 0x10a9e6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10aab4be8>, '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.BtJ7vr7dLN/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.5ZNk8XZnAd/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/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 02:31:45 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 02:31:45 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 02:31:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.BtJ7vr7dLN/Render', 02:31:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:31:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:31:45 INFO - 'HOME': '/Users/cltbld', 02:31:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:31:45 INFO - 'LOGNAME': 'cltbld', 02:31:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:31:45 INFO - 'MOZ_NO_REMOTE': '1', 02:31:45 INFO - 'NO_EM_RESTART': '1', 02:31:45 INFO - 'PAGER': '/bin/cat', 02:31:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:31:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:31:45 INFO - 'PWD': '/builds/slave/test', 02:31:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:31:45 INFO - 'SHELL': '/bin/bash', 02:31:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5ZNk8XZnAd/Listeners', 02:31:45 INFO - 'TMPDIR': '/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/', 02:31:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:31:45 INFO - 'USER': 'cltbld', 02:31:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:31:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:31:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:31:45 INFO - 'XPC_FLAGS': '0x0', 02:31:45 INFO - 'XPC_SERVICE_NAME': '0', 02:31:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:31:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:31:45 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 02:31:45 INFO - Cleaning up... 02:31:45 INFO - Return code: 0 02:31:45 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 02:31:45 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')]} 02:31:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:31:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:31:45 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')]} 02:31:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:31:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:31:45 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 0x10ace1b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ace4768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fde13458360>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aabfd30>, '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 0x10a9e6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10aab4be8>, '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.BtJ7vr7dLN/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.5ZNk8XZnAd/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/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 02:31:45 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 02:31:45 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 02:31:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.BtJ7vr7dLN/Render', 02:31:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:31:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:31:45 INFO - 'HOME': '/Users/cltbld', 02:31:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:31:45 INFO - 'LOGNAME': 'cltbld', 02:31:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:31:45 INFO - 'MOZ_NO_REMOTE': '1', 02:31:45 INFO - 'NO_EM_RESTART': '1', 02:31:45 INFO - 'PAGER': '/bin/cat', 02:31:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:31:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:31:45 INFO - 'PWD': '/builds/slave/test', 02:31:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:31:45 INFO - 'SHELL': '/bin/bash', 02:31:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5ZNk8XZnAd/Listeners', 02:31:45 INFO - 'TMPDIR': '/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/', 02:31:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:31:45 INFO - 'USER': 'cltbld', 02:31:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:31:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:31:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:31:45 INFO - 'XPC_FLAGS': '0x0', 02:31:45 INFO - 'XPC_SERVICE_NAME': '0', 02:31:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:31:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:31:45 INFO - Downloading/unpacking psutil>=3.1.1 02:31:45 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 02:31:45 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 02:31:45 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 02:31:45 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 02:31:45 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 02:31:45 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 02:31:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 02:31:49 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 02:31:49 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 02:31:49 INFO - Installing collected packages: psutil 02:31:49 INFO - Running setup.py install for psutil 02:31:49 INFO - building 'psutil._psutil_osx' extension 02:31:49 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 02:31:49 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 02:31:50 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 02:31:50 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 02:31:50 INFO - building 'psutil._psutil_posix' extension 02:31:50 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 02:31:50 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 02:31:50 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 02:31:50 INFO - ^ 02:31:50 INFO - 1 warning generated. 02:31:50 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 02:31:50 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 02:31:50 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 02:31:50 INFO - Successfully installed psutil 02:31:50 INFO - Cleaning up... 02:31:50 INFO - Return code: 0 02:31:50 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 02:31:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:31:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:31:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:31:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:31:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:31:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:31:50 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10ace1b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ace4768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fde13458360>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aabfd30>, '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 0x10a9e6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10aab4be8>, '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.BtJ7vr7dLN/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.5ZNk8XZnAd/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/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 02:31:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 02:31:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 02:31:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.BtJ7vr7dLN/Render', 02:31:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:31:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:31:50 INFO - 'HOME': '/Users/cltbld', 02:31:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:31:50 INFO - 'LOGNAME': 'cltbld', 02:31:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:31:50 INFO - 'MOZ_NO_REMOTE': '1', 02:31:50 INFO - 'NO_EM_RESTART': '1', 02:31:50 INFO - 'PAGER': '/bin/cat', 02:31:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:31:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:31:50 INFO - 'PWD': '/builds/slave/test', 02:31:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:31:50 INFO - 'SHELL': '/bin/bash', 02:31:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5ZNk8XZnAd/Listeners', 02:31:50 INFO - 'TMPDIR': '/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/', 02:31:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:31:50 INFO - 'USER': 'cltbld', 02:31:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:31:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:31:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:31:50 INFO - 'XPC_FLAGS': '0x0', 02:31:50 INFO - 'XPC_SERVICE_NAME': '0', 02:31:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:31:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:31:50 INFO - Downloading/unpacking mozsystemmonitor==0.3 02:31:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:31:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:31:50 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:31:50 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:31:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:31:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:31:53 INFO - Downloading mozsystemmonitor-0.3.tar.gz 02:31:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 02:31:53 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 02:31:53 INFO - Installing collected packages: mozsystemmonitor 02:31:53 INFO - Running setup.py install for mozsystemmonitor 02:31:53 INFO - Successfully installed mozsystemmonitor 02:31:53 INFO - Cleaning up... 02:31:53 INFO - Return code: 0 02:31:53 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 02:31:53 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')]} 02:31:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:31:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:31:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:31:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:31:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:31:54 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10ace1b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ace4768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fde13458360>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aabfd30>, '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 0x10a9e6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10aab4be8>, '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.BtJ7vr7dLN/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.5ZNk8XZnAd/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/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 02:31:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 02:31:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 02:31:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.BtJ7vr7dLN/Render', 02:31:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:31:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:31:54 INFO - 'HOME': '/Users/cltbld', 02:31:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:31:54 INFO - 'LOGNAME': 'cltbld', 02:31:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:31:54 INFO - 'MOZ_NO_REMOTE': '1', 02:31:54 INFO - 'NO_EM_RESTART': '1', 02:31:54 INFO - 'PAGER': '/bin/cat', 02:31:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:31:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:31:54 INFO - 'PWD': '/builds/slave/test', 02:31:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:31:54 INFO - 'SHELL': '/bin/bash', 02:31:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5ZNk8XZnAd/Listeners', 02:31:54 INFO - 'TMPDIR': '/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/', 02:31:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:31:54 INFO - 'USER': 'cltbld', 02:31:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:31:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:31:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:31:54 INFO - 'XPC_FLAGS': '0x0', 02:31:54 INFO - 'XPC_SERVICE_NAME': '0', 02:31:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:31:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:31:54 INFO - Downloading/unpacking jsonschema==2.5.1 02:31:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:31:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:31:54 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:31:54 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:31:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:31:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:31:57 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 02:31:57 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 02:31:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:31:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:31:57 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:31:57 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:31:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:31:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:31:58 INFO - Downloading functools32-3.2.3-2.tar.gz 02:31:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 02:31:58 INFO - warning: no files found matching '*.txt' 02:31:58 INFO - no previously-included directories found matching 'build' 02:31:58 INFO - no previously-included directories found matching 'dist' 02:31:58 INFO - no previously-included directories found matching '.git*' 02:31:58 INFO - Installing collected packages: jsonschema, functools32 02:31:58 INFO - Running setup.py install for functools32 02:31:58 INFO - warning: no files found matching '*.txt' 02:31:58 INFO - no previously-included directories found matching 'build' 02:31:58 INFO - no previously-included directories found matching 'dist' 02:31:58 INFO - no previously-included directories found matching '.git*' 02:31:58 INFO - Successfully installed jsonschema functools32 02:31:58 INFO - Cleaning up... 02:31:58 INFO - Return code: 0 02:31:58 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 02:31:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:31:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:31:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:31:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:31:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:31:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:31:58 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 0x10ace1b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ace4768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fde13458360>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aabfd30>, '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 0x10a9e6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10aab4be8>, '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.BtJ7vr7dLN/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.5ZNk8XZnAd/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/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 02:31:58 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 02:31:58 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 02:31:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.BtJ7vr7dLN/Render', 02:31:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:31:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:31:58 INFO - 'HOME': '/Users/cltbld', 02:31:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:31:58 INFO - 'LOGNAME': 'cltbld', 02:31:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:31:58 INFO - 'MOZ_NO_REMOTE': '1', 02:31:58 INFO - 'NO_EM_RESTART': '1', 02:31:58 INFO - 'PAGER': '/bin/cat', 02:31:58 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:31:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:31:58 INFO - 'PWD': '/builds/slave/test', 02:31:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:31:58 INFO - 'SHELL': '/bin/bash', 02:31:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5ZNk8XZnAd/Listeners', 02:31:58 INFO - 'TMPDIR': '/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/', 02:31:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:31:58 INFO - 'USER': 'cltbld', 02:31:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:31:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:31:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:31:58 INFO - 'XPC_FLAGS': '0x0', 02:31:58 INFO - 'XPC_SERVICE_NAME': '0', 02:31:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:31:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:31:58 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 02:31:58 INFO - Cleaning up... 02:31:58 INFO - Return code: 0 02:31:58 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 02:31:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:31:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:31:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:31:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:31:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:31:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:31:58 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 0x10ace1b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ace4768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fde13458360>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aabfd30>, '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 0x10a9e6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10aab4be8>, '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.BtJ7vr7dLN/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.5ZNk8XZnAd/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/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 02:31:58 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 02:31:58 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 02:31:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.BtJ7vr7dLN/Render', 02:31:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:31:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:31:58 INFO - 'HOME': '/Users/cltbld', 02:31:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:31:58 INFO - 'LOGNAME': 'cltbld', 02:31:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:31:58 INFO - 'MOZ_NO_REMOTE': '1', 02:31:58 INFO - 'NO_EM_RESTART': '1', 02:31:58 INFO - 'PAGER': '/bin/cat', 02:31:58 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:31:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:31:58 INFO - 'PWD': '/builds/slave/test', 02:31:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:31:58 INFO - 'SHELL': '/bin/bash', 02:31:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5ZNk8XZnAd/Listeners', 02:31:58 INFO - 'TMPDIR': '/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/', 02:31:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:31:58 INFO - 'USER': 'cltbld', 02:31:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:31:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:31:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:31:58 INFO - 'XPC_FLAGS': '0x0', 02:31:58 INFO - 'XPC_SERVICE_NAME': '0', 02:31:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:31:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:31:59 INFO - Downloading/unpacking blobuploader==1.2.4 02:31:59 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 02:31:59 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 02:31:59 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 02:31:59 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 02:31:59 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 02:31:59 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 02:32:01 INFO - Downloading blobuploader-1.2.4.tar.gz 02:32:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 02:32:01 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 02:32:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:32:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:32:01 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:32:01 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:32:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:32:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:32:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 02:32:02 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 02:32:02 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 02:32:02 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 02:32:02 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 02:32:02 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 02:32:02 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 02:32:02 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 02:32:03 INFO - Downloading docopt-0.6.1.tar.gz 02:32:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 02:32:03 INFO - Installing collected packages: blobuploader, requests, docopt 02:32:03 INFO - Running setup.py install for blobuploader 02:32:03 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 02:32:03 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 02:32:03 INFO - Running setup.py install for requests 02:32:03 INFO - Running setup.py install for docopt 02:32:03 INFO - Successfully installed blobuploader requests docopt 02:32:03 INFO - Cleaning up... 02:32:04 INFO - Return code: 0 02:32:04 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:32: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')]} 02:32:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:32:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:32: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')]} 02:32:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:32:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:32:04 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 0x10ace1b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ace4768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fde13458360>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aabfd30>, '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 0x10a9e6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10aab4be8>, '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.BtJ7vr7dLN/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.5ZNk8XZnAd/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/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 02:32:04 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 02:32:04 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 02:32:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.BtJ7vr7dLN/Render', 02:32:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:32:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:32:04 INFO - 'HOME': '/Users/cltbld', 02:32:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:32:04 INFO - 'LOGNAME': 'cltbld', 02:32:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:32:04 INFO - 'MOZ_NO_REMOTE': '1', 02:32:04 INFO - 'NO_EM_RESTART': '1', 02:32:04 INFO - 'PAGER': '/bin/cat', 02:32:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:32:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:32:04 INFO - 'PWD': '/builds/slave/test', 02:32:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:32:04 INFO - 'SHELL': '/bin/bash', 02:32:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5ZNk8XZnAd/Listeners', 02:32:04 INFO - 'TMPDIR': '/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/', 02:32:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:32:04 INFO - 'USER': 'cltbld', 02:32:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:32:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:32:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:32:04 INFO - 'XPC_FLAGS': '0x0', 02:32:04 INFO - 'XPC_SERVICE_NAME': '0', 02:32:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:32:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:32:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 02:32:04 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-ZJIxo9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 02:32:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 02:32:04 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-V1YnZX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 02:32:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 02:32:04 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-_WgcCk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 02:32:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 02:32:04 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-kPlrPc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 02:32:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 02:32:04 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-SR7BD_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 02:32:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 02:32:04 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-WNBc4S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 02:32:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 02:32:04 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-ikwr_M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 02:32:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 02:32:05 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-K2f18t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 02:32:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 02:32:05 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-RsrFtl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 02:32:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 02:32:05 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-QInmxs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 02:32:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 02:32:05 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-16kBMd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 02:32:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 02:32:05 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-M6Eomn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 02:32:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 02:32:05 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-_D936H-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 02:32:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 02:32:05 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-RUIUTh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 02:32:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 02:32:05 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-AS_fgo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 02:32:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 02:32:05 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-ilUdy3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 02:32:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 02:32:05 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-iRd16K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 02:32:06 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 02:32:06 INFO - Running setup.py install for manifestparser 02:32:06 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 02:32:06 INFO - Running setup.py install for mozcrash 02:32:06 INFO - Running setup.py install for mozdebug 02:32:06 INFO - Running setup.py install for mozdevice 02:32:06 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 02:32:06 INFO - Installing dm script to /builds/slave/test/build/venv/bin 02:32:06 INFO - Running setup.py install for mozfile 02:32:06 INFO - Running setup.py install for mozhttpd 02:32:06 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 02:32:06 INFO - Running setup.py install for mozinfo 02:32:07 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 02:32:07 INFO - Running setup.py install for mozInstall 02:32:07 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 02:32:07 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 02:32:07 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 02:32:07 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 02:32:07 INFO - Running setup.py install for mozleak 02:32:07 INFO - Running setup.py install for mozlog 02:32:07 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 02:32:07 INFO - Running setup.py install for moznetwork 02:32:07 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 02:32:07 INFO - Running setup.py install for mozprocess 02:32:07 INFO - Running setup.py install for mozprofile 02:32:08 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 02:32:08 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 02:32:08 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 02:32:08 INFO - Running setup.py install for mozrunner 02:32:08 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 02:32:08 INFO - Running setup.py install for mozscreenshot 02:32:08 INFO - Running setup.py install for moztest 02:32:08 INFO - Running setup.py install for mozversion 02:32:08 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 02:32:08 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 02:32:08 INFO - Cleaning up... 02:32:08 INFO - Return code: 0 02:32:08 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:32:08 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')]} 02:32:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:32:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:32:08 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')]} 02:32:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:32:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:32:08 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 0x10ace1b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ace4768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fde13458360>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aabfd30>, '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 0x10a9e6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10aab4be8>, '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.BtJ7vr7dLN/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.5ZNk8XZnAd/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/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 02:32:08 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 02:32:08 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 02:32:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.BtJ7vr7dLN/Render', 02:32:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:32:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:32:08 INFO - 'HOME': '/Users/cltbld', 02:32:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:32:08 INFO - 'LOGNAME': 'cltbld', 02:32:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:32:08 INFO - 'MOZ_NO_REMOTE': '1', 02:32:08 INFO - 'NO_EM_RESTART': '1', 02:32:08 INFO - 'PAGER': '/bin/cat', 02:32:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:32:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:32:08 INFO - 'PWD': '/builds/slave/test', 02:32:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:32:08 INFO - 'SHELL': '/bin/bash', 02:32:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5ZNk8XZnAd/Listeners', 02:32:08 INFO - 'TMPDIR': '/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/', 02:32:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:32:08 INFO - 'USER': 'cltbld', 02:32:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:32:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:32:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:32:08 INFO - 'XPC_FLAGS': '0x0', 02:32:08 INFO - 'XPC_SERVICE_NAME': '0', 02:32:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:32:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:32:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 02:32:08 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-UHoKGM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 02:32:09 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)) 02:32:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 02:32:09 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-5wiOUF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 02:32:09 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)) 02:32:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 02:32:09 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-Q7bqVU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 02:32:09 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)) 02:32:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 02:32:09 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-ZlZ0Dj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 02:32:09 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)) 02:32:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 02:32:09 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-6g5FQ1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 02:32:09 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)) 02:32:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 02:32:09 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-nPlwFO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 02:32:09 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)) 02:32:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 02:32:09 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-MPYrKF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 02:32:09 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)) 02:32:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 02:32:09 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-8Dhvyn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 02:32:09 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)) 02:32:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 02:32:09 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-Ex4ywS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 02:32:09 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)) 02:32:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 02:32:09 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-s5s5pb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 02:32:10 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)) 02:32:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 02:32:10 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-eAPCsi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 02:32:10 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)) 02:32:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 02:32:10 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-qv2ZBG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 02:32:10 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)) 02:32:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 02:32:10 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-hNT8FH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 02:32:10 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)) 02:32:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 02:32:10 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-sPYE57-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 02:32:10 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)) 02:32:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 02:32:10 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-DbqLqG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 02:32:10 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)) 02:32:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 02:32:10 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-cF5KUy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 02:32:10 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)) 02:32:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 02:32:10 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-jPo95C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 02:32:10 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)) 02:32:10 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)) 02:32:10 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)) 02:32:10 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)) 02:32:10 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)) 02:32:10 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)) 02:32:10 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 02:32: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 02:32: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 02:32: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 02:32: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 02:32: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 02:32: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 02:32:13 INFO - Downloading blessings-1.6.tar.gz 02:32:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 02:32:13 INFO - Installing collected packages: blessings 02:32:13 INFO - Running setup.py install for blessings 02:32:13 INFO - Successfully installed blessings 02:32:13 INFO - Cleaning up... 02:32:13 INFO - Return code: 0 02:32:13 INFO - Done creating virtualenv /builds/slave/test/build/venv. 02:32:13 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 02:32:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 02:32:14 INFO - Reading from file tmpfile_stdout 02:32:14 INFO - Current package versions: 02:32:14 INFO - blessings == 1.6 02:32:14 INFO - blobuploader == 1.2.4 02:32:14 INFO - docopt == 0.6.1 02:32:14 INFO - functools32 == 3.2.3-2 02:32:14 INFO - jsonschema == 2.5.1 02:32:14 INFO - manifestparser == 1.1 02:32:14 INFO - mozInstall == 1.12 02:32:14 INFO - mozcrash == 1.0 02:32:14 INFO - mozdebug == 0.1 02:32:14 INFO - mozdevice == 0.49 02:32:14 INFO - mozfile == 1.2 02:32:14 INFO - mozhttpd == 0.7 02:32:14 INFO - mozinfo == 0.9 02:32:14 INFO - mozleak == 0.1 02:32:14 INFO - mozlog == 3.4 02:32:14 INFO - moznetwork == 0.27 02:32:14 INFO - mozprocess == 0.25 02:32:14 INFO - mozprofile == 0.28 02:32:14 INFO - mozrunner == 6.13 02:32:14 INFO - mozscreenshot == 0.1 02:32:14 INFO - mozsystemmonitor == 0.3 02:32:14 INFO - moztest == 0.8 02:32:14 INFO - mozversion == 1.4 02:32:14 INFO - psutil == 3.1.1 02:32:14 INFO - requests == 1.2.3 02:32:14 INFO - wsgiref == 0.1.2 02:32:14 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:32:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:32:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:32:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:32:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:32:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:32:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:32:14 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 0x10ace1b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ace4768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fde13458360>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aabfd30>, '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 0x10a9e6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10aab4be8>, '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.BtJ7vr7dLN/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.5ZNk8XZnAd/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/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 02:32:14 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 02:32:14 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 02:32:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.BtJ7vr7dLN/Render', 02:32:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:32:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:32:14 INFO - 'HOME': '/Users/cltbld', 02:32:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:32:14 INFO - 'LOGNAME': 'cltbld', 02:32:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:32:14 INFO - 'MOZ_NO_REMOTE': '1', 02:32:14 INFO - 'NO_EM_RESTART': '1', 02:32:14 INFO - 'PAGER': '/bin/cat', 02:32:14 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:32:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:32:14 INFO - 'PWD': '/builds/slave/test', 02:32:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:32:14 INFO - 'SHELL': '/bin/bash', 02:32:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5ZNk8XZnAd/Listeners', 02:32:14 INFO - 'TMPDIR': '/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/', 02:32:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:32:14 INFO - 'USER': 'cltbld', 02:32:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:32:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:32:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:32:14 INFO - 'XPC_FLAGS': '0x0', 02:32:14 INFO - 'XPC_SERVICE_NAME': '0', 02:32:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:32:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:32:14 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)) 02:32:14 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)) 02:32:14 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)) 02:32:14 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)) 02:32:14 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)) 02:32:14 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)) 02:32:14 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)) 02:32:14 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)) 02:32:14 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)) 02:32:14 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)) 02:32:14 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)) 02:32:14 INFO - Cleaning up... 02:32:14 INFO - Return code: 0 02:32:14 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 02:32:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:32:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:32:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:32:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:32:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:32:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:32:14 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10ace1b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ace4768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fde13458360>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aabfd30>, '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 0x10a9e6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10aab4be8>, '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.BtJ7vr7dLN/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.5ZNk8XZnAd/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/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 02:32:14 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 02:32:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 02:32:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.BtJ7vr7dLN/Render', 02:32:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:32:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:32:14 INFO - 'HOME': '/Users/cltbld', 02:32:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:32:14 INFO - 'LOGNAME': 'cltbld', 02:32:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:32:14 INFO - 'MOZ_NO_REMOTE': '1', 02:32:14 INFO - 'NO_EM_RESTART': '1', 02:32:14 INFO - 'PAGER': '/bin/cat', 02:32:14 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:32:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:32:14 INFO - 'PWD': '/builds/slave/test', 02:32:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:32:14 INFO - 'SHELL': '/bin/bash', 02:32:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5ZNk8XZnAd/Listeners', 02:32:14 INFO - 'TMPDIR': '/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/', 02:32:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:32:14 INFO - 'USER': 'cltbld', 02:32:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:32:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:32:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:32:14 INFO - 'XPC_FLAGS': '0x0', 02:32:14 INFO - 'XPC_SERVICE_NAME': '0', 02:32:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:32:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:32:14 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 02:32:14 INFO - Cleaning up... 02:32:14 INFO - Return code: 0 02:32:14 INFO - Running post-action listener: _resource_record_post_action 02:32:14 INFO - Running post-action listener: _start_resource_monitoring 02:32:14 INFO - Starting resource monitoring. 02:32:14 INFO - [mozharness: 2017-04-18 09:32:14.606302Z] Finished create-virtualenv step (success) 02:32:14 INFO - [mozharness: 2017-04-18 09:32:14.607281Z] Running install step. 02:32:14 INFO - Running pre-action listener: _resource_record_pre_action 02:32:14 INFO - Running main action method: install 02:32:14 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 02:32:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 02:32:14 INFO - Reading from file tmpfile_stdout 02:32:14 INFO - Detecting whether we're running mozinstall >=1.0... 02:32:14 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 02:32:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 02:32:14 INFO - Reading from file tmpfile_stdout 02:32:14 INFO - Output received: 02:32:14 INFO - Usage: mozinstall [options] installer 02:32:14 INFO - Options: 02:32:14 INFO - -h, --help show this help message and exit 02:32:14 INFO - -d DEST, --destination=DEST 02:32:14 INFO - Directory to install application into. [default: 02:32:14 INFO - "/builds/slave/test"] 02:32:14 INFO - --app=APP Application being installed. [default: firefox] 02:32:14 INFO - mkdir: /builds/slave/test/build/application 02:32:14 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'] 02:32:14 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 02:32:35 INFO - Reading from file tmpfile_stdout 02:32:35 INFO - Output received: 02:32:35 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 02:32:35 INFO - Running post-action listener: _resource_record_post_action 02:32:35 INFO - [mozharness: 2017-04-18 09:32:35.085530Z] Finished install step (success) 02:32:35 INFO - [mozharness: 2017-04-18 09:32:35.085678Z] Running run-tests step. 02:32:35 INFO - Running pre-action listener: _resource_record_pre_action 02:32:35 INFO - Running main action method: run_tests 02:32:35 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 02:32:35 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 02:32:35 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 02:32:35 INFO - 2017-04-18 02:32:35.099 screenresolution[1622:7341] starting screenresolution argv=screenresolution get 02:32:35 INFO - 2017-04-18 02:32:35.102 screenresolution[1622:7341] Display 0: 1600x1200x32@60 02:32:35 INFO - 2017-04-18 02:32:35.108 screenresolution[1623:7344] starting screenresolution argv=screenresolution list 02:32:35 INFO - Available Modes on Display 0 02:32:35 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 02:32:35 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 02:32:35 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 02:32:35 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 02:32:35 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 02:32:35 INFO - Intel Iris: 02:32:35 INFO - Chipset Model: Intel Iris 02:32:35 INFO - Type: GPU 02:32:35 INFO - Bus: Built-In 02:32:35 INFO - VRAM (Dynamic, Max): 1536 MB 02:32:35 INFO - Vendor: Intel (0x8086) 02:32:35 INFO - Device ID: 0x0a2e 02:32:35 INFO - Revision ID: 0x0009 02:32:35 INFO - Displays: 02:32:35 INFO - Display: 02:32:35 INFO - Resolution: 1600 x 1200 @ 60 Hz 02:32:35 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 02:32:35 INFO - Display Serial Number: 1600x1200 60 02:32:35 INFO - Main Display: Yes 02:32:35 INFO - Mirror: Off 02:32:35 INFO - Online: Yes 02:32:35 INFO - Rotation: Supported 02:32:35 INFO - Return code: 0 02:32:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 02:32:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 02:32:35 INFO - Python 2.7.3 02:32:35 INFO - Return code: 0 02:32:35 INFO - grabbing minidump binary from tooltool 02:32:35 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')]} 02:32:35 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 0x10aabfd30>, '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 0x10a9e6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10aab4be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 02:32:35 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 02:32:35 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 02:32:35 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 02:32:35 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 02:32:35 INFO - Return code: 0 02:32:35 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 02:32:35 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 02:32:35 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 02:32:35 INFO - ENV: RUST_BACKTRACE is now 1 02:32:35 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 02:32:35 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 02:32:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/ebjpA2HvR3u8s21cSOswGw/artifacts/public/build/firefox-54.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0154', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 02:32:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/ebjpA2HvR3u8s21cSOswGw/artifacts/public/build/firefox-54.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0154 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 02:32:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.BtJ7vr7dLN/Render', 02:32:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:32:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:32:35 INFO - 'HOME': '/Users/cltbld', 02:32:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:32:35 INFO - 'LOGNAME': 'cltbld', 02:32:35 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 02:32:35 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 02:32:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:32:35 INFO - 'MOZ_NO_REMOTE': '1', 02:32:35 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 02:32:35 INFO - 'NO_EM_RESTART': '1', 02:32:35 INFO - 'PAGER': '/bin/cat', 02:32:35 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:32:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:32:35 INFO - 'PWD': '/builds/slave/test', 02:32:35 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 02:32:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:32:35 INFO - 'RUST_BACKTRACE': '1', 02:32:35 INFO - 'SHELL': '/bin/bash', 02:32:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5ZNk8XZnAd/Listeners', 02:32:35 INFO - 'TMPDIR': '/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/', 02:32:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:32:35 INFO - 'USER': 'cltbld', 02:32:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:32:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:32:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:32:35 INFO - 'XPC_FLAGS': '0x0', 02:32:35 INFO - 'XPC_SERVICE_NAME': '0', 02:32:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:32:35 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/ebjpA2HvR3u8s21cSOswGw/artifacts/public/build/firefox-54.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0154', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 02:32:35 INFO - mozversion application_buildid: 20170418004027 02:32:35 INFO - mozversion application_changeset: 105e456d811b8c4616de688e9afdcf0af620b80b 02:32:35 INFO - mozversion application_display_name: FirefoxDeveloperEdition 02:32:35 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 02:32:35 INFO - mozversion application_name: Firefox 02:32:35 INFO - mozversion application_remotingname: firefox-dev 02:32:35 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 02:32:35 INFO - mozversion application_vendor: Mozilla 02:32:35 INFO - mozversion application_version: 54.0a2 02:32:35 INFO - mozversion platform_buildid: 20170418004027 02:32:35 INFO - mozversion platform_changeset: 105e456d811b8c4616de688e9afdcf0af620b80b 02:32:35 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 02:32:35 INFO - mozversion platform_version: 54.0a2 02:32:35 INFO - using testdate: 1492507955 02:32:35 INFO - actual date: 1492507955 02:32:35 INFO - starting webserver on 'localhost:49237' 02:32:35 INFO - SUITE-START | Running 2 tests 02:32:35 INFO - TEST-START | dromaeo_css 02:32:35 INFO - operating with platform_type : mac_ 02:32:35 INFO - Initialising browser for dromaeo_css test... 02:32:35 INFO - TEST-INFO | started process 1633 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/tmpCzxRW8/profile http://localhost:49237/getInfo.html) 02:32:38 INFO - PROCESS | 1633 | 2017-04-18 02:32:38.688 plugin-container[1636:7520] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x903f, name = 'com.apple.tsm.portname' 02:32:38 INFO - PROCESS | 1633 | See /usr/include/servers/bootstrap_defs.h for the error codes. 02:32:38 INFO - PROCESS | 1633 | 2017-04-18 02:32:38.689 plugin-container[1636:7520] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4e17, name = 'com.apple.CFPasteboardClient' 02:32:38 INFO - PROCESS | 1633 | See /usr/include/servers/bootstrap_defs.h for the error codes. 02:32:38 INFO - PROCESS | 1633 | 2017-04-18 02:32:38.689 plugin-container[1636:7520] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 02:32:38 INFO - PROCESS | 1633 | __metrics Screen width/height:1600/1200 02:32:38 INFO - PROCESS | 1633 | colorDepth:24 02:32:38 INFO - PROCESS | 1633 | Browser inner width/height: 1024/689 02:32:38 INFO - PROCESS | 1633 | __metrics 02:32:38 ERROR - PROCESS | 1633 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3360: TypeError: this.tabs is undefined 02:32:39 INFO - TEST-INFO | 1633: exit 0 02:32:39 INFO - Browser initialized. 02:32:39 INFO - Running cycle 1/1 for dromaeo_css test... 02:32:39 INFO - TEST-INFO | started process 1637 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/tmpCzxRW8/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 02:32:42 INFO - PROCESS | 1637 | 2017-04-18 02:32:42.067 plugin-container[1638:7651] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8b43, name = 'com.apple.tsm.portname' 02:32:42 INFO - PROCESS | 1637 | See /usr/include/servers/bootstrap_defs.h for the error codes. 02:32:42 INFO - PROCESS | 1637 | 2017-04-18 02:32:42.068 plugin-container[1638:7651] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x900f, name = 'com.apple.CFPasteboardClient' 02:32:42 INFO - PROCESS | 1637 | See /usr/include/servers/bootstrap_defs.h for the error codes. 02:32:42 INFO - PROCESS | 1637 | 2017-04-18 02:32:42.068 plugin-container[1638:7651] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 02:32:51 INFO - PROCESS | 1637 | RSS: Main: 220778496 02:32:51 INFO - PROCESS | 1637 | 02:35:55 INFO - PROCESS | 1637 | Cycle 1(1): loaded http://localhost:49237/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49237/tests/dromaeo/cssquery-ext.html) 02:35:57 INFO - PROCESS | 1637 | RSS: Main: 243642368 02:35:57 INFO - PROCESS | 1637 | 02:37:40 INFO - PROCESS | 1637 | ************************* 02:37:40 INFO - PROCESS | 1637 | A coding exception was thrown and uncaught in a Task. 02:37:40 INFO - PROCESS | 1637 | 02:37:40 ERROR - PROCESS | 1637 | Full message: TypeError: NetworkError when attempting to fetch resource. 02:37:40 INFO - PROCESS | 1637 | Full stack: 02:37:40 INFO - PROCESS | 1637 | ************************* 02:37:40 INFO - PROCESS | 1637 | ************************* 02:37:40 INFO - PROCESS | 1637 | A coding exception was thrown and uncaught in a Task. 02:37:40 INFO - PROCESS | 1637 | 02:37:40 ERROR - PROCESS | 1637 | Full message: TypeError: NetworkError when attempting to fetch resource. 02:37:40 INFO - PROCESS | 1637 | Full stack: 02:37:40 INFO - PROCESS | 1637 | ************************* 02:37:40 INFO - PROCESS | 1637 | ************************* 02:37:40 INFO - PROCESS | 1637 | A coding exception was thrown and uncaught in a Task. 02:37:40 INFO - PROCESS | 1637 | 02:37:40 ERROR - PROCESS | 1637 | Full message: TypeError: NetworkError when attempting to fetch resource. 02:37:40 INFO - PROCESS | 1637 | Full stack: 02:37:40 INFO - PROCESS | 1637 | ************************* 02:37:40 INFO - PROCESS | 1637 | ************************* 02:37:40 INFO - PROCESS | 1637 | A coding exception was thrown and uncaught in a Task. 02:37:40 INFO - PROCESS | 1637 | 02:37:40 ERROR - PROCESS | 1637 | Full message: TypeError: NetworkError when attempting to fetch resource. 02:37:40 INFO - PROCESS | 1637 | Full stack: 02:37:40 INFO - PROCESS | 1637 | ************************* 02:38:59 INFO - PROCESS | 1637 | Cycle 1(1): loaded http://localhost:49237/tests/dromaeo/cssquery-ext.html (next: http://localhost:49237/tests/dromaeo/cssquery-jquery.html) 02:39:00 INFO - PROCESS | 1637 | RSS: Main: 253005824 02:39:00 INFO - PROCESS | 1637 | 02:42:02 INFO - PROCESS | 1637 | Cycle 1(1): loaded http://localhost:49237/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49237/tests/dromaeo/cssquery-mootools.html) 02:42:02 INFO - PROCESS | 1637 | RSS: Main: 259256320 02:42:02 INFO - PROCESS | 1637 | 02:45:04 INFO - PROCESS | 1637 | Cycle 1(1): loaded http://localhost:49237/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49237/tests/dromaeo/cssquery-prototype.html) 02:45:05 INFO - PROCESS | 1637 | RSS: Main: 256819200 02:45:05 INFO - PROCESS | 1637 | 02:48:08 INFO - PROCESS | 1637 | Cycle 1(1): loaded http://localhost:49237/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49237/tests/dromaeo/cssquery-yui.html) 02:48:09 INFO - PROCESS | 1637 | RSS: Main: 261820416 02:48:09 INFO - PROCESS | 1637 | 02:51:12 INFO - PROCESS | 1637 | Cycle 1(1): loaded http://localhost:49237/tests/dromaeo/cssquery-yui.html (next: http://localhost:49237/tests/dromaeo/cssquery-dojo.html) 02:51:12 INFO - PROCESS | 1637 | RSS: Main: 258052096 02:51:12 INFO - PROCESS | 1637 | 02:51:12 INFO - PROCESS | 1637 | __start_tp_report 02:51:12 INFO - PROCESS | 1637 | _x_x_mozilla_page_load 02:51:12 INFO - PROCESS | 1637 | _x_x_mozilla_page_load_details 02:51:12 INFO - PROCESS | 1637 | |i|pagename|runs| 02:51:12 INFO - PROCESS | 1637 | |0;dojo.html;2972.825733835136;3344.2475443025323;3488.598811136719;3513.4027215373385;3521.6478352164786;5273.709945952971;6053.576249662526;6069.757209711612;6113.908291375627;6120.418560236782;6085.361037091108;6148.323684394724;6151.8769624607485;6166.290876549198;6168.84577885552;5422.864428389292;5529.557635389179;5530.972345138269;5534.833954981357;5669.489745922866;5571.220029195916;5578.776848926038;5580.6372585782;5594.300712410949;5596.860078498029;5389.8113566025195;5400.162974738922;5585.078462053756;5589.273394458735;5594.384617692051;5494.478024587658;5519.337679478448;5539.058360078756;5569.526590239834;5586.91619625706;5409.56723462122;5517.006938751023;5520.3651580068;5527.806526771544;5569.860753481195;5647.407022262685;5832.125181222808;5894.115882617599;6102.69486525672;6161.706041731241;6311.368863113697;6318.020706790408;6379.266384365811;6414.326495717975;6523.641199734013;6633.469322454241;6635.236947751022;6640.136782218329;6640.667966601651;6645.435138013275;6224;6233.937660623381;6256.499480041586;6271.592346497463;6286.779962701329;6393.976963685788;6543.149390579194;6609.867802644017;6635.336466353326;6669.166354205724;6406.359364063556;6434.420902118832;6453.128827608307;6455.289918109004;6460.644664543495;6550.901736473957;6557.311482294386;6597.34026597346;6619.437347825393;6622.933770662232;6242.968785156045;6289.905651415233;6522.608643481406;6560.442362399154;6577.408033277028;6239.500839932794;6330.620162790247;6423.935760642334;6462.321456247027;6490.2536208335705;4568.931466028012;5185.507376799198;5228.529432351107;5245.737713114329;5286.867828304246;4621.491635920046;4650.348951146842;4679.67242293043;4683.7423941682855;4707.670463067621;5320.627556071116;6046.576739628272;6223.066540019034;6236.812895613139;6238.282597501255;4827.696521939057;6355.872882542323;6431.935680643227;6481.870362592722;6558.147440832661;6260.7495700171485;6270.717817698214;6272.529560282997;6274.2784579773;6274.874502509924;5815.651060936358;5833.79581714638;5839.065749480063;5842.094475356326;5843.2403787507355;4842.878928026827;6540.803775886731;6638.17022872141;6708.094407254958;6851.109355783717;6452.645104519296;6562.8359291018105;6569.507286953402;6579.407853293226;6604.735810567524;6020.856037352889;6791.490638202254;6793.898091528532;6822.181338239245;6855.931440685529;6204.689765511616;6308.148399965946;6364.904526432199;6508.8047358579315;6541.051547525677;6404;6601.801945941629;6627.403533681992;6628.370304821035;6648.7340506379205;6281.52888533371;6431.421172094347;6436.67816609177;6451.258105317761;6523.706433210422;5212.635115542027;6149.907751383822;6232.345603711545;6338.239411270677;6414.069959855888;5753.079507278815;6127.234095738033;6139.216166136355;6250.218722659668;6285.402886725937;6407.974724044317;6451;6452.612843229421;6456.289808121009;6461.773837915651;5208.869778255574;6134.344744984387;6136.825445958321;6242.969909964805;6279.874402511833 02:51:12 INFO - PROCESS | 1637 | |1;ext.html;782.3545574900706;784.9372050235978;826.7891687619658;887.9334049946251;940.7365937537492;36715.449268261;37394.8781536554;39002.21995560087;39053.609463905384;39191.80404097983;31030;38174;38316.425253621215;38362.808185959104;38567.80716096423;6811.523193376465;7966.044074711042;7980.800479987989;10328.741781455448;10579.84130238047;10425.895741042606;10463.895361046369;10503.684889453329;10524.789504209912;10537.789244215111;10511.63209287675;10585.311954722956;10586.99423554761;10600.045995860371;10631.893681063168;10503.10723588496;10519;10534.789304213911;10548.894511054868;10578.259521833475;10302.484875756258;10365.896341036569;10373.481325933673;10401.063904248655;10403.94798026005;33177.50233746496;33305.50041749376;33381.499277510855;33427.49858752121;33483.6651633483;3688.79711615861;3754.5869954305003;3756.605556416564;3792.051987003249;3802.2205447883393;24286.514269714684;24446.755532444626;24959;25637.23088307354;25772.613410798855;2976.255936015996;3039.361734035855;3117.4544454720335;3188.537662038991;3219.96780032199;359556;361067.9999999974;362666;364641;365158;43996.3400548992;44003;44043.55956440394;44068.77965610151;44089.338659920126;45219.547804521535;45461.772691136335;45747.542524574994;45775.77112114485;45844.7707761459;11124.666260012213;13194.142380745412;13408.664783380493;13560.864391356157;13601.387937542835;11733.76532469363;11784.175107742376;11797.17419780607;11802.822957655642;11853.407329633485;11240.55037798479;11780.469878855472;11792.587259445878;11802.878726520967;11881.524739010516;5662.094064949588;5679.886402271932;5693.202951586781;5694.117411801178;5725.51333136688;894.5035505294494;965.3146266151111;1072.538808312433;1073.5222825842425;1082.8862969388101;22977.540449191256;25724.971001159745;26078.739212608012;26433.603495947576;26483;923.7228831350568;1109.040679811973;1124.8706398764248;1141.2524796258465;1178.746569487547;13152.342382880817;13527.864721352857;13548.47656404754;13621.318934053257;13660.590182294547;418759;419357;419580;420446;420756;49561.25658115131;50136.997260054595;55685;55731.72134139304;56121.71939140278;1391.1374947532595;1415.356013014076;1417.8657074340485;1422.4523558430078;1423.0465588056027;20373.898130509253;26186.60720089162;26401.735982640697;26508.337291567866;27217;39859.00352491211;39966.80016599898;40025.79987100046;40066;40131;452780;453670;455456;455712;456915;22928.541429171655;26293.474130517665;28325;28382.574261385682;28433;31472.58373373158;32921.17697057497;34343.31313373768;36052.46496602586;36692.266154676225;26289.737102629493;26569.60145597779;27022.594661079707;27196.864015679796;29600.259993500335;27128.457430851668;27129.186124416297;27244.72755272422;27352.45295094047;27896.302592435353;31957.68042319547;32497.512537312432;32565;32590.34819303648;32667.509987350684;27729.722702772713;27750.44499110047;27906.581401278592;27993.72006279911;28029.439411211253;19254.518637033627;20582;23106;23198.884005579865;23273.301800946 02:51:12 INFO - PROCESS | 1637 | |2;jquery.html;358.8492833010137;410.46639368820564;417.4990011985617;422.7083312514365;451.86444066779956;17685.46943591691;19910.303139390126;20625.865577393255;20681.68977465339;21558.245461408853;20960.16159353624;21985.900704964766;22373.216937407193;22931;23147;6500.154979852612;6836.145481814773;6877.20912095108;6897.034415181854;6904.5512041717375;5467.726613669321;6496.57772244805;6744.730210791562;6746.763863264787;6768.086308348384;6452.9997850333275;6712.899306510406;6732.124823772927;6733.562318449285;6755.763548275787;6456.741730330781;6583.6708164592055;6622.973439116945;6623.271440141581;6638.302978187269;6437.967810160919;6472.8705425891685;6662.533622646417;7006.404455621278;7141.214466408691;20381.592368152702;25503.362415939566;25529.744702553107;25784;26145.346366340804;3262.3964566555264;3264.5592844965863;3311.1887587541205;3346.0296514010906;3364.0748794081824;15423.383064677399;16085.597860053475;16373;16869.156542172965;16883.986960782393;2059.7528296604505;2089.8014688604558;2103.1692481469736;2112.155137944819;2323.5585238804742;129541;149069.2546537271;154351.2282438592;155079;156770.21614891966;20724.481887952923;21380.251691190733;21396.78603213979;21510.677339839916;21611.459713506974;31422.37155256928;31661.683383165873;31676.36647267042;31984.520232196537;32042.359152816814;10196.28625996188;10362;10452.790944181073;10480.580776768997;10545.578176872992;7176.390006849372;8658.090900455487;8661.783455413539;8685.131486851264;8687.34844886636;9641.903580964101;9750.804983900282;9810.969848165983;9857.472091825779;9907.702768916944;4099.159672267178;4161.9375709364385;4189.811458484314;4211.199872024366;4229.6404805591255;989.2580564576567;992.8262554053011;995.9601615935426;1003.2676146413013;1033.4625994482826;14277.857221427652;19702.605947880962;21124;21410.571788564142;21516.354509364744;848.4273115646913;998.4259051045533;1007.1791489935679;1043.5617040842865;1050.968470945873;10665.626703065356;10970.396628185526;10988;10992;11005.724856878482;772484;774865;775366.659064084;783584;796743;113222.99999999836;147309;148443.28891777576;150161;152005.47994519913;1248.4070066718236;1298.5455090718174;1321.0488448317196;1324.6754545136503;1327.6946302350514;14741.92629036848;18078.819211807713;18302.81697183011;18646.627067458845;18907.338243161423;50103.74948125236;60624;61878.690606546676;62578.687106564175;63011.05483417844;79943;81060.59469702614;87326.56336718275;89299;89450.55274723585;10504.527296271839;10532.57869685204;11059.170562207706;11571.132165087483;11821.704457388634;10384.948075259575;12820.23078615286;12915.73974554468;12925.54760583375;12937.223766574036;9512.904870951203;9550.235981121632;9653.46905920181;9748.85376719364;9761.267904907018;8466.85697430839;10223.233257505568;10330.483475826028;10578.8413173804;10831.512581933674;11164.665060048212;11279.887201127884;11315.377654229096;11386.715332116431;11400.771984560428;9044.954775226082;11254.324740515594;11369.829452558384;11630.418479076181;11653.126015548698;9754.463504507321;9909.454979975882;10870.45647717595;11113.944430277797;11196.720081998015 02:51:12 INFO - PROCESS | 1637 | |3;mootools.html;1421.381698960952;1488.5459934719906;1509.1699565239117;1510.09394363382;1544.366809608061;10636.3086399384;11074.390908500029;11227.101831853453;11256.437178141101;11383.373914434724;11072.058874995615;11243.662690119289;11244.325340479556;11245.775084498306;11269.042131418819;2372.359462945004;2409.915652952147;2411.2886698423918;2411.445367565461;2415.0822687378773;2320.118355025088;2389.163792672568;2391.6053851114543;2398.8920498577604;2403.9399015024587;2312.364099872532;2356.045801450415;2369.5616310982437;2377.263048454976;2394.437307232799;2295.231097582312;2363.8936247868887;2372.988135059331;2373.2642880707035;2376.097083108425;2357.4342157881983;2360.681308023429;2384.2727967969763;2390.916317928864;2392.0192720984314;10957.56169753209;11153.776924461547;11163.665090047312;11189.44052797357;11196.160287978435;5524.558035357162;5669.659820410748;5688.914666279968;5696.8860622787315;5715.228444160069;3449.1549570355173;3531.364354416204;3584.1039740064984;3593.2813437312643;3595.496630471736;953.427943234061;1133.9943300283528;1150.8101163308045;1173.038108750816;1174.8825117488184;11034.951679590426;13705.314734263247;14815.555533334125;15413.768793468107;15450.227488625524;4135.17296540693;4199.580041995776;4519.186546421675;4654.348391225231;4730.858074257778;11827.822582661267;11959.581414650445;11986.220895641756;12044;12083.516659333529;5636.098224284095;5673.3475650299915;5682.374938756733;5709.714514274269;5724.885502289931;4720.725404140862;5063.341765570526;5141.074606570854;5143.717095559706;5180.197069454241;3467.809270490097;3843.7117216208894;3952.4268980997595;3968.4245784361106;3984.382420724792;1001.9098281154731;1003.8343673293824;1008.1078245752561;1019.2406657040552;1020.8979102089731;790.8734602463693;797.704849205786;814.739283429297;815.8205194857122;818.7257268814894;6732.3267673232285;6761.256261811197;6815.556988795713;6932.272111428329;6981.3716765490335;844.9185043144785;933.4446004627235;965.6813251627086;987.5704068729982;1008.037324355239;4262.829486820555;4273.487181538236;4288.4210631564965;4300.698951073395;4410.2282100632265;13002.544910928073;13613.387397567134;13641.658958526115;13643.931780341034;13703.45186192541;10912.945435272773;13275.20348779057;13501.324933753272;13526.864731352758;13547;8698.826023479622;10107.545160467796;10177.69466915979;10233.641822536176;10248.846267305997;6044.969775151096;6947.513674042769;7023.894641580483;7050.788476345718;7087.822804429724;12095.81856272139;12141;12151.513939442677;12164.330961797186;12175.025997920322;12427.875721242672;12474.251544907334;12488.937555312164;12559.43482543306;12580.433880475584;8496.490210587384;8523.531205783742;8572.785680358042;8612.18184272493;8644.870326945227;10248.743781405226;10396.948015260177;10407.68776936693;10488.160947124365;10499;8875.778105547413;8887.777805554913;8894.377393582647;8978.775530611787;9020.503872287087;8578;8644.870326944974;8703.91296087031;8744.606492707717;8755.343349248704;7722.382209423345;7926.524408535506;7971.601419929097;7982.640781164978;8018.158093400342;5913.201717768046;5921.2006379138265;6014.338422773404;6147.723352449061;6190.938090619036;5440.29276194108;5616.803411880564;5797.971010144923;5925.851853703692;6015.4586087252355 02:51:12 INFO - PROCESS | 1637 | |4;prototype.html;301.26942165249267;363.88355726167623;375.4537148449007;383.1494083135439;383.81576843115306;4044.6562042226446;4980.588297016546;5235.712035838027;5510.641808282458;5552.944470555293;4507.842225522108;4634.6987445816085;5248.611881593875;5263.394709608397;5267.394249661281;3787.659110680045;4078.062045729484;4301.978490107545;4430.712003719764;4443.666899930025;4042.7966758116895;4268.9573104268875;4317.827286908519;4449;4457.353683715859;3571.624979377167;3839.0996456753805;4291.377750226231;4316.892077698051;4561.543845615429;4100;4467.642588592905;4499.498225064783;4654.39492865927;4661.277501987197;3719.9584116447436;4176.310908700061;4480.238359478896;4508.413906192174;4524.660650451229;4822.445418776815;5141.408737995141;5161.380634323905;5167.7932882684645;5211.661242019257;4801.423829140525;4904.166291730415;5253.369595648508;5397.3523177218985;5414.214938833887;4917.114919314522;4934.938988117572;5264.92102618461;5762.884742305172;5798.826035218908;4141.358089496133;4334.414853994702;4456.306603490652;4745.477997420281;4916.237983112587;5693.811465579289;5761.942380576183;5920.585559010871;5953.940460595382;5954.612950158225;4085.8978525536504;4206.663466922639;5053.519915608012;5056.974715126401;5082.618803589746;4431.246688063037;4598.815562607801;5170.353705786777;5180;5246.396664383568;4754.833580824724;4842.806287748451;5309.8938021239355;5400.351957765093;5404.432534583891;4904.435989861212;5112.642115051911;5719;5739.282589676291;5750.108733146369;1572.4653617770014;1646.6295083606092;1712.7839234143898;1740.7998080220937;1796.568823482355;1299.4607237996315;1347.9595612131652;1432.717717642704;1446.768517037269;1479.3934486860335;4535.433070866142;4971.801127954914;5310.840674779685;5371.57027437804;5384.784608615611;5513.639570431932;5659.773609055674;5869.618474799125;5899.970500147558;5902.291724992942;4772.880677983008;4852.369192005017;5012.724300163453;5068.746562671926;5260.237265596467;2744.945101097967;2886.769058475317;2954.231899706049;2968.510195817666;3086.4228369311427;5500.779968801283;5761.48146666802;6123.519974410371;6163.19878415803;6186.969065154735;6054.576179667381;6137.60105593135;6151.72317245725;6296.261126409935;6334.493240540838;5993.400659933972;6236.314005459487;6237.438630523184;6248.4376406123665;6281.434670879643;4479.529649386898;5099.36257967754;5954.672493012926;5958.34458209588;6009.158717779602;5548.974624262196;5679.563863862469;5702.8289151325525;5936.22829032223;5936.851578710567;5232.633715639869;6101.901657701656;6164.6917654118015;6184.690765461619;6200.255969283715;4000.239954408653;4666.440027196758;4680.46174689915;4687.789049492849;4720.300691751036;5089.338386009795;5090.134677104827;5604.495595396434;5906.616069955439;5925.9407405925385;5263.972751199931;5424.674519528841;5518.310211223597;5735.9139612905;5754.223179870832;5412.718540632838;5736.913946290892;5749.281339832521;5750.6837123958585;5755.942440575541;4796.760161991956;4956.330895329084;5560.277163968658;5580.1629755537;5699.715014249354;5259.789608415621;5309.522143006993;5314.867128321668;5667.291588551431;5693.658380497183;4623.260278355447;5119.771254898872;5206.771801483104;5372.059889519397;5559.360673522597 02:51:12 INFO - PROCESS | 1637 | |5;yui.html;697.1877762406797;705.2453874354443;743.1936349061268;752.964673573836;818.1572979830775;12370.628881133569;15890.52328430146;15914.363425462967;15964.521064368088;16001.199940002996;14254.786178207309;15010;15233.847661523381;15244.771328430083;15303.923480382582;849.736581659686;912.2246090822814;921.7326975177191;953.0850383631722;957.6313119449028;932.5104320231899;935.1256575102267;948.0708905272837;948.1419315519466;950.4344914775697;991.7124034030159;1002.1932344462705;1002.7192386131858;1004.4425343934114;1006.7181189267017;997.2221667099674;997.8453339732389;998.2313618513704;998.4259051045641;999.550202408919;727.6980053277883;740.6333864736989;741.6514238308041;743.2827321634594;743.3124359967009;16159.192040397933;16489.175541223012;16634.833651663448;16711.91644041784;16883.91558042202;13188.538401155913;13584.320783960762;13625.727485450334;13867.930660346634;13948.790768138486;11797.587084452089;11865.762684746256;11909.106816988673;11922.821157682642;12106.213096148722;3600.567931848169;3662.011256960606;3665.157013886794;3665.9450108248398;3800.8217452589784;111234.99999999919;114318;118586;118743;120173;10719.624813131502;10806.837897431546;10825.13398928084;11277;11340.6030788922;20113.29603463872;21926.289877216142;22154.778452215272;22207.444813879458;22237.666435003488;7432.145303290083;7696.538207707556;7853.803654908559;7901.565413902291;8074.232947869943;4268.786560671954;4313.762743049099;4390.648748100144;4450.933236001462;4477.059817438374;3435.3988052090785;3505.614382417932;3534.0988048047584;3537.1864471171775;3537.6108628051406;565.4402141879592;632.0961025733245;649.655682488282;651.2250421997902;656.7471523463405;658.769430699261;662.9270780214173;696.853660731242;711.7935798618446;715.1668306422958;9964.402135871725;10297.227707921937;10511.789764204674;10518.368897866;10605.204609654307;649.7985624456434;683.3951952522053;690.2200513419804;690.7444245629049;723.4971694672257;4589.967257367066;4969.4782047884455;5020.974895125501;5022.874428139326;5036.949630503721;102282;105608.47195763972;106446.46776766067;106886;108587.45706271418;92462.53768731114;95420;96516;97297.0270297288;97462.02537974529;55488;70582.64708676423;76405.85391219136;83117;85673;9710.902890971;9789.9021009789;9801.754956126153;9817.116459518678;9849.852252216366;53889.652758681346;54931.45068549264;55329.24441602979;55934.72032639811;57066.42933570611;66579.33420665731;66605.66697166483;67064;67243;67796;10842.349459032483;10862.239643224899;11120.833187502032;11186.944065279622;11539;10679.626213082505;12486.750264994831;12661.366931653196;12721.427535760728;12999.220046796843;11818.231814932002;11940.641780746591;11959.282443053444;12054.51781928713;12120.969717574106;11710.882891170979;11897.345645989553;11918.225315354473;12081.395930203631;12309.815352769894;9911.603535858485;11850.822237666269;11945.343006134399;11976.101792365782;12008.699782505508;8580.957095214733;8633.22176229421;8874.334424918028;8974.506402147683;9223;8788.252998494943;9587.376820506644;9878.901210987799;9896.901030989598;10006.499675016366 02:51:12 INFO - PROCESS | 1637 | __end_tp_report 02:51:12 INFO - PROCESS | 1637 | __start_cc_report 02:51:12 INFO - PROCESS | 1637 | _x_x_mozilla_cycle_collect,414 02:51:12 INFO - PROCESS | 1637 | __end_cc_report 02:51:12 INFO - PROCESS | 1637 | __startTimestamp1492509072299__endTimestamp 02:51:12 INFO - PROCESS | 1637 | 02:51:12 INFO - PROCESS | 1637 | ------- Summary: start ------- 02:51:12 INFO - PROCESS | 1637 | Number of tests: 6 02:51:12 INFO - PROCESS | 1637 | 02:51:12 INFO - PROCESS | 1637 | [#0] dojo.html Cycles:165 Average:6010.03 Median:6242.97 stddev:703.63 (11.3%) stddev-sans-first:664.23 02:51:12 INFO - PROCESS | 1637 | Values: 2972.8 3344.2 3488.6 3513.4 3521.6 5273.7 6053.6 6069.8 6113.9 6120.4 6085.4 6148.3 6151.9 6166.3 6168.8 5422.9 5529.6 5531.0 5534.8 5669.5 5571.2 5578.8 5580.6 5594.3 5596.9 5389.8 5400.2 5585.1 5589.3 5594.4 5494.5 5519.3 5539.1 5569.5 5586.9 5409.6 5517.0 5520.4 5527.8 5569.9 5647.4 5832.1 5894.1 6102.7 6161.7 6311.4 6318.0 6379.3 6414.3 6523.6 6633.5 6635.2 6640.1 6640.7 6645.4 6224.0 6233.9 6256.5 6271.6 6286.8 6394.0 6543.1 6609.9 6635.3 6669.2 6406.4 6434.4 6453.1 6455.3 6460.6 6550.9 6557.3 6597.3 6619.4 6622.9 6243.0 6289.9 6522.6 6560.4 6577.4 6239.5 6330.6 6423.9 6462.3 6490.3 4568.9 5185.5 5228.5 5245.7 5286.9 4621.5 4650.3 4679.7 4683.7 4707.7 5320.6 6046.6 6223.1 6236.8 6238.3 4827.7 6355.9 6431.9 6481.9 6558.1 6260.7 6270.7 6272.5 6274.3 6274.9 5815.7 5833.8 5839.1 5842.1 5843.2 4842.9 6540.8 6638.2 6708.1 6851.1 6452.6 6562.8 6569.5 6579.4 6604.7 6020.9 6791.5 6793.9 6822.2 6855.9 6204.7 6308.1 6364.9 6508.8 6541.1 6404.0 6601.8 6627.4 6628.4 6648.7 6281.5 6431.4 6436.7 6451.3 6523.7 5212.6 6149.9 6232.3 6338.2 6414.1 5753.1 6127.2 6139.2 6250.2 6285.4 6408.0 6451.0 6452.6 6456.3 6461.8 5208.9 6134.3 6136.8 6243.0 6279.9 02:51:12 INFO - PROCESS | 1637 | 02:51:12 INFO - PROCESS | 1637 | [#1] ext.html Cycles:180 Average:53347.75 Median:28353.79 stddev:110038.67 (388.1%) stddev-sans-first:110276.58 02:51:12 INFO - PROCESS | 1637 | Values: 782.4 784.9 826.8 887.9 940.7 36715.4 37394.9 39002.2 39053.6 39191.8 31030.0 38174.0 38316.4 38362.8 38567.8 6811.5 7966.0 7980.8 10328.7 10579.8 10425.9 10463.9 10503.7 10524.8 10537.8 10511.6 10585.3 10587.0 10600.0 10631.9 10503.1 10519.0 10534.8 10548.9 10578.3 10302.5 10365.9 10373.5 10401.1 10403.9 33177.5 33305.5 33381.5 33427.5 33483.7 3688.8 3754.6 3756.6 3792.1 3802.2 24286.5 24446.8 24959.0 25637.2 25772.6 2976.3 3039.4 3117.5 3188.5 3220.0 359556.0 361068.0 362666.0 364641.0 365158.0 43996.3 44003.0 44043.6 44068.8 44089.3 45219.5 45461.8 45747.5 45775.8 45844.8 11124.7 13194.1 13408.7 13560.9 13601.4 11733.8 11784.2 11797.2 11802.8 11853.4 11240.6 11780.5 11792.6 11802.9 11881.5 5662.1 5679.9 5693.2 5694.1 5725.5 894.5 965.3 1072.5 1073.5 1082.9 22977.5 25725.0 26078.7 26433.6 26483.0 923.7 1109.0 1124.9 1141.3 1178.7 13152.3 13527.9 13548.5 13621.3 13660.6 418759.0 419357.0 419580.0 420446.0 420756.0 49561.3 50137.0 55685.0 55731.7 56121.7 1391.1 1415.4 1417.9 1422.5 1423.0 20373.9 26186.6 26401.7 26508.3 27217.0 39859.0 39966.8 40025.8 40066.0 40131.0 452780.0 453670.0 455456.0 455712.0 456915.0 22928.5 26293.5 28325.0 28382.6 28433.0 31472.6 32921.2 34343.3 36052.5 36692.3 26289.7 26569.6 27022.6 27196.9 29600.3 27128.5 27129.2 27244.7 27352.5 27896.3 31957.7 32497.5 32565.0 32590.3 32667.5 27729.7 27750.4 27906.6 27993.7 28029.4 19254.5 20582.0 23106.0 23198.9 23273.3 02:51:12 INFO - PROCESS | 1637 | 02:51:12 INFO - PROCESS | 1637 | [#2] jquery.html Cycles:180 Average:43134.15 Median:12735.28 stddev:129623.08 (1017.8%) stddev-sans-first:129946.92 02:51:12 INFO - PROCESS | 1637 | Values: 358.8 410.5 417.5 422.7 451.9 17685.5 19910.3 20625.9 20681.7 21558.2 20960.2 21985.9 22373.2 22931.0 23147.0 6500.2 6836.1 6877.2 6897.0 6904.6 5467.7 6496.6 6744.7 6746.8 6768.1 6453.0 6712.9 6732.1 6733.6 6755.8 6456.7 6583.7 6623.0 6623.3 6638.3 6438.0 6472.9 6662.5 7006.4 7141.2 20381.6 25503.4 25529.7 25784.0 26145.3 3262.4 3264.6 3311.2 3346.0 3364.1 15423.4 16085.6 16373.0 16869.2 16884.0 2059.8 2089.8 2103.2 2112.2 2323.6 129541.0 149069.3 154351.2 155079.0 156770.2 20724.5 21380.3 21396.8 21510.7 21611.5 31422.4 31661.7 31676.4 31984.5 32042.4 10196.3 10362.0 10452.8 10480.6 10545.6 7176.4 8658.1 8661.8 8685.1 8687.3 9641.9 9750.8 9811.0 9857.5 9907.7 4099.2 4161.9 4189.8 4211.2 4229.6 989.3 992.8 996.0 1003.3 1033.5 14277.9 19702.6 21124.0 21410.6 21516.4 848.4 998.4 1007.2 1043.6 1051.0 10665.6 10970.4 10988.0 10992.0 11005.7 772484.0 774865.0 775366.7 783584.0 796743.0 113223.0 147309.0 148443.3 150161.0 152005.5 1248.4 1298.5 1321.0 1324.7 1327.7 14741.9 18078.8 18302.8 18646.6 18907.3 50103.7 60624.0 61878.7 62578.7 63011.1 79943.0 81060.6 87326.6 89299.0 89450.6 10504.5 10532.6 11059.2 11571.1 11821.7 10384.9 12820.2 12915.7 12925.5 12937.2 9512.9 9550.2 9653.5 9748.9 9761.3 8466.9 10223.2 10330.5 10578.8 10831.5 11164.7 11279.9 11315.4 11386.7 11400.8 9045.0 11254.3 11369.8 11630.4 11653.1 9754.5 9909.5 10870.5 11113.9 11196.7 02:51:12 INFO - PROCESS | 1637 | 02:51:12 INFO - PROCESS | 1637 | [#3] mootools.html Cycles:180 Average:6676.07 Median:2410.60 stddev:4163.05 (172.7%) stddev-sans-first:4156.01 02:51:12 INFO - PROCESS | 1637 | Values: 1421.4 1488.5 1509.2 1510.1 1544.4 10636.3 11074.4 11227.1 11256.4 11383.4 11072.1 11243.7 11244.3 11245.8 11269.0 2372.4 2409.9 2411.3 2411.4 2415.1 2320.1 2389.2 2391.6 2398.9 2403.9 2312.4 2356.0 2369.6 2377.3 2394.4 2295.2 2363.9 2373.0 2373.3 2376.1 2357.4 2360.7 2384.3 2390.9 2392.0 10957.6 11153.8 11163.7 11189.4 11196.2 5524.6 5669.7 5688.9 5696.9 5715.2 3449.2 3531.4 3584.1 3593.3 3595.5 953.4 1134.0 1150.8 1173.0 1174.9 11035.0 13705.3 14815.6 15413.8 15450.2 4135.2 4199.6 4519.2 4654.3 4730.9 11827.8 11959.6 11986.2 12044.0 12083.5 5636.1 5673.3 5682.4 5709.7 5724.9 4720.7 5063.3 5141.1 5143.7 5180.2 3467.8 3843.7 3952.4 3968.4 3984.4 1001.9 1003.8 1008.1 1019.2 1020.9 790.9 797.7 814.7 815.8 818.7 6732.3 6761.3 6815.6 6932.3 6981.4 844.9 933.4 965.7 987.6 1008.0 4262.8 4273.5 4288.4 4300.7 4410.2 13002.5 13613.4 13641.7 13643.9 13703.5 10912.9 13275.2 13501.3 13526.9 13547.0 8698.8 10107.5 10177.7 10233.6 10248.8 6045.0 6947.5 7023.9 7050.8 7087.8 12095.8 12141.0 12151.5 12164.3 12175.0 12427.9 12474.3 12488.9 12559.4 12580.4 8496.5 8523.5 8572.8 8612.2 8644.9 10248.7 10396.9 10407.7 10488.2 10499.0 8875.8 8887.8 8894.4 8978.8 9020.5 8578.0 8644.9 8703.9 8744.6 8755.3 7722.4 7926.5 7971.6 7982.6 8018.2 5913.2 5921.2 6014.3 6147.7 6190.9 5440.3 5616.8 5798.0 5925.9 6015.5 02:51:12 INFO - PROCESS | 1637 | 02:51:12 INFO - PROCESS | 1637 | [#4] prototype.html Cycles:180 Average:4797.12 Median:5164.59 stddev:1326.69 (25.7%) stddev-sans-first:1286.78 02:51:12 INFO - PROCESS | 1637 | Values: 301.3 363.9 375.5 383.1 383.8 4044.7 4980.6 5235.7 5510.6 5552.9 4507.8 4634.7 5248.6 5263.4 5267.4 3787.7 4078.1 4302.0 4430.7 4443.7 4042.8 4269.0 4317.8 4449.0 4457.4 3571.6 3839.1 4291.4 4316.9 4561.5 4100.0 4467.6 4499.5 4654.4 4661.3 3720.0 4176.3 4480.2 4508.4 4524.7 4822.4 5141.4 5161.4 5167.8 5211.7 4801.4 4904.2 5253.4 5397.4 5414.2 4917.1 4934.9 5264.9 5762.9 5798.8 4141.4 4334.4 4456.3 4745.5 4916.2 5693.8 5761.9 5920.6 5953.9 5954.6 4085.9 4206.7 5053.5 5057.0 5082.6 4431.2 4598.8 5170.4 5180.0 5246.4 4754.8 4842.8 5309.9 5400.4 5404.4 4904.4 5112.6 5719.0 5739.3 5750.1 1572.5 1646.6 1712.8 1740.8 1796.6 1299.5 1348.0 1432.7 1446.8 1479.4 4535.4 4971.8 5310.8 5371.6 5384.8 5513.6 5659.8 5869.6 5900.0 5902.3 4772.9 4852.4 5012.7 5068.7 5260.2 2744.9 2886.8 2954.2 2968.5 3086.4 5500.8 5761.5 6123.5 6163.2 6187.0 6054.6 6137.6 6151.7 6296.3 6334.5 5993.4 6236.3 6237.4 6248.4 6281.4 4479.5 5099.4 5954.7 5958.3 6009.2 5549.0 5679.6 5702.8 5936.2 5936.9 5232.6 6101.9 6164.7 6184.7 6200.3 4000.2 4666.4 4680.5 4687.8 4720.3 5089.3 5090.1 5604.5 5906.6 5925.9 5264.0 5424.7 5518.3 5735.9 5754.2 5412.7 5736.9 5749.3 5750.7 5755.9 4796.8 4956.3 5560.3 5580.2 5699.7 5259.8 5309.5 5314.9 5667.3 5693.7 4623.3 5119.8 5206.8 5372.1 5559.4 02:51:12 INFO - PROCESS | 1637 | 02:51:12 INFO - PROCESS | 1637 | [#5] yui.html Cycles:180 Average:20818.55 Median:4420.79 stddev:31038.80 (702.1%) stddev-sans-first:31089.11 02:51:12 INFO - PROCESS | 1637 | Values: 697.2 705.2 743.2 753.0 818.2 12370.6 15890.5 15914.4 15964.5 16001.2 14254.8 15010.0 15233.8 15244.8 15303.9 849.7 912.2 921.7 953.1 957.6 932.5 935.1 948.1 948.1 950.4 991.7 1002.2 1002.7 1004.4 1006.7 997.2 997.8 998.2 998.4 999.6 727.7 740.6 741.7 743.3 743.3 16159.2 16489.2 16634.8 16711.9 16883.9 13188.5 13584.3 13625.7 13867.9 13948.8 11797.6 11865.8 11909.1 11922.8 12106.2 3600.6 3662.0 3665.2 3665.9 3800.8 111235.0 114318.0 118586.0 118743.0 120173.0 10719.6 10806.8 10825.1 11277.0 11340.6 20113.3 21926.3 22154.8 22207.4 22237.7 7432.1 7696.5 7853.8 7901.6 8074.2 4268.8 4313.8 4390.6 4450.9 4477.1 3435.4 3505.6 3534.1 3537.2 3537.6 565.4 632.1 649.7 651.2 656.7 658.8 662.9 696.9 711.8 715.2 9964.4 10297.2 10511.8 10518.4 10605.2 649.8 683.4 690.2 690.7 723.5 4590.0 4969.5 5021.0 5022.9 5036.9 102282.0 105608.5 106446.5 106886.0 108587.5 92462.5 95420.0 96516.0 97297.0 97462.0 55488.0 70582.6 76405.9 83117.0 85673.0 9710.9 9789.9 9801.8 9817.1 9849.9 53889.7 54931.5 55329.2 55934.7 57066.4 66579.3 66605.7 67064.0 67243.0 67796.0 10842.3 10862.2 11120.8 11186.9 11539.0 10679.6 12486.8 12661.4 12721.4 12999.2 11818.2 11940.6 11959.3 12054.5 12121.0 11710.9 11897.3 11918.2 12081.4 12309.8 9911.6 11850.8 11945.3 11976.1 12008.7 8581.0 8633.2 8874.3 8974.5 9223.0 8788.3 9587.4 9878.9 9896.9 10006.5 02:51:12 INFO - PROCESS | 1637 | -------- Summary: end -------- 02:51:12 INFO - PROCESS | 1637 | 02:51:13 INFO - TEST-INFO | 1637: exit 0 02:51:13 INFO - TEST-OK | dromaeo_css | took 1117392ms 02:51:13 INFO - TEST-START | kraken 02:51:13 INFO - operating with platform_type : mac_ 02:51:13 INFO - Initialising browser for kraken test... 02:51:13 INFO - TEST-INFO | started process 1649 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/tmppt_s2J/profile http://localhost:49237/getInfo.html) 02:51:15 INFO - PROCESS | 1649 | 2017-04-18 02:51:15.978 plugin-container[1651:10228] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x893f, name = 'com.apple.tsm.portname' 02:51:15 INFO - PROCESS | 1649 | See /usr/include/servers/bootstrap_defs.h for the error codes. 02:51:15 INFO - PROCESS | 1649 | 2017-04-18 02:51:15.979 plugin-container[1651:10228] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4423, name = 'com.apple.CFPasteboardClient' 02:51:15 INFO - PROCESS | 1649 | See /usr/include/servers/bootstrap_defs.h for the error codes. 02:51:15 INFO - PROCESS | 1649 | 2017-04-18 02:51:15.979 plugin-container[1651:10228] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 02:51:16 INFO - PROCESS | 1649 | __metrics Screen width/height:1600/1200 02:51:16 INFO - PROCESS | 1649 | colorDepth:24 02:51:16 INFO - PROCESS | 1649 | Browser inner width/height: 1024/689 02:51:16 INFO - PROCESS | 1649 | __metrics 02:51:16 ERROR - PROCESS | 1649 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3360: TypeError: this.tabs is undefined 02:51:16 INFO - TEST-INFO | 1649: exit 0 02:51:16 INFO - Browser initialized. 02:51:16 INFO - Running cycle 1/1 for kraken test... 02:51:16 INFO - TEST-INFO | started process 1652 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/tmppt_s2J/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 02:51:19 INFO - PROCESS | 1652 | 2017-04-18 02:51:19.452 plugin-container[1653:10351] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4647, name = 'com.apple.tsm.portname' 02:51:19 INFO - PROCESS | 1652 | See /usr/include/servers/bootstrap_defs.h for the error codes. 02:51:19 INFO - PROCESS | 1652 | 2017-04-18 02:51:19.453 plugin-container[1653:10351] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8f0b, name = 'com.apple.CFPasteboardClient' 02:51:19 INFO - PROCESS | 1652 | See /usr/include/servers/bootstrap_defs.h for the error codes. 02:51:19 INFO - PROCESS | 1652 | 2017-04-18 02:51:19.453 plugin-container[1653:10351] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 02:51:29 INFO - PROCESS | 1652 | RSS: Main: 220868608 02:51:29 INFO - PROCESS | 1652 | 02:51:32 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49237/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49237/tests/kraken/driver.html?testName=audio-beat-detection) 02:51:33 INFO - PROCESS | 1652 | RSS: Main: 235610112 02:51:33 INFO - PROCESS | 1652 | 02:51:35 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49237/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49237/tests/kraken/driver.html?testName=audio-dft) 02:51:36 INFO - PROCESS | 1652 | RSS: Main: 243556352 02:51:36 INFO - PROCESS | 1652 | 02:51:39 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49237/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49237/tests/kraken/driver.html?testName=audio-fft) 02:51:40 INFO - PROCESS | 1652 | RSS: Main: 247332864 02:51:40 INFO - PROCESS | 1652 | 02:51:42 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49237/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49237/tests/kraken/driver.html?testName=audio-oscillator) 02:51:43 INFO - PROCESS | 1652 | RSS: Main: 248705024 02:51:43 INFO - PROCESS | 1652 | 02:51:45 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49237/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49237/tests/kraken/driver.html?testName=imaging-gaussian-blur) 02:51:45 INFO - PROCESS | 1652 | RSS: Main: 248705024 02:51:45 INFO - PROCESS | 1652 | 02:51:49 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49237/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49237/tests/kraken/driver.html?testName=imaging-darkroom) 02:51:49 INFO - PROCESS | 1652 | RSS: Main: 252792832 02:51:49 INFO - PROCESS | 1652 | 02:51:53 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49237/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49237/tests/kraken/driver.html?testName=imaging-desaturate) 02:51:53 INFO - PROCESS | 1652 | RSS: Main: 252305408 02:51:53 INFO - PROCESS | 1652 | 02:51:56 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49237/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49237/tests/kraken/driver.html?testName=json-parse-financial) 02:51:57 INFO - PROCESS | 1652 | RSS: Main: 252125184 02:51:57 INFO - PROCESS | 1652 | 02:51:59 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49237/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49237/tests/kraken/driver.html?testName=json-stringify-tinderbox) 02:51:59 INFO - PROCESS | 1652 | RSS: Main: 251310080 02:51:59 INFO - PROCESS | 1652 | 02:52:01 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49237/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49237/tests/kraken/driver.html?testName=stanford-crypto-aes) 02:52:02 INFO - PROCESS | 1652 | RSS: Main: 251351040 02:52:02 INFO - PROCESS | 1652 | 02:52:04 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49237/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49237/tests/kraken/driver.html?testName=stanford-crypto-ccm) 02:52:05 INFO - PROCESS | 1652 | RSS: Main: 251584512 02:52:05 INFO - PROCESS | 1652 | 02:52:08 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49237/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49237/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 02:52:09 INFO - PROCESS | 1652 | RSS: Main: 252256256 02:52:09 INFO - PROCESS | 1652 | 02:52:12 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49237/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49237/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 02:52:12 INFO - PROCESS | 1652 | RSS: Main: 251695104 02:52:12 INFO - PROCESS | 1652 | 02:52:14 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49237/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49237/tests/kraken/driver.html?testName=ai-astar) 02:52:14 INFO - PROCESS | 1652 | RSS: Main: 255258624 02:52:14 INFO - PROCESS | 1652 | 02:52:14 INFO - PROCESS | 1652 | __start_tp_report 02:52:14 INFO - PROCESS | 1652 | _x_x_mozilla_page_load 02:52:14 INFO - PROCESS | 1652 | _x_x_mozilla_page_load_details 02:52:14 INFO - PROCESS | 1652 | |i|pagename|runs| 02:52:14 INFO - PROCESS | 1652 | |0;ai-astar;158;178;132;120;115;119;119;118;119;120 02:52:14 INFO - PROCESS | 1652 | |1;audio-beat-detection;176;144;118;167;137;103;134;143;164;127 02:52:14 INFO - PROCESS | 1652 | |2;audio-dft;194;218;240;197;202;201;199;198;202;200 02:52:14 INFO - PROCESS | 1652 | |3;audio-fft;87;61;59;88;72;70;64;62;75;68 02:52:14 INFO - PROCESS | 1652 | |4;audio-oscillator;87;72;85;73;73;73;100;101;102;95 02:52:14 INFO - PROCESS | 1652 | |5;imaging-gaussian-blur;135;89;139;95;102;138;137;90;137;92 02:52:14 INFO - PROCESS | 1652 | |6;imaging-darkroom;94;97;96;95;97;105;156;115;127;90 02:52:14 INFO - PROCESS | 1652 | |7;imaging-desaturate;99;116;88;91;91;90;75;143;147;93 02:52:14 INFO - PROCESS | 1652 | |8;json-parse-financial;67;65;67;64;68;69;69;67;70;67 02:52:14 INFO - PROCESS | 1652 | |9;json-stringify-tinderbox;56;61;62;62;74;85;85;85;60;58 02:52:14 INFO - PROCESS | 1652 | |10;stanford-crypto-aes;78;86;83;99;104;105;113;84;90;106 02:52:14 INFO - PROCESS | 1652 | |11;stanford-crypto-ccm;140;155;142;148;154;147;166;161;209;156 02:52:14 INFO - PROCESS | 1652 | |12;stanford-crypto-pbkdf2;173;207;172;171;173;172;235;184;191;199 02:52:14 INFO - PROCESS | 1652 | |13;stanford-crypto-sha256-iterative;68;68;65;66;82;66;64;81;75;87 02:52:14 INFO - PROCESS | 1652 | __end_tp_report 02:52:14 INFO - PROCESS | 1652 | __start_cc_report 02:52:14 INFO - PROCESS | 1652 | _x_x_mozilla_cycle_collect,1022 02:52:14 INFO - PROCESS | 1652 | __end_cc_report 02:52:14 INFO - PROCESS | 1652 | __startTimestamp1492509134836__endTimestamp 02:52:14 INFO - PROCESS | 1652 | 02:52:14 INFO - PROCESS | 1652 | ------- Summary: start ------- 02:52:14 INFO - PROCESS | 1652 | Number of tests: 14 02:52:14 INFO - PROCESS | 1652 | 02:52:14 INFO - PROCESS | 1652 | [#0] ai-astar Cycles:10 Average:129.80 Median:120.00 stddev:21.14 (17.6%) stddev-sans-first:19.81 02:52:14 INFO - PROCESS | 1652 | Values: 158.0 178.0 132.0 120.0 115.0 119.0 119.0 118.0 119.0 120.0 02:52:14 INFO - PROCESS | 1652 | 02:52:14 INFO - PROCESS | 1652 | [#1] audio-beat-detection Cycles:10 Average:141.30 Median:143.50 stddev:22.79 (15.9%) stddev-sans-first:20.43 02:52:14 INFO - PROCESS | 1652 | Values: 176.0 144.0 118.0 167.0 137.0 103.0 134.0 143.0 164.0 127.0 02:52:14 INFO - PROCESS | 1652 | 02:52:14 INFO - PROCESS | 1652 | [#2] audio-dft Cycles:10 Average:205.10 Median:201.50 stddev:13.84 (6.9%) stddev-sans-first:14.08 02:52:14 INFO - PROCESS | 1652 | Values: 194.0 218.0 240.0 197.0 202.0 201.0 199.0 198.0 202.0 200.0 02:52:14 INFO - PROCESS | 1652 | 02:52:14 INFO - PROCESS | 1652 | [#3] audio-fft Cycles:10 Average:70.60 Median:71.00 stddev:10.24 (14.4%) stddev-sans-first:8.98 02:52:14 INFO - PROCESS | 1652 | Values: 87.0 61.0 59.0 88.0 72.0 70.0 64.0 62.0 75.0 68.0 02:52:14 INFO - PROCESS | 1652 | 02:52:14 INFO - PROCESS | 1652 | [#4] audio-oscillator Cycles:10 Average:86.10 Median:73.00 stddev:12.75 (17.5%) stddev-sans-first:13.52 02:52:14 INFO - PROCESS | 1652 | Values: 87.0 72.0 85.0 73.0 73.0 73.0 100.0 101.0 102.0 95.0 02:52:14 INFO - PROCESS | 1652 | 02:52:14 INFO - PROCESS | 1652 | [#5] imaging-gaussian-blur Cycles:10 Average:115.40 Median:114.00 stddev:23.26 (20.4%) stddev-sans-first:23.57 02:52:14 INFO - PROCESS | 1652 | Values: 135.0 89.0 139.0 95.0 102.0 138.0 137.0 90.0 137.0 92.0 02:52:14 INFO - PROCESS | 1652 | 02:52:14 INFO - PROCESS | 1652 | [#6] imaging-darkroom Cycles:10 Average:107.20 Median:94.50 stddev:20.53 (21.7%) stddev-sans-first:21.21 02:52:14 INFO - PROCESS | 1652 | Values: 94.0 97.0 96.0 95.0 97.0 105.0 156.0 115.0 127.0 90.0 02:52:14 INFO - PROCESS | 1652 | 02:52:14 INFO - PROCESS | 1652 | [#7] imaging-desaturate Cycles:10 Average:103.30 Median:90.50 stddev:24.24 (26.8%) stddev-sans-first:25.66 02:52:14 INFO - PROCESS | 1652 | Values: 99.0 116.0 88.0 91.0 91.0 90.0 75.0 143.0 147.0 93.0 02:52:14 INFO - PROCESS | 1652 | 02:52:14 INFO - PROCESS | 1652 | [#8] json-parse-financial Cycles:10 Average:67.30 Median:67.50 stddev:1.83 (2.7%) stddev-sans-first:1.94 02:52:14 INFO - PROCESS | 1652 | Values: 67.0 65.0 67.0 64.0 68.0 69.0 69.0 67.0 70.0 67.0 02:52:14 INFO - PROCESS | 1652 | 02:52:14 INFO - PROCESS | 1652 | [#9] json-stringify-tinderbox Cycles:10 Average:68.80 Median:68.00 stddev:12.14 (17.8%) stddev-sans-first:11.96 02:52:14 INFO - PROCESS | 1652 | Values: 56.0 61.0 62.0 62.0 74.0 85.0 85.0 85.0 60.0 58.0 02:52:14 INFO - PROCESS | 1652 | 02:52:14 INFO - PROCESS | 1652 | [#10] stanford-crypto-aes Cycles:10 Average:94.80 Median:83.50 stddev:12.03 (14.4%) stddev-sans-first:11.11 02:52:14 INFO - PROCESS | 1652 | Values: 78.0 86.0 83.0 99.0 104.0 105.0 113.0 84.0 90.0 106.0 02:52:14 INFO - PROCESS | 1652 | 02:52:14 INFO - PROCESS | 1652 | [#11] stanford-crypto-ccm Cycles:10 Average:157.80 Median:155.50 stddev:19.73 (12.7%) stddev-sans-first:19.85 02:52:14 INFO - PROCESS | 1652 | Values: 140.0 155.0 142.0 148.0 154.0 147.0 166.0 161.0 209.0 156.0 02:52:14 INFO - PROCESS | 1652 | 02:52:14 INFO - PROCESS | 1652 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:187.70 Median:187.50 stddev:20.99 (11.2%) stddev-sans-first:21.58 02:52:14 INFO - PROCESS | 1652 | Values: 173.0 207.0 172.0 171.0 173.0 172.0 235.0 184.0 191.0 199.0 02:52:14 INFO - PROCESS | 1652 | 02:52:14 INFO - PROCESS | 1652 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:72.20 Median:71.50 stddev:8.38 (11.7%) stddev-sans-first:8.75 02:52:14 INFO - PROCESS | 1652 | Values: 68.0 68.0 65.0 66.0 82.0 66.0 64.0 81.0 75.0 87.0 02:52:14 INFO - PROCESS | 1652 | -------- Summary: end -------- 02:52:14 INFO - PROCESS | 1652 | 02:52:15 INFO - TEST-INFO | 1652: exit 0 02:52:15 INFO - TEST-OK | kraken | took 62686ms 02:52:15 INFO - SUITE-END | took 1180s 02:52:15 INFO - Completed test suite (00:19:40) 02:52:15 INFO - javascript benchmark 02:52:16 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7652.655052997458, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2972.825733835136, 3344.2475443025323, 3488.598811136719, 3513.4027215373385, 3521.6478352164786, 5273.709945952971, 6053.576249662526, 6069.757209711612, 6113.908291375627, 6120.418560236782, 6085.361037091108, 6148.323684394724, 6151.8769624607485, 6166.290876549198, 6168.84577885552, 5422.864428389292, 5529.557635389179, 5530.972345138269, 5534.833954981357, 5669.489745922866, 5571.220029195916, 5578.776848926038, 5580.6372585782, 5594.300712410949, 5596.860078498029, 5389.8113566025195, 5400.162974738922, 5585.078462053756, 5589.273394458735, 5594.384617692051, 5494.478024587658, 5519.337679478448, 5539.058360078756, 5569.526590239834, 5586.91619625706, 5409.56723462122, 5517.006938751023, 5520.3651580068, 5527.806526771544, 5569.860753481195, 5647.407022262685, 5832.125181222808, 5894.115882617599, 6102.69486525672, 6161.706041731241, 6311.368863113697, 6318.020706790408, 6379.266384365811, 6414.326495717975, 6523.641199734013, 6633.469322454241, 6635.236947751022, 6640.136782218329, 6640.667966601651, 6645.435138013275, 6224.0, 6233.937660623381, 6256.499480041586, 6271.592346497463, 6286.779962701329, 6393.976963685788, 6543.149390579194, 6609.867802644017, 6635.336466353326, 6669.166354205724, 6406.359364063556, 6434.420902118832, 6453.128827608307, 6455.289918109004, 6460.644664543495, 6550.901736473957, 6557.311482294386, 6597.34026597346, 6619.437347825393, 6622.933770662232, 6242.968785156045, 6289.905651415233, 6522.608643481406, 6560.442362399154, 6577.408033277028, 6239.500839932794, 6330.620162790247, 6423.935760642334, 6462.321456247027, 6490.2536208335705, 4568.931466028012, 5185.507376799198, 5228.529432351107, 5245.737713114329, 5286.867828304246, 4621.491635920046, 4650.348951146842, 4679.67242293043, 4683.7423941682855, 4707.670463067621, 5320.627556071116, 6046.576739628272, 6223.066540019034, 6236.812895613139, 6238.282597501255, 4827.696521939057, 6355.872882542323, 6431.935680643227, 6481.870362592722, 6558.147440832661, 6260.7495700171485, 6270.717817698214, 6272.529560282997, 6274.2784579773, 6274.874502509924, 5815.651060936358, 5833.79581714638, 5839.065749480063, 5842.094475356326, 5843.2403787507355, 4842.878928026827, 6540.803775886731, 6638.17022872141, 6708.094407254958, 6851.109355783717, 6452.645104519296, 6562.8359291018105, 6569.507286953402, 6579.407853293226, 6604.735810567524, 6020.856037352889, 6791.490638202254, 6793.898091528532, 6822.181338239245, 6855.931440685529, 6204.689765511616, 6308.148399965946, 6364.904526432199, 6508.8047358579315, 6541.051547525677, 6404.0, 6601.801945941629, 6627.403533681992, 6628.370304821035, 6648.7340506379205, 6281.52888533371, 6431.421172094347, 6436.67816609177, 6451.258105317761, 6523.706433210422, 5212.635115542027, 6149.907751383822, 6232.345603711545, 6338.239411270677, 6414.069959855888, 5753.079507278815, 6127.234095738033, 6139.216166136355, 6250.218722659668, 6285.402886725937, 6407.974724044317, 6451.0, 6452.612843229421, 6456.289808121009, 6461.773837915651, 5208.869778255574, 6134.344744984387, 6136.825445958321, 6242.969909964805, 6279.874402511833], "value": 5965.640790409417, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [782.3545574900706, 784.9372050235978, 826.7891687619658, 887.9334049946251, 940.7365937537492, 36715.449268261, 37394.8781536554, 39002.21995560087, 39053.609463905384, 39191.80404097983, 31030.0, 38174.0, 38316.425253621215, 38362.808185959104, 38567.80716096423, 6811.523193376465, 7966.044074711042, 7980.800479987989, 10328.741781455448, 10579.84130238047, 10425.895741042606, 10463.895361046369, 10503.684889453329, 10524.789504209912, 10537.789244215111, 10511.63209287675, 10585.311954722956, 10586.99423554761, 10600.045995860371, 10631.893681063168, 10503.10723588496, 10519.0, 10534.789304213911, 10548.894511054868, 10578.259521833475, 10302.484875756258, 10365.896341036569, 10373.481325933673, 10401.063904248655, 10403.94798026005, 33177.50233746496, 33305.50041749376, 33381.499277510855, 33427.49858752121, 33483.6651633483, 3688.79711615861, 3754.5869954305003, 3756.605556416564, 3792.051987003249, 3802.2205447883393, 24286.514269714684, 24446.755532444626, 24959.0, 25637.23088307354, 25772.613410798855, 2976.255936015996, 3039.361734035855, 3117.4544454720335, 3188.537662038991, 3219.96780032199, 359556.0, 361067.9999999974, 362666.0, 364641.0, 365158.0, 43996.3400548992, 44003.0, 44043.55956440394, 44068.77965610151, 44089.338659920126, 45219.547804521535, 45461.772691136335, 45747.542524574994, 45775.77112114485, 45844.7707761459, 11124.666260012213, 13194.142380745412, 13408.664783380493, 13560.864391356157, 13601.387937542835, 11733.76532469363, 11784.175107742376, 11797.17419780607, 11802.822957655642, 11853.407329633485, 11240.55037798479, 11780.469878855472, 11792.587259445878, 11802.878726520967, 11881.524739010516, 5662.094064949588, 5679.886402271932, 5693.202951586781, 5694.117411801178, 5725.51333136688, 894.5035505294494, 965.3146266151111, 1072.538808312433, 1073.5222825842425, 1082.8862969388101, 22977.540449191256, 25724.971001159745, 26078.739212608012, 26433.603495947576, 26483.0, 923.7228831350568, 1109.040679811973, 1124.8706398764248, 1141.2524796258465, 1178.746569487547, 13152.342382880817, 13527.864721352857, 13548.47656404754, 13621.318934053257, 13660.590182294547, 418759.0, 419357.0, 419580.0, 420446.0, 420756.0, 49561.25658115131, 50136.997260054595, 55685.0, 55731.72134139304, 56121.71939140278, 1391.1374947532595, 1415.356013014076, 1417.8657074340485, 1422.4523558430078, 1423.0465588056027, 20373.898130509253, 26186.60720089162, 26401.735982640697, 26508.337291567866, 27217.0, 39859.00352491211, 39966.80016599898, 40025.79987100046, 40066.0, 40131.0, 452780.0, 453670.0, 455456.0, 455712.0, 456915.0, 22928.541429171655, 26293.474130517665, 28325.0, 28382.574261385682, 28433.0, 31472.58373373158, 32921.17697057497, 34343.31313373768, 36052.46496602586, 36692.266154676225, 26289.737102629493, 26569.60145597779, 27022.594661079707, 27196.864015679796, 29600.259993500335, 27128.457430851668, 27129.186124416297, 27244.72755272422, 27352.45295094047, 27896.302592435353, 31957.68042319547, 32497.512537312432, 32565.0, 32590.34819303648, 32667.509987350684, 27729.722702772713, 27750.44499110047, 27906.581401278592, 27993.72006279911, 28029.439411211253, 19254.518637033627, 20582.0, 23106.0, 23198.884005579865, 23273.301800946], "value": 17610.6323858048, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [358.8492833010137, 410.46639368820564, 417.4990011985617, 422.7083312514365, 451.86444066779956, 17685.46943591691, 19910.303139390126, 20625.865577393255, 20681.68977465339, 21558.245461408853, 20960.16159353624, 21985.900704964766, 22373.216937407193, 22931.0, 23147.0, 6500.154979852612, 6836.145481814773, 6877.20912095108, 6897.034415181854, 6904.5512041717375, 5467.726613669321, 6496.57772244805, 6744.730210791562, 6746.763863264787, 6768.086308348384, 6452.9997850333275, 6712.899306510406, 6732.124823772927, 6733.562318449285, 6755.763548275787, 6456.741730330781, 6583.6708164592055, 6622.973439116945, 6623.271440141581, 6638.302978187269, 6437.967810160919, 6472.8705425891685, 6662.533622646417, 7006.404455621278, 7141.214466408691, 20381.592368152702, 25503.362415939566, 25529.744702553107, 25784.0, 26145.346366340804, 3262.3964566555264, 3264.5592844965863, 3311.1887587541205, 3346.0296514010906, 3364.0748794081824, 15423.383064677399, 16085.597860053475, 16373.0, 16869.156542172965, 16883.986960782393, 2059.7528296604505, 2089.8014688604558, 2103.1692481469736, 2112.155137944819, 2323.5585238804742, 129541.0, 149069.2546537271, 154351.2282438592, 155079.0, 156770.21614891966, 20724.481887952923, 21380.251691190733, 21396.78603213979, 21510.677339839916, 21611.459713506974, 31422.37155256928, 31661.683383165873, 31676.36647267042, 31984.520232196537, 32042.359152816814, 10196.28625996188, 10362.0, 10452.790944181073, 10480.580776768997, 10545.578176872992, 7176.390006849372, 8658.090900455487, 8661.783455413539, 8685.131486851264, 8687.34844886636, 9641.903580964101, 9750.804983900282, 9810.969848165983, 9857.472091825779, 9907.702768916944, 4099.159672267178, 4161.9375709364385, 4189.811458484314, 4211.199872024366, 4229.6404805591255, 989.2580564576567, 992.8262554053011, 995.9601615935426, 1003.2676146413013, 1033.4625994482826, 14277.857221427652, 19702.605947880962, 21124.0, 21410.571788564142, 21516.354509364744, 848.4273115646913, 998.4259051045533, 1007.1791489935679, 1043.5617040842865, 1050.968470945873, 10665.626703065356, 10970.396628185526, 10988.0, 10992.0, 11005.724856878482, 772484.0, 774865.0, 775366.659064084, 783584.0, 796743.0, 113222.99999999836, 147309.0, 148443.28891777576, 150161.0, 152005.47994519913, 1248.4070066718236, 1298.5455090718174, 1321.0488448317196, 1324.6754545136503, 1327.6946302350514, 14741.92629036848, 18078.819211807713, 18302.81697183011, 18646.627067458845, 18907.338243161423, 50103.74948125236, 60624.0, 61878.690606546676, 62578.687106564175, 63011.05483417844, 79943.0, 81060.59469702614, 87326.56336718275, 89299.0, 89450.55274723585, 10504.527296271839, 10532.57869685204, 11059.170562207706, 11571.132165087483, 11821.704457388634, 10384.948075259575, 12820.23078615286, 12915.73974554468, 12925.54760583375, 12937.223766574036, 9512.904870951203, 9550.235981121632, 9653.46905920181, 9748.85376719364, 9761.267904907018, 8466.85697430839, 10223.233257505568, 10330.483475826028, 10578.8413173804, 10831.512581933674, 11164.665060048212, 11279.887201127884, 11315.377654229096, 11386.715332116431, 11400.771984560428, 9044.954775226082, 11254.324740515594, 11369.829452558384, 11630.418479076181, 11653.126015548698, 9754.463504507321, 9909.454979975882, 10870.45647717595, 11113.944430277797, 11196.720081998015], "value": 11424.790451881716, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1421.381698960952, 1488.5459934719906, 1509.1699565239117, 1510.09394363382, 1544.366809608061, 10636.3086399384, 11074.390908500029, 11227.101831853453, 11256.437178141101, 11383.373914434724, 11072.058874995615, 11243.662690119289, 11244.325340479556, 11245.775084498306, 11269.042131418819, 2372.359462945004, 2409.915652952147, 2411.2886698423918, 2411.445367565461, 2415.0822687378773, 2320.118355025088, 2389.163792672568, 2391.6053851114543, 2398.8920498577604, 2403.9399015024587, 2312.364099872532, 2356.045801450415, 2369.5616310982437, 2377.263048454976, 2394.437307232799, 2295.231097582312, 2363.8936247868887, 2372.988135059331, 2373.2642880707035, 2376.097083108425, 2357.4342157881983, 2360.681308023429, 2384.2727967969763, 2390.916317928864, 2392.0192720984314, 10957.56169753209, 11153.776924461547, 11163.665090047312, 11189.44052797357, 11196.160287978435, 5524.558035357162, 5669.659820410748, 5688.914666279968, 5696.8860622787315, 5715.228444160069, 3449.1549570355173, 3531.364354416204, 3584.1039740064984, 3593.2813437312643, 3595.496630471736, 953.427943234061, 1133.9943300283528, 1150.8101163308045, 1173.038108750816, 1174.8825117488184, 11034.951679590426, 13705.314734263247, 14815.555533334125, 15413.768793468107, 15450.227488625524, 4135.17296540693, 4199.580041995776, 4519.186546421675, 4654.348391225231, 4730.858074257778, 11827.822582661267, 11959.581414650445, 11986.220895641756, 12044.0, 12083.516659333529, 5636.098224284095, 5673.3475650299915, 5682.374938756733, 5709.714514274269, 5724.885502289931, 4720.725404140862, 5063.341765570526, 5141.074606570854, 5143.717095559706, 5180.197069454241, 3467.809270490097, 3843.7117216208894, 3952.4268980997595, 3968.4245784361106, 3984.382420724792, 1001.9098281154731, 1003.8343673293824, 1008.1078245752561, 1019.2406657040552, 1020.8979102089731, 790.8734602463693, 797.704849205786, 814.739283429297, 815.8205194857122, 818.7257268814894, 6732.3267673232285, 6761.256261811197, 6815.556988795713, 6932.272111428329, 6981.3716765490335, 844.9185043144785, 933.4446004627235, 965.6813251627086, 987.5704068729982, 1008.037324355239, 4262.829486820555, 4273.487181538236, 4288.4210631564965, 4300.698951073395, 4410.2282100632265, 13002.544910928073, 13613.387397567134, 13641.658958526115, 13643.931780341034, 13703.45186192541, 10912.945435272773, 13275.20348779057, 13501.324933753272, 13526.864731352758, 13547.0, 8698.826023479622, 10107.545160467796, 10177.69466915979, 10233.641822536176, 10248.846267305997, 6044.969775151096, 6947.513674042769, 7023.894641580483, 7050.788476345718, 7087.822804429724, 12095.81856272139, 12141.0, 12151.513939442677, 12164.330961797186, 12175.025997920322, 12427.875721242672, 12474.251544907334, 12488.937555312164, 12559.43482543306, 12580.433880475584, 8496.490210587384, 8523.531205783742, 8572.785680358042, 8612.18184272493, 8644.870326945227, 10248.743781405226, 10396.948015260177, 10407.68776936693, 10488.160947124365, 10499.0, 8875.778105547413, 8887.777805554913, 8894.377393582647, 8978.775530611787, 9020.503872287087, 8578.0, 8644.870326944974, 8703.91296087031, 8744.606492707717, 8755.343349248704, 7722.382209423345, 7926.524408535506, 7971.601419929097, 7982.640781164978, 8018.158093400342, 5913.201717768046, 5921.2006379138265, 6014.338422773404, 6147.723352449061, 6190.938090619036, 5440.29276194108, 5616.803411880564, 5797.971010144923, 5925.851853703692, 6015.4586087252355], "value": 5063.408434715895, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [301.26942165249267, 363.88355726167623, 375.4537148449007, 383.1494083135439, 383.81576843115306, 4044.6562042226446, 4980.588297016546, 5235.712035838027, 5510.641808282458, 5552.944470555293, 4507.842225522108, 4634.6987445816085, 5248.611881593875, 5263.394709608397, 5267.394249661281, 3787.659110680045, 4078.062045729484, 4301.978490107545, 4430.712003719764, 4443.666899930025, 4042.7966758116895, 4268.9573104268875, 4317.827286908519, 4449.0, 4457.353683715859, 3571.624979377167, 3839.0996456753805, 4291.377750226231, 4316.892077698051, 4561.543845615429, 4100.0, 4467.642588592905, 4499.498225064783, 4654.39492865927, 4661.277501987197, 3719.9584116447436, 4176.310908700061, 4480.238359478896, 4508.413906192174, 4524.660650451229, 4822.445418776815, 5141.408737995141, 5161.380634323905, 5167.7932882684645, 5211.661242019257, 4801.423829140525, 4904.166291730415, 5253.369595648508, 5397.3523177218985, 5414.214938833887, 4917.114919314522, 4934.938988117572, 5264.92102618461, 5762.884742305172, 5798.826035218908, 4141.358089496133, 4334.414853994702, 4456.306603490652, 4745.477997420281, 4916.237983112587, 5693.811465579289, 5761.942380576183, 5920.585559010871, 5953.940460595382, 5954.612950158225, 4085.8978525536504, 4206.663466922639, 5053.519915608012, 5056.974715126401, 5082.618803589746, 4431.246688063037, 4598.815562607801, 5170.353705786777, 5180.0, 5246.396664383568, 4754.833580824724, 4842.806287748451, 5309.8938021239355, 5400.351957765093, 5404.432534583891, 4904.435989861212, 5112.642115051911, 5719.0, 5739.282589676291, 5750.108733146369, 1572.4653617770014, 1646.6295083606092, 1712.7839234143898, 1740.7998080220937, 1796.568823482355, 1299.4607237996315, 1347.9595612131652, 1432.717717642704, 1446.768517037269, 1479.3934486860335, 4535.433070866142, 4971.801127954914, 5310.840674779685, 5371.57027437804, 5384.784608615611, 5513.639570431932, 5659.773609055674, 5869.618474799125, 5899.970500147558, 5902.291724992942, 4772.880677983008, 4852.369192005017, 5012.724300163453, 5068.746562671926, 5260.237265596467, 2744.945101097967, 2886.769058475317, 2954.231899706049, 2968.510195817666, 3086.4228369311427, 5500.779968801283, 5761.48146666802, 6123.519974410371, 6163.19878415803, 6186.969065154735, 6054.576179667381, 6137.60105593135, 6151.72317245725, 6296.261126409935, 6334.493240540838, 5993.400659933972, 6236.314005459487, 6237.438630523184, 6248.4376406123665, 6281.434670879643, 4479.529649386898, 5099.36257967754, 5954.672493012926, 5958.34458209588, 6009.158717779602, 5548.974624262196, 5679.563863862469, 5702.8289151325525, 5936.22829032223, 5936.851578710567, 5232.633715639869, 6101.901657701656, 6164.6917654118015, 6184.690765461619, 6200.255969283715, 4000.239954408653, 4666.440027196758, 4680.46174689915, 4687.789049492849, 4720.300691751036, 5089.338386009795, 5090.134677104827, 5604.495595396434, 5906.616069955439, 5925.9407405925385, 5263.972751199931, 5424.674519528841, 5518.310211223597, 5735.9139612905, 5754.223179870832, 5412.718540632838, 5736.913946290892, 5749.281339832521, 5750.6837123958585, 5755.942440575541, 4796.760161991956, 4956.330895329084, 5560.277163968658, 5580.1629755537, 5699.715014249354, 5259.789608415621, 5309.522143006993, 5314.867128321668, 5667.291588551431, 5693.658380497183, 4623.260278355447, 5119.771254898872, 5206.771801483104, 5372.059889519397, 5559.360673522597], "value": 4415.314551271969, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [697.1877762406797, 705.2453874354443, 743.1936349061268, 752.964673573836, 818.1572979830775, 12370.628881133569, 15890.52328430146, 15914.363425462967, 15964.521064368088, 16001.199940002996, 14254.786178207309, 15010.0, 15233.847661523381, 15244.771328430083, 15303.923480382582, 849.736581659686, 912.2246090822814, 921.7326975177191, 953.0850383631722, 957.6313119449028, 932.5104320231899, 935.1256575102267, 948.0708905272837, 948.1419315519466, 950.4344914775697, 991.7124034030159, 1002.1932344462705, 1002.7192386131858, 1004.4425343934114, 1006.7181189267017, 997.2221667099674, 997.8453339732389, 998.2313618513704, 998.4259051045641, 999.550202408919, 727.6980053277883, 740.6333864736989, 741.6514238308041, 743.2827321634594, 743.3124359967009, 16159.192040397933, 16489.175541223012, 16634.833651663448, 16711.91644041784, 16883.91558042202, 13188.538401155913, 13584.320783960762, 13625.727485450334, 13867.930660346634, 13948.790768138486, 11797.587084452089, 11865.762684746256, 11909.106816988673, 11922.821157682642, 12106.213096148722, 3600.567931848169, 3662.011256960606, 3665.157013886794, 3665.9450108248398, 3800.8217452589784, 111234.99999999919, 114318.0, 118586.0, 118743.0, 120173.0, 10719.624813131502, 10806.837897431546, 10825.13398928084, 11277.0, 11340.6030788922, 20113.29603463872, 21926.289877216142, 22154.778452215272, 22207.444813879458, 22237.666435003488, 7432.145303290083, 7696.538207707556, 7853.803654908559, 7901.565413902291, 8074.232947869943, 4268.786560671954, 4313.762743049099, 4390.648748100144, 4450.933236001462, 4477.059817438374, 3435.3988052090785, 3505.614382417932, 3534.0988048047584, 3537.1864471171775, 3537.6108628051406, 565.4402141879592, 632.0961025733245, 649.655682488282, 651.2250421997902, 656.7471523463405, 658.769430699261, 662.9270780214173, 696.853660731242, 711.7935798618446, 715.1668306422958, 9964.402135871725, 10297.227707921937, 10511.789764204674, 10518.368897866, 10605.204609654307, 649.7985624456434, 683.3951952522053, 690.2200513419804, 690.7444245629049, 723.4971694672257, 4589.967257367066, 4969.4782047884455, 5020.974895125501, 5022.874428139326, 5036.949630503721, 102282.0, 105608.47195763972, 106446.46776766067, 106886.0, 108587.45706271418, 92462.53768731114, 95420.0, 96516.0, 97297.0270297288, 97462.02537974529, 55488.0, 70582.64708676423, 76405.85391219136, 83117.0, 85673.0, 9710.902890971, 9789.9021009789, 9801.754956126153, 9817.116459518678, 9849.852252216366, 53889.652758681346, 54931.45068549264, 55329.24441602979, 55934.72032639811, 57066.42933570611, 66579.33420665731, 66605.66697166483, 67064.0, 67243.0, 67796.0, 10842.349459032483, 10862.239643224899, 11120.833187502032, 11186.944065279622, 11539.0, 10679.626213082505, 12486.750264994831, 12661.366931653196, 12721.427535760728, 12999.220046796843, 11818.231814932002, 11940.641780746591, 11959.282443053444, 12054.51781928713, 12120.969717574106, 11710.882891170979, 11897.345645989553, 11918.225315354473, 12081.395930203631, 12309.815352769894, 9911.603535858485, 11850.822237666269, 11945.343006134399, 11976.101792365782, 12008.699782505508, 8580.957095214733, 8633.22176229421, 8874.334424918028, 8974.506402147683, 9223.0, 8788.252998494943, 9587.376820506644, 9878.901210987799, 9896.901030989598, 10006.499675016366], "value": 7484.293399974866, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1607.4, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [158.0, 178.0, 132.0, 120.0, 115.0, 119.0, 119.0, 118.0, 119.0, 120.0], "value": 129.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [176.0, 144.0, 118.0, 167.0, 137.0, 103.0, 134.0, 143.0, 164.0, 127.0], "value": 141.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [194.0, 218.0, 240.0, 197.0, 202.0, 201.0, 199.0, 198.0, 202.0, 200.0], "value": 205.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 61.0, 59.0, 88.0, 72.0, 70.0, 64.0, 62.0, 75.0, 68.0], "value": 70.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 72.0, 85.0, 73.0, 73.0, 73.0, 100.0, 101.0, 102.0, 95.0], "value": 86.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [135.0, 89.0, 139.0, 95.0, 102.0, 138.0, 137.0, 90.0, 137.0, 92.0], "value": 115.4, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 97.0, 96.0, 95.0, 97.0, 105.0, 156.0, 115.0, 127.0, 90.0], "value": 107.2, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [99.0, 116.0, 88.0, 91.0, 91.0, 90.0, 75.0, 143.0, 147.0, 93.0], "value": 103.3, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 65.0, 67.0, 64.0, 68.0, 69.0, 69.0, 67.0, 70.0, 67.0], "value": 67.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 61.0, 62.0, 62.0, 74.0, 85.0, 85.0, 85.0, 60.0, 58.0], "value": 68.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 86.0, 83.0, 99.0, 104.0, 105.0, 113.0, 84.0, 90.0, 106.0], "value": 94.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [140.0, 155.0, 142.0, 148.0, 154.0, 147.0, 166.0, 161.0, 209.0, 156.0], "value": 157.8, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [173.0, 207.0, 172.0, 171.0, 173.0, 172.0, 235.0, 184.0, 191.0, 199.0], "value": 187.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 68.0, 65.0, 66.0, 82.0, 66.0, 64.0, 81.0, 75.0, 87.0], "value": 72.2, "unit": "score"}]}]} 02:52:16 INFO - Return code: 0 02:52:16 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 02:52:16 ERROR - # TBPL SUCCESS # 02:52:16 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 02:52:16 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 02:52:16 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 02:52:16 INFO - 2017-04-18 02:52:16.153 screenresolution[1657:10735] starting screenresolution argv=screenresolution get 02:52:16 INFO - 2017-04-18 02:52:16.156 screenresolution[1657:10735] Display 0: 1600x1200x32@60 02:52:16 INFO - 2017-04-18 02:52:16.163 screenresolution[1658:10739] starting screenresolution argv=screenresolution list 02:52:16 INFO - Available Modes on Display 0 02:52:16 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 02:52:16 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 02:52:16 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 02:52:16 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 02:52:16 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 02:52:16 INFO - Intel Iris: 02:52:16 INFO - Chipset Model: Intel Iris 02:52:16 INFO - Type: GPU 02:52:16 INFO - Bus: Built-In 02:52:16 INFO - VRAM (Dynamic, Max): 1536 MB 02:52:16 INFO - Vendor: Intel (0x8086) 02:52:16 INFO - Device ID: 0x0a2e 02:52:16 INFO - Revision ID: 0x0009 02:52:16 INFO - Displays: 02:52:16 INFO - Display: 02:52:16 INFO - Resolution: 1600 x 1200 @ 60 Hz 02:52:16 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 02:52:16 INFO - Display Serial Number: 1600x1200 60 02:52:16 INFO - Main Display: Yes 02:52:16 INFO - Mirror: Off 02:52:16 INFO - Online: Yes 02:52:16 INFO - Rotation: Supported 02:52:16 INFO - Return code: 0 02:52:16 INFO - Running post-action listener: _resource_record_post_action 02:52:16 INFO - [mozharness: 2017-04-18 09:52:16.399741Z] Finished run-tests step (success) 02:52:16 INFO - Running post-run listener: _resource_record_post_run 02:52:16 INFO - Total resource usage - Wall time: 1201s; CPU: 54.0%; Read bytes: 52128768; Write bytes: 523024384; Read time: 614; Write time: 1960 02:52:16 INFO - TinderboxPrint: CPU usage
54.5% 02:52:16 INFO - TinderboxPrint: I/O read bytes / time
52,128,768 / 614 02:52:16 INFO - TinderboxPrint: I/O write bytes / time
523,024,384 / 1,960 02:52:16 INFO - TinderboxPrint: CPU idle
2,186.6 (45.5%) 02:52:16 INFO - TinderboxPrint: CPU system
962.1 (20.0%) 02:52:16 INFO - TinderboxPrint: CPU user
1,656.9 (34.5%) 02:52:16 INFO - TinderboxPrint: Swap in / out
253,108,224 / 0 02:52:16 INFO - install - Wall time: 20s; CPU: 54.0%; Read bytes: 203903488; Write bytes: 201590272; Read time: 15736; Write time: 420 02:52:16 INFO - run-tests - Wall time: 1181s; CPU: 55.0%; Read bytes: 50101248; Write bytes: 310632960; Read time: 592; Write time: 1506 02:52:16 INFO - Running post-run listener: _upload_blobber_files 02:52:16 INFO - Blob upload gear active. 02:52:16 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 02:52:16 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 02:52:16 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'] 02:52:16 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 02:52:17 INFO - (blobuploader) - INFO - Open directory for files ... 02:52:17 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 02:52:17 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:52:17 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:52:17 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 02:52:17 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:52:17 INFO - (blobuploader) - INFO - Done attempting. 02:52:17 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 02:52:17 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:52:17 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:52:18 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 02:52:18 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:52:18 INFO - (blobuploader) - INFO - Done attempting. 02:52:18 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 02:52:18 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:52:18 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:52:19 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 02:52:19 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:52:19 INFO - (blobuploader) - INFO - Done attempting. 02:52:19 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 02:52:19 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:52:19 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:52:22 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 02:52:22 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:52:22 INFO - (blobuploader) - INFO - Done attempting. 02:52:22 INFO - (blobuploader) - INFO - Iteration through files over. 02:52:22 INFO - Return code: 0 02:52:22 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 02:52:22 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 02:52:22 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/117e6cce4b1aa7533518869ee219c8c901a22dfeea54f434ca2dc00393f42725b7f0ca99ea6c5d0a5946fffe97537eecbb34ec3bad530c33da28de940b5e83b0", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/64ce9bac600b787f744a5d7694e38f50eb3342115cf6ebb03fef1145f602a8979383615fbfed08fdeac50de171ff72637a326f016bcf29775cde81ccada3424f", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/83be75d20cfa15e56674f000eac5e179087f5037ad3d72a153107a1e0e61c346dc2d536ca105570117a81f295ff75418c211c1d45da2db82b93ed14db096993f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/eb9cee2e56c50464fda04718ea601a5c67c2a2b6ff500ca7ec42745b262e73ba3ff62171a68c80c476712fae3509ae497615274ffe834e072c21ddc25fc5dc40"} 02:52:22 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 02:52:22 INFO - Writing to file /builds/slave/test/properties/blobber_files 02:52:22 INFO - Contents: 02:52:22 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/117e6cce4b1aa7533518869ee219c8c901a22dfeea54f434ca2dc00393f42725b7f0ca99ea6c5d0a5946fffe97537eecbb34ec3bad530c33da28de940b5e83b0", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/64ce9bac600b787f744a5d7694e38f50eb3342115cf6ebb03fef1145f602a8979383615fbfed08fdeac50de171ff72637a326f016bcf29775cde81ccada3424f", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/83be75d20cfa15e56674f000eac5e179087f5037ad3d72a153107a1e0e61c346dc2d536ca105570117a81f295ff75418c211c1d45da2db82b93ed14db096993f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/eb9cee2e56c50464fda04718ea601a5c67c2a2b6ff500ca7ec42745b262e73ba3ff62171a68c80c476712fae3509ae497615274ffe834e072c21ddc25fc5dc40"} 02:52:22 INFO - Running post-run listener: copy_logs_to_upload_dir 02:52:22 INFO - Copying logs to upload dir... 02:52:22 INFO - mkdir: /builds/slave/test/build/upload/logs 02:52:22 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1284.170433 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 24 secs) (at 2017-04-18 02:52:22.178666) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-04-18 02:52:22.182444) ========= 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.BtJ7vr7dLN/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.5ZNk8XZnAd/Listeners TMPDIR=/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/117e6cce4b1aa7533518869ee219c8c901a22dfeea54f434ca2dc00393f42725b7f0ca99ea6c5d0a5946fffe97537eecbb34ec3bad530c33da28de940b5e83b0", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/64ce9bac600b787f744a5d7694e38f50eb3342115cf6ebb03fef1145f602a8979383615fbfed08fdeac50de171ff72637a326f016bcf29775cde81ccada3424f", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/83be75d20cfa15e56674f000eac5e179087f5037ad3d72a153107a1e0e61c346dc2d536ca105570117a81f295ff75418c211c1d45da2db82b93ed14db096993f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/eb9cee2e56c50464fda04718ea601a5c67c2a2b6ff500ca7ec42745b262e73ba3ff62171a68c80c476712fae3509ae497615274ffe834e072c21ddc25fc5dc40"} build_url:https://queue.taskcluster.net/v1/task/ebjpA2HvR3u8s21cSOswGw/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010488 build_url: 'https://queue.taskcluster.net/v1/task/ebjpA2HvR3u8s21cSOswGw/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/117e6cce4b1aa7533518869ee219c8c901a22dfeea54f434ca2dc00393f42725b7f0ca99ea6c5d0a5946fffe97537eecbb34ec3bad530c33da28de940b5e83b0", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/64ce9bac600b787f744a5d7694e38f50eb3342115cf6ebb03fef1145f602a8979383615fbfed08fdeac50de171ff72637a326f016bcf29775cde81ccada3424f", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/83be75d20cfa15e56674f000eac5e179087f5037ad3d72a153107a1e0e61c346dc2d536ca105570117a81f295ff75418c211c1d45da2db82b93ed14db096993f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/eb9cee2e56c50464fda04718ea601a5c67c2a2b6ff500ca7ec42745b262e73ba3ff62171a68c80c476712fae3509ae497615274ffe834e072c21ddc25fc5dc40"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-04-18 02:52:22.218939) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-04-18 02:52:22.219513) ========= 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.BtJ7vr7dLN/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.5ZNk8XZnAd/Listeners TMPDIR=/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/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.005079 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-04-18 02:52:22.268886) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-04-18 02:52:22.269243) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-04-18 02:52:22.269633) ========= ========= Total master_lag: 0.19 =========