builder: mozilla-central_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0111 starttime: 1476318344.34 results: success (0) buildid: 20161012143919 builduid: 7ff115bc4bed46ab8dff7d359e876ff2 revision: 22be4ae74653b25186665f22e52a50e7027fd36b ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-10-12 17:25:44.343618) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-10-12 17:25:44.344086) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-12 17:25:44.344395) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-12 17:25:44.369294) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-12 17:25:44.369686) ========= 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.3RXa1LxaDs/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.h347kkx8t7/Listeners TMPDIR=/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/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-12 17:25:44-- 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% 12.8M=0.001s 2016-10-12 17:25:44 (12.8 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.129816 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-12 17:25:44.523870) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-12 17:25:44.524477) ========= 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.3RXa1LxaDs/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.h347kkx8t7/Listeners TMPDIR=/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/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.028606 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-12 17:25:44.574227) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-10-12 17:25:44.574695) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 22be4ae74653b25186665f22e52a50e7027fd36b --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 22be4ae74653b25186665f22e52a50e7027fd36b --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.3RXa1LxaDs/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.h347kkx8t7/Listeners TMPDIR=/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/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-12 17:25:44,648 truncating revision to first 12 chars 2016-10-12 17:25:44,648 Setting DEBUG logging. 2016-10-12 17:25:44,648 attempt 1/10 2016-10-12 17:25:44,648 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/22be4ae74653?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-10-12 17:25:45,703 unpacking tar archive at: mozilla-central-22be4ae74653/testing/mozharness/ program finished with exit code 0 elapsedTime=1.334262 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-10-12 17:25:45.930339) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-12 17:25:45.930657) ========= script_repo_revision: 22be4ae74653b25186665f22e52a50e7027fd36b ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-12 17:25:45.931035) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-12 17:25:45.931314) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-12 17:25:45.949304) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 15 secs) (at 2016-10-12 17:25:45.949677) ========= /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.3RXa1LxaDs/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.h347kkx8t7/Listeners TMPDIR=/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/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 17:25:46 INFO - MultiFileLogger online at 20161012 17:25:46 in /builds/slave/test 17:25:46 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 17:25:46 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 17:25:46 INFO - {'append_to_log': False, 17:25:46 INFO - 'base_work_dir': '/builds/slave/test', 17:25:46 INFO - 'blob_upload_branch': 'Firefox', 17:25:46 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 17:25:46 INFO - 'branch': 'Firefox', 17:25:46 INFO - 'buildbot_json_path': 'buildprops.json', 17:25:46 INFO - 'config_files': ('talos/mac_config.py',), 17:25:46 INFO - 'default_actions': ('clobber', 17:25:46 INFO - 'read-buildbot-config', 17:25:46 INFO - 'download-and-extract', 17:25:46 INFO - 'populate-webroot', 17:25:46 INFO - 'create-virtualenv', 17:25:46 INFO - 'install', 17:25:46 INFO - 'run-tests'), 17:25:46 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 17:25:46 INFO - 'download_minidump_stackwalk': True, 17:25:46 INFO - 'download_symbols': 'ondemand', 17:25:46 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 17:25:46 INFO - 'tooltool.py': '/tools/tooltool.py', 17:25:46 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 17:25:46 INFO - '/tools/misc-python/virtualenv.py')}, 17:25:46 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 17:25:46 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 17:25:46 INFO - 'installer_path': 'installer.exe', 17:25:46 INFO - 'log_level': 'info', 17:25:46 INFO - 'log_name': 'talos', 17:25:46 INFO - 'log_to_console': True, 17:25:46 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 17:25:46 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 17:25:46 INFO - 'opt_config_files': (), 17:25:46 INFO - 'pip_index': False, 17:25:46 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 17:25:46 INFO - 'cmd': ('bash', 17:25:46 INFO - '-c', 17:25:46 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 17:25:46 INFO - 'enabled': True, 17:25:46 INFO - 'halt_on_failure': False, 17:25:46 INFO - 'name': 'check_screen_resolution'},), 17:25:46 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 17:25:46 INFO - 'cmd': ('bash', 17:25:46 INFO - '-c', 17:25:46 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 17:25:46 INFO - 'enabled': True, 17:25:46 INFO - 'halt_on_failure': False, 17:25:46 INFO - 'name': 'check_screen_resolution'},), 17:25:46 INFO - 'run_cmd_checks_enabled': True, 17:25:46 INFO - 'sps_profile': False, 17:25:46 INFO - 'sps_profile_interval': 0, 17:25:46 INFO - 'suite': 'dromaeojs', 17:25:46 INFO - 'system_bits': '32', 17:25:46 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 17:25:46 INFO - 'title': 't-yosemite-r7-0111', 17:25:46 INFO - 'tooltool_cache': '/builds/tooltool_cache', 17:25:46 INFO - 'use_talos_json': True, 17:25:46 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 17:25:46 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 17:25:46 INFO - 'work_dir': 'build'} 17:25:46 INFO - [mozharness: 2016-10-13 00:25:46.152626Z] Running clobber step. 17:25:46 INFO - Running pre-action listener: _resource_record_pre_action 17:25:46 INFO - Running main action method: clobber 17:25:46 INFO - rmtree: /builds/slave/test/build 17:25:46 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 17:25:48 INFO - Running post-action listener: _resource_record_post_action 17:25:48 INFO - [mozharness: 2016-10-13 00:25:48.571972Z] Finished clobber step (success) 17:25:48 INFO - [mozharness: 2016-10-13 00:25:48.572098Z] Running read-buildbot-config step. 17:25:48 INFO - Running pre-action listener: _resource_record_pre_action 17:25:48 INFO - Running main action method: read_buildbot_config 17:25:48 INFO - Using buildbot properties: 17:25:48 INFO - { 17:25:48 INFO - "project": "", 17:25:48 INFO - "product": "firefox", 17:25:48 INFO - "script_repo_revision": "production", 17:25:48 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs", 17:25:48 INFO - "repository": "", 17:25:48 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs", 17:25:48 INFO - "buildid": "20161012143919", 17:25:48 INFO - "stage_platform": "macosx64", 17:25:48 INFO - "basedir": "/builds/slave/test", 17:25:48 INFO - "nightly_build": "True", 17:25:48 INFO - "buildnumber": 44, 17:25:48 INFO - "slavename": "t-yosemite-r7-0111", 17:25:48 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 17:25:48 INFO - "platform": "yosemite_r7", 17:25:48 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 17:25:48 INFO - "branch": "mozilla-central", 17:25:48 INFO - "repo_path": "mozilla-central", 17:25:48 INFO - "revision": "22be4ae74653b25186665f22e52a50e7027fd36b", 17:25:48 INFO - "pgo_build": "False", 17:25:48 INFO - "builduid": "7ff115bc4bed46ab8dff7d359e876ff2", 17:25:48 INFO - "slavebuilddir": "test" 17:25:48 INFO - } 17:25:48 INFO - Found installer url https://queue.taskcluster.net/v1/task/QJzK0NKsRYmn9yJfNq0ccA/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg. 17:25:48 INFO - Running post-action listener: _resource_record_post_action 17:25:48 INFO - [mozharness: 2016-10-13 00:25:48.575836Z] Finished read-buildbot-config step (success) 17:25:48 INFO - [mozharness: 2016-10-13 00:25:48.575966Z] Running download-and-extract step. 17:25:48 INFO - Running pre-action listener: _resource_record_pre_action 17:25:48 INFO - Running main action method: download_and_extract 17:25:48 INFO - mkdir: /builds/slave/test/build/tests 17:25:48 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')]} 17:25:48 INFO - trying https://queue.taskcluster.net/v1/task/QJzK0NKsRYmn9yJfNq0ccA/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json 17:25:48 INFO - Downloading https://queue.taskcluster.net/v1/task/QJzK0NKsRYmn9yJfNq0ccA/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 17:25:48 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/QJzK0NKsRYmn9yJfNq0ccA/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 17:25:49 INFO - Downloaded 1199 bytes. 17:25:49 INFO - Reading from file /builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json 17:25:49 INFO - Using the following test package requirements: 17:25:49 INFO - {u'common': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 17:25:49 INFO - u'cppunittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 17:25:49 INFO - u'firefox-52.0a1.en-US.mac.cppunittest.tests.zip'], 17:25:49 INFO - u'gtest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 17:25:49 INFO - u'firefox-52.0a1.en-US.mac.gtest.tests.zip'], 17:25:49 INFO - u'jittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 17:25:49 INFO - u'jsshell-mac.zip'], 17:25:49 INFO - u'mochitest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 17:25:49 INFO - u'firefox-52.0a1.en-US.mac.mochitest.tests.zip'], 17:25:49 INFO - u'mozbase': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 17:25:49 INFO - u'reftest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 17:25:49 INFO - u'firefox-52.0a1.en-US.mac.reftest.tests.zip'], 17:25:49 INFO - u'talos': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 17:25:49 INFO - u'firefox-52.0a1.en-US.mac.talos.tests.zip'], 17:25:49 INFO - u'web-platform': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 17:25:49 INFO - u'firefox-52.0a1.en-US.mac.web-platform.tests.zip'], 17:25:49 INFO - u'xpcshell': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 17:25:49 INFO - u'firefox-52.0a1.en-US.mac.xpcshell.tests.zip']} 17:25:49 INFO - Downloading packages: [u'firefox-52.0a1.en-US.mac.common.tests.zip'] for test suite category: common 17:25:49 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/QJzK0NKsRYmn9yJfNq0ccA/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 17:25:49 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/QJzK0NKsRYmn9yJfNq0ccA/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 17:25:49 INFO - Fetch https://queue.taskcluster.net/v1/task/QJzK0NKsRYmn9yJfNq0ccA/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 17:25:50 INFO - Http code: 200 17:25:50 INFO - via: 1.1 b3bccc0e4c2cc0a33e7e8308d88cacdd.cloudfront.net (CloudFront) 17:25:50 INFO - x-amz-cf-id: B7BjoOVjp584K9qnYuyEHo32O5IC9oZ8qsJC6cKXSEmx-21agPfCBw== 17:25:50 INFO - x-amz-version-id: 8ysWt9V5aUVVzawC8xxooVXhdWTFrvtL 17:25:50 INFO - Expected file size: 16511213 17:25:50 INFO - Obtained file size: 16511213 17:25:57 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 17:25:57 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/QJzK0NKsRYmn9yJfNq0ccA/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 17:25:57 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/QJzK0NKsRYmn9yJfNq0ccA/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 17:25:57 INFO - Fetch https://queue.taskcluster.net/v1/task/QJzK0NKsRYmn9yJfNq0ccA/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 17:25:57 INFO - Http code: 200 17:25:57 INFO - via: 1.1 ff555a0cc6346f82fe74596f8ed6cc33.cloudfront.net (CloudFront) 17:25:57 INFO - x-amz-cf-id: 0OnFgJHF-y41eTw-xvhbvbELpEHRKYM-buDiJPTn9QgsGZvlZSRQEw== 17:25:57 INFO - x-amz-version-id: 8ysWt9V5aUVVzawC8xxooVXhdWTFrvtL 17:25:58 INFO - Expected file size: 16511213 17:25:58 INFO - Obtained file size: 16511213 17:26:04 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/QJzK0NKsRYmn9yJfNq0ccA/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip 17:26:04 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/QJzK0NKsRYmn9yJfNq0ccA/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip'}, attempt #1 17:26:04 INFO - Fetch https://queue.taskcluster.net/v1/task/QJzK0NKsRYmn9yJfNq0ccA/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip into memory 17:26:05 INFO - Http code: 200 17:26:05 INFO - via: 1.1 0cf1d7257c633ae75623c5e75bf3805e.cloudfront.net (CloudFront) 17:26:05 INFO - x-amz-cf-id: unBiAj1JA6f84enQtOW8kkZ2LWKlwxRxX5gOVNh4zFYTiENf-B4J3w== 17:26:05 INFO - x-amz-version-id: .h09bsk7PdoC71g60.1gEt2vZe0B8yV7 17:26:05 INFO - Expected file size: 14409006 17:26:05 INFO - Obtained file size: 14409006 17:26:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:26:06 INFO - trying https://queue.taskcluster.net/v1/task/QJzK0NKsRYmn9yJfNq0ccA/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 17:26:06 INFO - Downloading https://queue.taskcluster.net/v1/task/QJzK0NKsRYmn9yJfNq0ccA/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg 17:26:06 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/QJzK0NKsRYmn9yJfNq0ccA/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 17:26:09 INFO - Downloaded 111832073 bytes. 17:26:09 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/QJzK0NKsRYmn9yJfNq0ccA/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 17:26:09 INFO - mkdir: /builds/slave/test/properties 17:26:09 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 17:26:09 INFO - Writing to file /builds/slave/test/properties/build_url 17:26:09 INFO - Contents: 17:26:09 INFO - build_url:https://queue.taskcluster.net/v1/task/QJzK0NKsRYmn9yJfNq0ccA/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 17:26:09 INFO - Running post-action listener: _resource_record_post_action 17:26:09 INFO - Running post-action listener: set_extra_try_arguments 17:26:09 INFO - [mozharness: 2016-10-13 00:26:09.710543Z] Finished download-and-extract step (success) 17:26:09 INFO - [mozharness: 2016-10-13 00:26:09.710696Z] Running populate-webroot step. 17:26:09 INFO - Running pre-action listener: _resource_record_pre_action 17:26:09 INFO - Running main action method: populate_webroot 17:26:09 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 17:26:09 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 17:26:09 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 17:26:09 INFO - '--tppagecycles', 17:26:09 INFO - '7'], 17:26:09 INFO - 'tests': ['tsvgm']}}, 17:26:09 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 17:26:09 INFO - 'tests': ['tresize', 'tcanvasmark']}, 17:26:09 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 17:26:09 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 17:26:09 INFO - 'tests': ['dromaeo_css', 'kraken']}, 17:26:09 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 17:26:09 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:26:09 INFO - 'talos_options': ['--disable-e10s'], 17:26:09 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 17:26:09 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:26:09 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 17:26:09 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:26:09 INFO - 'talos_options': ['--disable-e10s'], 17:26:09 INFO - 'tests': ['damp', 'tps']}, 17:26:09 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:26:09 INFO - 'tests': ['damp', 'tps']}, 17:26:09 INFO - 'g3': {'talos_options': ['--disable-e10s'], 17:26:09 INFO - 'tests': ['dromaeo_dom']}, 17:26:09 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 17:26:09 INFO - 'g4': {'talos_options': ['--disable-e10s'], 17:26:09 INFO - 'tests': ['basic_compositor_video']}, 17:26:09 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 17:26:09 INFO - 'other': {'talos_options': ['--disable-e10s'], 17:26:09 INFO - 'tests': ['a11yr', 17:26:09 INFO - 'ts_paint', 17:26:09 INFO - 'tpaint', 17:26:09 INFO - 'sessionrestore', 17:26:09 INFO - 'sessionrestore_no_auto_restore', 17:26:09 INFO - 'tabpaint']}, 17:26:09 INFO - 'other-e10s': {'tests': ['a11yr', 17:26:09 INFO - 'ts_paint', 17:26:09 INFO - 'tpaint', 17:26:09 INFO - 'sessionrestore', 17:26:09 INFO - 'sessionrestore_no_auto_restore', 17:26:09 INFO - 'tabpaint']}, 17:26:09 INFO - 'other-e10s_l64': {'tests': ['a11yr', 17:26:09 INFO - 'ts_paint', 17:26:09 INFO - 'tpaint', 17:26:09 INFO - 'sessionrestore', 17:26:09 INFO - 'sessionrestore_no_auto_restore', 17:26:09 INFO - 'tabpaint']}, 17:26:09 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 17:26:09 INFO - 'ts_paint', 17:26:09 INFO - 'tpaint', 17:26:09 INFO - 'sessionrestore', 17:26:09 INFO - 'sessionrestore_no_auto_restore', 17:26:09 INFO - 'tabpaint']}, 17:26:09 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 17:26:09 INFO - 'tests': ['a11yr', 17:26:09 INFO - 'ts_paint', 17:26:09 INFO - 'tpaint', 17:26:09 INFO - 'sessionrestore', 17:26:09 INFO - 'sessionrestore_no_auto_restore', 17:26:09 INFO - 'tabpaint']}, 17:26:09 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 17:26:09 INFO - 'tests': ['a11yr', 17:26:09 INFO - 'ts_paint', 17:26:09 INFO - 'tpaint', 17:26:09 INFO - 'sessionrestore', 17:26:09 INFO - 'sessionrestore_no_auto_restore', 17:26:09 INFO - 'tabpaint']}, 17:26:09 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 17:26:09 INFO - 'tests': ['tsvgx', 17:26:09 INFO - 'tsvgr_opacity', 17:26:09 INFO - 'tart', 17:26:09 INFO - 'tscrollx', 17:26:09 INFO - 'cart']}, 17:26:09 INFO - 'svgr-e10s': {'tests': ['tsvgx', 17:26:09 INFO - 'tsvgr_opacity', 17:26:09 INFO - 'tart', 17:26:09 INFO - 'tscrollx', 17:26:09 INFO - 'cart']}, 17:26:09 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:26:09 INFO - 'talos_options': ['--disable-e10s'], 17:26:09 INFO - 'tests': ['tp5o']}, 17:26:09 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:26:09 INFO - 'tests': ['tp5o']}, 17:26:09 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:26:09 INFO - 'talos_options': ['--disable-e10s', 17:26:09 INFO - '--xperf_path', 17:26:09 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 17:26:09 INFO - 'tests': ['tp5n']}, 17:26:09 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:26:09 INFO - 'talos_options': ['--xperf_path', 17:26:09 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 17:26:09 INFO - 'tests': ['tp5n']}}, 17:26:09 INFO - 'talos.zip': {'path': '', 17:26:09 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 17:26:09 INFO - Running post-action listener: _resource_record_post_action 17:26:09 INFO - [mozharness: 2016-10-13 00:26:09.729398Z] Finished populate-webroot step (success) 17:26:09 INFO - [mozharness: 2016-10-13 00:26:09.729529Z] Running create-virtualenv step. 17:26:09 INFO - Running pre-action listener: _resource_record_pre_action 17:26:09 INFO - Running main action method: create_virtualenv 17:26:09 INFO - Creating virtualenv /builds/slave/test/build/venv 17:26:09 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 17:26:09 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 17:26:09 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 17:26:09 INFO - Using real prefix '/tools/python27' 17:26:09 INFO - New python executable in /builds/slave/test/build/venv/bin/python 17:26:10 INFO - Installing distribute.............................................................................................................................................................................................done. 17:26:13 INFO - Installing pip.................done. 17:26:13 INFO - Return code: 0 17:26:13 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 17:26:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 17:26:13 INFO - Reading from file tmpfile_stdout 17:26:13 INFO - Output received: 17:26:13 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) 17:26:13 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 17:26:13 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')]} 17:26:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:26:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:26:13 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')]} 17:26:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:26:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:26:13 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 0x10a717490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a68d570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff079dbe310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a52bd30>, '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 0x10a351db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a523b20>, '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.3RXa1LxaDs/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.h347kkx8t7/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/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 17:26:13 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 17:26:13 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 17:26:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.3RXa1LxaDs/Render', 17:26:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:26:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:26:13 INFO - 'HOME': '/Users/cltbld', 17:26:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:26:13 INFO - 'LOGNAME': 'cltbld', 17:26:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:26:13 INFO - 'MOZ_NO_REMOTE': '1', 17:26:13 INFO - 'NO_EM_RESTART': '1', 17:26:13 INFO - 'PAGER': '/bin/cat', 17:26:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:26:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:26:13 INFO - 'PWD': '/builds/slave/test', 17:26:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:26:13 INFO - 'SHELL': '/bin/bash', 17:26:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.h347kkx8t7/Listeners', 17:26:13 INFO - 'TMPDIR': '/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/', 17:26:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:26:13 INFO - 'USER': 'cltbld', 17:26:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:26:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:26:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:26:13 INFO - 'XPC_FLAGS': '0x0', 17:26:13 INFO - 'XPC_SERVICE_NAME': '0', 17:26:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:26:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:26:13 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 17:26:13 INFO - Cleaning up... 17:26:13 INFO - Return code: 0 17:26:13 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 17:26:13 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')]} 17:26:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:26:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:26:13 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')]} 17:26:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:26:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:26:13 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 0x10a717490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a68d570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff079dbe310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a52bd30>, '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 0x10a351db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a523b20>, '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.3RXa1LxaDs/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.h347kkx8t7/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/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 17:26:13 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 17:26:13 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 17:26:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.3RXa1LxaDs/Render', 17:26:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:26:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:26:13 INFO - 'HOME': '/Users/cltbld', 17:26:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:26:13 INFO - 'LOGNAME': 'cltbld', 17:26:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:26:13 INFO - 'MOZ_NO_REMOTE': '1', 17:26:13 INFO - 'NO_EM_RESTART': '1', 17:26:13 INFO - 'PAGER': '/bin/cat', 17:26:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:26:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:26:13 INFO - 'PWD': '/builds/slave/test', 17:26:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:26:13 INFO - 'SHELL': '/bin/bash', 17:26:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.h347kkx8t7/Listeners', 17:26:13 INFO - 'TMPDIR': '/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/', 17:26:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:26:13 INFO - 'USER': 'cltbld', 17:26:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:26:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:26:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:26:13 INFO - 'XPC_FLAGS': '0x0', 17:26:13 INFO - 'XPC_SERVICE_NAME': '0', 17:26:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:26:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:26:14 INFO - Downloading/unpacking psutil>=3.1.1 17:26:14 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 17:26:14 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 17:26:14 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 17:26:14 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 17:26:14 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 17:26:14 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 17:26:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 17:26:17 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 17:26:17 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 17:26:17 INFO - Installing collected packages: psutil 17:26:17 INFO - Running setup.py install for psutil 17:26:17 INFO - building 'psutil._psutil_osx' extension 17:26:17 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 17:26:18 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 17:26:18 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 17:26:18 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 17:26:18 INFO - building 'psutil._psutil_posix' extension 17:26:18 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 17:26:18 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 17:26:18 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 17:26:18 INFO - ^ 17:26:18 INFO - 1 warning generated. 17:26:18 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 17:26:18 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 17:26:18 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 17:26:18 INFO - Successfully installed psutil 17:26:18 INFO - Cleaning up... 17:26:18 INFO - Return code: 0 17:26:18 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 17:26:18 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')]} 17:26:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:26:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:26:18 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')]} 17:26:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:26:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:26:18 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 0x10a717490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a68d570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff079dbe310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a52bd30>, '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 0x10a351db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a523b20>, '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.3RXa1LxaDs/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.h347kkx8t7/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/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 17:26:18 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 17:26:18 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 17:26:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.3RXa1LxaDs/Render', 17:26:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:26:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:26:18 INFO - 'HOME': '/Users/cltbld', 17:26:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:26:18 INFO - 'LOGNAME': 'cltbld', 17:26:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:26:18 INFO - 'MOZ_NO_REMOTE': '1', 17:26:18 INFO - 'NO_EM_RESTART': '1', 17:26:18 INFO - 'PAGER': '/bin/cat', 17:26:18 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:26:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:26:18 INFO - 'PWD': '/builds/slave/test', 17:26:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:26:18 INFO - 'SHELL': '/bin/bash', 17:26:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.h347kkx8t7/Listeners', 17:26:18 INFO - 'TMPDIR': '/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/', 17:26:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:26:18 INFO - 'USER': 'cltbld', 17:26:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:26:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:26:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:26:18 INFO - 'XPC_FLAGS': '0x0', 17:26:18 INFO - 'XPC_SERVICE_NAME': '0', 17:26:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:26:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:26:19 INFO - Downloading/unpacking mozsystemmonitor==0.3 17:26:19 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 17:26:19 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 17:26:19 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 17:26:19 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 17:26:19 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 17:26:19 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 17:26:21 INFO - Downloading mozsystemmonitor-0.3.tar.gz 17:26:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 17:26:21 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 17:26:21 INFO - Installing collected packages: mozsystemmonitor 17:26:21 INFO - Running setup.py install for mozsystemmonitor 17:26:21 INFO - Successfully installed mozsystemmonitor 17:26:21 INFO - Cleaning up... 17:26:21 INFO - Return code: 0 17:26:21 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 17:26:21 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')]} 17:26:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:26:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:26:21 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')]} 17:26:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:26:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:26:21 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 0x10a717490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a68d570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff079dbe310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a52bd30>, '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 0x10a351db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a523b20>, '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.3RXa1LxaDs/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.h347kkx8t7/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/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 17:26:21 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 17:26:21 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 17:26:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.3RXa1LxaDs/Render', 17:26:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:26:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:26:21 INFO - 'HOME': '/Users/cltbld', 17:26:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:26:21 INFO - 'LOGNAME': 'cltbld', 17:26:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:26:21 INFO - 'MOZ_NO_REMOTE': '1', 17:26:21 INFO - 'NO_EM_RESTART': '1', 17:26:21 INFO - 'PAGER': '/bin/cat', 17:26:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:26:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:26:21 INFO - 'PWD': '/builds/slave/test', 17:26:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:26:21 INFO - 'SHELL': '/bin/bash', 17:26:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.h347kkx8t7/Listeners', 17:26:21 INFO - 'TMPDIR': '/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/', 17:26:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:26:21 INFO - 'USER': 'cltbld', 17:26:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:26:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:26:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:26:21 INFO - 'XPC_FLAGS': '0x0', 17:26:21 INFO - 'XPC_SERVICE_NAME': '0', 17:26:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:26:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:26:22 INFO - Downloading/unpacking jsonschema==2.5.1 17:26:22 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 17:26:22 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 17:26:22 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 17:26:22 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 17:26:22 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 17:26:22 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 17:26:24 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 17:26:24 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 17:26:24 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 17:26:24 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 17:26:24 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 17:26:24 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 17:26:24 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 17:26:24 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 17:26:24 INFO - Downloading functools32-3.2.3-2.tar.gz 17:26:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 17:26:25 INFO - warning: no files found matching '*.txt' 17:26:25 INFO - no previously-included directories found matching 'build' 17:26:25 INFO - no previously-included directories found matching 'dist' 17:26:25 INFO - no previously-included directories found matching '.git*' 17:26:25 INFO - Installing collected packages: jsonschema, functools32 17:26:25 INFO - Running setup.py install for functools32 17:26:25 INFO - warning: no files found matching '*.txt' 17:26:25 INFO - no previously-included directories found matching 'build' 17:26:25 INFO - no previously-included directories found matching 'dist' 17:26:25 INFO - no previously-included directories found matching '.git*' 17:26:25 INFO - Successfully installed jsonschema functools32 17:26:25 INFO - Cleaning up... 17:26:25 INFO - Return code: 0 17:26:25 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 17:26:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:26:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:26:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:26:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:26:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:26:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:26:25 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 0x10a717490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a68d570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff079dbe310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a52bd30>, '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 0x10a351db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a523b20>, '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.3RXa1LxaDs/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.h347kkx8t7/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/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 17:26:25 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 17:26:25 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 17:26:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.3RXa1LxaDs/Render', 17:26:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:26:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:26:25 INFO - 'HOME': '/Users/cltbld', 17:26:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:26:25 INFO - 'LOGNAME': 'cltbld', 17:26:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:26:25 INFO - 'MOZ_NO_REMOTE': '1', 17:26:25 INFO - 'NO_EM_RESTART': '1', 17:26:25 INFO - 'PAGER': '/bin/cat', 17:26:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:26:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:26:25 INFO - 'PWD': '/builds/slave/test', 17:26:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:26:25 INFO - 'SHELL': '/bin/bash', 17:26:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.h347kkx8t7/Listeners', 17:26:25 INFO - 'TMPDIR': '/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/', 17:26:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:26:25 INFO - 'USER': 'cltbld', 17:26:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:26:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:26:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:26:25 INFO - 'XPC_FLAGS': '0x0', 17:26:25 INFO - 'XPC_SERVICE_NAME': '0', 17:26:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:26:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:26:25 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 17:26:25 INFO - Cleaning up... 17:26:25 INFO - Return code: 0 17:26:25 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 17:26:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:26:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:26:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:26:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:26:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:26:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:26:25 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 0x10a717490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a68d570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff079dbe310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a52bd30>, '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 0x10a351db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a523b20>, '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.3RXa1LxaDs/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.h347kkx8t7/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/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 17:26:25 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 17:26:25 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 17:26:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.3RXa1LxaDs/Render', 17:26:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:26:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:26:25 INFO - 'HOME': '/Users/cltbld', 17:26:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:26:25 INFO - 'LOGNAME': 'cltbld', 17:26:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:26:25 INFO - 'MOZ_NO_REMOTE': '1', 17:26:25 INFO - 'NO_EM_RESTART': '1', 17:26:25 INFO - 'PAGER': '/bin/cat', 17:26:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:26:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:26:25 INFO - 'PWD': '/builds/slave/test', 17:26:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:26:25 INFO - 'SHELL': '/bin/bash', 17:26:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.h347kkx8t7/Listeners', 17:26:25 INFO - 'TMPDIR': '/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/', 17:26:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:26:25 INFO - 'USER': 'cltbld', 17:26:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:26:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:26:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:26:25 INFO - 'XPC_FLAGS': '0x0', 17:26:25 INFO - 'XPC_SERVICE_NAME': '0', 17:26:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:26:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:26:25 INFO - Downloading/unpacking blobuploader==1.2.4 17:26:25 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 17:26:25 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 17:26:25 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 17:26:25 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 17:26:25 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 17:26:25 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 17:26:28 INFO - Downloading blobuploader-1.2.4.tar.gz 17:26:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 17:26:28 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 17:26:28 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 17:26:28 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 17:26:28 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 17:26:28 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 17:26:28 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 17:26:28 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 17:26:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 17:26:29 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 17:26:29 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 17:26:29 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 17:26:29 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 17:26:29 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 17:26:29 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 17:26:29 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 17:26:29 INFO - Downloading docopt-0.6.1.tar.gz 17:26:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 17:26:29 INFO - Installing collected packages: blobuploader, requests, docopt 17:26:29 INFO - Running setup.py install for blobuploader 17:26:29 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 17:26:29 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 17:26:29 INFO - Running setup.py install for requests 17:26:30 INFO - Running setup.py install for docopt 17:26:30 INFO - Successfully installed blobuploader requests docopt 17:26:30 INFO - Cleaning up... 17:26:30 INFO - Return code: 0 17:26:30 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:26:30 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')]} 17:26:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:26:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:26:30 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')]} 17:26:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:26:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:26:30 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 0x10a717490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a68d570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff079dbe310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a52bd30>, '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 0x10a351db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a523b20>, '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.3RXa1LxaDs/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.h347kkx8t7/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/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 17:26:30 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 17:26:30 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 17:26:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.3RXa1LxaDs/Render', 17:26:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:26:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:26:30 INFO - 'HOME': '/Users/cltbld', 17:26:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:26:30 INFO - 'LOGNAME': 'cltbld', 17:26:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:26:30 INFO - 'MOZ_NO_REMOTE': '1', 17:26:30 INFO - 'NO_EM_RESTART': '1', 17:26:30 INFO - 'PAGER': '/bin/cat', 17:26:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:26:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:26:30 INFO - 'PWD': '/builds/slave/test', 17:26:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:26:30 INFO - 'SHELL': '/bin/bash', 17:26:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.h347kkx8t7/Listeners', 17:26:30 INFO - 'TMPDIR': '/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/', 17:26:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:26:30 INFO - 'USER': 'cltbld', 17:26:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:26:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:26:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:26:30 INFO - 'XPC_FLAGS': '0x0', 17:26:30 INFO - 'XPC_SERVICE_NAME': '0', 17:26:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:26:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:26:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 17:26:30 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-VyV9XU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 17:26:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 17:26:30 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-6jwCWu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 17:26:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 17:26:31 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-o1xAof-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 17:26:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 17:26:31 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-NekZ6r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 17:26:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 17:26:31 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-NRt7v7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 17:26:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 17:26:31 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-j5zwpu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 17:26:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 17:26:31 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-o2DM3f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 17:26:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 17:26:31 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-C1KF0n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 17:26:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 17:26:31 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-2oSmel-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 17:26:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 17:26:31 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-IkFOam-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 17:26:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 17:26:31 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-edJX3H-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 17:26:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 17:26:31 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-89PUme-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 17:26:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 17:26:32 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-X_ztFp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 17:26:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 17:26:32 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-_HF2Zy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 17:26:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 17:26:32 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-l4EFGF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 17:26:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 17:26:32 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-F3CPke-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 17:26:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 17:26:32 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-DC_gWx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 17:26:32 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 17:26:32 INFO - Running setup.py install for manifestparser 17:26:32 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 17:26:32 INFO - Running setup.py install for mozcrash 17:26:32 INFO - Running setup.py install for mozdebug 17:26:32 INFO - Running setup.py install for mozdevice 17:26:33 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 17:26:33 INFO - Installing dm script to /builds/slave/test/build/venv/bin 17:26:33 INFO - Running setup.py install for mozfile 17:26:33 INFO - Running setup.py install for mozhttpd 17:26:33 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 17:26:33 INFO - Running setup.py install for mozinfo 17:26:33 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 17:26:33 INFO - Running setup.py install for mozInstall 17:26:33 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 17:26:33 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 17:26:33 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 17:26:33 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 17:26:33 INFO - Running setup.py install for mozleak 17:26:33 INFO - Running setup.py install for mozlog 17:26:34 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 17:26:34 INFO - Running setup.py install for moznetwork 17:26:34 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 17:26:34 INFO - Running setup.py install for mozprocess 17:26:34 INFO - Running setup.py install for mozprofile 17:26:34 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 17:26:34 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 17:26:34 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 17:26:34 INFO - Running setup.py install for mozrunner 17:26:34 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 17:26:34 INFO - Running setup.py install for mozscreenshot 17:26:34 INFO - Running setup.py install for moztest 17:26:34 INFO - Running setup.py install for mozversion 17:26:35 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 17:26:35 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 17:26:35 INFO - Cleaning up... 17:26:35 INFO - Return code: 0 17:26:35 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:26:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:26:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:26:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:26:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:26:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:26:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:26:35 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 0x10a717490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a68d570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff079dbe310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a52bd30>, '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 0x10a351db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a523b20>, '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.3RXa1LxaDs/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.h347kkx8t7/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/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 17:26:35 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 17:26:35 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 17:26:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.3RXa1LxaDs/Render', 17:26:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:26:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:26:35 INFO - 'HOME': '/Users/cltbld', 17:26:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:26:35 INFO - 'LOGNAME': 'cltbld', 17:26:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:26:35 INFO - 'MOZ_NO_REMOTE': '1', 17:26:35 INFO - 'NO_EM_RESTART': '1', 17:26:35 INFO - 'PAGER': '/bin/cat', 17:26:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:26:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:26:35 INFO - 'PWD': '/builds/slave/test', 17:26:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:26:35 INFO - 'SHELL': '/bin/bash', 17:26:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.h347kkx8t7/Listeners', 17:26:35 INFO - 'TMPDIR': '/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/', 17:26:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:26:35 INFO - 'USER': 'cltbld', 17:26:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:26:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:26:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:26:35 INFO - 'XPC_FLAGS': '0x0', 17:26:35 INFO - 'XPC_SERVICE_NAME': '0', 17:26:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:26:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:26:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 17:26:35 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-J4eZUp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 17:26:35 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)) 17:26:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 17:26:35 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-RqX45w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 17:26:35 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)) 17:26:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 17:26:35 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-yTM41Q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 17:26:35 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)) 17:26:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 17:26:35 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-6sKL1R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 17:26:35 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)) 17:26:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 17:26:35 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-MuKKqs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 17:26:35 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)) 17:26:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 17:26:35 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-huqEHW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 17:26:36 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)) 17:26:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 17:26:36 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-4Lvlla-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 17:26:36 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)) 17:26:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 17:26:36 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-Q0HdVr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 17:26:36 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)) 17:26:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 17:26:36 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-8PInLF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 17:26:36 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)) 17:26:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 17:26:36 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-s1uQj8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 17:26:36 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)) 17:26:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 17:26:36 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-_B8cop-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 17:26:36 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)) 17:26:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 17:26:36 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-Q27_UW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 17:26:36 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)) 17:26:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 17:26:36 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-9zMEEt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 17:26:36 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)) 17:26:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 17:26:36 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-BlGA3A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 17:26:36 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)) 17:26:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 17:26:36 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-_S9oJx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 17:26:37 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)) 17:26:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 17:26:37 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-0iNctU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 17:26:37 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)) 17:26:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 17:26:37 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-7B7u9r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 17:26:37 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)) 17:26:37 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)) 17:26:37 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)) 17:26:37 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)) 17:26:37 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)) 17:26:37 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)) 17:26:37 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 17:26:37 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 17:26:37 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 17:26:37 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 17:26:37 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 17:26:37 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 17:26:37 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 17:26:39 INFO - Downloading blessings-1.6.tar.gz 17:26:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 17:26:39 INFO - Installing collected packages: blessings 17:26:39 INFO - Running setup.py install for blessings 17:26:40 INFO - Successfully installed blessings 17:26:40 INFO - Cleaning up... 17:26:40 INFO - Return code: 0 17:26:40 INFO - Done creating virtualenv /builds/slave/test/build/venv. 17:26:40 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 17:26:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 17:26:40 INFO - Reading from file tmpfile_stdout 17:26:40 INFO - Current package versions: 17:26:40 INFO - blessings == 1.6 17:26:40 INFO - blobuploader == 1.2.4 17:26:40 INFO - docopt == 0.6.1 17:26:40 INFO - functools32 == 3.2.3-2 17:26:40 INFO - jsonschema == 2.5.1 17:26:40 INFO - manifestparser == 1.1 17:26:40 INFO - mozInstall == 1.12 17:26:40 INFO - mozcrash == 0.17 17:26:40 INFO - mozdebug == 0.1 17:26:40 INFO - mozdevice == 0.48 17:26:40 INFO - mozfile == 1.2 17:26:40 INFO - mozhttpd == 0.7 17:26:40 INFO - mozinfo == 0.9 17:26:40 INFO - mozleak == 0.1 17:26:40 INFO - mozlog == 3.4 17:26:40 INFO - moznetwork == 0.27 17:26:40 INFO - mozprocess == 0.23 17:26:40 INFO - mozprofile == 0.28 17:26:40 INFO - mozrunner == 6.12 17:26:40 INFO - mozscreenshot == 0.1 17:26:40 INFO - mozsystemmonitor == 0.3 17:26:40 INFO - moztest == 0.7 17:26:40 INFO - mozversion == 1.4 17:26:40 INFO - psutil == 3.1.1 17:26:40 INFO - requests == 1.2.3 17:26:40 INFO - wsgiref == 0.1.2 17:26:40 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:26:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:26:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:26:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:26:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:26:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:26:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:26:40 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 0x10a717490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a68d570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff079dbe310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a52bd30>, '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 0x10a351db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a523b20>, '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.3RXa1LxaDs/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.h347kkx8t7/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/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 17:26:40 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 17:26:40 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 17:26:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.3RXa1LxaDs/Render', 17:26:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:26:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:26:40 INFO - 'HOME': '/Users/cltbld', 17:26:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:26:40 INFO - 'LOGNAME': 'cltbld', 17:26:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:26:40 INFO - 'MOZ_NO_REMOTE': '1', 17:26:40 INFO - 'NO_EM_RESTART': '1', 17:26:40 INFO - 'PAGER': '/bin/cat', 17:26:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:26:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:26:40 INFO - 'PWD': '/builds/slave/test', 17:26:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:26:40 INFO - 'SHELL': '/bin/bash', 17:26:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.h347kkx8t7/Listeners', 17:26:40 INFO - 'TMPDIR': '/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/', 17:26:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:26:40 INFO - 'USER': 'cltbld', 17:26:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:26:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:26:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:26:40 INFO - 'XPC_FLAGS': '0x0', 17:26:40 INFO - 'XPC_SERVICE_NAME': '0', 17:26:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:26:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:26:40 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)) 17:26:40 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)) 17:26:40 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)) 17:26:40 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)) 17:26:40 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)) 17:26:40 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)) 17:26:40 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)) 17:26:40 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)) 17:26:40 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)) 17:26:40 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)) 17:26:40 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)) 17:26:40 INFO - Cleaning up... 17:26:40 INFO - Return code: 0 17:26:40 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 17:26:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:26:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:26:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:26:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:26:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:26:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:26:40 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 0x10a717490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a68d570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff079dbe310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a52bd30>, '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 0x10a351db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a523b20>, '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.3RXa1LxaDs/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.h347kkx8t7/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/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 17:26:40 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 17:26:40 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 17:26:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.3RXa1LxaDs/Render', 17:26:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:26:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:26:40 INFO - 'HOME': '/Users/cltbld', 17:26:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:26:40 INFO - 'LOGNAME': 'cltbld', 17:26:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:26:40 INFO - 'MOZ_NO_REMOTE': '1', 17:26:40 INFO - 'NO_EM_RESTART': '1', 17:26:40 INFO - 'PAGER': '/bin/cat', 17:26:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:26:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:26:40 INFO - 'PWD': '/builds/slave/test', 17:26:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:26:40 INFO - 'SHELL': '/bin/bash', 17:26:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.h347kkx8t7/Listeners', 17:26:40 INFO - 'TMPDIR': '/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/', 17:26:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:26:40 INFO - 'USER': 'cltbld', 17:26:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:26:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:26:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:26:40 INFO - 'XPC_FLAGS': '0x0', 17:26:40 INFO - 'XPC_SERVICE_NAME': '0', 17:26:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:26:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:26:40 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 17:26:40 INFO - Cleaning up... 17:26:40 INFO - Return code: 0 17:26:40 INFO - Running post-action listener: _resource_record_post_action 17:26:40 INFO - Running post-action listener: _start_resource_monitoring 17:26:40 INFO - Starting resource monitoring. 17:26:40 INFO - [mozharness: 2016-10-13 00:26:40.968094Z] Finished create-virtualenv step (success) 17:26:40 INFO - [mozharness: 2016-10-13 00:26:40.969162Z] Running install step. 17:26:40 INFO - Running pre-action listener: _resource_record_pre_action 17:26:40 INFO - Running main action method: install 17:26:40 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 17:26:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 17:26:41 INFO - Reading from file tmpfile_stdout 17:26:41 INFO - Detecting whether we're running mozinstall >=1.0... 17:26:41 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 17:26:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 17:26:41 INFO - Reading from file tmpfile_stdout 17:26:41 INFO - Output received: 17:26:41 INFO - Usage: mozinstall [options] installer 17:26:41 INFO - Options: 17:26:41 INFO - -h, --help show this help message and exit 17:26:41 INFO - -d DEST, --destination=DEST 17:26:41 INFO - Directory to install application into. [default: 17:26:41 INFO - "/builds/slave/test"] 17:26:41 INFO - --app=APP Application being installed. [default: firefox] 17:26:41 INFO - mkdir: /builds/slave/test/build/application 17:26:41 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'] 17:26:41 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 17:27:15 INFO - Reading from file tmpfile_stdout 17:27:15 INFO - Output received: 17:27:15 INFO - /builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox 17:27:15 INFO - Running post-action listener: _resource_record_post_action 17:27:15 INFO - [mozharness: 2016-10-13 00:27:15.811053Z] Finished install step (success) 17:27:15 INFO - [mozharness: 2016-10-13 00:27:15.811189Z] Running run-tests step. 17:27:15 INFO - Running pre-action listener: _resource_record_pre_action 17:27:15 INFO - Running main action method: run_tests 17:27:15 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 17:27:15 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 17:27:15 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 17:27:15 INFO - 2016-10-12 17:27:15.825 screenresolution[1638:7593] starting screenresolution argv=screenresolution get 17:27:15 INFO - 2016-10-12 17:27:15.828 screenresolution[1638:7593] Display 0: 1600x1200x32@60 17:27:15 INFO - 2016-10-12 17:27:15.834 screenresolution[1639:7595] starting screenresolution argv=screenresolution list 17:27:15 INFO - Available Modes on Display 0 17:27:15 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 17:27:15 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 17:27:15 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 17:27:15 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 17:27:16 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 17:27:16 INFO - Intel Iris: 17:27:16 INFO - Chipset Model: Intel Iris 17:27:16 INFO - Type: GPU 17:27:16 INFO - Bus: Built-In 17:27:16 INFO - VRAM (Dynamic, Max): 1536 MB 17:27:16 INFO - Vendor: Intel (0x8086) 17:27:16 INFO - Device ID: 0x0a2e 17:27:16 INFO - Revision ID: 0x0009 17:27:16 INFO - Displays: 17:27:16 INFO - Display: 17:27:16 INFO - Resolution: 1600 x 1200 @ 60 Hz 17:27:16 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 17:27:16 INFO - Display Serial Number: 1600x1200 60 17:27:16 INFO - Main Display: Yes 17:27:16 INFO - Mirror: Off 17:27:16 INFO - Online: Yes 17:27:16 INFO - Rotation: Supported 17:27:16 INFO - Return code: 0 17:27:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 17:27:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 17:27:16 INFO - Python 2.7.3 17:27:16 INFO - Return code: 0 17:27:16 INFO - grabbing minidump binary from tooltool 17:27:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:27:16 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 0x10a52bd30>, '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 0x10a351db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a523b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 17:27:16 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 17:27:16 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 17:27:16 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 17:27:16 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 17:27:16 INFO - Return code: 0 17:27:16 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 17:27:16 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 17:27:16 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 17:27:16 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 17:27:16 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 17:27:16 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/FirefoxNightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/QJzK0NKsRYmn9yJfNq0ccA/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0111', '--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 17:27:16 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/FirefoxNightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/QJzK0NKsRYmn9yJfNq0ccA/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0111 --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 17:27:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.3RXa1LxaDs/Render', 17:27:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:27:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:27:16 INFO - 'HOME': '/Users/cltbld', 17:27:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:27:16 INFO - 'LOGNAME': 'cltbld', 17:27:16 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 17:27:16 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 17:27:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:27:16 INFO - 'MOZ_NO_REMOTE': '1', 17:27:16 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 17:27:16 INFO - 'NO_EM_RESTART': '1', 17:27:16 INFO - 'PAGER': '/bin/cat', 17:27:16 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:27:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:27:16 INFO - 'PWD': '/builds/slave/test', 17:27:16 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 17:27:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:27:16 INFO - 'SHELL': '/bin/bash', 17:27:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.h347kkx8t7/Listeners', 17:27:16 INFO - 'TMPDIR': '/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/', 17:27:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:27:16 INFO - 'USER': 'cltbld', 17:27:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:27:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:27:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:27:16 INFO - 'XPC_FLAGS': '0x0', 17:27:16 INFO - 'XPC_SERVICE_NAME': '0', 17:27:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:27:16 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/FirefoxNightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/QJzK0NKsRYmn9yJfNq0ccA/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0111', '--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 17:27:16 INFO - mozversion application_buildid: 20161012143919 17:27:16 INFO - mozversion application_changeset: 22be4ae74653b25186665f22e52a50e7027fd36b 17:27:16 INFO - mozversion application_display_name: Nightly 17:27:16 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 17:27:16 INFO - mozversion application_name: Firefox 17:27:16 INFO - mozversion application_remotingname: firefox 17:27:16 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 17:27:16 INFO - mozversion application_vendor: Mozilla 17:27:16 INFO - mozversion application_version: 52.0a1 17:27:16 INFO - mozversion platform_buildid: 20161012143919 17:27:16 INFO - mozversion platform_changeset: 22be4ae74653b25186665f22e52a50e7027fd36b 17:27:16 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 17:27:16 INFO - mozversion platform_version: 52.0a1 17:27:16 INFO - using testdate: 1476318436 17:27:16 INFO - actual date: 1476318436 17:27:16 INFO - starting webserver on 'localhost:49272' 17:27:16 INFO - SUITE-START | Running 2 tests 17:27:16 INFO - TEST-START | dromaeo_css 17:27:16 INFO - operating with platform_type : mac_ 17:27:16 INFO - Initialising browser for dromaeo_css test... 17:27:16 INFO - TEST-INFO | started process 1649 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/tmpKcBzbt/profile http://localhost:49272/getInfo.html) 17:27:18 INFO - PROCESS | 1649 | __metrics Screen width/height:1600/1200 17:27:18 INFO - PROCESS | 1649 | colorDepth:24 17:27:18 INFO - PROCESS | 1649 | Browser inner width/height: 1024/689 17:27:18 INFO - PROCESS | 1649 | __metrics 17:27:18 INFO - PROCESS | 1649 | 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" 17:27:18 INFO - PROCESS | 1649 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 181: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 17:27:19 INFO - TEST-INFO | 1649: exit 0 17:27:19 INFO - Browser initialized. 17:27:19 INFO - Running cycle 1/1 for dromaeo_css test... 17:27:19 INFO - TEST-INFO | started process 1652 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/tmpKcBzbt/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 17:27:31 INFO - PROCESS | 1652 | RSS: Main: 234885120 17:27:31 INFO - PROCESS | 1652 | 17:28:23 INFO - PROCESS | 1652 | 1476318503260 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] 17:30:35 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49272/tests/dromaeo/cssquery-ext.html) 17:30:37 INFO - PROCESS | 1652 | RSS: Main: 422395904 17:30:37 INFO - PROCESS | 1652 | 17:33:40 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-ext.html (next: http://localhost:49272/tests/dromaeo/cssquery-jquery.html) 17:33:41 INFO - PROCESS | 1652 | RSS: Main: 370655232 17:33:41 INFO - PROCESS | 1652 | 17:36:43 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49272/tests/dromaeo/cssquery-mootools.html) 17:36:44 INFO - PROCESS | 1652 | RSS: Main: 345071616 17:36:44 INFO - PROCESS | 1652 | 17:39:46 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49272/tests/dromaeo/cssquery-prototype.html) 17:39:47 INFO - PROCESS | 1652 | RSS: Main: 356524032 17:39:47 INFO - PROCESS | 1652 | 17:42:50 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49272/tests/dromaeo/cssquery-yui.html) 17:42:51 INFO - PROCESS | 1652 | RSS: Main: 342130688 17:42:51 INFO - PROCESS | 1652 | 17:45:54 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-yui.html (next: http://localhost:49272/tests/dromaeo/cssquery-dojo.html) 17:45:54 INFO - PROCESS | 1652 | RSS: Main: 437563392 17:45:54 INFO - PROCESS | 1652 | 17:45:54 INFO - PROCESS | 1652 | __start_tp_report 17:45:54 INFO - PROCESS | 1652 | _x_x_mozilla_page_load 17:45:54 INFO - PROCESS | 1652 | _x_x_mozilla_page_load_details 17:45:54 INFO - PROCESS | 1652 | |i|pagename|runs| 17:45:54 INFO - PROCESS | 1652 | |0;dojo.html;2872.238856702974;3350.9162270943225;3366.915827104322;3381.7125544328733;3424.589049314083;4649.674522783403;5543.057680194366;5688.345840228375;5755.251817263755;5766.192733017376;5658.405867383917;5672.376038635747;5694.4590263924865;5714.942850571503;5761.827145185651;5147.073526765181;5154.561862241714;5164.845054648366;5217.530422261995;5236.345456817898;5291.656042357254;5342.626016178869;5344.198370244475;5345.919811202835;5363.973180134113;5141.15170996785;5183.937292854956;5262.81580144695;5299.284596579451;5348.197770334442;5207.0887594670785;5226.111561034633;5226.477352264743;5235.345581802275;5239.476052394768;5158.871028224287;5189.351331083614;5202.68783872965;5218.321618189649;5219.347581552306;5674.659520428746;5679.971600142014;5680.176374425685;5688.402717714622;5695.629784064064;6128.0169984388185;6142.140100385905;6156.322804491502;6168.414000669929;6225.470834979033;6182.96908515459;6205.379462053804;6271.811845644638;6278.87442251157;6283.151774510474;4823.782929768169;5747.8563035924435;5769;5778.508826749689;5834.154047663107;6216.844578885564;6233.563650544418;6274.403931626488;6297.622142671455;6299.779507717208;6157.753689852445;6160;6164.229471316085;6165.969170154121;6171.382861713792;6264.906026409608;6268.780592679234;6288.717007734663;6293.433590976834;6300.180976473121;5967.403259673998;6101.84745381369;6123.846903827351;6227.439530442191;6239.937600623935;6104.428839602322;6140.749659041545;6197.194364732556;6257.499400047986;6266.467350275187;4949.109160351171;4960.751962401865;4961.801527938842;4963.503649635008;4991.950080499221;4387.034852332482;4414.050979039522;4416.757078360657;4419.18245124653;4425.070735145591;5609.467100625434;5672.234248376416;5686.459786320293;5708.857278568069;5734.971325143348;5542.63972841764;5972.432618901197;6226;6229.034499652561;6232.407921247474;5724.112762521816;5728.684922329312;5733.627314224562;5735.2257445245195;5742.253507044141;5297.920531192035;5326.094563924142;5347.51872331492;5350.384705758887;5357.357117145967;6262.15460912771;6263.185785847902;6284.528660350584;6284.905726414016;6291.465225455796;6185.659788711664;6188.102725104744;6221.2845522763655;6233.501319894488;6235.781747638993;5216.921746173734;6242.625442473466;6307.400796924285;6317.115603815378;6328.746850125944;5244.947550524446;6161.075838624063;6171.722272497838;6175.135481032748;6188.412100850412;6030.216071910623;6069.9377608917375;6129.664444256008;6187.659678717714;6241.3134555197985;5908.05471124618;5984.700764961647;5988.760449582142;6085.330613632408;6132.63204207749;6032.849178770566;6039.4262545058145;6096.359882212481;6099.8780024398375;6111.633302001894;5792.4497172768515;5900.498457631149;5923.970380148072;5929.762809487572;5930.792422265373;5150.48495150482;5954.583179177416;6003.7898673546215;6092.817215483543;6115.174451449176;5949.0481522956115;5955.880882382415;5974.40255974399;6003.219581454558;6011.008183649649 17:45:54 INFO - PROCESS | 1652 | |1;ext.html;456.0104573076426;471.94808571057155;472.98108075676976;522.4305506997372;529.2537522093849;29839.850800745993;30199.84900075499;31223.375532489335;31255.374892502135;31274.68725312752;31275.90534331299;39038.80480597593;40136.79931600338;40145;42525.574744252546;7922.9603851980655;9511.80976380472;9514.429134251932;9515.952420237889;9527.333086683935;9264.49045302508;9278.304127190488;9405.247580193602;9408.670696525623;9435.669751558697;8528.829423411558;8753.824923501526;8755.124487551226;8851.380403371766;8883.200511953923;11109.166812489095;11397.943010284978;11435.256708313933;11640.301581905112;11641.59254426091;11249;11281.71795705114;11361.545538178463;11403.201775875692;11452.083833293316;36663.45004824903;38175.427368589495;38321.23357532862;38368.42447363292;38563.228735425415;3079.414911166871;3465.358908601917;3526.8589256429714;3557.94663080054;4036.7174297799165;29732.702672973424;30146.84926575353;30830;30966.69033309683;32127.35745285081;3190.5054716518976;3202.4876019836715;3221.5811944447305;3246.2675403165645;3332.8833490827947;494055;495658;497931;498272;504546;53929.46070539244;54626.81492740246;56110.719446403324;58496;58959;32727;33570.832145839115;33783.15542111418;56799.43200568024;62073.37926620767;15557.22213889301;15927.442539511061;15969.840301596836;15983.041017538984;15984;12144.14990950625;12266.448009839578;12273.570425035081;12275.140740148105;12295.815562766566;17419.65160696779;18150.727739083923;18150.727739083923;18166.000869952015;18182.27270909149;6298.7795427160745;6525.412712855866;7467.066616672916;7539.736109236151;7661.118971318258;848.8175042365921;921.9032001639865;1017.0214665945645;1035.77213013137;1064.9254552181428;28784.136475905758;28993.85503072513;29346.559801602994;29650.851745741133;29762.255943601584;951.5052172870069;1012.1599072769584;1024.9282550221562;1073.613499140309;1083.2254937719567;14235.145891246559;15058.397664093533;15132.697346053017;15292.388304467697;15429.537113886601;634252;644640;646802;647578;648376;47994.76002619965;48722.26916596254;54669.1799623006;55263.723681381336;55394.44605553974;1172.724409763722;1189.4885199364182;1318.643966129159;1321.1610627251764;1361.2717196300327;29792.95724649627;29932.102036938926;30164.825013856367;30231.395372092877;30490.542641859945;46510.76744616255;46985.76507117443;47068;47150.528494714614;47208.29187562121;469832;474506;619670;650031;668159;38922;39174.6082539171;39253;39360.65434438105;39577.406338905515;40228;42187.7890610545;44136;44256.11487770291;44917.77541112273;31198.532022019233;36552.63447365492;36949.83624890206;37751.433728494645;38246;31037.689623104383;31546.68759780282;32136.83931580327;36531.269374611824;37090.07274818151;36176.63823361733;36619;36936;37008;37105.0723731898;30033.699663003965;32484.02547923566;32557.837210813796;32765.508517372735;33102;23905.16331928374;24378.390540236636;24513.829781939534;24599.50800984006;24784.50430991406 17:45:54 INFO - PROCESS | 1652 | |2;jquery.html;344.42996840229426;362.6699703270025;363.6218332933749;371.53001453161755;392.9253441846049;15010.174440405774;15190.31643576039;16082.195890205487;20377.49056273594;20931.267405640767;19053.333133340337;19337.903310483427;20232.887191204478;20441.284555040576;21111.838848863346;5691.601587888839;5996.130561068642;5996.760129594811;6011.248593925759;6012.549058820584;4937.604991600663;6580.210374755012;6654.26803051664;6691.26396096429;6712.865742685168;5766.769329226826;6097.115918191859;6626.7017984190825;6649.035889795977;6671;6045.697715114226;6138.109974053782;6142.662153581551;6145.754169833202;6146.446819786196;5819.883602327972;5835.124731290298;5918.970405147989;5979.970100149515;5997.250343707036;12114.697132571668;12288.324142172178;12293.446794894251;21203;25273.494530109478;1789.579448829524;2051.200031987526;2101.401100686297;2107.525806693497;2280;9813.705588832347;9983;10042;10586.153107751361;10641.680749577525;1822.3166312632763;1892.3660573707825;1899.9265415040459;1923.1249781349452;1924.0860591219198;119022;125015;125547;128603;130382;10934.56261749537;11365.886341136647;11442.542298307975;11460.770784584261;11668.358240296866;14376.784348234785;14397.99214055006;14449.855501445061;14469.78295325571;14490.420383184766;8155.469894456842;8420.24217820387;8432.873506897402;8573.828523429622;8624.655013799378;7995.2804247618;8299.052403810378;8310.958445207736;8479.109693482096;8488.363372746946;4408.9118217636105;4500.122476117126;4515.2908255046705;4597.931031034537;4644.396228490342;1548.49673855995;1902.2771346888371;1905.1522072677526;1908.532409559639;1911;831.1397703377097;872.9388942774071;876.1961722488064;928.9117533834275;947.6872632031551;16883.746743798853;18703.25186992505;19147;21056.473588160417;21831;868.7576765225662;923.5751554285104;932.8274269260208;979.1921664626711;983.326207781099;10199;11371;11535.365554894393;11585.826212606818;11680.123990700562;525749;537275.6272437226;542429;560070;562581;89089.66365504522;118615;127781.72218277698;133222.66777332296;138421;903.3134817538718;952.7046615549202;969.7866469376726;971.1599466461602;975.4244995452717;18610.44168674942;19415.805841941965;19572.11925463329;19585.804141958397;19595.216191352185;51282;54019.45980540144;54032;55620.721896390256;55671.77312907438;75437;76153.61923190349;80524.19475805167;83801.58099209465;84705;7832.295093441618;7898.763037108896;8146.9185308148535;8187.877181842158;8324.292435143081;12472.314022728475;12535.498580056696;12772.680682983;13118.147320424141;13297.135686180367;9086.681966131153;10724.195685323475;10745;11476.196666233283;11603.5358585659;10771.784564308826;10880.80311165787;11032.613858514913;11180.888191117985;11396.601118960796;11036.834447483134;11289.379084150121;11582.6525204244;11779.941100294443;11948.462319195483;6538.738450461929;6872.69072891711;7511.399088073051;7952.920470795218;8320.252736990707;9610.135087842127;11250.381229032482;11414.94292528532;11455.198136130392;11479.139064570023 17:45:54 INFO - PROCESS | 1652 | |3;mootools.html;1500.0549653718156;1509.841466646002;1713.4174380710556;1899.7245399417077;1911.340587497314;8453.577321133942;9000.639974401032;9007.144321289483;9012.864807027892;9054.230390416822;7785.766427007199;8100.392470564717;8110.91889081109;8501.617427215775;8772.122787721224;2305.1125316753046;2338.53229354129;2341.6487526870937;2341.9414514637183;2342.672025916373;2317.756635553268;2323.7327707313707;2365.7515960824126;2369.30105618842;2369.6919400477914;1915.5977244778612;1990.3531352310486;2350.7296660883962;2361.1735892437596;2367.4081479630095;2316.652502124678;2328;2332.93001209964;2333.0900948629965;2337.7662233776655;2006.237629700719;2285.48576570272;2316.6061769499224;2318.07277089164;2318.7217533895873;8843.380963332569;8916.910830891673;8989.101089891024;8991.775205619912;9008.369414141012;5007.549320561167;5162.819301324474;5168.147255702842;5172.379314482287;5189.1697328427645;3450.706689931359;3514.2795726876184;3666.7249956253386;3670.284294562561;3673.6142705015855;1106.2201148190586;1108.578740078773;1760.2079064420934;1830.7253911913187;1874.2690350763214;11335.489902954387;11649.184557081007;11690;11708.824367634492;11763.35301558414;4367.323064924942;4461.643068554507;4627.30590411441;4628.259478483426;4630.652701047435;9156.267498600097;9178.86231706525;9184.265258779282;9186.173244408035;9199.586018629177;5197.8440646780655;5200.2979597754575;5205.713685747244;5216.26972223889;5216.739163041832;4341.370501277297;4492.775361231926;4510.413646225969;4687.156311863897;4880.853574392774;3659.121810765397;3660.158163622382;3791.2227993261317;3797.5442946846556;3823.483829683013;1121.506537123663;1145.6448500964727;1147.428023607663;1150.6433005768238;1159.368144361325;762.0093877958632;892.4600616626977;912.4388501071926;922.3082687984012;974.3277138774365;5052.898942021213;5391.975524650304;5467.972660136754;5830.7084645767545;6117.541184411187;891.3804905590682;904.4076130134772;933.780561568031;946.0964778636386;949.8670186173941;3559.6473340130583;3852.8651497198025;3943.5464921533817;3972.6226008529143;4020.396940458896;10301.742456438498;10832.729181770519;10844.620438284623;10862.239643225057;10903.34579925207;10783.730406739895;10794.622188223375;10802.67591972242;10818.323159910227;10823.350598964087;8106.4325497215805;8179.304759095544;8205.466644668077;8266.338692904554;8270.511307964503;4958.8760280993265;5669.149627555899;5775.7400916957995;5958.851028724143;5976.731047102804;9941.502924853872;10039.196864250696;10041.648542300984;10070.295079344376;10081.495925203564;9843.950780246052;10239.436830974368;10382.636607718694;10419.166466682798;10439.060484556427;6484.1894763154605;6730.865382692221;6950.409215216763;7063.328983746535;7072.929270707227;7928.84142317162;8125.106238313662;8280.50316980983;8464.153584641486;8573.22840944318;7389.408847292097;7467.178610352966;7473.252674732484;7533.096028476618;7667.00328957232;7328.120625524971;7336.633168341668;7385.8153546161775;7518.624068796647;7539.0576177977755;5267.446918073547;5600.971995139998;6918.7232510699005;6977.372036516738;6979.6859141337745;5080.161773307511;5132.3327967364;5177.637565370388;5229;5364.195370694271;5139.383274007143;5206.921896171485;5208.3333333334485;5216.686998780086;5411.214299281306 17:45:54 INFO - PROCESS | 1652 | |4;prototype.html;294.14110796474307;294.34361374135676;305.3953172718019;309.11438728044146;310.867881150511;5309.8229725812425;5338.466153384659;5340.679559226449;5365.463453654633;5377.542908852743;5005.974970125153;5157.4842515748405;5167.622893469009;5218.556422704065;5288.206768984644;3981.900452488682;4005.2390045891334;4022.109608484008;4027.5166979962446;4032.5967403259733;3615.6022837487853;3881.922361552767;3899.317619416605;4069.7558146511305;4121.9587804121875;3808.4766093562657;3927.7839718815453;3997.5202975642965;4010.4786377770847;4094.97952510237;3348.6483919188377;4012.4181993610764;4065.6137666921595;4070.5725898780647;4071.3511028033545;2881.366099458116;3456.9308613827834;3472.461768425898;3475.4960530723156;3491;4107.404426358191;4920.729359885205;5197.402298735656;5224.746060945346;5243.606729495303;4512.593866552026;4514.841980530666;4555.2711566149255;4561.677113637042;4592.632589392841;5561.137019098607;5578.386377498472;5580.888382232373;5595.524380427669;5598.24423702799;3081.845907704606;3495.038864312309;3814.43608120672;3823.3118038753014;3833.5974722654278;4911.803527858917;5847.678377689225;5850.736716847752;5863.794767183171;5891.204687367151;3493.6855682988653;3583.7670551414076;3819.2743378757946;3821.7515861468914;3849.2493963676816;3161.636411812671;3899.083715326896;3906.1797022625565;3930.449737036817;3937.468441760383;3819.4270859370763;4295.935560966588;4446.533114022981;4604.723716577016;4646.628269738413;5227.424983251839;5332.9733351333;5634.6337488063145;5647.29408823897;5662.197212379899;1256.761215369077;1317.538861398522;1335.6861137632648;1336.0847819243654;1341.745068436994;834.4104480963774;855.2132038524572;856.0369584217755;856.4133568505594;861.9956900215584;4156.010230179001;4276.87169384919;4465.437097016018;4578.656600754956;4619.706482184994;4333.566643335641;4777.416937297027;4781.42622885256;4817.325574419584;4874.731889746027;3870.6195689650663;4013.237484877864;4255.000149929529;4369.475662920407;4554.385158003627;2029.4216148397636;2321.9419514512274;2329.941751456227;2586.831855929396;2596.7143614202423;4077.225327187825;4477.201748906933;4627.3521706961055;4845.5881250094135;5093.694378337312;4713.552212539803;4873;4943.788771750944;5425.430329815392;5974.402559744078;6038.909416358758;6051.273847138372;6080.300765411946;6090.390960903963;6122.938770612326;4521.615662668579;4938.43208031081;5437.809676661298;5891.1457838612305;5912.575843903979;5048.217526283432;5175.197844334134;5337.359516858002;5755;5816.73824677882;4889.926651100165;5026.4973502649445;6078.240219972504;6082.2093127893095;6091.664958427329;3845.5193100862393;3874.5857761916136;3881;3893.8053097344455;3905.1408690088133;4369.89075273108;5087.669301495391;5413.9729301353245;5543.584231182758;5768.826935191951;5141.974290128525;5562.666240025612;5592.916106258328;5638.661680299195;5653.530082178745;4689.906201875875;4815.061063092803;5093.115547247542;5584.553235741212;5605.747741351731;4625.7455839929125;5516.282883225315;5564.443555644565;5577.944220557904;5587.441255874543;4969.577585905238;5421.918671219856;5449;5449.972750136224;5463.344398672185;4310.590493903074;5373.435789242073;5401.135818269058;5511.696856672921;5543.61194716366 17:45:54 INFO - PROCESS | 1652 | |5;yui.html;705.2242533213466;721.2066726600743;722.4942540221846;725.0001238046658;776.2276534847828;15039.924800375997;15415.69168616627;15418.152001639904;15442.61393465164;15491.922540387282;13968.650783730387;13973.790393144085;14004.649883752885;14029;14430.855691443083;668.9096971908779;684.5310961991028;821.3100995164076;827.3546633625751;844.3878188313485;833.2833762963846;845.9915400845974;850.9787255318636;853.4879072556447;854.4403415762686;904.1952662130709;905.551751882819;907.5825120444604;907.6142639378271;908.7955210077713;692.8233300508338;708.5359089796192;896.188949001155;896.4397251717677;897.9595918183697;560.1905246918204;642.1138828416755;658.3712554510479;666.9895108910006;668.2849351194224;14561.563153105424;14620.342084606218;14633.268336583129;14639.268036598234;14680.119192848464;12309.753804923941;12591.622251332383;13234.27211503367;13471.86528134716;13938.163710177421;12159.331236781973;12210.145289829716;12309.384530773516;12465.189762665397;12650.367481625883;4389.122175564868;4404.383386325917;4414.713043652185;4602.539746025371;4604.286335617984;111338;114565;117181;118536.40731796286;118798.40600797028;9214.539273036455;10690.732731681615;10837.891621083689;10993.175511836644;11120.22158448917;20972.265970691256;20976.265830696157;20979;21003.78996210018;21005.579888402146;7586.393088553013;7814.179511151247;7819.68721251155;7855.8821617675785;7920.009998750156;4211.578842115825;4316.935245971313;4337.522872484024;4387.714798538085;4477.686561940633;2986.9850650746607;3459.86160553575;3462.186386199241;3463.1688394785574;3464.220550376145;658.7944062365859;663.1975309875105;669.1668872253978;669.2872091222832;669.4911866981032;561.2731512691075;574.9223854779635;609.7286707415246;620.7734177025444;624.5971348480296;8239.670413183525;8509.957450212833;8578.699745508877;8750.781230469289;8971.775705607412;525.8869343091254;543.7308532276548;557.7089038874991;595.7051259626512;617.4473845907887;4203.70574059819;4258.318669012943;4389.62688171509;4499.167653984024;4576.176288268143;149168;151760;154847.22576387046;155466;156782.200681137;134940.65059349476;135530;137475.31262343624;137677;143189;87070;100302;102344;104092.47953760334;106053.46973265085;7586.0038229466545;7668.539887606761;8374;8471.195236452528;8487.278581320657;65447;66412.66793666194;66588.6670566644;66913.33086669266;67084;99134;101034;101580;101658.98341016495;101906.4714029305;11085.334879906908;11162.888371116185;11206.43967801623;11226.66320010401;11451.971265455632;11907.82138267944;11909.166358354832;12105.455254513745;12145.35137494569;12227.14409991292;9441.905580944378;10104.848427273744;10954;11511;11789.941050294694;10814.29707069038;11433.428328583372;11442.559888092523;11492.253003554742;12010.939945300217;11257.155713321697;11312.773744525228;11319.943400283275;11538.365389903636;11599.072074233873;9180.16078021878;9678.370905891095;9737.951310243403;10078.496075196064;10478.94760526223;9479.336446448682;10263.7434064149;10902.78194436091;11286.604968826052;11629.534818607499 17:45:54 INFO - PROCESS | 1652 | __end_tp_report 17:45:54 INFO - PROCESS | 1652 | __start_cc_report 17:45:54 INFO - PROCESS | 1652 | _x_x_mozilla_cycle_collect,1916 17:45:54 INFO - PROCESS | 1652 | __end_cc_report 17:45:54 INFO - PROCESS | 1652 | __startTimestamp1476319554909__endTimestamp 17:45:54 INFO - PROCESS | 1652 | 17:45:54 INFO - PROCESS | 1652 | ------- Summary: start ------- 17:45:54 INFO - PROCESS | 1652 | Number of tests: 6 17:45:54 INFO - PROCESS | 1652 | 17:45:54 INFO - PROCESS | 1652 | [#0] dojo.html Cycles:165 Average:5699.06 Median:5923.97 stddev:644.05 (10.9%) stddev-sans-first:606.65 17:45:54 INFO - PROCESS | 1652 | Values: 2872.2 3350.9 3366.9 3381.7 3424.6 4649.7 5543.1 5688.3 5755.3 5766.2 5658.4 5672.4 5694.5 5714.9 5761.8 5147.1 5154.6 5164.8 5217.5 5236.3 5291.7 5342.6 5344.2 5345.9 5364.0 5141.2 5183.9 5262.8 5299.3 5348.2 5207.1 5226.1 5226.5 5235.3 5239.5 5158.9 5189.4 5202.7 5218.3 5219.3 5674.7 5680.0 5680.2 5688.4 5695.6 6128.0 6142.1 6156.3 6168.4 6225.5 6183.0 6205.4 6271.8 6278.9 6283.2 4823.8 5747.9 5769.0 5778.5 5834.2 6216.8 6233.6 6274.4 6297.6 6299.8 6157.8 6160.0 6164.2 6166.0 6171.4 6264.9 6268.8 6288.7 6293.4 6300.2 5967.4 6101.8 6123.8 6227.4 6239.9 6104.4 6140.7 6197.2 6257.5 6266.5 4949.1 4960.8 4961.8 4963.5 4992.0 4387.0 4414.1 4416.8 4419.2 4425.1 5609.5 5672.2 5686.5 5708.9 5735.0 5542.6 5972.4 6226.0 6229.0 6232.4 5724.1 5728.7 5733.6 5735.2 5742.3 5297.9 5326.1 5347.5 5350.4 5357.4 6262.2 6263.2 6284.5 6284.9 6291.5 6185.7 6188.1 6221.3 6233.5 6235.8 5216.9 6242.6 6307.4 6317.1 6328.7 5244.9 6161.1 6171.7 6175.1 6188.4 6030.2 6069.9 6129.7 6187.7 6241.3 5908.1 5984.7 5988.8 6085.3 6132.6 6032.8 6039.4 6096.4 6099.9 6111.6 5792.4 5900.5 5924.0 5929.8 5930.8 5150.5 5954.6 6003.8 6092.8 6115.2 5949.0 5955.9 5974.4 6003.2 6011.0 17:45:54 INFO - PROCESS | 1652 | 17:45:54 INFO - PROCESS | 1652 | [#1] ext.html Cycles:180 Average:69183.11 Median:36541.95 stddev:154810.82 (423.7%) stddev-sans-first:155159.11 17:45:54 INFO - PROCESS | 1652 | Values: 456.0 471.9 473.0 522.4 529.3 29839.9 30199.8 31223.4 31255.4 31274.7 31275.9 39038.8 40136.8 40145.0 42525.6 7923.0 9511.8 9514.4 9516.0 9527.3 9264.5 9278.3 9405.2 9408.7 9435.7 8528.8 8753.8 8755.1 8851.4 8883.2 11109.2 11397.9 11435.3 11640.3 11641.6 11249.0 11281.7 11361.5 11403.2 11452.1 36663.5 38175.4 38321.2 38368.4 38563.2 3079.4 3465.4 3526.9 3557.9 4036.7 29732.7 30146.8 30830.0 30966.7 32127.4 3190.5 3202.5 3221.6 3246.3 3332.9 494055.0 495658.0 497931.0 498272.0 504546.0 53929.5 54626.8 56110.7 58496.0 58959.0 32727.0 33570.8 33783.2 56799.4 62073.4 15557.2 15927.4 15969.8 15983.0 15984.0 12144.1 12266.4 12273.6 12275.1 12295.8 17419.7 18150.7 18150.7 18166.0 18182.3 6298.8 6525.4 7467.1 7539.7 7661.1 848.8 921.9 1017.0 1035.8 1064.9 28784.1 28993.9 29346.6 29650.9 29762.3 951.5 1012.2 1024.9 1073.6 1083.2 14235.1 15058.4 15132.7 15292.4 15429.5 634252.0 644640.0 646802.0 647578.0 648376.0 47994.8 48722.3 54669.2 55263.7 55394.4 1172.7 1189.5 1318.6 1321.2 1361.3 29793.0 29932.1 30164.8 30231.4 30490.5 46510.8 46985.8 47068.0 47150.5 47208.3 469832.0 474506.0 619670.0 650031.0 668159.0 38922.0 39174.6 39253.0 39360.7 39577.4 40228.0 42187.8 44136.0 44256.1 44917.8 31198.5 36552.6 36949.8 37751.4 38246.0 31037.7 31546.7 32136.8 36531.3 37090.1 36176.6 36619.0 36936.0 37008.0 37105.1 30033.7 32484.0 32557.8 32765.5 33102.0 23905.2 24378.4 24513.8 24599.5 24784.5 17:45:54 INFO - PROCESS | 1652 | 17:45:54 INFO - PROCESS | 1652 | [#2] jquery.html Cycles:180 Average:33360.26 Median:353.55 stddev:91779.58 (25959.4%) stddev-sans-first:92003.57 17:45:54 INFO - PROCESS | 1652 | Values: 344.4 362.7 363.6 371.5 392.9 15010.2 15190.3 16082.2 20377.5 20931.3 19053.3 19337.9 20232.9 20441.3 21111.8 5691.6 5996.1 5996.8 6011.2 6012.5 4937.6 6580.2 6654.3 6691.3 6712.9 5766.8 6097.1 6626.7 6649.0 6671.0 6045.7 6138.1 6142.7 6145.8 6146.4 5819.9 5835.1 5919.0 5980.0 5997.3 12114.7 12288.3 12293.4 21203.0 25273.5 1789.6 2051.2 2101.4 2107.5 2280.0 9813.7 9983.0 10042.0 10586.2 10641.7 1822.3 1892.4 1899.9 1923.1 1924.1 119022.0 125015.0 125547.0 128603.0 130382.0 10934.6 11365.9 11442.5 11460.8 11668.4 14376.8 14398.0 14449.9 14469.8 14490.4 8155.5 8420.2 8432.9 8573.8 8624.7 7995.3 8299.1 8311.0 8479.1 8488.4 4408.9 4500.1 4515.3 4597.9 4644.4 1548.5 1902.3 1905.2 1908.5 1911.0 831.1 872.9 876.2 928.9 947.7 16883.7 18703.3 19147.0 21056.5 21831.0 868.8 923.6 932.8 979.2 983.3 10199.0 11371.0 11535.4 11585.8 11680.1 525749.0 537275.6 542429.0 560070.0 562581.0 89089.7 118615.0 127781.7 133222.7 138421.0 903.3 952.7 969.8 971.2 975.4 18610.4 19415.8 19572.1 19585.8 19595.2 51282.0 54019.5 54032.0 55620.7 55671.8 75437.0 76153.6 80524.2 83801.6 84705.0 7832.3 7898.8 8146.9 8187.9 8324.3 12472.3 12535.5 12772.7 13118.1 13297.1 9086.7 10724.2 10745.0 11476.2 11603.5 10771.8 10880.8 11032.6 11180.9 11396.6 11036.8 11289.4 11582.7 11779.9 11948.5 6538.7 6872.7 7511.4 7952.9 8320.3 9610.1 11250.4 11414.9 11455.2 11479.1 17:45:54 INFO - PROCESS | 1652 | 17:45:54 INFO - PROCESS | 1652 | [#3] mootools.html Cycles:180 Average:5650.34 Median:5066.53 stddev:3177.62 (62.7%) stddev-sans-first:3171.23 17:45:54 INFO - PROCESS | 1652 | Values: 1500.1 1509.8 1713.4 1899.7 1911.3 8453.6 9000.6 9007.1 9012.9 9054.2 7785.8 8100.4 8110.9 8501.6 8772.1 2305.1 2338.5 2341.6 2341.9 2342.7 2317.8 2323.7 2365.8 2369.3 2369.7 1915.6 1990.4 2350.7 2361.2 2367.4 2316.7 2328.0 2332.9 2333.1 2337.8 2006.2 2285.5 2316.6 2318.1 2318.7 8843.4 8916.9 8989.1 8991.8 9008.4 5007.5 5162.8 5168.1 5172.4 5189.2 3450.7 3514.3 3666.7 3670.3 3673.6 1106.2 1108.6 1760.2 1830.7 1874.3 11335.5 11649.2 11690.0 11708.8 11763.4 4367.3 4461.6 4627.3 4628.3 4630.7 9156.3 9178.9 9184.3 9186.2 9199.6 5197.8 5200.3 5205.7 5216.3 5216.7 4341.4 4492.8 4510.4 4687.2 4880.9 3659.1 3660.2 3791.2 3797.5 3823.5 1121.5 1145.6 1147.4 1150.6 1159.4 762.0 892.5 912.4 922.3 974.3 5052.9 5392.0 5468.0 5830.7 6117.5 891.4 904.4 933.8 946.1 949.9 3559.6 3852.9 3943.5 3972.6 4020.4 10301.7 10832.7 10844.6 10862.2 10903.3 10783.7 10794.6 10802.7 10818.3 10823.4 8106.4 8179.3 8205.5 8266.3 8270.5 4958.9 5669.1 5775.7 5958.9 5976.7 9941.5 10039.2 10041.6 10070.3 10081.5 9844.0 10239.4 10382.6 10419.2 10439.1 6484.2 6730.9 6950.4 7063.3 7072.9 7928.8 8125.1 8280.5 8464.2 8573.2 7389.4 7467.2 7473.3 7533.1 7667.0 7328.1 7336.6 7385.8 7518.6 7539.1 5267.4 5601.0 6918.7 6977.4 6979.7 5080.2 5132.3 5177.6 5229.0 5364.2 5139.4 5206.9 5208.3 5216.7 5411.2 17:45:54 INFO - PROCESS | 1652 | 17:45:54 INFO - PROCESS | 1652 | [#4] prototype.html Cycles:180 Average:4377.96 Median:4745.48 stddev:1374.61 (29.0%) stddev-sans-first:1343.85 17:45:54 INFO - PROCESS | 1652 | Values: 294.1 294.3 305.4 309.1 310.9 5309.8 5338.5 5340.7 5365.5 5377.5 5006.0 5157.5 5167.6 5218.6 5288.2 3981.9 4005.2 4022.1 4027.5 4032.6 3615.6 3881.9 3899.3 4069.8 4122.0 3808.5 3927.8 3997.5 4010.5 4095.0 3348.6 4012.4 4065.6 4070.6 4071.4 2881.4 3456.9 3472.5 3475.5 3491.0 4107.4 4920.7 5197.4 5224.7 5243.6 4512.6 4514.8 4555.3 4561.7 4592.6 5561.1 5578.4 5580.9 5595.5 5598.2 3081.8 3495.0 3814.4 3823.3 3833.6 4911.8 5847.7 5850.7 5863.8 5891.2 3493.7 3583.8 3819.3 3821.8 3849.2 3161.6 3899.1 3906.2 3930.4 3937.5 3819.4 4295.9 4446.5 4604.7 4646.6 5227.4 5333.0 5634.6 5647.3 5662.2 1256.8 1317.5 1335.7 1336.1 1341.7 834.4 855.2 856.0 856.4 862.0 4156.0 4276.9 4465.4 4578.7 4619.7 4333.6 4777.4 4781.4 4817.3 4874.7 3870.6 4013.2 4255.0 4369.5 4554.4 2029.4 2321.9 2329.9 2586.8 2596.7 4077.2 4477.2 4627.4 4845.6 5093.7 4713.6 4873.0 4943.8 5425.4 5974.4 6038.9 6051.3 6080.3 6090.4 6122.9 4521.6 4938.4 5437.8 5891.1 5912.6 5048.2 5175.2 5337.4 5755.0 5816.7 4889.9 5026.5 6078.2 6082.2 6091.7 3845.5 3874.6 3881.0 3893.8 3905.1 4369.9 5087.7 5414.0 5543.6 5768.8 5142.0 5562.7 5592.9 5638.7 5653.5 4689.9 4815.1 5093.1 5584.6 5605.7 4625.7 5516.3 5564.4 5577.9 5587.4 4969.6 5421.9 5449.0 5450.0 5463.3 4310.6 5373.4 5401.1 5511.7 5543.6 17:45:54 INFO - PROCESS | 1652 | 17:45:54 INFO - PROCESS | 1652 | [#5] yui.html Cycles:180 Average:25032.15 Median:3463.69 stddev:41228.62 (1190.3%) stddev-sans-first:41303.82 17:45:54 INFO - PROCESS | 1652 | Values: 705.2 721.2 722.5 725.0 776.2 15039.9 15415.7 15418.2 15442.6 15491.9 13968.7 13973.8 14004.6 14029.0 14430.9 668.9 684.5 821.3 827.4 844.4 833.3 846.0 851.0 853.5 854.4 904.2 905.6 907.6 907.6 908.8 692.8 708.5 896.2 896.4 898.0 560.2 642.1 658.4 667.0 668.3 14561.6 14620.3 14633.3 14639.3 14680.1 12309.8 12591.6 13234.3 13471.9 13938.2 12159.3 12210.1 12309.4 12465.2 12650.4 4389.1 4404.4 4414.7 4602.5 4604.3 111338.0 114565.0 117181.0 118536.4 118798.4 9214.5 10690.7 10837.9 10993.2 11120.2 20972.3 20976.3 20979.0 21003.8 21005.6 7586.4 7814.2 7819.7 7855.9 7920.0 4211.6 4316.9 4337.5 4387.7 4477.7 2987.0 3459.9 3462.2 3463.2 3464.2 658.8 663.2 669.2 669.3 669.5 561.3 574.9 609.7 620.8 624.6 8239.7 8510.0 8578.7 8750.8 8971.8 525.9 543.7 557.7 595.7 617.4 4203.7 4258.3 4389.6 4499.2 4576.2 149168.0 151760.0 154847.2 155466.0 156782.2 134940.7 135530.0 137475.3 137677.0 143189.0 87070.0 100302.0 102344.0 104092.5 106053.5 7586.0 7668.5 8374.0 8471.2 8487.3 65447.0 66412.7 66588.7 66913.3 67084.0 99134.0 101034.0 101580.0 101659.0 101906.5 11085.3 11162.9 11206.4 11226.7 11452.0 11907.8 11909.2 12105.5 12145.4 12227.1 9441.9 10104.8 10954.0 11511.0 11789.9 10814.3 11433.4 11442.6 11492.3 12010.9 11257.2 11312.8 11319.9 11538.4 11599.1 9180.2 9678.4 9738.0 10078.5 10478.9 9479.3 10263.7 10902.8 11286.6 11629.5 17:45:54 INFO - PROCESS | 1652 | -------- Summary: end -------- 17:45:54 INFO - PROCESS | 1652 | 17:45:55 INFO - TEST-INFO | 1652: exit 0 17:45:55 INFO - TEST-OK | dromaeo_css | took 1119273ms 17:45:55 INFO - TEST-START | kraken 17:45:55 INFO - operating with platform_type : mac_ 17:45:55 INFO - Initialising browser for kraken test... 17:45:55 INFO - TEST-INFO | started process 1663 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/tmp7LnAvD/profile http://localhost:49272/getInfo.html) 17:45:57 INFO - PROCESS | 1663 | __metrics Screen width/height:1600/1200 17:45:57 INFO - PROCESS | 1663 | colorDepth:24 17:45:57 INFO - PROCESS | 1663 | Browser inner width/height: 1024/689 17:45:57 INFO - PROCESS | 1663 | __metrics 17:45:58 INFO - PROCESS | 1663 | 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" 17:45:58 INFO - PROCESS | 1663 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 181: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 17:45:58 INFO - TEST-INFO | 1663: exit 0 17:45:58 INFO - Browser initialized. 17:45:58 INFO - Running cycle 1/1 for kraken test... 17:45:58 INFO - TEST-INFO | started process 1665 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/tmp7LnAvD/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 17:46:10 INFO - PROCESS | 1665 | RSS: Main: 243134464 17:46:10 INFO - PROCESS | 1665 | 17:46:13 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49272/tests/kraken/driver.html?testName=audio-beat-detection) 17:46:14 INFO - PROCESS | 1665 | RSS: Main: 294096896 17:46:14 INFO - PROCESS | 1665 | 17:46:17 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49272/tests/kraken/driver.html?testName=audio-dft) 17:46:17 INFO - PROCESS | 1665 | RSS: Main: 293777408 17:46:17 INFO - PROCESS | 1665 | 17:46:21 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49272/tests/kraken/driver.html?testName=audio-fft) 17:46:21 INFO - PROCESS | 1665 | RSS: Main: 383062016 17:46:21 INFO - PROCESS | 1665 | 17:46:23 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49272/tests/kraken/driver.html?testName=audio-oscillator) 17:46:24 INFO - PROCESS | 1665 | RSS: Main: 284286976 17:46:24 INFO - PROCESS | 1665 | 17:46:26 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49272/tests/kraken/driver.html?testName=imaging-gaussian-blur) 17:46:27 INFO - PROCESS | 1665 | RSS: Main: 288309248 17:46:27 INFO - PROCESS | 1665 | 17:46:29 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49272/tests/kraken/driver.html?testName=imaging-darkroom) 17:46:30 INFO - PROCESS | 1665 | RSS: Main: 326447104 17:46:30 INFO - PROCESS | 1665 | 17:46:33 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49272/tests/kraken/driver.html?testName=imaging-desaturate) 17:46:34 INFO - PROCESS | 1665 | RSS: Main: 327458816 17:46:34 INFO - PROCESS | 1665 | 17:46:36 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49272/tests/kraken/driver.html?testName=json-parse-financial) 17:46:37 INFO - PROCESS | 1665 | RSS: Main: 327393280 17:46:37 INFO - PROCESS | 1665 | 17:46:38 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49272/tests/kraken/driver.html?testName=json-stringify-tinderbox) 17:46:39 INFO - PROCESS | 1665 | RSS: Main: 300982272 17:46:39 INFO - PROCESS | 1665 | 17:46:41 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-aes) 17:46:42 INFO - PROCESS | 1665 | RSS: Main: 306442240 17:46:42 INFO - PROCESS | 1665 | 17:46:44 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-ccm) 17:46:44 INFO - PROCESS | 1665 | RSS: Main: 312238080 17:46:44 INFO - PROCESS | 1665 | 17:46:48 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 17:46:48 INFO - PROCESS | 1665 | RSS: Main: 320798720 17:46:48 INFO - PROCESS | 1665 | 17:46:51 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 17:46:52 INFO - PROCESS | 1665 | RSS: Main: 320180224 17:46:52 INFO - PROCESS | 1665 | 17:46:54 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49272/tests/kraken/driver.html?testName=ai-astar) 17:46:54 INFO - PROCESS | 1665 | RSS: Main: 364335104 17:46:54 INFO - PROCESS | 1665 | 17:46:54 INFO - PROCESS | 1665 | __start_tp_report 17:46:54 INFO - PROCESS | 1665 | _x_x_mozilla_page_load 17:46:54 INFO - PROCESS | 1665 | _x_x_mozilla_page_load_details 17:46:54 INFO - PROCESS | 1665 | |i|pagename|runs| 17:46:54 INFO - PROCESS | 1665 | |0;ai-astar;113;112;114;112;113;114;112;112;112;112 17:46:54 INFO - PROCESS | 1665 | |1;audio-beat-detection;147;112;114;150;111;114;114;112;142;112 17:46:54 INFO - PROCESS | 1665 | |2;audio-dft;184;184;254;225;184;182;178;177;185;183 17:46:54 INFO - PROCESS | 1665 | |3;audio-fft;76;56;54;83;67;53;82;82;108;56 17:46:54 INFO - PROCESS | 1665 | |4;audio-oscillator;86;71;93;70;69;69;93;67;68;91 17:46:54 INFO - PROCESS | 1665 | |5;imaging-gaussian-blur;88;89;136;87;89;85;89;87;90;85 17:46:54 INFO - PROCESS | 1665 | |6;imaging-darkroom;93;93;93;93;93;93;93;95;93;93 17:46:54 INFO - PROCESS | 1665 | |7;imaging-desaturate;126;132;93;84;86;84;84;83;84;84 17:46:54 INFO - PROCESS | 1665 | |8;json-parse-financial;57;54;55;54;55;55;56;55;54;55 17:46:54 INFO - PROCESS | 1665 | |9;json-stringify-tinderbox;69;70;68;80;71;72;69;69;70;69 17:46:54 INFO - PROCESS | 1665 | |10;stanford-crypto-aes;87;90;90;90;86;85;86;89;87;91 17:46:54 INFO - PROCESS | 1665 | |11;stanford-crypto-ccm;158;161;143;147;146;142;146;144;198;162 17:46:54 INFO - PROCESS | 1665 | |12;stanford-crypto-pbkdf2;171;171;174;177;174;173;171;224;173;169 17:46:54 INFO - PROCESS | 1665 | |13;stanford-crypto-sha256-iterative;67;69;69;69;67;68;69;66;69;69 17:46:54 INFO - PROCESS | 1665 | __end_tp_report 17:46:54 INFO - PROCESS | 1665 | __start_cc_report 17:46:54 INFO - PROCESS | 1665 | _x_x_mozilla_cycle_collect,1959 17:46:54 INFO - PROCESS | 1665 | __end_cc_report 17:46:54 INFO - PROCESS | 1665 | __startTimestamp1476319614336__endTimestamp 17:46:54 INFO - PROCESS | 1665 | 17:46:54 INFO - PROCESS | 1665 | ------- Summary: start ------- 17:46:54 INFO - PROCESS | 1665 | Number of tests: 14 17:46:54 INFO - PROCESS | 1665 | 17:46:54 INFO - PROCESS | 1665 | [#0] ai-astar Cycles:10 Average:112.60 Median:112.50 stddev:0.84 (0.7%) stddev-sans-first:0.88 17:46:54 INFO - PROCESS | 1665 | Values: 113.0 112.0 114.0 112.0 113.0 114.0 112.0 112.0 112.0 112.0 17:46:54 INFO - PROCESS | 1665 | 17:46:54 INFO - PROCESS | 1665 | [#1] audio-beat-detection Cycles:10 Average:122.80 Median:114.00 stddev:16.38 (14.4%) stddev-sans-first:14.85 17:46:54 INFO - PROCESS | 1665 | Values: 147.0 112.0 114.0 150.0 111.0 114.0 114.0 112.0 142.0 112.0 17:46:54 INFO - PROCESS | 1665 | 17:46:54 INFO - PROCESS | 1665 | [#2] audio-dft Cycles:10 Average:193.60 Median:184.00 stddev:25.28 (13.7%) stddev-sans-first:26.57 17:46:54 INFO - PROCESS | 1665 | Values: 184.0 184.0 254.0 225.0 184.0 182.0 178.0 177.0 185.0 183.0 17:46:54 INFO - PROCESS | 1665 | 17:46:54 INFO - PROCESS | 1665 | [#3] audio-fft Cycles:10 Average:71.70 Median:71.50 stddev:17.81 (24.9%) stddev-sans-first:18.82 17:46:54 INFO - PROCESS | 1665 | Values: 76.0 56.0 54.0 83.0 67.0 53.0 82.0 82.0 108.0 56.0 17:46:54 INFO - PROCESS | 1665 | 17:46:54 INFO - PROCESS | 1665 | [#4] audio-oscillator Cycles:10 Average:77.70 Median:78.50 stddev:11.44 (14.6%) stddev-sans-first:11.73 17:46:54 INFO - PROCESS | 1665 | Values: 86.0 71.0 93.0 70.0 69.0 69.0 93.0 67.0 68.0 91.0 17:46:54 INFO - PROCESS | 1665 | 17:46:54 INFO - PROCESS | 1665 | [#5] imaging-gaussian-blur Cycles:10 Average:92.50 Median:88.50 stddev:15.38 (17.4%) stddev-sans-first:16.22 17:46:54 INFO - PROCESS | 1665 | Values: 88.0 89.0 136.0 87.0 89.0 85.0 89.0 87.0 90.0 85.0 17:46:54 INFO - PROCESS | 1665 | 17:46:54 INFO - PROCESS | 1665 | [#6] imaging-darkroom Cycles:10 Average:93.20 Median:93.00 stddev:0.63 (0.7%) stddev-sans-first:0.67 17:46:54 INFO - PROCESS | 1665 | Values: 93.0 93.0 93.0 93.0 93.0 93.0 93.0 95.0 93.0 93.0 17:46:54 INFO - PROCESS | 1665 | 17:46:54 INFO - PROCESS | 1665 | [#7] imaging-desaturate Cycles:10 Average:94.00 Median:84.00 stddev:18.72 (22.3%) stddev-sans-first:15.88 17:46:54 INFO - PROCESS | 1665 | Values: 126.0 132.0 93.0 84.0 86.0 84.0 84.0 83.0 84.0 84.0 17:46:54 INFO - PROCESS | 1665 | 17:46:54 INFO - PROCESS | 1665 | [#8] json-parse-financial Cycles:10 Average:55.00 Median:55.00 stddev:0.94 (1.7%) stddev-sans-first:0.67 17:46:54 INFO - PROCESS | 1665 | Values: 57.0 54.0 55.0 54.0 55.0 55.0 56.0 55.0 54.0 55.0 17:46:54 INFO - PROCESS | 1665 | 17:46:54 INFO - PROCESS | 1665 | [#9] json-stringify-tinderbox Cycles:10 Average:70.70 Median:70.00 stddev:3.47 (5.0%) stddev-sans-first:3.62 17:46:54 INFO - PROCESS | 1665 | Values: 69.0 70.0 68.0 80.0 71.0 72.0 69.0 69.0 70.0 69.0 17:46:54 INFO - PROCESS | 1665 | 17:46:54 INFO - PROCESS | 1665 | [#10] stanford-crypto-aes Cycles:10 Average:88.10 Median:89.50 stddev:2.13 (2.4%) stddev-sans-first:2.22 17:46:54 INFO - PROCESS | 1665 | Values: 87.0 90.0 90.0 90.0 86.0 85.0 86.0 89.0 87.0 91.0 17:46:54 INFO - PROCESS | 1665 | 17:46:54 INFO - PROCESS | 1665 | [#11] stanford-crypto-ccm Cycles:10 Average:154.70 Median:152.50 stddev:17.00 (11.1%) stddev-sans-first:17.99 17:46:54 INFO - PROCESS | 1665 | Values: 158.0 161.0 143.0 147.0 146.0 142.0 146.0 144.0 198.0 162.0 17:46:54 INFO - PROCESS | 1665 | 17:46:54 INFO - PROCESS | 1665 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:177.70 Median:173.50 stddev:16.42 (9.5%) stddev-sans-first:17.23 17:46:54 INFO - PROCESS | 1665 | Values: 171.0 171.0 174.0 177.0 174.0 173.0 171.0 224.0 173.0 169.0 17:46:54 INFO - PROCESS | 1665 | 17:46:54 INFO - PROCESS | 1665 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.20 Median:69.00 stddev:1.14 (1.6%) stddev-sans-first:1.12 17:46:54 INFO - PROCESS | 1665 | Values: 67.0 69.0 69.0 69.0 67.0 68.0 69.0 66.0 69.0 69.0 17:46:54 INFO - PROCESS | 1665 | -------- Summary: end -------- 17:46:54 INFO - PROCESS | 1665 | 17:46:55 INFO - TEST-INFO | 1665: exit 0 17:46:55 INFO - TEST-OK | kraken | took 59543ms 17:46:55 INFO - SUITE-END | took 1178s 17:46:55 INFO - Completed test suite (00:19:39) 17:46:55 INFO - javascript benchmark 17:46:55 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2872.238856702974, 3350.9162270943225, 3366.915827104322, 3381.7125544328733, 3424.589049314083, 4649.674522783403, 5543.057680194366, 5688.345840228375, 5755.251817263755, 5766.192733017376, 5658.405867383917, 5672.376038635747, 5694.4590263924865, 5714.942850571503, 5761.827145185651, 5147.073526765181, 5154.561862241714, 5164.845054648366, 5217.530422261995, 5236.345456817898, 5291.656042357254, 5342.626016178869, 5344.198370244475, 5345.919811202835, 5363.973180134113, 5141.15170996785, 5183.937292854956, 5262.81580144695, 5299.284596579451, 5348.197770334442, 5207.0887594670785, 5226.111561034633, 5226.477352264743, 5235.345581802275, 5239.476052394768, 5158.871028224287, 5189.351331083614, 5202.68783872965, 5218.321618189649, 5219.347581552306, 5674.659520428746, 5679.971600142014, 5680.176374425685, 5688.402717714622, 5695.629784064064, 6128.0169984388185, 6142.140100385905, 6156.322804491502, 6168.414000669929, 6225.470834979033, 6182.96908515459, 6205.379462053804, 6271.811845644638, 6278.87442251157, 6283.151774510474, 4823.782929768169, 5747.8563035924435, 5769.0, 5778.508826749689, 5834.154047663107, 6216.844578885564, 6233.563650544418, 6274.403931626488, 6297.622142671455, 6299.779507717208, 6157.753689852445, 6160.0, 6164.229471316085, 6165.969170154121, 6171.382861713792, 6264.906026409608, 6268.780592679234, 6288.717007734663, 6293.433590976834, 6300.180976473121, 5967.403259673998, 6101.84745381369, 6123.846903827351, 6227.439530442191, 6239.937600623935, 6104.428839602322, 6140.749659041545, 6197.194364732556, 6257.499400047986, 6266.467350275187, 4949.109160351171, 4960.751962401865, 4961.801527938842, 4963.503649635008, 4991.950080499221, 4387.034852332482, 4414.050979039522, 4416.757078360657, 4419.18245124653, 4425.070735145591, 5609.467100625434, 5672.234248376416, 5686.459786320293, 5708.857278568069, 5734.971325143348, 5542.63972841764, 5972.432618901197, 6226.0, 6229.034499652561, 6232.407921247474, 5724.112762521816, 5728.684922329312, 5733.627314224562, 5735.2257445245195, 5742.253507044141, 5297.920531192035, 5326.094563924142, 5347.51872331492, 5350.384705758887, 5357.357117145967, 6262.15460912771, 6263.185785847902, 6284.528660350584, 6284.905726414016, 6291.465225455796, 6185.659788711664, 6188.102725104744, 6221.2845522763655, 6233.501319894488, 6235.781747638993, 5216.921746173734, 6242.625442473466, 6307.400796924285, 6317.115603815378, 6328.746850125944, 5244.947550524446, 6161.075838624063, 6171.722272497838, 6175.135481032748, 6188.412100850412, 6030.216071910623, 6069.9377608917375, 6129.664444256008, 6187.659678717714, 6241.3134555197985, 5908.05471124618, 5984.700764961647, 5988.760449582142, 6085.330613632408, 6132.63204207749, 6032.849178770566, 6039.4262545058145, 6096.359882212481, 6099.8780024398375, 6111.633302001894, 5792.4497172768515, 5900.498457631149, 5923.970380148072, 5929.762809487572, 5930.792422265373, 5150.48495150482, 5954.583179177416, 6003.7898673546215, 6092.817215483543, 6115.174451449176, 5949.0481522956115, 5955.880882382415, 5974.40255974399, 6003.219581454558, 6011.008183649649], "value": 5658.3463725690135, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [456.0104573076426, 471.94808571057155, 472.98108075676976, 522.4305506997372, 529.2537522093849, 29839.850800745993, 30199.84900075499, 31223.375532489335, 31255.374892502135, 31274.68725312752, 31275.90534331299, 39038.80480597593, 40136.79931600338, 40145.0, 42525.574744252546, 7922.9603851980655, 9511.80976380472, 9514.429134251932, 9515.952420237889, 9527.333086683935, 9264.49045302508, 9278.304127190488, 9405.247580193602, 9408.670696525623, 9435.669751558697, 8528.829423411558, 8753.824923501526, 8755.124487551226, 8851.380403371766, 8883.200511953923, 11109.166812489095, 11397.943010284978, 11435.256708313933, 11640.301581905112, 11641.59254426091, 11249.0, 11281.71795705114, 11361.545538178463, 11403.201775875692, 11452.083833293316, 36663.45004824903, 38175.427368589495, 38321.23357532862, 38368.42447363292, 38563.228735425415, 3079.414911166871, 3465.358908601917, 3526.8589256429714, 3557.94663080054, 4036.7174297799165, 29732.702672973424, 30146.84926575353, 30830.0, 30966.69033309683, 32127.35745285081, 3190.5054716518976, 3202.4876019836715, 3221.5811944447305, 3246.2675403165645, 3332.8833490827947, 494055.0, 495658.0, 497931.0, 498272.0, 504546.0, 53929.46070539244, 54626.81492740246, 56110.719446403324, 58496.0, 58959.0, 32727.0, 33570.832145839115, 33783.15542111418, 56799.43200568024, 62073.37926620767, 15557.22213889301, 15927.442539511061, 15969.840301596836, 15983.041017538984, 15984.0, 12144.14990950625, 12266.448009839578, 12273.570425035081, 12275.140740148105, 12295.815562766566, 17419.65160696779, 18150.727739083923, 18150.727739083923, 18166.000869952015, 18182.27270909149, 6298.7795427160745, 6525.412712855866, 7467.066616672916, 7539.736109236151, 7661.118971318258, 848.8175042365921, 921.9032001639865, 1017.0214665945645, 1035.77213013137, 1064.9254552181428, 28784.136475905758, 28993.85503072513, 29346.559801602994, 29650.851745741133, 29762.255943601584, 951.5052172870069, 1012.1599072769584, 1024.9282550221562, 1073.613499140309, 1083.2254937719567, 14235.145891246559, 15058.397664093533, 15132.697346053017, 15292.388304467697, 15429.537113886601, 634252.0, 644640.0, 646802.0, 647578.0, 648376.0, 47994.76002619965, 48722.26916596254, 54669.1799623006, 55263.723681381336, 55394.44605553974, 1172.724409763722, 1189.4885199364182, 1318.643966129159, 1321.1610627251764, 1361.2717196300327, 29792.95724649627, 29932.102036938926, 30164.825013856367, 30231.395372092877, 30490.542641859945, 46510.76744616255, 46985.76507117443, 47068.0, 47150.528494714614, 47208.29187562121, 469832.0, 474506.0, 619670.0, 650031.0, 668159.0, 38922.0, 39174.6082539171, 39253.0, 39360.65434438105, 39577.406338905515, 40228.0, 42187.7890610545, 44136.0, 44256.11487770291, 44917.77541112273, 31198.532022019233, 36552.63447365492, 36949.83624890206, 37751.433728494645, 38246.0, 31037.689623104383, 31546.68759780282, 32136.83931580327, 36531.269374611824, 37090.07274818151, 36176.63823361733, 36619.0, 36936.0, 37008.0, 37105.0723731898, 30033.699663003965, 32484.02547923566, 32557.837210813796, 32765.508517372735, 33102.0, 23905.16331928374, 24378.390540236636, 24513.829781939534, 24599.50800984006, 24784.50430991406], "value": 19371.24319819277, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [344.42996840229426, 362.6699703270025, 363.6218332933749, 371.53001453161755, 392.9253441846049, 15010.174440405774, 15190.31643576039, 16082.195890205487, 20377.49056273594, 20931.267405640767, 19053.333133340337, 19337.903310483427, 20232.887191204478, 20441.284555040576, 21111.838848863346, 5691.601587888839, 5996.130561068642, 5996.760129594811, 6011.248593925759, 6012.549058820584, 4937.604991600663, 6580.210374755012, 6654.26803051664, 6691.26396096429, 6712.865742685168, 5766.769329226826, 6097.115918191859, 6626.7017984190825, 6649.035889795977, 6671.0, 6045.697715114226, 6138.109974053782, 6142.662153581551, 6145.754169833202, 6146.446819786196, 5819.883602327972, 5835.124731290298, 5918.970405147989, 5979.970100149515, 5997.250343707036, 12114.697132571668, 12288.324142172178, 12293.446794894251, 21203.0, 25273.494530109478, 1789.579448829524, 2051.200031987526, 2101.401100686297, 2107.525806693497, 2280.0, 9813.705588832347, 9983.0, 10042.0, 10586.153107751361, 10641.680749577525, 1822.3166312632763, 1892.3660573707825, 1899.9265415040459, 1923.1249781349452, 1924.0860591219198, 119022.0, 125015.0, 125547.0, 128603.0, 130382.0, 10934.56261749537, 11365.886341136647, 11442.542298307975, 11460.770784584261, 11668.358240296866, 14376.784348234785, 14397.99214055006, 14449.855501445061, 14469.78295325571, 14490.420383184766, 8155.469894456842, 8420.24217820387, 8432.873506897402, 8573.828523429622, 8624.655013799378, 7995.2804247618, 8299.052403810378, 8310.958445207736, 8479.109693482096, 8488.363372746946, 4408.9118217636105, 4500.122476117126, 4515.2908255046705, 4597.931031034537, 4644.396228490342, 1548.49673855995, 1902.2771346888371, 1905.1522072677526, 1908.532409559639, 1911.0, 831.1397703377097, 872.9388942774071, 876.1961722488064, 928.9117533834275, 947.6872632031551, 16883.746743798853, 18703.25186992505, 19147.0, 21056.473588160417, 21831.0, 868.7576765225662, 923.5751554285104, 932.8274269260208, 979.1921664626711, 983.326207781099, 10199.0, 11371.0, 11535.365554894393, 11585.826212606818, 11680.123990700562, 525749.0, 537275.6272437226, 542429.0, 560070.0, 562581.0, 89089.66365504522, 118615.0, 127781.72218277698, 133222.66777332296, 138421.0, 903.3134817538718, 952.7046615549202, 969.7866469376726, 971.1599466461602, 975.4244995452717, 18610.44168674942, 19415.805841941965, 19572.11925463329, 19585.804141958397, 19595.216191352185, 51282.0, 54019.45980540144, 54032.0, 55620.721896390256, 55671.77312907438, 75437.0, 76153.61923190349, 80524.19475805167, 83801.58099209465, 84705.0, 7832.295093441618, 7898.763037108896, 8146.9185308148535, 8187.877181842158, 8324.292435143081, 12472.314022728475, 12535.498580056696, 12772.680682983, 13118.147320424141, 13297.135686180367, 9086.681966131153, 10724.195685323475, 10745.0, 11476.196666233283, 11603.5358585659, 10771.784564308826, 10880.80311165787, 11032.613858514913, 11180.888191117985, 11396.601118960796, 11036.834447483134, 11289.379084150121, 11582.6525204244, 11779.941100294443, 11948.462319195483, 6538.738450461929, 6872.69072891711, 7511.399088073051, 7952.920470795218, 8320.252736990707, 9610.135087842127, 11250.381229032482, 11414.94292528532, 11455.198136130392, 11479.139064570023], "value": 9385.477740616323, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1500.0549653718156, 1509.841466646002, 1713.4174380710556, 1899.7245399417077, 1911.340587497314, 8453.577321133942, 9000.639974401032, 9007.144321289483, 9012.864807027892, 9054.230390416822, 7785.766427007199, 8100.392470564717, 8110.91889081109, 8501.617427215775, 8772.122787721224, 2305.1125316753046, 2338.53229354129, 2341.6487526870937, 2341.9414514637183, 2342.672025916373, 2317.756635553268, 2323.7327707313707, 2365.7515960824126, 2369.30105618842, 2369.6919400477914, 1915.5977244778612, 1990.3531352310486, 2350.7296660883962, 2361.1735892437596, 2367.4081479630095, 2316.652502124678, 2328.0, 2332.93001209964, 2333.0900948629965, 2337.7662233776655, 2006.237629700719, 2285.48576570272, 2316.6061769499224, 2318.07277089164, 2318.7217533895873, 8843.380963332569, 8916.910830891673, 8989.101089891024, 8991.775205619912, 9008.369414141012, 5007.549320561167, 5162.819301324474, 5168.147255702842, 5172.379314482287, 5189.1697328427645, 3450.706689931359, 3514.2795726876184, 3666.7249956253386, 3670.284294562561, 3673.6142705015855, 1106.2201148190586, 1108.578740078773, 1760.2079064420934, 1830.7253911913187, 1874.2690350763214, 11335.489902954387, 11649.184557081007, 11690.0, 11708.824367634492, 11763.35301558414, 4367.323064924942, 4461.643068554507, 4627.30590411441, 4628.259478483426, 4630.652701047435, 9156.267498600097, 9178.86231706525, 9184.265258779282, 9186.173244408035, 9199.586018629177, 5197.8440646780655, 5200.2979597754575, 5205.713685747244, 5216.26972223889, 5216.739163041832, 4341.370501277297, 4492.775361231926, 4510.413646225969, 4687.156311863897, 4880.853574392774, 3659.121810765397, 3660.158163622382, 3791.2227993261317, 3797.5442946846556, 3823.483829683013, 1121.506537123663, 1145.6448500964727, 1147.428023607663, 1150.6433005768238, 1159.368144361325, 762.0093877958632, 892.4600616626977, 912.4388501071926, 922.3082687984012, 974.3277138774365, 5052.898942021213, 5391.975524650304, 5467.972660136754, 5830.7084645767545, 6117.541184411187, 891.3804905590682, 904.4076130134772, 933.780561568031, 946.0964778636386, 949.8670186173941, 3559.6473340130583, 3852.8651497198025, 3943.5464921533817, 3972.6226008529143, 4020.396940458896, 10301.742456438498, 10832.729181770519, 10844.620438284623, 10862.239643225057, 10903.34579925207, 10783.730406739895, 10794.622188223375, 10802.67591972242, 10818.323159910227, 10823.350598964087, 8106.4325497215805, 8179.304759095544, 8205.466644668077, 8266.338692904554, 8270.511307964503, 4958.8760280993265, 5669.149627555899, 5775.7400916957995, 5958.851028724143, 5976.731047102804, 9941.502924853872, 10039.196864250696, 10041.648542300984, 10070.295079344376, 10081.495925203564, 9843.950780246052, 10239.436830974368, 10382.636607718694, 10419.166466682798, 10439.060484556427, 6484.1894763154605, 6730.865382692221, 6950.409215216763, 7063.328983746535, 7072.929270707227, 7928.84142317162, 8125.106238313662, 8280.50316980983, 8464.153584641486, 8573.22840944318, 7389.408847292097, 7467.178610352966, 7473.252674732484, 7533.096028476618, 7667.00328957232, 7328.120625524971, 7336.633168341668, 7385.8153546161775, 7518.624068796647, 7539.0576177977755, 5267.446918073547, 5600.971995139998, 6918.7232510699005, 6977.372036516738, 6979.6859141337745, 5080.161773307511, 5132.3327967364, 5177.637565370388, 5229.0, 5364.195370694271, 5139.383274007143, 5206.921896171485, 5208.3333333334485, 5216.686998780086, 5411.214299281306], "value": 4563.314363008952, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [294.14110796474307, 294.34361374135676, 305.3953172718019, 309.11438728044146, 310.867881150511, 5309.8229725812425, 5338.466153384659, 5340.679559226449, 5365.463453654633, 5377.542908852743, 5005.974970125153, 5157.4842515748405, 5167.622893469009, 5218.556422704065, 5288.206768984644, 3981.900452488682, 4005.2390045891334, 4022.109608484008, 4027.5166979962446, 4032.5967403259733, 3615.6022837487853, 3881.922361552767, 3899.317619416605, 4069.7558146511305, 4121.9587804121875, 3808.4766093562657, 3927.7839718815453, 3997.5202975642965, 4010.4786377770847, 4094.97952510237, 3348.6483919188377, 4012.4181993610764, 4065.6137666921595, 4070.5725898780647, 4071.3511028033545, 2881.366099458116, 3456.9308613827834, 3472.461768425898, 3475.4960530723156, 3491.0, 4107.404426358191, 4920.729359885205, 5197.402298735656, 5224.746060945346, 5243.606729495303, 4512.593866552026, 4514.841980530666, 4555.2711566149255, 4561.677113637042, 4592.632589392841, 5561.137019098607, 5578.386377498472, 5580.888382232373, 5595.524380427669, 5598.24423702799, 3081.845907704606, 3495.038864312309, 3814.43608120672, 3823.3118038753014, 3833.5974722654278, 4911.803527858917, 5847.678377689225, 5850.736716847752, 5863.794767183171, 5891.204687367151, 3493.6855682988653, 3583.7670551414076, 3819.2743378757946, 3821.7515861468914, 3849.2493963676816, 3161.636411812671, 3899.083715326896, 3906.1797022625565, 3930.449737036817, 3937.468441760383, 3819.4270859370763, 4295.935560966588, 4446.533114022981, 4604.723716577016, 4646.628269738413, 5227.424983251839, 5332.9733351333, 5634.6337488063145, 5647.29408823897, 5662.197212379899, 1256.761215369077, 1317.538861398522, 1335.6861137632648, 1336.0847819243654, 1341.745068436994, 834.4104480963774, 855.2132038524572, 856.0369584217755, 856.4133568505594, 861.9956900215584, 4156.010230179001, 4276.87169384919, 4465.437097016018, 4578.656600754956, 4619.706482184994, 4333.566643335641, 4777.416937297027, 4781.42622885256, 4817.325574419584, 4874.731889746027, 3870.6195689650663, 4013.237484877864, 4255.000149929529, 4369.475662920407, 4554.385158003627, 2029.4216148397636, 2321.9419514512274, 2329.941751456227, 2586.831855929396, 2596.7143614202423, 4077.225327187825, 4477.201748906933, 4627.3521706961055, 4845.5881250094135, 5093.694378337312, 4713.552212539803, 4873.0, 4943.788771750944, 5425.430329815392, 5974.402559744078, 6038.909416358758, 6051.273847138372, 6080.300765411946, 6090.390960903963, 6122.938770612326, 4521.615662668579, 4938.43208031081, 5437.809676661298, 5891.1457838612305, 5912.575843903979, 5048.217526283432, 5175.197844334134, 5337.359516858002, 5755.0, 5816.73824677882, 4889.926651100165, 5026.4973502649445, 6078.240219972504, 6082.2093127893095, 6091.664958427329, 3845.5193100862393, 3874.5857761916136, 3881.0, 3893.8053097344455, 3905.1408690088133, 4369.89075273108, 5087.669301495391, 5413.9729301353245, 5543.584231182758, 5768.826935191951, 5141.974290128525, 5562.666240025612, 5592.916106258328, 5638.661680299195, 5653.530082178745, 4689.906201875875, 4815.061063092803, 5093.115547247542, 5584.553235741212, 5605.747741351731, 4625.7455839929125, 5516.282883225315, 5564.443555644565, 5577.944220557904, 5587.441255874543, 4969.577585905238, 5421.918671219856, 5449.0, 5449.972750136224, 5463.344398672185, 4310.590493903074, 5373.435789242073, 5401.135818269058, 5511.696856672921, 5543.61194716366], "value": 3945.0864722117435, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [705.2242533213466, 721.2066726600743, 722.4942540221846, 725.0001238046658, 776.2276534847828, 15039.924800375997, 15415.69168616627, 15418.152001639904, 15442.61393465164, 15491.922540387282, 13968.650783730387, 13973.790393144085, 14004.649883752885, 14029.0, 14430.855691443083, 668.9096971908779, 684.5310961991028, 821.3100995164076, 827.3546633625751, 844.3878188313485, 833.2833762963846, 845.9915400845974, 850.9787255318636, 853.4879072556447, 854.4403415762686, 904.1952662130709, 905.551751882819, 907.5825120444604, 907.6142639378271, 908.7955210077713, 692.8233300508338, 708.5359089796192, 896.188949001155, 896.4397251717677, 897.9595918183697, 560.1905246918204, 642.1138828416755, 658.3712554510479, 666.9895108910006, 668.2849351194224, 14561.563153105424, 14620.342084606218, 14633.268336583129, 14639.268036598234, 14680.119192848464, 12309.753804923941, 12591.622251332383, 13234.27211503367, 13471.86528134716, 13938.163710177421, 12159.331236781973, 12210.145289829716, 12309.384530773516, 12465.189762665397, 12650.367481625883, 4389.122175564868, 4404.383386325917, 4414.713043652185, 4602.539746025371, 4604.286335617984, 111338.0, 114565.0, 117181.0, 118536.40731796286, 118798.40600797028, 9214.539273036455, 10690.732731681615, 10837.891621083689, 10993.175511836644, 11120.22158448917, 20972.265970691256, 20976.265830696157, 20979.0, 21003.78996210018, 21005.579888402146, 7586.393088553013, 7814.179511151247, 7819.68721251155, 7855.8821617675785, 7920.009998750156, 4211.578842115825, 4316.935245971313, 4337.522872484024, 4387.714798538085, 4477.686561940633, 2986.9850650746607, 3459.86160553575, 3462.186386199241, 3463.1688394785574, 3464.220550376145, 658.7944062365859, 663.1975309875105, 669.1668872253978, 669.2872091222832, 669.4911866981032, 561.2731512691075, 574.9223854779635, 609.7286707415246, 620.7734177025444, 624.5971348480296, 8239.670413183525, 8509.957450212833, 8578.699745508877, 8750.781230469289, 8971.775705607412, 525.8869343091254, 543.7308532276548, 557.7089038874991, 595.7051259626512, 617.4473845907887, 4203.70574059819, 4258.318669012943, 4389.62688171509, 4499.167653984024, 4576.176288268143, 149168.0, 151760.0, 154847.22576387046, 155466.0, 156782.200681137, 134940.65059349476, 135530.0, 137475.31262343624, 137677.0, 143189.0, 87070.0, 100302.0, 102344.0, 104092.47953760334, 106053.46973265085, 7586.0038229466545, 7668.539887606761, 8374.0, 8471.195236452528, 8487.278581320657, 65447.0, 66412.66793666194, 66588.6670566644, 66913.33086669266, 67084.0, 99134.0, 101034.0, 101580.0, 101658.98341016495, 101906.4714029305, 11085.334879906908, 11162.888371116185, 11206.43967801623, 11226.66320010401, 11451.971265455632, 11907.82138267944, 11909.166358354832, 12105.455254513745, 12145.35137494569, 12227.14409991292, 9441.905580944378, 10104.848427273744, 10954.0, 11511.0, 11789.941050294694, 10814.29707069038, 11433.428328583372, 11442.559888092523, 11492.253003554742, 12010.939945300217, 11257.155713321697, 11312.773744525228, 11319.943400283275, 11538.365389903636, 11599.072074233873, 9180.16078021878, 9678.370905891095, 9737.951310243403, 10078.496075196064, 10478.94760526223, 9479.336446448682, 10263.7434064149, 10902.78194436091, 11286.604968826052, 11629.534818607499], "value": 7490.902920436742, "unit": "score"}], "name": "dromaeo_css", "value": 7195.099974138309, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 112.0, 114.0, 112.0, 113.0, 114.0, 112.0, 112.0, 112.0, 112.0], "value": 112.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 112.0, 114.0, 150.0, 111.0, 114.0, 114.0, 112.0, 142.0, 112.0], "value": 122.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [184.0, 184.0, 254.0, 225.0, 184.0, 182.0, 178.0, 177.0, 185.0, 183.0], "value": 193.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 56.0, 54.0, 83.0, 67.0, 53.0, 82.0, 82.0, 108.0, 56.0], "value": 71.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 71.0, 93.0, 70.0, 69.0, 69.0, 93.0, 67.0, 68.0, 91.0], "value": 77.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 89.0, 136.0, 87.0, 89.0, 85.0, 89.0, 87.0, 90.0, 85.0], "value": 92.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 93.0, 93.0, 93.0, 93.0, 93.0, 93.0, 95.0, 93.0, 93.0], "value": 93.2, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [126.0, 132.0, 93.0, 84.0, 86.0, 84.0, 84.0, 83.0, 84.0, 84.0], "value": 94.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 54.0, 55.0, 54.0, 55.0, 55.0, 56.0, 55.0, 54.0, 55.0], "value": 55.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 70.0, 68.0, 80.0, 71.0, 72.0, 69.0, 69.0, 70.0, 69.0], "value": 70.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 90.0, 90.0, 90.0, 86.0, 85.0, 86.0, 89.0, 87.0, 91.0], "value": 88.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [158.0, 161.0, 143.0, 147.0, 146.0, 142.0, 146.0, 144.0, 198.0, 162.0], "value": 154.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [171.0, 171.0, 174.0, 177.0, 174.0, 173.0, 171.0, 224.0, 173.0, 169.0], "value": 177.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 69.0, 69.0, 69.0, 67.0, 68.0, 69.0, 66.0, 69.0, 69.0], "value": 68.2, "unit": "score"}], "name": "kraken", "value": 1472.5000000000002, "alertThreshold": 2.0}]} 17:46:55 INFO - Return code: 0 17:46:55 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 17:46:55 INFO - # TBPL SUCCESS # 17:46:55 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 17:46:55 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 17:46:55 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 17:46:55 INFO - 2016-10-12 17:46:55.666 screenresolution[1669:10118] starting screenresolution argv=screenresolution get 17:46:55 INFO - 2016-10-12 17:46:55.669 screenresolution[1669:10118] Display 0: 1600x1200x32@60 17:46:55 INFO - 2016-10-12 17:46:55.675 screenresolution[1670:10122] starting screenresolution argv=screenresolution list 17:46:55 INFO - Available Modes on Display 0 17:46:55 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 17:46:55 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 17:46:55 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 17:46:55 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 17:46:55 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 17:46:55 INFO - Intel Iris: 17:46:55 INFO - Chipset Model: Intel Iris 17:46:55 INFO - Type: GPU 17:46:55 INFO - Bus: Built-In 17:46:55 INFO - VRAM (Dynamic, Max): 1536 MB 17:46:55 INFO - Vendor: Intel (0x8086) 17:46:55 INFO - Device ID: 0x0a2e 17:46:55 INFO - Revision ID: 0x0009 17:46:55 INFO - Displays: 17:46:55 INFO - Display: 17:46:55 INFO - Resolution: 1600 x 1200 @ 60 Hz 17:46:55 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 17:46:55 INFO - Display Serial Number: 1600x1200 60 17:46:55 INFO - Main Display: Yes 17:46:55 INFO - Mirror: Off 17:46:55 INFO - Online: Yes 17:46:55 INFO - Rotation: Supported 17:46:55 INFO - Return code: 0 17:46:55 INFO - Running post-action listener: _resource_record_post_action 17:46:55 INFO - [mozharness: 2016-10-13 00:46:55.910410Z] Finished run-tests step (success) 17:46:55 INFO - Running post-run listener: _resource_record_post_run 17:46:55 INFO - Total resource usage - Wall time: 1214s; CPU: 53.0%; Read bytes: 38687744; Write bytes: 710779904; Read time: 376; Write time: 1954 17:46:55 INFO - TinderboxPrint: CPU usage
53.5% 17:46:55 INFO - TinderboxPrint: I/O read bytes / time
38,687,744 / 376 17:46:55 INFO - TinderboxPrint: I/O write bytes / time
710,779,904 / 1,954 17:46:55 INFO - TinderboxPrint: CPU idle
2,258.9 (46.5%) 17:46:55 INFO - TinderboxPrint: CPU system
969.2 (20.0%) 17:46:55 INFO - TinderboxPrint: CPU user
1,629.6 (33.5%) 17:46:55 INFO - TinderboxPrint: Swap in / out
446,033,920 / 0 17:46:55 INFO - install - Wall time: 35s; CPU: 54.0%; Read bytes: 389210624; Write bytes: 384423936; Read time: 29322; Write time: 746 17:46:55 INFO - run-tests - Wall time: 1180s; CPU: 53.0%; Read bytes: 37704704; Write bytes: 299858944; Read time: 361; Write time: 1158 17:46:56 INFO - Running post-run listener: _upload_blobber_files 17:46:56 INFO - Blob upload gear active. 17:46:56 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 17:46:56 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 17:46:56 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'] 17:46:56 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 17:46:56 INFO - (blobuploader) - INFO - Open directory for files ... 17:46:56 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 17:46:56 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:46:56 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:46:57 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 17:46:57 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:46:57 INFO - (blobuploader) - INFO - Done attempting. 17:46:57 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 17:46:57 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:46:57 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:46:59 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 17:46:59 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:46:59 INFO - (blobuploader) - INFO - Done attempting. 17:46:59 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 17:46:59 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:46:59 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:47:01 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 17:47:01 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:47:01 INFO - (blobuploader) - INFO - Done attempting. 17:47:01 INFO - (blobuploader) - INFO - Iteration through files over. 17:47:01 INFO - Return code: 0 17:47:01 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 17:47:01 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 17:47:01 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e472f9fcb6f6d0c497f5ef69280088595f9828f8422dc758be466295587b7641d92286558492de13746bf573230aaa0d34ef01413b95d1d1ef602dace7e3ab73", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a3fbc8843d89e6f59caf9d41aac17cdc69ebccbf85bdae6fdfd663249da85aeb05af534ab895ebfb629abea4836cca6c91a4ed5ec3cf8dc980c1635742061f3a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b998ba6a4cdf5ff05cddafc7560a8bfebd40ceeb121cad0b6ea75bd8c2feb9390b7847221beb8b82b57bbd2feb75f0ef2259e73437b01cd9a987c5f8922509b3"} 17:47:01 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 17:47:01 INFO - Writing to file /builds/slave/test/properties/blobber_files 17:47:01 INFO - Contents: 17:47:01 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e472f9fcb6f6d0c497f5ef69280088595f9828f8422dc758be466295587b7641d92286558492de13746bf573230aaa0d34ef01413b95d1d1ef602dace7e3ab73", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a3fbc8843d89e6f59caf9d41aac17cdc69ebccbf85bdae6fdfd663249da85aeb05af534ab895ebfb629abea4836cca6c91a4ed5ec3cf8dc980c1635742061f3a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b998ba6a4cdf5ff05cddafc7560a8bfebd40ceeb121cad0b6ea75bd8c2feb9390b7847221beb8b82b57bbd2feb75f0ef2259e73437b01cd9a987c5f8922509b3"} 17:47:01 INFO - Running post-run listener: copy_logs_to_upload_dir 17:47:01 INFO - Copying logs to upload dir... 17:47:01 INFO - mkdir: /builds/slave/test/build/upload/logs 17:47:01 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1275.372237 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 15 secs) (at 2016-10-12 17:47:01.373636) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-12 17:47:01.377175) ========= 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.3RXa1LxaDs/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.h347kkx8t7/Listeners TMPDIR=/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/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/e472f9fcb6f6d0c497f5ef69280088595f9828f8422dc758be466295587b7641d92286558492de13746bf573230aaa0d34ef01413b95d1d1ef602dace7e3ab73", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a3fbc8843d89e6f59caf9d41aac17cdc69ebccbf85bdae6fdfd663249da85aeb05af534ab895ebfb629abea4836cca6c91a4ed5ec3cf8dc980c1635742061f3a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b998ba6a4cdf5ff05cddafc7560a8bfebd40ceeb121cad0b6ea75bd8c2feb9390b7847221beb8b82b57bbd2feb75f0ef2259e73437b01cd9a987c5f8922509b3"} build_url:https://queue.taskcluster.net/v1/task/QJzK0NKsRYmn9yJfNq0ccA/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011060 build_url: 'https://queue.taskcluster.net/v1/task/QJzK0NKsRYmn9yJfNq0ccA/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/e472f9fcb6f6d0c497f5ef69280088595f9828f8422dc758be466295587b7641d92286558492de13746bf573230aaa0d34ef01413b95d1d1ef602dace7e3ab73", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a3fbc8843d89e6f59caf9d41aac17cdc69ebccbf85bdae6fdfd663249da85aeb05af534ab895ebfb629abea4836cca6c91a4ed5ec3cf8dc980c1635742061f3a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b998ba6a4cdf5ff05cddafc7560a8bfebd40ceeb121cad0b6ea75bd8c2feb9390b7847221beb8b82b57bbd2feb75f0ef2259e73437b01cd9a987c5f8922509b3"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-12 17:47:01.410465) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-12 17:47:01.410941) ========= 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.3RXa1LxaDs/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.h347kkx8t7/Listeners TMPDIR=/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/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.005009 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-12 17:47:01.463920) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-12 17:47:01.464475) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-12 17:47:01.465053) ========= ========= Total master_lag: 0.19 =========