builder: mozilla-aurora_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0298 starttime: 1480464421.18 results: success (0) buildid: 20161129135426 builduid: 0309fa65b5e0444a92f169b24e5a713f revision: f51d3ab82dc60c54403cc6f64f4e0a5a87a5f5ff ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-11-29 16:07:01.183616) ========= master: http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-11-29 16:07:01.184027) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-29 16:07:01.184296) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-29 16:07:01.208666) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-29 16:07:01.209014) ========= 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.XP3Fhzc5Xj/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.47dfj7GoKz/Listeners TMPDIR=/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/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-29 16:07:01-- 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% 69.5M=0s 2016-11-29 16:07:01 (69.5 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.098201 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-29 16:07:01.330437) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-29 16:07:01.330745) ========= 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.XP3Fhzc5Xj/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.47dfj7GoKz/Listeners TMPDIR=/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/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.020867 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-29 16:07:01.379688) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-29 16:07:01.380038) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev f51d3ab82dc60c54403cc6f64f4e0a5a87a5f5ff --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 f51d3ab82dc60c54403cc6f64f4e0a5a87a5f5ff --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.XP3Fhzc5Xj/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.47dfj7GoKz/Listeners TMPDIR=/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/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-29 16:07:01,456 truncating revision to first 12 chars 2016-11-29 16:07:01,456 Setting DEBUG logging. 2016-11-29 16:07:01,456 attempt 1/10 2016-11-29 16:07:01,456 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/f51d3ab82dc6?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-11-29 16:07:01,752 unpacking tar archive at: mozilla-aurora-f51d3ab82dc6/testing/mozharness/ program finished with exit code 0 elapsedTime=0.559538 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-29 16:07:01.964898) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-29 16:07:01.965214) ========= script_repo_revision: f51d3ab82dc60c54403cc6f64f4e0a5a87a5f5ff ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-29 16:07:01.965603) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-29 16:07:01.965870) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-29 16:07:01.985307) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 14 secs) (at 2016-11-29 16:07:01.985577) ========= /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.XP3Fhzc5Xj/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.47dfj7GoKz/Listeners TMPDIR=/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/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 16:07:02 INFO - MultiFileLogger online at 20161129 16:07:02 in /builds/slave/test 16:07:02 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 16:07:02 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 16:07:02 INFO - {'append_to_log': False, 16:07:02 INFO - 'base_work_dir': '/builds/slave/test', 16:07:02 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 16:07:02 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 16:07:02 INFO - 'branch': 'Mozilla-Aurora', 16:07:02 INFO - 'buildbot_json_path': 'buildprops.json', 16:07:02 INFO - 'config_files': ('talos/mac_config.py',), 16:07:02 INFO - 'default_actions': ('clobber', 16:07:02 INFO - 'read-buildbot-config', 16:07:02 INFO - 'download-and-extract', 16:07:02 INFO - 'populate-webroot', 16:07:02 INFO - 'create-virtualenv', 16:07:02 INFO - 'install', 16:07:02 INFO - 'run-tests'), 16:07:02 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 16:07:02 INFO - 'download_minidump_stackwalk': True, 16:07:02 INFO - 'download_symbols': 'ondemand', 16:07:02 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 16:07:02 INFO - 'tooltool.py': '/tools/tooltool.py', 16:07:02 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 16:07:02 INFO - '/tools/misc-python/virtualenv.py')}, 16:07:02 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 16:07:02 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 16:07:02 INFO - 'installer_path': 'installer.exe', 16:07:02 INFO - 'log_level': 'info', 16:07:02 INFO - 'log_name': 'talos', 16:07:02 INFO - 'log_to_console': True, 16:07:02 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 16:07:02 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 16:07:02 INFO - 'opt_config_files': (), 16:07:02 INFO - 'pip_index': False, 16:07:02 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 16:07:02 INFO - 'cmd': ('bash', 16:07:02 INFO - '-c', 16:07:02 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 16:07:02 INFO - 'enabled': True, 16:07:02 INFO - 'halt_on_failure': False, 16:07:02 INFO - 'name': 'check_screen_resolution'},), 16:07:02 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 16:07:02 INFO - 'cmd': ('bash', 16:07:02 INFO - '-c', 16:07:02 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 16:07:02 INFO - 'enabled': True, 16:07:02 INFO - 'halt_on_failure': False, 16:07:02 INFO - 'name': 'check_screen_resolution'},), 16:07:02 INFO - 'run_cmd_checks_enabled': True, 16:07:02 INFO - 'sps_profile': False, 16:07:02 INFO - 'sps_profile_interval': 0, 16:07:02 INFO - 'suite': 'dromaeojs', 16:07:02 INFO - 'system_bits': '32', 16:07:02 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 16:07:02 INFO - 'title': 't-yosemite-r7-0298', 16:07:02 INFO - 'tooltool_cache': '/builds/tooltool_cache', 16:07:02 INFO - 'use_talos_json': True, 16:07:02 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 16:07:02 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 16:07:02 INFO - 'work_dir': 'build'} 16:07:02 INFO - [mozharness: 2016-11-30 00:07:02.180370Z] Running clobber step. 16:07:02 INFO - Running pre-action listener: _resource_record_pre_action 16:07:02 INFO - Running main action method: clobber 16:07:02 INFO - rmtree: /builds/slave/test/build 16:07:02 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 16:07:03 INFO - Running post-action listener: _resource_record_post_action 16:07:03 INFO - [mozharness: 2016-11-30 00:07:03.934157Z] Finished clobber step (success) 16:07:03 INFO - [mozharness: 2016-11-30 00:07:03.934324Z] Running read-buildbot-config step. 16:07:03 INFO - Running pre-action listener: _resource_record_pre_action 16:07:03 INFO - Running main action method: read_buildbot_config 16:07:03 INFO - Using buildbot properties: 16:07:03 INFO - { 16:07:03 INFO - "project": "", 16:07:03 INFO - "product": "firefox", 16:07:03 INFO - "script_repo_revision": "production", 16:07:03 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs", 16:07:03 INFO - "repository": "", 16:07:03 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs", 16:07:03 INFO - "buildid": "20161129135426", 16:07:03 INFO - "pgo_build": "False", 16:07:03 INFO - "basedir": "/builds/slave/test", 16:07:03 INFO - "buildnumber": 7, 16:07:03 INFO - "slavename": "t-yosemite-r7-0298", 16:07:03 INFO - "master": "http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/", 16:07:03 INFO - "platform": "yosemite_r7", 16:07:03 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 16:07:03 INFO - "branch": "mozilla-aurora", 16:07:03 INFO - "repo_path": "releases/mozilla-aurora", 16:07:03 INFO - "revision": "f51d3ab82dc60c54403cc6f64f4e0a5a87a5f5ff", 16:07:03 INFO - "stage_platform": "macosx64", 16:07:03 INFO - "builduid": "0309fa65b5e0444a92f169b24e5a713f", 16:07:03 INFO - "slavebuilddir": "test" 16:07:03 INFO - } 16:07:03 INFO - Found installer url https://queue.taskcluster.net/v1/task/MMEi2XmgQfGQkhleRYTTVQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg. 16:07:03 INFO - Running post-action listener: _resource_record_post_action 16:07:03 INFO - [mozharness: 2016-11-30 00:07:03.938476Z] Finished read-buildbot-config step (success) 16:07:03 INFO - [mozharness: 2016-11-30 00:07:03.938596Z] Running download-and-extract step. 16:07:03 INFO - Running pre-action listener: _resource_record_pre_action 16:07:03 INFO - Running main action method: download_and_extract 16:07:03 INFO - mkdir: /builds/slave/test/build/tests 16:07:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:07:03 INFO - trying https://queue.taskcluster.net/v1/task/MMEi2XmgQfGQkhleRYTTVQ/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json 16:07:03 INFO - Downloading https://queue.taskcluster.net/v1/task/MMEi2XmgQfGQkhleRYTTVQ/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 16:07:03 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/MMEi2XmgQfGQkhleRYTTVQ/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 16:07:04 INFO - Downloaded 1199 bytes. 16:07:04 INFO - Reading from file /builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json 16:07:04 INFO - Using the following test package requirements: 16:07:04 INFO - {u'common': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 16:07:04 INFO - u'cppunittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 16:07:04 INFO - u'firefox-52.0a2.en-US.mac.cppunittest.tests.zip'], 16:07:04 INFO - u'gtest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 16:07:04 INFO - u'firefox-52.0a2.en-US.mac.gtest.tests.zip'], 16:07:04 INFO - u'jittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 16:07:04 INFO - u'jsshell-mac.zip'], 16:07:04 INFO - u'mochitest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 16:07:04 INFO - u'firefox-52.0a2.en-US.mac.mochitest.tests.zip'], 16:07:04 INFO - u'mozbase': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 16:07:04 INFO - u'reftest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 16:07:04 INFO - u'firefox-52.0a2.en-US.mac.reftest.tests.zip'], 16:07:04 INFO - u'talos': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 16:07:04 INFO - u'firefox-52.0a2.en-US.mac.talos.tests.zip'], 16:07:04 INFO - u'web-platform': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 16:07:04 INFO - u'firefox-52.0a2.en-US.mac.web-platform.tests.zip'], 16:07:04 INFO - u'xpcshell': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 16:07:04 INFO - u'firefox-52.0a2.en-US.mac.xpcshell.tests.zip']} 16:07:04 INFO - Downloading packages: [u'firefox-52.0a2.en-US.mac.common.tests.zip'] for test suite category: common 16:07:04 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/MMEi2XmgQfGQkhleRYTTVQ/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 16:07:04 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/MMEi2XmgQfGQkhleRYTTVQ/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 16:07:04 INFO - Fetch https://queue.taskcluster.net/v1/task/MMEi2XmgQfGQkhleRYTTVQ/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 16:07:05 INFO - Http code: 200 16:07:05 INFO - via: 1.1 1b52a5dd431f9e3c81753e61dfdf467a.cloudfront.net (CloudFront) 16:07:05 INFO - x-amz-cf-id: 9iRpAKtHJ_FnAEU_Rpn92lY6Mh-L0cX1XoyCRgJw8F3JGzebgFv0Jw== 16:07:05 INFO - x-amz-version-id: m795suFLPfcdNNdaLOUqHcXRCFQg9cKz 16:07:05 INFO - Expected file size: 16282916 16:07:05 INFO - Obtained file size: 16282916 16:07:12 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 16:07:12 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/MMEi2XmgQfGQkhleRYTTVQ/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 16:07:12 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/MMEi2XmgQfGQkhleRYTTVQ/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 16:07:12 INFO - Fetch https://queue.taskcluster.net/v1/task/MMEi2XmgQfGQkhleRYTTVQ/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 16:07:12 INFO - Http code: 200 16:07:12 INFO - via: 1.1 b9a8ce441329f20cea895e08f19f1ec8.cloudfront.net (CloudFront) 16:07:12 INFO - x-amz-cf-id: p-6U0arBJqMMfMs7uE0ajoDVZk_axt4nQQQWdJ-CJykujc7NYo4fyA== 16:07:12 INFO - x-amz-version-id: m795suFLPfcdNNdaLOUqHcXRCFQg9cKz 16:07:13 INFO - Expected file size: 16282916 16:07:13 INFO - Obtained file size: 16282916 16:07:19 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/MMEi2XmgQfGQkhleRYTTVQ/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip 16:07:19 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/MMEi2XmgQfGQkhleRYTTVQ/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip'}, attempt #1 16:07:19 INFO - Fetch https://queue.taskcluster.net/v1/task/MMEi2XmgQfGQkhleRYTTVQ/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip into memory 16:07:20 INFO - Http code: 200 16:07:20 INFO - via: 1.1 a1aa00de8387e7235a256b2a5b73ede8.cloudfront.net (CloudFront) 16:07:20 INFO - x-amz-cf-id: FPe79kDQ8PxqgVl2SGVrwBKnUXRyg8gmlVXkQ_U_zvn6nUAAJY9a6w== 16:07:20 INFO - x-amz-version-id: QCGqD_5pmpOUFa.k1KKjdxkMZvD9rmR4 16:07:20 INFO - Expected file size: 14409038 16:07:20 INFO - Obtained file size: 14409038 16:07:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:07:21 INFO - trying https://queue.taskcluster.net/v1/task/MMEi2XmgQfGQkhleRYTTVQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 16:07:21 INFO - Downloading https://queue.taskcluster.net/v1/task/MMEi2XmgQfGQkhleRYTTVQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg 16:07:21 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/MMEi2XmgQfGQkhleRYTTVQ/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 16:07:23 INFO - Downloaded 109741350 bytes. 16:07:23 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/MMEi2XmgQfGQkhleRYTTVQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 16:07:23 INFO - mkdir: /builds/slave/test/properties 16:07:23 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 16:07:23 INFO - Writing to file /builds/slave/test/properties/build_url 16:07:23 INFO - Contents: 16:07:23 INFO - build_url:https://queue.taskcluster.net/v1/task/MMEi2XmgQfGQkhleRYTTVQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 16:07:24 INFO - Running post-action listener: _resource_record_post_action 16:07:24 INFO - Running post-action listener: set_extra_try_arguments 16:07:24 INFO - [mozharness: 2016-11-30 00:07:24.560481Z] Finished download-and-extract step (success) 16:07:24 INFO - [mozharness: 2016-11-30 00:07:24.560707Z] Running populate-webroot step. 16:07:24 INFO - Running pre-action listener: _resource_record_pre_action 16:07:24 INFO - Running main action method: populate_webroot 16:07:24 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 16:07:24 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 16:07:24 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 16:07:24 INFO - '--tppagecycles', 16:07:24 INFO - '7'], 16:07:24 INFO - 'tests': ['tsvgm']}}, 16:07:24 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 16:07:24 INFO - 'tests': ['tresize', 'tcanvasmark']}, 16:07:24 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 16:07:24 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 16:07:24 INFO - 'tests': ['dromaeo_css', 'kraken']}, 16:07:24 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 16:07:24 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:07:24 INFO - 'talos_options': ['--disable-e10s'], 16:07:24 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:07:24 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:07:24 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:07:24 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:07:24 INFO - 'talos_options': ['--disable-e10s'], 16:07:24 INFO - 'tests': ['damp', 'tps']}, 16:07:24 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:07:24 INFO - 'tests': ['damp', 'tps']}, 16:07:24 INFO - 'g3': {'talos_options': ['--disable-e10s'], 16:07:24 INFO - 'tests': ['dromaeo_dom']}, 16:07:24 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 16:07:24 INFO - 'g4': {'talos_options': ['--disable-e10s'], 16:07:24 INFO - 'tests': ['basic_compositor_video']}, 16:07:24 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 16:07:24 INFO - 'other': {'talos_options': ['--disable-e10s'], 16:07:24 INFO - 'tests': ['a11yr', 16:07:24 INFO - 'ts_paint', 16:07:24 INFO - 'tpaint', 16:07:24 INFO - 'sessionrestore', 16:07:24 INFO - 'sessionrestore_no_auto_restore', 16:07:24 INFO - 'tabpaint']}, 16:07:24 INFO - 'other-e10s': {'tests': ['a11yr', 16:07:24 INFO - 'ts_paint', 16:07:24 INFO - 'tpaint', 16:07:24 INFO - 'sessionrestore', 16:07:24 INFO - 'sessionrestore_no_auto_restore', 16:07:24 INFO - 'tabpaint']}, 16:07:24 INFO - 'other-e10s_l64': {'tests': ['a11yr', 16:07:24 INFO - 'ts_paint', 16:07:24 INFO - 'tpaint', 16:07:24 INFO - 'sessionrestore', 16:07:24 INFO - 'sessionrestore_no_auto_restore', 16:07:24 INFO - 'tabpaint']}, 16:07:24 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 16:07:24 INFO - 'ts_paint', 16:07:24 INFO - 'tpaint', 16:07:24 INFO - 'sessionrestore', 16:07:24 INFO - 'sessionrestore_no_auto_restore', 16:07:24 INFO - 'tabpaint']}, 16:07:24 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 16:07:24 INFO - 'tests': ['a11yr', 16:07:24 INFO - 'ts_paint', 16:07:24 INFO - 'tpaint', 16:07:24 INFO - 'sessionrestore', 16:07:24 INFO - 'sessionrestore_no_auto_restore', 16:07:24 INFO - 'tabpaint']}, 16:07:24 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 16:07:24 INFO - 'tests': ['a11yr', 16:07:24 INFO - 'ts_paint', 16:07:24 INFO - 'tpaint', 16:07:24 INFO - 'sessionrestore', 16:07:24 INFO - 'sessionrestore_no_auto_restore', 16:07:24 INFO - 'tabpaint']}, 16:07:24 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 16:07:24 INFO - 'tests': ['tsvgx', 16:07:24 INFO - 'tsvgr_opacity', 16:07:24 INFO - 'tart', 16:07:24 INFO - 'tscrollx', 16:07:24 INFO - 'cart']}, 16:07:24 INFO - 'svgr-e10s': {'tests': ['tsvgx', 16:07:24 INFO - 'tsvgr_opacity', 16:07:24 INFO - 'tart', 16:07:24 INFO - 'tscrollx', 16:07:24 INFO - 'cart']}, 16:07:24 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:07:24 INFO - 'talos_options': ['--disable-e10s'], 16:07:24 INFO - 'tests': ['tp5o']}, 16:07:24 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:07:24 INFO - 'tests': ['tp5o']}, 16:07:24 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:07:24 INFO - 'talos_options': ['--disable-e10s', 16:07:24 INFO - '--xperf_path', 16:07:24 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:07:24 INFO - 'tests': ['tp5n']}, 16:07:24 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:07:24 INFO - 'talos_options': ['--xperf_path', 16:07:24 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:07:24 INFO - 'tests': ['tp5n']}}, 16:07:24 INFO - 'talos.zip': {'path': '', 16:07:24 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 16:07:24 INFO - Running post-action listener: _resource_record_post_action 16:07:24 INFO - [mozharness: 2016-11-30 00:07:24.583078Z] Finished populate-webroot step (success) 16:07:24 INFO - [mozharness: 2016-11-30 00:07:24.583188Z] Running create-virtualenv step. 16:07:24 INFO - Running pre-action listener: _resource_record_pre_action 16:07:24 INFO - Running main action method: create_virtualenv 16:07:24 INFO - Creating virtualenv /builds/slave/test/build/venv 16:07:24 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 16:07:24 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 16:07:24 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 16:07:24 INFO - Using real prefix '/tools/python27' 16:07:24 INFO - New python executable in /builds/slave/test/build/venv/bin/python 16:07:25 INFO - Installing distribute.............................................................................................................................................................................................done. 16:07:28 INFO - Installing pip.................done. 16:07:28 INFO - Return code: 0 16:07:28 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 16:07:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 16:07:28 INFO - Reading from file tmpfile_stdout 16:07:28 INFO - Output received: 16:07:28 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) 16:07:28 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 16:07: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')]} 16:07:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:07:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:07: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')]} 16:07:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:07:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:07: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', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1103d2490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110348570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fca02cc4860>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1101e6d30>, '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 0x11004cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1101deb20>, '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.XP3Fhzc5Xj/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.47dfj7GoKz/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/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 16:07: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', 'pip>=1.5'] in /builds/slave/test/build 16:07: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 pip>=1.5 16:07:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.XP3Fhzc5Xj/Render', 16:07:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:07:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:07:28 INFO - 'HOME': '/Users/cltbld', 16:07:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:07:28 INFO - 'LOGNAME': 'cltbld', 16:07:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:07:28 INFO - 'MOZ_NO_REMOTE': '1', 16:07:28 INFO - 'NO_EM_RESTART': '1', 16:07:28 INFO - 'PAGER': '/bin/cat', 16:07:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:07:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:07:28 INFO - 'PWD': '/builds/slave/test', 16:07:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:07:28 INFO - 'SHELL': '/bin/bash', 16:07:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.47dfj7GoKz/Listeners', 16:07:28 INFO - 'TMPDIR': '/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/', 16:07:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:07:28 INFO - 'USER': 'cltbld', 16:07:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:07:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:07:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:07:28 INFO - 'XPC_FLAGS': '0x0', 16:07:28 INFO - 'XPC_SERVICE_NAME': '0', 16:07:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:07:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:07:28 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 16:07:28 INFO - Cleaning up... 16:07:28 INFO - Return code: 0 16:07:28 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 16:07: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')]} 16:07:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:07:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:07: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')]} 16:07:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:07:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:07: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', '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 0x1103d2490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110348570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fca02cc4860>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1101e6d30>, '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 0x11004cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1101deb20>, '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.XP3Fhzc5Xj/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.47dfj7GoKz/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/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 16:07: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', 'psutil>=3.1.1'] in /builds/slave/test/build 16:07: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 psutil>=3.1.1 16:07:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.XP3Fhzc5Xj/Render', 16:07:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:07:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:07:28 INFO - 'HOME': '/Users/cltbld', 16:07:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:07:28 INFO - 'LOGNAME': 'cltbld', 16:07:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:07:28 INFO - 'MOZ_NO_REMOTE': '1', 16:07:28 INFO - 'NO_EM_RESTART': '1', 16:07:28 INFO - 'PAGER': '/bin/cat', 16:07:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:07:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:07:28 INFO - 'PWD': '/builds/slave/test', 16:07:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:07:28 INFO - 'SHELL': '/bin/bash', 16:07:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.47dfj7GoKz/Listeners', 16:07:28 INFO - 'TMPDIR': '/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/', 16:07:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:07:28 INFO - 'USER': 'cltbld', 16:07:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:07:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:07:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:07:28 INFO - 'XPC_FLAGS': '0x0', 16:07:28 INFO - 'XPC_SERVICE_NAME': '0', 16:07:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:07:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:07:29 INFO - Downloading/unpacking psutil>=3.1.1 16:07:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:07:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:07:29 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:07:29 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:07:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:07:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:07:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 16:07:32 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:07:32 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:07:32 INFO - Installing collected packages: psutil 16:07:32 INFO - Running setup.py install for psutil 16:07:32 INFO - building 'psutil._psutil_osx' extension 16:07:32 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 16:07:33 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 16:07:33 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 16:07:33 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 16:07:33 INFO - building 'psutil._psutil_posix' extension 16:07:33 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 16:07:33 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 16:07:33 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 16:07:33 INFO - ^ 16:07:33 INFO - 1 warning generated. 16:07:33 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 16:07:33 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:07:33 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:07:33 INFO - Successfully installed psutil 16:07:33 INFO - Cleaning up... 16:07:33 INFO - Return code: 0 16:07:33 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 16:07:33 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')]} 16:07:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:07:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:07:33 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')]} 16:07:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:07:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:07:33 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 0x1103d2490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110348570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fca02cc4860>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1101e6d30>, '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 0x11004cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1101deb20>, '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.XP3Fhzc5Xj/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.47dfj7GoKz/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/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 16:07:33 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 16:07:33 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 16:07:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.XP3Fhzc5Xj/Render', 16:07:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:07:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:07:33 INFO - 'HOME': '/Users/cltbld', 16:07:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:07:33 INFO - 'LOGNAME': 'cltbld', 16:07:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:07:33 INFO - 'MOZ_NO_REMOTE': '1', 16:07:33 INFO - 'NO_EM_RESTART': '1', 16:07:33 INFO - 'PAGER': '/bin/cat', 16:07:33 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:07:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:07:33 INFO - 'PWD': '/builds/slave/test', 16:07:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:07:33 INFO - 'SHELL': '/bin/bash', 16:07:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.47dfj7GoKz/Listeners', 16:07:33 INFO - 'TMPDIR': '/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/', 16:07:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:07:33 INFO - 'USER': 'cltbld', 16:07:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:07:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:07:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:07:33 INFO - 'XPC_FLAGS': '0x0', 16:07:33 INFO - 'XPC_SERVICE_NAME': '0', 16:07:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:07:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:07:33 INFO - Downloading/unpacking mozsystemmonitor==0.3 16:07:33 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 16:07:33 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 16:07:33 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 16:07:33 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 16:07:33 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 16:07:33 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 16:07:36 INFO - Downloading mozsystemmonitor-0.3.tar.gz 16:07:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 16:07:36 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 16:07:36 INFO - Installing collected packages: mozsystemmonitor 16:07:36 INFO - Running setup.py install for mozsystemmonitor 16:07:36 INFO - Successfully installed mozsystemmonitor 16:07:36 INFO - Cleaning up... 16:07:36 INFO - Return code: 0 16:07:36 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 16:07:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:07:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:07:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:07:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:07:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:07:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:07:36 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1103d2490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110348570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fca02cc4860>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1101e6d30>, '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 0x11004cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1101deb20>, '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.XP3Fhzc5Xj/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.47dfj7GoKz/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/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 16:07:36 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 16:07:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 16:07:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.XP3Fhzc5Xj/Render', 16:07:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:07:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:07:36 INFO - 'HOME': '/Users/cltbld', 16:07:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:07:36 INFO - 'LOGNAME': 'cltbld', 16:07:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:07:36 INFO - 'MOZ_NO_REMOTE': '1', 16:07:36 INFO - 'NO_EM_RESTART': '1', 16:07:36 INFO - 'PAGER': '/bin/cat', 16:07:36 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:07:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:07:36 INFO - 'PWD': '/builds/slave/test', 16:07:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:07:36 INFO - 'SHELL': '/bin/bash', 16:07:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.47dfj7GoKz/Listeners', 16:07:36 INFO - 'TMPDIR': '/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/', 16:07:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:07:36 INFO - 'USER': 'cltbld', 16:07:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:07:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:07:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:07:36 INFO - 'XPC_FLAGS': '0x0', 16:07:36 INFO - 'XPC_SERVICE_NAME': '0', 16:07:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:07:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:07:37 INFO - Downloading/unpacking jsonschema==2.5.1 16:07:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:07:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:07:37 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:07:37 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:07:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:07:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:07:39 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 16:07:39 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 16:07:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:07:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:07:39 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:07:39 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:07:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:07:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:07:39 INFO - Downloading functools32-3.2.3-2.tar.gz 16:07:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 16:07:40 INFO - warning: no files found matching '*.txt' 16:07:40 INFO - no previously-included directories found matching 'build' 16:07:40 INFO - no previously-included directories found matching 'dist' 16:07:40 INFO - no previously-included directories found matching '.git*' 16:07:40 INFO - Installing collected packages: jsonschema, functools32 16:07:40 INFO - Running setup.py install for functools32 16:07:40 INFO - warning: no files found matching '*.txt' 16:07:40 INFO - no previously-included directories found matching 'build' 16:07:40 INFO - no previously-included directories found matching 'dist' 16:07:40 INFO - no previously-included directories found matching '.git*' 16:07:40 INFO - Successfully installed jsonschema functools32 16:07:40 INFO - Cleaning up... 16:07:40 INFO - Return code: 0 16:07:40 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 16:07:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:07:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:07:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:07:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:07:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:07:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:07:40 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1103d2490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110348570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fca02cc4860>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1101e6d30>, '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 0x11004cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1101deb20>, '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.XP3Fhzc5Xj/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.47dfj7GoKz/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/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 16:07:40 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 16:07:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 16:07:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.XP3Fhzc5Xj/Render', 16:07:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:07:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:07:40 INFO - 'HOME': '/Users/cltbld', 16:07:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:07:40 INFO - 'LOGNAME': 'cltbld', 16:07:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:07:40 INFO - 'MOZ_NO_REMOTE': '1', 16:07:40 INFO - 'NO_EM_RESTART': '1', 16:07:40 INFO - 'PAGER': '/bin/cat', 16:07:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:07:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:07:40 INFO - 'PWD': '/builds/slave/test', 16:07:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:07:40 INFO - 'SHELL': '/bin/bash', 16:07:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.47dfj7GoKz/Listeners', 16:07:40 INFO - 'TMPDIR': '/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/', 16:07:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:07:40 INFO - 'USER': 'cltbld', 16:07:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:07:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:07:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:07:40 INFO - 'XPC_FLAGS': '0x0', 16:07:40 INFO - 'XPC_SERVICE_NAME': '0', 16:07:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:07:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:07:40 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 16:07:40 INFO - Cleaning up... 16:07:40 INFO - Return code: 0 16:07:40 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 16:07:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:07:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:07:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:07:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:07:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:07:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:07:40 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1103d2490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110348570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fca02cc4860>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1101e6d30>, '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 0x11004cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1101deb20>, '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.XP3Fhzc5Xj/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.47dfj7GoKz/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/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 16:07:40 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 16:07:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 16:07:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.XP3Fhzc5Xj/Render', 16:07:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:07:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:07:40 INFO - 'HOME': '/Users/cltbld', 16:07:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:07:40 INFO - 'LOGNAME': 'cltbld', 16:07:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:07:40 INFO - 'MOZ_NO_REMOTE': '1', 16:07:40 INFO - 'NO_EM_RESTART': '1', 16:07:40 INFO - 'PAGER': '/bin/cat', 16:07:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:07:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:07:40 INFO - 'PWD': '/builds/slave/test', 16:07:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:07:40 INFO - 'SHELL': '/bin/bash', 16:07:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.47dfj7GoKz/Listeners', 16:07:40 INFO - 'TMPDIR': '/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/', 16:07:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:07:40 INFO - 'USER': 'cltbld', 16:07:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:07:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:07:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:07:40 INFO - 'XPC_FLAGS': '0x0', 16:07:40 INFO - 'XPC_SERVICE_NAME': '0', 16:07:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:07:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:07:40 INFO - Downloading/unpacking blobuploader==1.2.4 16:07:40 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 16:07:40 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 16:07:40 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 16:07:40 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 16:07:40 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 16:07:40 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 16:07:43 INFO - Downloading blobuploader-1.2.4.tar.gz 16:07:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 16:07:43 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 16:07: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 16:07: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 16:07: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 16:07: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 16:07: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 16:07: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 16:07:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 16:07:44 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 16:07:44 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 16:07:44 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 16:07:44 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 16:07:44 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 16:07:44 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 16:07:44 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 16:07:44 INFO - Downloading docopt-0.6.1.tar.gz 16:07:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 16:07:44 INFO - Installing collected packages: blobuploader, requests, docopt 16:07:44 INFO - Running setup.py install for blobuploader 16:07:44 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 16:07:44 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 16:07:44 INFO - Running setup.py install for requests 16:07:45 INFO - Running setup.py install for docopt 16:07:45 INFO - Successfully installed blobuploader requests docopt 16:07:45 INFO - Cleaning up... 16:07:45 INFO - Return code: 0 16:07:45 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:07:45 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')]} 16:07:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:07:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:07:45 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')]} 16:07:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:07:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:07:45 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 0x1103d2490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110348570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fca02cc4860>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1101e6d30>, '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 0x11004cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1101deb20>, '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.XP3Fhzc5Xj/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.47dfj7GoKz/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/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 16:07:45 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 16:07:45 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 16:07:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.XP3Fhzc5Xj/Render', 16:07:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:07:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:07:45 INFO - 'HOME': '/Users/cltbld', 16:07:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:07:45 INFO - 'LOGNAME': 'cltbld', 16:07:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:07:45 INFO - 'MOZ_NO_REMOTE': '1', 16:07:45 INFO - 'NO_EM_RESTART': '1', 16:07:45 INFO - 'PAGER': '/bin/cat', 16:07:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:07:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:07:45 INFO - 'PWD': '/builds/slave/test', 16:07:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:07:45 INFO - 'SHELL': '/bin/bash', 16:07:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.47dfj7GoKz/Listeners', 16:07:45 INFO - 'TMPDIR': '/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/', 16:07:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:07:45 INFO - 'USER': 'cltbld', 16:07:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:07:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:07:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:07:45 INFO - 'XPC_FLAGS': '0x0', 16:07:45 INFO - 'XPC_SERVICE_NAME': '0', 16:07:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:07:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:07:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:07:45 INFO - Running setup.py (path:/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/pip-ZqfIdz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:07:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:07:45 INFO - Running setup.py (path:/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/pip-3xHdAI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:07:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:07:46 INFO - Running setup.py (path:/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/pip-3B2VKR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:07:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:07:46 INFO - Running setup.py (path:/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/pip-W0oWcN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:07:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:07:46 INFO - Running setup.py (path:/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/pip-BYyB9z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:07:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:07:46 INFO - Running setup.py (path:/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/pip-fWBhOw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:07:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:07:46 INFO - Running setup.py (path:/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/pip-vZE7Nu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:07:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:07:46 INFO - Running setup.py (path:/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/pip-U2N5C4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:07:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:07:46 INFO - Running setup.py (path:/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/pip-vFD4Wu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:07:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:07:46 INFO - Running setup.py (path:/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/pip-P6cQ74-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:07:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:07:46 INFO - Running setup.py (path:/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/pip-A3E1uR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:07:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:07:47 INFO - Running setup.py (path:/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/pip-T0NRih-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:07:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:07:47 INFO - Running setup.py (path:/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/pip-VYVHa9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:07:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:07:47 INFO - Running setup.py (path:/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/pip-8IELdn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:07:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:07:47 INFO - Running setup.py (path:/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/pip-JEG6Z4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:07:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:07:47 INFO - Running setup.py (path:/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/pip-wJyYGx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:07:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:07:47 INFO - Running setup.py (path:/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/pip-QSJos7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:07:47 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 16:07:47 INFO - Running setup.py install for manifestparser 16:07:47 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 16:07:47 INFO - Running setup.py install for mozcrash 16:07:47 INFO - Running setup.py install for mozdebug 16:07:48 INFO - Running setup.py install for mozdevice 16:07:48 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 16:07:48 INFO - Installing dm script to /builds/slave/test/build/venv/bin 16:07:48 INFO - Running setup.py install for mozfile 16:07:48 INFO - Running setup.py install for mozhttpd 16:07:48 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 16:07:48 INFO - Running setup.py install for mozinfo 16:07:48 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 16:07:48 INFO - Running setup.py install for mozInstall 16:07:48 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 16:07:48 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 16:07:48 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 16:07:48 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 16:07:48 INFO - Running setup.py install for mozleak 16:07:48 INFO - Running setup.py install for mozlog 16:07:49 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 16:07:49 INFO - Running setup.py install for moznetwork 16:07:49 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 16:07:49 INFO - Running setup.py install for mozprocess 16:07:49 INFO - Running setup.py install for mozprofile 16:07:49 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 16:07:49 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 16:07:49 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 16:07:49 INFO - Running setup.py install for mozrunner 16:07:49 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 16:07:49 INFO - Running setup.py install for mozscreenshot 16:07:49 INFO - Running setup.py install for moztest 16:07:50 INFO - Running setup.py install for mozversion 16:07:50 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 16:07:50 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 16:07:50 INFO - Cleaning up... 16:07:50 INFO - Return code: 0 16:07:50 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:07:50 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')]} 16:07:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:07:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:07:50 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')]} 16:07:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:07:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:07:50 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 0x1103d2490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110348570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fca02cc4860>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1101e6d30>, '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 0x11004cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1101deb20>, '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.XP3Fhzc5Xj/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.47dfj7GoKz/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/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 16:07:50 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 16:07:50 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 16:07:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.XP3Fhzc5Xj/Render', 16:07:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:07:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:07:50 INFO - 'HOME': '/Users/cltbld', 16:07:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:07:50 INFO - 'LOGNAME': 'cltbld', 16:07:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:07:50 INFO - 'MOZ_NO_REMOTE': '1', 16:07:50 INFO - 'NO_EM_RESTART': '1', 16:07:50 INFO - 'PAGER': '/bin/cat', 16:07:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:07:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:07:50 INFO - 'PWD': '/builds/slave/test', 16:07:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:07:50 INFO - 'SHELL': '/bin/bash', 16:07:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.47dfj7GoKz/Listeners', 16:07:50 INFO - 'TMPDIR': '/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/', 16:07:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:07:50 INFO - 'USER': 'cltbld', 16:07:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:07:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:07:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:07:50 INFO - 'XPC_FLAGS': '0x0', 16:07:50 INFO - 'XPC_SERVICE_NAME': '0', 16:07:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:07:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:07:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:07:50 INFO - Running setup.py (path:/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/pip-faJexc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:07:50 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)) 16:07:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:07:50 INFO - Running setup.py (path:/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/pip-eCSA3p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:07:50 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)) 16:07:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:07:50 INFO - Running setup.py (path:/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/pip-Y1yKLF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:07:50 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)) 16:07:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:07:50 INFO - Running setup.py (path:/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/pip-iAVt1E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:07:50 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)) 16:07:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:07:50 INFO - Running setup.py (path:/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/pip-ODUYPV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:07:51 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)) 16:07:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:07:51 INFO - Running setup.py (path:/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/pip-6Cpn22-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:07:51 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)) 16:07:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:07:51 INFO - Running setup.py (path:/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/pip-wPcL34-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:07:51 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)) 16:07:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:07:51 INFO - Running setup.py (path:/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/pip-uWGANP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:07:51 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)) 16:07:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:07:51 INFO - Running setup.py (path:/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/pip-DQ30gk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:07:51 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)) 16:07:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:07:51 INFO - Running setup.py (path:/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/pip-5ArT3G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:07:51 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)) 16:07:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:07:51 INFO - Running setup.py (path:/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/pip-0VmJlJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:07:51 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)) 16:07:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:07:51 INFO - Running setup.py (path:/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/pip-ow2TYq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:07:51 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)) 16:07:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:07:51 INFO - Running setup.py (path:/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/pip-D0md3D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:07:51 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)) 16:07:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:07:51 INFO - Running setup.py (path:/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/pip-T_vmaR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:07:52 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)) 16:07:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:07:52 INFO - Running setup.py (path:/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/pip-RJWNsw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:07:52 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)) 16:07:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:07:52 INFO - Running setup.py (path:/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/pip-R8C5UD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:07:52 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)) 16:07:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:07:52 INFO - Running setup.py (path:/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/pip-uhrIxG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:07:52 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)) 16:07:52 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)) 16:07:52 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)) 16:07:52 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)) 16:07:52 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)) 16:07:52 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)) 16:07:52 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 16:07:52 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 16:07:52 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 16:07:52 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 16:07:52 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 16:07:52 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 16:07:52 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 16:07:54 INFO - Downloading blessings-1.6.tar.gz 16:07:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 16:07:55 INFO - Installing collected packages: blessings 16:07:55 INFO - Running setup.py install for blessings 16:07:55 INFO - Successfully installed blessings 16:07:55 INFO - Cleaning up... 16:07:55 INFO - Return code: 0 16:07:55 INFO - Done creating virtualenv /builds/slave/test/build/venv. 16:07:55 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:07:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:07:55 INFO - Reading from file tmpfile_stdout 16:07:55 INFO - Current package versions: 16:07:55 INFO - blessings == 1.6 16:07:55 INFO - blobuploader == 1.2.4 16:07:55 INFO - docopt == 0.6.1 16:07:55 INFO - functools32 == 3.2.3-2 16:07:55 INFO - jsonschema == 2.5.1 16:07:55 INFO - manifestparser == 1.1 16:07:55 INFO - mozInstall == 1.12 16:07:55 INFO - mozcrash == 1.0 16:07:55 INFO - mozdebug == 0.1 16:07:55 INFO - mozdevice == 0.48 16:07:55 INFO - mozfile == 1.2 16:07:55 INFO - mozhttpd == 0.7 16:07:55 INFO - mozinfo == 0.9 16:07:55 INFO - mozleak == 0.1 16:07:55 INFO - mozlog == 3.4 16:07:55 INFO - moznetwork == 0.27 16:07:55 INFO - mozprocess == 0.23 16:07:55 INFO - mozprofile == 0.28 16:07:55 INFO - mozrunner == 6.13 16:07:55 INFO - mozscreenshot == 0.1 16:07:55 INFO - mozsystemmonitor == 0.3 16:07:55 INFO - moztest == 0.7 16:07:55 INFO - mozversion == 1.4 16:07:55 INFO - psutil == 3.1.1 16:07:55 INFO - requests == 1.2.3 16:07:55 INFO - wsgiref == 0.1.2 16:07:55 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:07:55 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')]} 16:07:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:07:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:07:55 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')]} 16:07:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:07:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:07:55 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 0x1103d2490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110348570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fca02cc4860>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1101e6d30>, '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 0x11004cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1101deb20>, '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.XP3Fhzc5Xj/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.47dfj7GoKz/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/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 16:07:55 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 16:07:55 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 16:07:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.XP3Fhzc5Xj/Render', 16:07:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:07:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:07:55 INFO - 'HOME': '/Users/cltbld', 16:07:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:07:55 INFO - 'LOGNAME': 'cltbld', 16:07:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:07:55 INFO - 'MOZ_NO_REMOTE': '1', 16:07:55 INFO - 'NO_EM_RESTART': '1', 16:07:55 INFO - 'PAGER': '/bin/cat', 16:07:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:07:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:07:55 INFO - 'PWD': '/builds/slave/test', 16:07:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:07:55 INFO - 'SHELL': '/bin/bash', 16:07:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.47dfj7GoKz/Listeners', 16:07:55 INFO - 'TMPDIR': '/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/', 16:07:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:07:55 INFO - 'USER': 'cltbld', 16:07:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:07:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:07:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:07:55 INFO - 'XPC_FLAGS': '0x0', 16:07:55 INFO - 'XPC_SERVICE_NAME': '0', 16:07:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:07:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:07:55 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)) 16:07:55 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)) 16:07:55 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)) 16:07:55 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)) 16:07:55 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)) 16:07:55 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)) 16:07:55 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)) 16:07:55 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)) 16:07:55 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)) 16:07:55 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)) 16:07:55 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)) 16:07:55 INFO - Cleaning up... 16:07:55 INFO - Return code: 0 16:07:55 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 16:07:55 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')]} 16:07:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:07:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:07:55 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')]} 16:07:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:07:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:07:55 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 0x1103d2490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110348570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fca02cc4860>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1101e6d30>, '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 0x11004cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1101deb20>, '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.XP3Fhzc5Xj/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.47dfj7GoKz/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/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 16:07:55 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 16:07:55 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 16:07:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.XP3Fhzc5Xj/Render', 16:07:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:07:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:07:55 INFO - 'HOME': '/Users/cltbld', 16:07:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:07:55 INFO - 'LOGNAME': 'cltbld', 16:07:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:07:55 INFO - 'MOZ_NO_REMOTE': '1', 16:07:55 INFO - 'NO_EM_RESTART': '1', 16:07:55 INFO - 'PAGER': '/bin/cat', 16:07:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:07:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:07:55 INFO - 'PWD': '/builds/slave/test', 16:07:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:07:55 INFO - 'SHELL': '/bin/bash', 16:07:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.47dfj7GoKz/Listeners', 16:07:55 INFO - 'TMPDIR': '/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/', 16:07:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:07:55 INFO - 'USER': 'cltbld', 16:07:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:07:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:07:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:07:55 INFO - 'XPC_FLAGS': '0x0', 16:07:55 INFO - 'XPC_SERVICE_NAME': '0', 16:07:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:07:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:07:56 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 16:07:56 INFO - Cleaning up... 16:07:56 INFO - Return code: 0 16:07:56 INFO - Running post-action listener: _resource_record_post_action 16:07:56 INFO - Running post-action listener: _start_resource_monitoring 16:07:56 INFO - Starting resource monitoring. 16:07:56 INFO - [mozharness: 2016-11-30 00:07:56.209382Z] Finished create-virtualenv step (success) 16:07:56 INFO - [mozharness: 2016-11-30 00:07:56.210432Z] Running install step. 16:07:56 INFO - Running pre-action listener: _resource_record_pre_action 16:07:56 INFO - Running main action method: install 16:07:56 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:07:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:07:56 INFO - Reading from file tmpfile_stdout 16:07:56 INFO - Detecting whether we're running mozinstall >=1.0... 16:07:56 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 16:07:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 16:07:56 INFO - Reading from file tmpfile_stdout 16:07:56 INFO - Output received: 16:07:56 INFO - Usage: mozinstall [options] installer 16:07:56 INFO - Options: 16:07:56 INFO - -h, --help show this help message and exit 16:07:56 INFO - -d DEST, --destination=DEST 16:07:56 INFO - Directory to install application into. [default: 16:07:56 INFO - "/builds/slave/test"] 16:07:56 INFO - --app=APP Application being installed. [default: firefox] 16:07:56 INFO - mkdir: /builds/slave/test/build/application 16:07:56 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'] 16:07:56 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 16:08:28 INFO - Reading from file tmpfile_stdout 16:08:28 INFO - Output received: 16:08:28 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 16:08:28 INFO - Running post-action listener: _resource_record_post_action 16:08:28 INFO - [mozharness: 2016-11-30 00:08:28.146459Z] Finished install step (success) 16:08:28 INFO - [mozharness: 2016-11-30 00:08:28.146615Z] Running run-tests step. 16:08:28 INFO - Running pre-action listener: _resource_record_pre_action 16:08:28 INFO - Running main action method: run_tests 16:08:28 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 16:08:28 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 16:08:28 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 16:08:28 INFO - 2016-11-29 16:08:28.161 screenresolution[2584:21872] starting screenresolution argv=screenresolution get 16:08:28 INFO - 2016-11-29 16:08:28.164 screenresolution[2584:21872] Display 0: 1600x1200x32@60 16:08:28 INFO - 2016-11-29 16:08:28.170 screenresolution[2585:21874] starting screenresolution argv=screenresolution list 16:08:28 INFO - Available Modes on Display 0 16:08:28 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 16:08:28 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 16:08:28 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 16:08:28 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 16:08:28 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 16:08:28 INFO - Intel Iris: 16:08:28 INFO - Chipset Model: Intel Iris 16:08:28 INFO - Type: GPU 16:08:28 INFO - Bus: Built-In 16:08:28 INFO - VRAM (Dynamic, Max): 1536 MB 16:08:28 INFO - Vendor: Intel (0x8086) 16:08:28 INFO - Device ID: 0x0a2e 16:08:28 INFO - Revision ID: 0x0009 16:08:28 INFO - Displays: 16:08:28 INFO - Display: 16:08:28 INFO - Resolution: 1600 x 1200 @ 60 Hz 16:08:28 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 16:08:28 INFO - Display Serial Number: 1600x1200 60 16:08:28 INFO - Main Display: Yes 16:08:28 INFO - Mirror: Off 16:08:28 INFO - Online: Yes 16:08:28 INFO - Rotation: Supported 16:08:28 INFO - Return code: 0 16:08:28 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 16:08:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 16:08:28 INFO - Python 2.7.3 16:08:28 INFO - Return code: 0 16:08:28 INFO - grabbing minidump binary from tooltool 16:08: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')]} 16:08:28 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 0x1101e6d30>, '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 0x11004cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1101deb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 16:08:28 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 16:08:28 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 16:08:28 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 16:08:28 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 16:08:28 INFO - Return code: 0 16:08:28 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 16:08:28 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 16:08:28 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 16:08:28 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 16:08:28 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 16:08:28 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/MMEi2XmgQfGQkhleRYTTVQ/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0298', '--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 16:08:28 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/MMEi2XmgQfGQkhleRYTTVQ/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0298 --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 16:08:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.XP3Fhzc5Xj/Render', 16:08:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:08:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:08:28 INFO - 'HOME': '/Users/cltbld', 16:08:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:08:28 INFO - 'LOGNAME': 'cltbld', 16:08:28 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 16:08:28 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 16:08:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:08:28 INFO - 'MOZ_NO_REMOTE': '1', 16:08:28 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 16:08:28 INFO - 'NO_EM_RESTART': '1', 16:08:28 INFO - 'PAGER': '/bin/cat', 16:08:28 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:08:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:08:28 INFO - 'PWD': '/builds/slave/test', 16:08:28 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 16:08:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:08:28 INFO - 'SHELL': '/bin/bash', 16:08:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.47dfj7GoKz/Listeners', 16:08:28 INFO - 'TMPDIR': '/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/', 16:08:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:08:28 INFO - 'USER': 'cltbld', 16:08:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:08:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:08:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:08:28 INFO - 'XPC_FLAGS': '0x0', 16:08:28 INFO - 'XPC_SERVICE_NAME': '0', 16:08:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:08:28 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/MMEi2XmgQfGQkhleRYTTVQ/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0298', '--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 16:08:28 INFO - mozversion application_buildid: 20161129135426 16:08:28 INFO - mozversion application_changeset: f51d3ab82dc60c54403cc6f64f4e0a5a87a5f5ff 16:08:28 INFO - mozversion application_display_name: FirefoxDeveloperEdition 16:08:28 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 16:08:28 INFO - mozversion application_name: Firefox 16:08:28 INFO - mozversion application_remotingname: firefox-dev 16:08:28 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 16:08:28 INFO - mozversion application_vendor: Mozilla 16:08:28 INFO - mozversion application_version: 52.0a2 16:08:28 INFO - mozversion platform_buildid: 20161129135426 16:08:28 INFO - mozversion platform_changeset: f51d3ab82dc60c54403cc6f64f4e0a5a87a5f5ff 16:08:28 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 16:08:28 INFO - mozversion platform_version: 52.0a2 16:08:28 INFO - using testdate: 1480464508 16:08:28 INFO - actual date: 1480464508 16:08:28 INFO - starting webserver on 'localhost:51908' 16:08:28 INFO - SUITE-START | Running 2 tests 16:08:28 INFO - TEST-START | dromaeo_css 16:08:28 INFO - operating with platform_type : mac_ 16:08:28 INFO - Initialising browser for dromaeo_css test... 16:08:28 INFO - TEST-INFO | started process 2595 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/tmp6krdnS/profile http://localhost:51908/getInfo.html) 16:08:31 INFO - PROCESS | 2595 | __metrics Screen width/height:1600/1200 16:08:31 INFO - PROCESS | 2595 | colorDepth:24 16:08:31 INFO - PROCESS | 2595 | Browser inner width/height: 1024/689 16:08:31 INFO - PROCESS | 2595 | __metrics 16:08:31 INFO - PROCESS | 2595 | 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" 16:08:31 INFO - PROCESS | 2595 | JavaScript error: resource:///modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 16:08:32 INFO - TEST-INFO | 2595: exit 0 16:08:32 INFO - Browser initialized. 16:08:32 INFO - Running cycle 1/1 for dromaeo_css test... 16:08:32 INFO - TEST-INFO | started process 2597 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/tmp6krdnS/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:08:44 INFO - PROCESS | 2597 | RSS: Main: 219136000 16:08:44 INFO - PROCESS | 2597 | 16:09:35 INFO - PROCESS | 2597 | 1480464575416 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] 16:11:48 INFO - PROCESS | 2597 | Cycle 1(1): loaded http://localhost:51908/tests/dromaeo/cssquery-dojo.html (next: http://localhost:51908/tests/dromaeo/cssquery-ext.html) 16:11:51 INFO - PROCESS | 2597 | RSS: Main: 497262592 16:11:51 INFO - PROCESS | 2597 | 16:14:55 INFO - PROCESS | 2597 | Cycle 1(1): loaded http://localhost:51908/tests/dromaeo/cssquery-ext.html (next: http://localhost:51908/tests/dromaeo/cssquery-jquery.html) 16:14:55 INFO - PROCESS | 2597 | RSS: Main: 349114368 16:14:55 INFO - PROCESS | 2597 | 16:17:57 INFO - PROCESS | 2597 | Cycle 1(1): loaded http://localhost:51908/tests/dromaeo/cssquery-jquery.html (next: http://localhost:51908/tests/dromaeo/cssquery-mootools.html) 16:17:58 INFO - PROCESS | 2597 | RSS: Main: 342462464 16:17:58 INFO - PROCESS | 2597 | 16:21:00 INFO - PROCESS | 2597 | Cycle 1(1): loaded http://localhost:51908/tests/dromaeo/cssquery-mootools.html (next: http://localhost:51908/tests/dromaeo/cssquery-prototype.html) 16:21:01 INFO - PROCESS | 2597 | RSS: Main: 323239936 16:21:01 INFO - PROCESS | 2597 | 16:24:04 INFO - PROCESS | 2597 | Cycle 1(1): loaded http://localhost:51908/tests/dromaeo/cssquery-prototype.html (next: http://localhost:51908/tests/dromaeo/cssquery-yui.html) 16:24:05 INFO - PROCESS | 2597 | RSS: Main: 311771136 16:24:05 INFO - PROCESS | 2597 | 16:27:08 INFO - PROCESS | 2597 | Cycle 1(1): loaded http://localhost:51908/tests/dromaeo/cssquery-yui.html (next: http://localhost:51908/tests/dromaeo/cssquery-dojo.html) 16:27:08 INFO - PROCESS | 2597 | RSS: Main: 379080704 16:27:08 INFO - PROCESS | 2597 | 16:27:08 INFO - PROCESS | 2597 | __start_tp_report 16:27:08 INFO - PROCESS | 2597 | _x_x_mozilla_page_load 16:27:08 INFO - PROCESS | 2597 | _x_x_mozilla_page_load_details 16:27:08 INFO - PROCESS | 2597 | |i|pagename|runs| 16:27:08 INFO - PROCESS | 2597 | |0;dojo.html;2941.39701361221;3244.789088709232;3251.7886337388054;3281.753868459866;3296.703296703296;5622.550195984321;5641.323041235048;5692.146178073291;5698.173764804101;5731.512821410175;5626.212330273765;5705.3357209483265;5710.486056254937;5720.685362305072;5778.59549831512;5164.380274367072;5164.457731938149;5186.403563590197;5222.164453687411;5255;4990.251462280668;4992;5005.57452616528;5275.894482110355;5327.627066105355;4219.198352313051;5060.645754797166;5074.695518268915;5088.84733457995;5097.260897169908;5078.39059312883;5096.872578185538;5102.030614183293;5103.8468845934685;5104.5405913467785;4947.356843610308;5039.949600503985;5048.974755126201;5054.974725126388;5058.962912602908;5559.9444005559835;5561.944380556183;5565.554755619582;5566.582506312043;5567.526760225345;6109.890546868033;6115.005896079736;6118.680093383066;6130.459285710702;6137.815865524041;6216.720247588869;6228.128062071269;6234.563580549318;6236.563440559163;6305;5738.397468265814;5786.652800831964;5789.305283366023;5796.50729687977;5820.35976042635;6211.254649442096;6309.432151106376;6318.241810982666;6323.462505687068;6343.111964324999;5983.072623743326;6015.368386319374;6043.214382130295;6056.152138700585;6065.272167339859;6199.2730169795705;6297.118403423525;6309.621422714652;6311.368863113652;6319.526035547352;5217.643412712722;6235.3452887446165;6240.594361366588;6250.499960003189;6273.466755325757;6062.878742425127;6107.006761825774;6133.355997620275;6144.064619644798;6187.412195841388;4901.068796928572;4907.09218794517;4918.434380046269;4928.211486162269;4931.988942266827;4400;4416.558344165622;4429.601335879786;4435.2016637005;4442.2892337225885;5150.948490515122;5556.416576259516;5619.93221287954;5635.436456354414;5663.093904975267;5044.520770526794;5212.270282160501;6145.863015342176;6201.937980620226;6253.187085678924;5745.195672605839;5746.856328591819;5752.338481074647;5753.942460575341;5767.826965191051;4496.775161241925;5266.947330526722;5373;5390.622656414013;5430.484104010113;6270.0908368287255;6277.372262773686;6300.7479700811455;6313.779017734358;6343;5520.06158952987;5982.102684597346;6232.314445410911;6234.75060997555;6238.407351301619;6197.907031394442;6280.560360774884;6305.716242768995;6323.810285691437;6334.651594162366;5145.61407894402;6098.29869564988;6179.227596550431;6182.412670796267;6182.505399568114;4714;6129.478994285536;6269.843253918689;6271.153394291712;6292.402221788922;4737.573618374363;5968.343482216866;6035.215421995288;6056.0007598745715;6058.7273572688455;6018.8194354169445;6022.126791615104;6022.518198544193;6029.276486821609;6032.276126864805;5838.299404071538;5841.094630332305;5856.853578660397;5867.970660146672;5868.618539794899;6031.728572214349;6051.364606716407;6059.606125601822;6061.818145455643;6065.059915713072;5827.271591051119;5843.328017277898;5851.882962340815;5870.559708022;5946.405359464019 16:27:08 INFO - PROCESS | 2597 | |1;ext.html;492.7807125829008;494.18952917215773;502.9638943775821;505.9949400505996;547.6987656788765;26820.86589567052;30804.537931931038;30918.53622195669;30920.226994325152;30928.690713092863;31470.68529314706;33051.66948330516;38946.415803762895;39744;42086.57913420865;9158.771030724218;9311.394759340621;9340.626374944994;9342.673006444777;9354.15812576868;9141.634334626608;9246.722598322061;9249.815003699921;9250.86123708145;9253.30600204984;8342.83314333713;8558.101399353041;8576.614052367628;8629.784255393633;8630.913690863104;10795.190360722938;11058.612948546843;11091.389973551451;11194.38430886301;11200.887991120066;10961.12311015117;11033.227674062819;11033.282836615594;11039;11046.281991670516;38082.61917380818;38266.23467530634;38278;38347.23305533901;38360.616393836266;3174.158847905307;3194.984025079883;3209.6629853865243;3250.236194494292;3272.2637406583326;28939;29005.41989160226;29321.56017659737;29912.401751965055;30625.928092516657;3117.7194052535156;3157.4158780625658;3157.873685052595;3204.13488358142;3252.788568743024;491508;495162;495372;495453;498057;32834;33683;58782.70608646929;59189.52026199293;59268;34680.30639387198;35032.949011529694;35242.47136292957;35289.94130176099;35408.82295588557;14153.716925661429;14291.213983231022;14305.78541321881;14313.141211527134;14414.567562973127;11960.581379651845;12063.155579109378;12083.972862306802;12088.03295736339;12112.515499379926;7757.650905709256;7774.92225077742;7797.961010194913;7855.057393112863;7886.093099293655;2935.222166125978;3035.848207589612;3135.1221657935816;3137.9686203137676;3218.4045951499047;923.2475532441039;925.2551695884742;946.0397696338222;986.0435377683635;1005.8642083318805;28424.715752842207;28974.855125724236;29206.853965730035;29764.702352976627;29987.950421735135;991.3258983890879;1027.3168343051786;1028.7222449938474;1049.21833234241;1052.984205236922;15274.236288185546;15290.541283761506;15292.311845966735;15597.376104955902;15664.843351566566;663989;935573.322133385;941875.2906235562;942201.2889935643;944614;56750.71624641933;57732.422675773545;57737.71131144317;57795.71102144462;57853.13220301699;1210.2073142091945;1247.8377810884526;1250.2685928731794;1347.8382594088773;1372.9038967272195;28942.42115157643;30867.845660771553;31053.8447307762;31411.37177256488;31689.683103169595;54067.648308792595;54596.45403545914;54754.45245547653;54828.72585637046;54880.35358939078;599880;602135;606968;607482;608801;23104.537909241386;23587.41031474227;23682.407939801644;25802.96788128529;26801.463970720866;43765.56234437615;44417.33373999328;45529.77235113803;46052;48392;36943;37413;37629.05927351728;37785;38810.6118938807;36942.630573694994;37092.629073708915;37446.06384840292;37977.43033854439;38182.23635527218;36270.81864590766;37180;37261.06847328838;37298;37306.62693373032;31299;33609.495857562644;33672.663273366954;33691;33891.830540847135;20656.690149647467;24645.63031554564;24890.751092488845;25205.74794252034;25467.23598292054 16:27:08 INFO - PROCESS | 2597 | |2;jquery.html;319.3724331688233;362.68446451587107;369.2854326877492;382.7569493371708;395.04990497852674;18407.447776566707;18974;19596.31412900549;20061.899690501545;20305.98470076494;19613;19755.308564200255;20099.698504522443;20222.988850557485;20266.18935242592;5664.915026274609;5680.573956953224;5986.132010858445;5991.580589358747;5994.610350327215;5791.276090488689;5823.854403639901;5854.414558544133;5855.975204339224;5860.769238459929;5528.087865502207;5727.856803579923;5809.302883653967;5812.767489300423;5818.650880947135;5718.885622287552;5788.82633520995;5789.797357092502;5790.420957904197;5797.217375654275;4861.562459378673;5056.62075344347;5092.974535127338;5097.923531147036;5098.770555325019;22079.66880496794;24221.63667544988;24745;25073.24780256595;25170.622440663406;2242.372135801978;2330.988345058264;2716.6332545106357;3025.6369235691636;3073.969260307413;15049.172295523742;15169.469068582657;15391.846081539154;15437.140714821295;15713.371465141381;1777.2624360890215;1790.6239689665185;1802.7025540785755;1818.4271954334474;1871.9064046797605;113496.4325178377;118201.40899295534;118951.99999999914;119712.40143799312;119988;10304.227182961307;10597.735056623493;10709.036186743231;10841.186910981707;10996.450177491093;13537.458501659823;13558.93220533891;13569.457221711022;13569.525066622818;13591.388387522586;8108.027036755627;8380.16198380157;8391.496510209407;8480.660773569112;8490.915090849136;8435.283000944866;8444.282236010007;8447.36644751646;8597.269232115215;8638.26574741154;6280.466160376419;6446.774362897276;6453.225612926479;6473.676316184172;6576.408123268833;1738.852197563196;1785.9553511162064;1787.8569714422815;1810.9909450452926;1814.3014939248485;694.7915625312386;904.8959369672572;928.5125309212717;991.0634450444261;1005.1757558801712;20901.37295881126;21779.45551361197;21885.343439696833;21911.342659720234;21948.34154975353;810.6878851642042;825.2778818533783;861.9312053054168;938.957746901391;980.1032055669126;8389.538575378412;11473.139514536444;11795.646130616095;11898;12008.579699710468;556307;562014;572594;574664.1266793639;581210.657626981;72791.54416911631;116216.41891790656;120750;122174.3891280538;136660.6333936648;945.2579724915907;961.1781926452783;978.1783302026332;985.8964808694984;996.7009897030862;18327.450176494727;18752.906235468734;19099.618007639492;19185.561082918557;19462.708059378838;54929.72535137299;55531;55975.16347711405;56277.437225628855;56661.71669141628;74741.25258747561;80945.78581321167;82765.58617206875;84309.73535397097;86584;5831.7375718091935;7640.312371886557;7685.154632990479;7756.573388463688;8265.173482651686;8803.735887923373;11545.769084618092;12540.811887821872;12641;12927.353632318534;10264.538095785558;10358.637447689294;10884.129269658566;11009.06422954058;11147.272172813227;10325.74185645365;10477.528511217166;10632.734181645521;11126.443677815914;11304.378259195495;11107.166962477684;11127.499262533043;11303;11396.031337336088;11534.596289130175;6983.406410454965;7326.9267307326245;7372.926270737438;7484.176740558425;8243.299319557906;9491.857622135536;10530.894691052992;11086.61196858106;11365.943170284096;11462.254953428 16:27:08 INFO - PROCESS | 2597 | |3;mootools.html;1456.0390142505944;1486.4797320937669;1630.9429169979053;1759.8064212936567;1814.0113638067244;7973;8433.91566084339;8446.113158118402;8448.155184481557;8450.239478446938;7332.78001659951;7646.92353076469;7648.426368022407;7648.732294369698;7656.425768067404;2081.687746837971;2214.6567282071305;2295.047555264563;2298.643710224918;2312.930612081632;2009.276660402254;2308.815294776414;2321.5589038082794;2339.8245131615113;2342.61346877765;2291.8510296830655;2317.721873375189;2320.7447180810095;2324.535092981402;2327.4763178284834;2270.2167752125574;2306.4810417656145;2307.4462129089065;2307.7922986931258;2309.9653505197434;2307.227078928561;2311.8150547956125;2328.941776455585;2329.906803727849;2332.288651961151;8234.752957411287;8251.463654862415;8317.251447369705;8329.250367466957;8351.164883511661;4984.501549845023;5159;5168.069747445458;5169.069567477853;5175.301334319894;2795.9580606290924;3442.3115376924716;3527.894163175109;3536.3634545781747;3545.2554963457446;1000.2498126405196;1043.8486419469134;1083.3445765311951;1091.0998426298256;1097.8408130820985;10781.838272425919;10812.405317707602;10818;10820.404877731722;10837.729056773565;4118.361653943643;4350.34686819013;4550.112728018004;4642.814287428465;4717.2216584263215;9268.860967085498;9276.16514513697;9279.443233406017;9286.860697089549;9289.21041711462;4953.578945789576;5007.799688012438;5141.38303403594;5143.27994080829;5153.793848246029;4496.437945256843;4505.414296141481;4581.839635612738;4582.610478109398;4586.220342541776;3600.963990360115;3620.2759448109955;3643.5263415756312;3677.099110717838;3702.1485058436174;1251.2304932466468;1262.949482020709;1263.91784534005;1268.6447794617523;1273.5096987659633;871.8299931513294;881.2245224202661;896.3143195455482;897.6902968475865;907.1427501011613;4930.457649658535;5366.302380690485;5373.811916582979;5558.694271815089;5806.187133801272;434.4699466650681;444.822071171534;445.9509453960062;447.8634016624961;451.525897807301;3699.648533389324;3705.1292946157632;3719.497867787868;3778.3199024175365;3884.4173373993563;9674.371165874196;9962.651307204213;10124.392536447836;10128.696139115838;10144.797104057878;9625.759356016155;9922.652707155215;10062.949685251528;10067.899321006696;10077.596896124072;7676.347510461674;7834.138244793068;7834.7257845976255;7846.254605812553;7847.2152784721075;5347.5721942243745;5508.283923089973;5533.667979921218;5542.4734650209775;5612.214289999485;9279.76800579991;9330.440173589606;9475.905240947777;9490.335676502578;9495;9042.457452552868;9440.95279523598;9452.196563292197;9455.621775128919;9466.337356384987;6542.149520562487;6739.359760822714;6756.898646520404;6918.238993710587;7027;7183.640817959185;7555.904393862749;7748.883766743391;7755.650995705094;7941.642626081955;7064.82337941535;7083.716651333889;7121.287871212838;7139.2503787101605;7238.23998480152;6906.343897329745;6984.161900571964;6998.825029374103;7008.369246767816;7032.964835175791;6406.807795766134;6559.934400655932;6562.3109573496;6565.671716414256;6581.243157036811;4532.08649446197;4804.135255654016;4826.01066781316;4847.103285892122;4908.828191013297;4767.833125840579;4773.880652983703;4790.592799612072;4947.282644016669;4949.802007919787 16:27:08 INFO - PROCESS | 2597 | |4;prototype.html;295.39444139513995;296.1751522011198;303.5947010740661;310.4907950960424;310.60397992559496;5046.82336118236;5168.689878607286;5302.4697530247;5313;5329.866753331164;4086.5300490443606;4178.415021896929;5092.9745351273195;5197.558207552363;5230.267762513242;3517.613062563116;4002.919941601166;4031.7984100794843;4036.7376120552217;4056.9797151014204;3332.1003329101127;3530.32923744489;4009.9198016039663;4011.558728539873;4066.5323487798837;3599.2621512589985;3969.5831937646562;3974.841006359742;3974.992628722491;3984.3027470192746;3402.2344972381266;3910.608939106095;3956.7625942443547;3957.960420395788;3962.7622342659242;3159.004913452255;3460.2560449503476;3465.462853257749;3467.098554375855;3472;4359.994402574819;4382.123575284956;4819.325294458778;4894.286999550129;4997.600191984632;4505.3917721107555;4512.458504979423;4527.4114365132655;4532.682712210147;4547.226971414867;5042.949570504285;5478.698671573062;5505.119180931032;5531.680991405165;5532.170174473861;3287.4247006773717;3582.1223800169128;3783.659470647655;3787.356149454572;3791.791451470168;4947.876303092451;5723.227364305807;5744.511716504102;5793.290979161157;5804.187413762077;3816.847326106925;3859.4017927221325;3859.4403811447746;3880.7477513961503;3887.4644515416253;3907.4529565860803;3909.2963266612282;3911.3741801311653;3922.86269980555;3938.7439816411843;4264.829406823755;4524.321351797191;4534.762349486348;4541.43232095988;4547.545245475426;4814.831480898222;5479.808206712746;5540.9168862467095;5583.302087239095;5584.385717571064;1121.1815374776459;1162.151629310608;1176.100283283289;1185.1822242652543;1198.3642328221915;724.3987490383075;857.6998050682336;862.3489265604428;863.6761214544546;864.9308055355557;4318.719283246579;4716.363290955677;4969.428515720666;4974.228994505857;4996.90006199874;5046;5438.700871452108;5498.395176530578;5502.394736578973;5514.200440936122;4834.758262086882;5248.950209957948;5290.761915713802;5325.707086110301;5333.359996800409;2620.829646073037;2737.219892330716;2824.2233385819063;2835.503786837295;2854.1152242804797;5163.328767260232;5300.416954135042;5567.777288908398;5655.971720141373;5709.547382249942;4375.956240437555;5568.276124103841;5572.972135139379;5573.5819813513335;5576.749046292926;4960.776765045582;5590.133529302964;5634.971825140848;5666.6600003999065;5699.352414454752;4699.498873339384;5369.785208591613;5483.533899618417;5587.357453892854;5730.905311131942;4956.330895329229;5417.864553386197;5557.916631250615;5727.570432217683;5730.598858079894;5016.448190698947;5678.630888992202;5938.376470470538;5981.641101533922;5993.520518358608;3501.177223352561;3656.213914008501;3768.5491085931567;3857.9035524112123;3869.8065096744485;5776.508996735324;5781.508571771446;5781.768729250951;5794;5805.593608447368;4652.046330502307;4672.7897244624755;5205.401378841481;5340.626156169186;5383.569314454756;5562.72186390687;5609.943900561106;5611.971940140274;5615.129654903497;5625.00312389096;4910.3125562422;4940.555350018516;5037.9924015196375;5212.609054321017;5478.671279723229;5053.45869509803;5131.87170320745;5560.749766260447;5579;5639;5006.549410553068;5444.945550544444;5447.618666693452;5456.59075569326;5461.098918678533 16:27:08 INFO - PROCESS | 2597 | |5;yui.html;682.5938566552901;684.4626967830254;735.871222536056;746.9402447804176;786.5752493653429;13755.44978200872;14372.993890427675;15674.529764107067;16259.593510162253;16275.59311017225;14778.926105369485;14809.555713328617;14812.963092583497;14822;14841.480548180816;649.4804156674642;672.7443571442844;805.5730462854682;835.0355339582761;839.2656425627575;672.1027428383114;806.2824086562964;824.3158178711685;837.7738010737097;843.1989609870617;869.8086420987372;872.8559787635032;873.3624454148476;873.6505397840851;877.4340550345023;680;865.6321063547967;866.0343716755804;866.9133086691344;867.0592407238154;517.2783966366898;623.2676604989165;637.9266384365765;643.8969764837603;645.370763505583;14208.360623771956;14237.572872813833;14250.2162381069;14261.928690356483;14270.429182832675;13142.474301027947;13190.934045329712;13306.401211945486;13437.059405841595;13500.257485838274;10811;11813.88186118145;11829.408529573488;12112.636620901387;12565.434555445026;4398.186335527937;4455.4876189238;4488.775561221926;4526.54734526548;4539.205639013159;87194;89107;89180;89646.65530017055;93667;10273;10444.356278516198;10525.36847789135;10669.78660426787;10806.189535784848;20127;20780.79219207819;21113.894430527747;21646.02592883324;21667;7801.4929029612895;7815.6482958267015;7841.411894107965;7915.960420197862;8038.316743076904;4160.417541544186;4231.915361692749;4299.935500967488;4301.548337424525;4404.273294906305;3400.9659903400648;3489.459133834209;3491.7381196409865;3497.440409534513;3624.1845584743223;679.1341040173719;682.5324652612877;685.6400389795398;693.0435998322283;699.566268913277;593.7476572456688;647.346180357842;654.4077609763171;662.1656712542135;675.4461341699857;7486.9993112879265;8657.523836188944;8972.685955991508;9130.406523575946;9156.404833685923;540.3434826685595;577.9393163717833;593.6289818863211;646.0567571345888;659.7492952677952;3461.5153878457036;4394.099209662011;4395.7582332972;4398.362237475548;4443.822247110144;70070.64964675246;70180.64909675419;109250.36124458139;112866.43566782278;119287.40356298337;69684.30315696778;103503.4824825871;104676.95323046825;107499.46250268699;110801.44599277113;57635.42364576385;58001;66359.67741129037;77742.6112869432;92231;8447.831043379221;8617;8624.094470080801;8670.436421632574;8783.560821959005;56689.43310566842;57621;58391.416085838595;59863.92517384298;60628.69685651544;76428;80474;82905.58547207226;83553.64463553597;87537.68693469475;11339.149563782914;11481.024112950492;11570.826437603275;11816.586419475278;11880.881191188324;11957.103217258566;12119.394030298627;12154.878451215374;12386.62840114798;12446.626601201979;10168.530101058446;10635.734106647396;11997.340146292037;12094.334811585448;12364.814527781911;12625.368731563569;13027.934860325639;13047.28239946774;13214.735705285648;13528.72942541124;12023.879761202626;12282.692932676397;12340.876591233973;12538.498460061495;12595.307258100525;10218.948905255425;10325.120207279942;10485;10740.677779666623;10741.140708743438;10567.63013294531;10691.09125724291;10937.343759374464;10957.342559446459;10980.341179529254 16:27:08 INFO - PROCESS | 2597 | __end_tp_report 16:27:08 INFO - PROCESS | 2597 | __start_cc_report 16:27:08 INFO - PROCESS | 2597 | _x_x_mozilla_cycle_collect,3516 16:27:08 INFO - PROCESS | 2597 | __end_cc_report 16:27:08 INFO - PROCESS | 2597 | __startTimestamp1480465628560__endTimestamp 16:27:08 INFO - PROCESS | 2597 | 16:27:08 INFO - PROCESS | 2597 | ------- Summary: start ------- 16:27:08 INFO - PROCESS | 2597 | Number of tests: 6 16:27:08 INFO - PROCESS | 2597 | 16:27:08 INFO - PROCESS | 2597 | [#0] dojo.html Cycles:165 Average:5640.29 Median:5827.27 stddev:681.01 (11.7%) stddev-sans-first:649.35 16:27:08 INFO - PROCESS | 2597 | Values: 2941.4 3244.8 3251.8 3281.8 3296.7 5622.6 5641.3 5692.1 5698.2 5731.5 5626.2 5705.3 5710.5 5720.7 5778.6 5164.4 5164.5 5186.4 5222.2 5255.0 4990.3 4992.0 5005.6 5275.9 5327.6 4219.2 5060.6 5074.7 5088.8 5097.3 5078.4 5096.9 5102.0 5103.8 5104.5 4947.4 5039.9 5049.0 5055.0 5059.0 5559.9 5561.9 5565.6 5566.6 5567.5 6109.9 6115.0 6118.7 6130.5 6137.8 6216.7 6228.1 6234.6 6236.6 6305.0 5738.4 5786.7 5789.3 5796.5 5820.4 6211.3 6309.4 6318.2 6323.5 6343.1 5983.1 6015.4 6043.2 6056.2 6065.3 6199.3 6297.1 6309.6 6311.4 6319.5 5217.6 6235.3 6240.6 6250.5 6273.5 6062.9 6107.0 6133.4 6144.1 6187.4 4901.1 4907.1 4918.4 4928.2 4932.0 4400.0 4416.6 4429.6 4435.2 4442.3 5150.9 5556.4 5619.9 5635.4 5663.1 5044.5 5212.3 6145.9 6201.9 6253.2 5745.2 5746.9 5752.3 5753.9 5767.8 4496.8 5266.9 5373.0 5390.6 5430.5 6270.1 6277.4 6300.7 6313.8 6343.0 5520.1 5982.1 6232.3 6234.8 6238.4 6197.9 6280.6 6305.7 6323.8 6334.7 5145.6 6098.3 6179.2 6182.4 6182.5 4714.0 6129.5 6269.8 6271.2 6292.4 4737.6 5968.3 6035.2 6056.0 6058.7 6018.8 6022.1 6022.5 6029.3 6032.3 5838.3 5841.1 5856.9 5868.0 5868.6 6031.7 6051.4 6059.6 6061.8 6065.1 5827.3 5843.3 5851.9 5870.6 5946.4 16:27:08 INFO - PROCESS | 2597 | 16:27:08 INFO - PROCESS | 2597 | [#1] ext.html Cycles:180 Average:75802.89 Median:35137.71 stddev:185156.16 (526.9%) stddev-sans-first:185589.23 16:27:08 INFO - PROCESS | 2597 | Values: 492.8 494.2 503.0 506.0 547.7 26820.9 30804.5 30918.5 30920.2 30928.7 31470.7 33051.7 38946.4 39744.0 42086.6 9158.8 9311.4 9340.6 9342.7 9354.2 9141.6 9246.7 9249.8 9250.9 9253.3 8342.8 8558.1 8576.6 8629.8 8630.9 10795.2 11058.6 11091.4 11194.4 11200.9 10961.1 11033.2 11033.3 11039.0 11046.3 38082.6 38266.2 38278.0 38347.2 38360.6 3174.2 3195.0 3209.7 3250.2 3272.3 28939.0 29005.4 29321.6 29912.4 30625.9 3117.7 3157.4 3157.9 3204.1 3252.8 491508.0 495162.0 495372.0 495453.0 498057.0 32834.0 33683.0 58782.7 59189.5 59268.0 34680.3 35032.9 35242.5 35289.9 35408.8 14153.7 14291.2 14305.8 14313.1 14414.6 11960.6 12063.2 12084.0 12088.0 12112.5 7757.7 7774.9 7798.0 7855.1 7886.1 2935.2 3035.8 3135.1 3138.0 3218.4 923.2 925.3 946.0 986.0 1005.9 28424.7 28974.9 29206.9 29764.7 29988.0 991.3 1027.3 1028.7 1049.2 1053.0 15274.2 15290.5 15292.3 15597.4 15664.8 663989.0 935573.3 941875.3 942201.3 944614.0 56750.7 57732.4 57737.7 57795.7 57853.1 1210.2 1247.8 1250.3 1347.8 1372.9 28942.4 30867.8 31053.8 31411.4 31689.7 54067.6 54596.5 54754.5 54828.7 54880.4 599880.0 602135.0 606968.0 607482.0 608801.0 23104.5 23587.4 23682.4 25803.0 26801.5 43765.6 44417.3 45529.8 46052.0 48392.0 36943.0 37413.0 37629.1 37785.0 38810.6 36942.6 37092.6 37446.1 37977.4 38182.2 36270.8 37180.0 37261.1 37298.0 37306.6 31299.0 33609.5 33672.7 33691.0 33891.8 20656.7 24645.6 24890.8 25205.7 25467.2 16:27:08 INFO - PROCESS | 2597 | 16:27:08 INFO - PROCESS | 2597 | [#2] jquery.html Cycles:180 Average:34032.51 Median:3049.80 stddev:95086.71 (3117.8%) stddev-sans-first:95319.76 16:27:08 INFO - PROCESS | 2597 | Values: 319.4 362.7 369.3 382.8 395.0 18407.4 18974.0 19596.3 20061.9 20306.0 19613.0 19755.3 20099.7 20223.0 20266.2 5664.9 5680.6 5986.1 5991.6 5994.6 5791.3 5823.9 5854.4 5856.0 5860.8 5528.1 5727.9 5809.3 5812.8 5818.7 5718.9 5788.8 5789.8 5790.4 5797.2 4861.6 5056.6 5093.0 5097.9 5098.8 22079.7 24221.6 24745.0 25073.2 25170.6 2242.4 2331.0 2716.6 3025.6 3074.0 15049.2 15169.5 15391.8 15437.1 15713.4 1777.3 1790.6 1802.7 1818.4 1871.9 113496.4 118201.4 118952.0 119712.4 119988.0 10304.2 10597.7 10709.0 10841.2 10996.5 13537.5 13558.9 13569.5 13569.5 13591.4 8108.0 8380.2 8391.5 8480.7 8490.9 8435.3 8444.3 8447.4 8597.3 8638.3 6280.5 6446.8 6453.2 6473.7 6576.4 1738.9 1786.0 1787.9 1811.0 1814.3 694.8 904.9 928.5 991.1 1005.2 20901.4 21779.5 21885.3 21911.3 21948.3 810.7 825.3 861.9 939.0 980.1 8389.5 11473.1 11795.6 11898.0 12008.6 556307.0 562014.0 572594.0 574664.1 581210.7 72791.5 116216.4 120750.0 122174.4 136660.6 945.3 961.2 978.2 985.9 996.7 18327.5 18752.9 19099.6 19185.6 19462.7 54929.7 55531.0 55975.2 56277.4 56661.7 74741.3 80945.8 82765.6 84309.7 86584.0 5831.7 7640.3 7685.2 7756.6 8265.2 8803.7 11545.8 12540.8 12641.0 12927.4 10264.5 10358.6 10884.1 11009.1 11147.3 10325.7 10477.5 10632.7 11126.4 11304.4 11107.2 11127.5 11303.0 11396.0 11534.6 6983.4 7326.9 7372.9 7484.2 8243.3 9491.9 10530.9 11086.6 11365.9 11462.3 16:27:08 INFO - PROCESS | 2597 | 16:27:08 INFO - PROCESS | 2597 | [#3] mootools.html Cycles:180 Average:5368.32 Median:4948.54 stddev:2970.57 (60.0%) stddev-sans-first:2964.36 16:27:08 INFO - PROCESS | 2597 | Values: 1456.0 1486.5 1630.9 1759.8 1814.0 7973.0 8433.9 8446.1 8448.2 8450.2 7332.8 7646.9 7648.4 7648.7 7656.4 2081.7 2214.7 2295.0 2298.6 2312.9 2009.3 2308.8 2321.6 2339.8 2342.6 2291.9 2317.7 2320.7 2324.5 2327.5 2270.2 2306.5 2307.4 2307.8 2310.0 2307.2 2311.8 2328.9 2329.9 2332.3 8234.8 8251.5 8317.3 8329.3 8351.2 4984.5 5159.0 5168.1 5169.1 5175.3 2796.0 3442.3 3527.9 3536.4 3545.3 1000.2 1043.8 1083.3 1091.1 1097.8 10781.8 10812.4 10818.0 10820.4 10837.7 4118.4 4350.3 4550.1 4642.8 4717.2 9268.9 9276.2 9279.4 9286.9 9289.2 4953.6 5007.8 5141.4 5143.3 5153.8 4496.4 4505.4 4581.8 4582.6 4586.2 3601.0 3620.3 3643.5 3677.1 3702.1 1251.2 1262.9 1263.9 1268.6 1273.5 871.8 881.2 896.3 897.7 907.1 4930.5 5366.3 5373.8 5558.7 5806.2 434.5 444.8 446.0 447.9 451.5 3699.6 3705.1 3719.5 3778.3 3884.4 9674.4 9962.7 10124.4 10128.7 10144.8 9625.8 9922.7 10062.9 10067.9 10077.6 7676.3 7834.1 7834.7 7846.3 7847.2 5347.6 5508.3 5533.7 5542.5 5612.2 9279.8 9330.4 9475.9 9490.3 9495.0 9042.5 9441.0 9452.2 9455.6 9466.3 6542.1 6739.4 6756.9 6918.2 7027.0 7183.6 7555.9 7748.9 7755.7 7941.6 7064.8 7083.7 7121.3 7139.3 7238.2 6906.3 6984.2 6998.8 7008.4 7033.0 6406.8 6559.9 6562.3 6565.7 6581.2 4532.1 4804.1 4826.0 4847.1 4908.8 4767.8 4773.9 4790.6 4947.3 4949.8 16:27:08 INFO - PROCESS | 2597 | 16:27:08 INFO - PROCESS | 2597 | [#4] prototype.html Cycles:180 Average:4439.54 Median:4985.56 stddev:1375.54 (27.6%) stddev-sans-first:1343.77 16:27:08 INFO - PROCESS | 2597 | Values: 295.4 296.2 303.6 310.5 310.6 5046.8 5168.7 5302.5 5313.0 5329.9 4086.5 4178.4 5093.0 5197.6 5230.3 3517.6 4002.9 4031.8 4036.7 4057.0 3332.1 3530.3 4009.9 4011.6 4066.5 3599.3 3969.6 3974.8 3975.0 3984.3 3402.2 3910.6 3956.8 3958.0 3962.8 3159.0 3460.3 3465.5 3467.1 3472.0 4360.0 4382.1 4819.3 4894.3 4997.6 4505.4 4512.5 4527.4 4532.7 4547.2 5042.9 5478.7 5505.1 5531.7 5532.2 3287.4 3582.1 3783.7 3787.4 3791.8 4947.9 5723.2 5744.5 5793.3 5804.2 3816.8 3859.4 3859.4 3880.7 3887.5 3907.5 3909.3 3911.4 3922.9 3938.7 4264.8 4524.3 4534.8 4541.4 4547.5 4814.8 5479.8 5540.9 5583.3 5584.4 1121.2 1162.2 1176.1 1185.2 1198.4 724.4 857.7 862.3 863.7 864.9 4318.7 4716.4 4969.4 4974.2 4996.9 5046.0 5438.7 5498.4 5502.4 5514.2 4834.8 5249.0 5290.8 5325.7 5333.4 2620.8 2737.2 2824.2 2835.5 2854.1 5163.3 5300.4 5567.8 5656.0 5709.5 4376.0 5568.3 5573.0 5573.6 5576.7 4960.8 5590.1 5635.0 5666.7 5699.4 4699.5 5369.8 5483.5 5587.4 5730.9 4956.3 5417.9 5557.9 5727.6 5730.6 5016.4 5678.6 5938.4 5981.6 5993.5 3501.2 3656.2 3768.5 3857.9 3869.8 5776.5 5781.5 5781.8 5794.0 5805.6 4652.0 4672.8 5205.4 5340.6 5383.6 5562.7 5609.9 5612.0 5615.1 5625.0 4910.3 4940.6 5038.0 5212.6 5478.7 5053.5 5131.9 5560.7 5579.0 5639.0 5006.5 5444.9 5447.6 5456.6 5461.1 16:27:08 INFO - PROCESS | 2597 | 16:27:08 INFO - PROCESS | 2597 | [#5] yui.html Cycles:180 Average:20218.97 Median:4401.32 stddev:29560.28 (671.6%) stddev-sans-first:29606.80 16:27:08 INFO - PROCESS | 2597 | Values: 682.6 684.5 735.9 746.9 786.6 13755.4 14373.0 15674.5 16259.6 16275.6 14778.9 14809.6 14813.0 14822.0 14841.5 649.5 672.7 805.6 835.0 839.3 672.1 806.3 824.3 837.8 843.2 869.8 872.9 873.4 873.7 877.4 680.0 865.6 866.0 866.9 867.1 517.3 623.3 637.9 643.9 645.4 14208.4 14237.6 14250.2 14261.9 14270.4 13142.5 13190.9 13306.4 13437.1 13500.3 10811.0 11813.9 11829.4 12112.6 12565.4 4398.2 4455.5 4488.8 4526.5 4539.2 87194.0 89107.0 89180.0 89646.7 93667.0 10273.0 10444.4 10525.4 10669.8 10806.2 20127.0 20780.8 21113.9 21646.0 21667.0 7801.5 7815.6 7841.4 7916.0 8038.3 4160.4 4231.9 4299.9 4301.5 4404.3 3401.0 3489.5 3491.7 3497.4 3624.2 679.1 682.5 685.6 693.0 699.6 593.7 647.3 654.4 662.2 675.4 7487.0 8657.5 8972.7 9130.4 9156.4 540.3 577.9 593.6 646.1 659.7 3461.5 4394.1 4395.8 4398.4 4443.8 70070.6 70180.6 109250.4 112866.4 119287.4 69684.3 103503.5 104677.0 107499.5 110801.4 57635.4 58001.0 66359.7 77742.6 92231.0 8447.8 8617.0 8624.1 8670.4 8783.6 56689.4 57621.0 58391.4 59863.9 60628.7 76428.0 80474.0 82905.6 83553.6 87537.7 11339.1 11481.0 11570.8 11816.6 11880.9 11957.1 12119.4 12154.9 12386.6 12446.6 10168.5 10635.7 11997.3 12094.3 12364.8 12625.4 13027.9 13047.3 13214.7 13528.7 12023.9 12282.7 12340.9 12538.5 12595.3 10218.9 10325.1 10485.0 10740.7 10741.1 10567.6 10691.1 10937.3 10957.3 10980.3 16:27:08 INFO - PROCESS | 2597 | -------- Summary: end -------- 16:27:08 INFO - PROCESS | 2597 | 16:27:09 INFO - TEST-INFO | 2597: exit 0 16:27:09 INFO - TEST-OK | dromaeo_css | took 1120461ms 16:27:09 INFO - TEST-START | kraken 16:27:09 INFO - operating with platform_type : mac_ 16:27:09 INFO - Initialising browser for kraken test... 16:27:09 INFO - TEST-INFO | started process 2599 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/tmplGTcjH/profile http://localhost:51908/getInfo.html) 16:27:11 INFO - PROCESS | 2599 | __metrics Screen width/height:1600/1200 16:27:11 INFO - PROCESS | 2599 | colorDepth:24 16:27:11 INFO - PROCESS | 2599 | Browser inner width/height: 1024/689 16:27:11 INFO - PROCESS | 2599 | __metrics 16:27:11 INFO - PROCESS | 2599 | 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" 16:27:11 INFO - PROCESS | 2599 | JavaScript error: resource:///modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 16:27:12 INFO - TEST-INFO | 2599: exit 0 16:27:12 INFO - Browser initialized. 16:27:12 INFO - Running cycle 1/1 for kraken test... 16:27:12 INFO - TEST-INFO | started process 2600 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/tmplGTcjH/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:27:25 INFO - PROCESS | 2600 | RSS: Main: 223625216 16:27:25 INFO - PROCESS | 2600 | 16:27:28 INFO - PROCESS | 2600 | Cycle 1(1): loaded http://localhost:51908/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:51908/tests/kraken/driver.html?testName=audio-beat-detection) 16:27:28 INFO - PROCESS | 2600 | RSS: Main: 275595264 16:27:28 INFO - PROCESS | 2600 | 16:27:31 INFO - PROCESS | 2600 | Cycle 1(1): loaded http://localhost:51908/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:51908/tests/kraken/driver.html?testName=audio-dft) 16:27:32 INFO - PROCESS | 2600 | RSS: Main: 286793728 16:27:32 INFO - PROCESS | 2600 | 16:27:35 INFO - PROCESS | 2600 | Cycle 1(1): loaded http://localhost:51908/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:51908/tests/kraken/driver.html?testName=audio-fft) 16:27:36 INFO - PROCESS | 2600 | RSS: Main: 382197760 16:27:36 INFO - PROCESS | 2600 | 16:27:38 INFO - PROCESS | 2600 | Cycle 1(1): loaded http://localhost:51908/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:51908/tests/kraken/driver.html?testName=audio-oscillator) 16:27:38 INFO - PROCESS | 2600 | RSS: Main: 284008448 16:27:38 INFO - PROCESS | 2600 | 16:27:41 INFO - PROCESS | 2600 | Cycle 1(1): loaded http://localhost:51908/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:51908/tests/kraken/driver.html?testName=imaging-gaussian-blur) 16:27:41 INFO - PROCESS | 2600 | RSS: Main: 285130752 16:27:41 INFO - PROCESS | 2600 | 16:27:44 INFO - PROCESS | 2600 | Cycle 1(1): loaded http://localhost:51908/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:51908/tests/kraken/driver.html?testName=imaging-darkroom) 16:27:45 INFO - PROCESS | 2600 | RSS: Main: 327770112 16:27:45 INFO - PROCESS | 2600 | 16:27:48 INFO - PROCESS | 2600 | Cycle 1(1): loaded http://localhost:51908/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:51908/tests/kraken/driver.html?testName=imaging-desaturate) 16:27:48 INFO - PROCESS | 2600 | RSS: Main: 327757824 16:27:48 INFO - PROCESS | 2600 | 16:27:51 INFO - PROCESS | 2600 | Cycle 1(1): loaded http://localhost:51908/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:51908/tests/kraken/driver.html?testName=json-parse-financial) 16:27:52 INFO - PROCESS | 2600 | RSS: Main: 326959104 16:27:52 INFO - PROCESS | 2600 | 16:27:53 INFO - PROCESS | 2600 | Cycle 1(1): loaded http://localhost:51908/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:51908/tests/kraken/driver.html?testName=json-stringify-tinderbox) 16:27:54 INFO - PROCESS | 2600 | RSS: Main: 301441024 16:27:54 INFO - PROCESS | 2600 | 16:27:56 INFO - PROCESS | 2600 | Cycle 1(1): loaded http://localhost:51908/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:51908/tests/kraken/driver.html?testName=stanford-crypto-aes) 16:27:57 INFO - PROCESS | 2600 | RSS: Main: 304316416 16:27:57 INFO - PROCESS | 2600 | 16:27:59 INFO - PROCESS | 2600 | Cycle 1(1): loaded http://localhost:51908/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:51908/tests/kraken/driver.html?testName=stanford-crypto-ccm) 16:27:59 INFO - PROCESS | 2600 | RSS: Main: 306843648 16:27:59 INFO - PROCESS | 2600 | 16:28:02 INFO - PROCESS | 2600 | Cycle 1(1): loaded http://localhost:51908/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:51908/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 16:28:03 INFO - PROCESS | 2600 | RSS: Main: 325496832 16:28:03 INFO - PROCESS | 2600 | 16:28:06 INFO - PROCESS | 2600 | Cycle 1(1): loaded http://localhost:51908/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:51908/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 16:28:07 INFO - PROCESS | 2600 | RSS: Main: 319401984 16:28:07 INFO - PROCESS | 2600 | 16:28:08 INFO - PROCESS | 2600 | Cycle 1(1): loaded http://localhost:51908/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:51908/tests/kraken/driver.html?testName=ai-astar) 16:28:09 INFO - PROCESS | 2600 | RSS: Main: 363216896 16:28:09 INFO - PROCESS | 2600 | 16:28:09 INFO - PROCESS | 2600 | __start_tp_report 16:28:09 INFO - PROCESS | 2600 | _x_x_mozilla_page_load 16:28:09 INFO - PROCESS | 2600 | _x_x_mozilla_page_load_details 16:28:09 INFO - PROCESS | 2600 | |i|pagename|runs| 16:28:09 INFO - PROCESS | 2600 | |0;ai-astar;116;115;116;114;122;170;153;114;115;114 16:28:09 INFO - PROCESS | 2600 | |1;audio-beat-detection;160;143;144;148;111;113;120;141;177;128 16:28:09 INFO - PROCESS | 2600 | |2;audio-dft;184;183;183;186;191;260;184;185;182;185 16:28:09 INFO - PROCESS | 2600 | |3;audio-fft;83;60;58;92;62;59;60;59;82;75 16:28:09 INFO - PROCESS | 2600 | |4;audio-oscillator;93;93;127;100;67;68;88;67;68;92 16:28:09 INFO - PROCESS | 2600 | |5;imaging-gaussian-blur;116;130;99;86;134;129;91;88;90;87 16:28:09 INFO - PROCESS | 2600 | |6;imaging-darkroom;98;97;97;97;98;98;98;97;97;97 16:28:09 INFO - PROCESS | 2600 | |7;imaging-desaturate;85;83;113;134;128;83;82;84;81;85 16:28:09 INFO - PROCESS | 2600 | |8;json-parse-financial;57;54;57;56;59;59;60;60;60;61 16:28:09 INFO - PROCESS | 2600 | |9;json-stringify-tinderbox;66;74;76;75;73;71;72;73;71;73 16:28:09 INFO - PROCESS | 2600 | |10;stanford-crypto-aes;85;83;85;85;85;85;85;84;87;85 16:28:09 INFO - PROCESS | 2600 | |11;stanford-crypto-ccm;150;146;153;146;166;158;154;151;191;174 16:28:09 INFO - PROCESS | 2600 | |12;stanford-crypto-pbkdf2;169;169;169;169;167;169;168;169;168;165 16:28:09 INFO - PROCESS | 2600 | |13;stanford-crypto-sha256-iterative;64;65;57;67;85;84;86;87;73;68 16:28:09 INFO - PROCESS | 2600 | __end_tp_report 16:28:09 INFO - PROCESS | 2600 | __start_cc_report 16:28:09 INFO - PROCESS | 2600 | _x_x_mozilla_cycle_collect,2104 16:28:09 INFO - PROCESS | 2600 | __end_cc_report 16:28:09 INFO - PROCESS | 2600 | __startTimestamp1480465689245__endTimestamp 16:28:09 INFO - PROCESS | 2600 | 16:28:09 INFO - PROCESS | 2600 | ------- Summary: start ------- 16:28:09 INFO - PROCESS | 2600 | Number of tests: 14 16:28:09 INFO - PROCESS | 2600 | 16:28:09 INFO - PROCESS | 2600 | [#0] ai-astar Cycles:10 Average:124.90 Median:116.00 stddev:19.84 (17.1%) stddev-sans-first:20.78 16:28:09 INFO - PROCESS | 2600 | Values: 116.0 115.0 116.0 114.0 122.0 170.0 153.0 114.0 115.0 114.0 16:28:09 INFO - PROCESS | 2600 | 16:28:09 INFO - PROCESS | 2600 | [#1] audio-beat-detection Cycles:10 Average:138.50 Median:143.50 stddev:20.95 (14.6%) stddev-sans-first:20.73 16:28:09 INFO - PROCESS | 2600 | Values: 160.0 143.0 144.0 148.0 111.0 113.0 120.0 141.0 177.0 128.0 16:28:09 INFO - PROCESS | 2600 | 16:28:09 INFO - PROCESS | 2600 | [#2] audio-dft Cycles:10 Average:192.30 Median:185.00 stddev:23.92 (12.9%) stddev-sans-first:25.18 16:28:09 INFO - PROCESS | 2600 | Values: 184.0 183.0 183.0 186.0 191.0 260.0 184.0 185.0 182.0 185.0 16:28:09 INFO - PROCESS | 2600 | 16:28:09 INFO - PROCESS | 2600 | [#3] audio-fft Cycles:10 Average:69.00 Median:68.50 stddev:12.75 (18.6%) stddev-sans-first:12.47 16:28:09 INFO - PROCESS | 2600 | Values: 83.0 60.0 58.0 92.0 62.0 59.0 60.0 59.0 82.0 75.0 16:28:09 INFO - PROCESS | 2600 | 16:28:09 INFO - PROCESS | 2600 | [#4] audio-oscillator Cycles:10 Average:86.30 Median:78.00 stddev:19.39 (24.9%) stddev-sans-first:20.42 16:28:09 INFO - PROCESS | 2600 | Values: 93.0 93.0 127.0 100.0 67.0 68.0 88.0 67.0 68.0 92.0 16:28:09 INFO - PROCESS | 2600 | 16:28:09 INFO - PROCESS | 2600 | [#5] imaging-gaussian-blur Cycles:10 Average:105.00 Median:87.50 stddev:19.98 (22.8%) stddev-sans-first:20.80 16:28:09 INFO - PROCESS | 2600 | Values: 116.0 130.0 99.0 86.0 134.0 129.0 91.0 88.0 90.0 87.0 16:28:09 INFO - PROCESS | 2600 | 16:28:09 INFO - PROCESS | 2600 | [#6] imaging-darkroom Cycles:10 Average:97.40 Median:97.50 stddev:0.52 (0.5%) stddev-sans-first:0.50 16:28:09 INFO - PROCESS | 2600 | Values: 98.0 97.0 97.0 97.0 98.0 98.0 98.0 97.0 97.0 97.0 16:28:09 INFO - PROCESS | 2600 | 16:28:09 INFO - PROCESS | 2600 | [#7] imaging-desaturate Cycles:10 Average:95.80 Median:83.00 stddev:20.82 (25.1%) stddev-sans-first:21.71 16:28:09 INFO - PROCESS | 2600 | Values: 85.0 83.0 113.0 134.0 128.0 83.0 82.0 84.0 81.0 85.0 16:28:09 INFO - PROCESS | 2600 | 16:28:09 INFO - PROCESS | 2600 | [#8] json-parse-financial Cycles:10 Average:58.30 Median:59.50 stddev:2.21 (3.7%) stddev-sans-first:2.30 16:28:09 INFO - PROCESS | 2600 | Values: 57.0 54.0 57.0 56.0 59.0 59.0 60.0 60.0 60.0 61.0 16:28:09 INFO - PROCESS | 2600 | 16:28:09 INFO - PROCESS | 2600 | [#9] json-stringify-tinderbox Cycles:10 Average:72.40 Median:73.00 stddev:2.76 (3.8%) stddev-sans-first:1.69 16:28:09 INFO - PROCESS | 2600 | Values: 66.0 74.0 76.0 75.0 73.0 71.0 72.0 73.0 71.0 73.0 16:28:09 INFO - PROCESS | 2600 | 16:28:09 INFO - PROCESS | 2600 | [#10] stanford-crypto-aes Cycles:10 Average:84.90 Median:85.00 stddev:0.99 (1.2%) stddev-sans-first:1.05 16:28:09 INFO - PROCESS | 2600 | Values: 85.0 83.0 85.0 85.0 85.0 85.0 85.0 84.0 87.0 85.0 16:28:09 INFO - PROCESS | 2600 | 16:28:09 INFO - PROCESS | 2600 | [#11] stanford-crypto-ccm Cycles:10 Average:158.90 Median:156.00 stddev:14.31 (9.2%) stddev-sans-first:14.81 16:28:09 INFO - PROCESS | 2600 | Values: 150.0 146.0 153.0 146.0 166.0 158.0 154.0 151.0 191.0 174.0 16:28:09 INFO - PROCESS | 2600 | 16:28:09 INFO - PROCESS | 2600 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:168.20 Median:169.00 stddev:1.32 (0.8%) stddev-sans-first:1.36 16:28:09 INFO - PROCESS | 2600 | Values: 169.0 169.0 169.0 169.0 167.0 169.0 168.0 169.0 168.0 165.0 16:28:09 INFO - PROCESS | 2600 | 16:28:09 INFO - PROCESS | 2600 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:73.60 Median:78.50 stddev:11.00 (14.0%) stddev-sans-first:11.10 16:28:09 INFO - PROCESS | 2600 | Values: 64.0 65.0 57.0 67.0 85.0 84.0 86.0 87.0 73.0 68.0 16:28:09 INFO - PROCESS | 2600 | -------- Summary: end -------- 16:28:09 INFO - PROCESS | 2600 | 16:28:10 INFO - TEST-INFO | 2600: exit 0 16:28:10 INFO - TEST-OK | kraken | took 61119ms 16:28:10 INFO - SUITE-END | took 1181s 16:28:10 INFO - Completed test suite (00:19:41) 16:28:10 INFO - javascript benchmark 16:28:10 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2941.39701361221, 3244.789088709232, 3251.7886337388054, 3281.753868459866, 3296.703296703296, 5622.550195984321, 5641.323041235048, 5692.146178073291, 5698.173764804101, 5731.512821410175, 5626.212330273765, 5705.3357209483265, 5710.486056254937, 5720.685362305072, 5778.59549831512, 5164.380274367072, 5164.457731938149, 5186.403563590197, 5222.164453687411, 5255.0, 4990.251462280668, 4992.0, 5005.57452616528, 5275.894482110355, 5327.627066105355, 4219.198352313051, 5060.645754797166, 5074.695518268915, 5088.84733457995, 5097.260897169908, 5078.39059312883, 5096.872578185538, 5102.030614183293, 5103.8468845934685, 5104.5405913467785, 4947.356843610308, 5039.949600503985, 5048.974755126201, 5054.974725126388, 5058.962912602908, 5559.9444005559835, 5561.944380556183, 5565.554755619582, 5566.582506312043, 5567.526760225345, 6109.890546868033, 6115.005896079736, 6118.680093383066, 6130.459285710702, 6137.815865524041, 6216.720247588869, 6228.128062071269, 6234.563580549318, 6236.563440559163, 6305.0, 5738.397468265814, 5786.652800831964, 5789.305283366023, 5796.50729687977, 5820.35976042635, 6211.254649442096, 6309.432151106376, 6318.241810982666, 6323.462505687068, 6343.111964324999, 5983.072623743326, 6015.368386319374, 6043.214382130295, 6056.152138700585, 6065.272167339859, 6199.2730169795705, 6297.118403423525, 6309.621422714652, 6311.368863113652, 6319.526035547352, 5217.643412712722, 6235.3452887446165, 6240.594361366588, 6250.499960003189, 6273.466755325757, 6062.878742425127, 6107.006761825774, 6133.355997620275, 6144.064619644798, 6187.412195841388, 4901.068796928572, 4907.09218794517, 4918.434380046269, 4928.211486162269, 4931.988942266827, 4400.0, 4416.558344165622, 4429.601335879786, 4435.2016637005, 4442.2892337225885, 5150.948490515122, 5556.416576259516, 5619.93221287954, 5635.436456354414, 5663.093904975267, 5044.520770526794, 5212.270282160501, 6145.863015342176, 6201.937980620226, 6253.187085678924, 5745.195672605839, 5746.856328591819, 5752.338481074647, 5753.942460575341, 5767.826965191051, 4496.775161241925, 5266.947330526722, 5373.0, 5390.622656414013, 5430.484104010113, 6270.0908368287255, 6277.372262773686, 6300.7479700811455, 6313.779017734358, 6343.0, 5520.06158952987, 5982.102684597346, 6232.314445410911, 6234.75060997555, 6238.407351301619, 6197.907031394442, 6280.560360774884, 6305.716242768995, 6323.810285691437, 6334.651594162366, 5145.61407894402, 6098.29869564988, 6179.227596550431, 6182.412670796267, 6182.505399568114, 4714.0, 6129.478994285536, 6269.843253918689, 6271.153394291712, 6292.402221788922, 4737.573618374363, 5968.343482216866, 6035.215421995288, 6056.0007598745715, 6058.7273572688455, 6018.8194354169445, 6022.126791615104, 6022.518198544193, 6029.276486821609, 6032.276126864805, 5838.299404071538, 5841.094630332305, 5856.853578660397, 5867.970660146672, 5868.618539794899, 6031.728572214349, 6051.364606716407, 6059.606125601822, 6061.818145455643, 6065.059915713072, 5827.271591051119, 5843.328017277898, 5851.882962340815, 5870.559708022, 5946.405359464019], "value": 5596.609136549612, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [492.7807125829008, 494.18952917215773, 502.9638943775821, 505.9949400505996, 547.6987656788765, 26820.86589567052, 30804.537931931038, 30918.53622195669, 30920.226994325152, 30928.690713092863, 31470.68529314706, 33051.66948330516, 38946.415803762895, 39744.0, 42086.57913420865, 9158.771030724218, 9311.394759340621, 9340.626374944994, 9342.673006444777, 9354.15812576868, 9141.634334626608, 9246.722598322061, 9249.815003699921, 9250.86123708145, 9253.30600204984, 8342.83314333713, 8558.101399353041, 8576.614052367628, 8629.784255393633, 8630.913690863104, 10795.190360722938, 11058.612948546843, 11091.389973551451, 11194.38430886301, 11200.887991120066, 10961.12311015117, 11033.227674062819, 11033.282836615594, 11039.0, 11046.281991670516, 38082.61917380818, 38266.23467530634, 38278.0, 38347.23305533901, 38360.616393836266, 3174.158847905307, 3194.984025079883, 3209.6629853865243, 3250.236194494292, 3272.2637406583326, 28939.0, 29005.41989160226, 29321.56017659737, 29912.401751965055, 30625.928092516657, 3117.7194052535156, 3157.4158780625658, 3157.873685052595, 3204.13488358142, 3252.788568743024, 491508.0, 495162.0, 495372.0, 495453.0, 498057.0, 32834.0, 33683.0, 58782.70608646929, 59189.52026199293, 59268.0, 34680.30639387198, 35032.949011529694, 35242.47136292957, 35289.94130176099, 35408.82295588557, 14153.716925661429, 14291.213983231022, 14305.78541321881, 14313.141211527134, 14414.567562973127, 11960.581379651845, 12063.155579109378, 12083.972862306802, 12088.03295736339, 12112.515499379926, 7757.650905709256, 7774.92225077742, 7797.961010194913, 7855.057393112863, 7886.093099293655, 2935.222166125978, 3035.848207589612, 3135.1221657935816, 3137.9686203137676, 3218.4045951499047, 923.2475532441039, 925.2551695884742, 946.0397696338222, 986.0435377683635, 1005.8642083318805, 28424.715752842207, 28974.855125724236, 29206.853965730035, 29764.702352976627, 29987.950421735135, 991.3258983890879, 1027.3168343051786, 1028.7222449938474, 1049.21833234241, 1052.984205236922, 15274.236288185546, 15290.541283761506, 15292.311845966735, 15597.376104955902, 15664.843351566566, 663989.0, 935573.322133385, 941875.2906235562, 942201.2889935643, 944614.0, 56750.71624641933, 57732.422675773545, 57737.71131144317, 57795.71102144462, 57853.13220301699, 1210.2073142091945, 1247.8377810884526, 1250.2685928731794, 1347.8382594088773, 1372.9038967272195, 28942.42115157643, 30867.845660771553, 31053.8447307762, 31411.37177256488, 31689.683103169595, 54067.648308792595, 54596.45403545914, 54754.45245547653, 54828.72585637046, 54880.35358939078, 599880.0, 602135.0, 606968.0, 607482.0, 608801.0, 23104.537909241386, 23587.41031474227, 23682.407939801644, 25802.96788128529, 26801.463970720866, 43765.56234437615, 44417.33373999328, 45529.77235113803, 46052.0, 48392.0, 36943.0, 37413.0, 37629.05927351728, 37785.0, 38810.6118938807, 36942.630573694994, 37092.629073708915, 37446.06384840292, 37977.43033854439, 38182.23635527218, 36270.81864590766, 37180.0, 37261.06847328838, 37298.0, 37306.62693373032, 31299.0, 33609.495857562644, 33672.663273366954, 33691.0, 33891.830540847135, 20656.690149647467, 24645.63031554564, 24890.751092488845, 25205.74794252034, 25467.23598292054], "value": 18322.168838393856, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [319.3724331688233, 362.68446451587107, 369.2854326877492, 382.7569493371708, 395.04990497852674, 18407.447776566707, 18974.0, 19596.31412900549, 20061.899690501545, 20305.98470076494, 19613.0, 19755.308564200255, 20099.698504522443, 20222.988850557485, 20266.18935242592, 5664.915026274609, 5680.573956953224, 5986.132010858445, 5991.580589358747, 5994.610350327215, 5791.276090488689, 5823.854403639901, 5854.414558544133, 5855.975204339224, 5860.769238459929, 5528.087865502207, 5727.856803579923, 5809.302883653967, 5812.767489300423, 5818.650880947135, 5718.885622287552, 5788.82633520995, 5789.797357092502, 5790.420957904197, 5797.217375654275, 4861.562459378673, 5056.62075344347, 5092.974535127338, 5097.923531147036, 5098.770555325019, 22079.66880496794, 24221.63667544988, 24745.0, 25073.24780256595, 25170.622440663406, 2242.372135801978, 2330.988345058264, 2716.6332545106357, 3025.6369235691636, 3073.969260307413, 15049.172295523742, 15169.469068582657, 15391.846081539154, 15437.140714821295, 15713.371465141381, 1777.2624360890215, 1790.6239689665185, 1802.7025540785755, 1818.4271954334474, 1871.9064046797605, 113496.4325178377, 118201.40899295534, 118951.99999999914, 119712.40143799312, 119988.0, 10304.227182961307, 10597.735056623493, 10709.036186743231, 10841.186910981707, 10996.450177491093, 13537.458501659823, 13558.93220533891, 13569.457221711022, 13569.525066622818, 13591.388387522586, 8108.027036755627, 8380.16198380157, 8391.496510209407, 8480.660773569112, 8490.915090849136, 8435.283000944866, 8444.282236010007, 8447.36644751646, 8597.269232115215, 8638.26574741154, 6280.466160376419, 6446.774362897276, 6453.225612926479, 6473.676316184172, 6576.408123268833, 1738.852197563196, 1785.9553511162064, 1787.8569714422815, 1810.9909450452926, 1814.3014939248485, 694.7915625312386, 904.8959369672572, 928.5125309212717, 991.0634450444261, 1005.1757558801712, 20901.37295881126, 21779.45551361197, 21885.343439696833, 21911.342659720234, 21948.34154975353, 810.6878851642042, 825.2778818533783, 861.9312053054168, 938.957746901391, 980.1032055669126, 8389.538575378412, 11473.139514536444, 11795.646130616095, 11898.0, 12008.579699710468, 556307.0, 562014.0, 572594.0, 574664.1266793639, 581210.657626981, 72791.54416911631, 116216.41891790656, 120750.0, 122174.3891280538, 136660.6333936648, 945.2579724915907, 961.1781926452783, 978.1783302026332, 985.8964808694984, 996.7009897030862, 18327.450176494727, 18752.906235468734, 19099.618007639492, 19185.561082918557, 19462.708059378838, 54929.72535137299, 55531.0, 55975.16347711405, 56277.437225628855, 56661.71669141628, 74741.25258747561, 80945.78581321167, 82765.58617206875, 84309.73535397097, 86584.0, 5831.7375718091935, 7640.312371886557, 7685.154632990479, 7756.573388463688, 8265.173482651686, 8803.735887923373, 11545.769084618092, 12540.811887821872, 12641.0, 12927.353632318534, 10264.538095785558, 10358.637447689294, 10884.129269658566, 11009.06422954058, 11147.272172813227, 10325.74185645365, 10477.528511217166, 10632.734181645521, 11126.443677815914, 11304.378259195495, 11107.166962477684, 11127.499262533043, 11303.0, 11396.031337336088, 11534.596289130175, 6983.406410454965, 7326.9267307326245, 7372.926270737438, 7484.176740558425, 8243.299319557906, 9491.857622135536, 10530.894691052992, 11086.61196858106, 11365.943170284096, 11462.254953428], "value": 9564.149589318817, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1456.0390142505944, 1486.4797320937669, 1630.9429169979053, 1759.8064212936567, 1814.0113638067244, 7973.0, 8433.91566084339, 8446.113158118402, 8448.155184481557, 8450.239478446938, 7332.78001659951, 7646.92353076469, 7648.426368022407, 7648.732294369698, 7656.425768067404, 2081.687746837971, 2214.6567282071305, 2295.047555264563, 2298.643710224918, 2312.930612081632, 2009.276660402254, 2308.815294776414, 2321.5589038082794, 2339.8245131615113, 2342.61346877765, 2291.8510296830655, 2317.721873375189, 2320.7447180810095, 2324.535092981402, 2327.4763178284834, 2270.2167752125574, 2306.4810417656145, 2307.4462129089065, 2307.7922986931258, 2309.9653505197434, 2307.227078928561, 2311.8150547956125, 2328.941776455585, 2329.906803727849, 2332.288651961151, 8234.752957411287, 8251.463654862415, 8317.251447369705, 8329.250367466957, 8351.164883511661, 4984.501549845023, 5159.0, 5168.069747445458, 5169.069567477853, 5175.301334319894, 2795.9580606290924, 3442.3115376924716, 3527.894163175109, 3536.3634545781747, 3545.2554963457446, 1000.2498126405196, 1043.8486419469134, 1083.3445765311951, 1091.0998426298256, 1097.8408130820985, 10781.838272425919, 10812.405317707602, 10818.0, 10820.404877731722, 10837.729056773565, 4118.361653943643, 4350.34686819013, 4550.112728018004, 4642.814287428465, 4717.2216584263215, 9268.860967085498, 9276.16514513697, 9279.443233406017, 9286.860697089549, 9289.21041711462, 4953.578945789576, 5007.799688012438, 5141.38303403594, 5143.27994080829, 5153.793848246029, 4496.437945256843, 4505.414296141481, 4581.839635612738, 4582.610478109398, 4586.220342541776, 3600.963990360115, 3620.2759448109955, 3643.5263415756312, 3677.099110717838, 3702.1485058436174, 1251.2304932466468, 1262.949482020709, 1263.91784534005, 1268.6447794617523, 1273.5096987659633, 871.8299931513294, 881.2245224202661, 896.3143195455482, 897.6902968475865, 907.1427501011613, 4930.457649658535, 5366.302380690485, 5373.811916582979, 5558.694271815089, 5806.187133801272, 434.4699466650681, 444.822071171534, 445.9509453960062, 447.8634016624961, 451.525897807301, 3699.648533389324, 3705.1292946157632, 3719.497867787868, 3778.3199024175365, 3884.4173373993563, 9674.371165874196, 9962.651307204213, 10124.392536447836, 10128.696139115838, 10144.797104057878, 9625.759356016155, 9922.652707155215, 10062.949685251528, 10067.899321006696, 10077.596896124072, 7676.347510461674, 7834.138244793068, 7834.7257845976255, 7846.254605812553, 7847.2152784721075, 5347.5721942243745, 5508.283923089973, 5533.667979921218, 5542.4734650209775, 5612.214289999485, 9279.76800579991, 9330.440173589606, 9475.905240947777, 9490.335676502578, 9495.0, 9042.457452552868, 9440.95279523598, 9452.196563292197, 9455.621775128919, 9466.337356384987, 6542.149520562487, 6739.359760822714, 6756.898646520404, 6918.238993710587, 7027.0, 7183.640817959185, 7555.904393862749, 7748.883766743391, 7755.650995705094, 7941.642626081955, 7064.82337941535, 7083.716651333889, 7121.287871212838, 7139.2503787101605, 7238.23998480152, 6906.343897329745, 6984.161900571964, 6998.825029374103, 7008.369246767816, 7032.964835175791, 6406.807795766134, 6559.934400655932, 6562.3109573496, 6565.671716414256, 6581.243157036811, 4532.08649446197, 4804.135255654016, 4826.01066781316, 4847.103285892122, 4908.828191013297, 4767.833125840579, 4773.880652983703, 4790.592799612072, 4947.282644016669, 4949.802007919787], "value": 4285.776646610487, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [295.39444139513995, 296.1751522011198, 303.5947010740661, 310.4907950960424, 310.60397992559496, 5046.82336118236, 5168.689878607286, 5302.4697530247, 5313.0, 5329.866753331164, 4086.5300490443606, 4178.415021896929, 5092.9745351273195, 5197.558207552363, 5230.267762513242, 3517.613062563116, 4002.919941601166, 4031.7984100794843, 4036.7376120552217, 4056.9797151014204, 3332.1003329101127, 3530.32923744489, 4009.9198016039663, 4011.558728539873, 4066.5323487798837, 3599.2621512589985, 3969.5831937646562, 3974.841006359742, 3974.992628722491, 3984.3027470192746, 3402.2344972381266, 3910.608939106095, 3956.7625942443547, 3957.960420395788, 3962.7622342659242, 3159.004913452255, 3460.2560449503476, 3465.462853257749, 3467.098554375855, 3472.0, 4359.994402574819, 4382.123575284956, 4819.325294458778, 4894.286999550129, 4997.600191984632, 4505.3917721107555, 4512.458504979423, 4527.4114365132655, 4532.682712210147, 4547.226971414867, 5042.949570504285, 5478.698671573062, 5505.119180931032, 5531.680991405165, 5532.170174473861, 3287.4247006773717, 3582.1223800169128, 3783.659470647655, 3787.356149454572, 3791.791451470168, 4947.876303092451, 5723.227364305807, 5744.511716504102, 5793.290979161157, 5804.187413762077, 3816.847326106925, 3859.4017927221325, 3859.4403811447746, 3880.7477513961503, 3887.4644515416253, 3907.4529565860803, 3909.2963266612282, 3911.3741801311653, 3922.86269980555, 3938.7439816411843, 4264.829406823755, 4524.321351797191, 4534.762349486348, 4541.43232095988, 4547.545245475426, 4814.831480898222, 5479.808206712746, 5540.9168862467095, 5583.302087239095, 5584.385717571064, 1121.1815374776459, 1162.151629310608, 1176.100283283289, 1185.1822242652543, 1198.3642328221915, 724.3987490383075, 857.6998050682336, 862.3489265604428, 863.6761214544546, 864.9308055355557, 4318.719283246579, 4716.363290955677, 4969.428515720666, 4974.228994505857, 4996.90006199874, 5046.0, 5438.700871452108, 5498.395176530578, 5502.394736578973, 5514.200440936122, 4834.758262086882, 5248.950209957948, 5290.761915713802, 5325.707086110301, 5333.359996800409, 2620.829646073037, 2737.219892330716, 2824.2233385819063, 2835.503786837295, 2854.1152242804797, 5163.328767260232, 5300.416954135042, 5567.777288908398, 5655.971720141373, 5709.547382249942, 4375.956240437555, 5568.276124103841, 5572.972135139379, 5573.5819813513335, 5576.749046292926, 4960.776765045582, 5590.133529302964, 5634.971825140848, 5666.6600003999065, 5699.352414454752, 4699.498873339384, 5369.785208591613, 5483.533899618417, 5587.357453892854, 5730.905311131942, 4956.330895329229, 5417.864553386197, 5557.916631250615, 5727.570432217683, 5730.598858079894, 5016.448190698947, 5678.630888992202, 5938.376470470538, 5981.641101533922, 5993.520518358608, 3501.177223352561, 3656.213914008501, 3768.5491085931567, 3857.9035524112123, 3869.8065096744485, 5776.508996735324, 5781.508571771446, 5781.768729250951, 5794.0, 5805.593608447368, 4652.046330502307, 4672.7897244624755, 5205.401378841481, 5340.626156169186, 5383.569314454756, 5562.72186390687, 5609.943900561106, 5611.971940140274, 5615.129654903497, 5625.00312389096, 4910.3125562422, 4940.555350018516, 5037.9924015196375, 5212.609054321017, 5478.671279723229, 5053.45869509803, 5131.87170320745, 5560.749766260447, 5579.0, 5639.0, 5006.549410553068, 5444.945550544444, 5447.618666693452, 5456.59075569326, 5461.098918678533], "value": 3992.8545174155347, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [682.5938566552901, 684.4626967830254, 735.871222536056, 746.9402447804176, 786.5752493653429, 13755.44978200872, 14372.993890427675, 15674.529764107067, 16259.593510162253, 16275.59311017225, 14778.926105369485, 14809.555713328617, 14812.963092583497, 14822.0, 14841.480548180816, 649.4804156674642, 672.7443571442844, 805.5730462854682, 835.0355339582761, 839.2656425627575, 672.1027428383114, 806.2824086562964, 824.3158178711685, 837.7738010737097, 843.1989609870617, 869.8086420987372, 872.8559787635032, 873.3624454148476, 873.6505397840851, 877.4340550345023, 680.0, 865.6321063547967, 866.0343716755804, 866.9133086691344, 867.0592407238154, 517.2783966366898, 623.2676604989165, 637.9266384365765, 643.8969764837603, 645.370763505583, 14208.360623771956, 14237.572872813833, 14250.2162381069, 14261.928690356483, 14270.429182832675, 13142.474301027947, 13190.934045329712, 13306.401211945486, 13437.059405841595, 13500.257485838274, 10811.0, 11813.88186118145, 11829.408529573488, 12112.636620901387, 12565.434555445026, 4398.186335527937, 4455.4876189238, 4488.775561221926, 4526.54734526548, 4539.205639013159, 87194.0, 89107.0, 89180.0, 89646.65530017055, 93667.0, 10273.0, 10444.356278516198, 10525.36847789135, 10669.78660426787, 10806.189535784848, 20127.0, 20780.79219207819, 21113.894430527747, 21646.02592883324, 21667.0, 7801.4929029612895, 7815.6482958267015, 7841.411894107965, 7915.960420197862, 8038.316743076904, 4160.417541544186, 4231.915361692749, 4299.935500967488, 4301.548337424525, 4404.273294906305, 3400.9659903400648, 3489.459133834209, 3491.7381196409865, 3497.440409534513, 3624.1845584743223, 679.1341040173719, 682.5324652612877, 685.6400389795398, 693.0435998322283, 699.566268913277, 593.7476572456688, 647.346180357842, 654.4077609763171, 662.1656712542135, 675.4461341699857, 7486.9993112879265, 8657.523836188944, 8972.685955991508, 9130.406523575946, 9156.404833685923, 540.3434826685595, 577.9393163717833, 593.6289818863211, 646.0567571345888, 659.7492952677952, 3461.5153878457036, 4394.099209662011, 4395.7582332972, 4398.362237475548, 4443.822247110144, 70070.64964675246, 70180.64909675419, 109250.36124458139, 112866.43566782278, 119287.40356298337, 69684.30315696778, 103503.4824825871, 104676.95323046825, 107499.46250268699, 110801.44599277113, 57635.42364576385, 58001.0, 66359.67741129037, 77742.6112869432, 92231.0, 8447.831043379221, 8617.0, 8624.094470080801, 8670.436421632574, 8783.560821959005, 56689.43310566842, 57621.0, 58391.416085838595, 59863.92517384298, 60628.69685651544, 76428.0, 80474.0, 82905.58547207226, 83553.64463553597, 87537.68693469475, 11339.149563782914, 11481.024112950492, 11570.826437603275, 11816.586419475278, 11880.881191188324, 11957.103217258566, 12119.394030298627, 12154.878451215374, 12386.62840114798, 12446.626601201979, 10168.530101058446, 10635.734106647396, 11997.340146292037, 12094.334811585448, 12364.814527781911, 12625.368731563569, 13027.934860325639, 13047.28239946774, 13214.735705285648, 13528.72942541124, 12023.879761202626, 12282.692932676397, 12340.876591233973, 12538.498460061495, 12595.307258100525, 10218.948905255425, 10325.120207279942, 10485.0, 10740.677779666623, 10741.140708743438, 10567.63013294531, 10691.09125724291, 10937.343759374464, 10957.342559446459, 10980.341179529254], "value": 7234.974787661131, "unit": "score"}], "name": "dromaeo_css", "value": 7037.052632098704, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 115.0, 116.0, 114.0, 122.0, 170.0, 153.0, 114.0, 115.0, 114.0], "value": 124.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 143.0, 144.0, 148.0, 111.0, 113.0, 120.0, 141.0, 177.0, 128.0], "value": 138.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [184.0, 183.0, 183.0, 186.0, 191.0, 260.0, 184.0, 185.0, 182.0, 185.0], "value": 192.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 60.0, 58.0, 92.0, 62.0, 59.0, 60.0, 59.0, 82.0, 75.0], "value": 69.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 93.0, 127.0, 100.0, 67.0, 68.0, 88.0, 67.0, 68.0, 92.0], "value": 86.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 130.0, 99.0, 86.0, 134.0, 129.0, 91.0, 88.0, 90.0, 87.0], "value": 105.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [98.0, 97.0, 97.0, 97.0, 98.0, 98.0, 98.0, 97.0, 97.0, 97.0], "value": 97.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 83.0, 113.0, 134.0, 128.0, 83.0, 82.0, 84.0, 81.0, 85.0], "value": 95.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 54.0, 57.0, 56.0, 59.0, 59.0, 60.0, 60.0, 60.0, 61.0], "value": 58.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 74.0, 76.0, 75.0, 73.0, 71.0, 72.0, 73.0, 71.0, 73.0], "value": 72.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 83.0, 85.0, 85.0, 85.0, 85.0, 85.0, 84.0, 87.0, 85.0], "value": 84.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 146.0, 153.0, 146.0, 166.0, 158.0, 154.0, 151.0, 191.0, 174.0], "value": 158.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [169.0, 169.0, 169.0, 169.0, 167.0, 169.0, 168.0, 169.0, 168.0, 165.0], "value": 168.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 65.0, 57.0, 67.0, 85.0, 84.0, 86.0, 87.0, 73.0, 68.0], "value": 73.6, "unit": "score"}], "name": "kraken", "value": 1525.5, "alertThreshold": 2.0}]} 16:28:10 INFO - Return code: 0 16:28:10 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 16:28:10 INFO - # TBPL SUCCESS # 16:28:10 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 16:28:10 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 16:28:10 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 16:28:10 INFO - 2016-11-29 16:28:10.590 screenresolution[2604:23922] starting screenresolution argv=screenresolution get 16:28:10 INFO - 2016-11-29 16:28:10.592 screenresolution[2604:23922] Display 0: 1600x1200x32@60 16:28:10 INFO - 2016-11-29 16:28:10.599 screenresolution[2605:23925] starting screenresolution argv=screenresolution list 16:28:10 INFO - Available Modes on Display 0 16:28:10 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 16:28:10 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 16:28:10 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 16:28:10 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 16:28:10 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 16:28:10 INFO - Intel Iris: 16:28:10 INFO - Chipset Model: Intel Iris 16:28:10 INFO - Type: GPU 16:28:10 INFO - Bus: Built-In 16:28:10 INFO - VRAM (Dynamic, Max): 1536 MB 16:28:10 INFO - Vendor: Intel (0x8086) 16:28:10 INFO - Device ID: 0x0a2e 16:28:10 INFO - Revision ID: 0x0009 16:28:10 INFO - Displays: 16:28:10 INFO - Display: 16:28:10 INFO - Resolution: 1600 x 1200 @ 60 Hz 16:28:10 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 16:28:10 INFO - Display Serial Number: 1600x1200 60 16:28:10 INFO - Main Display: Yes 16:28:10 INFO - Mirror: Off 16:28:10 INFO - Online: Yes 16:28:10 INFO - Rotation: Supported 16:28:10 INFO - Return code: 0 16:28:10 INFO - Running post-action listener: _resource_record_post_action 16:28:10 INFO - [mozharness: 2016-11-30 00:28:10.844194Z] Finished run-tests step (success) 16:28:10 INFO - Running post-run listener: _resource_record_post_run 16:28:10 INFO - Total resource usage - Wall time: 1214s; CPU: 54.0%; Read bytes: 274432; Write bytes: 655981568; Read time: 3; Write time: 1882 16:28:10 INFO - TinderboxPrint: CPU usage
53.6% 16:28:10 INFO - TinderboxPrint: I/O read bytes / time
274,432 / 3 16:28:10 INFO - TinderboxPrint: I/O write bytes / time
655,981,568 / 1,882 16:28:10 INFO - TinderboxPrint: CPU idle
2,253.6 (46.4%) 16:28:10 INFO - TinderboxPrint: CPU system
978.1 (20.1%) 16:28:10 INFO - TinderboxPrint: CPU user
1,626.1 (33.5%) 16:28:10 INFO - TinderboxPrint: Swap in / out
348,213,248 / 0 16:28:10 INFO - install - Wall time: 32s; CPU: 55.0%; Read bytes: 335936000; Write bytes: 333699072; Read time: 26667; Write time: 693 16:28:10 INFO - run-tests - Wall time: 1183s; CPU: 54.0%; Read bytes: 274432; Write bytes: 304550912; Read time: 3; Write time: 1132 16:28:11 INFO - Running post-run listener: _upload_blobber_files 16:28:11 INFO - Blob upload gear active. 16:28:11 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 16:28:11 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 16:28:11 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'] 16:28:11 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 16:28:11 INFO - (blobuploader) - INFO - Open directory for files ... 16:28:11 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 16:28:11 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:28:11 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:28:12 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 16:28:12 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:28:12 INFO - (blobuploader) - INFO - Done attempting. 16:28:12 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 16:28:12 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:28:12 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:28:13 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 16:28:13 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:28:13 INFO - (blobuploader) - INFO - Done attempting. 16:28:13 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 16:28:13 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:28:13 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:28:16 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 16:28:16 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:28:16 INFO - (blobuploader) - INFO - Done attempting. 16:28:16 INFO - (blobuploader) - INFO - Iteration through files over. 16:28:16 INFO - Return code: 0 16:28:16 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 16:28:16 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 16:28:16 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/94baa55d691eadcae8118f3b31ae965f6e2e9c73c6fbc15c0c6e3d346c3e747eb99fae9ebf9d9c087aabe298ae237dc674b5487d07bb36d43a0dc9c30e81afec", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/9bd1613f7e901d5e2b9e4296ccb09c9b762c08a11a68a08fa7d0471a826164c45ea22660ed0c42b6c5d0e389cbf8211d692cd321b39940b1908a742462d6d5bf", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c220bd9a01be1815feb2a934eeaa27aa5ddd3b99e74e7b544b2a089606aad27ee24dde05a3c4c6d21e1dff0f2abc855ad4f23b879b9fc483050cef4428e3269c"} 16:28:16 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 16:28:16 INFO - Writing to file /builds/slave/test/properties/blobber_files 16:28:16 INFO - Contents: 16:28:16 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/94baa55d691eadcae8118f3b31ae965f6e2e9c73c6fbc15c0c6e3d346c3e747eb99fae9ebf9d9c087aabe298ae237dc674b5487d07bb36d43a0dc9c30e81afec", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/9bd1613f7e901d5e2b9e4296ccb09c9b762c08a11a68a08fa7d0471a826164c45ea22660ed0c42b6c5d0e389cbf8211d692cd321b39940b1908a742462d6d5bf", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c220bd9a01be1815feb2a934eeaa27aa5ddd3b99e74e7b544b2a089606aad27ee24dde05a3c4c6d21e1dff0f2abc855ad4f23b879b9fc483050cef4428e3269c"} 16:28:16 INFO - Running post-run listener: copy_logs_to_upload_dir 16:28:16 INFO - Copying logs to upload dir... 16:28:16 INFO - mkdir: /builds/slave/test/build/upload/logs 16:28:16 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1274.589945 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 14 secs) (at 2016-11-29 16:28:16.621071) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-29 16:28:16.624439) ========= 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.XP3Fhzc5Xj/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.47dfj7GoKz/Listeners TMPDIR=/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/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/94baa55d691eadcae8118f3b31ae965f6e2e9c73c6fbc15c0c6e3d346c3e747eb99fae9ebf9d9c087aabe298ae237dc674b5487d07bb36d43a0dc9c30e81afec", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/9bd1613f7e901d5e2b9e4296ccb09c9b762c08a11a68a08fa7d0471a826164c45ea22660ed0c42b6c5d0e389cbf8211d692cd321b39940b1908a742462d6d5bf", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c220bd9a01be1815feb2a934eeaa27aa5ddd3b99e74e7b544b2a089606aad27ee24dde05a3c4c6d21e1dff0f2abc855ad4f23b879b9fc483050cef4428e3269c"} build_url:https://queue.taskcluster.net/v1/task/MMEi2XmgQfGQkhleRYTTVQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011316 build_url: 'https://queue.taskcluster.net/v1/task/MMEi2XmgQfGQkhleRYTTVQ/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/94baa55d691eadcae8118f3b31ae965f6e2e9c73c6fbc15c0c6e3d346c3e747eb99fae9ebf9d9c087aabe298ae237dc674b5487d07bb36d43a0dc9c30e81afec", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/9bd1613f7e901d5e2b9e4296ccb09c9b762c08a11a68a08fa7d0471a826164c45ea22660ed0c42b6c5d0e389cbf8211d692cd321b39940b1908a742462d6d5bf", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c220bd9a01be1815feb2a934eeaa27aa5ddd3b99e74e7b544b2a089606aad27ee24dde05a3c4c6d21e1dff0f2abc855ad4f23b879b9fc483050cef4428e3269c"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-29 16:28:16.660129) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-29 16:28:16.660471) ========= 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.XP3Fhzc5Xj/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.47dfj7GoKz/Listeners TMPDIR=/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/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.005351 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-29 16:28:16.708893) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-29 16:28:16.709235) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-29 16:28:16.709548) ========= ========= Total master_lag: 0.19 =========