builder: fx-team_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0278 starttime: 1475230176.49 results: success (0) buildid: 20160930005519 builduid: ee5614aa0fa9436fa004a92ca5b03095 revision: 5bf0ee1a10f44ef12f13bfe03f58726feffa59a1 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-09-30 03:09:36.487185) ========= master: http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-09-30 03:09:36.487656) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-30 03:09:36.487945) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-30 03:09:36.528777) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-30 03:09:36.529245) ========= 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.QBha3K4Xwx/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.erbt3jYA3j/Listeners TMPDIR=/var/folders/jw/lfbylzg17jd15qryjvmblvym00000w/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-09-30 03:09:36-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 18.9M=0.001s 2016-09-30 03:09:36 (18.9 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.189888 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-30 03:09:36.741540) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-30 03:09:36.741865) ========= 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.QBha3K4Xwx/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.erbt3jYA3j/Listeners TMPDIR=/var/folders/jw/lfbylzg17jd15qryjvmblvym00000w/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.040452 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-30 03:09:36.802500) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-30 03:09:36.802871) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 5bf0ee1a10f44ef12f13bfe03f58726feffa59a1 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/fx-team --rev 5bf0ee1a10f44ef12f13bfe03f58726feffa59a1 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.QBha3K4Xwx/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.erbt3jYA3j/Listeners TMPDIR=/var/folders/jw/lfbylzg17jd15qryjvmblvym00000w/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-09-30 03:09:36,883 truncating revision to first 12 chars 2016-09-30 03:09:36,883 Setting DEBUG logging. 2016-09-30 03:09:36,883 attempt 1/10 2016-09-30 03:09:36,883 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/5bf0ee1a10f4?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-09-30 03:09:38,496 unpacking tar archive at: fx-team-5bf0ee1a10f4/testing/mozharness/ program finished with exit code 0 elapsedTime=1.889327 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-30 03:09:38.713787) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-30 03:09:38.714107) ========= script_repo_revision: 5bf0ee1a10f44ef12f13bfe03f58726feffa59a1 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-30 03:09:38.714532) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-30 03:09:38.714814) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-30 03:09:38.733947) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 49 secs) (at 2016-09-30 03:09:38.734220) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Fx-Team', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.QBha3K4Xwx/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.erbt3jYA3j/Listeners TMPDIR=/var/folders/jw/lfbylzg17jd15qryjvmblvym00000w/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 03:09:38 INFO - MultiFileLogger online at 20160930 03:09:38 in /builds/slave/test 03:09:38 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 03:09:38 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 03:09:38 INFO - {'append_to_log': False, 03:09:38 INFO - 'base_work_dir': '/builds/slave/test', 03:09:38 INFO - 'blob_upload_branch': 'Fx-Team', 03:09:38 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 03:09:38 INFO - 'branch': 'Fx-Team', 03:09:38 INFO - 'buildbot_json_path': 'buildprops.json', 03:09:38 INFO - 'config_files': ('talos/mac_config.py',), 03:09:38 INFO - 'default_actions': ('clobber', 03:09:38 INFO - 'read-buildbot-config', 03:09:38 INFO - 'download-and-extract', 03:09:38 INFO - 'populate-webroot', 03:09:38 INFO - 'create-virtualenv', 03:09:38 INFO - 'install', 03:09:38 INFO - 'run-tests'), 03:09:38 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 03:09:38 INFO - 'download_minidump_stackwalk': True, 03:09:38 INFO - 'download_symbols': 'ondemand', 03:09:38 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 03:09:38 INFO - 'tooltool.py': '/tools/tooltool.py', 03:09:38 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 03:09:38 INFO - '/tools/misc-python/virtualenv.py')}, 03:09:38 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 03:09:38 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 03:09:38 INFO - 'installer_path': 'installer.exe', 03:09:38 INFO - 'log_level': 'info', 03:09:38 INFO - 'log_name': 'talos', 03:09:38 INFO - 'log_to_console': True, 03:09:38 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 03:09:38 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 03:09:38 INFO - 'opt_config_files': (), 03:09:38 INFO - 'pip_index': False, 03:09:38 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 03:09:38 INFO - 'cmd': ('bash', 03:09:38 INFO - '-c', 03:09:38 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 03:09:38 INFO - 'enabled': True, 03:09:38 INFO - 'halt_on_failure': False, 03:09:38 INFO - 'name': 'check_screen_resolution'},), 03:09:38 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 03:09:38 INFO - 'cmd': ('bash', 03:09:38 INFO - '-c', 03:09:38 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 03:09:38 INFO - 'enabled': True, 03:09:38 INFO - 'halt_on_failure': False, 03:09:38 INFO - 'name': 'check_screen_resolution'},), 03:09:38 INFO - 'run_cmd_checks_enabled': True, 03:09:38 INFO - 'sps_profile': False, 03:09:38 INFO - 'sps_profile_interval': 0, 03:09:38 INFO - 'suite': 'dromaeojs', 03:09:38 INFO - 'system_bits': '32', 03:09:38 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 03:09:38 INFO - 'title': 't-yosemite-r7-0278', 03:09:38 INFO - 'tooltool_cache': '/builds/tooltool_cache', 03:09:38 INFO - 'use_talos_json': True, 03:09:38 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 03:09:38 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 03:09:38 INFO - 'work_dir': 'build'} 03:09:38 INFO - [mozharness: 2016-09-30 10:09:38.935889Z] Running clobber step. 03:09:38 INFO - Running pre-action listener: _resource_record_pre_action 03:09:38 INFO - Running main action method: clobber 03:09:38 INFO - rmtree: /builds/slave/test/build 03:09:38 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 03:09:41 INFO - Running post-action listener: _resource_record_post_action 03:09:41 INFO - [mozharness: 2016-09-30 10:09:41.017400Z] Finished clobber step (success) 03:09:41 INFO - [mozharness: 2016-09-30 10:09:41.017712Z] Running read-buildbot-config step. 03:09:41 INFO - Running pre-action listener: _resource_record_pre_action 03:09:41 INFO - Running main action method: read_buildbot_config 03:09:41 INFO - Using buildbot properties: 03:09:41 INFO - { 03:09:41 INFO - "project": "", 03:09:41 INFO - "product": "firefox", 03:09:41 INFO - "script_repo_revision": "production", 03:09:41 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs", 03:09:41 INFO - "repository": "", 03:09:41 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs", 03:09:41 INFO - "buildid": "20160930005519", 03:09:41 INFO - "pgo_build": "False", 03:09:41 INFO - "basedir": "/builds/slave/test", 03:09:41 INFO - "buildnumber": 175, 03:09:41 INFO - "slavename": "t-yosemite-r7-0278", 03:09:41 INFO - "master": "http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/", 03:09:41 INFO - "platform": "yosemite_r7", 03:09:41 INFO - "scheduler": "tests-fx-team-macosx64-talos", 03:09:41 INFO - "branch": "fx-team", 03:09:41 INFO - "repo_path": "integration/fx-team", 03:09:41 INFO - "revision": "5bf0ee1a10f44ef12f13bfe03f58726feffa59a1", 03:09:41 INFO - "stage_platform": "macosx64", 03:09:41 INFO - "builduid": "ee5614aa0fa9436fa004a92ca5b03095", 03:09:41 INFO - "slavebuilddir": "test" 03:09:41 INFO - } 03:09:41 INFO - Found installer url https://queue.taskcluster.net/v1/task/Oa1drpckQO-XF4tPHFgqxQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg. 03:09:41 INFO - Running post-action listener: _resource_record_post_action 03:09:41 INFO - [mozharness: 2016-09-30 10:09:41.024307Z] Finished read-buildbot-config step (success) 03:09:41 INFO - [mozharness: 2016-09-30 10:09:41.024542Z] Running download-and-extract step. 03:09:41 INFO - Running pre-action listener: _resource_record_pre_action 03:09:41 INFO - Running main action method: download_and_extract 03:09:41 INFO - mkdir: /builds/slave/test/build/tests 03:09:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:09:41 INFO - trying https://queue.taskcluster.net/v1/task/Oa1drpckQO-XF4tPHFgqxQ/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json 03:09:41 INFO - Downloading https://queue.taskcluster.net/v1/task/Oa1drpckQO-XF4tPHFgqxQ/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json 03:09:41 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Oa1drpckQO-XF4tPHFgqxQ/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json'}, attempt #1 03:09:42 INFO - Downloaded 1199 bytes. 03:09:42 INFO - Reading from file /builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json 03:09:42 INFO - Using the following test package requirements: 03:09:42 INFO - {u'common': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 03:09:42 INFO - u'cppunittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 03:09:42 INFO - u'firefox-52.0a1.en-US.mac.cppunittest.tests.zip'], 03:09:42 INFO - u'gtest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 03:09:42 INFO - u'firefox-52.0a1.en-US.mac.gtest.tests.zip'], 03:09:42 INFO - u'jittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 03:09:42 INFO - u'jsshell-mac.zip'], 03:09:42 INFO - u'mochitest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 03:09:42 INFO - u'firefox-52.0a1.en-US.mac.mochitest.tests.zip'], 03:09:42 INFO - u'mozbase': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 03:09:42 INFO - u'reftest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 03:09:42 INFO - u'firefox-52.0a1.en-US.mac.reftest.tests.zip'], 03:09:42 INFO - u'talos': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 03:09:42 INFO - u'firefox-52.0a1.en-US.mac.talos.tests.zip'], 03:09:42 INFO - u'web-platform': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 03:09:42 INFO - u'firefox-52.0a1.en-US.mac.web-platform.tests.zip'], 03:09:42 INFO - u'xpcshell': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 03:09:42 INFO - u'firefox-52.0a1.en-US.mac.xpcshell.tests.zip']} 03:09:42 INFO - Downloading packages: [u'firefox-52.0a1.en-US.mac.common.tests.zip'] for test suite category: common 03:09:42 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Oa1drpckQO-XF4tPHFgqxQ/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 03:09:42 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Oa1drpckQO-XF4tPHFgqxQ/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 03:09:42 INFO - Fetch https://queue.taskcluster.net/v1/task/Oa1drpckQO-XF4tPHFgqxQ/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 03:09:42 INFO - Http code: 200 03:09:42 INFO - Content-Encoding: None 03:09:42 INFO - Content-Type: application/zip 03:09:42 INFO - via: 1.1 49c80a47c1441dd194a8337982f1cd7e.cloudfront.net (CloudFront) 03:09:42 INFO - x-amz-cf-id: drgD2CB8zDsUhU1ur7uZNZ0LnIpCI9Jqp-OVl09Gzt9oTWOEh5F5-g== 03:09:42 INFO - x-amz-version-id: fQjZ9zBkQjQFKw8VFM1D42tuXz0.Vcup 03:09:42 INFO - x-cache: Hit from cloudfront 03:10:18 INFO - Expected file size: 16473434 03:10:18 INFO - Obtained file size: 16473434 03:10:25 INFO - Downloading packages: [u'firefox-52.0a1.en-US.mac.common.tests.zip', u'firefox-52.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 03:10:25 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Oa1drpckQO-XF4tPHFgqxQ/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 03:10:25 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Oa1drpckQO-XF4tPHFgqxQ/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 03:10:25 INFO - Fetch https://queue.taskcluster.net/v1/task/Oa1drpckQO-XF4tPHFgqxQ/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 03:10:25 INFO - Http code: 200 03:10:25 INFO - Content-Encoding: None 03:10:25 INFO - Content-Type: application/zip 03:10:25 INFO - via: 1.1 66ed69e8b47ad05050331602c798132f.cloudfront.net (CloudFront) 03:10:25 INFO - x-amz-cf-id: U32Jf3bFo0EwopBO_wxPtY0DyzkDiJcJAgRs56humqQzHJjRsN-gDA== 03:10:25 INFO - x-amz-version-id: fQjZ9zBkQjQFKw8VFM1D42tuXz0.Vcup 03:10:25 INFO - x-cache: Hit from cloudfront 03:10:26 INFO - Expected file size: 16473434 03:10:26 INFO - Obtained file size: 16473434 03:10:32 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Oa1drpckQO-XF4tPHFgqxQ/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip 03:10:32 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Oa1drpckQO-XF4tPHFgqxQ/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip'}, attempt #1 03:10:32 INFO - Fetch https://queue.taskcluster.net/v1/task/Oa1drpckQO-XF4tPHFgqxQ/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip into memory 03:10:32 INFO - Http code: 200 03:10:32 INFO - Content-Encoding: None 03:10:32 INFO - Content-Type: application/zip 03:10:32 INFO - via: 1.1 73fa5a1ee49cf827e596b502b5927eab.cloudfront.net (CloudFront) 03:10:32 INFO - x-amz-cf-id: bJDpemlqPLvBlKluLzxXAMoUf0dF-mJVopsjtO45NPqn_mdaj4G0Ng== 03:10:32 INFO - x-amz-version-id: E6p2woQvSWSb8a81.mFqqAVFuxwvSCi9 03:10:32 INFO - x-cache: Hit from cloudfront 03:10:33 INFO - Expected file size: 14414067 03:10:33 INFO - Obtained file size: 14414067 03:10:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:10:34 INFO - trying https://queue.taskcluster.net/v1/task/Oa1drpckQO-XF4tPHFgqxQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 03:10:34 INFO - Downloading https://queue.taskcluster.net/v1/task/Oa1drpckQO-XF4tPHFgqxQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg 03:10:34 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Oa1drpckQO-XF4tPHFgqxQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg'}, attempt #1 03:10:37 INFO - Downloaded 111809301 bytes. 03:10:37 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Oa1drpckQO-XF4tPHFgqxQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 03:10:37 INFO - mkdir: /builds/slave/test/properties 03:10:37 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 03:10:37 INFO - Writing to file /builds/slave/test/properties/build_url 03:10:37 INFO - Contents: 03:10:37 INFO - build_url:https://queue.taskcluster.net/v1/task/Oa1drpckQO-XF4tPHFgqxQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 03:10:38 INFO - Running post-action listener: _resource_record_post_action 03:10:38 INFO - Running post-action listener: set_extra_try_arguments 03:10:38 INFO - [mozharness: 2016-09-30 10:10:38.094928Z] Finished download-and-extract step (success) 03:10:38 INFO - [mozharness: 2016-09-30 10:10:38.095044Z] Running populate-webroot step. 03:10:38 INFO - Running pre-action listener: _resource_record_pre_action 03:10:38 INFO - Running main action method: populate_webroot 03:10:38 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 03:10:38 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 03:10:38 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 03:10:38 INFO - '--tppagecycles', 03:10:38 INFO - '7'], 03:10:38 INFO - 'tests': ['tsvgm']}}, 03:10:38 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 03:10:38 INFO - 'tests': ['tresize', 'tcanvasmark']}, 03:10:38 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 03:10:38 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 03:10:38 INFO - 'tests': ['dromaeo_css', 'kraken']}, 03:10:38 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 03:10:38 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:10:38 INFO - 'talos_options': ['--disable-e10s'], 03:10:38 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:10:38 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:10:38 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:10:38 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:10:38 INFO - 'talos_options': ['--disable-e10s'], 03:10:38 INFO - 'tests': ['damp', 'tps']}, 03:10:38 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:10:38 INFO - 'tests': ['damp', 'tps']}, 03:10:38 INFO - 'g3': {'talos_options': ['--disable-e10s'], 03:10:38 INFO - 'tests': ['dromaeo_dom']}, 03:10:38 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 03:10:38 INFO - 'g4': {'talos_options': ['--disable-e10s'], 03:10:38 INFO - 'tests': ['basic_compositor_video']}, 03:10:38 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 03:10:38 INFO - 'other': {'talos_options': ['--disable-e10s'], 03:10:38 INFO - 'tests': ['a11yr', 03:10:38 INFO - 'ts_paint', 03:10:38 INFO - 'tpaint', 03:10:38 INFO - 'sessionrestore', 03:10:38 INFO - 'sessionrestore_no_auto_restore', 03:10:38 INFO - 'tabpaint']}, 03:10:38 INFO - 'other-e10s': {'tests': ['a11yr', 03:10:38 INFO - 'ts_paint', 03:10:38 INFO - 'tpaint', 03:10:38 INFO - 'sessionrestore', 03:10:38 INFO - 'sessionrestore_no_auto_restore', 03:10:38 INFO - 'tabpaint']}, 03:10:38 INFO - 'other-e10s_l64': {'tests': ['a11yr', 03:10:38 INFO - 'ts_paint', 03:10:38 INFO - 'tpaint', 03:10:38 INFO - 'sessionrestore', 03:10:38 INFO - 'sessionrestore_no_auto_restore', 03:10:38 INFO - 'tabpaint']}, 03:10:38 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 03:10:38 INFO - 'ts_paint', 03:10:38 INFO - 'tpaint', 03:10:38 INFO - 'sessionrestore', 03:10:38 INFO - 'sessionrestore_no_auto_restore', 03:10:38 INFO - 'tabpaint']}, 03:10:38 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 03:10:38 INFO - 'tests': ['a11yr', 03:10:38 INFO - 'ts_paint', 03:10:38 INFO - 'tpaint', 03:10:38 INFO - 'sessionrestore', 03:10:38 INFO - 'sessionrestore_no_auto_restore', 03:10:38 INFO - 'tabpaint']}, 03:10:38 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 03:10:38 INFO - 'tests': ['a11yr', 03:10:38 INFO - 'ts_paint', 03:10:38 INFO - 'tpaint', 03:10:38 INFO - 'sessionrestore', 03:10:38 INFO - 'sessionrestore_no_auto_restore', 03:10:38 INFO - 'tabpaint']}, 03:10:38 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 03:10:38 INFO - 'tests': ['tsvgx', 03:10:38 INFO - 'tsvgr_opacity', 03:10:38 INFO - 'tart', 03:10:38 INFO - 'tscrollx', 03:10:38 INFO - 'cart']}, 03:10:38 INFO - 'svgr-e10s': {'tests': ['tsvgx', 03:10:38 INFO - 'tsvgr_opacity', 03:10:38 INFO - 'tart', 03:10:38 INFO - 'tscrollx', 03:10:38 INFO - 'cart']}, 03:10:38 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:10:38 INFO - 'talos_options': ['--disable-e10s'], 03:10:38 INFO - 'tests': ['tp5o']}, 03:10:38 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:10:38 INFO - 'tests': ['tp5o']}, 03:10:38 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:10:38 INFO - 'talos_options': ['--disable-e10s', 03:10:38 INFO - '--xperf_path', 03:10:38 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:10:38 INFO - 'tests': ['tp5n']}, 03:10:38 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:10:38 INFO - 'talos_options': ['--xperf_path', 03:10:38 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:10:38 INFO - 'tests': ['tp5n']}}, 03:10:38 INFO - 'talos.zip': {'path': '', 03:10:38 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 03:10:38 INFO - Running post-action listener: _resource_record_post_action 03:10:38 INFO - [mozharness: 2016-09-30 10:10:38.113742Z] Finished populate-webroot step (success) 03:10:38 INFO - [mozharness: 2016-09-30 10:10:38.113883Z] Running create-virtualenv step. 03:10:38 INFO - Running pre-action listener: _resource_record_pre_action 03:10:38 INFO - Running main action method: create_virtualenv 03:10:38 INFO - Creating virtualenv /builds/slave/test/build/venv 03:10:38 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 03:10:38 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 03:10:38 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 03:10:38 INFO - Using real prefix '/tools/python27' 03:10:38 INFO - New python executable in /builds/slave/test/build/venv/bin/python 03:10:38 INFO - Installing distribute.............................................................................................................................................................................................done. 03:10:41 INFO - Installing pip.................done. 03:10:41 INFO - Return code: 0 03:10:41 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 03:10:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 03:10:42 INFO - Reading from file tmpfile_stdout 03:10:42 INFO - Output received: 03:10:42 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) 03:10:42 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 03:10:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:10:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:10:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:10:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:10:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:10:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:10:42 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1103c3490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110339570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc59bc59800>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1101d7d30>, '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 0x10fffddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1101cfb20>, '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.QBha3K4Xwx/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.erbt3jYA3j/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jw/lfbylzg17jd15qryjvmblvym00000w/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 03:10:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 03:10:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 03:10:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.QBha3K4Xwx/Render', 03:10:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:10:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:10:42 INFO - 'HOME': '/Users/cltbld', 03:10:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:10:42 INFO - 'LOGNAME': 'cltbld', 03:10:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:10:42 INFO - 'MOZ_NO_REMOTE': '1', 03:10:42 INFO - 'NO_EM_RESTART': '1', 03:10:42 INFO - 'PAGER': '/bin/cat', 03:10:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:10:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:10:42 INFO - 'PWD': '/builds/slave/test', 03:10:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:10:42 INFO - 'SHELL': '/bin/bash', 03:10:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.erbt3jYA3j/Listeners', 03:10:42 INFO - 'TMPDIR': '/var/folders/jw/lfbylzg17jd15qryjvmblvym00000w/T/', 03:10:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:10:42 INFO - 'USER': 'cltbld', 03:10:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:10:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:10:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:10:42 INFO - 'XPC_FLAGS': '0x0', 03:10:42 INFO - 'XPC_SERVICE_NAME': '0', 03:10:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:10:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:10:42 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 03:10:42 INFO - Cleaning up... 03:10:42 INFO - Return code: 0 03:10:42 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 03:10:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:10:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:10:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:10:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:10:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:10:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:10:42 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1103c3490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110339570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc59bc59800>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1101d7d30>, '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 0x10fffddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1101cfb20>, '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.QBha3K4Xwx/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.erbt3jYA3j/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jw/lfbylzg17jd15qryjvmblvym00000w/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 03:10:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 03:10:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 03:10:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.QBha3K4Xwx/Render', 03:10:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:10:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:10:42 INFO - 'HOME': '/Users/cltbld', 03:10:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:10:42 INFO - 'LOGNAME': 'cltbld', 03:10:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:10:42 INFO - 'MOZ_NO_REMOTE': '1', 03:10:42 INFO - 'NO_EM_RESTART': '1', 03:10:42 INFO - 'PAGER': '/bin/cat', 03:10:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:10:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:10:42 INFO - 'PWD': '/builds/slave/test', 03:10:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:10:42 INFO - 'SHELL': '/bin/bash', 03:10:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.erbt3jYA3j/Listeners', 03:10:42 INFO - 'TMPDIR': '/var/folders/jw/lfbylzg17jd15qryjvmblvym00000w/T/', 03:10:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:10:42 INFO - 'USER': 'cltbld', 03:10:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:10:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:10:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:10:42 INFO - 'XPC_FLAGS': '0x0', 03:10:42 INFO - 'XPC_SERVICE_NAME': '0', 03:10:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:10:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:10:42 INFO - Downloading/unpacking psutil>=3.1.1 03:10:42 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 03:10:42 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 03:10:42 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 03:10:42 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 03:10:42 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 03:10:42 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 03:10:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 03:10:46 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:10:46 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:10:46 INFO - Installing collected packages: psutil 03:10:46 INFO - Running setup.py install for psutil 03:10:46 INFO - building 'psutil._psutil_osx' extension 03:10:46 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 03:10:46 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 03:10:47 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 03:10:47 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 03:10:47 INFO - building 'psutil._psutil_posix' extension 03:10:47 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 03:10:47 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 03:10:47 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 03:10:47 INFO - ^ 03:10:47 INFO - 1 warning generated. 03:10:47 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 03:10:47 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:10:47 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:10:47 INFO - Successfully installed psutil 03:10:47 INFO - Cleaning up... 03:10:47 INFO - Return code: 0 03:10:47 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 03:10:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:10:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:10:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:10:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:10:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:10:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:10:47 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1103c3490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110339570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc59bc59800>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1101d7d30>, '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 0x10fffddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1101cfb20>, '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.QBha3K4Xwx/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.erbt3jYA3j/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jw/lfbylzg17jd15qryjvmblvym00000w/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 03:10:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 03:10:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 03:10:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.QBha3K4Xwx/Render', 03:10:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:10:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:10:47 INFO - 'HOME': '/Users/cltbld', 03:10:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:10:47 INFO - 'LOGNAME': 'cltbld', 03:10:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:10:47 INFO - 'MOZ_NO_REMOTE': '1', 03:10:47 INFO - 'NO_EM_RESTART': '1', 03:10:47 INFO - 'PAGER': '/bin/cat', 03:10:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:10:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:10:47 INFO - 'PWD': '/builds/slave/test', 03:10:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:10:47 INFO - 'SHELL': '/bin/bash', 03:10:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.erbt3jYA3j/Listeners', 03:10:47 INFO - 'TMPDIR': '/var/folders/jw/lfbylzg17jd15qryjvmblvym00000w/T/', 03:10:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:10:47 INFO - 'USER': 'cltbld', 03:10:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:10:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:10:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:10:47 INFO - 'XPC_FLAGS': '0x0', 03:10:47 INFO - 'XPC_SERVICE_NAME': '0', 03:10:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:10:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:10:47 INFO - Downloading/unpacking mozsystemmonitor==0.3 03:10:47 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 03:10:47 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 03:10:47 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 03:10:47 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 03:10:47 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 03:10:47 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 03:10:50 INFO - Downloading mozsystemmonitor-0.3.tar.gz 03:10:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 03:10:50 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 03:10:50 INFO - Installing collected packages: mozsystemmonitor 03:10:50 INFO - Running setup.py install for mozsystemmonitor 03:10:50 INFO - Successfully installed mozsystemmonitor 03:10:50 INFO - Cleaning up... 03:10:50 INFO - Return code: 0 03:10:50 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 03:10: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')]} 03:10:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:10:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:10: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')]} 03:10:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:10:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:10:50 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 0x1103c3490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110339570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc59bc59800>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1101d7d30>, '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 0x10fffddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1101cfb20>, '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.QBha3K4Xwx/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.erbt3jYA3j/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jw/lfbylzg17jd15qryjvmblvym00000w/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 03:10:50 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 03:10:50 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 03:10:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.QBha3K4Xwx/Render', 03:10:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:10:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:10:50 INFO - 'HOME': '/Users/cltbld', 03:10:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:10:50 INFO - 'LOGNAME': 'cltbld', 03:10:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:10:50 INFO - 'MOZ_NO_REMOTE': '1', 03:10:50 INFO - 'NO_EM_RESTART': '1', 03:10:50 INFO - 'PAGER': '/bin/cat', 03:10:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:10:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:10:50 INFO - 'PWD': '/builds/slave/test', 03:10:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:10:50 INFO - 'SHELL': '/bin/bash', 03:10:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.erbt3jYA3j/Listeners', 03:10:50 INFO - 'TMPDIR': '/var/folders/jw/lfbylzg17jd15qryjvmblvym00000w/T/', 03:10:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:10:50 INFO - 'USER': 'cltbld', 03:10:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:10:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:10:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:10:50 INFO - 'XPC_FLAGS': '0x0', 03:10:50 INFO - 'XPC_SERVICE_NAME': '0', 03:10:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:10:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:10:50 INFO - Downloading/unpacking jsonschema==2.5.1 03:10:50 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 03:10:50 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 03:10:50 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 03:10:50 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 03:10:50 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 03:10:50 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 03:10:53 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 03:10:53 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 03:10:53 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 03:10:53 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 03:10:53 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 03:10:53 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 03:10:53 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 03:10:53 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 03:10:53 INFO - Downloading functools32-3.2.3-2.tar.gz 03:10:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 03:10:53 INFO - warning: no files found matching '*.txt' 03:10:53 INFO - no previously-included directories found matching 'build' 03:10:53 INFO - no previously-included directories found matching 'dist' 03:10:53 INFO - no previously-included directories found matching '.git*' 03:10:53 INFO - Installing collected packages: jsonschema, functools32 03:10:53 INFO - Running setup.py install for functools32 03:10:53 INFO - warning: no files found matching '*.txt' 03:10:53 INFO - no previously-included directories found matching 'build' 03:10:53 INFO - no previously-included directories found matching 'dist' 03:10:53 INFO - no previously-included directories found matching '.git*' 03:10:53 INFO - Successfully installed jsonschema functools32 03:10:53 INFO - Cleaning up... 03:10:54 INFO - Return code: 0 03:10:54 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 03:10:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:10:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:10:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:10:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:10:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:10:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:10:54 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 0x1103c3490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110339570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc59bc59800>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1101d7d30>, '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 0x10fffddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1101cfb20>, '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.QBha3K4Xwx/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.erbt3jYA3j/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jw/lfbylzg17jd15qryjvmblvym00000w/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 03:10:54 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 03:10:54 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 03:10:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.QBha3K4Xwx/Render', 03:10:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:10:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:10:54 INFO - 'HOME': '/Users/cltbld', 03:10:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:10:54 INFO - 'LOGNAME': 'cltbld', 03:10:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:10:54 INFO - 'MOZ_NO_REMOTE': '1', 03:10:54 INFO - 'NO_EM_RESTART': '1', 03:10:54 INFO - 'PAGER': '/bin/cat', 03:10:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:10:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:10:54 INFO - 'PWD': '/builds/slave/test', 03:10:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:10:54 INFO - 'SHELL': '/bin/bash', 03:10:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.erbt3jYA3j/Listeners', 03:10:54 INFO - 'TMPDIR': '/var/folders/jw/lfbylzg17jd15qryjvmblvym00000w/T/', 03:10:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:10:54 INFO - 'USER': 'cltbld', 03:10:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:10:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:10:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:10:54 INFO - 'XPC_FLAGS': '0x0', 03:10:54 INFO - 'XPC_SERVICE_NAME': '0', 03:10:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:10:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:10:54 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 03:10:54 INFO - Cleaning up... 03:10:54 INFO - Return code: 0 03:10:54 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 03:10:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:10:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:10:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:10:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:10:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:10:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:10:54 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 0x1103c3490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110339570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc59bc59800>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1101d7d30>, '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 0x10fffddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1101cfb20>, '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.QBha3K4Xwx/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.erbt3jYA3j/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jw/lfbylzg17jd15qryjvmblvym00000w/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 03:10:54 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 03:10:54 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 03:10:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.QBha3K4Xwx/Render', 03:10:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:10:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:10:54 INFO - 'HOME': '/Users/cltbld', 03:10:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:10:54 INFO - 'LOGNAME': 'cltbld', 03:10:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:10:54 INFO - 'MOZ_NO_REMOTE': '1', 03:10:54 INFO - 'NO_EM_RESTART': '1', 03:10:54 INFO - 'PAGER': '/bin/cat', 03:10:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:10:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:10:54 INFO - 'PWD': '/builds/slave/test', 03:10:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:10:54 INFO - 'SHELL': '/bin/bash', 03:10:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.erbt3jYA3j/Listeners', 03:10:54 INFO - 'TMPDIR': '/var/folders/jw/lfbylzg17jd15qryjvmblvym00000w/T/', 03:10:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:10:54 INFO - 'USER': 'cltbld', 03:10:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:10:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:10:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:10:54 INFO - 'XPC_FLAGS': '0x0', 03:10:54 INFO - 'XPC_SERVICE_NAME': '0', 03:10:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:10:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:10:54 INFO - Downloading/unpacking blobuploader==1.2.4 03:10:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:10:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:10:54 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:10:54 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:10:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:10:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:10:56 INFO - Downloading blobuploader-1.2.4.tar.gz 03:10:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 03:10:57 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 03:10:57 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 03:10:57 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 03:10:57 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 03:10:57 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 03:10:57 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 03:10:57 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 03:10:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 03:10:57 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 03:10:57 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 03:10:57 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 03:10:57 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 03:10:57 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 03:10:57 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 03:10:57 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 03:10:58 INFO - Downloading docopt-0.6.1.tar.gz 03:10:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 03:10:58 INFO - Installing collected packages: blobuploader, requests, docopt 03:10:58 INFO - Running setup.py install for blobuploader 03:10:58 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 03:10:58 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 03:10:58 INFO - Running setup.py install for requests 03:10:58 INFO - Running setup.py install for docopt 03:10:59 INFO - Successfully installed blobuploader requests docopt 03:10:59 INFO - Cleaning up... 03:10:59 INFO - Return code: 0 03:10:59 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:10:59 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')]} 03:10:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:10:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:10:59 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')]} 03:10:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:10:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:10:59 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 0x1103c3490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110339570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc59bc59800>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1101d7d30>, '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 0x10fffddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1101cfb20>, '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.QBha3K4Xwx/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.erbt3jYA3j/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jw/lfbylzg17jd15qryjvmblvym00000w/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 03:10:59 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 03:10:59 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 03:10:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.QBha3K4Xwx/Render', 03:10:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:10:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:10:59 INFO - 'HOME': '/Users/cltbld', 03:10:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:10:59 INFO - 'LOGNAME': 'cltbld', 03:10:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:10:59 INFO - 'MOZ_NO_REMOTE': '1', 03:10:59 INFO - 'NO_EM_RESTART': '1', 03:10:59 INFO - 'PAGER': '/bin/cat', 03:10:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:10:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:10:59 INFO - 'PWD': '/builds/slave/test', 03:10:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:10:59 INFO - 'SHELL': '/bin/bash', 03:10:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.erbt3jYA3j/Listeners', 03:10:59 INFO - 'TMPDIR': '/var/folders/jw/lfbylzg17jd15qryjvmblvym00000w/T/', 03:10:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:10:59 INFO - 'USER': 'cltbld', 03:10:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:10:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:10:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:10:59 INFO - 'XPC_FLAGS': '0x0', 03:10:59 INFO - 'XPC_SERVICE_NAME': '0', 03:10:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:10:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:10:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:10:59 INFO - Running setup.py (path:/var/folders/jw/lfbylzg17jd15qryjvmblvym00000w/T/pip-T2fY6c-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:10:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:10:59 INFO - Running setup.py (path:/var/folders/jw/lfbylzg17jd15qryjvmblvym00000w/T/pip-9hMfxM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:10:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:10:59 INFO - Running setup.py (path:/var/folders/jw/lfbylzg17jd15qryjvmblvym00000w/T/pip-v1KfpK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:10:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:10:59 INFO - Running setup.py (path:/var/folders/jw/lfbylzg17jd15qryjvmblvym00000w/T/pip-cBZ4V8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:10:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:10:59 INFO - Running setup.py (path:/var/folders/jw/lfbylzg17jd15qryjvmblvym00000w/T/pip-3i7aCn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:10:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:10:59 INFO - Running setup.py (path:/var/folders/jw/lfbylzg17jd15qryjvmblvym00000w/T/pip-1hYt2i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:11:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:11:00 INFO - Running setup.py (path:/var/folders/jw/lfbylzg17jd15qryjvmblvym00000w/T/pip-ZIbRSa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:11:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:11:00 INFO - Running setup.py (path:/var/folders/jw/lfbylzg17jd15qryjvmblvym00000w/T/pip-upGkE3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:11:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:11:00 INFO - Running setup.py (path:/var/folders/jw/lfbylzg17jd15qryjvmblvym00000w/T/pip-7mUtXE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:11:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:11:00 INFO - Running setup.py (path:/var/folders/jw/lfbylzg17jd15qryjvmblvym00000w/T/pip-eBHZKs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:11:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:11:00 INFO - Running setup.py (path:/var/folders/jw/lfbylzg17jd15qryjvmblvym00000w/T/pip-x5ObuY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:11:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:11:00 INFO - Running setup.py (path:/var/folders/jw/lfbylzg17jd15qryjvmblvym00000w/T/pip-2gFjtt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:11:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:11:00 INFO - Running setup.py (path:/var/folders/jw/lfbylzg17jd15qryjvmblvym00000w/T/pip-ismWwE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:11:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:11:00 INFO - Running setup.py (path:/var/folders/jw/lfbylzg17jd15qryjvmblvym00000w/T/pip-9UaAWg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:11:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:11:00 INFO - Running setup.py (path:/var/folders/jw/lfbylzg17jd15qryjvmblvym00000w/T/pip-ac8el7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:11:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:11:00 INFO - Running setup.py (path:/var/folders/jw/lfbylzg17jd15qryjvmblvym00000w/T/pip-VzMVYr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:11:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:11:01 INFO - Running setup.py (path:/var/folders/jw/lfbylzg17jd15qryjvmblvym00000w/T/pip-bpAENR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:11:01 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 03:11:01 INFO - Running setup.py install for manifestparser 03:11:01 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 03:11:01 INFO - Running setup.py install for mozcrash 03:11:01 INFO - Running setup.py install for mozdebug 03:11:01 INFO - Running setup.py install for mozdevice 03:11:01 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 03:11:01 INFO - Installing dm script to /builds/slave/test/build/venv/bin 03:11:01 INFO - Running setup.py install for mozfile 03:11:01 INFO - Running setup.py install for mozhttpd 03:11:02 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 03:11:02 INFO - Running setup.py install for mozinfo 03:11:02 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 03:11:02 INFO - Running setup.py install for mozInstall 03:11:02 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 03:11:02 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 03:11:02 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 03:11:02 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 03:11:02 INFO - Running setup.py install for mozleak 03:11:02 INFO - Running setup.py install for mozlog 03:11:02 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 03:11:02 INFO - Running setup.py install for moznetwork 03:11:02 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 03:11:02 INFO - Running setup.py install for mozprocess 03:11:02 INFO - Running setup.py install for mozprofile 03:11:03 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 03:11:03 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 03:11:03 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 03:11:03 INFO - Running setup.py install for mozrunner 03:11:03 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 03:11:03 INFO - Running setup.py install for mozscreenshot 03:11:03 INFO - Running setup.py install for moztest 03:11:03 INFO - Running setup.py install for mozversion 03:11:03 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 03:11:03 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 03:11:03 INFO - Cleaning up... 03:11:03 INFO - Return code: 0 03:11:03 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:11: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')]} 03:11:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:11:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:11: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')]} 03:11:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:11:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:11:03 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 0x1103c3490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110339570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc59bc59800>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1101d7d30>, '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 0x10fffddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1101cfb20>, '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.QBha3K4Xwx/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.erbt3jYA3j/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jw/lfbylzg17jd15qryjvmblvym00000w/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 03:11:03 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 03:11:03 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 03:11:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.QBha3K4Xwx/Render', 03:11:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:11:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:11:03 INFO - 'HOME': '/Users/cltbld', 03:11:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:11:03 INFO - 'LOGNAME': 'cltbld', 03:11:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:11:03 INFO - 'MOZ_NO_REMOTE': '1', 03:11:03 INFO - 'NO_EM_RESTART': '1', 03:11:03 INFO - 'PAGER': '/bin/cat', 03:11:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:11:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:11:03 INFO - 'PWD': '/builds/slave/test', 03:11:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:11:03 INFO - 'SHELL': '/bin/bash', 03:11:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.erbt3jYA3j/Listeners', 03:11:03 INFO - 'TMPDIR': '/var/folders/jw/lfbylzg17jd15qryjvmblvym00000w/T/', 03:11:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:11:03 INFO - 'USER': 'cltbld', 03:11:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:11:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:11:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:11:03 INFO - 'XPC_FLAGS': '0x0', 03:11:03 INFO - 'XPC_SERVICE_NAME': '0', 03:11:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:11:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:11:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:11:04 INFO - Running setup.py (path:/var/folders/jw/lfbylzg17jd15qryjvmblvym00000w/T/pip-_LmVcc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:11:04 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)) 03:11:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:11:04 INFO - Running setup.py (path:/var/folders/jw/lfbylzg17jd15qryjvmblvym00000w/T/pip-J5wz2C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:11:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 03:11:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:11:04 INFO - Running setup.py (path:/var/folders/jw/lfbylzg17jd15qryjvmblvym00000w/T/pip-xcSatp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:11:04 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)) 03:11:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:11:04 INFO - Running setup.py (path:/var/folders/jw/lfbylzg17jd15qryjvmblvym00000w/T/pip-HYY1Mr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:11:04 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)) 03:11:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:11:04 INFO - Running setup.py (path:/var/folders/jw/lfbylzg17jd15qryjvmblvym00000w/T/pip-4xZT9F-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:11:04 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)) 03:11:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:11:04 INFO - Running setup.py (path:/var/folders/jw/lfbylzg17jd15qryjvmblvym00000w/T/pip-hOyUyH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:11:04 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)) 03:11:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:11:04 INFO - Running setup.py (path:/var/folders/jw/lfbylzg17jd15qryjvmblvym00000w/T/pip-lvhVsa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:11:04 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)) 03:11:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:11:04 INFO - Running setup.py (path:/var/folders/jw/lfbylzg17jd15qryjvmblvym00000w/T/pip-K9HA9A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:11:04 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)) 03:11:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:11:04 INFO - Running setup.py (path:/var/folders/jw/lfbylzg17jd15qryjvmblvym00000w/T/pip-0MRb6A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:11:04 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)) 03:11:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:11:04 INFO - Running setup.py (path:/var/folders/jw/lfbylzg17jd15qryjvmblvym00000w/T/pip-Y2jvr2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:11:05 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)) 03:11:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:11:05 INFO - Running setup.py (path:/var/folders/jw/lfbylzg17jd15qryjvmblvym00000w/T/pip-DO0PC4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:11:05 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)) 03:11:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:11:05 INFO - Running setup.py (path:/var/folders/jw/lfbylzg17jd15qryjvmblvym00000w/T/pip-EAuLGg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:11:05 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)) 03:11:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:11:05 INFO - Running setup.py (path:/var/folders/jw/lfbylzg17jd15qryjvmblvym00000w/T/pip-TR_Dmh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:11:05 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)) 03:11:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:11:05 INFO - Running setup.py (path:/var/folders/jw/lfbylzg17jd15qryjvmblvym00000w/T/pip-tx1pV9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:11:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 03:11:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:11:05 INFO - Running setup.py (path:/var/folders/jw/lfbylzg17jd15qryjvmblvym00000w/T/pip-rh5Lmu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:11:05 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)) 03:11:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:11:05 INFO - Running setup.py (path:/var/folders/jw/lfbylzg17jd15qryjvmblvym00000w/T/pip-OdI7ZR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:11:05 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)) 03:11:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:11:05 INFO - Running setup.py (path:/var/folders/jw/lfbylzg17jd15qryjvmblvym00000w/T/pip-KGAA2j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:11:05 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)) 03:11:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 03:11:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 03:11:05 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)) 03:11:05 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)) 03:11:05 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)) 03:11:05 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 03:11:05 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 03:11:05 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 03:11:05 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 03:11:05 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 03:11:05 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 03:11:05 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 03:11:08 INFO - Downloading blessings-1.6.tar.gz 03:11:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 03:11:08 INFO - Installing collected packages: blessings 03:11:08 INFO - Running setup.py install for blessings 03:11:08 INFO - Successfully installed blessings 03:11:08 INFO - Cleaning up... 03:11:08 INFO - Return code: 0 03:11:08 INFO - Done creating virtualenv /builds/slave/test/build/venv. 03:11:08 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:11:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:11:08 INFO - Reading from file tmpfile_stdout 03:11:08 INFO - Current package versions: 03:11:08 INFO - blessings == 1.6 03:11:08 INFO - blobuploader == 1.2.4 03:11:08 INFO - docopt == 0.6.1 03:11:08 INFO - functools32 == 3.2.3-2 03:11:08 INFO - jsonschema == 2.5.1 03:11:08 INFO - manifestparser == 1.1 03:11:08 INFO - mozInstall == 1.12 03:11:08 INFO - mozcrash == 0.17 03:11:08 INFO - mozdebug == 0.1 03:11:08 INFO - mozdevice == 0.48 03:11:08 INFO - mozfile == 1.2 03:11:08 INFO - mozhttpd == 0.7 03:11:08 INFO - mozinfo == 0.9 03:11:08 INFO - mozleak == 0.1 03:11:08 INFO - mozlog == 3.4 03:11:08 INFO - moznetwork == 0.27 03:11:08 INFO - mozprocess == 0.23 03:11:08 INFO - mozprofile == 0.28 03:11:08 INFO - mozrunner == 6.12 03:11:08 INFO - mozscreenshot == 0.1 03:11:08 INFO - mozsystemmonitor == 0.3 03:11:08 INFO - moztest == 0.7 03:11:08 INFO - mozversion == 1.4 03:11:08 INFO - psutil == 3.1.1 03:11:08 INFO - requests == 1.2.3 03:11:08 INFO - wsgiref == 0.1.2 03:11:08 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:11:08 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')]} 03:11:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:11:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:11:08 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')]} 03:11:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:11:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:11:08 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 0x1103c3490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110339570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc59bc59800>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1101d7d30>, '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 0x10fffddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1101cfb20>, '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.QBha3K4Xwx/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.erbt3jYA3j/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jw/lfbylzg17jd15qryjvmblvym00000w/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 03:11:08 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 03:11:08 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 03:11:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.QBha3K4Xwx/Render', 03:11:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:11:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:11:08 INFO - 'HOME': '/Users/cltbld', 03:11:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:11:08 INFO - 'LOGNAME': 'cltbld', 03:11:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:11:08 INFO - 'MOZ_NO_REMOTE': '1', 03:11:08 INFO - 'NO_EM_RESTART': '1', 03:11:08 INFO - 'PAGER': '/bin/cat', 03:11:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:11:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:11:08 INFO - 'PWD': '/builds/slave/test', 03:11:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:11:08 INFO - 'SHELL': '/bin/bash', 03:11:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.erbt3jYA3j/Listeners', 03:11:08 INFO - 'TMPDIR': '/var/folders/jw/lfbylzg17jd15qryjvmblvym00000w/T/', 03:11:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:11:08 INFO - 'USER': 'cltbld', 03:11:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:11:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:11:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:11:08 INFO - 'XPC_FLAGS': '0x0', 03:11:08 INFO - 'XPC_SERVICE_NAME': '0', 03:11:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:11:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:11:09 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)) 03:11:09 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)) 03:11:09 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)) 03:11:09 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)) 03:11:09 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)) 03:11:09 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)) 03:11:09 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)) 03:11:09 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)) 03:11:09 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)) 03:11:09 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)) 03:11:09 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)) 03:11:09 INFO - Cleaning up... 03:11:09 INFO - Return code: 0 03:11:09 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 03:11:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:11:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:11:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:11:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:11:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:11:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:11:09 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1103c3490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110339570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc59bc59800>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1101d7d30>, '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 0x10fffddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1101cfb20>, '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.QBha3K4Xwx/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.erbt3jYA3j/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jw/lfbylzg17jd15qryjvmblvym00000w/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 03:11:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 03:11:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 03:11:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.QBha3K4Xwx/Render', 03:11:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:11:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:11:09 INFO - 'HOME': '/Users/cltbld', 03:11:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:11:09 INFO - 'LOGNAME': 'cltbld', 03:11:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:11:09 INFO - 'MOZ_NO_REMOTE': '1', 03:11:09 INFO - 'NO_EM_RESTART': '1', 03:11:09 INFO - 'PAGER': '/bin/cat', 03:11:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:11:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:11:09 INFO - 'PWD': '/builds/slave/test', 03:11:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:11:09 INFO - 'SHELL': '/bin/bash', 03:11:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.erbt3jYA3j/Listeners', 03:11:09 INFO - 'TMPDIR': '/var/folders/jw/lfbylzg17jd15qryjvmblvym00000w/T/', 03:11:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:11:09 INFO - 'USER': 'cltbld', 03:11:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:11:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:11:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:11:09 INFO - 'XPC_FLAGS': '0x0', 03:11:09 INFO - 'XPC_SERVICE_NAME': '0', 03:11:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:11:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:11:09 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 03:11:09 INFO - Cleaning up... 03:11:09 INFO - Return code: 0 03:11:09 INFO - Running post-action listener: _resource_record_post_action 03:11:09 INFO - Running post-action listener: _start_resource_monitoring 03:11:09 INFO - Starting resource monitoring. 03:11:09 INFO - [mozharness: 2016-09-30 10:11:09.501467Z] Finished create-virtualenv step (success) 03:11:09 INFO - [mozharness: 2016-09-30 10:11:09.502520Z] Running install step. 03:11:09 INFO - Running pre-action listener: _resource_record_pre_action 03:11:09 INFO - Running main action method: install 03:11:09 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:11:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:11:09 INFO - Reading from file tmpfile_stdout 03:11:09 INFO - Detecting whether we're running mozinstall >=1.0... 03:11:09 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 03:11:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 03:11:09 INFO - Reading from file tmpfile_stdout 03:11:09 INFO - Output received: 03:11:09 INFO - Usage: mozinstall [options] installer 03:11:09 INFO - Options: 03:11:09 INFO - -h, --help show this help message and exit 03:11:09 INFO - -d DEST, --destination=DEST 03:11:09 INFO - Directory to install application into. [default: 03:11:09 INFO - "/builds/slave/test"] 03:11:09 INFO - --app=APP Application being installed. [default: firefox] 03:11:09 INFO - mkdir: /builds/slave/test/build/application 03:11:09 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 03:11:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 03:11:44 INFO - Reading from file tmpfile_stdout 03:11:44 INFO - Output received: 03:11:44 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 03:11:44 INFO - Running post-action listener: _resource_record_post_action 03:11:44 INFO - [mozharness: 2016-09-30 10:11:44.749056Z] Finished install step (success) 03:11:44 INFO - [mozharness: 2016-09-30 10:11:44.749198Z] Running run-tests step. 03:11:44 INFO - Running pre-action listener: _resource_record_pre_action 03:11:44 INFO - Running main action method: run_tests 03:11:44 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 03:11:44 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 03:11:44 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 03:11:44 INFO - 2016-09-30 03:11:44.763 screenresolution[1749:8546] starting screenresolution argv=screenresolution get 03:11:44 INFO - 2016-09-30 03:11:44.766 screenresolution[1749:8546] Display 0: 1600x1200x32@60 03:11:44 INFO - 2016-09-30 03:11:44.771 screenresolution[1750:8548] starting screenresolution argv=screenresolution list 03:11:44 INFO - Available Modes on Display 0 03:11:44 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 03:11:44 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 03:11:44 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 03:11:44 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 03:11:44 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 03:11:44 INFO - Intel Iris: 03:11:44 INFO - Chipset Model: Intel Iris 03:11:44 INFO - Type: GPU 03:11:44 INFO - Bus: Built-In 03:11:44 INFO - VRAM (Dynamic, Max): 1536 MB 03:11:44 INFO - Vendor: Intel (0x8086) 03:11:44 INFO - Device ID: 0x0a2e 03:11:44 INFO - Revision ID: 0x0009 03:11:45 INFO - Displays: 03:11:45 INFO - Display: 03:11:45 INFO - Resolution: 1600 x 1200 @ 60 Hz 03:11:45 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 03:11:45 INFO - Display Serial Number: 1600x1200 60 03:11:45 INFO - Main Display: Yes 03:11:45 INFO - Mirror: Off 03:11:45 INFO - Online: Yes 03:11:45 INFO - Rotation: Supported 03:11:45 INFO - Return code: 0 03:11:45 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 03:11:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 03:11:45 INFO - Python 2.7.3 03:11:45 INFO - Return code: 0 03:11:45 INFO - grabbing minidump binary from tooltool 03:11: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')]} 03:11:45 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 0x1101d7d30>, '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 0x10fffddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1101cfb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 03:11:45 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 03:11:45 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 03:11:45 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 03:11:45 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 03:11:45 INFO - Return code: 0 03:11:45 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 03:11:45 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 03:11:45 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 03:11:45 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 03:11:45 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 03:11:45 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Oa1drpckQO-XF4tPHFgqxQ/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0278', '--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 03:11:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Fx-Team --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/Oa1drpckQO-XF4tPHFgqxQ/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0278 --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 03:11:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.QBha3K4Xwx/Render', 03:11:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:11:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:11:45 INFO - 'HOME': '/Users/cltbld', 03:11:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:11:45 INFO - 'LOGNAME': 'cltbld', 03:11:45 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 03:11:45 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 03:11:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:11:45 INFO - 'MOZ_NO_REMOTE': '1', 03:11:45 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 03:11:45 INFO - 'NO_EM_RESTART': '1', 03:11:45 INFO - 'PAGER': '/bin/cat', 03:11:45 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:11:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:11:45 INFO - 'PWD': '/builds/slave/test', 03:11:45 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 03:11:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:11:45 INFO - 'SHELL': '/bin/bash', 03:11:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.erbt3jYA3j/Listeners', 03:11:45 INFO - 'TMPDIR': '/var/folders/jw/lfbylzg17jd15qryjvmblvym00000w/T/', 03:11:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:11:45 INFO - 'USER': 'cltbld', 03:11:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:11:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:11:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:11:45 INFO - 'XPC_FLAGS': '0x0', 03:11:45 INFO - 'XPC_SERVICE_NAME': '0', 03:11:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:11:45 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Oa1drpckQO-XF4tPHFgqxQ/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0278', '--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 03:11:45 INFO - mozversion application_buildid: 20160930005519 03:11:45 INFO - mozversion application_changeset: 5bf0ee1a10f44ef12f13bfe03f58726feffa59a1 03:11:45 INFO - mozversion application_display_name: Nightly 03:11:45 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 03:11:45 INFO - mozversion application_name: Firefox 03:11:45 INFO - mozversion application_remotingname: firefox 03:11:45 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 03:11:45 INFO - mozversion application_vendor: Mozilla 03:11:45 INFO - mozversion application_version: 52.0a1 03:11:45 INFO - mozversion platform_buildid: 20160930005519 03:11:45 INFO - mozversion platform_changeset: 5bf0ee1a10f44ef12f13bfe03f58726feffa59a1 03:11:45 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 03:11:45 INFO - mozversion platform_version: 52.0a1 03:11:45 INFO - using testdate: 1475230305 03:11:45 INFO - actual date: 1475230305 03:11:45 INFO - starting webserver on 'localhost:49271' 03:11:45 INFO - SUITE-START | Running 2 tests 03:11:45 INFO - TEST-START | dromaeo_css 03:11:45 INFO - operating with platform_type : mac_ 03:11:45 INFO - Initialising browser for dromaeo_css test... 03:11:45 INFO - TEST-INFO | started process 1761 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/jw/lfbylzg17jd15qryjvmblvym00000w/T/tmpxfFgK6/profile http://localhost:49271/getInfo.html) 03:11:48 INFO - PROCESS | 1761 | __metrics Screen width/height:1600/1200 03:11:48 INFO - PROCESS | 1761 | colorDepth:24 03:11:48 INFO - PROCESS | 1761 | Browser inner width/height: 1024/689 03:11:48 INFO - PROCESS | 1761 | __metrics 03:11:48 INFO - PROCESS | 1761 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 03:11:48 INFO - PROCESS | 1761 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 03:11:48 INFO - TEST-INFO | 1761: exit 0 03:11:48 INFO - Browser initialized. 03:11:48 INFO - Running cycle 1/1 for dromaeo_css test... 03:11:48 INFO - TEST-INFO | started process 1764 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/jw/lfbylzg17jd15qryjvmblvym00000w/T/tmpxfFgK6/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 03:12:00 INFO - PROCESS | 1764 | RSS: Main: 236130304 03:12:00 INFO - PROCESS | 1764 | 03:12:51 INFO - PROCESS | 1764 | 1475230371906 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] 03:15:03 INFO - PROCESS | 1764 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49271/tests/dromaeo/cssquery-ext.html) 03:15:05 INFO - PROCESS | 1764 | RSS: Main: 398106624 03:15:05 INFO - PROCESS | 1764 | 03:18:08 INFO - PROCESS | 1764 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-ext.html (next: http://localhost:49271/tests/dromaeo/cssquery-jquery.html) 03:18:09 INFO - PROCESS | 1764 | RSS: Main: 372162560 03:18:09 INFO - PROCESS | 1764 | 03:21:11 INFO - PROCESS | 1764 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49271/tests/dromaeo/cssquery-mootools.html) 03:21:12 INFO - PROCESS | 1764 | RSS: Main: 332206080 03:21:12 INFO - PROCESS | 1764 | 03:24:14 INFO - PROCESS | 1764 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49271/tests/dromaeo/cssquery-prototype.html) 03:24:15 INFO - PROCESS | 1764 | RSS: Main: 335351808 03:24:15 INFO - PROCESS | 1764 | 03:27:18 INFO - PROCESS | 1764 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49271/tests/dromaeo/cssquery-yui.html) 03:27:19 INFO - PROCESS | 1764 | RSS: Main: 333860864 03:27:19 INFO - PROCESS | 1764 | 03:30:22 INFO - PROCESS | 1764 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-yui.html (next: http://localhost:49271/tests/dromaeo/cssquery-dojo.html) 03:30:22 INFO - PROCESS | 1764 | RSS: Main: 484335616 03:30:22 INFO - PROCESS | 1764 | 03:30:22 INFO - PROCESS | 1764 | __start_tp_report 03:30:22 INFO - PROCESS | 1764 | _x_x_mozilla_page_load 03:30:22 INFO - PROCESS | 1764 | _x_x_mozilla_page_load_details 03:30:22 INFO - PROCESS | 1764 | |i|pagename|runs| 03:30:22 INFO - PROCESS | 1764 | |0;dojo.html;3299.4390953537895;3305.785123966942;3309.702126808587;3335.316260166666;3371.1235078879486;5546.86132846678;5593.944060559398;5630.746616402262;5655.830325090244;5683.715814209294;5592.4407559244055;5615.073512870382;5618.438156184379;5638.633488823224;5696.800611978571;5143.922841157386;5183.87040323993;5196.506331898464;5200.3759548854005;5216.6869987800665;4162.334026555752;5159.819406320779;5206.505381988705;5218.191180367049;5237.921431178535;5080.974595127019;5228.346456692913;5252.395974462947;5302.655327403726;5308.097623404031;5062;5195.558377537914;5204.609654275944;5213.973930130325;5248.8687782805355;5039.395272567315;5085.745712714386;5096.872578185538;5099.872503187413;5135.691858488465;5509.3664228613425;5527.972360138174;5528.143137813645;5557.305336832896;5569.136783798518;6118.755249790003;6162.22972128484;6172.981458059415;6183.134361189437;6185.474234690012;6067.18093057436;6087.239095113111;6098.8558443233605;6138.693065346715;6211.378862113797;4903;5598.992181407304;5685.857853553694;5709.486146246858;5733.3406658234;6244.219472565929;6245.594036387711;6246.500279977591;6263.530235232284;6273.090401891792;5884.794032208938;5944.316403613553;5946.07835785454;6039.516838652898;6055.576109672369;6103.1455596216565;6118.284172697687;6119.4416233545735;6191.226096737908;6204.193454850931;6083.809409864473;6122.326544080148;6141.938580614226;6147.985582378947;6158;6108.899064304165;6175.938240617536;6190.350013248544;6192.195014648157;6195.876082478415;4766.594839438617;4798;4812.1578723723205;4816.229403295456;4853.878653033702;4332.545082766264;4355.978220108879;4393.362962370438;4424.247877860771;4443.466783985878;5572.275604171513;5604.495595396434;5653.830385088454;5659.547236221092;5667.1782591524825;6046.727897244635;6109.7387008107025;6127.586602260269;6146.248319228998;6200.751969921243;5630.014747419185;5687.203791469197;5711.143328500758;5779.33537643168;5801.01382764931;5278.947210527922;5324.227986941872;5325.786968521294;5342.572594192456;5348.064088784447;6255.43701066897;6263.96868015657;6278.183836101278;6299.338569450142;6324.209473815773;6028;6109.920916985209;6119.441623354662;6141.723622437091;6155.322914479314;6247.906281405873;6256.843578910563;6264.31092579825;6277.71750271248;6282.968585157045;6021.946159421996;6084.178635884099;6109.633421994695;6114.633122012693;6145.8156255312415;4821.866861287524;5178.818741344035;6076.1493390924425;6195.690215489298;6215.65813880241;5371.543418809445;5932.228810254639;5966.910496342645;6014.188084608521;6088.847778805565;5961.731722072431;5963.373845746134;5973.940260597512;5974.61165024272;5978.850528736816;5725.8568535786935;5796.594238403271;5803.970980145073;5825.76696932118;5838.591298609056;4952.257161425815;5843.328017277898;5870.471657550841;5975.223220981418;6015.72929218195;5870.119482077723;5875.911861322169;5876.853078673067;5907.054871220584;5930.169776231417 03:30:22 INFO - PROCESS | 1764 | |1;ext.html;471.3707200886816;509.79353361888434;532.135280169724;550.8319962411464;573.8163787587973;30297.697023029763;39607;40920.386194207036;40930.181396372056;42828;41825.58174418255;42156.15687686252;42175.3673694894;42291.15417691644;42325;9607.951960240207;9791.657291994781;9795.657151999681;9828.361156524803;9829.90170098297;9689.806203875918;9789.6084156634;9805.607775688964;9822.017798220193;9834.704958851245;9771.267154963407;9818.803623927517;9831.705048848546;9852.950735246313;9875.358101723365;9764.609415623367;9804.705858824247;9811.26415518839;9811.607535698564;9835.852462213072;9661.661841835503;9736.75658108553;9738.805223895553;9744.220462362993;9748.220142388593;39658.61194858195;39890.60109398898;39980.20039599221;40032.79983600092;40083.39874901879;3236.967630323714;3635.8909232722795;3892.0853599404118;3899.298126337258;4048.7975601219823;28327.008554700693;28593.428131437464;30426.84786576053;30926.690733092608;31069.378612427852;3024.107888172994;3126.546650735653;3191.2979144588385;3196.1689960610174;3252.7235185009304;470005;471632;473874;474728;475278.99999999657;54593;54797.72601136969;54873;54889.72555137199;54907.901841962936;33545.49681754727;34220;34280.657193427745;57771.844563108505;58048;15757.842421575637;15962.92018539923;15988.36046558148;15993.760093598605;16001.959872608244;12134.150609457252;12202.694932626579;12227.205231659913;12248.510059597695;12252.081093917992;18351.54121146982;18362.265509379475;18383.264669413074;18398.448046558624;18420.17109230088;6168.977201171483;7309.269073092648;7406.111266647929;7406.370458511087;7569.394448444111;851.7913111287775;886.5833058462513;917.6329468212634;929.5027160469117;1003.1322905686528;24074.96251874063;29047.27381815429;31156.909508167104;31656.525152122736;32367;1003.1022235099691;1013.8124446977333;1027.457043816548;1034.8616521826082;1109.4951796932376;13946.093503922213;14361.784573231409;14616.561503154922;14746;14793.852061479247;672286;675800;677647;679167;681081;51086;51477;51490.74254628703;51619.22571161436;51698;1198.3828328410875;1244.7821631214683;1311.1280998136133;1339.430741934674;1349.1702602899343;25483.490330192923;29319.560206597343;29567.852160739058;29902.850485747433;29965.101046968626;45960;46311.97944267224;46430.53569464397;46432;46651;528326;534989.3250533878;535131;535730;536043;22146.557068858856;22633.321000370015;24355.512889742462;24964.500709985336;25027.87486062558;42773.57226427696;42876.142477150905;43171.35242971295;43594.64053594615;43844.78077609592;35242.82378588091;35390.46914296339;35460.64539354573;36122.81938590395;36980;34989.825050874584;35243.47134793032;35861;35961;36479.45280820737;34688;34758.82620586881;34821.47767783536;35433.291334173686;35533.64466355303;29933;31364.686353136178;31400.685993139778;31495.740170393845;31553.526697099103;22480.775192247867;23614.29157125289;23768.76231237735;24245.757542425057;24386.634200487362 03:30:22 INFO - PROCESS | 1764 | |2;jquery.html;303.92098054505834;331.6981546792419;345.5611373555586;377.6393544165322;397.46380240370956;15054.623634409132;15419.460318888841;15613.21933903306;15847.762283565755;16128.83871161288;18837.2685960536;19244.80755192451;19619.60760784787;19734.309299174532;20019.999000049975;5715.257016587838;5928.703564821763;5948.1969934059;6094.512439004869;6096.878062438748;5759.107338362561;5981.671008094553;5989.460948514633;6051.697415129248;6100.7864724734645;5983.042713165894;5983.700814959257;5997.310309314419;6004.789832355868;6005.12925625784;5714.771409143629;5957.672328021957;5989.8203053908455;6015.75936962521;6023.819285421444;5184.341010876521;5192.402873669538;5194.3247377840635;5196.92204616931;5201.271821944931;25766.74233257662;26046.78602318805;26217.344566385993;26376.86811565949;26588.202353929413;2166.490874644457;2245.69683092782;2297.7127859017623;2609.647697560824;2825.293676580855;14796.70406591862;14910.403583856632;15432.537023889301;15661.686766264611;15753.92123039389;1621;1836.2654938024764;1847.7782666080022;1908.2653178526368;1933.6132773445368;90853.09146908512;104820;105641.41537876861;110342.34486482709;113865.43067284611;8297.668093276323;10412.895871041193;10463.476826158814;10757.623483178051;10898.237123401444;12993.090483666241;13196.406161722745;13198.934005329911;13225.470981160839;13255;8128.959355203305;8398.83202335962;8444.78888027792;8581;8601.82796344082;8454.281386082252;8458.069612342637;8500.192481714228;8651.351148663749;8673.696420625249;6224.844378890564;6358.650274234961;6389.872202555923;6401.775937842153;6477.4170324671;1525.3745964154643;1763.8147994460653;1807.6384723055442;1810.6016676331185;1811.9909400452914;695.8886578147473;804.8873157757919;851.7444766570004;864.2740098317444;908.1009800297658;20948.37154885356;21906.342809715734;21938;22033.89830508468;22107.889460552593;919.1911118215927;931.9673811416703;932.9207017403461;933.0809152984441;1014.5028935821542;10739.946300268448;11617.083181275164;11684.786683662061;11686.941565292289;11687.415629218505;576068;577462.2253777493;582827;604768;604979;116504;118786;121125.15499380103;121209;121345.57308853774;997.7405874472689;997.8645698205845;1012.7012531303136;1023.155896385485;1024.5799222318813;18814.717779233597;19252.518687032938;19299.903500482407;19399.515012124808;19476;53054.204186936455;53215.46784532105;53767.053095177514;53950.46049539612;54265.45734542762;45379;56337.154942676716;74474.62762686369;75596.24403755892;76578.46843062996;7615.733449329468;7624.961875190588;7789.065312162576;7792.1428642848105;8198.672053118047;12237.755244894874;12508.499660013498;12757.744845103232;13097.083204175617;13126.934365328112;10807.513661885392;10991.835122473329;11261.71845703864;11438.599649012245;11481.368524730886;10044.246681498771;10892.801791803055;11196.66410007701;11525.942370288094;11613.070954323803;11628.825567616323;11645.534178632759;11659.591914282959;11706.297622142698;11811.586594469152;8191.221833925768;11695;11794.587189448328;11837.76324473523;12453.377331133588;11678.532858685558;11737.237079589799;11964.880351196376;12064.51741930348;12241.20432171906 03:30:22 INFO - PROCESS | 1764 | |3;mootools.html;1407.1908652524794;1466.9413223471063;1622.4483675550312;1850.2876392588848;1877.971830422544;7446.51597646154;8353.498790072592;8826.249768769647;8858.822823543533;8903.243224325925;7810.609469526516;8035.236652518016;8035.477693949889;8451.323894088473;8659.653613855438;2245.382519807054;2311.861288322706;2312.9653055204103;2313.710786151731;2316.513532158249;2347.4366152123453;2382.1424287256573;2383.13015749252;2400.31590996566;2405.482821193443;2361.0437772702085;2370.277065495023;2377.8811059447044;2389.3309873235435;2402.8678422686744;2107.9894600527055;2311.838171328008;2352.1532248390567;2371.8814059297138;2382.2257766226044;2336.6027775278076;2364.75170107138;2368.9407764805846;2371.0794256651993;2374.655674927143;8783.297336213087;8805.559722013937;8842.557872106368;8844.646214151426;8870.911290887074;5163.690178589259;5317.680939143625;5322.574194064466;5334.22653715213;5335.959487899861;3421.6578342165835;3512.9297414051575;3665.4685070664873;3666.6150054244454;3670.7797532148156;1039.0596510158243;1052.4001319248032;1057.3761480726332;1075.2258373970728;1081.8160912644792;11420.714482138013;11589.13081518881;11590.420478976102;11606.593769218121;11610.825837612443;4362.8909277267685;4543.886402839956;4681.274402467623;4792.161371759998;4794.2089555223;9692.176165026052;9832.950835245778;9837.60649574009;9839.852402213972;9845.852312215322;5019.623528235324;5069.543741063322;5092.414372347153;5092.974535127375;5096.108181068298;4265.488141422987;4308.827646894152;4671.719696818202;4674.579287864109;4814.470408255089;3838.6353296436796;3851.171998020439;3929.2141571685206;3956.841726330972;3982.4026396040244;1261.2495565138727;1274.6303571964027;1276.9361531923366;1286.3439645780531;1288.3429450980068;805.8912046873714;870.9259712924346;896.3770179725159;923.5982347678781;930.9581068851783;5198.714070726146;5667.773289068391;5812.6221795584;5923.289205295392;6031.125486804389;911.0070023674227;919.7332773495746;925.8194652042922;938.7606160429046;980.3970558106713;3767.9261410497875;3917.275304068756;3931.7837518936735;4044.3529035354204;4045.6763458923215;9041;10838.729031774112;10842.674719758419;10857.837132443019;10864.565417383215;10765.30025548337;10794.406307652998;10825.13398928084;10831;10845.186611004048;7679.040119985002;8051.959740201261;8068.193180682002;8093.271605555528;8243.381746368927;5821.679807610622;5889.146073819186;5937.881242375215;5960.552958528041;6029.155918171372;9334.299927505328;9463.763405914908;9672.806543868943;9767.706968790948;9807.264455166034;9148.176664100263;9598.088181622734;9711;9788.559514821707;9900.306978511435;6049.848753781191;7401.925980740124;7443.627818609156;7519.285667861764;7579.052618422697;7431.851362972819;7925.5733967886335;8256.174382561696;8317.542535160625;8322.875156872531;7264.237255088351;7339.706411743684;7360.595167265852;7435.442341824493;7492.700291988477;7261.3101755333155;7269.890951635835;7274.381677557467;7283.817904552428;7400.888986665097;6878.484113691593;6923.8961415579815;6991.335823096798;6995.915633076881;7009.369156775914;4946.925796113133;4966.627502937222;5049.990001999541;5053.949460505495;5110.3356563646485;4894.017922741153;5000.074986127578;5042.974785126051;5189;5205.479452054916 03:30:22 INFO - PROCESS | 1764 | |4;prototype.html;264.3932175656867;296.7551769789923;305.2445198134617;307.5955119018491;310.07597359867594;4251.702380833343;5232.816851410202;5253.264542963979;5253.4221235664045;5289.841304760854;4209.347551129572;4808.134535783557;5162.251473536335;5187.221916712495;5189.431231540393;3766.43882160131;3810.733248672601;3879.301725689375;3978.18447218321;3995.180987897474;3339.6159441664277;3994.4621320791557;4004.299247631667;4044.746128700098;4045.8786236412957;3301.7783420134583;3976.2107051826647;3980.8208630611693;3984.422258772476;3989.760614363133;3567.2687099144614;3728.869489567879;3981.5023122109737;3998.780067096294;4014.69522405218;3102.395032968572;3250.756193285489;3419.5212670226188;3466.8959931202103;3477.8608855645743;5015;5022.8196373852115;5055.848324550269;5060.84817455477;5079.323823138356;4157.8960525987095;4158.126793373395;4251.319788833803;4373.234683930299;4521.796519156613;4480.417545719036;5328.215047958938;5428.782848686087;5456.863578410532;5493.917591236134;3164.984175079133;3772.3398405278967;3773.2453509298252;3787.3561494545993;3830.3105441020607;4592.885177870547;5497.670139791625;5717.599768016241;5803.738831752539;5808.6224395414165;3235.433799085151;3268.771186016956;3684.949789310034;3784.745119197488;3803.695704343646;3130.3269796993754;3323.7839540429795;3820.8662696806036;3844.866099314851;3875.2443273561385;3435.845386957593;3750.6497660842074;4493.415855938707;4514.255147900625;4535.231259808677;5292.285541451854;5486.31421072366;5516.448355164452;5527.170924361378;5538.252335934678;1293.4373547506864;1295.3976400973404;1306.9607911762662;1307.3920626908532;1314.9480415667429;760.5208718507415;789.1516619633917;803.0684406088909;803.7468197517763;806.1414593457948;4141.97929010353;4276.161250662245;4504.391907092566;5040.7731652075745;5117.948820511821;4702.764861756967;4796.616270698336;5271.920921186185;5646.378898321182;5689.231953686282;4314.115606300701;4615.469221039623;4975.726335051534;5179.378474583074;5269.394019687785;2904.404597057598;2924.605178300902;2969.792114552004;2975.4495418347674;2979.7765167612515;4740.928886066712;5071.2139618359215;5148.588112950955;5840.364697884599;5877.706114694248;4707.8587642370785;4810.7835147418455;5468.316460442445;5786.623869448473;5851.1515830205235;5188.521271437547;5300.893982120336;5425.213344065089;5922.615030023035;5971.850703732354;5030.0322809541085;5719.57103217252;5751.9424805751405;5777.711114444177;5803.738831752666;4937.7531123444405;4954.281629163823;5247.947520524746;5613.635113717595;5666.490015898589;4859.222524396221;5502.752376143003;5943.3849106393045;5948.018576934757;6016.781022153127;3273.869045238164;3694.0949467380665;3869.438931354994;3880.8253628586217;3899.863504777319;4700.76496175183;5557.693941923726;5649.435056494318;5712;5728.71104001613;5535.750891209986;5545.8388718431725;5548.167774833807;5558.777648893999;5573.467296493334;4584.9913019137075;5315.335583052119;5455.918161227664;5562.833115006556;5618.04493236159;4651.023375014449;4658.883527911829;5235.973820130875;5438.9394068156325;5471.945280547303;5281.313429254326;5427.592930530147;5459;5552.972700050504;5559.471850174227;5130.615203859651;5381.7352922064065;5454.481824226692;5484.067708489487;5505.201745746925 03:30:22 INFO - PROCESS | 1764 | |5;yui.html;702.6521871673523;703.4899697719153;758.1205801270523;806.1615919443778;808.1312588966862;15644.843551564481;15866;15891.364345426198;15909.204539773023;15911.363545458167;14174.220417877013;14268.215248161347;14279.286035698226;14296.285185740697;14377.856221437782;676.6751959059654;698.8148140742708;701.7087908517984;833.1834801894147;840.1430540848331;847.9660813567481;849.218718778725;850.9234168924795;853.9615717292705;862.2670729879615;699.9685014174374;906.7415786500849;907.7639813648433;907.8638204269347;911.3802614222359;898.6046139698511;900.8693739407814;902.7562558109306;903;906.2659246010719;523.1786095829549;540.8755986123174;543.9592030597721;653.4315145823165;666.1074160624738;14871.553853384416;15260.465883694129;15297.541073767694;15299.923500382538;15302.770458443132;13377.26425046622;13564.186148831002;13608.591742247749;13869.104956602921;14031.579052628336;12109.212901161396;12339.197952133083;12391.750825766938;12437.564685236064;12802;4444.35556844259;4497.302918047708;4604.217283061887;4609.861704148881;4713.929291060636;95685.52157239169;113804.86195138026;115116;115189;117490;10725.249232553802;10793.352398856094;10858;10964.671059868217;11006.944965275123;20938.895305523376;21019;21047.894760526404;21047.947602619806;21065.15739370408;7701.653425595861;7753.069631644126;7800.648970796327;7839.608019598997;7847.529148251123;4264.616184543406;4314.9784251078545;4327.697061205686;4363.952651363713;4499.055198408371;3323.4350160472777;3841.5390153181797;4010.8796736097966;4030.556638769733;4057.695672824491;711.5908352697116;725.4704066031721;736.5249414127858;742.4283301857539;748.8764353881658;564.6470955652717;567.8551969247816;602.4397310501188;625.8185126313318;675.1729131813489;7791.727289544839;8367.790805229917;9147.679831205876;9197.632094716137;9371.437713737198;619.4177473175201;636.4780879678619;639.9808005759835;648.4423395879539;649.3506493506494;3445.8277086146095;4465.106978604292;4507.625174321798;4561.452625684873;4591.9081618367445;100291.49854250682;112569;139807;152461.23769381084;175134;117474.41262793803;144205;144384.27807860894;145668;146807;63852.36147638557;76479.23520764832;106178;118848.40575797066;119082.2137667953;8272.751817445485;8495.192956669107;8516.70191543293;8579.35654825878;8776.78058048554;63932.3606763945;66287.38782415958;67000.6649966747;68228;68912.65543672249;61885.69057154866;104824.47587762012;106511;106657.46671266593;106967;13213.867861321649;13227.537036203687;13349.733005339645;13578.728425431633;13992.230427326187;12657.367131643197;14406.711865762836;14631.853681463475;14797.89015823796;15262.3895044197;13708.3831227593;13900.930495347458;14018.509352172625;14083.859161408254;14233.146011239358;11884.643460696192;13505.39225734864;13623.114497557626;14062.718745625234;14380.137191768108;13611.727765444437;13701.725965480833;13776.517821876187;13803.51687690926;13848.446062157402;10286.228532859916;10965.890341096487;10994.177677944203;11324.32054076758;11384.601538946097;11328.433578320886;11414.771704565695;11491.770164596828;11653.825192622287;11677.299362038304 03:30:22 INFO - PROCESS | 1764 | __end_tp_report 03:30:22 INFO - PROCESS | 1764 | __start_cc_report 03:30:22 INFO - PROCESS | 1764 | _x_x_mozilla_cycle_collect,1572 03:30:22 INFO - PROCESS | 1764 | __end_cc_report 03:30:22 INFO - PROCESS | 1764 | __startTimestamp1475231422542__endTimestamp 03:30:22 INFO - PROCESS | 1764 | 03:30:22 INFO - PROCESS | 1764 | ------- Summary: start ------- 03:30:22 INFO - PROCESS | 1764 | Number of tests: 6 03:30:22 INFO - PROCESS | 1764 | 03:30:22 INFO - PROCESS | 1764 | [#0] dojo.html Cycles:165 Average:5637.20 Median:5825.77 stddev:639.88 (11.0%) stddev-sans-first:615.00 03:30:22 INFO - PROCESS | 1764 | Values: 3299.4 3305.8 3309.7 3335.3 3371.1 5546.9 5593.9 5630.7 5655.8 5683.7 5592.4 5615.1 5618.4 5638.6 5696.8 5143.9 5183.9 5196.5 5200.4 5216.7 4162.3 5159.8 5206.5 5218.2 5237.9 5081.0 5228.3 5252.4 5302.7 5308.1 5062.0 5195.6 5204.6 5214.0 5248.9 5039.4 5085.7 5096.9 5099.9 5135.7 5509.4 5528.0 5528.1 5557.3 5569.1 6118.8 6162.2 6173.0 6183.1 6185.5 6067.2 6087.2 6098.9 6138.7 6211.4 4903.0 5599.0 5685.9 5709.5 5733.3 6244.2 6245.6 6246.5 6263.5 6273.1 5884.8 5944.3 5946.1 6039.5 6055.6 6103.1 6118.3 6119.4 6191.2 6204.2 6083.8 6122.3 6141.9 6148.0 6158.0 6108.9 6175.9 6190.4 6192.2 6195.9 4766.6 4798.0 4812.2 4816.2 4853.9 4332.5 4356.0 4393.4 4424.2 4443.5 5572.3 5604.5 5653.8 5659.5 5667.2 6046.7 6109.7 6127.6 6146.2 6200.8 5630.0 5687.2 5711.1 5779.3 5801.0 5278.9 5324.2 5325.8 5342.6 5348.1 6255.4 6264.0 6278.2 6299.3 6324.2 6028.0 6109.9 6119.4 6141.7 6155.3 6247.9 6256.8 6264.3 6277.7 6283.0 6021.9 6084.2 6109.6 6114.6 6145.8 4821.9 5178.8 6076.1 6195.7 6215.7 5371.5 5932.2 5966.9 6014.2 6088.8 5961.7 5963.4 5973.9 5974.6 5978.9 5725.9 5796.6 5804.0 5825.8 5838.6 4952.3 5843.3 5870.5 5975.2 6015.7 5870.1 5875.9 5876.9 5907.1 5930.2 03:30:22 INFO - PROCESS | 1764 | 03:30:22 INFO - PROCESS | 1764 | [#1] ext.html Cycles:180 Average:67951.46 Median:39774.61 stddev:152085.33 (382.4%) stddev-sans-first:152427.58 03:30:22 INFO - PROCESS | 1764 | Values: 471.4 509.8 532.1 550.8 573.8 30297.7 39607.0 40920.4 40930.2 42828.0 41825.6 42156.2 42175.4 42291.2 42325.0 9608.0 9791.7 9795.7 9828.4 9829.9 9689.8 9789.6 9805.6 9822.0 9834.7 9771.3 9818.8 9831.7 9853.0 9875.4 9764.6 9804.7 9811.3 9811.6 9835.9 9661.7 9736.8 9738.8 9744.2 9748.2 39658.6 39890.6 39980.2 40032.8 40083.4 3237.0 3635.9 3892.1 3899.3 4048.8 28327.0 28593.4 30426.8 30926.7 31069.4 3024.1 3126.5 3191.3 3196.2 3252.7 470005.0 471632.0 473874.0 474728.0 475279.0 54593.0 54797.7 54873.0 54889.7 54907.9 33545.5 34220.0 34280.7 57771.8 58048.0 15757.8 15962.9 15988.4 15993.8 16002.0 12134.2 12202.7 12227.2 12248.5 12252.1 18351.5 18362.3 18383.3 18398.4 18420.2 6169.0 7309.3 7406.1 7406.4 7569.4 851.8 886.6 917.6 929.5 1003.1 24075.0 29047.3 31156.9 31656.5 32367.0 1003.1 1013.8 1027.5 1034.9 1109.5 13946.1 14361.8 14616.6 14746.0 14793.9 672286.0 675800.0 677647.0 679167.0 681081.0 51086.0 51477.0 51490.7 51619.2 51698.0 1198.4 1244.8 1311.1 1339.4 1349.2 25483.5 29319.6 29567.9 29902.9 29965.1 45960.0 46312.0 46430.5 46432.0 46651.0 528326.0 534989.3 535131.0 535730.0 536043.0 22146.6 22633.3 24355.5 24964.5 25027.9 42773.6 42876.1 43171.4 43594.6 43844.8 35242.8 35390.5 35460.6 36122.8 36980.0 34989.8 35243.5 35861.0 35961.0 36479.5 34688.0 34758.8 34821.5 35433.3 35533.6 29933.0 31364.7 31400.7 31495.7 31553.5 22480.8 23614.3 23768.8 24245.8 24386.6 03:30:22 INFO - PROCESS | 1764 | 03:30:22 INFO - PROCESS | 1764 | [#2] jquery.html Cycles:180 Average:34030.15 Median:22070.89 stddev:97814.19 (443.2%) stddev-sans-first:98055.81 03:30:22 INFO - PROCESS | 1764 | Values: 303.9 331.7 345.6 377.6 397.5 15054.6 15419.5 15613.2 15847.8 16128.8 18837.3 19244.8 19619.6 19734.3 20020.0 5715.3 5928.7 5948.2 6094.5 6096.9 5759.1 5981.7 5989.5 6051.7 6100.8 5983.0 5983.7 5997.3 6004.8 6005.1 5714.8 5957.7 5989.8 6015.8 6023.8 5184.3 5192.4 5194.3 5196.9 5201.3 25766.7 26046.8 26217.3 26376.9 26588.2 2166.5 2245.7 2297.7 2609.6 2825.3 14796.7 14910.4 15432.5 15661.7 15753.9 1621.0 1836.3 1847.8 1908.3 1933.6 90853.1 104820.0 105641.4 110342.3 113865.4 8297.7 10412.9 10463.5 10757.6 10898.2 12993.1 13196.4 13198.9 13225.5 13255.0 8129.0 8398.8 8444.8 8581.0 8601.8 8454.3 8458.1 8500.2 8651.4 8673.7 6224.8 6358.7 6389.9 6401.8 6477.4 1525.4 1763.8 1807.6 1810.6 1812.0 695.9 804.9 851.7 864.3 908.1 20948.4 21906.3 21938.0 22033.9 22107.9 919.2 932.0 932.9 933.1 1014.5 10739.9 11617.1 11684.8 11686.9 11687.4 576068.0 577462.2 582827.0 604768.0 604979.0 116504.0 118786.0 121125.2 121209.0 121345.6 997.7 997.9 1012.7 1023.2 1024.6 18814.7 19252.5 19299.9 19399.5 19476.0 53054.2 53215.5 53767.1 53950.5 54265.5 45379.0 56337.2 74474.6 75596.2 76578.5 7615.7 7625.0 7789.1 7792.1 8198.7 12237.8 12508.5 12757.7 13097.1 13126.9 10807.5 10991.8 11261.7 11438.6 11481.4 10044.2 10892.8 11196.7 11525.9 11613.1 11628.8 11645.5 11659.6 11706.3 11811.6 8191.2 11695.0 11794.6 11837.8 12453.4 11678.5 11737.2 11964.9 12064.5 12241.2 03:30:22 INFO - PROCESS | 1764 | 03:30:22 INFO - PROCESS | 1764 | [#3] mootools.html Cycles:180 Average:5627.94 Median:5103.22 stddev:3130.71 (61.3%) stddev-sans-first:3123.43 03:30:22 INFO - PROCESS | 1764 | Values: 1407.2 1466.9 1622.4 1850.3 1878.0 7446.5 8353.5 8826.2 8858.8 8903.2 7810.6 8035.2 8035.5 8451.3 8659.7 2245.4 2311.9 2313.0 2313.7 2316.5 2347.4 2382.1 2383.1 2400.3 2405.5 2361.0 2370.3 2377.9 2389.3 2402.9 2108.0 2311.8 2352.2 2371.9 2382.2 2336.6 2364.8 2368.9 2371.1 2374.7 8783.3 8805.6 8842.6 8844.6 8870.9 5163.7 5317.7 5322.6 5334.2 5336.0 3421.7 3512.9 3665.5 3666.6 3670.8 1039.1 1052.4 1057.4 1075.2 1081.8 11420.7 11589.1 11590.4 11606.6 11610.8 4362.9 4543.9 4681.3 4792.2 4794.2 9692.2 9833.0 9837.6 9839.9 9845.9 5019.6 5069.5 5092.4 5093.0 5096.1 4265.5 4308.8 4671.7 4674.6 4814.5 3838.6 3851.2 3929.2 3956.8 3982.4 1261.2 1274.6 1276.9 1286.3 1288.3 805.9 870.9 896.4 923.6 931.0 5198.7 5667.8 5812.6 5923.3 6031.1 911.0 919.7 925.8 938.8 980.4 3767.9 3917.3 3931.8 4044.4 4045.7 9041.0 10838.7 10842.7 10857.8 10864.6 10765.3 10794.4 10825.1 10831.0 10845.2 7679.0 8052.0 8068.2 8093.3 8243.4 5821.7 5889.1 5937.9 5960.6 6029.2 9334.3 9463.8 9672.8 9767.7 9807.3 9148.2 9598.1 9711.0 9788.6 9900.3 6049.8 7401.9 7443.6 7519.3 7579.1 7431.9 7925.6 8256.2 8317.5 8322.9 7264.2 7339.7 7360.6 7435.4 7492.7 7261.3 7269.9 7274.4 7283.8 7400.9 6878.5 6923.9 6991.3 6995.9 7009.4 4946.9 4966.6 5050.0 5053.9 5110.3 4894.0 5000.1 5043.0 5189.0 5205.5 03:30:22 INFO - PROCESS | 1764 | 03:30:22 INFO - PROCESS | 1764 | [#4] prototype.html Cycles:180 Average:4391.89 Median:4946.02 stddev:1371.03 (27.7%) stddev-sans-first:1339.41 03:30:22 INFO - PROCESS | 1764 | Values: 264.4 296.8 305.2 307.6 310.1 4251.7 5232.8 5253.3 5253.4 5289.8 4209.3 4808.1 5162.3 5187.2 5189.4 3766.4 3810.7 3879.3 3978.2 3995.2 3339.6 3994.5 4004.3 4044.7 4045.9 3301.8 3976.2 3980.8 3984.4 3989.8 3567.3 3728.9 3981.5 3998.8 4014.7 3102.4 3250.8 3419.5 3466.9 3477.9 5015.0 5022.8 5055.8 5060.8 5079.3 4157.9 4158.1 4251.3 4373.2 4521.8 4480.4 5328.2 5428.8 5456.9 5493.9 3165.0 3772.3 3773.2 3787.4 3830.3 4592.9 5497.7 5717.6 5803.7 5808.6 3235.4 3268.8 3684.9 3784.7 3803.7 3130.3 3323.8 3820.9 3844.9 3875.2 3435.8 3750.6 4493.4 4514.3 4535.2 5292.3 5486.3 5516.4 5527.2 5538.3 1293.4 1295.4 1307.0 1307.4 1314.9 760.5 789.2 803.1 803.7 806.1 4142.0 4276.2 4504.4 5040.8 5117.9 4702.8 4796.6 5271.9 5646.4 5689.2 4314.1 4615.5 4975.7 5179.4 5269.4 2904.4 2924.6 2969.8 2975.4 2979.8 4740.9 5071.2 5148.6 5840.4 5877.7 4707.9 4810.8 5468.3 5786.6 5851.2 5188.5 5300.9 5425.2 5922.6 5971.9 5030.0 5719.6 5751.9 5777.7 5803.7 4937.8 4954.3 5247.9 5613.6 5666.5 4859.2 5502.8 5943.4 5948.0 6016.8 3273.9 3694.1 3869.4 3880.8 3899.9 4700.8 5557.7 5649.4 5712.0 5728.7 5535.8 5545.8 5548.2 5558.8 5573.5 4585.0 5315.3 5455.9 5562.8 5618.0 4651.0 4658.9 5236.0 5438.9 5471.9 5281.3 5427.6 5459.0 5553.0 5559.5 5130.6 5381.7 5454.5 5484.1 5505.2 03:30:22 INFO - PROCESS | 1764 | 03:30:22 INFO - PROCESS | 1764 | [#5] yui.html Cycles:180 Average:24780.89 Median:12194.30 stddev:39733.46 (325.8%) stddev-sans-first:39803.79 03:30:22 INFO - PROCESS | 1764 | Values: 702.7 703.5 758.1 806.2 808.1 15644.8 15866.0 15891.4 15909.2 15911.4 14174.2 14268.2 14279.3 14296.3 14377.9 676.7 698.8 701.7 833.2 840.1 848.0 849.2 850.9 854.0 862.3 700.0 906.7 907.8 907.9 911.4 898.6 900.9 902.8 903.0 906.3 523.2 540.9 544.0 653.4 666.1 14871.6 15260.5 15297.5 15299.9 15302.8 13377.3 13564.2 13608.6 13869.1 14031.6 12109.2 12339.2 12391.8 12437.6 12802.0 4444.4 4497.3 4604.2 4609.9 4713.9 95685.5 113804.9 115116.0 115189.0 117490.0 10725.2 10793.4 10858.0 10964.7 11006.9 20938.9 21019.0 21047.9 21047.9 21065.2 7701.7 7753.1 7800.6 7839.6 7847.5 4264.6 4315.0 4327.7 4364.0 4499.1 3323.4 3841.5 4010.9 4030.6 4057.7 711.6 725.5 736.5 742.4 748.9 564.6 567.9 602.4 625.8 675.2 7791.7 8367.8 9147.7 9197.6 9371.4 619.4 636.5 640.0 648.4 649.4 3445.8 4465.1 4507.6 4561.5 4591.9 100291.5 112569.0 139807.0 152461.2 175134.0 117474.4 144205.0 144384.3 145668.0 146807.0 63852.4 76479.2 106178.0 118848.4 119082.2 8272.8 8495.2 8516.7 8579.4 8776.8 63932.4 66287.4 67000.7 68228.0 68912.7 61885.7 104824.5 106511.0 106657.5 106967.0 13213.9 13227.5 13349.7 13578.7 13992.2 12657.4 14406.7 14631.9 14797.9 15262.4 13708.4 13900.9 14018.5 14083.9 14233.1 11884.6 13505.4 13623.1 14062.7 14380.1 13611.7 13701.7 13776.5 13803.5 13848.4 10286.2 10965.9 10994.2 11324.3 11384.6 11328.4 11414.8 11491.8 11653.8 11677.3 03:30:22 INFO - PROCESS | 1764 | -------- Summary: end -------- 03:30:22 INFO - PROCESS | 1764 | 03:30:23 INFO - TEST-INFO | 1764: exit 0 03:30:23 INFO - TEST-OK | dromaeo_css | took 1117863ms 03:30:23 INFO - TEST-START | kraken 03:30:23 INFO - operating with platform_type : mac_ 03:30:23 INFO - Initialising browser for kraken test... 03:30:23 INFO - TEST-INFO | started process 1882 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/jw/lfbylzg17jd15qryjvmblvym00000w/T/tmppKu5w8/profile http://localhost:49271/getInfo.html) 03:30:25 INFO - PROCESS | 1882 | __metrics Screen width/height:1600/1200 03:30:25 INFO - PROCESS | 1882 | colorDepth:24 03:30:25 INFO - PROCESS | 1882 | Browser inner width/height: 1024/689 03:30:25 INFO - PROCESS | 1882 | __metrics 03:30:25 INFO - PROCESS | 1882 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 03:30:25 INFO - PROCESS | 1882 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 03:30:26 INFO - TEST-INFO | 1882: exit 0 03:30:26 INFO - Browser initialized. 03:30:26 INFO - Running cycle 1/1 for kraken test... 03:30:26 INFO - TEST-INFO | started process 1883 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/jw/lfbylzg17jd15qryjvmblvym00000w/T/tmppKu5w8/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 03:30:38 INFO - PROCESS | 1883 | RSS: Main: 236269568 03:30:38 INFO - PROCESS | 1883 | 03:30:41 INFO - PROCESS | 1883 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49271/tests/kraken/driver.html?testName=audio-beat-detection) 03:30:41 INFO - PROCESS | 1883 | RSS: Main: 294662144 03:30:41 INFO - PROCESS | 1883 | 03:30:44 INFO - PROCESS | 1883 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49271/tests/kraken/driver.html?testName=audio-dft) 03:30:45 INFO - PROCESS | 1883 | RSS: Main: 293560320 03:30:45 INFO - PROCESS | 1883 | 03:30:48 INFO - PROCESS | 1883 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49271/tests/kraken/driver.html?testName=audio-fft) 03:30:49 INFO - PROCESS | 1883 | RSS: Main: 385859584 03:30:49 INFO - PROCESS | 1883 | 03:30:51 INFO - PROCESS | 1883 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49271/tests/kraken/driver.html?testName=audio-oscillator) 03:30:52 INFO - PROCESS | 1883 | RSS: Main: 287121408 03:30:52 INFO - PROCESS | 1883 | 03:30:54 INFO - PROCESS | 1883 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49271/tests/kraken/driver.html?testName=imaging-gaussian-blur) 03:30:54 INFO - PROCESS | 1883 | RSS: Main: 291237888 03:30:54 INFO - PROCESS | 1883 | 03:30:57 INFO - PROCESS | 1883 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49271/tests/kraken/driver.html?testName=imaging-darkroom) 03:30:58 INFO - PROCESS | 1883 | RSS: Main: 328261632 03:30:58 INFO - PROCESS | 1883 | 03:31:01 INFO - PROCESS | 1883 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49271/tests/kraken/driver.html?testName=imaging-desaturate) 03:31:01 INFO - PROCESS | 1883 | RSS: Main: 329904128 03:31:01 INFO - PROCESS | 1883 | 03:31:04 INFO - PROCESS | 1883 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49271/tests/kraken/driver.html?testName=json-parse-financial) 03:31:05 INFO - PROCESS | 1883 | RSS: Main: 327008256 03:31:05 INFO - PROCESS | 1883 | 03:31:06 INFO - PROCESS | 1883 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49271/tests/kraken/driver.html?testName=json-stringify-tinderbox) 03:31:07 INFO - PROCESS | 1883 | RSS: Main: 304738304 03:31:07 INFO - PROCESS | 1883 | 03:31:09 INFO - PROCESS | 1883 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-aes) 03:31:09 INFO - PROCESS | 1883 | RSS: Main: 304750592 03:31:09 INFO - PROCESS | 1883 | 03:31:11 INFO - PROCESS | 1883 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-ccm) 03:31:12 INFO - PROCESS | 1883 | RSS: Main: 309059584 03:31:12 INFO - PROCESS | 1883 | 03:31:15 INFO - PROCESS | 1883 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 03:31:16 INFO - PROCESS | 1883 | RSS: Main: 317775872 03:31:16 INFO - PROCESS | 1883 | 03:31:19 INFO - PROCESS | 1883 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 03:31:19 INFO - PROCESS | 1883 | RSS: Main: 319680512 03:31:19 INFO - PROCESS | 1883 | 03:31:21 INFO - PROCESS | 1883 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49271/tests/kraken/driver.html?testName=ai-astar) 03:31:21 INFO - PROCESS | 1883 | RSS: Main: 363524096 03:31:21 INFO - PROCESS | 1883 | 03:31:21 INFO - PROCESS | 1883 | __start_tp_report 03:31:21 INFO - PROCESS | 1883 | _x_x_mozilla_page_load 03:31:21 INFO - PROCESS | 1883 | _x_x_mozilla_page_load_details 03:31:21 INFO - PROCESS | 1883 | |i|pagename|runs| 03:31:21 INFO - PROCESS | 1883 | |0;ai-astar;112;111;113;112;112;113;113;112;110;112 03:31:21 INFO - PROCESS | 1883 | |1;audio-beat-detection;147;119;140;180;134;110;113;133;142;109 03:31:21 INFO - PROCESS | 1883 | |2;audio-dft;226;257;183;184;185;182;181;187;180;185 03:31:21 INFO - PROCESS | 1883 | |3;audio-fft;80;60;58;89;63;59;58;58;78;72 03:31:21 INFO - PROCESS | 1883 | |4;audio-oscillator;76;97;125;102;83;70;92;68;68;89 03:31:21 INFO - PROCESS | 1883 | |5;imaging-gaussian-blur;87;83;86;83;90;84;87;84;88;84 03:31:21 INFO - PROCESS | 1883 | |6;imaging-darkroom;93;93;93;94;94;93;93;94;92;93 03:31:21 INFO - PROCESS | 1883 | |7;imaging-desaturate;86;86;87;84;85;86;84;85;85;84 03:31:21 INFO - PROCESS | 1883 | |8;json-parse-financial;55;54;53;52;54;55;56;54;55;55 03:31:21 INFO - PROCESS | 1883 | |9;json-stringify-tinderbox;66;72;75;75;73;70;69;70;69;72 03:31:21 INFO - PROCESS | 1883 | |10;stanford-crypto-aes;86;88;85;88;85;84;84;87;87;105 03:31:21 INFO - PROCESS | 1883 | |11;stanford-crypto-ccm;138;144;147;150;148;137;139;143;165;139 03:31:21 INFO - PROCESS | 1883 | |12;stanford-crypto-pbkdf2;172;169;170;170;171;170;168;170;169;168 03:31:21 INFO - PROCESS | 1883 | |13;stanford-crypto-sha256-iterative;68;67;67;67;67;67;68;68;66;67 03:31:21 INFO - PROCESS | 1883 | __end_tp_report 03:31:21 INFO - PROCESS | 1883 | __start_cc_report 03:31:21 INFO - PROCESS | 1883 | _x_x_mozilla_cycle_collect,1948 03:31:21 INFO - PROCESS | 1883 | __end_cc_report 03:31:21 INFO - PROCESS | 1883 | __startTimestamp1475231481680__endTimestamp 03:31:21 INFO - PROCESS | 1883 | 03:31:21 INFO - PROCESS | 1883 | ------- Summary: start ------- 03:31:21 INFO - PROCESS | 1883 | Number of tests: 14 03:31:21 INFO - PROCESS | 1883 | 03:31:21 INFO - PROCESS | 1883 | [#0] ai-astar Cycles:10 Average:112.00 Median:112.00 stddev:0.94 (0.8%) stddev-sans-first:1.00 03:31:21 INFO - PROCESS | 1883 | Values: 112.0 111.0 113.0 112.0 112.0 113.0 113.0 112.0 110.0 112.0 03:31:21 INFO - PROCESS | 1883 | 03:31:21 INFO - PROCESS | 1883 | [#1] audio-beat-detection Cycles:10 Average:132.70 Median:137.00 stddev:21.70 (15.8%) stddev-sans-first:22.39 03:31:21 INFO - PROCESS | 1883 | Values: 147.0 119.0 140.0 180.0 134.0 110.0 113.0 133.0 142.0 109.0 03:31:21 INFO - PROCESS | 1883 | 03:31:21 INFO - PROCESS | 1883 | [#2] audio-dft Cycles:10 Average:195.00 Median:185.00 stddev:25.66 (13.9%) stddev-sans-first:24.64 03:31:21 INFO - PROCESS | 1883 | Values: 226.0 257.0 183.0 184.0 185.0 182.0 181.0 187.0 180.0 185.0 03:31:21 INFO - PROCESS | 1883 | 03:31:21 INFO - PROCESS | 1883 | [#3] audio-fft Cycles:10 Average:67.50 Median:67.50 stddev:11.39 (16.9%) stddev-sans-first:11.15 03:31:21 INFO - PROCESS | 1883 | Values: 80.0 60.0 58.0 89.0 63.0 59.0 58.0 58.0 78.0 72.0 03:31:21 INFO - PROCESS | 1883 | 03:31:21 INFO - PROCESS | 1883 | [#4] audio-oscillator Cycles:10 Average:87.00 Median:79.50 stddev:18.09 (22.8%) stddev-sans-first:18.75 03:31:21 INFO - PROCESS | 1883 | Values: 76.0 97.0 125.0 102.0 83.0 70.0 92.0 68.0 68.0 89.0 03:31:21 INFO - PROCESS | 1883 | 03:31:21 INFO - PROCESS | 1883 | [#5] imaging-gaussian-blur Cycles:10 Average:85.60 Median:86.50 stddev:2.37 (2.7%) stddev-sans-first:2.46 03:31:21 INFO - PROCESS | 1883 | Values: 87.0 83.0 86.0 83.0 90.0 84.0 87.0 84.0 88.0 84.0 03:31:21 INFO - PROCESS | 1883 | 03:31:21 INFO - PROCESS | 1883 | [#6] imaging-darkroom Cycles:10 Average:93.20 Median:93.00 stddev:0.63 (0.7%) stddev-sans-first:0.67 03:31:21 INFO - PROCESS | 1883 | Values: 93.0 93.0 93.0 94.0 94.0 93.0 93.0 94.0 92.0 93.0 03:31:21 INFO - PROCESS | 1883 | 03:31:21 INFO - PROCESS | 1883 | [#7] imaging-desaturate Cycles:10 Average:85.20 Median:85.50 stddev:1.03 (1.2%) stddev-sans-first:1.05 03:31:21 INFO - PROCESS | 1883 | Values: 86.0 86.0 87.0 84.0 85.0 86.0 84.0 85.0 85.0 84.0 03:31:21 INFO - PROCESS | 1883 | 03:31:21 INFO - PROCESS | 1883 | [#8] json-parse-financial Cycles:10 Average:54.30 Median:55.00 stddev:1.16 (2.1%) stddev-sans-first:1.20 03:31:21 INFO - PROCESS | 1883 | Values: 55.0 54.0 53.0 52.0 54.0 55.0 56.0 54.0 55.0 55.0 03:31:21 INFO - PROCESS | 1883 | 03:31:21 INFO - PROCESS | 1883 | [#9] json-stringify-tinderbox Cycles:10 Average:71.10 Median:72.00 stddev:2.85 (4.0%) stddev-sans-first:2.35 03:31:21 INFO - PROCESS | 1883 | Values: 66.0 72.0 75.0 75.0 73.0 70.0 69.0 70.0 69.0 72.0 03:31:21 INFO - PROCESS | 1883 | 03:31:21 INFO - PROCESS | 1883 | [#10] stanford-crypto-aes Cycles:10 Average:87.90 Median:86.50 stddev:6.19 (7.2%) stddev-sans-first:6.53 03:31:21 INFO - PROCESS | 1883 | Values: 86.0 88.0 85.0 88.0 85.0 84.0 84.0 87.0 87.0 105.0 03:31:21 INFO - PROCESS | 1883 | 03:31:21 INFO - PROCESS | 1883 | [#11] stanford-crypto-ccm Cycles:10 Average:145.00 Median:145.50 stddev:8.35 (5.7%) stddev-sans-first:8.47 03:31:21 INFO - PROCESS | 1883 | Values: 138.0 144.0 147.0 150.0 148.0 137.0 139.0 143.0 165.0 139.0 03:31:21 INFO - PROCESS | 1883 | 03:31:21 INFO - PROCESS | 1883 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:169.70 Median:170.00 stddev:1.25 (0.7%) stddev-sans-first:1.01 03:31:21 INFO - PROCESS | 1883 | Values: 172.0 169.0 170.0 170.0 171.0 170.0 168.0 170.0 169.0 168.0 03:31:21 INFO - PROCESS | 1883 | 03:31:21 INFO - PROCESS | 1883 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.20 Median:67.00 stddev:0.63 (0.9%) stddev-sans-first:0.60 03:31:21 INFO - PROCESS | 1883 | Values: 68.0 67.0 67.0 67.0 67.0 67.0 68.0 68.0 66.0 67.0 03:31:21 INFO - PROCESS | 1883 | -------- Summary: end -------- 03:31:21 INFO - PROCESS | 1883 | 03:31:22 INFO - TEST-INFO | 1883: exit 0 03:31:22 INFO - TEST-OK | kraken | took 59372ms 03:31:22 INFO - SUITE-END | took 1177s 03:31:22 INFO - Completed test suite (00:19:37) 03:31:22 INFO - javascript benchmark 03:31:22 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3299.4390953537895, 3305.785123966942, 3309.702126808587, 3335.316260166666, 3371.1235078879486, 5546.86132846678, 5593.944060559398, 5630.746616402262, 5655.830325090244, 5683.715814209294, 5592.4407559244055, 5615.073512870382, 5618.438156184379, 5638.633488823224, 5696.800611978571, 5143.922841157386, 5183.87040323993, 5196.506331898464, 5200.3759548854005, 5216.6869987800665, 4162.334026555752, 5159.819406320779, 5206.505381988705, 5218.191180367049, 5237.921431178535, 5080.974595127019, 5228.346456692913, 5252.395974462947, 5302.655327403726, 5308.097623404031, 5062.0, 5195.558377537914, 5204.609654275944, 5213.973930130325, 5248.8687782805355, 5039.395272567315, 5085.745712714386, 5096.872578185538, 5099.872503187413, 5135.691858488465, 5509.3664228613425, 5527.972360138174, 5528.143137813645, 5557.305336832896, 5569.136783798518, 6118.755249790003, 6162.22972128484, 6172.981458059415, 6183.134361189437, 6185.474234690012, 6067.18093057436, 6087.239095113111, 6098.8558443233605, 6138.693065346715, 6211.378862113797, 4903.0, 5598.992181407304, 5685.857853553694, 5709.486146246858, 5733.3406658234, 6244.219472565929, 6245.594036387711, 6246.500279977591, 6263.530235232284, 6273.090401891792, 5884.794032208938, 5944.316403613553, 5946.07835785454, 6039.516838652898, 6055.576109672369, 6103.1455596216565, 6118.284172697687, 6119.4416233545735, 6191.226096737908, 6204.193454850931, 6083.809409864473, 6122.326544080148, 6141.938580614226, 6147.985582378947, 6158.0, 6108.899064304165, 6175.938240617536, 6190.350013248544, 6192.195014648157, 6195.876082478415, 4766.594839438617, 4798.0, 4812.1578723723205, 4816.229403295456, 4853.878653033702, 4332.545082766264, 4355.978220108879, 4393.362962370438, 4424.247877860771, 4443.466783985878, 5572.275604171513, 5604.495595396434, 5653.830385088454, 5659.547236221092, 5667.1782591524825, 6046.727897244635, 6109.7387008107025, 6127.586602260269, 6146.248319228998, 6200.751969921243, 5630.014747419185, 5687.203791469197, 5711.143328500758, 5779.33537643168, 5801.01382764931, 5278.947210527922, 5324.227986941872, 5325.786968521294, 5342.572594192456, 5348.064088784447, 6255.43701066897, 6263.96868015657, 6278.183836101278, 6299.338569450142, 6324.209473815773, 6028.0, 6109.920916985209, 6119.441623354662, 6141.723622437091, 6155.322914479314, 6247.906281405873, 6256.843578910563, 6264.31092579825, 6277.71750271248, 6282.968585157045, 6021.946159421996, 6084.178635884099, 6109.633421994695, 6114.633122012693, 6145.8156255312415, 4821.866861287524, 5178.818741344035, 6076.1493390924425, 6195.690215489298, 6215.65813880241, 5371.543418809445, 5932.228810254639, 5966.910496342645, 6014.188084608521, 6088.847778805565, 5961.731722072431, 5963.373845746134, 5973.940260597512, 5974.61165024272, 5978.850528736816, 5725.8568535786935, 5796.594238403271, 5803.970980145073, 5825.76696932118, 5838.591298609056, 4952.257161425815, 5843.328017277898, 5870.471657550841, 5975.223220981418, 6015.72929218195, 5870.119482077723, 5875.911861322169, 5876.853078673067, 5907.054871220584, 5930.169776231417], "value": 5597.229458992698, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [471.3707200886816, 509.79353361888434, 532.135280169724, 550.8319962411464, 573.8163787587973, 30297.697023029763, 39607.0, 40920.386194207036, 40930.181396372056, 42828.0, 41825.58174418255, 42156.15687686252, 42175.3673694894, 42291.15417691644, 42325.0, 9607.951960240207, 9791.657291994781, 9795.657151999681, 9828.361156524803, 9829.90170098297, 9689.806203875918, 9789.6084156634, 9805.607775688964, 9822.017798220193, 9834.704958851245, 9771.267154963407, 9818.803623927517, 9831.705048848546, 9852.950735246313, 9875.358101723365, 9764.609415623367, 9804.705858824247, 9811.26415518839, 9811.607535698564, 9835.852462213072, 9661.661841835503, 9736.75658108553, 9738.805223895553, 9744.220462362993, 9748.220142388593, 39658.61194858195, 39890.60109398898, 39980.20039599221, 40032.79983600092, 40083.39874901879, 3236.967630323714, 3635.8909232722795, 3892.0853599404118, 3899.298126337258, 4048.7975601219823, 28327.008554700693, 28593.428131437464, 30426.84786576053, 30926.690733092608, 31069.378612427852, 3024.107888172994, 3126.546650735653, 3191.2979144588385, 3196.1689960610174, 3252.7235185009304, 470005.0, 471632.0, 473874.0, 474728.0, 475278.99999999657, 54593.0, 54797.72601136969, 54873.0, 54889.72555137199, 54907.901841962936, 33545.49681754727, 34220.0, 34280.657193427745, 57771.844563108505, 58048.0, 15757.842421575637, 15962.92018539923, 15988.36046558148, 15993.760093598605, 16001.959872608244, 12134.150609457252, 12202.694932626579, 12227.205231659913, 12248.510059597695, 12252.081093917992, 18351.54121146982, 18362.265509379475, 18383.264669413074, 18398.448046558624, 18420.17109230088, 6168.977201171483, 7309.269073092648, 7406.111266647929, 7406.370458511087, 7569.394448444111, 851.7913111287775, 886.5833058462513, 917.6329468212634, 929.5027160469117, 1003.1322905686528, 24074.96251874063, 29047.27381815429, 31156.909508167104, 31656.525152122736, 32367.0, 1003.1022235099691, 1013.8124446977333, 1027.457043816548, 1034.8616521826082, 1109.4951796932376, 13946.093503922213, 14361.784573231409, 14616.561503154922, 14746.0, 14793.852061479247, 672286.0, 675800.0, 677647.0, 679167.0, 681081.0, 51086.0, 51477.0, 51490.74254628703, 51619.22571161436, 51698.0, 1198.3828328410875, 1244.7821631214683, 1311.1280998136133, 1339.430741934674, 1349.1702602899343, 25483.490330192923, 29319.560206597343, 29567.852160739058, 29902.850485747433, 29965.101046968626, 45960.0, 46311.97944267224, 46430.53569464397, 46432.0, 46651.0, 528326.0, 534989.3250533878, 535131.0, 535730.0, 536043.0, 22146.557068858856, 22633.321000370015, 24355.512889742462, 24964.500709985336, 25027.87486062558, 42773.57226427696, 42876.142477150905, 43171.35242971295, 43594.64053594615, 43844.78077609592, 35242.82378588091, 35390.46914296339, 35460.64539354573, 36122.81938590395, 36980.0, 34989.825050874584, 35243.47134793032, 35861.0, 35961.0, 36479.45280820737, 34688.0, 34758.82620586881, 34821.47767783536, 35433.291334173686, 35533.64466355303, 29933.0, 31364.686353136178, 31400.685993139778, 31495.740170393845, 31553.526697099103, 22480.775192247867, 23614.29157125289, 23768.76231237735, 24245.757542425057, 24386.634200487362], "value": 19146.10394411311, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [303.92098054505834, 331.6981546792419, 345.5611373555586, 377.6393544165322, 397.46380240370956, 15054.623634409132, 15419.460318888841, 15613.21933903306, 15847.762283565755, 16128.83871161288, 18837.2685960536, 19244.80755192451, 19619.60760784787, 19734.309299174532, 20019.999000049975, 5715.257016587838, 5928.703564821763, 5948.1969934059, 6094.512439004869, 6096.878062438748, 5759.107338362561, 5981.671008094553, 5989.460948514633, 6051.697415129248, 6100.7864724734645, 5983.042713165894, 5983.700814959257, 5997.310309314419, 6004.789832355868, 6005.12925625784, 5714.771409143629, 5957.672328021957, 5989.8203053908455, 6015.75936962521, 6023.819285421444, 5184.341010876521, 5192.402873669538, 5194.3247377840635, 5196.92204616931, 5201.271821944931, 25766.74233257662, 26046.78602318805, 26217.344566385993, 26376.86811565949, 26588.202353929413, 2166.490874644457, 2245.69683092782, 2297.7127859017623, 2609.647697560824, 2825.293676580855, 14796.70406591862, 14910.403583856632, 15432.537023889301, 15661.686766264611, 15753.92123039389, 1621.0, 1836.2654938024764, 1847.7782666080022, 1908.2653178526368, 1933.6132773445368, 90853.09146908512, 104820.0, 105641.41537876861, 110342.34486482709, 113865.43067284611, 8297.668093276323, 10412.895871041193, 10463.476826158814, 10757.623483178051, 10898.237123401444, 12993.090483666241, 13196.406161722745, 13198.934005329911, 13225.470981160839, 13255.0, 8128.959355203305, 8398.83202335962, 8444.78888027792, 8581.0, 8601.82796344082, 8454.281386082252, 8458.069612342637, 8500.192481714228, 8651.351148663749, 8673.696420625249, 6224.844378890564, 6358.650274234961, 6389.872202555923, 6401.775937842153, 6477.4170324671, 1525.3745964154643, 1763.8147994460653, 1807.6384723055442, 1810.6016676331185, 1811.9909400452914, 695.8886578147473, 804.8873157757919, 851.7444766570004, 864.2740098317444, 908.1009800297658, 20948.37154885356, 21906.342809715734, 21938.0, 22033.89830508468, 22107.889460552593, 919.1911118215927, 931.9673811416703, 932.9207017403461, 933.0809152984441, 1014.5028935821542, 10739.946300268448, 11617.083181275164, 11684.786683662061, 11686.941565292289, 11687.415629218505, 576068.0, 577462.2253777493, 582827.0, 604768.0, 604979.0, 116504.0, 118786.0, 121125.15499380103, 121209.0, 121345.57308853774, 997.7405874472689, 997.8645698205845, 1012.7012531303136, 1023.155896385485, 1024.5799222318813, 18814.717779233597, 19252.518687032938, 19299.903500482407, 19399.515012124808, 19476.0, 53054.204186936455, 53215.46784532105, 53767.053095177514, 53950.46049539612, 54265.45734542762, 45379.0, 56337.154942676716, 74474.62762686369, 75596.24403755892, 76578.46843062996, 7615.733449329468, 7624.961875190588, 7789.065312162576, 7792.1428642848105, 8198.672053118047, 12237.755244894874, 12508.499660013498, 12757.744845103232, 13097.083204175617, 13126.934365328112, 10807.513661885392, 10991.835122473329, 11261.71845703864, 11438.599649012245, 11481.368524730886, 10044.246681498771, 10892.801791803055, 11196.66410007701, 11525.942370288094, 11613.070954323803, 11628.825567616323, 11645.534178632759, 11659.591914282959, 11706.297622142698, 11811.586594469152, 8191.221833925768, 11695.0, 11794.587189448328, 11837.76324473523, 12453.377331133588, 11678.532858685558, 11737.237079589799, 11964.880351196376, 12064.51741930348, 12241.20432171906], "value": 9631.432591709221, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1407.1908652524794, 1466.9413223471063, 1622.4483675550312, 1850.2876392588848, 1877.971830422544, 7446.51597646154, 8353.498790072592, 8826.249768769647, 8858.822823543533, 8903.243224325925, 7810.609469526516, 8035.236652518016, 8035.477693949889, 8451.323894088473, 8659.653613855438, 2245.382519807054, 2311.861288322706, 2312.9653055204103, 2313.710786151731, 2316.513532158249, 2347.4366152123453, 2382.1424287256573, 2383.13015749252, 2400.31590996566, 2405.482821193443, 2361.0437772702085, 2370.277065495023, 2377.8811059447044, 2389.3309873235435, 2402.8678422686744, 2107.9894600527055, 2311.838171328008, 2352.1532248390567, 2371.8814059297138, 2382.2257766226044, 2336.6027775278076, 2364.75170107138, 2368.9407764805846, 2371.0794256651993, 2374.655674927143, 8783.297336213087, 8805.559722013937, 8842.557872106368, 8844.646214151426, 8870.911290887074, 5163.690178589259, 5317.680939143625, 5322.574194064466, 5334.22653715213, 5335.959487899861, 3421.6578342165835, 3512.9297414051575, 3665.4685070664873, 3666.6150054244454, 3670.7797532148156, 1039.0596510158243, 1052.4001319248032, 1057.3761480726332, 1075.2258373970728, 1081.8160912644792, 11420.714482138013, 11589.13081518881, 11590.420478976102, 11606.593769218121, 11610.825837612443, 4362.8909277267685, 4543.886402839956, 4681.274402467623, 4792.161371759998, 4794.2089555223, 9692.176165026052, 9832.950835245778, 9837.60649574009, 9839.852402213972, 9845.852312215322, 5019.623528235324, 5069.543741063322, 5092.414372347153, 5092.974535127375, 5096.108181068298, 4265.488141422987, 4308.827646894152, 4671.719696818202, 4674.579287864109, 4814.470408255089, 3838.6353296436796, 3851.171998020439, 3929.2141571685206, 3956.841726330972, 3982.4026396040244, 1261.2495565138727, 1274.6303571964027, 1276.9361531923366, 1286.3439645780531, 1288.3429450980068, 805.8912046873714, 870.9259712924346, 896.3770179725159, 923.5982347678781, 930.9581068851783, 5198.714070726146, 5667.773289068391, 5812.6221795584, 5923.289205295392, 6031.125486804389, 911.0070023674227, 919.7332773495746, 925.8194652042922, 938.7606160429046, 980.3970558106713, 3767.9261410497875, 3917.275304068756, 3931.7837518936735, 4044.3529035354204, 4045.6763458923215, 9041.0, 10838.729031774112, 10842.674719758419, 10857.837132443019, 10864.565417383215, 10765.30025548337, 10794.406307652998, 10825.13398928084, 10831.0, 10845.186611004048, 7679.040119985002, 8051.959740201261, 8068.193180682002, 8093.271605555528, 8243.381746368927, 5821.679807610622, 5889.146073819186, 5937.881242375215, 5960.552958528041, 6029.155918171372, 9334.299927505328, 9463.763405914908, 9672.806543868943, 9767.706968790948, 9807.264455166034, 9148.176664100263, 9598.088181622734, 9711.0, 9788.559514821707, 9900.306978511435, 6049.848753781191, 7401.925980740124, 7443.627818609156, 7519.285667861764, 7579.052618422697, 7431.851362972819, 7925.5733967886335, 8256.174382561696, 8317.542535160625, 8322.875156872531, 7264.237255088351, 7339.706411743684, 7360.595167265852, 7435.442341824493, 7492.700291988477, 7261.3101755333155, 7269.890951635835, 7274.381677557467, 7283.817904552428, 7400.888986665097, 6878.484113691593, 6923.8961415579815, 6991.335823096798, 6995.915633076881, 7009.369156775914, 4946.925796113133, 4966.627502937222, 5049.990001999541, 5053.949460505495, 5110.3356563646485, 4894.017922741153, 5000.074986127578, 5042.974785126051, 5189.0, 5205.479452054916], "value": 4540.85383527921, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [264.3932175656867, 296.7551769789923, 305.2445198134617, 307.5955119018491, 310.07597359867594, 4251.702380833343, 5232.816851410202, 5253.264542963979, 5253.4221235664045, 5289.841304760854, 4209.347551129572, 4808.134535783557, 5162.251473536335, 5187.221916712495, 5189.431231540393, 3766.43882160131, 3810.733248672601, 3879.301725689375, 3978.18447218321, 3995.180987897474, 3339.6159441664277, 3994.4621320791557, 4004.299247631667, 4044.746128700098, 4045.8786236412957, 3301.7783420134583, 3976.2107051826647, 3980.8208630611693, 3984.422258772476, 3989.760614363133, 3567.2687099144614, 3728.869489567879, 3981.5023122109737, 3998.780067096294, 4014.69522405218, 3102.395032968572, 3250.756193285489, 3419.5212670226188, 3466.8959931202103, 3477.8608855645743, 5015.0, 5022.8196373852115, 5055.848324550269, 5060.84817455477, 5079.323823138356, 4157.8960525987095, 4158.126793373395, 4251.319788833803, 4373.234683930299, 4521.796519156613, 4480.417545719036, 5328.215047958938, 5428.782848686087, 5456.863578410532, 5493.917591236134, 3164.984175079133, 3772.3398405278967, 3773.2453509298252, 3787.3561494545993, 3830.3105441020607, 4592.885177870547, 5497.670139791625, 5717.599768016241, 5803.738831752539, 5808.6224395414165, 3235.433799085151, 3268.771186016956, 3684.949789310034, 3784.745119197488, 3803.695704343646, 3130.3269796993754, 3323.7839540429795, 3820.8662696806036, 3844.866099314851, 3875.2443273561385, 3435.845386957593, 3750.6497660842074, 4493.415855938707, 4514.255147900625, 4535.231259808677, 5292.285541451854, 5486.31421072366, 5516.448355164452, 5527.170924361378, 5538.252335934678, 1293.4373547506864, 1295.3976400973404, 1306.9607911762662, 1307.3920626908532, 1314.9480415667429, 760.5208718507415, 789.1516619633917, 803.0684406088909, 803.7468197517763, 806.1414593457948, 4141.97929010353, 4276.161250662245, 4504.391907092566, 5040.7731652075745, 5117.948820511821, 4702.764861756967, 4796.616270698336, 5271.920921186185, 5646.378898321182, 5689.231953686282, 4314.115606300701, 4615.469221039623, 4975.726335051534, 5179.378474583074, 5269.394019687785, 2904.404597057598, 2924.605178300902, 2969.792114552004, 2975.4495418347674, 2979.7765167612515, 4740.928886066712, 5071.2139618359215, 5148.588112950955, 5840.364697884599, 5877.706114694248, 4707.8587642370785, 4810.7835147418455, 5468.316460442445, 5786.623869448473, 5851.1515830205235, 5188.521271437547, 5300.893982120336, 5425.213344065089, 5922.615030023035, 5971.850703732354, 5030.0322809541085, 5719.57103217252, 5751.9424805751405, 5777.711114444177, 5803.738831752666, 4937.7531123444405, 4954.281629163823, 5247.947520524746, 5613.635113717595, 5666.490015898589, 4859.222524396221, 5502.752376143003, 5943.3849106393045, 5948.018576934757, 6016.781022153127, 3273.869045238164, 3694.0949467380665, 3869.438931354994, 3880.8253628586217, 3899.863504777319, 4700.76496175183, 5557.693941923726, 5649.435056494318, 5712.0, 5728.71104001613, 5535.750891209986, 5545.8388718431725, 5548.167774833807, 5558.777648893999, 5573.467296493334, 4584.9913019137075, 5315.335583052119, 5455.918161227664, 5562.833115006556, 5618.04493236159, 4651.023375014449, 4658.883527911829, 5235.973820130875, 5438.9394068156325, 5471.945280547303, 5281.313429254326, 5427.592930530147, 5459.0, 5552.972700050504, 5559.471850174227, 5130.615203859651, 5381.7352922064065, 5454.481824226692, 5484.067708489487, 5505.201745746925], "value": 3954.5888967048136, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [702.6521871673523, 703.4899697719153, 758.1205801270523, 806.1615919443778, 808.1312588966862, 15644.843551564481, 15866.0, 15891.364345426198, 15909.204539773023, 15911.363545458167, 14174.220417877013, 14268.215248161347, 14279.286035698226, 14296.285185740697, 14377.856221437782, 676.6751959059654, 698.8148140742708, 701.7087908517984, 833.1834801894147, 840.1430540848331, 847.9660813567481, 849.218718778725, 850.9234168924795, 853.9615717292705, 862.2670729879615, 699.9685014174374, 906.7415786500849, 907.7639813648433, 907.8638204269347, 911.3802614222359, 898.6046139698511, 900.8693739407814, 902.7562558109306, 903.0, 906.2659246010719, 523.1786095829549, 540.8755986123174, 543.9592030597721, 653.4315145823165, 666.1074160624738, 14871.553853384416, 15260.465883694129, 15297.541073767694, 15299.923500382538, 15302.770458443132, 13377.26425046622, 13564.186148831002, 13608.591742247749, 13869.104956602921, 14031.579052628336, 12109.212901161396, 12339.197952133083, 12391.750825766938, 12437.564685236064, 12802.0, 4444.35556844259, 4497.302918047708, 4604.217283061887, 4609.861704148881, 4713.929291060636, 95685.52157239169, 113804.86195138026, 115116.0, 115189.0, 117490.0, 10725.249232553802, 10793.352398856094, 10858.0, 10964.671059868217, 11006.944965275123, 20938.895305523376, 21019.0, 21047.894760526404, 21047.947602619806, 21065.15739370408, 7701.653425595861, 7753.069631644126, 7800.648970796327, 7839.608019598997, 7847.529148251123, 4264.616184543406, 4314.9784251078545, 4327.697061205686, 4363.952651363713, 4499.055198408371, 3323.4350160472777, 3841.5390153181797, 4010.8796736097966, 4030.556638769733, 4057.695672824491, 711.5908352697116, 725.4704066031721, 736.5249414127858, 742.4283301857539, 748.8764353881658, 564.6470955652717, 567.8551969247816, 602.4397310501188, 625.8185126313318, 675.1729131813489, 7791.727289544839, 8367.790805229917, 9147.679831205876, 9197.632094716137, 9371.437713737198, 619.4177473175201, 636.4780879678619, 639.9808005759835, 648.4423395879539, 649.3506493506494, 3445.8277086146095, 4465.106978604292, 4507.625174321798, 4561.452625684873, 4591.9081618367445, 100291.49854250682, 112569.0, 139807.0, 152461.23769381084, 175134.0, 117474.41262793803, 144205.0, 144384.27807860894, 145668.0, 146807.0, 63852.36147638557, 76479.23520764832, 106178.0, 118848.40575797066, 119082.2137667953, 8272.751817445485, 8495.192956669107, 8516.70191543293, 8579.35654825878, 8776.78058048554, 63932.3606763945, 66287.38782415958, 67000.6649966747, 68228.0, 68912.65543672249, 61885.69057154866, 104824.47587762012, 106511.0, 106657.46671266593, 106967.0, 13213.867861321649, 13227.537036203687, 13349.733005339645, 13578.728425431633, 13992.230427326187, 12657.367131643197, 14406.711865762836, 14631.853681463475, 14797.89015823796, 15262.3895044197, 13708.3831227593, 13900.930495347458, 14018.509352172625, 14083.859161408254, 14233.146011239358, 11884.643460696192, 13505.39225734864, 13623.114497557626, 14062.718745625234, 14380.137191768108, 13611.727765444437, 13701.725965480833, 13776.517821876187, 13803.51687690926, 13848.446062157402, 10286.228532859916, 10965.890341096487, 10994.177677944203, 11324.32054076758, 11384.601538946097, 11328.433578320886, 11414.771704565695, 11491.770164596828, 11653.825192622287, 11677.299362038304], "value": 7813.920291096098, "unit": "score"}], "name": "dromaeo_css", "value": 7246.8595799584955, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 111.0, 113.0, 112.0, 112.0, 113.0, 113.0, 112.0, 110.0, 112.0], "value": 112.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 119.0, 140.0, 180.0, 134.0, 110.0, 113.0, 133.0, 142.0, 109.0], "value": 132.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [226.0, 257.0, 183.0, 184.0, 185.0, 182.0, 181.0, 187.0, 180.0, 185.0], "value": 195.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 60.0, 58.0, 89.0, 63.0, 59.0, 58.0, 58.0, 78.0, 72.0], "value": 67.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 97.0, 125.0, 102.0, 83.0, 70.0, 92.0, 68.0, 68.0, 89.0], "value": 87.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 83.0, 86.0, 83.0, 90.0, 84.0, 87.0, 84.0, 88.0, 84.0], "value": 85.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 93.0, 93.0, 94.0, 94.0, 93.0, 93.0, 94.0, 92.0, 93.0], "value": 93.2, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 86.0, 87.0, 84.0, 85.0, 86.0, 84.0, 85.0, 85.0, 84.0], "value": 85.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 54.0, 53.0, 52.0, 54.0, 55.0, 56.0, 54.0, 55.0, 55.0], "value": 54.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 72.0, 75.0, 75.0, 73.0, 70.0, 69.0, 70.0, 69.0, 72.0], "value": 71.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 88.0, 85.0, 88.0, 85.0, 84.0, 84.0, 87.0, 87.0, 105.0], "value": 87.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [138.0, 144.0, 147.0, 150.0, 148.0, 137.0, 139.0, 143.0, 165.0, 139.0], "value": 145.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [172.0, 169.0, 170.0, 170.0, 171.0, 170.0, 168.0, 170.0, 169.0, 168.0], "value": 169.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 67.0, 67.0, 67.0, 67.0, 67.0, 68.0, 68.0, 66.0, 67.0], "value": 67.2, "unit": "score"}], "name": "kraken", "value": 1453.4000000000003, "alertThreshold": 2.0}]} 03:31:22 INFO - Return code: 0 03:31:22 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 03:31:23 INFO - # TBPL SUCCESS # 03:31:23 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 03:31:23 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 03:31:23 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 03:31:23 INFO - 2016-09-30 03:31:23.019 screenresolution[1893:10860] starting screenresolution argv=screenresolution get 03:31:23 INFO - 2016-09-30 03:31:23.022 screenresolution[1893:10860] Display 0: 1600x1200x32@60 03:31:23 INFO - 2016-09-30 03:31:23.028 screenresolution[1894:10863] starting screenresolution argv=screenresolution list 03:31:23 INFO - Available Modes on Display 0 03:31:23 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 03:31:23 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 03:31:23 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 03:31:23 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 03:31:23 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 03:31:23 INFO - Intel Iris: 03:31:23 INFO - Chipset Model: Intel Iris 03:31:23 INFO - Type: GPU 03:31:23 INFO - Bus: Built-In 03:31:23 INFO - VRAM (Dynamic, Max): 1536 MB 03:31:23 INFO - Vendor: Intel (0x8086) 03:31:23 INFO - Device ID: 0x0a2e 03:31:23 INFO - Revision ID: 0x0009 03:31:23 INFO - Displays: 03:31:23 INFO - Display: 03:31:23 INFO - Resolution: 1600 x 1200 @ 60 Hz 03:31:23 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 03:31:23 INFO - Display Serial Number: 1600x1200 60 03:31:23 INFO - Main Display: Yes 03:31:23 INFO - Mirror: Off 03:31:23 INFO - Online: Yes 03:31:23 INFO - Rotation: Supported 03:31:23 INFO - Return code: 0 03:31:23 INFO - Running post-action listener: _resource_record_post_action 03:31:23 INFO - [mozharness: 2016-09-30 10:31:23.259968Z] Finished run-tests step (success) 03:31:23 INFO - Running post-run listener: _resource_record_post_run 03:31:23 INFO - Total resource usage - Wall time: 1213s; CPU: 54.0%; Read bytes: 32156672; Write bytes: 712673280; Read time: 199; Write time: 2025 03:31:23 INFO - TinderboxPrint: CPU usage
53.6% 03:31:23 INFO - TinderboxPrint: I/O read bytes / time
32,156,672 / 199 03:31:23 INFO - TinderboxPrint: I/O write bytes / time
712,673,280 / 2,025 03:31:23 INFO - TinderboxPrint: CPU idle
2,254.4 (46.4%) 03:31:23 INFO - TinderboxPrint: CPU system
973.2 (20.1%) 03:31:23 INFO - TinderboxPrint: CPU user
1,626.0 (33.5%) 03:31:23 INFO - TinderboxPrint: Swap in / out
436,031,488 / 0 03:31:23 INFO - install - Wall time: 35s; CPU: 55.0%; Read bytes: 404228608; Write bytes: 399095808; Read time: 29960; Write time: 801 03:31:23 INFO - run-tests - Wall time: 1179s; CPU: 54.0%; Read bytes: 29256704; Write bytes: 300609536; Read time: 178; Write time: 1186 03:31:23 INFO - Running post-run listener: _upload_blobber_files 03:31:23 INFO - Blob upload gear active. 03:31:23 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 03:31:23 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 03:31:23 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', 'Fx-Team', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 03:31:23 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 Fx-Team -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 03:31:24 INFO - (blobuploader) - INFO - Open directory for files ... 03:31:24 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 03:31:24 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:31:24 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:31:25 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 03:31:25 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:31:25 INFO - (blobuploader) - INFO - Done attempting. 03:31:25 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 03:31:25 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:31:25 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:31:25 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 03:31:25 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:31:25 INFO - (blobuploader) - INFO - Done attempting. 03:31:25 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 03:31:26 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:31:26 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:31:28 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 03:31:28 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:31:28 INFO - (blobuploader) - INFO - Done attempting. 03:31:28 INFO - (blobuploader) - INFO - Iteration through files over. 03:31:28 INFO - Return code: 0 03:31:28 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 03:31:28 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 03:31:28 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/42abeef3babe7a89741b6a1f49bdebe00315a4b62a15ed11b81a7aa34b6e3ba553c847ee491ad2e1b1bb0e7044e20bea78f3839cdbe4343a7e1382643147b588", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/15db2f62532124388e669e4ff0133927a0d69a1bed916c9771753a4824aff66eeb5d086a436f8f65f76967017110a047ae9df3625acb23bff2ee9e1200e51a19", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/6c84ec1125c69770eccf61d09173512ec1e1cd6249dfb37e0dc180f280de73f73d41dc23b52215ae90dbbe92aff445d8c173117221fdf77b395c7e6656c9477f"} 03:31:28 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 03:31:28 INFO - Writing to file /builds/slave/test/properties/blobber_files 03:31:28 INFO - Contents: 03:31:28 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/42abeef3babe7a89741b6a1f49bdebe00315a4b62a15ed11b81a7aa34b6e3ba553c847ee491ad2e1b1bb0e7044e20bea78f3839cdbe4343a7e1382643147b588", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/15db2f62532124388e669e4ff0133927a0d69a1bed916c9771753a4824aff66eeb5d086a436f8f65f76967017110a047ae9df3625acb23bff2ee9e1200e51a19", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/6c84ec1125c69770eccf61d09173512ec1e1cd6249dfb37e0dc180f280de73f73d41dc23b52215ae90dbbe92aff445d8c173117221fdf77b395c7e6656c9477f"} 03:31:28 INFO - Running post-run listener: copy_logs_to_upload_dir 03:31:28 INFO - Copying logs to upload dir... 03:31:28 INFO - mkdir: /builds/slave/test/build/upload/logs 03:31:28 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1309.591649 ========= master_lag: 0.06 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 49 secs) (at 2016-09-30 03:31:28.382830) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 2 secs) (at 2016-09-30 03:31:28.384143) ========= 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.QBha3K4Xwx/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.erbt3jYA3j/Listeners TMPDIR=/var/folders/jw/lfbylzg17jd15qryjvmblvym00000w/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/Fx-Team/sha512/42abeef3babe7a89741b6a1f49bdebe00315a4b62a15ed11b81a7aa34b6e3ba553c847ee491ad2e1b1bb0e7044e20bea78f3839cdbe4343a7e1382643147b588", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/15db2f62532124388e669e4ff0133927a0d69a1bed916c9771753a4824aff66eeb5d086a436f8f65f76967017110a047ae9df3625acb23bff2ee9e1200e51a19", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/6c84ec1125c69770eccf61d09173512ec1e1cd6249dfb37e0dc180f280de73f73d41dc23b52215ae90dbbe92aff445d8c173117221fdf77b395c7e6656c9477f"} build_url:https://queue.taskcluster.net/v1/task/Oa1drpckQO-XF4tPHFgqxQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011076 build_url: 'https://queue.taskcluster.net/v1/task/Oa1drpckQO-XF4tPHFgqxQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/42abeef3babe7a89741b6a1f49bdebe00315a4b62a15ed11b81a7aa34b6e3ba553c847ee491ad2e1b1bb0e7044e20bea78f3839cdbe4343a7e1382643147b588", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/15db2f62532124388e669e4ff0133927a0d69a1bed916c9771753a4824aff66eeb5d086a436f8f65f76967017110a047ae9df3625acb23bff2ee9e1200e51a19", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/6c84ec1125c69770eccf61d09173512ec1e1cd6249dfb37e0dc180f280de73f73d41dc23b52215ae90dbbe92aff445d8c173117221fdf77b395c7e6656c9477f"}' ========= master_lag: 2.35 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 2 secs) (at 2016-09-30 03:31:30.742870) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-30 03:31:30.743279) ========= 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.QBha3K4Xwx/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.erbt3jYA3j/Listeners TMPDIR=/var/folders/jw/lfbylzg17jd15qryjvmblvym00000w/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.005120 ========= master_lag: 0.02 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-30 03:31:30.773213) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-30 03:31:30.773580) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-30 03:31:30.773949) ========= ========= Total master_lag: 2.49 =========