builder: mozilla-beta_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0266 starttime: 1483590613.88 results: success (0) buildid: 20170104184438 builduid: e67fff94699f4e0aba2ee88483ca1868 revision: 9d0309c49d23e002410e1b0afd0aa33fc8e1c745 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-01-04 20:30:13.878177) ========= master: http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-01-04 20:30:13.878659) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-04 20:30:13.878940) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-04 20:30:13.904219) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-04 20:30:13.904587) ========= 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.qq18IEDFVL/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.DOWzfiOESs/Listeners TMPDIR=/var/folders/nt/w1wt5wh94cg0328j1nmkplv400000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2017-01-04 20:30:13-- 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% 7.96M=0.001s 2017-01-04 20:30:14 (7.96 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.126998 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-04 20:30:14.055171) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-04 20:30:14.055485) ========= 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.qq18IEDFVL/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.DOWzfiOESs/Listeners TMPDIR=/var/folders/nt/w1wt5wh94cg0328j1nmkplv400000w/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.021631 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-04 20:30:14.103733) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-01-04 20:30:14.104239) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 9d0309c49d23e002410e1b0afd0aa33fc8e1c745 --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 9d0309c49d23e002410e1b0afd0aa33fc8e1c745 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.qq18IEDFVL/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.DOWzfiOESs/Listeners TMPDIR=/var/folders/nt/w1wt5wh94cg0328j1nmkplv400000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2017-01-04 20:30:14,186 truncating revision to first 12 chars 2017-01-04 20:30:14,187 Setting DEBUG logging. 2017-01-04 20:30:14,187 attempt 1/10 2017-01-04 20:30:14,187 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/9d0309c49d23?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-01-04 20:30:15,635 unpacking tar archive at: mozilla-beta-9d0309c49d23/testing/mozharness/ program finished with exit code 0 elapsedTime=1.733126 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-01-04 20:30:15.861247) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-04 20:30:15.861566) ========= script_repo_revision: 9d0309c49d23e002410e1b0afd0aa33fc8e1c745 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-04 20:30:15.861983) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-04 20:30:15.862267) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-04 20:30:15.881940) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 20 mins, 55 secs) (at 2017-01-04 20:30:15.882287) ========= /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.qq18IEDFVL/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.DOWzfiOESs/Listeners TMPDIR=/var/folders/nt/w1wt5wh94cg0328j1nmkplv400000w/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 20:30:16 INFO - MultiFileLogger online at 20170104 20:30:16 in /builds/slave/test 20:30:16 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 20:30:16 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 20:30:16 INFO - {'append_to_log': False, 20:30:16 INFO - 'base_work_dir': '/builds/slave/test', 20:30:16 INFO - 'blob_upload_branch': 'Mozilla-Beta', 20:30:16 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 20:30:16 INFO - 'branch': 'Mozilla-Beta', 20:30:16 INFO - 'buildbot_json_path': 'buildprops.json', 20:30:16 INFO - 'config_files': ('talos/mac_config.py',), 20:30:16 INFO - 'default_actions': ('clobber', 20:30:16 INFO - 'read-buildbot-config', 20:30:16 INFO - 'download-and-extract', 20:30:16 INFO - 'populate-webroot', 20:30:16 INFO - 'create-virtualenv', 20:30:16 INFO - 'install', 20:30:16 INFO - 'run-tests'), 20:30:16 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 20:30:16 INFO - 'download_minidump_stackwalk': True, 20:30:16 INFO - 'download_symbols': 'ondemand', 20:30:16 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 20:30:16 INFO - 'tooltool.py': '/tools/tooltool.py', 20:30:16 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 20:30:16 INFO - '/tools/misc-python/virtualenv.py')}, 20:30:16 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 20:30:16 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 20:30:16 INFO - 'installer_path': 'installer.exe', 20:30:16 INFO - 'log_level': 'info', 20:30:16 INFO - 'log_name': 'talos', 20:30:16 INFO - 'log_to_console': True, 20:30:16 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 20:30:16 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 20:30:16 INFO - 'opt_config_files': (), 20:30:16 INFO - 'pip_index': False, 20:30:16 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 20:30:16 INFO - 'cmd': ('bash', 20:30:16 INFO - '-c', 20:30:16 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 20:30:16 INFO - 'enabled': True, 20:30:16 INFO - 'halt_on_failure': False, 20:30:16 INFO - 'name': 'check_screen_resolution'},), 20:30:16 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 20:30:16 INFO - 'cmd': ('bash', 20:30:16 INFO - '-c', 20:30:16 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 20:30:16 INFO - 'enabled': True, 20:30:16 INFO - 'halt_on_failure': False, 20:30:16 INFO - 'name': 'check_screen_resolution'},), 20:30:16 INFO - 'run_cmd_checks_enabled': True, 20:30:16 INFO - 'sps_profile': False, 20:30:16 INFO - 'sps_profile_interval': 0, 20:30:16 INFO - 'suite': 'dromaeojs', 20:30:16 INFO - 'system_bits': '32', 20:30:16 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 20:30:16 INFO - 'title': 't-yosemite-r7-0266', 20:30:16 INFO - 'tooltool_cache': '/builds/tooltool_cache', 20:30:16 INFO - 'use_talos_json': True, 20:30:16 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 20:30:16 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 20:30:16 INFO - 'work_dir': 'build'} 20:30:16 INFO - [mozharness: 2017-01-05 04:30:16.076910Z] Running clobber step. 20:30:16 INFO - Running pre-action listener: _resource_record_pre_action 20:30:16 INFO - Running main action method: clobber 20:30:16 INFO - rmtree: /builds/slave/test/build 20:30:16 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 20:30:17 INFO - Running post-action listener: _resource_record_post_action 20:30:17 INFO - [mozharness: 2017-01-05 04:30:17.674028Z] Finished clobber step (success) 20:30:17 INFO - [mozharness: 2017-01-05 04:30:17.674301Z] Running read-buildbot-config step. 20:30:17 INFO - Running pre-action listener: _resource_record_pre_action 20:30:17 INFO - Running main action method: read_buildbot_config 20:30:17 INFO - Using buildbot properties: 20:30:17 INFO - { 20:30:17 INFO - "project": "", 20:30:17 INFO - "product": "firefox", 20:30:17 INFO - "script_repo_revision": "production", 20:30:17 INFO - "builddir": "mozilla-beta_yosemite_r7_test-dromaeojs", 20:30:17 INFO - "repository": "", 20:30:17 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-beta talos dromaeojs", 20:30:17 INFO - "buildid": "20170104184438", 20:30:17 INFO - "pgo_build": "False", 20:30:17 INFO - "basedir": "/builds/slave/test", 20:30:17 INFO - "buildnumber": 5, 20:30:17 INFO - "slavename": "t-yosemite-r7-0266", 20:30:17 INFO - "master": "http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/", 20:30:17 INFO - "platform": "yosemite_r7", 20:30:17 INFO - "scheduler": "tests-mozilla-beta-macosx64-talos", 20:30:17 INFO - "branch": "mozilla-beta", 20:30:17 INFO - "repo_path": "releases/mozilla-beta", 20:30:17 INFO - "revision": "9d0309c49d23e002410e1b0afd0aa33fc8e1c745", 20:30:17 INFO - "stage_platform": "macosx64", 20:30:17 INFO - "builduid": "e67fff94699f4e0aba2ee88483ca1868", 20:30:17 INFO - "slavebuilddir": "test" 20:30:17 INFO - } 20:30:17 INFO - Found installer url https://queue.taskcluster.net/v1/task/PVYjI-z6S5qoQRXyCRoALw/artifacts/public/build/firefox-51.0.en-US.mac.dmg. 20:30:17 INFO - Running post-action listener: _resource_record_post_action 20:30:17 INFO - [mozharness: 2017-01-05 04:30:17.679926Z] Finished read-buildbot-config step (success) 20:30:17 INFO - [mozharness: 2017-01-05 04:30:17.680117Z] Running download-and-extract step. 20:30:17 INFO - Running pre-action listener: _resource_record_pre_action 20:30:17 INFO - Running main action method: download_and_extract 20:30:17 INFO - mkdir: /builds/slave/test/build/tests 20:30: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')]} 20:30:17 INFO - trying https://queue.taskcluster.net/v1/task/PVYjI-z6S5qoQRXyCRoALw/artifacts/public/build/firefox-51.0.en-US.mac.test_packages.json 20:30:17 INFO - Downloading https://queue.taskcluster.net/v1/task/PVYjI-z6S5qoQRXyCRoALw/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 20:30:17 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/PVYjI-z6S5qoQRXyCRoALw/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 20:30:19 INFO - Downloaded 1165 bytes. 20:30:19 INFO - Reading from file /builds/slave/test/build/firefox-51.0.en-US.mac.test_packages.json 20:30:19 INFO - Using the following test package requirements: 20:30:19 INFO - {u'common': [u'firefox-51.0.en-US.mac.common.tests.zip'], 20:30:19 INFO - u'cppunittest': [u'firefox-51.0.en-US.mac.common.tests.zip', 20:30:19 INFO - u'firefox-51.0.en-US.mac.cppunittest.tests.zip'], 20:30:19 INFO - u'gtest': [u'firefox-51.0.en-US.mac.common.tests.zip', 20:30:19 INFO - u'firefox-51.0.en-US.mac.gtest.tests.zip'], 20:30:19 INFO - u'jittest': [u'firefox-51.0.en-US.mac.common.tests.zip', u'jsshell-mac.zip'], 20:30:19 INFO - u'mochitest': [u'firefox-51.0.en-US.mac.common.tests.zip', 20:30:19 INFO - u'firefox-51.0.en-US.mac.mochitest.tests.zip'], 20:30:19 INFO - u'mozbase': [u'firefox-51.0.en-US.mac.common.tests.zip'], 20:30:19 INFO - u'reftest': [u'firefox-51.0.en-US.mac.common.tests.zip', 20:30:19 INFO - u'firefox-51.0.en-US.mac.reftest.tests.zip'], 20:30:19 INFO - u'talos': [u'firefox-51.0.en-US.mac.common.tests.zip', 20:30:19 INFO - u'firefox-51.0.en-US.mac.talos.tests.zip'], 20:30:19 INFO - u'web-platform': [u'firefox-51.0.en-US.mac.common.tests.zip', 20:30:19 INFO - u'firefox-51.0.en-US.mac.web-platform.tests.zip'], 20:30:19 INFO - u'xpcshell': [u'firefox-51.0.en-US.mac.common.tests.zip', 20:30:19 INFO - u'firefox-51.0.en-US.mac.xpcshell.tests.zip']} 20:30:19 INFO - Downloading packages: [u'firefox-51.0.en-US.mac.common.tests.zip'] for test suite category: common 20:30:19 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/PVYjI-z6S5qoQRXyCRoALw/artifacts/public/build/firefox-51.0.en-US.mac.common.tests.zip 20:30:19 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/PVYjI-z6S5qoQRXyCRoALw/artifacts/public/build/firefox-51.0.en-US.mac.common.tests.zip'}, attempt #1 20:30:19 INFO - Fetch https://queue.taskcluster.net/v1/task/PVYjI-z6S5qoQRXyCRoALw/artifacts/public/build/firefox-51.0.en-US.mac.common.tests.zip into memory 20:30:19 INFO - Expected file size: 16234511 20:30:26 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 20:30:26 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/PVYjI-z6S5qoQRXyCRoALw/artifacts/public/build/firefox-51.0.en-US.mac.common.tests.zip 20:30:26 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/PVYjI-z6S5qoQRXyCRoALw/artifacts/public/build/firefox-51.0.en-US.mac.common.tests.zip'}, attempt #1 20:30:26 INFO - Fetch https://queue.taskcluster.net/v1/task/PVYjI-z6S5qoQRXyCRoALw/artifacts/public/build/firefox-51.0.en-US.mac.common.tests.zip into memory 20:30:27 INFO - Expected file size: 16234511 20:30:33 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/PVYjI-z6S5qoQRXyCRoALw/artifacts/public/build/firefox-51.0.en-US.mac.talos.tests.zip 20:30:33 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/PVYjI-z6S5qoQRXyCRoALw/artifacts/public/build/firefox-51.0.en-US.mac.talos.tests.zip'}, attempt #1 20:30:33 INFO - Fetch https://queue.taskcluster.net/v1/task/PVYjI-z6S5qoQRXyCRoALw/artifacts/public/build/firefox-51.0.en-US.mac.talos.tests.zip into memory 20:30:34 INFO - Expected file size: 14414089 20:30:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:30:34 INFO - trying https://queue.taskcluster.net/v1/task/PVYjI-z6S5qoQRXyCRoALw/artifacts/public/build/firefox-51.0.en-US.mac.dmg 20:30:34 INFO - Downloading https://queue.taskcluster.net/v1/task/PVYjI-z6S5qoQRXyCRoALw/artifacts/public/build/firefox-51.0.en-US.mac.dmg to /builds/slave/test/build/firefox-51.0.en-US.mac.dmg 20:30:34 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/PVYjI-z6S5qoQRXyCRoALw/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 20:30:37 INFO - Downloaded 86100990 bytes. 20:30:37 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/PVYjI-z6S5qoQRXyCRoALw/artifacts/public/build/firefox-51.0.en-US.mac.dmg 20:30:37 INFO - mkdir: /builds/slave/test/properties 20:30:37 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 20:30:37 INFO - Writing to file /builds/slave/test/properties/build_url 20:30:37 INFO - Contents: 20:30:37 INFO - build_url:https://queue.taskcluster.net/v1/task/PVYjI-z6S5qoQRXyCRoALw/artifacts/public/build/firefox-51.0.en-US.mac.dmg 20:30:37 INFO - Running post-action listener: _resource_record_post_action 20:30:37 INFO - Running post-action listener: set_extra_try_arguments 20:30:37 INFO - [mozharness: 2017-01-05 04:30:37.683818Z] Finished download-and-extract step (success) 20:30:37 INFO - [mozharness: 2017-01-05 04:30:37.683935Z] Running populate-webroot step. 20:30:37 INFO - Running pre-action listener: _resource_record_pre_action 20:30:37 INFO - Running main action method: populate_webroot 20:30:37 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 20:30:37 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 20:30:37 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 20:30:37 INFO - '--tppagecycles', 20:30:37 INFO - '7'], 20:30:37 INFO - 'tests': ['tsvgm']}}, 20:30:37 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 20:30:37 INFO - 'tests': ['tresize', 'tcanvasmark']}, 20:30:37 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 20:30:37 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 20:30:37 INFO - 'tests': ['dromaeo_css', 'kraken']}, 20:30:37 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 20:30:37 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:30:37 INFO - 'talos_options': ['--disable-e10s'], 20:30:37 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 20:30:37 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:30:37 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 20:30:37 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:30:37 INFO - 'talos_options': ['--disable-e10s'], 20:30:37 INFO - 'tests': ['damp', 'tps']}, 20:30:37 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:30:37 INFO - 'tests': ['damp', 'tps']}, 20:30:37 INFO - 'g3': {'talos_options': ['--disable-e10s'], 20:30:37 INFO - 'tests': ['dromaeo_dom']}, 20:30:37 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 20:30:37 INFO - 'g4': {'talos_options': ['--disable-e10s'], 20:30:37 INFO - 'tests': ['basic_compositor_video']}, 20:30:37 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 20:30:37 INFO - 'other': {'talos_options': ['--disable-e10s'], 20:30:37 INFO - 'tests': ['a11yr', 20:30:37 INFO - 'ts_paint', 20:30:37 INFO - 'tpaint', 20:30:37 INFO - 'sessionrestore', 20:30:37 INFO - 'sessionrestore_no_auto_restore', 20:30:37 INFO - 'tabpaint']}, 20:30:37 INFO - 'other-e10s': {'tests': ['a11yr', 20:30:37 INFO - 'ts_paint', 20:30:37 INFO - 'tpaint', 20:30:37 INFO - 'sessionrestore', 20:30:37 INFO - 'sessionrestore_no_auto_restore', 20:30:37 INFO - 'tabpaint']}, 20:30:37 INFO - 'other-e10s_l64': {'tests': ['a11yr', 20:30:37 INFO - 'ts_paint', 20:30:37 INFO - 'tpaint', 20:30:37 INFO - 'sessionrestore', 20:30:37 INFO - 'sessionrestore_no_auto_restore', 20:30:37 INFO - 'tabpaint']}, 20:30:37 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 20:30:37 INFO - 'ts_paint', 20:30:37 INFO - 'tpaint', 20:30:37 INFO - 'sessionrestore', 20:30:37 INFO - 'sessionrestore_no_auto_restore', 20:30:37 INFO - 'tabpaint']}, 20:30:37 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 20:30:37 INFO - 'tests': ['a11yr', 20:30:37 INFO - 'ts_paint', 20:30:37 INFO - 'tpaint', 20:30:37 INFO - 'sessionrestore', 20:30:37 INFO - 'sessionrestore_no_auto_restore', 20:30:37 INFO - 'tabpaint']}, 20:30:37 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 20:30:37 INFO - 'tests': ['a11yr', 20:30:37 INFO - 'ts_paint', 20:30:37 INFO - 'tpaint', 20:30:37 INFO - 'sessionrestore', 20:30:37 INFO - 'sessionrestore_no_auto_restore', 20:30:37 INFO - 'tabpaint']}, 20:30:37 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 20:30:37 INFO - 'tests': ['tsvgx', 20:30:37 INFO - 'tsvgr_opacity', 20:30:37 INFO - 'tart', 20:30:37 INFO - 'tscrollx', 20:30:37 INFO - 'cart']}, 20:30:37 INFO - 'svgr-e10s': {'tests': ['tsvgx', 20:30:37 INFO - 'tsvgr_opacity', 20:30:37 INFO - 'tart', 20:30:37 INFO - 'tscrollx', 20:30:37 INFO - 'cart']}, 20:30:37 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:30:37 INFO - 'talos_options': ['--disable-e10s'], 20:30:37 INFO - 'tests': ['tp5o']}, 20:30:37 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:30:37 INFO - 'tests': ['tp5o']}, 20:30:37 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:30:37 INFO - 'talos_options': ['--disable-e10s', 20:30:37 INFO - '--xperf_path', 20:30:37 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 20:30:37 INFO - 'tests': ['tp5n']}, 20:30:37 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:30:37 INFO - 'talos_options': ['--xperf_path', 20:30:37 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 20:30:37 INFO - 'tests': ['tp5n']}}, 20:30:37 INFO - 'talos.zip': {'path': '', 20:30:37 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 20:30:37 INFO - Running post-action listener: _resource_record_post_action 20:30:37 INFO - [mozharness: 2017-01-05 04:30:37.702605Z] Finished populate-webroot step (success) 20:30:37 INFO - [mozharness: 2017-01-05 04:30:37.702722Z] Running create-virtualenv step. 20:30:37 INFO - Running pre-action listener: _resource_record_pre_action 20:30:37 INFO - Running main action method: create_virtualenv 20:30:37 INFO - Creating virtualenv /builds/slave/test/build/venv 20:30:37 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 20:30:37 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 20:30:37 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 20:30:37 INFO - Using real prefix '/tools/python27' 20:30:37 INFO - New python executable in /builds/slave/test/build/venv/bin/python 20:30:38 INFO - Installing distribute.............................................................................................................................................................................................done. 20:30:41 INFO - Installing pip.................done. 20:30:41 INFO - Return code: 0 20:30:41 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 20:30:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:30:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:30:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:30:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:30:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:30:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:30:41 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x11026a490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1101e0570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f80a1cbea30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11007ed30>, '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 0x10fee4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x110076b20>, '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.qq18IEDFVL/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.DOWzfiOESs/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/nt/w1wt5wh94cg0328j1nmkplv400000w/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 20:30:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 20:30:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 20:30:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qq18IEDFVL/Render', 20:30:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:30:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:30:41 INFO - 'HOME': '/Users/cltbld', 20:30:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:30:41 INFO - 'LOGNAME': 'cltbld', 20:30:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:30:41 INFO - 'MOZ_NO_REMOTE': '1', 20:30:41 INFO - 'NO_EM_RESTART': '1', 20:30:41 INFO - 'PAGER': '/bin/cat', 20:30:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:30:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:30:41 INFO - 'PWD': '/builds/slave/test', 20:30:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:30:41 INFO - 'SHELL': '/bin/bash', 20:30:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.DOWzfiOESs/Listeners', 20:30:41 INFO - 'TMPDIR': '/var/folders/nt/w1wt5wh94cg0328j1nmkplv400000w/T/', 20:30:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:30:41 INFO - 'USER': 'cltbld', 20:30:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:30:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:30:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:30:41 INFO - 'XPC_FLAGS': '0x0', 20:30:41 INFO - 'XPC_SERVICE_NAME': '0', 20:30:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:30:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:30:41 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 20:30:41 INFO - Cleaning up... 20:30:41 INFO - Return code: 0 20:30:41 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 20:30:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:30:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:30:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:30:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:30:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:30:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:30:41 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x11026a490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1101e0570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f80a1cbea30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11007ed30>, '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 0x10fee4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x110076b20>, '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.qq18IEDFVL/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.DOWzfiOESs/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/nt/w1wt5wh94cg0328j1nmkplv400000w/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 20:30:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 20:30:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 20:30:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qq18IEDFVL/Render', 20:30:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:30:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:30:41 INFO - 'HOME': '/Users/cltbld', 20:30:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:30:41 INFO - 'LOGNAME': 'cltbld', 20:30:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:30:41 INFO - 'MOZ_NO_REMOTE': '1', 20:30:41 INFO - 'NO_EM_RESTART': '1', 20:30:41 INFO - 'PAGER': '/bin/cat', 20:30:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:30:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:30:41 INFO - 'PWD': '/builds/slave/test', 20:30:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:30:41 INFO - 'SHELL': '/bin/bash', 20:30:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.DOWzfiOESs/Listeners', 20:30:41 INFO - 'TMPDIR': '/var/folders/nt/w1wt5wh94cg0328j1nmkplv400000w/T/', 20:30:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:30:41 INFO - 'USER': 'cltbld', 20:30:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:30:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:30:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:30:41 INFO - 'XPC_FLAGS': '0x0', 20:30:41 INFO - 'XPC_SERVICE_NAME': '0', 20:30:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:30:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:30:41 INFO - Downloading/unpacking psutil>=3.1.1 20:30:41 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 20:30:41 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 20:30:41 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 20:30:41 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 20:30:41 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 20:30:41 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 20:30:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 20:30:45 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 20:30:45 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 20:30:45 INFO - Installing collected packages: psutil 20:30:45 INFO - Running setup.py install for psutil 20:30:45 INFO - building 'psutil._psutil_osx' extension 20:30:45 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 20:30:45 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 20:30:45 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 20:30:45 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 20:30:45 INFO - building 'psutil._psutil_posix' extension 20:30:45 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 20:30:45 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 20:30:45 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 20:30:45 INFO - ^ 20:30:45 INFO - 1 warning generated. 20:30:45 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 20:30:45 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 20:30:45 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 20:30:45 INFO - Successfully installed psutil 20:30:45 INFO - Cleaning up... 20:30:45 INFO - Return code: 0 20:30:45 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 20:30:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:30:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:30:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:30:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:30:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:30:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:30:45 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x11026a490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1101e0570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f80a1cbea30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11007ed30>, '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 0x10fee4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x110076b20>, '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.qq18IEDFVL/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.DOWzfiOESs/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/nt/w1wt5wh94cg0328j1nmkplv400000w/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 20:30:45 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 20:30:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 20:30:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qq18IEDFVL/Render', 20:30:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:30:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:30:45 INFO - 'HOME': '/Users/cltbld', 20:30:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:30:45 INFO - 'LOGNAME': 'cltbld', 20:30:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:30:45 INFO - 'MOZ_NO_REMOTE': '1', 20:30:45 INFO - 'NO_EM_RESTART': '1', 20:30:45 INFO - 'PAGER': '/bin/cat', 20:30:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:30:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:30:45 INFO - 'PWD': '/builds/slave/test', 20:30:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:30:45 INFO - 'SHELL': '/bin/bash', 20:30:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.DOWzfiOESs/Listeners', 20:30:45 INFO - 'TMPDIR': '/var/folders/nt/w1wt5wh94cg0328j1nmkplv400000w/T/', 20:30:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:30:45 INFO - 'USER': 'cltbld', 20:30:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:30:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:30:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:30:45 INFO - 'XPC_FLAGS': '0x0', 20:30:45 INFO - 'XPC_SERVICE_NAME': '0', 20:30:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:30:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:30:46 INFO - Downloading/unpacking mozsystemmonitor==0.3 20:30:46 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 20:30:46 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 20:30:46 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 20:30:46 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 20:30:46 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 20:30:46 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 20:30:48 INFO - Downloading mozsystemmonitor-0.3.tar.gz 20:30:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 20:30:48 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 20:30:48 INFO - Installing collected packages: mozsystemmonitor 20:30:48 INFO - Running setup.py install for mozsystemmonitor 20:30:49 INFO - Successfully installed mozsystemmonitor 20:30:49 INFO - Cleaning up... 20:30:49 INFO - Return code: 0 20:30:49 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 20: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')]} 20:30:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:30:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20: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')]} 20:30:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:30:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20: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', '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 0x11026a490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1101e0570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f80a1cbea30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11007ed30>, '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 0x10fee4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x110076b20>, '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.qq18IEDFVL/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.DOWzfiOESs/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/nt/w1wt5wh94cg0328j1nmkplv400000w/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 20: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', 'jsonschema==2.5.1'] in /builds/slave/test/build 20: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 jsonschema==2.5.1 20:30:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qq18IEDFVL/Render', 20:30:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:30:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:30:49 INFO - 'HOME': '/Users/cltbld', 20:30:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:30:49 INFO - 'LOGNAME': 'cltbld', 20:30:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:30:49 INFO - 'MOZ_NO_REMOTE': '1', 20:30:49 INFO - 'NO_EM_RESTART': '1', 20:30:49 INFO - 'PAGER': '/bin/cat', 20:30:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:30:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:30:49 INFO - 'PWD': '/builds/slave/test', 20:30:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:30:49 INFO - 'SHELL': '/bin/bash', 20:30:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.DOWzfiOESs/Listeners', 20:30:49 INFO - 'TMPDIR': '/var/folders/nt/w1wt5wh94cg0328j1nmkplv400000w/T/', 20:30:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:30:49 INFO - 'USER': 'cltbld', 20:30:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:30:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:30:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:30:49 INFO - 'XPC_FLAGS': '0x0', 20:30:49 INFO - 'XPC_SERVICE_NAME': '0', 20:30:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:30:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:30:49 INFO - Downloading/unpacking jsonschema==2.5.1 20:30:49 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 20:30:49 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 20:30:49 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 20:30:49 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 20:30:49 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 20:30:49 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 20:30:52 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 20:30:52 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 20:30:52 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 20:30:52 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 20:30:52 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 20:30:52 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 20:30:52 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 20:30:52 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 20:30:52 INFO - Downloading functools32-3.2.3-2.tar.gz 20:30:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 20:30:52 INFO - warning: no files found matching '*.txt' 20:30:52 INFO - no previously-included directories found matching 'build' 20:30:52 INFO - no previously-included directories found matching 'dist' 20:30:52 INFO - no previously-included directories found matching '.git*' 20:30:52 INFO - Installing collected packages: jsonschema, functools32 20:30:52 INFO - Running setup.py install for functools32 20:30:52 INFO - warning: no files found matching '*.txt' 20:30:52 INFO - no previously-included directories found matching 'build' 20:30:52 INFO - no previously-included directories found matching 'dist' 20:30:52 INFO - no previously-included directories found matching '.git*' 20:30:52 INFO - Successfully installed jsonschema functools32 20:30:52 INFO - Cleaning up... 20:30:52 INFO - Return code: 0 20:30:52 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 20:30:52 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')]} 20:30:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:30:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:30:52 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')]} 20:30:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:30:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:30:52 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 0x11026a490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1101e0570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f80a1cbea30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11007ed30>, '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 0x10fee4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x110076b20>, '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.qq18IEDFVL/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.DOWzfiOESs/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/nt/w1wt5wh94cg0328j1nmkplv400000w/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 20:30:52 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 20:30:52 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 20:30:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qq18IEDFVL/Render', 20:30:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:30:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:30:52 INFO - 'HOME': '/Users/cltbld', 20:30:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:30:52 INFO - 'LOGNAME': 'cltbld', 20:30:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:30:52 INFO - 'MOZ_NO_REMOTE': '1', 20:30:52 INFO - 'NO_EM_RESTART': '1', 20:30:52 INFO - 'PAGER': '/bin/cat', 20:30:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:30:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:30:52 INFO - 'PWD': '/builds/slave/test', 20:30:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:30:52 INFO - 'SHELL': '/bin/bash', 20:30:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.DOWzfiOESs/Listeners', 20:30:52 INFO - 'TMPDIR': '/var/folders/nt/w1wt5wh94cg0328j1nmkplv400000w/T/', 20:30:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:30:52 INFO - 'USER': 'cltbld', 20:30:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:30:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:30:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:30:52 INFO - 'XPC_FLAGS': '0x0', 20:30:52 INFO - 'XPC_SERVICE_NAME': '0', 20:30:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:30:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:30:53 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 20:30:53 INFO - Cleaning up... 20:30:53 INFO - Return code: 0 20:30:53 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 20:30:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:30:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:30:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:30:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:30:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:30:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:30:53 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 0x11026a490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1101e0570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f80a1cbea30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11007ed30>, '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 0x10fee4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x110076b20>, '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.qq18IEDFVL/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.DOWzfiOESs/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/nt/w1wt5wh94cg0328j1nmkplv400000w/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 20:30:53 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 20:30:53 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 20:30:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qq18IEDFVL/Render', 20:30:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:30:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:30:53 INFO - 'HOME': '/Users/cltbld', 20:30:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:30:53 INFO - 'LOGNAME': 'cltbld', 20:30:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:30:53 INFO - 'MOZ_NO_REMOTE': '1', 20:30:53 INFO - 'NO_EM_RESTART': '1', 20:30:53 INFO - 'PAGER': '/bin/cat', 20:30:53 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:30:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:30:53 INFO - 'PWD': '/builds/slave/test', 20:30:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:30:53 INFO - 'SHELL': '/bin/bash', 20:30:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.DOWzfiOESs/Listeners', 20:30:53 INFO - 'TMPDIR': '/var/folders/nt/w1wt5wh94cg0328j1nmkplv400000w/T/', 20:30:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:30:53 INFO - 'USER': 'cltbld', 20:30:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:30:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:30:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:30:53 INFO - 'XPC_FLAGS': '0x0', 20:30:53 INFO - 'XPC_SERVICE_NAME': '0', 20:30:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:30:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:30:53 INFO - Downloading/unpacking blobuploader==1.2.4 20:30:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:30:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:30:53 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:30:53 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:30:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:30:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:30:56 INFO - Downloading blobuploader-1.2.4.tar.gz 20:30:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 20:30:56 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 20:30:56 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 20:30:56 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 20:30:56 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 20:30:56 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 20:30:56 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 20:30:56 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 20:30:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 20:30:56 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 20:30:56 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 20:30:56 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 20:30:56 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 20:30:56 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 20:30:56 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 20:30:56 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 20:30:57 INFO - Downloading docopt-0.6.1.tar.gz 20:30:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 20:30:57 INFO - Installing collected packages: blobuploader, requests, docopt 20:30:57 INFO - Running setup.py install for blobuploader 20:30:57 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 20:30:57 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 20:30:57 INFO - Running setup.py install for requests 20:30:58 INFO - Running setup.py install for docopt 20:30:58 INFO - Successfully installed blobuploader requests docopt 20:30:58 INFO - Cleaning up... 20:30:58 INFO - Return code: 0 20:30:58 INFO - Installing None into virtualenv /builds/slave/test/build/venv 20:30:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:30:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:30:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:30:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:30:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:30:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:30:58 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 0x11026a490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1101e0570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f80a1cbea30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11007ed30>, '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 0x10fee4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x110076b20>, '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.qq18IEDFVL/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.DOWzfiOESs/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/nt/w1wt5wh94cg0328j1nmkplv400000w/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 20:30:58 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 20:30:58 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 20:30:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qq18IEDFVL/Render', 20:30:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:30:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:30:58 INFO - 'HOME': '/Users/cltbld', 20:30:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:30:58 INFO - 'LOGNAME': 'cltbld', 20:30:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:30:58 INFO - 'MOZ_NO_REMOTE': '1', 20:30:58 INFO - 'NO_EM_RESTART': '1', 20:30:58 INFO - 'PAGER': '/bin/cat', 20:30:58 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:30:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:30:58 INFO - 'PWD': '/builds/slave/test', 20:30:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:30:58 INFO - 'SHELL': '/bin/bash', 20:30:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.DOWzfiOESs/Listeners', 20:30:58 INFO - 'TMPDIR': '/var/folders/nt/w1wt5wh94cg0328j1nmkplv400000w/T/', 20:30:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:30:58 INFO - 'USER': 'cltbld', 20:30:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:30:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:30:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:30:58 INFO - 'XPC_FLAGS': '0x0', 20:30:58 INFO - 'XPC_SERVICE_NAME': '0', 20:30:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:30:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:30:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 20:30:58 INFO - Running setup.py (path:/var/folders/nt/w1wt5wh94cg0328j1nmkplv400000w/T/pip-MJUXAu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 20:30:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 20:30:58 INFO - Running setup.py (path:/var/folders/nt/w1wt5wh94cg0328j1nmkplv400000w/T/pip-7Svet9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 20:30:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 20:30:58 INFO - Running setup.py (path:/var/folders/nt/w1wt5wh94cg0328j1nmkplv400000w/T/pip-5EE_OZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 20:30:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 20:30:58 INFO - Running setup.py (path:/var/folders/nt/w1wt5wh94cg0328j1nmkplv400000w/T/pip-oTYFIh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 20:30:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 20:30:59 INFO - Running setup.py (path:/var/folders/nt/w1wt5wh94cg0328j1nmkplv400000w/T/pip-mpCVmY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 20:30:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 20:30:59 INFO - Running setup.py (path:/var/folders/nt/w1wt5wh94cg0328j1nmkplv400000w/T/pip-d0oJoN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 20:30:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 20:30:59 INFO - Running setup.py (path:/var/folders/nt/w1wt5wh94cg0328j1nmkplv400000w/T/pip-FZE3b8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 20:30:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 20:30:59 INFO - Running setup.py (path:/var/folders/nt/w1wt5wh94cg0328j1nmkplv400000w/T/pip-K2th2t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 20:30:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 20:30:59 INFO - Running setup.py (path:/var/folders/nt/w1wt5wh94cg0328j1nmkplv400000w/T/pip-lNUOCu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 20:30:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 20:30:59 INFO - Running setup.py (path:/var/folders/nt/w1wt5wh94cg0328j1nmkplv400000w/T/pip-xwNvcM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 20:30:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 20:30:59 INFO - Running setup.py (path:/var/folders/nt/w1wt5wh94cg0328j1nmkplv400000w/T/pip-67AwzV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 20:30:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 20:30:59 INFO - Running setup.py (path:/var/folders/nt/w1wt5wh94cg0328j1nmkplv400000w/T/pip-S1nvOp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 20:30:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 20:30:59 INFO - Running setup.py (path:/var/folders/nt/w1wt5wh94cg0328j1nmkplv400000w/T/pip-eT5PYH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 20:30:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 20:30:59 INFO - Running setup.py (path:/var/folders/nt/w1wt5wh94cg0328j1nmkplv400000w/T/pip-fIwj91-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 20:31:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 20:31:00 INFO - Running setup.py (path:/var/folders/nt/w1wt5wh94cg0328j1nmkplv400000w/T/pip-z6otoZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 20:31:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 20:31:00 INFO - Running setup.py (path:/var/folders/nt/w1wt5wh94cg0328j1nmkplv400000w/T/pip-l0C_6o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 20:31:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 20:31:00 INFO - Running setup.py (path:/var/folders/nt/w1wt5wh94cg0328j1nmkplv400000w/T/pip-L6S4pG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 20:31:00 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 20:31:00 INFO - Running setup.py install for manifestparser 20:31:00 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 20:31:00 INFO - Running setup.py install for mozcrash 20:31:00 INFO - Running setup.py install for mozdebug 20:31:00 INFO - Running setup.py install for mozdevice 20:31:00 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 20:31:00 INFO - Installing dm script to /builds/slave/test/build/venv/bin 20:31:01 INFO - Running setup.py install for mozfile 20:31:01 INFO - Running setup.py install for mozhttpd 20:31:01 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 20:31:01 INFO - Running setup.py install for mozinfo 20:31:01 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 20:31:01 INFO - Running setup.py install for mozInstall 20:31:01 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 20:31:01 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 20:31:01 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 20:31:01 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 20:31:01 INFO - Running setup.py install for mozleak 20:31:01 INFO - Running setup.py install for mozlog 20:31:01 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 20:31:01 INFO - Running setup.py install for moznetwork 20:31:02 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 20:31:02 INFO - Running setup.py install for mozprocess 20:31:02 INFO - Running setup.py install for mozprofile 20:31:02 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 20:31:02 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 20:31:02 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 20:31:02 INFO - Running setup.py install for mozrunner 20:31:02 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 20:31:02 INFO - Running setup.py install for mozscreenshot 20:31:02 INFO - Running setup.py install for moztest 20:31:02 INFO - Running setup.py install for mozversion 20:31:02 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 20:31:02 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 20:31:02 INFO - Cleaning up... 20:31:02 INFO - Return code: 0 20:31:02 INFO - Installing None into virtualenv /builds/slave/test/build/venv 20:31:02 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')]} 20:31:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:31:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:31:02 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')]} 20:31:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:31:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:31:02 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 0x11026a490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1101e0570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f80a1cbea30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11007ed30>, '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 0x10fee4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x110076b20>, '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.qq18IEDFVL/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.DOWzfiOESs/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/nt/w1wt5wh94cg0328j1nmkplv400000w/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 20:31:02 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 20:31:02 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 20:31:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qq18IEDFVL/Render', 20:31:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:31:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:31:02 INFO - 'HOME': '/Users/cltbld', 20:31:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:31:03 INFO - 'LOGNAME': 'cltbld', 20:31:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:31:03 INFO - 'MOZ_NO_REMOTE': '1', 20:31:03 INFO - 'NO_EM_RESTART': '1', 20:31:03 INFO - 'PAGER': '/bin/cat', 20:31:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:31:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:31:03 INFO - 'PWD': '/builds/slave/test', 20:31:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:31:03 INFO - 'SHELL': '/bin/bash', 20:31:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.DOWzfiOESs/Listeners', 20:31:03 INFO - 'TMPDIR': '/var/folders/nt/w1wt5wh94cg0328j1nmkplv400000w/T/', 20:31:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:31:03 INFO - 'USER': 'cltbld', 20:31:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:31:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:31:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:31:03 INFO - 'XPC_FLAGS': '0x0', 20:31:03 INFO - 'XPC_SERVICE_NAME': '0', 20:31:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:31:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:31:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 20:31:03 INFO - Running setup.py (path:/var/folders/nt/w1wt5wh94cg0328j1nmkplv400000w/T/pip-1lZ4Fm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 20:31:03 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)) 20:31:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 20:31:03 INFO - Running setup.py (path:/var/folders/nt/w1wt5wh94cg0328j1nmkplv400000w/T/pip-wN2TwU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 20:31:03 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)) 20:31:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 20:31:03 INFO - Running setup.py (path:/var/folders/nt/w1wt5wh94cg0328j1nmkplv400000w/T/pip-8vnDhm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 20:31:03 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)) 20:31:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 20:31:03 INFO - Running setup.py (path:/var/folders/nt/w1wt5wh94cg0328j1nmkplv400000w/T/pip-mqTr9T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 20:31:03 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)) 20:31:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 20:31:03 INFO - Running setup.py (path:/var/folders/nt/w1wt5wh94cg0328j1nmkplv400000w/T/pip-sSRWDs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 20:31:03 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)) 20:31:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 20:31:03 INFO - Running setup.py (path:/var/folders/nt/w1wt5wh94cg0328j1nmkplv400000w/T/pip-t_OREP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 20:31:03 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)) 20:31:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 20:31:03 INFO - Running setup.py (path:/var/folders/nt/w1wt5wh94cg0328j1nmkplv400000w/T/pip-C4KnpT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 20:31:04 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)) 20:31:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 20:31:04 INFO - Running setup.py (path:/var/folders/nt/w1wt5wh94cg0328j1nmkplv400000w/T/pip-EPJG3e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 20:31:04 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)) 20:31:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 20:31:04 INFO - Running setup.py (path:/var/folders/nt/w1wt5wh94cg0328j1nmkplv400000w/T/pip-QmdyXZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 20:31:04 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)) 20:31:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 20:31:04 INFO - Running setup.py (path:/var/folders/nt/w1wt5wh94cg0328j1nmkplv400000w/T/pip-2gNX9H-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 20:31:04 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)) 20:31:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 20:31:04 INFO - Running setup.py (path:/var/folders/nt/w1wt5wh94cg0328j1nmkplv400000w/T/pip-5QTRwn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 20:31:04 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)) 20:31:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 20:31:04 INFO - Running setup.py (path:/var/folders/nt/w1wt5wh94cg0328j1nmkplv400000w/T/pip-29Fr0G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 20:31:04 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)) 20:31:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 20:31:04 INFO - Running setup.py (path:/var/folders/nt/w1wt5wh94cg0328j1nmkplv400000w/T/pip-m8iCVr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 20:31:04 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)) 20:31:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 20:31:04 INFO - Running setup.py (path:/var/folders/nt/w1wt5wh94cg0328j1nmkplv400000w/T/pip-Hy9Vfy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 20:31:04 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)) 20:31:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 20:31:04 INFO - Running setup.py (path:/var/folders/nt/w1wt5wh94cg0328j1nmkplv400000w/T/pip-V7EuNX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 20:31:04 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)) 20:31:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 20:31:04 INFO - Running setup.py (path:/var/folders/nt/w1wt5wh94cg0328j1nmkplv400000w/T/pip-obSHt9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 20:31:04 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)) 20:31:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 20:31:04 INFO - Running setup.py (path:/var/folders/nt/w1wt5wh94cg0328j1nmkplv400000w/T/pip-KGXAnX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 20:31:05 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)) 20:31:05 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)) 20:31:05 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)) 20:31:05 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)) 20:31:05 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)) 20:31:05 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)) 20:31:05 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 20:31:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20: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 20:31:05 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:31:05 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:31:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20: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 20:31:07 INFO - Downloading blessings-1.6.tar.gz 20:31:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 20:31:07 INFO - Installing collected packages: blessings 20:31:07 INFO - Running setup.py install for blessings 20:31:07 INFO - Successfully installed blessings 20:31:07 INFO - Cleaning up... 20:31:08 INFO - Return code: 0 20:31:08 INFO - Done creating virtualenv /builds/slave/test/build/venv. 20:31:08 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 20:31:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 20:31:08 INFO - Reading from file tmpfile_stdout 20:31:08 INFO - Current package versions: 20:31:08 INFO - blessings == 1.6 20:31:08 INFO - blobuploader == 1.2.4 20:31:08 INFO - docopt == 0.6.1 20:31:08 INFO - functools32 == 3.2.3-2 20:31:08 INFO - jsonschema == 2.5.1 20:31:08 INFO - manifestparser == 1.1 20:31:08 INFO - mozInstall == 1.12 20:31:08 INFO - mozcrash == 0.17 20:31:08 INFO - mozdebug == 0.1 20:31:08 INFO - mozdevice == 0.48 20:31:08 INFO - mozfile == 1.2 20:31:08 INFO - mozhttpd == 0.7 20:31:08 INFO - mozinfo == 0.9 20:31:08 INFO - mozleak == 0.1 20:31:08 INFO - mozlog == 3.4 20:31:08 INFO - moznetwork == 0.27 20:31:08 INFO - mozprocess == 0.23 20:31:08 INFO - mozprofile == 0.28 20:31:08 INFO - mozrunner == 6.12 20:31:08 INFO - mozscreenshot == 0.1 20:31:08 INFO - mozsystemmonitor == 0.3 20:31:08 INFO - moztest == 0.7 20:31:08 INFO - mozversion == 1.4 20:31:08 INFO - psutil == 3.1.1 20:31:08 INFO - requests == 1.2.3 20:31:08 INFO - wsgiref == 0.1.2 20:31:08 INFO - Installing None into virtualenv /builds/slave/test/build/venv 20:31:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:31:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:31:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:31:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:31:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:31:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:31:08 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 0x11026a490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1101e0570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f80a1cbea30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11007ed30>, '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 0x10fee4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x110076b20>, '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.qq18IEDFVL/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.DOWzfiOESs/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/nt/w1wt5wh94cg0328j1nmkplv400000w/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 20:31:08 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 20:31:08 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 20:31:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qq18IEDFVL/Render', 20:31:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:31:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:31:08 INFO - 'HOME': '/Users/cltbld', 20:31:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:31:08 INFO - 'LOGNAME': 'cltbld', 20:31:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:31:08 INFO - 'MOZ_NO_REMOTE': '1', 20:31:08 INFO - 'NO_EM_RESTART': '1', 20:31:08 INFO - 'PAGER': '/bin/cat', 20:31:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:31:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:31:08 INFO - 'PWD': '/builds/slave/test', 20:31:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:31:08 INFO - 'SHELL': '/bin/bash', 20:31:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.DOWzfiOESs/Listeners', 20:31:08 INFO - 'TMPDIR': '/var/folders/nt/w1wt5wh94cg0328j1nmkplv400000w/T/', 20:31:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:31:08 INFO - 'USER': 'cltbld', 20:31:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:31:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:31:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:31:08 INFO - 'XPC_FLAGS': '0x0', 20:31:08 INFO - 'XPC_SERVICE_NAME': '0', 20:31:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:31:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:31:08 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)) 20:31:08 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)) 20:31:08 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)) 20:31:08 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)) 20:31:08 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)) 20:31:08 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)) 20:31:08 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)) 20:31:08 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)) 20:31:08 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)) 20:31:08 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)) 20:31:08 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)) 20:31:08 INFO - Cleaning up... 20:31:08 INFO - Return code: 0 20:31:08 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 20:31:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:31:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:31:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:31:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:31:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:31:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:31:08 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 0x11026a490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1101e0570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f80a1cbea30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11007ed30>, '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 0x10fee4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x110076b20>, '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.qq18IEDFVL/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.DOWzfiOESs/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/nt/w1wt5wh94cg0328j1nmkplv400000w/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 20:31:08 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 20:31:08 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 20:31:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qq18IEDFVL/Render', 20:31:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:31:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:31:08 INFO - 'HOME': '/Users/cltbld', 20:31:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:31:08 INFO - 'LOGNAME': 'cltbld', 20:31:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:31:08 INFO - 'MOZ_NO_REMOTE': '1', 20:31:08 INFO - 'NO_EM_RESTART': '1', 20:31:08 INFO - 'PAGER': '/bin/cat', 20:31:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:31:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:31:08 INFO - 'PWD': '/builds/slave/test', 20:31:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:31:08 INFO - 'SHELL': '/bin/bash', 20:31:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.DOWzfiOESs/Listeners', 20:31:08 INFO - 'TMPDIR': '/var/folders/nt/w1wt5wh94cg0328j1nmkplv400000w/T/', 20:31:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:31:08 INFO - 'USER': 'cltbld', 20:31:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:31:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:31:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:31:08 INFO - 'XPC_FLAGS': '0x0', 20:31:08 INFO - 'XPC_SERVICE_NAME': '0', 20:31:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:31:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:31:08 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 20:31:08 INFO - Cleaning up... 20:31:08 INFO - Return code: 0 20:31:08 INFO - Running post-action listener: _resource_record_post_action 20:31:08 INFO - Running post-action listener: _start_resource_monitoring 20:31:08 INFO - Starting resource monitoring. 20:31:08 INFO - [mozharness: 2017-01-05 04:31:08.926137Z] Finished create-virtualenv step (success) 20:31:08 INFO - [mozharness: 2017-01-05 04:31:08.927084Z] Running install step. 20:31:08 INFO - Running pre-action listener: _resource_record_pre_action 20:31:08 INFO - Running main action method: install 20:31:08 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 20:31:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 20:31:09 INFO - Reading from file tmpfile_stdout 20:31:09 INFO - Detecting whether we're running mozinstall >=1.0... 20:31:09 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 20:31:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 20:31:09 INFO - Reading from file tmpfile_stdout 20:31:09 INFO - Output received: 20:31:09 INFO - Usage: mozinstall [options] installer 20:31:09 INFO - Options: 20:31:09 INFO - -h, --help show this help message and exit 20:31:09 INFO - -d DEST, --destination=DEST 20:31:09 INFO - Directory to install application into. [default: 20:31:09 INFO - "/builds/slave/test"] 20:31:09 INFO - --app=APP Application being installed. [default: firefox] 20:31:09 INFO - mkdir: /builds/slave/test/build/application 20:31:09 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'] 20:31:09 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 20:31:31 INFO - Reading from file tmpfile_stdout 20:31:31 INFO - Output received: 20:31:31 INFO - /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox 20:31:31 INFO - Running post-action listener: _resource_record_post_action 20:31:31 INFO - [mozharness: 2017-01-05 04:31:31.345188Z] Finished install step (success) 20:31:31 INFO - [mozharness: 2017-01-05 04:31:31.345309Z] Running run-tests step. 20:31:31 INFO - Running pre-action listener: _resource_record_pre_action 20:31:31 INFO - Running main action method: run_tests 20:31:31 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 20:31:31 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 20:31:31 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 20:31:31 INFO - 2017-01-04 20:31:31.360 screenresolution[1903:12006] starting screenresolution argv=screenresolution get 20:31:31 INFO - 2017-01-04 20:31:31.362 screenresolution[1903:12006] Display 0: 1600x1200x32@60 20:31:31 INFO - 2017-01-04 20:31:31.368 screenresolution[1904:12009] starting screenresolution argv=screenresolution list 20:31:31 INFO - Available Modes on Display 0 20:31:31 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 20:31:31 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 20:31:31 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 20:31:31 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 20:31:31 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 20:31:31 INFO - Intel Iris: 20:31:31 INFO - Chipset Model: Intel Iris 20:31:31 INFO - Type: GPU 20:31:31 INFO - Bus: Built-In 20:31:31 INFO - VRAM (Dynamic, Max): 1536 MB 20:31:31 INFO - Vendor: Intel (0x8086) 20:31:31 INFO - Device ID: 0x0a2e 20:31:31 INFO - Revision ID: 0x0009 20:31:31 INFO - Displays: 20:31:31 INFO - Display: 20:31:31 INFO - Resolution: 1600 x 1200 @ 60 Hz 20:31:31 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 20:31:31 INFO - Display Serial Number: 1600x1200 60 20:31:31 INFO - Main Display: Yes 20:31:31 INFO - Mirror: Off 20:31:31 INFO - Online: Yes 20:31:31 INFO - Rotation: Supported 20:31:31 INFO - Return code: 0 20:31:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 20:31:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 20:31:31 INFO - Python 2.7.3 20:31:31 INFO - Return code: 0 20:31:31 INFO - grabbing minidump binary from tooltool 20:31:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:31:31 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11007ed30>, '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 0x10fee4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x110076b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 20:31:31 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 20:31:31 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 20:31:31 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 20:31:31 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 20:31:31 INFO - Return code: 0 20:31:31 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 20:31:31 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 20:31:31 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 20:31:31 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 20:31:31 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 20:31:31 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/PVYjI-z6S5qoQRXyCRoALw/artifacts/public/build/firefox-51.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0266', '--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 20:31:31 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/PVYjI-z6S5qoQRXyCRoALw/artifacts/public/build/firefox-51.0.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0266 --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 20:31:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qq18IEDFVL/Render', 20:31:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:31:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:31:31 INFO - 'HOME': '/Users/cltbld', 20:31:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:31:31 INFO - 'LOGNAME': 'cltbld', 20:31:31 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 20:31:31 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 20:31:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:31:31 INFO - 'MOZ_NO_REMOTE': '1', 20:31:31 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 20:31:31 INFO - 'NO_EM_RESTART': '1', 20:31:31 INFO - 'PAGER': '/bin/cat', 20:31:31 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:31:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:31:31 INFO - 'PWD': '/builds/slave/test', 20:31:31 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 20:31:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:31:31 INFO - 'SHELL': '/bin/bash', 20:31:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.DOWzfiOESs/Listeners', 20:31:31 INFO - 'TMPDIR': '/var/folders/nt/w1wt5wh94cg0328j1nmkplv400000w/T/', 20:31:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:31:31 INFO - 'USER': 'cltbld', 20:31:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:31:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:31:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:31:31 INFO - 'XPC_FLAGS': '0x0', 20:31:31 INFO - 'XPC_SERVICE_NAME': '0', 20:31:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:31:31 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/PVYjI-z6S5qoQRXyCRoALw/artifacts/public/build/firefox-51.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0266', '--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 20:31:31 INFO - mozversion application_buildid: 20170104184438 20:31:31 INFO - mozversion application_changeset: 9d0309c49d23e002410e1b0afd0aa33fc8e1c745 20:31:31 INFO - mozversion application_display_name: Firefox 20:31:31 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 20:31:31 INFO - mozversion application_name: Firefox 20:31:31 INFO - mozversion application_remotingname: firefox 20:31:31 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 20:31:31 INFO - mozversion application_vendor: Mozilla 20:31:31 INFO - mozversion application_version: 51.0 20:31:31 INFO - mozversion platform_buildid: 20170104184438 20:31:31 INFO - mozversion platform_changeset: 9d0309c49d23e002410e1b0afd0aa33fc8e1c745 20:31:31 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 20:31:31 INFO - mozversion platform_version: 51.0 20:31:31 INFO - using testdate: 1483590691 20:31:31 INFO - actual date: 1483590691 20:31:31 INFO - starting webserver on 'localhost:51291' 20:31:31 INFO - SUITE-START | Running 2 tests 20:31:31 INFO - TEST-START | dromaeo_css 20:31:31 INFO - operating with platform_type : mac_ 20:31:31 INFO - Initialising browser for dromaeo_css test... 20:31:31 INFO - TEST-INFO | started process 1914 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/nt/w1wt5wh94cg0328j1nmkplv400000w/T/tmpu4ZmsU/profile http://localhost:51291/getInfo.html) 20:31:34 INFO - PROCESS | 1914 | __metrics Screen width/height:1600/1200 20:31:34 INFO - PROCESS | 1914 | colorDepth:24 20:31:34 INFO - PROCESS | 1914 | Browser inner width/height: 1024/689 20:31:34 INFO - PROCESS | 1914 | __metrics 20:31:34 INFO - PROCESS | 1914 | 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" 20:31:34 INFO - PROCESS | 1914 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 20:31:34 INFO - TEST-INFO | 1914: exit 0 20:31:34 INFO - Browser initialized. 20:31:34 INFO - Running cycle 1/1 for dromaeo_css test... 20:31:34 INFO - TEST-INFO | started process 1917 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/nt/w1wt5wh94cg0328j1nmkplv400000w/T/tmpu4ZmsU/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 20:31:46 INFO - PROCESS | 1917 | RSS: Main: 164438016 20:31:46 INFO - PROCESS | 1917 | 20:34:49 INFO - PROCESS | 1917 | Cycle 1(1): loaded http://localhost:51291/tests/dromaeo/cssquery-dojo.html (next: http://localhost:51291/tests/dromaeo/cssquery-ext.html) 20:34:50 INFO - PROCESS | 1917 | RSS: Main: 291299328 20:34:50 INFO - PROCESS | 1917 | 20:37:53 INFO - PROCESS | 1917 | Cycle 1(1): loaded http://localhost:51291/tests/dromaeo/cssquery-ext.html (next: http://localhost:51291/tests/dromaeo/cssquery-jquery.html) 20:37:53 INFO - PROCESS | 1917 | RSS: Main: 298283008 20:37:53 INFO - PROCESS | 1917 | 20:40:56 INFO - PROCESS | 1917 | Cycle 1(1): loaded http://localhost:51291/tests/dromaeo/cssquery-jquery.html (next: http://localhost:51291/tests/dromaeo/cssquery-mootools.html) 20:40:56 INFO - PROCESS | 1917 | RSS: Main: 278134784 20:40:56 INFO - PROCESS | 1917 | 20:43:59 INFO - PROCESS | 1917 | Cycle 1(1): loaded http://localhost:51291/tests/dromaeo/cssquery-mootools.html (next: http://localhost:51291/tests/dromaeo/cssquery-prototype.html) 20:43:59 INFO - PROCESS | 1917 | RSS: Main: 281976832 20:43:59 INFO - PROCESS | 1917 | 20:47:02 INFO - PROCESS | 1917 | Cycle 1(1): loaded http://localhost:51291/tests/dromaeo/cssquery-prototype.html (next: http://localhost:51291/tests/dromaeo/cssquery-yui.html) 20:47:03 INFO - PROCESS | 1917 | RSS: Main: 258093056 20:47:03 INFO - PROCESS | 1917 | 20:50:06 INFO - PROCESS | 1917 | Cycle 1(1): loaded http://localhost:51291/tests/dromaeo/cssquery-yui.html (next: http://localhost:51291/tests/dromaeo/cssquery-dojo.html) 20:50:06 INFO - PROCESS | 1917 | RSS: Main: 318193664 20:50:06 INFO - PROCESS | 1917 | 20:50:06 INFO - PROCESS | 1917 | __start_tp_report 20:50:06 INFO - PROCESS | 1917 | _x_x_mozilla_page_load 20:50:06 INFO - PROCESS | 1917 | _x_x_mozilla_page_load_details 20:50:06 INFO - PROCESS | 1917 | |i|pagename|runs| 20:50:06 INFO - PROCESS | 1917 | |0;dojo.html;3460.2733425980546;3506.3513250048727;3530.5763308402998;3543.8482493189713;3544.9291014179717;5916.585838991267;5945.554083443737;5953.732082056307;5993.160957465952;5994.760209591616;5932.525397968163;6000.309964354101;6001.21984142061;6022.126791615224;6028.27660680719;5297.496737809912;5340.092184328673;5346.27825243591;5372.274742909716;5471.50756431921;5274.7626356814035;5302.973485132588;5323.787048518055;5423.674579525222;5462.180672899077;5397.865053373677;5407.351117865862;5416.350037995446;5419.268398766175;5419.268398766175;5302.681839089667;5345.679259244458;5400.081986062379;5404.540614047791;5409.161579955114;5193.792248310101;5290.55030322423;5323.095073837457;5352.143657014898;5359.222912677679;5012.624053196025;5966.612170208922;6063.54523410746;6080;6117.327094019654;6167.383261673842;6190.288116866573;6209.658468784215;6213.844653883644;6241.21984751906;6170.6297622142365;6224.719887605069;6242.687865606701;6273.247210334789;6304.1804565406665;6051.152838602599;6061.212042434543;6067.635941843415;6067.7269522871575;6079.209702738615;6169.9383006170265;6245.469135123474;6260.436560709467;6308.116863639094;6338.936610633835;6268.592541484789;6286.339934306832;6286.339934306923;6287.9056814147825;6310.810675679737;6272.968635156886;6281.120643109968;6285.717142728497;6289.559730818799;6298.401651843067;5266.236395722675;6157.922363586355;6172.845678857974;6255.874882502416;6263.937360626335;6242.563020588515;6244.438000579969;6262.780802671885;6264.968675156595;6276.058591211355;5040.823571174991;5056.9633225188745;5091.0836049511445;5099.107656160157;5099.949000509948;4458;4527.275635898241;4561.24739417999;4561.429821272341;4619.8845028873875;5784.305883293948;5823.912641310384;5840.2115714378015;5872.853178670482;5876.324222714443;6217.6891155442045;6239.594426362272;6249.843753906189;6250.062490626442;6256.061590761422;5800.361960184377;5815.2149459822385;5816.5055970243;5829.7085145743395;5835.2122463466885;5504.2018907258225;5524.917126243109;5533.972330138324;5547.334319881559;5553.250311207935;6258.15514905491;6304.306526282014;6336.334684858116;6342.61944283326;6344.746210151635;6301.779437719658;6310.368963103837;6313.779017734541;6322.0200868865395;6333.429991300805;5463.918041229305;6423.389777971085;6465.77369792055;6468.676566171766;6471.611703297817;6166.938330616816;6241.906371404523;6270.278917924315;6294.213223347082;6294.748210071546;6201.410865967726;6217.595856269328;6265.091561723616;6304.590201636879;6317.336679648754;6237.563370564017;6258.217722784652;6277.74889004453;6279.497640188865;6285.874282514233;6118.619179173164;6240.188775459162;6260.28006779208;6265.749370025148;6300.842478938063;6056.757729690763;6094.512439004958;6095.664738439428;6144.539159562962;6191.597546159485;6242.53181011435;6248.593841400295;6270.184875966278;6274.654893980693;6281.654509002049;6091.147239386578;6128.50971922254;6156.5998210116195;6159.969200153971;6173.104899789595 20:50:06 INFO - PROCESS | 1917 | |1;ext.html;463.21716299453925;482.2838085443116;511.9479469689786;516.161237988269;564.7515093358925;26024.86987565062;32587.83706081469;41436.378454323174;42881.142377152515;42897.71306860799;44609.55390446095;44839;44852;44865.55134448663;44933.10133797322;9925.851112233304;10126.544305506233;10128.898711012906;10137.18902487803;10148.289619726622;9263.305252106085;9488.051194880525;9965.55155018026;12258.20321679088;12489.375531223448;12282.201656892274;12351.567695130672;12373.938130309336;12377.876221237762;12381.38093095346;12477.126601137925;12533.185342952771;12568.43442045101;12571.434285457222;12584.81122783159;12337.136400451973;12400.875991240062;12406.131570790048;12407.503699851995;12433.316167610777;42117.789411053054;42194;42321.57678423238;42358.57641423577;42436;3420.9737078876237;3459.446488561818;3477.0959550516795;3517.9648203517895;3552.6980206682465;32020.839895800604;32132.678673213202;32171;32187.51718724221;32526.83736581302;3268.7385009199443;3322.4850148227065;3404.285100128976;3448.534447849533;3507.9123021924656;554202;557169;558731;558968;559931;64288.678556606916;64618.67690661517;64737.70524589576;64783;64837.675811621586;36270.45594316087;37047.85093267049;37077.44383834245;37794.43308350377;68157.3184268151;17316.913415433093;17510.73733893967;17528.12359382026;17530.298788048334;17562.121893905252;12242.571509997108;13358.198508089547;13457.528986485426;13472.191668499921;13476.730465390638;16603.916980415022;17762.28950841952;19430.402666918035;20044.797312161318;20625.587488250152;8007;8201.466904651297;8229.588520573947;8233.38249631268;8270.048944371354;898.6135961536602;998.8951160128228;1040.2822052783556;1092.9672109836717;1114.899659030691;30958.69041309558;31140.844295778377;31274.458064066763;31596.998285162874;31713.524297135562;947.7772723409993;1072.790805792863;1089.760252744395;1093.5407129005678;1160.257435241446;15335.079895206323;15661;15889.205539723198;16017.67964640724;16029.839701602834;732701;741114;741896;741938;743666;59654.70172649109;60378.396216038156;60399.698001509714;60426;60452;1303.9348032598332;1343.2544937559749;1423.555091582045;1442.473497173524;1456.6431224350101;31213.219669507536;31258.68741312558;31817.204569885012;32547;32982.835085825376;51682.48317516777;52195.739021306166;52218;52229.47770522246;52396.42810715685;479713;560865;562334;562782;708017;42267.57732422637;44358.11283774242;44681.32978005268;44815.775921120185;45056.0988780216;40258.597414025484;48558;48786.51213487962;48847.755761220964;49145;39153.608463915;39782;40115.59884401118;40283.59716402798;41506;35729.64270357263;35923.82038089793;36026.09934751652;37777.433338499395;39650.40524392079;39652.80173599113;42107.1578568433;42370.576294236664;42820.571794281655;42868.78565607152;39599;42706.359404609575;43884.561154388044;45887;46144.538554614024;30930.226744331572;32923.17692057718;32979.34041319208;32992.505112422856;34164 20:50:06 INFO - PROCESS | 1917 | |2;jquery.html;335.1049813305756;368.14957448294456;388.1886856469977;389.9337112690842;409.64565650712126;15674;15789.28948197331;17756.112194390276;20684.8965755171;21130.788692113074;17235.569110772238;20146.59706805863;21287.25494607689;22018.779812201872;22026.44933876654;5294;5601.047821870282;5700;5790.36306006339;5793.4206579341935;4163.937540936889;6219.751209951596;6316;6320.494360451175;6330.050492426127;4257.382679511469;4281.785910704452;4401.405810215612;4403.4495688039;4405.043883357751;4373.825046998132;7088.432925365958;7095.432365410755;7103.502754807166;7106.182788979256;6002.54980876436;6202.81391558254;6241.906371404432;6246.3441338659695;6256.280527739323;13395.86604133956;13489.865101349058;13496.797548036788;21946.780532194633;25014;2281.4524514116492;2294.8508346957396;2629.895443913561;2691.596260560912;2965.644122705289;10080.395176289447;10328.638497652546;10420.010099040579;10513;10741.409222492759;1908.9427317180507;1924.5765931495048;1928.363639998797;1991.7709463411604;1994.58113796103;105252.47373763159;127229.09156362661;132878.33560832232;134490;136343;11392;11698.532058717556;11805.52777888892;11824.467898944567;11991.280523168638;14883;15128.167950762814;15145;15164.545063648107;15171.24143792806;8859.822803544022;8938.68714594986;8964.027913858941;8981.14679105484;9094.727158185266;7441.255874412624;8969.820603588023;8977.910220897838;9135.908640913638;9138.862917056249;4582.075528278131;4606.216943119678;4608.815647374067;4616.722996620214;4739.597134243628;1803.4769916724265;1846.0123833749149;1908.3988543608718;1950.8634395592455;1952.2386269354963;803.377382528545;844.6705784743955;914.9817003660023;939.2673714502562;984.542187882638;22047;23719.881400593233;23812.285631431085;23925.52148957011;23960.7603923962;951.8001219743793;955.8852937647526;974.3958745577787;982.8722266105503;985.3693636072919;10723.001527747576;11379.431028448544;11380.772384552263;11392.91767282107;11429.142814288962;629475;630005;643145;646971;672484;134157.3292133533;136672.31663841815;137815;140323.29838350744;141808;1014.1683819268277;1044.4203467075695;1048.8374301983206;1054.288355360129;1056.3661802918189;15703.921480392524;19539.804601953798;20175.79824201739;20179.697304540736;20237.291694790612;45557.86105347393;57282;58744.412555873896;58795.53011174755;58805.82388352294;78261.60869195845;79170.2082979186;80161.59919200366;80343.79484307623;83679.5816020916;8306.916930830614;8424.199701028392;8512.191341822518;8537.146285371662;8708.738737837875;12430.254184748945;13094;13157;13217.537386191438;13623.591292261248;11275.605353812578;11338.09295256394;11365.090792736395;11546.538138474367;11908.225965312227;11176.329420234812;11200.767915529448;11312.434378281218;11747.765044699228;11829.82255266189;10872;10879.129669626569;11623.6512904613;11761.529538818351;12086.818697719717;6942.271061538668;7765.0681918170185;8065.556394398364;8281;8663.176998185163;8537.146285371413;10750.24748267645;10988.890111098786;11168.720781980515;11770.705732356759 20:50:06 INFO - PROCESS | 1917 | |3;mootools.html;1542.637480192155;1561.7501199808032;1570.97643535347;1895.8388536974358;1951.2585217617311;8684.522351270678;9225.538723063844;9230;9257.16685498305;9289.210417114537;8119.025716913964;8357.080721120687;8583.48499090055;8885.244754195885;8909.331800114986;2348.389418751124;2399.664047033417;2401.1595941420537;2401.171595799455;2404.5311164322966;2383.2016274548046;2389.5101504191684;2417.153996101368;2418.697662792151;2420.2376251480814;2389.8685572293516;2401.0755858994326;2401.8799060047013;2417.601095819188;2423.8060955123547;2375.489269807;2390.1395497620842;2401.5317013182434;2403.194929698553;2403.927882163538;2383.5232953409386;2386.606209975352;2387.307680772574;2389.3787615220167;2391.2109004028803;7705.730299439493;8818.559072046437;8969.775755606097;8986.101389860962;8996.550172491414;5382.865428364322;5539.750711218005;5541.722913854291;5546.473085056913;5549.750261238213;3055.8319292438905;3641.9453708194396;3650.926981460356;3663.3955397359405;3672.137047793766;1066.7333166708322;1068.759529105945;1069.9251052426334;1082.9241953063288;1085.5440714899762;11654.533818647244;11731.354775487345;11847.289162650182;11853.762924741457;11870.643880683594;4191.497020357516;4689.109069276827;4721.197396442613;4918.926216106761;4948.65359424844;10241.334313269614;10251.282410231212;10252.948735256276;10253.948730256301;10272.948635256926;5453.945460545344;5505.256790333334;5534.446555344433;5543.417941116206;5545.528630066409;4448.644108471314;4599.632029437637;4908.730019848871;5016.322796422509;5061.215511595708;4059.0867054913;4104.302268614343;4173.812178451977;4204.558521355275;4206.8527601534415;1298.3313593499356;1317.0648839323997;1322.9470821167238;1329.6144118205611;1336.779431393809;771.7376092128703;886.5567216391804;888.6401007591936;935.8783358163395;936.5176933879058;5754.827355179351;5868.354481007086;5967.403259673998;6053.092036194606;6163.1679723237685;924.9537523123817;949.4208532794989;966.4394651102343;1004.6232662751469;1030.9381337222674;3759.774413535197;4015.819288132041;4115.609017143366;4136.21411931732;4138.358554424069;10658.147348212124;11034.779304413867;11045.28205666629;11052.502637381338;11081.445927703582;10345.793084138426;10767.676969690921;10792;10972.396518191576;10986.945065274622;8233.300169485541;8411.86439830615;8426.747197584082;8428.747137586004;8440.62017209227;6076.088586712029;6076.90884636722;6081.452669259788;6114.2357205349335;6217.564770466024;9914.553845076845;10266.897331026594;10267.640632577824;10271.691849244535;10412.114970227312;9882.209423246266;10327.535260913428;10376.429296388771;10397.480125993821;10509.421981790769;5852.561057920588;6393.808185754435;6983.44132469391;7168.53404528704;7434.516756410816;8546.871796923175;8695.608697608497;8698.47809131454;8701.43440676354;8704.695335663222;7529.13414957265;7604.391648667983;7640.121386040676;7780.416468764752;7791.844163116819;6858.211305699909;7247.565146091251;7453.142888567667;7524.962375188089;7714.6914123434435;7048.823779405556;7150.821229469097;7163.391111755559;7164;7167.104111986002;4327.242732521733;4766.83212612912;4919.409670839522;4955.72743499111;4977.527134922177;4952.826651067196;4991.42598601151;5038.395392552917;5108.106081435808;5149.897002059863 20:50:06 INFO - PROCESS | 1917 | |4;prototype.html;265.25066686610324;314.11733030185184;319.4186580423629;324.0844613965548;328.24176153086387;5371.650842695222;5394.730263486825;5417.431169727181;5435.701036442999;5442.265294185294;4387.047763745135;4826.2519309507;5166.535011848933;5192.428832828386;5373.301470808799;3110.2068972412003;3581.9433267186114;3759.718021148411;3945.487086678728;4132.979335103335;3446.8448919798543;3841.481400010991;3841.7694938303653;4126.525449573308;4134.751914885116;3344;3383.6108847482487;3716.2010167813914;3898.3372826619543;4115.814788334517;3110.1136176189902;3443.001529556451;3732.869349572779;4091.8772436826944;4127.834886604547;3425.657434256579;3596;3603.297357015383;3610.7291953103622;3622.8662207190855;4721.504242054603;5207.869803254911;5232.92150617741;5245.554127899095;5254.789808407659;4331.480222373335;4717.646176536773;4735.952640473585;4744.928826067578;4755.571998520115;4920.606351491872;5357.544608708265;5589.664620122806;5634.535020894556;5641.492265696066;3386.1703882548686;3969.5435024972207;3992.680585553149;4014.959850401488;4033.1731994940965;4640.930386044212;5691.373948865621;5803.158542011384;5872.823815285548;5881.41185881417;4035.8385664573675;4049;4049.230646177217;4050.7772072535704;4064.6545043671026;4116.0327323079055;4120;4134.896627584334;4160.54234034256;4170.35359519275;4508.120916421266;4654.206821440583;4733.763311834395;4767.118083154627;4780.330753694416;4834.154023045953;5547.890421915633;5630.254621067482;5641.294838145232;5653.095504719226;1381.9447222111205;1405.6907480354305;1412.258564253775;1416.8583141685956;1436.9281535923162;771.8687823070092;838.4801423117584;867.4621734524635;898.910108989109;929.3958926697566;4188.434561334242;4577.244754615518;4802.358702713564;5230.555402790729;5276.894462110737;5689.402612725687;5695.658260504383;5702.887936852357;5713.314402271671;5720.713964301768;4995.375578052744;5203.32356793622;5218.791248350023;5293.814716484982;5419.83740487786;2386.9045238190624;2619.201143651205;2877.424515096989;3012.7554971546338;3060.9797533627698;5767.961766882002;6018.21763170785;6019.879602407927;6022.819315420545;6054.697265136726;4915.238138088602;5093.617978651616;5131.7690703918415;5947.434993675594;5982.371850955674;5653.434656534314;5788.884222315446;6011.398860113954;6016.909746353808;6022.277326720734;5054.71104868271;5374.871277031867;5569.66582005081;5590.468905453975;5741.799037033684;4789.4971028041555;5717.312333544757;5722.885542289047;5744.655320680606;5770.855728606818;4585.73006361853;5036.622253331088;5453.836384908459;6028.728707208098;6093.451589356801;3713.7586056906216;3744.7191460641106;3890.824912878984;3928.7642741435607;3945.723799334019;5720.856978575569;5753.9136912945505;5764.42355764437;5768.53851691872;5780.682062486603;4645.907081858276;4789.688670236424;5322.175062865262;5593.328800543961;5646.435356464321;4502.4371953505815;4853.272009198648;5580.665160090408;5655.773769049192;5661.518770904519;4944.579710724629;5120.948790512196;5186.792528298976;5377.934577290906;5503.817099004706;4503.90992180148;4943.431505376928;5295.258663786996;5651.265335506358;5695.829125126253;4136.937945930874;5211.697075731067;5440.633324503853;5545.306836645419;5574.581906357121 20:50:06 INFO - PROCESS | 1917 | |5;yui.html;717.6017310392733;786.9252421020001;822.1080128061054;838.7651457591872;855.3755758296439;15939.36242550298;16914.746278805796;17037.488875333744;17042.31830726771;17816.287348506045;16652.916735416336;16915.661686766256;17287.222075006623;17289.04910229937;17301.91349043253;709.4359983812881;783.5847001089431;889.6619284671816;889.6841621224469;891.3315013739696;726.0851327327553;881.6561540999015;901.733988473402;903.2773780975226;908.2280061947358;942.0202988891544;942.7218970403748;942.797298580805;944.3153713557684;946.2950102173891;936.1621348892737;936.2463217110258;937.1706040154444;937.2689302344114;938.174406522256;700.4396482813729;700.7232143303418;700.8948657701335;700.9509334346598;702.1363722621154;13804.861951380459;15666.764998525032;15673.529794106194;15695.843041569553;15729.292181851844;14415.42338306477;14419.927900360432;14597.708045839025;14701.779473307908;14828.70342593142;12045.578404755792;12249.387530623522;12272.509099636003;12282.815757763641;12351.258924464471;3850.191459793447;3953.3081710700512;3956.4658771065824;3994.6404823566018;4108.137291168858;122130;128540;130936;131195;131720.6827931718;11015.779684406427;11074.280171788809;11387.316760994367;11508.654740357802;11669.00813430851;20430.978451077386;21936;22943.541129177323;22957.19649812282;22958.311250662508;7899.565523896126;8191.221833925768;8261.545614991113;8491.702790402307;8553.700620478254;4492.9775351123035;4515.2549829277805;4694.788734506956;4830.6135509159185;4851.054044461296;3680.9815950920074;3785.6592906637984;3809.2191100824266;3811.1996480739354;3819.8281077351585;657.6580178307349;661.6923130744227;662.460095022555;667.0927538547571;687.7386593094592;584.33677775724;588.9204957330791;594.925634295713;615.5075939248583;637.0380725105032;8579.785505362415;9071.81856362869;9963;10025.899741002497;10114.140298074746;673.9022841688026;694.4964900447136;707.8088915992653;717.5371885134059;722.4978639845363;4619.745913974696;4836.250381190921;4860.222364421676;4862.902741945212;4885.658003939761;97204;100091;104091;135470.32264838612;160430.59354109698;109813.80372392667;113335.4333228345;113474.43262783633;130469;141371;102138;103518.96481035244;107155.39266911002;110936;113215.30177047515;10006.499675016366;10222.284440089123;10301.536430860479;10383.376997380181;10461.790764184825;61075.69462152661;61702.38297616966;62709.059364110486;63641;63663;89688;91902;92326;94709.63225919407;97888.14782965124;13035;13294;13521.661958451117;13727.107737996997;13779.311034448037;13381.66545836362;14540.694907187322;15443.845561544691;16283.592910176867;16406.671866562363;10732;14630.487932922297;14972.176530290939;15468.458603948808;15691.45079922243;14994.47519336818;15056;15402.306896189923;15604.297806598503;15651.765223521885;13355.399007044512;13420.530281440493;14157.929210353883;14569.125852448888;14845.777313340093;11503.654890353302;11833.822492662444;12046.337451440255;12275.851725948101;12447.066470014604;11640.650780476599;11908.761824763284;11951.282923024646;12250.571230006906;12263.69340766488 20:50:06 INFO - PROCESS | 1917 | __end_tp_report 20:50:06 INFO - PROCESS | 1917 | __start_cc_report 20:50:06 INFO - PROCESS | 1917 | _x_x_mozilla_cycle_collect,1155 20:50:06 INFO - PROCESS | 1917 | __end_cc_report 20:50:06 INFO - PROCESS | 1917 | __startTimestamp1483591806809__endTimestamp 20:50:06 INFO - PROCESS | 1917 | 20:50:06 INFO - PROCESS | 1917 | ------- Summary: start ------- 20:50:06 INFO - PROCESS | 1917 | Number of tests: 6 20:50:06 INFO - PROCESS | 1917 | 20:50:06 INFO - PROCESS | 1917 | [#0] dojo.html Cycles:165 Average:5856.31 Median:6128.51 stddev:615.09 (10.0%) stddev-sans-first:587.55 20:50:06 INFO - PROCESS | 1917 | Values: 3460.3 3506.4 3530.6 3543.8 3544.9 5916.6 5945.6 5953.7 5993.2 5994.8 5932.5 6000.3 6001.2 6022.1 6028.3 5297.5 5340.1 5346.3 5372.3 5471.5 5274.8 5303.0 5323.8 5423.7 5462.2 5397.9 5407.4 5416.4 5419.3 5419.3 5302.7 5345.7 5400.1 5404.5 5409.2 5193.8 5290.6 5323.1 5352.1 5359.2 5012.6 5966.6 6063.5 6080.0 6117.3 6167.4 6190.3 6209.7 6213.8 6241.2 6170.6 6224.7 6242.7 6273.2 6304.2 6051.2 6061.2 6067.6 6067.7 6079.2 6169.9 6245.5 6260.4 6308.1 6338.9 6268.6 6286.3 6286.3 6287.9 6310.8 6273.0 6281.1 6285.7 6289.6 6298.4 5266.2 6157.9 6172.8 6255.9 6263.9 6242.6 6244.4 6262.8 6265.0 6276.1 5040.8 5057.0 5091.1 5099.1 5099.9 4458.0 4527.3 4561.2 4561.4 4619.9 5784.3 5823.9 5840.2 5872.9 5876.3 6217.7 6239.6 6249.8 6250.1 6256.1 5800.4 5815.2 5816.5 5829.7 5835.2 5504.2 5524.9 5534.0 5547.3 5553.3 6258.2 6304.3 6336.3 6342.6 6344.7 6301.8 6310.4 6313.8 6322.0 6333.4 5463.9 6423.4 6465.8 6468.7 6471.6 6166.9 6241.9 6270.3 6294.2 6294.7 6201.4 6217.6 6265.1 6304.6 6317.3 6237.6 6258.2 6277.7 6279.5 6285.9 6118.6 6240.2 6260.3 6265.7 6300.8 6056.8 6094.5 6095.7 6144.5 6191.6 6242.5 6248.6 6270.2 6274.7 6281.7 6091.1 6128.5 6156.6 6160.0 6173.1 20:50:06 INFO - PROCESS | 1917 | 20:50:06 INFO - PROCESS | 1917 | [#1] ext.html Cycles:180 Average:76177.43 Median:37427.44 stddev:168847.77 (451.1%) stddev-sans-first:169225.73 20:50:06 INFO - PROCESS | 1917 | Values: 463.2 482.3 511.9 516.2 564.8 26024.9 32587.8 41436.4 42881.1 42897.7 44609.6 44839.0 44852.0 44865.6 44933.1 9925.9 10126.5 10128.9 10137.2 10148.3 9263.3 9488.1 9965.6 12258.2 12489.4 12282.2 12351.6 12373.9 12377.9 12381.4 12477.1 12533.2 12568.4 12571.4 12584.8 12337.1 12400.9 12406.1 12407.5 12433.3 42117.8 42194.0 42321.6 42358.6 42436.0 3421.0 3459.4 3477.1 3518.0 3552.7 32020.8 32132.7 32171.0 32187.5 32526.8 3268.7 3322.5 3404.3 3448.5 3507.9 554202.0 557169.0 558731.0 558968.0 559931.0 64288.7 64618.7 64737.7 64783.0 64837.7 36270.5 37047.9 37077.4 37794.4 68157.3 17316.9 17510.7 17528.1 17530.3 17562.1 12242.6 13358.2 13457.5 13472.2 13476.7 16603.9 17762.3 19430.4 20044.8 20625.6 8007.0 8201.5 8229.6 8233.4 8270.0 898.6 998.9 1040.3 1093.0 1114.9 30958.7 31140.8 31274.5 31597.0 31713.5 947.8 1072.8 1089.8 1093.5 1160.3 15335.1 15661.0 15889.2 16017.7 16029.8 732701.0 741114.0 741896.0 741938.0 743666.0 59654.7 60378.4 60399.7 60426.0 60452.0 1303.9 1343.3 1423.6 1442.5 1456.6 31213.2 31258.7 31817.2 32547.0 32982.8 51682.5 52195.7 52218.0 52229.5 52396.4 479713.0 560865.0 562334.0 562782.0 708017.0 42267.6 44358.1 44681.3 44815.8 45056.1 40258.6 48558.0 48786.5 48847.8 49145.0 39153.6 39782.0 40115.6 40283.6 41506.0 35729.6 35923.8 36026.1 37777.4 39650.4 39652.8 42107.2 42370.6 42820.6 42868.8 39599.0 42706.4 43884.6 45887.0 46144.5 30930.2 32923.2 32979.3 32992.5 34164.0 20:50:06 INFO - PROCESS | 1917 | 20:50:06 INFO - PROCESS | 1917 | [#2] jquery.html Cycles:180 Average:36904.87 Median:13007.37 stddev:107705.52 (828.0%) stddev-sans-first:107972.66 20:50:06 INFO - PROCESS | 1917 | Values: 335.1 368.1 388.2 389.9 409.6 15674.0 15789.3 17756.1 20684.9 21130.8 17235.6 20146.6 21287.3 22018.8 22026.4 5294.0 5601.0 5700.0 5790.4 5793.4 4163.9 6219.8 6316.0 6320.5 6330.1 4257.4 4281.8 4401.4 4403.4 4405.0 4373.8 7088.4 7095.4 7103.5 7106.2 6002.5 6202.8 6241.9 6246.3 6256.3 13395.9 13489.9 13496.8 21946.8 25014.0 2281.5 2294.9 2629.9 2691.6 2965.6 10080.4 10328.6 10420.0 10513.0 10741.4 1908.9 1924.6 1928.4 1991.8 1994.6 105252.5 127229.1 132878.3 134490.0 136343.0 11392.0 11698.5 11805.5 11824.5 11991.3 14883.0 15128.2 15145.0 15164.5 15171.2 8859.8 8938.7 8964.0 8981.1 9094.7 7441.3 8969.8 8977.9 9135.9 9138.9 4582.1 4606.2 4608.8 4616.7 4739.6 1803.5 1846.0 1908.4 1950.9 1952.2 803.4 844.7 915.0 939.3 984.5 22047.0 23719.9 23812.3 23925.5 23960.8 951.8 955.9 974.4 982.9 985.4 10723.0 11379.4 11380.8 11392.9 11429.1 629475.0 630005.0 643145.0 646971.0 672484.0 134157.3 136672.3 137815.0 140323.3 141808.0 1014.2 1044.4 1048.8 1054.3 1056.4 15703.9 19539.8 20175.8 20179.7 20237.3 45557.9 57282.0 58744.4 58795.5 58805.8 78261.6 79170.2 80161.6 80343.8 83679.6 8306.9 8424.2 8512.2 8537.1 8708.7 12430.3 13094.0 13157.0 13217.5 13623.6 11275.6 11338.1 11365.1 11546.5 11908.2 11176.3 11200.8 11312.4 11747.8 11829.8 10872.0 10879.1 11623.7 11761.5 12086.8 6942.3 7765.1 8065.6 8281.0 8663.2 8537.1 10750.2 10988.9 11168.7 11770.7 20:50:06 INFO - PROCESS | 1917 | 20:50:06 INFO - PROCESS | 1917 | [#3] mootools.html Cycles:180 Average:5800.57 Median:4744.01 stddev:3252.63 (68.6%) stddev-sans-first:3246.01 20:50:06 INFO - PROCESS | 1917 | Values: 1542.6 1561.8 1571.0 1895.8 1951.3 8684.5 9225.5 9230.0 9257.2 9289.2 8119.0 8357.1 8583.5 8885.2 8909.3 2348.4 2399.7 2401.2 2401.2 2404.5 2383.2 2389.5 2417.2 2418.7 2420.2 2389.9 2401.1 2401.9 2417.6 2423.8 2375.5 2390.1 2401.5 2403.2 2403.9 2383.5 2386.6 2387.3 2389.4 2391.2 7705.7 8818.6 8969.8 8986.1 8996.6 5382.9 5539.8 5541.7 5546.5 5549.8 3055.8 3641.9 3650.9 3663.4 3672.1 1066.7 1068.8 1069.9 1082.9 1085.5 11654.5 11731.4 11847.3 11853.8 11870.6 4191.5 4689.1 4721.2 4918.9 4948.7 10241.3 10251.3 10252.9 10253.9 10272.9 5453.9 5505.3 5534.4 5543.4 5545.5 4448.6 4599.6 4908.7 5016.3 5061.2 4059.1 4104.3 4173.8 4204.6 4206.9 1298.3 1317.1 1322.9 1329.6 1336.8 771.7 886.6 888.6 935.9 936.5 5754.8 5868.4 5967.4 6053.1 6163.2 925.0 949.4 966.4 1004.6 1030.9 3759.8 4015.8 4115.6 4136.2 4138.4 10658.1 11034.8 11045.3 11052.5 11081.4 10345.8 10767.7 10792.0 10972.4 10986.9 8233.3 8411.9 8426.7 8428.7 8440.6 6076.1 6076.9 6081.5 6114.2 6217.6 9914.6 10266.9 10267.6 10271.7 10412.1 9882.2 10327.5 10376.4 10397.5 10509.4 5852.6 6393.8 6983.4 7168.5 7434.5 8546.9 8695.6 8698.5 8701.4 8704.7 7529.1 7604.4 7640.1 7780.4 7791.8 6858.2 7247.6 7453.1 7525.0 7714.7 7048.8 7150.8 7163.4 7164.0 7167.1 4327.2 4766.8 4919.4 4955.7 4977.5 4952.8 4991.4 5038.4 5108.1 5149.9 20:50:06 INFO - PROCESS | 1917 | 20:50:06 INFO - PROCESS | 1917 | [#4] prototype.html Cycles:180 Average:4514.15 Median:5016.00 stddev:1390.51 (27.7%) stddev-sans-first:1357.35 20:50:06 INFO - PROCESS | 1917 | Values: 265.3 314.1 319.4 324.1 328.2 5371.7 5394.7 5417.4 5435.7 5442.3 4387.0 4826.3 5166.5 5192.4 5373.3 3110.2 3581.9 3759.7 3945.5 4133.0 3446.8 3841.5 3841.8 4126.5 4134.8 3344.0 3383.6 3716.2 3898.3 4115.8 3110.1 3443.0 3732.9 4091.9 4127.8 3425.7 3596.0 3603.3 3610.7 3622.9 4721.5 5207.9 5232.9 5245.6 5254.8 4331.5 4717.6 4736.0 4744.9 4755.6 4920.6 5357.5 5589.7 5634.5 5641.5 3386.2 3969.5 3992.7 4015.0 4033.2 4640.9 5691.4 5803.2 5872.8 5881.4 4035.8 4049.0 4049.2 4050.8 4064.7 4116.0 4120.0 4134.9 4160.5 4170.4 4508.1 4654.2 4733.8 4767.1 4780.3 4834.2 5547.9 5630.3 5641.3 5653.1 1381.9 1405.7 1412.3 1416.9 1436.9 771.9 838.5 867.5 898.9 929.4 4188.4 4577.2 4802.4 5230.6 5276.9 5689.4 5695.7 5702.9 5713.3 5720.7 4995.4 5203.3 5218.8 5293.8 5419.8 2386.9 2619.2 2877.4 3012.8 3061.0 5768.0 6018.2 6019.9 6022.8 6054.7 4915.2 5093.6 5131.8 5947.4 5982.4 5653.4 5788.9 6011.4 6016.9 6022.3 5054.7 5374.9 5569.7 5590.5 5741.8 4789.5 5717.3 5722.9 5744.7 5770.9 4585.7 5036.6 5453.8 6028.7 6093.5 3713.8 3744.7 3890.8 3928.8 3945.7 5720.9 5753.9 5764.4 5768.5 5780.7 4645.9 4789.7 5322.2 5593.3 5646.4 4502.4 4853.3 5580.7 5655.8 5661.5 4944.6 5120.9 5186.8 5377.9 5503.8 4503.9 4943.4 5295.3 5651.3 5695.8 4136.9 5211.7 5440.6 5545.3 5574.6 20:50:06 INFO - PROCESS | 1917 | 20:50:06 INFO - PROCESS | 1917 | [#5] yui.html Cycles:180 Average:24774.87 Median:19123.63 stddev:38126.66 (199.4%) stddev-sans-first:38190.83 20:50:06 INFO - PROCESS | 1917 | Values: 717.6 786.9 822.1 838.8 855.4 15939.4 16914.7 17037.5 17042.3 17816.3 16652.9 16915.7 17287.2 17289.0 17301.9 709.4 783.6 889.7 889.7 891.3 726.1 881.7 901.7 903.3 908.2 942.0 942.7 942.8 944.3 946.3 936.2 936.2 937.2 937.3 938.2 700.4 700.7 700.9 701.0 702.1 13804.9 15666.8 15673.5 15695.8 15729.3 14415.4 14419.9 14597.7 14701.8 14828.7 12045.6 12249.4 12272.5 12282.8 12351.3 3850.2 3953.3 3956.5 3994.6 4108.1 122130.0 128540.0 130936.0 131195.0 131720.7 11015.8 11074.3 11387.3 11508.7 11669.0 20431.0 21936.0 22943.5 22957.2 22958.3 7899.6 8191.2 8261.5 8491.7 8553.7 4493.0 4515.3 4694.8 4830.6 4851.1 3681.0 3785.7 3809.2 3811.2 3819.8 657.7 661.7 662.5 667.1 687.7 584.3 588.9 594.9 615.5 637.0 8579.8 9071.8 9963.0 10025.9 10114.1 673.9 694.5 707.8 717.5 722.5 4619.7 4836.3 4860.2 4862.9 4885.7 97204.0 100091.0 104091.0 135470.3 160430.6 109813.8 113335.4 113474.4 130469.0 141371.0 102138.0 103519.0 107155.4 110936.0 113215.3 10006.5 10222.3 10301.5 10383.4 10461.8 61075.7 61702.4 62709.1 63641.0 63663.0 89688.0 91902.0 92326.0 94709.6 97888.1 13035.0 13294.0 13521.7 13727.1 13779.3 13381.7 14540.7 15443.8 16283.6 16406.7 10732.0 14630.5 14972.2 15468.5 15691.5 14994.5 15056.0 15402.3 15604.3 15651.8 13355.4 13420.5 14157.9 14569.1 14845.8 11503.7 11833.8 12046.3 12275.9 12447.1 11640.7 11908.8 11951.3 12250.6 12263.7 20:50:06 INFO - PROCESS | 1917 | -------- Summary: end -------- 20:50:06 INFO - PROCESS | 1917 | 20:50:07 INFO - TEST-INFO | 1917: exit 0 20:50:07 INFO - TEST-OK | dromaeo_css | took 1115417ms 20:50:07 INFO - TEST-START | kraken 20:50:07 INFO - operating with platform_type : mac_ 20:50:07 INFO - Initialising browser for kraken test... 20:50:07 INFO - TEST-INFO | started process 1921 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/nt/w1wt5wh94cg0328j1nmkplv400000w/T/tmpReu9G_/profile http://localhost:51291/getInfo.html) 20:50:09 INFO - PROCESS | 1921 | __metrics Screen width/height:1600/1200 20:50:09 INFO - PROCESS | 1921 | colorDepth:24 20:50:09 INFO - PROCESS | 1921 | Browser inner width/height: 1024/689 20:50:09 INFO - PROCESS | 1921 | __metrics 20:50:09 INFO - PROCESS | 1921 | 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" 20:50:09 INFO - PROCESS | 1921 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 20:50:10 INFO - TEST-INFO | 1921: exit 0 20:50:10 INFO - Browser initialized. 20:50:10 INFO - Running cycle 1/1 for kraken test... 20:50:10 INFO - TEST-INFO | started process 1923 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/nt/w1wt5wh94cg0328j1nmkplv400000w/T/tmpReu9G_/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 20:50:22 INFO - PROCESS | 1923 | RSS: Main: 169160704 20:50:22 INFO - PROCESS | 1923 | 20:50:24 INFO - PROCESS | 1923 | Cycle 1(1): loaded http://localhost:51291/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:51291/tests/kraken/driver.html?testName=audio-beat-detection) 20:50:25 INFO - PROCESS | 1923 | RSS: Main: 218816512 20:50:25 INFO - PROCESS | 1923 | 20:50:28 INFO - PROCESS | 1923 | Cycle 1(1): loaded http://localhost:51291/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:51291/tests/kraken/driver.html?testName=audio-dft) 20:50:28 INFO - PROCESS | 1923 | RSS: Main: 232206336 20:50:28 INFO - PROCESS | 1923 | 20:50:31 INFO - PROCESS | 1923 | Cycle 1(1): loaded http://localhost:51291/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:51291/tests/kraken/driver.html?testName=audio-fft) 20:50:32 INFO - PROCESS | 1923 | RSS: Main: 329719808 20:50:32 INFO - PROCESS | 1923 | 20:50:34 INFO - PROCESS | 1923 | Cycle 1(1): loaded http://localhost:51291/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:51291/tests/kraken/driver.html?testName=audio-oscillator) 20:50:35 INFO - PROCESS | 1923 | RSS: Main: 229257216 20:50:35 INFO - PROCESS | 1923 | 20:50:37 INFO - PROCESS | 1923 | Cycle 1(1): loaded http://localhost:51291/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:51291/tests/kraken/driver.html?testName=imaging-gaussian-blur) 20:50:37 INFO - PROCESS | 1923 | RSS: Main: 230014976 20:50:37 INFO - PROCESS | 1923 | 20:50:40 INFO - PROCESS | 1923 | Cycle 1(1): loaded http://localhost:51291/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:51291/tests/kraken/driver.html?testName=imaging-darkroom) 20:50:40 INFO - PROCESS | 1923 | RSS: Main: 267370496 20:50:40 INFO - PROCESS | 1923 | 20:50:43 INFO - PROCESS | 1923 | Cycle 1(1): loaded http://localhost:51291/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:51291/tests/kraken/driver.html?testName=imaging-desaturate) 20:50:44 INFO - PROCESS | 1923 | RSS: Main: 268369920 20:50:44 INFO - PROCESS | 1923 | 20:50:47 INFO - PROCESS | 1923 | Cycle 1(1): loaded http://localhost:51291/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:51291/tests/kraken/driver.html?testName=json-parse-financial) 20:50:47 INFO - PROCESS | 1923 | RSS: Main: 266297344 20:50:47 INFO - PROCESS | 1923 | 20:50:49 INFO - PROCESS | 1923 | Cycle 1(1): loaded http://localhost:51291/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:51291/tests/kraken/driver.html?testName=json-stringify-tinderbox) 20:50:49 INFO - PROCESS | 1923 | RSS: Main: 240095232 20:50:49 INFO - PROCESS | 1923 | 20:50:51 INFO - PROCESS | 1923 | Cycle 1(1): loaded http://localhost:51291/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:51291/tests/kraken/driver.html?testName=stanford-crypto-aes) 20:50:52 INFO - PROCESS | 1923 | RSS: Main: 245960704 20:50:52 INFO - PROCESS | 1923 | 20:50:54 INFO - PROCESS | 1923 | Cycle 1(1): loaded http://localhost:51291/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:51291/tests/kraken/driver.html?testName=stanford-crypto-ccm) 20:50:55 INFO - PROCESS | 1923 | RSS: Main: 251207680 20:50:55 INFO - PROCESS | 1923 | 20:50:58 INFO - PROCESS | 1923 | Cycle 1(1): loaded http://localhost:51291/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:51291/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 20:50:58 INFO - PROCESS | 1923 | RSS: Main: 261148672 20:50:58 INFO - PROCESS | 1923 | 20:51:01 INFO - PROCESS | 1923 | Cycle 1(1): loaded http://localhost:51291/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:51291/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 20:51:02 INFO - PROCESS | 1923 | RSS: Main: 261615616 20:51:02 INFO - PROCESS | 1923 | 20:51:03 INFO - PROCESS | 1923 | Cycle 1(1): loaded http://localhost:51291/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:51291/tests/kraken/driver.html?testName=ai-astar) 20:51:04 INFO - PROCESS | 1923 | RSS: Main: 305078272 20:51:04 INFO - PROCESS | 1923 | 20:51:04 INFO - PROCESS | 1923 | __start_tp_report 20:51:04 INFO - PROCESS | 1923 | _x_x_mozilla_page_load 20:51:04 INFO - PROCESS | 1923 | _x_x_mozilla_page_load_details 20:51:04 INFO - PROCESS | 1923 | |i|pagename|runs| 20:51:04 INFO - PROCESS | 1923 | |0;ai-astar;114;110;110;111;112;111;112;111;109;111 20:51:04 INFO - PROCESS | 1923 | |1;audio-beat-detection;142;107;110;143;108;109;108;107;138;111 20:51:04 INFO - PROCESS | 1923 | |2;audio-dft;181;184;180;184;183;186;184;184;184;184 20:51:04 INFO - PROCESS | 1923 | |3;audio-fft;78;58;58;97;54;58;49;80;99;81 20:51:04 INFO - PROCESS | 1923 | |4;audio-oscillator;82;72;92;69;67;69;93;67;68;89 20:51:04 INFO - PROCESS | 1923 | |5;imaging-gaussian-blur;86;83;86;84;86;83;86;83;85;88 20:51:04 INFO - PROCESS | 1923 | |6;imaging-darkroom;115;122;92;92;91;93;92;92;92;93 20:51:04 INFO - PROCESS | 1923 | |7;imaging-desaturate;82;85;106;134;136;86;85;86;87;83 20:51:04 INFO - PROCESS | 1923 | |8;json-parse-financial;56;55;54;52;55;57;56;56;56;56 20:51:04 INFO - PROCESS | 1923 | |9;json-stringify-tinderbox;64;67;68;76;68;68;70;67;68;68 20:51:04 INFO - PROCESS | 1923 | |10;stanford-crypto-aes;82;81;100;100;99;84;80;83;83;81 20:51:04 INFO - PROCESS | 1923 | |11;stanford-crypto-ccm;144;142;147;145;134;134;143;153;173;135 20:51:04 INFO - PROCESS | 1923 | |12;stanford-crypto-pbkdf2;161;161;160;160;162;163;162;161;160;162 20:51:04 INFO - PROCESS | 1923 | |13;stanford-crypto-sha256-iterative;67;66;66;65;66;67;65;66;65;66 20:51:04 INFO - PROCESS | 1923 | __end_tp_report 20:51:04 INFO - PROCESS | 1923 | __start_cc_report 20:51:04 INFO - PROCESS | 1923 | _x_x_mozilla_cycle_collect,1868 20:51:04 INFO - PROCESS | 1923 | __end_cc_report 20:51:04 INFO - PROCESS | 1923 | __startTimestamp1483591864115__endTimestamp 20:51:04 INFO - PROCESS | 1923 | 20:51:04 INFO - PROCESS | 1923 | ------- Summary: start ------- 20:51:04 INFO - PROCESS | 1923 | Number of tests: 14 20:51:04 INFO - PROCESS | 1923 | 20:51:04 INFO - PROCESS | 1923 | [#0] ai-astar Cycles:10 Average:111.10 Median:111.00 stddev:1.37 (1.2%) stddev-sans-first:0.97 20:51:04 INFO - PROCESS | 1923 | Values: 114.0 110.0 110.0 111.0 112.0 111.0 112.0 111.0 109.0 111.0 20:51:04 INFO - PROCESS | 1923 | 20:51:04 INFO - PROCESS | 1923 | [#1] audio-beat-detection Cycles:10 Average:118.30 Median:110.50 stddev:15.76 (14.3%) stddev-sans-first:14.20 20:51:04 INFO - PROCESS | 1923 | Values: 142.0 107.0 110.0 143.0 108.0 109.0 108.0 107.0 138.0 111.0 20:51:04 INFO - PROCESS | 1923 | 20:51:04 INFO - PROCESS | 1923 | [#2] audio-dft Cycles:10 Average:183.40 Median:184.00 stddev:1.71 (0.9%) stddev-sans-first:1.58 20:51:04 INFO - PROCESS | 1923 | Values: 181.0 184.0 180.0 184.0 183.0 186.0 184.0 184.0 184.0 184.0 20:51:04 INFO - PROCESS | 1923 | 20:51:04 INFO - PROCESS | 1923 | [#3] audio-fft Cycles:10 Average:71.20 Median:79.00 stddev:18.16 (23.0%) stddev-sans-first:19.10 20:51:04 INFO - PROCESS | 1923 | Values: 78.0 58.0 58.0 97.0 54.0 58.0 49.0 80.0 99.0 81.0 20:51:04 INFO - PROCESS | 1923 | 20:51:04 INFO - PROCESS | 1923 | [#4] audio-oscillator Cycles:10 Average:76.80 Median:77.00 stddev:10.97 (14.3%) stddev-sans-first:11.48 20:51:04 INFO - PROCESS | 1923 | Values: 82.0 72.0 92.0 69.0 67.0 69.0 93.0 67.0 68.0 89.0 20:51:04 INFO - PROCESS | 1923 | 20:51:04 INFO - PROCESS | 1923 | [#5] imaging-gaussian-blur Cycles:10 Average:85.00 Median:86.00 stddev:1.70 (2.0%) stddev-sans-first:1.76 20:51:04 INFO - PROCESS | 1923 | Values: 86.0 83.0 86.0 84.0 86.0 83.0 86.0 83.0 85.0 88.0 20:51:04 INFO - PROCESS | 1923 | 20:51:04 INFO - PROCESS | 1923 | [#6] imaging-darkroom Cycles:10 Average:97.40 Median:92.00 stddev:11.26 (12.2%) stddev-sans-first:9.98 20:51:04 INFO - PROCESS | 1923 | Values: 115.0 122.0 92.0 92.0 91.0 93.0 92.0 92.0 92.0 93.0 20:51:04 INFO - PROCESS | 1923 | 20:51:04 INFO - PROCESS | 1923 | [#7] imaging-desaturate Cycles:10 Average:97.00 Median:85.00 stddev:21.14 (24.9%) stddev-sans-first:21.71 20:51:04 INFO - PROCESS | 1923 | Values: 82.0 85.0 106.0 134.0 136.0 86.0 85.0 86.0 87.0 83.0 20:51:04 INFO - PROCESS | 1923 | 20:51:04 INFO - PROCESS | 1923 | [#8] json-parse-financial Cycles:10 Average:55.30 Median:56.00 stddev:1.42 (2.5%) stddev-sans-first:1.48 20:51:04 INFO - PROCESS | 1923 | Values: 56.0 55.0 54.0 52.0 55.0 57.0 56.0 56.0 56.0 56.0 20:51:04 INFO - PROCESS | 1923 | 20:51:04 INFO - PROCESS | 1923 | [#9] json-stringify-tinderbox Cycles:10 Average:68.40 Median:68.00 stddev:3.06 (4.5%) stddev-sans-first:2.80 20:51:04 INFO - PROCESS | 1923 | Values: 64.0 67.0 68.0 76.0 68.0 68.0 70.0 67.0 68.0 68.0 20:51:04 INFO - PROCESS | 1923 | 20:51:04 INFO - PROCESS | 1923 | [#10] stanford-crypto-aes Cycles:10 Average:87.30 Median:82.50 stddev:8.62 (10.4%) stddev-sans-first:8.92 20:51:04 INFO - PROCESS | 1923 | Values: 82.0 81.0 100.0 100.0 99.0 84.0 80.0 83.0 83.0 81.0 20:51:04 INFO - PROCESS | 1923 | 20:51:04 INFO - PROCESS | 1923 | [#11] stanford-crypto-ccm Cycles:10 Average:145.00 Median:144.50 stddev:11.59 (8.0%) stddev-sans-first:12.28 20:51:04 INFO - PROCESS | 1923 | Values: 144.0 142.0 147.0 145.0 134.0 134.0 143.0 153.0 173.0 135.0 20:51:04 INFO - PROCESS | 1923 | 20:51:04 INFO - PROCESS | 1923 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:161.20 Median:161.50 stddev:1.03 (0.6%) stddev-sans-first:1.09 20:51:04 INFO - PROCESS | 1923 | Values: 161.0 161.0 160.0 160.0 162.0 163.0 162.0 161.0 160.0 162.0 20:51:04 INFO - PROCESS | 1923 | 20:51:04 INFO - PROCESS | 1923 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:65.90 Median:66.00 stddev:0.74 (1.1%) stddev-sans-first:0.67 20:51:04 INFO - PROCESS | 1923 | Values: 67.0 66.0 66.0 65.0 66.0 67.0 65.0 66.0 65.0 66.0 20:51:04 INFO - PROCESS | 1923 | -------- Summary: end -------- 20:51:04 INFO - PROCESS | 1923 | 20:51:04 INFO - TEST-INFO | 1923: exit 0 20:51:05 INFO - TEST-OK | kraken | took 57641ms 20:51:05 INFO - SUITE-END | took 1173s 20:51:05 INFO - Completed test suite (00:19:33) 20:51:05 INFO - javascript benchmark 20:51:05 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3460.2733425980546, 3506.3513250048727, 3530.5763308402998, 3543.8482493189713, 3544.9291014179717, 5916.585838991267, 5945.554083443737, 5953.732082056307, 5993.160957465952, 5994.760209591616, 5932.525397968163, 6000.309964354101, 6001.21984142061, 6022.126791615224, 6028.27660680719, 5297.496737809912, 5340.092184328673, 5346.27825243591, 5372.274742909716, 5471.50756431921, 5274.7626356814035, 5302.973485132588, 5323.787048518055, 5423.674579525222, 5462.180672899077, 5397.865053373677, 5407.351117865862, 5416.350037995446, 5419.268398766175, 5419.268398766175, 5302.681839089667, 5345.679259244458, 5400.081986062379, 5404.540614047791, 5409.161579955114, 5193.792248310101, 5290.55030322423, 5323.095073837457, 5352.143657014898, 5359.222912677679, 5012.624053196025, 5966.612170208922, 6063.54523410746, 6080.0, 6117.327094019654, 6167.383261673842, 6190.288116866573, 6209.658468784215, 6213.844653883644, 6241.21984751906, 6170.6297622142365, 6224.719887605069, 6242.687865606701, 6273.247210334789, 6304.1804565406665, 6051.152838602599, 6061.212042434543, 6067.635941843415, 6067.7269522871575, 6079.209702738615, 6169.9383006170265, 6245.469135123474, 6260.436560709467, 6308.116863639094, 6338.936610633835, 6268.592541484789, 6286.339934306832, 6286.339934306923, 6287.9056814147825, 6310.810675679737, 6272.968635156886, 6281.120643109968, 6285.717142728497, 6289.559730818799, 6298.401651843067, 5266.236395722675, 6157.922363586355, 6172.845678857974, 6255.874882502416, 6263.937360626335, 6242.563020588515, 6244.438000579969, 6262.780802671885, 6264.968675156595, 6276.058591211355, 5040.823571174991, 5056.9633225188745, 5091.0836049511445, 5099.107656160157, 5099.949000509948, 4458.0, 4527.275635898241, 4561.24739417999, 4561.429821272341, 4619.8845028873875, 5784.305883293948, 5823.912641310384, 5840.2115714378015, 5872.853178670482, 5876.324222714443, 6217.6891155442045, 6239.594426362272, 6249.843753906189, 6250.062490626442, 6256.061590761422, 5800.361960184377, 5815.2149459822385, 5816.5055970243, 5829.7085145743395, 5835.2122463466885, 5504.2018907258225, 5524.917126243109, 5533.972330138324, 5547.334319881559, 5553.250311207935, 6258.15514905491, 6304.306526282014, 6336.334684858116, 6342.61944283326, 6344.746210151635, 6301.779437719658, 6310.368963103837, 6313.779017734541, 6322.0200868865395, 6333.429991300805, 5463.918041229305, 6423.389777971085, 6465.77369792055, 6468.676566171766, 6471.611703297817, 6166.938330616816, 6241.906371404523, 6270.278917924315, 6294.213223347082, 6294.748210071546, 6201.410865967726, 6217.595856269328, 6265.091561723616, 6304.590201636879, 6317.336679648754, 6237.563370564017, 6258.217722784652, 6277.74889004453, 6279.497640188865, 6285.874282514233, 6118.619179173164, 6240.188775459162, 6260.28006779208, 6265.749370025148, 6300.842478938063, 6056.757729690763, 6094.512439004958, 6095.664738439428, 6144.539159562962, 6191.597546159485, 6242.53181011435, 6248.593841400295, 6270.184875966278, 6274.654893980693, 6281.654509002049, 6091.147239386578, 6128.50971922254, 6156.5998210116195, 6159.969200153971, 6173.104899789595], "value": 5819.38138229897, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [463.21716299453925, 482.2838085443116, 511.9479469689786, 516.161237988269, 564.7515093358925, 26024.86987565062, 32587.83706081469, 41436.378454323174, 42881.142377152515, 42897.71306860799, 44609.55390446095, 44839.0, 44852.0, 44865.55134448663, 44933.10133797322, 9925.851112233304, 10126.544305506233, 10128.898711012906, 10137.18902487803, 10148.289619726622, 9263.305252106085, 9488.051194880525, 9965.55155018026, 12258.20321679088, 12489.375531223448, 12282.201656892274, 12351.567695130672, 12373.938130309336, 12377.876221237762, 12381.38093095346, 12477.126601137925, 12533.185342952771, 12568.43442045101, 12571.434285457222, 12584.81122783159, 12337.136400451973, 12400.875991240062, 12406.131570790048, 12407.503699851995, 12433.316167610777, 42117.789411053054, 42194.0, 42321.57678423238, 42358.57641423577, 42436.0, 3420.9737078876237, 3459.446488561818, 3477.0959550516795, 3517.9648203517895, 3552.6980206682465, 32020.839895800604, 32132.678673213202, 32171.0, 32187.51718724221, 32526.83736581302, 3268.7385009199443, 3322.4850148227065, 3404.285100128976, 3448.534447849533, 3507.9123021924656, 554202.0, 557169.0, 558731.0, 558968.0, 559931.0, 64288.678556606916, 64618.67690661517, 64737.70524589576, 64783.0, 64837.675811621586, 36270.45594316087, 37047.85093267049, 37077.44383834245, 37794.43308350377, 68157.3184268151, 17316.913415433093, 17510.73733893967, 17528.12359382026, 17530.298788048334, 17562.121893905252, 12242.571509997108, 13358.198508089547, 13457.528986485426, 13472.191668499921, 13476.730465390638, 16603.916980415022, 17762.28950841952, 19430.402666918035, 20044.797312161318, 20625.587488250152, 8007.0, 8201.466904651297, 8229.588520573947, 8233.38249631268, 8270.048944371354, 898.6135961536602, 998.8951160128228, 1040.2822052783556, 1092.9672109836717, 1114.899659030691, 30958.69041309558, 31140.844295778377, 31274.458064066763, 31596.998285162874, 31713.524297135562, 947.7772723409993, 1072.790805792863, 1089.760252744395, 1093.5407129005678, 1160.257435241446, 15335.079895206323, 15661.0, 15889.205539723198, 16017.67964640724, 16029.839701602834, 732701.0, 741114.0, 741896.0, 741938.0, 743666.0, 59654.70172649109, 60378.396216038156, 60399.698001509714, 60426.0, 60452.0, 1303.9348032598332, 1343.2544937559749, 1423.555091582045, 1442.473497173524, 1456.6431224350101, 31213.219669507536, 31258.68741312558, 31817.204569885012, 32547.0, 32982.835085825376, 51682.48317516777, 52195.739021306166, 52218.0, 52229.47770522246, 52396.42810715685, 479713.0, 560865.0, 562334.0, 562782.0, 708017.0, 42267.57732422637, 44358.11283774242, 44681.32978005268, 44815.775921120185, 45056.0988780216, 40258.597414025484, 48558.0, 48786.51213487962, 48847.755761220964, 49145.0, 39153.608463915, 39782.0, 40115.59884401118, 40283.59716402798, 41506.0, 35729.64270357263, 35923.82038089793, 36026.09934751652, 37777.433338499395, 39650.40524392079, 39652.80173599113, 42107.1578568433, 42370.576294236664, 42820.571794281655, 42868.78565607152, 39599.0, 42706.359404609575, 43884.561154388044, 45887.0, 46144.538554614024, 30930.226744331572, 32923.17692057718, 32979.34041319208, 32992.505112422856, 34164.0], "value": 21518.433954678025, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [335.1049813305756, 368.14957448294456, 388.1886856469977, 389.9337112690842, 409.64565650712126, 15674.0, 15789.28948197331, 17756.112194390276, 20684.8965755171, 21130.788692113074, 17235.569110772238, 20146.59706805863, 21287.25494607689, 22018.779812201872, 22026.44933876654, 5294.0, 5601.047821870282, 5700.0, 5790.36306006339, 5793.4206579341935, 4163.937540936889, 6219.751209951596, 6316.0, 6320.494360451175, 6330.050492426127, 4257.382679511469, 4281.785910704452, 4401.405810215612, 4403.4495688039, 4405.043883357751, 4373.825046998132, 7088.432925365958, 7095.432365410755, 7103.502754807166, 7106.182788979256, 6002.54980876436, 6202.81391558254, 6241.906371404432, 6246.3441338659695, 6256.280527739323, 13395.86604133956, 13489.865101349058, 13496.797548036788, 21946.780532194633, 25014.0, 2281.4524514116492, 2294.8508346957396, 2629.895443913561, 2691.596260560912, 2965.644122705289, 10080.395176289447, 10328.638497652546, 10420.010099040579, 10513.0, 10741.409222492759, 1908.9427317180507, 1924.5765931495048, 1928.363639998797, 1991.7709463411604, 1994.58113796103, 105252.47373763159, 127229.09156362661, 132878.33560832232, 134490.0, 136343.0, 11392.0, 11698.532058717556, 11805.52777888892, 11824.467898944567, 11991.280523168638, 14883.0, 15128.167950762814, 15145.0, 15164.545063648107, 15171.24143792806, 8859.822803544022, 8938.68714594986, 8964.027913858941, 8981.14679105484, 9094.727158185266, 7441.255874412624, 8969.820603588023, 8977.910220897838, 9135.908640913638, 9138.862917056249, 4582.075528278131, 4606.216943119678, 4608.815647374067, 4616.722996620214, 4739.597134243628, 1803.4769916724265, 1846.0123833749149, 1908.3988543608718, 1950.8634395592455, 1952.2386269354963, 803.377382528545, 844.6705784743955, 914.9817003660023, 939.2673714502562, 984.542187882638, 22047.0, 23719.881400593233, 23812.285631431085, 23925.52148957011, 23960.7603923962, 951.8001219743793, 955.8852937647526, 974.3958745577787, 982.8722266105503, 985.3693636072919, 10723.001527747576, 11379.431028448544, 11380.772384552263, 11392.91767282107, 11429.142814288962, 629475.0, 630005.0, 643145.0, 646971.0, 672484.0, 134157.3292133533, 136672.31663841815, 137815.0, 140323.29838350744, 141808.0, 1014.1683819268277, 1044.4203467075695, 1048.8374301983206, 1054.288355360129, 1056.3661802918189, 15703.921480392524, 19539.804601953798, 20175.79824201739, 20179.697304540736, 20237.291694790612, 45557.86105347393, 57282.0, 58744.412555873896, 58795.53011174755, 58805.82388352294, 78261.60869195845, 79170.2082979186, 80161.59919200366, 80343.79484307623, 83679.5816020916, 8306.916930830614, 8424.199701028392, 8512.191341822518, 8537.146285371662, 8708.738737837875, 12430.254184748945, 13094.0, 13157.0, 13217.537386191438, 13623.591292261248, 11275.605353812578, 11338.09295256394, 11365.090792736395, 11546.538138474367, 11908.225965312227, 11176.329420234812, 11200.767915529448, 11312.434378281218, 11747.765044699228, 11829.82255266189, 10872.0, 10879.129669626569, 11623.6512904613, 11761.529538818351, 12086.818697719717, 6942.271061538668, 7765.0681918170185, 8065.556394398364, 8281.0, 8663.176998185163, 8537.146285371413, 10750.24748267645, 10988.890111098786, 11168.720781980515, 11770.705732356759], "value": 9676.471081036312, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1542.637480192155, 1561.7501199808032, 1570.97643535347, 1895.8388536974358, 1951.2585217617311, 8684.522351270678, 9225.538723063844, 9230.0, 9257.16685498305, 9289.210417114537, 8119.025716913964, 8357.080721120687, 8583.48499090055, 8885.244754195885, 8909.331800114986, 2348.389418751124, 2399.664047033417, 2401.1595941420537, 2401.171595799455, 2404.5311164322966, 2383.2016274548046, 2389.5101504191684, 2417.153996101368, 2418.697662792151, 2420.2376251480814, 2389.8685572293516, 2401.0755858994326, 2401.8799060047013, 2417.601095819188, 2423.8060955123547, 2375.489269807, 2390.1395497620842, 2401.5317013182434, 2403.194929698553, 2403.927882163538, 2383.5232953409386, 2386.606209975352, 2387.307680772574, 2389.3787615220167, 2391.2109004028803, 7705.730299439493, 8818.559072046437, 8969.775755606097, 8986.101389860962, 8996.550172491414, 5382.865428364322, 5539.750711218005, 5541.722913854291, 5546.473085056913, 5549.750261238213, 3055.8319292438905, 3641.9453708194396, 3650.926981460356, 3663.3955397359405, 3672.137047793766, 1066.7333166708322, 1068.759529105945, 1069.9251052426334, 1082.9241953063288, 1085.5440714899762, 11654.533818647244, 11731.354775487345, 11847.289162650182, 11853.762924741457, 11870.643880683594, 4191.497020357516, 4689.109069276827, 4721.197396442613, 4918.926216106761, 4948.65359424844, 10241.334313269614, 10251.282410231212, 10252.948735256276, 10253.948730256301, 10272.948635256926, 5453.945460545344, 5505.256790333334, 5534.446555344433, 5543.417941116206, 5545.528630066409, 4448.644108471314, 4599.632029437637, 4908.730019848871, 5016.322796422509, 5061.215511595708, 4059.0867054913, 4104.302268614343, 4173.812178451977, 4204.558521355275, 4206.8527601534415, 1298.3313593499356, 1317.0648839323997, 1322.9470821167238, 1329.6144118205611, 1336.779431393809, 771.7376092128703, 886.5567216391804, 888.6401007591936, 935.8783358163395, 936.5176933879058, 5754.827355179351, 5868.354481007086, 5967.403259673998, 6053.092036194606, 6163.1679723237685, 924.9537523123817, 949.4208532794989, 966.4394651102343, 1004.6232662751469, 1030.9381337222674, 3759.774413535197, 4015.819288132041, 4115.609017143366, 4136.21411931732, 4138.358554424069, 10658.147348212124, 11034.779304413867, 11045.28205666629, 11052.502637381338, 11081.445927703582, 10345.793084138426, 10767.676969690921, 10792.0, 10972.396518191576, 10986.945065274622, 8233.300169485541, 8411.86439830615, 8426.747197584082, 8428.747137586004, 8440.62017209227, 6076.088586712029, 6076.90884636722, 6081.452669259788, 6114.2357205349335, 6217.564770466024, 9914.553845076845, 10266.897331026594, 10267.640632577824, 10271.691849244535, 10412.114970227312, 9882.209423246266, 10327.535260913428, 10376.429296388771, 10397.480125993821, 10509.421981790769, 5852.561057920588, 6393.808185754435, 6983.44132469391, 7168.53404528704, 7434.516756410816, 8546.871796923175, 8695.608697608497, 8698.47809131454, 8701.43440676354, 8704.695335663222, 7529.13414957265, 7604.391648667983, 7640.121386040676, 7780.416468764752, 7791.844163116819, 6858.211305699909, 7247.565146091251, 7453.142888567667, 7524.962375188089, 7714.6914123434435, 7048.823779405556, 7150.821229469097, 7163.391111755559, 7164.0, 7167.104111986002, 4327.242732521733, 4766.83212612912, 4919.409670839522, 4955.72743499111, 4977.527134922177, 4952.826651067196, 4991.42598601151, 5038.395392552917, 5108.106081435808, 5149.897002059863], "value": 4665.648152252828, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [265.25066686610324, 314.11733030185184, 319.4186580423629, 324.0844613965548, 328.24176153086387, 5371.650842695222, 5394.730263486825, 5417.431169727181, 5435.701036442999, 5442.265294185294, 4387.047763745135, 4826.2519309507, 5166.535011848933, 5192.428832828386, 5373.301470808799, 3110.2068972412003, 3581.9433267186114, 3759.718021148411, 3945.487086678728, 4132.979335103335, 3446.8448919798543, 3841.481400010991, 3841.7694938303653, 4126.525449573308, 4134.751914885116, 3344.0, 3383.6108847482487, 3716.2010167813914, 3898.3372826619543, 4115.814788334517, 3110.1136176189902, 3443.001529556451, 3732.869349572779, 4091.8772436826944, 4127.834886604547, 3425.657434256579, 3596.0, 3603.297357015383, 3610.7291953103622, 3622.8662207190855, 4721.504242054603, 5207.869803254911, 5232.92150617741, 5245.554127899095, 5254.789808407659, 4331.480222373335, 4717.646176536773, 4735.952640473585, 4744.928826067578, 4755.571998520115, 4920.606351491872, 5357.544608708265, 5589.664620122806, 5634.535020894556, 5641.492265696066, 3386.1703882548686, 3969.5435024972207, 3992.680585553149, 4014.959850401488, 4033.1731994940965, 4640.930386044212, 5691.373948865621, 5803.158542011384, 5872.823815285548, 5881.41185881417, 4035.8385664573675, 4049.0, 4049.230646177217, 4050.7772072535704, 4064.6545043671026, 4116.0327323079055, 4120.0, 4134.896627584334, 4160.54234034256, 4170.35359519275, 4508.120916421266, 4654.206821440583, 4733.763311834395, 4767.118083154627, 4780.330753694416, 4834.154023045953, 5547.890421915633, 5630.254621067482, 5641.294838145232, 5653.095504719226, 1381.9447222111205, 1405.6907480354305, 1412.258564253775, 1416.8583141685956, 1436.9281535923162, 771.8687823070092, 838.4801423117584, 867.4621734524635, 898.910108989109, 929.3958926697566, 4188.434561334242, 4577.244754615518, 4802.358702713564, 5230.555402790729, 5276.894462110737, 5689.402612725687, 5695.658260504383, 5702.887936852357, 5713.314402271671, 5720.713964301768, 4995.375578052744, 5203.32356793622, 5218.791248350023, 5293.814716484982, 5419.83740487786, 2386.9045238190624, 2619.201143651205, 2877.424515096989, 3012.7554971546338, 3060.9797533627698, 5767.961766882002, 6018.21763170785, 6019.879602407927, 6022.819315420545, 6054.697265136726, 4915.238138088602, 5093.617978651616, 5131.7690703918415, 5947.434993675594, 5982.371850955674, 5653.434656534314, 5788.884222315446, 6011.398860113954, 6016.909746353808, 6022.277326720734, 5054.71104868271, 5374.871277031867, 5569.66582005081, 5590.468905453975, 5741.799037033684, 4789.4971028041555, 5717.312333544757, 5722.885542289047, 5744.655320680606, 5770.855728606818, 4585.73006361853, 5036.622253331088, 5453.836384908459, 6028.728707208098, 6093.451589356801, 3713.7586056906216, 3744.7191460641106, 3890.824912878984, 3928.7642741435607, 3945.723799334019, 5720.856978575569, 5753.9136912945505, 5764.42355764437, 5768.53851691872, 5780.682062486603, 4645.907081858276, 4789.688670236424, 5322.175062865262, 5593.328800543961, 5646.435356464321, 4502.4371953505815, 4853.272009198648, 5580.665160090408, 5655.773769049192, 5661.518770904519, 4944.579710724629, 5120.948790512196, 5186.792528298976, 5377.934577290906, 5503.817099004706, 4503.90992180148, 4943.431505376928, 5295.258663786996, 5651.265335506358, 5695.829125126253, 4136.937945930874, 5211.697075731067, 5440.633324503853, 5545.306836645419, 5574.581906357121], "value": 4076.1064988506596, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [717.6017310392733, 786.9252421020001, 822.1080128061054, 838.7651457591872, 855.3755758296439, 15939.36242550298, 16914.746278805796, 17037.488875333744, 17042.31830726771, 17816.287348506045, 16652.916735416336, 16915.661686766256, 17287.222075006623, 17289.04910229937, 17301.91349043253, 709.4359983812881, 783.5847001089431, 889.6619284671816, 889.6841621224469, 891.3315013739696, 726.0851327327553, 881.6561540999015, 901.733988473402, 903.2773780975226, 908.2280061947358, 942.0202988891544, 942.7218970403748, 942.797298580805, 944.3153713557684, 946.2950102173891, 936.1621348892737, 936.2463217110258, 937.1706040154444, 937.2689302344114, 938.174406522256, 700.4396482813729, 700.7232143303418, 700.8948657701335, 700.9509334346598, 702.1363722621154, 13804.861951380459, 15666.764998525032, 15673.529794106194, 15695.843041569553, 15729.292181851844, 14415.42338306477, 14419.927900360432, 14597.708045839025, 14701.779473307908, 14828.70342593142, 12045.578404755792, 12249.387530623522, 12272.509099636003, 12282.815757763641, 12351.258924464471, 3850.191459793447, 3953.3081710700512, 3956.4658771065824, 3994.6404823566018, 4108.137291168858, 122130.0, 128540.0, 130936.0, 131195.0, 131720.6827931718, 11015.779684406427, 11074.280171788809, 11387.316760994367, 11508.654740357802, 11669.00813430851, 20430.978451077386, 21936.0, 22943.541129177323, 22957.19649812282, 22958.311250662508, 7899.565523896126, 8191.221833925768, 8261.545614991113, 8491.702790402307, 8553.700620478254, 4492.9775351123035, 4515.2549829277805, 4694.788734506956, 4830.6135509159185, 4851.054044461296, 3680.9815950920074, 3785.6592906637984, 3809.2191100824266, 3811.1996480739354, 3819.8281077351585, 657.6580178307349, 661.6923130744227, 662.460095022555, 667.0927538547571, 687.7386593094592, 584.33677775724, 588.9204957330791, 594.925634295713, 615.5075939248583, 637.0380725105032, 8579.785505362415, 9071.81856362869, 9963.0, 10025.899741002497, 10114.140298074746, 673.9022841688026, 694.4964900447136, 707.8088915992653, 717.5371885134059, 722.4978639845363, 4619.745913974696, 4836.250381190921, 4860.222364421676, 4862.902741945212, 4885.658003939761, 97204.0, 100091.0, 104091.0, 135470.32264838612, 160430.59354109698, 109813.80372392667, 113335.4333228345, 113474.43262783633, 130469.0, 141371.0, 102138.0, 103518.96481035244, 107155.39266911002, 110936.0, 113215.30177047515, 10006.499675016366, 10222.284440089123, 10301.536430860479, 10383.376997380181, 10461.790764184825, 61075.69462152661, 61702.38297616966, 62709.059364110486, 63641.0, 63663.0, 89688.0, 91902.0, 92326.0, 94709.63225919407, 97888.14782965124, 13035.0, 13294.0, 13521.661958451117, 13727.107737996997, 13779.311034448037, 13381.66545836362, 14540.694907187322, 15443.845561544691, 16283.592910176867, 16406.671866562363, 10732.0, 14630.487932922297, 14972.176530290939, 15468.458603948808, 15691.45079922243, 14994.47519336818, 15056.0, 15402.306896189923, 15604.297806598503, 15651.765223521885, 13355.399007044512, 13420.530281440493, 14157.929210353883, 14569.125852448888, 14845.777313340093, 11503.654890353302, 11833.822492662444, 12046.337451440255, 12275.851725948101, 12447.066470014604, 11640.650780476599, 11908.761824763284, 11951.282923024646, 12250.571230006906, 12263.69340766488], "value": 8139.2494397068895, "unit": "score"}], "name": "dromaeo_css", "value": 7565.990102397458, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 110.0, 110.0, 111.0, 112.0, 111.0, 112.0, 111.0, 109.0, 111.0], "value": 111.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [142.0, 107.0, 110.0, 143.0, 108.0, 109.0, 108.0, 107.0, 138.0, 111.0], "value": 118.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [181.0, 184.0, 180.0, 184.0, 183.0, 186.0, 184.0, 184.0, 184.0, 184.0], "value": 183.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 58.0, 58.0, 97.0, 54.0, 58.0, 49.0, 80.0, 99.0, 81.0], "value": 71.2, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 72.0, 92.0, 69.0, 67.0, 69.0, 93.0, 67.0, 68.0, 89.0], "value": 76.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 83.0, 86.0, 84.0, 86.0, 83.0, 86.0, 83.0, 85.0, 88.0], "value": 85.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [115.0, 122.0, 92.0, 92.0, 91.0, 93.0, 92.0, 92.0, 92.0, 93.0], "value": 97.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 85.0, 106.0, 134.0, 136.0, 86.0, 85.0, 86.0, 87.0, 83.0], "value": 97.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 55.0, 54.0, 52.0, 55.0, 57.0, 56.0, 56.0, 56.0, 56.0], "value": 55.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 67.0, 68.0, 76.0, 68.0, 68.0, 70.0, 67.0, 68.0, 68.0], "value": 68.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 81.0, 100.0, 100.0, 99.0, 84.0, 80.0, 83.0, 83.0, 81.0], "value": 87.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [144.0, 142.0, 147.0, 145.0, 134.0, 134.0, 143.0, 153.0, 173.0, 135.0], "value": 145.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 161.0, 160.0, 160.0, 162.0, 163.0, 162.0, 161.0, 160.0, 162.0], "value": 161.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 66.0, 66.0, 65.0, 66.0, 67.0, 65.0, 66.0, 65.0, 66.0], "value": 65.9, "unit": "score"}], "name": "kraken", "value": 1423.3, "alertThreshold": 2.0}]} 20:51:05 INFO - Return code: 0 20:51:05 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 20:51:05 INFO - # TBPL SUCCESS # 20:51:05 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 20:51:05 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 20:51:05 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 20:51:05 INFO - 2017-01-04 20:51:05.437 screenresolution[1927:14102] starting screenresolution argv=screenresolution get 20:51:05 INFO - 2017-01-04 20:51:05.439 screenresolution[1927:14102] Display 0: 1600x1200x32@60 20:51:05 INFO - 2017-01-04 20:51:05.446 screenresolution[1928:14105] starting screenresolution argv=screenresolution list 20:51:05 INFO - Available Modes on Display 0 20:51:05 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 20:51:05 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 20:51:05 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 20:51:05 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 20:51:05 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 20:51:05 INFO - Intel Iris: 20:51:05 INFO - Chipset Model: Intel Iris 20:51:05 INFO - Type: GPU 20:51:05 INFO - Bus: Built-In 20:51:05 INFO - VRAM (Dynamic, Max): 1536 MB 20:51:05 INFO - Vendor: Intel (0x8086) 20:51:05 INFO - Device ID: 0x0a2e 20:51:05 INFO - Revision ID: 0x0009 20:51:05 INFO - Displays: 20:51:05 INFO - Display: 20:51:05 INFO - Resolution: 1600 x 1200 @ 60 Hz 20:51:05 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 20:51:05 INFO - Display Serial Number: 1600x1200 60 20:51:05 INFO - Main Display: Yes 20:51:05 INFO - Mirror: Off 20:51:05 INFO - Online: Yes 20:51:05 INFO - Rotation: Supported 20:51:05 INFO - Return code: 0 20:51:05 INFO - Running post-action listener: _resource_record_post_action 20:51:05 INFO - [mozharness: 2017-01-05 04:51:05.680309Z] Finished run-tests step (success) 20:51:05 INFO - Running post-run listener: _resource_record_post_run 20:51:05 INFO - Total resource usage - Wall time: 1196s; CPU: 54.0%; Read bytes: 1687552; Write bytes: 494982144; Read time: 15; Write time: 1550 20:51:05 INFO - TinderboxPrint: CPU usage
53.6% 20:51:05 INFO - TinderboxPrint: I/O read bytes / time
1,687,552 / 15 20:51:05 INFO - TinderboxPrint: I/O write bytes / time
494,982,144 / 1,550 20:51:05 INFO - TinderboxPrint: CPU idle
2,222.8 (46.4%) 20:51:05 INFO - TinderboxPrint: CPU system
959.9 (20.1%) 20:51:05 INFO - TinderboxPrint: CPU user
1,603.0 (33.5%) 20:51:05 INFO - TinderboxPrint: Swap in / out
197,611,520 / 0 20:51:05 INFO - install - Wall time: 22s; CPU: 54.0%; Read bytes: 190362112; Write bytes: 189171712; Read time: 17353; Write time: 399 20:51:05 INFO - run-tests - Wall time: 1174s; CPU: 54.0%; Read bytes: 851968; Write bytes: 294616064; Read time: 12; Write time: 1108 20:51:06 INFO - Running post-run listener: _upload_blobber_files 20:51:06 INFO - Blob upload gear active. 20:51:06 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 20:51:06 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 20:51:06 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'] 20:51:06 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 20:51:06 INFO - (blobuploader) - INFO - Open directory for files ... 20:51:06 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 20:51:06 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 20:51:06 INFO - (blobuploader) - INFO - Uploading, attempt #1. 20:51:07 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 20:51:07 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 20:51:07 INFO - (blobuploader) - INFO - Done attempting. 20:51:07 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 20:51:07 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 20:51:07 INFO - (blobuploader) - INFO - Uploading, attempt #1. 20:51:08 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 20:51:08 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 20:51:08 INFO - (blobuploader) - INFO - Done attempting. 20:51:08 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 20:51:08 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 20:51:08 INFO - (blobuploader) - INFO - Uploading, attempt #1. 20:51:10 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 20:51:10 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 20:51:10 INFO - (blobuploader) - INFO - Done attempting. 20:51:10 INFO - (blobuploader) - INFO - Iteration through files over. 20:51:10 INFO - Return code: 0 20:51:10 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 20:51:10 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 20:51:10 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/6cdd926c71dd52139f0cd90ab2b0da55a06df33b456fa0b169cb799d715be47aad71a7b99107ce2d8ccf47347fd7e027f4dd64f2f22ca008948344a593950a5e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/b350ab57656d8848519ab946e6d8855ad2420a5ce8605b59b731e1a5d57d7942f190fc9db91f04b851e9de2099a48f9bcc1ef2359c4e1d929d060f28764575ad", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/5435c793b191833b1b23907986726b99e8e54847c7f1694245a0dec25c4d607819401f586e19f8d02ada9df8cd48cbfc149c5c41837dafd13eae60cac9320dcf"} 20:51:10 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 20:51:10 INFO - Writing to file /builds/slave/test/properties/blobber_files 20:51:10 INFO - Contents: 20:51:10 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/6cdd926c71dd52139f0cd90ab2b0da55a06df33b456fa0b169cb799d715be47aad71a7b99107ce2d8ccf47347fd7e027f4dd64f2f22ca008948344a593950a5e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/b350ab57656d8848519ab946e6d8855ad2420a5ce8605b59b731e1a5d57d7942f190fc9db91f04b851e9de2099a48f9bcc1ef2359c4e1d929d060f28764575ad", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/5435c793b191833b1b23907986726b99e8e54847c7f1694245a0dec25c4d607819401f586e19f8d02ada9df8cd48cbfc149c5c41837dafd13eae60cac9320dcf"} 20:51:10 INFO - Running post-run listener: copy_logs_to_upload_dir 20:51:10 INFO - Copying logs to upload dir... 20:51:10 INFO - mkdir: /builds/slave/test/build/upload/logs 20:51:10 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1255.091914 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 20 mins, 55 secs) (at 2017-01-04 20:51:11.012200) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-04 20:51:11.015386) ========= 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.qq18IEDFVL/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.DOWzfiOESs/Listeners TMPDIR=/var/folders/nt/w1wt5wh94cg0328j1nmkplv400000w/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/6cdd926c71dd52139f0cd90ab2b0da55a06df33b456fa0b169cb799d715be47aad71a7b99107ce2d8ccf47347fd7e027f4dd64f2f22ca008948344a593950a5e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/b350ab57656d8848519ab946e6d8855ad2420a5ce8605b59b731e1a5d57d7942f190fc9db91f04b851e9de2099a48f9bcc1ef2359c4e1d929d060f28764575ad", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/5435c793b191833b1b23907986726b99e8e54847c7f1694245a0dec25c4d607819401f586e19f8d02ada9df8cd48cbfc149c5c41837dafd13eae60cac9320dcf"} build_url:https://queue.taskcluster.net/v1/task/PVYjI-z6S5qoQRXyCRoALw/artifacts/public/build/firefox-51.0.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010986 build_url: 'https://queue.taskcluster.net/v1/task/PVYjI-z6S5qoQRXyCRoALw/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/6cdd926c71dd52139f0cd90ab2b0da55a06df33b456fa0b169cb799d715be47aad71a7b99107ce2d8ccf47347fd7e027f4dd64f2f22ca008948344a593950a5e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/b350ab57656d8848519ab946e6d8855ad2420a5ce8605b59b731e1a5d57d7942f190fc9db91f04b851e9de2099a48f9bcc1ef2359c4e1d929d060f28764575ad", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/5435c793b191833b1b23907986726b99e8e54847c7f1694245a0dec25c4d607819401f586e19f8d02ada9df8cd48cbfc149c5c41837dafd13eae60cac9320dcf"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-04 20:51:11.052034) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-04 20:51:11.052343) ========= 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.qq18IEDFVL/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.DOWzfiOESs/Listeners TMPDIR=/var/folders/nt/w1wt5wh94cg0328j1nmkplv400000w/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.005104 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-04 20:51:11.100615) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-04 20:51:11.100909) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-04 20:51:11.101228) ========= ========= Total master_lag: 0.18 =========