builder: mozilla-central_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0089 starttime: 1477631821.86 results: success (0) buildid: 20161027192219 builduid: c193b4f3423945a1a7795dfefb47f0a0 revision: 944cb0fd05526894fcd90fbe7d1e625ee53cd73d ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-10-27 22:17:01.856008) ========= master: http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-10-27 22:17:01.856477) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-27 22:17:01.856898) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-27 22:17:01.882902) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-27 22:17:01.883478) ========= 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.8m8ug64qmu/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.q9UhaPupy5/Listeners TMPDIR=/var/folders/g3/g2lxvbfd1c33ggtssv6lgc3r00000w/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-10-27 22:17:01-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 124M=0s 2016-10-27 22:17:02 (124 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.150458 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-27 22:17:02.054629) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-27 22:17:02.054944) ========= 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.8m8ug64qmu/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.q9UhaPupy5/Listeners TMPDIR=/var/folders/g3/g2lxvbfd1c33ggtssv6lgc3r00000w/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.026236 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-27 22:17:02.103667) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-10-27 22:17:02.104044) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 944cb0fd05526894fcd90fbe7d1e625ee53cd73d --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev 944cb0fd05526894fcd90fbe7d1e625ee53cd73d --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.8m8ug64qmu/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.q9UhaPupy5/Listeners TMPDIR=/var/folders/g3/g2lxvbfd1c33ggtssv6lgc3r00000w/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-10-27 22:17:02,185 truncating revision to first 12 chars 2016-10-27 22:17:02,185 Setting DEBUG logging. 2016-10-27 22:17:02,185 attempt 1/10 2016-10-27 22:17:02,185 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/944cb0fd0552?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-10-27 22:17:03,357 unpacking tar archive at: mozilla-central-944cb0fd0552/testing/mozharness/ program finished with exit code 0 elapsedTime=1.436682 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-10-27 22:17:03.563086) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-27 22:17:03.563443) ========= script_repo_revision: 944cb0fd05526894fcd90fbe7d1e625ee53cd73d ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-27 22:17:03.563878) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-27 22:17:03.564151) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-27 22:17:03.583249) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 16 secs) (at 2016-10-27 22:17:03.583706) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 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', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.8m8ug64qmu/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.q9UhaPupy5/Listeners TMPDIR=/var/folders/g3/g2lxvbfd1c33ggtssv6lgc3r00000w/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 22:17:03 INFO - MultiFileLogger online at 20161027 22:17:03 in /builds/slave/test 22:17:03 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 22:17:03 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 22:17:03 INFO - {'append_to_log': False, 22:17:03 INFO - 'base_work_dir': '/builds/slave/test', 22:17:03 INFO - 'blob_upload_branch': 'Firefox', 22:17:03 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 22:17:03 INFO - 'branch': 'Firefox', 22:17:03 INFO - 'buildbot_json_path': 'buildprops.json', 22:17:03 INFO - 'config_files': ('talos/mac_config.py',), 22:17:03 INFO - 'default_actions': ('clobber', 22:17:03 INFO - 'read-buildbot-config', 22:17:03 INFO - 'download-and-extract', 22:17:03 INFO - 'populate-webroot', 22:17:03 INFO - 'create-virtualenv', 22:17:03 INFO - 'install', 22:17:03 INFO - 'run-tests'), 22:17:03 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 22:17:03 INFO - 'download_minidump_stackwalk': True, 22:17:03 INFO - 'download_symbols': 'ondemand', 22:17:03 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 22:17:03 INFO - 'tooltool.py': '/tools/tooltool.py', 22:17:03 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 22:17:03 INFO - '/tools/misc-python/virtualenv.py')}, 22:17:03 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 22:17:03 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 22:17:03 INFO - 'installer_path': 'installer.exe', 22:17:03 INFO - 'log_level': 'info', 22:17:03 INFO - 'log_name': 'talos', 22:17:03 INFO - 'log_to_console': True, 22:17:03 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 22:17:03 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 22:17:03 INFO - 'opt_config_files': (), 22:17:03 INFO - 'pip_index': False, 22:17:03 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 22:17:03 INFO - 'cmd': ('bash', 22:17:03 INFO - '-c', 22:17:03 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 22:17:03 INFO - 'enabled': True, 22:17:03 INFO - 'halt_on_failure': False, 22:17:03 INFO - 'name': 'check_screen_resolution'},), 22:17:03 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 22:17:03 INFO - 'cmd': ('bash', 22:17:03 INFO - '-c', 22:17:03 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 22:17:03 INFO - 'enabled': True, 22:17:03 INFO - 'halt_on_failure': False, 22:17:03 INFO - 'name': 'check_screen_resolution'},), 22:17:03 INFO - 'run_cmd_checks_enabled': True, 22:17:03 INFO - 'sps_profile': False, 22:17:03 INFO - 'sps_profile_interval': 0, 22:17:03 INFO - 'suite': 'dromaeojs', 22:17:03 INFO - 'system_bits': '32', 22:17:03 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 22:17:03 INFO - 'title': 't-yosemite-r7-0089', 22:17:03 INFO - 'tooltool_cache': '/builds/tooltool_cache', 22:17:03 INFO - 'use_talos_json': True, 22:17:03 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 22:17:03 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 22:17:03 INFO - 'work_dir': 'build'} 22:17:03 INFO - [mozharness: 2016-10-28 05:17:03.790457Z] Running clobber step. 22:17:03 INFO - Running pre-action listener: _resource_record_pre_action 22:17:03 INFO - Running main action method: clobber 22:17:03 INFO - rmtree: /builds/slave/test/build 22:17:03 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 22:17:06 INFO - Running post-action listener: _resource_record_post_action 22:17:06 INFO - [mozharness: 2016-10-28 05:17:06.085746Z] Finished clobber step (success) 22:17:06 INFO - [mozharness: 2016-10-28 05:17:06.085945Z] Running read-buildbot-config step. 22:17:06 INFO - Running pre-action listener: _resource_record_pre_action 22:17:06 INFO - Running main action method: read_buildbot_config 22:17:06 INFO - Using buildbot properties: 22:17:06 INFO - { 22:17:06 INFO - "project": "", 22:17:06 INFO - "product": "firefox", 22:17:06 INFO - "script_repo_revision": "production", 22:17:06 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs", 22:17:06 INFO - "repository": "", 22:17:06 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs", 22:17:06 INFO - "buildid": "20161027192219", 22:17:06 INFO - "pgo_build": "False", 22:17:06 INFO - "basedir": "/builds/slave/test", 22:17:06 INFO - "buildnumber": 126, 22:17:06 INFO - "slavename": "t-yosemite-r7-0089", 22:17:06 INFO - "master": "http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/", 22:17:06 INFO - "platform": "yosemite_r7", 22:17:06 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 22:17:06 INFO - "branch": "mozilla-central", 22:17:06 INFO - "repo_path": "mozilla-central", 22:17:06 INFO - "revision": "944cb0fd05526894fcd90fbe7d1e625ee53cd73d", 22:17:06 INFO - "stage_platform": "macosx64", 22:17:06 INFO - "builduid": "c193b4f3423945a1a7795dfefb47f0a0", 22:17:06 INFO - "slavebuilddir": "test" 22:17:06 INFO - } 22:17:06 INFO - Found installer url https://queue.taskcluster.net/v1/task/bfBCfpzpTQK2DiO08yHbvQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg. 22:17:06 INFO - Running post-action listener: _resource_record_post_action 22:17:06 INFO - [mozharness: 2016-10-28 05:17:06.091732Z] Finished read-buildbot-config step (success) 22:17:06 INFO - [mozharness: 2016-10-28 05:17:06.091950Z] Running download-and-extract step. 22:17:06 INFO - Running pre-action listener: _resource_record_pre_action 22:17:06 INFO - Running main action method: download_and_extract 22:17:06 INFO - mkdir: /builds/slave/test/build/tests 22:17: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')]} 22:17:06 INFO - trying https://queue.taskcluster.net/v1/task/bfBCfpzpTQK2DiO08yHbvQ/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json 22:17:06 INFO - Downloading https://queue.taskcluster.net/v1/task/bfBCfpzpTQK2DiO08yHbvQ/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json 22:17:06 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/bfBCfpzpTQK2DiO08yHbvQ/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json'}, attempt #1 22:17:07 INFO - Downloaded 1199 bytes. 22:17:07 INFO - Reading from file /builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json 22:17:07 INFO - Using the following test package requirements: 22:17:07 INFO - {u'common': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 22:17:07 INFO - u'cppunittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 22:17:07 INFO - u'firefox-52.0a1.en-US.mac.cppunittest.tests.zip'], 22:17:07 INFO - u'gtest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 22:17:07 INFO - u'firefox-52.0a1.en-US.mac.gtest.tests.zip'], 22:17:07 INFO - u'jittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 22:17:07 INFO - u'jsshell-mac.zip'], 22:17:07 INFO - u'mochitest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 22:17:07 INFO - u'firefox-52.0a1.en-US.mac.mochitest.tests.zip'], 22:17:07 INFO - u'mozbase': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 22:17:07 INFO - u'reftest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 22:17:07 INFO - u'firefox-52.0a1.en-US.mac.reftest.tests.zip'], 22:17:07 INFO - u'talos': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 22:17:07 INFO - u'firefox-52.0a1.en-US.mac.talos.tests.zip'], 22:17:07 INFO - u'web-platform': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 22:17:07 INFO - u'firefox-52.0a1.en-US.mac.web-platform.tests.zip'], 22:17:07 INFO - u'xpcshell': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 22:17:07 INFO - u'firefox-52.0a1.en-US.mac.xpcshell.tests.zip']} 22:17:07 INFO - Downloading packages: [u'firefox-52.0a1.en-US.mac.common.tests.zip'] for test suite category: common 22:17:07 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/bfBCfpzpTQK2DiO08yHbvQ/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 22:17:07 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/bfBCfpzpTQK2DiO08yHbvQ/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 22:17:07 INFO - Fetch https://queue.taskcluster.net/v1/task/bfBCfpzpTQK2DiO08yHbvQ/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 22:17:08 INFO - Http code: 200 22:17:08 INFO - via: 1.1 20f1c35f343f4b271ae8dcacfd7ea0e9.cloudfront.net (CloudFront) 22:17:08 INFO - x-amz-cf-id: WpElv3Fy_0F3IVZOCvZBHhTlJdbYS57A2JtSYCaSTN8EmVcpuVKpqA== 22:17:08 INFO - x-amz-version-id: elUrSEMT5OakK3pmyMJYdbfc5njjaIaT 22:17:08 INFO - Expected file size: 16377319 22:17:08 INFO - Obtained file size: 16377319 22:17:14 INFO - Downloading packages: [u'firefox-52.0a1.en-US.mac.common.tests.zip', u'firefox-52.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 22:17:14 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/bfBCfpzpTQK2DiO08yHbvQ/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 22:17:14 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/bfBCfpzpTQK2DiO08yHbvQ/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 22:17:14 INFO - Fetch https://queue.taskcluster.net/v1/task/bfBCfpzpTQK2DiO08yHbvQ/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 22:17:15 INFO - Http code: 200 22:17:15 INFO - via: 1.1 11dd60d1f68e8258294f92935b53a91f.cloudfront.net (CloudFront) 22:17:15 INFO - x-amz-cf-id: VgfqciHIle_IhKYpBcKXkpMUiRYx3GRjny7UQLZDBnQo5f7MgFXvkg== 22:17:15 INFO - x-amz-version-id: elUrSEMT5OakK3pmyMJYdbfc5njjaIaT 22:17:15 INFO - Expected file size: 16377319 22:17:15 INFO - Obtained file size: 16377319 22:17:22 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/bfBCfpzpTQK2DiO08yHbvQ/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip 22:17:22 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/bfBCfpzpTQK2DiO08yHbvQ/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip'}, attempt #1 22:17:22 INFO - Fetch https://queue.taskcluster.net/v1/task/bfBCfpzpTQK2DiO08yHbvQ/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip into memory 22:17:22 INFO - Http code: 200 22:17:22 INFO - via: 1.1 ae162f6796e551002447afd7c07ec67a.cloudfront.net (CloudFront) 22:17:22 INFO - x-amz-cf-id: zw6LCeUN-e85Lt-RJDVwWMIasnOmTV9xsI66T-GjiOTDnorN1O751w== 22:17:22 INFO - x-amz-version-id: wY.p4qsjMSIPxjDfLF_w3CR.97q9VQFE 22:17:23 INFO - Expected file size: 14409028 22:17:23 INFO - Obtained file size: 14409028 22:17:23 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')]} 22:17:23 INFO - trying https://queue.taskcluster.net/v1/task/bfBCfpzpTQK2DiO08yHbvQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 22:17:23 INFO - Downloading https://queue.taskcluster.net/v1/task/bfBCfpzpTQK2DiO08yHbvQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg 22:17:23 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/bfBCfpzpTQK2DiO08yHbvQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg'}, attempt #1 22:17:26 INFO - Downloaded 111544952 bytes. 22:17:26 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/bfBCfpzpTQK2DiO08yHbvQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 22:17:26 INFO - mkdir: /builds/slave/test/properties 22:17:26 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 22:17:26 INFO - Writing to file /builds/slave/test/properties/build_url 22:17:26 INFO - Contents: 22:17:26 INFO - build_url:https://queue.taskcluster.net/v1/task/bfBCfpzpTQK2DiO08yHbvQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 22:17:27 INFO - Running post-action listener: _resource_record_post_action 22:17:27 INFO - Running post-action listener: set_extra_try_arguments 22:17:27 INFO - [mozharness: 2016-10-28 05:17:27.010133Z] Finished download-and-extract step (success) 22:17:27 INFO - [mozharness: 2016-10-28 05:17:27.010261Z] Running populate-webroot step. 22:17:27 INFO - Running pre-action listener: _resource_record_pre_action 22:17:27 INFO - Running main action method: populate_webroot 22:17:27 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 22:17:27 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 22:17:27 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 22:17:27 INFO - '--tppagecycles', 22:17:27 INFO - '7'], 22:17:27 INFO - 'tests': ['tsvgm']}}, 22:17:27 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 22:17:27 INFO - 'tests': ['tresize', 'tcanvasmark']}, 22:17:27 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 22:17:27 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 22:17:27 INFO - 'tests': ['dromaeo_css', 'kraken']}, 22:17:27 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 22:17:27 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:17:27 INFO - 'talos_options': ['--disable-e10s'], 22:17:27 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 22:17:27 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:17:27 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 22:17:27 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:17:27 INFO - 'talos_options': ['--disable-e10s'], 22:17:27 INFO - 'tests': ['damp', 'tps']}, 22:17:27 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:17:27 INFO - 'tests': ['damp', 'tps']}, 22:17:27 INFO - 'g3': {'talos_options': ['--disable-e10s'], 22:17:27 INFO - 'tests': ['dromaeo_dom']}, 22:17:27 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 22:17:27 INFO - 'g4': {'talos_options': ['--disable-e10s'], 22:17:27 INFO - 'tests': ['basic_compositor_video']}, 22:17:27 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 22:17:27 INFO - 'other': {'talos_options': ['--disable-e10s'], 22:17:27 INFO - 'tests': ['a11yr', 22:17:27 INFO - 'ts_paint', 22:17:27 INFO - 'tpaint', 22:17:27 INFO - 'sessionrestore', 22:17:27 INFO - 'sessionrestore_no_auto_restore', 22:17:27 INFO - 'tabpaint']}, 22:17:27 INFO - 'other-e10s': {'tests': ['a11yr', 22:17:27 INFO - 'ts_paint', 22:17:27 INFO - 'tpaint', 22:17:27 INFO - 'sessionrestore', 22:17:27 INFO - 'sessionrestore_no_auto_restore', 22:17:27 INFO - 'tabpaint']}, 22:17:27 INFO - 'other-e10s_l64': {'tests': ['a11yr', 22:17:27 INFO - 'ts_paint', 22:17:27 INFO - 'tpaint', 22:17:27 INFO - 'sessionrestore', 22:17:27 INFO - 'sessionrestore_no_auto_restore', 22:17:27 INFO - 'tabpaint']}, 22:17:27 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 22:17:27 INFO - 'ts_paint', 22:17:27 INFO - 'tpaint', 22:17:27 INFO - 'sessionrestore', 22:17:27 INFO - 'sessionrestore_no_auto_restore', 22:17:27 INFO - 'tabpaint']}, 22:17:27 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 22:17:27 INFO - 'tests': ['a11yr', 22:17:27 INFO - 'ts_paint', 22:17:27 INFO - 'tpaint', 22:17:27 INFO - 'sessionrestore', 22:17:27 INFO - 'sessionrestore_no_auto_restore', 22:17:27 INFO - 'tabpaint']}, 22:17:27 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 22:17:27 INFO - 'tests': ['a11yr', 22:17:27 INFO - 'ts_paint', 22:17:27 INFO - 'tpaint', 22:17:27 INFO - 'sessionrestore', 22:17:27 INFO - 'sessionrestore_no_auto_restore', 22:17:27 INFO - 'tabpaint']}, 22:17:27 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 22:17:27 INFO - 'tests': ['tsvgx', 22:17:27 INFO - 'tsvgr_opacity', 22:17:27 INFO - 'tart', 22:17:27 INFO - 'tscrollx', 22:17:27 INFO - 'cart']}, 22:17:27 INFO - 'svgr-e10s': {'tests': ['tsvgx', 22:17:27 INFO - 'tsvgr_opacity', 22:17:27 INFO - 'tart', 22:17:27 INFO - 'tscrollx', 22:17:27 INFO - 'cart']}, 22:17:27 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:17:27 INFO - 'talos_options': ['--disable-e10s'], 22:17:27 INFO - 'tests': ['tp5o']}, 22:17:27 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:17:27 INFO - 'tests': ['tp5o']}, 22:17:27 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:17:27 INFO - 'talos_options': ['--disable-e10s', 22:17:27 INFO - '--xperf_path', 22:17:27 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 22:17:27 INFO - 'tests': ['tp5n']}, 22:17:27 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:17:27 INFO - 'talos_options': ['--xperf_path', 22:17:27 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 22:17:27 INFO - 'tests': ['tp5n']}}, 22:17:27 INFO - 'talos.zip': {'path': '', 22:17:27 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 22:17:27 INFO - Running post-action listener: _resource_record_post_action 22:17:27 INFO - [mozharness: 2016-10-28 05:17:27.029095Z] Finished populate-webroot step (success) 22:17:27 INFO - [mozharness: 2016-10-28 05:17:27.029226Z] Running create-virtualenv step. 22:17:27 INFO - Running pre-action listener: _resource_record_pre_action 22:17:27 INFO - Running main action method: create_virtualenv 22:17:27 INFO - Creating virtualenv /builds/slave/test/build/venv 22:17:27 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 22:17:27 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 22:17:27 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 22:17:27 INFO - Using real prefix '/tools/python27' 22:17:27 INFO - New python executable in /builds/slave/test/build/venv/bin/python 22:17:27 INFO - Installing distribute.............................................................................................................................................................................................done. 22:17:30 INFO - Installing pip.................done. 22:17:30 INFO - Return code: 0 22:17:30 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 22:17:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 22:17:31 INFO - Reading from file tmpfile_stdout 22:17:31 INFO - Output received: 22:17:31 INFO - pip 1.5.5 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 22:17:31 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 22:17:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:17:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:17:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:17:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:17:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:17:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:17:31 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 0x109793490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109709570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc6f34d9f80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1095a7d30>, '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 0x1093cddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10959fb20>, '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.8m8ug64qmu/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.q9UhaPupy5/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/g3/g2lxvbfd1c33ggtssv6lgc3r00000w/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 22:17:31 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 22:17:31 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 22:17:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.8m8ug64qmu/Render', 22:17:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:17:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:17:31 INFO - 'HOME': '/Users/cltbld', 22:17:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:17:31 INFO - 'LOGNAME': 'cltbld', 22:17:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:17:31 INFO - 'MOZ_NO_REMOTE': '1', 22:17:31 INFO - 'NO_EM_RESTART': '1', 22:17:31 INFO - 'PAGER': '/bin/cat', 22:17:31 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:17:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:17:31 INFO - 'PWD': '/builds/slave/test', 22:17:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:17:31 INFO - 'SHELL': '/bin/bash', 22:17:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.q9UhaPupy5/Listeners', 22:17:31 INFO - 'TMPDIR': '/var/folders/g3/g2lxvbfd1c33ggtssv6lgc3r00000w/T/', 22:17:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:17:31 INFO - 'USER': 'cltbld', 22:17:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:17:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:17:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:17:31 INFO - 'XPC_FLAGS': '0x0', 22:17:31 INFO - 'XPC_SERVICE_NAME': '0', 22:17:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:17:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:17:31 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 22:17:31 INFO - Cleaning up... 22:17:31 INFO - Return code: 0 22:17:31 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 22:17:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:17:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:17:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:17:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:17:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:17:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:17:31 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 0x109793490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109709570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc6f34d9f80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1095a7d30>, '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 0x1093cddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10959fb20>, '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.8m8ug64qmu/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.q9UhaPupy5/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/g3/g2lxvbfd1c33ggtssv6lgc3r00000w/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 22:17:31 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 22:17:31 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 22:17:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.8m8ug64qmu/Render', 22:17:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:17:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:17:31 INFO - 'HOME': '/Users/cltbld', 22:17:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:17:31 INFO - 'LOGNAME': 'cltbld', 22:17:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:17:31 INFO - 'MOZ_NO_REMOTE': '1', 22:17:31 INFO - 'NO_EM_RESTART': '1', 22:17:31 INFO - 'PAGER': '/bin/cat', 22:17:31 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:17:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:17:31 INFO - 'PWD': '/builds/slave/test', 22:17:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:17:31 INFO - 'SHELL': '/bin/bash', 22:17:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.q9UhaPupy5/Listeners', 22:17:31 INFO - 'TMPDIR': '/var/folders/g3/g2lxvbfd1c33ggtssv6lgc3r00000w/T/', 22:17:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:17:31 INFO - 'USER': 'cltbld', 22:17:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:17:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:17:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:17:31 INFO - 'XPC_FLAGS': '0x0', 22:17:31 INFO - 'XPC_SERVICE_NAME': '0', 22:17:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:17:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:17:31 INFO - Downloading/unpacking psutil>=3.1.1 22:17:31 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 22:17:31 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 22:17:31 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 22:17:31 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 22:17:31 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 22:17:31 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 22:17:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 22:17:34 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 22:17:34 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 22:17:34 INFO - Installing collected packages: psutil 22:17:34 INFO - Running setup.py install for psutil 22:17:35 INFO - building 'psutil._psutil_osx' extension 22:17:35 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 22:17:35 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 22:17:35 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 22:17:35 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 22:17:35 INFO - building 'psutil._psutil_posix' extension 22:17:35 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 22:17:35 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 22:17:35 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 22:17:35 INFO - ^ 22:17:36 INFO - 1 warning generated. 22:17:36 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 22:17:36 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 22:17:36 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 22:17:36 INFO - Successfully installed psutil 22:17:36 INFO - Cleaning up... 22:17:36 INFO - Return code: 0 22:17:36 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 22:17:36 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')]} 22:17:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:17:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:17:36 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')]} 22:17:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:17:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:17:36 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 0x109793490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109709570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc6f34d9f80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1095a7d30>, '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 0x1093cddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10959fb20>, '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.8m8ug64qmu/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.q9UhaPupy5/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/g3/g2lxvbfd1c33ggtssv6lgc3r00000w/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 22:17:36 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 22:17:36 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 22:17:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.8m8ug64qmu/Render', 22:17:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:17:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:17:36 INFO - 'HOME': '/Users/cltbld', 22:17:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:17:36 INFO - 'LOGNAME': 'cltbld', 22:17:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:17:36 INFO - 'MOZ_NO_REMOTE': '1', 22:17:36 INFO - 'NO_EM_RESTART': '1', 22:17:36 INFO - 'PAGER': '/bin/cat', 22:17:36 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:17:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:17:36 INFO - 'PWD': '/builds/slave/test', 22:17:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:17:36 INFO - 'SHELL': '/bin/bash', 22:17:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.q9UhaPupy5/Listeners', 22:17:36 INFO - 'TMPDIR': '/var/folders/g3/g2lxvbfd1c33ggtssv6lgc3r00000w/T/', 22:17:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:17:36 INFO - 'USER': 'cltbld', 22:17:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:17:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:17:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:17:36 INFO - 'XPC_FLAGS': '0x0', 22:17:36 INFO - 'XPC_SERVICE_NAME': '0', 22:17:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:17:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:17:36 INFO - Downloading/unpacking mozsystemmonitor==0.3 22:17:36 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 22:17:36 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 22:17:36 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 22:17:36 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 22:17:36 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 22:17:36 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 22:17:38 INFO - Downloading mozsystemmonitor-0.3.tar.gz 22:17:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 22:17:39 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 22:17:39 INFO - Installing collected packages: mozsystemmonitor 22:17:39 INFO - Running setup.py install for mozsystemmonitor 22:17:39 INFO - Successfully installed mozsystemmonitor 22:17:39 INFO - Cleaning up... 22:17:39 INFO - Return code: 0 22:17:39 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 22:17:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:17:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:17:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:17:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:17:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:17:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:17:39 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 0x109793490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109709570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc6f34d9f80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1095a7d30>, '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 0x1093cddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10959fb20>, '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.8m8ug64qmu/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.q9UhaPupy5/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/g3/g2lxvbfd1c33ggtssv6lgc3r00000w/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 22:17:39 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 22:17:39 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 22:17:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.8m8ug64qmu/Render', 22:17:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:17:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:17:39 INFO - 'HOME': '/Users/cltbld', 22:17:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:17:39 INFO - 'LOGNAME': 'cltbld', 22:17:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:17:39 INFO - 'MOZ_NO_REMOTE': '1', 22:17:39 INFO - 'NO_EM_RESTART': '1', 22:17:39 INFO - 'PAGER': '/bin/cat', 22:17:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:17:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:17:39 INFO - 'PWD': '/builds/slave/test', 22:17:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:17:39 INFO - 'SHELL': '/bin/bash', 22:17:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.q9UhaPupy5/Listeners', 22:17:39 INFO - 'TMPDIR': '/var/folders/g3/g2lxvbfd1c33ggtssv6lgc3r00000w/T/', 22:17:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:17:39 INFO - 'USER': 'cltbld', 22:17:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:17:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:17:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:17:39 INFO - 'XPC_FLAGS': '0x0', 22:17:39 INFO - 'XPC_SERVICE_NAME': '0', 22:17:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:17:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:17:39 INFO - Downloading/unpacking jsonschema==2.5.1 22:17:39 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 22:17:39 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 22:17:39 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 22:17:39 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 22:17:39 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 22:17:39 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 22:17:41 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 22:17:41 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 22:17:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:17:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:17:41 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:17:41 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:17:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:17:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:17:42 INFO - Downloading functools32-3.2.3-2.tar.gz 22:17:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 22:17:42 INFO - warning: no files found matching '*.txt' 22:17:42 INFO - no previously-included directories found matching 'build' 22:17:42 INFO - no previously-included directories found matching 'dist' 22:17:42 INFO - no previously-included directories found matching '.git*' 22:17:42 INFO - Installing collected packages: jsonschema, functools32 22:17:42 INFO - Running setup.py install for functools32 22:17:42 INFO - warning: no files found matching '*.txt' 22:17:42 INFO - no previously-included directories found matching 'build' 22:17:42 INFO - no previously-included directories found matching 'dist' 22:17:42 INFO - no previously-included directories found matching '.git*' 22:17:42 INFO - Successfully installed jsonschema functools32 22:17:42 INFO - Cleaning up... 22:17:42 INFO - Return code: 0 22:17:42 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 22:17: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')]} 22:17:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:17:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:17: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')]} 22:17:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:17:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:17:42 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 0x109793490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109709570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc6f34d9f80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1095a7d30>, '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 0x1093cddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10959fb20>, '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.8m8ug64qmu/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.q9UhaPupy5/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/g3/g2lxvbfd1c33ggtssv6lgc3r00000w/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 22:17:42 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 22:17:42 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 22:17:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.8m8ug64qmu/Render', 22:17:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:17:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:17:42 INFO - 'HOME': '/Users/cltbld', 22:17:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:17:42 INFO - 'LOGNAME': 'cltbld', 22:17:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:17:42 INFO - 'MOZ_NO_REMOTE': '1', 22:17:42 INFO - 'NO_EM_RESTART': '1', 22:17:42 INFO - 'PAGER': '/bin/cat', 22:17:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:17:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:17:42 INFO - 'PWD': '/builds/slave/test', 22:17:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:17:42 INFO - 'SHELL': '/bin/bash', 22:17:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.q9UhaPupy5/Listeners', 22:17:42 INFO - 'TMPDIR': '/var/folders/g3/g2lxvbfd1c33ggtssv6lgc3r00000w/T/', 22:17:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:17:42 INFO - 'USER': 'cltbld', 22:17:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:17:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:17:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:17:42 INFO - 'XPC_FLAGS': '0x0', 22:17:42 INFO - 'XPC_SERVICE_NAME': '0', 22:17:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:17:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:17:43 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 22:17:43 INFO - Cleaning up... 22:17:43 INFO - Return code: 0 22:17:43 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 22:17:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:17:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:17:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:17:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:17:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:17:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:17:43 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 0x109793490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109709570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc6f34d9f80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1095a7d30>, '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 0x1093cddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10959fb20>, '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.8m8ug64qmu/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.q9UhaPupy5/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/g3/g2lxvbfd1c33ggtssv6lgc3r00000w/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 22:17:43 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 22:17:43 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 22:17:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.8m8ug64qmu/Render', 22:17:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:17:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:17:43 INFO - 'HOME': '/Users/cltbld', 22:17:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:17:43 INFO - 'LOGNAME': 'cltbld', 22:17:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:17:43 INFO - 'MOZ_NO_REMOTE': '1', 22:17:43 INFO - 'NO_EM_RESTART': '1', 22:17:43 INFO - 'PAGER': '/bin/cat', 22:17:43 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:17:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:17:43 INFO - 'PWD': '/builds/slave/test', 22:17:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:17:43 INFO - 'SHELL': '/bin/bash', 22:17:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.q9UhaPupy5/Listeners', 22:17:43 INFO - 'TMPDIR': '/var/folders/g3/g2lxvbfd1c33ggtssv6lgc3r00000w/T/', 22:17:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:17:43 INFO - 'USER': 'cltbld', 22:17:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:17:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:17:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:17:43 INFO - 'XPC_FLAGS': '0x0', 22:17:43 INFO - 'XPC_SERVICE_NAME': '0', 22:17:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:17:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:17:43 INFO - Downloading/unpacking blobuploader==1.2.4 22:17:43 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 22:17:43 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 22:17:43 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 22:17:43 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 22:17:43 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 22:17:43 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 22:17:45 INFO - Downloading blobuploader-1.2.4.tar.gz 22:17:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 22:17:45 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 22:17:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:17:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:17:45 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:17:45 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:17:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:17:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:17:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 22:17:46 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 22:17:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:17:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:17:46 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:17:46 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:17:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:17:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:17:46 INFO - Downloading docopt-0.6.1.tar.gz 22:17:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 22:17:47 INFO - Installing collected packages: blobuploader, requests, docopt 22:17:47 INFO - Running setup.py install for blobuploader 22:17:47 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 22:17:47 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 22:17:47 INFO - Running setup.py install for requests 22:17:47 INFO - Running setup.py install for docopt 22:17:47 INFO - Successfully installed blobuploader requests docopt 22:17:47 INFO - Cleaning up... 22:17:47 INFO - Return code: 0 22:17:47 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:17:47 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')]} 22:17:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:17:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:17:47 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')]} 22:17:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:17:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:17:47 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 0x109793490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109709570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc6f34d9f80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1095a7d30>, '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 0x1093cddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10959fb20>, '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.8m8ug64qmu/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.q9UhaPupy5/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/g3/g2lxvbfd1c33ggtssv6lgc3r00000w/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 22:17:47 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 22:17:47 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 22:17:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.8m8ug64qmu/Render', 22:17:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:17:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:17:47 INFO - 'HOME': '/Users/cltbld', 22:17:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:17:47 INFO - 'LOGNAME': 'cltbld', 22:17:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:17:47 INFO - 'MOZ_NO_REMOTE': '1', 22:17:47 INFO - 'NO_EM_RESTART': '1', 22:17:47 INFO - 'PAGER': '/bin/cat', 22:17:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:17:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:17:47 INFO - 'PWD': '/builds/slave/test', 22:17:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:17:47 INFO - 'SHELL': '/bin/bash', 22:17:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.q9UhaPupy5/Listeners', 22:17:47 INFO - 'TMPDIR': '/var/folders/g3/g2lxvbfd1c33ggtssv6lgc3r00000w/T/', 22:17:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:17:47 INFO - 'USER': 'cltbld', 22:17:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:17:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:17:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:17:47 INFO - 'XPC_FLAGS': '0x0', 22:17:47 INFO - 'XPC_SERVICE_NAME': '0', 22:17:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:17:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:17:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 22:17:48 INFO - Running setup.py (path:/var/folders/g3/g2lxvbfd1c33ggtssv6lgc3r00000w/T/pip-o0ohm1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 22:17:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 22:17:48 INFO - Running setup.py (path:/var/folders/g3/g2lxvbfd1c33ggtssv6lgc3r00000w/T/pip-4drL6Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 22:17:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 22:17:48 INFO - Running setup.py (path:/var/folders/g3/g2lxvbfd1c33ggtssv6lgc3r00000w/T/pip-s1YLrM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 22:17:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 22:17:48 INFO - Running setup.py (path:/var/folders/g3/g2lxvbfd1c33ggtssv6lgc3r00000w/T/pip-lKXSZn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 22:17:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 22:17:48 INFO - Running setup.py (path:/var/folders/g3/g2lxvbfd1c33ggtssv6lgc3r00000w/T/pip-zslyn1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 22:17:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 22:17:48 INFO - Running setup.py (path:/var/folders/g3/g2lxvbfd1c33ggtssv6lgc3r00000w/T/pip-uQ2aP9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 22:17:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 22:17:48 INFO - Running setup.py (path:/var/folders/g3/g2lxvbfd1c33ggtssv6lgc3r00000w/T/pip-8vXjKA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 22:17:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 22:17:48 INFO - Running setup.py (path:/var/folders/g3/g2lxvbfd1c33ggtssv6lgc3r00000w/T/pip-TA63wu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 22:17:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 22:17:49 INFO - Running setup.py (path:/var/folders/g3/g2lxvbfd1c33ggtssv6lgc3r00000w/T/pip-uAOGW2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 22:17:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 22:17:49 INFO - Running setup.py (path:/var/folders/g3/g2lxvbfd1c33ggtssv6lgc3r00000w/T/pip-nIS8Zf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 22:17:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 22:17:49 INFO - Running setup.py (path:/var/folders/g3/g2lxvbfd1c33ggtssv6lgc3r00000w/T/pip-YqVIUs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 22:17:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 22:17:49 INFO - Running setup.py (path:/var/folders/g3/g2lxvbfd1c33ggtssv6lgc3r00000w/T/pip-7c5b1C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 22:17:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 22:17:49 INFO - Running setup.py (path:/var/folders/g3/g2lxvbfd1c33ggtssv6lgc3r00000w/T/pip-E79Wbh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 22:17:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 22:17:49 INFO - Running setup.py (path:/var/folders/g3/g2lxvbfd1c33ggtssv6lgc3r00000w/T/pip-bbmFxk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 22:17:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 22:17:49 INFO - Running setup.py (path:/var/folders/g3/g2lxvbfd1c33ggtssv6lgc3r00000w/T/pip-b81QhY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 22:17:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 22:17:49 INFO - Running setup.py (path:/var/folders/g3/g2lxvbfd1c33ggtssv6lgc3r00000w/T/pip-g7jT9f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 22:17:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 22:17:49 INFO - Running setup.py (path:/var/folders/g3/g2lxvbfd1c33ggtssv6lgc3r00000w/T/pip-RuWo3Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 22:17:49 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 22:17:49 INFO - Running setup.py install for manifestparser 22:17:50 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 22:17:50 INFO - Running setup.py install for mozcrash 22:17:50 INFO - Running setup.py install for mozdebug 22:17:50 INFO - Running setup.py install for mozdevice 22:17:50 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 22:17:50 INFO - Installing dm script to /builds/slave/test/build/venv/bin 22:17:50 INFO - Running setup.py install for mozfile 22:17:50 INFO - Running setup.py install for mozhttpd 22:17:50 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 22:17:50 INFO - Running setup.py install for mozinfo 22:17:50 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 22:17:50 INFO - Running setup.py install for mozInstall 22:17:51 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 22:17:51 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 22:17:51 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 22:17:51 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 22:17:51 INFO - Running setup.py install for mozleak 22:17:51 INFO - Running setup.py install for mozlog 22:17:51 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 22:17:51 INFO - Running setup.py install for moznetwork 22:17:51 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 22:17:51 INFO - Running setup.py install for mozprocess 22:17:51 INFO - Running setup.py install for mozprofile 22:17:51 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 22:17:51 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 22:17:51 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 22:17:51 INFO - Running setup.py install for mozrunner 22:17:52 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 22:17:52 INFO - Running setup.py install for mozscreenshot 22:17:52 INFO - Running setup.py install for moztest 22:17:52 INFO - Running setup.py install for mozversion 22:17:52 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 22:17:52 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 22:17:52 INFO - Cleaning up... 22:17:52 INFO - Return code: 0 22:17:52 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:17:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:17:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:17:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:17:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:17:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:17:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:17:52 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 0x109793490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109709570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc6f34d9f80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1095a7d30>, '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 0x1093cddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10959fb20>, '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.8m8ug64qmu/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.q9UhaPupy5/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/g3/g2lxvbfd1c33ggtssv6lgc3r00000w/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 22:17:52 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 22:17:52 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 22:17:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.8m8ug64qmu/Render', 22:17:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:17:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:17:52 INFO - 'HOME': '/Users/cltbld', 22:17:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:17:52 INFO - 'LOGNAME': 'cltbld', 22:17:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:17:52 INFO - 'MOZ_NO_REMOTE': '1', 22:17:52 INFO - 'NO_EM_RESTART': '1', 22:17:52 INFO - 'PAGER': '/bin/cat', 22:17:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:17:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:17:52 INFO - 'PWD': '/builds/slave/test', 22:17:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:17:52 INFO - 'SHELL': '/bin/bash', 22:17:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.q9UhaPupy5/Listeners', 22:17:52 INFO - 'TMPDIR': '/var/folders/g3/g2lxvbfd1c33ggtssv6lgc3r00000w/T/', 22:17:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:17:52 INFO - 'USER': 'cltbld', 22:17:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:17:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:17:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:17:52 INFO - 'XPC_FLAGS': '0x0', 22:17:52 INFO - 'XPC_SERVICE_NAME': '0', 22:17:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:17:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:17:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 22:17:52 INFO - Running setup.py (path:/var/folders/g3/g2lxvbfd1c33ggtssv6lgc3r00000w/T/pip-nECDnL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 22:17:52 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)) 22:17:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 22:17:52 INFO - Running setup.py (path:/var/folders/g3/g2lxvbfd1c33ggtssv6lgc3r00000w/T/pip-c9Jwlr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 22:17:53 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)) 22:17:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 22:17:53 INFO - Running setup.py (path:/var/folders/g3/g2lxvbfd1c33ggtssv6lgc3r00000w/T/pip-4F8yCw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 22:17:53 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)) 22:17:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 22:17:53 INFO - Running setup.py (path:/var/folders/g3/g2lxvbfd1c33ggtssv6lgc3r00000w/T/pip-fpVSc6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 22:17:53 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)) 22:17:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 22:17:53 INFO - Running setup.py (path:/var/folders/g3/g2lxvbfd1c33ggtssv6lgc3r00000w/T/pip-H1YE0s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 22:17:53 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)) 22:17:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 22:17:53 INFO - Running setup.py (path:/var/folders/g3/g2lxvbfd1c33ggtssv6lgc3r00000w/T/pip-fW2uNv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 22:17:53 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)) 22:17:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 22:17:53 INFO - Running setup.py (path:/var/folders/g3/g2lxvbfd1c33ggtssv6lgc3r00000w/T/pip-7JxthJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 22:17:53 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)) 22:17:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 22:17:53 INFO - Running setup.py (path:/var/folders/g3/g2lxvbfd1c33ggtssv6lgc3r00000w/T/pip-NagOmf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 22:17:53 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)) 22:17:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 22:17:53 INFO - Running setup.py (path:/var/folders/g3/g2lxvbfd1c33ggtssv6lgc3r00000w/T/pip-Jt3t7Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 22:17:53 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)) 22:17:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 22:17:53 INFO - Running setup.py (path:/var/folders/g3/g2lxvbfd1c33ggtssv6lgc3r00000w/T/pip-nIIZx2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 22:17:53 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)) 22:17:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 22:17:53 INFO - Running setup.py (path:/var/folders/g3/g2lxvbfd1c33ggtssv6lgc3r00000w/T/pip-PCJpWH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 22:17:53 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)) 22:17:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 22:17:53 INFO - Running setup.py (path:/var/folders/g3/g2lxvbfd1c33ggtssv6lgc3r00000w/T/pip-0qo7vw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 22:17:54 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)) 22:17:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 22:17:54 INFO - Running setup.py (path:/var/folders/g3/g2lxvbfd1c33ggtssv6lgc3r00000w/T/pip-wHqbxs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 22:17:54 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)) 22:17:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 22:17:54 INFO - Running setup.py (path:/var/folders/g3/g2lxvbfd1c33ggtssv6lgc3r00000w/T/pip-0T5dYb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 22:17:54 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)) 22:17:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 22:17:54 INFO - Running setup.py (path:/var/folders/g3/g2lxvbfd1c33ggtssv6lgc3r00000w/T/pip-DyJ23S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 22:17:54 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)) 22:17:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 22:17:54 INFO - Running setup.py (path:/var/folders/g3/g2lxvbfd1c33ggtssv6lgc3r00000w/T/pip-lZ9thD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 22:17:54 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)) 22:17:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 22:17:54 INFO - Running setup.py (path:/var/folders/g3/g2lxvbfd1c33ggtssv6lgc3r00000w/T/pip-2li4Rm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 22:17:54 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)) 22:17:54 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)) 22:17:54 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)) 22:17:54 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)) 22:17:54 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)) 22:17:54 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)) 22:17:54 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 22:17:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:17:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:17:54 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:17:54 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:17:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:17:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:17:57 INFO - Downloading blessings-1.6.tar.gz 22:17:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 22:17:57 INFO - Installing collected packages: blessings 22:17:57 INFO - Running setup.py install for blessings 22:17:57 INFO - Successfully installed blessings 22:17:57 INFO - Cleaning up... 22:17:57 INFO - Return code: 0 22:17:57 INFO - Done creating virtualenv /builds/slave/test/build/venv. 22:17:57 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 22:17:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 22:17:57 INFO - Reading from file tmpfile_stdout 22:17:57 INFO - Current package versions: 22:17:57 INFO - blessings == 1.6 22:17:57 INFO - blobuploader == 1.2.4 22:17:57 INFO - docopt == 0.6.1 22:17:57 INFO - functools32 == 3.2.3-2 22:17:57 INFO - jsonschema == 2.5.1 22:17:57 INFO - manifestparser == 1.1 22:17:57 INFO - mozInstall == 1.12 22:17:57 INFO - mozcrash == 0.17 22:17:57 INFO - mozdebug == 0.1 22:17:57 INFO - mozdevice == 0.48 22:17:57 INFO - mozfile == 1.2 22:17:57 INFO - mozhttpd == 0.7 22:17:57 INFO - mozinfo == 0.9 22:17:57 INFO - mozleak == 0.1 22:17:57 INFO - mozlog == 3.4 22:17:57 INFO - moznetwork == 0.27 22:17:57 INFO - mozprocess == 0.23 22:17:57 INFO - mozprofile == 0.28 22:17:57 INFO - mozrunner == 6.12 22:17:57 INFO - mozscreenshot == 0.1 22:17:57 INFO - mozsystemmonitor == 0.3 22:17:57 INFO - moztest == 0.7 22:17:57 INFO - mozversion == 1.4 22:17:57 INFO - psutil == 3.1.1 22:17:57 INFO - requests == 1.2.3 22:17:57 INFO - wsgiref == 0.1.2 22:17:57 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:17:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:17:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:17:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:17:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:17:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:17:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:17:57 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 0x109793490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109709570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc6f34d9f80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1095a7d30>, '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 0x1093cddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10959fb20>, '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.8m8ug64qmu/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.q9UhaPupy5/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/g3/g2lxvbfd1c33ggtssv6lgc3r00000w/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 22:17:57 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 22:17:57 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 22:17:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.8m8ug64qmu/Render', 22:17:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:17:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:17:57 INFO - 'HOME': '/Users/cltbld', 22:17:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:17:57 INFO - 'LOGNAME': 'cltbld', 22:17:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:17:57 INFO - 'MOZ_NO_REMOTE': '1', 22:17:57 INFO - 'NO_EM_RESTART': '1', 22:17:57 INFO - 'PAGER': '/bin/cat', 22:17:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:17:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:17:57 INFO - 'PWD': '/builds/slave/test', 22:17:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:17:57 INFO - 'SHELL': '/bin/bash', 22:17:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.q9UhaPupy5/Listeners', 22:17:57 INFO - 'TMPDIR': '/var/folders/g3/g2lxvbfd1c33ggtssv6lgc3r00000w/T/', 22:17:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:17:57 INFO - 'USER': 'cltbld', 22:17:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:17:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:17:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:17:57 INFO - 'XPC_FLAGS': '0x0', 22:17:57 INFO - 'XPC_SERVICE_NAME': '0', 22:17:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:17:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:17:57 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)) 22:17:57 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)) 22:17:57 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)) 22:17:57 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)) 22:17:57 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)) 22:17:57 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)) 22:17:57 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)) 22:17:57 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)) 22:17:57 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)) 22:17:57 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)) 22:17:57 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)) 22:17:57 INFO - Cleaning up... 22:17:57 INFO - Return code: 0 22:17:57 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 22:17:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:17:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:17:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:17:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:17:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:17:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:17:57 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x109793490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109709570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc6f34d9f80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1095a7d30>, '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 0x1093cddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10959fb20>, '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.8m8ug64qmu/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.q9UhaPupy5/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/g3/g2lxvbfd1c33ggtssv6lgc3r00000w/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 22:17:57 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 22:17:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 22:17:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.8m8ug64qmu/Render', 22:17:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:17:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:17:57 INFO - 'HOME': '/Users/cltbld', 22:17:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:17:57 INFO - 'LOGNAME': 'cltbld', 22:17:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:17:57 INFO - 'MOZ_NO_REMOTE': '1', 22:17:57 INFO - 'NO_EM_RESTART': '1', 22:17:57 INFO - 'PAGER': '/bin/cat', 22:17:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:17:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:17:57 INFO - 'PWD': '/builds/slave/test', 22:17:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:17:57 INFO - 'SHELL': '/bin/bash', 22:17:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.q9UhaPupy5/Listeners', 22:17:57 INFO - 'TMPDIR': '/var/folders/g3/g2lxvbfd1c33ggtssv6lgc3r00000w/T/', 22:17:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:17:57 INFO - 'USER': 'cltbld', 22:17:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:17:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:17:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:17:57 INFO - 'XPC_FLAGS': '0x0', 22:17:57 INFO - 'XPC_SERVICE_NAME': '0', 22:17:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:17:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:17:58 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 22:17:58 INFO - Cleaning up... 22:17:58 INFO - Return code: 0 22:17:58 INFO - Running post-action listener: _resource_record_post_action 22:17:58 INFO - Running post-action listener: _start_resource_monitoring 22:17:58 INFO - Starting resource monitoring. 22:17:58 INFO - [mozharness: 2016-10-28 05:17:58.244400Z] Finished create-virtualenv step (success) 22:17:58 INFO - [mozharness: 2016-10-28 05:17:58.245371Z] Running install step. 22:17:58 INFO - Running pre-action listener: _resource_record_pre_action 22:17:58 INFO - Running main action method: install 22:17:58 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 22:17:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 22:17:58 INFO - Reading from file tmpfile_stdout 22:17:58 INFO - Detecting whether we're running mozinstall >=1.0... 22:17:58 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 22:17:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 22:17:58 INFO - Reading from file tmpfile_stdout 22:17:58 INFO - Output received: 22:17:58 INFO - Usage: mozinstall [options] installer 22:17:58 INFO - Options: 22:17:58 INFO - -h, --help show this help message and exit 22:17:58 INFO - -d DEST, --destination=DEST 22:17:58 INFO - Directory to install application into. [default: 22:17:58 INFO - "/builds/slave/test"] 22:17:58 INFO - --app=APP Application being installed. [default: firefox] 22:17:58 INFO - mkdir: /builds/slave/test/build/application 22:17:58 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 22:17:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 22:18:33 INFO - Reading from file tmpfile_stdout 22:18:33 INFO - Output received: 22:18:33 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 22:18:33 INFO - Running post-action listener: _resource_record_post_action 22:18:33 INFO - [mozharness: 2016-10-28 05:18:33.803269Z] Finished install step (success) 22:18:33 INFO - [mozharness: 2016-10-28 05:18:33.803393Z] Running run-tests step. 22:18:33 INFO - Running pre-action listener: _resource_record_pre_action 22:18:33 INFO - Running main action method: run_tests 22:18:33 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 22:18:33 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 22:18:33 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 22:18:33 INFO - 2016-10-27 22:18:33.815 screenresolution[1642:7513] starting screenresolution argv=screenresolution get 22:18:33 INFO - 2016-10-27 22:18:33.818 screenresolution[1642:7513] Display 0: 1600x1200x32@60 22:18:33 INFO - 2016-10-27 22:18:33.823 screenresolution[1643:7515] starting screenresolution argv=screenresolution list 22:18:33 INFO - Available Modes on Display 0 22:18:33 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 22:18:33 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 22:18:33 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 22:18:33 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 22:18:34 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 22:18:34 INFO - Intel Iris: 22:18:34 INFO - Chipset Model: Intel Iris 22:18:34 INFO - Type: GPU 22:18:34 INFO - Bus: Built-In 22:18:34 INFO - VRAM (Dynamic, Max): 1536 MB 22:18:34 INFO - Vendor: Intel (0x8086) 22:18:34 INFO - Device ID: 0x0a2e 22:18:34 INFO - Revision ID: 0x0009 22:18:34 INFO - Displays: 22:18:34 INFO - Display: 22:18:34 INFO - Resolution: 1600 x 1200 @ 60 Hz 22:18:34 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 22:18:34 INFO - Display Serial Number: 1600x1200 60 22:18:34 INFO - Main Display: Yes 22:18:34 INFO - Mirror: Off 22:18:34 INFO - Online: Yes 22:18:34 INFO - Rotation: Supported 22:18:34 INFO - Return code: 0 22:18:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 22:18:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 22:18:34 INFO - Python 2.7.3 22:18:34 INFO - Return code: 0 22:18:34 INFO - grabbing minidump binary from tooltool 22:18:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:18:34 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 0x1095a7d30>, '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 0x1093cddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10959fb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 22:18:34 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 22:18:34 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 22:18:34 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 22:18:34 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 22:18:34 INFO - Return code: 0 22:18:34 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 22:18:34 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 22:18:34 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 22:18:34 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 22:18:34 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 22:18:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/bfBCfpzpTQK2DiO08yHbvQ/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0089', '--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 22:18:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/bfBCfpzpTQK2DiO08yHbvQ/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0089 --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 22:18:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.8m8ug64qmu/Render', 22:18:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:18:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:18:34 INFO - 'HOME': '/Users/cltbld', 22:18:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:18:34 INFO - 'LOGNAME': 'cltbld', 22:18:34 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 22:18:34 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 22:18:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:18:34 INFO - 'MOZ_NO_REMOTE': '1', 22:18:34 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 22:18:34 INFO - 'NO_EM_RESTART': '1', 22:18:34 INFO - 'PAGER': '/bin/cat', 22:18:34 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:18:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:18:34 INFO - 'PWD': '/builds/slave/test', 22:18:34 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 22:18:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:18:34 INFO - 'SHELL': '/bin/bash', 22:18:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.q9UhaPupy5/Listeners', 22:18:34 INFO - 'TMPDIR': '/var/folders/g3/g2lxvbfd1c33ggtssv6lgc3r00000w/T/', 22:18:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:18:34 INFO - 'USER': 'cltbld', 22:18:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:18:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:18:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:18:34 INFO - 'XPC_FLAGS': '0x0', 22:18:34 INFO - 'XPC_SERVICE_NAME': '0', 22:18:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:18:34 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/bfBCfpzpTQK2DiO08yHbvQ/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0089', '--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 22:18:34 INFO - mozversion application_buildid: 20161027192219 22:18:34 INFO - mozversion application_changeset: 944cb0fd05526894fcd90fbe7d1e625ee53cd73d 22:18:34 INFO - mozversion application_display_name: Nightly 22:18:34 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 22:18:34 INFO - mozversion application_name: Firefox 22:18:34 INFO - mozversion application_remotingname: firefox 22:18:34 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 22:18:34 INFO - mozversion application_vendor: Mozilla 22:18:34 INFO - mozversion application_version: 52.0a1 22:18:34 INFO - mozversion platform_buildid: 20161027192219 22:18:34 INFO - mozversion platform_changeset: 944cb0fd05526894fcd90fbe7d1e625ee53cd73d 22:18:34 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 22:18:34 INFO - mozversion platform_version: 52.0a1 22:18:34 INFO - using testdate: 1477631914 22:18:34 INFO - actual date: 1477631914 22:18:34 INFO - starting webserver on 'localhost:49275' 22:18:34 INFO - SUITE-START | Running 2 tests 22:18:34 INFO - TEST-START | dromaeo_css 22:18:34 INFO - operating with platform_type : mac_ 22:18:34 INFO - Initialising browser for dromaeo_css test... 22:18:34 INFO - TEST-INFO | started process 1653 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/g3/g2lxvbfd1c33ggtssv6lgc3r00000w/T/tmpnzG_Ws/profile http://localhost:49275/getInfo.html) 22:18:36 INFO - PROCESS | 1653 | __metrics Screen width/height:1600/1200 22:18:36 INFO - PROCESS | 1653 | colorDepth:24 22:18:36 INFO - PROCESS | 1653 | Browser inner width/height: 1024/689 22:18:36 INFO - PROCESS | 1653 | __metrics 22:18:36 INFO - PROCESS | 1653 | 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" 22:18:36 INFO - PROCESS | 1653 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 205: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 22:18:37 INFO - TEST-INFO | 1653: exit 0 22:18:37 INFO - Browser initialized. 22:18:37 INFO - Running cycle 1/1 for dromaeo_css test... 22:18:37 INFO - TEST-INFO | started process 1655 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/g3/g2lxvbfd1c33ggtssv6lgc3r00000w/T/tmpnzG_Ws/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 22:18:49 INFO - PROCESS | 1655 | RSS: Main: 230014976 22:18:49 INFO - PROCESS | 1655 | 22:19:40 INFO - PROCESS | 1655 | 1477631980713 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] 22:21:53 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49275/tests/dromaeo/cssquery-ext.html) 22:21:55 INFO - PROCESS | 1655 | RSS: Main: 393310208 22:21:55 INFO - PROCESS | 1655 | 22:24:58 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-ext.html (next: http://localhost:49275/tests/dromaeo/cssquery-jquery.html) 22:24:59 INFO - PROCESS | 1655 | RSS: Main: 381554688 22:24:59 INFO - PROCESS | 1655 | 22:28:01 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49275/tests/dromaeo/cssquery-mootools.html) 22:28:02 INFO - PROCESS | 1655 | RSS: Main: 345100288 22:28:02 INFO - PROCESS | 1655 | 22:31:04 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49275/tests/dromaeo/cssquery-prototype.html) 22:31:05 INFO - PROCESS | 1655 | RSS: Main: 348073984 22:31:05 INFO - PROCESS | 1655 | 22:34:08 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49275/tests/dromaeo/cssquery-yui.html) 22:34:09 INFO - PROCESS | 1655 | RSS: Main: 334782464 22:34:09 INFO - PROCESS | 1655 | 22:37:12 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-yui.html (next: http://localhost:49275/tests/dromaeo/cssquery-dojo.html) 22:37:12 INFO - PROCESS | 1655 | RSS: Main: 526319616 22:37:12 INFO - PROCESS | 1655 | 22:37:12 INFO - PROCESS | 1655 | __start_tp_report 22:37:12 INFO - PROCESS | 1655 | _x_x_mozilla_page_load 22:37:12 INFO - PROCESS | 1655 | _x_x_mozilla_page_load_details 22:37:12 INFO - PROCESS | 1655 | |i|pagename|runs| 22:37:12 INFO - PROCESS | 1655 | |0;dojo.html;2715.7963152763546;3304.5043243513487;3307.4212012897738;3334.533165356852;3357.9832100839494;5453.890922181554;5545.9445405545985;5556.138798486231;5609.943900560993;5620.466055724704;5550.16747487877;5553.194786755928;5620.213170156171;5638.069718496443;5670.291213598301;4252.213340532004;4419.3150061740325;5172.534471897528;5184.377874655047;5184.922226166611;5148.3564554430695;5216.843494695166;5230.92153617696;5232.189010703328;5234.345706786652;4545.659075569329;5035.823746168885;5044.319016932722;5176.482351764831;5199.740012999354;5037.949620503785;5090.643654944155;5162.535371816554;5183.326167598226;5195.974020129913;5043.571296439807;5077.416097148838;5083.262926875619;5083.6441449098575;5104.438511763703;5526.309211348582;5552.222688823568;5555.2222688823595;5601;5622.803201887914;6150.169727086831;6158.507319414436;6159.599626024294;6161.291451483092;6173.660448675321;6067.666278354689;6110.022396416596;6131.785387511461;6141.938580614226;6166.660833654148;4633;4733.171694953369;5686.516646085129;5736;5784.334801497839;6304.211973503312;6332.208473940757;6334.778282760081;6340.841478963062;6346.048092786119;5811.099279611667;5878.323992740804;5890.410958904161;5971.671558064261;5981.641101533922;5092.949070509321;5139.486051394905;6065.878682426327;6139.539534534928;6180.5673602848265;6019.458248757545;6060.181875446847;6069.332373438918;6069.393060693984;6084.939150608438;5914.319853216849;6144.969275153596;6147.754089836446;6149.96925015372;6157.076438534255;4772.188727916263;4809.134355815917;4819.084373968934;4819.831305904277;4825.179719447702;4280.357946308017;4375.956240437618;4382.342648602671;4388.890277743082;4390;5567;5568.832935011957;5572.665640061609;5573.164025396223;5587.636803607753;6009.248843894513;6079.483243924316;6098;6100.359462256488;6106.328303886569;5559.527440167631;5593.636413633101;5599.020171469976;5599.580031497654;5600.4399560044485;5273.973630131824;5279.9208011879855;5283.181106928432;5286.97356513215;5286.995470860601;6279.560430769893;6326.715297811609;6349.587276826992;6350.968245158745;6375;6170.660613666291;6193.690315484298;6198.597091189058;6200.937990620036;6203.441690247719;6227.50179985591;6272.968635156795;6285.779997700059;6315.873682526232;6324.525660575383;4717.5046620104395;5944.940550594439;6026.186464827192;6062.666553339609;6087.786927457518;5515.448455154453;6036.275646922398;6107.328193898757;6167.383261673796;6190.50475961913;5760.711964401847;5842.7662893485485;5945.524358051259;5952.404759524013;5969.492593129633;5863.706814659165;5880.470757631833;5880.8235752927485;5957.821265362046;6038.879222415615;5816.680082595329;5821.09772985188;5829.912551311649;5846.736896839568;5895.351511333835;5916.471588113352;5954.910676339945;5955.196048533566;5968.970155149196;5985.221921150397;5650.519705825051;5730;5733.655980641175;5802.6808525531505;5825.825225243249 22:37:12 INFO - PROCESS | 1655 | |1;ext.html;488.80936434790414;494.61172979211284;524.3157679228607;533.7998250656004;557.0085248258101;30352;40142.19360128794;41058.58941410585;41088;42558;42010.57989420105;42431.15137697244;42477.362839557354;42484.72545823622;42628.14743705124;9425.952870235657;9598.712038638852;9602.567884445216;9612.471314077722;9625.807483850318;9523.904760952406;9529.904700952971;9619.711408657751;9623.615055397775;9678.032196780301;9625.663101791439;9659.71020869375;9660.468674222915;9683.12851843334;9717.271204659644;9588.808223835518;9637;9667.081627245436;9668.806623867484;9670.56482458291;9460;9504.097110774464;9509.809803803955;9511.239100871913;9516.571754271074;39687.60312396868;39910.800445997585;39911;39950.20099598021;40092.198156037004;3488.7557870949045;3630.5280313559333;3816.580176180618;3849.0176417445296;4045.37296719009;29900.401991960258;31237.53143702846;31593.578288977053;31636;31985.840070799728;3150.417172823035;3214.180384002088;3215.710586047267;3271.2803183299634;3324.401607710611;449825;455032;456441.4355856432;457185.9999999967;466104;57171.71414142986;57331.42668573261;57613.42386576081;57614;57646.423535764945;60598;60647.393526065054;60690;60706.69646651827;60737.392626074055;15339.463118790789;15548.144852033021;15559.29983150761;15563.922180389025;15608.063516189064;12386.876131238572;12442.937785311016;12445.315507646985;12453.377331133406;12456.314902680258;13298.06913516064;14434.494792682415;14863.628409289639;15080.54758357251;15490.690186196212;6938.514303998772;7001.509894307452;7043.506954513135;7136.892946605805;7195.92804071963;793.5159552672865;854.4873076154257;864.9697260595849;994.5872462927948;1043.7181960870523;29169.979050733124;29319.827206911912;30123;30253.848730756206;30316.393672126876;1054.3568423261804;1097.6213206443765;1117.9664610061711;1148.2995372822572;1148.3741360958297;12109.697257568456;15256.46602368934;15428.214419592652;15555.433353496546;15775.895687302009;616529;619857;619948;620820;622130;58349;58368;58485;58533;58923.70538147368;1262.7095767973417;1313.835770528684;1366.8291463567252;1402.7755559110494;1421.751193541147;29665.851670741507;30666.540001899542;30691;31153.688463115985;31212.843935781086;46670.83322916954;47281.29078063901;47400.2889956644;47454.76272618615;47634;501174;501779;503414;503660;772497;31624.683753162175;38194.42708359321;39369;39386.80306598449;40564.33510146371;46476.767616161706;46787.83030424266;47262.76368618135;47453;47643.04713905633;30443.543346849372;38185;38925;39467.40798888076;39803.00492487595;32064.51903221407;38374;38695.6130438692;39472.605273948044;39746.2050758989;37182;37884.24231515409;37988.24023519569;38225.808870955465;38641;28407.573886391307;30598.694013059583;30639.2340191497;30645.846770766897;30738.84630576833;21400.35798926035;25255.36861578475;25469.74530254748;25508.23475295744;25708.228753137435 22:37:12 INFO - PROCESS | 1655 | |2;jquery.html;329.6835038363172;370.9758865673731;374.9737518373713;390.97654140751575;402.2357520710651;19932;20268.69596956045;20790.792092079075;21212.681809772846;21413.357599272047;12132.514699412013;12306.63080107596;17183;20503;21228.044737986787;5150.768215430296;5412.566994640439;5417.45825417457;5419.807642318684;5433.130699088147;5712.200291959109;5987.550933679991;6061.757529698807;6089.573729838913;6099.329073801879;5740.368559458456;6023.759049638031;6093.207882975207;6102;6568.277489476154;6506.186726659168;6681.966590167067;6683.93316066838;6690.6320152391845;6701.798946031578;5670;5900.822975310747;5913.083472061838;5970.164177015221;5995.730192141321;11964.102692298113;12075.154739168262;13108.410121544459;13309.733805323936;13350.732985340239;2122.3420739570784;2149.9247526336503;2186.0709198590534;2247.3033359658557;2258.7402448718444;9776.364536305118;9867.506624668738;9893.763279590052;10160.975341754205;10386.948065259625;1743.9738403923825;1798.4424828303138;1852.536865783554;1877.4836919847014;1887.5564242403025;111070.3339449909;118671;119129.8087019136;119738;119776.40111799385;9153.405028673114;9688.515574221401;9721.562529686182;9782.75543111428;11317.71705707364;12326;12400.379981000913;12425.62723118308;12431.06766992461;12445.813312800316;7856.489328193764;7965.721199757981;8000.479968802126;8019.318357939524;8111.229433203836;7554.206808285161;7796.883046754303;8383.413161078666;8500.532470714046;8506.064332923374;3818.84724611018;3884.902877428087;3895.2014836958356;3937.330653788863;3946.427767973685;1868.5982513759607;1921.6541022616063;1926.248762982438;1945.6108778244409;1950.8146726061002;852.5438890193717;861.3711990247155;866.9609867555976;890.4879694175745;1072.6406653771073;19752.90123549373;20560.785039066956;20561.794382056287;20764.733150897948;21039.474013149793;894.8434024045766;927.5547737086237;987.6493844685177;1012.6784546167175;1045.9581616735397;8587.785305367415;10437.425941573134;10958.342499450058;10963.287386320024;10969.616063437741;586099;587865;597020;597735;599225;115447.42276288565;118115.40942295405;123364.59764621586;124441;124498;946.2666433514082;965.3098034905217;977.8288799460208;979.6718099436627;981.1316984468797;14079.788803168165;17751.556211094827;18159.818401815814;18380.17289222015;18381.54046148857;40959.79520102381;51418;51789.7410512945;51886.481135188165;52325.7383713079;76359.95572353073;76442;78531.60734196292;79333;79911;7126.429885609243;7423.700852350925;7662.237685332293;7689.769306920802;8221.342292616697;11090.611828586283;12774.872251277367;13064.21614703121;13096.607101786962;13147.013973952184;9275.86086208694;9976.900230997888;10929.344239345664;10974.286671366335;11736.0611151106;10961.671149865517;11224.66326010221;11317.320960742381;11594.420278986187;11700.35648039366;10081.89918100839;10681.664791901012;11098.667039988813;11259;11881.881181188422;6293.276273228455;8052.315553178046;8158.510489370657;8167.224113709188;8406.159384061544;10681.46592670379;10887.346759194474;11413.201075924606;11485.598004069818;11502.82745758831 22:37:12 INFO - PROCESS | 1655 | |3;mootools.html;1324.1988596898875;1360.9455621775116;1535.9555502258465;1699.4646686293815;1928.7685477742673;7331.303526165012;7656.157822639505;7943.165967573408;8189.262966333044;8199.303059239957;8061.677532898691;8146.1446548112335;8152.91847081529;8154.184581541857;8162;1992.7409436773198;2218.4914258304348;2257.9887100564515;2281.2129815213807;2286.2912497125944;2254.9098036078617;2302.2157827683595;2305.9769402306015;2308.7806658367426;2310.1106074161485;2217.944551386212;2253.346529506441;2279.1794953816698;2283.7373702024315;2293.5183611441616;2222.2111150541486;2240.8991595378166;2260.7287125544876;2261.2424837679396;2267.5351552931693;2236.888155592214;2262.5361800830956;2279.7347472152946;2281.6349384098467;2286.554121946221;8123.309518690919;8199;8203.343732501386;8224.794380140485;8225.177482251727;4961.90076198474;5132.9743351283005;5162.6902385856965;5163.225516172604;5197.090509160882;3219.227385427504;3475.6871881530783;3536.752427330088;3539.3629146753574;3548.1484443733575;1006.6484950749226;1013.5236438873718;1034.7620047389066;1046.2048842879467;1057.5346847387125;10463.947680261626;10510.894891051144;10582.682519524427;10595.205359598061;10614.628488002883;4042.514898212233;4247.957520424756;4333.566643335705;4336.111097225062;4350.456192975878;8240.546769927712;8396.496210227406;8411.957940210259;8431.241188293083;8433.072362040171;4991.50084991498;4994.02616489781;4994.625403094782;4995.725235112031;4998.800047998039;3589.7128229741556;3956.248312820555;4212.7472351659;4706.976465117653;4714.38712967312;3520.0847779577493;3570.9642903571153;3586.8744593939086;3607.2244467439627;3630.8910732678064;962.7400601837464;1116.7543140509074;1120.9383483908464;1121.327203677803;1133.82992551118;812.6505602590825;885.7254251182154;903.5362712405945;908.6547112097361;941.3786900645541;5107.948920510822;5352.357717073898;5595.300587426572;5651.208830763697;5772.6536407815665;417.84539720303684;437.06904293853887;438.5242012416564;440.72234492269666;443.60297533707154;3808.200277941608;3871.5094688545114;3898.1229223424502;3906.8046597670004;3913.25648126855;9383.967763546005;9903.960084191056;9916.900830991597;9927.602895884229;9942.502874856229;9233.215176710055;9493.294411092806;9739.756506087262;9874.753131171634;9894.505274736235;7307.3058059484265;7549.396048316122;7635.961820190863;7762.92237077622;7765.3011228988535;5682.431756824284;5714.085746280742;5760.539156867524;5762.971185144047;5882.265607601446;9042.824432823954;9097.863532047157;9112.179903808688;9187.632494700138;9320.207782338577;7663.923360766544;9015.053419391132;9169.908300917172;9330.533473326172;9375.156235938526;7001.50989430735;7122.323379278961;7125.251848555827;7132.358087772125;7140.750073747394;7584.848303033798;7629.771106866803;7665.23347665229;7761.223877612194;7779.455438119504;6812.14848143982;6873.347032031949;6982.965085174542;7044.15470143586;7068.469864760266;6658.267590564937;6733.0573719678305;6777.661116944035;6953.130858642669;7025.227225005143;6398.93601064002;6582.572132811352;6596.340365963365;6596.934030659632;6601.438877695449;4672.159011377985;4822.987172693774;4828.903421931612;4844.467108617979;4948.505149485023;4689.343491911067;4793.9041219176115;4852.8544143675745;4886.9022619546695;4978.576820970114 22:37:12 INFO - PROCESS | 1655 | |4;prototype.html;246.5635824590475;257.92778022153806;293.6226948371343;306.88031667649614;309.5696981196138;5174.223866420034;5184.844454666361;5188.403333616635;5200.7399630018535;5202.453742357055;3920.333543297639;4502.707324023944;4662.0675864827;5011.924821127686;5129.358830146231;3978.323684973547;3988.78061706606;3991.720579559432;4003.2593970115477;4005.799710014502;3483.0742176571803;3625.930350546582;3777.490038844762;3993.2030586236165;4010.5989401059805;3344.5317655528247;3712.3688972874675;3898.8830335089992;3946.861859834906;3971.344728119857;3898.7465814721954;3906.199229158016;3940.4680368150507;3957.5448823385245;3966.464527288837;3362.36115138123;3369.5114208439886;3374.9368948781057;3380.5943286805496;3384.627690953993;4102.958970410287;4979.7261150636705;4985.975070124626;4999.525045120708;5003.4996500350035;3934.8032598370255;4204.2432362174795;4223.176480586287;4523.140603285398;4533.964771353498;4745.193317136095;5435.864103397407;5470.91793623096;5490.039243132436;5525.309336332958;3108.4715598348107;3748.9250214995545;3765.8681946131755;3767.2842159989514;3768.434734789776;4650.674557751058;4801.255805350176;5193.8054247522905;5301.920471192935;5696.515796157331;3300.290437555937;3325.6341802401716;3495.9300813983577;3758.4174452959833;3760.1163726524246;3198.9680103199134;3808.4858544096746;3825.9234815304094;3828.3683192272965;3838.370716059508;4418.226810308248;4431.844885429059;4461.34930075874;4497.617702495325;4553.704009239389;5083.339165908408;5444.40111587725;5478.397376288605;5492.258545096361;5513.255710479114;1132.7960967026015;1248.9134453025927;1282.70497785508;1312.2454588611586;1313.238321773369;815.9510429374138;836.5068352989883;838.1827717974926;840.218596705058;843.7595285343648;4616.3306320584;4971.304017437562;4973.32860063894;4996.6502344836235;5063.392392912874;4270.7864606769535;5561.527270181999;5607.327120745536;5611.494965453129;5648.9717551412805;4695.460022097434;4759.643026773006;5091.210862316273;5123.667846359973;5175.430702622709;2353.952920941606;2650.3689944775397;2723.3872378715027;2894.8118372305735;2936.841421555155;4873.1959226727195;4914.385701787277;5776.826695199151;5808.244928159397;5871.647701137946;4674.439067311944;5645.830625081254;5674.035413979634;5728.971355143198;5742.684152371659;4805.447373552016;5029.622778291569;5200.6879587224885;5708.659449625634;5763.204845649857;4288.442502474658;4904.705717656952;5155.53600175986;5525;5543.362513311021;5443.510084092451;5490.792025754327;5519.668819870821;5522.9447705524035;5551.944480555143;5719.599628026164;5726.74229659658;5737.397573254748;5749.310082790092;5749.8562535936935;2900.912972610825;3069.1866655335907;3205.3108581655056;3206.3487304038904;3411.641777613414;4857.295692124551;5548.805791797267;5573.386927437898;5620.943790562042;5626.943730562642;5031.723255220998;5409.729513524387;5414.106672399168;5446.863828404163;5448.673079615236;5227.3465816772905;5368.677879327097;5448.757988996541;5514.393416724077;5517.917231241454;4449;4745.430548334222;5023.748812559431;5311.256424100552;5353.598480114085;4733.692309999839;5333.599980001438;5397.649152805055;5515.069725596009;5529.834104976857;4454.354118652842;5383.327084114486;5394.973025134849;5409.729513524387;5419.34967803866 22:37:12 INFO - PROCESS | 1655 | |5;yui.html;723.2116992478198;723.2514347650183;744.6090802328777;788.2275370137265;805.4643661964793;14996.77504837427;15304.234788260597;15337.079775213495;15354.769678454832;15534.766978495318;13847.93076034621;13923.512677056306;13954.372053257603;13961.511347102853;13985.230812305319;693.7398475571661;717.921028686844;721.1238345410326;820.7209548753423;851.9946463173453;796.5141263829059;835.9801042727893;883.5582208895552;887.2103827593447;888.1251966812684;868.6481974800184;918.677104968846;950.9919485345521;954.3128947158035;956.2827879090682;946.8248374050822;947.3605316411395;947.8388673925449;948.0803620488116;948.791265921503;541.6777017674477;563.7829435667297;671.3286713286714;694.892291694783;705.5555000349747;14800.40798368064;14891.925540372336;15140.545783626509;15147.848521514754;15155.696886062327;13363.265020423873;13560.321983900863;13675.658108547266;13691.794623080661;13754.587362379145;12039;12184.573539926148;12234.755304893852;12464.376781160996;12770.61688149357;4449.644028477746;4476.418065651477;4525.140223357552;4635.7218566886095;4640.211164102111;68963.65518172427;87932;88100.55949720275;88922.99999999936;89357;10791.136709063256;10821.350718956888;10876.564937402574;11043.825416049907;11397.487113079931;21098.05058772359;21199.25802596902;21290.04194811237;21332.466688332916;21634.45913852135;7590.278923502258;7645;7755.689772409153;7784.727534536264;7913.129555748863;4280.315149576053;4291.334843099325;4338.457692788402;4365.80353884076;4453.821847126079;3294.6870047345465;3403.4724617684296;3409.113630456099;3411.5394421752744;3412.2151905061974;561.2619405481778;568.7696482924422;580.1674596953385;640.193356370976;643.9903401448983;546.9206964990054;548.314606741573;560.44235985195;603.2730559675581;644.452215616722;7368.815779605553;8283.751487455385;8627.784305392415;8733.30133589324;8825.735227943173;519.6985748265996;533.8335736415944;599.101347978033;624.307019208678;625.6496362037274;4089.9386509202386;4213.5154457237195;4225.345071513921;4299.656027517791;4313.525512193656;107084;109014;109117;113656;117239.41380293044;66373.33626663768;74946.62526687332;98235;101314;102860;57526;58451;87356.83898478802;87804.56097719599;90239;7831.729026941444;8220.712275070344;8248.876266856121;8362.916370836214;8536.615852286537;39527.60472395239;42614.57385426106;57392.42607573871;58058.7097064512;60628.48428789315;72318.63840680763;80107;80347;83959.16040839514;85683;10821.67534973952;11200.943995279971;11294.322340659586;11314.83027754568;11935.641930742091;9330.95334523323;11302.717432064264;11705.180637355303;11850.466728997388;11874.168808183344;11272.887271127183;11656.76686466249;11661.416929153678;11665.416729163677;11927.582534611247;9638.325317228007;11173;11584.768304634028;11709.473073711533;11930;10966.06788422993;11292.43537823122;11351.262167959056;11550.537978480766;11588.536458541901;9653.855192172263;9876.308658394131;9945.403275803475;9999.3000489965;10114.59541618327;7498.137714162941;8290.46112002718;8779.25376343018;9509.239260859253;9565.378250413702 22:37:12 INFO - PROCESS | 1655 | __end_tp_report 22:37:12 INFO - PROCESS | 1655 | __start_cc_report 22:37:12 INFO - PROCESS | 1655 | _x_x_mozilla_cycle_collect,1718 22:37:12 INFO - PROCESS | 1655 | __end_cc_report 22:37:12 INFO - PROCESS | 1655 | __startTimestamp1477633032678__endTimestamp 22:37:12 INFO - PROCESS | 1655 | 22:37:12 INFO - PROCESS | 1655 | ------- Summary: start ------- 22:37:12 INFO - PROCESS | 1655 | Number of tests: 6 22:37:12 INFO - PROCESS | 1655 | 22:37:12 INFO - PROCESS | 1655 | [#0] dojo.html Cycles:165 Average:5593.36 Median:5784.33 stddev:670.10 (11.6%) stddev-sans-first:632.99 22:37:12 INFO - PROCESS | 1655 | Values: 2715.8 3304.5 3307.4 3334.5 3358.0 5453.9 5545.9 5556.1 5609.9 5620.5 5550.2 5553.2 5620.2 5638.1 5670.3 4252.2 4419.3 5172.5 5184.4 5184.9 5148.4 5216.8 5230.9 5232.2 5234.3 4545.7 5035.8 5044.3 5176.5 5199.7 5037.9 5090.6 5162.5 5183.3 5196.0 5043.6 5077.4 5083.3 5083.6 5104.4 5526.3 5552.2 5555.2 5601.0 5622.8 6150.2 6158.5 6159.6 6161.3 6173.7 6067.7 6110.0 6131.8 6141.9 6166.7 4633.0 4733.2 5686.5 5736.0 5784.3 6304.2 6332.2 6334.8 6340.8 6346.0 5811.1 5878.3 5890.4 5971.7 5981.6 5092.9 5139.5 6065.9 6139.5 6180.6 6019.5 6060.2 6069.3 6069.4 6084.9 5914.3 6145.0 6147.8 6150.0 6157.1 4772.2 4809.1 4819.1 4819.8 4825.2 4280.4 4376.0 4382.3 4388.9 4390.0 5567.0 5568.8 5572.7 5573.2 5587.6 6009.2 6079.5 6098.0 6100.4 6106.3 5559.5 5593.6 5599.0 5599.6 5600.4 5274.0 5279.9 5283.2 5287.0 5287.0 6279.6 6326.7 6349.6 6351.0 6375.0 6170.7 6193.7 6198.6 6200.9 6203.4 6227.5 6273.0 6285.8 6315.9 6324.5 4717.5 5944.9 6026.2 6062.7 6087.8 5515.4 6036.3 6107.3 6167.4 6190.5 5760.7 5842.8 5945.5 5952.4 5969.5 5863.7 5880.5 5880.8 5957.8 6038.9 5816.7 5821.1 5829.9 5846.7 5895.4 5916.5 5954.9 5955.2 5969.0 5985.2 5650.5 5730.0 5733.7 5802.7 5825.8 22:37:12 INFO - PROCESS | 1655 | 22:37:12 INFO - PROCESS | 1655 | [#1] ext.html Cycles:180 Average:67961.22 Median:22093.78 stddev:147544.49 (667.8%) stddev-sans-first:147871.42 22:37:12 INFO - PROCESS | 1655 | Values: 488.8 494.6 524.3 533.8 557.0 30352.0 40142.2 41058.6 41088.0 42558.0 42010.6 42431.2 42477.4 42484.7 42628.1 9426.0 9598.7 9602.6 9612.5 9625.8 9523.9 9529.9 9619.7 9623.6 9678.0 9625.7 9659.7 9660.5 9683.1 9717.3 9588.8 9637.0 9667.1 9668.8 9670.6 9460.0 9504.1 9509.8 9511.2 9516.6 39687.6 39910.8 39911.0 39950.2 40092.2 3488.8 3630.5 3816.6 3849.0 4045.4 29900.4 31237.5 31593.6 31636.0 31985.8 3150.4 3214.2 3215.7 3271.3 3324.4 449825.0 455032.0 456441.4 457186.0 466104.0 57171.7 57331.4 57613.4 57614.0 57646.4 60598.0 60647.4 60690.0 60706.7 60737.4 15339.5 15548.1 15559.3 15563.9 15608.1 12386.9 12442.9 12445.3 12453.4 12456.3 13298.1 14434.5 14863.6 15080.5 15490.7 6938.5 7001.5 7043.5 7136.9 7195.9 793.5 854.5 865.0 994.6 1043.7 29170.0 29319.8 30123.0 30253.8 30316.4 1054.4 1097.6 1118.0 1148.3 1148.4 12109.7 15256.5 15428.2 15555.4 15775.9 616529.0 619857.0 619948.0 620820.0 622130.0 58349.0 58368.0 58485.0 58533.0 58923.7 1262.7 1313.8 1366.8 1402.8 1421.8 29665.9 30666.5 30691.0 31153.7 31212.8 46670.8 47281.3 47400.3 47454.8 47634.0 501174.0 501779.0 503414.0 503660.0 772497.0 31624.7 38194.4 39369.0 39386.8 40564.3 46476.8 46787.8 47262.8 47453.0 47643.0 30443.5 38185.0 38925.0 39467.4 39803.0 32064.5 38374.0 38695.6 39472.6 39746.2 37182.0 37884.2 37988.2 38225.8 38641.0 28407.6 30598.7 30639.2 30645.8 30738.8 21400.4 25255.4 25469.7 25508.2 25708.2 22:37:12 INFO - PROCESS | 1655 | 22:37:12 INFO - PROCESS | 1655 | [#2] jquery.html Cycles:180 Average:33956.50 Median:2096.91 stddev:99045.52 (4723.4%) stddev-sans-first:99291.19 22:37:12 INFO - PROCESS | 1655 | Values: 329.7 371.0 375.0 391.0 402.2 19932.0 20268.7 20790.8 21212.7 21413.4 12132.5 12306.6 17183.0 20503.0 21228.0 5150.8 5412.6 5417.5 5419.8 5433.1 5712.2 5987.6 6061.8 6089.6 6099.3 5740.4 6023.8 6093.2 6102.0 6568.3 6506.2 6682.0 6683.9 6690.6 6701.8 5670.0 5900.8 5913.1 5970.2 5995.7 11964.1 12075.2 13108.4 13309.7 13350.7 2122.3 2149.9 2186.1 2247.3 2258.7 9776.4 9867.5 9893.8 10161.0 10386.9 1744.0 1798.4 1852.5 1877.5 1887.6 111070.3 118671.0 119129.8 119738.0 119776.4 9153.4 9688.5 9721.6 9782.8 11317.7 12326.0 12400.4 12425.6 12431.1 12445.8 7856.5 7965.7 8000.5 8019.3 8111.2 7554.2 7796.9 8383.4 8500.5 8506.1 3818.8 3884.9 3895.2 3937.3 3946.4 1868.6 1921.7 1926.2 1945.6 1950.8 852.5 861.4 867.0 890.5 1072.6 19752.9 20560.8 20561.8 20764.7 21039.5 894.8 927.6 987.6 1012.7 1046.0 8587.8 10437.4 10958.3 10963.3 10969.6 586099.0 587865.0 597020.0 597735.0 599225.0 115447.4 118115.4 123364.6 124441.0 124498.0 946.3 965.3 977.8 979.7 981.1 14079.8 17751.6 18159.8 18380.2 18381.5 40959.8 51418.0 51789.7 51886.5 52325.7 76360.0 76442.0 78531.6 79333.0 79911.0 7126.4 7423.7 7662.2 7689.8 8221.3 11090.6 12774.9 13064.2 13096.6 13147.0 9275.9 9976.9 10929.3 10974.3 11736.1 10961.7 11224.7 11317.3 11594.4 11700.4 10081.9 10681.7 11098.7 11259.0 11881.9 6293.3 8052.3 8158.5 8167.2 8406.2 10681.5 10887.3 11413.2 11485.6 11502.8 22:37:12 INFO - PROCESS | 1655 | 22:37:12 INFO - PROCESS | 1655 | [#3] mootools.html Cycles:180 Average:5282.24 Median:5120.46 stddev:2894.02 (56.5%) stddev-sans-first:2886.85 22:37:12 INFO - PROCESS | 1655 | Values: 1324.2 1360.9 1536.0 1699.5 1928.8 7331.3 7656.2 7943.2 8189.3 8199.3 8061.7 8146.1 8152.9 8154.2 8162.0 1992.7 2218.5 2258.0 2281.2 2286.3 2254.9 2302.2 2306.0 2308.8 2310.1 2217.9 2253.3 2279.2 2283.7 2293.5 2222.2 2240.9 2260.7 2261.2 2267.5 2236.9 2262.5 2279.7 2281.6 2286.6 8123.3 8199.0 8203.3 8224.8 8225.2 4961.9 5133.0 5162.7 5163.2 5197.1 3219.2 3475.7 3536.8 3539.4 3548.1 1006.6 1013.5 1034.8 1046.2 1057.5 10463.9 10510.9 10582.7 10595.2 10614.6 4042.5 4248.0 4333.6 4336.1 4350.5 8240.5 8396.5 8412.0 8431.2 8433.1 4991.5 4994.0 4994.6 4995.7 4998.8 3589.7 3956.2 4212.7 4707.0 4714.4 3520.1 3571.0 3586.9 3607.2 3630.9 962.7 1116.8 1120.9 1121.3 1133.8 812.7 885.7 903.5 908.7 941.4 5107.9 5352.4 5595.3 5651.2 5772.7 417.8 437.1 438.5 440.7 443.6 3808.2 3871.5 3898.1 3906.8 3913.3 9384.0 9904.0 9916.9 9927.6 9942.5 9233.2 9493.3 9739.8 9874.8 9894.5 7307.3 7549.4 7636.0 7762.9 7765.3 5682.4 5714.1 5760.5 5763.0 5882.3 9042.8 9097.9 9112.2 9187.6 9320.2 7663.9 9015.1 9169.9 9330.5 9375.2 7001.5 7122.3 7125.3 7132.4 7140.8 7584.8 7629.8 7665.2 7761.2 7779.5 6812.1 6873.3 6983.0 7044.2 7068.5 6658.3 6733.1 6777.7 6953.1 7025.2 6398.9 6582.6 6596.3 6596.9 6601.4 4672.2 4823.0 4828.9 4844.5 4948.5 4689.3 4793.9 4852.9 4886.9 4978.6 22:37:12 INFO - PROCESS | 1655 | 22:37:12 INFO - PROCESS | 1655 | [#4] prototype.html Cycles:180 Average:4353.79 Median:4909.55 stddev:1363.92 (27.8%) stddev-sans-first:1332.45 22:37:12 INFO - PROCESS | 1655 | Values: 246.6 257.9 293.6 306.9 309.6 5174.2 5184.8 5188.4 5200.7 5202.5 3920.3 4502.7 4662.1 5011.9 5129.4 3978.3 3988.8 3991.7 4003.3 4005.8 3483.1 3625.9 3777.5 3993.2 4010.6 3344.5 3712.4 3898.9 3946.9 3971.3 3898.7 3906.2 3940.5 3957.5 3966.5 3362.4 3369.5 3374.9 3380.6 3384.6 4103.0 4979.7 4986.0 4999.5 5003.5 3934.8 4204.2 4223.2 4523.1 4534.0 4745.2 5435.9 5470.9 5490.0 5525.3 3108.5 3748.9 3765.9 3767.3 3768.4 4650.7 4801.3 5193.8 5301.9 5696.5 3300.3 3325.6 3495.9 3758.4 3760.1 3199.0 3808.5 3825.9 3828.4 3838.4 4418.2 4431.8 4461.3 4497.6 4553.7 5083.3 5444.4 5478.4 5492.3 5513.3 1132.8 1248.9 1282.7 1312.2 1313.2 816.0 836.5 838.2 840.2 843.8 4616.3 4971.3 4973.3 4996.7 5063.4 4270.8 5561.5 5607.3 5611.5 5649.0 4695.5 4759.6 5091.2 5123.7 5175.4 2354.0 2650.4 2723.4 2894.8 2936.8 4873.2 4914.4 5776.8 5808.2 5871.6 4674.4 5645.8 5674.0 5729.0 5742.7 4805.4 5029.6 5200.7 5708.7 5763.2 4288.4 4904.7 5155.5 5525.0 5543.4 5443.5 5490.8 5519.7 5522.9 5551.9 5719.6 5726.7 5737.4 5749.3 5749.9 2900.9 3069.2 3205.3 3206.3 3411.6 4857.3 5548.8 5573.4 5620.9 5626.9 5031.7 5409.7 5414.1 5446.9 5448.7 5227.3 5368.7 5448.8 5514.4 5517.9 4449.0 4745.4 5023.7 5311.3 5353.6 4733.7 5333.6 5397.6 5515.1 5529.8 4454.4 5383.3 5395.0 5409.7 5419.3 22:37:12 INFO - PROCESS | 1655 | 22:37:12 INFO - PROCESS | 1655 | [#5] yui.html Cycles:180 Average:19955.71 Median:544.30 stddev:29579.68 (5434.5%) stddev-sans-first:29627.41 22:37:12 INFO - PROCESS | 1655 | Values: 723.2 723.3 744.6 788.2 805.5 14996.8 15304.2 15337.1 15354.8 15534.8 13847.9 13923.5 13954.4 13961.5 13985.2 693.7 717.9 721.1 820.7 852.0 796.5 836.0 883.6 887.2 888.1 868.6 918.7 951.0 954.3 956.3 946.8 947.4 947.8 948.1 948.8 541.7 563.8 671.3 694.9 705.6 14800.4 14891.9 15140.5 15147.8 15155.7 13363.3 13560.3 13675.7 13691.8 13754.6 12039.0 12184.6 12234.8 12464.4 12770.6 4449.6 4476.4 4525.1 4635.7 4640.2 68963.7 87932.0 88100.6 88923.0 89357.0 10791.1 10821.4 10876.6 11043.8 11397.5 21098.1 21199.3 21290.0 21332.5 21634.5 7590.3 7645.0 7755.7 7784.7 7913.1 4280.3 4291.3 4338.5 4365.8 4453.8 3294.7 3403.5 3409.1 3411.5 3412.2 561.3 568.8 580.2 640.2 644.0 546.9 548.3 560.4 603.3 644.5 7368.8 8283.8 8627.8 8733.3 8825.7 519.7 533.8 599.1 624.3 625.6 4089.9 4213.5 4225.3 4299.7 4313.5 107084.0 109014.0 109117.0 113656.0 117239.4 66373.3 74946.6 98235.0 101314.0 102860.0 57526.0 58451.0 87356.8 87804.6 90239.0 7831.7 8220.7 8248.9 8362.9 8536.6 39527.6 42614.6 57392.4 58058.7 60628.5 72318.6 80107.0 80347.0 83959.2 85683.0 10821.7 11200.9 11294.3 11314.8 11935.6 9331.0 11302.7 11705.2 11850.5 11874.2 11272.9 11656.8 11661.4 11665.4 11927.6 9638.3 11173.0 11584.8 11709.5 11930.0 10966.1 11292.4 11351.3 11550.5 11588.5 9653.9 9876.3 9945.4 9999.3 10114.6 7498.1 8290.5 8779.3 9509.2 9565.4 22:37:12 INFO - PROCESS | 1655 | -------- Summary: end -------- 22:37:12 INFO - PROCESS | 1655 | 22:37:13 INFO - TEST-INFO | 1655: exit 0 22:37:13 INFO - TEST-OK | dromaeo_css | took 1118918ms 22:37:13 INFO - TEST-START | kraken 22:37:13 INFO - operating with platform_type : mac_ 22:37:13 INFO - Initialising browser for kraken test... 22:37:13 INFO - TEST-INFO | started process 1665 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/g3/g2lxvbfd1c33ggtssv6lgc3r00000w/T/tmpdjhVHU/profile http://localhost:49275/getInfo.html) 22:37:15 INFO - PROCESS | 1665 | __metrics Screen width/height:1600/1200 22:37:15 INFO - PROCESS | 1665 | colorDepth:24 22:37:15 INFO - PROCESS | 1665 | Browser inner width/height: 1024/689 22:37:15 INFO - PROCESS | 1665 | __metrics 22:37:15 INFO - PROCESS | 1665 | 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" 22:37:15 INFO - PROCESS | 1665 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 205: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 22:37:16 INFO - TEST-INFO | 1665: exit 0 22:37:16 INFO - Browser initialized. 22:37:16 INFO - Running cycle 1/1 for kraken test... 22:37:16 INFO - TEST-INFO | started process 1666 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/g3/g2lxvbfd1c33ggtssv6lgc3r00000w/T/tmpdjhVHU/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 22:37:28 INFO - PROCESS | 1666 | RSS: Main: 230547456 22:37:28 INFO - PROCESS | 1666 | 22:37:31 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49275/tests/kraken/driver.html?testName=audio-beat-detection) 22:37:32 INFO - PROCESS | 1666 | RSS: Main: 291454976 22:37:32 INFO - PROCESS | 1666 | 22:37:34 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49275/tests/kraken/driver.html?testName=audio-dft) 22:37:35 INFO - PROCESS | 1666 | RSS: Main: 292708352 22:37:35 INFO - PROCESS | 1666 | 22:37:39 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49275/tests/kraken/driver.html?testName=audio-fft) 22:37:39 INFO - PROCESS | 1666 | RSS: Main: 383803392 22:37:39 INFO - PROCESS | 1666 | 22:37:41 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49275/tests/kraken/driver.html?testName=audio-oscillator) 22:37:42 INFO - PROCESS | 1666 | RSS: Main: 284180480 22:37:42 INFO - PROCESS | 1666 | 22:37:44 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49275/tests/kraken/driver.html?testName=imaging-gaussian-blur) 22:37:44 INFO - PROCESS | 1666 | RSS: Main: 288292864 22:37:44 INFO - PROCESS | 1666 | 22:37:47 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49275/tests/kraken/driver.html?testName=imaging-darkroom) 22:37:48 INFO - PROCESS | 1666 | RSS: Main: 324919296 22:37:48 INFO - PROCESS | 1666 | 22:37:51 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49275/tests/kraken/driver.html?testName=imaging-desaturate) 22:37:51 INFO - PROCESS | 1666 | RSS: Main: 326676480 22:37:51 INFO - PROCESS | 1666 | 22:37:54 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49275/tests/kraken/driver.html?testName=json-parse-financial) 22:37:55 INFO - PROCESS | 1666 | RSS: Main: 326848512 22:37:55 INFO - PROCESS | 1666 | 22:37:56 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49275/tests/kraken/driver.html?testName=json-stringify-tinderbox) 22:37:57 INFO - PROCESS | 1666 | RSS: Main: 301146112 22:37:57 INFO - PROCESS | 1666 | 22:37:59 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-aes) 22:38:00 INFO - PROCESS | 1666 | RSS: Main: 305086464 22:38:00 INFO - PROCESS | 1666 | 22:38:02 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-ccm) 22:38:03 INFO - PROCESS | 1666 | RSS: Main: 310996992 22:38:03 INFO - PROCESS | 1666 | 22:38:05 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 22:38:06 INFO - PROCESS | 1666 | RSS: Main: 324599808 22:38:06 INFO - PROCESS | 1666 | 22:38:09 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 22:38:10 INFO - PROCESS | 1666 | RSS: Main: 317444096 22:38:10 INFO - PROCESS | 1666 | 22:38:12 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49275/tests/kraken/driver.html?testName=ai-astar) 22:38:12 INFO - PROCESS | 1666 | RSS: Main: 361254912 22:38:12 INFO - PROCESS | 1666 | 22:38:12 INFO - PROCESS | 1666 | __start_tp_report 22:38:12 INFO - PROCESS | 1666 | _x_x_mozilla_page_load 22:38:12 INFO - PROCESS | 1666 | _x_x_mozilla_page_load_details 22:38:12 INFO - PROCESS | 1666 | |i|pagename|runs| 22:38:12 INFO - PROCESS | 1666 | |0;ai-astar;110;114;126;168;146;114;114;114;113;111 22:38:12 INFO - PROCESS | 1666 | |1;audio-beat-detection;150;114;116;144;112;112;111;113;143;113 22:38:12 INFO - PROCESS | 1666 | |2;audio-dft;185;184;184;196;267;184;182;183;187;186 22:38:12 INFO - PROCESS | 1666 | |3;audio-fft;79;59;58;85;64;59;59;60;83;58 22:38:12 INFO - PROCESS | 1666 | |4;audio-oscillator;87;70;92;71;68;69;94;68;67;91 22:38:12 INFO - PROCESS | 1666 | |5;imaging-gaussian-blur;92;88;91;89;91;87;91;89;91;88 22:38:12 INFO - PROCESS | 1666 | |6;imaging-darkroom;100;99;99;104;99;98;98;98;98;98 22:38:12 INFO - PROCESS | 1666 | |7;imaging-desaturate;85;97;122;65;126;128;132;85;83;85 22:38:12 INFO - PROCESS | 1666 | |8;json-parse-financial;59;56;56;54;55;55;56;56;56;55 22:38:12 INFO - PROCESS | 1666 | |9;json-stringify-tinderbox;64;72;74;82;74;74;71;72;69;71 22:38:12 INFO - PROCESS | 1666 | |10;stanford-crypto-aes;91;101;96;108;108;90;85;86;91;93 22:38:12 INFO - PROCESS | 1666 | |11;stanford-crypto-ccm;148;144;146;144;148;153;155;161;180;147 22:38:12 INFO - PROCESS | 1666 | |12;stanford-crypto-pbkdf2;172;172;173;175;172;171;174;179;242;240 22:38:12 INFO - PROCESS | 1666 | |13;stanford-crypto-sha256-iterative;69;70;70;69;66;71;85;86;76;69 22:38:12 INFO - PROCESS | 1666 | __end_tp_report 22:38:12 INFO - PROCESS | 1666 | __start_cc_report 22:38:12 INFO - PROCESS | 1666 | _x_x_mozilla_cycle_collect,2000 22:38:12 INFO - PROCESS | 1666 | __end_cc_report 22:38:12 INFO - PROCESS | 1666 | __startTimestamp1477633092467__endTimestamp 22:38:12 INFO - PROCESS | 1666 | 22:38:12 INFO - PROCESS | 1666 | ------- Summary: start ------- 22:38:12 INFO - PROCESS | 1666 | Number of tests: 14 22:38:12 INFO - PROCESS | 1666 | 22:38:12 INFO - PROCESS | 1666 | [#0] ai-astar Cycles:10 Average:123.00 Median:114.00 stddev:19.15 (16.8%) stddev-sans-first:19.72 22:38:12 INFO - PROCESS | 1666 | Values: 110.0 114.0 126.0 168.0 146.0 114.0 114.0 114.0 113.0 111.0 22:38:12 INFO - PROCESS | 1666 | 22:38:12 INFO - PROCESS | 1666 | [#1] audio-beat-detection Cycles:10 Average:122.80 Median:115.00 stddev:15.94 (13.9%) stddev-sans-first:13.53 22:38:12 INFO - PROCESS | 1666 | Values: 150.0 114.0 116.0 144.0 112.0 112.0 111.0 113.0 143.0 113.0 22:38:12 INFO - PROCESS | 1666 | 22:38:12 INFO - PROCESS | 1666 | [#2] audio-dft Cycles:10 Average:193.80 Median:185.50 stddev:26.02 (14.0%) stddev-sans-first:27.40 22:38:12 INFO - PROCESS | 1666 | Values: 185.0 184.0 184.0 196.0 267.0 184.0 182.0 183.0 187.0 186.0 22:38:12 INFO - PROCESS | 1666 | 22:38:12 INFO - PROCESS | 1666 | [#3] audio-fft Cycles:10 Average:66.40 Median:62.00 stddev:11.22 (18.1%) stddev-sans-first:10.93 22:38:12 INFO - PROCESS | 1666 | Values: 79.0 59.0 58.0 85.0 64.0 59.0 59.0 60.0 83.0 58.0 22:38:12 INFO - PROCESS | 1666 | 22:38:12 INFO - PROCESS | 1666 | [#4] audio-oscillator Cycles:10 Average:77.70 Median:79.00 stddev:11.62 (14.7%) stddev-sans-first:11.83 22:38:12 INFO - PROCESS | 1666 | Values: 87.0 70.0 92.0 71.0 68.0 69.0 94.0 68.0 67.0 91.0 22:38:12 INFO - PROCESS | 1666 | 22:38:12 INFO - PROCESS | 1666 | [#5] imaging-gaussian-blur Cycles:10 Average:89.70 Median:91.00 stddev:1.70 (1.9%) stddev-sans-first:1.59 22:38:12 INFO - PROCESS | 1666 | Values: 92.0 88.0 91.0 89.0 91.0 87.0 91.0 89.0 91.0 88.0 22:38:12 INFO - PROCESS | 1666 | 22:38:12 INFO - PROCESS | 1666 | [#6] imaging-darkroom Cycles:10 Average:99.10 Median:98.00 stddev:1.85 (1.9%) stddev-sans-first:1.94 22:38:12 INFO - PROCESS | 1666 | Values: 100.0 99.0 99.0 104.0 99.0 98.0 98.0 98.0 98.0 98.0 22:38:12 INFO - PROCESS | 1666 | 22:38:12 INFO - PROCESS | 1666 | [#7] imaging-desaturate Cycles:10 Average:100.80 Median:84.00 stddev:23.94 (28.5%) stddev-sans-first:24.70 22:38:12 INFO - PROCESS | 1666 | Values: 85.0 97.0 122.0 65.0 126.0 128.0 132.0 85.0 83.0 85.0 22:38:12 INFO - PROCESS | 1666 | 22:38:12 INFO - PROCESS | 1666 | [#8] json-parse-financial Cycles:10 Average:55.80 Median:56.00 stddev:1.32 (2.4%) stddev-sans-first:0.73 22:38:12 INFO - PROCESS | 1666 | Values: 59.0 56.0 56.0 54.0 55.0 55.0 56.0 56.0 56.0 55.0 22:38:12 INFO - PROCESS | 1666 | 22:38:12 INFO - PROCESS | 1666 | [#9] json-stringify-tinderbox Cycles:10 Average:72.30 Median:73.00 stddev:4.55 (6.2%) stddev-sans-first:3.70 22:38:12 INFO - PROCESS | 1666 | Values: 64.0 72.0 74.0 82.0 74.0 74.0 71.0 72.0 69.0 71.0 22:38:12 INFO - PROCESS | 1666 | 22:38:12 INFO - PROCESS | 1666 | [#10] stanford-crypto-aes Cycles:10 Average:94.90 Median:90.50 stddev:8.28 (9.1%) stddev-sans-first:8.66 22:38:12 INFO - PROCESS | 1666 | Values: 91.0 101.0 96.0 108.0 108.0 90.0 85.0 86.0 91.0 93.0 22:38:12 INFO - PROCESS | 1666 | 22:38:12 INFO - PROCESS | 1666 | [#11] stanford-crypto-ccm Cycles:10 Average:152.60 Median:150.50 stddev:11.02 (7.3%) stddev-sans-first:11.56 22:38:12 INFO - PROCESS | 1666 | Values: 148.0 144.0 146.0 144.0 148.0 153.0 155.0 161.0 180.0 147.0 22:38:12 INFO - PROCESS | 1666 | 22:38:12 INFO - PROCESS | 1666 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:187.00 Median:174.50 stddev:28.55 (16.4%) stddev-sans-first:29.77 22:38:12 INFO - PROCESS | 1666 | Values: 172.0 172.0 173.0 175.0 172.0 171.0 174.0 179.0 242.0 240.0 22:38:12 INFO - PROCESS | 1666 | 22:38:12 INFO - PROCESS | 1666 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:73.10 Median:70.50 stddev:7.00 (9.9%) stddev-sans-first:7.26 22:38:12 INFO - PROCESS | 1666 | Values: 69.0 70.0 70.0 69.0 66.0 71.0 85.0 86.0 76.0 69.0 22:38:12 INFO - PROCESS | 1666 | -------- Summary: end -------- 22:38:12 INFO - PROCESS | 1666 | 22:38:13 INFO - TEST-INFO | 1666: exit 0 22:38:13 INFO - TEST-OK | kraken | took 60059ms 22:38:13 INFO - SUITE-END | took 1178s 22:38:13 INFO - Completed test suite (00:19:39) 22:38:13 INFO - javascript benchmark 22:38:13 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2715.7963152763546, 3304.5043243513487, 3307.4212012897738, 3334.533165356852, 3357.9832100839494, 5453.890922181554, 5545.9445405545985, 5556.138798486231, 5609.943900560993, 5620.466055724704, 5550.16747487877, 5553.194786755928, 5620.213170156171, 5638.069718496443, 5670.291213598301, 4252.213340532004, 4419.3150061740325, 5172.534471897528, 5184.377874655047, 5184.922226166611, 5148.3564554430695, 5216.843494695166, 5230.92153617696, 5232.189010703328, 5234.345706786652, 4545.659075569329, 5035.823746168885, 5044.319016932722, 5176.482351764831, 5199.740012999354, 5037.949620503785, 5090.643654944155, 5162.535371816554, 5183.326167598226, 5195.974020129913, 5043.571296439807, 5077.416097148838, 5083.262926875619, 5083.6441449098575, 5104.438511763703, 5526.309211348582, 5552.222688823568, 5555.2222688823595, 5601.0, 5622.803201887914, 6150.169727086831, 6158.507319414436, 6159.599626024294, 6161.291451483092, 6173.660448675321, 6067.666278354689, 6110.022396416596, 6131.785387511461, 6141.938580614226, 6166.660833654148, 4633.0, 4733.171694953369, 5686.516646085129, 5736.0, 5784.334801497839, 6304.211973503312, 6332.208473940757, 6334.778282760081, 6340.841478963062, 6346.048092786119, 5811.099279611667, 5878.323992740804, 5890.410958904161, 5971.671558064261, 5981.641101533922, 5092.949070509321, 5139.486051394905, 6065.878682426327, 6139.539534534928, 6180.5673602848265, 6019.458248757545, 6060.181875446847, 6069.332373438918, 6069.393060693984, 6084.939150608438, 5914.319853216849, 6144.969275153596, 6147.754089836446, 6149.96925015372, 6157.076438534255, 4772.188727916263, 4809.134355815917, 4819.084373968934, 4819.831305904277, 4825.179719447702, 4280.357946308017, 4375.956240437618, 4382.342648602671, 4388.890277743082, 4390.0, 5567.0, 5568.832935011957, 5572.665640061609, 5573.164025396223, 5587.636803607753, 6009.248843894513, 6079.483243924316, 6098.0, 6100.359462256488, 6106.328303886569, 5559.527440167631, 5593.636413633101, 5599.020171469976, 5599.580031497654, 5600.4399560044485, 5273.973630131824, 5279.9208011879855, 5283.181106928432, 5286.97356513215, 5286.995470860601, 6279.560430769893, 6326.715297811609, 6349.587276826992, 6350.968245158745, 6375.0, 6170.660613666291, 6193.690315484298, 6198.597091189058, 6200.937990620036, 6203.441690247719, 6227.50179985591, 6272.968635156795, 6285.779997700059, 6315.873682526232, 6324.525660575383, 4717.5046620104395, 5944.940550594439, 6026.186464827192, 6062.666553339609, 6087.786927457518, 5515.448455154453, 6036.275646922398, 6107.328193898757, 6167.383261673796, 6190.50475961913, 5760.711964401847, 5842.7662893485485, 5945.524358051259, 5952.404759524013, 5969.492593129633, 5863.706814659165, 5880.470757631833, 5880.8235752927485, 5957.821265362046, 6038.879222415615, 5816.680082595329, 5821.09772985188, 5829.912551311649, 5846.736896839568, 5895.351511333835, 5916.471588113352, 5954.910676339945, 5955.196048533566, 5968.970155149196, 5985.221921150397, 5650.519705825051, 5730.0, 5733.655980641175, 5802.6808525531505, 5825.825225243249], "value": 5549.757609862976, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [488.80936434790414, 494.61172979211284, 524.3157679228607, 533.7998250656004, 557.0085248258101, 30352.0, 40142.19360128794, 41058.58941410585, 41088.0, 42558.0, 42010.57989420105, 42431.15137697244, 42477.362839557354, 42484.72545823622, 42628.14743705124, 9425.952870235657, 9598.712038638852, 9602.567884445216, 9612.471314077722, 9625.807483850318, 9523.904760952406, 9529.904700952971, 9619.711408657751, 9623.615055397775, 9678.032196780301, 9625.663101791439, 9659.71020869375, 9660.468674222915, 9683.12851843334, 9717.271204659644, 9588.808223835518, 9637.0, 9667.081627245436, 9668.806623867484, 9670.56482458291, 9460.0, 9504.097110774464, 9509.809803803955, 9511.239100871913, 9516.571754271074, 39687.60312396868, 39910.800445997585, 39911.0, 39950.20099598021, 40092.198156037004, 3488.7557870949045, 3630.5280313559333, 3816.580176180618, 3849.0176417445296, 4045.37296719009, 29900.401991960258, 31237.53143702846, 31593.578288977053, 31636.0, 31985.840070799728, 3150.417172823035, 3214.180384002088, 3215.710586047267, 3271.2803183299634, 3324.401607710611, 449825.0, 455032.0, 456441.4355856432, 457185.9999999967, 466104.0, 57171.71414142986, 57331.42668573261, 57613.42386576081, 57614.0, 57646.423535764945, 60598.0, 60647.393526065054, 60690.0, 60706.69646651827, 60737.392626074055, 15339.463118790789, 15548.144852033021, 15559.29983150761, 15563.922180389025, 15608.063516189064, 12386.876131238572, 12442.937785311016, 12445.315507646985, 12453.377331133406, 12456.314902680258, 13298.06913516064, 14434.494792682415, 14863.628409289639, 15080.54758357251, 15490.690186196212, 6938.514303998772, 7001.509894307452, 7043.506954513135, 7136.892946605805, 7195.92804071963, 793.5159552672865, 854.4873076154257, 864.9697260595849, 994.5872462927948, 1043.7181960870523, 29169.979050733124, 29319.827206911912, 30123.0, 30253.848730756206, 30316.393672126876, 1054.3568423261804, 1097.6213206443765, 1117.9664610061711, 1148.2995372822572, 1148.3741360958297, 12109.697257568456, 15256.46602368934, 15428.214419592652, 15555.433353496546, 15775.895687302009, 616529.0, 619857.0, 619948.0, 620820.0, 622130.0, 58349.0, 58368.0, 58485.0, 58533.0, 58923.70538147368, 1262.7095767973417, 1313.835770528684, 1366.8291463567252, 1402.7755559110494, 1421.751193541147, 29665.851670741507, 30666.540001899542, 30691.0, 31153.688463115985, 31212.843935781086, 46670.83322916954, 47281.29078063901, 47400.2889956644, 47454.76272618615, 47634.0, 501174.0, 501779.0, 503414.0, 503660.0, 772497.0, 31624.683753162175, 38194.42708359321, 39369.0, 39386.80306598449, 40564.33510146371, 46476.767616161706, 46787.83030424266, 47262.76368618135, 47453.0, 47643.04713905633, 30443.543346849372, 38185.0, 38925.0, 39467.40798888076, 39803.00492487595, 32064.51903221407, 38374.0, 38695.6130438692, 39472.605273948044, 39746.2050758989, 37182.0, 37884.24231515409, 37988.24023519569, 38225.808870955465, 38641.0, 28407.573886391307, 30598.694013059583, 30639.2340191497, 30645.846770766897, 30738.84630576833, 21400.35798926035, 25255.36861578475, 25469.74530254748, 25508.23475295744, 25708.228753137435], "value": 19740.516587698465, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [329.6835038363172, 370.9758865673731, 374.9737518373713, 390.97654140751575, 402.2357520710651, 19932.0, 20268.69596956045, 20790.792092079075, 21212.681809772846, 21413.357599272047, 12132.514699412013, 12306.63080107596, 17183.0, 20503.0, 21228.044737986787, 5150.768215430296, 5412.566994640439, 5417.45825417457, 5419.807642318684, 5433.130699088147, 5712.200291959109, 5987.550933679991, 6061.757529698807, 6089.573729838913, 6099.329073801879, 5740.368559458456, 6023.759049638031, 6093.207882975207, 6102.0, 6568.277489476154, 6506.186726659168, 6681.966590167067, 6683.93316066838, 6690.6320152391845, 6701.798946031578, 5670.0, 5900.822975310747, 5913.083472061838, 5970.164177015221, 5995.730192141321, 11964.102692298113, 12075.154739168262, 13108.410121544459, 13309.733805323936, 13350.732985340239, 2122.3420739570784, 2149.9247526336503, 2186.0709198590534, 2247.3033359658557, 2258.7402448718444, 9776.364536305118, 9867.506624668738, 9893.763279590052, 10160.975341754205, 10386.948065259625, 1743.9738403923825, 1798.4424828303138, 1852.536865783554, 1877.4836919847014, 1887.5564242403025, 111070.3339449909, 118671.0, 119129.8087019136, 119738.0, 119776.40111799385, 9153.405028673114, 9688.515574221401, 9721.562529686182, 9782.75543111428, 11317.71705707364, 12326.0, 12400.379981000913, 12425.62723118308, 12431.06766992461, 12445.813312800316, 7856.489328193764, 7965.721199757981, 8000.479968802126, 8019.318357939524, 8111.229433203836, 7554.206808285161, 7796.883046754303, 8383.413161078666, 8500.532470714046, 8506.064332923374, 3818.84724611018, 3884.902877428087, 3895.2014836958356, 3937.330653788863, 3946.427767973685, 1868.5982513759607, 1921.6541022616063, 1926.248762982438, 1945.6108778244409, 1950.8146726061002, 852.5438890193717, 861.3711990247155, 866.9609867555976, 890.4879694175745, 1072.6406653771073, 19752.90123549373, 20560.785039066956, 20561.794382056287, 20764.733150897948, 21039.474013149793, 894.8434024045766, 927.5547737086237, 987.6493844685177, 1012.6784546167175, 1045.9581616735397, 8587.785305367415, 10437.425941573134, 10958.342499450058, 10963.287386320024, 10969.616063437741, 586099.0, 587865.0, 597020.0, 597735.0, 599225.0, 115447.42276288565, 118115.40942295405, 123364.59764621586, 124441.0, 124498.0, 946.2666433514082, 965.3098034905217, 977.8288799460208, 979.6718099436627, 981.1316984468797, 14079.788803168165, 17751.556211094827, 18159.818401815814, 18380.17289222015, 18381.54046148857, 40959.79520102381, 51418.0, 51789.7410512945, 51886.481135188165, 52325.7383713079, 76359.95572353073, 76442.0, 78531.60734196292, 79333.0, 79911.0, 7126.429885609243, 7423.700852350925, 7662.237685332293, 7689.769306920802, 8221.342292616697, 11090.611828586283, 12774.872251277367, 13064.21614703121, 13096.607101786962, 13147.013973952184, 9275.86086208694, 9976.900230997888, 10929.344239345664, 10974.286671366335, 11736.0611151106, 10961.671149865517, 11224.66326010221, 11317.320960742381, 11594.420278986187, 11700.35648039366, 10081.89918100839, 10681.664791901012, 11098.667039988813, 11259.0, 11881.881181188422, 6293.276273228455, 8052.315553178046, 8158.510489370657, 8167.224113709188, 8406.159384061544, 10681.46592670379, 10887.346759194474, 11413.201075924606, 11485.598004069818, 11502.82745758831], "value": 9116.872015420957, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1324.1988596898875, 1360.9455621775116, 1535.9555502258465, 1699.4646686293815, 1928.7685477742673, 7331.303526165012, 7656.157822639505, 7943.165967573408, 8189.262966333044, 8199.303059239957, 8061.677532898691, 8146.1446548112335, 8152.91847081529, 8154.184581541857, 8162.0, 1992.7409436773198, 2218.4914258304348, 2257.9887100564515, 2281.2129815213807, 2286.2912497125944, 2254.9098036078617, 2302.2157827683595, 2305.9769402306015, 2308.7806658367426, 2310.1106074161485, 2217.944551386212, 2253.346529506441, 2279.1794953816698, 2283.7373702024315, 2293.5183611441616, 2222.2111150541486, 2240.8991595378166, 2260.7287125544876, 2261.2424837679396, 2267.5351552931693, 2236.888155592214, 2262.5361800830956, 2279.7347472152946, 2281.6349384098467, 2286.554121946221, 8123.309518690919, 8199.0, 8203.343732501386, 8224.794380140485, 8225.177482251727, 4961.90076198474, 5132.9743351283005, 5162.6902385856965, 5163.225516172604, 5197.090509160882, 3219.227385427504, 3475.6871881530783, 3536.752427330088, 3539.3629146753574, 3548.1484443733575, 1006.6484950749226, 1013.5236438873718, 1034.7620047389066, 1046.2048842879467, 1057.5346847387125, 10463.947680261626, 10510.894891051144, 10582.682519524427, 10595.205359598061, 10614.628488002883, 4042.514898212233, 4247.957520424756, 4333.566643335705, 4336.111097225062, 4350.456192975878, 8240.546769927712, 8396.496210227406, 8411.957940210259, 8431.241188293083, 8433.072362040171, 4991.50084991498, 4994.02616489781, 4994.625403094782, 4995.725235112031, 4998.800047998039, 3589.7128229741556, 3956.248312820555, 4212.7472351659, 4706.976465117653, 4714.38712967312, 3520.0847779577493, 3570.9642903571153, 3586.8744593939086, 3607.2244467439627, 3630.8910732678064, 962.7400601837464, 1116.7543140509074, 1120.9383483908464, 1121.327203677803, 1133.82992551118, 812.6505602590825, 885.7254251182154, 903.5362712405945, 908.6547112097361, 941.3786900645541, 5107.948920510822, 5352.357717073898, 5595.300587426572, 5651.208830763697, 5772.6536407815665, 417.84539720303684, 437.06904293853887, 438.5242012416564, 440.72234492269666, 443.60297533707154, 3808.200277941608, 3871.5094688545114, 3898.1229223424502, 3906.8046597670004, 3913.25648126855, 9383.967763546005, 9903.960084191056, 9916.900830991597, 9927.602895884229, 9942.502874856229, 9233.215176710055, 9493.294411092806, 9739.756506087262, 9874.753131171634, 9894.505274736235, 7307.3058059484265, 7549.396048316122, 7635.961820190863, 7762.92237077622, 7765.3011228988535, 5682.431756824284, 5714.085746280742, 5760.539156867524, 5762.971185144047, 5882.265607601446, 9042.824432823954, 9097.863532047157, 9112.179903808688, 9187.632494700138, 9320.207782338577, 7663.923360766544, 9015.053419391132, 9169.908300917172, 9330.533473326172, 9375.156235938526, 7001.50989430735, 7122.323379278961, 7125.251848555827, 7132.358087772125, 7140.750073747394, 7584.848303033798, 7629.771106866803, 7665.23347665229, 7761.223877612194, 7779.455438119504, 6812.14848143982, 6873.347032031949, 6982.965085174542, 7044.15470143586, 7068.469864760266, 6658.267590564937, 6733.0573719678305, 6777.661116944035, 6953.130858642669, 7025.227225005143, 6398.93601064002, 6582.572132811352, 6596.340365963365, 6596.934030659632, 6601.438877695449, 4672.159011377985, 4822.987172693774, 4828.903421931612, 4844.467108617979, 4948.505149485023, 4689.343491911067, 4793.9041219176115, 4852.8544143675745, 4886.9022619546695, 4978.576820970114], "value": 4213.608892298886, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [246.5635824590475, 257.92778022153806, 293.6226948371343, 306.88031667649614, 309.5696981196138, 5174.223866420034, 5184.844454666361, 5188.403333616635, 5200.7399630018535, 5202.453742357055, 3920.333543297639, 4502.707324023944, 4662.0675864827, 5011.924821127686, 5129.358830146231, 3978.323684973547, 3988.78061706606, 3991.720579559432, 4003.2593970115477, 4005.799710014502, 3483.0742176571803, 3625.930350546582, 3777.490038844762, 3993.2030586236165, 4010.5989401059805, 3344.5317655528247, 3712.3688972874675, 3898.8830335089992, 3946.861859834906, 3971.344728119857, 3898.7465814721954, 3906.199229158016, 3940.4680368150507, 3957.5448823385245, 3966.464527288837, 3362.36115138123, 3369.5114208439886, 3374.9368948781057, 3380.5943286805496, 3384.627690953993, 4102.958970410287, 4979.7261150636705, 4985.975070124626, 4999.525045120708, 5003.4996500350035, 3934.8032598370255, 4204.2432362174795, 4223.176480586287, 4523.140603285398, 4533.964771353498, 4745.193317136095, 5435.864103397407, 5470.91793623096, 5490.039243132436, 5525.309336332958, 3108.4715598348107, 3748.9250214995545, 3765.8681946131755, 3767.2842159989514, 3768.434734789776, 4650.674557751058, 4801.255805350176, 5193.8054247522905, 5301.920471192935, 5696.515796157331, 3300.290437555937, 3325.6341802401716, 3495.9300813983577, 3758.4174452959833, 3760.1163726524246, 3198.9680103199134, 3808.4858544096746, 3825.9234815304094, 3828.3683192272965, 3838.370716059508, 4418.226810308248, 4431.844885429059, 4461.34930075874, 4497.617702495325, 4553.704009239389, 5083.339165908408, 5444.40111587725, 5478.397376288605, 5492.258545096361, 5513.255710479114, 1132.7960967026015, 1248.9134453025927, 1282.70497785508, 1312.2454588611586, 1313.238321773369, 815.9510429374138, 836.5068352989883, 838.1827717974926, 840.218596705058, 843.7595285343648, 4616.3306320584, 4971.304017437562, 4973.32860063894, 4996.6502344836235, 5063.392392912874, 4270.7864606769535, 5561.527270181999, 5607.327120745536, 5611.494965453129, 5648.9717551412805, 4695.460022097434, 4759.643026773006, 5091.210862316273, 5123.667846359973, 5175.430702622709, 2353.952920941606, 2650.3689944775397, 2723.3872378715027, 2894.8118372305735, 2936.841421555155, 4873.1959226727195, 4914.385701787277, 5776.826695199151, 5808.244928159397, 5871.647701137946, 4674.439067311944, 5645.830625081254, 5674.035413979634, 5728.971355143198, 5742.684152371659, 4805.447373552016, 5029.622778291569, 5200.6879587224885, 5708.659449625634, 5763.204845649857, 4288.442502474658, 4904.705717656952, 5155.53600175986, 5525.0, 5543.362513311021, 5443.510084092451, 5490.792025754327, 5519.668819870821, 5522.9447705524035, 5551.944480555143, 5719.599628026164, 5726.74229659658, 5737.397573254748, 5749.310082790092, 5749.8562535936935, 2900.912972610825, 3069.1866655335907, 3205.3108581655056, 3206.3487304038904, 3411.641777613414, 4857.295692124551, 5548.805791797267, 5573.386927437898, 5620.943790562042, 5626.943730562642, 5031.723255220998, 5409.729513524387, 5414.106672399168, 5446.863828404163, 5448.673079615236, 5227.3465816772905, 5368.677879327097, 5448.757988996541, 5514.393416724077, 5517.917231241454, 4449.0, 4745.430548334222, 5023.748812559431, 5311.256424100552, 5353.598480114085, 4733.692309999839, 5333.599980001438, 5397.649152805055, 5515.069725596009, 5529.834104976857, 4454.354118652842, 5383.327084114486, 5394.973025134849, 5409.729513524387, 5419.34967803866], "value": 3914.718962887625, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [723.2116992478198, 723.2514347650183, 744.6090802328777, 788.2275370137265, 805.4643661964793, 14996.77504837427, 15304.234788260597, 15337.079775213495, 15354.769678454832, 15534.766978495318, 13847.93076034621, 13923.512677056306, 13954.372053257603, 13961.511347102853, 13985.230812305319, 693.7398475571661, 717.921028686844, 721.1238345410326, 820.7209548753423, 851.9946463173453, 796.5141263829059, 835.9801042727893, 883.5582208895552, 887.2103827593447, 888.1251966812684, 868.6481974800184, 918.677104968846, 950.9919485345521, 954.3128947158035, 956.2827879090682, 946.8248374050822, 947.3605316411395, 947.8388673925449, 948.0803620488116, 948.791265921503, 541.6777017674477, 563.7829435667297, 671.3286713286714, 694.892291694783, 705.5555000349747, 14800.40798368064, 14891.925540372336, 15140.545783626509, 15147.848521514754, 15155.696886062327, 13363.265020423873, 13560.321983900863, 13675.658108547266, 13691.794623080661, 13754.587362379145, 12039.0, 12184.573539926148, 12234.755304893852, 12464.376781160996, 12770.61688149357, 4449.644028477746, 4476.418065651477, 4525.140223357552, 4635.7218566886095, 4640.211164102111, 68963.65518172427, 87932.0, 88100.55949720275, 88922.99999999936, 89357.0, 10791.136709063256, 10821.350718956888, 10876.564937402574, 11043.825416049907, 11397.487113079931, 21098.05058772359, 21199.25802596902, 21290.04194811237, 21332.466688332916, 21634.45913852135, 7590.278923502258, 7645.0, 7755.689772409153, 7784.727534536264, 7913.129555748863, 4280.315149576053, 4291.334843099325, 4338.457692788402, 4365.80353884076, 4453.821847126079, 3294.6870047345465, 3403.4724617684296, 3409.113630456099, 3411.5394421752744, 3412.2151905061974, 561.2619405481778, 568.7696482924422, 580.1674596953385, 640.193356370976, 643.9903401448983, 546.9206964990054, 548.314606741573, 560.44235985195, 603.2730559675581, 644.452215616722, 7368.815779605553, 8283.751487455385, 8627.784305392415, 8733.30133589324, 8825.735227943173, 519.6985748265996, 533.8335736415944, 599.101347978033, 624.307019208678, 625.6496362037274, 4089.9386509202386, 4213.5154457237195, 4225.345071513921, 4299.656027517791, 4313.525512193656, 107084.0, 109014.0, 109117.0, 113656.0, 117239.41380293044, 66373.33626663768, 74946.62526687332, 98235.0, 101314.0, 102860.0, 57526.0, 58451.0, 87356.83898478802, 87804.56097719599, 90239.0, 7831.729026941444, 8220.712275070344, 8248.876266856121, 8362.916370836214, 8536.615852286537, 39527.60472395239, 42614.57385426106, 57392.42607573871, 58058.7097064512, 60628.48428789315, 72318.63840680763, 80107.0, 80347.0, 83959.16040839514, 85683.0, 10821.67534973952, 11200.943995279971, 11294.322340659586, 11314.83027754568, 11935.641930742091, 9330.95334523323, 11302.717432064264, 11705.180637355303, 11850.466728997388, 11874.168808183344, 11272.887271127183, 11656.76686466249, 11661.416929153678, 11665.416729163677, 11927.582534611247, 9638.325317228007, 11173.0, 11584.768304634028, 11709.473073711533, 11930.0, 10966.06788422993, 11292.43537823122, 11351.262167959056, 11550.537978480766, 11588.536458541901, 9653.855192172263, 9876.308658394131, 9945.403275803475, 9999.3000489965, 10114.59541618327, 7498.137714162941, 8290.46112002718, 8779.25376343018, 9509.239260859253, 9565.378250413702], "value": 7108.931941992783, "unit": "score"}], "name": "dromaeo_css", "value": 6994.897172620026, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [110.0, 114.0, 126.0, 168.0, 146.0, 114.0, 114.0, 114.0, 113.0, 111.0], "value": 123.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 114.0, 116.0, 144.0, 112.0, 112.0, 111.0, 113.0, 143.0, 113.0], "value": 122.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [185.0, 184.0, 184.0, 196.0, 267.0, 184.0, 182.0, 183.0, 187.0, 186.0], "value": 193.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 59.0, 58.0, 85.0, 64.0, 59.0, 59.0, 60.0, 83.0, 58.0], "value": 66.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 70.0, 92.0, 71.0, 68.0, 69.0, 94.0, 68.0, 67.0, 91.0], "value": 77.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 88.0, 91.0, 89.0, 91.0, 87.0, 91.0, 89.0, 91.0, 88.0], "value": 89.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [100.0, 99.0, 99.0, 104.0, 99.0, 98.0, 98.0, 98.0, 98.0, 98.0], "value": 99.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 97.0, 122.0, 65.0, 126.0, 128.0, 132.0, 85.0, 83.0, 85.0], "value": 100.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 56.0, 56.0, 54.0, 55.0, 55.0, 56.0, 56.0, 56.0, 55.0], "value": 55.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 72.0, 74.0, 82.0, 74.0, 74.0, 71.0, 72.0, 69.0, 71.0], "value": 72.3, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 101.0, 96.0, 108.0, 108.0, 90.0, 85.0, 86.0, 91.0, 93.0], "value": 94.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 144.0, 146.0, 144.0, 148.0, 153.0, 155.0, 161.0, 180.0, 147.0], "value": 152.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [172.0, 172.0, 173.0, 175.0, 172.0, 171.0, 174.0, 179.0, 242.0, 240.0], "value": 187.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 70.0, 70.0, 69.0, 66.0, 71.0, 85.0, 86.0, 76.0, 69.0], "value": 73.1, "unit": "score"}], "name": "kraken", "value": 1508.9999999999998, "alertThreshold": 2.0}]} 22:38:13 INFO - Return code: 0 22:38:13 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 22:38:13 INFO - # TBPL SUCCESS # 22:38:13 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 22:38:13 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 22:38:13 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 22:38:13 INFO - 2016-10-27 22:38:13.813 screenresolution[1670:10029] starting screenresolution argv=screenresolution get 22:38:13 INFO - 2016-10-27 22:38:13.816 screenresolution[1670:10029] Display 0: 1600x1200x32@60 22:38:13 INFO - 2016-10-27 22:38:13.822 screenresolution[1671:10032] starting screenresolution argv=screenresolution list 22:38:13 INFO - Available Modes on Display 0 22:38:13 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 22:38:13 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 22:38:13 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 22:38:13 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 22:38:14 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 22:38:14 INFO - Intel Iris: 22:38:14 INFO - Chipset Model: Intel Iris 22:38:14 INFO - Type: GPU 22:38:14 INFO - Bus: Built-In 22:38:14 INFO - VRAM (Dynamic, Max): 1536 MB 22:38:14 INFO - Vendor: Intel (0x8086) 22:38:14 INFO - Device ID: 0x0a2e 22:38:14 INFO - Revision ID: 0x0009 22:38:14 INFO - Displays: 22:38:14 INFO - Display: 22:38:14 INFO - Resolution: 1600 x 1200 @ 60 Hz 22:38:14 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 22:38:14 INFO - Display Serial Number: 1600x1200 60 22:38:14 INFO - Main Display: Yes 22:38:14 INFO - Mirror: Off 22:38:14 INFO - Online: Yes 22:38:14 INFO - Rotation: Supported 22:38:14 INFO - Return code: 0 22:38:14 INFO - Running post-action listener: _resource_record_post_action 22:38:14 INFO - [mozharness: 2016-10-28 05:38:14.057503Z] Finished run-tests step (success) 22:38:14 INFO - Running post-run listener: _resource_record_post_run 22:38:14 INFO - Total resource usage - Wall time: 1215s; CPU: 54.0%; Read bytes: 42218496; Write bytes: 707137024; Read time: 561; Write time: 2103 22:38:14 INFO - TinderboxPrint: CPU usage
53.5% 22:38:14 INFO - TinderboxPrint: I/O read bytes / time
42,218,496 / 561 22:38:14 INFO - TinderboxPrint: I/O write bytes / time
707,137,024 / 2,103 22:38:14 INFO - TinderboxPrint: CPU idle
2,259.1 (46.5%) 22:38:14 INFO - TinderboxPrint: CPU system
977.1 (20.1%) 22:38:14 INFO - TinderboxPrint: CPU user
1,625.5 (33.4%) 22:38:14 INFO - TinderboxPrint: Swap in / out
446,930,944 / 0 22:38:14 INFO - install - Wall time: 36s; CPU: 55.0%; Read bytes: 398090752; Write bytes: 391300608; Read time: 30628; Write time: 703 22:38:14 INFO - run-tests - Wall time: 1180s; CPU: 54.0%; Read bytes: 40289280; Write bytes: 299579392; Read time: 541; Write time: 1360 22:38:14 INFO - Running post-run listener: _upload_blobber_files 22:38:14 INFO - Blob upload gear active. 22:38:14 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 22:38:14 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 22:38:14 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', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 22:38:14 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 Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 22:38:15 INFO - (blobuploader) - INFO - Open directory for files ... 22:38:15 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 22:38:15 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:38:15 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:38:16 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 22:38:16 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:38:16 INFO - (blobuploader) - INFO - Done attempting. 22:38:16 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 22:38:16 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:38:16 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:38:17 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 22:38:17 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:38:17 INFO - (blobuploader) - INFO - Done attempting. 22:38:17 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 22:38:17 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:38:17 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:38:19 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 22:38:19 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:38:19 INFO - (blobuploader) - INFO - Done attempting. 22:38:19 INFO - (blobuploader) - INFO - Iteration through files over. 22:38:19 INFO - Return code: 0 22:38:19 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 22:38:19 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 22:38:19 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/942084df3993a8adab03772562d1ab5bc3d33acaaea9b8726c685d7ec4fbeb5cda07999491ee5567f0d4199afbf1272ee1e6bf902c6989e871d2b0fa31a46704", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8ae6012207be4183ff038eda1481cf7a325e631e0bb5ce528bbfefb5d45fa2d4733727f4f9d2e26d13049aa8add1f384dec57d3002f212cb8d03c35a076960ae", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/66c16b7e6a8e4c26fa764b7049dae9717ded42bdefcbb11b168e840e7f161ae7a4ebdd6ca9056b7d285ad6ef1704f05b0a9d24b7aa8217087a6443f7d1646c90"} 22:38:19 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 22:38:19 INFO - Writing to file /builds/slave/test/properties/blobber_files 22:38:19 INFO - Contents: 22:38:19 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/942084df3993a8adab03772562d1ab5bc3d33acaaea9b8726c685d7ec4fbeb5cda07999491ee5567f0d4199afbf1272ee1e6bf902c6989e871d2b0fa31a46704", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8ae6012207be4183ff038eda1481cf7a325e631e0bb5ce528bbfefb5d45fa2d4733727f4f9d2e26d13049aa8add1f384dec57d3002f212cb8d03c35a076960ae", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/66c16b7e6a8e4c26fa764b7049dae9717ded42bdefcbb11b168e840e7f161ae7a4ebdd6ca9056b7d285ad6ef1704f05b0a9d24b7aa8217087a6443f7d1646c90"} 22:38:19 INFO - Running post-run listener: copy_logs_to_upload_dir 22:38:19 INFO - Copying logs to upload dir... 22:38:19 INFO - mkdir: /builds/slave/test/build/upload/logs 22:38:19 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1276.142242 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 16 secs) (at 2016-10-27 22:38:19.764479) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-27 22:38:19.767749) ========= 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.8m8ug64qmu/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.q9UhaPupy5/Listeners TMPDIR=/var/folders/g3/g2lxvbfd1c33ggtssv6lgc3r00000w/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/Firefox/sha512/942084df3993a8adab03772562d1ab5bc3d33acaaea9b8726c685d7ec4fbeb5cda07999491ee5567f0d4199afbf1272ee1e6bf902c6989e871d2b0fa31a46704", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8ae6012207be4183ff038eda1481cf7a325e631e0bb5ce528bbfefb5d45fa2d4733727f4f9d2e26d13049aa8add1f384dec57d3002f212cb8d03c35a076960ae", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/66c16b7e6a8e4c26fa764b7049dae9717ded42bdefcbb11b168e840e7f161ae7a4ebdd6ca9056b7d285ad6ef1704f05b0a9d24b7aa8217087a6443f7d1646c90"} build_url:https://queue.taskcluster.net/v1/task/bfBCfpzpTQK2DiO08yHbvQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011074 build_url: 'https://queue.taskcluster.net/v1/task/bfBCfpzpTQK2DiO08yHbvQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/942084df3993a8adab03772562d1ab5bc3d33acaaea9b8726c685d7ec4fbeb5cda07999491ee5567f0d4199afbf1272ee1e6bf902c6989e871d2b0fa31a46704", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8ae6012207be4183ff038eda1481cf7a325e631e0bb5ce528bbfefb5d45fa2d4733727f4f9d2e26d13049aa8add1f384dec57d3002f212cb8d03c35a076960ae", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/66c16b7e6a8e4c26fa764b7049dae9717ded42bdefcbb11b168e840e7f161ae7a4ebdd6ca9056b7d285ad6ef1704f05b0a9d24b7aa8217087a6443f7d1646c90"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-27 22:38:19.800778) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-27 22:38:19.801107) ========= 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.8m8ug64qmu/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.q9UhaPupy5/Listeners TMPDIR=/var/folders/g3/g2lxvbfd1c33ggtssv6lgc3r00000w/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.005167 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-27 22:38:19.850937) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-27 22:38:19.851416) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-27 22:38:19.851915) ========= ========= Total master_lag: 0.17 =========