builder: autoland_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0196 starttime: 1475559633.06 results: success (0) buildid: 20161003204100 builduid: 24a451d26c1344d284bf76ddeed30c0b revision: 0c95d5dec6d9ded18b7c57e8b4156944da527646 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-10-03 22:40:33.057562) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-10-03 22:40:33.058111) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-03 22:40:33.058388) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-03 22:40:33.081876) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-03 22:40:33.082283) ========= 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.f9sAHOF1pk/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.0mBAGTFu4j/Listeners TMPDIR=/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/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-03 22:40:33-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 12.1M=0.001s 2016-10-03 22:40:33 (12.1 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.126168 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-03 22:40:33.230198) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-03 22:40:33.230537) ========= 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.f9sAHOF1pk/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.0mBAGTFu4j/Listeners TMPDIR=/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/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.021742 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-03 22:40:33.282180) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-10-03 22:40:33.282949) ========= bash -c 'python archiver_client.py mozharness --repo integration/autoland --rev 0c95d5dec6d9ded18b7c57e8b4156944da527646 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/autoland --rev 0c95d5dec6d9ded18b7c57e8b4156944da527646 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.f9sAHOF1pk/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.0mBAGTFu4j/Listeners TMPDIR=/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/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-03 22:40:33,358 truncating revision to first 12 chars 2016-10-03 22:40:33,358 Setting DEBUG logging. 2016-10-03 22:40:33,358 attempt 1/10 2016-10-03 22:40:33,358 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/autoland/0c95d5dec6d9?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-10-03 22:40:35,491 unpacking tar archive at: autoland-0c95d5dec6d9/testing/mozharness/ program finished with exit code 0 elapsedTime=2.399110 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-10-03 22:40:35.705069) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-03 22:40:35.705390) ========= script_repo_revision: 0c95d5dec6d9ded18b7c57e8b4156944da527646 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-03 22:40:35.705770) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-03 22:40:35.706051) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-03 22:40:35.724326) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 14 secs) (at 2016-10-03 22:40:35.724692) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Autoland --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Autoland', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Autoland'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.f9sAHOF1pk/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.0mBAGTFu4j/Listeners TMPDIR=/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 22:40:35 INFO - MultiFileLogger online at 20161003 22:40:35 in /builds/slave/test 22:40:35 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Autoland --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland 22:40:35 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 22:40:35 INFO - {'append_to_log': False, 22:40:35 INFO - 'base_work_dir': '/builds/slave/test', 22:40:35 INFO - 'blob_upload_branch': 'Autoland', 22:40:35 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 22:40:35 INFO - 'branch': 'Autoland', 22:40:35 INFO - 'buildbot_json_path': 'buildprops.json', 22:40:35 INFO - 'config_files': ('talos/mac_config.py',), 22:40:35 INFO - 'default_actions': ('clobber', 22:40:35 INFO - 'read-buildbot-config', 22:40:35 INFO - 'download-and-extract', 22:40:35 INFO - 'populate-webroot', 22:40:35 INFO - 'create-virtualenv', 22:40:35 INFO - 'install', 22:40:35 INFO - 'run-tests'), 22:40:35 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 22:40:35 INFO - 'download_minidump_stackwalk': True, 22:40:35 INFO - 'download_symbols': 'ondemand', 22:40:35 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 22:40:35 INFO - 'tooltool.py': '/tools/tooltool.py', 22:40:35 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 22:40:35 INFO - '/tools/misc-python/virtualenv.py')}, 22:40:35 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 22:40:35 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 22:40:35 INFO - 'installer_path': 'installer.exe', 22:40:35 INFO - 'log_level': 'info', 22:40:35 INFO - 'log_name': 'talos', 22:40:35 INFO - 'log_to_console': True, 22:40:35 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 22:40:35 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 22:40:35 INFO - 'opt_config_files': (), 22:40:35 INFO - 'pip_index': False, 22:40:35 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 22:40:35 INFO - 'cmd': ('bash', 22:40:35 INFO - '-c', 22:40:35 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 22:40:35 INFO - 'enabled': True, 22:40:35 INFO - 'halt_on_failure': False, 22:40:35 INFO - 'name': 'check_screen_resolution'},), 22:40:35 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 22:40:35 INFO - 'cmd': ('bash', 22:40:35 INFO - '-c', 22:40:35 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 22:40:35 INFO - 'enabled': True, 22:40:35 INFO - 'halt_on_failure': False, 22:40:35 INFO - 'name': 'check_screen_resolution'},), 22:40:35 INFO - 'run_cmd_checks_enabled': True, 22:40:35 INFO - 'sps_profile': False, 22:40:35 INFO - 'sps_profile_interval': 0, 22:40:35 INFO - 'suite': 'dromaeojs', 22:40:35 INFO - 'system_bits': '32', 22:40:35 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 22:40:35 INFO - 'title': 't-yosemite-r7-0196', 22:40:35 INFO - 'tooltool_cache': '/builds/tooltool_cache', 22:40:35 INFO - 'use_talos_json': True, 22:40:35 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 22:40:35 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 22:40:35 INFO - 'work_dir': 'build'} 22:40:35 INFO - [mozharness: 2016-10-04 05:40:35.928052Z] Running clobber step. 22:40:35 INFO - Running pre-action listener: _resource_record_pre_action 22:40:35 INFO - Running main action method: clobber 22:40:35 INFO - rmtree: /builds/slave/test/build 22:40:35 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 22:40:36 INFO - Running post-action listener: _resource_record_post_action 22:40:36 INFO - [mozharness: 2016-10-04 05:40:36.923044Z] Finished clobber step (success) 22:40:36 INFO - [mozharness: 2016-10-04 05:40:36.923166Z] Running read-buildbot-config step. 22:40:36 INFO - Running pre-action listener: _resource_record_pre_action 22:40:36 INFO - Running main action method: read_buildbot_config 22:40:36 INFO - Using buildbot properties: 22:40:36 INFO - { 22:40:36 INFO - "project": "", 22:40:36 INFO - "product": "firefox", 22:40:36 INFO - "script_repo_revision": "production", 22:40:36 INFO - "builddir": "autoland_yosemite_r7_test-dromaeojs", 22:40:36 INFO - "repository": "", 22:40:36 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 autoland talos dromaeojs", 22:40:36 INFO - "buildid": "20161003204100", 22:40:36 INFO - "pgo_build": "False", 22:40:36 INFO - "basedir": "/builds/slave/test", 22:40:36 INFO - "buildnumber": 346, 22:40:36 INFO - "slavename": "t-yosemite-r7-0196", 22:40:36 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 22:40:36 INFO - "platform": "yosemite_r7", 22:40:36 INFO - "scheduler": "tests-autoland-macosx64-talos", 22:40:36 INFO - "branch": "autoland", 22:40:36 INFO - "repo_path": "integration/autoland", 22:40:36 INFO - "revision": "0c95d5dec6d9ded18b7c57e8b4156944da527646", 22:40:36 INFO - "stage_platform": "macosx64", 22:40:36 INFO - "builduid": "24a451d26c1344d284bf76ddeed30c0b", 22:40:36 INFO - "slavebuilddir": "test" 22:40:36 INFO - } 22:40:36 INFO - Found installer url https://queue.taskcluster.net/v1/task/KyhhvRdESxeWzeLuxv047w/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg. 22:40:36 INFO - Running post-action listener: _resource_record_post_action 22:40:36 INFO - [mozharness: 2016-10-04 05:40:36.926352Z] Finished read-buildbot-config step (success) 22:40:36 INFO - [mozharness: 2016-10-04 05:40:36.926448Z] Running download-and-extract step. 22:40:36 INFO - Running pre-action listener: _resource_record_pre_action 22:40:36 INFO - Running main action method: download_and_extract 22:40:36 INFO - mkdir: /builds/slave/test/build/tests 22:40:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:40:36 INFO - trying https://queue.taskcluster.net/v1/task/KyhhvRdESxeWzeLuxv047w/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json 22:40:36 INFO - Downloading https://queue.taskcluster.net/v1/task/KyhhvRdESxeWzeLuxv047w/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json 22:40:36 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/KyhhvRdESxeWzeLuxv047w/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json'}, attempt #1 22:40:38 INFO - Downloaded 1199 bytes. 22:40:38 INFO - Reading from file /builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json 22:40:38 INFO - Using the following test package requirements: 22:40:38 INFO - {u'common': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 22:40:38 INFO - u'cppunittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 22:40:38 INFO - u'firefox-52.0a1.en-US.mac.cppunittest.tests.zip'], 22:40:38 INFO - u'gtest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 22:40:38 INFO - u'firefox-52.0a1.en-US.mac.gtest.tests.zip'], 22:40:38 INFO - u'jittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 22:40:38 INFO - u'jsshell-mac.zip'], 22:40:38 INFO - u'mochitest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 22:40:38 INFO - u'firefox-52.0a1.en-US.mac.mochitest.tests.zip'], 22:40:38 INFO - u'mozbase': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 22:40:38 INFO - u'reftest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 22:40:38 INFO - u'firefox-52.0a1.en-US.mac.reftest.tests.zip'], 22:40:38 INFO - u'talos': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 22:40:38 INFO - u'firefox-52.0a1.en-US.mac.talos.tests.zip'], 22:40:38 INFO - u'web-platform': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 22:40:38 INFO - u'firefox-52.0a1.en-US.mac.web-platform.tests.zip'], 22:40:38 INFO - u'xpcshell': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 22:40:38 INFO - u'firefox-52.0a1.en-US.mac.xpcshell.tests.zip']} 22:40:38 INFO - Downloading packages: [u'firefox-52.0a1.en-US.mac.common.tests.zip'] for test suite category: common 22:40:38 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/KyhhvRdESxeWzeLuxv047w/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 22:40:38 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/KyhhvRdESxeWzeLuxv047w/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 22:40:38 INFO - Fetch https://queue.taskcluster.net/v1/task/KyhhvRdESxeWzeLuxv047w/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 22:40:38 INFO - Http code: 200 22:40:38 INFO - via: 1.1 ff555a0cc6346f82fe74596f8ed6cc33.cloudfront.net (CloudFront) 22:40:38 INFO - x-amz-cf-id: w_v-6Jys0Jj8eKob1yENtgmfXf4h-IGdCOHgoeGXjEoAOP0gfbvNGA== 22:40:38 INFO - x-amz-version-id: kmEBtIM3W7e2FJ_5nfzVUeKB.oqK1ZPJ 22:40:39 INFO - Expected file size: 16476571 22:40:39 INFO - Obtained file size: 16476571 22:40:45 INFO - Downloading packages: [u'firefox-52.0a1.en-US.mac.common.tests.zip', u'firefox-52.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 22:40:45 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/KyhhvRdESxeWzeLuxv047w/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 22:40:45 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/KyhhvRdESxeWzeLuxv047w/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 22:40:45 INFO - Fetch https://queue.taskcluster.net/v1/task/KyhhvRdESxeWzeLuxv047w/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 22:40:46 INFO - Http code: 200 22:40:46 INFO - via: 1.1 23172d761378a3865feafcda78328053.cloudfront.net (CloudFront) 22:40:46 INFO - x-amz-cf-id: rLtfUy18rGWHxxEepL0rOT6mZRD0Fuc3NeNvnPwGSsuSf6GWCltbxA== 22:40:46 INFO - x-amz-version-id: kmEBtIM3W7e2FJ_5nfzVUeKB.oqK1ZPJ 22:40:46 INFO - Expected file size: 16476571 22:40:46 INFO - Obtained file size: 16476571 22:40:53 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/KyhhvRdESxeWzeLuxv047w/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip 22:40:53 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/KyhhvRdESxeWzeLuxv047w/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip'}, attempt #1 22:40:53 INFO - Fetch https://queue.taskcluster.net/v1/task/KyhhvRdESxeWzeLuxv047w/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip into memory 22:40:53 INFO - Http code: 200 22:40:53 INFO - via: 1.1 bfc29822e21ecc352f71dce83e06ab74.cloudfront.net (CloudFront) 22:40:53 INFO - x-amz-cf-id: ez__qls0TqDyfkdr62159OEuNFmA7SMByYRep7bdcarPDf2LNb4Ppw== 22:40:53 INFO - x-amz-version-id: tbGDRzC0LuhThAkQRrvccsA1afBvMksT 22:40:54 INFO - Expected file size: 14408522 22:40:54 INFO - Obtained file size: 14408522 22:40:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:40:54 INFO - trying https://queue.taskcluster.net/v1/task/KyhhvRdESxeWzeLuxv047w/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 22:40:54 INFO - Downloading https://queue.taskcluster.net/v1/task/KyhhvRdESxeWzeLuxv047w/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg 22:40:54 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/KyhhvRdESxeWzeLuxv047w/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg'}, attempt #1 22:40:57 INFO - Downloaded 111747964 bytes. 22:40:57 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/KyhhvRdESxeWzeLuxv047w/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 22:40:57 INFO - mkdir: /builds/slave/test/properties 22:40:57 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 22:40:57 INFO - Writing to file /builds/slave/test/properties/build_url 22:40:57 INFO - Contents: 22:40:57 INFO - build_url:https://queue.taskcluster.net/v1/task/KyhhvRdESxeWzeLuxv047w/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 22:40:57 INFO - Running post-action listener: _resource_record_post_action 22:40:57 INFO - Running post-action listener: set_extra_try_arguments 22:40:57 INFO - [mozharness: 2016-10-04 05:40:57.978014Z] Finished download-and-extract step (success) 22:40:57 INFO - [mozharness: 2016-10-04 05:40:57.978175Z] Running populate-webroot step. 22:40:57 INFO - Running pre-action listener: _resource_record_pre_action 22:40:57 INFO - Running main action method: populate_webroot 22:40:57 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 22:40:57 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 22:40:57 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 22:40:57 INFO - '--tppagecycles', 22:40:57 INFO - '7'], 22:40:57 INFO - 'tests': ['tsvgm']}}, 22:40:57 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 22:40:57 INFO - 'tests': ['tresize', 'tcanvasmark']}, 22:40:57 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 22:40:57 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 22:40:57 INFO - 'tests': ['dromaeo_css', 'kraken']}, 22:40:57 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 22:40:57 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:40:57 INFO - 'talos_options': ['--disable-e10s'], 22:40:57 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 22:40:57 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:40:57 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 22:40:57 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:40:57 INFO - 'talos_options': ['--disable-e10s'], 22:40:57 INFO - 'tests': ['damp', 'tps']}, 22:40:57 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:40:57 INFO - 'tests': ['damp', 'tps']}, 22:40:57 INFO - 'g3': {'talos_options': ['--disable-e10s'], 22:40:57 INFO - 'tests': ['dromaeo_dom']}, 22:40:57 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 22:40:57 INFO - 'g4': {'talos_options': ['--disable-e10s'], 22:40:57 INFO - 'tests': ['basic_compositor_video']}, 22:40:57 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 22:40:57 INFO - 'other': {'talos_options': ['--disable-e10s'], 22:40:57 INFO - 'tests': ['a11yr', 22:40:57 INFO - 'ts_paint', 22:40:57 INFO - 'tpaint', 22:40:57 INFO - 'sessionrestore', 22:40:57 INFO - 'sessionrestore_no_auto_restore', 22:40:57 INFO - 'tabpaint']}, 22:40:57 INFO - 'other-e10s': {'tests': ['a11yr', 22:40:57 INFO - 'ts_paint', 22:40:57 INFO - 'tpaint', 22:40:57 INFO - 'sessionrestore', 22:40:57 INFO - 'sessionrestore_no_auto_restore', 22:40:57 INFO - 'tabpaint']}, 22:40:57 INFO - 'other-e10s_l64': {'tests': ['a11yr', 22:40:57 INFO - 'ts_paint', 22:40:57 INFO - 'tpaint', 22:40:57 INFO - 'sessionrestore', 22:40:57 INFO - 'sessionrestore_no_auto_restore', 22:40:57 INFO - 'tabpaint']}, 22:40:57 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 22:40:57 INFO - 'ts_paint', 22:40:57 INFO - 'tpaint', 22:40:57 INFO - 'sessionrestore', 22:40:57 INFO - 'sessionrestore_no_auto_restore', 22:40:57 INFO - 'tabpaint']}, 22:40:57 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 22:40:57 INFO - 'tests': ['a11yr', 22:40:57 INFO - 'ts_paint', 22:40:57 INFO - 'tpaint', 22:40:57 INFO - 'sessionrestore', 22:40:57 INFO - 'sessionrestore_no_auto_restore', 22:40:57 INFO - 'tabpaint']}, 22:40:57 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 22:40:57 INFO - 'tests': ['a11yr', 22:40:57 INFO - 'ts_paint', 22:40:57 INFO - 'tpaint', 22:40:57 INFO - 'sessionrestore', 22:40:57 INFO - 'sessionrestore_no_auto_restore', 22:40:57 INFO - 'tabpaint']}, 22:40:57 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 22:40:57 INFO - 'tests': ['tsvgx', 22:40:57 INFO - 'tsvgr_opacity', 22:40:57 INFO - 'tart', 22:40:57 INFO - 'tscrollx', 22:40:57 INFO - 'cart']}, 22:40:57 INFO - 'svgr-e10s': {'tests': ['tsvgx', 22:40:57 INFO - 'tsvgr_opacity', 22:40:57 INFO - 'tart', 22:40:57 INFO - 'tscrollx', 22:40:57 INFO - 'cart']}, 22:40:57 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:40:57 INFO - 'talos_options': ['--disable-e10s'], 22:40:57 INFO - 'tests': ['tp5o']}, 22:40:57 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:40:57 INFO - 'tests': ['tp5o']}, 22:40:57 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:40:57 INFO - 'talos_options': ['--disable-e10s', 22:40:57 INFO - '--xperf_path', 22:40:57 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 22:40:57 INFO - 'tests': ['tp5n']}, 22:40:57 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:40:57 INFO - 'talos_options': ['--xperf_path', 22:40:57 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 22:40:57 INFO - 'tests': ['tp5n']}}, 22:40:57 INFO - 'talos.zip': {'path': '', 22:40:57 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 22:40:57 INFO - Running post-action listener: _resource_record_post_action 22:40:57 INFO - [mozharness: 2016-10-04 05:40:57.996500Z] Finished populate-webroot step (success) 22:40:57 INFO - [mozharness: 2016-10-04 05:40:57.996608Z] Running create-virtualenv step. 22:40:57 INFO - Running pre-action listener: _resource_record_pre_action 22:40:57 INFO - Running main action method: create_virtualenv 22:40:57 INFO - Creating virtualenv /builds/slave/test/build/venv 22:40:57 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 22:40:57 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 22:40:58 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 22:40:58 INFO - Using real prefix '/tools/python27' 22:40:58 INFO - New python executable in /builds/slave/test/build/venv/bin/python 22:40:58 INFO - Installing distribute.............................................................................................................................................................................................done. 22:41:01 INFO - Installing pip.................done. 22:41:01 INFO - Return code: 0 22:41:01 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 22:41:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 22:41:01 INFO - Reading from file tmpfile_stdout 22:41:01 INFO - Output received: 22:41:01 INFO - pip 1.5.5 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 22:41:01 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 22:41:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:41:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:41:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:41:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:41:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:41:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:41:01 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10d84f490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d7c5570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8ccb567360>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d663d30>, '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 0x10d489db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d65bb20>, '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.f9sAHOF1pk/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.0mBAGTFu4j/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 22:41:01 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 22:41:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 22:41:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.f9sAHOF1pk/Render', 22:41:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:41:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:41:01 INFO - 'HOME': '/Users/cltbld', 22:41:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:41:01 INFO - 'LOGNAME': 'cltbld', 22:41:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:41:01 INFO - 'MOZ_NO_REMOTE': '1', 22:41:01 INFO - 'NO_EM_RESTART': '1', 22:41:01 INFO - 'PAGER': '/bin/cat', 22:41:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:41:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:41:01 INFO - 'PWD': '/builds/slave/test', 22:41:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:41:01 INFO - 'SHELL': '/bin/bash', 22:41:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.0mBAGTFu4j/Listeners', 22:41:01 INFO - 'TMPDIR': '/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/T/', 22:41:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:41:01 INFO - 'USER': 'cltbld', 22:41:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:41:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:41:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:41:01 INFO - 'XPC_FLAGS': '0x0', 22:41:01 INFO - 'XPC_SERVICE_NAME': '0', 22:41:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:41:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:41:02 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 22:41:02 INFO - Cleaning up... 22:41:02 INFO - Return code: 0 22:41:02 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 22:41:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:41:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:41:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:41:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:41:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:41:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:41:02 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 0x10d84f490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d7c5570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8ccb567360>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d663d30>, '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 0x10d489db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d65bb20>, '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.f9sAHOF1pk/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.0mBAGTFu4j/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 22:41:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 22:41:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 22:41:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.f9sAHOF1pk/Render', 22:41:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:41:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:41:02 INFO - 'HOME': '/Users/cltbld', 22:41:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:41:02 INFO - 'LOGNAME': 'cltbld', 22:41:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:41:02 INFO - 'MOZ_NO_REMOTE': '1', 22:41:02 INFO - 'NO_EM_RESTART': '1', 22:41:02 INFO - 'PAGER': '/bin/cat', 22:41:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:41:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:41:02 INFO - 'PWD': '/builds/slave/test', 22:41:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:41:02 INFO - 'SHELL': '/bin/bash', 22:41:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.0mBAGTFu4j/Listeners', 22:41:02 INFO - 'TMPDIR': '/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/T/', 22:41:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:41:02 INFO - 'USER': 'cltbld', 22:41:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:41:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:41:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:41:02 INFO - 'XPC_FLAGS': '0x0', 22:41:02 INFO - 'XPC_SERVICE_NAME': '0', 22:41:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:41:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:41:02 INFO - Downloading/unpacking psutil>=3.1.1 22:41:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:41:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:41:02 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:41:02 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:41:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:41:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:41:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 22:41:05 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 22:41:05 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 22:41:05 INFO - Installing collected packages: psutil 22:41:05 INFO - Running setup.py install for psutil 22:41:06 INFO - building 'psutil._psutil_osx' extension 22:41:06 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 22:41:06 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 22:41:06 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 22:41:06 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 22:41:06 INFO - building 'psutil._psutil_posix' extension 22:41:06 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 22:41:06 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 22:41:06 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 22:41:06 INFO - ^ 22:41:06 INFO - 1 warning generated. 22:41:06 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 22:41:06 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 22:41:06 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 22:41:06 INFO - Successfully installed psutil 22:41:06 INFO - Cleaning up... 22:41:06 INFO - Return code: 0 22:41:06 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 22:41:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:41:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:41:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:41:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:41:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:41:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:41:06 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 0x10d84f490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d7c5570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8ccb567360>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d663d30>, '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 0x10d489db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d65bb20>, '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.f9sAHOF1pk/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.0mBAGTFu4j/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 22:41:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 22:41:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 22:41:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.f9sAHOF1pk/Render', 22:41:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:41:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:41:06 INFO - 'HOME': '/Users/cltbld', 22:41:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:41:06 INFO - 'LOGNAME': 'cltbld', 22:41:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:41:06 INFO - 'MOZ_NO_REMOTE': '1', 22:41:06 INFO - 'NO_EM_RESTART': '1', 22:41:06 INFO - 'PAGER': '/bin/cat', 22:41:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:41:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:41:06 INFO - 'PWD': '/builds/slave/test', 22:41:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:41:06 INFO - 'SHELL': '/bin/bash', 22:41:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.0mBAGTFu4j/Listeners', 22:41:06 INFO - 'TMPDIR': '/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/T/', 22:41:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:41:06 INFO - 'USER': 'cltbld', 22:41:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:41:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:41:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:41:06 INFO - 'XPC_FLAGS': '0x0', 22:41:06 INFO - 'XPC_SERVICE_NAME': '0', 22:41:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:41:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:41:07 INFO - Downloading/unpacking mozsystemmonitor==0.3 22:41:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:41:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:41:07 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:41:07 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:41:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:41:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:41:09 INFO - Downloading mozsystemmonitor-0.3.tar.gz 22:41:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 22:41:09 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 22:41:09 INFO - Installing collected packages: mozsystemmonitor 22:41:09 INFO - Running setup.py install for mozsystemmonitor 22:41:09 INFO - Successfully installed mozsystemmonitor 22:41:09 INFO - Cleaning up... 22:41:09 INFO - Return code: 0 22:41:09 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 22:41:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:41:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:41:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:41:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:41:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:41:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:41:09 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 0x10d84f490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d7c5570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8ccb567360>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d663d30>, '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 0x10d489db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d65bb20>, '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.f9sAHOF1pk/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.0mBAGTFu4j/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 22:41:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 22:41:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 22:41:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.f9sAHOF1pk/Render', 22:41:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:41:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:41:09 INFO - 'HOME': '/Users/cltbld', 22:41:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:41:09 INFO - 'LOGNAME': 'cltbld', 22:41:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:41:09 INFO - 'MOZ_NO_REMOTE': '1', 22:41:09 INFO - 'NO_EM_RESTART': '1', 22:41:09 INFO - 'PAGER': '/bin/cat', 22:41:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:41:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:41:09 INFO - 'PWD': '/builds/slave/test', 22:41:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:41:09 INFO - 'SHELL': '/bin/bash', 22:41:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.0mBAGTFu4j/Listeners', 22:41:09 INFO - 'TMPDIR': '/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/T/', 22:41:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:41:09 INFO - 'USER': 'cltbld', 22:41:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:41:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:41:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:41:09 INFO - 'XPC_FLAGS': '0x0', 22:41:09 INFO - 'XPC_SERVICE_NAME': '0', 22:41:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:41:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:41:10 INFO - Downloading/unpacking jsonschema==2.5.1 22:41:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:41:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:41:10 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:41:10 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:41:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:41:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:41:12 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 22:41:12 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 22:41:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:41:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:41:12 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:41:12 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:41:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:41:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:41:13 INFO - Downloading functools32-3.2.3-2.tar.gz 22:41:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 22:41:13 INFO - warning: no files found matching '*.txt' 22:41:13 INFO - no previously-included directories found matching 'build' 22:41:13 INFO - no previously-included directories found matching 'dist' 22:41:13 INFO - no previously-included directories found matching '.git*' 22:41:13 INFO - Installing collected packages: jsonschema, functools32 22:41:13 INFO - Running setup.py install for functools32 22:41:13 INFO - warning: no files found matching '*.txt' 22:41:13 INFO - no previously-included directories found matching 'build' 22:41:13 INFO - no previously-included directories found matching 'dist' 22:41:13 INFO - no previously-included directories found matching '.git*' 22:41:13 INFO - Successfully installed jsonschema functools32 22:41:13 INFO - Cleaning up... 22:41:13 INFO - Return code: 0 22:41:13 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 22:41: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')]} 22:41:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:41:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:41: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')]} 22:41:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:41:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:41: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', '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 0x10d84f490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d7c5570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8ccb567360>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d663d30>, '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 0x10d489db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d65bb20>, '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.f9sAHOF1pk/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.0mBAGTFu4j/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 22:41: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', 'functools32==3.2.3-2'] in /builds/slave/test/build 22:41: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 functools32==3.2.3-2 22:41:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.f9sAHOF1pk/Render', 22:41:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:41:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:41:13 INFO - 'HOME': '/Users/cltbld', 22:41:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:41:13 INFO - 'LOGNAME': 'cltbld', 22:41:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:41:13 INFO - 'MOZ_NO_REMOTE': '1', 22:41:13 INFO - 'NO_EM_RESTART': '1', 22:41:13 INFO - 'PAGER': '/bin/cat', 22:41:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:41:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:41:13 INFO - 'PWD': '/builds/slave/test', 22:41:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:41:13 INFO - 'SHELL': '/bin/bash', 22:41:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.0mBAGTFu4j/Listeners', 22:41:13 INFO - 'TMPDIR': '/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/T/', 22:41:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:41:13 INFO - 'USER': 'cltbld', 22:41:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:41:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:41:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:41:13 INFO - 'XPC_FLAGS': '0x0', 22:41:13 INFO - 'XPC_SERVICE_NAME': '0', 22:41:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:41:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:41:13 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 22:41:13 INFO - Cleaning up... 22:41:13 INFO - Return code: 0 22:41:13 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 22:41: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')]} 22:41:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:41:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:41: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')]} 22:41:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:41:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:41: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', '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 0x10d84f490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d7c5570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8ccb567360>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d663d30>, '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 0x10d489db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d65bb20>, '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.f9sAHOF1pk/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.0mBAGTFu4j/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 22:41: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', 'blobuploader==1.2.4'] in /builds/slave/test/build 22:41: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 blobuploader==1.2.4 22:41:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.f9sAHOF1pk/Render', 22:41:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:41:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:41:13 INFO - 'HOME': '/Users/cltbld', 22:41:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:41:13 INFO - 'LOGNAME': 'cltbld', 22:41:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:41:13 INFO - 'MOZ_NO_REMOTE': '1', 22:41:13 INFO - 'NO_EM_RESTART': '1', 22:41:13 INFO - 'PAGER': '/bin/cat', 22:41:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:41:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:41:13 INFO - 'PWD': '/builds/slave/test', 22:41:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:41:13 INFO - 'SHELL': '/bin/bash', 22:41:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.0mBAGTFu4j/Listeners', 22:41:13 INFO - 'TMPDIR': '/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/T/', 22:41:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:41:13 INFO - 'USER': 'cltbld', 22:41:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:41:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:41:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:41:13 INFO - 'XPC_FLAGS': '0x0', 22:41:13 INFO - 'XPC_SERVICE_NAME': '0', 22:41:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:41:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:41:13 INFO - Downloading/unpacking blobuploader==1.2.4 22:41:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:41:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:41:13 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:41:13 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:41:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:41:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:41:16 INFO - Downloading blobuploader-1.2.4.tar.gz 22:41:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 22:41:16 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 22:41:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:41:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:41:16 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:41:16 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:41:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:41:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:41:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 22:41:17 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 22:41:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:41:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:41:17 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:41:17 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:41:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:41:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:41:17 INFO - Downloading docopt-0.6.1.tar.gz 22:41:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 22:41:17 INFO - Installing collected packages: blobuploader, requests, docopt 22:41:17 INFO - Running setup.py install for blobuploader 22:41:17 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 22:41:17 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 22:41:17 INFO - Running setup.py install for requests 22:41:18 INFO - Running setup.py install for docopt 22:41:18 INFO - Successfully installed blobuploader requests docopt 22:41:18 INFO - Cleaning up... 22:41:18 INFO - Return code: 0 22:41:18 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:41: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')]} 22:41:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:41:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:41: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')]} 22:41:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:41:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:41:18 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 0x10d84f490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d7c5570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8ccb567360>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d663d30>, '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 0x10d489db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d65bb20>, '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.f9sAHOF1pk/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.0mBAGTFu4j/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 22:41:18 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 22:41:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 22:41:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.f9sAHOF1pk/Render', 22:41:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:41:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:41:18 INFO - 'HOME': '/Users/cltbld', 22:41:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:41:18 INFO - 'LOGNAME': 'cltbld', 22:41:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:41:18 INFO - 'MOZ_NO_REMOTE': '1', 22:41:18 INFO - 'NO_EM_RESTART': '1', 22:41:18 INFO - 'PAGER': '/bin/cat', 22:41:18 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:41:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:41:18 INFO - 'PWD': '/builds/slave/test', 22:41:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:41:18 INFO - 'SHELL': '/bin/bash', 22:41:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.0mBAGTFu4j/Listeners', 22:41:18 INFO - 'TMPDIR': '/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/T/', 22:41:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:41:18 INFO - 'USER': 'cltbld', 22:41:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:41:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:41:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:41:18 INFO - 'XPC_FLAGS': '0x0', 22:41:18 INFO - 'XPC_SERVICE_NAME': '0', 22:41:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:41:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:41:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 22:41:18 INFO - Running setup.py (path:/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/T/pip-_H26Yq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 22:41:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 22:41:19 INFO - Running setup.py (path:/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/T/pip-1jK5l1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 22:41:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 22:41:19 INFO - Running setup.py (path:/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/T/pip-ABxKMu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 22:41:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 22:41:19 INFO - Running setup.py (path:/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/T/pip-MfC0Sy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 22:41:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 22:41:19 INFO - Running setup.py (path:/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/T/pip-j5YQxq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 22:41:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 22:41:19 INFO - Running setup.py (path:/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/T/pip-1SBXJD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 22:41:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 22:41:19 INFO - Running setup.py (path:/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/T/pip-DPgS2k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 22:41:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 22:41:19 INFO - Running setup.py (path:/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/T/pip-NRHLQ8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 22:41:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 22:41:19 INFO - Running setup.py (path:/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/T/pip-TRbOuK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 22:41:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 22:41:19 INFO - Running setup.py (path:/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/T/pip-XkRChd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 22:41:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 22:41:20 INFO - Running setup.py (path:/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/T/pip-ChW2cb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 22:41:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 22:41:20 INFO - Running setup.py (path:/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/T/pip-XG0TSO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 22:41:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 22:41:20 INFO - Running setup.py (path:/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/T/pip-rf9j4T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 22:41:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 22:41:20 INFO - Running setup.py (path:/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/T/pip-uQOJEW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 22:41:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 22:41:20 INFO - Running setup.py (path:/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/T/pip-7sRAI6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 22:41:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 22:41:20 INFO - Running setup.py (path:/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/T/pip-IIwuZM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 22:41:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 22:41:20 INFO - Running setup.py (path:/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/T/pip-bzdmt4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 22:41:20 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 22:41:20 INFO - Running setup.py install for manifestparser 22:41:20 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 22:41:20 INFO - Running setup.py install for mozcrash 22:41:21 INFO - Running setup.py install for mozdebug 22:41:21 INFO - Running setup.py install for mozdevice 22:41:21 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 22:41:21 INFO - Installing dm script to /builds/slave/test/build/venv/bin 22:41:21 INFO - Running setup.py install for mozfile 22:41:21 INFO - Running setup.py install for mozhttpd 22:41:21 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 22:41:21 INFO - Running setup.py install for mozinfo 22:41:21 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 22:41:21 INFO - Running setup.py install for mozInstall 22:41:21 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 22:41:21 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 22:41:21 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 22:41:21 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 22:41:21 INFO - Running setup.py install for mozleak 22:41:22 INFO - Running setup.py install for mozlog 22:41:22 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 22:41:22 INFO - Running setup.py install for moznetwork 22:41:22 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 22:41:22 INFO - Running setup.py install for mozprocess 22:41:22 INFO - Running setup.py install for mozprofile 22:41:22 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 22:41:22 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 22:41:22 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 22:41:22 INFO - Running setup.py install for mozrunner 22:41:22 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 22:41:22 INFO - Running setup.py install for mozscreenshot 22:41:23 INFO - Running setup.py install for moztest 22:41:23 INFO - Running setup.py install for mozversion 22:41:23 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 22:41:23 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 22:41:23 INFO - Cleaning up... 22:41:23 INFO - Return code: 0 22:41:23 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:41:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:41:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:41:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:41:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:41:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:41:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:41:23 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 0x10d84f490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d7c5570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8ccb567360>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d663d30>, '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 0x10d489db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d65bb20>, '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.f9sAHOF1pk/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.0mBAGTFu4j/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 22:41:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 22:41:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 22:41:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.f9sAHOF1pk/Render', 22:41:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:41:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:41:23 INFO - 'HOME': '/Users/cltbld', 22:41:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:41:23 INFO - 'LOGNAME': 'cltbld', 22:41:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:41:23 INFO - 'MOZ_NO_REMOTE': '1', 22:41:23 INFO - 'NO_EM_RESTART': '1', 22:41:23 INFO - 'PAGER': '/bin/cat', 22:41:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:41:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:41:23 INFO - 'PWD': '/builds/slave/test', 22:41:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:41:23 INFO - 'SHELL': '/bin/bash', 22:41:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.0mBAGTFu4j/Listeners', 22:41:23 INFO - 'TMPDIR': '/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/T/', 22:41:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:41:23 INFO - 'USER': 'cltbld', 22:41:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:41:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:41:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:41:23 INFO - 'XPC_FLAGS': '0x0', 22:41:23 INFO - 'XPC_SERVICE_NAME': '0', 22:41:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:41:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:41:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 22:41:23 INFO - Running setup.py (path:/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/T/pip-cPP75w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 22:41:23 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 22:41:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 22:41:23 INFO - Running setup.py (path:/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/T/pip-t9akpx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 22:41:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 22:41:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 22:41:23 INFO - Running setup.py (path:/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/T/pip-Bvpn6r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 22:41:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 22:41:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 22:41:24 INFO - Running setup.py (path:/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/T/pip-zUbNFS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 22:41:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 22:41:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 22:41:24 INFO - Running setup.py (path:/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/T/pip-7mdaR6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 22:41:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 22:41:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 22:41:24 INFO - Running setup.py (path:/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/T/pip-h93t_r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 22:41:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 22:41:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 22:41:24 INFO - Running setup.py (path:/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/T/pip-LuF8s8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 22:41:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 22:41:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 22:41:24 INFO - Running setup.py (path:/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/T/pip-w7Lbnu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 22:41:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 22:41:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 22:41:24 INFO - Running setup.py (path:/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/T/pip-3CAa_v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 22:41:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 22:41:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 22:41:24 INFO - Running setup.py (path:/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/T/pip-IH3fno-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 22:41:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 22:41:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 22:41:24 INFO - Running setup.py (path:/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/T/pip-N4UpfT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 22:41:24 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 22:41:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 22:41:24 INFO - Running setup.py (path:/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/T/pip-oAoo6v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 22:41:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 22:41:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 22:41:24 INFO - Running setup.py (path:/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/T/pip-zLN2Ll-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 22:41:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 22:41:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 22:41:25 INFO - Running setup.py (path:/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/T/pip-3oFiQG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 22:41:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 22:41:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 22:41:25 INFO - Running setup.py (path:/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/T/pip-ZWebe8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 22:41:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 22:41:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 22:41:25 INFO - Running setup.py (path:/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/T/pip-52vSEr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 22:41:25 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 22:41:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 22:41:25 INFO - Running setup.py (path:/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/T/pip-1RqTpi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 22:41:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 22:41:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 22:41:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 22:41:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 22:41:25 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 22:41:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 22:41:25 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 22:41: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 22:41: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 22:41: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 22:41: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 22:41: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 22:41: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 22:41:27 INFO - Downloading blessings-1.6.tar.gz 22:41:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 22:41:27 INFO - Installing collected packages: blessings 22:41:27 INFO - Running setup.py install for blessings 22:41:28 INFO - Successfully installed blessings 22:41:28 INFO - Cleaning up... 22:41:28 INFO - Return code: 0 22:41:28 INFO - Done creating virtualenv /builds/slave/test/build/venv. 22:41:28 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 22:41:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 22:41:28 INFO - Reading from file tmpfile_stdout 22:41:28 INFO - Current package versions: 22:41:28 INFO - blessings == 1.6 22:41:28 INFO - blobuploader == 1.2.4 22:41:28 INFO - docopt == 0.6.1 22:41:28 INFO - functools32 == 3.2.3-2 22:41:28 INFO - jsonschema == 2.5.1 22:41:28 INFO - manifestparser == 1.1 22:41:28 INFO - mozInstall == 1.12 22:41:28 INFO - mozcrash == 0.17 22:41:28 INFO - mozdebug == 0.1 22:41:28 INFO - mozdevice == 0.48 22:41:28 INFO - mozfile == 1.2 22:41:28 INFO - mozhttpd == 0.7 22:41:28 INFO - mozinfo == 0.9 22:41:28 INFO - mozleak == 0.1 22:41:28 INFO - mozlog == 3.4 22:41:28 INFO - moznetwork == 0.27 22:41:28 INFO - mozprocess == 0.23 22:41:28 INFO - mozprofile == 0.28 22:41:28 INFO - mozrunner == 6.12 22:41:28 INFO - mozscreenshot == 0.1 22:41:28 INFO - mozsystemmonitor == 0.3 22:41:28 INFO - moztest == 0.7 22:41:28 INFO - mozversion == 1.4 22:41:28 INFO - psutil == 3.1.1 22:41:28 INFO - requests == 1.2.3 22:41:28 INFO - wsgiref == 0.1.2 22:41:28 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:41:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:41:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:41:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:41:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:41:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:41:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:41:28 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 0x10d84f490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d7c5570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8ccb567360>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d663d30>, '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 0x10d489db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d65bb20>, '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.f9sAHOF1pk/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.0mBAGTFu4j/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 22:41:28 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 22:41:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 22:41:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.f9sAHOF1pk/Render', 22:41:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:41:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:41:28 INFO - 'HOME': '/Users/cltbld', 22:41:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:41:28 INFO - 'LOGNAME': 'cltbld', 22:41:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:41:28 INFO - 'MOZ_NO_REMOTE': '1', 22:41:28 INFO - 'NO_EM_RESTART': '1', 22:41:28 INFO - 'PAGER': '/bin/cat', 22:41:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:41:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:41:28 INFO - 'PWD': '/builds/slave/test', 22:41:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:41:28 INFO - 'SHELL': '/bin/bash', 22:41:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.0mBAGTFu4j/Listeners', 22:41:28 INFO - 'TMPDIR': '/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/T/', 22:41:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:41:28 INFO - 'USER': 'cltbld', 22:41:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:41:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:41:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:41:28 INFO - 'XPC_FLAGS': '0x0', 22:41:28 INFO - 'XPC_SERVICE_NAME': '0', 22:41:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:41:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:41:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 22:41:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 22:41:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 22:41:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 22:41:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 22:41:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 22:41:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 22:41:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 22:41:28 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 22:41:28 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 22:41:28 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 22:41:28 INFO - Cleaning up... 22:41:28 INFO - Return code: 0 22:41:28 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 22:41:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:41:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:41:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:41:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:41:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:41:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:41:28 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 0x10d84f490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d7c5570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8ccb567360>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d663d30>, '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 0x10d489db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d65bb20>, '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.f9sAHOF1pk/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.0mBAGTFu4j/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 22:41:28 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 22:41:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 22:41:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.f9sAHOF1pk/Render', 22:41:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:41:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:41:28 INFO - 'HOME': '/Users/cltbld', 22:41:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:41:28 INFO - 'LOGNAME': 'cltbld', 22:41:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:41:28 INFO - 'MOZ_NO_REMOTE': '1', 22:41:28 INFO - 'NO_EM_RESTART': '1', 22:41:28 INFO - 'PAGER': '/bin/cat', 22:41:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:41:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:41:28 INFO - 'PWD': '/builds/slave/test', 22:41:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:41:28 INFO - 'SHELL': '/bin/bash', 22:41:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.0mBAGTFu4j/Listeners', 22:41:28 INFO - 'TMPDIR': '/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/T/', 22:41:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:41:28 INFO - 'USER': 'cltbld', 22:41:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:41:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:41:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:41:28 INFO - 'XPC_FLAGS': '0x0', 22:41:28 INFO - 'XPC_SERVICE_NAME': '0', 22:41:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:41:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:41:29 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 22:41:29 INFO - Cleaning up... 22:41:29 INFO - Return code: 0 22:41:29 INFO - Running post-action listener: _resource_record_post_action 22:41:29 INFO - Running post-action listener: _start_resource_monitoring 22:41:29 INFO - Starting resource monitoring. 22:41:29 INFO - [mozharness: 2016-10-04 05:41:29.090043Z] Finished create-virtualenv step (success) 22:41:29 INFO - [mozharness: 2016-10-04 05:41:29.091000Z] Running install step. 22:41:29 INFO - Running pre-action listener: _resource_record_pre_action 22:41:29 INFO - Running main action method: install 22:41:29 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 22:41:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 22:41:29 INFO - Reading from file tmpfile_stdout 22:41:29 INFO - Detecting whether we're running mozinstall >=1.0... 22:41:29 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 22:41:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 22:41:29 INFO - Reading from file tmpfile_stdout 22:41:29 INFO - Output received: 22:41:29 INFO - Usage: mozinstall [options] installer 22:41:29 INFO - Options: 22:41:29 INFO - -h, --help show this help message and exit 22:41:29 INFO - -d DEST, --destination=DEST 22:41:29 INFO - Directory to install application into. [default: 22:41:29 INFO - "/builds/slave/test"] 22:41:29 INFO - --app=APP Application being installed. [default: firefox] 22:41:29 INFO - mkdir: /builds/slave/test/build/application 22:41:29 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 22:41:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 22:42:03 INFO - Reading from file tmpfile_stdout 22:42:03 INFO - Output received: 22:42:03 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 22:42:03 INFO - Running post-action listener: _resource_record_post_action 22:42:03 INFO - [mozharness: 2016-10-04 05:42:03.698905Z] Finished install step (success) 22:42:03 INFO - [mozharness: 2016-10-04 05:42:03.699043Z] Running run-tests step. 22:42:03 INFO - Running pre-action listener: _resource_record_pre_action 22:42:03 INFO - Running main action method: run_tests 22:42:03 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 22:42:03 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 22:42:03 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 22:42:03 INFO - 2016-10-03 22:42:03.713 screenresolution[1936:11903] starting screenresolution argv=screenresolution get 22:42:03 INFO - 2016-10-03 22:42:03.716 screenresolution[1936:11903] Display 0: 1600x1200x32@60 22:42:03 INFO - 2016-10-03 22:42:03.721 screenresolution[1937:11906] starting screenresolution argv=screenresolution list 22:42:03 INFO - Available Modes on Display 0 22:42:03 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 22:42:03 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 22:42:03 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 22:42:03 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 22:42:03 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 22:42:03 INFO - Intel Iris: 22:42:03 INFO - Chipset Model: Intel Iris 22:42:03 INFO - Type: GPU 22:42:03 INFO - Bus: Built-In 22:42:03 INFO - VRAM (Dynamic, Max): 1536 MB 22:42:03 INFO - Vendor: Intel (0x8086) 22:42:03 INFO - Device ID: 0x0a2e 22:42:03 INFO - Revision ID: 0x0009 22:42:03 INFO - Displays: 22:42:03 INFO - Display: 22:42:03 INFO - Resolution: 1600 x 1200 @ 60 Hz 22:42:03 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 22:42:03 INFO - Display Serial Number: 1600x1200 60 22:42:03 INFO - Main Display: Yes 22:42:03 INFO - Mirror: Off 22:42:03 INFO - Online: Yes 22:42:03 INFO - Rotation: Supported 22:42:03 INFO - Return code: 0 22:42:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 22:42:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 22:42:03 INFO - Python 2.7.3 22:42:03 INFO - Return code: 0 22:42:03 INFO - grabbing minidump binary from tooltool 22:42:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:42:03 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 0x10d663d30>, '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 0x10d489db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d65bb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 22:42:03 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 22:42:03 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 22:42:03 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 22:42:04 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 22:42:04 INFO - Return code: 0 22:42:04 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 22:42:04 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 22:42:04 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 22:42:04 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 22:42:04 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 22:42:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Autoland', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/KyhhvRdESxeWzeLuxv047w/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0196', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 22:42:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Autoland --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/KyhhvRdESxeWzeLuxv047w/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0196 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 22:42:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.f9sAHOF1pk/Render', 22:42:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:42:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:42:04 INFO - 'HOME': '/Users/cltbld', 22:42:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:42:04 INFO - 'LOGNAME': 'cltbld', 22:42:04 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 22:42:04 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 22:42:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:42:04 INFO - 'MOZ_NO_REMOTE': '1', 22:42:04 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 22:42:04 INFO - 'NO_EM_RESTART': '1', 22:42:04 INFO - 'PAGER': '/bin/cat', 22:42:04 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:42:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:42:04 INFO - 'PWD': '/builds/slave/test', 22:42:04 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 22:42:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:42:04 INFO - 'SHELL': '/bin/bash', 22:42:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.0mBAGTFu4j/Listeners', 22:42:04 INFO - 'TMPDIR': '/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/T/', 22:42:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:42:04 INFO - 'USER': 'cltbld', 22:42:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:42:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:42:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:42:04 INFO - 'XPC_FLAGS': '0x0', 22:42:04 INFO - 'XPC_SERVICE_NAME': '0', 22:42:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:42:04 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Autoland', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/KyhhvRdESxeWzeLuxv047w/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0196', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 22:42:04 INFO - mozversion application_buildid: 20161003204100 22:42:04 INFO - mozversion application_changeset: 0c95d5dec6d9ded18b7c57e8b4156944da527646 22:42:04 INFO - mozversion application_display_name: Nightly 22:42:04 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 22:42:04 INFO - mozversion application_name: Firefox 22:42:04 INFO - mozversion application_remotingname: firefox 22:42:04 INFO - mozversion application_repository: https://hg.mozilla.org/integration/autoland 22:42:04 INFO - mozversion application_vendor: Mozilla 22:42:04 INFO - mozversion application_version: 52.0a1 22:42:04 INFO - mozversion platform_buildid: 20161003204100 22:42:04 INFO - mozversion platform_changeset: 0c95d5dec6d9ded18b7c57e8b4156944da527646 22:42:04 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/autoland 22:42:04 INFO - mozversion platform_version: 52.0a1 22:42:04 INFO - using testdate: 1475559724 22:42:04 INFO - actual date: 1475559724 22:42:04 INFO - starting webserver on 'localhost:51856' 22:42:04 INFO - SUITE-START | Running 2 tests 22:42:04 INFO - TEST-START | dromaeo_css 22:42:04 INFO - operating with platform_type : mac_ 22:42:04 INFO - Initialising browser for dromaeo_css test... 22:42:04 INFO - TEST-INFO | started process 1947 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/T/tmpchvCu9/profile http://localhost:51856/getInfo.html) 22:42:06 INFO - PROCESS | 1947 | __metrics Screen width/height:1600/1200 22:42:06 INFO - PROCESS | 1947 | colorDepth:24 22:42:06 INFO - PROCESS | 1947 | Browser inner width/height: 1024/689 22:42:06 INFO - PROCESS | 1947 | __metrics 22:42:06 INFO - PROCESS | 1947 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 22:42:06 INFO - PROCESS | 1947 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 22:42:07 INFO - TEST-INFO | 1947: exit 0 22:42:07 INFO - Browser initialized. 22:42:07 INFO - Running cycle 1/1 for dromaeo_css test... 22:42:07 INFO - TEST-INFO | started process 1949 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/T/tmpchvCu9/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 22:42:19 INFO - PROCESS | 1949 | RSS: Main: 236044288 22:42:19 INFO - PROCESS | 1949 | 22:43:11 INFO - PROCESS | 1949 | 1475559791350 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 22:45:23 INFO - PROCESS | 1949 | Cycle 1(1): loaded http://localhost:51856/tests/dromaeo/cssquery-dojo.html (next: http://localhost:51856/tests/dromaeo/cssquery-ext.html) 22:45:25 INFO - PROCESS | 1949 | RSS: Main: 415531008 22:45:25 INFO - PROCESS | 1949 | 22:48:28 INFO - PROCESS | 1949 | Cycle 1(1): loaded http://localhost:51856/tests/dromaeo/cssquery-ext.html (next: http://localhost:51856/tests/dromaeo/cssquery-jquery.html) 22:48:28 INFO - PROCESS | 1949 | RSS: Main: 371007488 22:48:28 INFO - PROCESS | 1949 | 22:51:31 INFO - PROCESS | 1949 | Cycle 1(1): loaded http://localhost:51856/tests/dromaeo/cssquery-jquery.html (next: http://localhost:51856/tests/dromaeo/cssquery-mootools.html) 22:51:31 INFO - PROCESS | 1949 | RSS: Main: 338972672 22:51:31 INFO - PROCESS | 1949 | 22:54:34 INFO - PROCESS | 1949 | Cycle 1(1): loaded http://localhost:51856/tests/dromaeo/cssquery-mootools.html (next: http://localhost:51856/tests/dromaeo/cssquery-prototype.html) 22:54:34 INFO - PROCESS | 1949 | RSS: Main: 359972864 22:54:34 INFO - PROCESS | 1949 | 22:57:38 INFO - PROCESS | 1949 | Cycle 1(1): loaded http://localhost:51856/tests/dromaeo/cssquery-prototype.html (next: http://localhost:51856/tests/dromaeo/cssquery-yui.html) 22:57:38 INFO - PROCESS | 1949 | RSS: Main: 332324864 22:57:38 INFO - PROCESS | 1949 | 23:00:42 INFO - PROCESS | 1949 | Cycle 1(1): loaded http://localhost:51856/tests/dromaeo/cssquery-yui.html (next: http://localhost:51856/tests/dromaeo/cssquery-dojo.html) 23:00:42 INFO - PROCESS | 1949 | RSS: Main: 427069440 23:00:42 INFO - PROCESS | 1949 | 23:00:42 INFO - PROCESS | 1949 | __start_tp_report 23:00:42 INFO - PROCESS | 1949 | _x_x_mozilla_page_load 23:00:42 INFO - PROCESS | 1949 | _x_x_mozilla_page_load_details 23:00:42 INFO - PROCESS | 1949 | |i|pagename|runs| 23:00:42 INFO - PROCESS | 1949 | |0;dojo.html;2911.344947386838;3300.686434788695;3319.5850518685147;3343.448331025381;3356.88250911218;5577.609567330289;5664.660120392779;5676.886462270752;5684.8578785530335;5698.829035128948;5645.717714114298;5710.086386178213;5777.133429985493;5777.364489906107;5810.18657387965;5065.113605119108;5142.460041695625;5152.74236288186;5202.115640341151;5204.583633309345;5268.209768534713;5268.998890210867;5279.788808447676;5282.102042652758;5286.709230992294;5133.512316329943;5228.529432351107;5244;5304.443033481487;5348.064088784467;5160.200168973815;5204.401493828221;5206.7917283308625;5209.166533354645;5226.2944502492055;5123.615728820316;5148.2535032420465;5175.249588809639;5181.715005674687;5187.988342273258;5634.464725851038;5638.887222255573;5639.379668236491;5693.487586117228;5721.7711291548285;6129.570930034899;6147.907781383283;6164.106204600308;6208.565400421972;6212.9068063979075;5464.207689884985;5527.778888844482;6067.666278354689;6282.246130464374;6286.057091436275;5583.469570390806;5671.744771485293;5682.914756278659;5706.486416222477;5727.11229759388;6312.084747711556;6354.936450635527;6361.236651601745;6366.586171898812;6371.267304260069;6082.300535438481;6092.238470191226;6105.572609917263;6114.449699527064;6116.113163591254;6107.877842443127;6128.1364821545585;6129.511613758974;6157.230346206724;6247.812565623039;6097.815189446183;6161.7227224775;6180.8763824723255;6181.227346581677;6181.289151747606;6080.483158931452;6168.753249869955;6197.783077592263;6215.223097112861;6242.500599951993;4538.909221815611;4816.759162041954;4854.83008094715;4868.8782780430065;4900.485449027872;4405.867823965286;4412.271975124068;4443.266860967904;4443.4445694288215;4445.888852778707;5702.54379649627;5707.1153971134545;5717.542596592262;5722.484976352148;5731.140328950607;6136.886723216021;6156.5998210116195;6168.876622467616;6172.074188871616;6179.62922224668;5754.1944127822135;5764.884702305931;5764.971175144097;5809.941900581025;5818.418158184148;5359.866003349869;5374.3013408256675;5383.865403364869;5385.919211211835;5391.353037635509;6260.906086408617;6328.114064030948;6334.524910631813;6337.96831015842;6354.936450635527;5159.303494028258;6157.322694503511;6163.845903852261;6185.907211391743;6213.285472170577;6323.620582765049;6360.300366959723;6364.586301890362;6374.713137908712;6384.840378990377;6059.666718330534;6149.723262453111;6161.5686901918225;6168.938310617016;6177.752889884354;6099.64271536338;6114.6636934969;6147.108669242845;6147.616786223064;6230.532710046674;5438.972805135949;5909.734061967136;6070.817875463743;6075.665838378754;6077.6353418795015;4547.658925580661;5989.790357337472;6043.425874541911;6054.091886217103;6058.606190597597;5742.569307301886;5858.9414105860105;5870.354261031198;5888.941110588839;5896.115582662635;5953.761849526144;5995.790147344822;6005.039193728982;6067.059605761114;6090.817275481742;4912.557869791736;5909.995300798961;5961.165436838933;5962.433568810956;5992.43071908168 23:00:42 INFO - PROCESS | 1949 | |1;ext.html;462.5998511287736;464.35222864104577;477.31028663581117;524.2817340243867;526.310533201506;29960.400791984146;39233.80383098084;40279.194416111735;40303.39544906829;42327.15345693092;40869.79565102178;41283;41292;41337.37993930093;41338.37992430116;9498.382605130644;9667.323287369887;9678.032196780336;9688.612455501772;9701.369410988264;9515.714528564155;9616.615335386576;9655.420674759502;9657.565409556586;9666.854997175047;8298.21166989135;9651.324407291493;9696.70909872705;9703.563339649732;9715.659951901685;9589.472579008152;9617.759556011086;9647.90352096477;9660.516974151264;9669.468179250138;9568.995255498212;9652.131308182297;9653.517324133765;9654.951725241399;9658.661946831897;39213.41179882304;39534.40698389498;39577.208455830725;39581.010474738076;39613.405798912754;3247.3180632067056;3517.9120521987156;3669.3028324618504;3927.626875446828;4065.451164092839;29574.26064348387;30124.644391002457;30856.53715194274;30992;31102;3145.8898938537272;3278.557394751702;3310.9834450827834;3340;3340.3486320167576;449360.7531962352;453028.46971530194;453525;454412;457095;51439;51513.71215719562;51582.74208628933;51680;51810.963780724174;57797.42202578005;57812;57831.13253301204;57836.42163578395;57863;15571.987820791612;15777.68444631124;15792.763108553381;15818.9209053954;15830.12934288602;12265;12294.016478681684;12308.015358771278;12313.815292770616;12319.445624946899;14514.709705805824;14907.776383354258;15064.472743453927;15114.848851511344;15286.235688215767;6189.442950134509;6243.43809057178;6249.78125765596;6302.180716506825;6345.777897773556;861.5821326656567;925.3984909808678;972.7519482531909;973.5375696544085;1110.272771334777;25449.618255726178;29193.270168245537;29597.399250699113;29597.408051838844;29781.553276700866;873.4497266722052;1033.813913495563;1064.7391389109719;1105.2705214558353;1144.8053830848776;15909.284082216096;16167.949083309548;16171.948823326446;16380.935239209413;16685.831991760704;658952;662503;662633;662777;665874;54762.72618636881;54835.72582137064;54878;54890.45109548853;55008;1204.1029433072242;1243.4963839644959;1318.4133060787858;1349.1230700045048;1361.7072329449215;24980.875860586682;29760.40479190361;29999.55000674948;30753.692463075084;31492;47170.82072948066;47790;47863.5213647873;47933.5206647943;47961;549136;550594;552201;552753;554265.3720388395;23560.6465903015;25797.484050319264;25830.966761329335;26417.735822641527;26514.86742566275;44202.67391978246;44521.55478445304;45066.77466612646;46476.070478589565;47728;31970.84014579912;37080;37278.06804829792;37614.24771504609;38414.615853841104;37005.629943700216;37095;38058;38065.809670951465;38811.6118838808;36347;36776.816115919246;37565.43651845279;37685.05787355229;37704.245915082094;31991.84004079965;33468.304804522486;33547.664523354455;33662.663373365955;34174;24970.250892473254;25159.49681006406;25193.622095668947;25324.873375633742;25626 23:00:42 INFO - PROCESS | 1949 | |2;jquery.html;316.6216371436133;339.30611898667223;348.10884136610264;368.5448471138144;371.72120909318005;15168.165750883696;16042.91978540107;18414.723779143325;18917.24331026759;20013.399598012045;16943.237554310053;19514.512137196576;19897.701534476957;21066.789332106713;21081.156753729872;5403.432639572838;5819.62172458791;5926.377730338317;5957.910631340534;5968.224130862982;5718.77124915005;5785.652860828363;5921.763129474816;5922.940770592282;5931.110333449974;5927.347991720907;6026.487748541357;6140.263168419796;6151.231096112986;6167.938320616804;5873.471387575096;6049.304330002062;6125.418085281891;6138.815835524942;6139.754409823602;5385.703786291752;5481.150421684645;5896.97051514744;5903.49820265278;5904.911426328608;12964.092513524058;13158.407871645702;13182.406791694397;13282.468701251939;13505.797413038812;1806.9819301806945;2138.208862720803;2319.188284100568;2320.8027317678016;2751.5184842652657;9421.38760980534;9968.202543796478;10419.218558608134;10424.374537527698;10535.367877927349;1573.3549244809574;1672.7909011373579;1815.8002619711822;1821.571930596309;1879.135597625094;80236.19763802404;98577;98664;114849;122361.77638223593;10883.292585981886;11306.491207895664;11486.655400338002;11491.655250342503;11662.475188616532;14458.855411445962;14473.27633618315;14493.78259326111;14497.130172189702;14519.274036298355;7985.960070199611;8315.29320007794;8376.958115209385;8491.74524764258;8579.442336248125;8342.37432192588;8353.289970352587;8372.372072094495;8542.487450752975;8546.957265213758;4451.554844515587;4518.977405112953;4589.1051245007575;4590.403247577793;4593.081383723269;1457.7157454296523;1850.1304386938118;1888.5373083594607;1914.5405102775233;1914.731937528747;711.7473296979498;736.8931504931755;741.3031750154837;770.1836053783007;891.777055736066;19789.03164525304;20475.59048819015;21470.914598182564;21591.244011729093;21663;886.822635472908;888.9777755556033;915.2997956563235;931.65063101337;942.5146049784366;10611.151107911348;11172.385518796382;11211.60759373418;11219.214654974237;11247.606333778278;565807;574826.1258693764;576766;591506;593168;124046;130575.69424305635;131013.34493327473;131108;133519.3324033393;944.1974321826392;961.4327546747453;980.2990861534038;1017.5421060522795;1027.2193133218807;13823.51617693376;18854.3400980965;19481.318153864548;20075.498112547302;20227.59544809125;54466;54620.726896366854;54954.62613434696;55207;55220.51507682244;77193.61403192948;77606.6119669398;78445;78542.21457785578;80263;5874.353821079763;6408.455281300955;7557.82853657684;7725.381969442544;8274.29668478162;6867.896981545381;10496.265261431627;11371.488283027118;11384.772304554028;12267.202631828903;10447.268691191544;10867.347959122479;11081.168912331446;11157.051650609788;11268.718282043015;9330.720078397659;10656.360618362924;11091.445427728742;11249.987501124939;11386.089112871116;11165.665030048787;11318.886811132114;11384.14618903569;11548.538058477567;11575.305481671126;7729.420293478124;7986.600669966362;7986.960065199869;8103.1491693371345;8253.711120110767;8094;9534.141927226583;10667.733306667395;11012.063974562252;11121.110311174925 23:00:42 INFO - PROCESS | 1949 | |3;mootools.html;1431.8067060946773;1502.1287653161169;1603.8797090218222;1694.1529235382309;1844.3360390259504;8556.401051926368;9073;9104;9105.998340182583;9109.271258299337;7986.800329991753;8299.294559962394;8305.169483051692;8679.784830123788;8995.145461181193;2362.066983541504;2387.916422925198;2402.411409204747;2406.783389494945;2407.8194135439912;2139.5506943541873;2242.1816037146486;2339.2982105368405;2347.6947996760396;2360.7639236076343;1939.9612007759836;2344.542814151241;2373.584622691022;2375.049980008002;2387.3554140382084;2325.4883925536355;2345.5074434368803;2354.411397150712;2364.2552595932225;2367.3963139399343;2359.6106642403984;2361.9645705314433;2362.6574146748626;2367.0768400323886;2369.893354799038;8907.688230911886;8994.550272486349;9011.684591039282;9025.638974441015;9027.503487308195;5186.481351864821;5188.273641690166;5361.169018802092;5364.329458817648;5374.086405311106;3016.3816417634553;3543.5039094526787;3587.3901436755814;3694.3350196964534;3718.4608231806237;1067.2902519824308;1094.141099237103;1098.1544210957597;1113.8440618313443;1117.044926587764;12067.396630168456;12200.206986545845;12203.816942745865;12211.633650990485;12216.022718182612;4518.231900576844;4635.860924172281;4771.66598338113;4831.371921650163;4840.903181936342;10233.641822536176;10242.692719218436;10249.795004100026;10254.333468324685;10292.38245705245;4513.977430112828;5303.946960530422;5357.008953343644;5365.114756065205;5366.302380690563;4381.9123617527475;4424.80088396023;4557.521460246693;4729.574338309569;4909.459959404534;3839.539255289383;3861.6331448512346;3985.20295940813;4002.599740026032;4015.9397609035886;1226.178460431513;1250.7998720204723;1251.7934540800666;1263.4882872436679;1264.7344057747791;788.4402074527035;886.7561420609384;904.2630256341192;907;952.8618350339301;4514.413126293626;5693.28833895763;5965.433283838029;6038.6678732670125;6116.816495505142;352.75307284900674;435.8627032484757;440.43624161073876;445.11866504321625;450.25258071600985;3575.94636080459;3887.8056097195026;3907.1013666856784;3993.740406873544;4035.3139966205817;10461.320014199053;10681.412522311191;10798.568057277778;10828.458577071115;10886.67339979786;10759.569617215224;10770.56917723298;10786.298890572089;10793.730156745987;10846.67459976202;7893.13175550689;8000.8799868002025;8154.347652187811;8254.133316001853;8281.047679516812;5799.797007104731;5850.824475265749;5855.063189889597;5914.911276330772;5916.67458289781;9791.902080979098;9850.014998500093;9927.305088644014;10045.447500387549;10147.18822494184;10094.041066098709;10168.542415591464;10231.94884025575;10260.897391025994;10404.24287563781;7461.100795360917;7535.924640753522;7539.396848252017;7595.506292090997;7643.847123057619;8250.628721707657;8350.749477515685;8355.83288334242;8362.247397734232;8364.7908802278;7173.46199035064;7447.851042979219;7472.131967008248;7538.396928245617;7619.695212191672;7287.781366559012;7330.157031941182;7335.449841262033;7336.853262934605;7451;6892.345227203407;7020.262872398324;7025.508214424941;7030.9296907030275;7031.1211098612675;4570.908581828278;4576.130535198302;4911.63162762787;4994.675346102492;5129.87175320605;4531.025829446553;4533.659975501784;5065.3161823153405;5106.846794596168;5141.691498510101 23:00:42 INFO - PROCESS | 1949 | |4;prototype.html;294.976401887849;296.99109026729195;304.60097272572926;309.4306476084005;311.59337065129995;3875.9612403875954;4300.860272027907;4397.241103558574;4958.826441074563;4985.202367621177;4814.518548145184;4958.975205123969;4969.310434452285;4988.975055124719;5054.545090941814;3286.5867676898924;3827.674647654953;3891.2217556488818;3923.1957448723015;3937.4684417603685;3027.4399236141276;3672.8347224374966;3895.4028848172256;3937.940930886039;3958.148997965437;3673.559172899256;3864.343061679507;3887.358585833334;3905.5899130591306;3907.765534067965;3180.930402161925;3860.285847118264;3895.4351619015083;3903.043754280205;3924.9411258830996;3026.3039500914692;3107.5960125183806;3290.210349516099;3385.036678726333;3388.491726241059;3564.0020794177412;4805.94269260762;5033;5061.645684802065;5139.560922941582;3861.710371722104;4399.5820397062225;4404.14119246747;4452.220861349251;4452.621527170194;4651.255799072166;5331.226972089025;5342.946570534284;5353.625246232766;5401.972990135064;2973.4201830643033;3252.495863141217;3699.3896007158787;3722.664960153599;3723.441483777428;4341.978290108561;4911.656184067117;5584.748686309126;5596.972015139939;5610.70780852648;3152.716255537013;3162.4623813951684;3722.7766334020043;3739.5512538495555;3778.5465744110884;3214.244652506659;3788.1855401088897;3818.694504439638;3837.6354246346555;3849.4610754494392;4106.527749308808;4116.118716898123;4398.75806830621;4482.207117153165;4520.52534483881;4536.455625324982;4537.863864084083;5202.947970520322;5413.699177849477;5440.292761940922;1212.4846940050443;1222.5598784437595;1256.9127704535695;1285.4279845468864;1304.4195333076689;850.8936382952131;877.8112705768291;885.2165833237631;888.0852721696663;889.7508697564692;5011.373578302712;5037.546620804145;5045.697258164522;5057.898842023213;5070.543651071422;4145.174168834279;4592.726600332857;4811.989482208775;4831.855044348676;5497.47773961473;4633.305004249322;4933.309336692866;4956.033573453142;4966.751662416865;5000.449950505442;2663.533881570756;2669.998750468574;2858.9285267868468;2875.252434367038;2907.011616050511;4906.803727850846;5668.51817595509;5673.517750991129;5698.886022279614;5718.285214348207;4683.086798074411;4711.18619331553;5203.216279290561;5508.768948575833;5602.347307544284;4866.853994380174;5058.620603454756;5399.433059528771;5624.537620218691;5624.71876406178;4969.732718156847;5070.771815268396;5422.891542169055;5429.212764149097;5432.728363581884;5361.882056587704;5381.973090134681;5388.144283529736;5396.271503347155;5407;4616.307553866947;5579.442055794388;5643.322801263875;5643.3510146332055;5648.774049037993;3268.607767067967;3421.743369247366;3708.5364329458816;3768.792716400624;3779.8110094494614;5343.278657381204;5540.7783688652;5566.332040155207;5593.734537512314;5601.579881508985;4387.837223135808;5416.781224224518;5420.214068960057;5436.646617969819;5438.945610543844;4448.532904045158;5232.723765162166;5318.760655770577;5373.731313434391;5383.896301258154;4626.352310676575;5011.974940125276;5092.796288148433;5163.509466600667;5283.762230699551;5400.432954539717;5405.8107966221;5407.107827208467;5409.945900540944;5461.836144915659;5297.17893726473;5298.039725301663;5316.946830531645;5320.52115309624;5340.946590534045 23:00:42 INFO - PROCESS | 1949 | |5;yui.html;706.5195666946476;721.9422446204301;771.155584634825;802.7070119406418;814.7555733280017;15492.535223943272;15860;15866.444674436398;15912.443064492745;15914.68170636588;14207.28963551821;14302.78545821811;14305.92847035766;14306.642333941656;14328.856711432882;689.1936434371772;710.7690000749751;847.9745607631781;851.4508142248262;879.3448880583974;879.5821984557316;887.0109827542309;888.2938064238946;892.9062448442918;894.7941973346134;937.4375374775149;937.6249500199906;937.7374335186158;938.7887725261829;941.1952780372785;725.5465334166093;931.4690626342987;932.6222879733718;933.9859902101474;935.8783358163395;553.1730063554971;554.4594020829699;555.1921953557564;555.5611067256863;666.6100331306192;15165.393384264617;15489.14809685467;15512;15524.534263972098;15540.300686469136;13854;14026.748868890747;14145.646358841008;14174.43302267908;14384.784228236585;12415.379231038502;12862.742745145044;12864.228146311158;12930.159539629894;13247.07270491066;4576;4642.234031384817;4701.764911754399;4830.6135509159185;4833.323334733105;78980.60509697415;105322.47338763258;105778.71987281696;105795;110789;10549.736256593647;10696.197785166143;10903.400312982703;11176.944115279372;11415.942920285346;22004.669929951062;22056.669149962763;22069.227577034726;22072.896355182176;22074.11703531873;7824.765257042184;7844.568548729874;7862.056553213651;7865.567393793282;8152.714654987047;4154.750714957112;4191.64371028466;4269.701120921506;4338.653230410323;4485.349624304523;3443.27691184854;3526.947095793565;3552.502649629054;3562.0560551453887;3570.696490798259;648.7599588152319;657.431321906557;657.8782925158861;671.6876652356677;675.5305062981279;538.3781732099432;581.9156222347735;594.5006194796392;626.589583822597;634.9904751428733;8490.150984901584;8557.871631925525;8737.257333126629;8927.285817134614;9069.708213274384;500.55950763328036;550.3368441028551;616.7717944360616;628.424991133108;647.5370110371014;3646.288973650113;4091.447654566655;4307.461567304087;4371;4377.321515165155;88335.55832220797;121638.3918080404;152357.23821380822;161854;162909;121003.26957495646;134878;138914.22171556513;142411;144814;120077;122290;122426;122519;125130.37434812768;8326.918270432392;8368.41421100517;8409.52827272658;8486.533240671823;8643.222110010129;44887.55112448834;59694.70152649209;60974;61457;63860;91231.54384228302;99172.50413747886;101554.98445015751;101636.49181754045;103164.45253321055;11332.093432525213;11399.373034483184;11447.656570302905;11458.19792614509;11814.388016294348;11946;11977.461014254206;12079.214851034654;12405.255684658949;12496.500139994298;11167.385793781095;11319.264247823865;11345.716357091138;11439.199256052329;11795.691376280001;9299.6280148796;11726.413679315829;11832.391779017657;11877.406129693309;12013.459394327101;11330.94334528322;11469.770604588028;11502.252353596989;11520.020798232244;12009.339486328336;8680.910127398058;8829.249513791143;9136.583829506437;9452.905470945478;10070.295079344376;9581.664641737505;9627.422354658744;9635.614575416905;9764.45013549307;9811.920688724378 23:00:42 INFO - PROCESS | 1949 | __end_tp_report 23:00:42 INFO - PROCESS | 1949 | __start_cc_report 23:00:42 INFO - PROCESS | 1949 | _x_x_mozilla_cycle_collect,1887 23:00:42 INFO - PROCESS | 1949 | __end_cc_report 23:00:42 INFO - PROCESS | 1949 | __startTimestamp1475560842348__endTimestamp 23:00:42 INFO - PROCESS | 1949 | 23:00:42 INFO - PROCESS | 1949 | ------- Summary: start ------- 23:00:42 INFO - PROCESS | 1949 | Number of tests: 6 23:00:42 INFO - PROCESS | 1949 | 23:00:42 INFO - PROCESS | 1949 | [#0] dojo.html Cycles:165 Average:5684.27 Median:5858.94 stddev:652.10 (11.1%) stddev-sans-first:616.75 23:00:42 INFO - PROCESS | 1949 | Values: 2911.3 3300.7 3319.6 3343.4 3356.9 5577.6 5664.7 5676.9 5684.9 5698.8 5645.7 5710.1 5777.1 5777.4 5810.2 5065.1 5142.5 5152.7 5202.1 5204.6 5268.2 5269.0 5279.8 5282.1 5286.7 5133.5 5228.5 5244.0 5304.4 5348.1 5160.2 5204.4 5206.8 5209.2 5226.3 5123.6 5148.3 5175.2 5181.7 5188.0 5634.5 5638.9 5639.4 5693.5 5721.8 6129.6 6147.9 6164.1 6208.6 6212.9 5464.2 5527.8 6067.7 6282.2 6286.1 5583.5 5671.7 5682.9 5706.5 5727.1 6312.1 6354.9 6361.2 6366.6 6371.3 6082.3 6092.2 6105.6 6114.4 6116.1 6107.9 6128.1 6129.5 6157.2 6247.8 6097.8 6161.7 6180.9 6181.2 6181.3 6080.5 6168.8 6197.8 6215.2 6242.5 4538.9 4816.8 4854.8 4868.9 4900.5 4405.9 4412.3 4443.3 4443.4 4445.9 5702.5 5707.1 5717.5 5722.5 5731.1 6136.9 6156.6 6168.9 6172.1 6179.6 5754.2 5764.9 5765.0 5809.9 5818.4 5359.9 5374.3 5383.9 5385.9 5391.4 6260.9 6328.1 6334.5 6338.0 6354.9 5159.3 6157.3 6163.8 6185.9 6213.3 6323.6 6360.3 6364.6 6374.7 6384.8 6059.7 6149.7 6161.6 6168.9 6177.8 6099.6 6114.7 6147.1 6147.6 6230.5 5439.0 5909.7 6070.8 6075.7 6077.6 4547.7 5989.8 6043.4 6054.1 6058.6 5742.6 5858.9 5870.4 5888.9 5896.1 5953.8 5995.8 6005.0 6067.1 6090.8 4912.6 5910.0 5961.2 5962.4 5992.4 23:00:42 INFO - PROCESS | 1949 | 23:00:42 INFO - PROCESS | 1949 | [#1] ext.html Cycles:180 Average:68147.38 Median:39946.30 stddev:150479.50 (376.7%) stddev-sans-first:150815.82 23:00:42 INFO - PROCESS | 1949 | Values: 462.6 464.4 477.3 524.3 526.3 29960.4 39233.8 40279.2 40303.4 42327.2 40869.8 41283.0 41292.0 41337.4 41338.4 9498.4 9667.3 9678.0 9688.6 9701.4 9515.7 9616.6 9655.4 9657.6 9666.9 8298.2 9651.3 9696.7 9703.6 9715.7 9589.5 9617.8 9647.9 9660.5 9669.5 9569.0 9652.1 9653.5 9655.0 9658.7 39213.4 39534.4 39577.2 39581.0 39613.4 3247.3 3517.9 3669.3 3927.6 4065.5 29574.3 30124.6 30856.5 30992.0 31102.0 3145.9 3278.6 3311.0 3340.0 3340.3 449360.8 453028.5 453525.0 454412.0 457095.0 51439.0 51513.7 51582.7 51680.0 51811.0 57797.4 57812.0 57831.1 57836.4 57863.0 15572.0 15777.7 15792.8 15818.9 15830.1 12265.0 12294.0 12308.0 12313.8 12319.4 14514.7 14907.8 15064.5 15114.8 15286.2 6189.4 6243.4 6249.8 6302.2 6345.8 861.6 925.4 972.8 973.5 1110.3 25449.6 29193.3 29597.4 29597.4 29781.6 873.4 1033.8 1064.7 1105.3 1144.8 15909.3 16167.9 16171.9 16380.9 16685.8 658952.0 662503.0 662633.0 662777.0 665874.0 54762.7 54835.7 54878.0 54890.5 55008.0 1204.1 1243.5 1318.4 1349.1 1361.7 24980.9 29760.4 29999.6 30753.7 31492.0 47170.8 47790.0 47863.5 47933.5 47961.0 549136.0 550594.0 552201.0 552753.0 554265.4 23560.6 25797.5 25831.0 26417.7 26514.9 44202.7 44521.6 45066.8 46476.1 47728.0 31970.8 37080.0 37278.1 37614.2 38414.6 37005.6 37095.0 38058.0 38065.8 38811.6 36347.0 36776.8 37565.4 37685.1 37704.2 31991.8 33468.3 33547.7 33662.7 34174.0 24970.3 25159.5 25193.6 25324.9 25626.0 23:00:42 INFO - PROCESS | 1949 | 23:00:42 INFO - PROCESS | 1949 | [#2] jquery.html Cycles:180 Average:33680.18 Median:358.33 stddev:96927.12 (27049.9%) stddev-sans-first:97166.65 23:00:42 INFO - PROCESS | 1949 | Values: 316.6 339.3 348.1 368.5 371.7 15168.2 16042.9 18414.7 18917.2 20013.4 16943.2 19514.5 19897.7 21066.8 21081.2 5403.4 5819.6 5926.4 5957.9 5968.2 5718.8 5785.7 5921.8 5922.9 5931.1 5927.3 6026.5 6140.3 6151.2 6167.9 5873.5 6049.3 6125.4 6138.8 6139.8 5385.7 5481.2 5897.0 5903.5 5904.9 12964.1 13158.4 13182.4 13282.5 13505.8 1807.0 2138.2 2319.2 2320.8 2751.5 9421.4 9968.2 10419.2 10424.4 10535.4 1573.4 1672.8 1815.8 1821.6 1879.1 80236.2 98577.0 98664.0 114849.0 122361.8 10883.3 11306.5 11486.7 11491.7 11662.5 14458.9 14473.3 14493.8 14497.1 14519.3 7986.0 8315.3 8377.0 8491.7 8579.4 8342.4 8353.3 8372.4 8542.5 8547.0 4451.6 4519.0 4589.1 4590.4 4593.1 1457.7 1850.1 1888.5 1914.5 1914.7 711.7 736.9 741.3 770.2 891.8 19789.0 20475.6 21470.9 21591.2 21663.0 886.8 889.0 915.3 931.7 942.5 10611.2 11172.4 11211.6 11219.2 11247.6 565807.0 574826.1 576766.0 591506.0 593168.0 124046.0 130575.7 131013.3 131108.0 133519.3 944.2 961.4 980.3 1017.5 1027.2 13823.5 18854.3 19481.3 20075.5 20227.6 54466.0 54620.7 54954.6 55207.0 55220.5 77193.6 77606.6 78445.0 78542.2 80263.0 5874.4 6408.5 7557.8 7725.4 8274.3 6867.9 10496.3 11371.5 11384.8 12267.2 10447.3 10867.3 11081.2 11157.1 11268.7 9330.7 10656.4 11091.4 11250.0 11386.1 11165.7 11318.9 11384.1 11548.5 11575.3 7729.4 7986.6 7987.0 8103.1 8253.7 8094.0 9534.1 10667.7 11012.1 11121.1 23:00:42 INFO - PROCESS | 1949 | 23:00:42 INFO - PROCESS | 1949 | [#3] mootools.html Cycles:180 Average:5714.35 Median:4516.32 stddev:3288.38 (72.8%) stddev-sans-first:3281.86 23:00:42 INFO - PROCESS | 1949 | Values: 1431.8 1502.1 1603.9 1694.2 1844.3 8556.4 9073.0 9104.0 9106.0 9109.3 7986.8 8299.3 8305.2 8679.8 8995.1 2362.1 2387.9 2402.4 2406.8 2407.8 2139.6 2242.2 2339.3 2347.7 2360.8 1940.0 2344.5 2373.6 2375.0 2387.4 2325.5 2345.5 2354.4 2364.3 2367.4 2359.6 2362.0 2362.7 2367.1 2369.9 8907.7 8994.6 9011.7 9025.6 9027.5 5186.5 5188.3 5361.2 5364.3 5374.1 3016.4 3543.5 3587.4 3694.3 3718.5 1067.3 1094.1 1098.2 1113.8 1117.0 12067.4 12200.2 12203.8 12211.6 12216.0 4518.2 4635.9 4771.7 4831.4 4840.9 10233.6 10242.7 10249.8 10254.3 10292.4 4514.0 5303.9 5357.0 5365.1 5366.3 4381.9 4424.8 4557.5 4729.6 4909.5 3839.5 3861.6 3985.2 4002.6 4015.9 1226.2 1250.8 1251.8 1263.5 1264.7 788.4 886.8 904.3 907.0 952.9 4514.4 5693.3 5965.4 6038.7 6116.8 352.8 435.9 440.4 445.1 450.3 3575.9 3887.8 3907.1 3993.7 4035.3 10461.3 10681.4 10798.6 10828.5 10886.7 10759.6 10770.6 10786.3 10793.7 10846.7 7893.1 8000.9 8154.3 8254.1 8281.0 5799.8 5850.8 5855.1 5914.9 5916.7 9791.9 9850.0 9927.3 10045.4 10147.2 10094.0 10168.5 10231.9 10260.9 10404.2 7461.1 7535.9 7539.4 7595.5 7643.8 8250.6 8350.7 8355.8 8362.2 8364.8 7173.5 7447.9 7472.1 7538.4 7619.7 7287.8 7330.2 7335.4 7336.9 7451.0 6892.3 7020.3 7025.5 7030.9 7031.1 4570.9 4576.1 4911.6 4994.7 5129.9 4531.0 4533.7 5065.3 5106.8 5141.7 23:00:42 INFO - PROCESS | 1949 | 23:00:42 INFO - PROCESS | 1949 | [#4] prototype.html Cycles:180 Average:4293.02 Median:4849.35 stddev:1326.47 (27.4%) stddev-sans-first:1295.80 23:00:42 INFO - PROCESS | 1949 | Values: 295.0 297.0 304.6 309.4 311.6 3876.0 4300.9 4397.2 4958.8 4985.2 4814.5 4959.0 4969.3 4989.0 5054.5 3286.6 3827.7 3891.2 3923.2 3937.5 3027.4 3672.8 3895.4 3937.9 3958.1 3673.6 3864.3 3887.4 3905.6 3907.8 3180.9 3860.3 3895.4 3903.0 3924.9 3026.3 3107.6 3290.2 3385.0 3388.5 3564.0 4805.9 5033.0 5061.6 5139.6 3861.7 4399.6 4404.1 4452.2 4452.6 4651.3 5331.2 5342.9 5353.6 5402.0 2973.4 3252.5 3699.4 3722.7 3723.4 4342.0 4911.7 5584.7 5597.0 5610.7 3152.7 3162.5 3722.8 3739.6 3778.5 3214.2 3788.2 3818.7 3837.6 3849.5 4106.5 4116.1 4398.8 4482.2 4520.5 4536.5 4537.9 5202.9 5413.7 5440.3 1212.5 1222.6 1256.9 1285.4 1304.4 850.9 877.8 885.2 888.1 889.8 5011.4 5037.5 5045.7 5057.9 5070.5 4145.2 4592.7 4812.0 4831.9 5497.5 4633.3 4933.3 4956.0 4966.8 5000.4 2663.5 2670.0 2858.9 2875.3 2907.0 4906.8 5668.5 5673.5 5698.9 5718.3 4683.1 4711.2 5203.2 5508.8 5602.3 4866.9 5058.6 5399.4 5624.5 5624.7 4969.7 5070.8 5422.9 5429.2 5432.7 5361.9 5382.0 5388.1 5396.3 5407.0 4616.3 5579.4 5643.3 5643.4 5648.8 3268.6 3421.7 3708.5 3768.8 3779.8 5343.3 5540.8 5566.3 5593.7 5601.6 4387.8 5416.8 5420.2 5436.6 5438.9 4448.5 5232.7 5318.8 5373.7 5383.9 4626.4 5012.0 5092.8 5163.5 5283.8 5400.4 5405.8 5407.1 5409.9 5461.8 5297.2 5298.0 5316.9 5320.5 5340.9 23:00:42 INFO - PROCESS | 1949 | 23:00:42 INFO - PROCESS | 1949 | [#5] yui.html Cycles:180 Average:24614.74 Median:4288.58 stddev:40285.66 (939.4%) stddev-sans-first:40358.67 23:00:42 INFO - PROCESS | 1949 | Values: 706.5 721.9 771.2 802.7 814.8 15492.5 15860.0 15866.4 15912.4 15914.7 14207.3 14302.8 14305.9 14306.6 14328.9 689.2 710.8 848.0 851.5 879.3 879.6 887.0 888.3 892.9 894.8 937.4 937.6 937.7 938.8 941.2 725.5 931.5 932.6 934.0 935.9 553.2 554.5 555.2 555.6 666.6 15165.4 15489.1 15512.0 15524.5 15540.3 13854.0 14026.7 14145.6 14174.4 14384.8 12415.4 12862.7 12864.2 12930.2 13247.1 4576.0 4642.2 4701.8 4830.6 4833.3 78980.6 105322.5 105778.7 105795.0 110789.0 10549.7 10696.2 10903.4 11176.9 11415.9 22004.7 22056.7 22069.2 22072.9 22074.1 7824.8 7844.6 7862.1 7865.6 8152.7 4154.8 4191.6 4269.7 4338.7 4485.3 3443.3 3526.9 3552.5 3562.1 3570.7 648.8 657.4 657.9 671.7 675.5 538.4 581.9 594.5 626.6 635.0 8490.2 8557.9 8737.3 8927.3 9069.7 500.6 550.3 616.8 628.4 647.5 3646.3 4091.4 4307.5 4371.0 4377.3 88335.6 121638.4 152357.2 161854.0 162909.0 121003.3 134878.0 138914.2 142411.0 144814.0 120077.0 122290.0 122426.0 122519.0 125130.4 8326.9 8368.4 8409.5 8486.5 8643.2 44887.6 59694.7 60974.0 61457.0 63860.0 91231.5 99172.5 101555.0 101636.5 103164.5 11332.1 11399.4 11447.7 11458.2 11814.4 11946.0 11977.5 12079.2 12405.3 12496.5 11167.4 11319.3 11345.7 11439.2 11795.7 9299.6 11726.4 11832.4 11877.4 12013.5 11330.9 11469.8 11502.3 11520.0 12009.3 8680.9 8829.2 9136.6 9452.9 10070.3 9581.7 9627.4 9635.6 9764.5 9811.9 23:00:42 INFO - PROCESS | 1949 | -------- Summary: end -------- 23:00:42 INFO - PROCESS | 1949 | 23:00:43 INFO - TEST-INFO | 1949: exit 0 23:00:43 INFO - TEST-OK | dromaeo_css | took 1118796ms 23:00:43 INFO - TEST-START | kraken 23:00:43 INFO - operating with platform_type : mac_ 23:00:43 INFO - Initialising browser for kraken test... 23:00:43 INFO - TEST-INFO | started process 1952 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/T/tmpl0iGHQ/profile http://localhost:51856/getInfo.html) 23:00:45 INFO - PROCESS | 1952 | __metrics Screen width/height:1600/1200 23:00:45 INFO - PROCESS | 1952 | colorDepth:24 23:00:45 INFO - PROCESS | 1952 | Browser inner width/height: 1024/689 23:00:45 INFO - PROCESS | 1952 | __metrics 23:00:45 INFO - PROCESS | 1952 | 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" 23:00:45 INFO - PROCESS | 1952 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 23:00:46 INFO - TEST-INFO | 1952: exit 0 23:00:46 INFO - Browser initialized. 23:00:46 INFO - Running cycle 1/1 for kraken test... 23:00:46 INFO - TEST-INFO | started process 1954 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/T/tmpl0iGHQ/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 23:00:58 INFO - PROCESS | 1954 | RSS: Main: 236257280 23:00:58 INFO - PROCESS | 1954 | 23:01:01 INFO - PROCESS | 1954 | Cycle 1(1): loaded http://localhost:51856/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:51856/tests/kraken/driver.html?testName=audio-beat-detection) 23:01:02 INFO - PROCESS | 1954 | RSS: Main: 295219200 23:01:02 INFO - PROCESS | 1954 | 23:01:04 INFO - PROCESS | 1954 | Cycle 1(1): loaded http://localhost:51856/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:51856/tests/kraken/driver.html?testName=audio-dft) 23:01:05 INFO - PROCESS | 1954 | RSS: Main: 294121472 23:01:05 INFO - PROCESS | 1954 | 23:01:08 INFO - PROCESS | 1954 | Cycle 1(1): loaded http://localhost:51856/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:51856/tests/kraken/driver.html?testName=audio-fft) 23:01:09 INFO - PROCESS | 1954 | RSS: Main: 387047424 23:01:09 INFO - PROCESS | 1954 | 23:01:11 INFO - PROCESS | 1954 | Cycle 1(1): loaded http://localhost:51856/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:51856/tests/kraken/driver.html?testName=audio-oscillator) 23:01:12 INFO - PROCESS | 1954 | RSS: Main: 287981568 23:01:12 INFO - PROCESS | 1954 | 23:01:14 INFO - PROCESS | 1954 | Cycle 1(1): loaded http://localhost:51856/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:51856/tests/kraken/driver.html?testName=imaging-gaussian-blur) 23:01:14 INFO - PROCESS | 1954 | RSS: Main: 292392960 23:01:14 INFO - PROCESS | 1954 | 23:01:17 INFO - PROCESS | 1954 | Cycle 1(1): loaded http://localhost:51856/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:51856/tests/kraken/driver.html?testName=imaging-darkroom) 23:01:18 INFO - PROCESS | 1954 | RSS: Main: 329736192 23:01:18 INFO - PROCESS | 1954 | 23:01:21 INFO - PROCESS | 1954 | Cycle 1(1): loaded http://localhost:51856/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:51856/tests/kraken/driver.html?testName=imaging-desaturate) 23:01:21 INFO - PROCESS | 1954 | RSS: Main: 330866688 23:01:21 INFO - PROCESS | 1954 | 23:01:24 INFO - PROCESS | 1954 | Cycle 1(1): loaded http://localhost:51856/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:51856/tests/kraken/driver.html?testName=json-parse-financial) 23:01:25 INFO - PROCESS | 1954 | RSS: Main: 328548352 23:01:25 INFO - PROCESS | 1954 | 23:01:26 INFO - PROCESS | 1954 | Cycle 1(1): loaded http://localhost:51856/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:51856/tests/kraken/driver.html?testName=json-stringify-tinderbox) 23:01:27 INFO - PROCESS | 1954 | RSS: Main: 302452736 23:01:27 INFO - PROCESS | 1954 | 23:01:29 INFO - PROCESS | 1954 | Cycle 1(1): loaded http://localhost:51856/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:51856/tests/kraken/driver.html?testName=stanford-crypto-aes) 23:01:29 INFO - PROCESS | 1954 | RSS: Main: 307015680 23:01:29 INFO - PROCESS | 1954 | 23:01:32 INFO - PROCESS | 1954 | Cycle 1(1): loaded http://localhost:51856/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:51856/tests/kraken/driver.html?testName=stanford-crypto-ccm) 23:01:32 INFO - PROCESS | 1954 | RSS: Main: 312152064 23:01:32 INFO - PROCESS | 1954 | 23:01:35 INFO - PROCESS | 1954 | Cycle 1(1): loaded http://localhost:51856/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:51856/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 23:01:36 INFO - PROCESS | 1954 | RSS: Main: 338817024 23:01:36 INFO - PROCESS | 1954 | 23:01:39 INFO - PROCESS | 1954 | Cycle 1(1): loaded http://localhost:51856/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:51856/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 23:01:39 INFO - PROCESS | 1954 | RSS: Main: 325160960 23:01:39 INFO - PROCESS | 1954 | 23:01:41 INFO - PROCESS | 1954 | Cycle 1(1): loaded http://localhost:51856/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:51856/tests/kraken/driver.html?testName=ai-astar) 23:01:41 INFO - PROCESS | 1954 | RSS: Main: 369115136 23:01:41 INFO - PROCESS | 1954 | 23:01:41 INFO - PROCESS | 1954 | __start_tp_report 23:01:41 INFO - PROCESS | 1954 | _x_x_mozilla_page_load 23:01:41 INFO - PROCESS | 1954 | _x_x_mozilla_page_load_details 23:01:41 INFO - PROCESS | 1954 | |i|pagename|runs| 23:01:41 INFO - PROCESS | 1954 | |0;ai-astar;113;112;112;113;113;111;113;112;112;113 23:01:41 INFO - PROCESS | 1954 | |1;audio-beat-detection;152;115;114;149;128;98;111;116;172;144 23:01:41 INFO - PROCESS | 1954 | |2;audio-dft;184;183;183;187;186;182;184;178;186;184 23:01:41 INFO - PROCESS | 1954 | |3;audio-fft;80;59;58;89;61;60;58;58;82;58 23:01:41 INFO - PROCESS | 1954 | |4;audio-oscillator;87;72;92;71;68;69;93;68;69;90 23:01:41 INFO - PROCESS | 1954 | |5;imaging-gaussian-blur;89;103;136;71;131;131;105;86;88;85 23:01:41 INFO - PROCESS | 1954 | |6;imaging-darkroom;93;93;94;93;98;93;94;94;93;93 23:01:41 INFO - PROCESS | 1954 | |7;imaging-desaturate;84;89;84;85;85;86;84;88;87;85 23:01:41 INFO - PROCESS | 1954 | |8;json-parse-financial;57;52;52;53;54;54;54;54;54;55 23:01:41 INFO - PROCESS | 1954 | |9;json-stringify-tinderbox;63;72;67;79;73;72;68;69;70;73 23:01:41 INFO - PROCESS | 1954 | |10;stanford-crypto-aes;91;85;86;91;91;89;89;91;91;118 23:01:41 INFO - PROCESS | 1954 | |11;stanford-crypto-ccm;147;152;152;143;130;152;163;163;146;147 23:01:41 INFO - PROCESS | 1954 | |12;stanford-crypto-pbkdf2;173;171;172;173;171;172;172;169;172;173 23:01:41 INFO - PROCESS | 1954 | |13;stanford-crypto-sha256-iterative;72;90;88;69;68;69;69;68;69;69 23:01:41 INFO - PROCESS | 1954 | __end_tp_report 23:01:41 INFO - PROCESS | 1954 | __start_cc_report 23:01:41 INFO - PROCESS | 1954 | _x_x_mozilla_cycle_collect,1973 23:01:41 INFO - PROCESS | 1954 | __end_cc_report 23:01:41 INFO - PROCESS | 1954 | __startTimestamp1475560901964__endTimestamp 23:01:41 INFO - PROCESS | 1954 | 23:01:41 INFO - PROCESS | 1954 | ------- Summary: start ------- 23:01:41 INFO - PROCESS | 1954 | Number of tests: 14 23:01:41 INFO - PROCESS | 1954 | 23:01:41 INFO - PROCESS | 1954 | [#0] ai-astar Cycles:10 Average:112.40 Median:113.00 stddev:0.70 (0.6%) stddev-sans-first:0.71 23:01:41 INFO - PROCESS | 1954 | Values: 113.0 112.0 112.0 113.0 113.0 111.0 113.0 112.0 112.0 113.0 23:01:41 INFO - PROCESS | 1954 | 23:01:41 INFO - PROCESS | 1954 | [#1] audio-beat-detection Cycles:10 Average:129.90 Median:146.50 stddev:23.26 (15.9%) stddev-sans-first:23.26 23:01:41 INFO - PROCESS | 1954 | Values: 152.0 115.0 114.0 149.0 128.0 98.0 111.0 116.0 172.0 144.0 23:01:41 INFO - PROCESS | 1954 | 23:01:41 INFO - PROCESS | 1954 | [#2] audio-dft Cycles:10 Average:183.70 Median:184.00 stddev:2.54 (1.4%) stddev-sans-first:2.69 23:01:41 INFO - PROCESS | 1954 | Values: 184.0 183.0 183.0 187.0 186.0 182.0 184.0 178.0 186.0 184.0 23:01:41 INFO - PROCESS | 1954 | 23:01:41 INFO - PROCESS | 1954 | [#3] audio-fft Cycles:10 Average:66.30 Median:60.50 stddev:12.23 (20.2%) stddev-sans-first:11.92 23:01:41 INFO - PROCESS | 1954 | Values: 80.0 59.0 58.0 89.0 61.0 60.0 58.0 58.0 82.0 58.0 23:01:41 INFO - PROCESS | 1954 | 23:01:41 INFO - PROCESS | 1954 | [#4] audio-oscillator Cycles:10 Average:77.90 Median:79.50 stddev:11.02 (13.9%) stddev-sans-first:11.19 23:01:41 INFO - PROCESS | 1954 | Values: 87.0 72.0 92.0 71.0 68.0 69.0 93.0 68.0 69.0 90.0 23:01:41 INFO - PROCESS | 1954 | 23:01:41 INFO - PROCESS | 1954 | [#5] imaging-gaussian-blur Cycles:10 Average:102.50 Median:78.00 stddev:22.89 (29.3%) stddev-sans-first:23.75 23:01:41 INFO - PROCESS | 1954 | Values: 89.0 103.0 136.0 71.0 131.0 131.0 105.0 86.0 88.0 85.0 23:01:41 INFO - PROCESS | 1954 | 23:01:41 INFO - PROCESS | 1954 | [#6] imaging-darkroom Cycles:10 Average:93.80 Median:93.50 stddev:1.55 (1.7%) stddev-sans-first:1.62 23:01:41 INFO - PROCESS | 1954 | Values: 93.0 93.0 94.0 93.0 98.0 93.0 94.0 94.0 93.0 93.0 23:01:41 INFO - PROCESS | 1954 | 23:01:41 INFO - PROCESS | 1954 | [#7] imaging-desaturate Cycles:10 Average:85.70 Median:85.50 stddev:1.77 (2.1%) stddev-sans-first:1.76 23:01:41 INFO - PROCESS | 1954 | Values: 84.0 89.0 84.0 85.0 85.0 86.0 84.0 88.0 87.0 85.0 23:01:41 INFO - PROCESS | 1954 | 23:01:41 INFO - PROCESS | 1954 | [#8] json-parse-financial Cycles:10 Average:53.90 Median:54.00 stddev:1.45 (2.7%) stddev-sans-first:1.01 23:01:41 INFO - PROCESS | 1954 | Values: 57.0 52.0 52.0 53.0 54.0 54.0 54.0 54.0 54.0 55.0 23:01:41 INFO - PROCESS | 1954 | 23:01:41 INFO - PROCESS | 1954 | [#9] json-stringify-tinderbox Cycles:10 Average:70.60 Median:72.00 stddev:4.30 (6.0%) stddev-sans-first:3.57 23:01:41 INFO - PROCESS | 1954 | Values: 63.0 72.0 67.0 79.0 73.0 72.0 68.0 69.0 70.0 73.0 23:01:41 INFO - PROCESS | 1954 | 23:01:41 INFO - PROCESS | 1954 | [#10] stanford-crypto-aes Cycles:10 Average:92.20 Median:91.00 stddev:9.33 (10.3%) stddev-sans-first:9.89 23:01:41 INFO - PROCESS | 1954 | Values: 91.0 85.0 86.0 91.0 91.0 89.0 89.0 91.0 91.0 118.0 23:01:41 INFO - PROCESS | 1954 | 23:01:41 INFO - PROCESS | 1954 | [#11] stanford-crypto-ccm Cycles:10 Average:149.50 Median:152.00 stddev:9.61 (6.3%) stddev-sans-first:10.15 23:01:41 INFO - PROCESS | 1954 | Values: 147.0 152.0 152.0 143.0 130.0 152.0 163.0 163.0 146.0 147.0 23:01:41 INFO - PROCESS | 1954 | 23:01:41 INFO - PROCESS | 1954 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:171.80 Median:172.00 stddev:1.23 (0.7%) stddev-sans-first:1.22 23:01:41 INFO - PROCESS | 1954 | Values: 173.0 171.0 172.0 173.0 171.0 172.0 172.0 169.0 172.0 173.0 23:01:41 INFO - PROCESS | 1954 | 23:01:41 INFO - PROCESS | 1954 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:73.10 Median:69.00 stddev:8.46 (12.3%) stddev-sans-first:8.97 23:01:41 INFO - PROCESS | 1954 | Values: 72.0 90.0 88.0 69.0 68.0 69.0 69.0 68.0 69.0 69.0 23:01:41 INFO - PROCESS | 1954 | -------- Summary: end -------- 23:01:41 INFO - PROCESS | 1954 | 23:01:42 INFO - TEST-INFO | 1954: exit 0 23:01:42 INFO - TEST-OK | kraken | took 59760ms 23:01:42 INFO - SUITE-END | took 1178s 23:01:43 INFO - Completed test suite (00:19:38) 23:01:43 INFO - javascript benchmark 23:01:43 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2911.344947386838, 3300.686434788695, 3319.5850518685147, 3343.448331025381, 3356.88250911218, 5577.609567330289, 5664.660120392779, 5676.886462270752, 5684.8578785530335, 5698.829035128948, 5645.717714114298, 5710.086386178213, 5777.133429985493, 5777.364489906107, 5810.18657387965, 5065.113605119108, 5142.460041695625, 5152.74236288186, 5202.115640341151, 5204.583633309345, 5268.209768534713, 5268.998890210867, 5279.788808447676, 5282.102042652758, 5286.709230992294, 5133.512316329943, 5228.529432351107, 5244.0, 5304.443033481487, 5348.064088784467, 5160.200168973815, 5204.401493828221, 5206.7917283308625, 5209.166533354645, 5226.2944502492055, 5123.615728820316, 5148.2535032420465, 5175.249588809639, 5181.715005674687, 5187.988342273258, 5634.464725851038, 5638.887222255573, 5639.379668236491, 5693.487586117228, 5721.7711291548285, 6129.570930034899, 6147.907781383283, 6164.106204600308, 6208.565400421972, 6212.9068063979075, 5464.207689884985, 5527.778888844482, 6067.666278354689, 6282.246130464374, 6286.057091436275, 5583.469570390806, 5671.744771485293, 5682.914756278659, 5706.486416222477, 5727.11229759388, 6312.084747711556, 6354.936450635527, 6361.236651601745, 6366.586171898812, 6371.267304260069, 6082.300535438481, 6092.238470191226, 6105.572609917263, 6114.449699527064, 6116.113163591254, 6107.877842443127, 6128.1364821545585, 6129.511613758974, 6157.230346206724, 6247.812565623039, 6097.815189446183, 6161.7227224775, 6180.8763824723255, 6181.227346581677, 6181.289151747606, 6080.483158931452, 6168.753249869955, 6197.783077592263, 6215.223097112861, 6242.500599951993, 4538.909221815611, 4816.759162041954, 4854.83008094715, 4868.8782780430065, 4900.485449027872, 4405.867823965286, 4412.271975124068, 4443.266860967904, 4443.4445694288215, 4445.888852778707, 5702.54379649627, 5707.1153971134545, 5717.542596592262, 5722.484976352148, 5731.140328950607, 6136.886723216021, 6156.5998210116195, 6168.876622467616, 6172.074188871616, 6179.62922224668, 5754.1944127822135, 5764.884702305931, 5764.971175144097, 5809.941900581025, 5818.418158184148, 5359.866003349869, 5374.3013408256675, 5383.865403364869, 5385.919211211835, 5391.353037635509, 6260.906086408617, 6328.114064030948, 6334.524910631813, 6337.96831015842, 6354.936450635527, 5159.303494028258, 6157.322694503511, 6163.845903852261, 6185.907211391743, 6213.285472170577, 6323.620582765049, 6360.300366959723, 6364.586301890362, 6374.713137908712, 6384.840378990377, 6059.666718330534, 6149.723262453111, 6161.5686901918225, 6168.938310617016, 6177.752889884354, 6099.64271536338, 6114.6636934969, 6147.108669242845, 6147.616786223064, 6230.532710046674, 5438.972805135949, 5909.734061967136, 6070.817875463743, 6075.665838378754, 6077.6353418795015, 4547.658925580661, 5989.790357337472, 6043.425874541911, 6054.091886217103, 6058.606190597597, 5742.569307301886, 5858.9414105860105, 5870.354261031198, 5888.941110588839, 5896.115582662635, 5953.761849526144, 5995.790147344822, 6005.039193728982, 6067.059605761114, 6090.817275481742, 4912.557869791736, 5909.995300798961, 5961.165436838933, 5962.433568810956, 5992.43071908168], "value": 5642.4211625314265, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [462.5998511287736, 464.35222864104577, 477.31028663581117, 524.2817340243867, 526.310533201506, 29960.400791984146, 39233.80383098084, 40279.194416111735, 40303.39544906829, 42327.15345693092, 40869.79565102178, 41283.0, 41292.0, 41337.37993930093, 41338.37992430116, 9498.382605130644, 9667.323287369887, 9678.032196780336, 9688.612455501772, 9701.369410988264, 9515.714528564155, 9616.615335386576, 9655.420674759502, 9657.565409556586, 9666.854997175047, 8298.21166989135, 9651.324407291493, 9696.70909872705, 9703.563339649732, 9715.659951901685, 9589.472579008152, 9617.759556011086, 9647.90352096477, 9660.516974151264, 9669.468179250138, 9568.995255498212, 9652.131308182297, 9653.517324133765, 9654.951725241399, 9658.661946831897, 39213.41179882304, 39534.40698389498, 39577.208455830725, 39581.010474738076, 39613.405798912754, 3247.3180632067056, 3517.9120521987156, 3669.3028324618504, 3927.626875446828, 4065.451164092839, 29574.26064348387, 30124.644391002457, 30856.53715194274, 30992.0, 31102.0, 3145.8898938537272, 3278.557394751702, 3310.9834450827834, 3340.0, 3340.3486320167576, 449360.7531962352, 453028.46971530194, 453525.0, 454412.0, 457095.0, 51439.0, 51513.71215719562, 51582.74208628933, 51680.0, 51810.963780724174, 57797.42202578005, 57812.0, 57831.13253301204, 57836.42163578395, 57863.0, 15571.987820791612, 15777.68444631124, 15792.763108553381, 15818.9209053954, 15830.12934288602, 12265.0, 12294.016478681684, 12308.015358771278, 12313.815292770616, 12319.445624946899, 14514.709705805824, 14907.776383354258, 15064.472743453927, 15114.848851511344, 15286.235688215767, 6189.442950134509, 6243.43809057178, 6249.78125765596, 6302.180716506825, 6345.777897773556, 861.5821326656567, 925.3984909808678, 972.7519482531909, 973.5375696544085, 1110.272771334777, 25449.618255726178, 29193.270168245537, 29597.399250699113, 29597.408051838844, 29781.553276700866, 873.4497266722052, 1033.813913495563, 1064.7391389109719, 1105.2705214558353, 1144.8053830848776, 15909.284082216096, 16167.949083309548, 16171.948823326446, 16380.935239209413, 16685.831991760704, 658952.0, 662503.0, 662633.0, 662777.0, 665874.0, 54762.72618636881, 54835.72582137064, 54878.0, 54890.45109548853, 55008.0, 1204.1029433072242, 1243.4963839644959, 1318.4133060787858, 1349.1230700045048, 1361.7072329449215, 24980.875860586682, 29760.40479190361, 29999.55000674948, 30753.692463075084, 31492.0, 47170.82072948066, 47790.0, 47863.5213647873, 47933.5206647943, 47961.0, 549136.0, 550594.0, 552201.0, 552753.0, 554265.3720388395, 23560.6465903015, 25797.484050319264, 25830.966761329335, 26417.735822641527, 26514.86742566275, 44202.67391978246, 44521.55478445304, 45066.77466612646, 46476.070478589565, 47728.0, 31970.84014579912, 37080.0, 37278.06804829792, 37614.24771504609, 38414.615853841104, 37005.629943700216, 37095.0, 38058.0, 38065.809670951465, 38811.6118838808, 36347.0, 36776.816115919246, 37565.43651845279, 37685.05787355229, 37704.245915082094, 31991.84004079965, 33468.304804522486, 33547.664523354455, 33662.663373365955, 34174.0, 24970.250892473254, 25159.49681006406, 25193.622095668947, 25324.873375633742, 25626.0], "value": 19321.76327359206, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [316.6216371436133, 339.30611898667223, 348.10884136610264, 368.5448471138144, 371.72120909318005, 15168.165750883696, 16042.91978540107, 18414.723779143325, 18917.24331026759, 20013.399598012045, 16943.237554310053, 19514.512137196576, 19897.701534476957, 21066.789332106713, 21081.156753729872, 5403.432639572838, 5819.62172458791, 5926.377730338317, 5957.910631340534, 5968.224130862982, 5718.77124915005, 5785.652860828363, 5921.763129474816, 5922.940770592282, 5931.110333449974, 5927.347991720907, 6026.487748541357, 6140.263168419796, 6151.231096112986, 6167.938320616804, 5873.471387575096, 6049.304330002062, 6125.418085281891, 6138.815835524942, 6139.754409823602, 5385.703786291752, 5481.150421684645, 5896.97051514744, 5903.49820265278, 5904.911426328608, 12964.092513524058, 13158.407871645702, 13182.406791694397, 13282.468701251939, 13505.797413038812, 1806.9819301806945, 2138.208862720803, 2319.188284100568, 2320.8027317678016, 2751.5184842652657, 9421.38760980534, 9968.202543796478, 10419.218558608134, 10424.374537527698, 10535.367877927349, 1573.3549244809574, 1672.7909011373579, 1815.8002619711822, 1821.571930596309, 1879.135597625094, 80236.19763802404, 98577.0, 98664.0, 114849.0, 122361.77638223593, 10883.292585981886, 11306.491207895664, 11486.655400338002, 11491.655250342503, 11662.475188616532, 14458.855411445962, 14473.27633618315, 14493.78259326111, 14497.130172189702, 14519.274036298355, 7985.960070199611, 8315.29320007794, 8376.958115209385, 8491.74524764258, 8579.442336248125, 8342.37432192588, 8353.289970352587, 8372.372072094495, 8542.487450752975, 8546.957265213758, 4451.554844515587, 4518.977405112953, 4589.1051245007575, 4590.403247577793, 4593.081383723269, 1457.7157454296523, 1850.1304386938118, 1888.5373083594607, 1914.5405102775233, 1914.731937528747, 711.7473296979498, 736.8931504931755, 741.3031750154837, 770.1836053783007, 891.777055736066, 19789.03164525304, 20475.59048819015, 21470.914598182564, 21591.244011729093, 21663.0, 886.822635472908, 888.9777755556033, 915.2997956563235, 931.65063101337, 942.5146049784366, 10611.151107911348, 11172.385518796382, 11211.60759373418, 11219.214654974237, 11247.606333778278, 565807.0, 574826.1258693764, 576766.0, 591506.0, 593168.0, 124046.0, 130575.69424305635, 131013.34493327473, 131108.0, 133519.3324033393, 944.1974321826392, 961.4327546747453, 980.2990861534038, 1017.5421060522795, 1027.2193133218807, 13823.51617693376, 18854.3400980965, 19481.318153864548, 20075.498112547302, 20227.59544809125, 54466.0, 54620.726896366854, 54954.62613434696, 55207.0, 55220.51507682244, 77193.61403192948, 77606.6119669398, 78445.0, 78542.21457785578, 80263.0, 5874.353821079763, 6408.455281300955, 7557.82853657684, 7725.381969442544, 8274.29668478162, 6867.896981545381, 10496.265261431627, 11371.488283027118, 11384.772304554028, 12267.202631828903, 10447.268691191544, 10867.347959122479, 11081.168912331446, 11157.051650609788, 11268.718282043015, 9330.720078397659, 10656.360618362924, 11091.445427728742, 11249.987501124939, 11386.089112871116, 11165.665030048787, 11318.886811132114, 11384.14618903569, 11548.538058477567, 11575.305481671126, 7729.420293478124, 7986.600669966362, 7986.960065199869, 8103.1491693371345, 8253.711120110767, 8094.0, 9534.141927226583, 10667.733306667395, 11012.063974562252, 11121.110311174925], "value": 9120.4950520984, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1431.8067060946773, 1502.1287653161169, 1603.8797090218222, 1694.1529235382309, 1844.3360390259504, 8556.401051926368, 9073.0, 9104.0, 9105.998340182583, 9109.271258299337, 7986.800329991753, 8299.294559962394, 8305.169483051692, 8679.784830123788, 8995.145461181193, 2362.066983541504, 2387.916422925198, 2402.411409204747, 2406.783389494945, 2407.8194135439912, 2139.5506943541873, 2242.1816037146486, 2339.2982105368405, 2347.6947996760396, 2360.7639236076343, 1939.9612007759836, 2344.542814151241, 2373.584622691022, 2375.049980008002, 2387.3554140382084, 2325.4883925536355, 2345.5074434368803, 2354.411397150712, 2364.2552595932225, 2367.3963139399343, 2359.6106642403984, 2361.9645705314433, 2362.6574146748626, 2367.0768400323886, 2369.893354799038, 8907.688230911886, 8994.550272486349, 9011.684591039282, 9025.638974441015, 9027.503487308195, 5186.481351864821, 5188.273641690166, 5361.169018802092, 5364.329458817648, 5374.086405311106, 3016.3816417634553, 3543.5039094526787, 3587.3901436755814, 3694.3350196964534, 3718.4608231806237, 1067.2902519824308, 1094.141099237103, 1098.1544210957597, 1113.8440618313443, 1117.044926587764, 12067.396630168456, 12200.206986545845, 12203.816942745865, 12211.633650990485, 12216.022718182612, 4518.231900576844, 4635.860924172281, 4771.66598338113, 4831.371921650163, 4840.903181936342, 10233.641822536176, 10242.692719218436, 10249.795004100026, 10254.333468324685, 10292.38245705245, 4513.977430112828, 5303.946960530422, 5357.008953343644, 5365.114756065205, 5366.302380690563, 4381.9123617527475, 4424.80088396023, 4557.521460246693, 4729.574338309569, 4909.459959404534, 3839.539255289383, 3861.6331448512346, 3985.20295940813, 4002.599740026032, 4015.9397609035886, 1226.178460431513, 1250.7998720204723, 1251.7934540800666, 1263.4882872436679, 1264.7344057747791, 788.4402074527035, 886.7561420609384, 904.2630256341192, 907.0, 952.8618350339301, 4514.413126293626, 5693.28833895763, 5965.433283838029, 6038.6678732670125, 6116.816495505142, 352.75307284900674, 435.8627032484757, 440.43624161073876, 445.11866504321625, 450.25258071600985, 3575.94636080459, 3887.8056097195026, 3907.1013666856784, 3993.740406873544, 4035.3139966205817, 10461.320014199053, 10681.412522311191, 10798.568057277778, 10828.458577071115, 10886.67339979786, 10759.569617215224, 10770.56917723298, 10786.298890572089, 10793.730156745987, 10846.67459976202, 7893.13175550689, 8000.8799868002025, 8154.347652187811, 8254.133316001853, 8281.047679516812, 5799.797007104731, 5850.824475265749, 5855.063189889597, 5914.911276330772, 5916.67458289781, 9791.902080979098, 9850.014998500093, 9927.305088644014, 10045.447500387549, 10147.18822494184, 10094.041066098709, 10168.542415591464, 10231.94884025575, 10260.897391025994, 10404.24287563781, 7461.100795360917, 7535.924640753522, 7539.396848252017, 7595.506292090997, 7643.847123057619, 8250.628721707657, 8350.749477515685, 8355.83288334242, 8362.247397734232, 8364.7908802278, 7173.46199035064, 7447.851042979219, 7472.131967008248, 7538.396928245617, 7619.695212191672, 7287.781366559012, 7330.157031941182, 7335.449841262033, 7336.853262934605, 7451.0, 6892.345227203407, 7020.262872398324, 7025.508214424941, 7030.9296907030275, 7031.1211098612675, 4570.908581828278, 4576.130535198302, 4911.63162762787, 4994.675346102492, 5129.87175320605, 4531.025829446553, 4533.659975501784, 5065.3161823153405, 5106.846794596168, 5141.691498510101], "value": 4484.2040384330085, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [294.976401887849, 296.99109026729195, 304.60097272572926, 309.4306476084005, 311.59337065129995, 3875.9612403875954, 4300.860272027907, 4397.241103558574, 4958.826441074563, 4985.202367621177, 4814.518548145184, 4958.975205123969, 4969.310434452285, 4988.975055124719, 5054.545090941814, 3286.5867676898924, 3827.674647654953, 3891.2217556488818, 3923.1957448723015, 3937.4684417603685, 3027.4399236141276, 3672.8347224374966, 3895.4028848172256, 3937.940930886039, 3958.148997965437, 3673.559172899256, 3864.343061679507, 3887.358585833334, 3905.5899130591306, 3907.765534067965, 3180.930402161925, 3860.285847118264, 3895.4351619015083, 3903.043754280205, 3924.9411258830996, 3026.3039500914692, 3107.5960125183806, 3290.210349516099, 3385.036678726333, 3388.491726241059, 3564.0020794177412, 4805.94269260762, 5033.0, 5061.645684802065, 5139.560922941582, 3861.710371722104, 4399.5820397062225, 4404.14119246747, 4452.220861349251, 4452.621527170194, 4651.255799072166, 5331.226972089025, 5342.946570534284, 5353.625246232766, 5401.972990135064, 2973.4201830643033, 3252.495863141217, 3699.3896007158787, 3722.664960153599, 3723.441483777428, 4341.978290108561, 4911.656184067117, 5584.748686309126, 5596.972015139939, 5610.70780852648, 3152.716255537013, 3162.4623813951684, 3722.7766334020043, 3739.5512538495555, 3778.5465744110884, 3214.244652506659, 3788.1855401088897, 3818.694504439638, 3837.6354246346555, 3849.4610754494392, 4106.527749308808, 4116.118716898123, 4398.75806830621, 4482.207117153165, 4520.52534483881, 4536.455625324982, 4537.863864084083, 5202.947970520322, 5413.699177849477, 5440.292761940922, 1212.4846940050443, 1222.5598784437595, 1256.9127704535695, 1285.4279845468864, 1304.4195333076689, 850.8936382952131, 877.8112705768291, 885.2165833237631, 888.0852721696663, 889.7508697564692, 5011.373578302712, 5037.546620804145, 5045.697258164522, 5057.898842023213, 5070.543651071422, 4145.174168834279, 4592.726600332857, 4811.989482208775, 4831.855044348676, 5497.47773961473, 4633.305004249322, 4933.309336692866, 4956.033573453142, 4966.751662416865, 5000.449950505442, 2663.533881570756, 2669.998750468574, 2858.9285267868468, 2875.252434367038, 2907.011616050511, 4906.803727850846, 5668.51817595509, 5673.517750991129, 5698.886022279614, 5718.285214348207, 4683.086798074411, 4711.18619331553, 5203.216279290561, 5508.768948575833, 5602.347307544284, 4866.853994380174, 5058.620603454756, 5399.433059528771, 5624.537620218691, 5624.71876406178, 4969.732718156847, 5070.771815268396, 5422.891542169055, 5429.212764149097, 5432.728363581884, 5361.882056587704, 5381.973090134681, 5388.144283529736, 5396.271503347155, 5407.0, 4616.307553866947, 5579.442055794388, 5643.322801263875, 5643.3510146332055, 5648.774049037993, 3268.607767067967, 3421.743369247366, 3708.5364329458816, 3768.792716400624, 3779.8110094494614, 5343.278657381204, 5540.7783688652, 5566.332040155207, 5593.734537512314, 5601.579881508985, 4387.837223135808, 5416.781224224518, 5420.214068960057, 5436.646617969819, 5438.945610543844, 4448.532904045158, 5232.723765162166, 5318.760655770577, 5373.731313434391, 5383.896301258154, 4626.352310676575, 5011.974940125276, 5092.796288148433, 5163.509466600667, 5283.762230699551, 5400.432954539717, 5405.8107966221, 5407.107827208467, 5409.945900540944, 5461.836144915659, 5297.17893726473, 5298.039725301663, 5316.946830531645, 5320.52115309624, 5340.946590534045], "value": 3880.1794642123327, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [706.5195666946476, 721.9422446204301, 771.155584634825, 802.7070119406418, 814.7555733280017, 15492.535223943272, 15860.0, 15866.444674436398, 15912.443064492745, 15914.68170636588, 14207.28963551821, 14302.78545821811, 14305.92847035766, 14306.642333941656, 14328.856711432882, 689.1936434371772, 710.7690000749751, 847.9745607631781, 851.4508142248262, 879.3448880583974, 879.5821984557316, 887.0109827542309, 888.2938064238946, 892.9062448442918, 894.7941973346134, 937.4375374775149, 937.6249500199906, 937.7374335186158, 938.7887725261829, 941.1952780372785, 725.5465334166093, 931.4690626342987, 932.6222879733718, 933.9859902101474, 935.8783358163395, 553.1730063554971, 554.4594020829699, 555.1921953557564, 555.5611067256863, 666.6100331306192, 15165.393384264617, 15489.14809685467, 15512.0, 15524.534263972098, 15540.300686469136, 13854.0, 14026.748868890747, 14145.646358841008, 14174.43302267908, 14384.784228236585, 12415.379231038502, 12862.742745145044, 12864.228146311158, 12930.159539629894, 13247.07270491066, 4576.0, 4642.234031384817, 4701.764911754399, 4830.6135509159185, 4833.323334733105, 78980.60509697415, 105322.47338763258, 105778.71987281696, 105795.0, 110789.0, 10549.736256593647, 10696.197785166143, 10903.400312982703, 11176.944115279372, 11415.942920285346, 22004.669929951062, 22056.669149962763, 22069.227577034726, 22072.896355182176, 22074.11703531873, 7824.765257042184, 7844.568548729874, 7862.056553213651, 7865.567393793282, 8152.714654987047, 4154.750714957112, 4191.64371028466, 4269.701120921506, 4338.653230410323, 4485.349624304523, 3443.27691184854, 3526.947095793565, 3552.502649629054, 3562.0560551453887, 3570.696490798259, 648.7599588152319, 657.431321906557, 657.8782925158861, 671.6876652356677, 675.5305062981279, 538.3781732099432, 581.9156222347735, 594.5006194796392, 626.589583822597, 634.9904751428733, 8490.150984901584, 8557.871631925525, 8737.257333126629, 8927.285817134614, 9069.708213274384, 500.55950763328036, 550.3368441028551, 616.7717944360616, 628.424991133108, 647.5370110371014, 3646.288973650113, 4091.447654566655, 4307.461567304087, 4371.0, 4377.321515165155, 88335.55832220797, 121638.3918080404, 152357.23821380822, 161854.0, 162909.0, 121003.26957495646, 134878.0, 138914.22171556513, 142411.0, 144814.0, 120077.0, 122290.0, 122426.0, 122519.0, 125130.37434812768, 8326.918270432392, 8368.41421100517, 8409.52827272658, 8486.533240671823, 8643.222110010129, 44887.55112448834, 59694.70152649209, 60974.0, 61457.0, 63860.0, 91231.54384228302, 99172.50413747886, 101554.98445015751, 101636.49181754045, 103164.45253321055, 11332.093432525213, 11399.373034483184, 11447.656570302905, 11458.19792614509, 11814.388016294348, 11946.0, 11977.461014254206, 12079.214851034654, 12405.255684658949, 12496.500139994298, 11167.385793781095, 11319.264247823865, 11345.716357091138, 11439.199256052329, 11795.691376280001, 9299.6280148796, 11726.413679315829, 11832.391779017657, 11877.406129693309, 12013.459394327101, 11330.94334528322, 11469.770604588028, 11502.252353596989, 11520.020798232244, 12009.339486328336, 8680.910127398058, 8829.249513791143, 9136.583829506437, 9452.905470945478, 10070.295079344376, 9581.664641737505, 9627.422354658744, 9635.614575416905, 9764.45013549307, 9811.920688724378], "value": 7553.191664563797, "unit": "score"}], "name": "dromaeo_css", "value": 7123.733243026531, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 112.0, 112.0, 113.0, 113.0, 111.0, 113.0, 112.0, 112.0, 113.0], "value": 112.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [152.0, 115.0, 114.0, 149.0, 128.0, 98.0, 111.0, 116.0, 172.0, 144.0], "value": 129.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [184.0, 183.0, 183.0, 187.0, 186.0, 182.0, 184.0, 178.0, 186.0, 184.0], "value": 183.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 59.0, 58.0, 89.0, 61.0, 60.0, 58.0, 58.0, 82.0, 58.0], "value": 66.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 72.0, 92.0, 71.0, 68.0, 69.0, 93.0, 68.0, 69.0, 90.0], "value": 77.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 103.0, 136.0, 71.0, 131.0, 131.0, 105.0, 86.0, 88.0, 85.0], "value": 102.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 93.0, 94.0, 93.0, 98.0, 93.0, 94.0, 94.0, 93.0, 93.0], "value": 93.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 89.0, 84.0, 85.0, 85.0, 86.0, 84.0, 88.0, 87.0, 85.0], "value": 85.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 52.0, 52.0, 53.0, 54.0, 54.0, 54.0, 54.0, 54.0, 55.0], "value": 53.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 72.0, 67.0, 79.0, 73.0, 72.0, 68.0, 69.0, 70.0, 73.0], "value": 70.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 85.0, 86.0, 91.0, 91.0, 89.0, 89.0, 91.0, 91.0, 118.0], "value": 92.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 152.0, 152.0, 143.0, 130.0, 152.0, 163.0, 163.0, 146.0, 147.0], "value": 149.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [173.0, 171.0, 172.0, 173.0, 171.0, 172.0, 172.0, 169.0, 172.0, 173.0], "value": 171.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [72.0, 90.0, 88.0, 69.0, 68.0, 69.0, 69.0, 68.0, 69.0, 69.0], "value": 73.1, "unit": "score"}], "name": "kraken", "value": 1463.3, "alertThreshold": 2.0}]} 23:01:43 INFO - Return code: 0 23:01:43 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 23:01:43 INFO - # TBPL SUCCESS # 23:01:43 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 23:01:43 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 23:01:43 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 23:01:43 INFO - 2016-10-03 23:01:43.304 screenresolution[1961:13833] starting screenresolution argv=screenresolution get 23:01:43 INFO - 2016-10-03 23:01:43.308 screenresolution[1961:13833] Display 0: 1600x1200x32@60 23:01:43 INFO - 2016-10-03 23:01:43.314 screenresolution[1962:13835] starting screenresolution argv=screenresolution list 23:01:43 INFO - Available Modes on Display 0 23:01:43 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 23:01:43 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 23:01:43 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 23:01:43 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 23:01:43 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 23:01:43 INFO - Intel Iris: 23:01:43 INFO - Chipset Model: Intel Iris 23:01:43 INFO - Type: GPU 23:01:43 INFO - Bus: Built-In 23:01:43 INFO - VRAM (Dynamic, Max): 1536 MB 23:01:43 INFO - Vendor: Intel (0x8086) 23:01:43 INFO - Device ID: 0x0a2e 23:01:43 INFO - Revision ID: 0x0009 23:01:43 INFO - Displays: 23:01:43 INFO - Display: 23:01:43 INFO - Resolution: 1600 x 1200 @ 60 Hz 23:01:43 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 23:01:43 INFO - Display Serial Number: 1600x1200 60 23:01:43 INFO - Main Display: Yes 23:01:43 INFO - Mirror: Off 23:01:43 INFO - Online: Yes 23:01:43 INFO - Rotation: Supported 23:01:43 INFO - Return code: 0 23:01:43 INFO - Running post-action listener: _resource_record_post_action 23:01:43 INFO - [mozharness: 2016-10-04 06:01:43.550635Z] Finished run-tests step (success) 23:01:43 INFO - Running post-run listener: _resource_record_post_run 23:01:43 INFO - Total resource usage - Wall time: 1213s; CPU: 53.0%; Read bytes: 5980160; Write bytes: 705146880; Read time: 188; Write time: 2367 23:01:43 INFO - TinderboxPrint: CPU usage
53.4% 23:01:43 INFO - TinderboxPrint: I/O read bytes / time
5,980,160 / 188 23:01:43 INFO - TinderboxPrint: I/O write bytes / time
705,146,880 / 2,367 23:01:43 INFO - TinderboxPrint: CPU idle
2,259.9 (46.6%) 23:01:43 INFO - TinderboxPrint: CPU system
973.5 (20.1%) 23:01:43 INFO - TinderboxPrint: CPU user
1,620.3 (33.4%) 23:01:43 INFO - TinderboxPrint: Swap in / out
413,319,168 / 0 23:01:43 INFO - install - Wall time: 35s; CPU: 54.0%; Read bytes: 395401728; Write bytes: 389826560; Read time: 29589; Write time: 738 23:01:43 INFO - run-tests - Wall time: 1180s; CPU: 53.0%; Read bytes: 5980160; Write bytes: 294344704; Read time: 188; Write time: 1583 23:01:44 INFO - Running post-run listener: _upload_blobber_files 23:01:44 INFO - Blob upload gear active. 23:01:44 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 23:01:44 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 23:01:44 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', 'Autoland', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 23:01:44 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 Autoland -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 23:01:44 INFO - (blobuploader) - INFO - Open directory for files ... 23:01:44 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 23:01:44 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 23:01:44 INFO - (blobuploader) - INFO - Uploading, attempt #1. 23:01:45 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 23:01:45 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 23:01:45 INFO - (blobuploader) - INFO - Done attempting. 23:01:45 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 23:01:45 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 23:01:45 INFO - (blobuploader) - INFO - Uploading, attempt #1. 23:01:46 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 23:01:46 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 23:01:46 INFO - (blobuploader) - INFO - Done attempting. 23:01:46 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 23:01:46 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 23:01:46 INFO - (blobuploader) - INFO - Uploading, attempt #1. 23:01:48 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 23:01:48 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 23:01:48 INFO - (blobuploader) - INFO - Done attempting. 23:01:48 INFO - (blobuploader) - INFO - Iteration through files over. 23:01:48 INFO - Return code: 0 23:01:48 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 23:01:48 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 23:01:48 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/09742c4b4ca6bd7fdaf706272bd1eae8888d7ab012e2836da098cc269bbb8f16e89017e3ac3552567ab78ffeedbdd1f042be62d12695d528c103eab083a845e5", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/89d8c8ea2f6a31be8ab83c0c15ed7483bbd8b38110a85910bc8a95b0882cef5c47c3b78241845e27f51c1a5379716b5b632d10459d8b407ed94d1fb356cbe7c0", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/ad59029c21716720c14cb0b66fafded2436856545aca4d3f11397323d626b5b47d455d59374586ef5e9ff47663d25013801f54c084f304cb953b6c5d89c7123d"} 23:01:48 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 23:01:48 INFO - Writing to file /builds/slave/test/properties/blobber_files 23:01:48 INFO - Contents: 23:01:48 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/09742c4b4ca6bd7fdaf706272bd1eae8888d7ab012e2836da098cc269bbb8f16e89017e3ac3552567ab78ffeedbdd1f042be62d12695d528c103eab083a845e5", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/89d8c8ea2f6a31be8ab83c0c15ed7483bbd8b38110a85910bc8a95b0882cef5c47c3b78241845e27f51c1a5379716b5b632d10459d8b407ed94d1fb356cbe7c0", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/ad59029c21716720c14cb0b66fafded2436856545aca4d3f11397323d626b5b47d455d59374586ef5e9ff47663d25013801f54c084f304cb953b6c5d89c7123d"} 23:01:48 INFO - Running post-run listener: copy_logs_to_upload_dir 23:01:48 INFO - Copying logs to upload dir... 23:01:48 INFO - mkdir: /builds/slave/test/build/upload/logs 23:01:48 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1272.637196 ========= master_lag: 1.43 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 14 secs) (at 2016-10-03 23:01:49.787114) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-03 23:01:49.793375) ========= 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.f9sAHOF1pk/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.0mBAGTFu4j/Listeners TMPDIR=/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/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/Autoland/sha512/09742c4b4ca6bd7fdaf706272bd1eae8888d7ab012e2836da098cc269bbb8f16e89017e3ac3552567ab78ffeedbdd1f042be62d12695d528c103eab083a845e5", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/89d8c8ea2f6a31be8ab83c0c15ed7483bbd8b38110a85910bc8a95b0882cef5c47c3b78241845e27f51c1a5379716b5b632d10459d8b407ed94d1fb356cbe7c0", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/ad59029c21716720c14cb0b66fafded2436856545aca4d3f11397323d626b5b47d455d59374586ef5e9ff47663d25013801f54c084f304cb953b6c5d89c7123d"} build_url:https://queue.taskcluster.net/v1/task/KyhhvRdESxeWzeLuxv047w/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011474 build_url: 'https://queue.taskcluster.net/v1/task/KyhhvRdESxeWzeLuxv047w/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/09742c4b4ca6bd7fdaf706272bd1eae8888d7ab012e2836da098cc269bbb8f16e89017e3ac3552567ab78ffeedbdd1f042be62d12695d528c103eab083a845e5", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/89d8c8ea2f6a31be8ab83c0c15ed7483bbd8b38110a85910bc8a95b0882cef5c47c3b78241845e27f51c1a5379716b5b632d10459d8b407ed94d1fb356cbe7c0", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/ad59029c21716720c14cb0b66fafded2436856545aca4d3f11397323d626b5b47d455d59374586ef5e9ff47663d25013801f54c084f304cb953b6c5d89c7123d"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-03 23:01:49.832935) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 1 secs) (at 2016-10-03 23:01:49.833268) ========= 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.f9sAHOF1pk/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.0mBAGTFu4j/Listeners TMPDIR=/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/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.005408 ========= master_lag: 1.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 1 secs) (at 2016-10-03 23:01:50.881556) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-03 23:01:50.881889) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-03 23:01:50.938944) ========= ========= Total master_lag: 2.57 =========