builder: mozilla-aurora_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0024 starttime: 1480008051.93 results: success (0) buildid: 20161124062419 builduid: ec4fa613ddcc4926a86b2cd9dd35f999 revision: 41a407cb625b22b1cd413dff0fa5142151784647 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-11-24 09:20:51.927874) ========= master: http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-11-24 09:20:51.928436) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-24 09:20:51.928744) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-24 09:20:51.954157) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-24 09:20:51.954587) ========= 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.8RKUl74iT1/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.0eOqmWDMSi/Listeners TMPDIR=/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-11-24 09:20:51-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 140M=0s 2016-11-24 09:20:52 (140 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.133502 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-24 09:20:52.111994) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-24 09:20:52.112385) ========= 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.8RKUl74iT1/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.0eOqmWDMSi/Listeners TMPDIR=/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/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.020009 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-24 09:20:52.163136) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-11-24 09:20:52.163600) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 41a407cb625b22b1cd413dff0fa5142151784647 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 41a407cb625b22b1cd413dff0fa5142151784647 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.8RKUl74iT1/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.0eOqmWDMSi/Listeners TMPDIR=/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-11-24 09:20:52,240 truncating revision to first 12 chars 2016-11-24 09:20:52,241 Setting DEBUG logging. 2016-11-24 09:20:52,241 attempt 1/10 2016-11-24 09:20:52,241 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/41a407cb625b?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-11-24 09:20:54,169 unpacking tar archive at: mozilla-aurora-41a407cb625b/testing/mozharness/ program finished with exit code 0 elapsedTime=2.176667 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-11-24 09:20:54.365259) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-24 09:20:54.365648) ========= script_repo_revision: 41a407cb625b22b1cd413dff0fa5142151784647 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-24 09:20:54.366073) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-24 09:20:54.366440) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-24 09:20:54.386675) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 10 secs) (at 2016-11-24 09:20:54.387125) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Aurora', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.8RKUl74iT1/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.0eOqmWDMSi/Listeners TMPDIR=/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/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 09:20:54 INFO - MultiFileLogger online at 20161124 09:20:54 in /builds/slave/test 09:20:54 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 09:20:54 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 09:20:54 INFO - {'append_to_log': False, 09:20:54 INFO - 'base_work_dir': '/builds/slave/test', 09:20:54 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 09:20:54 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 09:20:54 INFO - 'branch': 'Mozilla-Aurora', 09:20:54 INFO - 'buildbot_json_path': 'buildprops.json', 09:20:54 INFO - 'config_files': ('talos/mac_config.py',), 09:20:54 INFO - 'default_actions': ('clobber', 09:20:54 INFO - 'read-buildbot-config', 09:20:54 INFO - 'download-and-extract', 09:20:54 INFO - 'populate-webroot', 09:20:54 INFO - 'create-virtualenv', 09:20:54 INFO - 'install', 09:20:54 INFO - 'run-tests'), 09:20:54 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:20:54 INFO - 'download_minidump_stackwalk': True, 09:20:54 INFO - 'download_symbols': 'ondemand', 09:20:54 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 09:20:54 INFO - 'tooltool.py': '/tools/tooltool.py', 09:20:54 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 09:20:54 INFO - '/tools/misc-python/virtualenv.py')}, 09:20:54 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:20:54 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:20:54 INFO - 'installer_path': 'installer.exe', 09:20:54 INFO - 'log_level': 'info', 09:20:54 INFO - 'log_name': 'talos', 09:20:54 INFO - 'log_to_console': True, 09:20:54 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 09:20:54 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 09:20:54 INFO - 'opt_config_files': (), 09:20:54 INFO - 'pip_index': False, 09:20:54 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:20:54 INFO - 'cmd': ('bash', 09:20:54 INFO - '-c', 09:20:54 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:20:54 INFO - 'enabled': True, 09:20:54 INFO - 'halt_on_failure': False, 09:20:54 INFO - 'name': 'check_screen_resolution'},), 09:20:54 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:20:54 INFO - 'cmd': ('bash', 09:20:54 INFO - '-c', 09:20:54 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:20:54 INFO - 'enabled': True, 09:20:54 INFO - 'halt_on_failure': False, 09:20:54 INFO - 'name': 'check_screen_resolution'},), 09:20:54 INFO - 'run_cmd_checks_enabled': True, 09:20:54 INFO - 'sps_profile': False, 09:20:54 INFO - 'sps_profile_interval': 0, 09:20:54 INFO - 'suite': 'dromaeojs', 09:20:54 INFO - 'system_bits': '32', 09:20:54 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:20:54 INFO - 'title': 't-yosemite-r7-0024', 09:20:54 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:20:54 INFO - 'use_talos_json': True, 09:20:54 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 09:20:54 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:20:54 INFO - 'work_dir': 'build'} 09:20:54 INFO - [mozharness: 2016-11-24 17:20:54.590908Z] Running clobber step. 09:20:54 INFO - Running pre-action listener: _resource_record_pre_action 09:20:54 INFO - Running main action method: clobber 09:20:54 INFO - rmtree: /builds/slave/test/build 09:20:54 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 09:20:56 INFO - Running post-action listener: _resource_record_post_action 09:20:56 INFO - [mozharness: 2016-11-24 17:20:56.238323Z] Finished clobber step (success) 09:20:56 INFO - [mozharness: 2016-11-24 17:20:56.238466Z] Running read-buildbot-config step. 09:20:56 INFO - Running pre-action listener: _resource_record_pre_action 09:20:56 INFO - Running main action method: read_buildbot_config 09:20:56 INFO - Using buildbot properties: 09:20:56 INFO - { 09:20:56 INFO - "project": "", 09:20:56 INFO - "product": "firefox", 09:20:56 INFO - "script_repo_revision": "production", 09:20:56 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs", 09:20:56 INFO - "repository": "", 09:20:56 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs", 09:20:56 INFO - "buildid": "20161124062419", 09:20:56 INFO - "pgo_build": "False", 09:20:56 INFO - "basedir": "/builds/slave/test", 09:20:56 INFO - "buildnumber": 4, 09:20:56 INFO - "slavename": "t-yosemite-r7-0024", 09:20:56 INFO - "master": "http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/", 09:20:56 INFO - "platform": "yosemite_r7", 09:20:56 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 09:20:56 INFO - "branch": "mozilla-aurora", 09:20:56 INFO - "repo_path": "releases/mozilla-aurora", 09:20:56 INFO - "revision": "41a407cb625b22b1cd413dff0fa5142151784647", 09:20:56 INFO - "stage_platform": "macosx64", 09:20:56 INFO - "builduid": "ec4fa613ddcc4926a86b2cd9dd35f999", 09:20:56 INFO - "slavebuilddir": "test" 09:20:56 INFO - } 09:20:56 INFO - Found installer url https://queue.taskcluster.net/v1/task/e0ek61HuSpqpUqKl1-mBIg/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg. 09:20:56 INFO - Running post-action listener: _resource_record_post_action 09:20:56 INFO - [mozharness: 2016-11-24 17:20:56.241794Z] Finished read-buildbot-config step (success) 09:20:56 INFO - [mozharness: 2016-11-24 17:20:56.241938Z] Running download-and-extract step. 09:20:56 INFO - Running pre-action listener: _resource_record_pre_action 09:20:56 INFO - Running main action method: download_and_extract 09:20:56 INFO - mkdir: /builds/slave/test/build/tests 09:20:56 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')]} 09:20:56 INFO - trying https://queue.taskcluster.net/v1/task/e0ek61HuSpqpUqKl1-mBIg/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json 09:20:56 INFO - Downloading https://queue.taskcluster.net/v1/task/e0ek61HuSpqpUqKl1-mBIg/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json 09:20:56 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/e0ek61HuSpqpUqKl1-mBIg/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json'}, attempt #1 09:20:57 INFO - Downloaded 1199 bytes. 09:20:57 INFO - Reading from file /builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json 09:20:57 INFO - Using the following test package requirements: 09:20:57 INFO - {u'common': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 09:20:57 INFO - u'cppunittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 09:20:57 INFO - u'firefox-52.0a2.en-US.mac.cppunittest.tests.zip'], 09:20:57 INFO - u'gtest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 09:20:57 INFO - u'firefox-52.0a2.en-US.mac.gtest.tests.zip'], 09:20:57 INFO - u'jittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 09:20:57 INFO - u'jsshell-mac.zip'], 09:20:57 INFO - u'mochitest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 09:20:57 INFO - u'firefox-52.0a2.en-US.mac.mochitest.tests.zip'], 09:20:57 INFO - u'mozbase': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 09:20:57 INFO - u'reftest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 09:20:57 INFO - u'firefox-52.0a2.en-US.mac.reftest.tests.zip'], 09:20:57 INFO - u'talos': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 09:20:57 INFO - u'firefox-52.0a2.en-US.mac.talos.tests.zip'], 09:20:57 INFO - u'web-platform': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 09:20:57 INFO - u'firefox-52.0a2.en-US.mac.web-platform.tests.zip'], 09:20:57 INFO - u'xpcshell': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 09:20:57 INFO - u'firefox-52.0a2.en-US.mac.xpcshell.tests.zip']} 09:20:57 INFO - Downloading packages: [u'firefox-52.0a2.en-US.mac.common.tests.zip'] for test suite category: common 09:20:57 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/e0ek61HuSpqpUqKl1-mBIg/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 09:20:57 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/e0ek61HuSpqpUqKl1-mBIg/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 09:20:57 INFO - Fetch https://queue.taskcluster.net/v1/task/e0ek61HuSpqpUqKl1-mBIg/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 09:20:57 INFO - Http code: 200 09:20:57 INFO - via: 1.1 37af14ee951b12568cf1cc160d08abe6.cloudfront.net (CloudFront) 09:20:57 INFO - x-amz-cf-id: 4kmt9fAA9x_JbxmXrTJJIRj1gSupM2s7YfcL3dpmQ9SqHMUP7bCuyA== 09:20:57 INFO - x-amz-version-id: bqAyA.R7PFQtKO7B1M.MWkfeO2DqNKQG 09:20:58 INFO - Expected file size: 16279004 09:20:58 INFO - Obtained file size: 16279004 09:21:04 INFO - Downloading packages: [u'firefox-52.0a2.en-US.mac.common.tests.zip', u'firefox-52.0a2.en-US.mac.talos.tests.zip'] for test suite category: talos 09:21:04 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/e0ek61HuSpqpUqKl1-mBIg/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 09:21:04 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/e0ek61HuSpqpUqKl1-mBIg/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 09:21:04 INFO - Fetch https://queue.taskcluster.net/v1/task/e0ek61HuSpqpUqKl1-mBIg/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 09:21:05 INFO - Http code: 200 09:21:05 INFO - via: 1.1 7f9e159e8ac7593f5d3d995abc6d2db3.cloudfront.net (CloudFront) 09:21:05 INFO - x-amz-cf-id: DQUYgpKahmSOFOi-1eGMBDCxSfTdMuWO3BwMHD4EYzXTVNGV6LaYlw== 09:21:05 INFO - x-amz-version-id: bqAyA.R7PFQtKO7B1M.MWkfeO2DqNKQG 09:21:05 INFO - Expected file size: 16279004 09:21:05 INFO - Obtained file size: 16279004 09:21:11 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/e0ek61HuSpqpUqKl1-mBIg/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip 09:21:11 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/e0ek61HuSpqpUqKl1-mBIg/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip'}, attempt #1 09:21:11 INFO - Fetch https://queue.taskcluster.net/v1/task/e0ek61HuSpqpUqKl1-mBIg/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip into memory 09:21:12 INFO - Http code: 200 09:21:12 INFO - via: 1.1 bfd7406fdd888e34dfca79886cb7c964.cloudfront.net (CloudFront) 09:21:12 INFO - x-amz-cf-id: nL48cRAfK-jtA8xASvWC6N4QXdFB0ZEKAXYoCOu2tsSaKR-gCwaG4w== 09:21:12 INFO - x-amz-version-id: PbGk8ZCztOLak093oUPF2lUfXW3F6Xep 09:21:12 INFO - Expected file size: 14409038 09:21:12 INFO - Obtained file size: 14409038 09:21:12 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')]} 09:21:12 INFO - trying https://queue.taskcluster.net/v1/task/e0ek61HuSpqpUqKl1-mBIg/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 09:21:12 INFO - Downloading https://queue.taskcluster.net/v1/task/e0ek61HuSpqpUqKl1-mBIg/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg 09:21:12 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/e0ek61HuSpqpUqKl1-mBIg/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg'}, attempt #1 09:21:15 INFO - Downloaded 109694081 bytes. 09:21:15 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/e0ek61HuSpqpUqKl1-mBIg/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 09:21:15 INFO - mkdir: /builds/slave/test/properties 09:21:15 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 09:21:15 INFO - Writing to file /builds/slave/test/properties/build_url 09:21:15 INFO - Contents: 09:21:15 INFO - build_url:https://queue.taskcluster.net/v1/task/e0ek61HuSpqpUqKl1-mBIg/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 09:21:16 INFO - Running post-action listener: _resource_record_post_action 09:21:16 INFO - Running post-action listener: set_extra_try_arguments 09:21:16 INFO - [mozharness: 2016-11-24 17:21:16.297384Z] Finished download-and-extract step (success) 09:21:16 INFO - [mozharness: 2016-11-24 17:21:16.297518Z] Running populate-webroot step. 09:21:16 INFO - Running pre-action listener: _resource_record_pre_action 09:21:16 INFO - Running main action method: populate_webroot 09:21:16 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:21:16 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:21:16 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:21:16 INFO - '--tppagecycles', 09:21:16 INFO - '7'], 09:21:16 INFO - 'tests': ['tsvgm']}}, 09:21:16 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 09:21:16 INFO - 'tests': ['tresize', 'tcanvasmark']}, 09:21:16 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 09:21:16 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 09:21:16 INFO - 'tests': ['dromaeo_css', 'kraken']}, 09:21:16 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 09:21:16 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:21:16 INFO - 'talos_options': ['--disable-e10s'], 09:21:16 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:21:16 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:21:16 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:21:16 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:21:16 INFO - 'talos_options': ['--disable-e10s'], 09:21:16 INFO - 'tests': ['damp', 'tps']}, 09:21:16 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:21:16 INFO - 'tests': ['damp', 'tps']}, 09:21:16 INFO - 'g3': {'talos_options': ['--disable-e10s'], 09:21:16 INFO - 'tests': ['dromaeo_dom']}, 09:21:16 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:21:16 INFO - 'g4': {'talos_options': ['--disable-e10s'], 09:21:16 INFO - 'tests': ['basic_compositor_video']}, 09:21:16 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 09:21:16 INFO - 'other': {'talos_options': ['--disable-e10s'], 09:21:16 INFO - 'tests': ['a11yr', 09:21:16 INFO - 'ts_paint', 09:21:16 INFO - 'tpaint', 09:21:16 INFO - 'sessionrestore', 09:21:16 INFO - 'sessionrestore_no_auto_restore', 09:21:16 INFO - 'tabpaint']}, 09:21:16 INFO - 'other-e10s': {'tests': ['a11yr', 09:21:16 INFO - 'ts_paint', 09:21:16 INFO - 'tpaint', 09:21:16 INFO - 'sessionrestore', 09:21:16 INFO - 'sessionrestore_no_auto_restore', 09:21:16 INFO - 'tabpaint']}, 09:21:16 INFO - 'other-e10s_l64': {'tests': ['a11yr', 09:21:16 INFO - 'ts_paint', 09:21:16 INFO - 'tpaint', 09:21:16 INFO - 'sessionrestore', 09:21:16 INFO - 'sessionrestore_no_auto_restore', 09:21:16 INFO - 'tabpaint']}, 09:21:16 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 09:21:16 INFO - 'ts_paint', 09:21:16 INFO - 'tpaint', 09:21:16 INFO - 'sessionrestore', 09:21:16 INFO - 'sessionrestore_no_auto_restore', 09:21:16 INFO - 'tabpaint']}, 09:21:16 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 09:21:16 INFO - 'tests': ['a11yr', 09:21:16 INFO - 'ts_paint', 09:21:16 INFO - 'tpaint', 09:21:16 INFO - 'sessionrestore', 09:21:16 INFO - 'sessionrestore_no_auto_restore', 09:21:16 INFO - 'tabpaint']}, 09:21:16 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 09:21:16 INFO - 'tests': ['a11yr', 09:21:16 INFO - 'ts_paint', 09:21:16 INFO - 'tpaint', 09:21:16 INFO - 'sessionrestore', 09:21:16 INFO - 'sessionrestore_no_auto_restore', 09:21:16 INFO - 'tabpaint']}, 09:21:16 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 09:21:16 INFO - 'tests': ['tsvgx', 09:21:16 INFO - 'tsvgr_opacity', 09:21:16 INFO - 'tart', 09:21:16 INFO - 'tscrollx', 09:21:16 INFO - 'cart']}, 09:21:16 INFO - 'svgr-e10s': {'tests': ['tsvgx', 09:21:16 INFO - 'tsvgr_opacity', 09:21:16 INFO - 'tart', 09:21:16 INFO - 'tscrollx', 09:21:16 INFO - 'cart']}, 09:21:16 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:21:16 INFO - 'talos_options': ['--disable-e10s'], 09:21:16 INFO - 'tests': ['tp5o']}, 09:21:16 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:21:16 INFO - 'tests': ['tp5o']}, 09:21:16 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:21:16 INFO - 'talos_options': ['--disable-e10s', 09:21:16 INFO - '--xperf_path', 09:21:16 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:21:16 INFO - 'tests': ['tp5n']}, 09:21:16 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:21:16 INFO - 'talos_options': ['--xperf_path', 09:21:16 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:21:16 INFO - 'tests': ['tp5n']}}, 09:21:16 INFO - 'talos.zip': {'path': '', 09:21:16 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:21:16 INFO - Running post-action listener: _resource_record_post_action 09:21:16 INFO - [mozharness: 2016-11-24 17:21:16.316181Z] Finished populate-webroot step (success) 09:21:16 INFO - [mozharness: 2016-11-24 17:21:16.316314Z] Running create-virtualenv step. 09:21:16 INFO - Running pre-action listener: _resource_record_pre_action 09:21:16 INFO - Running main action method: create_virtualenv 09:21:16 INFO - Creating virtualenv /builds/slave/test/build/venv 09:21:16 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 09:21:16 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 09:21:16 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:21:16 INFO - Using real prefix '/tools/python27' 09:21:16 INFO - New python executable in /builds/slave/test/build/venv/bin/python 09:21:17 INFO - Installing distribute.............................................................................................................................................................................................done. 09:21:19 INFO - Installing pip.................done. 09:21:19 INFO - Return code: 0 09:21:19 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 09:21:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 09:21:20 INFO - Reading from file tmpfile_stdout 09:21:20 INFO - Output received: 09:21:20 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) 09:21:20 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 09:21:20 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')]} 09:21:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:21:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:21:20 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')]} 09:21:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:21:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:21:20 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 0x106ecc490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106e42570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8418d563d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106ce0d30>, '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 0x106b46db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106cd8b20>, '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.8RKUl74iT1/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.0eOqmWDMSi/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/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 09:21:20 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 09:21:20 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 09:21:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.8RKUl74iT1/Render', 09:21:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:21:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:21:20 INFO - 'HOME': '/Users/cltbld', 09:21:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:21:20 INFO - 'LOGNAME': 'cltbld', 09:21:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:21:20 INFO - 'MOZ_NO_REMOTE': '1', 09:21:20 INFO - 'NO_EM_RESTART': '1', 09:21:20 INFO - 'PAGER': '/bin/cat', 09:21:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:21:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:21:20 INFO - 'PWD': '/builds/slave/test', 09:21:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:21:20 INFO - 'SHELL': '/bin/bash', 09:21:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.0eOqmWDMSi/Listeners', 09:21:20 INFO - 'TMPDIR': '/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/', 09:21:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:21:20 INFO - 'USER': 'cltbld', 09:21:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:21:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:21:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:21:20 INFO - 'XPC_FLAGS': '0x0', 09:21:20 INFO - 'XPC_SERVICE_NAME': '0', 09:21:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:21:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:21:20 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 09:21:20 INFO - Cleaning up... 09:21:20 INFO - Return code: 0 09:21:20 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 09:21:20 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')]} 09:21:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:21:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:21:20 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')]} 09:21:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:21:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:21:20 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 0x106ecc490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106e42570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8418d563d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106ce0d30>, '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 0x106b46db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106cd8b20>, '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.8RKUl74iT1/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.0eOqmWDMSi/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/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 09:21:20 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 09:21:20 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 09:21:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.8RKUl74iT1/Render', 09:21:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:21:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:21:20 INFO - 'HOME': '/Users/cltbld', 09:21:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:21:20 INFO - 'LOGNAME': 'cltbld', 09:21:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:21:20 INFO - 'MOZ_NO_REMOTE': '1', 09:21:20 INFO - 'NO_EM_RESTART': '1', 09:21:20 INFO - 'PAGER': '/bin/cat', 09:21:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:21:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:21:20 INFO - 'PWD': '/builds/slave/test', 09:21:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:21:20 INFO - 'SHELL': '/bin/bash', 09:21:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.0eOqmWDMSi/Listeners', 09:21:20 INFO - 'TMPDIR': '/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/', 09:21:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:21:20 INFO - 'USER': 'cltbld', 09:21:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:21:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:21:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:21:20 INFO - 'XPC_FLAGS': '0x0', 09:21:20 INFO - 'XPC_SERVICE_NAME': '0', 09:21:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:21:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:21:20 INFO - Downloading/unpacking psutil>=3.1.1 09:21:20 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 09:21:20 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 09:21:20 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 09:21:20 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 09:21:20 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 09:21:20 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 09:21:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 09:21:24 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:21:24 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:21:24 INFO - Installing collected packages: psutil 09:21:24 INFO - Running setup.py install for psutil 09:21:24 INFO - building 'psutil._psutil_osx' extension 09:21:24 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 09:21:24 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 09:21:24 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 09:21:24 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 09:21:24 INFO - building 'psutil._psutil_posix' extension 09:21:24 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 09:21:24 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 09:21:24 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 09:21:24 INFO - ^ 09:21:24 INFO - 1 warning generated. 09:21:24 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 09:21:25 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:21:25 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:21:25 INFO - Successfully installed psutil 09:21:25 INFO - Cleaning up... 09:21:25 INFO - Return code: 0 09:21:25 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 09:21:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:21:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:21:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:21:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:21:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:21:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:21:25 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x106ecc490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106e42570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8418d563d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106ce0d30>, '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 0x106b46db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106cd8b20>, '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.8RKUl74iT1/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.0eOqmWDMSi/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/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 09:21:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 09:21:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 09:21:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.8RKUl74iT1/Render', 09:21:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:21:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:21:25 INFO - 'HOME': '/Users/cltbld', 09:21:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:21:25 INFO - 'LOGNAME': 'cltbld', 09:21:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:21:25 INFO - 'MOZ_NO_REMOTE': '1', 09:21:25 INFO - 'NO_EM_RESTART': '1', 09:21:25 INFO - 'PAGER': '/bin/cat', 09:21:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:21:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:21:25 INFO - 'PWD': '/builds/slave/test', 09:21:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:21:25 INFO - 'SHELL': '/bin/bash', 09:21:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.0eOqmWDMSi/Listeners', 09:21:25 INFO - 'TMPDIR': '/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/', 09:21:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:21:25 INFO - 'USER': 'cltbld', 09:21:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:21:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:21:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:21:25 INFO - 'XPC_FLAGS': '0x0', 09:21:25 INFO - 'XPC_SERVICE_NAME': '0', 09:21:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:21:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:21:25 INFO - Downloading/unpacking mozsystemmonitor==0.3 09:21: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 09:21: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 09:21: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 09:21: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 09:21: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 09:21: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 09:21:27 INFO - Downloading mozsystemmonitor-0.3.tar.gz 09:21:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:21:28 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 09:21:28 INFO - Installing collected packages: mozsystemmonitor 09:21:28 INFO - Running setup.py install for mozsystemmonitor 09:21:28 INFO - Successfully installed mozsystemmonitor 09:21:28 INFO - Cleaning up... 09:21:28 INFO - Return code: 0 09:21:28 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 09:21: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')]} 09:21:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:21:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:21: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')]} 09:21:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:21:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:21: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==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x106ecc490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106e42570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8418d563d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106ce0d30>, '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 0x106b46db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106cd8b20>, '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.8RKUl74iT1/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.0eOqmWDMSi/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/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 09:21: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==2.5.1'] in /builds/slave/test/build 09:21: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==2.5.1 09:21:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.8RKUl74iT1/Render', 09:21:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:21:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:21:28 INFO - 'HOME': '/Users/cltbld', 09:21:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:21:28 INFO - 'LOGNAME': 'cltbld', 09:21:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:21:28 INFO - 'MOZ_NO_REMOTE': '1', 09:21:28 INFO - 'NO_EM_RESTART': '1', 09:21:28 INFO - 'PAGER': '/bin/cat', 09:21:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:21:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:21:28 INFO - 'PWD': '/builds/slave/test', 09:21:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:21:28 INFO - 'SHELL': '/bin/bash', 09:21:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.0eOqmWDMSi/Listeners', 09:21:28 INFO - 'TMPDIR': '/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/', 09:21:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:21:28 INFO - 'USER': 'cltbld', 09:21:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:21:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:21:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:21:28 INFO - 'XPC_FLAGS': '0x0', 09:21:28 INFO - 'XPC_SERVICE_NAME': '0', 09:21:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:21:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:21:28 INFO - Downloading/unpacking jsonschema==2.5.1 09:21:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:21:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:21:28 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:21:28 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:21:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:21:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:21:30 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:21:30 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 09:21:30 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 09:21:30 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 09:21:30 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 09:21:30 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 09:21:30 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 09:21:30 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 09:21:31 INFO - Downloading functools32-3.2.3-2.tar.gz 09:21:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 09:21:31 INFO - warning: no files found matching '*.txt' 09:21:31 INFO - no previously-included directories found matching 'build' 09:21:31 INFO - no previously-included directories found matching 'dist' 09:21:31 INFO - no previously-included directories found matching '.git*' 09:21:31 INFO - Installing collected packages: jsonschema, functools32 09:21:31 INFO - Running setup.py install for functools32 09:21:31 INFO - warning: no files found matching '*.txt' 09:21:31 INFO - no previously-included directories found matching 'build' 09:21:31 INFO - no previously-included directories found matching 'dist' 09:21:31 INFO - no previously-included directories found matching '.git*' 09:21:31 INFO - Successfully installed jsonschema functools32 09:21:31 INFO - Cleaning up... 09:21:31 INFO - Return code: 0 09:21:31 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 09:21:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:21:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:21:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:21:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:21:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:21:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:21:31 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x106ecc490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106e42570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8418d563d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106ce0d30>, '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 0x106b46db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106cd8b20>, '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.8RKUl74iT1/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.0eOqmWDMSi/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/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 09:21:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 09:21:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 09:21:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.8RKUl74iT1/Render', 09:21:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:21:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:21:31 INFO - 'HOME': '/Users/cltbld', 09:21:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:21:31 INFO - 'LOGNAME': 'cltbld', 09:21:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:21:31 INFO - 'MOZ_NO_REMOTE': '1', 09:21:31 INFO - 'NO_EM_RESTART': '1', 09:21:31 INFO - 'PAGER': '/bin/cat', 09:21:31 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:21:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:21:31 INFO - 'PWD': '/builds/slave/test', 09:21:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:21:31 INFO - 'SHELL': '/bin/bash', 09:21:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.0eOqmWDMSi/Listeners', 09:21:31 INFO - 'TMPDIR': '/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/', 09:21:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:21:31 INFO - 'USER': 'cltbld', 09:21:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:21:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:21:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:21:31 INFO - 'XPC_FLAGS': '0x0', 09:21:31 INFO - 'XPC_SERVICE_NAME': '0', 09:21:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:21:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:21:32 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 09:21:32 INFO - Cleaning up... 09:21:32 INFO - Return code: 0 09:21:32 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 09:21:32 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')]} 09:21:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:21:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:21:32 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')]} 09:21:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:21:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:21:32 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 0x106ecc490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106e42570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8418d563d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106ce0d30>, '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 0x106b46db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106cd8b20>, '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.8RKUl74iT1/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.0eOqmWDMSi/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/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 09:21:32 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 09:21:32 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 09:21:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.8RKUl74iT1/Render', 09:21:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:21:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:21:32 INFO - 'HOME': '/Users/cltbld', 09:21:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:21:32 INFO - 'LOGNAME': 'cltbld', 09:21:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:21:32 INFO - 'MOZ_NO_REMOTE': '1', 09:21:32 INFO - 'NO_EM_RESTART': '1', 09:21:32 INFO - 'PAGER': '/bin/cat', 09:21:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:21:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:21:32 INFO - 'PWD': '/builds/slave/test', 09:21:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:21:32 INFO - 'SHELL': '/bin/bash', 09:21:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.0eOqmWDMSi/Listeners', 09:21:32 INFO - 'TMPDIR': '/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/', 09:21:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:21:32 INFO - 'USER': 'cltbld', 09:21:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:21:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:21:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:21:32 INFO - 'XPC_FLAGS': '0x0', 09:21:32 INFO - 'XPC_SERVICE_NAME': '0', 09:21:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:21:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:21:32 INFO - Downloading/unpacking blobuploader==1.2.4 09:21:32 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 09:21:32 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 09:21:32 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 09:21:32 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 09:21:32 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 09:21:32 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 09:21:34 INFO - Downloading blobuploader-1.2.4.tar.gz 09:21:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:21:34 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:21:34 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 09:21:34 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 09:21:34 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 09:21:34 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 09:21:34 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 09:21:34 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 09:21:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 09:21:35 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:21:35 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 09:21:35 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 09:21:35 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 09:21:35 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 09:21:35 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 09:21:35 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 09:21:36 INFO - Downloading docopt-0.6.1.tar.gz 09:21:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 09:21:36 INFO - Installing collected packages: blobuploader, requests, docopt 09:21:36 INFO - Running setup.py install for blobuploader 09:21:36 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:21:36 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 09:21:36 INFO - Running setup.py install for requests 09:21:36 INFO - Running setup.py install for docopt 09:21:36 INFO - Successfully installed blobuploader requests docopt 09:21:36 INFO - Cleaning up... 09:21:37 INFO - Return code: 0 09:21:37 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:21:37 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')]} 09:21:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:21:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:21:37 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')]} 09:21:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:21:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:21:37 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 0x106ecc490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106e42570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8418d563d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106ce0d30>, '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 0x106b46db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106cd8b20>, '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.8RKUl74iT1/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.0eOqmWDMSi/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/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 09:21:37 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 09:21:37 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 09:21:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.8RKUl74iT1/Render', 09:21:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:21:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:21:37 INFO - 'HOME': '/Users/cltbld', 09:21:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:21:37 INFO - 'LOGNAME': 'cltbld', 09:21:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:21:37 INFO - 'MOZ_NO_REMOTE': '1', 09:21:37 INFO - 'NO_EM_RESTART': '1', 09:21:37 INFO - 'PAGER': '/bin/cat', 09:21:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:21:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:21:37 INFO - 'PWD': '/builds/slave/test', 09:21:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:21:37 INFO - 'SHELL': '/bin/bash', 09:21:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.0eOqmWDMSi/Listeners', 09:21:37 INFO - 'TMPDIR': '/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/', 09:21:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:21:37 INFO - 'USER': 'cltbld', 09:21:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:21:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:21:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:21:37 INFO - 'XPC_FLAGS': '0x0', 09:21:37 INFO - 'XPC_SERVICE_NAME': '0', 09:21:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:21:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:21:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:21:37 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-aRT7nz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:21:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:21:37 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-9j6Re1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:21:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:21:37 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-a_xMtI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:21:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:21:37 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-Z4LvGJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:21:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:21:37 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-kqzGfT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:21:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:21:37 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-WE01x0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:21:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:21:37 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-YOnh0y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:21:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:21:38 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-OamCqL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:21:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:21:38 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-KsQAIh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:21:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:21:38 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-XEhbad-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:21:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:21:38 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-XvjcBz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:21:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:21:38 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-4YwZ7t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:21:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:21:38 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-IuiuxT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:21:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:21:38 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-QhZ1E_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:21:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:21:38 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-Xd6Luz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:21:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:21:38 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-dnO0ds-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:21:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:21:38 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-CPP1op-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:21:39 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:21:39 INFO - Running setup.py install for manifestparser 09:21:39 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 09:21:39 INFO - Running setup.py install for mozcrash 09:21:39 INFO - Running setup.py install for mozdebug 09:21:39 INFO - Running setup.py install for mozdevice 09:21:39 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 09:21:39 INFO - Installing dm script to /builds/slave/test/build/venv/bin 09:21:39 INFO - Running setup.py install for mozfile 09:21:39 INFO - Running setup.py install for mozhttpd 09:21:39 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 09:21:39 INFO - Running setup.py install for mozinfo 09:21:40 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 09:21:40 INFO - Running setup.py install for mozInstall 09:21:40 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 09:21:40 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 09:21:40 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 09:21:40 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 09:21:40 INFO - Running setup.py install for mozleak 09:21:40 INFO - Running setup.py install for mozlog 09:21:40 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 09:21:40 INFO - Running setup.py install for moznetwork 09:21:40 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 09:21:40 INFO - Running setup.py install for mozprocess 09:21:40 INFO - Running setup.py install for mozprofile 09:21:40 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 09:21:40 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 09:21:40 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 09:21:40 INFO - Running setup.py install for mozrunner 09:21:41 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 09:21:41 INFO - Running setup.py install for mozscreenshot 09:21:41 INFO - Running setup.py install for moztest 09:21:41 INFO - Running setup.py install for mozversion 09:21:41 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 09:21:41 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:21:41 INFO - Cleaning up... 09:21:41 INFO - Return code: 0 09:21:41 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:21:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:21:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:21:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:21:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:21:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:21:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:21:41 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 0x106ecc490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106e42570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8418d563d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106ce0d30>, '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 0x106b46db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106cd8b20>, '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.8RKUl74iT1/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.0eOqmWDMSi/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/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 09:21:41 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 09:21:41 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 09:21:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.8RKUl74iT1/Render', 09:21:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:21:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:21:41 INFO - 'HOME': '/Users/cltbld', 09:21:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:21:41 INFO - 'LOGNAME': 'cltbld', 09:21:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:21:41 INFO - 'MOZ_NO_REMOTE': '1', 09:21:41 INFO - 'NO_EM_RESTART': '1', 09:21:41 INFO - 'PAGER': '/bin/cat', 09:21:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:21:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:21:41 INFO - 'PWD': '/builds/slave/test', 09:21:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:21:41 INFO - 'SHELL': '/bin/bash', 09:21:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.0eOqmWDMSi/Listeners', 09:21:41 INFO - 'TMPDIR': '/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/', 09:21:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:21:41 INFO - 'USER': 'cltbld', 09:21:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:21:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:21:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:21:41 INFO - 'XPC_FLAGS': '0x0', 09:21:41 INFO - 'XPC_SERVICE_NAME': '0', 09:21:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:21:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:21:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:21:41 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-zk57rP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:21:41 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)) 09:21:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:21:41 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-dNH4Vv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:21:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 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)) 09:21:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:21:42 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-qnCAAV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:21:42 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)) 09:21:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:21:42 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-vuajNd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:21:42 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)) 09:21:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:21:42 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-7Y2ioq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:21:42 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)) 09:21:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:21:42 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-jYfMuT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:21:42 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)) 09:21:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:21:42 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-iRIz9M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:21:42 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)) 09:21:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:21:42 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-1pgX8h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:21:42 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)) 09:21:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:21:42 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-wT7N30-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:21:42 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)) 09:21:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:21:42 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-N7_N4U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:21:42 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)) 09:21:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:21:42 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-Vq2mBg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:21:43 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)) 09:21:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:21:43 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-9i0u4K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:21:43 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)) 09:21:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:21:43 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-7hmIvY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:21:43 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)) 09:21:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:21:43 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-D4VVk4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:21:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 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)) 09:21:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:21:43 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-fgfY03-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:21:43 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)) 09:21:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:21:43 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-8mYzEo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:21:43 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)) 09:21:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:21:43 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-PwYhIQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:21:43 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)) 09:21:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:21:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:21:43 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)) 09:21:43 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)) 09:21:43 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)) 09:21:43 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:21:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:21:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:21:43 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:21:43 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:21:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:21:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:21:46 INFO - Downloading blessings-1.6.tar.gz 09:21:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 09:21:46 INFO - Installing collected packages: blessings 09:21:46 INFO - Running setup.py install for blessings 09:21:46 INFO - Successfully installed blessings 09:21:46 INFO - Cleaning up... 09:21:46 INFO - Return code: 0 09:21:46 INFO - Done creating virtualenv /builds/slave/test/build/venv. 09:21:46 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:21:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:21:46 INFO - Reading from file tmpfile_stdout 09:21:46 INFO - Current package versions: 09:21:46 INFO - blessings == 1.6 09:21:46 INFO - blobuploader == 1.2.4 09:21:46 INFO - docopt == 0.6.1 09:21:46 INFO - functools32 == 3.2.3-2 09:21:46 INFO - jsonschema == 2.5.1 09:21:46 INFO - manifestparser == 1.1 09:21:46 INFO - mozInstall == 1.12 09:21:46 INFO - mozcrash == 1.0 09:21:46 INFO - mozdebug == 0.1 09:21:46 INFO - mozdevice == 0.48 09:21:46 INFO - mozfile == 1.2 09:21:46 INFO - mozhttpd == 0.7 09:21:46 INFO - mozinfo == 0.9 09:21:46 INFO - mozleak == 0.1 09:21:46 INFO - mozlog == 3.4 09:21:46 INFO - moznetwork == 0.27 09:21:46 INFO - mozprocess == 0.23 09:21:46 INFO - mozprofile == 0.28 09:21:46 INFO - mozrunner == 6.13 09:21:46 INFO - mozscreenshot == 0.1 09:21:46 INFO - mozsystemmonitor == 0.3 09:21:46 INFO - moztest == 0.7 09:21:46 INFO - mozversion == 1.4 09:21:46 INFO - psutil == 3.1.1 09:21:46 INFO - requests == 1.2.3 09:21:46 INFO - wsgiref == 0.1.2 09:21:46 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:21:46 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')]} 09:21:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:21:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:21:46 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')]} 09:21:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:21:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:21:46 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 0x106ecc490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106e42570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8418d563d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106ce0d30>, '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 0x106b46db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106cd8b20>, '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.8RKUl74iT1/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.0eOqmWDMSi/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/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 09:21:46 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 09:21:46 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 09:21:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.8RKUl74iT1/Render', 09:21:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:21:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:21:46 INFO - 'HOME': '/Users/cltbld', 09:21:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:21:46 INFO - 'LOGNAME': 'cltbld', 09:21:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:21:46 INFO - 'MOZ_NO_REMOTE': '1', 09:21:46 INFO - 'NO_EM_RESTART': '1', 09:21:46 INFO - 'PAGER': '/bin/cat', 09:21:46 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:21:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:21:46 INFO - 'PWD': '/builds/slave/test', 09:21:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:21:46 INFO - 'SHELL': '/bin/bash', 09:21:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.0eOqmWDMSi/Listeners', 09:21:46 INFO - 'TMPDIR': '/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/', 09:21:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:21:46 INFO - 'USER': 'cltbld', 09:21:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:21:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:21:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:21:46 INFO - 'XPC_FLAGS': '0x0', 09:21:46 INFO - 'XPC_SERVICE_NAME': '0', 09:21:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:21:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:21:47 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)) 09:21:47 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)) 09:21:47 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)) 09:21:47 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)) 09:21:47 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)) 09:21:47 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)) 09:21:47 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)) 09:21:47 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)) 09:21:47 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)) 09:21:47 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)) 09:21:47 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)) 09:21:47 INFO - Cleaning up... 09:21:47 INFO - Return code: 0 09:21:47 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 09:21:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:21:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:21:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:21:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:21:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:21:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:21:47 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 0x106ecc490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106e42570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8418d563d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106ce0d30>, '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 0x106b46db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106cd8b20>, '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.8RKUl74iT1/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.0eOqmWDMSi/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/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 09:21:47 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 09:21:47 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 09:21:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.8RKUl74iT1/Render', 09:21:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:21:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:21:47 INFO - 'HOME': '/Users/cltbld', 09:21:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:21:47 INFO - 'LOGNAME': 'cltbld', 09:21:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:21:47 INFO - 'MOZ_NO_REMOTE': '1', 09:21:47 INFO - 'NO_EM_RESTART': '1', 09:21:47 INFO - 'PAGER': '/bin/cat', 09:21:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:21:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:21:47 INFO - 'PWD': '/builds/slave/test', 09:21:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:21:47 INFO - 'SHELL': '/bin/bash', 09:21:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.0eOqmWDMSi/Listeners', 09:21:47 INFO - 'TMPDIR': '/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/', 09:21:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:21:47 INFO - 'USER': 'cltbld', 09:21:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:21:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:21:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:21:47 INFO - 'XPC_FLAGS': '0x0', 09:21:47 INFO - 'XPC_SERVICE_NAME': '0', 09:21:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:21:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:21:47 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 09:21:47 INFO - Cleaning up... 09:21:47 INFO - Return code: 0 09:21:47 INFO - Running post-action listener: _resource_record_post_action 09:21:47 INFO - Running post-action listener: _start_resource_monitoring 09:21:47 INFO - Starting resource monitoring. 09:21:47 INFO - [mozharness: 2016-11-24 17:21:47.351813Z] Finished create-virtualenv step (success) 09:21:47 INFO - [mozharness: 2016-11-24 17:21:47.352769Z] Running install step. 09:21:47 INFO - Running pre-action listener: _resource_record_pre_action 09:21:47 INFO - Running main action method: install 09:21:47 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:21:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:21:47 INFO - Reading from file tmpfile_stdout 09:21:47 INFO - Detecting whether we're running mozinstall >=1.0... 09:21:47 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 09:21:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 09:21:47 INFO - Reading from file tmpfile_stdout 09:21:47 INFO - Output received: 09:21:47 INFO - Usage: mozinstall [options] installer 09:21:47 INFO - Options: 09:21:47 INFO - -h, --help show this help message and exit 09:21:47 INFO - -d DEST, --destination=DEST 09:21:47 INFO - Directory to install application into. [default: 09:21:47 INFO - "/builds/slave/test"] 09:21:47 INFO - --app=APP Application being installed. [default: firefox] 09:21:47 INFO - mkdir: /builds/slave/test/build/application 09:21:47 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 09:21:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg --destination /builds/slave/test/build/application 09:22:18 INFO - Reading from file tmpfile_stdout 09:22:18 INFO - Output received: 09:22:18 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 09:22:18 INFO - Running post-action listener: _resource_record_post_action 09:22:18 INFO - [mozharness: 2016-11-24 17:22:18.276023Z] Finished install step (success) 09:22:18 INFO - [mozharness: 2016-11-24 17:22:18.276141Z] Running run-tests step. 09:22:18 INFO - Running pre-action listener: _resource_record_pre_action 09:22:18 INFO - Running main action method: run_tests 09:22:18 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:22:18 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:22:18 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:22:18 INFO - 2016-11-24 09:22:18.290 screenresolution[1880:14339] starting screenresolution argv=screenresolution get 09:22:18 INFO - 2016-11-24 09:22:18.293 screenresolution[1880:14339] Display 0: 1600x1200x32@60 09:22:18 INFO - 2016-11-24 09:22:18.298 screenresolution[1881:14343] starting screenresolution argv=screenresolution list 09:22:18 INFO - Available Modes on Display 0 09:22:18 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:22:18 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:22:18 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:22:18 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:22:18 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:22:18 INFO - Intel Iris: 09:22:18 INFO - Chipset Model: Intel Iris 09:22:18 INFO - Type: GPU 09:22:18 INFO - Bus: Built-In 09:22:18 INFO - VRAM (Dynamic, Max): 1536 MB 09:22:18 INFO - Vendor: Intel (0x8086) 09:22:18 INFO - Device ID: 0x0a2e 09:22:18 INFO - Revision ID: 0x0009 09:22:18 INFO - Displays: 09:22:18 INFO - Display: 09:22:18 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:22:18 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:22:18 INFO - Display Serial Number: 1600x1200 60 09:22:18 INFO - Main Display: Yes 09:22:18 INFO - Mirror: Off 09:22:18 INFO - Online: Yes 09:22:18 INFO - Rotation: Supported 09:22:18 INFO - Return code: 0 09:22:18 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 09:22:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 09:22:18 INFO - Python 2.7.3 09:22:18 INFO - Return code: 0 09:22:18 INFO - grabbing minidump binary from tooltool 09:22: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')]} 09:22:18 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 0x106ce0d30>, '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 0x106b46db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106cd8b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 09:22:18 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 09:22:18 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 09:22:18 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 09:22:18 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:22:18 INFO - Return code: 0 09:22:18 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 09:22:18 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 09:22:18 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 09:22:18 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 09:22:18 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 09:22:18 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/e0ek61HuSpqpUqKl1-mBIg/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0024', '--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 09:22:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs --executablePath /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/e0ek61HuSpqpUqKl1-mBIg/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0024 --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 09:22:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.8RKUl74iT1/Render', 09:22:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:22:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:22:18 INFO - 'HOME': '/Users/cltbld', 09:22:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:22:18 INFO - 'LOGNAME': 'cltbld', 09:22:18 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:22:18 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 09:22:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:22:18 INFO - 'MOZ_NO_REMOTE': '1', 09:22:18 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:22:18 INFO - 'NO_EM_RESTART': '1', 09:22:18 INFO - 'PAGER': '/bin/cat', 09:22:18 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:22:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:22:18 INFO - 'PWD': '/builds/slave/test', 09:22:18 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:22:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:22:18 INFO - 'SHELL': '/bin/bash', 09:22:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.0eOqmWDMSi/Listeners', 09:22:18 INFO - 'TMPDIR': '/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/', 09:22:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:22:18 INFO - 'USER': 'cltbld', 09:22:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:22:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:22:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:22:18 INFO - 'XPC_FLAGS': '0x0', 09:22:18 INFO - 'XPC_SERVICE_NAME': '0', 09:22:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:22:18 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/e0ek61HuSpqpUqKl1-mBIg/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0024', '--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 09:22:18 INFO - mozversion application_buildid: 20161124062419 09:22:18 INFO - mozversion application_changeset: 41a407cb625b22b1cd413dff0fa5142151784647 09:22:18 INFO - mozversion application_display_name: FirefoxDeveloperEdition 09:22:18 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:22:18 INFO - mozversion application_name: Firefox 09:22:18 INFO - mozversion application_remotingname: firefox-dev 09:22:18 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 09:22:18 INFO - mozversion application_vendor: Mozilla 09:22:18 INFO - mozversion application_version: 52.0a2 09:22:18 INFO - mozversion platform_buildid: 20161124062419 09:22:18 INFO - mozversion platform_changeset: 41a407cb625b22b1cd413dff0fa5142151784647 09:22:18 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 09:22:18 INFO - mozversion platform_version: 52.0a2 09:22:18 INFO - using testdate: 1480008138 09:22:18 INFO - actual date: 1480008138 09:22:18 INFO - starting webserver on 'localhost:51547' 09:22:18 INFO - SUITE-START | Running 2 tests 09:22:18 INFO - TEST-START | dromaeo_css 09:22:18 INFO - operating with platform_type : mac_ 09:22:18 INFO - Initialising browser for dromaeo_css test... 09:22:18 INFO - TEST-INFO | started process 1891 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/tmpUajD4g/profile http://localhost:51547/getInfo.html) 09:22:21 INFO - PROCESS | 1891 | __metrics Screen width/height:1600/1200 09:22:21 INFO - PROCESS | 1891 | colorDepth:24 09:22:21 INFO - PROCESS | 1891 | Browser inner width/height: 1024/689 09:22:21 INFO - PROCESS | 1891 | __metrics 09:22:21 INFO - PROCESS | 1891 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 09:22:21 INFO - PROCESS | 1891 | JavaScript error: resource:///modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:22:22 INFO - TEST-INFO | 1891: exit 0 09:22:22 INFO - Browser initialized. 09:22:22 INFO - Running cycle 1/1 for dromaeo_css test... 09:22:22 INFO - TEST-INFO | started process 1893 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/tmpUajD4g/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:22:34 INFO - PROCESS | 1893 | RSS: Main: 223088640 09:22:34 INFO - PROCESS | 1893 | 09:23:26 INFO - PROCESS | 1893 | 1480008206484 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] 09:25:37 INFO - PROCESS | 1893 | Cycle 1(1): loaded http://localhost:51547/tests/dromaeo/cssquery-dojo.html (next: http://localhost:51547/tests/dromaeo/cssquery-ext.html) 09:25:40 INFO - PROCESS | 1893 | RSS: Main: 455888896 09:25:40 INFO - PROCESS | 1893 | 09:28:43 INFO - PROCESS | 1893 | Cycle 1(1): loaded http://localhost:51547/tests/dromaeo/cssquery-ext.html (next: http://localhost:51547/tests/dromaeo/cssquery-jquery.html) 09:28:44 INFO - PROCESS | 1893 | RSS: Main: 349757440 09:28:44 INFO - PROCESS | 1893 | 09:31:46 INFO - PROCESS | 1893 | Cycle 1(1): loaded http://localhost:51547/tests/dromaeo/cssquery-jquery.html (next: http://localhost:51547/tests/dromaeo/cssquery-mootools.html) 09:31:47 INFO - PROCESS | 1893 | RSS: Main: 312696832 09:31:47 INFO - PROCESS | 1893 | 09:34:49 INFO - PROCESS | 1893 | Cycle 1(1): loaded http://localhost:51547/tests/dromaeo/cssquery-mootools.html (next: http://localhost:51547/tests/dromaeo/cssquery-prototype.html) 09:34:50 INFO - PROCESS | 1893 | RSS: Main: 317743104 09:34:50 INFO - PROCESS | 1893 | 09:37:54 INFO - PROCESS | 1893 | Cycle 1(1): loaded http://localhost:51547/tests/dromaeo/cssquery-prototype.html (next: http://localhost:51547/tests/dromaeo/cssquery-yui.html) 09:37:54 INFO - PROCESS | 1893 | RSS: Main: 306737152 09:37:54 INFO - PROCESS | 1893 | 09:40:57 INFO - PROCESS | 1893 | Cycle 1(1): loaded http://localhost:51547/tests/dromaeo/cssquery-yui.html (next: http://localhost:51547/tests/dromaeo/cssquery-dojo.html) 09:40:58 INFO - PROCESS | 1893 | RSS: Main: 354881536 09:40:58 INFO - PROCESS | 1893 | 09:40:58 INFO - PROCESS | 1893 | __start_tp_report 09:40:58 INFO - PROCESS | 1893 | _x_x_mozilla_page_load 09:40:58 INFO - PROCESS | 1893 | _x_x_mozilla_page_load_details 09:40:58 INFO - PROCESS | 1893 | |i|pagename|runs| 09:40:58 INFO - PROCESS | 1893 | |0;dojo.html;3340.1983523954245;3348.4642457206846;3366.9158271043234;3373.055544447555;3395.3718562066006;5662.5186859116975;5733.455321744427;5747.080467125261;5749.5400367970615;5762.250907382039;5622.943770562293;5655;5660.688662123582;5686.886262274752;5732.111522713975;5198.558122559587;5223.190405487156;5229.555487783534;5253.4221235664045;5296.205569164617;5167.560757335631;5169.6898186108765;5174.197999310113;5223.503767142115;5318.0161670090965;5253.159494480884;5287.524122828944;5300.284461597674;5305.946940530584;5330;5289.629725919187;5296.152615581527;5307.363116426034;5327.760250788724;5355.598330125256;5035.144025515671;5126.128558145087;5203.245529398216;5221.1124108901195;5230.895382092337;5656.688882111483;5676.574256930706;5677.489025987639;5738.225339579187;5752.942470575283;6172.22847144107;6196.783112591038;6231.501479881598;6249.0626406039;6266.342034086447;6191.435893608058;6232.283287421914;6256.624602523818;6257.624542527463;6267.655278959655;5632.070708333161;5762.366139724627;5774.88450230993;5789.044807606782;5871.765129394819;6268.49852011838;6286.05709143623;6293.465055470337;6297.590656607397;6302.023186406114;6167.167432396659;6182.103594978703;6188.566800323934;6191.535634827406;6194.34959329273;6091.756329746849;6139.461473530676;6223.533235007392;6224.097505861715;6229.50163986889;6143.218466644712;6143.43342447673;6198.318184999646;6211.720472578745;6250.9062364064575;6173.722182501709;6231.345708700521;6243.96878015607;6248.5313601480075;6268.874622507525;4972.875678108004;4976.129177393942;4978.427480839678;4985.401751789808;5003.4746351633285;4381.277089280296;4389.582989615981;4409.536998615099;4409.8456554021095;4447.066116115588;5816.243888294494;5825.941740582624;5834.562407819346;5840.445157710011;5873.647581145145;6160.722767475474;6165.475934545614;6177.660228687465;6224.439800417894;6242.968785156045;5589.720513974366;5684.431556844365;5697.202391665171;5814.273215848019;5820.068788993741;5376.354837419456;5412.972935135299;5418.241446197536;5433.565314774887;5434.239206511092;6339.936600633934;6351.745930162834;6366.108744775643;6372.553921225655;6375.744970201233;5847.473727364558;6219.253689557282;6221.720022598904;6228.03465462854;6242.344553821965;6238.064290356483;6242.469390101892;6259.123722678738;6270.7491700331475;6365.395287447685;6136.6011209271255;6167.259928808572;6178.783742568989;6179.6910154493;6190.690465476799;4985.900281994267;6184.567080304336;6218.906716399167;6227.221597300338;6241.344658810941;5972.104184372379;6021;6069.119977603134;6100.267967843887;6114.296855861633;6060.272767267956;6074.271087469532;6080.087986802015;6081.27024757032;6083.574149809648;5920.73356698941;5924.407559244041;5938.257717785277;5939.406059394026;5949.137375080676;6019.337872833898;6078.45293923549;6090.756369745338;6091.664958427329;6093.086037094471;5914.319853216935;5920.8815823684145;5927.229460170151;5927.614705044159;5930.991731405585 09:40:58 INFO - PROCESS | 1893 | |1;ext.html;442.0562099916679;458.6010171151098;462.1750175935955;470.8675635097591;503.3154909323322;26647.33381665457;30759;39243.60756392435;40267.79866100665;40431.59568404315;40723.79638101805;41353.966150846245;41435.585644143546;41559.58440415595;41590.58409415912;9380.015098414671;9571.80856382872;9573.04269573044;9582.377145485521;9589;8565.443246189008;8760;11267.380294083821;11508.367039812807;11561.768764624703;11343.149263805248;11361.091112711005;11421.771564568704;11449.313041217554;11534.365609891494;11419;11496.022838058774;11497.367644779491;11499.655010349703;11514.827277590843;8516.616752246164;8748.168923952215;11076.224664273504;11419.714507137389;11431.314121152758;33002.504962425584;39100;39599.40600890989;39658.40512392316;39824.40263396022;2672.786177105827;3133.937321253562;3282.1138292661085;3292.0453068610072;3364.6971772540587;28141.29646758827;28398.858005710044;29565;29873;30106.24734381636;3145.622525296956;3162.3675264946874;3299.1752061984503;3321.0534997525588;3325.1022223999385;479972;480977;481007;482389.5880520575;483476;58036;58378;58521;58551;58625.12062319068;60291.69854150701;60357.698211508665;60368.094478582854;60513.09230361548;60551.88068417672;15859.682806344039;16116.194190290438;16137.59656008609;16146.919265403832;16164.515064548083;11190.66428007161;12552.497900083894;12640.431180596896;12645.367731613382;12654.430550625244;13545.796813047813;13956.93021534906;15348.309326080353;15503.84496155024;15776.211189440482;6110.847228819315;6161.876762464725;6647.0029495576055;6876.931230687729;6997.440204783605;864.2567392042838;960.4333443268505;1035.5961175141701;1047.9056884880397;1058.2645061682058;24879;28806.839487038556;29069.563956540667;29501.26246843846;29660.258493537833;1032.385730490357;1036.290141253244;1046.853440518328;1062.9202809789297;1066.6053560182627;14834.10995340283;15381.769273460906;15415.59302435691;15676.921615392079;15893.205339732967;642522;648727;649915;651520;883949;47356;55950.72024639932;57055.71472142613;57199.714001429726;60280.39719602835;1328.7076843094503;1352.6753579140936;1372.1355546005952;1380.075349515847;1380.9861901380857;29694.257643559085;30171.396572067995;30281.545776813808;30329.393412131194;30780.23049423782;48887.75556122339;49458.505414945386;49512;49525;49592.76018099576;585704;588899;589608;589746;590103;38525.42211866768;38542.80728596339;38680.6131938677;38987;39039.80480097581;44899.77550112228;45019;45073.57086902933;45229.51237681959;45264;37225.81387093047;37584;37989.43015854709;39155.80422097985;39176.020599485244;37129;37498;37814.62185378111;38349.808250958566;38697.80651096727;36498.452523212705;37488.437673434375;37853.81073094617;38066.23867522579;38182.809085954395;27625.309367265978;33080.33839323152;33470.99587012394;34384.82807586046;34447;23295.534089317778;24780.50438991174;24967.250982470556;25427;25970.74029259683 09:40:58 INFO - PROCESS | 1893 | |2;jquery.html;352.7777500174889;367.8216065208374;369.6543731610944;383.75631474014;402.69596454676713;14587.781183282259;14897.702045959088;15398.769018464704;18917.7162342565;19934.900325498387;17637.82362176378;19387;19928.003599819986;20011.1995520179;20204.39386818398;5805.390434004422;6101.60339577926;6103.511719062487;6106.053561697921;6112.724927378279;4898.7060776353355;5955.4218132195;6005.159277701126;6015.308239552463;6039.607425517349;5675.5459563235045;5967.194428752106;5971.611845230068;5977.222961015062;5990.790322338719;5884.794032208873;6008.729607167645;6009.489193418564;6013.819585412444;6027.041211606245;4970.975145124287;5127.794888204505;5198.688078715251;5229.2156176573435;5247;24719.764011799518;25148.622770658272;25197.992080316766;25266.242012739647;25597.23208303754;2112.8837913914726;2230.8996095175594;2273.4771002669477;2462.2243993142283;2548.9745102548923;15327.61680957985;15385;15550.922245388701;15706;15788.289526971314;1622.8458296461818;1846.0954132475124;1853.6014756827212;1899.582091939771;1920.9711854322056;92152;112184;116708;116837.41581292124;117720.82279177185;11301.660950171508;11378.601748938747;11544.249623774596;11558.711032224262;11703.88296117045;14578.635534111732;14587.124772513684;14598.854011459962;14600;14619.707605847823;8241.340692744567;8350.08149103598;8373.015873015873;8388.622511986976;8510.148985101441;6800.829979250559;8176.550289734003;8313.459625124347;8457.450265732708;8474.322054235647;4334.718243314238;4445.1998640245065;4457.863244872537;4515.525869783625;4530.728156310632;1591.7504758764314;1845.1973391574709;1899.582091939771;1902.5053486093716;1906.685396909522;704.0776582676608;716.8530451257479;959.856021596766;964.7395203295212;967.0377973915998;16452;18879.686703897198;20446.488837779176;20654.58690826175;20669.379918602466;934.5093825741404;941.684535680541;1006.1347241372414;1007.5868893753673;1028.88682244953;10435.530401131968;10802.776010181411;10926.890731092746;10967.122630189726;11976.940115299367;591163;611024;620907;621458;625414;72288.63855680688;72742.2725772746;104761.95238047522;131983;142554.2872285632;911.4941207629829;940.083418666808;973.6202880876464;975.0493269062607;1003.7791685829252;17915;18404.35584754527;18660.253589856253;19085;19320.32378866733;44591.10817783691;55493.72253138709;58149;58207.83584328374;58771;76944;79924;80776;81883;82659.58670206611;7743.728969486041;7873;7942.563159026309;8070.071941726542;8076.071251806117;8838.690645827364;12229.633111006684;12331.44508497102;12732.172408793398;12737.172083814892;10666.146708263164;10716;10826.025657690845;10943.179261555575;11032.724181895517;10598.576056957636;10642.787144257227;11024.062954648945;11081.944590276997;11155.386453744797;10579.682609521727;10657.25399222047;10978.615748448767;11070.557177713123;11153.163512736415;7829.804254893673;7933.801654958672;8078.394120441108;8201.58992050407;8206.876896846672;9428.858567121362;10227;10315.329503582243;10334.793304133726;10418.166546676399 09:40:58 INFO - PROCESS | 1893 | |3;mootools.html;1298.253504235065;1335.3390071914405;1452.8692417682407;1598.5603958911297;1839.0620783400461;7590.240975902407;8013.559254241014;8397.999999999993;8667.349948753837;8684.13158684133;8525.488470691764;8609.56952152393;8614.224719775219;8615.18155775202;8617.879675642173;2318.281332786833;2352.9647055294186;2357.010055776578;2358.2689366296504;2360.303710405426;2343.086196383412;2378.6194208926577;2395;2395.3053614451874;2405.4226985523524;2328.092044102801;2328.5459335429596;2361.1499860050453;2363.385519764865;2365.526894621074;2299.7355304139905;2309.6304591265402;2332.661764044204;2332.731735850382;2335.311083230451;2330.4057465346395;2342.7071616047992;2367.8342516023886;2368;2368.5736567417857;8322.791930201793;8479.067302596772;8483.194096560817;8489.66041358345;8489.702860399857;5076.162433198518;5111.514406131411;5258.763355649005;5309.362876454811;5328.973355133238;2994.116735563014;3079.1994081538737;3448.7607834389614;3527.1005893496995;3563.572371315433;1023.8924912884114;1058.544825724938;1090.4820210400076;1099.8075336816025;1117.279354816139;11242;11343.149263805248;11400.94299528497;11406.31562106268;11410.258333208314;4194.916101677949;4455.6212721919;4526.411566496335;4551.5676010778925;4554.749488778149;8973.50645714492;8974.28205743539;8978.506182159914;8978.595963181673;8986.370954033155;5183.637145399785;5198.584113270929;5199.1161502544655;5202.453742356998;5214.0875346814155;4077.55146933837;4335.783210839445;4469.128519938646;4475.619572336388;4883.487233840467;3340.3820293245826;3533.169705119295;3620.6017338093316;3631.6913062389995;3722.7952462614276;1112.5994641928899;1123.095907794217;1127.9436028198556;1135.5628083188033;1141.3779490177874;723.706898706025;779.3842864137268;825.8141918068386;840.5797101449275;869.3262721390972;5159.432462429129;5229.607779416559;5430.185472179205;5458.426865179099;5605.887882242414;838.983220335602;920.4201353147474;923.6997975657938;925.8379783537989;947.2185992075135;3750.812459377075;3773.7924414157483;3846.6345697158727;3897.629725176104;3952.6837852971694;9925.950370248103;10310.948445257876;10324.690259292083;10337;10344;10121.69634910954;10321.432321222255;10431.21765867563;10441.947790261;10446.63436779709;7853.568053756984;8053.879191812128;8058;8059.63731632078;8062.072861620872;5026.924596130988;5367.5169234769055;5374.892502149857;5485.917711234414;5741.368449470639;9518.95240523793;9540.475273860004;9613.711588652352;9738.220942324455;9741.318107732392;9676.274279429212;9778.119969202522;9786.559604817658;9891.159251463418;9986.60053597848;7923.405744569065;7985.361171106414;8016.358691304798;8158.3065439438315;8162.020557533134;8022.679092836222;8119.959400202961;8123.147069557847;8133.308668763221;8135.552544610104;7197;7380.335769780745;7385.556866588022;7426.480146389701;7492.550446973199;6962.373386395248;7069.823254418475;7125.394341481032;7181.210066892533;7183.892241616275;6296.150019747275;6597.472202223907;6812.795616131524;6825.2151002633345;6915.204751453647;4630.513796051501;4688.882777930579;4726.432828060517;4785.425748910153;4799.112164249625;3665;4707.62339012885;4874.147024270667;4879.780409881493;4980.028894365564 09:40:58 INFO - PROCESS | 1893 | |4;prototype.html;250.39404640769337;274.601827350342;307.2349848876298;310.2383648143807;313.06549948403995;4855.4416242132165;4915.975420122899;5072.224721347527;5151.716655583941;5177.5081367270095;4126.133511962484;4972.428170760364;5061.392632884059;5067.771950262238;5176.301199338088;3528.9470657940024;3983.9203215935663;3996.2407142642805;4015.096603264257;4024.215278020787;3118.3919135768533;3606.711463082947;3896.2856343209037;3911.5697273299916;3941.507311586052;3369.7135743461713;3429.4512877939533;3971.702122340836;3991.62289010291;4079.3065178919655;3146.622405311354;3966.147278335143;3975.2248311579256;3980.522337319526;3993.820278087493;2929.2383980165096;3172.5558421820965;3279.2949515853966;3443.1220038890174;3485.372632926072;4280.468613393931;4972.055309491221;5059.797608095671;5089.949100509021;5147.305113809625;4316.6546676265825;4544.341070544786;4548.56788605082;4565.1098035883015;4578.893708893913;4780.856574302777;5030.220315851049;5342.732863356856;5391.218273350342;5462.7268636568015;3307.6361600223954;3725.100198898554;3740.4576336431064;3746.7002639789025;3825;5128.435872054109;5131.639445854911;5522.861928451781;5550.4449555044985;5625.915611265835;3168.9560233649167;3177.920551986219;3694.37447523089;3771.830267637963;3837.9424308635394;3394.100563350714;3792.4311353296675;3814.809259537012;3838.2707285615643;3839.4816699745734;3954.861579844749;4270.743755374625;4539.659525535532;4541.8183272669385;4546.840860569864;4268.679849011274;4440.711353761995;5226.764795584208;5504.669719816824;5601.775928962796;1114.8829372915727;1145.707844499664;1167.4921409187032;1174.3676289956966;1185.1822242652715;745.414849343263;833.2417500075023;840.9236177692563;847.3941132090584;856.07543852639;4442.644588432918;4514.751688657156;4684.812607495662;4759.547842954914;4897.873489097527;4393.428854248991;4807.855764327076;5240.069947539346;5330.067238233293;5335.706536140472;3828.655421012122;3842.538895332522;4729.148753224384;4743.810247590127;4753.765730106844;2576.136994106995;2600.1679462571797;2609.9869500652376;2707.417905150402;2785.832850029005;4820.517948205152;5087;5347.812826551052;5387.164989426645;5389.757460914268;4235.975822968193;4302.9354559681005;4794.5958369558;5120.183898855689;5293.761780719877;4392.692511524163;4655.906881862344;4850.563449289581;5265.315508983884;5386.569074474111;4353.540249758876;5145.768440420265;5189.195674670432;5202.817901373433;5206.167013277857;5209.95800839826;5476;5567.638103523258;5658.434156584309;5675.943240567542;4550.3629491871825;5364;5804.071348584206;5912.793052243323;5916.733746981482;3059.2963618367453;3794.5256842894637;3838.6353296436796;3844.4041173618134;3863.74885632433;4881.341018962395;5204.869878253074;5581.190727344431;5744.023516002206;5790.884182316246;4671.953280467288;4825.238787842482;5445.83662490126;5572.721363931868;5573.860653483695;4679.719216847;5263.578913686819;5409.890972350738;5515.4760297771645;5558;4221.387898754602;4301.44081269433;4484.125595508975;4509.233430316788;5330.680159190461;5276.736163191748;5356.651817631841;5451.972740136274;5470.863228419322;5487.094629386266;4462.910741785081;5082.796688132434;5270.183121616155;5337.919931201112;5448.346198456211 09:40:58 INFO - PROCESS | 1893 | |5;yui.html;677.9288174741652;693.7190437872663;701.5124488480504;726.4805663950275;741.5402450480696;14008.859911400896;14382.280885955686;14726.558203253893;14730.705385892275;14734.705305893902;12928.547500837472;12971.416286267116;12998.480060797558;13069.411876465581;13069.542566010192;644.9484041276687;665.1685393258427;665.2781731820955;803.2008151888879;807.7657479331002;819.9262066414021;830.3523251863556;833.3166803221361;836.9665213391457;837.3092198935872;876.2814492116468;878.565110270417;878.5826732302169;879.1252703559935;879.1999280654605;681.9829504262384;707.6284950401081;843.9240468357816;868.769776009358;872.0146234754686;650.2879347114902;653.0563335979464;653.0856800479366;653.7875190563086;655.387212955883;13922.721545569031;13953.581392558239;13957.58127256184;13967.79048314276;13988.300584970711;12957.611271661865;13100.737985240337;13205.801912971314;13306.268155251457;13319.933400332937;11697.941510292394;11904.583339583072;11907.464164112636;12302.876971230353;12399.380030998414;4104.4048612950955;4442.977785111086;4491.842785502525;4507.143642707875;4534.863954081383;90557.09442905619;93637;94297;94336.52831735798;94345.58481622781;9817.432478956574;10373.325733827429;10636;10674.573017079385;10782.353058816496;19867.105980230925;20400.183992640425;20548.897255513926;20551.383458496268;20703.896480517804;7635.847283054308;7680.078390593165;7699;7755;7847.529148251009;4254.617084462413;4279.786010699452;4293.763842988603;4375.8030888609455;4470.687051906335;3159.352332771822;3228.3704677588116;3249.6262929762906;3256.886008989721;3263.477843545021;579.7043507811047;582.860113572748;603.4177019176516;604.8608819971431;610.4749915073033;543.4647617249765;572.9512991395694;596.9046799123656;606.6026752477046;627.5418944170781;8069.515829050276;8639.870401943976;8730.91269087301;8754.912450875538;9025.41334813235;531.7367902888095;592.120700759372;597.5906504044743;615.5629503052883;627.8367624417593;4148.502179738451;4181.71714365101;4347.456567929009;4367.519572846984;4415.602595766396;75198.24801752022;75757.6212118936;75862.62068689731;90772.54613726889;108162;59285;59518;81399;101008.97982040465;102697;75897.62051189819;86638.56680716557;89803.55098224468;91634.54182729177;92898.53550732338;8153;8474.110218426977;8478.965048388465;8741.475511469333;8939.195472407515;59164.40835591589;59281.70359148177;59864.70067649634;59992;60804.98299227151;76260.61869690838;81297;83840.74238886543;85246;86901.56549217213;10082.89917100849;10158.74603134898;10439.217058720475;10589.9470502647;10885.619003334845;11756.412179391167;13099.607011789662;13408;13743.518976835763;14029.078708234128;10424.478776061318;12026.639200823989;12120.333381664093;12586;12973.09188356806;12741.299228542148;12744.171628844095;13126.868731312565;13668.043236973714;13733;12130;12437.067219958359;13351.332433378098;13479.19124852512;13492.93253533726;9036.774080648036;9921.65274215399;10720.678379648623;10905.509252083833;11422.31466112036;9569.808603827745;9879.419292913099;10237.72028496438;10660.84008739885;11151.83272250933 09:40:58 INFO - PROCESS | 1893 | __end_tp_report 09:40:58 INFO - PROCESS | 1893 | __start_cc_report 09:40:58 INFO - PROCESS | 1893 | _x_x_mozilla_cycle_collect,3039 09:40:58 INFO - PROCESS | 1893 | __end_cc_report 09:40:58 INFO - PROCESS | 1893 | __startTimestamp1480009258178__endTimestamp 09:40:58 INFO - PROCESS | 1893 | 09:40:58 INFO - PROCESS | 1893 | ------- Summary: start ------- 09:40:58 INFO - PROCESS | 1893 | Number of tests: 6 09:40:58 INFO - PROCESS | 1893 | 09:40:58 INFO - PROCESS | 1893 | [#0] dojo.html Cycles:165 Average:5744.11 Median:5927.61 stddev:628.91 (10.6%) stddev-sans-first:601.90 09:40:58 INFO - PROCESS | 1893 | Values: 3340.2 3348.5 3366.9 3373.1 3395.4 5662.5 5733.5 5747.1 5749.5 5762.3 5622.9 5655.0 5660.7 5686.9 5732.1 5198.6 5223.2 5229.6 5253.4 5296.2 5167.6 5169.7 5174.2 5223.5 5318.0 5253.2 5287.5 5300.3 5305.9 5330.0 5289.6 5296.2 5307.4 5327.8 5355.6 5035.1 5126.1 5203.2 5221.1 5230.9 5656.7 5676.6 5677.5 5738.2 5752.9 6172.2 6196.8 6231.5 6249.1 6266.3 6191.4 6232.3 6256.6 6257.6 6267.7 5632.1 5762.4 5774.9 5789.0 5871.8 6268.5 6286.1 6293.5 6297.6 6302.0 6167.2 6182.1 6188.6 6191.5 6194.3 6091.8 6139.5 6223.5 6224.1 6229.5 6143.2 6143.4 6198.3 6211.7 6250.9 6173.7 6231.3 6244.0 6248.5 6268.9 4972.9 4976.1 4978.4 4985.4 5003.5 4381.3 4389.6 4409.5 4409.8 4447.1 5816.2 5825.9 5834.6 5840.4 5873.6 6160.7 6165.5 6177.7 6224.4 6243.0 5589.7 5684.4 5697.2 5814.3 5820.1 5376.4 5413.0 5418.2 5433.6 5434.2 6339.9 6351.7 6366.1 6372.6 6375.7 5847.5 6219.3 6221.7 6228.0 6242.3 6238.1 6242.5 6259.1 6270.7 6365.4 6136.6 6167.3 6178.8 6179.7 6190.7 4985.9 6184.6 6218.9 6227.2 6241.3 5972.1 6021.0 6069.1 6100.3 6114.3 6060.3 6074.3 6080.1 6081.3 6083.6 5920.7 5924.4 5938.3 5939.4 5949.1 6019.3 6078.5 6090.8 6091.7 6093.1 5914.3 5920.9 5927.2 5927.6 5931.0 09:40:58 INFO - PROCESS | 1893 | 09:40:58 INFO - PROCESS | 1893 | [#1] ext.html Cycles:180 Average:71556.05 Median:37493.22 stddev:160077.12 (426.9%) stddev-sans-first:160437.13 09:40:58 INFO - PROCESS | 1893 | Values: 442.1 458.6 462.2 470.9 503.3 26647.3 30759.0 39243.6 40267.8 40431.6 40723.8 41354.0 41435.6 41559.6 41590.6 9380.0 9571.8 9573.0 9582.4 9589.0 8565.4 8760.0 11267.4 11508.4 11561.8 11343.1 11361.1 11421.8 11449.3 11534.4 11419.0 11496.0 11497.4 11499.7 11514.8 8516.6 8748.2 11076.2 11419.7 11431.3 33002.5 39100.0 39599.4 39658.4 39824.4 2672.8 3133.9 3282.1 3292.0 3364.7 28141.3 28398.9 29565.0 29873.0 30106.2 3145.6 3162.4 3299.2 3321.1 3325.1 479972.0 480977.0 481007.0 482389.6 483476.0 58036.0 58378.0 58521.0 58551.0 58625.1 60291.7 60357.7 60368.1 60513.1 60551.9 15859.7 16116.2 16137.6 16146.9 16164.5 11190.7 12552.5 12640.4 12645.4 12654.4 13545.8 13956.9 15348.3 15503.8 15776.2 6110.8 6161.9 6647.0 6876.9 6997.4 864.3 960.4 1035.6 1047.9 1058.3 24879.0 28806.8 29069.6 29501.3 29660.3 1032.4 1036.3 1046.9 1062.9 1066.6 14834.1 15381.8 15415.6 15676.9 15893.2 642522.0 648727.0 649915.0 651520.0 883949.0 47356.0 55950.7 57055.7 57199.7 60280.4 1328.7 1352.7 1372.1 1380.1 1381.0 29694.3 30171.4 30281.5 30329.4 30780.2 48887.8 49458.5 49512.0 49525.0 49592.8 585704.0 588899.0 589608.0 589746.0 590103.0 38525.4 38542.8 38680.6 38987.0 39039.8 44899.8 45019.0 45073.6 45229.5 45264.0 37225.8 37584.0 37989.4 39155.8 39176.0 37129.0 37498.0 37814.6 38349.8 38697.8 36498.5 37488.4 37853.8 38066.2 38182.8 27625.3 33080.3 33471.0 34384.8 34447.0 23295.5 24780.5 24967.3 25427.0 25970.7 09:40:58 INFO - PROCESS | 1893 | 09:40:58 INFO - PROCESS | 1893 | [#2] jquery.html Cycles:180 Average:34640.42 Median:25232.12 stddev:101876.66 (403.8%) stddev-sans-first:102129.92 09:40:58 INFO - PROCESS | 1893 | Values: 352.8 367.8 369.7 383.8 402.7 14587.8 14897.7 15398.8 18917.7 19934.9 17637.8 19387.0 19928.0 20011.2 20204.4 5805.4 6101.6 6103.5 6106.1 6112.7 4898.7 5955.4 6005.2 6015.3 6039.6 5675.5 5967.2 5971.6 5977.2 5990.8 5884.8 6008.7 6009.5 6013.8 6027.0 4971.0 5127.8 5198.7 5229.2 5247.0 24719.8 25148.6 25198.0 25266.2 25597.2 2112.9 2230.9 2273.5 2462.2 2549.0 15327.6 15385.0 15550.9 15706.0 15788.3 1622.8 1846.1 1853.6 1899.6 1921.0 92152.0 112184.0 116708.0 116837.4 117720.8 11301.7 11378.6 11544.2 11558.7 11703.9 14578.6 14587.1 14598.9 14600.0 14619.7 8241.3 8350.1 8373.0 8388.6 8510.1 6800.8 8176.6 8313.5 8457.5 8474.3 4334.7 4445.2 4457.9 4515.5 4530.7 1591.8 1845.2 1899.6 1902.5 1906.7 704.1 716.9 959.9 964.7 967.0 16452.0 18879.7 20446.5 20654.6 20669.4 934.5 941.7 1006.1 1007.6 1028.9 10435.5 10802.8 10926.9 10967.1 11976.9 591163.0 611024.0 620907.0 621458.0 625414.0 72288.6 72742.3 104762.0 131983.0 142554.3 911.5 940.1 973.6 975.0 1003.8 17915.0 18404.4 18660.3 19085.0 19320.3 44591.1 55493.7 58149.0 58207.8 58771.0 76944.0 79924.0 80776.0 81883.0 82659.6 7743.7 7873.0 7942.6 8070.1 8076.1 8838.7 12229.6 12331.4 12732.2 12737.2 10666.1 10716.0 10826.0 10943.2 11032.7 10598.6 10642.8 11024.1 11081.9 11155.4 10579.7 10657.3 10978.6 11070.6 11153.2 7829.8 7933.8 8078.4 8201.6 8206.9 9428.9 10227.0 10315.3 10334.8 10418.2 09:40:58 INFO - PROCESS | 1893 | 09:40:58 INFO - PROCESS | 1893 | [#3] mootools.html Cycles:180 Average:5511.92 Median:5093.84 stddev:3102.14 (60.9%) stddev-sans-first:3094.68 09:40:58 INFO - PROCESS | 1893 | Values: 1298.3 1335.3 1452.9 1598.6 1839.1 7590.2 8013.6 8398.0 8667.3 8684.1 8525.5 8609.6 8614.2 8615.2 8617.9 2318.3 2353.0 2357.0 2358.3 2360.3 2343.1 2378.6 2395.0 2395.3 2405.4 2328.1 2328.5 2361.1 2363.4 2365.5 2299.7 2309.6 2332.7 2332.7 2335.3 2330.4 2342.7 2367.8 2368.0 2368.6 8322.8 8479.1 8483.2 8489.7 8489.7 5076.2 5111.5 5258.8 5309.4 5329.0 2994.1 3079.2 3448.8 3527.1 3563.6 1023.9 1058.5 1090.5 1099.8 1117.3 11242.0 11343.1 11400.9 11406.3 11410.3 4194.9 4455.6 4526.4 4551.6 4554.7 8973.5 8974.3 8978.5 8978.6 8986.4 5183.6 5198.6 5199.1 5202.5 5214.1 4077.6 4335.8 4469.1 4475.6 4883.5 3340.4 3533.2 3620.6 3631.7 3722.8 1112.6 1123.1 1127.9 1135.6 1141.4 723.7 779.4 825.8 840.6 869.3 5159.4 5229.6 5430.2 5458.4 5605.9 839.0 920.4 923.7 925.8 947.2 3750.8 3773.8 3846.6 3897.6 3952.7 9926.0 10310.9 10324.7 10337.0 10344.0 10121.7 10321.4 10431.2 10441.9 10446.6 7853.6 8053.9 8058.0 8059.6 8062.1 5026.9 5367.5 5374.9 5485.9 5741.4 9519.0 9540.5 9613.7 9738.2 9741.3 9676.3 9778.1 9786.6 9891.2 9986.6 7923.4 7985.4 8016.4 8158.3 8162.0 8022.7 8120.0 8123.1 8133.3 8135.6 7197.0 7380.3 7385.6 7426.5 7492.6 6962.4 7069.8 7125.4 7181.2 7183.9 6296.2 6597.5 6812.8 6825.2 6915.2 4630.5 4688.9 4726.4 4785.4 4799.1 3665.0 4707.6 4874.1 4879.8 4980.0 09:40:58 INFO - PROCESS | 1893 | 09:40:58 INFO - PROCESS | 1893 | [#4] prototype.html Cycles:180 Average:4258.79 Median:4675.84 stddev:1316.68 (28.2%) stddev-sans-first:1285.54 09:40:58 INFO - PROCESS | 1893 | Values: 250.4 274.6 307.2 310.2 313.1 4855.4 4916.0 5072.2 5151.7 5177.5 4126.1 4972.4 5061.4 5067.8 5176.3 3528.9 3983.9 3996.2 4015.1 4024.2 3118.4 3606.7 3896.3 3911.6 3941.5 3369.7 3429.5 3971.7 3991.6 4079.3 3146.6 3966.1 3975.2 3980.5 3993.8 2929.2 3172.6 3279.3 3443.1 3485.4 4280.5 4972.1 5059.8 5089.9 5147.3 4316.7 4544.3 4548.6 4565.1 4578.9 4780.9 5030.2 5342.7 5391.2 5462.7 3307.6 3725.1 3740.5 3746.7 3825.0 5128.4 5131.6 5522.9 5550.4 5625.9 3169.0 3177.9 3694.4 3771.8 3837.9 3394.1 3792.4 3814.8 3838.3 3839.5 3954.9 4270.7 4539.7 4541.8 4546.8 4268.7 4440.7 5226.8 5504.7 5601.8 1114.9 1145.7 1167.5 1174.4 1185.2 745.4 833.2 840.9 847.4 856.1 4442.6 4514.8 4684.8 4759.5 4897.9 4393.4 4807.9 5240.1 5330.1 5335.7 3828.7 3842.5 4729.1 4743.8 4753.8 2576.1 2600.2 2610.0 2707.4 2785.8 4820.5 5087.0 5347.8 5387.2 5389.8 4236.0 4302.9 4794.6 5120.2 5293.8 4392.7 4655.9 4850.6 5265.3 5386.6 4353.5 5145.8 5189.2 5202.8 5206.2 5210.0 5476.0 5567.6 5658.4 5675.9 4550.4 5364.0 5804.1 5912.8 5916.7 3059.3 3794.5 3838.6 3844.4 3863.7 4881.3 5204.9 5581.2 5744.0 5790.9 4672.0 4825.2 5445.8 5572.7 5573.9 4679.7 5263.6 5409.9 5515.5 5558.0 4221.4 4301.4 4484.1 4509.2 5330.7 5276.7 5356.7 5452.0 5470.9 5487.1 4462.9 5082.8 5270.2 5337.9 5448.3 09:40:58 INFO - PROCESS | 1893 | 09:40:58 INFO - PROCESS | 1893 | [#5] yui.html Cycles:180 Average:19915.41 Median:576.33 stddev:28730.29 (4985.1%) stddev-sans-first:28774.57 09:40:58 INFO - PROCESS | 1893 | Values: 677.9 693.7 701.5 726.5 741.5 14008.9 14382.3 14726.6 14730.7 14734.7 12928.5 12971.4 12998.5 13069.4 13069.5 644.9 665.2 665.3 803.2 807.8 819.9 830.4 833.3 837.0 837.3 876.3 878.6 878.6 879.1 879.2 682.0 707.6 843.9 868.8 872.0 650.3 653.1 653.1 653.8 655.4 13922.7 13953.6 13957.6 13967.8 13988.3 12957.6 13100.7 13205.8 13306.3 13319.9 11697.9 11904.6 11907.5 12302.9 12399.4 4104.4 4443.0 4491.8 4507.1 4534.9 90557.1 93637.0 94297.0 94336.5 94345.6 9817.4 10373.3 10636.0 10674.6 10782.4 19867.1 20400.2 20548.9 20551.4 20703.9 7635.8 7680.1 7699.0 7755.0 7847.5 4254.6 4279.8 4293.8 4375.8 4470.7 3159.4 3228.4 3249.6 3256.9 3263.5 579.7 582.9 603.4 604.9 610.5 543.5 573.0 596.9 606.6 627.5 8069.5 8639.9 8730.9 8754.9 9025.4 531.7 592.1 597.6 615.6 627.8 4148.5 4181.7 4347.5 4367.5 4415.6 75198.2 75757.6 75862.6 90772.5 108162.0 59285.0 59518.0 81399.0 101009.0 102697.0 75897.6 86638.6 89803.6 91634.5 92898.5 8153.0 8474.1 8479.0 8741.5 8939.2 59164.4 59281.7 59864.7 59992.0 60805.0 76260.6 81297.0 83840.7 85246.0 86901.6 10082.9 10158.7 10439.2 10589.9 10885.6 11756.4 13099.6 13408.0 13743.5 14029.1 10424.5 12026.6 12120.3 12586.0 12973.1 12741.3 12744.2 13126.9 13668.0 13733.0 12130.0 12437.1 13351.3 13479.2 13492.9 9036.8 9921.7 10720.7 10905.5 11422.3 9569.8 9879.4 10237.7 10660.8 11151.8 09:40:58 INFO - PROCESS | 1893 | -------- Summary: end -------- 09:40:58 INFO - PROCESS | 1893 | 09:40:58 INFO - TEST-INFO | 1893: exit 0 09:40:58 INFO - TEST-OK | dromaeo_css | took 1120034ms 09:40:58 INFO - TEST-START | kraken 09:40:58 INFO - operating with platform_type : mac_ 09:40:58 INFO - Initialising browser for kraken test... 09:40:58 INFO - TEST-INFO | started process 1898 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/tmpOjK1vv/profile http://localhost:51547/getInfo.html) 09:41:01 INFO - PROCESS | 1898 | __metrics Screen width/height:1600/1200 09:41:01 INFO - PROCESS | 1898 | colorDepth:24 09:41:01 INFO - PROCESS | 1898 | Browser inner width/height: 1024/689 09:41:01 INFO - PROCESS | 1898 | __metrics 09:41:01 INFO - PROCESS | 1898 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 09:41:01 INFO - PROCESS | 1898 | JavaScript error: resource:///modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:41:02 INFO - TEST-INFO | 1898: exit 0 09:41:02 INFO - Browser initialized. 09:41:02 INFO - Running cycle 1/1 for kraken test... 09:41:02 INFO - TEST-INFO | started process 1899 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/tmpOjK1vv/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:41:14 INFO - PROCESS | 1899 | RSS: Main: 222994432 09:41:14 INFO - PROCESS | 1899 | 09:41:17 INFO - PROCESS | 1899 | Cycle 1(1): loaded http://localhost:51547/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:51547/tests/kraken/driver.html?testName=audio-beat-detection) 09:41:18 INFO - PROCESS | 1899 | RSS: Main: 272584704 09:41:18 INFO - PROCESS | 1899 | 09:41:21 INFO - PROCESS | 1899 | Cycle 1(1): loaded http://localhost:51547/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:51547/tests/kraken/driver.html?testName=audio-dft) 09:41:21 INFO - PROCESS | 1899 | RSS: Main: 282341376 09:41:21 INFO - PROCESS | 1899 | 09:41:25 INFO - PROCESS | 1899 | Cycle 1(1): loaded http://localhost:51547/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:51547/tests/kraken/driver.html?testName=audio-fft) 09:41:25 INFO - PROCESS | 1899 | RSS: Main: 379908096 09:41:25 INFO - PROCESS | 1899 | 09:41:27 INFO - PROCESS | 1899 | Cycle 1(1): loaded http://localhost:51547/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:51547/tests/kraken/driver.html?testName=audio-oscillator) 09:41:28 INFO - PROCESS | 1899 | RSS: Main: 283725824 09:41:28 INFO - PROCESS | 1899 | 09:41:30 INFO - PROCESS | 1899 | Cycle 1(1): loaded http://localhost:51547/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:51547/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:41:30 INFO - PROCESS | 1899 | RSS: Main: 285417472 09:41:30 INFO - PROCESS | 1899 | 09:41:33 INFO - PROCESS | 1899 | Cycle 1(1): loaded http://localhost:51547/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:51547/tests/kraken/driver.html?testName=imaging-darkroom) 09:41:34 INFO - PROCESS | 1899 | RSS: Main: 325840896 09:41:34 INFO - PROCESS | 1899 | 09:41:37 INFO - PROCESS | 1899 | Cycle 1(1): loaded http://localhost:51547/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:51547/tests/kraken/driver.html?testName=imaging-desaturate) 09:41:37 INFO - PROCESS | 1899 | RSS: Main: 325931008 09:41:37 INFO - PROCESS | 1899 | 09:41:40 INFO - PROCESS | 1899 | Cycle 1(1): loaded http://localhost:51547/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:51547/tests/kraken/driver.html?testName=json-parse-financial) 09:41:41 INFO - PROCESS | 1899 | RSS: Main: 323772416 09:41:41 INFO - PROCESS | 1899 | 09:41:42 INFO - PROCESS | 1899 | Cycle 1(1): loaded http://localhost:51547/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:51547/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:41:43 INFO - PROCESS | 1899 | RSS: Main: 301953024 09:41:43 INFO - PROCESS | 1899 | 09:41:45 INFO - PROCESS | 1899 | Cycle 1(1): loaded http://localhost:51547/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:51547/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:41:45 INFO - PROCESS | 1899 | RSS: Main: 301699072 09:41:45 INFO - PROCESS | 1899 | 09:41:48 INFO - PROCESS | 1899 | Cycle 1(1): loaded http://localhost:51547/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:51547/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:41:48 INFO - PROCESS | 1899 | RSS: Main: 304353280 09:41:48 INFO - PROCESS | 1899 | 09:41:51 INFO - PROCESS | 1899 | Cycle 1(1): loaded http://localhost:51547/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:51547/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:41:52 INFO - PROCESS | 1899 | RSS: Main: 324014080 09:41:52 INFO - PROCESS | 1899 | 09:41:55 INFO - PROCESS | 1899 | Cycle 1(1): loaded http://localhost:51547/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:51547/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:41:55 INFO - PROCESS | 1899 | RSS: Main: 316694528 09:41:55 INFO - PROCESS | 1899 | 09:41:57 INFO - PROCESS | 1899 | Cycle 1(1): loaded http://localhost:51547/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:51547/tests/kraken/driver.html?testName=ai-astar) 09:41:57 INFO - PROCESS | 1899 | RSS: Main: 360747008 09:41:57 INFO - PROCESS | 1899 | 09:41:57 INFO - PROCESS | 1899 | __start_tp_report 09:41:57 INFO - PROCESS | 1899 | _x_x_mozilla_page_load 09:41:57 INFO - PROCESS | 1899 | _x_x_mozilla_page_load_details 09:41:57 INFO - PROCESS | 1899 | |i|pagename|runs| 09:41:57 INFO - PROCESS | 1899 | |0;ai-astar;117;115;115;114;115;116;115;114;116;115 09:41:57 INFO - PROCESS | 1899 | |1;audio-beat-detection;151;113;117;146;126;145;114;112;142;111 09:41:57 INFO - PROCESS | 1899 | |2;audio-dft;185;183;184;183;183;262;208;186;183;184 09:41:57 INFO - PROCESS | 1899 | |3;audio-fft;81;59;59;89;62;59;58;60;82;57 09:41:57 INFO - PROCESS | 1899 | |4;audio-oscillator;89;70;95;69;69;67;90;68;68;91 09:41:57 INFO - PROCESS | 1899 | |5;imaging-gaussian-blur;86;83;86;84;85;83;85;83;85;83 09:41:57 INFO - PROCESS | 1899 | |6;imaging-darkroom;94;94;95;95;95;94;94;95;125;165 09:41:57 INFO - PROCESS | 1899 | |7;imaging-desaturate;85;82;80;83;91;122;134;84;82;82 09:41:57 INFO - PROCESS | 1899 | |8;json-parse-financial;53;53;51;55;58;58;59;56;57;57 09:41:57 INFO - PROCESS | 1899 | |9;json-stringify-tinderbox;64;72;71;75;76;70;70;72;70;71 09:41:57 INFO - PROCESS | 1899 | |10;stanford-crypto-aes;83;84;84;73;98;93;103;92;84;84 09:41:57 INFO - PROCESS | 1899 | |11;stanford-crypto-ccm;148;146;147;146;148;151;152;150;192;176 09:41:57 INFO - PROCESS | 1899 | |12;stanford-crypto-pbkdf2;168;167;164;168;168;165;173;167;164;164 09:41:57 INFO - PROCESS | 1899 | |13;stanford-crypto-sha256-iterative;66;67;68;67;67;65;64;66;65;65 09:41:57 INFO - PROCESS | 1899 | __end_tp_report 09:41:57 INFO - PROCESS | 1899 | __start_cc_report 09:41:57 INFO - PROCESS | 1899 | _x_x_mozilla_cycle_collect,2011 09:41:57 INFO - PROCESS | 1899 | __end_cc_report 09:41:57 INFO - PROCESS | 1899 | __startTimestamp1480009317913__endTimestamp 09:41:57 INFO - PROCESS | 1899 | 09:41:57 INFO - PROCESS | 1899 | ------- Summary: start ------- 09:41:57 INFO - PROCESS | 1899 | Number of tests: 14 09:41:57 INFO - PROCESS | 1899 | 09:41:57 INFO - PROCESS | 1899 | [#0] ai-astar Cycles:10 Average:115.20 Median:115.00 stddev:0.92 (0.8%) stddev-sans-first:0.71 09:41:57 INFO - PROCESS | 1899 | Values: 117.0 115.0 115.0 114.0 115.0 116.0 115.0 114.0 116.0 115.0 09:41:57 INFO - PROCESS | 1899 | 09:41:57 INFO - PROCESS | 1899 | [#1] audio-beat-detection Cycles:10 Average:127.70 Median:134.00 stddev:16.43 (12.3%) stddev-sans-first:15.10 09:41:57 INFO - PROCESS | 1899 | Values: 151.0 113.0 117.0 146.0 126.0 145.0 114.0 112.0 142.0 111.0 09:41:57 INFO - PROCESS | 1899 | 09:41:57 INFO - PROCESS | 1899 | [#2] audio-dft Cycles:10 Average:194.10 Median:184.50 stddev:25.05 (13.6%) stddev-sans-first:26.36 09:41:57 INFO - PROCESS | 1899 | Values: 185.0 183.0 184.0 183.0 183.0 262.0 208.0 186.0 183.0 184.0 09:41:57 INFO - PROCESS | 1899 | 09:41:57 INFO - PROCESS | 1899 | [#3] audio-fft Cycles:10 Average:66.60 Median:61.00 stddev:12.25 (20.1%) stddev-sans-first:11.83 09:41:57 INFO - PROCESS | 1899 | Values: 81.0 59.0 59.0 89.0 62.0 59.0 58.0 60.0 82.0 57.0 09:41:57 INFO - PROCESS | 1899 | 09:41:57 INFO - PROCESS | 1899 | [#4] audio-oscillator Cycles:10 Average:77.60 Median:79.50 stddev:11.87 (14.9%) stddev-sans-first:11.85 09:41:57 INFO - PROCESS | 1899 | Values: 89.0 70.0 95.0 69.0 69.0 67.0 90.0 68.0 68.0 91.0 09:41:57 INFO - PROCESS | 1899 | 09:41:57 INFO - PROCESS | 1899 | [#5] imaging-gaussian-blur Cycles:10 Average:84.30 Median:85.00 stddev:1.25 (1.5%) stddev-sans-first:1.17 09:41:57 INFO - PROCESS | 1899 | Values: 86.0 83.0 86.0 84.0 85.0 83.0 85.0 83.0 85.0 83.0 09:41:57 INFO - PROCESS | 1899 | 09:41:57 INFO - PROCESS | 1899 | [#6] imaging-darkroom Cycles:10 Average:104.60 Median:94.50 stddev:23.29 (24.6%) stddev-sans-first:24.39 09:41:57 INFO - PROCESS | 1899 | Values: 94.0 94.0 95.0 95.0 95.0 94.0 94.0 95.0 125.0 165.0 09:41:57 INFO - PROCESS | 1899 | 09:41:57 INFO - PROCESS | 1899 | [#7] imaging-desaturate Cycles:10 Average:92.50 Median:82.50 stddev:19.15 (23.2%) stddev-sans-first:20.12 09:41:57 INFO - PROCESS | 1899 | Values: 85.0 82.0 80.0 83.0 91.0 122.0 134.0 84.0 82.0 82.0 09:41:57 INFO - PROCESS | 1899 | 09:41:57 INFO - PROCESS | 1899 | [#8] json-parse-financial Cycles:10 Average:55.70 Median:57.00 stddev:2.63 (4.6%) stddev-sans-first:2.60 09:41:57 INFO - PROCESS | 1899 | Values: 53.0 53.0 51.0 55.0 58.0 58.0 59.0 56.0 57.0 57.0 09:41:57 INFO - PROCESS | 1899 | 09:41:57 INFO - PROCESS | 1899 | [#9] json-stringify-tinderbox Cycles:10 Average:71.10 Median:71.50 stddev:3.25 (4.5%) stddev-sans-first:2.20 09:41:57 INFO - PROCESS | 1899 | Values: 64.0 72.0 71.0 75.0 76.0 70.0 70.0 72.0 70.0 71.0 09:41:57 INFO - PROCESS | 1899 | 09:41:57 INFO - PROCESS | 1899 | [#10] stanford-crypto-aes Cycles:10 Average:87.80 Median:84.00 stddev:8.69 (10.3%) stddev-sans-first:9.04 09:41:57 INFO - PROCESS | 1899 | Values: 83.0 84.0 84.0 73.0 98.0 93.0 103.0 92.0 84.0 84.0 09:41:57 INFO - PROCESS | 1899 | 09:41:57 INFO - PROCESS | 1899 | [#11] stanford-crypto-ccm Cycles:10 Average:155.60 Median:150.50 stddev:15.56 (10.3%) stddev-sans-first:16.26 09:41:57 INFO - PROCESS | 1899 | Values: 148.0 146.0 147.0 146.0 148.0 151.0 152.0 150.0 192.0 176.0 09:41:57 INFO - PROCESS | 1899 | 09:41:57 INFO - PROCESS | 1899 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:166.80 Median:167.50 stddev:2.78 (1.7%) stddev-sans-first:2.92 09:41:57 INFO - PROCESS | 1899 | Values: 168.0 167.0 164.0 168.0 168.0 165.0 173.0 167.0 164.0 164.0 09:41:57 INFO - PROCESS | 1899 | 09:41:57 INFO - PROCESS | 1899 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.00 Median:66.50 stddev:1.25 (1.9%) stddev-sans-first:1.32 09:41:57 INFO - PROCESS | 1899 | Values: 66.0 67.0 68.0 67.0 67.0 65.0 64.0 66.0 65.0 65.0 09:41:57 INFO - PROCESS | 1899 | -------- Summary: end -------- 09:41:57 INFO - PROCESS | 1899 | 09:41:58 INFO - TEST-INFO | 1899: exit 0 09:41:58 INFO - TEST-OK | kraken | took 60048ms 09:41:58 INFO - SUITE-END | took 1180s 09:41:59 INFO - Completed test suite (00:19:40) 09:41:59 INFO - javascript benchmark 09:41:59 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3340.1983523954245, 3348.4642457206846, 3366.9158271043234, 3373.055544447555, 3395.3718562066006, 5662.5186859116975, 5733.455321744427, 5747.080467125261, 5749.5400367970615, 5762.250907382039, 5622.943770562293, 5655.0, 5660.688662123582, 5686.886262274752, 5732.111522713975, 5198.558122559587, 5223.190405487156, 5229.555487783534, 5253.4221235664045, 5296.205569164617, 5167.560757335631, 5169.6898186108765, 5174.197999310113, 5223.503767142115, 5318.0161670090965, 5253.159494480884, 5287.524122828944, 5300.284461597674, 5305.946940530584, 5330.0, 5289.629725919187, 5296.152615581527, 5307.363116426034, 5327.760250788724, 5355.598330125256, 5035.144025515671, 5126.128558145087, 5203.245529398216, 5221.1124108901195, 5230.895382092337, 5656.688882111483, 5676.574256930706, 5677.489025987639, 5738.225339579187, 5752.942470575283, 6172.22847144107, 6196.783112591038, 6231.501479881598, 6249.0626406039, 6266.342034086447, 6191.435893608058, 6232.283287421914, 6256.624602523818, 6257.624542527463, 6267.655278959655, 5632.070708333161, 5762.366139724627, 5774.88450230993, 5789.044807606782, 5871.765129394819, 6268.49852011838, 6286.05709143623, 6293.465055470337, 6297.590656607397, 6302.023186406114, 6167.167432396659, 6182.103594978703, 6188.566800323934, 6191.535634827406, 6194.34959329273, 6091.756329746849, 6139.461473530676, 6223.533235007392, 6224.097505861715, 6229.50163986889, 6143.218466644712, 6143.43342447673, 6198.318184999646, 6211.720472578745, 6250.9062364064575, 6173.722182501709, 6231.345708700521, 6243.96878015607, 6248.5313601480075, 6268.874622507525, 4972.875678108004, 4976.129177393942, 4978.427480839678, 4985.401751789808, 5003.4746351633285, 4381.277089280296, 4389.582989615981, 4409.536998615099, 4409.8456554021095, 4447.066116115588, 5816.243888294494, 5825.941740582624, 5834.562407819346, 5840.445157710011, 5873.647581145145, 6160.722767475474, 6165.475934545614, 6177.660228687465, 6224.439800417894, 6242.968785156045, 5589.720513974366, 5684.431556844365, 5697.202391665171, 5814.273215848019, 5820.068788993741, 5376.354837419456, 5412.972935135299, 5418.241446197536, 5433.565314774887, 5434.239206511092, 6339.936600633934, 6351.745930162834, 6366.108744775643, 6372.553921225655, 6375.744970201233, 5847.473727364558, 6219.253689557282, 6221.720022598904, 6228.03465462854, 6242.344553821965, 6238.064290356483, 6242.469390101892, 6259.123722678738, 6270.7491700331475, 6365.395287447685, 6136.6011209271255, 6167.259928808572, 6178.783742568989, 6179.6910154493, 6190.690465476799, 4985.900281994267, 6184.567080304336, 6218.906716399167, 6227.221597300338, 6241.344658810941, 5972.104184372379, 6021.0, 6069.119977603134, 6100.267967843887, 6114.296855861633, 6060.272767267956, 6074.271087469532, 6080.087986802015, 6081.27024757032, 6083.574149809648, 5920.73356698941, 5924.407559244041, 5938.257717785277, 5939.406059394026, 5949.137375080676, 6019.337872833898, 6078.45293923549, 6090.756369745338, 6091.664958427329, 6093.086037094471, 5914.319853216935, 5920.8815823684145, 5927.229460170151, 5927.614705044159, 5930.991731405585], "value": 5703.985508247958, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [442.0562099916679, 458.6010171151098, 462.1750175935955, 470.8675635097591, 503.3154909323322, 26647.33381665457, 30759.0, 39243.60756392435, 40267.79866100665, 40431.59568404315, 40723.79638101805, 41353.966150846245, 41435.585644143546, 41559.58440415595, 41590.58409415912, 9380.015098414671, 9571.80856382872, 9573.04269573044, 9582.377145485521, 9589.0, 8565.443246189008, 8760.0, 11267.380294083821, 11508.367039812807, 11561.768764624703, 11343.149263805248, 11361.091112711005, 11421.771564568704, 11449.313041217554, 11534.365609891494, 11419.0, 11496.022838058774, 11497.367644779491, 11499.655010349703, 11514.827277590843, 8516.616752246164, 8748.168923952215, 11076.224664273504, 11419.714507137389, 11431.314121152758, 33002.504962425584, 39100.0, 39599.40600890989, 39658.40512392316, 39824.40263396022, 2672.786177105827, 3133.937321253562, 3282.1138292661085, 3292.0453068610072, 3364.6971772540587, 28141.29646758827, 28398.858005710044, 29565.0, 29873.0, 30106.24734381636, 3145.622525296956, 3162.3675264946874, 3299.1752061984503, 3321.0534997525588, 3325.1022223999385, 479972.0, 480977.0, 481007.0, 482389.5880520575, 483476.0, 58036.0, 58378.0, 58521.0, 58551.0, 58625.12062319068, 60291.69854150701, 60357.698211508665, 60368.094478582854, 60513.09230361548, 60551.88068417672, 15859.682806344039, 16116.194190290438, 16137.59656008609, 16146.919265403832, 16164.515064548083, 11190.66428007161, 12552.497900083894, 12640.431180596896, 12645.367731613382, 12654.430550625244, 13545.796813047813, 13956.93021534906, 15348.309326080353, 15503.84496155024, 15776.211189440482, 6110.847228819315, 6161.876762464725, 6647.0029495576055, 6876.931230687729, 6997.440204783605, 864.2567392042838, 960.4333443268505, 1035.5961175141701, 1047.9056884880397, 1058.2645061682058, 24879.0, 28806.839487038556, 29069.563956540667, 29501.26246843846, 29660.258493537833, 1032.385730490357, 1036.290141253244, 1046.853440518328, 1062.9202809789297, 1066.6053560182627, 14834.10995340283, 15381.769273460906, 15415.59302435691, 15676.921615392079, 15893.205339732967, 642522.0, 648727.0, 649915.0, 651520.0, 883949.0, 47356.0, 55950.72024639932, 57055.71472142613, 57199.714001429726, 60280.39719602835, 1328.7076843094503, 1352.6753579140936, 1372.1355546005952, 1380.075349515847, 1380.9861901380857, 29694.257643559085, 30171.396572067995, 30281.545776813808, 30329.393412131194, 30780.23049423782, 48887.75556122339, 49458.505414945386, 49512.0, 49525.0, 49592.76018099576, 585704.0, 588899.0, 589608.0, 589746.0, 590103.0, 38525.42211866768, 38542.80728596339, 38680.6131938677, 38987.0, 39039.80480097581, 44899.77550112228, 45019.0, 45073.57086902933, 45229.51237681959, 45264.0, 37225.81387093047, 37584.0, 37989.43015854709, 39155.80422097985, 39176.020599485244, 37129.0, 37498.0, 37814.62185378111, 38349.808250958566, 38697.80651096727, 36498.452523212705, 37488.437673434375, 37853.81073094617, 38066.23867522579, 38182.809085954395, 27625.309367265978, 33080.33839323152, 33470.99587012394, 34384.82807586046, 34447.0, 23295.534089317778, 24780.50438991174, 24967.250982470556, 25427.0, 25970.74029259683], "value": 19885.80238247159, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [352.7777500174889, 367.8216065208374, 369.6543731610944, 383.75631474014, 402.69596454676713, 14587.781183282259, 14897.702045959088, 15398.769018464704, 18917.7162342565, 19934.900325498387, 17637.82362176378, 19387.0, 19928.003599819986, 20011.1995520179, 20204.39386818398, 5805.390434004422, 6101.60339577926, 6103.511719062487, 6106.053561697921, 6112.724927378279, 4898.7060776353355, 5955.4218132195, 6005.159277701126, 6015.308239552463, 6039.607425517349, 5675.5459563235045, 5967.194428752106, 5971.611845230068, 5977.222961015062, 5990.790322338719, 5884.794032208873, 6008.729607167645, 6009.489193418564, 6013.819585412444, 6027.041211606245, 4970.975145124287, 5127.794888204505, 5198.688078715251, 5229.2156176573435, 5247.0, 24719.764011799518, 25148.622770658272, 25197.992080316766, 25266.242012739647, 25597.23208303754, 2112.8837913914726, 2230.8996095175594, 2273.4771002669477, 2462.2243993142283, 2548.9745102548923, 15327.61680957985, 15385.0, 15550.922245388701, 15706.0, 15788.289526971314, 1622.8458296461818, 1846.0954132475124, 1853.6014756827212, 1899.582091939771, 1920.9711854322056, 92152.0, 112184.0, 116708.0, 116837.41581292124, 117720.82279177185, 11301.660950171508, 11378.601748938747, 11544.249623774596, 11558.711032224262, 11703.88296117045, 14578.635534111732, 14587.124772513684, 14598.854011459962, 14600.0, 14619.707605847823, 8241.340692744567, 8350.08149103598, 8373.015873015873, 8388.622511986976, 8510.148985101441, 6800.829979250559, 8176.550289734003, 8313.459625124347, 8457.450265732708, 8474.322054235647, 4334.718243314238, 4445.1998640245065, 4457.863244872537, 4515.525869783625, 4530.728156310632, 1591.7504758764314, 1845.1973391574709, 1899.582091939771, 1902.5053486093716, 1906.685396909522, 704.0776582676608, 716.8530451257479, 959.856021596766, 964.7395203295212, 967.0377973915998, 16452.0, 18879.686703897198, 20446.488837779176, 20654.58690826175, 20669.379918602466, 934.5093825741404, 941.684535680541, 1006.1347241372414, 1007.5868893753673, 1028.88682244953, 10435.530401131968, 10802.776010181411, 10926.890731092746, 10967.122630189726, 11976.940115299367, 591163.0, 611024.0, 620907.0, 621458.0, 625414.0, 72288.63855680688, 72742.2725772746, 104761.95238047522, 131983.0, 142554.2872285632, 911.4941207629829, 940.083418666808, 973.6202880876464, 975.0493269062607, 1003.7791685829252, 17915.0, 18404.35584754527, 18660.253589856253, 19085.0, 19320.32378866733, 44591.10817783691, 55493.72253138709, 58149.0, 58207.83584328374, 58771.0, 76944.0, 79924.0, 80776.0, 81883.0, 82659.58670206611, 7743.728969486041, 7873.0, 7942.563159026309, 8070.071941726542, 8076.071251806117, 8838.690645827364, 12229.633111006684, 12331.44508497102, 12732.172408793398, 12737.172083814892, 10666.146708263164, 10716.0, 10826.025657690845, 10943.179261555575, 11032.724181895517, 10598.576056957636, 10642.787144257227, 11024.062954648945, 11081.944590276997, 11155.386453744797, 10579.682609521727, 10657.25399222047, 10978.615748448767, 11070.557177713123, 11153.163512736415, 7829.804254893673, 7933.801654958672, 8078.394120441108, 8201.58992050407, 8206.876896846672, 9428.858567121362, 10227.0, 10315.329503582243, 10334.793304133726, 10418.166546676399], "value": 9432.72847462717, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1298.253504235065, 1335.3390071914405, 1452.8692417682407, 1598.5603958911297, 1839.0620783400461, 7590.240975902407, 8013.559254241014, 8397.999999999993, 8667.349948753837, 8684.13158684133, 8525.488470691764, 8609.56952152393, 8614.224719775219, 8615.18155775202, 8617.879675642173, 2318.281332786833, 2352.9647055294186, 2357.010055776578, 2358.2689366296504, 2360.303710405426, 2343.086196383412, 2378.6194208926577, 2395.0, 2395.3053614451874, 2405.4226985523524, 2328.092044102801, 2328.5459335429596, 2361.1499860050453, 2363.385519764865, 2365.526894621074, 2299.7355304139905, 2309.6304591265402, 2332.661764044204, 2332.731735850382, 2335.311083230451, 2330.4057465346395, 2342.7071616047992, 2367.8342516023886, 2368.0, 2368.5736567417857, 8322.791930201793, 8479.067302596772, 8483.194096560817, 8489.66041358345, 8489.702860399857, 5076.162433198518, 5111.514406131411, 5258.763355649005, 5309.362876454811, 5328.973355133238, 2994.116735563014, 3079.1994081538737, 3448.7607834389614, 3527.1005893496995, 3563.572371315433, 1023.8924912884114, 1058.544825724938, 1090.4820210400076, 1099.8075336816025, 1117.279354816139, 11242.0, 11343.149263805248, 11400.94299528497, 11406.31562106268, 11410.258333208314, 4194.916101677949, 4455.6212721919, 4526.411566496335, 4551.5676010778925, 4554.749488778149, 8973.50645714492, 8974.28205743539, 8978.506182159914, 8978.595963181673, 8986.370954033155, 5183.637145399785, 5198.584113270929, 5199.1161502544655, 5202.453742356998, 5214.0875346814155, 4077.55146933837, 4335.783210839445, 4469.128519938646, 4475.619572336388, 4883.487233840467, 3340.3820293245826, 3533.169705119295, 3620.6017338093316, 3631.6913062389995, 3722.7952462614276, 1112.5994641928899, 1123.095907794217, 1127.9436028198556, 1135.5628083188033, 1141.3779490177874, 723.706898706025, 779.3842864137268, 825.8141918068386, 840.5797101449275, 869.3262721390972, 5159.432462429129, 5229.607779416559, 5430.185472179205, 5458.426865179099, 5605.887882242414, 838.983220335602, 920.4201353147474, 923.6997975657938, 925.8379783537989, 947.2185992075135, 3750.812459377075, 3773.7924414157483, 3846.6345697158727, 3897.629725176104, 3952.6837852971694, 9925.950370248103, 10310.948445257876, 10324.690259292083, 10337.0, 10344.0, 10121.69634910954, 10321.432321222255, 10431.21765867563, 10441.947790261, 10446.63436779709, 7853.568053756984, 8053.879191812128, 8058.0, 8059.63731632078, 8062.072861620872, 5026.924596130988, 5367.5169234769055, 5374.892502149857, 5485.917711234414, 5741.368449470639, 9518.95240523793, 9540.475273860004, 9613.711588652352, 9738.220942324455, 9741.318107732392, 9676.274279429212, 9778.119969202522, 9786.559604817658, 9891.159251463418, 9986.60053597848, 7923.405744569065, 7985.361171106414, 8016.358691304798, 8158.3065439438315, 8162.020557533134, 8022.679092836222, 8119.959400202961, 8123.147069557847, 8133.308668763221, 8135.552544610104, 7197.0, 7380.335769780745, 7385.556866588022, 7426.480146389701, 7492.550446973199, 6962.373386395248, 7069.823254418475, 7125.394341481032, 7181.210066892533, 7183.892241616275, 6296.150019747275, 6597.472202223907, 6812.795616131524, 6825.2151002633345, 6915.204751453647, 4630.513796051501, 4688.882777930579, 4726.432828060517, 4785.425748910153, 4799.112164249625, 3665.0, 4707.62339012885, 4874.147024270667, 4879.780409881493, 4980.028894365564], "value": 4419.058160940939, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [250.39404640769337, 274.601827350342, 307.2349848876298, 310.2383648143807, 313.06549948403995, 4855.4416242132165, 4915.975420122899, 5072.224721347527, 5151.716655583941, 5177.5081367270095, 4126.133511962484, 4972.428170760364, 5061.392632884059, 5067.771950262238, 5176.301199338088, 3528.9470657940024, 3983.9203215935663, 3996.2407142642805, 4015.096603264257, 4024.215278020787, 3118.3919135768533, 3606.711463082947, 3896.2856343209037, 3911.5697273299916, 3941.507311586052, 3369.7135743461713, 3429.4512877939533, 3971.702122340836, 3991.62289010291, 4079.3065178919655, 3146.622405311354, 3966.147278335143, 3975.2248311579256, 3980.522337319526, 3993.820278087493, 2929.2383980165096, 3172.5558421820965, 3279.2949515853966, 3443.1220038890174, 3485.372632926072, 4280.468613393931, 4972.055309491221, 5059.797608095671, 5089.949100509021, 5147.305113809625, 4316.6546676265825, 4544.341070544786, 4548.56788605082, 4565.1098035883015, 4578.893708893913, 4780.856574302777, 5030.220315851049, 5342.732863356856, 5391.218273350342, 5462.7268636568015, 3307.6361600223954, 3725.100198898554, 3740.4576336431064, 3746.7002639789025, 3825.0, 5128.435872054109, 5131.639445854911, 5522.861928451781, 5550.4449555044985, 5625.915611265835, 3168.9560233649167, 3177.920551986219, 3694.37447523089, 3771.830267637963, 3837.9424308635394, 3394.100563350714, 3792.4311353296675, 3814.809259537012, 3838.2707285615643, 3839.4816699745734, 3954.861579844749, 4270.743755374625, 4539.659525535532, 4541.8183272669385, 4546.840860569864, 4268.679849011274, 4440.711353761995, 5226.764795584208, 5504.669719816824, 5601.775928962796, 1114.8829372915727, 1145.707844499664, 1167.4921409187032, 1174.3676289956966, 1185.1822242652715, 745.414849343263, 833.2417500075023, 840.9236177692563, 847.3941132090584, 856.07543852639, 4442.644588432918, 4514.751688657156, 4684.812607495662, 4759.547842954914, 4897.873489097527, 4393.428854248991, 4807.855764327076, 5240.069947539346, 5330.067238233293, 5335.706536140472, 3828.655421012122, 3842.538895332522, 4729.148753224384, 4743.810247590127, 4753.765730106844, 2576.136994106995, 2600.1679462571797, 2609.9869500652376, 2707.417905150402, 2785.832850029005, 4820.517948205152, 5087.0, 5347.812826551052, 5387.164989426645, 5389.757460914268, 4235.975822968193, 4302.9354559681005, 4794.5958369558, 5120.183898855689, 5293.761780719877, 4392.692511524163, 4655.906881862344, 4850.563449289581, 5265.315508983884, 5386.569074474111, 4353.540249758876, 5145.768440420265, 5189.195674670432, 5202.817901373433, 5206.167013277857, 5209.95800839826, 5476.0, 5567.638103523258, 5658.434156584309, 5675.943240567542, 4550.3629491871825, 5364.0, 5804.071348584206, 5912.793052243323, 5916.733746981482, 3059.2963618367453, 3794.5256842894637, 3838.6353296436796, 3844.4041173618134, 3863.74885632433, 4881.341018962395, 5204.869878253074, 5581.190727344431, 5744.023516002206, 5790.884182316246, 4671.953280467288, 4825.238787842482, 5445.83662490126, 5572.721363931868, 5573.860653483695, 4679.719216847, 5263.578913686819, 5409.890972350738, 5515.4760297771645, 5558.0, 4221.387898754602, 4301.44081269433, 4484.125595508975, 4509.233430316788, 5330.680159190461, 5276.736163191748, 5356.651817631841, 5451.972740136274, 5470.863228419322, 5487.094629386266, 4462.910741785081, 5082.796688132434, 5270.183121616155, 5337.919931201112, 5448.346198456211], "value": 3838.9364019865916, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [677.9288174741652, 693.7190437872663, 701.5124488480504, 726.4805663950275, 741.5402450480696, 14008.859911400896, 14382.280885955686, 14726.558203253893, 14730.705385892275, 14734.705305893902, 12928.547500837472, 12971.416286267116, 12998.480060797558, 13069.411876465581, 13069.542566010192, 644.9484041276687, 665.1685393258427, 665.2781731820955, 803.2008151888879, 807.7657479331002, 819.9262066414021, 830.3523251863556, 833.3166803221361, 836.9665213391457, 837.3092198935872, 876.2814492116468, 878.565110270417, 878.5826732302169, 879.1252703559935, 879.1999280654605, 681.9829504262384, 707.6284950401081, 843.9240468357816, 868.769776009358, 872.0146234754686, 650.2879347114902, 653.0563335979464, 653.0856800479366, 653.7875190563086, 655.387212955883, 13922.721545569031, 13953.581392558239, 13957.58127256184, 13967.79048314276, 13988.300584970711, 12957.611271661865, 13100.737985240337, 13205.801912971314, 13306.268155251457, 13319.933400332937, 11697.941510292394, 11904.583339583072, 11907.464164112636, 12302.876971230353, 12399.380030998414, 4104.4048612950955, 4442.977785111086, 4491.842785502525, 4507.143642707875, 4534.863954081383, 90557.09442905619, 93637.0, 94297.0, 94336.52831735798, 94345.58481622781, 9817.432478956574, 10373.325733827429, 10636.0, 10674.573017079385, 10782.353058816496, 19867.105980230925, 20400.183992640425, 20548.897255513926, 20551.383458496268, 20703.896480517804, 7635.847283054308, 7680.078390593165, 7699.0, 7755.0, 7847.529148251009, 4254.617084462413, 4279.786010699452, 4293.763842988603, 4375.8030888609455, 4470.687051906335, 3159.352332771822, 3228.3704677588116, 3249.6262929762906, 3256.886008989721, 3263.477843545021, 579.7043507811047, 582.860113572748, 603.4177019176516, 604.8608819971431, 610.4749915073033, 543.4647617249765, 572.9512991395694, 596.9046799123656, 606.6026752477046, 627.5418944170781, 8069.515829050276, 8639.870401943976, 8730.91269087301, 8754.912450875538, 9025.41334813235, 531.7367902888095, 592.120700759372, 597.5906504044743, 615.5629503052883, 627.8367624417593, 4148.502179738451, 4181.71714365101, 4347.456567929009, 4367.519572846984, 4415.602595766396, 75198.24801752022, 75757.6212118936, 75862.62068689731, 90772.54613726889, 108162.0, 59285.0, 59518.0, 81399.0, 101008.97982040465, 102697.0, 75897.62051189819, 86638.56680716557, 89803.55098224468, 91634.54182729177, 92898.53550732338, 8153.0, 8474.110218426977, 8478.965048388465, 8741.475511469333, 8939.195472407515, 59164.40835591589, 59281.70359148177, 59864.70067649634, 59992.0, 60804.98299227151, 76260.61869690838, 81297.0, 83840.74238886543, 85246.0, 86901.56549217213, 10082.89917100849, 10158.74603134898, 10439.217058720475, 10589.9470502647, 10885.619003334845, 11756.412179391167, 13099.607011789662, 13408.0, 13743.518976835763, 14029.078708234128, 10424.478776061318, 12026.639200823989, 12120.333381664093, 12586.0, 12973.09188356806, 12741.299228542148, 12744.171628844095, 13126.868731312565, 13668.043236973714, 13733.0, 12130.0, 12437.067219958359, 13351.332433378098, 13479.19124852512, 13492.93253533726, 9036.774080648036, 9921.65274215399, 10720.678379648623, 10905.509252083833, 11422.31466112036, 9569.808603827745, 9879.419292913099, 10237.72028496438, 10660.84008739885, 11151.83272250933], "value": 7109.803856027135, "unit": "score"}], "name": "dromaeo_css", "value": 7108.879906450952, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 115.0, 115.0, 114.0, 115.0, 116.0, 115.0, 114.0, 116.0, 115.0], "value": 115.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [151.0, 113.0, 117.0, 146.0, 126.0, 145.0, 114.0, 112.0, 142.0, 111.0], "value": 127.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [185.0, 183.0, 184.0, 183.0, 183.0, 262.0, 208.0, 186.0, 183.0, 184.0], "value": 194.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 59.0, 59.0, 89.0, 62.0, 59.0, 58.0, 60.0, 82.0, 57.0], "value": 66.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 70.0, 95.0, 69.0, 69.0, 67.0, 90.0, 68.0, 68.0, 91.0], "value": 77.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 83.0, 86.0, 84.0, 85.0, 83.0, 85.0, 83.0, 85.0, 83.0], "value": 84.3, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 95.0, 95.0, 95.0, 94.0, 94.0, 95.0, 125.0, 165.0], "value": 104.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 82.0, 80.0, 83.0, 91.0, 122.0, 134.0, 84.0, 82.0, 82.0], "value": 92.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [53.0, 53.0, 51.0, 55.0, 58.0, 58.0, 59.0, 56.0, 57.0, 57.0], "value": 55.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 72.0, 71.0, 75.0, 76.0, 70.0, 70.0, 72.0, 70.0, 71.0], "value": 71.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 84.0, 84.0, 73.0, 98.0, 93.0, 103.0, 92.0, 84.0, 84.0], "value": 87.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 146.0, 147.0, 146.0, 148.0, 151.0, 152.0, 150.0, 192.0, 176.0], "value": 155.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [168.0, 167.0, 164.0, 168.0, 168.0, 165.0, 173.0, 167.0, 164.0, 164.0], "value": 166.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 67.0, 68.0, 67.0, 67.0, 65.0, 64.0, 66.0, 65.0, 65.0], "value": 66.0, "unit": "score"}], "name": "kraken", "value": 1465.6, "alertThreshold": 2.0}]} 09:41:59 INFO - Return code: 0 09:41:59 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 09:41:59 INFO - # TBPL SUCCESS # 09:41:59 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:41:59 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:41:59 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:41:59 INFO - 2016-11-24 09:41:59.220 screenresolution[1903:16345] starting screenresolution argv=screenresolution get 09:41:59 INFO - 2016-11-24 09:41:59.223 screenresolution[1903:16345] Display 0: 1600x1200x32@60 09:41:59 INFO - 2016-11-24 09:41:59.229 screenresolution[1904:16348] starting screenresolution argv=screenresolution list 09:41:59 INFO - Available Modes on Display 0 09:41:59 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:41:59 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:41:59 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:41:59 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:41:59 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:41:59 INFO - Intel Iris: 09:41:59 INFO - Chipset Model: Intel Iris 09:41:59 INFO - Type: GPU 09:41:59 INFO - Bus: Built-In 09:41:59 INFO - VRAM (Dynamic, Max): 1536 MB 09:41:59 INFO - Vendor: Intel (0x8086) 09:41:59 INFO - Device ID: 0x0a2e 09:41:59 INFO - Revision ID: 0x0009 09:41:59 INFO - Displays: 09:41:59 INFO - Display: 09:41:59 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:41:59 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:41:59 INFO - Display Serial Number: 1600x1200 60 09:41:59 INFO - Main Display: Yes 09:41:59 INFO - Mirror: Off 09:41:59 INFO - Online: Yes 09:41:59 INFO - Rotation: Supported 09:41:59 INFO - Return code: 0 09:41:59 INFO - Running post-action listener: _resource_record_post_action 09:41:59 INFO - [mozharness: 2016-11-24 17:41:59.477032Z] Finished run-tests step (success) 09:41:59 INFO - Running post-run listener: _resource_record_post_run 09:41:59 INFO - Total resource usage - Wall time: 1211s; CPU: 53.0%; Read bytes: 847872; Write bytes: 655641088; Read time: 13; Write time: 1777 09:41:59 INFO - TinderboxPrint: CPU usage
53.5% 09:41:59 INFO - TinderboxPrint: I/O read bytes / time
847,872 / 13 09:41:59 INFO - TinderboxPrint: I/O write bytes / time
655,641,088 / 1,777 09:41:59 INFO - TinderboxPrint: CPU idle
2,255.3 (46.5%) 09:41:59 INFO - TinderboxPrint: CPU system
972.5 (20.1%) 09:41:59 INFO - TinderboxPrint: CPU user
1,617.9 (33.4%) 09:41:59 INFO - TinderboxPrint: Swap in / out
349,458,432 / 0 09:41:59 INFO - install - Wall time: 31s; CPU: 54.0%; Read bytes: 333597184; Write bytes: 334165504; Read time: 25658; Write time: 620 09:41:59 INFO - run-tests - Wall time: 1181s; CPU: 53.0%; Read bytes: 847872; Write bytes: 303744000; Read time: 13; Write time: 1104 09:41:59 INFO - Running post-run listener: _upload_blobber_files 09:41:59 INFO - Blob upload gear active. 09:41:59 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:41:59 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 09:41:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 09:41:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 09:42:00 INFO - (blobuploader) - INFO - Open directory for files ... 09:42:00 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 09:42:00 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:42:00 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:42:01 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 09:42:01 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:42:01 INFO - (blobuploader) - INFO - Done attempting. 09:42:01 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 09:42:01 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:42:01 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:42:02 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 09:42:02 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:42:02 INFO - (blobuploader) - INFO - Done attempting. 09:42:02 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 09:42:02 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:42:02 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:42:05 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 09:42:05 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:42:05 INFO - (blobuploader) - INFO - Done attempting. 09:42:05 INFO - (blobuploader) - INFO - Iteration through files over. 09:42:05 INFO - Return code: 0 09:42:05 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:42:05 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:42:05 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/af53b2a923c64dc071ed17e2c32a49119490dd597b6ff3d47b3532d87506a00bb508458e1863b0582936c2c7fb44619381bbaa337691d98c3230f35d0dc1d9bb", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/feb61419d77e21f819fc1bf1392555425d565c3eea87b7c0cd2b83e61d66508cad7459384d3732e33912c055a22ed151aa6e1eb6dbc4095458b8c652b3031e42", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/dbdfdd6589eb2500035e435b4b6c71074c5e2585251209fb2c4868b315f8fe8b2e926aba61abf3a8cd18f7f5b85ab5d27c23fcda7154acc9cad975bb75fb15d7"} 09:42:05 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:42:05 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:42:05 INFO - Contents: 09:42:05 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/af53b2a923c64dc071ed17e2c32a49119490dd597b6ff3d47b3532d87506a00bb508458e1863b0582936c2c7fb44619381bbaa337691d98c3230f35d0dc1d9bb", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/feb61419d77e21f819fc1bf1392555425d565c3eea87b7c0cd2b83e61d66508cad7459384d3732e33912c055a22ed151aa6e1eb6dbc4095458b8c652b3031e42", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/dbdfdd6589eb2500035e435b4b6c71074c5e2585251209fb2c4868b315f8fe8b2e926aba61abf3a8cd18f7f5b85ab5d27c23fcda7154acc9cad975bb75fb15d7"} 09:42:05 INFO - Running post-run listener: copy_logs_to_upload_dir 09:42:05 INFO - Copying logs to upload dir... 09:42:05 INFO - mkdir: /builds/slave/test/build/upload/logs 09:42:05 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1270.884621 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 10 secs) (at 2016-11-24 09:42:05.310720) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-24 09:42:05.313984) ========= 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.8RKUl74iT1/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.0eOqmWDMSi/Listeners TMPDIR=/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/af53b2a923c64dc071ed17e2c32a49119490dd597b6ff3d47b3532d87506a00bb508458e1863b0582936c2c7fb44619381bbaa337691d98c3230f35d0dc1d9bb", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/feb61419d77e21f819fc1bf1392555425d565c3eea87b7c0cd2b83e61d66508cad7459384d3732e33912c055a22ed151aa6e1eb6dbc4095458b8c652b3031e42", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/dbdfdd6589eb2500035e435b4b6c71074c5e2585251209fb2c4868b315f8fe8b2e926aba61abf3a8cd18f7f5b85ab5d27c23fcda7154acc9cad975bb75fb15d7"} build_url:https://queue.taskcluster.net/v1/task/e0ek61HuSpqpUqKl1-mBIg/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011003 build_url: 'https://queue.taskcluster.net/v1/task/e0ek61HuSpqpUqKl1-mBIg/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/af53b2a923c64dc071ed17e2c32a49119490dd597b6ff3d47b3532d87506a00bb508458e1863b0582936c2c7fb44619381bbaa337691d98c3230f35d0dc1d9bb", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/feb61419d77e21f819fc1bf1392555425d565c3eea87b7c0cd2b83e61d66508cad7459384d3732e33912c055a22ed151aa6e1eb6dbc4095458b8c652b3031e42", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/dbdfdd6589eb2500035e435b4b6c71074c5e2585251209fb2c4868b315f8fe8b2e926aba61abf3a8cd18f7f5b85ab5d27c23fcda7154acc9cad975bb75fb15d7"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-24 09:42:05.348883) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-24 09:42:05.349345) ========= 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.8RKUl74iT1/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.0eOqmWDMSi/Listeners TMPDIR=/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/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.005212 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-24 09:42:05.399791) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-24 09:42:05.400168) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-24 09:42:05.400768) ========= ========= Total master_lag: 0.19 =========