builder: mozilla-beta_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0095 starttime: 1481733018.53 results: success (0) buildid: 20161214061502 builduid: d16d7ff0167c4a7ab66766dec2bab63e revision: bd43ce1d7c258f727eec694b8e7d72f072c8905c ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-12-14 08:30:18.532777) ========= master: http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-12-14 08:30:18.533192) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-14 08:30:18.533500) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-14 08:30:18.560090) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-14 08:30:18.560347) ========= 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.8T3nWQnoSG/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.jSl6PfDAnn/Listeners TMPDIR=/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-12-14 08:30:18-- 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% 8.49M=0.001s 2016-12-14 08:30:18 (8.49 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.118596 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-14 08:30:18.702219) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-14 08:30:18.702523) ========= 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.8T3nWQnoSG/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.jSl6PfDAnn/Listeners TMPDIR=/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/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.020086 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-14 08:30:18.753293) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 5 secs) (at 2016-12-14 08:30:18.753594) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev bd43ce1d7c258f727eec694b8e7d72f072c8905c --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-beta --rev bd43ce1d7c258f727eec694b8e7d72f072c8905c --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.8T3nWQnoSG/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.jSl6PfDAnn/Listeners TMPDIR=/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-12-14 08:30:18,877 truncating revision to first 12 chars 2016-12-14 08:30:18,877 Setting DEBUG logging. 2016-12-14 08:30:18,877 attempt 1/10 2016-12-14 08:30:18,877 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/bd43ce1d7c25?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-12-14 08:30:20,110 unpacking tar archive at: mozilla-beta-bd43ce1d7c25/testing/mozharness/ program finished with exit code 0 elapsedTime=1.485155 ========= master_lag: 3.52 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 5 secs) (at 2016-12-14 08:30:23.760776) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-14 08:30:23.761107) ========= script_repo_revision: bd43ce1d7c258f727eec694b8e7d72f072c8905c ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-14 08:30:23.761488) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-14 08:30:23.761750) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-14 08:30:23.789030) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 20 mins, 56 secs) (at 2016-12-14 08:30:23.789293) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Beta', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Beta'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.8T3nWQnoSG/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.jSl6PfDAnn/Listeners TMPDIR=/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/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 08:30:24 INFO - MultiFileLogger online at 20161214 08:30:24 in /builds/slave/test 08:30:24 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta 08:30:24 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 08:30:24 INFO - {'append_to_log': False, 08:30:24 INFO - 'base_work_dir': '/builds/slave/test', 08:30:24 INFO - 'blob_upload_branch': 'Mozilla-Beta', 08:30:24 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 08:30:24 INFO - 'branch': 'Mozilla-Beta', 08:30:24 INFO - 'buildbot_json_path': 'buildprops.json', 08:30:24 INFO - 'config_files': ('talos/mac_config.py',), 08:30:24 INFO - 'default_actions': ('clobber', 08:30:24 INFO - 'read-buildbot-config', 08:30:24 INFO - 'download-and-extract', 08:30:24 INFO - 'populate-webroot', 08:30:24 INFO - 'create-virtualenv', 08:30:24 INFO - 'install', 08:30:24 INFO - 'run-tests'), 08:30:24 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 08:30:24 INFO - 'download_minidump_stackwalk': True, 08:30:24 INFO - 'download_symbols': 'ondemand', 08:30:24 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 08:30:24 INFO - 'tooltool.py': '/tools/tooltool.py', 08:30:24 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 08:30:24 INFO - '/tools/misc-python/virtualenv.py')}, 08:30:24 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 08:30:24 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 08:30:24 INFO - 'installer_path': 'installer.exe', 08:30:24 INFO - 'log_level': 'info', 08:30:24 INFO - 'log_name': 'talos', 08:30:24 INFO - 'log_to_console': True, 08:30:24 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 08:30:24 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 08:30:24 INFO - 'opt_config_files': (), 08:30:24 INFO - 'pip_index': False, 08:30:24 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 08:30:24 INFO - 'cmd': ('bash', 08:30:24 INFO - '-c', 08:30:24 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 08:30:24 INFO - 'enabled': True, 08:30:24 INFO - 'halt_on_failure': False, 08:30:24 INFO - 'name': 'check_screen_resolution'},), 08:30:24 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 08:30:24 INFO - 'cmd': ('bash', 08:30:24 INFO - '-c', 08:30:24 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 08:30:24 INFO - 'enabled': True, 08:30:24 INFO - 'halt_on_failure': False, 08:30:24 INFO - 'name': 'check_screen_resolution'},), 08:30:24 INFO - 'run_cmd_checks_enabled': True, 08:30:24 INFO - 'sps_profile': False, 08:30:24 INFO - 'sps_profile_interval': 0, 08:30:24 INFO - 'suite': 'dromaeojs', 08:30:24 INFO - 'system_bits': '32', 08:30:24 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 08:30:24 INFO - 'title': 't-yosemite-r7-0095', 08:30:24 INFO - 'tooltool_cache': '/builds/tooltool_cache', 08:30:24 INFO - 'use_talos_json': True, 08:30:24 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 08:30:24 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 08:30:24 INFO - 'work_dir': 'build'} 08:30:24 INFO - [mozharness: 2016-12-14 16:30:24.035191Z] Running clobber step. 08:30:24 INFO - Running pre-action listener: _resource_record_pre_action 08:30:24 INFO - Running main action method: clobber 08:30:24 INFO - rmtree: /builds/slave/test/build 08:30:24 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 08:30:25 INFO - Running post-action listener: _resource_record_post_action 08:30:25 INFO - [mozharness: 2016-12-14 16:30:25.562681Z] Finished clobber step (success) 08:30:25 INFO - [mozharness: 2016-12-14 16:30:25.562844Z] Running read-buildbot-config step. 08:30:25 INFO - Running pre-action listener: _resource_record_pre_action 08:30:25 INFO - Running main action method: read_buildbot_config 08:30:25 INFO - Using buildbot properties: 08:30:25 INFO - { 08:30:25 INFO - "project": "", 08:30:25 INFO - "product": "firefox", 08:30:25 INFO - "script_repo_revision": "production", 08:30:25 INFO - "builddir": "mozilla-beta_yosemite_r7_test-dromaeojs", 08:30:25 INFO - "repository": "", 08:30:25 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-beta talos dromaeojs", 08:30:25 INFO - "buildid": "20161214061502", 08:30:25 INFO - "pgo_build": "False", 08:30:25 INFO - "basedir": "/builds/slave/test", 08:30:25 INFO - "buildnumber": 2, 08:30:25 INFO - "slavename": "t-yosemite-r7-0095", 08:30:25 INFO - "master": "http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/", 08:30:25 INFO - "platform": "yosemite_r7", 08:30:25 INFO - "scheduler": "tests-mozilla-beta-macosx64-talos", 08:30:25 INFO - "branch": "mozilla-beta", 08:30:25 INFO - "repo_path": "releases/mozilla-beta", 08:30:25 INFO - "revision": "bd43ce1d7c258f727eec694b8e7d72f072c8905c", 08:30:25 INFO - "stage_platform": "macosx64", 08:30:25 INFO - "builduid": "d16d7ff0167c4a7ab66766dec2bab63e", 08:30:25 INFO - "slavebuilddir": "test" 08:30:25 INFO - } 08:30:25 INFO - Found installer url https://queue.taskcluster.net/v1/task/byLSYhfuTRWjcPSYscrdcg/artifacts/public/build/firefox-51.0.en-US.mac.dmg. 08:30:25 INFO - Running post-action listener: _resource_record_post_action 08:30:25 INFO - [mozharness: 2016-12-14 16:30:25.568492Z] Finished read-buildbot-config step (success) 08:30:25 INFO - [mozharness: 2016-12-14 16:30:25.568719Z] Running download-and-extract step. 08:30:25 INFO - Running pre-action listener: _resource_record_pre_action 08:30:25 INFO - Running main action method: download_and_extract 08:30:25 INFO - mkdir: /builds/slave/test/build/tests 08:30:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:30:25 INFO - trying https://queue.taskcluster.net/v1/task/byLSYhfuTRWjcPSYscrdcg/artifacts/public/build/firefox-51.0.en-US.mac.test_packages.json 08:30:25 INFO - Downloading https://queue.taskcluster.net/v1/task/byLSYhfuTRWjcPSYscrdcg/artifacts/public/build/firefox-51.0.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-51.0.en-US.mac.test_packages.json 08:30:25 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/byLSYhfuTRWjcPSYscrdcg/artifacts/public/build/firefox-51.0.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-51.0.en-US.mac.test_packages.json'}, attempt #1 08:30:26 INFO - Downloaded 1165 bytes. 08:30:26 INFO - Reading from file /builds/slave/test/build/firefox-51.0.en-US.mac.test_packages.json 08:30:26 INFO - Using the following test package requirements: 08:30:26 INFO - {u'common': [u'firefox-51.0.en-US.mac.common.tests.zip'], 08:30:26 INFO - u'cppunittest': [u'firefox-51.0.en-US.mac.common.tests.zip', 08:30:26 INFO - u'firefox-51.0.en-US.mac.cppunittest.tests.zip'], 08:30:26 INFO - u'gtest': [u'firefox-51.0.en-US.mac.common.tests.zip', 08:30:26 INFO - u'firefox-51.0.en-US.mac.gtest.tests.zip'], 08:30:26 INFO - u'jittest': [u'firefox-51.0.en-US.mac.common.tests.zip', u'jsshell-mac.zip'], 08:30:26 INFO - u'mochitest': [u'firefox-51.0.en-US.mac.common.tests.zip', 08:30:26 INFO - u'firefox-51.0.en-US.mac.mochitest.tests.zip'], 08:30:26 INFO - u'mozbase': [u'firefox-51.0.en-US.mac.common.tests.zip'], 08:30:26 INFO - u'reftest': [u'firefox-51.0.en-US.mac.common.tests.zip', 08:30:26 INFO - u'firefox-51.0.en-US.mac.reftest.tests.zip'], 08:30:26 INFO - u'talos': [u'firefox-51.0.en-US.mac.common.tests.zip', 08:30:26 INFO - u'firefox-51.0.en-US.mac.talos.tests.zip'], 08:30:26 INFO - u'web-platform': [u'firefox-51.0.en-US.mac.common.tests.zip', 08:30:26 INFO - u'firefox-51.0.en-US.mac.web-platform.tests.zip'], 08:30:26 INFO - u'xpcshell': [u'firefox-51.0.en-US.mac.common.tests.zip', 08:30:26 INFO - u'firefox-51.0.en-US.mac.xpcshell.tests.zip']} 08:30:26 INFO - Downloading packages: [u'firefox-51.0.en-US.mac.common.tests.zip'] for test suite category: common 08:30:26 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/byLSYhfuTRWjcPSYscrdcg/artifacts/public/build/firefox-51.0.en-US.mac.common.tests.zip 08:30:26 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/byLSYhfuTRWjcPSYscrdcg/artifacts/public/build/firefox-51.0.en-US.mac.common.tests.zip'}, attempt #1 08:30:26 INFO - Fetch https://queue.taskcluster.net/v1/task/byLSYhfuTRWjcPSYscrdcg/artifacts/public/build/firefox-51.0.en-US.mac.common.tests.zip into memory 08:30:27 INFO - Expected file size: 16232520 08:30:34 INFO - Downloading packages: [u'firefox-51.0.en-US.mac.common.tests.zip', u'firefox-51.0.en-US.mac.talos.tests.zip'] for test suite category: talos 08:30:34 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/byLSYhfuTRWjcPSYscrdcg/artifacts/public/build/firefox-51.0.en-US.mac.common.tests.zip 08:30:34 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/byLSYhfuTRWjcPSYscrdcg/artifacts/public/build/firefox-51.0.en-US.mac.common.tests.zip'}, attempt #1 08:30:34 INFO - Fetch https://queue.taskcluster.net/v1/task/byLSYhfuTRWjcPSYscrdcg/artifacts/public/build/firefox-51.0.en-US.mac.common.tests.zip into memory 08:30:34 INFO - Expected file size: 16232520 08:30:41 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/byLSYhfuTRWjcPSYscrdcg/artifacts/public/build/firefox-51.0.en-US.mac.talos.tests.zip 08:30:41 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/byLSYhfuTRWjcPSYscrdcg/artifacts/public/build/firefox-51.0.en-US.mac.talos.tests.zip'}, attempt #1 08:30:41 INFO - Fetch https://queue.taskcluster.net/v1/task/byLSYhfuTRWjcPSYscrdcg/artifacts/public/build/firefox-51.0.en-US.mac.talos.tests.zip into memory 08:30:42 INFO - Expected file size: 14414089 08:30:43 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')]} 08:30:43 INFO - trying https://queue.taskcluster.net/v1/task/byLSYhfuTRWjcPSYscrdcg/artifacts/public/build/firefox-51.0.en-US.mac.dmg 08:30:43 INFO - Downloading https://queue.taskcluster.net/v1/task/byLSYhfuTRWjcPSYscrdcg/artifacts/public/build/firefox-51.0.en-US.mac.dmg to /builds/slave/test/build/firefox-51.0.en-US.mac.dmg 08:30:43 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/byLSYhfuTRWjcPSYscrdcg/artifacts/public/build/firefox-51.0.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-51.0.en-US.mac.dmg'}, attempt #1 08:30:45 INFO - Downloaded 86043469 bytes. 08:30:45 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/byLSYhfuTRWjcPSYscrdcg/artifacts/public/build/firefox-51.0.en-US.mac.dmg 08:30:45 INFO - mkdir: /builds/slave/test/properties 08:30:45 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 08:30:45 INFO - Writing to file /builds/slave/test/properties/build_url 08:30:45 INFO - Contents: 08:30:45 INFO - build_url:https://queue.taskcluster.net/v1/task/byLSYhfuTRWjcPSYscrdcg/artifacts/public/build/firefox-51.0.en-US.mac.dmg 08:30:46 INFO - Running post-action listener: _resource_record_post_action 08:30:46 INFO - Running post-action listener: set_extra_try_arguments 08:30:46 INFO - [mozharness: 2016-12-14 16:30:46.064995Z] Finished download-and-extract step (success) 08:30:46 INFO - [mozharness: 2016-12-14 16:30:46.065116Z] Running populate-webroot step. 08:30:46 INFO - Running pre-action listener: _resource_record_pre_action 08:30:46 INFO - Running main action method: populate_webroot 08:30:46 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 08:30:46 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 08:30:46 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 08:30:46 INFO - '--tppagecycles', 08:30:46 INFO - '7'], 08:30:46 INFO - 'tests': ['tsvgm']}}, 08:30:46 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 08:30:46 INFO - 'tests': ['tresize', 'tcanvasmark']}, 08:30:46 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 08:30:46 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 08:30:46 INFO - 'tests': ['dromaeo_css', 'kraken']}, 08:30:46 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 08:30:46 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:30:46 INFO - 'talos_options': ['--disable-e10s'], 08:30:46 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:30:46 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:30:46 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:30:46 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:30:46 INFO - 'talos_options': ['--disable-e10s'], 08:30:46 INFO - 'tests': ['damp', 'tps']}, 08:30:46 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:30:46 INFO - 'tests': ['damp', 'tps']}, 08:30:46 INFO - 'g3': {'talos_options': ['--disable-e10s'], 08:30:46 INFO - 'tests': ['dromaeo_dom']}, 08:30:46 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 08:30:46 INFO - 'g4': {'talos_options': ['--disable-e10s'], 08:30:46 INFO - 'tests': ['basic_compositor_video']}, 08:30:46 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 08:30:46 INFO - 'other': {'talos_options': ['--disable-e10s'], 08:30:46 INFO - 'tests': ['a11yr', 08:30:46 INFO - 'ts_paint', 08:30:46 INFO - 'tpaint', 08:30:46 INFO - 'sessionrestore', 08:30:46 INFO - 'sessionrestore_no_auto_restore', 08:30:46 INFO - 'tabpaint']}, 08:30:46 INFO - 'other-e10s': {'tests': ['a11yr', 08:30:46 INFO - 'ts_paint', 08:30:46 INFO - 'tpaint', 08:30:46 INFO - 'sessionrestore', 08:30:46 INFO - 'sessionrestore_no_auto_restore', 08:30:46 INFO - 'tabpaint']}, 08:30:46 INFO - 'other-e10s_l64': {'tests': ['a11yr', 08:30:46 INFO - 'ts_paint', 08:30:46 INFO - 'tpaint', 08:30:46 INFO - 'sessionrestore', 08:30:46 INFO - 'sessionrestore_no_auto_restore', 08:30:46 INFO - 'tabpaint']}, 08:30:46 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 08:30:46 INFO - 'ts_paint', 08:30:46 INFO - 'tpaint', 08:30:46 INFO - 'sessionrestore', 08:30:46 INFO - 'sessionrestore_no_auto_restore', 08:30:46 INFO - 'tabpaint']}, 08:30:46 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 08:30:46 INFO - 'tests': ['a11yr', 08:30:46 INFO - 'ts_paint', 08:30:46 INFO - 'tpaint', 08:30:46 INFO - 'sessionrestore', 08:30:46 INFO - 'sessionrestore_no_auto_restore', 08:30:46 INFO - 'tabpaint']}, 08:30:46 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 08:30:46 INFO - 'tests': ['a11yr', 08:30:46 INFO - 'ts_paint', 08:30:46 INFO - 'tpaint', 08:30:46 INFO - 'sessionrestore', 08:30:46 INFO - 'sessionrestore_no_auto_restore', 08:30:46 INFO - 'tabpaint']}, 08:30:46 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 08:30:46 INFO - 'tests': ['tsvgx', 08:30:46 INFO - 'tsvgr_opacity', 08:30:46 INFO - 'tart', 08:30:46 INFO - 'tscrollx', 08:30:46 INFO - 'cart']}, 08:30:46 INFO - 'svgr-e10s': {'tests': ['tsvgx', 08:30:46 INFO - 'tsvgr_opacity', 08:30:46 INFO - 'tart', 08:30:46 INFO - 'tscrollx', 08:30:46 INFO - 'cart']}, 08:30:46 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:30:46 INFO - 'talos_options': ['--disable-e10s'], 08:30:46 INFO - 'tests': ['tp5o']}, 08:30:46 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:30:46 INFO - 'tests': ['tp5o']}, 08:30:46 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:30:46 INFO - 'talos_options': ['--disable-e10s', 08:30:46 INFO - '--xperf_path', 08:30:46 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:30:46 INFO - 'tests': ['tp5n']}, 08:30:46 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:30:46 INFO - 'talos_options': ['--xperf_path', 08:30:46 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:30:46 INFO - 'tests': ['tp5n']}}, 08:30:46 INFO - 'talos.zip': {'path': '', 08:30:46 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 08:30:46 INFO - Running post-action listener: _resource_record_post_action 08:30:46 INFO - [mozharness: 2016-12-14 16:30:46.084374Z] Finished populate-webroot step (success) 08:30:46 INFO - [mozharness: 2016-12-14 16:30:46.084478Z] Running create-virtualenv step. 08:30:46 INFO - Running pre-action listener: _resource_record_pre_action 08:30:46 INFO - Running main action method: create_virtualenv 08:30:46 INFO - Creating virtualenv /builds/slave/test/build/venv 08:30:46 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 08:30:46 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 08:30:46 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 08:30:46 INFO - Using real prefix '/tools/python27' 08:30:46 INFO - New python executable in /builds/slave/test/build/venv/bin/python 08:30:46 INFO - Installing distribute.............................................................................................................................................................................................done. 08:30:49 INFO - Installing pip.................done. 08:30:49 INFO - Return code: 0 08:30:49 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 08:30:49 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')]} 08:30:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:30:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:30:49 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')]} 08:30:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:30:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:30:49 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 0x103614490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10358a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f88824e7740>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103428d30>, '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 0x10328edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103420b20>, '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.8T3nWQnoSG/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.jSl6PfDAnn/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/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 08:30:49 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 08:30:49 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 08:30:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.8T3nWQnoSG/Render', 08:30:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:30:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:30:49 INFO - 'HOME': '/Users/cltbld', 08:30:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:30:49 INFO - 'LOGNAME': 'cltbld', 08:30:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:30:49 INFO - 'MOZ_NO_REMOTE': '1', 08:30:49 INFO - 'NO_EM_RESTART': '1', 08:30:49 INFO - 'PAGER': '/bin/cat', 08:30:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:30:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:30:49 INFO - 'PWD': '/builds/slave/test', 08:30:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:30:49 INFO - 'SHELL': '/bin/bash', 08:30:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.jSl6PfDAnn/Listeners', 08:30:49 INFO - 'TMPDIR': '/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/', 08:30:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:30:49 INFO - 'USER': 'cltbld', 08:30:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:30:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:30:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:30:49 INFO - 'XPC_FLAGS': '0x0', 08:30:49 INFO - 'XPC_SERVICE_NAME': '0', 08:30:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:30:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:30:50 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 08:30:50 INFO - Cleaning up... 08:30:50 INFO - Return code: 0 08:30:50 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 08:30: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')]} 08:30:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:30:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:30: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')]} 08:30:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:30:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:30: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', '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 0x103614490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10358a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f88824e7740>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103428d30>, '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 0x10328edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103420b20>, '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.8T3nWQnoSG/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.jSl6PfDAnn/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/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 08:30: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', 'psutil>=3.1.1'] in /builds/slave/test/build 08:30: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 psutil>=3.1.1 08:30:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.8T3nWQnoSG/Render', 08:30:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:30:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:30:50 INFO - 'HOME': '/Users/cltbld', 08:30:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:30:50 INFO - 'LOGNAME': 'cltbld', 08:30:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:30:50 INFO - 'MOZ_NO_REMOTE': '1', 08:30:50 INFO - 'NO_EM_RESTART': '1', 08:30:50 INFO - 'PAGER': '/bin/cat', 08:30:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:30:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:30:50 INFO - 'PWD': '/builds/slave/test', 08:30:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:30:50 INFO - 'SHELL': '/bin/bash', 08:30:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.jSl6PfDAnn/Listeners', 08:30:50 INFO - 'TMPDIR': '/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/', 08:30:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:30:50 INFO - 'USER': 'cltbld', 08:30:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:30:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:30:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:30:50 INFO - 'XPC_FLAGS': '0x0', 08:30:50 INFO - 'XPC_SERVICE_NAME': '0', 08:30:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:30:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:30:50 INFO - Downloading/unpacking psutil>=3.1.1 08:30: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 08:30: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 08:30: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 08:30: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 08:30: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 08:30: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 08:30:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 08:30:53 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:30:53 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:30:53 INFO - Installing collected packages: psutil 08:30:53 INFO - Running setup.py install for psutil 08:30:53 INFO - building 'psutil._psutil_osx' extension 08:30:53 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 08:30:53 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 08:30:53 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 08:30:54 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 08:30:54 INFO - building 'psutil._psutil_posix' extension 08:30:54 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 08:30:54 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 08:30:54 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 08:30:54 INFO - ^ 08:30:54 INFO - 1 warning generated. 08:30:54 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 08:30:54 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:30:54 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:30:54 INFO - Successfully installed psutil 08:30:54 INFO - Cleaning up... 08:30:54 INFO - Return code: 0 08:30:54 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 08:30: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')]} 08:30:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:30:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:30: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')]} 08:30:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:30:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:30: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', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x103614490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10358a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f88824e7740>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103428d30>, '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 0x10328edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103420b20>, '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.8T3nWQnoSG/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.jSl6PfDAnn/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/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 08:30: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', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 08:30: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 mozsystemmonitor==0.3 08:30:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.8T3nWQnoSG/Render', 08:30:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:30:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:30:54 INFO - 'HOME': '/Users/cltbld', 08:30:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:30:54 INFO - 'LOGNAME': 'cltbld', 08:30:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:30:54 INFO - 'MOZ_NO_REMOTE': '1', 08:30:54 INFO - 'NO_EM_RESTART': '1', 08:30:54 INFO - 'PAGER': '/bin/cat', 08:30:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:30:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:30:54 INFO - 'PWD': '/builds/slave/test', 08:30:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:30:54 INFO - 'SHELL': '/bin/bash', 08:30:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.jSl6PfDAnn/Listeners', 08:30:54 INFO - 'TMPDIR': '/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/', 08:30:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:30:54 INFO - 'USER': 'cltbld', 08:30:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:30:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:30:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:30:54 INFO - 'XPC_FLAGS': '0x0', 08:30:54 INFO - 'XPC_SERVICE_NAME': '0', 08:30:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:30:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:30:54 INFO - Downloading/unpacking mozsystemmonitor==0.3 08:30: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 08:30: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 08:30: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 08:30: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 08:30: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 08:30: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 08:30:57 INFO - Downloading mozsystemmonitor-0.3.tar.gz 08:30:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 08:30:57 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 08:30:57 INFO - Installing collected packages: mozsystemmonitor 08:30:57 INFO - Running setup.py install for mozsystemmonitor 08:30:57 INFO - Successfully installed mozsystemmonitor 08:30:57 INFO - Cleaning up... 08:30:57 INFO - Return code: 0 08:30:57 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 08:30:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:30:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:30:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:30:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:30:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:30:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:30:57 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x103614490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10358a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f88824e7740>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103428d30>, '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 0x10328edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103420b20>, '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.8T3nWQnoSG/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.jSl6PfDAnn/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/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 08:30:57 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 08:30:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 08:30:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.8T3nWQnoSG/Render', 08:30:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:30:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:30:57 INFO - 'HOME': '/Users/cltbld', 08:30:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:30:57 INFO - 'LOGNAME': 'cltbld', 08:30:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:30:57 INFO - 'MOZ_NO_REMOTE': '1', 08:30:57 INFO - 'NO_EM_RESTART': '1', 08:30:57 INFO - 'PAGER': '/bin/cat', 08:30:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:30:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:30:57 INFO - 'PWD': '/builds/slave/test', 08:30:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:30:57 INFO - 'SHELL': '/bin/bash', 08:30:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.jSl6PfDAnn/Listeners', 08:30:57 INFO - 'TMPDIR': '/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/', 08:30:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:30:57 INFO - 'USER': 'cltbld', 08:30:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:30:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:30:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:30:57 INFO - 'XPC_FLAGS': '0x0', 08:30:57 INFO - 'XPC_SERVICE_NAME': '0', 08:30:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:30:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:30:57 INFO - Downloading/unpacking jsonschema==2.5.1 08:30: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 08:30: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 08:30: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 08:30: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 08:30: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 08:30: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 08:31:00 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 08:31:00 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 08:31:00 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 08:31:00 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 08:31:00 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 08:31:00 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 08:31:00 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 08:31:00 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 08:31:00 INFO - Downloading functools32-3.2.3-2.tar.gz 08:31:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 08:31:00 INFO - warning: no files found matching '*.txt' 08:31:00 INFO - no previously-included directories found matching 'build' 08:31:00 INFO - no previously-included directories found matching 'dist' 08:31:00 INFO - no previously-included directories found matching '.git*' 08:31:00 INFO - Installing collected packages: jsonschema, functools32 08:31:00 INFO - Running setup.py install for functools32 08:31:00 INFO - warning: no files found matching '*.txt' 08:31:00 INFO - no previously-included directories found matching 'build' 08:31:00 INFO - no previously-included directories found matching 'dist' 08:31:00 INFO - no previously-included directories found matching '.git*' 08:31:01 INFO - Successfully installed jsonschema functools32 08:31:01 INFO - Cleaning up... 08:31:01 INFO - Return code: 0 08:31:01 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 08:31:01 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')]} 08:31:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:31:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:31:01 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')]} 08:31:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:31:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:31:01 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 0x103614490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10358a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f88824e7740>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103428d30>, '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 0x10328edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103420b20>, '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.8T3nWQnoSG/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.jSl6PfDAnn/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/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 08:31:01 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 08:31:01 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 08:31:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.8T3nWQnoSG/Render', 08:31:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:31:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:31:01 INFO - 'HOME': '/Users/cltbld', 08:31:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:31:01 INFO - 'LOGNAME': 'cltbld', 08:31:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:31:01 INFO - 'MOZ_NO_REMOTE': '1', 08:31:01 INFO - 'NO_EM_RESTART': '1', 08:31:01 INFO - 'PAGER': '/bin/cat', 08:31:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:31:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:31:01 INFO - 'PWD': '/builds/slave/test', 08:31:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:31:01 INFO - 'SHELL': '/bin/bash', 08:31:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.jSl6PfDAnn/Listeners', 08:31:01 INFO - 'TMPDIR': '/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/', 08:31:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:31:01 INFO - 'USER': 'cltbld', 08:31:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:31:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:31:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:31:01 INFO - 'XPC_FLAGS': '0x0', 08:31:01 INFO - 'XPC_SERVICE_NAME': '0', 08:31:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:31:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:31:01 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 08:31:01 INFO - Cleaning up... 08:31:01 INFO - Return code: 0 08:31:01 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 08:31:01 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')]} 08:31:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:31:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:31:01 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')]} 08:31:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:31:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:31:01 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 0x103614490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10358a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f88824e7740>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103428d30>, '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 0x10328edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103420b20>, '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.8T3nWQnoSG/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.jSl6PfDAnn/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/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 08:31:01 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 08:31:01 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 08:31:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.8T3nWQnoSG/Render', 08:31:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:31:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:31:01 INFO - 'HOME': '/Users/cltbld', 08:31:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:31:01 INFO - 'LOGNAME': 'cltbld', 08:31:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:31:01 INFO - 'MOZ_NO_REMOTE': '1', 08:31:01 INFO - 'NO_EM_RESTART': '1', 08:31:01 INFO - 'PAGER': '/bin/cat', 08:31:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:31:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:31:01 INFO - 'PWD': '/builds/slave/test', 08:31:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:31:01 INFO - 'SHELL': '/bin/bash', 08:31:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.jSl6PfDAnn/Listeners', 08:31:01 INFO - 'TMPDIR': '/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/', 08:31:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:31:01 INFO - 'USER': 'cltbld', 08:31:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:31:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:31:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:31:01 INFO - 'XPC_FLAGS': '0x0', 08:31:01 INFO - 'XPC_SERVICE_NAME': '0', 08:31:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:31:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:31:01 INFO - Downloading/unpacking blobuploader==1.2.4 08:31: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 08:31: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 08:31: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 08:31: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 08:31: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 08:31: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 08:31:04 INFO - Downloading blobuploader-1.2.4.tar.gz 08:31:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 08:31:04 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 08:31:04 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 08:31:04 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 08:31:04 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 08:31:04 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 08:31:04 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 08:31:04 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 08:31:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 08:31:04 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 08:31:04 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 08:31:04 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 08:31:04 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 08:31:04 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 08:31:04 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 08:31:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:31:05 INFO - Downloading docopt-0.6.1.tar.gz 08:31:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 08:31:05 INFO - Installing collected packages: blobuploader, requests, docopt 08:31:05 INFO - Running setup.py install for blobuploader 08:31:05 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 08:31:05 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 08:31:05 INFO - Running setup.py install for requests 08:31:06 INFO - Running setup.py install for docopt 08:31:06 INFO - Successfully installed blobuploader requests docopt 08:31:06 INFO - Cleaning up... 08:31:06 INFO - Return code: 0 08:31:06 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:31:06 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')]} 08:31:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:31:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:31:06 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')]} 08:31:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:31:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:31:06 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 0x103614490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10358a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f88824e7740>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103428d30>, '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 0x10328edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103420b20>, '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.8T3nWQnoSG/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.jSl6PfDAnn/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/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 08:31:06 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 08:31:06 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 08:31:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.8T3nWQnoSG/Render', 08:31:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:31:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:31:06 INFO - 'HOME': '/Users/cltbld', 08:31:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:31:06 INFO - 'LOGNAME': 'cltbld', 08:31:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:31:06 INFO - 'MOZ_NO_REMOTE': '1', 08:31:06 INFO - 'NO_EM_RESTART': '1', 08:31:06 INFO - 'PAGER': '/bin/cat', 08:31:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:31:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:31:06 INFO - 'PWD': '/builds/slave/test', 08:31:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:31:06 INFO - 'SHELL': '/bin/bash', 08:31:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.jSl6PfDAnn/Listeners', 08:31:06 INFO - 'TMPDIR': '/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/', 08:31:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:31:06 INFO - 'USER': 'cltbld', 08:31:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:31:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:31:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:31:06 INFO - 'XPC_FLAGS': '0x0', 08:31:06 INFO - 'XPC_SERVICE_NAME': '0', 08:31:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:31:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:31:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:31:06 INFO - Running setup.py (path:/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/pip-Jyczfx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:31:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:31:06 INFO - Running setup.py (path:/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/pip-oTM4mb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:31:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:31:06 INFO - Running setup.py (path:/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/pip-SkM1IR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:31:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:31:06 INFO - Running setup.py (path:/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/pip-Err8FU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:31:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:31:07 INFO - Running setup.py (path:/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/pip-X7XF3x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:31:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:31:07 INFO - Running setup.py (path:/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/pip-2gPoY6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:31:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:31:07 INFO - Running setup.py (path:/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/pip-zL01V4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:31:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:31:07 INFO - Running setup.py (path:/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/pip-k73hJ7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:31:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:31:07 INFO - Running setup.py (path:/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/pip-UNinly-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:31:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:31:07 INFO - Running setup.py (path:/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/pip-aU3yTb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:31:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:31:07 INFO - Running setup.py (path:/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/pip-Ll7YtN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:31:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:31:07 INFO - Running setup.py (path:/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/pip-khfPi6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:31:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:31:07 INFO - Running setup.py (path:/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/pip-G21bc_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:31:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:31:08 INFO - Running setup.py (path:/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/pip-ZXMpcI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:31:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:31:08 INFO - Running setup.py (path:/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/pip-omWat6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:31:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:31:08 INFO - Running setup.py (path:/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/pip-xJeBoh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:31:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:31:08 INFO - Running setup.py (path:/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/pip-uHP2lI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:31:08 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 08:31:08 INFO - Running setup.py install for manifestparser 08:31:08 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 08:31:08 INFO - Running setup.py install for mozcrash 08:31:08 INFO - Running setup.py install for mozdebug 08:31:08 INFO - Running setup.py install for mozdevice 08:31:09 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 08:31:09 INFO - Installing dm script to /builds/slave/test/build/venv/bin 08:31:09 INFO - Running setup.py install for mozfile 08:31:09 INFO - Running setup.py install for mozhttpd 08:31:09 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 08:31:09 INFO - Running setup.py install for mozinfo 08:31:09 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 08:31:09 INFO - Running setup.py install for mozInstall 08:31:09 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 08:31:09 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 08:31:09 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 08:31:09 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 08:31:09 INFO - Running setup.py install for mozleak 08:31:09 INFO - Running setup.py install for mozlog 08:31:09 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 08:31:09 INFO - Running setup.py install for moznetwork 08:31:10 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 08:31:10 INFO - Running setup.py install for mozprocess 08:31:10 INFO - Running setup.py install for mozprofile 08:31:10 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 08:31:10 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 08:31:10 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 08:31:10 INFO - Running setup.py install for mozrunner 08:31:10 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 08:31:10 INFO - Running setup.py install for mozscreenshot 08:31:10 INFO - Running setup.py install for moztest 08:31:10 INFO - Running setup.py install for mozversion 08:31:10 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 08:31:11 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 08:31:11 INFO - Cleaning up... 08:31:11 INFO - Return code: 0 08:31:11 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:31:11 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')]} 08:31:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:31:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:31:11 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')]} 08:31:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:31:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:31:11 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 0x103614490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10358a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f88824e7740>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103428d30>, '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 0x10328edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103420b20>, '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.8T3nWQnoSG/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.jSl6PfDAnn/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/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 08:31:11 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 08:31:11 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 08:31:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.8T3nWQnoSG/Render', 08:31:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:31:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:31:11 INFO - 'HOME': '/Users/cltbld', 08:31:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:31:11 INFO - 'LOGNAME': 'cltbld', 08:31:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:31:11 INFO - 'MOZ_NO_REMOTE': '1', 08:31:11 INFO - 'NO_EM_RESTART': '1', 08:31:11 INFO - 'PAGER': '/bin/cat', 08:31:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:31:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:31:11 INFO - 'PWD': '/builds/slave/test', 08:31:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:31:11 INFO - 'SHELL': '/bin/bash', 08:31:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.jSl6PfDAnn/Listeners', 08:31:11 INFO - 'TMPDIR': '/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/', 08:31:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:31:11 INFO - 'USER': 'cltbld', 08:31:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:31:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:31:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:31:11 INFO - 'XPC_FLAGS': '0x0', 08:31:11 INFO - 'XPC_SERVICE_NAME': '0', 08:31:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:31:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:31:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:31:11 INFO - Running setup.py (path:/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/pip-qM2QTy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:31:11 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)) 08:31:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:31:11 INFO - Running setup.py (path:/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/pip-zyZHs1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:31:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 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)) 08:31:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:31:11 INFO - Running setup.py (path:/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/pip-zMSinV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:31:11 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)) 08:31:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:31:11 INFO - Running setup.py (path:/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/pip-DQ7llA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:31:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 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)) 08:31:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:31:11 INFO - Running setup.py (path:/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/pip-FAdtQj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:31:11 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)) 08:31:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:31:11 INFO - Running setup.py (path:/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/pip-kbqC3w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:31:11 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)) 08:31:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:31:11 INFO - Running setup.py (path:/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/pip-HY5dKP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:31:12 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)) 08:31:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:31:12 INFO - Running setup.py (path:/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/pip-OE7JY0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:31:12 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)) 08:31:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:31:12 INFO - Running setup.py (path:/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/pip-b9XGqM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:31:12 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)) 08:31:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:31:12 INFO - Running setup.py (path:/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/pip-UsWuy5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:31:12 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)) 08:31:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:31:12 INFO - Running setup.py (path:/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/pip-7eJzCx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:31:12 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)) 08:31:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:31:12 INFO - Running setup.py (path:/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/pip-VIyuuq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:31:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 08:31:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:31:12 INFO - Running setup.py (path:/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/pip-8w7NE5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:31:12 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)) 08:31:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:31:12 INFO - Running setup.py (path:/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/pip-9T_3lS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:31:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 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)) 08:31:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:31:12 INFO - Running setup.py (path:/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/pip-dq6T9G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:31:12 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)) 08:31:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:31:12 INFO - Running setup.py (path:/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/pip-BxeqvB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:31:13 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 08:31:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:31:13 INFO - Running setup.py (path:/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/pip-KIzldb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:31:13 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)) 08:31:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 08:31:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 08:31:13 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)) 08:31:13 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.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 08:31:13 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.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 08:31:13 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 08:31:13 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 08:31:13 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 08:31:13 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 08:31:13 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 08:31:13 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 08:31:13 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 08:31:15 INFO - Downloading blessings-1.6.tar.gz 08:31:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 08:31:15 INFO - Installing collected packages: blessings 08:31:15 INFO - Running setup.py install for blessings 08:31:16 INFO - Successfully installed blessings 08:31:16 INFO - Cleaning up... 08:31:16 INFO - Return code: 0 08:31:16 INFO - Done creating virtualenv /builds/slave/test/build/venv. 08:31:16 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:31:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:31:16 INFO - Reading from file tmpfile_stdout 08:31:16 INFO - Current package versions: 08:31:16 INFO - blessings == 1.6 08:31:16 INFO - blobuploader == 1.2.4 08:31:16 INFO - docopt == 0.6.1 08:31:16 INFO - functools32 == 3.2.3-2 08:31:16 INFO - jsonschema == 2.5.1 08:31:16 INFO - manifestparser == 1.1 08:31:16 INFO - mozInstall == 1.12 08:31:16 INFO - mozcrash == 0.17 08:31:16 INFO - mozdebug == 0.1 08:31:16 INFO - mozdevice == 0.48 08:31:16 INFO - mozfile == 1.2 08:31:16 INFO - mozhttpd == 0.7 08:31:16 INFO - mozinfo == 0.9 08:31:16 INFO - mozleak == 0.1 08:31:16 INFO - mozlog == 3.4 08:31:16 INFO - moznetwork == 0.27 08:31:16 INFO - mozprocess == 0.23 08:31:16 INFO - mozprofile == 0.28 08:31:16 INFO - mozrunner == 6.12 08:31:16 INFO - mozscreenshot == 0.1 08:31:16 INFO - mozsystemmonitor == 0.3 08:31:16 INFO - moztest == 0.7 08:31:16 INFO - mozversion == 1.4 08:31:16 INFO - psutil == 3.1.1 08:31:16 INFO - requests == 1.2.3 08:31:16 INFO - wsgiref == 0.1.2 08:31:16 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:31:16 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')]} 08:31:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:31:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:31:16 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')]} 08:31:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:31:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:31:16 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 0x103614490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10358a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f88824e7740>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103428d30>, '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 0x10328edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103420b20>, '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.8T3nWQnoSG/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.jSl6PfDAnn/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/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 08:31:16 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 08:31:16 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 08:31:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.8T3nWQnoSG/Render', 08:31:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:31:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:31:16 INFO - 'HOME': '/Users/cltbld', 08:31:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:31:16 INFO - 'LOGNAME': 'cltbld', 08:31:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:31:16 INFO - 'MOZ_NO_REMOTE': '1', 08:31:16 INFO - 'NO_EM_RESTART': '1', 08:31:16 INFO - 'PAGER': '/bin/cat', 08:31:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:31:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:31:16 INFO - 'PWD': '/builds/slave/test', 08:31:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:31:16 INFO - 'SHELL': '/bin/bash', 08:31:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.jSl6PfDAnn/Listeners', 08:31:16 INFO - 'TMPDIR': '/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/', 08:31:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:31:16 INFO - 'USER': 'cltbld', 08:31:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:31:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:31:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:31:16 INFO - 'XPC_FLAGS': '0x0', 08:31:16 INFO - 'XPC_SERVICE_NAME': '0', 08:31:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:31:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:31:17 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)) 08:31:17 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)) 08:31:17 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)) 08:31:17 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)) 08:31:17 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)) 08:31:17 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)) 08:31:17 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)) 08:31:17 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)) 08:31:17 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)) 08:31:17 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)) 08:31:17 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)) 08:31:17 INFO - Cleaning up... 08:31:17 INFO - Return code: 0 08:31:17 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 08:31:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:31:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:31:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:31:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:31:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:31:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:31:17 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 0x103614490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10358a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f88824e7740>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103428d30>, '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 0x10328edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103420b20>, '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.8T3nWQnoSG/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.jSl6PfDAnn/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/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 08:31:17 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 08:31:17 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 08:31:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.8T3nWQnoSG/Render', 08:31:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:31:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:31:17 INFO - 'HOME': '/Users/cltbld', 08:31:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:31:17 INFO - 'LOGNAME': 'cltbld', 08:31:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:31:17 INFO - 'MOZ_NO_REMOTE': '1', 08:31:17 INFO - 'NO_EM_RESTART': '1', 08:31:17 INFO - 'PAGER': '/bin/cat', 08:31:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:31:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:31:17 INFO - 'PWD': '/builds/slave/test', 08:31:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:31:17 INFO - 'SHELL': '/bin/bash', 08:31:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.jSl6PfDAnn/Listeners', 08:31:17 INFO - 'TMPDIR': '/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/', 08:31:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:31:17 INFO - 'USER': 'cltbld', 08:31:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:31:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:31:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:31:17 INFO - 'XPC_FLAGS': '0x0', 08:31:17 INFO - 'XPC_SERVICE_NAME': '0', 08:31:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:31:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:31:17 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 08:31:17 INFO - Cleaning up... 08:31:17 INFO - Return code: 0 08:31:17 INFO - Running post-action listener: _resource_record_post_action 08:31:17 INFO - Running post-action listener: _start_resource_monitoring 08:31:17 INFO - Starting resource monitoring. 08:31:17 INFO - [mozharness: 2016-12-14 16:31:17.386869Z] Finished create-virtualenv step (success) 08:31:17 INFO - [mozharness: 2016-12-14 16:31:17.387933Z] Running install step. 08:31:17 INFO - Running pre-action listener: _resource_record_pre_action 08:31:17 INFO - Running main action method: install 08:31:17 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:31:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:31:17 INFO - Reading from file tmpfile_stdout 08:31:17 INFO - Detecting whether we're running mozinstall >=1.0... 08:31:17 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 08:31:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 08:31:17 INFO - Reading from file tmpfile_stdout 08:31:17 INFO - Output received: 08:31:17 INFO - Usage: mozinstall [options] installer 08:31:17 INFO - Options: 08:31:17 INFO - -h, --help show this help message and exit 08:31:17 INFO - -d DEST, --destination=DEST 08:31:17 INFO - Directory to install application into. [default: 08:31:17 INFO - "/builds/slave/test"] 08:31:17 INFO - --app=APP Application being installed. [default: firefox] 08:31:17 INFO - mkdir: /builds/slave/test/build/application 08:31:17 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-51.0.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 08:31:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-51.0.en-US.mac.dmg --destination /builds/slave/test/build/application 08:31:39 INFO - Reading from file tmpfile_stdout 08:31:39 INFO - Output received: 08:31:39 INFO - /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox 08:31:39 INFO - Running post-action listener: _resource_record_post_action 08:31:39 INFO - [mozharness: 2016-12-14 16:31:39.663635Z] Finished install step (success) 08:31:39 INFO - [mozharness: 2016-12-14 16:31:39.663755Z] Running run-tests step. 08:31:39 INFO - Running pre-action listener: _resource_record_pre_action 08:31:39 INFO - Running main action method: run_tests 08:31:39 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 08:31:39 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 08:31:39 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 08:31:39 INFO - 2016-12-14 08:31:39.678 screenresolution[2189:25488] starting screenresolution argv=screenresolution get 08:31:39 INFO - 2016-12-14 08:31:39.681 screenresolution[2189:25488] Display 0: 1600x1200x32@60 08:31:39 INFO - 2016-12-14 08:31:39.686 screenresolution[2190:25491] starting screenresolution argv=screenresolution list 08:31:39 INFO - Available Modes on Display 0 08:31:39 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 08:31:39 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 08:31:39 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 08:31:39 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 08:31:39 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 08:31:39 INFO - Intel Iris: 08:31:39 INFO - Chipset Model: Intel Iris 08:31:39 INFO - Type: GPU 08:31:39 INFO - Bus: Built-In 08:31:39 INFO - VRAM (Dynamic, Max): 1536 MB 08:31:39 INFO - Vendor: Intel (0x8086) 08:31:39 INFO - Device ID: 0x0a2e 08:31:39 INFO - Revision ID: 0x0009 08:31:39 INFO - Displays: 08:31:39 INFO - Display: 08:31:39 INFO - Resolution: 1600 x 1200 @ 60 Hz 08:31:39 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 08:31:39 INFO - Display Serial Number: 1600x1200 60 08:31:39 INFO - Main Display: Yes 08:31:39 INFO - Mirror: Off 08:31:39 INFO - Online: Yes 08:31:39 INFO - Rotation: Supported 08:31:39 INFO - Return code: 0 08:31:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 08:31:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 08:31:39 INFO - Python 2.7.3 08:31:39 INFO - Return code: 0 08:31:39 INFO - grabbing minidump binary from tooltool 08:31:39 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')]} 08:31:39 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 0x103428d30>, '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 0x10328edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103420b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 08:31:39 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 08:31:39 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 08:31:39 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 08:31:39 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 08:31:40 INFO - Return code: 0 08:31:40 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 08:31:40 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 08:31:40 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 08:31:40 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 08:31:40 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 08:31:40 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/byLSYhfuTRWjcPSYscrdcg/artifacts/public/build/firefox-51.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0095', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 08:31:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Beta --suite dromaeojs --executablePath /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/byLSYhfuTRWjcPSYscrdcg/artifacts/public/build/firefox-51.0.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0095 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 08:31:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.8T3nWQnoSG/Render', 08:31:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:31:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:31:40 INFO - 'HOME': '/Users/cltbld', 08:31:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:31:40 INFO - 'LOGNAME': 'cltbld', 08:31:40 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 08:31:40 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 08:31:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:31:40 INFO - 'MOZ_NO_REMOTE': '1', 08:31:40 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 08:31:40 INFO - 'NO_EM_RESTART': '1', 08:31:40 INFO - 'PAGER': '/bin/cat', 08:31:40 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:31:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:31:40 INFO - 'PWD': '/builds/slave/test', 08:31:40 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 08:31:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:31:40 INFO - 'SHELL': '/bin/bash', 08:31:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.jSl6PfDAnn/Listeners', 08:31:40 INFO - 'TMPDIR': '/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/', 08:31:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:31:40 INFO - 'USER': 'cltbld', 08:31:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:31:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:31:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:31:40 INFO - 'XPC_FLAGS': '0x0', 08:31:40 INFO - 'XPC_SERVICE_NAME': '0', 08:31:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:31:40 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/byLSYhfuTRWjcPSYscrdcg/artifacts/public/build/firefox-51.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0095', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 08:31:40 INFO - mozversion application_buildid: 20161214061502 08:31:40 INFO - mozversion application_changeset: bd43ce1d7c258f727eec694b8e7d72f072c8905c 08:31:40 INFO - mozversion application_display_name: Firefox 08:31:40 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 08:31:40 INFO - mozversion application_name: Firefox 08:31:40 INFO - mozversion application_remotingname: firefox 08:31:40 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 08:31:40 INFO - mozversion application_vendor: Mozilla 08:31:40 INFO - mozversion application_version: 51.0 08:31:40 INFO - mozversion platform_buildid: 20161214061502 08:31:40 INFO - mozversion platform_changeset: bd43ce1d7c258f727eec694b8e7d72f072c8905c 08:31:40 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 08:31:40 INFO - mozversion platform_version: 51.0 08:31:40 INFO - using testdate: 1481733100 08:31:40 INFO - actual date: 1481733100 08:31:40 INFO - starting webserver on 'localhost:56801' 08:31:40 INFO - SUITE-START | Running 2 tests 08:31:40 INFO - TEST-START | dromaeo_css 08:31:40 INFO - operating with platform_type : mac_ 08:31:40 INFO - Initialising browser for dromaeo_css test... 08:31:40 INFO - TEST-INFO | started process 2200 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/tmpRaMW_n/profile http://localhost:56801/getInfo.html) 08:31:42 INFO - PROCESS | 2200 | __metrics Screen width/height:1600/1200 08:31:42 INFO - PROCESS | 2200 | colorDepth:24 08:31:42 INFO - PROCESS | 2200 | Browser inner width/height: 1024/689 08:31:42 INFO - PROCESS | 2200 | __metrics 08:31:42 INFO - PROCESS | 2200 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 08:31:42 INFO - PROCESS | 2200 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 08:31:43 INFO - TEST-INFO | 2200: exit 0 08:31:43 INFO - Browser initialized. 08:31:43 INFO - Running cycle 1/1 for dromaeo_css test... 08:31:43 INFO - TEST-INFO | started process 2203 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/tmpRaMW_n/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:31:55 INFO - PROCESS | 2203 | RSS: Main: 168620032 08:31:55 INFO - PROCESS | 2203 | 08:32:49 INFO - PROCESS | 2203 | 1481733169321 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 08:34:57 INFO - PROCESS | 2203 | Cycle 1(1): loaded http://localhost:56801/tests/dromaeo/cssquery-dojo.html (next: http://localhost:56801/tests/dromaeo/cssquery-ext.html) 08:34:59 INFO - PROCESS | 2203 | RSS: Main: 305512448 08:34:59 INFO - PROCESS | 2203 | 08:38:01 INFO - PROCESS | 2203 | Cycle 1(1): loaded http://localhost:56801/tests/dromaeo/cssquery-ext.html (next: http://localhost:56801/tests/dromaeo/cssquery-jquery.html) 08:38:02 INFO - PROCESS | 2203 | RSS: Main: 300687360 08:38:02 INFO - PROCESS | 2203 | 08:41:04 INFO - PROCESS | 2203 | Cycle 1(1): loaded http://localhost:56801/tests/dromaeo/cssquery-jquery.html (next: http://localhost:56801/tests/dromaeo/cssquery-mootools.html) 08:41:05 INFO - PROCESS | 2203 | RSS: Main: 270753792 08:41:05 INFO - PROCESS | 2203 | 08:44:07 INFO - PROCESS | 2203 | Cycle 1(1): loaded http://localhost:56801/tests/dromaeo/cssquery-mootools.html (next: http://localhost:56801/tests/dromaeo/cssquery-prototype.html) 08:44:08 INFO - PROCESS | 2203 | RSS: Main: 266178560 08:44:08 INFO - PROCESS | 2203 | 08:47:11 INFO - PROCESS | 2203 | Cycle 1(1): loaded http://localhost:56801/tests/dromaeo/cssquery-prototype.html (next: http://localhost:56801/tests/dromaeo/cssquery-yui.html) 08:47:12 INFO - PROCESS | 2203 | RSS: Main: 261464064 08:47:12 INFO - PROCESS | 2203 | 08:50:15 INFO - PROCESS | 2203 | Cycle 1(1): loaded http://localhost:56801/tests/dromaeo/cssquery-yui.html (next: http://localhost:56801/tests/dromaeo/cssquery-dojo.html) 08:50:15 INFO - PROCESS | 2203 | RSS: Main: 430559232 08:50:15 INFO - PROCESS | 2203 | 08:50:15 INFO - PROCESS | 2203 | __start_tp_report 08:50:15 INFO - PROCESS | 2203 | _x_x_mozilla_page_load 08:50:15 INFO - PROCESS | 2203 | _x_x_mozilla_page_load_details 08:50:15 INFO - PROCESS | 2203 | |i|pagename|runs| 08:50:15 INFO - PROCESS | 2203 | |0;dojo.html;3368.6631336866308;3476.7566270361076;3490.8429120689566;3508.35095507331;3548.2193917338177;5797.681127537984;5870.61840980336;5878.147668588057;5883.705814709268;5936.851578710534;5850.531957443405;5896.970515147429;5900.937831190384;5906.055031194999;5912.201852749878;5289.86775330616;5315.415304316522;5322.760475778599;5401.78392864285;5431.21247419124;5289.735513224343;5298.761555730001;5329.706866122362;5415.593830462712;5441.891162176754;5401.891962160755;5411.702356370398;5416.647917885344;5416.864578385552;5419.945800541984;5364.7317634118135;5379.1662292344745;5385.219143224211;5388.110961691316;5428.809991650312;5204.245384419276;5280.630355875091;5312.893742125175;5370.3287089113865;5401;5894.705264736789;5932.525397968152;5959.463648271676;5998.7600495980105;6010.489108425789;5180.4042535108565;6164.506839452878;6253.468455181315;6257.374262573751;6285.308616052231;6254.718537665816;6286.025666021774;6296.1500197473215;6325.557210995232;6331.651759153245;5941.3761555036535;5946.226990491295;5952.9702351488395;5961.7615295387695;5999.400059993965;6243.313235544087;6278.466330361879;6290.119383286344;6294.496440284766;6396.776112836028;6203.627782333075;6305.621662700252;6311.589746666452;6314.336994615499;6316.9368306317265;6358.491320694426;6365.968170159213;6375.171227740364;6378.043293505918;6380.9361906380345;6191.380861913773;6248.843778905563;6277.686115694289;6355.459785918249;6380.55336126476;6280.0893870389455;6328.145700330397;6346.428821406095;6394.680265986682;6397.040443933447;5010.423801262828;5021.748912554358;5050.873728156826;5075.644704870623;5082.872928176826;4424.845130420485;4474.306482495218;4549.5905368517;4573.725576465423;4602.769861506911;5998.220231369981;6045.395460453919;6073.726682299307;6092.055731361646;6119.692370570856;6297.992321228581;6309.274433440121;6330.841728956813;6333.778317758948;6344.968275158687;6118.265808103056;6119.5104391648565;6120.326764055951;6139.539534534928;6142.109394137825;5398.946010539844;5501;5503.449655034465;5575;5596.244506991586;5376.677399356051;6391.96804015977;6491.091247225392;6585.374389432996;6601.438877695449;6318.210223722035;6327.145835312174;6328.430441260309;6332.9366706334185;6368.713407896563;6320.841978950563;6447.903281450688;6556.868862622626;6575.671216439063;6578;6216.191895053614;6241.719122639402;6266.937330626635;6270.404311590391;6349.809505714836;6305.337939516285;6320.810375688737;6331.0819931110655;6332.145160403471;6354.142190804182;5396.406395296436;6231.688415579112;6254.749810007549;6287.402696743802;6297.244330680348;5016.272640467185;5950.642961422329;6146.139540464249;6147.415995480422;6196.659183744938;5070.923936140887;6137.41694539018;6152.753889844356;6152.938470615237;6154.569180157346;6247.468965138014;6254.781082662085;6265.028920517327;6274.1843560336865;6275.686215689106;5927.288725352985;5984.341722410444;6080.209572755691;6086;6148.846278843064 08:50:15 INFO - PROCESS | 2203 | |1;ext.html;519.9792008319668;528.1444060621792;538.079883399387;551.1759918921213;553.0542771860119;24836.87581562092;33521.32957340855;35129;43145.56854431455;44588.777056114755;45803.77098114505;46331.76834115825;46363.53636463634;46368.53631463684;46372.76813615936;9912.653057143001;10091.091801737843;10110.949445252763;10122.848157277647;10130.797384052314;7802.8439431211345;9524.761880952998;9538.284628652878;9659.130678238957;9939.055789699967;9478.573464194093;12304.81542776859;12665.936670316636;12690.238585684841;12883.54907578235;12664.303463309514;12767.680807979828;12767.936160319232;12772.48910043597;12780.424880880382;12538.122331436804;12634.49462021518;12640.178388404816;12643.810342844865;12652.367381630973;42931.57068429307;43306.78346608279;43461.78269108666;43464.91337716551;43479.782601086794;3371.662833716633;3378.594568651753;3507.1056880495566;3507.4037413639744;3528.7177025837873;27952;31383.05850824479;31466;33066.17334566631;33185;3185.051175931199;3224.967750322514;3252.5121231815415;3266.069170286457;3316.3864685033345;493669;494949;495495;497852.510737444;499189.50405248103;29420;34009;35506.11234719101;67336.6633166831;68017.97973030408;37397;37548.43677344842;37591.06022349408;37647.81176094102;37654.43518347227;15730.764038539432;15892.205389730698;15935.681286374207;15938.28277727505;15949.521514354588;13275.269860157785;13420.664483387993;13423.060385773097;13425.462981480827;13429.328533573282;7657;7852;8082.2725954664365;8305.543195124204;8390.79023024417;2953.719396657314;3337.8831740888954;3368.3768502827056;3379.864805407756;3384.390809654286;950.9809803804023;983.498415807943;1023.0843395161289;1053.5838343854289;1073.683263437288;31885;32037.67962320347;32224.516632250536;32239.194020149214;32437;922.9030951750104;931.0456781798711;1093.059968427152;1167.3812879174052;1184.3190165654783;16366.263518141473;16781;16805.884553048247;16847.904886182358;16994.980301181968;700390;705272;705536.9446305471;708707;710630.4468477728;63311.68344158342;63856.361436385974;63910.36089639044;63916.68041659762;64062;1419.410944458059;1434.7202295552268;1460.3282490054694;1462.7440197965313;1463.2244910197826;30481.54277685792;30656.08031759051;31720.682793171774;31751;32465.35069298648;55724.72137639286;55849.72075139761;55999;56119.43880561142;56371.15443268272;584756;585322;587524;588167;588978;25255.242342729747;25910.61134083028;27675.584866227426;27964.44071118607;28198;39745;50682.732931677005;51340.22989655084;51610.48389516057;51858;40967.97580060403;41813.79093104515;42381.36427953645;42616.360754589325;43703.78148109366;35267.11832204215;42020;42407.575924240366;43004.92487687833;43642.90892727707;40843.795781020905;41359.586404135574;41632.167356653306;41767;41806.372904407064;34127.4880876792;36017;36367.090822729646;37173;37197.628023719415;24609.015639374225;25602.103926362488;26786.732132678422;27178.45643087167;27714.8914043436 08:50:15 INFO - PROCESS | 2203 | |2;jquery.html;385.9652631263186;386.6230425335298;389.2914894891299;398.67707157202676;421.1009494728753;14211;20312.289069882558;21115.894420527915;21510.784892151092;21542.138314467422;20608.278710245144;20801.479963000933;21087.472813179676;21190.470238244052;21296.148154073857;5959.1359252908305;6218.875622487548;6271.216097987752;6285.874282514347;6286.46565041972;5910.143029260755;6117.632942023492;6158.907616385758;6161.907571386433;6225.097360882669;6139.723712432951;6143.416375444325;6158.107074474198;6158.56890017699;6164.568480206387;5959.65907670775;5997.85005374867;6133.846653833645;6164.198654174974;6165.0444181152;5387.272718183034;5389.056915039852;5389.487998640123;5390.056740070511;5397.27136836526;22084.447888802748;24764.876175619007;25799.484010319688;26869.73130268692;27659.58510622342;2211;2408.638704194367;2491.6511688363644;2531.190019193859;2819.9295017624518;10245.487725613764;10384.878387214283;10601.946990265076;10647.84028239577;10683.358998460117;1887.8300952914303;1945.1538580717292;1953.1210955070133;1979.653560626899;1995.521074942018;111620;120092;122622;124290.37854810759;124873;11353.943230283796;11634.88365116338;11736.765264694659;11750.471228794724;11962.58130965412;13187.010974176976;13782.586522404345;14990.47533336328;15007.924960375129;15025.624359391102;7605.733799317108;8627.95686021566;8647.221750042527;8667.913320866837;8948.8657670135;7108.680109394986;8820.5589720515;8835.602397892111;8965.641374345083;9015.008349081596;4222.923154595558;4310.612044915973;4552.6130278926;4616.330632058332;4708.364370809965;1593.334964961618;1648.967020659604;1949.541857663448;1955.1592815089364;1959.5982823521144;837.5644664774284;906.4425378392373;912.9543522823832;954.4607296877381;1083.3337497439177;20848;21608.675869861963;21632;21682;21695.02372393246;944.1172503709054;949.2358651285643;953.4851180362665;964.6334392930642;1005.4520286443906;10912.890871091346;11221.887781122085;11316.490757915913;11429.142814288962;11430.428478576037;603582;611209;614411;629822;633106;78095;87387;128945.35527322491;133955.83961074278;137963;880.4761167105562;986.8322385194534;990.2078337330107;1010.7473131717071;1012.7569383347943;16410.933289336153;17021.82978170252;19073.427797166107;19091;19117.71323430122;51420.74289628528;51686.22470662868;52348;52644;53122.20316695175;72268.63865680638;77374.61312693625;80637.59681201792;81996.59001704953;84005;6900.551464154814;7955.5226686399;7972.242636949481;8139.186081391814;8245.299149572353;12071.69820754453;12345.814812777995;12372;12833.614991550268;12882.613521594367;9474;11199.944000279946;11239.831402529133;11355.03482204022;11462.885371146516;7974.880376794236;9488.193503552275;9511.904880951379;9670.081342272473;9698.175655069115;11072.557097716;11425.97166255041;11494.367809770209;11494.905712079517;11677.532898684298;6525.64108974011;7169.928300716926;7518.812029699301;7957.80105497344;8424.073351931405;10958.780824383308;11419.429028548706;11576.826347604625;11716.472758726048;11841.467133979164 08:50:15 INFO - PROCESS | 2203 | |3;mootools.html;1380.6341319550327;1490.9850901490986;1545.505438259757;1784.6163074938893;1953.199188332784;7991.240832120947;8538.445001074933;8554.486730796156;8782.692605758799;9085;8948.06045365237;8985.685501007467;9004.549772511382;9012.233960113383;9017;2006.8193862552369;2389.0682633772844;2418.467937053845;2421.5762241607736;2422.7213870404867;2360.5396947595227;2392.6650268962358;2408.2895545814026;2413.3604594782396;2416.867072311031;2368.6802281691926;2393.186316652338;2402.255300856731;2407.4462873539096;2407.9277621671376;2347.6713260143592;2362.70466191726;2377.405648587853;2377.702787151606;2387.3912152401026;2346.0615753698485;2378.988105059481;2381.3808409813573;2382.190055381179;2387.0451819272257;8895.199432051146;8947.463152210888;9002.729918102403;9035.909640903637;9041.050689677555;5324.627276090675;5505.889882202334;5506.229127922094;5560.999020176367;5568.136938774496;3680.5583330000304;3713.7002049282805;3741;3797.1076796952693;3798.7150963677836;1118.2954738514684;1118.8769235384102;1124.2916962313773;1150.5972909481613;1151.5624062856145;11936.522539098425;11959.222650527689;12031.518739250507;12050.638480845588;12056.879431205663;4509.233430316854;4556.908861822745;4737.644676649265;4754.976225118921;4762.59517940972;9456.243500519942;9459.432434053979;9474.810503790024;9493.762655933519;9511.239100872051;5376.892462150813;5396.298481197419;5402.270693456412;5403.2165336026055;5410.458954104558;4275.230458517497;4535.410396648481;4665.183592871302;4677.9942312403;5059.87350316245;3886.145048089416;3934.5475270343704;3982.6614737747036;4019.698522610816;4031.677465802729;1174.900133488646;1178.4284621958343;1190.458341454636;1196.5094311332487;1202.5670758526924;833.2167762303536;915.1410180695595;924.523870206844;924.6024209589941;928.6749637626776;5703.857403564944;5821.417858214229;5873.236479257755;5990.981533139377;6110.5722599418505;451.6138701410247;455.34885114286226;468.0732150342341;471.6486217767716;472.47319239048903;3871.8644847430205;3969.8213580388374;3999.120193557471;4012.9799351003057;4037.6164264394833;10487.423191724529;10988.670339889815;10997.945010274898;11021.283616564897;11022.779544409228;10418.21863360251;10810;10970.451477426097;11151.107911367071;11172.162087843444;8308.833823323499;8354.331653467709;8373.49759014461;8439;8557.27263182624;6016.308124565556;6067.666278354556;6113.480354169946;6199.5040396767245;6274.968625156845;10081.092701656886;10113.898861011296;10391.37651740898;10473.162147028372;10622.36265824053;9895.950520247352;10282.07461328484;10644.520996555018;10701.946490267499;10710.785784284426;7006.334398232159;7299;7342.853142937218;7348.448866335153;7371.447141464304;8505.447145935494;8545.060043395346;8597.355198360023;8619.569021548772;8666.826663466569;7591.164971853203;7701.6534255957495;7805.687772489036;7891.84216315682;7896.960515197387;7611.3530349920875;7613.162552119373;7663.808404789925;7703.653335599799;7714.845703085794;7080.468964827555;7142.428605711634;7178.5692858428665;7180.892286615601;7187.065681461377;5116.667416617908;5123.257127716534;5157.097507936021;5165.535101840853;5223.399309079353;5090.745462726923;5098.515641014098;5190.714510701948;5257.973710131425;5273.393559740679 08:50:15 INFO - PROCESS | 2203 | |4;prototype.html;258.2304731898942;306.5539639824056;306.98925537606175;315.4400938334456;319.4569232305082;5379.515843574078;5501.2848329717135;5550.694711790855;5570.554355651549;5600.299962504687;4664.600619814059;5344.259018686916;5378.757955891984;5438.700871452069;5574.745682221498;4267.911682520953;4296.5273819879785;4305.784710764449;4306.418633484479;4320.207114047666;3666.8716594919183;3886.145048089416;3941.3496773032416;4088.5911408859024;4172.853950111747;4128.112455821998;4165.291900376943;4172.5201601815725;4176.415301857743;4188.497380314367;3951.7628942263414;4040.474738284004;4166.937495937578;4175.770332631704;4199.664026877873;3430.5540279763723;3576.8748093816853;3614.493970844084;3635.4001589737663;3635.563732352108;4415;5380.24676545284;5382.91925621116;5406.729418586629;5427.240186373911;4243.575642435762;4748.865748605904;4771.045790841811;4789.664723469358;4791.688540244873;5116.897662046738;5539.9169012464845;5741.799037033726;5794.319647302277;5858.76564937402;3776;3973;3974.5627980922077;3988.720789544733;4010.979945100285;6014.41380206511;6162.7534898604;6177.691115444255;6177.96911015442;6193.801200573776;3528.4883691864534;4041.8787436376956;4043.0189207288217;4045.7572545646767;4063.715539912178;3316.9834150829092;3953.1303113315025;4051.513818341818;4095.6313931746286;4127.731697439657;4242.85150019754;4391.516933137353;4779.060877560345;4793.448753393334;4810.5670489656095;4996.625253106032;5814.924239015796;5919.253820123074;5932.495737862244;5949.553783466171;1281.5386460874079;1302.5766625846638;1340.4035204333977;1369.7465968795805;1389.4303335632544;766.4481573267238;773.7079077939854;795.9005124359455;809.0938149272852;814.9470284431493;3957.762534247954;4116.6912481564;4154.771487568152;4622.375979242826;4635.953640463552;4058.0057885818164;4606.378138951266;4650.6046986005895;4654.511276316006;4849.733264670477;3853.3064048470983;4590.255702832902;4605.4933957264675;4615.576822168388;4622.722636641813;2629.0272599138057;2676.6081637548364;2810.901618443345;2848.5015122353507;3232.3858466891215;5827.737751801179;6339.683015849189;6359.3640635936035;6372.240378176898;6374.203224596926;5078.4413714491375;5100.327764009907;5758.827235182951;6156.569040167145;6281.7173227204885;5101.387833460009;6272.655003974825;6317.936820631827;6320.968395157995;6334.683265836689;4875.68308059975;5173.689578625294;5240.68555886648;6116.724747386289;6143.600665956698;4988.72562009093;5132.5124113209185;5686.10008895458;5991.921454138297;6104.1615648723555;5529.391766905717;5722.512146841768;5994.520438365008;6429.517786165963;6446.258680251643;3948.3090459170103;4072.5927407259037;4102.835886564504;4104.8768536943935;4138.916507202153;5823.699638642797;6070.908936366046;6095.933211687849;6175.69121543912;6189.473894718999;5123.923141152811;5848.766049358148;5852.561057920588;5858.882822343615;5878.617889837147;4910.8281210157475;5679.716014199356;5829.621074630305;5996.980513312835;6007.459328660441;5726.676359348665;5806.577388539835;5871.823845284648;5877.94122058774;5892.587518873637;5146.485351464824;5975.970120149545;5976.521878249642;5979.551533634908;5989.371116032754;5139.535232458731;5546.667199968015;5685.630434021776;5859.707014649336;5873.88540125563 08:50:15 INFO - PROCESS | 2203 | |5;yui.html;719.6689522819503;743.5315751076818;757.9469437139401;783.854580092078;858.6007506509471;15498;16811.663766724672;16847.15764211789;17557.12214389282;18775;16999.23503442345;17382.47852564422;17412.912935435306;17436.476905692816;17441.65116697665;637.5027478566725;870.403773415211;896.9910300896972;897.0401670212875;898.0884402331639;728.100795517538;734.7281505842845;734.9669264883094;736.1938677148514;736.7936977646268;935.2658163341778;942.726609283307;950.4629884115452;967.1827305926516;972.6401231544303;957.1003256938455;957.5403806173041;958.9616415343447;959.0217977662815;959.5010594490826;707.5719189890094;708.0299989015082;708.9078419805444;710.1478226128667;710.7370272999024;15895.523134305873;15901.20493975308;15933.920330398274;15952.680946381124;15965.441209557726;10835.566577336896;13748.725025499534;14046.789298160536;14115.717685646332;14412.855871441256;12198.939005304917;12217.266963982102;12305;12368.13423060386;12506.374681265901;4501.324801279801;4643.466001409847;4698.765061746899;4787.257975013878;4915.0661374339115;127975.08037379447;129264;130067;130661.34669326688;132924;10931.453427328603;11278.210525263154;11295.943520282346;11473.885261147281;11503.942480287546;21697.891510542348;22797.430064248525;22813.771862281497;22827.771722282898;22832.543349133255;8091.716789912325;8260.463069900557;8488.660453581788;8501.702440414556;8586.055533891267;4380.685794261709;4602.171609110393;4647.697899636581;4680.087382960358;4703.988642441891;3663.0659181908977;3823.751456155341;3828.1386687995537;3829.329867273216;3837.597052309523;684.4968947823346;692.7229108356597;703.6235613946515;711.9181294151135;722.37514549915;549.4121290219426;627.9497640188774;680.680080362229;712.6116266634697;736.2416710787898;8886;9130.726078217664;9581.568829402693;10182.541785619665;10322.896771032343;603.4297588778562;685.7074712301031;709.8757717399434;714.0931017607609;715.6135686729108;4480.686351955397;4893.388326459192;4918.262260660858;4922;4935.87660308488;106365.46817265864;155834;157404.42595574126;159531;168107;149607;151839;152858;153609.2319538395;153926;113693;114762;115371;116039;121266.78733212908;9281.72154835356;9364.906350936404;9426.95286523563;9439.52802359893;9479.810403792024;58040.4195958035;61882.69058654678;68661.65669171623;68972.3102768986;69393;78271.21728782867;82007;82904.17095828964;88549.5572522159;90706;13415.664608384868;13901.860981390057;13916.930415347859;13940.30298485092;14331.78502322487;14305.64235894111;14639.92680036593;15079.321430535872;15314;15330.770038449655;14325.426982920566;14955.177465239516;15173.620659483602;15353.846461535242;15870.285837137126;14258.144511329354;14288.499902503363;15276.541703748906;15479.690406191588;15836.445724400051;12020.819687704516;13419.932900335436;14241.35913883857;14341.64145896361;15144.772828407362;9902.801943960936;11005.669829905115;12062.215955962834;12085.741024079342;12642.431090601129;8179.754607361789;9707.514624268899;10207.387556746618;11206.943965280447;11544.538218471167 08:50:15 INFO - PROCESS | 2203 | __end_tp_report 08:50:15 INFO - PROCESS | 2203 | __start_cc_report 08:50:15 INFO - PROCESS | 2203 | _x_x_mozilla_cycle_collect,1165 08:50:15 INFO - PROCESS | 2203 | __end_cc_report 08:50:15 INFO - PROCESS | 2203 | __startTimestamp1481734215298__endTimestamp 08:50:15 INFO - PROCESS | 2203 | 08:50:15 INFO - PROCESS | 2203 | ------- Summary: start ------- 08:50:15 INFO - PROCESS | 2203 | Number of tests: 6 08:50:15 INFO - PROCESS | 2203 | 08:50:15 INFO - PROCESS | 2203 | [#0] dojo.html Cycles:165 Average:5862.71 Median:6120.33 stddev:642.15 (10.5%) stddev-sans-first:613.58 08:50:15 INFO - PROCESS | 2203 | Values: 3368.7 3476.8 3490.8 3508.4 3548.2 5797.7 5870.6 5878.1 5883.7 5936.9 5850.5 5897.0 5900.9 5906.1 5912.2 5289.9 5315.4 5322.8 5401.8 5431.2 5289.7 5298.8 5329.7 5415.6 5441.9 5401.9 5411.7 5416.6 5416.9 5419.9 5364.7 5379.2 5385.2 5388.1 5428.8 5204.2 5280.6 5312.9 5370.3 5401.0 5894.7 5932.5 5959.5 5998.8 6010.5 5180.4 6164.5 6253.5 6257.4 6285.3 6254.7 6286.0 6296.2 6325.6 6331.7 5941.4 5946.2 5953.0 5961.8 5999.4 6243.3 6278.5 6290.1 6294.5 6396.8 6203.6 6305.6 6311.6 6314.3 6316.9 6358.5 6366.0 6375.2 6378.0 6380.9 6191.4 6248.8 6277.7 6355.5 6380.6 6280.1 6328.1 6346.4 6394.7 6397.0 5010.4 5021.7 5050.9 5075.6 5082.9 4424.8 4474.3 4549.6 4573.7 4602.8 5998.2 6045.4 6073.7 6092.1 6119.7 6298.0 6309.3 6330.8 6333.8 6345.0 6118.3 6119.5 6120.3 6139.5 6142.1 5398.9 5501.0 5503.4 5575.0 5596.2 5376.7 6392.0 6491.1 6585.4 6601.4 6318.2 6327.1 6328.4 6332.9 6368.7 6320.8 6447.9 6556.9 6575.7 6578.0 6216.2 6241.7 6266.9 6270.4 6349.8 6305.3 6320.8 6331.1 6332.1 6354.1 5396.4 6231.7 6254.7 6287.4 6297.2 5016.3 5950.6 6146.1 6147.4 6196.7 5070.9 6137.4 6152.8 6152.9 6154.6 6247.5 6254.8 6265.0 6274.2 6275.7 5927.3 5984.3 6080.2 6086.0 6148.8 08:50:15 INFO - PROCESS | 2203 | 08:50:15 INFO - PROCESS | 2203 | [#1] ext.html Cycles:180 Average:72302.73 Median:19517.42 stddev:161394.70 (826.9%) stddev-sans-first:161757.46 08:50:15 INFO - PROCESS | 2203 | Values: 520.0 528.1 538.1 551.2 553.1 24836.9 33521.3 35129.0 43145.6 44588.8 45803.8 46331.8 46363.5 46368.5 46372.8 9912.7 10091.1 10110.9 10122.8 10130.8 7802.8 9524.8 9538.3 9659.1 9939.1 9478.6 12304.8 12665.9 12690.2 12883.5 12664.3 12767.7 12767.9 12772.5 12780.4 12538.1 12634.5 12640.2 12643.8 12652.4 42931.6 43306.8 43461.8 43464.9 43479.8 3371.7 3378.6 3507.1 3507.4 3528.7 27952.0 31383.1 31466.0 33066.2 33185.0 3185.1 3225.0 3252.5 3266.1 3316.4 493669.0 494949.0 495495.0 497852.5 499189.5 29420.0 34009.0 35506.1 67336.7 68018.0 37397.0 37548.4 37591.1 37647.8 37654.4 15730.8 15892.2 15935.7 15938.3 15949.5 13275.3 13420.7 13423.1 13425.5 13429.3 7657.0 7852.0 8082.3 8305.5 8390.8 2953.7 3337.9 3368.4 3379.9 3384.4 951.0 983.5 1023.1 1053.6 1073.7 31885.0 32037.7 32224.5 32239.2 32437.0 922.9 931.0 1093.1 1167.4 1184.3 16366.3 16781.0 16805.9 16847.9 16995.0 700390.0 705272.0 705536.9 708707.0 710630.4 63311.7 63856.4 63910.4 63916.7 64062.0 1419.4 1434.7 1460.3 1462.7 1463.2 30481.5 30656.1 31720.7 31751.0 32465.4 55724.7 55849.7 55999.0 56119.4 56371.2 584756.0 585322.0 587524.0 588167.0 588978.0 25255.2 25910.6 27675.6 27964.4 28198.0 39745.0 50682.7 51340.2 51610.5 51858.0 40968.0 41813.8 42381.4 42616.4 43703.8 35267.1 42020.0 42407.6 43004.9 43642.9 40843.8 41359.6 41632.2 41767.0 41806.4 34127.5 36017.0 36367.1 37173.0 37197.6 24609.0 25602.1 26786.7 27178.5 27714.9 08:50:15 INFO - PROCESS | 2203 | 08:50:15 INFO - PROCESS | 2203 | [#2] jquery.html Cycles:180 Average:35266.55 Median:1602.95 stddev:102872.48 (6417.7%) stddev-sans-first:103127.72 08:50:15 INFO - PROCESS | 2203 | Values: 386.0 386.6 389.3 398.7 421.1 14211.0 20312.3 21115.9 21510.8 21542.1 20608.3 20801.5 21087.5 21190.5 21296.1 5959.1 6218.9 6271.2 6285.9 6286.5 5910.1 6117.6 6158.9 6161.9 6225.1 6139.7 6143.4 6158.1 6158.6 6164.6 5959.7 5997.9 6133.8 6164.2 6165.0 5387.3 5389.1 5389.5 5390.1 5397.3 22084.4 24764.9 25799.5 26869.7 27659.6 2211.0 2408.6 2491.7 2531.2 2819.9 10245.5 10384.9 10601.9 10647.8 10683.4 1887.8 1945.2 1953.1 1979.7 1995.5 111620.0 120092.0 122622.0 124290.4 124873.0 11353.9 11634.9 11736.8 11750.5 11962.6 13187.0 13782.6 14990.5 15007.9 15025.6 7605.7 8628.0 8647.2 8667.9 8948.9 7108.7 8820.6 8835.6 8965.6 9015.0 4222.9 4310.6 4552.6 4616.3 4708.4 1593.3 1649.0 1949.5 1955.2 1959.6 837.6 906.4 913.0 954.5 1083.3 20848.0 21608.7 21632.0 21682.0 21695.0 944.1 949.2 953.5 964.6 1005.5 10912.9 11221.9 11316.5 11429.1 11430.4 603582.0 611209.0 614411.0 629822.0 633106.0 78095.0 87387.0 128945.4 133955.8 137963.0 880.5 986.8 990.2 1010.7 1012.8 16410.9 17021.8 19073.4 19091.0 19117.7 51420.7 51686.2 52348.0 52644.0 53122.2 72268.6 77374.6 80637.6 81996.6 84005.0 6900.6 7955.5 7972.2 8139.2 8245.3 12071.7 12345.8 12372.0 12833.6 12882.6 9474.0 11199.9 11239.8 11355.0 11462.9 7974.9 9488.2 9511.9 9670.1 9698.2 11072.6 11426.0 11494.4 11494.9 11677.5 6525.6 7169.9 7518.8 7957.8 8424.1 10958.8 11419.4 11576.8 11716.5 11841.5 08:50:15 INFO - PROCESS | 2203 | 08:50:15 INFO - PROCESS | 2203 | [#3] mootools.html Cycles:180 Average:5804.61 Median:4746.31 stddev:3289.03 (69.3%) stddev-sans-first:3281.46 08:50:15 INFO - PROCESS | 2203 | Values: 1380.6 1491.0 1545.5 1784.6 1953.2 7991.2 8538.4 8554.5 8782.7 9085.0 8948.1 8985.7 9004.5 9012.2 9017.0 2006.8 2389.1 2418.5 2421.6 2422.7 2360.5 2392.7 2408.3 2413.4 2416.9 2368.7 2393.2 2402.3 2407.4 2407.9 2347.7 2362.7 2377.4 2377.7 2387.4 2346.1 2379.0 2381.4 2382.2 2387.0 8895.2 8947.5 9002.7 9035.9 9041.1 5324.6 5505.9 5506.2 5561.0 5568.1 3680.6 3713.7 3741.0 3797.1 3798.7 1118.3 1118.9 1124.3 1150.6 1151.6 11936.5 11959.2 12031.5 12050.6 12056.9 4509.2 4556.9 4737.6 4755.0 4762.6 9456.2 9459.4 9474.8 9493.8 9511.2 5376.9 5396.3 5402.3 5403.2 5410.5 4275.2 4535.4 4665.2 4678.0 5059.9 3886.1 3934.5 3982.7 4019.7 4031.7 1174.9 1178.4 1190.5 1196.5 1202.6 833.2 915.1 924.5 924.6 928.7 5703.9 5821.4 5873.2 5991.0 6110.6 451.6 455.3 468.1 471.6 472.5 3871.9 3969.8 3999.1 4013.0 4037.6 10487.4 10988.7 10997.9 11021.3 11022.8 10418.2 10810.0 10970.5 11151.1 11172.2 8308.8 8354.3 8373.5 8439.0 8557.3 6016.3 6067.7 6113.5 6199.5 6275.0 10081.1 10113.9 10391.4 10473.2 10622.4 9896.0 10282.1 10644.5 10701.9 10710.8 7006.3 7299.0 7342.9 7348.4 7371.4 8505.4 8545.1 8597.4 8619.6 8666.8 7591.2 7701.7 7805.7 7891.8 7897.0 7611.4 7613.2 7663.8 7703.7 7714.8 7080.5 7142.4 7178.6 7180.9 7187.1 5116.7 5123.3 5157.1 5165.5 5223.4 5090.7 5098.5 5190.7 5258.0 5273.4 08:50:15 INFO - PROCESS | 2203 | 08:50:15 INFO - PROCESS | 2203 | [#4] prototype.html Cycles:180 Average:4643.59 Median:5037.53 stddev:1474.84 (29.3%) stddev-sans-first:1441.78 08:50:15 INFO - PROCESS | 2203 | Values: 258.2 306.6 307.0 315.4 319.5 5379.5 5501.3 5550.7 5570.6 5600.3 4664.6 5344.3 5378.8 5438.7 5574.7 4267.9 4296.5 4305.8 4306.4 4320.2 3666.9 3886.1 3941.3 4088.6 4172.9 4128.1 4165.3 4172.5 4176.4 4188.5 3951.8 4040.5 4166.9 4175.8 4199.7 3430.6 3576.9 3614.5 3635.4 3635.6 4415.0 5380.2 5382.9 5406.7 5427.2 4243.6 4748.9 4771.0 4789.7 4791.7 5116.9 5539.9 5741.8 5794.3 5858.8 3776.0 3973.0 3974.6 3988.7 4011.0 6014.4 6162.8 6177.7 6178.0 6193.8 3528.5 4041.9 4043.0 4045.8 4063.7 3317.0 3953.1 4051.5 4095.6 4127.7 4242.9 4391.5 4779.1 4793.4 4810.6 4996.6 5814.9 5919.3 5932.5 5949.6 1281.5 1302.6 1340.4 1369.7 1389.4 766.4 773.7 795.9 809.1 814.9 3957.8 4116.7 4154.8 4622.4 4636.0 4058.0 4606.4 4650.6 4654.5 4849.7 3853.3 4590.3 4605.5 4615.6 4622.7 2629.0 2676.6 2810.9 2848.5 3232.4 5827.7 6339.7 6359.4 6372.2 6374.2 5078.4 5100.3 5758.8 6156.6 6281.7 5101.4 6272.7 6317.9 6321.0 6334.7 4875.7 5173.7 5240.7 6116.7 6143.6 4988.7 5132.5 5686.1 5991.9 6104.2 5529.4 5722.5 5994.5 6429.5 6446.3 3948.3 4072.6 4102.8 4104.9 4138.9 5823.7 6070.9 6095.9 6175.7 6189.5 5123.9 5848.8 5852.6 5858.9 5878.6 4910.8 5679.7 5829.6 5997.0 6007.5 5726.7 5806.6 5871.8 5877.9 5892.6 5146.5 5976.0 5976.5 5979.6 5989.4 5139.5 5546.7 5685.6 5859.7 5873.9 08:50:15 INFO - PROCESS | 2203 | 08:50:15 INFO - PROCESS | 2203 | [#5] yui.html Cycles:180 Average:26527.93 Median:3825.95 stddev:42963.89 (1123.0%) stddev-sans-first:43040.71 08:50:15 INFO - PROCESS | 2203 | Values: 719.7 743.5 757.9 783.9 858.6 15498.0 16811.7 16847.2 17557.1 18775.0 16999.2 17382.5 17412.9 17436.5 17441.7 637.5 870.4 897.0 897.0 898.1 728.1 734.7 735.0 736.2 736.8 935.3 942.7 950.5 967.2 972.6 957.1 957.5 959.0 959.0 959.5 707.6 708.0 708.9 710.1 710.7 15895.5 15901.2 15933.9 15952.7 15965.4 10835.6 13748.7 14046.8 14115.7 14412.9 12198.9 12217.3 12305.0 12368.1 12506.4 4501.3 4643.5 4698.8 4787.3 4915.1 127975.1 129264.0 130067.0 130661.3 132924.0 10931.5 11278.2 11295.9 11473.9 11503.9 21697.9 22797.4 22813.8 22827.8 22832.5 8091.7 8260.5 8488.7 8501.7 8586.1 4380.7 4602.2 4647.7 4680.1 4704.0 3663.1 3823.8 3828.1 3829.3 3837.6 684.5 692.7 703.6 711.9 722.4 549.4 627.9 680.7 712.6 736.2 8886.0 9130.7 9581.6 10182.5 10322.9 603.4 685.7 709.9 714.1 715.6 4480.7 4893.4 4918.3 4922.0 4935.9 106365.5 155834.0 157404.4 159531.0 168107.0 149607.0 151839.0 152858.0 153609.2 153926.0 113693.0 114762.0 115371.0 116039.0 121266.8 9281.7 9364.9 9427.0 9439.5 9479.8 58040.4 61882.7 68661.7 68972.3 69393.0 78271.2 82007.0 82904.2 88549.6 90706.0 13415.7 13901.9 13916.9 13940.3 14331.8 14305.6 14639.9 15079.3 15314.0 15330.8 14325.4 14955.2 15173.6 15353.8 15870.3 14258.1 14288.5 15276.5 15479.7 15836.4 12020.8 13419.9 14241.4 14341.6 15144.8 9902.8 11005.7 12062.2 12085.7 12642.4 8179.8 9707.5 10207.4 11206.9 11544.5 08:50:15 INFO - PROCESS | 2203 | -------- Summary: end -------- 08:50:15 INFO - PROCESS | 2203 | 08:50:15 INFO - TEST-INFO | 2203: exit 0 08:50:15 INFO - TEST-OK | dromaeo_css | took 1115629ms 08:50:15 INFO - TEST-START | kraken 08:50:15 INFO - operating with platform_type : mac_ 08:50:15 INFO - Initialising browser for kraken test... 08:50:15 INFO - TEST-INFO | started process 2206 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/tmpM12hb4/profile http://localhost:56801/getInfo.html) 08:50:18 INFO - PROCESS | 2206 | __metrics Screen width/height:1600/1200 08:50:18 INFO - PROCESS | 2206 | colorDepth:24 08:50:18 INFO - PROCESS | 2206 | Browser inner width/height: 1024/689 08:50:18 INFO - PROCESS | 2206 | __metrics 08:50:18 INFO - PROCESS | 2206 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 08:50:18 INFO - PROCESS | 2206 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 08:50:18 INFO - TEST-INFO | 2206: exit 0 08:50:18 INFO - Browser initialized. 08:50:18 INFO - Running cycle 1/1 for kraken test... 08:50:18 INFO - TEST-INFO | started process 2208 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/tmpM12hb4/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:50:30 INFO - PROCESS | 2208 | RSS: Main: 168554496 08:50:30 INFO - PROCESS | 2208 | 08:50:33 INFO - PROCESS | 2208 | Cycle 1(1): loaded http://localhost:56801/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:56801/tests/kraken/driver.html?testName=audio-beat-detection) 08:50:34 INFO - PROCESS | 2208 | RSS: Main: 219299840 08:50:34 INFO - PROCESS | 2208 | 08:50:36 INFO - PROCESS | 2208 | Cycle 1(1): loaded http://localhost:56801/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:56801/tests/kraken/driver.html?testName=audio-dft) 08:50:37 INFO - PROCESS | 2208 | RSS: Main: 232337408 08:50:37 INFO - PROCESS | 2208 | 08:50:40 INFO - PROCESS | 2208 | Cycle 1(1): loaded http://localhost:56801/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:56801/tests/kraken/driver.html?testName=audio-fft) 08:50:41 INFO - PROCESS | 2208 | RSS: Main: 329646080 08:50:41 INFO - PROCESS | 2208 | 08:50:43 INFO - PROCESS | 2208 | Cycle 1(1): loaded http://localhost:56801/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:56801/tests/kraken/driver.html?testName=audio-oscillator) 08:50:44 INFO - PROCESS | 2208 | RSS: Main: 228216832 08:50:44 INFO - PROCESS | 2208 | 08:50:45 INFO - PROCESS | 2208 | Cycle 1(1): loaded http://localhost:56801/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:56801/tests/kraken/driver.html?testName=imaging-gaussian-blur) 08:50:46 INFO - PROCESS | 2208 | RSS: Main: 228696064 08:50:46 INFO - PROCESS | 2208 | 08:50:49 INFO - PROCESS | 2208 | Cycle 1(1): loaded http://localhost:56801/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:56801/tests/kraken/driver.html?testName=imaging-darkroom) 08:50:50 INFO - PROCESS | 2208 | RSS: Main: 266387456 08:50:50 INFO - PROCESS | 2208 | 08:50:53 INFO - PROCESS | 2208 | Cycle 1(1): loaded http://localhost:56801/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:56801/tests/kraken/driver.html?testName=imaging-desaturate) 08:50:53 INFO - PROCESS | 2208 | RSS: Main: 266866688 08:50:53 INFO - PROCESS | 2208 | 08:50:56 INFO - PROCESS | 2208 | Cycle 1(1): loaded http://localhost:56801/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:56801/tests/kraken/driver.html?testName=json-parse-financial) 08:50:57 INFO - PROCESS | 2208 | RSS: Main: 265998336 08:50:57 INFO - PROCESS | 2208 | 08:50:58 INFO - PROCESS | 2208 | Cycle 1(1): loaded http://localhost:56801/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:56801/tests/kraken/driver.html?testName=json-stringify-tinderbox) 08:50:59 INFO - PROCESS | 2208 | RSS: Main: 238727168 08:50:59 INFO - PROCESS | 2208 | 08:51:01 INFO - PROCESS | 2208 | Cycle 1(1): loaded http://localhost:56801/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:56801/tests/kraken/driver.html?testName=stanford-crypto-aes) 08:51:01 INFO - PROCESS | 2208 | RSS: Main: 245129216 08:51:01 INFO - PROCESS | 2208 | 08:51:03 INFO - PROCESS | 2208 | Cycle 1(1): loaded http://localhost:56801/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:56801/tests/kraken/driver.html?testName=stanford-crypto-ccm) 08:51:04 INFO - PROCESS | 2208 | RSS: Main: 252030976 08:51:04 INFO - PROCESS | 2208 | 08:51:07 INFO - PROCESS | 2208 | Cycle 1(1): loaded http://localhost:56801/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:56801/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 08:51:08 INFO - PROCESS | 2208 | RSS: Main: 265457664 08:51:08 INFO - PROCESS | 2208 | 08:51:10 INFO - PROCESS | 2208 | Cycle 1(1): loaded http://localhost:56801/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:56801/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 08:51:11 INFO - PROCESS | 2208 | RSS: Main: 259702784 08:51:11 INFO - PROCESS | 2208 | 08:51:13 INFO - PROCESS | 2208 | Cycle 1(1): loaded http://localhost:56801/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:56801/tests/kraken/driver.html?testName=ai-astar) 08:51:13 INFO - PROCESS | 2208 | RSS: Main: 303960064 08:51:13 INFO - PROCESS | 2208 | 08:51:13 INFO - PROCESS | 2208 | __start_tp_report 08:51:13 INFO - PROCESS | 2208 | _x_x_mozilla_page_load 08:51:13 INFO - PROCESS | 2208 | _x_x_mozilla_page_load_details 08:51:13 INFO - PROCESS | 2208 | |i|pagename|runs| 08:51:13 INFO - PROCESS | 2208 | |0;ai-astar;117;113;112;112;113;116;112;115;113;111 08:51:13 INFO - PROCESS | 2208 | |1;audio-beat-detection;146;110;109;140;110;108;109;108;140;107 08:51:13 INFO - PROCESS | 2208 | |2;audio-dft;184;185;186;183;183;185;184;184;199;260 08:51:13 INFO - PROCESS | 2208 | |3;audio-fft;82;57;60;105;85;81;59;59;82;58 08:51:13 INFO - PROCESS | 2208 | |4;audio-oscillator;86;72;93;69;67;68;91;67;68;90 08:51:13 INFO - PROCESS | 2208 | |5;imaging-gaussian-blur;117;130;102;87;91;86;90;87;90;85 08:51:13 INFO - PROCESS | 2208 | |6;imaging-darkroom;95;94;94;96;94;94;100;140;149;150 08:51:13 INFO - PROCESS | 2208 | |7;imaging-desaturate;83;86;84;83;85;86;100;134;142;84 08:51:13 INFO - PROCESS | 2208 | |8;json-parse-financial;57;54;53;50;55;56;57;56;57;56 08:51:13 INFO - PROCESS | 2208 | |9;json-stringify-tinderbox;65;70;67;72;71;69;68;69;69;70 08:51:13 INFO - PROCESS | 2208 | |10;stanford-crypto-aes;85;96;99;81;83;81;82;85;84;90 08:51:13 INFO - PROCESS | 2208 | |11;stanford-crypto-ccm;137;139;135;134;144;147;128;138;167;142 08:51:13 INFO - PROCESS | 2208 | |12;stanford-crypto-pbkdf2;162;164;160;161;162;164;160;160;161;159 08:51:13 INFO - PROCESS | 2208 | |13;stanford-crypto-sha256-iterative;67;65;68;65;66;64;66;65;65;65 08:51:13 INFO - PROCESS | 2208 | __end_tp_report 08:51:13 INFO - PROCESS | 2208 | __start_cc_report 08:51:13 INFO - PROCESS | 2208 | _x_x_mozilla_cycle_collect,1900 08:51:13 INFO - PROCESS | 2208 | __end_cc_report 08:51:13 INFO - PROCESS | 2208 | __startTimestamp1481734273417__endTimestamp 08:51:13 INFO - PROCESS | 2208 | 08:51:13 INFO - PROCESS | 2208 | ------- Summary: start ------- 08:51:13 INFO - PROCESS | 2208 | Number of tests: 14 08:51:13 INFO - PROCESS | 2208 | 08:51:13 INFO - PROCESS | 2208 | [#0] ai-astar Cycles:10 Average:113.40 Median:113.00 stddev:1.96 (1.7%) stddev-sans-first:1.58 08:51:13 INFO - PROCESS | 2208 | Values: 117.0 113.0 112.0 112.0 113.0 116.0 112.0 115.0 113.0 111.0 08:51:13 INFO - PROCESS | 2208 | 08:51:13 INFO - PROCESS | 2208 | [#1] audio-beat-detection Cycles:10 Average:118.70 Median:110.00 stddev:16.19 (14.7%) stddev-sans-first:13.83 08:51:13 INFO - PROCESS | 2208 | Values: 146.0 110.0 109.0 140.0 110.0 108.0 109.0 108.0 140.0 107.0 08:51:13 INFO - PROCESS | 2208 | 08:51:13 INFO - PROCESS | 2208 | [#2] audio-dft Cycles:10 Average:193.30 Median:185.00 stddev:23.91 (12.9%) stddev-sans-first:25.12 08:51:13 INFO - PROCESS | 2208 | Values: 184.0 185.0 186.0 183.0 183.0 185.0 184.0 184.0 199.0 260.0 08:51:13 INFO - PROCESS | 2208 | 08:51:13 INFO - PROCESS | 2208 | [#3] audio-fft Cycles:10 Average:72.80 Median:70.50 stddev:16.45 (23.3%) stddev-sans-first:17.11 08:51:13 INFO - PROCESS | 2208 | Values: 82.0 57.0 60.0 105.0 85.0 81.0 59.0 59.0 82.0 58.0 08:51:13 INFO - PROCESS | 2208 | 08:51:13 INFO - PROCESS | 2208 | [#4] audio-oscillator Cycles:10 Average:77.10 Median:79.00 stddev:11.32 (14.3%) stddev-sans-first:11.54 08:51:13 INFO - PROCESS | 2208 | Values: 86.0 72.0 93.0 69.0 67.0 68.0 91.0 67.0 68.0 90.0 08:51:13 INFO - PROCESS | 2208 | 08:51:13 INFO - PROCESS | 2208 | [#5] imaging-gaussian-blur Cycles:10 Average:96.50 Median:87.00 stddev:15.31 (17.6%) stddev-sans-first:14.33 08:51:13 INFO - PROCESS | 2208 | Values: 117.0 130.0 102.0 87.0 91.0 86.0 90.0 87.0 90.0 85.0 08:51:13 INFO - PROCESS | 2208 | 08:51:13 INFO - PROCESS | 2208 | [#6] imaging-darkroom Cycles:10 Average:110.60 Median:94.00 stddev:24.86 (26.4%) stddev-sans-first:25.72 08:51:13 INFO - PROCESS | 2208 | Values: 95.0 94.0 94.0 96.0 94.0 94.0 100.0 140.0 149.0 150.0 08:51:13 INFO - PROCESS | 2208 | 08:51:13 INFO - PROCESS | 2208 | [#7] imaging-desaturate Cycles:10 Average:96.70 Median:84.00 stddev:22.41 (26.7%) stddev-sans-first:23.21 08:51:13 INFO - PROCESS | 2208 | Values: 83.0 86.0 84.0 83.0 85.0 86.0 100.0 134.0 142.0 84.0 08:51:13 INFO - PROCESS | 2208 | 08:51:13 INFO - PROCESS | 2208 | [#8] json-parse-financial Cycles:10 Average:55.10 Median:56.00 stddev:2.23 (4.0%) stddev-sans-first:2.26 08:51:13 INFO - PROCESS | 2208 | Values: 57.0 54.0 53.0 50.0 55.0 56.0 57.0 56.0 57.0 56.0 08:51:13 INFO - PROCESS | 2208 | 08:51:13 INFO - PROCESS | 2208 | [#9] json-stringify-tinderbox Cycles:10 Average:69.00 Median:69.50 stddev:2.00 (2.9%) stddev-sans-first:1.51 08:51:13 INFO - PROCESS | 2208 | Values: 65.0 70.0 67.0 72.0 71.0 69.0 68.0 69.0 69.0 70.0 08:51:13 INFO - PROCESS | 2208 | 08:51:13 INFO - PROCESS | 2208 | [#10] stanford-crypto-aes Cycles:10 Average:86.60 Median:85.00 stddev:6.35 (7.5%) stddev-sans-first:6.70 08:51:13 INFO - PROCESS | 2208 | Values: 85.0 96.0 99.0 81.0 83.0 81.0 82.0 85.0 84.0 90.0 08:51:13 INFO - PROCESS | 2208 | 08:51:13 INFO - PROCESS | 2208 | [#11] stanford-crypto-ccm Cycles:10 Average:141.10 Median:140.50 stddev:10.57 (7.5%) stddev-sans-first:11.10 08:51:13 INFO - PROCESS | 2208 | Values: 137.0 139.0 135.0 134.0 144.0 147.0 128.0 138.0 167.0 142.0 08:51:13 INFO - PROCESS | 2208 | 08:51:13 INFO - PROCESS | 2208 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:161.30 Median:161.50 stddev:1.70 (1.1%) stddev-sans-first:1.79 08:51:13 INFO - PROCESS | 2208 | Values: 162.0 164.0 160.0 161.0 162.0 164.0 160.0 160.0 161.0 159.0 08:51:13 INFO - PROCESS | 2208 | 08:51:13 INFO - PROCESS | 2208 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:65.60 Median:65.50 stddev:1.17 (1.8%) stddev-sans-first:1.13 08:51:13 INFO - PROCESS | 2208 | Values: 67.0 65.0 68.0 65.0 66.0 64.0 66.0 65.0 65.0 65.0 08:51:13 INFO - PROCESS | 2208 | -------- Summary: end -------- 08:51:13 INFO - PROCESS | 2208 | 08:51:14 INFO - TEST-INFO | 2208: exit 0 08:51:14 INFO - TEST-OK | kraken | took 58302ms 08:51:14 INFO - SUITE-END | took 1173s 08:51:14 INFO - Completed test suite (00:19:34) 08:51:14 INFO - javascript benchmark 08:51:14 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3368.6631336866308, 3476.7566270361076, 3490.8429120689566, 3508.35095507331, 3548.2193917338177, 5797.681127537984, 5870.61840980336, 5878.147668588057, 5883.705814709268, 5936.851578710534, 5850.531957443405, 5896.970515147429, 5900.937831190384, 5906.055031194999, 5912.201852749878, 5289.86775330616, 5315.415304316522, 5322.760475778599, 5401.78392864285, 5431.21247419124, 5289.735513224343, 5298.761555730001, 5329.706866122362, 5415.593830462712, 5441.891162176754, 5401.891962160755, 5411.702356370398, 5416.647917885344, 5416.864578385552, 5419.945800541984, 5364.7317634118135, 5379.1662292344745, 5385.219143224211, 5388.110961691316, 5428.809991650312, 5204.245384419276, 5280.630355875091, 5312.893742125175, 5370.3287089113865, 5401.0, 5894.705264736789, 5932.525397968152, 5959.463648271676, 5998.7600495980105, 6010.489108425789, 5180.4042535108565, 6164.506839452878, 6253.468455181315, 6257.374262573751, 6285.308616052231, 6254.718537665816, 6286.025666021774, 6296.1500197473215, 6325.557210995232, 6331.651759153245, 5941.3761555036535, 5946.226990491295, 5952.9702351488395, 5961.7615295387695, 5999.400059993965, 6243.313235544087, 6278.466330361879, 6290.119383286344, 6294.496440284766, 6396.776112836028, 6203.627782333075, 6305.621662700252, 6311.589746666452, 6314.336994615499, 6316.9368306317265, 6358.491320694426, 6365.968170159213, 6375.171227740364, 6378.043293505918, 6380.9361906380345, 6191.380861913773, 6248.843778905563, 6277.686115694289, 6355.459785918249, 6380.55336126476, 6280.0893870389455, 6328.145700330397, 6346.428821406095, 6394.680265986682, 6397.040443933447, 5010.423801262828, 5021.748912554358, 5050.873728156826, 5075.644704870623, 5082.872928176826, 4424.845130420485, 4474.306482495218, 4549.5905368517, 4573.725576465423, 4602.769861506911, 5998.220231369981, 6045.395460453919, 6073.726682299307, 6092.055731361646, 6119.692370570856, 6297.992321228581, 6309.274433440121, 6330.841728956813, 6333.778317758948, 6344.968275158687, 6118.265808103056, 6119.5104391648565, 6120.326764055951, 6139.539534534928, 6142.109394137825, 5398.946010539844, 5501.0, 5503.449655034465, 5575.0, 5596.244506991586, 5376.677399356051, 6391.96804015977, 6491.091247225392, 6585.374389432996, 6601.438877695449, 6318.210223722035, 6327.145835312174, 6328.430441260309, 6332.9366706334185, 6368.713407896563, 6320.841978950563, 6447.903281450688, 6556.868862622626, 6575.671216439063, 6578.0, 6216.191895053614, 6241.719122639402, 6266.937330626635, 6270.404311590391, 6349.809505714836, 6305.337939516285, 6320.810375688737, 6331.0819931110655, 6332.145160403471, 6354.142190804182, 5396.406395296436, 6231.688415579112, 6254.749810007549, 6287.402696743802, 6297.244330680348, 5016.272640467185, 5950.642961422329, 6146.139540464249, 6147.415995480422, 6196.659183744938, 5070.923936140887, 6137.41694539018, 6152.753889844356, 6152.938470615237, 6154.569180157346, 6247.468965138014, 6254.781082662085, 6265.028920517327, 6274.1843560336865, 6275.686215689106, 5927.288725352985, 5984.341722410444, 6080.209572755691, 6086.0, 6148.846278843064], "value": 5823.733778962273, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [519.9792008319668, 528.1444060621792, 538.079883399387, 551.1759918921213, 553.0542771860119, 24836.87581562092, 33521.32957340855, 35129.0, 43145.56854431455, 44588.777056114755, 45803.77098114505, 46331.76834115825, 46363.53636463634, 46368.53631463684, 46372.76813615936, 9912.653057143001, 10091.091801737843, 10110.949445252763, 10122.848157277647, 10130.797384052314, 7802.8439431211345, 9524.761880952998, 9538.284628652878, 9659.130678238957, 9939.055789699967, 9478.573464194093, 12304.81542776859, 12665.936670316636, 12690.238585684841, 12883.54907578235, 12664.303463309514, 12767.680807979828, 12767.936160319232, 12772.48910043597, 12780.424880880382, 12538.122331436804, 12634.49462021518, 12640.178388404816, 12643.810342844865, 12652.367381630973, 42931.57068429307, 43306.78346608279, 43461.78269108666, 43464.91337716551, 43479.782601086794, 3371.662833716633, 3378.594568651753, 3507.1056880495566, 3507.4037413639744, 3528.7177025837873, 27952.0, 31383.05850824479, 31466.0, 33066.17334566631, 33185.0, 3185.051175931199, 3224.967750322514, 3252.5121231815415, 3266.069170286457, 3316.3864685033345, 493669.0, 494949.0, 495495.0, 497852.510737444, 499189.50405248103, 29420.0, 34009.0, 35506.11234719101, 67336.6633166831, 68017.97973030408, 37397.0, 37548.43677344842, 37591.06022349408, 37647.81176094102, 37654.43518347227, 15730.764038539432, 15892.205389730698, 15935.681286374207, 15938.28277727505, 15949.521514354588, 13275.269860157785, 13420.664483387993, 13423.060385773097, 13425.462981480827, 13429.328533573282, 7657.0, 7852.0, 8082.2725954664365, 8305.543195124204, 8390.79023024417, 2953.719396657314, 3337.8831740888954, 3368.3768502827056, 3379.864805407756, 3384.390809654286, 950.9809803804023, 983.498415807943, 1023.0843395161289, 1053.5838343854289, 1073.683263437288, 31885.0, 32037.67962320347, 32224.516632250536, 32239.194020149214, 32437.0, 922.9030951750104, 931.0456781798711, 1093.059968427152, 1167.3812879174052, 1184.3190165654783, 16366.263518141473, 16781.0, 16805.884553048247, 16847.904886182358, 16994.980301181968, 700390.0, 705272.0, 705536.9446305471, 708707.0, 710630.4468477728, 63311.68344158342, 63856.361436385974, 63910.36089639044, 63916.68041659762, 64062.0, 1419.410944458059, 1434.7202295552268, 1460.3282490054694, 1462.7440197965313, 1463.2244910197826, 30481.54277685792, 30656.08031759051, 31720.682793171774, 31751.0, 32465.35069298648, 55724.72137639286, 55849.72075139761, 55999.0, 56119.43880561142, 56371.15443268272, 584756.0, 585322.0, 587524.0, 588167.0, 588978.0, 25255.242342729747, 25910.61134083028, 27675.584866227426, 27964.44071118607, 28198.0, 39745.0, 50682.732931677005, 51340.22989655084, 51610.48389516057, 51858.0, 40967.97580060403, 41813.79093104515, 42381.36427953645, 42616.360754589325, 43703.78148109366, 35267.11832204215, 42020.0, 42407.575924240366, 43004.92487687833, 43642.90892727707, 40843.795781020905, 41359.586404135574, 41632.167356653306, 41767.0, 41806.372904407064, 34127.4880876792, 36017.0, 36367.090822729646, 37173.0, 37197.628023719415, 24609.015639374225, 25602.103926362488, 26786.732132678422, 27178.45643087167, 27714.8914043436], "value": 19756.19101817324, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [385.9652631263186, 386.6230425335298, 389.2914894891299, 398.67707157202676, 421.1009494728753, 14211.0, 20312.289069882558, 21115.894420527915, 21510.784892151092, 21542.138314467422, 20608.278710245144, 20801.479963000933, 21087.472813179676, 21190.470238244052, 21296.148154073857, 5959.1359252908305, 6218.875622487548, 6271.216097987752, 6285.874282514347, 6286.46565041972, 5910.143029260755, 6117.632942023492, 6158.907616385758, 6161.907571386433, 6225.097360882669, 6139.723712432951, 6143.416375444325, 6158.107074474198, 6158.56890017699, 6164.568480206387, 5959.65907670775, 5997.85005374867, 6133.846653833645, 6164.198654174974, 6165.0444181152, 5387.272718183034, 5389.056915039852, 5389.487998640123, 5390.056740070511, 5397.27136836526, 22084.447888802748, 24764.876175619007, 25799.484010319688, 26869.73130268692, 27659.58510622342, 2211.0, 2408.638704194367, 2491.6511688363644, 2531.190019193859, 2819.9295017624518, 10245.487725613764, 10384.878387214283, 10601.946990265076, 10647.84028239577, 10683.358998460117, 1887.8300952914303, 1945.1538580717292, 1953.1210955070133, 1979.653560626899, 1995.521074942018, 111620.0, 120092.0, 122622.0, 124290.37854810759, 124873.0, 11353.943230283796, 11634.88365116338, 11736.765264694659, 11750.471228794724, 11962.58130965412, 13187.010974176976, 13782.586522404345, 14990.47533336328, 15007.924960375129, 15025.624359391102, 7605.733799317108, 8627.95686021566, 8647.221750042527, 8667.913320866837, 8948.8657670135, 7108.680109394986, 8820.5589720515, 8835.602397892111, 8965.641374345083, 9015.008349081596, 4222.923154595558, 4310.612044915973, 4552.6130278926, 4616.330632058332, 4708.364370809965, 1593.334964961618, 1648.967020659604, 1949.541857663448, 1955.1592815089364, 1959.5982823521144, 837.5644664774284, 906.4425378392373, 912.9543522823832, 954.4607296877381, 1083.3337497439177, 20848.0, 21608.675869861963, 21632.0, 21682.0, 21695.02372393246, 944.1172503709054, 949.2358651285643, 953.4851180362665, 964.6334392930642, 1005.4520286443906, 10912.890871091346, 11221.887781122085, 11316.490757915913, 11429.142814288962, 11430.428478576037, 603582.0, 611209.0, 614411.0, 629822.0, 633106.0, 78095.0, 87387.0, 128945.35527322491, 133955.83961074278, 137963.0, 880.4761167105562, 986.8322385194534, 990.2078337330107, 1010.7473131717071, 1012.7569383347943, 16410.933289336153, 17021.82978170252, 19073.427797166107, 19091.0, 19117.71323430122, 51420.74289628528, 51686.22470662868, 52348.0, 52644.0, 53122.20316695175, 72268.63865680638, 77374.61312693625, 80637.59681201792, 81996.59001704953, 84005.0, 6900.551464154814, 7955.5226686399, 7972.242636949481, 8139.186081391814, 8245.299149572353, 12071.69820754453, 12345.814812777995, 12372.0, 12833.614991550268, 12882.613521594367, 9474.0, 11199.944000279946, 11239.831402529133, 11355.03482204022, 11462.885371146516, 7974.880376794236, 9488.193503552275, 9511.904880951379, 9670.081342272473, 9698.175655069115, 11072.557097716, 11425.97166255041, 11494.367809770209, 11494.905712079517, 11677.532898684298, 6525.64108974011, 7169.928300716926, 7518.812029699301, 7957.80105497344, 8424.073351931405, 10958.780824383308, 11419.429028548706, 11576.826347604625, 11716.472758726048, 11841.467133979164], "value": 9587.442946318595, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1380.6341319550327, 1490.9850901490986, 1545.505438259757, 1784.6163074938893, 1953.199188332784, 7991.240832120947, 8538.445001074933, 8554.486730796156, 8782.692605758799, 9085.0, 8948.06045365237, 8985.685501007467, 9004.549772511382, 9012.233960113383, 9017.0, 2006.8193862552369, 2389.0682633772844, 2418.467937053845, 2421.5762241607736, 2422.7213870404867, 2360.5396947595227, 2392.6650268962358, 2408.2895545814026, 2413.3604594782396, 2416.867072311031, 2368.6802281691926, 2393.186316652338, 2402.255300856731, 2407.4462873539096, 2407.9277621671376, 2347.6713260143592, 2362.70466191726, 2377.405648587853, 2377.702787151606, 2387.3912152401026, 2346.0615753698485, 2378.988105059481, 2381.3808409813573, 2382.190055381179, 2387.0451819272257, 8895.199432051146, 8947.463152210888, 9002.729918102403, 9035.909640903637, 9041.050689677555, 5324.627276090675, 5505.889882202334, 5506.229127922094, 5560.999020176367, 5568.136938774496, 3680.5583330000304, 3713.7002049282805, 3741.0, 3797.1076796952693, 3798.7150963677836, 1118.2954738514684, 1118.8769235384102, 1124.2916962313773, 1150.5972909481613, 1151.5624062856145, 11936.522539098425, 11959.222650527689, 12031.518739250507, 12050.638480845588, 12056.879431205663, 4509.233430316854, 4556.908861822745, 4737.644676649265, 4754.976225118921, 4762.59517940972, 9456.243500519942, 9459.432434053979, 9474.810503790024, 9493.762655933519, 9511.239100872051, 5376.892462150813, 5396.298481197419, 5402.270693456412, 5403.2165336026055, 5410.458954104558, 4275.230458517497, 4535.410396648481, 4665.183592871302, 4677.9942312403, 5059.87350316245, 3886.145048089416, 3934.5475270343704, 3982.6614737747036, 4019.698522610816, 4031.677465802729, 1174.900133488646, 1178.4284621958343, 1190.458341454636, 1196.5094311332487, 1202.5670758526924, 833.2167762303536, 915.1410180695595, 924.523870206844, 924.6024209589941, 928.6749637626776, 5703.857403564944, 5821.417858214229, 5873.236479257755, 5990.981533139377, 6110.5722599418505, 451.6138701410247, 455.34885114286226, 468.0732150342341, 471.6486217767716, 472.47319239048903, 3871.8644847430205, 3969.8213580388374, 3999.120193557471, 4012.9799351003057, 4037.6164264394833, 10487.423191724529, 10988.670339889815, 10997.945010274898, 11021.283616564897, 11022.779544409228, 10418.21863360251, 10810.0, 10970.451477426097, 11151.107911367071, 11172.162087843444, 8308.833823323499, 8354.331653467709, 8373.49759014461, 8439.0, 8557.27263182624, 6016.308124565556, 6067.666278354556, 6113.480354169946, 6199.5040396767245, 6274.968625156845, 10081.092701656886, 10113.898861011296, 10391.37651740898, 10473.162147028372, 10622.36265824053, 9895.950520247352, 10282.07461328484, 10644.520996555018, 10701.946490267499, 10710.785784284426, 7006.334398232159, 7299.0, 7342.853142937218, 7348.448866335153, 7371.447141464304, 8505.447145935494, 8545.060043395346, 8597.355198360023, 8619.569021548772, 8666.826663466569, 7591.164971853203, 7701.6534255957495, 7805.687772489036, 7891.84216315682, 7896.960515197387, 7611.3530349920875, 7613.162552119373, 7663.808404789925, 7703.653335599799, 7714.845703085794, 7080.468964827555, 7142.428605711634, 7178.5692858428665, 7180.892286615601, 7187.065681461377, 5116.667416617908, 5123.257127716534, 5157.097507936021, 5165.535101840853, 5223.399309079353, 5090.745462726923, 5098.515641014098, 5190.714510701948, 5257.973710131425, 5273.393559740679], "value": 4572.450527096132, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [258.2304731898942, 306.5539639824056, 306.98925537606175, 315.4400938334456, 319.4569232305082, 5379.515843574078, 5501.2848329717135, 5550.694711790855, 5570.554355651549, 5600.299962504687, 4664.600619814059, 5344.259018686916, 5378.757955891984, 5438.700871452069, 5574.745682221498, 4267.911682520953, 4296.5273819879785, 4305.784710764449, 4306.418633484479, 4320.207114047666, 3666.8716594919183, 3886.145048089416, 3941.3496773032416, 4088.5911408859024, 4172.853950111747, 4128.112455821998, 4165.291900376943, 4172.5201601815725, 4176.415301857743, 4188.497380314367, 3951.7628942263414, 4040.474738284004, 4166.937495937578, 4175.770332631704, 4199.664026877873, 3430.5540279763723, 3576.8748093816853, 3614.493970844084, 3635.4001589737663, 3635.563732352108, 4415.0, 5380.24676545284, 5382.91925621116, 5406.729418586629, 5427.240186373911, 4243.575642435762, 4748.865748605904, 4771.045790841811, 4789.664723469358, 4791.688540244873, 5116.897662046738, 5539.9169012464845, 5741.799037033726, 5794.319647302277, 5858.76564937402, 3776.0, 3973.0, 3974.5627980922077, 3988.720789544733, 4010.979945100285, 6014.41380206511, 6162.7534898604, 6177.691115444255, 6177.96911015442, 6193.801200573776, 3528.4883691864534, 4041.8787436376956, 4043.0189207288217, 4045.7572545646767, 4063.715539912178, 3316.9834150829092, 3953.1303113315025, 4051.513818341818, 4095.6313931746286, 4127.731697439657, 4242.85150019754, 4391.516933137353, 4779.060877560345, 4793.448753393334, 4810.5670489656095, 4996.625253106032, 5814.924239015796, 5919.253820123074, 5932.495737862244, 5949.553783466171, 1281.5386460874079, 1302.5766625846638, 1340.4035204333977, 1369.7465968795805, 1389.4303335632544, 766.4481573267238, 773.7079077939854, 795.9005124359455, 809.0938149272852, 814.9470284431493, 3957.762534247954, 4116.6912481564, 4154.771487568152, 4622.375979242826, 4635.953640463552, 4058.0057885818164, 4606.378138951266, 4650.6046986005895, 4654.511276316006, 4849.733264670477, 3853.3064048470983, 4590.255702832902, 4605.4933957264675, 4615.576822168388, 4622.722636641813, 2629.0272599138057, 2676.6081637548364, 2810.901618443345, 2848.5015122353507, 3232.3858466891215, 5827.737751801179, 6339.683015849189, 6359.3640635936035, 6372.240378176898, 6374.203224596926, 5078.4413714491375, 5100.327764009907, 5758.827235182951, 6156.569040167145, 6281.7173227204885, 5101.387833460009, 6272.655003974825, 6317.936820631827, 6320.968395157995, 6334.683265836689, 4875.68308059975, 5173.689578625294, 5240.68555886648, 6116.724747386289, 6143.600665956698, 4988.72562009093, 5132.5124113209185, 5686.10008895458, 5991.921454138297, 6104.1615648723555, 5529.391766905717, 5722.512146841768, 5994.520438365008, 6429.517786165963, 6446.258680251643, 3948.3090459170103, 4072.5927407259037, 4102.835886564504, 4104.8768536943935, 4138.916507202153, 5823.699638642797, 6070.908936366046, 6095.933211687849, 6175.69121543912, 6189.473894718999, 5123.923141152811, 5848.766049358148, 5852.561057920588, 5858.882822343615, 5878.617889837147, 4910.8281210157475, 5679.716014199356, 5829.621074630305, 5996.980513312835, 6007.459328660441, 5726.676359348665, 5806.577388539835, 5871.823845284648, 5877.94122058774, 5892.587518873637, 5146.485351464824, 5975.970120149545, 5976.521878249642, 5979.551533634908, 5989.371116032754, 5139.535232458731, 5546.667199968015, 5685.630434021776, 5859.707014649336, 5873.88540125563], "value": 4162.2679394953, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [719.6689522819503, 743.5315751076818, 757.9469437139401, 783.854580092078, 858.6007506509471, 15498.0, 16811.663766724672, 16847.15764211789, 17557.12214389282, 18775.0, 16999.23503442345, 17382.47852564422, 17412.912935435306, 17436.476905692816, 17441.65116697665, 637.5027478566725, 870.403773415211, 896.9910300896972, 897.0401670212875, 898.0884402331639, 728.100795517538, 734.7281505842845, 734.9669264883094, 736.1938677148514, 736.7936977646268, 935.2658163341778, 942.726609283307, 950.4629884115452, 967.1827305926516, 972.6401231544303, 957.1003256938455, 957.5403806173041, 958.9616415343447, 959.0217977662815, 959.5010594490826, 707.5719189890094, 708.0299989015082, 708.9078419805444, 710.1478226128667, 710.7370272999024, 15895.523134305873, 15901.20493975308, 15933.920330398274, 15952.680946381124, 15965.441209557726, 10835.566577336896, 13748.725025499534, 14046.789298160536, 14115.717685646332, 14412.855871441256, 12198.939005304917, 12217.266963982102, 12305.0, 12368.13423060386, 12506.374681265901, 4501.324801279801, 4643.466001409847, 4698.765061746899, 4787.257975013878, 4915.0661374339115, 127975.08037379447, 129264.0, 130067.0, 130661.34669326688, 132924.0, 10931.453427328603, 11278.210525263154, 11295.943520282346, 11473.885261147281, 11503.942480287546, 21697.891510542348, 22797.430064248525, 22813.771862281497, 22827.771722282898, 22832.543349133255, 8091.716789912325, 8260.463069900557, 8488.660453581788, 8501.702440414556, 8586.055533891267, 4380.685794261709, 4602.171609110393, 4647.697899636581, 4680.087382960358, 4703.988642441891, 3663.0659181908977, 3823.751456155341, 3828.1386687995537, 3829.329867273216, 3837.597052309523, 684.4968947823346, 692.7229108356597, 703.6235613946515, 711.9181294151135, 722.37514549915, 549.4121290219426, 627.9497640188774, 680.680080362229, 712.6116266634697, 736.2416710787898, 8886.0, 9130.726078217664, 9581.568829402693, 10182.541785619665, 10322.896771032343, 603.4297588778562, 685.7074712301031, 709.8757717399434, 714.0931017607609, 715.6135686729108, 4480.686351955397, 4893.388326459192, 4918.262260660858, 4922.0, 4935.87660308488, 106365.46817265864, 155834.0, 157404.42595574126, 159531.0, 168107.0, 149607.0, 151839.0, 152858.0, 153609.2319538395, 153926.0, 113693.0, 114762.0, 115371.0, 116039.0, 121266.78733212908, 9281.72154835356, 9364.906350936404, 9426.95286523563, 9439.52802359893, 9479.810403792024, 58040.4195958035, 61882.69058654678, 68661.65669171623, 68972.3102768986, 69393.0, 78271.21728782867, 82007.0, 82904.17095828964, 88549.5572522159, 90706.0, 13415.664608384868, 13901.860981390057, 13916.930415347859, 13940.30298485092, 14331.78502322487, 14305.64235894111, 14639.92680036593, 15079.321430535872, 15314.0, 15330.770038449655, 14325.426982920566, 14955.177465239516, 15173.620659483602, 15353.846461535242, 15870.285837137126, 14258.144511329354, 14288.499902503363, 15276.541703748906, 15479.690406191588, 15836.445724400051, 12020.819687704516, 13419.932900335436, 14241.35913883857, 14341.64145896361, 15144.772828407362, 9902.801943960936, 11005.669829905115, 12062.215955962834, 12085.741024079342, 12642.431090601129, 8179.754607361789, 9707.514624268899, 10207.387556746618, 11206.943965280447, 11544.538218471167], "value": 8222.650469109996, "unit": "score"}], "name": "dromaeo_css", "value": 7462.033534558034, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 113.0, 112.0, 112.0, 113.0, 116.0, 112.0, 115.0, 113.0, 111.0], "value": 113.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 110.0, 109.0, 140.0, 110.0, 108.0, 109.0, 108.0, 140.0, 107.0], "value": 118.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [184.0, 185.0, 186.0, 183.0, 183.0, 185.0, 184.0, 184.0, 199.0, 260.0], "value": 193.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 57.0, 60.0, 105.0, 85.0, 81.0, 59.0, 59.0, 82.0, 58.0], "value": 72.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 72.0, 93.0, 69.0, 67.0, 68.0, 91.0, 67.0, 68.0, 90.0], "value": 77.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 130.0, 102.0, 87.0, 91.0, 86.0, 90.0, 87.0, 90.0, 85.0], "value": 96.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 94.0, 94.0, 96.0, 94.0, 94.0, 100.0, 140.0, 149.0, 150.0], "value": 110.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 86.0, 84.0, 83.0, 85.0, 86.0, 100.0, 134.0, 142.0, 84.0], "value": 96.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 54.0, 53.0, 50.0, 55.0, 56.0, 57.0, 56.0, 57.0, 56.0], "value": 55.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 70.0, 67.0, 72.0, 71.0, 69.0, 68.0, 69.0, 69.0, 70.0], "value": 69.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 96.0, 99.0, 81.0, 83.0, 81.0, 82.0, 85.0, 84.0, 90.0], "value": 86.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [137.0, 139.0, 135.0, 134.0, 144.0, 147.0, 128.0, 138.0, 167.0, 142.0], "value": 141.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 164.0, 160.0, 161.0, 162.0, 164.0, 160.0, 160.0, 161.0, 159.0], "value": 161.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 65.0, 68.0, 65.0, 66.0, 64.0, 66.0, 65.0, 65.0, 65.0], "value": 65.6, "unit": "score"}], "name": "kraken", "value": 1457.8, "alertThreshold": 2.0}]} 08:51:14 INFO - Return code: 0 08:51:14 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 08:51:14 INFO - # TBPL SUCCESS # 08:51:14 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 08:51:14 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 08:51:14 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 08:51:14 INFO - 2016-12-14 08:51:14.723 screenresolution[2212:27760] starting screenresolution argv=screenresolution get 08:51:14 INFO - 2016-12-14 08:51:14.726 screenresolution[2212:27760] Display 0: 1600x1200x32@60 08:51:14 INFO - 2016-12-14 08:51:14.732 screenresolution[2213:27763] starting screenresolution argv=screenresolution list 08:51:14 INFO - Available Modes on Display 0 08:51:14 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 08:51:14 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 08:51:14 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 08:51:14 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 08:51:14 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 08:51:14 INFO - Intel Iris: 08:51:14 INFO - Chipset Model: Intel Iris 08:51:14 INFO - Type: GPU 08:51:14 INFO - Bus: Built-In 08:51:14 INFO - VRAM (Dynamic, Max): 1536 MB 08:51:14 INFO - Vendor: Intel (0x8086) 08:51:14 INFO - Device ID: 0x0a2e 08:51:14 INFO - Revision ID: 0x0009 08:51:14 INFO - Displays: 08:51:14 INFO - Display: 08:51:14 INFO - Resolution: 1600 x 1200 @ 60 Hz 08:51:14 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 08:51:14 INFO - Display Serial Number: 1600x1200 60 08:51:14 INFO - Main Display: Yes 08:51:14 INFO - Mirror: Off 08:51:14 INFO - Online: Yes 08:51:14 INFO - Rotation: Supported 08:51:14 INFO - Return code: 0 08:51:14 INFO - Running post-action listener: _resource_record_post_action 08:51:14 INFO - [mozharness: 2016-12-14 16:51:14.966728Z] Finished run-tests step (success) 08:51:14 INFO - Running post-run listener: _resource_record_post_run 08:51:15 INFO - Total resource usage - Wall time: 1197s; CPU: 53.0%; Read bytes: 1454080; Write bytes: 494925824; Read time: 10; Write time: 1654 08:51:15 INFO - TinderboxPrint: CPU usage
53.2% 08:51:15 INFO - TinderboxPrint: I/O read bytes / time
1,454,080 / 10 08:51:15 INFO - TinderboxPrint: I/O write bytes / time
494,925,824 / 1,654 08:51:15 INFO - TinderboxPrint: CPU idle
2,241.6 (46.8%) 08:51:15 INFO - TinderboxPrint: CPU system
957.5 (20.0%) 08:51:15 INFO - TinderboxPrint: CPU user
1,590.5 (33.2%) 08:51:15 INFO - TinderboxPrint: Swap in / out
196,214,784 / 0 08:51:15 INFO - install - Wall time: 22s; CPU: 54.0%; Read bytes: 194290176; Write bytes: 187577856; Read time: 17928; Write time: 397 08:51:15 INFO - run-tests - Wall time: 1175s; CPU: 53.0%; Read bytes: 569344; Write bytes: 296063488; Read time: 5; Write time: 1221 08:51:15 INFO - Running post-run listener: _upload_blobber_files 08:51:15 INFO - Blob upload gear active. 08:51:15 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 08:51:15 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 08:51:15 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-Beta', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 08:51:15 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-Beta -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 08:51:15 INFO - (blobuploader) - INFO - Open directory for files ... 08:51:15 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 08:51:15 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:51:15 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:51:16 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 08:51:16 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:51:16 INFO - (blobuploader) - INFO - Done attempting. 08:51:16 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 08:51:16 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:51:16 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:51:17 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 08:51:17 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:51:17 INFO - (blobuploader) - INFO - Done attempting. 08:51:17 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 08:51:17 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:51:17 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:51:20 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 08:51:20 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:51:20 INFO - (blobuploader) - INFO - Done attempting. 08:51:20 INFO - (blobuploader) - INFO - Iteration through files over. 08:51:20 INFO - Return code: 0 08:51:20 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 08:51:20 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 08:51:20 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/2e892d9c74492cdc280d7cbd0794893808a6786619c17246870f467ee59cef16418595f42c30c668174206844db7fd0d847548a07276f98948e2b30a320cdafd", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/d1d39a8db7bab048dfa66f0aab1869f0e0ff309fdde661d58f578c2307e2fa7a84483531e3e0aef6667a16b43667b8a7d1b5195318a2a593dcd8cf73e4477e44", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/baf67d81ecb1262597e9728bc04d51629f6a47a418be56319059437a99295725fe86b5375ab7f848ec29ea1e5cd636bb4ebe890ad7eaf493578ce2bd0a3cfeea"} 08:51:20 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 08:51:20 INFO - Writing to file /builds/slave/test/properties/blobber_files 08:51:20 INFO - Contents: 08:51:20 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/2e892d9c74492cdc280d7cbd0794893808a6786619c17246870f467ee59cef16418595f42c30c668174206844db7fd0d847548a07276f98948e2b30a320cdafd", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/d1d39a8db7bab048dfa66f0aab1869f0e0ff309fdde661d58f578c2307e2fa7a84483531e3e0aef6667a16b43667b8a7d1b5195318a2a593dcd8cf73e4477e44", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/baf67d81ecb1262597e9728bc04d51629f6a47a418be56319059437a99295725fe86b5375ab7f848ec29ea1e5cd636bb4ebe890ad7eaf493578ce2bd0a3cfeea"} 08:51:20 INFO - Running post-run listener: copy_logs_to_upload_dir 08:51:20 INFO - Copying logs to upload dir... 08:51:20 INFO - mkdir: /builds/slave/test/build/upload/logs 08:51:20 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1256.400913 ========= master_lag: 0.36 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 20 mins, 56 secs) (at 2016-12-14 08:51:20.549073) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-14 08:51:20.556271) ========= 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.8T3nWQnoSG/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.jSl6PfDAnn/Listeners TMPDIR=/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/2e892d9c74492cdc280d7cbd0794893808a6786619c17246870f467ee59cef16418595f42c30c668174206844db7fd0d847548a07276f98948e2b30a320cdafd", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/d1d39a8db7bab048dfa66f0aab1869f0e0ff309fdde661d58f578c2307e2fa7a84483531e3e0aef6667a16b43667b8a7d1b5195318a2a593dcd8cf73e4477e44", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/baf67d81ecb1262597e9728bc04d51629f6a47a418be56319059437a99295725fe86b5375ab7f848ec29ea1e5cd636bb4ebe890ad7eaf493578ce2bd0a3cfeea"} build_url:https://queue.taskcluster.net/v1/task/byLSYhfuTRWjcPSYscrdcg/artifacts/public/build/firefox-51.0.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011312 build_url: 'https://queue.taskcluster.net/v1/task/byLSYhfuTRWjcPSYscrdcg/artifacts/public/build/firefox-51.0.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/2e892d9c74492cdc280d7cbd0794893808a6786619c17246870f467ee59cef16418595f42c30c668174206844db7fd0d847548a07276f98948e2b30a320cdafd", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/d1d39a8db7bab048dfa66f0aab1869f0e0ff309fdde661d58f578c2307e2fa7a84483531e3e0aef6667a16b43667b8a7d1b5195318a2a593dcd8cf73e4477e44", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/baf67d81ecb1262597e9728bc04d51629f6a47a418be56319059437a99295725fe86b5375ab7f848ec29ea1e5cd636bb4ebe890ad7eaf493578ce2bd0a3cfeea"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-14 08:51:20.592991) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-14 08:51:20.593293) ========= 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.8T3nWQnoSG/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.jSl6PfDAnn/Listeners TMPDIR=/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/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.005366 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-14 08:51:20.641464) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-14 08:51:20.641760) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-14 08:51:20.645069) ========= ========= Total master_lag: 4.00 =========