builder: mozilla-beta_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0005 starttime: 1479475520.29 results: success (0) buildid: 20161118040828 builduid: 1aa1d4ab7c794f94a737edcf8e37d62f revision: 11b5acda902f5ecb13ab2d451d36c7a9063f2b26 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-11-18 05:25:20.288121) ========= master: http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-11-18 05:25:20.288607) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-18 05:25:20.288892) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-18 05:25:20.313885) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-18 05:25:20.314182) ========= 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.k6CRI8gPHs/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.IFD6gywel9/Listeners TMPDIR=/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-11-18 05:25:20-- 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% 20.7M=0.001s 2016-11-18 05:25:20 (20.7 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.124285 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-18 05:25:20.461441) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-18 05:25:20.461764) ========= 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.k6CRI8gPHs/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.IFD6gywel9/Listeners TMPDIR=/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/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.027831 ========= master_lag: 0.24 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-18 05:25:20.730703) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-11-18 05:25:20.731069) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 11b5acda902f5ecb13ab2d451d36c7a9063f2b26 --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 11b5acda902f5ecb13ab2d451d36c7a9063f2b26 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.k6CRI8gPHs/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.IFD6gywel9/Listeners TMPDIR=/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-11-18 05:25:20,825 truncating revision to first 12 chars 2016-11-18 05:25:20,825 Setting DEBUG logging. 2016-11-18 05:25:20,825 attempt 1/10 2016-11-18 05:25:20,826 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/11b5acda902f?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-11-18 05:25:22,629 unpacking tar archive at: mozilla-beta-11b5acda902f/testing/mozharness/ program finished with exit code 0 elapsedTime=2.088248 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-11-18 05:25:22.845899) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-18 05:25:22.846697) ========= script_repo_revision: 11b5acda902f5ecb13ab2d451d36c7a9063f2b26 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-18 05:25:22.847325) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-18 05:25:22.847642) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-18 05:25:22.866970) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 20 mins, 58 secs) (at 2016-11-18 05:25:22.867728) ========= /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.k6CRI8gPHs/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.IFD6gywel9/Listeners TMPDIR=/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/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 05:25:23 INFO - MultiFileLogger online at 20161118 05:25:23 in /builds/slave/test 05:25:23 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 05:25:23 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 05:25:23 INFO - {'append_to_log': False, 05:25:23 INFO - 'base_work_dir': '/builds/slave/test', 05:25:23 INFO - 'blob_upload_branch': 'Mozilla-Beta', 05:25:23 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 05:25:23 INFO - 'branch': 'Mozilla-Beta', 05:25:23 INFO - 'buildbot_json_path': 'buildprops.json', 05:25:23 INFO - 'config_files': ('talos/mac_config.py',), 05:25:23 INFO - 'default_actions': ('clobber', 05:25:23 INFO - 'read-buildbot-config', 05:25:23 INFO - 'download-and-extract', 05:25:23 INFO - 'populate-webroot', 05:25:23 INFO - 'create-virtualenv', 05:25:23 INFO - 'install', 05:25:23 INFO - 'run-tests'), 05:25:23 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 05:25:23 INFO - 'download_minidump_stackwalk': True, 05:25:23 INFO - 'download_symbols': 'ondemand', 05:25:23 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 05:25:23 INFO - 'tooltool.py': '/tools/tooltool.py', 05:25:23 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 05:25:23 INFO - '/tools/misc-python/virtualenv.py')}, 05:25:23 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 05:25:23 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 05:25:23 INFO - 'installer_path': 'installer.exe', 05:25:23 INFO - 'log_level': 'info', 05:25:23 INFO - 'log_name': 'talos', 05:25:23 INFO - 'log_to_console': True, 05:25:23 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 05:25:23 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 05:25:23 INFO - 'opt_config_files': (), 05:25:23 INFO - 'pip_index': False, 05:25:23 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:25:23 INFO - 'cmd': ('bash', 05:25:23 INFO - '-c', 05:25:23 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:25:23 INFO - 'enabled': True, 05:25:23 INFO - 'halt_on_failure': False, 05:25:23 INFO - 'name': 'check_screen_resolution'},), 05:25:23 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:25:23 INFO - 'cmd': ('bash', 05:25:23 INFO - '-c', 05:25:23 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:25:23 INFO - 'enabled': True, 05:25:23 INFO - 'halt_on_failure': False, 05:25:23 INFO - 'name': 'check_screen_resolution'},), 05:25:23 INFO - 'run_cmd_checks_enabled': True, 05:25:23 INFO - 'sps_profile': False, 05:25:23 INFO - 'sps_profile_interval': 0, 05:25:23 INFO - 'suite': 'dromaeojs', 05:25:23 INFO - 'system_bits': '32', 05:25:23 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 05:25:23 INFO - 'title': 't-yosemite-r7-0005', 05:25:23 INFO - 'tooltool_cache': '/builds/tooltool_cache', 05:25:23 INFO - 'use_talos_json': True, 05:25:23 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 05:25:23 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 05:25:23 INFO - 'work_dir': 'build'} 05:25:23 INFO - [mozharness: 2016-11-18 13:25:23.070161Z] Running clobber step. 05:25:23 INFO - Running pre-action listener: _resource_record_pre_action 05:25:23 INFO - Running main action method: clobber 05:25:23 INFO - rmtree: /builds/slave/test/build 05:25:23 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 05:25:25 INFO - Running post-action listener: _resource_record_post_action 05:25:25 INFO - [mozharness: 2016-11-18 13:25:25.575420Z] Finished clobber step (success) 05:25:25 INFO - [mozharness: 2016-11-18 13:25:25.575547Z] Running read-buildbot-config step. 05:25:25 INFO - Running pre-action listener: _resource_record_pre_action 05:25:25 INFO - Running main action method: read_buildbot_config 05:25:25 INFO - Using buildbot properties: 05:25:25 INFO - { 05:25:25 INFO - "project": "", 05:25:25 INFO - "product": "firefox", 05:25:25 INFO - "script_repo_revision": "production", 05:25:25 INFO - "builddir": "mozilla-beta_yosemite_r7_test-dromaeojs", 05:25:25 INFO - "repository": "", 05:25:25 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-beta talos dromaeojs", 05:25:25 INFO - "buildid": "20161118040828", 05:25:25 INFO - "pgo_build": "False", 05:25:25 INFO - "basedir": "/builds/slave/test", 05:25:25 INFO - "buildnumber": 68, 05:25:25 INFO - "slavename": "t-yosemite-r7-0005", 05:25:25 INFO - "master": "http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/", 05:25:25 INFO - "platform": "yosemite_r7", 05:25:25 INFO - "scheduler": "tests-mozilla-beta-macosx64-talos", 05:25:25 INFO - "branch": "mozilla-beta", 05:25:25 INFO - "repo_path": "releases/mozilla-beta", 05:25:25 INFO - "revision": "11b5acda902f5ecb13ab2d451d36c7a9063f2b26", 05:25:25 INFO - "stage_platform": "macosx64", 05:25:25 INFO - "builduid": "1aa1d4ab7c794f94a737edcf8e37d62f", 05:25:25 INFO - "slavebuilddir": "test" 05:25:25 INFO - } 05:25:25 INFO - Found installer url https://queue.taskcluster.net/v1/task/FmRmLTisR-qnEO4mwOEHDg/artifacts/public/build/firefox-51.0.en-US.mac.dmg. 05:25:25 INFO - Running post-action listener: _resource_record_post_action 05:25:25 INFO - [mozharness: 2016-11-18 13:25:25.578916Z] Finished read-buildbot-config step (success) 05:25:25 INFO - [mozharness: 2016-11-18 13:25:25.579039Z] Running download-and-extract step. 05:25:25 INFO - Running pre-action listener: _resource_record_pre_action 05:25:25 INFO - Running main action method: download_and_extract 05:25:25 INFO - mkdir: /builds/slave/test/build/tests 05:25:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:25:25 INFO - trying https://queue.taskcluster.net/v1/task/FmRmLTisR-qnEO4mwOEHDg/artifacts/public/build/firefox-51.0.en-US.mac.test_packages.json 05:25:25 INFO - Downloading https://queue.taskcluster.net/v1/task/FmRmLTisR-qnEO4mwOEHDg/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 05:25:25 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/FmRmLTisR-qnEO4mwOEHDg/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 05:25:26 INFO - Downloaded 1165 bytes. 05:25:26 INFO - Reading from file /builds/slave/test/build/firefox-51.0.en-US.mac.test_packages.json 05:25:26 INFO - Using the following test package requirements: 05:25:26 INFO - {u'common': [u'firefox-51.0.en-US.mac.common.tests.zip'], 05:25:26 INFO - u'cppunittest': [u'firefox-51.0.en-US.mac.common.tests.zip', 05:25:26 INFO - u'firefox-51.0.en-US.mac.cppunittest.tests.zip'], 05:25:26 INFO - u'gtest': [u'firefox-51.0.en-US.mac.common.tests.zip', 05:25:26 INFO - u'firefox-51.0.en-US.mac.gtest.tests.zip'], 05:25:26 INFO - u'jittest': [u'firefox-51.0.en-US.mac.common.tests.zip', u'jsshell-mac.zip'], 05:25:26 INFO - u'mochitest': [u'firefox-51.0.en-US.mac.common.tests.zip', 05:25:26 INFO - u'firefox-51.0.en-US.mac.mochitest.tests.zip'], 05:25:26 INFO - u'mozbase': [u'firefox-51.0.en-US.mac.common.tests.zip'], 05:25:26 INFO - u'reftest': [u'firefox-51.0.en-US.mac.common.tests.zip', 05:25:26 INFO - u'firefox-51.0.en-US.mac.reftest.tests.zip'], 05:25:26 INFO - u'talos': [u'firefox-51.0.en-US.mac.common.tests.zip', 05:25:26 INFO - u'firefox-51.0.en-US.mac.talos.tests.zip'], 05:25:26 INFO - u'web-platform': [u'firefox-51.0.en-US.mac.common.tests.zip', 05:25:26 INFO - u'firefox-51.0.en-US.mac.web-platform.tests.zip'], 05:25:26 INFO - u'xpcshell': [u'firefox-51.0.en-US.mac.common.tests.zip', 05:25:26 INFO - u'firefox-51.0.en-US.mac.xpcshell.tests.zip']} 05:25:26 INFO - Downloading packages: [u'firefox-51.0.en-US.mac.common.tests.zip'] for test suite category: common 05:25:26 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/FmRmLTisR-qnEO4mwOEHDg/artifacts/public/build/firefox-51.0.en-US.mac.common.tests.zip 05:25:26 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/FmRmLTisR-qnEO4mwOEHDg/artifacts/public/build/firefox-51.0.en-US.mac.common.tests.zip'}, attempt #1 05:25:26 INFO - Fetch https://queue.taskcluster.net/v1/task/FmRmLTisR-qnEO4mwOEHDg/artifacts/public/build/firefox-51.0.en-US.mac.common.tests.zip into memory 05:25:27 INFO - Expected file size: 16231139 05:25:34 INFO - Downloading packages: [u'firefox-51.0.en-US.mac.common.tests.zip', u'firefox-51.0.en-US.mac.talos.tests.zip'] for test suite category: talos 05:25:34 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/FmRmLTisR-qnEO4mwOEHDg/artifacts/public/build/firefox-51.0.en-US.mac.common.tests.zip 05:25:34 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/FmRmLTisR-qnEO4mwOEHDg/artifacts/public/build/firefox-51.0.en-US.mac.common.tests.zip'}, attempt #1 05:25:34 INFO - Fetch https://queue.taskcluster.net/v1/task/FmRmLTisR-qnEO4mwOEHDg/artifacts/public/build/firefox-51.0.en-US.mac.common.tests.zip into memory 05:25:34 INFO - Expected file size: 16231139 05:25:41 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/FmRmLTisR-qnEO4mwOEHDg/artifacts/public/build/firefox-51.0.en-US.mac.talos.tests.zip 05:25:41 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/FmRmLTisR-qnEO4mwOEHDg/artifacts/public/build/firefox-51.0.en-US.mac.talos.tests.zip'}, attempt #1 05:25:41 INFO - Fetch https://queue.taskcluster.net/v1/task/FmRmLTisR-qnEO4mwOEHDg/artifacts/public/build/firefox-51.0.en-US.mac.talos.tests.zip into memory 05:25:42 INFO - Expected file size: 14414089 05:25:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:25:42 INFO - trying https://queue.taskcluster.net/v1/task/FmRmLTisR-qnEO4mwOEHDg/artifacts/public/build/firefox-51.0.en-US.mac.dmg 05:25:42 INFO - Downloading https://queue.taskcluster.net/v1/task/FmRmLTisR-qnEO4mwOEHDg/artifacts/public/build/firefox-51.0.en-US.mac.dmg to /builds/slave/test/build/firefox-51.0.en-US.mac.dmg 05:25:42 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/FmRmLTisR-qnEO4mwOEHDg/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 05:25:45 INFO - Downloaded 86009518 bytes. 05:25:45 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/FmRmLTisR-qnEO4mwOEHDg/artifacts/public/build/firefox-51.0.en-US.mac.dmg 05:25:45 INFO - mkdir: /builds/slave/test/properties 05:25:45 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 05:25:45 INFO - Writing to file /builds/slave/test/properties/build_url 05:25:45 INFO - Contents: 05:25:45 INFO - build_url:https://queue.taskcluster.net/v1/task/FmRmLTisR-qnEO4mwOEHDg/artifacts/public/build/firefox-51.0.en-US.mac.dmg 05:25:45 INFO - Running post-action listener: _resource_record_post_action 05:25:45 INFO - Running post-action listener: set_extra_try_arguments 05:25:45 INFO - [mozharness: 2016-11-18 13:25:45.884287Z] Finished download-and-extract step (success) 05:25:45 INFO - [mozharness: 2016-11-18 13:25:45.884451Z] Running populate-webroot step. 05:25:45 INFO - Running pre-action listener: _resource_record_pre_action 05:25:45 INFO - Running main action method: populate_webroot 05:25:45 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 05:25:45 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 05:25:45 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 05:25:45 INFO - '--tppagecycles', 05:25:45 INFO - '7'], 05:25:45 INFO - 'tests': ['tsvgm']}}, 05:25:45 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 05:25:45 INFO - 'tests': ['tresize', 'tcanvasmark']}, 05:25:45 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 05:25:45 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 05:25:45 INFO - 'tests': ['dromaeo_css', 'kraken']}, 05:25:45 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 05:25:45 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:25:45 INFO - 'talos_options': ['--disable-e10s'], 05:25:45 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:25:45 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:25:45 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:25:45 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:25:45 INFO - 'talos_options': ['--disable-e10s'], 05:25:45 INFO - 'tests': ['damp', 'tps']}, 05:25:45 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:25:45 INFO - 'tests': ['damp', 'tps']}, 05:25:45 INFO - 'g3': {'talos_options': ['--disable-e10s'], 05:25:45 INFO - 'tests': ['dromaeo_dom']}, 05:25:45 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 05:25:45 INFO - 'g4': {'talos_options': ['--disable-e10s'], 05:25:45 INFO - 'tests': ['basic_compositor_video']}, 05:25:45 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 05:25:45 INFO - 'other': {'talos_options': ['--disable-e10s'], 05:25:45 INFO - 'tests': ['a11yr', 05:25:45 INFO - 'ts_paint', 05:25:45 INFO - 'tpaint', 05:25:45 INFO - 'sessionrestore', 05:25:45 INFO - 'sessionrestore_no_auto_restore', 05:25:45 INFO - 'tabpaint']}, 05:25:45 INFO - 'other-e10s': {'tests': ['a11yr', 05:25:45 INFO - 'ts_paint', 05:25:45 INFO - 'tpaint', 05:25:45 INFO - 'sessionrestore', 05:25:45 INFO - 'sessionrestore_no_auto_restore', 05:25:45 INFO - 'tabpaint']}, 05:25:45 INFO - 'other-e10s_l64': {'tests': ['a11yr', 05:25:45 INFO - 'ts_paint', 05:25:45 INFO - 'tpaint', 05:25:45 INFO - 'sessionrestore', 05:25:45 INFO - 'sessionrestore_no_auto_restore', 05:25:45 INFO - 'tabpaint']}, 05:25:45 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 05:25:45 INFO - 'ts_paint', 05:25:45 INFO - 'tpaint', 05:25:45 INFO - 'sessionrestore', 05:25:45 INFO - 'sessionrestore_no_auto_restore', 05:25:45 INFO - 'tabpaint']}, 05:25:45 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 05:25:45 INFO - 'tests': ['a11yr', 05:25:45 INFO - 'ts_paint', 05:25:45 INFO - 'tpaint', 05:25:45 INFO - 'sessionrestore', 05:25:45 INFO - 'sessionrestore_no_auto_restore', 05:25:45 INFO - 'tabpaint']}, 05:25:45 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 05:25:45 INFO - 'tests': ['a11yr', 05:25:45 INFO - 'ts_paint', 05:25:45 INFO - 'tpaint', 05:25:45 INFO - 'sessionrestore', 05:25:45 INFO - 'sessionrestore_no_auto_restore', 05:25:45 INFO - 'tabpaint']}, 05:25:45 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 05:25:45 INFO - 'tests': ['tsvgx', 05:25:45 INFO - 'tsvgr_opacity', 05:25:45 INFO - 'tart', 05:25:45 INFO - 'tscrollx', 05:25:45 INFO - 'cart']}, 05:25:45 INFO - 'svgr-e10s': {'tests': ['tsvgx', 05:25:45 INFO - 'tsvgr_opacity', 05:25:45 INFO - 'tart', 05:25:45 INFO - 'tscrollx', 05:25:45 INFO - 'cart']}, 05:25:45 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:25:45 INFO - 'talos_options': ['--disable-e10s'], 05:25:45 INFO - 'tests': ['tp5o']}, 05:25:45 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:25:45 INFO - 'tests': ['tp5o']}, 05:25:45 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:25:45 INFO - 'talos_options': ['--disable-e10s', 05:25:45 INFO - '--xperf_path', 05:25:45 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:25:45 INFO - 'tests': ['tp5n']}, 05:25:45 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:25:45 INFO - 'talos_options': ['--xperf_path', 05:25:45 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:25:45 INFO - 'tests': ['tp5n']}}, 05:25:45 INFO - 'talos.zip': {'path': '', 05:25:45 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 05:25:45 INFO - Running post-action listener: _resource_record_post_action 05:25:45 INFO - [mozharness: 2016-11-18 13:25:45.903147Z] Finished populate-webroot step (success) 05:25:45 INFO - [mozharness: 2016-11-18 13:25:45.903250Z] Running create-virtualenv step. 05:25:45 INFO - Running pre-action listener: _resource_record_pre_action 05:25:45 INFO - Running main action method: create_virtualenv 05:25:45 INFO - Creating virtualenv /builds/slave/test/build/venv 05:25:45 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 05:25:45 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 05:25:46 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 05:25:46 INFO - Using real prefix '/tools/python27' 05:25:46 INFO - New python executable in /builds/slave/test/build/venv/bin/python 05:25:46 INFO - Installing distribute.............................................................................................................................................................................................done. 05:25:49 INFO - Installing pip.................done. 05:25:49 INFO - Return code: 0 05:25:49 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 05:25: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')]} 05:25:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:25:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:25: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')]} 05:25:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:25:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:25:49 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x108f1f490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108e95570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f97e8d5ff60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108d33d30>, '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 0x108b99db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108d2bb20>, '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.k6CRI8gPHs/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.IFD6gywel9/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/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 05:25:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 05:25:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 05:25:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.k6CRI8gPHs/Render', 05:25:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:25:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:25:49 INFO - 'HOME': '/Users/cltbld', 05:25:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:25:49 INFO - 'LOGNAME': 'cltbld', 05:25:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:25:49 INFO - 'MOZ_NO_REMOTE': '1', 05:25:49 INFO - 'NO_EM_RESTART': '1', 05:25:49 INFO - 'PAGER': '/bin/cat', 05:25:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:25:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:25:49 INFO - 'PWD': '/builds/slave/test', 05:25:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:25:49 INFO - 'SHELL': '/bin/bash', 05:25:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.IFD6gywel9/Listeners', 05:25:49 INFO - 'TMPDIR': '/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/', 05:25:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:25:49 INFO - 'USER': 'cltbld', 05:25:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:25:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:25:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:25:49 INFO - 'XPC_FLAGS': '0x0', 05:25:49 INFO - 'XPC_SERVICE_NAME': '0', 05:25:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:25:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:25:49 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 05:25:49 INFO - Cleaning up... 05:25:49 INFO - Return code: 0 05:25:49 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 05:25: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')]} 05:25:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:25:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:25: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')]} 05:25:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:25:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:25: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', '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 0x108f1f490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108e95570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f97e8d5ff60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108d33d30>, '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 0x108b99db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108d2bb20>, '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.k6CRI8gPHs/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.IFD6gywel9/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/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 05:25: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', 'psutil>=3.1.1'] in /builds/slave/test/build 05:25: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 psutil>=3.1.1 05:25:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.k6CRI8gPHs/Render', 05:25:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:25:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:25:49 INFO - 'HOME': '/Users/cltbld', 05:25:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:25:49 INFO - 'LOGNAME': 'cltbld', 05:25:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:25:49 INFO - 'MOZ_NO_REMOTE': '1', 05:25:49 INFO - 'NO_EM_RESTART': '1', 05:25:49 INFO - 'PAGER': '/bin/cat', 05:25:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:25:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:25:49 INFO - 'PWD': '/builds/slave/test', 05:25:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:25:49 INFO - 'SHELL': '/bin/bash', 05:25:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.IFD6gywel9/Listeners', 05:25:49 INFO - 'TMPDIR': '/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/', 05:25:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:25:49 INFO - 'USER': 'cltbld', 05:25:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:25:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:25:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:25:49 INFO - 'XPC_FLAGS': '0x0', 05:25:49 INFO - 'XPC_SERVICE_NAME': '0', 05:25:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:25:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:25:50 INFO - Downloading/unpacking psutil>=3.1.1 05:25:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:25:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:25:50 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:25:50 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:25:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:25:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:25:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 05:25:53 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:25:53 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:25:53 INFO - Installing collected packages: psutil 05:25:53 INFO - Running setup.py install for psutil 05:25:53 INFO - building 'psutil._psutil_osx' extension 05:25:53 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 05:25:54 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 05:25:54 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 05:25:54 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 05:25:54 INFO - building 'psutil._psutil_posix' extension 05:25:54 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 05:25:54 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 05:25:54 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 05:25:54 INFO - ^ 05:25:54 INFO - 1 warning generated. 05:25:54 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 05:25:54 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:25:54 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:25:54 INFO - Successfully installed psutil 05:25:54 INFO - Cleaning up... 05:25:54 INFO - Return code: 0 05:25:54 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 05:25:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:25:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:25:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:25:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:25:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:25:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:25:54 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x108f1f490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108e95570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f97e8d5ff60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108d33d30>, '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 0x108b99db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108d2bb20>, '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.k6CRI8gPHs/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.IFD6gywel9/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/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 05:25:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 05:25:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 05:25:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.k6CRI8gPHs/Render', 05:25:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:25:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:25:54 INFO - 'HOME': '/Users/cltbld', 05:25:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:25:54 INFO - 'LOGNAME': 'cltbld', 05:25:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:25:54 INFO - 'MOZ_NO_REMOTE': '1', 05:25:54 INFO - 'NO_EM_RESTART': '1', 05:25:54 INFO - 'PAGER': '/bin/cat', 05:25:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:25:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:25:54 INFO - 'PWD': '/builds/slave/test', 05:25:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:25:54 INFO - 'SHELL': '/bin/bash', 05:25:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.IFD6gywel9/Listeners', 05:25:54 INFO - 'TMPDIR': '/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/', 05:25:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:25:54 INFO - 'USER': 'cltbld', 05:25:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:25:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:25:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:25:54 INFO - 'XPC_FLAGS': '0x0', 05:25:54 INFO - 'XPC_SERVICE_NAME': '0', 05:25:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:25:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:25:55 INFO - Downloading/unpacking mozsystemmonitor==0.3 05:25:55 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 05:25:55 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 05:25:55 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 05:25:55 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 05:25:55 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 05:25:55 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 05:25:57 INFO - Downloading mozsystemmonitor-0.3.tar.gz 05:25:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 05:25:57 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 05:25:57 INFO - Installing collected packages: mozsystemmonitor 05:25:57 INFO - Running setup.py install for mozsystemmonitor 05:25:57 INFO - Successfully installed mozsystemmonitor 05:25:57 INFO - Cleaning up... 05:25:58 INFO - Return code: 0 05:25:58 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 05:25: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')]} 05:25:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:25:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:25: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')]} 05:25:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:25:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:25:58 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x108f1f490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108e95570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f97e8d5ff60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108d33d30>, '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 0x108b99db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108d2bb20>, '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.k6CRI8gPHs/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.IFD6gywel9/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/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 05:25:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 05:25:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 05:25:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.k6CRI8gPHs/Render', 05:25:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:25:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:25:58 INFO - 'HOME': '/Users/cltbld', 05:25:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:25:58 INFO - 'LOGNAME': 'cltbld', 05:25:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:25:58 INFO - 'MOZ_NO_REMOTE': '1', 05:25:58 INFO - 'NO_EM_RESTART': '1', 05:25:58 INFO - 'PAGER': '/bin/cat', 05:25:58 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:25:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:25:58 INFO - 'PWD': '/builds/slave/test', 05:25:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:25:58 INFO - 'SHELL': '/bin/bash', 05:25:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.IFD6gywel9/Listeners', 05:25:58 INFO - 'TMPDIR': '/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/', 05:25:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:25:58 INFO - 'USER': 'cltbld', 05:25:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:25:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:25:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:25:58 INFO - 'XPC_FLAGS': '0x0', 05:25:58 INFO - 'XPC_SERVICE_NAME': '0', 05:25:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:25:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:25:58 INFO - Downloading/unpacking jsonschema==2.5.1 05:25:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:25:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:25:58 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:25:58 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:25:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:25:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:26:00 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 05:26:00 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 05:26:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:26:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:26:00 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:26:00 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:26:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:26:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:26:01 INFO - Downloading functools32-3.2.3-2.tar.gz 05:26:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 05:26:01 INFO - warning: no files found matching '*.txt' 05:26:01 INFO - no previously-included directories found matching 'build' 05:26:01 INFO - no previously-included directories found matching 'dist' 05:26:01 INFO - no previously-included directories found matching '.git*' 05:26:01 INFO - Installing collected packages: jsonschema, functools32 05:26:01 INFO - Running setup.py install for functools32 05:26:01 INFO - warning: no files found matching '*.txt' 05:26:01 INFO - no previously-included directories found matching 'build' 05:26:01 INFO - no previously-included directories found matching 'dist' 05:26:01 INFO - no previously-included directories found matching '.git*' 05:26:01 INFO - Successfully installed jsonschema functools32 05:26:01 INFO - Cleaning up... 05:26:01 INFO - Return code: 0 05:26:01 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 05:26:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:26:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:26:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:26:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:26:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:26:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:26:01 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x108f1f490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108e95570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f97e8d5ff60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108d33d30>, '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 0x108b99db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108d2bb20>, '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.k6CRI8gPHs/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.IFD6gywel9/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/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 05:26:01 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 05:26:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 05:26:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.k6CRI8gPHs/Render', 05:26:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:26:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:26:01 INFO - 'HOME': '/Users/cltbld', 05:26:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:26:01 INFO - 'LOGNAME': 'cltbld', 05:26:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:26:01 INFO - 'MOZ_NO_REMOTE': '1', 05:26:01 INFO - 'NO_EM_RESTART': '1', 05:26:01 INFO - 'PAGER': '/bin/cat', 05:26:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:26:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:26:01 INFO - 'PWD': '/builds/slave/test', 05:26:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:26:01 INFO - 'SHELL': '/bin/bash', 05:26:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.IFD6gywel9/Listeners', 05:26:01 INFO - 'TMPDIR': '/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/', 05:26:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:26:01 INFO - 'USER': 'cltbld', 05:26:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:26:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:26:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:26:01 INFO - 'XPC_FLAGS': '0x0', 05:26:01 INFO - 'XPC_SERVICE_NAME': '0', 05:26:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:26:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:26:01 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 05:26:01 INFO - Cleaning up... 05:26:01 INFO - Return code: 0 05:26:01 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 05:26:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:26:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:26:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:26:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:26:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:26:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:26:01 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x108f1f490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108e95570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f97e8d5ff60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108d33d30>, '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 0x108b99db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108d2bb20>, '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.k6CRI8gPHs/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.IFD6gywel9/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/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 05:26:01 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 05:26:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 05:26:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.k6CRI8gPHs/Render', 05:26:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:26:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:26:01 INFO - 'HOME': '/Users/cltbld', 05:26:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:26:01 INFO - 'LOGNAME': 'cltbld', 05:26:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:26:01 INFO - 'MOZ_NO_REMOTE': '1', 05:26:01 INFO - 'NO_EM_RESTART': '1', 05:26:01 INFO - 'PAGER': '/bin/cat', 05:26:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:26:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:26:01 INFO - 'PWD': '/builds/slave/test', 05:26:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:26:01 INFO - 'SHELL': '/bin/bash', 05:26:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.IFD6gywel9/Listeners', 05:26:01 INFO - 'TMPDIR': '/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/', 05:26:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:26:01 INFO - 'USER': 'cltbld', 05:26:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:26:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:26:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:26:01 INFO - 'XPC_FLAGS': '0x0', 05:26:01 INFO - 'XPC_SERVICE_NAME': '0', 05:26:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:26:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:26:02 INFO - Downloading/unpacking blobuploader==1.2.4 05:26:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:26:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:26:02 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:26:02 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:26:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:26:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:26:04 INFO - Downloading blobuploader-1.2.4.tar.gz 05:26:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 05:26:04 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 05:26:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:26:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:26:04 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:26:04 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:26:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:26:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:26:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 05:26:05 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 05:26: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 05:26: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 05:26: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 05:26: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 05:26: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 05:26: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 05:26:05 INFO - Downloading docopt-0.6.1.tar.gz 05:26:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 05:26:05 INFO - Installing collected packages: blobuploader, requests, docopt 05:26:05 INFO - Running setup.py install for blobuploader 05:26:05 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 05:26:05 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 05:26:05 INFO - Running setup.py install for requests 05:26:06 INFO - Running setup.py install for docopt 05:26:06 INFO - Successfully installed blobuploader requests docopt 05:26:06 INFO - Cleaning up... 05:26:06 INFO - Return code: 0 05:26:06 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:26:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:26:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:26:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:26:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:26:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:26:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:26:06 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x108f1f490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108e95570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f97e8d5ff60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108d33d30>, '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 0x108b99db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108d2bb20>, '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.k6CRI8gPHs/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.IFD6gywel9/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/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 05:26:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 05:26:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 05:26:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.k6CRI8gPHs/Render', 05:26:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:26:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:26:06 INFO - 'HOME': '/Users/cltbld', 05:26:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:26:06 INFO - 'LOGNAME': 'cltbld', 05:26:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:26:06 INFO - 'MOZ_NO_REMOTE': '1', 05:26:06 INFO - 'NO_EM_RESTART': '1', 05:26:06 INFO - 'PAGER': '/bin/cat', 05:26:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:26:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:26:06 INFO - 'PWD': '/builds/slave/test', 05:26:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:26:06 INFO - 'SHELL': '/bin/bash', 05:26:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.IFD6gywel9/Listeners', 05:26:06 INFO - 'TMPDIR': '/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/', 05:26:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:26:06 INFO - 'USER': 'cltbld', 05:26:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:26:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:26:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:26:06 INFO - 'XPC_FLAGS': '0x0', 05:26:06 INFO - 'XPC_SERVICE_NAME': '0', 05:26:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:26:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:26:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:26:07 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-mNqaaW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:26:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:26:07 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-OT2mkZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:26:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:26:07 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-xxlFE5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:26:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:26:07 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-GoKUSn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:26:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:26:07 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-0RivRy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:26:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:26:07 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-JkC6dJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:26:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:26:07 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-fVvqcf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:26:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:26:07 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-MJDa7O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:26:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:26:07 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-vlB8FF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:26:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:26:07 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-jAgwwZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:26:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:26:08 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-q86tgU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:26:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:26:08 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-qig9mp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:26:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:26:08 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-MQY9q8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:26:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:26:08 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-BX1FaS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:26:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:26:08 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-Dx7ubd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:26:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:26:08 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-54bYvS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:26:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:26:08 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-hy2aHW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:26:08 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 05:26:08 INFO - Running setup.py install for manifestparser 05:26:08 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 05:26:08 INFO - Running setup.py install for mozcrash 05:26:09 INFO - Running setup.py install for mozdebug 05:26:09 INFO - Running setup.py install for mozdevice 05:26:09 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 05:26:09 INFO - Installing dm script to /builds/slave/test/build/venv/bin 05:26:09 INFO - Running setup.py install for mozfile 05:26:09 INFO - Running setup.py install for mozhttpd 05:26:09 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 05:26:09 INFO - Running setup.py install for mozinfo 05:26:09 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 05:26:09 INFO - Running setup.py install for mozInstall 05:26:09 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 05:26:09 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 05:26:09 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 05:26:09 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 05:26:09 INFO - Running setup.py install for mozleak 05:26:10 INFO - Running setup.py install for mozlog 05:26:10 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 05:26:10 INFO - Running setup.py install for moznetwork 05:26:10 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 05:26:10 INFO - Running setup.py install for mozprocess 05:26:10 INFO - Running setup.py install for mozprofile 05:26:10 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 05:26:10 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 05:26:10 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 05:26:10 INFO - Running setup.py install for mozrunner 05:26:10 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 05:26:10 INFO - Running setup.py install for mozscreenshot 05:26:11 INFO - Running setup.py install for moztest 05:26:11 INFO - Running setup.py install for mozversion 05:26:11 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 05:26:11 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 05:26:11 INFO - Cleaning up... 05:26:11 INFO - Return code: 0 05:26:11 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:26:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:26:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:26:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:26:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:26:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:26:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:26:11 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x108f1f490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108e95570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f97e8d5ff60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108d33d30>, '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 0x108b99db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108d2bb20>, '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.k6CRI8gPHs/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.IFD6gywel9/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/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 05:26:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 05:26:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 05:26:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.k6CRI8gPHs/Render', 05:26:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:26:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:26:11 INFO - 'HOME': '/Users/cltbld', 05:26:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:26:11 INFO - 'LOGNAME': 'cltbld', 05:26:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:26:11 INFO - 'MOZ_NO_REMOTE': '1', 05:26:11 INFO - 'NO_EM_RESTART': '1', 05:26:11 INFO - 'PAGER': '/bin/cat', 05:26:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:26:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:26:11 INFO - 'PWD': '/builds/slave/test', 05:26:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:26:11 INFO - 'SHELL': '/bin/bash', 05:26:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.IFD6gywel9/Listeners', 05:26:11 INFO - 'TMPDIR': '/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/', 05:26:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:26:11 INFO - 'USER': 'cltbld', 05:26:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:26:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:26:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:26:11 INFO - 'XPC_FLAGS': '0x0', 05:26:11 INFO - 'XPC_SERVICE_NAME': '0', 05:26:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:26:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:26:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:26:11 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-AKz_cd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:26:11 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 05:26:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:26:11 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-BqAULq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:26:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 05:26:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:26:11 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-YPE0pF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:26:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 05:26:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:26:11 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-O_wqdZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:26:12 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)) 05:26:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:26:12 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-vbPvUm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:26:12 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)) 05:26:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:26:12 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-LdsRPZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:26:12 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)) 05:26:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:26:12 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-IOLR6A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:26:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 05:26:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:26:12 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-Col8Or-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:26:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 05:26:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:26:12 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-NlcD1m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:26:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 05:26:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:26:12 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-4XRvGu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:26:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:26:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:26:12 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-Gb53hQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:26:12 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 05:26:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:26:12 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-zKbpyt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:26:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 05:26:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:26:12 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-awjBnV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:26:13 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)) 05:26:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:26:13 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-J55Y7u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:26:13 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)) 05:26:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:26:13 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-EBlud2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:26:13 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)) 05:26:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:26:13 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-4XbJyF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:26:13 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 05:26:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:26:13 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-YvAecd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:26:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 05:26:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 05:26:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 05:26:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 05:26:13 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 05:26:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 05:26:13 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:26:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:26:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:26:13 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:26:13 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:26:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:26:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:26:16 INFO - Downloading blessings-1.6.tar.gz 05:26:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 05:26:16 INFO - Installing collected packages: blessings 05:26:16 INFO - Running setup.py install for blessings 05:26:16 INFO - Successfully installed blessings 05:26:16 INFO - Cleaning up... 05:26:16 INFO - Return code: 0 05:26:16 INFO - Done creating virtualenv /builds/slave/test/build/venv. 05:26:16 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:26:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:26:16 INFO - Reading from file tmpfile_stdout 05:26:16 INFO - Current package versions: 05:26:16 INFO - blessings == 1.6 05:26:16 INFO - blobuploader == 1.2.4 05:26:16 INFO - docopt == 0.6.1 05:26:16 INFO - functools32 == 3.2.3-2 05:26:16 INFO - jsonschema == 2.5.1 05:26:16 INFO - manifestparser == 1.1 05:26:16 INFO - mozInstall == 1.12 05:26:16 INFO - mozcrash == 0.17 05:26:16 INFO - mozdebug == 0.1 05:26:16 INFO - mozdevice == 0.48 05:26:16 INFO - mozfile == 1.2 05:26:16 INFO - mozhttpd == 0.7 05:26:16 INFO - mozinfo == 0.9 05:26:16 INFO - mozleak == 0.1 05:26:16 INFO - mozlog == 3.4 05:26:16 INFO - moznetwork == 0.27 05:26:16 INFO - mozprocess == 0.23 05:26:16 INFO - mozprofile == 0.28 05:26:16 INFO - mozrunner == 6.12 05:26:16 INFO - mozscreenshot == 0.1 05:26:16 INFO - mozsystemmonitor == 0.3 05:26:16 INFO - moztest == 0.7 05:26:16 INFO - mozversion == 1.4 05:26:16 INFO - psutil == 3.1.1 05:26:16 INFO - requests == 1.2.3 05:26:16 INFO - wsgiref == 0.1.2 05:26:16 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:26:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:26:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:26:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:26: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')]} 05:26:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:26:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:26:17 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 0x108f1f490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108e95570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f97e8d5ff60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108d33d30>, '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 0x108b99db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108d2bb20>, '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.k6CRI8gPHs/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.IFD6gywel9/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/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 05:26:17 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 05:26:17 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 05:26:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.k6CRI8gPHs/Render', 05:26:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:26:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:26:17 INFO - 'HOME': '/Users/cltbld', 05:26:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:26:17 INFO - 'LOGNAME': 'cltbld', 05:26:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:26:17 INFO - 'MOZ_NO_REMOTE': '1', 05:26:17 INFO - 'NO_EM_RESTART': '1', 05:26:17 INFO - 'PAGER': '/bin/cat', 05:26:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:26:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:26:17 INFO - 'PWD': '/builds/slave/test', 05:26:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:26:17 INFO - 'SHELL': '/bin/bash', 05:26:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.IFD6gywel9/Listeners', 05:26:17 INFO - 'TMPDIR': '/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/', 05:26:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:26:17 INFO - 'USER': 'cltbld', 05:26:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:26:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:26:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:26:17 INFO - 'XPC_FLAGS': '0x0', 05:26:17 INFO - 'XPC_SERVICE_NAME': '0', 05:26:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:26:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:26:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 05:26:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 05:26:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 05:26:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 05:26:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 05:26:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 05:26:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 05:26:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 05:26:17 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 05:26:17 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 05:26:17 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 05:26:17 INFO - Cleaning up... 05:26:17 INFO - Return code: 0 05:26:17 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 05:26: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')]} 05:26:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:26:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:26: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')]} 05:26:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:26:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:26:17 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x108f1f490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108e95570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f97e8d5ff60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108d33d30>, '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 0x108b99db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108d2bb20>, '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.k6CRI8gPHs/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.IFD6gywel9/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/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 05:26:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 05:26:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 05:26:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.k6CRI8gPHs/Render', 05:26:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:26:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:26:17 INFO - 'HOME': '/Users/cltbld', 05:26:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:26:17 INFO - 'LOGNAME': 'cltbld', 05:26:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:26:17 INFO - 'MOZ_NO_REMOTE': '1', 05:26:17 INFO - 'NO_EM_RESTART': '1', 05:26:17 INFO - 'PAGER': '/bin/cat', 05:26:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:26:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:26:17 INFO - 'PWD': '/builds/slave/test', 05:26:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:26:17 INFO - 'SHELL': '/bin/bash', 05:26:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.IFD6gywel9/Listeners', 05:26:17 INFO - 'TMPDIR': '/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/', 05:26:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:26:17 INFO - 'USER': 'cltbld', 05:26:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:26:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:26:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:26:17 INFO - 'XPC_FLAGS': '0x0', 05:26:17 INFO - 'XPC_SERVICE_NAME': '0', 05:26:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:26:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:26:17 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 05:26:17 INFO - Cleaning up... 05:26:17 INFO - Return code: 0 05:26:17 INFO - Running post-action listener: _resource_record_post_action 05:26:17 INFO - Running post-action listener: _start_resource_monitoring 05:26:17 INFO - Starting resource monitoring. 05:26:17 INFO - [mozharness: 2016-11-18 13:26:17.587639Z] Finished create-virtualenv step (success) 05:26:17 INFO - [mozharness: 2016-11-18 13:26:17.588589Z] Running install step. 05:26:17 INFO - Running pre-action listener: _resource_record_pre_action 05:26:17 INFO - Running main action method: install 05:26:17 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:26:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:26:17 INFO - Reading from file tmpfile_stdout 05:26:17 INFO - Detecting whether we're running mozinstall >=1.0... 05:26:17 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 05:26:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 05:26:17 INFO - Reading from file tmpfile_stdout 05:26:17 INFO - Output received: 05:26:17 INFO - Usage: mozinstall [options] installer 05:26:17 INFO - Options: 05:26:17 INFO - -h, --help show this help message and exit 05:26:17 INFO - -d DEST, --destination=DEST 05:26:17 INFO - Directory to install application into. [default: 05:26:17 INFO - "/builds/slave/test"] 05:26:17 INFO - --app=APP Application being installed. [default: firefox] 05:26:17 INFO - mkdir: /builds/slave/test/build/application 05:26:17 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-51.0.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 05:26:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-51.0.en-US.mac.dmg --destination /builds/slave/test/build/application 05:26:40 INFO - Reading from file tmpfile_stdout 05:26:40 INFO - Output received: 05:26:40 INFO - /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox 05:26:40 INFO - Running post-action listener: _resource_record_post_action 05:26:40 INFO - [mozharness: 2016-11-18 13:26:40.301948Z] Finished install step (success) 05:26:40 INFO - [mozharness: 2016-11-18 13:26:40.302067Z] Running run-tests step. 05:26:40 INFO - Running pre-action listener: _resource_record_pre_action 05:26:40 INFO - Running main action method: run_tests 05:26:40 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:26:40 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:26:40 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:26:40 INFO - 2016-11-18 05:26:40.314 screenresolution[1625:7343] starting screenresolution argv=screenresolution get 05:26:40 INFO - 2016-11-18 05:26:40.316 screenresolution[1625:7343] Display 0: 1600x1200x32@60 05:26:40 INFO - 2016-11-18 05:26:40.321 screenresolution[1626:7345] starting screenresolution argv=screenresolution list 05:26:40 INFO - Available Modes on Display 0 05:26:40 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:26:40 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:26:40 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:26:40 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:26:40 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:26:40 INFO - Intel Iris: 05:26:40 INFO - Chipset Model: Intel Iris 05:26:40 INFO - Type: GPU 05:26:40 INFO - Bus: Built-In 05:26:40 INFO - VRAM (Dynamic, Max): 1536 MB 05:26:40 INFO - Vendor: Intel (0x8086) 05:26:40 INFO - Device ID: 0x0a2e 05:26:40 INFO - Revision ID: 0x0009 05:26:40 INFO - Displays: 05:26:40 INFO - Display: 05:26:40 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:26:40 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:26:40 INFO - Display Serial Number: 1600x1200 60 05:26:40 INFO - Main Display: Yes 05:26:40 INFO - Mirror: Off 05:26:40 INFO - Online: Yes 05:26:40 INFO - Rotation: Supported 05:26:40 INFO - Return code: 0 05:26:40 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 05:26:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 05:26:40 INFO - Python 2.7.3 05:26:40 INFO - Return code: 0 05:26:40 INFO - grabbing minidump binary from tooltool 05:26:40 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')]} 05:26:40 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 0x108d33d30>, '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 0x108b99db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108d2bb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 05:26:40 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 05:26:40 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 05:26:40 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 05:26:40 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 05:26:40 INFO - Return code: 0 05:26:40 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 05:26:40 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 05:26:40 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 05:26:40 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 05:26:40 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 05:26:40 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/FmRmLTisR-qnEO4mwOEHDg/artifacts/public/build/firefox-51.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0005', '--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 05:26:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Beta --suite dromaeojs --executablePath /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/FmRmLTisR-qnEO4mwOEHDg/artifacts/public/build/firefox-51.0.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0005 --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 05:26:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.k6CRI8gPHs/Render', 05:26:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:26:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:26:40 INFO - 'HOME': '/Users/cltbld', 05:26:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:26:40 INFO - 'LOGNAME': 'cltbld', 05:26:40 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 05:26:40 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 05:26:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:26:40 INFO - 'MOZ_NO_REMOTE': '1', 05:26:40 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 05:26:40 INFO - 'NO_EM_RESTART': '1', 05:26:40 INFO - 'PAGER': '/bin/cat', 05:26:40 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:26:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:26:40 INFO - 'PWD': '/builds/slave/test', 05:26:40 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 05:26:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:26:40 INFO - 'SHELL': '/bin/bash', 05:26:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.IFD6gywel9/Listeners', 05:26:40 INFO - 'TMPDIR': '/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/', 05:26:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:26:40 INFO - 'USER': 'cltbld', 05:26:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:26:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:26:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:26:40 INFO - 'XPC_FLAGS': '0x0', 05:26:40 INFO - 'XPC_SERVICE_NAME': '0', 05:26:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:26:40 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/FmRmLTisR-qnEO4mwOEHDg/artifacts/public/build/firefox-51.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0005', '--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 05:26:40 INFO - mozversion application_buildid: 20161118040828 05:26:40 INFO - mozversion application_changeset: 11b5acda902f5ecb13ab2d451d36c7a9063f2b26 05:26:40 INFO - mozversion application_display_name: Firefox 05:26:40 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 05:26:40 INFO - mozversion application_name: Firefox 05:26:40 INFO - mozversion application_remotingname: firefox 05:26:40 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 05:26:40 INFO - mozversion application_vendor: Mozilla 05:26:40 INFO - mozversion application_version: 51.0 05:26:40 INFO - mozversion platform_buildid: 20161118040828 05:26:40 INFO - mozversion platform_changeset: 11b5acda902f5ecb13ab2d451d36c7a9063f2b26 05:26:40 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 05:26:40 INFO - mozversion platform_version: 51.0 05:26:40 INFO - using testdate: 1479475600 05:26:40 INFO - actual date: 1479475600 05:26:40 INFO - starting webserver on 'localhost:49246' 05:26:40 INFO - SUITE-START | Running 2 tests 05:26:40 INFO - TEST-START | dromaeo_css 05:26:40 INFO - operating with platform_type : mac_ 05:26:40 INFO - Initialising browser for dromaeo_css test... 05:26:40 INFO - TEST-INFO | started process 1636 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/tmpQopAoX/profile http://localhost:49246/getInfo.html) 05:26:43 INFO - PROCESS | 1636 | __metrics Screen width/height:1600/1200 05:26:43 INFO - PROCESS | 1636 | colorDepth:24 05:26:43 INFO - PROCESS | 1636 | Browser inner width/height: 1024/689 05:26:43 INFO - PROCESS | 1636 | __metrics 05:26:43 INFO - PROCESS | 1636 | 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" 05:26:43 INFO - PROCESS | 1636 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 05:26:44 INFO - TEST-INFO | 1636: exit 0 05:26:44 INFO - Browser initialized. 05:26:44 INFO - Running cycle 1/1 for dromaeo_css test... 05:26:44 INFO - TEST-INFO | started process 1639 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/tmpQopAoX/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:26:56 INFO - PROCESS | 1639 | RSS: Main: 168677376 05:26:56 INFO - PROCESS | 1639 | 05:27:47 INFO - PROCESS | 1639 | 1479475667896 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 05:29:58 INFO - PROCESS | 1639 | Cycle 1(1): loaded http://localhost:49246/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49246/tests/dromaeo/cssquery-ext.html) 05:30:00 INFO - PROCESS | 1639 | RSS: Main: 355737600 05:30:00 INFO - PROCESS | 1639 | 05:33:03 INFO - PROCESS | 1639 | Cycle 1(1): loaded http://localhost:49246/tests/dromaeo/cssquery-ext.html (next: http://localhost:49246/tests/dromaeo/cssquery-jquery.html) 05:33:04 INFO - PROCESS | 1639 | RSS: Main: 302407680 05:33:04 INFO - PROCESS | 1639 | 05:36:06 INFO - PROCESS | 1639 | Cycle 1(1): loaded http://localhost:49246/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49246/tests/dromaeo/cssquery-mootools.html) 05:36:07 INFO - PROCESS | 1639 | RSS: Main: 273756160 05:36:07 INFO - PROCESS | 1639 | 05:39:09 INFO - PROCESS | 1639 | Cycle 1(1): loaded http://localhost:49246/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49246/tests/dromaeo/cssquery-prototype.html) 05:39:09 INFO - PROCESS | 1639 | RSS: Main: 271736832 05:39:09 INFO - PROCESS | 1639 | 05:42:12 INFO - PROCESS | 1639 | Cycle 1(1): loaded http://localhost:49246/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49246/tests/dromaeo/cssquery-yui.html) 05:42:13 INFO - PROCESS | 1639 | RSS: Main: 261046272 05:42:13 INFO - PROCESS | 1639 | 05:45:16 INFO - PROCESS | 1639 | Cycle 1(1): loaded http://localhost:49246/tests/dromaeo/cssquery-yui.html (next: http://localhost:49246/tests/dromaeo/cssquery-dojo.html) 05:45:16 INFO - PROCESS | 1639 | RSS: Main: 319823872 05:45:16 INFO - PROCESS | 1639 | 05:45:16 INFO - PROCESS | 1639 | __start_tp_report 05:45:16 INFO - PROCESS | 1639 | _x_x_mozilla_page_load 05:45:16 INFO - PROCESS | 1639 | _x_x_mozilla_page_load_details 05:45:16 INFO - PROCESS | 1639 | |i|pagename|runs| 05:45:16 INFO - PROCESS | 1639 | |0;dojo.html;3483.773554718943;3493.825308734563;3537.1157210697324;3548.5741710994685;3595.5146055282544;5836.32882218545;5886.735096920638;5904.114382842576;5925.674087925162;5945.821625351241;5827.825165245039;5890.998530249857;5904.1734157218025;5904.852378690535;5909.911351329733;4647.69789963653;4678.836240731575;5284.867878303054;5316.282301889234;5372.811951581695;4330.718503297291;5349.438308977559;5354.411014788389;5393.649412788176;5412.350517937853;4750.168720473903;5161.870953226181;5387.164989426645;5404.135338345865;5414.187871819219;5394.514493695547;5394.973025134869;5401.945980540184;5405.297311349539;5409.945900541023;5229.058769421503;5255.185446255837;5340.813071542516;5345.732713364355;5394;5894.8821023579285;5915.349311575723;5930.288365396137;5971.671558064305;6003.969980150115;6317.273513545955;6361.936380636181;6364.554481186319;6377.681115944184;6379.170707808002;6276.843078923018;6302.6218426894075;6310.558260921737;6322.589031712924;6355.523335749837;5892.528597712216;5919.6152250103605;5962.791302304398;6008.519318454601;6034.849128771728;6413.583117097373;6418.390252925965;6424.967875160594;6447.6131432114225;6456;6142.3759981611065;6273.592216505913;6324.1778568786685;6326.145970293951;6341.936580634227;6281.623102613858;6298.118263443122;6329.208848893888;6393.264774550893;6398.712057957403;6346.30190679025;6347.52393570484;6358.141650877073;6360.968195158995;6374.235091789015;6193.318734939153;6248.343923888017;6357.4914006879335;6386.16979792624;6405.839854003687;5078.669886457368;5093.083245015859;5132.538071573576;5139.331886854685;5146.073706732824;4604.240300350471;4613.1465678848845;4629.490756016836;4647.814087436464;4648.442186937522;6089.421504957022;6091.299500557404;6092.908606370908;6095.969520152371;6104.511639068864;6235.033569796688;6259.6870156492;6270.843228919222;6343.302236753953;6359.491240700733;5978.163057172;6034.336223015464;6040.516758659297;6058.787942421994;6064.9090263646085;5591.440855914376;5607.971960140173;5608.579356548194;5611.663300202001;5621.7751289948765;6234.096056071935;6353.936460635519;6520.054592084216;6520.18497687789;6530.869382612226;6309.211348581427;6355.904661430175;6372.776952806629;6372.872542549216;6380.9361906380345;6491.545591808717;6493.350664933469;6510.153680021567;6515.609063456208;6523.543351965317;6199.411055949678;6329.1139240505445;6359.4276515112015;6364.331745166691;6406.711697973696;6340.968295158495;6346.428821406095;6375.4262116409745;6383.489320854228;6387.457066149429;6162.26052873658;6247.09417134522;6270.7491700331475;6345.714442850175;6356.078368636614;6283.96858015707;6330.113784070142;6334.714937827901;6353.650549219652;6354.078658594385;6000.609960352737;6209.254889413119;6215.65813880241;6230.314665386894;6243.687815609111;6175.62946223228;6196.411340922605;6219.968900155471;6267.310595834546;6268.310485846644;6082.9695851520455;6150.323464419;6200.720967556561;6207.81376558704;6216.067589861557 05:45:16 INFO - PROCESS | 1639 | |1;ext.html;436.77069538492293;538.6794857060048;544.0234778572463;545.3265217456441;547.8685115572259;33479.497807532876;43943.901402464915;44766.77616611916;44887.10225795482;47543.286850697266;46646.766766166205;46988.29517557239;47180.764096179555;47205;47242.29136562954;9368.953155234249;10081.69754907354;10252.487375631226;10274.332168409066;10279.948600256988;9148.359614826964;9542.666006689768;9545;9547.665831695893;12450.501979920793;12458.688532786708;12705.364731763375;12714.618561443125;12716.364181790874;12719.682007949783;12638.620841374774;12718.936405318007;12735.681607959876;12749.617511474624;12753.405824271966;10505.842412363821;12243.510259589606;12554.435050422753;12577.245365278113;12585.748285034248;38729.61270387288;41070.1785964282;43711.344329835076;44095.77952110219;44112;3471.94792078119;3540.345036168317;3671.375866102769;3708.9258214835554;3733.5519737631384;32455.350892982246;33312.50031249533;33752.987410377726;33880.81417150388;34483.482747758804;3349.882754103619;3361.966380336214;3447.4311738563106;3463.515107884898;3507.6492350764975;542983;543866;543985;544571;545628;67646.66176669086;68022;68068.6596567014;68152.6592367035;68203.65898170478;29247.707522924924;36995.815020924725;37005.07487312785;37876.43185352222;37993.24013519714;18433.446996589857;18571.25714971386;18604.06979651012;18610.06949652512;18626.44120676382;13996.650083747987;14077.859221407654;14079.648008799863;14141.43434262618;14142.646433839236;18205.7269140963;18337.816621833612;18347.90826045861;18562;20118.798812011693;7176.892346614805;8332.87506186657;8354.28988535969;8354.992202806985;8433.536155511505;937.6062053937362;968.5109019944882;970.6020531582018;1114.3982249585295;1115.1190559457937;26274.6058809118;32067.51898721521;32134.517982229816;32273.51589726156;33129.83435082809;891.8528442806994;1008.3395376028712;1061.3207547169818;1186.3534373766317;1225.5342969671647;15875.285612147482;16375.508734737978;16623.3350665972;16641.833581664272;16857.325706971584;750613.481595553;753506;754379;755987;766151;53516.73241633767;54610.726946365015;55228.723856380464;62446.5021399148;63058;1441.3081720774087;1455.8835293176521;1471.0945881883194;1486.0786312486327;1488.8064551608438;27584;28437.00470483523;29045.7095429043;29094.854525727234;29406.264843379086;56144.87710245854;60712.69643651754;61279.387206127365;61415.078773817535;65741.01388479081;625745;719931;720120;928932;952630;45059;45214.77392613016;45507.08985820331;45898;46043.3093503591;43695.78152109219;46970.060598787146;49843.50156498388;49924.00151997012;50392.49607503878;37636.62363376331;40720.592794072865;42483.78758106189;42645.30696267286;45119;35410.46884296789;42451.787741061096;42692.57307426886;43094.78452607842;44075;41254.79372603118;41997.79001104975;42248.94377640461;42257;42391.15217695691;35875;36615.45076823796;36676.0830979217;37210.883673489836;37627.43558846565;27499.86250068737;27519.797339078756;27536.17391478259;27538.862305688344;27882.44235115327 05:45:16 INFO - PROCESS | 1639 | |2;jquery.html;373.6786363727196;380.3230250154724;385.4447305123825;392.99410508842357;404.1391835390618;16230;17568.858024228433;20456.69314960277;22450.775492245073;22523.662145067836;12029.578964736236;12240.143189976705;12569.937150314236;12581.433835477403;12592.496300147983;5428.4300148484435;5769.826905192851;5770.278715160605;5773.364929857733;5791.652500849941;4070.8982275443054;4230.492340919094;4251.6173544381;5423.83728488144;5679.119736440837;7035.190953040389;7037.155541335047;7156.892646610305;7161.534500257492;7162.4628152888745;6833.4191593714595;7040.084788977426;7088.858222835566;7108.786736397917;7137.785866424016;5991.6405015698765;6226.40849119333;6234.407731265523;6239.438450539473;6240;13738.519151829638;13980.951428642893;17871.195796189204;26607.20178394651;26855.46289074227;1974;2256.751757306695;2260.7061082059226;2459.446624509489;2702.3109107177543;10591.099756520705;11658;12027.27836329823;12880.613581592566;13320.666983325398;1872.5412273992822;1934.516370907273;1953.7655481342188;2010.4772759082744;2058.372196480073;88415.11584884198;103915;113891.43054284758;128004.35997820045;136140;11194.664160075048;11435;11572.363520006313;11767.940610393147;11893.88106118945;15025.774613380809;15059.322330495153;15061.698766024618;15074.698506030038;15086.773698394314;7773.5230306242765;8798.912010879938;9027.819443611223;9029.23251523628;9043.59303831329;9075.2286055686;9093.090690930987;9112.589933453011;9231.76920576991;9297.95351023254;4580.725156490621;4587.587117159472;4602.631789456836;4608.493065762764;4694.108119457293;1632.9712281262728;1909.303104366924;1944.1542928825722;1947.9805201948082;1958.676818324989;762.4243696009478;787.629813987425;847.2036285891243;879.6877108626474;970.1462712812821;20880.582388352148;22201.555968880533;22315.21896733639;22334.056312406803;22511;951.8619800128943;988.17981075707;1008.1380419741163;1044.4099084017657;1050.9421981790922;11390.829137562943;11683.123765717604;11833;11859.288442693465;11870.821937670942;615573;617746;620216;629946;630303;102836.97163028424;129151.35424323006;131873.20170651926;133980;136602.63397366097;837.656560810065;970.7573106723319;991.9652812151685;994.5623925472769;1027.9897201028043;18697.813021869606;18953.90523047376;19481.805181948566;19497;19521.828690278628;44273.77863110664;46202.76898615486;51687.74156129195;52373.47626523686;55385.44614553803;57761.71119144378;77626.61186694031;77665.61167194128;77736;84544;7459;7904.103015276177;8321.459105158145;8337.416380853281;8385.832283354177;12971.610851674464;13014.479420823061;13218.339083045617;13583.592492225249;13917.234552099326;10343.793124137626;10626.73433164177;10733.463326833471;11907.285562866256;12092.516299347928;11426.428678566204;11590.5363785451;11683.474243659226;11906.821397678868;12042.75914481723;11735.882641173479;11763.764804695726;11842.940785296018;12003.939980300393;12222.75554488923;7674.347680447225;7696.576688282198;7754.185810490043;7802.765917022499;7905.723299684483;8038.316743076904;11641.53433862636;11757.412129393326;11851.466683999413;12023.338716370683 05:45:16 INFO - PROCESS | 1639 | |3;mootools.html;1523.428714232163;1614.9273282702277;1819.417786308381;1827.0773259503949;1956.5206524401524;8930.24092952099;9488.905110948888;9497.762555936097;9499.762505937355;9506.85739713904;8322.126176751444;8532.146785321465;9062.229710474601;9198.908010919906;9218.40080394774;2456.4595788926454;2489;2490.0164435048146;2490.340059884132;2490.5267999080206;2425.8059355251626;2483.50329934013;2487.2040946897;2489.4896546208074;2490.9252722418355;2455.1407007547396;2471.789897858684;2482.441450673602;2485.453200295932;2486.7264600893886;2413.0347860855622;2425.6240282756203;2433.829631925766;2454.0306578901414;2458.532878753031;2435.9147429839873;2453.288546321565;2461.052494789501;2462.384403899025;2468.4816188600416;9293.070692930653;9315.161635452841;9402.15380615748;9415.670451534163;9430.387024843363;5471.835844924619;5643.125315576092;5647.689377084259;5649.774009039634;5659.717014149317;3608.855645774166;3719.79541125238;3796.772193668361;3800.6959443244473;3801.353769859131;908.7500937242245;1103.5475455063386;1106.4910141334994;1125.4654039331333;1128.8081026225561;12702.618921432371;12755.61733148007;12760.680982975407;12768.170068945488;12784.808227876589;4275;4696.94318778279;4826.276058591169;5012.1980483122525;5024.196128619476;10746.838797418044;10767;10771.461426928623;10773.83839242412;10779.622713205;5654.575906807006;5657.462541058593;5658.434156584391;5662.405447428043;5664.20701101846;4646.907061858744;4659.813607455664;4778.426588809365;5113.002964421902;5187.170052791535;4095.607493452181;4115.773632450184;4193.32906734927;4222.556631553643;4234.13200293939;1295.598364507006;1307.267929959226;1311.3246677960858;1325.3969443903002;1325.5956933135294;797.9162187970179;846.3398549131687;886.4902680958475;919.0809190809191;936.2182577547687;5793.94206057934;5924.763009479572;5961.314448838352;6151.815445536641;6256.6871656417;923.971484328328;970.8786401699788;981.3817295104038;1003.9247056470793;1014.1177175857051;3357.3117510910206;3912.4718163047623;4009.51885773709;4014.237294913957;4091.57038510958;10966.945165274283;11314.660560183207;11339.376334301693;11633.127515436376;11639.06887449002;11543.711407214887;11548.249363791327;11596.652100437;11629.651110466699;11707.473163707653;8570.914290857012;8801.91198088011;8803.779905502413;8804.207621314112;8810.911890881009;5819.76720931175;6158.753649854135;6164.291106522807;6180.6909654517995;6302.968485157545;10312.896871031493;10519;10608.416537090534;10738.46307684597;10871.23901326931;10754.569817207224;11057.115430765358;11066.944665276622;11085.556577736801;11089.05743011853;7016.649167541705;7548.962255188909;7913.089994650459;8080.191980801873;8088.63601137962;8566.871496927675;8641.82716345657;8645.783855403664;8646.351523635627;8651.87022194655;6274.46667033289;7294.452916031213;7444.739434119996;7449.813754656177;7535.849283014419;7208.819779505345;7241.203467618663;7242.058532390755;7349.926500735138;7431.702731890664;7244.89132663;7427.7771666850085;7434.182239953492;7435.665395057127;7435.962820185864;5134.743262836918;5141.048905952411;5167.199084141964;5202.791888324425;5317.867053323698;4805.687630304019;4921.384826896638;5072.721000345016;5084.288199651977;5276.050310943913 05:45:16 INFO - PROCESS | 1639 | |4;prototype.html;275.3281991939667;286.8150043222123;301.4017175905825;309.52024362238524;313.1045210697405;4550.450648494176;5083;5297.920531192035;5429.511343979041;5435.592330575208;4284.850030248942;5148.819791307305;5278.551323137528;5353.411124776271;5380.461953804617;3351.463765797473;3525.2596954639557;3858.517685289346;4077.1641813428178;4112.013116851964;3249.8375081245845;3384.6615338466204;4053.27041132596;4119.794010299488;4122.628963393294;3504.281622267429;3851.28751181031;3969.7618142911374;4024.859129930453;4053.209624123297;3601.94597081044;3643.6336373859804;3674.6036506127766;4017.4375587417785;4026.174634199982;3412.522246885438;3497.7376696747847;3500.912477188065;3507.912547110404;3547.2018795771005;4764.785584648699;4960.528749768766;5199.844004679866;5226.719453733822;5303.36359636846;4158.459400277975;4301.849435269782;4573.353592706623;4601.7699115044115;4616;4984.953159836438;5210.869728256786;5649.350324712669;5655.5963715420785;5662.6036177467595;3083.4758091124563;3560.9821950890337;3807.428885667144;3828.5405751310022;3882.9223415531815;5824.213731146283;5878.676672782995;5933.893467818905;5951.49412299955;5958.020989505248;3382.3573521030926;3821.9235615288094;3870.5161854768153;3881.670058045098;3973.205358928226;3681.7606855554304;3860.2665493556133;4017.4288455388573;4038.3336749436603;4116.814743336557;4555.658325625525;4596.195665758479;4596.5173656765555;4605.585497305258;4683.414573178353;4993.725345105981;5010.215244441622;5247.658902171346;5673.3475650299915;5680.687562184037;1275.3113318807925;1307.8169056332122;1323.3656434303628;1337.0506940072462;1341.208686874729;840.4201101240125;879.8108406692592;883.394874510962;914.3051281026468;921.1709461484584;5215.5226267523385;5215.869603259873;5232.895342093213;5286.590149353646;5295.311609490742;5697.544196464273;5747.896136086467;5753.223314852525;5757.222774925415;5773.055741571829;4950.777215025332;5206.843794686166;5223.850752834371;5290.338707661543;5307.416184219733;2196.7473740520045;2215.1360969222014;2302.4228403543293;2525.3055409762096;2552.970388541114;4141.337386018283;4241.97879010603;4387.232234358974;4514.142312960527;4659.627229821606;4178.498580170399;4225.915481690349;4411;4870.806652370192;5224;4945.727984960861;5757.5393968481585;5933.525317974638;5951.8214453567325;5970.9701451492465;4912.803487860446;5236.607254455855;5275.498827611525;5313.707746074003;5769.221155144001;4364.650827933821;5187.974060129675;5266.418835192107;5657.943420565742;5683.943160568341;4619.076184763128;5944.64332140073;5977.760889564369;6074.149619053248;6085.4523092921845;3951.348027575533;4098.918021639491;4102.835886564504;4114.259433302034;4130.607592278729;4987.576056035277;5095.490450954875;5400.540954018952;5418.618252345626;5570.91643625354;5272.367315922113;5499.395066542757;5512.173174023928;5563.304586926634;5641.294838145232;4378.190034843564;5523.502884740232;5624.550035997192;5654.180143879197;5666.065099258576;4875.024995000943;5191;5225.059489291964;5362.65142765719;5595.776168953196;5316.946830531645;5595.972020139873;5611.306572845139;5613.147661271689;5637.351704553865;5148.536631703165;5394.595405344537;5511.724413779375;5541.9168712470155;5543.722813859372 05:45:16 INFO - PROCESS | 1639 | |5;yui.html;708.4757279613085;756.6027835386423;787.2403130978604;800.4516905919446;830.4560512864076;16275.023498590079;17107.40124095657;17113.22990465429;17149.485515434524;17192;15211.011284266515;15299.15854627995;15309.84690153101;15310.928235023553;15317.617059573517;703.3529153179062;718.3283629806122;865.329369738453;873.9344549158807;898.3890954151175;895.7133717210495;901.1529162587179;905.9094090590921;907.5008745190119;907.8728977943093;952.8999455057224;953.0659953245823;954.8949615542285;955.1356022799378;957.9760505987337;947.0766003146946;948.8149810786899;949.7150854743618;950.325269058969;951.9809603807885;703.3739971425384;705.3510770091477;706.1103010207124;706.2302090721139;706.8550947055841;15451.459198928096;15578.29897654597;15622.375104995786;15625.609359766097;15649.530514084596;12739.936300318532;12763;13550.93224533871;13690.726185476335;13986.440542378292;13612.93193534026;13788;13890.722185556333;13969.3713782879;14063.367148478343;4491.820327186909;4571.3828633134435;4632.189366860786;4760.119377915097;4776.880577985544;77622;78216;105735.94264057415;138715.30642346825;138883;11253.774924501628;11292.266002709797;11384.772304553862;11564.76870462586;11764.82352764692;22036;22036.559268814533;22048.779512204994;22057.77942220557;22087;7947.960260198662;7949.205079492121;8274.710385136492;8361.414700970996;8477.576121193915;4573.862784116482;4592.425946756655;4628.166929952641;4697.389339385858;4835.323054772335;3599.964000359963;3734.327820992193;3736.5142531470733;3739.719521035933;3742.4947632069866;523.5235935298936;534.9144136938071;536.493014101678;537.9784808607612;544.4256309593417;548.9807856724996;631.3087169549336;645.7804346522205;668.5387082912774;679.5582871133803;9135.817283654289;9403.811923761486;9462.243020558339;9485.387484127716;9624;556.6854727079187;564.5370795947284;635.5995722694673;637.7353398339637;660.9504287178481;3963.365861462152;4303.311470164821;4565.178267911808;4595.64963780219;4627.583517483443;107847;171551;173212.26787731962;176386;178912.10543947198;112225.43887280674;120095.39952300182;120775.33797296195;137533.3123334377;139930.3003484976;97777;97951.51024244833;106703;110758;116921;9542.898557968549;10269.537870795682;10611.257211995086;10682;10689.57241710354;47510.762446187546;61612.07581886365;62659.06011409924;62683.37316626775;62840.685796570724;71374;85498.4350469487;90395;92707.53646231725;93505;13585.701576385416;14068.929655351658;14083.436662533793;14288.642374008588;14378.568642940729;13182.01134914866;15463.316131927575;15565.299561519532;15751.763723544385;15785.210739463211;14222.857771422154;14557.781633275721;14876.330565124379;14900.701985960004;14924.701505970037;14503.492377766728;14777.630559236106;14887.47893823711;14900.552983410515;15050.849491504945;11096.5561377544;11852.348120853436;14387.568372948828;14622.853771462149;14734.926325368733;11159.442027898735;11789.351585662871;11850.747751776153;12100.757984840078;12296.077794165294;11925;11925.437304317948;11987.820182697093;12223.266604003788;12229.082818788449 05:45:16 INFO - PROCESS | 1639 | __end_tp_report 05:45:16 INFO - PROCESS | 1639 | __start_cc_report 05:45:16 INFO - PROCESS | 1639 | _x_x_mozilla_cycle_collect,1804 05:45:16 INFO - PROCESS | 1639 | __end_cc_report 05:45:16 INFO - PROCESS | 1639 | __startTimestamp1479476716539__endTimestamp 05:45:16 INFO - PROCESS | 1639 | 05:45:16 INFO - PROCESS | 1639 | ------- Summary: start ------- 05:45:16 INFO - PROCESS | 1639 | Number of tests: 6 05:45:16 INFO - PROCESS | 1639 | 05:45:16 INFO - PROCESS | 1639 | [#0] dojo.html Cycles:165 Average:5901.64 Median:6196.41 stddev:655.39 (10.6%) stddev-sans-first:629.36 05:45:16 INFO - PROCESS | 1639 | Values: 3483.8 3493.8 3537.1 3548.6 3595.5 5836.3 5886.7 5904.1 5925.7 5945.8 5827.8 5891.0 5904.2 5904.9 5909.9 4647.7 4678.8 5284.9 5316.3 5372.8 4330.7 5349.4 5354.4 5393.6 5412.4 4750.2 5161.9 5387.2 5404.1 5414.2 5394.5 5395.0 5401.9 5405.3 5409.9 5229.1 5255.2 5340.8 5345.7 5394.0 5894.9 5915.3 5930.3 5971.7 6004.0 6317.3 6361.9 6364.6 6377.7 6379.2 6276.8 6302.6 6310.6 6322.6 6355.5 5892.5 5919.6 5962.8 6008.5 6034.8 6413.6 6418.4 6425.0 6447.6 6456.0 6142.4 6273.6 6324.2 6326.1 6341.9 6281.6 6298.1 6329.2 6393.3 6398.7 6346.3 6347.5 6358.1 6361.0 6374.2 6193.3 6248.3 6357.5 6386.2 6405.8 5078.7 5093.1 5132.5 5139.3 5146.1 4604.2 4613.1 4629.5 4647.8 4648.4 6089.4 6091.3 6092.9 6096.0 6104.5 6235.0 6259.7 6270.8 6343.3 6359.5 5978.2 6034.3 6040.5 6058.8 6064.9 5591.4 5608.0 5608.6 5611.7 5621.8 6234.1 6353.9 6520.1 6520.2 6530.9 6309.2 6355.9 6372.8 6372.9 6380.9 6491.5 6493.4 6510.2 6515.6 6523.5 6199.4 6329.1 6359.4 6364.3 6406.7 6341.0 6346.4 6375.4 6383.5 6387.5 6162.3 6247.1 6270.7 6345.7 6356.1 6284.0 6330.1 6334.7 6353.7 6354.1 6000.6 6209.3 6215.7 6230.3 6243.7 6175.6 6196.4 6220.0 6267.3 6268.3 6083.0 6150.3 6200.7 6207.8 6216.1 05:45:16 INFO - PROCESS | 1639 | 05:45:16 INFO - PROCESS | 1639 | [#1] ext.html Cycles:180 Average:82398.79 Median:37632.03 stddev:190470.27 (506.1%) stddev-sans-first:190905.18 05:45:16 INFO - PROCESS | 1639 | Values: 436.8 538.7 544.0 545.3 547.9 33479.5 43943.9 44766.8 44887.1 47543.3 46646.8 46988.3 47180.8 47205.0 47242.3 9369.0 10081.7 10252.5 10274.3 10279.9 9148.4 9542.7 9545.0 9547.7 12450.5 12458.7 12705.4 12714.6 12716.4 12719.7 12638.6 12718.9 12735.7 12749.6 12753.4 10505.8 12243.5 12554.4 12577.2 12585.7 38729.6 41070.2 43711.3 44095.8 44112.0 3471.9 3540.3 3671.4 3708.9 3733.6 32455.4 33312.5 33753.0 33880.8 34483.5 3349.9 3362.0 3447.4 3463.5 3507.6 542983.0 543866.0 543985.0 544571.0 545628.0 67646.7 68022.0 68068.7 68152.7 68203.7 29247.7 36995.8 37005.1 37876.4 37993.2 18433.4 18571.3 18604.1 18610.1 18626.4 13996.7 14077.9 14079.6 14141.4 14142.6 18205.7 18337.8 18347.9 18562.0 20118.8 7176.9 8332.9 8354.3 8355.0 8433.5 937.6 968.5 970.6 1114.4 1115.1 26274.6 32067.5 32134.5 32273.5 33129.8 891.9 1008.3 1061.3 1186.4 1225.5 15875.3 16375.5 16623.3 16641.8 16857.3 750613.5 753506.0 754379.0 755987.0 766151.0 53516.7 54610.7 55228.7 62446.5 63058.0 1441.3 1455.9 1471.1 1486.1 1488.8 27584.0 28437.0 29045.7 29094.9 29406.3 56144.9 60712.7 61279.4 61415.1 65741.0 625745.0 719931.0 720120.0 928932.0 952630.0 45059.0 45214.8 45507.1 45898.0 46043.3 43695.8 46970.1 49843.5 49924.0 50392.5 37636.6 40720.6 42483.8 42645.3 45119.0 35410.5 42451.8 42692.6 43094.8 44075.0 41254.8 41997.8 42248.9 42257.0 42391.2 35875.0 36615.5 36676.1 37210.9 37627.4 27499.9 27519.8 27536.2 27538.9 27882.4 05:45:16 INFO - PROCESS | 1639 | 05:45:16 INFO - PROCESS | 1639 | [#2] jquery.html Cycles:180 Average:35285.44 Median:12390.21 stddev:103639.65 (836.5%) stddev-sans-first:103897.24 05:45:16 INFO - PROCESS | 1639 | Values: 373.7 380.3 385.4 393.0 404.1 16230.0 17568.9 20456.7 22450.8 22523.7 12029.6 12240.1 12569.9 12581.4 12592.5 5428.4 5769.8 5770.3 5773.4 5791.7 4070.9 4230.5 4251.6 5423.8 5679.1 7035.2 7037.2 7156.9 7161.5 7162.5 6833.4 7040.1 7088.9 7108.8 7137.8 5991.6 6226.4 6234.4 6239.4 6240.0 13738.5 13981.0 17871.2 26607.2 26855.5 1974.0 2256.8 2260.7 2459.4 2702.3 10591.1 11658.0 12027.3 12880.6 13320.7 1872.5 1934.5 1953.8 2010.5 2058.4 88415.1 103915.0 113891.4 128004.4 136140.0 11194.7 11435.0 11572.4 11767.9 11893.9 15025.8 15059.3 15061.7 15074.7 15086.8 7773.5 8798.9 9027.8 9029.2 9043.6 9075.2 9093.1 9112.6 9231.8 9298.0 4580.7 4587.6 4602.6 4608.5 4694.1 1633.0 1909.3 1944.2 1948.0 1958.7 762.4 787.6 847.2 879.7 970.1 20880.6 22201.6 22315.2 22334.1 22511.0 951.9 988.2 1008.1 1044.4 1050.9 11390.8 11683.1 11833.0 11859.3 11870.8 615573.0 617746.0 620216.0 629946.0 630303.0 102837.0 129151.4 131873.2 133980.0 136602.6 837.7 970.8 992.0 994.6 1028.0 18697.8 18953.9 19481.8 19497.0 19521.8 44273.8 46202.8 51687.7 52373.5 55385.4 57761.7 77626.6 77665.6 77736.0 84544.0 7459.0 7904.1 8321.5 8337.4 8385.8 12971.6 13014.5 13218.3 13583.6 13917.2 10343.8 10626.7 10733.5 11907.3 12092.5 11426.4 11590.5 11683.5 11906.8 12042.8 11735.9 11763.8 11842.9 12003.9 12222.8 7674.3 7696.6 7754.2 7802.8 7905.7 8038.3 11641.5 11757.4 11851.5 12023.3 05:45:16 INFO - PROCESS | 1639 | 05:45:16 INFO - PROCESS | 1639 | [#3] mootools.html Cycles:180 Average:6004.41 Median:4792.06 stddev:3447.66 (71.9%) stddev-sans-first:3440.89 05:45:16 INFO - PROCESS | 1639 | Values: 1523.4 1614.9 1819.4 1827.1 1956.5 8930.2 9488.9 9497.8 9499.8 9506.9 8322.1 8532.1 9062.2 9198.9 9218.4 2456.5 2489.0 2490.0 2490.3 2490.5 2425.8 2483.5 2487.2 2489.5 2490.9 2455.1 2471.8 2482.4 2485.5 2486.7 2413.0 2425.6 2433.8 2454.0 2458.5 2435.9 2453.3 2461.1 2462.4 2468.5 9293.1 9315.2 9402.2 9415.7 9430.4 5471.8 5643.1 5647.7 5649.8 5659.7 3608.9 3719.8 3796.8 3800.7 3801.4 908.8 1103.5 1106.5 1125.5 1128.8 12702.6 12755.6 12760.7 12768.2 12784.8 4275.0 4696.9 4826.3 5012.2 5024.2 10746.8 10767.0 10771.5 10773.8 10779.6 5654.6 5657.5 5658.4 5662.4 5664.2 4646.9 4659.8 4778.4 5113.0 5187.2 4095.6 4115.8 4193.3 4222.6 4234.1 1295.6 1307.3 1311.3 1325.4 1325.6 797.9 846.3 886.5 919.1 936.2 5793.9 5924.8 5961.3 6151.8 6256.7 924.0 970.9 981.4 1003.9 1014.1 3357.3 3912.5 4009.5 4014.2 4091.6 10966.9 11314.7 11339.4 11633.1 11639.1 11543.7 11548.2 11596.7 11629.7 11707.5 8570.9 8801.9 8803.8 8804.2 8810.9 5819.8 6158.8 6164.3 6180.7 6303.0 10312.9 10519.0 10608.4 10738.5 10871.2 10754.6 11057.1 11066.9 11085.6 11089.1 7016.6 7549.0 7913.1 8080.2 8088.6 8566.9 8641.8 8645.8 8646.4 8651.9 6274.5 7294.5 7444.7 7449.8 7535.8 7208.8 7241.2 7242.1 7349.9 7431.7 7244.9 7427.8 7434.2 7435.7 7436.0 5134.7 5141.0 5167.2 5202.8 5317.9 4805.7 4921.4 5072.7 5084.3 5276.1 05:45:16 INFO - PROCESS | 1639 | 05:45:16 INFO - PROCESS | 1639 | [#4] prototype.html Cycles:180 Average:4434.50 Median:4986.26 stddev:1385.87 (27.8%) stddev-sans-first:1354.15 05:45:16 INFO - PROCESS | 1639 | Values: 275.3 286.8 301.4 309.5 313.1 4550.5 5083.0 5297.9 5429.5 5435.6 4284.9 5148.8 5278.6 5353.4 5380.5 3351.5 3525.3 3858.5 4077.2 4112.0 3249.8 3384.7 4053.3 4119.8 4122.6 3504.3 3851.3 3969.8 4024.9 4053.2 3601.9 3643.6 3674.6 4017.4 4026.2 3412.5 3497.7 3500.9 3507.9 3547.2 4764.8 4960.5 5199.8 5226.7 5303.4 4158.5 4301.8 4573.4 4601.8 4616.0 4985.0 5210.9 5649.4 5655.6 5662.6 3083.5 3561.0 3807.4 3828.5 3882.9 5824.2 5878.7 5933.9 5951.5 5958.0 3382.4 3821.9 3870.5 3881.7 3973.2 3681.8 3860.3 4017.4 4038.3 4116.8 4555.7 4596.2 4596.5 4605.6 4683.4 4993.7 5010.2 5247.7 5673.3 5680.7 1275.3 1307.8 1323.4 1337.1 1341.2 840.4 879.8 883.4 914.3 921.2 5215.5 5215.9 5232.9 5286.6 5295.3 5697.5 5747.9 5753.2 5757.2 5773.1 4950.8 5206.8 5223.9 5290.3 5307.4 2196.7 2215.1 2302.4 2525.3 2553.0 4141.3 4242.0 4387.2 4514.1 4659.6 4178.5 4225.9 4411.0 4870.8 5224.0 4945.7 5757.5 5933.5 5951.8 5971.0 4912.8 5236.6 5275.5 5313.7 5769.2 4364.7 5188.0 5266.4 5657.9 5683.9 4619.1 5944.6 5977.8 6074.1 6085.5 3951.3 4098.9 4102.8 4114.3 4130.6 4987.6 5095.5 5400.5 5418.6 5570.9 5272.4 5499.4 5512.2 5563.3 5641.3 4378.2 5523.5 5624.6 5654.2 5666.1 4875.0 5191.0 5225.1 5362.7 5595.8 5316.9 5596.0 5611.3 5613.1 5637.4 5148.5 5394.6 5511.7 5541.9 5543.7 05:45:16 INFO - PROCESS | 1639 | 05:45:16 INFO - PROCESS | 1639 | [#5] yui.html Cycles:180 Average:25126.50 Median:3667.15 stddev:40381.76 (1101.2%) stddev-sans-first:40453.42 05:45:16 INFO - PROCESS | 1639 | Values: 708.5 756.6 787.2 800.5 830.5 16275.0 17107.4 17113.2 17149.5 17192.0 15211.0 15299.2 15309.8 15310.9 15317.6 703.4 718.3 865.3 873.9 898.4 895.7 901.2 905.9 907.5 907.9 952.9 953.1 954.9 955.1 958.0 947.1 948.8 949.7 950.3 952.0 703.4 705.4 706.1 706.2 706.9 15451.5 15578.3 15622.4 15625.6 15649.5 12739.9 12763.0 13550.9 13690.7 13986.4 13612.9 13788.0 13890.7 13969.4 14063.4 4491.8 4571.4 4632.2 4760.1 4776.9 77622.0 78216.0 105735.9 138715.3 138883.0 11253.8 11292.3 11384.8 11564.8 11764.8 22036.0 22036.6 22048.8 22057.8 22087.0 7948.0 7949.2 8274.7 8361.4 8477.6 4573.9 4592.4 4628.2 4697.4 4835.3 3600.0 3734.3 3736.5 3739.7 3742.5 523.5 534.9 536.5 538.0 544.4 549.0 631.3 645.8 668.5 679.6 9135.8 9403.8 9462.2 9485.4 9624.0 556.7 564.5 635.6 637.7 661.0 3963.4 4303.3 4565.2 4595.6 4627.6 107847.0 171551.0 173212.3 176386.0 178912.1 112225.4 120095.4 120775.3 137533.3 139930.3 97777.0 97951.5 106703.0 110758.0 116921.0 9542.9 10269.5 10611.3 10682.0 10689.6 47510.8 61612.1 62659.1 62683.4 62840.7 71374.0 85498.4 90395.0 92707.5 93505.0 13585.7 14068.9 14083.4 14288.6 14378.6 13182.0 15463.3 15565.3 15751.8 15785.2 14222.9 14557.8 14876.3 14900.7 14924.7 14503.5 14777.6 14887.5 14900.6 15050.8 11096.6 11852.3 14387.6 14622.9 14734.9 11159.4 11789.4 11850.7 12100.8 12296.1 11925.0 11925.4 11987.8 12223.3 12229.1 05:45:16 INFO - PROCESS | 1639 | -------- Summary: end -------- 05:45:16 INFO - PROCESS | 1639 | 05:45:17 INFO - TEST-INFO | 1639: exit 0 05:45:17 INFO - TEST-OK | dromaeo_css | took 1116239ms 05:45:17 INFO - TEST-START | kraken 05:45:17 INFO - operating with platform_type : mac_ 05:45:17 INFO - Initialising browser for kraken test... 05:45:17 INFO - TEST-INFO | started process 1650 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/tmpcnFR47/profile http://localhost:49246/getInfo.html) 05:45:19 INFO - PROCESS | 1650 | __metrics Screen width/height:1600/1200 05:45:19 INFO - PROCESS | 1650 | colorDepth:24 05:45:19 INFO - PROCESS | 1650 | Browser inner width/height: 1024/689 05:45:19 INFO - PROCESS | 1650 | __metrics 05:45:19 INFO - PROCESS | 1650 | 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" 05:45:19 INFO - PROCESS | 1650 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 05:45:19 INFO - TEST-INFO | 1650: exit 0 05:45:19 INFO - Browser initialized. 05:45:19 INFO - Running cycle 1/1 for kraken test... 05:45:19 INFO - TEST-INFO | started process 1652 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/tmpcnFR47/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:45:31 INFO - PROCESS | 1652 | RSS: Main: 168808448 05:45:31 INFO - PROCESS | 1652 | 05:45:34 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49246/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49246/tests/kraken/driver.html?testName=audio-beat-detection) 05:45:35 INFO - PROCESS | 1652 | RSS: Main: 218497024 05:45:35 INFO - PROCESS | 1652 | 05:45:38 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49246/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49246/tests/kraken/driver.html?testName=audio-dft) 05:45:38 INFO - PROCESS | 1652 | RSS: Main: 228773888 05:45:38 INFO - PROCESS | 1652 | 05:45:41 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49246/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49246/tests/kraken/driver.html?testName=audio-fft) 05:45:42 INFO - PROCESS | 1652 | RSS: Main: 326021120 05:45:42 INFO - PROCESS | 1652 | 05:45:44 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49246/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49246/tests/kraken/driver.html?testName=audio-oscillator) 05:45:45 INFO - PROCESS | 1652 | RSS: Main: 228061184 05:45:45 INFO - PROCESS | 1652 | 05:45:47 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49246/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49246/tests/kraken/driver.html?testName=imaging-gaussian-blur) 05:45:47 INFO - PROCESS | 1652 | RSS: Main: 228548608 05:45:47 INFO - PROCESS | 1652 | 05:45:50 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49246/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49246/tests/kraken/driver.html?testName=imaging-darkroom) 05:45:51 INFO - PROCESS | 1652 | RSS: Main: 266690560 05:45:51 INFO - PROCESS | 1652 | 05:45:54 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49246/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49246/tests/kraken/driver.html?testName=imaging-desaturate) 05:45:54 INFO - PROCESS | 1652 | RSS: Main: 265240576 05:45:54 INFO - PROCESS | 1652 | 05:45:57 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49246/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49246/tests/kraken/driver.html?testName=json-parse-financial) 05:45:57 INFO - PROCESS | 1652 | RSS: Main: 262926336 05:45:57 INFO - PROCESS | 1652 | 05:45:59 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49246/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49246/tests/kraken/driver.html?testName=json-stringify-tinderbox) 05:46:00 INFO - PROCESS | 1652 | RSS: Main: 240812032 05:46:00 INFO - PROCESS | 1652 | 05:46:02 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49246/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49246/tests/kraken/driver.html?testName=stanford-crypto-aes) 05:46:02 INFO - PROCESS | 1652 | RSS: Main: 244776960 05:46:02 INFO - PROCESS | 1652 | 05:46:04 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49246/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49246/tests/kraken/driver.html?testName=stanford-crypto-ccm) 05:46:05 INFO - PROCESS | 1652 | RSS: Main: 256663552 05:46:05 INFO - PROCESS | 1652 | 05:46:08 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49246/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49246/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 05:46:09 INFO - PROCESS | 1652 | RSS: Main: 266010624 05:46:09 INFO - PROCESS | 1652 | 05:46:11 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49246/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49246/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 05:46:12 INFO - PROCESS | 1652 | RSS: Main: 260108288 05:46:12 INFO - PROCESS | 1652 | 05:46:14 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49246/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49246/tests/kraken/driver.html?testName=ai-astar) 05:46:14 INFO - PROCESS | 1652 | RSS: Main: 304025600 05:46:14 INFO - PROCESS | 1652 | 05:46:14 INFO - PROCESS | 1652 | __start_tp_report 05:46:14 INFO - PROCESS | 1652 | _x_x_mozilla_page_load 05:46:14 INFO - PROCESS | 1652 | _x_x_mozilla_page_load_details 05:46:14 INFO - PROCESS | 1652 | |i|pagename|runs| 05:46:14 INFO - PROCESS | 1652 | |0;ai-astar;113;119;167;161;111;116;160;112;112;111 05:46:14 INFO - PROCESS | 1652 | |1;audio-beat-detection;141;108;107;139;108;106;107;106;138;108 05:46:14 INFO - PROCESS | 1652 | |2;audio-dft;194;262;207;182;183;183;185;183;182;182 05:46:14 INFO - PROCESS | 1652 | |3;audio-fft;86;60;83;88;61;58;58;57;83;57 05:46:14 INFO - PROCESS | 1652 | |4;audio-oscillator;86;71;91;68;66;67;92;67;68;89 05:46:14 INFO - PROCESS | 1652 | |5;imaging-gaussian-blur;119;80;119;125;89;85;90;87;90;87 05:46:14 INFO - PROCESS | 1652 | |6;imaging-darkroom;95;96;107;96;100;144;137;95;96;95 05:46:14 INFO - PROCESS | 1652 | |7;imaging-desaturate;83;83;85;83;84;83;83;83;84;83 05:46:14 INFO - PROCESS | 1652 | |8;json-parse-financial;56;53;52;52;54;53;75;84;84;86 05:46:14 INFO - PROCESS | 1652 | |9;json-stringify-tinderbox;72;64;69;75;70;67;68;69;69;70 05:46:14 INFO - PROCESS | 1652 | |10;stanford-crypto-aes;84;80;80;84;80;80;80;89;88;81 05:46:14 INFO - PROCESS | 1652 | |11;stanford-crypto-ccm;133;134;137;139;142;128;140;146;195;149 05:46:14 INFO - PROCESS | 1652 | |12;stanford-crypto-pbkdf2;160;165;161;160;161;158;158;167;221;188 05:46:14 INFO - PROCESS | 1652 | |13;stanford-crypto-sha256-iterative;65;65;65;65;65;64;66;65;65;64 05:46:14 INFO - PROCESS | 1652 | __end_tp_report 05:46:14 INFO - PROCESS | 1652 | __start_cc_report 05:46:14 INFO - PROCESS | 1652 | _x_x_mozilla_cycle_collect,1882 05:46:14 INFO - PROCESS | 1652 | __end_cc_report 05:46:14 INFO - PROCESS | 1652 | __startTimestamp1479476774404__endTimestamp 05:46:14 INFO - PROCESS | 1652 | 05:46:14 INFO - PROCESS | 1652 | ------- Summary: start ------- 05:46:14 INFO - PROCESS | 1652 | Number of tests: 14 05:46:14 INFO - PROCESS | 1652 | 05:46:14 INFO - PROCESS | 1652 | [#0] ai-astar Cycles:10 Average:128.20 Median:117.50 stddev:23.98 (20.4%) stddev-sans-first:24.79 05:46:14 INFO - PROCESS | 1652 | Values: 113.0 119.0 167.0 161.0 111.0 116.0 160.0 112.0 112.0 111.0 05:46:14 INFO - PROCESS | 1652 | 05:46:14 INFO - PROCESS | 1652 | [#1] audio-beat-detection Cycles:10 Average:116.80 Median:108.00 stddev:15.58 (14.4%) stddev-sans-first:13.85 05:46:14 INFO - PROCESS | 1652 | Values: 141.0 108.0 107.0 139.0 108.0 106.0 107.0 106.0 138.0 108.0 05:46:14 INFO - PROCESS | 1652 | 05:46:14 INFO - PROCESS | 1652 | [#2] audio-dft Cycles:10 Average:194.30 Median:184.00 stddev:25.10 (13.6%) stddev-sans-first:26.62 05:46:14 INFO - PROCESS | 1652 | Values: 194.0 262.0 207.0 182.0 183.0 183.0 185.0 183.0 182.0 182.0 05:46:14 INFO - PROCESS | 1652 | 05:46:14 INFO - PROCESS | 1652 | [#3] audio-fft Cycles:10 Average:69.10 Median:72.00 stddev:13.81 (19.2%) stddev-sans-first:13.23 05:46:14 INFO - PROCESS | 1652 | Values: 86.0 60.0 83.0 88.0 61.0 58.0 58.0 57.0 83.0 57.0 05:46:14 INFO - PROCESS | 1652 | 05:46:14 INFO - PROCESS | 1652 | [#4] audio-oscillator Cycles:10 Average:76.50 Median:78.50 stddev:11.37 (14.5%) stddev-sans-first:11.52 05:46:14 INFO - PROCESS | 1652 | Values: 86.0 71.0 91.0 68.0 66.0 67.0 92.0 67.0 68.0 89.0 05:46:14 INFO - PROCESS | 1652 | 05:46:14 INFO - PROCESS | 1652 | [#5] imaging-gaussian-blur Cycles:10 Average:97.10 Median:87.00 stddev:16.82 (19.3%) stddev-sans-first:15.87 05:46:14 INFO - PROCESS | 1652 | Values: 119.0 80.0 119.0 125.0 89.0 85.0 90.0 87.0 90.0 87.0 05:46:14 INFO - PROCESS | 1652 | 05:46:14 INFO - PROCESS | 1652 | [#6] imaging-darkroom Cycles:10 Average:106.10 Median:95.00 stddev:18.57 (19.6%) stddev-sans-first:19.26 05:46:14 INFO - PROCESS | 1652 | Values: 95.0 96.0 107.0 96.0 100.0 144.0 137.0 95.0 96.0 95.0 05:46:14 INFO - PROCESS | 1652 | 05:46:14 INFO - PROCESS | 1652 | [#7] imaging-desaturate Cycles:10 Average:83.40 Median:83.00 stddev:0.70 (0.8%) stddev-sans-first:0.73 05:46:14 INFO - PROCESS | 1652 | Values: 83.0 83.0 85.0 83.0 84.0 83.0 83.0 83.0 84.0 83.0 05:46:14 INFO - PROCESS | 1652 | 05:46:14 INFO - PROCESS | 1652 | [#8] json-parse-financial Cycles:10 Average:64.90 Median:65.50 stddev:15.24 (23.3%) stddev-sans-first:15.82 05:46:14 INFO - PROCESS | 1652 | Values: 56.0 53.0 52.0 52.0 54.0 53.0 75.0 84.0 84.0 86.0 05:46:14 INFO - PROCESS | 1652 | 05:46:14 INFO - PROCESS | 1652 | [#9] json-stringify-tinderbox Cycles:10 Average:69.30 Median:69.50 stddev:2.91 (4.2%) stddev-sans-first:2.92 05:46:14 INFO - PROCESS | 1652 | Values: 72.0 64.0 69.0 75.0 70.0 67.0 68.0 69.0 69.0 70.0 05:46:14 INFO - PROCESS | 1652 | 05:46:14 INFO - PROCESS | 1652 | [#10] stanford-crypto-aes Cycles:10 Average:82.60 Median:82.50 stddev:3.50 (4.2%) stddev-sans-first:3.68 05:46:14 INFO - PROCESS | 1652 | Values: 84.0 80.0 80.0 84.0 80.0 80.0 80.0 89.0 88.0 81.0 05:46:14 INFO - PROCESS | 1652 | 05:46:14 INFO - PROCESS | 1652 | [#11] stanford-crypto-ccm Cycles:10 Average:144.30 Median:141.00 stddev:18.86 (13.4%) stddev-sans-first:19.55 05:46:14 INFO - PROCESS | 1652 | Values: 133.0 134.0 137.0 139.0 142.0 128.0 140.0 146.0 195.0 149.0 05:46:14 INFO - PROCESS | 1652 | 05:46:14 INFO - PROCESS | 1652 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:169.90 Median:163.00 stddev:20.02 (12.3%) stddev-sans-first:20.92 05:46:14 INFO - PROCESS | 1652 | Values: 160.0 165.0 161.0 160.0 161.0 158.0 158.0 167.0 221.0 188.0 05:46:14 INFO - PROCESS | 1652 | 05:46:14 INFO - PROCESS | 1652 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:64.90 Median:65.00 stddev:0.57 (0.9%) stddev-sans-first:0.60 05:46:14 INFO - PROCESS | 1652 | Values: 65.0 65.0 65.0 65.0 65.0 64.0 66.0 65.0 65.0 64.0 05:46:14 INFO - PROCESS | 1652 | -------- Summary: end -------- 05:46:14 INFO - PROCESS | 1652 | 05:46:15 INFO - TEST-INFO | 1652: exit 0 05:46:15 INFO - TEST-OK | kraken | took 58065ms 05:46:15 INFO - SUITE-END | took 1174s 05:46:15 INFO - Completed test suite (00:19:34) 05:46:15 INFO - javascript benchmark 05:46:15 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3483.773554718943, 3493.825308734563, 3537.1157210697324, 3548.5741710994685, 3595.5146055282544, 5836.32882218545, 5886.735096920638, 5904.114382842576, 5925.674087925162, 5945.821625351241, 5827.825165245039, 5890.998530249857, 5904.1734157218025, 5904.852378690535, 5909.911351329733, 4647.69789963653, 4678.836240731575, 5284.867878303054, 5316.282301889234, 5372.811951581695, 4330.718503297291, 5349.438308977559, 5354.411014788389, 5393.649412788176, 5412.350517937853, 4750.168720473903, 5161.870953226181, 5387.164989426645, 5404.135338345865, 5414.187871819219, 5394.514493695547, 5394.973025134869, 5401.945980540184, 5405.297311349539, 5409.945900541023, 5229.058769421503, 5255.185446255837, 5340.813071542516, 5345.732713364355, 5394.0, 5894.8821023579285, 5915.349311575723, 5930.288365396137, 5971.671558064305, 6003.969980150115, 6317.273513545955, 6361.936380636181, 6364.554481186319, 6377.681115944184, 6379.170707808002, 6276.843078923018, 6302.6218426894075, 6310.558260921737, 6322.589031712924, 6355.523335749837, 5892.528597712216, 5919.6152250103605, 5962.791302304398, 6008.519318454601, 6034.849128771728, 6413.583117097373, 6418.390252925965, 6424.967875160594, 6447.6131432114225, 6456.0, 6142.3759981611065, 6273.592216505913, 6324.1778568786685, 6326.145970293951, 6341.936580634227, 6281.623102613858, 6298.118263443122, 6329.208848893888, 6393.264774550893, 6398.712057957403, 6346.30190679025, 6347.52393570484, 6358.141650877073, 6360.968195158995, 6374.235091789015, 6193.318734939153, 6248.343923888017, 6357.4914006879335, 6386.16979792624, 6405.839854003687, 5078.669886457368, 5093.083245015859, 5132.538071573576, 5139.331886854685, 5146.073706732824, 4604.240300350471, 4613.1465678848845, 4629.490756016836, 4647.814087436464, 4648.442186937522, 6089.421504957022, 6091.299500557404, 6092.908606370908, 6095.969520152371, 6104.511639068864, 6235.033569796688, 6259.6870156492, 6270.843228919222, 6343.302236753953, 6359.491240700733, 5978.163057172, 6034.336223015464, 6040.516758659297, 6058.787942421994, 6064.9090263646085, 5591.440855914376, 5607.971960140173, 5608.579356548194, 5611.663300202001, 5621.7751289948765, 6234.096056071935, 6353.936460635519, 6520.054592084216, 6520.18497687789, 6530.869382612226, 6309.211348581427, 6355.904661430175, 6372.776952806629, 6372.872542549216, 6380.9361906380345, 6491.545591808717, 6493.350664933469, 6510.153680021567, 6515.609063456208, 6523.543351965317, 6199.411055949678, 6329.1139240505445, 6359.4276515112015, 6364.331745166691, 6406.711697973696, 6340.968295158495, 6346.428821406095, 6375.4262116409745, 6383.489320854228, 6387.457066149429, 6162.26052873658, 6247.09417134522, 6270.7491700331475, 6345.714442850175, 6356.078368636614, 6283.96858015707, 6330.113784070142, 6334.714937827901, 6353.650549219652, 6354.078658594385, 6000.609960352737, 6209.254889413119, 6215.65813880241, 6230.314665386894, 6243.687815609111, 6175.62946223228, 6196.411340922605, 6219.968900155471, 6267.310595834546, 6268.310485846644, 6082.9695851520455, 6150.323464419, 6200.720967556561, 6207.81376558704, 6216.067589861557], "value": 5860.134925771374, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [436.77069538492293, 538.6794857060048, 544.0234778572463, 545.3265217456441, 547.8685115572259, 33479.497807532876, 43943.901402464915, 44766.77616611916, 44887.10225795482, 47543.286850697266, 46646.766766166205, 46988.29517557239, 47180.764096179555, 47205.0, 47242.29136562954, 9368.953155234249, 10081.69754907354, 10252.487375631226, 10274.332168409066, 10279.948600256988, 9148.359614826964, 9542.666006689768, 9545.0, 9547.665831695893, 12450.501979920793, 12458.688532786708, 12705.364731763375, 12714.618561443125, 12716.364181790874, 12719.682007949783, 12638.620841374774, 12718.936405318007, 12735.681607959876, 12749.617511474624, 12753.405824271966, 10505.842412363821, 12243.510259589606, 12554.435050422753, 12577.245365278113, 12585.748285034248, 38729.61270387288, 41070.1785964282, 43711.344329835076, 44095.77952110219, 44112.0, 3471.94792078119, 3540.345036168317, 3671.375866102769, 3708.9258214835554, 3733.5519737631384, 32455.350892982246, 33312.50031249533, 33752.987410377726, 33880.81417150388, 34483.482747758804, 3349.882754103619, 3361.966380336214, 3447.4311738563106, 3463.515107884898, 3507.6492350764975, 542983.0, 543866.0, 543985.0, 544571.0, 545628.0, 67646.66176669086, 68022.0, 68068.6596567014, 68152.6592367035, 68203.65898170478, 29247.707522924924, 36995.815020924725, 37005.07487312785, 37876.43185352222, 37993.24013519714, 18433.446996589857, 18571.25714971386, 18604.06979651012, 18610.06949652512, 18626.44120676382, 13996.650083747987, 14077.859221407654, 14079.648008799863, 14141.43434262618, 14142.646433839236, 18205.7269140963, 18337.816621833612, 18347.90826045861, 18562.0, 20118.798812011693, 7176.892346614805, 8332.87506186657, 8354.28988535969, 8354.992202806985, 8433.536155511505, 937.6062053937362, 968.5109019944882, 970.6020531582018, 1114.3982249585295, 1115.1190559457937, 26274.6058809118, 32067.51898721521, 32134.517982229816, 32273.51589726156, 33129.83435082809, 891.8528442806994, 1008.3395376028712, 1061.3207547169818, 1186.3534373766317, 1225.5342969671647, 15875.285612147482, 16375.508734737978, 16623.3350665972, 16641.833581664272, 16857.325706971584, 750613.481595553, 753506.0, 754379.0, 755987.0, 766151.0, 53516.73241633767, 54610.726946365015, 55228.723856380464, 62446.5021399148, 63058.0, 1441.3081720774087, 1455.8835293176521, 1471.0945881883194, 1486.0786312486327, 1488.8064551608438, 27584.0, 28437.00470483523, 29045.7095429043, 29094.854525727234, 29406.264843379086, 56144.87710245854, 60712.69643651754, 61279.387206127365, 61415.078773817535, 65741.01388479081, 625745.0, 719931.0, 720120.0, 928932.0, 952630.0, 45059.0, 45214.77392613016, 45507.08985820331, 45898.0, 46043.3093503591, 43695.78152109219, 46970.060598787146, 49843.50156498388, 49924.00151997012, 50392.49607503878, 37636.62363376331, 40720.592794072865, 42483.78758106189, 42645.30696267286, 45119.0, 35410.46884296789, 42451.787741061096, 42692.57307426886, 43094.78452607842, 44075.0, 41254.79372603118, 41997.79001104975, 42248.94377640461, 42257.0, 42391.15217695691, 35875.0, 36615.45076823796, 36676.0830979217, 37210.883673489836, 37627.43558846565, 27499.86250068737, 27519.797339078756, 27536.17391478259, 27538.862305688344, 27882.44235115327], "value": 21819.499414693248, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [373.6786363727196, 380.3230250154724, 385.4447305123825, 392.99410508842357, 404.1391835390618, 16230.0, 17568.858024228433, 20456.69314960277, 22450.775492245073, 22523.662145067836, 12029.578964736236, 12240.143189976705, 12569.937150314236, 12581.433835477403, 12592.496300147983, 5428.4300148484435, 5769.826905192851, 5770.278715160605, 5773.364929857733, 5791.652500849941, 4070.8982275443054, 4230.492340919094, 4251.6173544381, 5423.83728488144, 5679.119736440837, 7035.190953040389, 7037.155541335047, 7156.892646610305, 7161.534500257492, 7162.4628152888745, 6833.4191593714595, 7040.084788977426, 7088.858222835566, 7108.786736397917, 7137.785866424016, 5991.6405015698765, 6226.40849119333, 6234.407731265523, 6239.438450539473, 6240.0, 13738.519151829638, 13980.951428642893, 17871.195796189204, 26607.20178394651, 26855.46289074227, 1974.0, 2256.751757306695, 2260.7061082059226, 2459.446624509489, 2702.3109107177543, 10591.099756520705, 11658.0, 12027.27836329823, 12880.613581592566, 13320.666983325398, 1872.5412273992822, 1934.516370907273, 1953.7655481342188, 2010.4772759082744, 2058.372196480073, 88415.11584884198, 103915.0, 113891.43054284758, 128004.35997820045, 136140.0, 11194.664160075048, 11435.0, 11572.363520006313, 11767.940610393147, 11893.88106118945, 15025.774613380809, 15059.322330495153, 15061.698766024618, 15074.698506030038, 15086.773698394314, 7773.5230306242765, 8798.912010879938, 9027.819443611223, 9029.23251523628, 9043.59303831329, 9075.2286055686, 9093.090690930987, 9112.589933453011, 9231.76920576991, 9297.95351023254, 4580.725156490621, 4587.587117159472, 4602.631789456836, 4608.493065762764, 4694.108119457293, 1632.9712281262728, 1909.303104366924, 1944.1542928825722, 1947.9805201948082, 1958.676818324989, 762.4243696009478, 787.629813987425, 847.2036285891243, 879.6877108626474, 970.1462712812821, 20880.582388352148, 22201.555968880533, 22315.21896733639, 22334.056312406803, 22511.0, 951.8619800128943, 988.17981075707, 1008.1380419741163, 1044.4099084017657, 1050.9421981790922, 11390.829137562943, 11683.123765717604, 11833.0, 11859.288442693465, 11870.821937670942, 615573.0, 617746.0, 620216.0, 629946.0, 630303.0, 102836.97163028424, 129151.35424323006, 131873.20170651926, 133980.0, 136602.63397366097, 837.656560810065, 970.7573106723319, 991.9652812151685, 994.5623925472769, 1027.9897201028043, 18697.813021869606, 18953.90523047376, 19481.805181948566, 19497.0, 19521.828690278628, 44273.77863110664, 46202.76898615486, 51687.74156129195, 52373.47626523686, 55385.44614553803, 57761.71119144378, 77626.61186694031, 77665.61167194128, 77736.0, 84544.0, 7459.0, 7904.103015276177, 8321.459105158145, 8337.416380853281, 8385.832283354177, 12971.610851674464, 13014.479420823061, 13218.339083045617, 13583.592492225249, 13917.234552099326, 10343.793124137626, 10626.73433164177, 10733.463326833471, 11907.285562866256, 12092.516299347928, 11426.428678566204, 11590.5363785451, 11683.474243659226, 11906.821397678868, 12042.75914481723, 11735.882641173479, 11763.764804695726, 11842.940785296018, 12003.939980300393, 12222.75554488923, 7674.347680447225, 7696.576688282198, 7754.185810490043, 7802.765917022499, 7905.723299684483, 8038.316743076904, 11641.53433862636, 11757.412129393326, 11851.466683999413, 12023.338716370683], "value": 9580.169181230225, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1523.428714232163, 1614.9273282702277, 1819.417786308381, 1827.0773259503949, 1956.5206524401524, 8930.24092952099, 9488.905110948888, 9497.762555936097, 9499.762505937355, 9506.85739713904, 8322.126176751444, 8532.146785321465, 9062.229710474601, 9198.908010919906, 9218.40080394774, 2456.4595788926454, 2489.0, 2490.0164435048146, 2490.340059884132, 2490.5267999080206, 2425.8059355251626, 2483.50329934013, 2487.2040946897, 2489.4896546208074, 2490.9252722418355, 2455.1407007547396, 2471.789897858684, 2482.441450673602, 2485.453200295932, 2486.7264600893886, 2413.0347860855622, 2425.6240282756203, 2433.829631925766, 2454.0306578901414, 2458.532878753031, 2435.9147429839873, 2453.288546321565, 2461.052494789501, 2462.384403899025, 2468.4816188600416, 9293.070692930653, 9315.161635452841, 9402.15380615748, 9415.670451534163, 9430.387024843363, 5471.835844924619, 5643.125315576092, 5647.689377084259, 5649.774009039634, 5659.717014149317, 3608.855645774166, 3719.79541125238, 3796.772193668361, 3800.6959443244473, 3801.353769859131, 908.7500937242245, 1103.5475455063386, 1106.4910141334994, 1125.4654039331333, 1128.8081026225561, 12702.618921432371, 12755.61733148007, 12760.680982975407, 12768.170068945488, 12784.808227876589, 4275.0, 4696.94318778279, 4826.276058591169, 5012.1980483122525, 5024.196128619476, 10746.838797418044, 10767.0, 10771.461426928623, 10773.83839242412, 10779.622713205, 5654.575906807006, 5657.462541058593, 5658.434156584391, 5662.405447428043, 5664.20701101846, 4646.907061858744, 4659.813607455664, 4778.426588809365, 5113.002964421902, 5187.170052791535, 4095.607493452181, 4115.773632450184, 4193.32906734927, 4222.556631553643, 4234.13200293939, 1295.598364507006, 1307.267929959226, 1311.3246677960858, 1325.3969443903002, 1325.5956933135294, 797.9162187970179, 846.3398549131687, 886.4902680958475, 919.0809190809191, 936.2182577547687, 5793.94206057934, 5924.763009479572, 5961.314448838352, 6151.815445536641, 6256.6871656417, 923.971484328328, 970.8786401699788, 981.3817295104038, 1003.9247056470793, 1014.1177175857051, 3357.3117510910206, 3912.4718163047623, 4009.51885773709, 4014.237294913957, 4091.57038510958, 10966.945165274283, 11314.660560183207, 11339.376334301693, 11633.127515436376, 11639.06887449002, 11543.711407214887, 11548.249363791327, 11596.652100437, 11629.651110466699, 11707.473163707653, 8570.914290857012, 8801.91198088011, 8803.779905502413, 8804.207621314112, 8810.911890881009, 5819.76720931175, 6158.753649854135, 6164.291106522807, 6180.6909654517995, 6302.968485157545, 10312.896871031493, 10519.0, 10608.416537090534, 10738.46307684597, 10871.23901326931, 10754.569817207224, 11057.115430765358, 11066.944665276622, 11085.556577736801, 11089.05743011853, 7016.649167541705, 7548.962255188909, 7913.089994650459, 8080.191980801873, 8088.63601137962, 8566.871496927675, 8641.82716345657, 8645.783855403664, 8646.351523635627, 8651.87022194655, 6274.46667033289, 7294.452916031213, 7444.739434119996, 7449.813754656177, 7535.849283014419, 7208.819779505345, 7241.203467618663, 7242.058532390755, 7349.926500735138, 7431.702731890664, 7244.89132663, 7427.7771666850085, 7434.182239953492, 7435.665395057127, 7435.962820185864, 5134.743262836918, 5141.048905952411, 5167.199084141964, 5202.791888324425, 5317.867053323698, 4805.687630304019, 4921.384826896638, 5072.721000345016, 5084.288199651977, 5276.050310943913], "value": 4790.985940215555, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [275.3281991939667, 286.8150043222123, 301.4017175905825, 309.52024362238524, 313.1045210697405, 4550.450648494176, 5083.0, 5297.920531192035, 5429.511343979041, 5435.592330575208, 4284.850030248942, 5148.819791307305, 5278.551323137528, 5353.411124776271, 5380.461953804617, 3351.463765797473, 3525.2596954639557, 3858.517685289346, 4077.1641813428178, 4112.013116851964, 3249.8375081245845, 3384.6615338466204, 4053.27041132596, 4119.794010299488, 4122.628963393294, 3504.281622267429, 3851.28751181031, 3969.7618142911374, 4024.859129930453, 4053.209624123297, 3601.94597081044, 3643.6336373859804, 3674.6036506127766, 4017.4375587417785, 4026.174634199982, 3412.522246885438, 3497.7376696747847, 3500.912477188065, 3507.912547110404, 3547.2018795771005, 4764.785584648699, 4960.528749768766, 5199.844004679866, 5226.719453733822, 5303.36359636846, 4158.459400277975, 4301.849435269782, 4573.353592706623, 4601.7699115044115, 4616.0, 4984.953159836438, 5210.869728256786, 5649.350324712669, 5655.5963715420785, 5662.6036177467595, 3083.4758091124563, 3560.9821950890337, 3807.428885667144, 3828.5405751310022, 3882.9223415531815, 5824.213731146283, 5878.676672782995, 5933.893467818905, 5951.49412299955, 5958.020989505248, 3382.3573521030926, 3821.9235615288094, 3870.5161854768153, 3881.670058045098, 3973.205358928226, 3681.7606855554304, 3860.2665493556133, 4017.4288455388573, 4038.3336749436603, 4116.814743336557, 4555.658325625525, 4596.195665758479, 4596.5173656765555, 4605.585497305258, 4683.414573178353, 4993.725345105981, 5010.215244441622, 5247.658902171346, 5673.3475650299915, 5680.687562184037, 1275.3113318807925, 1307.8169056332122, 1323.3656434303628, 1337.0506940072462, 1341.208686874729, 840.4201101240125, 879.8108406692592, 883.394874510962, 914.3051281026468, 921.1709461484584, 5215.5226267523385, 5215.869603259873, 5232.895342093213, 5286.590149353646, 5295.311609490742, 5697.544196464273, 5747.896136086467, 5753.223314852525, 5757.222774925415, 5773.055741571829, 4950.777215025332, 5206.843794686166, 5223.850752834371, 5290.338707661543, 5307.416184219733, 2196.7473740520045, 2215.1360969222014, 2302.4228403543293, 2525.3055409762096, 2552.970388541114, 4141.337386018283, 4241.97879010603, 4387.232234358974, 4514.142312960527, 4659.627229821606, 4178.498580170399, 4225.915481690349, 4411.0, 4870.806652370192, 5224.0, 4945.727984960861, 5757.5393968481585, 5933.525317974638, 5951.8214453567325, 5970.9701451492465, 4912.803487860446, 5236.607254455855, 5275.498827611525, 5313.707746074003, 5769.221155144001, 4364.650827933821, 5187.974060129675, 5266.418835192107, 5657.943420565742, 5683.943160568341, 4619.076184763128, 5944.64332140073, 5977.760889564369, 6074.149619053248, 6085.4523092921845, 3951.348027575533, 4098.918021639491, 4102.835886564504, 4114.259433302034, 4130.607592278729, 4987.576056035277, 5095.490450954875, 5400.540954018952, 5418.618252345626, 5570.91643625354, 5272.367315922113, 5499.395066542757, 5512.173174023928, 5563.304586926634, 5641.294838145232, 4378.190034843564, 5523.502884740232, 5624.550035997192, 5654.180143879197, 5666.065099258576, 4875.024995000943, 5191.0, 5225.059489291964, 5362.65142765719, 5595.776168953196, 5316.946830531645, 5595.972020139873, 5611.306572845139, 5613.147661271689, 5637.351704553865, 5148.536631703165, 5394.595405344537, 5511.724413779375, 5541.9168712470155, 5543.722813859372], "value": 3993.4318084685497, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [708.4757279613085, 756.6027835386423, 787.2403130978604, 800.4516905919446, 830.4560512864076, 16275.023498590079, 17107.40124095657, 17113.22990465429, 17149.485515434524, 17192.0, 15211.011284266515, 15299.15854627995, 15309.84690153101, 15310.928235023553, 15317.617059573517, 703.3529153179062, 718.3283629806122, 865.329369738453, 873.9344549158807, 898.3890954151175, 895.7133717210495, 901.1529162587179, 905.9094090590921, 907.5008745190119, 907.8728977943093, 952.8999455057224, 953.0659953245823, 954.8949615542285, 955.1356022799378, 957.9760505987337, 947.0766003146946, 948.8149810786899, 949.7150854743618, 950.325269058969, 951.9809603807885, 703.3739971425384, 705.3510770091477, 706.1103010207124, 706.2302090721139, 706.8550947055841, 15451.459198928096, 15578.29897654597, 15622.375104995786, 15625.609359766097, 15649.530514084596, 12739.936300318532, 12763.0, 13550.93224533871, 13690.726185476335, 13986.440542378292, 13612.93193534026, 13788.0, 13890.722185556333, 13969.3713782879, 14063.367148478343, 4491.820327186909, 4571.3828633134435, 4632.189366860786, 4760.119377915097, 4776.880577985544, 77622.0, 78216.0, 105735.94264057415, 138715.30642346825, 138883.0, 11253.774924501628, 11292.266002709797, 11384.772304553862, 11564.76870462586, 11764.82352764692, 22036.0, 22036.559268814533, 22048.779512204994, 22057.77942220557, 22087.0, 7947.960260198662, 7949.205079492121, 8274.710385136492, 8361.414700970996, 8477.576121193915, 4573.862784116482, 4592.425946756655, 4628.166929952641, 4697.389339385858, 4835.323054772335, 3599.964000359963, 3734.327820992193, 3736.5142531470733, 3739.719521035933, 3742.4947632069866, 523.5235935298936, 534.9144136938071, 536.493014101678, 537.9784808607612, 544.4256309593417, 548.9807856724996, 631.3087169549336, 645.7804346522205, 668.5387082912774, 679.5582871133803, 9135.817283654289, 9403.811923761486, 9462.243020558339, 9485.387484127716, 9624.0, 556.6854727079187, 564.5370795947284, 635.5995722694673, 637.7353398339637, 660.9504287178481, 3963.365861462152, 4303.311470164821, 4565.178267911808, 4595.64963780219, 4627.583517483443, 107847.0, 171551.0, 173212.26787731962, 176386.0, 178912.10543947198, 112225.43887280674, 120095.39952300182, 120775.33797296195, 137533.3123334377, 139930.3003484976, 97777.0, 97951.51024244833, 106703.0, 110758.0, 116921.0, 9542.898557968549, 10269.537870795682, 10611.257211995086, 10682.0, 10689.57241710354, 47510.762446187546, 61612.07581886365, 62659.06011409924, 62683.37316626775, 62840.685796570724, 71374.0, 85498.4350469487, 90395.0, 92707.53646231725, 93505.0, 13585.701576385416, 14068.929655351658, 14083.436662533793, 14288.642374008588, 14378.568642940729, 13182.01134914866, 15463.316131927575, 15565.299561519532, 15751.763723544385, 15785.210739463211, 14222.857771422154, 14557.781633275721, 14876.330565124379, 14900.701985960004, 14924.701505970037, 14503.492377766728, 14777.630559236106, 14887.47893823711, 14900.552983410515, 15050.849491504945, 11096.5561377544, 11852.348120853436, 14387.568372948828, 14622.853771462149, 14734.926325368733, 11159.442027898735, 11789.351585662871, 11850.747751776153, 12100.757984840078, 12296.077794165294, 11925.0, 11925.437304317948, 11987.820182697093, 12223.266604003788, 12229.082818788449], "value": 8066.052426897265, "unit": "score"}], "name": "dromaeo_css", "value": 7575.902929357817, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 119.0, 167.0, 161.0, 111.0, 116.0, 160.0, 112.0, 112.0, 111.0], "value": 128.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [141.0, 108.0, 107.0, 139.0, 108.0, 106.0, 107.0, 106.0, 138.0, 108.0], "value": 116.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [194.0, 262.0, 207.0, 182.0, 183.0, 183.0, 185.0, 183.0, 182.0, 182.0], "value": 194.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 60.0, 83.0, 88.0, 61.0, 58.0, 58.0, 57.0, 83.0, 57.0], "value": 69.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 71.0, 91.0, 68.0, 66.0, 67.0, 92.0, 67.0, 68.0, 89.0], "value": 76.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [119.0, 80.0, 119.0, 125.0, 89.0, 85.0, 90.0, 87.0, 90.0, 87.0], "value": 97.1, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 96.0, 107.0, 96.0, 100.0, 144.0, 137.0, 95.0, 96.0, 95.0], "value": 106.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 83.0, 85.0, 83.0, 84.0, 83.0, 83.0, 83.0, 84.0, 83.0], "value": 83.4, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 53.0, 52.0, 52.0, 54.0, 53.0, 75.0, 84.0, 84.0, 86.0], "value": 64.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [72.0, 64.0, 69.0, 75.0, 70.0, 67.0, 68.0, 69.0, 69.0, 70.0], "value": 69.3, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 80.0, 80.0, 84.0, 80.0, 80.0, 80.0, 89.0, 88.0, 81.0], "value": 82.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [133.0, 134.0, 137.0, 139.0, 142.0, 128.0, 140.0, 146.0, 195.0, 149.0], "value": 144.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 165.0, 161.0, 160.0, 161.0, 158.0, 158.0, 167.0, 221.0, 188.0], "value": 169.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 65.0, 65.0, 65.0, 65.0, 64.0, 66.0, 65.0, 65.0, 64.0], "value": 64.9, "unit": "score"}], "name": "kraken", "value": 1467.4, "alertThreshold": 2.0}]} 05:46:15 INFO - Return code: 0 05:46:15 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 05:46:15 INFO - # TBPL SUCCESS # 05:46:15 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:46:15 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:46:15 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:46:15 INFO - 2016-11-18 05:46:15.751 screenresolution[1656:9730] starting screenresolution argv=screenresolution get 05:46:15 INFO - 2016-11-18 05:46:15.753 screenresolution[1656:9730] Display 0: 1600x1200x32@60 05:46:15 INFO - 2016-11-18 05:46:15.759 screenresolution[1657:9733] starting screenresolution argv=screenresolution list 05:46:15 INFO - Available Modes on Display 0 05:46:15 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:46:15 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:46:15 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:46:15 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:46:15 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:46:15 INFO - Intel Iris: 05:46:15 INFO - Chipset Model: Intel Iris 05:46:15 INFO - Type: GPU 05:46:15 INFO - Bus: Built-In 05:46:15 INFO - VRAM (Dynamic, Max): 1536 MB 05:46:15 INFO - Vendor: Intel (0x8086) 05:46:15 INFO - Device ID: 0x0a2e 05:46:15 INFO - Revision ID: 0x0009 05:46:15 INFO - Displays: 05:46:15 INFO - Display: 05:46:15 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:46:15 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:46:15 INFO - Display Serial Number: 1600x1200 60 05:46:15 INFO - Main Display: Yes 05:46:15 INFO - Mirror: Off 05:46:15 INFO - Online: Yes 05:46:15 INFO - Rotation: Supported 05:46:15 INFO - Return code: 0 05:46:15 INFO - Running post-action listener: _resource_record_post_action 05:46:15 INFO - [mozharness: 2016-11-18 13:46:15.995642Z] Finished run-tests step (success) 05:46:15 INFO - Running post-run listener: _resource_record_post_run 05:46:16 INFO - Total resource usage - Wall time: 1197s; CPU: 53.0%; Read bytes: 38917120; Write bytes: 494200320; Read time: 379; Write time: 1611 05:46:16 INFO - TinderboxPrint: CPU usage
53.1% 05:46:16 INFO - TinderboxPrint: I/O read bytes / time
38,917,120 / 379 05:46:16 INFO - TinderboxPrint: I/O write bytes / time
494,200,320 / 1,611 05:46:16 INFO - TinderboxPrint: CPU idle
2,246.1 (46.9%) 05:46:16 INFO - TinderboxPrint: CPU system
954.2 (19.9%) 05:46:16 INFO - TinderboxPrint: CPU user
1,589.3 (33.2%) 05:46:16 INFO - TinderboxPrint: Swap in / out
234,016,768 / 0 05:46:16 INFO - install - Wall time: 23s; CPU: 54.0%; Read bytes: 188162560; Write bytes: 187697152; Read time: 17690; Write time: 388 05:46:16 INFO - run-tests - Wall time: 1176s; CPU: 53.0%; Read bytes: 37970944; Write bytes: 296316416; Read time: 366; Write time: 1156 05:46:16 INFO - Running post-run listener: _upload_blobber_files 05:46:16 INFO - Blob upload gear active. 05:46:16 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 05:46:16 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 05:46:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Beta', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 05:46:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Beta -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 05:46:16 INFO - (blobuploader) - INFO - Open directory for files ... 05:46:16 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 05:46:16 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:46:16 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:46:17 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 05:46:17 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:46:17 INFO - (blobuploader) - INFO - Done attempting. 05:46:17 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 05:46:17 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:46:17 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:46:18 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 05:46:18 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:46:18 INFO - (blobuploader) - INFO - Done attempting. 05:46:18 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 05:46:18 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:46:18 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:46:21 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 05:46:21 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:46:21 INFO - (blobuploader) - INFO - Done attempting. 05:46:21 INFO - (blobuploader) - INFO - Iteration through files over. 05:46:21 INFO - Return code: 0 05:46:21 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 05:46:21 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 05:46:21 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/8e1a4a14f0588ffc1676b7977bc5798a4a96c0089534202f95a498674034b10523961b6285333031dff99d47d4e5585106b3c0fe38da06031a8b666086bfbb64", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/364ad6055c07186e080f1e0c46e6f40da3720a9924f0f1e4d1fcf26a5fd78c68503f1143b3011a3cc1c8c15fef245e45cccfbea88d9f7bd724be149dad85732f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/05c2b8febc9b9083530276f4ef4d1f2aa707cce27c931e5f56cd8eb2895a087687df4eb3b272544386b274941d863532543bc6027b400b2d59d8fd394c4b63ba"} 05:46:21 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 05:46:21 INFO - Writing to file /builds/slave/test/properties/blobber_files 05:46:21 INFO - Contents: 05:46:21 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/8e1a4a14f0588ffc1676b7977bc5798a4a96c0089534202f95a498674034b10523961b6285333031dff99d47d4e5585106b3c0fe38da06031a8b666086bfbb64", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/364ad6055c07186e080f1e0c46e6f40da3720a9924f0f1e4d1fcf26a5fd78c68503f1143b3011a3cc1c8c15fef245e45cccfbea88d9f7bd724be149dad85732f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/05c2b8febc9b9083530276f4ef4d1f2aa707cce27c931e5f56cd8eb2895a087687df4eb3b272544386b274941d863532543bc6027b400b2d59d8fd394c4b63ba"} 05:46:21 INFO - Running post-run listener: copy_logs_to_upload_dir 05:46:21 INFO - Copying logs to upload dir... 05:46:21 INFO - mkdir: /builds/slave/test/build/upload/logs 05:46:21 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1258.298651 ========= master_lag: 0.32 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 20 mins, 58 secs) (at 2016-11-18 05:46:21.488501) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-18 05:46:21.492482) ========= 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.k6CRI8gPHs/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.IFD6gywel9/Listeners TMPDIR=/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/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/8e1a4a14f0588ffc1676b7977bc5798a4a96c0089534202f95a498674034b10523961b6285333031dff99d47d4e5585106b3c0fe38da06031a8b666086bfbb64", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/364ad6055c07186e080f1e0c46e6f40da3720a9924f0f1e4d1fcf26a5fd78c68503f1143b3011a3cc1c8c15fef245e45cccfbea88d9f7bd724be149dad85732f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/05c2b8febc9b9083530276f4ef4d1f2aa707cce27c931e5f56cd8eb2895a087687df4eb3b272544386b274941d863532543bc6027b400b2d59d8fd394c4b63ba"} build_url:https://queue.taskcluster.net/v1/task/FmRmLTisR-qnEO4mwOEHDg/artifacts/public/build/firefox-51.0.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010758 build_url: 'https://queue.taskcluster.net/v1/task/FmRmLTisR-qnEO4mwOEHDg/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/8e1a4a14f0588ffc1676b7977bc5798a4a96c0089534202f95a498674034b10523961b6285333031dff99d47d4e5585106b3c0fe38da06031a8b666086bfbb64", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/364ad6055c07186e080f1e0c46e6f40da3720a9924f0f1e4d1fcf26a5fd78c68503f1143b3011a3cc1c8c15fef245e45cccfbea88d9f7bd724be149dad85732f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/05c2b8febc9b9083530276f4ef4d1f2aa707cce27c931e5f56cd8eb2895a087687df4eb3b272544386b274941d863532543bc6027b400b2d59d8fd394c4b63ba"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-18 05:46:21.529526) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-18 05:46:21.530287) ========= 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.k6CRI8gPHs/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.IFD6gywel9/Listeners TMPDIR=/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/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.004793 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-18 05:46:21.584434) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-18 05:46:21.585204) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-18 05:46:21.585838) ========= ========= Total master_lag: 0.69 =========