builder: mozilla-beta_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0374 starttime: 1486053665.88 results: success (0) buildid: 20170202061309 builduid: e962563ef9714fb884be45cb17e3f222 revision: 339082e4a33aa237def532c990aaab2b5a4b5723 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-02-02 08:41:05.877431) ========= master: http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-02-02 08:41:05.877912) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-02-02 08:41:05.878204) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-02-02 08:41:05.904949) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-02 08:41:05.905367) ========= 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.Zip9ggWnY3/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.rr6NnwXpHR/Listeners TMPDIR=/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/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 --2017-02-02 08:41:05-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 17.7M=0.001s 2017-02-02 08:41:06 (17.7 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.094957 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-02 08:41:06.023939) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-02-02 08:41:06.024262) ========= 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.Zip9ggWnY3/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.rr6NnwXpHR/Listeners TMPDIR=/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/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.024532 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-02-02 08:41:06.072024) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-02-02 08:41:06.072399) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 339082e4a33aa237def532c990aaab2b5a4b5723 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 339082e4a33aa237def532c990aaab2b5a4b5723 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.Zip9ggWnY3/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.rr6NnwXpHR/Listeners TMPDIR=/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/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 2017-02-02 08:41:06,179 truncating revision to first 12 chars 2017-02-02 08:41:06,179 Setting DEBUG logging. 2017-02-02 08:41:06,179 attempt 1/10 2017-02-02 08:41:06,180 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/339082e4a33a?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-02-02 08:41:07,397 unpacking tar archive at: mozilla-beta-339082e4a33a/testing/mozharness/ program finished with exit code 0 elapsedTime=1.490491 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-02-02 08:41:07.585928) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-02-02 08:41:07.586246) ========= script_repo_revision: 339082e4a33aa237def532c990aaab2b5a4b5723 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-02-02 08:41:07.586637) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-02-02 08:41:07.586901) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-02-02 08:41:07.606871) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 0 secs) (at 2017-02-02 08:41:07.607268) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Beta', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Beta'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.Zip9ggWnY3/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.rr6NnwXpHR/Listeners TMPDIR=/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/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 08:41:07 INFO - MultiFileLogger online at 20170202 08:41:07 in /builds/slave/test 08:41:07 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta 08:41:07 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 08:41:07 INFO - {'append_to_log': False, 08:41:07 INFO - 'base_work_dir': '/builds/slave/test', 08:41:07 INFO - 'blob_upload_branch': 'Mozilla-Beta', 08:41:07 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 08:41:07 INFO - 'branch': 'Mozilla-Beta', 08:41:07 INFO - 'buildbot_json_path': 'buildprops.json', 08:41:07 INFO - 'config_files': ('talos/mac_config.py',), 08:41:07 INFO - 'default_actions': ('clobber', 08:41:07 INFO - 'read-buildbot-config', 08:41:07 INFO - 'download-and-extract', 08:41:07 INFO - 'populate-webroot', 08:41:07 INFO - 'create-virtualenv', 08:41:07 INFO - 'install', 08:41:07 INFO - 'run-tests'), 08:41:07 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 08:41:07 INFO - 'download_minidump_stackwalk': True, 08:41:07 INFO - 'download_symbols': 'ondemand', 08:41:07 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 08:41:07 INFO - 'tooltool.py': '/tools/tooltool.py', 08:41:07 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 08:41:07 INFO - '/tools/misc-python/virtualenv.py')}, 08:41:07 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 08:41:07 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 08:41:07 INFO - 'installer_path': 'installer.exe', 08:41:07 INFO - 'log_level': 'info', 08:41:07 INFO - 'log_name': 'talos', 08:41:07 INFO - 'log_to_console': True, 08:41:07 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 08:41:07 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 08:41:07 INFO - 'opt_config_files': (), 08:41:07 INFO - 'pip_index': False, 08:41:07 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 08:41:07 INFO - 'cmd': ('bash', 08:41:07 INFO - '-c', 08:41:07 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 08:41:07 INFO - 'enabled': True, 08:41:07 INFO - 'halt_on_failure': False, 08:41:07 INFO - 'name': 'check_screen_resolution'},), 08:41:07 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 08:41:07 INFO - 'cmd': ('bash', 08:41:07 INFO - '-c', 08:41:07 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 08:41:07 INFO - 'enabled': True, 08:41:07 INFO - 'halt_on_failure': False, 08:41:07 INFO - 'name': 'check_screen_resolution'},), 08:41:07 INFO - 'run_cmd_checks_enabled': True, 08:41:07 INFO - 'sps_profile': False, 08:41:07 INFO - 'sps_profile_interval': 0, 08:41:07 INFO - 'suite': 'dromaeojs-e10s', 08:41:07 INFO - 'system_bits': '32', 08:41:07 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 08:41:07 INFO - 'title': 't-yosemite-r7-0374', 08:41:07 INFO - 'tooltool_cache': '/builds/tooltool_cache', 08:41:07 INFO - 'use_talos_json': True, 08:41:07 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 08:41:07 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 08:41:07 INFO - 'work_dir': 'build'} 08:41:07 INFO - [mozharness: 2017-02-02 16:41:07.817123Z] Running clobber step. 08:41:07 INFO - Running pre-action listener: _resource_record_pre_action 08:41:07 INFO - Running main action method: clobber 08:41:07 INFO - rmtree: /builds/slave/test/build 08:41:07 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 08:41:10 INFO - Running post-action listener: _resource_record_post_action 08:41:10 INFO - [mozharness: 2017-02-02 16:41:10.219279Z] Finished clobber step (success) 08:41:10 INFO - [mozharness: 2017-02-02 16:41:10.219458Z] Running read-buildbot-config step. 08:41:10 INFO - Running pre-action listener: _resource_record_pre_action 08:41:10 INFO - Running main action method: read_buildbot_config 08:41:10 INFO - Using buildbot properties: 08:41:10 INFO - { 08:41:10 INFO - "project": "", 08:41:10 INFO - "product": "firefox", 08:41:10 INFO - "script_repo_revision": "production", 08:41:10 INFO - "builddir": "mozilla-beta_yosemite_r7_test-dromaeojs-e10s", 08:41:10 INFO - "repository": "", 08:41:10 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-beta talos dromaeojs-e10s", 08:41:10 INFO - "buildid": "20170202061309", 08:41:10 INFO - "pgo_build": "False", 08:41:10 INFO - "basedir": "/builds/slave/test", 08:41:10 INFO - "buildnumber": 3, 08:41:10 INFO - "slavename": "t-yosemite-r7-0374", 08:41:10 INFO - "master": "http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/", 08:41:10 INFO - "platform": "yosemite_r7", 08:41:10 INFO - "scheduler": "tests-mozilla-beta-macosx64-talos", 08:41:10 INFO - "branch": "mozilla-beta", 08:41:10 INFO - "repo_path": "releases/mozilla-beta", 08:41:10 INFO - "revision": "339082e4a33aa237def532c990aaab2b5a4b5723", 08:41:10 INFO - "stage_platform": "macosx64", 08:41:10 INFO - "builduid": "e962563ef9714fb884be45cb17e3f222", 08:41:10 INFO - "slavebuilddir": "test" 08:41:10 INFO - } 08:41:10 INFO - Found installer url https://queue.taskcluster.net/v1/task/TEyjGFceQTyCFc5rxFCMSg/artifacts/public/build/firefox-52.0.en-US.mac.dmg. 08:41:10 INFO - Running post-action listener: _resource_record_post_action 08:41:10 INFO - [mozharness: 2017-02-02 16:41:10.223629Z] Finished read-buildbot-config step (success) 08:41:10 INFO - [mozharness: 2017-02-02 16:41:10.223752Z] Running download-and-extract step. 08:41:10 INFO - Running pre-action listener: _resource_record_pre_action 08:41:10 INFO - Running main action method: download_and_extract 08:41:10 INFO - mkdir: /builds/slave/test/build/tests 08:41:10 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')]} 08:41:10 INFO - trying https://queue.taskcluster.net/v1/task/TEyjGFceQTyCFc5rxFCMSg/artifacts/public/build/firefox-52.0.en-US.mac.test_packages.json 08:41:10 INFO - Downloading https://queue.taskcluster.net/v1/task/TEyjGFceQTyCFc5rxFCMSg/artifacts/public/build/firefox-52.0.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-52.0.en-US.mac.test_packages.json 08:41:10 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/TEyjGFceQTyCFc5rxFCMSg/artifacts/public/build/firefox-52.0.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-52.0.en-US.mac.test_packages.json'}, attempt #1 08:41:11 INFO - Downloaded 1165 bytes. 08:41:11 INFO - Reading from file /builds/slave/test/build/firefox-52.0.en-US.mac.test_packages.json 08:41:11 INFO - Using the following test package requirements: 08:41:11 INFO - {u'common': [u'firefox-52.0.en-US.mac.common.tests.zip'], 08:41:11 INFO - u'cppunittest': [u'firefox-52.0.en-US.mac.common.tests.zip', 08:41:11 INFO - u'firefox-52.0.en-US.mac.cppunittest.tests.zip'], 08:41:11 INFO - u'gtest': [u'firefox-52.0.en-US.mac.common.tests.zip', 08:41:11 INFO - u'firefox-52.0.en-US.mac.gtest.tests.zip'], 08:41:11 INFO - u'jittest': [u'firefox-52.0.en-US.mac.common.tests.zip', u'jsshell-mac.zip'], 08:41:11 INFO - u'mochitest': [u'firefox-52.0.en-US.mac.common.tests.zip', 08:41:11 INFO - u'firefox-52.0.en-US.mac.mochitest.tests.zip'], 08:41:11 INFO - u'mozbase': [u'firefox-52.0.en-US.mac.common.tests.zip'], 08:41:11 INFO - u'reftest': [u'firefox-52.0.en-US.mac.common.tests.zip', 08:41:11 INFO - u'firefox-52.0.en-US.mac.reftest.tests.zip'], 08:41:11 INFO - u'talos': [u'firefox-52.0.en-US.mac.common.tests.zip', 08:41:11 INFO - u'firefox-52.0.en-US.mac.talos.tests.zip'], 08:41:11 INFO - u'web-platform': [u'firefox-52.0.en-US.mac.common.tests.zip', 08:41:11 INFO - u'firefox-52.0.en-US.mac.web-platform.tests.zip'], 08:41:11 INFO - u'xpcshell': [u'firefox-52.0.en-US.mac.common.tests.zip', 08:41:11 INFO - u'firefox-52.0.en-US.mac.xpcshell.tests.zip']} 08:41:11 INFO - Downloading packages: [u'firefox-52.0.en-US.mac.common.tests.zip'] for test suite category: common 08:41:11 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/TEyjGFceQTyCFc5rxFCMSg/artifacts/public/build/firefox-52.0.en-US.mac.common.tests.zip 08:41:11 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/TEyjGFceQTyCFc5rxFCMSg/artifacts/public/build/firefox-52.0.en-US.mac.common.tests.zip'}, attempt #1 08:41:11 INFO - Fetch https://queue.taskcluster.net/v1/task/TEyjGFceQTyCFc5rxFCMSg/artifacts/public/build/firefox-52.0.en-US.mac.common.tests.zip into memory 08:41:12 INFO - Http code: 200 08:41:12 INFO - via: 1.1 455cf6ccbccc261c46d02110de1c0237.cloudfront.net (CloudFront) 08:41:12 INFO - x-amz-cf-id: l1DiKPE3AISIv1nibIzwDaWpNyeUmx52VrFfNm0tGJQSjhXq-zQ8rA== 08:41:12 INFO - x-amz-version-id: wwGHUGKPWQBy06YJIxDQgyMB.aaSiost 08:41:12 INFO - Expected file size: 16092028 08:41:12 INFO - Obtained file size: 16092028 08:41:19 INFO - Downloading packages: [u'firefox-52.0.en-US.mac.common.tests.zip', u'firefox-52.0.en-US.mac.talos.tests.zip'] for test suite category: talos 08:41:19 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/TEyjGFceQTyCFc5rxFCMSg/artifacts/public/build/firefox-52.0.en-US.mac.common.tests.zip 08:41:19 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/TEyjGFceQTyCFc5rxFCMSg/artifacts/public/build/firefox-52.0.en-US.mac.common.tests.zip'}, attempt #1 08:41:19 INFO - Fetch https://queue.taskcluster.net/v1/task/TEyjGFceQTyCFc5rxFCMSg/artifacts/public/build/firefox-52.0.en-US.mac.common.tests.zip into memory 08:41:19 INFO - Http code: 200 08:41:19 INFO - via: 1.1 5ed093c37d643652e278a198e0556a40.cloudfront.net (CloudFront) 08:41:19 INFO - x-amz-cf-id: k274jlMxian2lPokmHTkzCcGfZTlWg8oZTNSbYEq8BmuPFokKaAvjQ== 08:41:19 INFO - x-amz-version-id: wwGHUGKPWQBy06YJIxDQgyMB.aaSiost 08:41:19 INFO - Expected file size: 16092028 08:41:19 INFO - Obtained file size: 16092028 08:41:26 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/TEyjGFceQTyCFc5rxFCMSg/artifacts/public/build/firefox-52.0.en-US.mac.talos.tests.zip 08:41:26 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/TEyjGFceQTyCFc5rxFCMSg/artifacts/public/build/firefox-52.0.en-US.mac.talos.tests.zip'}, attempt #1 08:41:26 INFO - Fetch https://queue.taskcluster.net/v1/task/TEyjGFceQTyCFc5rxFCMSg/artifacts/public/build/firefox-52.0.en-US.mac.talos.tests.zip into memory 08:41:26 INFO - Http code: 200 08:41:26 INFO - via: 1.1 ccaa055c9a26ce13da709353563e04a5.cloudfront.net (CloudFront) 08:41:26 INFO - x-amz-cf-id: 3QYxtC2U0S4WPSFc-koJ2TklSaQwXVQKTvJeVUIFeFEEf5QZdylufQ== 08:41:26 INFO - x-amz-version-id: Ka7sKJnWrJIGkA0EOthPdJ4HuU_.7ZDk 08:41:27 INFO - Expected file size: 14403005 08:41:27 INFO - Obtained file size: 14403005 08:41:27 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')]} 08:41:27 INFO - trying https://queue.taskcluster.net/v1/task/TEyjGFceQTyCFc5rxFCMSg/artifacts/public/build/firefox-52.0.en-US.mac.dmg 08:41:27 INFO - Downloading https://queue.taskcluster.net/v1/task/TEyjGFceQTyCFc5rxFCMSg/artifacts/public/build/firefox-52.0.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0.en-US.mac.dmg 08:41:27 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/TEyjGFceQTyCFc5rxFCMSg/artifacts/public/build/firefox-52.0.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-52.0.en-US.mac.dmg'}, attempt #1 08:41:29 INFO - Downloaded 82137248 bytes. 08:41:29 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/TEyjGFceQTyCFc5rxFCMSg/artifacts/public/build/firefox-52.0.en-US.mac.dmg 08:41:29 INFO - mkdir: /builds/slave/test/properties 08:41:29 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 08:41:29 INFO - Writing to file /builds/slave/test/properties/build_url 08:41:29 INFO - Contents: 08:41:29 INFO - build_url:https://queue.taskcluster.net/v1/task/TEyjGFceQTyCFc5rxFCMSg/artifacts/public/build/firefox-52.0.en-US.mac.dmg 08:41:30 INFO - Running post-action listener: _resource_record_post_action 08:41:30 INFO - Running post-action listener: set_extra_try_arguments 08:41:30 INFO - [mozharness: 2017-02-02 16:41:30.314769Z] Finished download-and-extract step (success) 08:41:30 INFO - [mozharness: 2017-02-02 16:41:30.314938Z] Running populate-webroot step. 08:41:30 INFO - Running pre-action listener: _resource_record_pre_action 08:41:30 INFO - Running main action method: populate_webroot 08:41:30 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 08:41:30 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 08:41:30 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 08:41:30 INFO - '--tppagecycles', 08:41:30 INFO - '7'], 08:41:30 INFO - 'tests': ['tsvgm']}}, 08:41:30 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 08:41:30 INFO - 'tests': ['tresize', 'tcanvasmark']}, 08:41:30 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 08:41:30 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 08:41:30 INFO - 'tests': ['dromaeo_css', 'kraken']}, 08:41:30 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 08:41:30 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:41:30 INFO - 'talos_options': ['--disable-e10s'], 08:41:30 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:41:30 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:41:30 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:41:30 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:41:30 INFO - 'talos_options': ['--disable-e10s'], 08:41:30 INFO - 'tests': ['damp', 'tps']}, 08:41:30 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:41:30 INFO - 'tests': ['damp', 'tps']}, 08:41:30 INFO - 'g3': {'talos_options': ['--disable-e10s'], 08:41:30 INFO - 'tests': ['dromaeo_dom']}, 08:41:30 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 08:41:30 INFO - 'g4': {'talos_options': ['--disable-e10s'], 08:41:30 INFO - 'tests': ['basic_compositor_video']}, 08:41:30 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 08:41:30 INFO - 'other': {'talos_options': ['--disable-e10s'], 08:41:30 INFO - 'tests': ['a11yr', 08:41:30 INFO - 'ts_paint', 08:41:30 INFO - 'tpaint', 08:41:30 INFO - 'sessionrestore', 08:41:30 INFO - 'sessionrestore_no_auto_restore', 08:41:30 INFO - 'tabpaint']}, 08:41:30 INFO - 'other-e10s': {'tests': ['a11yr', 08:41:30 INFO - 'ts_paint', 08:41:30 INFO - 'tpaint', 08:41:30 INFO - 'sessionrestore', 08:41:30 INFO - 'sessionrestore_no_auto_restore', 08:41:30 INFO - 'tabpaint']}, 08:41:30 INFO - 'other-e10s_l64': {'tests': ['a11yr', 08:41:30 INFO - 'ts_paint', 08:41:30 INFO - 'tpaint', 08:41:30 INFO - 'sessionrestore', 08:41:30 INFO - 'sessionrestore_no_auto_restore', 08:41:30 INFO - 'tabpaint']}, 08:41:30 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 08:41:30 INFO - 'ts_paint', 08:41:30 INFO - 'tpaint', 08:41:30 INFO - 'sessionrestore', 08:41:30 INFO - 'sessionrestore_no_auto_restore', 08:41:30 INFO - 'tabpaint']}, 08:41:30 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 08:41:30 INFO - 'tests': ['a11yr', 08:41:30 INFO - 'ts_paint', 08:41:30 INFO - 'tpaint', 08:41:30 INFO - 'sessionrestore', 08:41:30 INFO - 'sessionrestore_no_auto_restore', 08:41:30 INFO - 'tabpaint']}, 08:41:30 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 08:41:30 INFO - 'tests': ['a11yr', 08:41:30 INFO - 'ts_paint', 08:41:30 INFO - 'tpaint', 08:41:30 INFO - 'sessionrestore', 08:41:30 INFO - 'sessionrestore_no_auto_restore', 08:41:30 INFO - 'tabpaint']}, 08:41:30 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 08:41:30 INFO - 'tests': ['tsvgx', 08:41:30 INFO - 'tsvgr_opacity', 08:41:30 INFO - 'tart', 08:41:30 INFO - 'tscrollx', 08:41:30 INFO - 'cart']}, 08:41:30 INFO - 'svgr-e10s': {'tests': ['tsvgx', 08:41:30 INFO - 'tsvgr_opacity', 08:41:30 INFO - 'tart', 08:41:30 INFO - 'tscrollx', 08:41:30 INFO - 'cart']}, 08:41:30 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:41:30 INFO - 'talos_options': ['--disable-e10s'], 08:41:30 INFO - 'tests': ['tp5o']}, 08:41:30 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:41:30 INFO - 'tests': ['tp5o']}, 08:41:30 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:41:30 INFO - 'talos_options': ['--disable-e10s', 08:41:30 INFO - '--xperf_path', 08:41:30 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:41:30 INFO - 'tests': ['tp5n']}, 08:41:30 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:41:30 INFO - 'talos_options': ['--xperf_path', 08:41:30 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:41:30 INFO - 'tests': ['tp5n']}}, 08:41:30 INFO - 'talos.zip': {'path': '', 08:41:30 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 08:41:30 INFO - Running post-action listener: _resource_record_post_action 08:41:30 INFO - [mozharness: 2017-02-02 16:41:30.333444Z] Finished populate-webroot step (success) 08:41:30 INFO - [mozharness: 2017-02-02 16:41:30.333533Z] Running create-virtualenv step. 08:41:30 INFO - Running pre-action listener: _resource_record_pre_action 08:41:30 INFO - Running main action method: create_virtualenv 08:41:30 INFO - Creating virtualenv /builds/slave/test/build/venv 08:41:30 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 08:41:30 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 08:41:30 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 08:41:30 INFO - Using real prefix '/tools/python27' 08:41:30 INFO - New python executable in /builds/slave/test/build/venv/bin/python 08:41:31 INFO - Installing distribute.............................................................................................................................................................................................done. 08:41:34 INFO - Installing pip.................done. 08:41:34 INFO - Return code: 0 08:41:34 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 08:41:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 08:41:34 INFO - Reading from file tmpfile_stdout 08:41:34 INFO - Output received: 08:41:34 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) 08:41:34 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 08:41: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')]} 08:41:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:41:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:41: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')]} 08:41:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:41:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:41:34 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 0x108575490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1084eb570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffe4b482140>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108389d30>, '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 0x1081efdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108381b20>, '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.Zip9ggWnY3/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.rr6NnwXpHR/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/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 08:41:34 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 08:41:34 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 08:41:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Zip9ggWnY3/Render', 08:41:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:41:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:41:34 INFO - 'HOME': '/Users/cltbld', 08:41:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:41:34 INFO - 'LOGNAME': 'cltbld', 08:41:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:41:34 INFO - 'MOZ_NO_REMOTE': '1', 08:41:34 INFO - 'NO_EM_RESTART': '1', 08:41:34 INFO - 'PAGER': '/bin/cat', 08:41:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:41:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:41:34 INFO - 'PWD': '/builds/slave/test', 08:41:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:41:34 INFO - 'SHELL': '/bin/bash', 08:41:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.rr6NnwXpHR/Listeners', 08:41:34 INFO - 'TMPDIR': '/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/T/', 08:41:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:41:34 INFO - 'USER': 'cltbld', 08:41:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:41:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:41:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:41:34 INFO - 'XPC_FLAGS': '0x0', 08:41:34 INFO - 'XPC_SERVICE_NAME': '0', 08:41:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:41:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:41:34 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 08:41:34 INFO - Cleaning up... 08:41:34 INFO - Return code: 0 08:41:34 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 08:41: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')]} 08:41:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:41:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:41: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')]} 08:41:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:41:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:41:34 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 0x108575490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1084eb570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffe4b482140>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108389d30>, '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 0x1081efdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108381b20>, '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.Zip9ggWnY3/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.rr6NnwXpHR/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/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 08:41:34 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 08:41:34 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 08:41:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Zip9ggWnY3/Render', 08:41:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:41:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:41:34 INFO - 'HOME': '/Users/cltbld', 08:41:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:41:34 INFO - 'LOGNAME': 'cltbld', 08:41:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:41:34 INFO - 'MOZ_NO_REMOTE': '1', 08:41:34 INFO - 'NO_EM_RESTART': '1', 08:41:34 INFO - 'PAGER': '/bin/cat', 08:41:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:41:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:41:34 INFO - 'PWD': '/builds/slave/test', 08:41:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:41:34 INFO - 'SHELL': '/bin/bash', 08:41:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.rr6NnwXpHR/Listeners', 08:41:34 INFO - 'TMPDIR': '/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/T/', 08:41:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:41:34 INFO - 'USER': 'cltbld', 08:41:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:41:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:41:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:41:34 INFO - 'XPC_FLAGS': '0x0', 08:41:34 INFO - 'XPC_SERVICE_NAME': '0', 08:41:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:41:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:41:34 INFO - Downloading/unpacking psutil>=3.1.1 08:41:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:41:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:41:34 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:41:34 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:41:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:41:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:41:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 08:41:38 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:41:38 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:41:38 INFO - Installing collected packages: psutil 08:41:38 INFO - Running setup.py install for psutil 08:41:38 INFO - building 'psutil._psutil_osx' extension 08:41:38 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 08:41:38 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 08:41:39 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 08:41:39 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 08:41:39 INFO - building 'psutil._psutil_posix' extension 08:41:39 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 08:41:39 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 08:41:39 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 08:41:39 INFO - ^ 08:41:39 INFO - 1 warning generated. 08:41:39 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 08:41:39 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:41:39 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:41:39 INFO - Successfully installed psutil 08:41:39 INFO - Cleaning up... 08:41:39 INFO - Return code: 0 08:41:39 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 08:41:39 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')]} 08:41:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:41:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:41:39 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')]} 08:41:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:41:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:41:39 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 0x108575490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1084eb570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffe4b482140>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108389d30>, '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 0x1081efdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108381b20>, '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.Zip9ggWnY3/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.rr6NnwXpHR/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/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 08:41:39 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 08:41:39 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 08:41:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Zip9ggWnY3/Render', 08:41:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:41:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:41:39 INFO - 'HOME': '/Users/cltbld', 08:41:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:41:39 INFO - 'LOGNAME': 'cltbld', 08:41:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:41:39 INFO - 'MOZ_NO_REMOTE': '1', 08:41:39 INFO - 'NO_EM_RESTART': '1', 08:41:39 INFO - 'PAGER': '/bin/cat', 08:41:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:41:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:41:39 INFO - 'PWD': '/builds/slave/test', 08:41:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:41:39 INFO - 'SHELL': '/bin/bash', 08:41:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.rr6NnwXpHR/Listeners', 08:41:39 INFO - 'TMPDIR': '/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/T/', 08:41:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:41:39 INFO - 'USER': 'cltbld', 08:41:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:41:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:41:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:41:39 INFO - 'XPC_FLAGS': '0x0', 08:41:39 INFO - 'XPC_SERVICE_NAME': '0', 08:41:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:41:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:41:39 INFO - Downloading/unpacking mozsystemmonitor==0.3 08:41:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:41:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:41:39 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:41:39 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:41:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:41:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:41:42 INFO - Downloading mozsystemmonitor-0.3.tar.gz 08:41:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 08:41:42 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 08:41:42 INFO - Installing collected packages: mozsystemmonitor 08:41:42 INFO - Running setup.py install for mozsystemmonitor 08:41:42 INFO - Successfully installed mozsystemmonitor 08:41:42 INFO - Cleaning up... 08:41:42 INFO - Return code: 0 08:41:42 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 08:41: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')]} 08:41:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:41:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:41: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')]} 08:41:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:41:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:41: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', '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 0x108575490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1084eb570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffe4b482140>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108389d30>, '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 0x1081efdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108381b20>, '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.Zip9ggWnY3/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.rr6NnwXpHR/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/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 08:41: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', 'jsonschema==2.5.1'] in /builds/slave/test/build 08:41: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 jsonschema==2.5.1 08:41:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Zip9ggWnY3/Render', 08:41:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:41:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:41:42 INFO - 'HOME': '/Users/cltbld', 08:41:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:41:42 INFO - 'LOGNAME': 'cltbld', 08:41:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:41:42 INFO - 'MOZ_NO_REMOTE': '1', 08:41:42 INFO - 'NO_EM_RESTART': '1', 08:41:42 INFO - 'PAGER': '/bin/cat', 08:41:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:41:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:41:42 INFO - 'PWD': '/builds/slave/test', 08:41:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:41:42 INFO - 'SHELL': '/bin/bash', 08:41:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.rr6NnwXpHR/Listeners', 08:41:42 INFO - 'TMPDIR': '/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/T/', 08:41:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:41:42 INFO - 'USER': 'cltbld', 08:41:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:41:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:41:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:41:42 INFO - 'XPC_FLAGS': '0x0', 08:41:42 INFO - 'XPC_SERVICE_NAME': '0', 08:41:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:41:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:41:42 INFO - Downloading/unpacking jsonschema==2.5.1 08:41: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 08:41: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 08:41: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 08:41: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 08:41: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 08:41: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 08:41:45 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 08:41:45 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 08:41:45 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 08:41:45 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 08:41:45 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 08:41:45 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 08:41:45 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 08:41:45 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 08:41:45 INFO - Downloading functools32-3.2.3-2.tar.gz 08:41:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 08:41:45 INFO - warning: no files found matching '*.txt' 08:41:45 INFO - no previously-included directories found matching 'build' 08:41:45 INFO - no previously-included directories found matching 'dist' 08:41:45 INFO - no previously-included directories found matching '.git*' 08:41:45 INFO - Installing collected packages: jsonschema, functools32 08:41:46 INFO - Running setup.py install for functools32 08:41:46 INFO - warning: no files found matching '*.txt' 08:41:46 INFO - no previously-included directories found matching 'build' 08:41:46 INFO - no previously-included directories found matching 'dist' 08:41:46 INFO - no previously-included directories found matching '.git*' 08:41:46 INFO - Successfully installed jsonschema functools32 08:41:46 INFO - Cleaning up... 08:41:46 INFO - Return code: 0 08:41:46 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 08:41:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:41:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:41:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:41:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:41:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:41:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:41:46 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 0x108575490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1084eb570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffe4b482140>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108389d30>, '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 0x1081efdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108381b20>, '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.Zip9ggWnY3/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.rr6NnwXpHR/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/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 08:41:46 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 08:41:46 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 08:41:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Zip9ggWnY3/Render', 08:41:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:41:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:41:46 INFO - 'HOME': '/Users/cltbld', 08:41:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:41:46 INFO - 'LOGNAME': 'cltbld', 08:41:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:41:46 INFO - 'MOZ_NO_REMOTE': '1', 08:41:46 INFO - 'NO_EM_RESTART': '1', 08:41:46 INFO - 'PAGER': '/bin/cat', 08:41:46 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:41:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:41:46 INFO - 'PWD': '/builds/slave/test', 08:41:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:41:46 INFO - 'SHELL': '/bin/bash', 08:41:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.rr6NnwXpHR/Listeners', 08:41:46 INFO - 'TMPDIR': '/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/T/', 08:41:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:41:46 INFO - 'USER': 'cltbld', 08:41:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:41:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:41:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:41:46 INFO - 'XPC_FLAGS': '0x0', 08:41:46 INFO - 'XPC_SERVICE_NAME': '0', 08:41:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:41:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:41:46 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 08:41:46 INFO - Cleaning up... 08:41:46 INFO - Return code: 0 08:41:46 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 08:41:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:41:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:41:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:41:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:41:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:41:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:41:46 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 0x108575490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1084eb570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffe4b482140>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108389d30>, '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 0x1081efdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108381b20>, '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.Zip9ggWnY3/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.rr6NnwXpHR/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/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 08:41:46 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 08:41:46 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 08:41:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Zip9ggWnY3/Render', 08:41:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:41:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:41:46 INFO - 'HOME': '/Users/cltbld', 08:41:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:41:46 INFO - 'LOGNAME': 'cltbld', 08:41:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:41:46 INFO - 'MOZ_NO_REMOTE': '1', 08:41:46 INFO - 'NO_EM_RESTART': '1', 08:41:46 INFO - 'PAGER': '/bin/cat', 08:41:46 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:41:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:41:46 INFO - 'PWD': '/builds/slave/test', 08:41:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:41:46 INFO - 'SHELL': '/bin/bash', 08:41:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.rr6NnwXpHR/Listeners', 08:41:46 INFO - 'TMPDIR': '/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/T/', 08:41:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:41:46 INFO - 'USER': 'cltbld', 08:41:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:41:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:41:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:41:46 INFO - 'XPC_FLAGS': '0x0', 08:41:46 INFO - 'XPC_SERVICE_NAME': '0', 08:41:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:41:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:41:46 INFO - Downloading/unpacking blobuploader==1.2.4 08:41:46 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 08:41:46 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 08:41:46 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 08:41:46 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 08:41:46 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 08:41:46 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 08:41:49 INFO - Downloading blobuploader-1.2.4.tar.gz 08:41:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 08:41:49 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 08:41:49 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 08:41:49 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 08:41:49 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 08:41:49 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 08:41:49 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 08:41:49 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 08:41:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 08:41:50 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 08:41: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 08:41: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 08:41: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 08:41: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 08:41: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 08:41: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 08:41:50 INFO - Downloading docopt-0.6.1.tar.gz 08:41:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 08:41:50 INFO - Installing collected packages: blobuploader, requests, docopt 08:41:50 INFO - Running setup.py install for blobuploader 08:41:50 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 08:41:50 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 08:41:50 INFO - Running setup.py install for requests 08:41:51 INFO - Running setup.py install for docopt 08:41:51 INFO - Successfully installed blobuploader requests docopt 08:41:51 INFO - Cleaning up... 08:41:51 INFO - Return code: 0 08:41:51 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:41:51 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')]} 08:41:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:41:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:41:51 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')]} 08:41:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:41:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:41:51 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 0x108575490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1084eb570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffe4b482140>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108389d30>, '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 0x1081efdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108381b20>, '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.Zip9ggWnY3/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.rr6NnwXpHR/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/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 08:41:51 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 08:41:51 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 08:41:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Zip9ggWnY3/Render', 08:41:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:41:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:41:51 INFO - 'HOME': '/Users/cltbld', 08:41:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:41:51 INFO - 'LOGNAME': 'cltbld', 08:41:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:41:51 INFO - 'MOZ_NO_REMOTE': '1', 08:41:51 INFO - 'NO_EM_RESTART': '1', 08:41:51 INFO - 'PAGER': '/bin/cat', 08:41:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:41:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:41:51 INFO - 'PWD': '/builds/slave/test', 08:41:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:41:51 INFO - 'SHELL': '/bin/bash', 08:41:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.rr6NnwXpHR/Listeners', 08:41:51 INFO - 'TMPDIR': '/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/T/', 08:41:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:41:51 INFO - 'USER': 'cltbld', 08:41:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:41:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:41:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:41:51 INFO - 'XPC_FLAGS': '0x0', 08:41:51 INFO - 'XPC_SERVICE_NAME': '0', 08:41:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:41:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:41:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:41:51 INFO - Running setup.py (path:/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/T/pip-luLW5_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:41:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:41:52 INFO - Running setup.py (path:/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/T/pip-bX8e9o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:41:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:41:52 INFO - Running setup.py (path:/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/T/pip-kIN14r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:41:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:41:52 INFO - Running setup.py (path:/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/T/pip-OtPeob-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:41:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:41:52 INFO - Running setup.py (path:/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/T/pip-EsWkoj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:41:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:41:52 INFO - Running setup.py (path:/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/T/pip-HWl2rw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:41:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:41:52 INFO - Running setup.py (path:/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/T/pip-Q1bzxH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:41:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:41:52 INFO - Running setup.py (path:/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/T/pip-SSe8gS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:41:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:41:52 INFO - Running setup.py (path:/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/T/pip-eVK9ki-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:41:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:41:52 INFO - Running setup.py (path:/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/T/pip-soT4Hb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:41:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:41:52 INFO - Running setup.py (path:/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/T/pip-4XwUgd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:41:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:41:53 INFO - Running setup.py (path:/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/T/pip-KOUzgz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:41:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:41:53 INFO - Running setup.py (path:/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/T/pip-MgqT5g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:41:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:41:53 INFO - Running setup.py (path:/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/T/pip-Qg_fQH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:41:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:41:53 INFO - Running setup.py (path:/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/T/pip-hbCup9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:41:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:41:53 INFO - Running setup.py (path:/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/T/pip-Dcq46V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:41:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:41:53 INFO - Running setup.py (path:/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/T/pip-8Irq4v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:41:53 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 08:41:53 INFO - Running setup.py install for manifestparser 08:41:53 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 08:41:53 INFO - Running setup.py install for mozcrash 08:41:53 INFO - Running setup.py install for mozdebug 08:41:54 INFO - Running setup.py install for mozdevice 08:41:54 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 08:41:54 INFO - Installing dm script to /builds/slave/test/build/venv/bin 08:41:54 INFO - Running setup.py install for mozfile 08:41:54 INFO - Running setup.py install for mozhttpd 08:41:54 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 08:41:54 INFO - Running setup.py install for mozinfo 08:41:54 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 08:41:54 INFO - Running setup.py install for mozInstall 08:41:54 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 08:41:54 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 08:41:54 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 08:41:54 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 08:41:54 INFO - Running setup.py install for mozleak 08:41:54 INFO - Running setup.py install for mozlog 08:41:55 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 08:41:55 INFO - Running setup.py install for moznetwork 08:41:55 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 08:41:55 INFO - Running setup.py install for mozprocess 08:41:55 INFO - Running setup.py install for mozprofile 08:41:55 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 08:41:55 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 08:41:55 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 08:41:55 INFO - Running setup.py install for mozrunner 08:41:55 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 08:41:55 INFO - Running setup.py install for mozscreenshot 08:41:55 INFO - Running setup.py install for moztest 08:41:56 INFO - Running setup.py install for mozversion 08:41:56 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 08:41:56 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 08:41:56 INFO - Cleaning up... 08:41:56 INFO - Return code: 0 08:41:56 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:41:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:41:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:41:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:41:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:41:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:41:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:41:56 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 0x108575490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1084eb570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffe4b482140>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108389d30>, '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 0x1081efdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108381b20>, '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.Zip9ggWnY3/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.rr6NnwXpHR/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/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 08:41:56 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 08:41:56 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 08:41:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Zip9ggWnY3/Render', 08:41:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:41:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:41:56 INFO - 'HOME': '/Users/cltbld', 08:41:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:41:56 INFO - 'LOGNAME': 'cltbld', 08:41:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:41:56 INFO - 'MOZ_NO_REMOTE': '1', 08:41:56 INFO - 'NO_EM_RESTART': '1', 08:41:56 INFO - 'PAGER': '/bin/cat', 08:41:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:41:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:41:56 INFO - 'PWD': '/builds/slave/test', 08:41:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:41:56 INFO - 'SHELL': '/bin/bash', 08:41:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.rr6NnwXpHR/Listeners', 08:41:56 INFO - 'TMPDIR': '/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/T/', 08:41:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:41:56 INFO - 'USER': 'cltbld', 08:41:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:41:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:41:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:41:56 INFO - 'XPC_FLAGS': '0x0', 08:41:56 INFO - 'XPC_SERVICE_NAME': '0', 08:41:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:41:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:41:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:41:56 INFO - Running setup.py (path:/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/T/pip-PwOV4M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:41:56 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)) 08:41:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:41:56 INFO - Running setup.py (path:/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/T/pip-51OkbH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:41:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 08:41:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:41:56 INFO - Running setup.py (path:/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/T/pip-4yYkLP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:41:56 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)) 08:41:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:41:56 INFO - Running setup.py (path:/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/T/pip-Eebp5_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:41:56 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)) 08:41:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:41:56 INFO - Running setup.py (path:/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/T/pip-XHVP5f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:41:57 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)) 08:41:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:41:57 INFO - Running setup.py (path:/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/T/pip-rMlANI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:41:57 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)) 08:41:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:41:57 INFO - Running setup.py (path:/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/T/pip-crkbDe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:41:57 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)) 08:41:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:41:57 INFO - Running setup.py (path:/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/T/pip-8Ex3kf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:41:57 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)) 08:41:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:41:57 INFO - Running setup.py (path:/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/T/pip-X7DzET-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:41:57 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)) 08:41:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:41:57 INFO - Running setup.py (path:/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/T/pip-j1aVfp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:41:57 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)) 08:41:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:41:57 INFO - Running setup.py (path:/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/T/pip-ktUNcg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:41:57 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)) 08:41:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:41:57 INFO - Running setup.py (path:/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/T/pip-xYR7IR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:41:57 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)) 08:41:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:41:57 INFO - Running setup.py (path:/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/T/pip-ytojT9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:41:57 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)) 08:41:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:41:57 INFO - Running setup.py (path:/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/T/pip-2KGsCY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:41:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 08:41:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:41:57 INFO - Running setup.py (path:/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/T/pip-Fv9_x0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:41:58 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)) 08:41:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:41:58 INFO - Running setup.py (path:/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/T/pip-m9xQlZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:41:58 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 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)) 08:41:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:41:58 INFO - Running setup.py (path:/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/T/pip-OtSSQg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:41:58 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)) 08:41:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 08:41:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 08:41:58 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)) 08:41:58 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)) 08:41:58 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)) 08:41:58 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 08:41:58 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 08:41:58 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 08:41:58 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 08:41:58 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 08:41:58 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 08:41:58 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 08:42:00 INFO - Downloading blessings-1.6.tar.gz 08:42:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 08:42:01 INFO - Installing collected packages: blessings 08:42:01 INFO - Running setup.py install for blessings 08:42:01 INFO - Successfully installed blessings 08:42:01 INFO - Cleaning up... 08:42:01 INFO - Return code: 0 08:42:01 INFO - Done creating virtualenv /builds/slave/test/build/venv. 08:42:01 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:42:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:42:01 INFO - Reading from file tmpfile_stdout 08:42:01 INFO - Current package versions: 08:42:01 INFO - blessings == 1.6 08:42:01 INFO - blobuploader == 1.2.4 08:42:01 INFO - docopt == 0.6.1 08:42:01 INFO - functools32 == 3.2.3-2 08:42:01 INFO - jsonschema == 2.5.1 08:42:01 INFO - manifestparser == 1.1 08:42:01 INFO - mozInstall == 1.12 08:42:01 INFO - mozcrash == 1.0 08:42:01 INFO - mozdebug == 0.1 08:42:01 INFO - mozdevice == 0.48 08:42:01 INFO - mozfile == 1.2 08:42:01 INFO - mozhttpd == 0.7 08:42:01 INFO - mozinfo == 0.9 08:42:01 INFO - mozleak == 0.1 08:42:01 INFO - mozlog == 3.4 08:42:01 INFO - moznetwork == 0.27 08:42:01 INFO - mozprocess == 0.23 08:42:01 INFO - mozprofile == 0.28 08:42:01 INFO - mozrunner == 6.13 08:42:01 INFO - mozscreenshot == 0.1 08:42:01 INFO - mozsystemmonitor == 0.3 08:42:01 INFO - moztest == 0.8 08:42:01 INFO - mozversion == 1.4 08:42:01 INFO - psutil == 3.1.1 08:42:01 INFO - requests == 1.2.3 08:42:01 INFO - wsgiref == 0.1.2 08:42:01 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:42:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:42:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:42:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:42:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:42:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:42:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:42:01 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 0x108575490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1084eb570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffe4b482140>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108389d30>, '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 0x1081efdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108381b20>, '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.Zip9ggWnY3/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.rr6NnwXpHR/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/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 08:42:01 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 08:42:01 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 08:42:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Zip9ggWnY3/Render', 08:42:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:42:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:42:01 INFO - 'HOME': '/Users/cltbld', 08:42:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:42:01 INFO - 'LOGNAME': 'cltbld', 08:42:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:42:01 INFO - 'MOZ_NO_REMOTE': '1', 08:42:01 INFO - 'NO_EM_RESTART': '1', 08:42:01 INFO - 'PAGER': '/bin/cat', 08:42:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:42:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:42:01 INFO - 'PWD': '/builds/slave/test', 08:42:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:42:01 INFO - 'SHELL': '/bin/bash', 08:42:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.rr6NnwXpHR/Listeners', 08:42:01 INFO - 'TMPDIR': '/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/T/', 08:42:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:42:01 INFO - 'USER': 'cltbld', 08:42:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:42:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:42:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:42:01 INFO - 'XPC_FLAGS': '0x0', 08:42:01 INFO - 'XPC_SERVICE_NAME': '0', 08:42:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:42:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:42:01 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)) 08:42:01 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)) 08:42:01 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)) 08:42:01 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)) 08:42:01 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)) 08:42:01 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)) 08:42:01 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)) 08:42:01 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)) 08:42:01 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)) 08:42:01 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)) 08:42:01 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)) 08:42:01 INFO - Cleaning up... 08:42:01 INFO - Return code: 0 08:42:01 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 08:42:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:42:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:42:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:42:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:42:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:42:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:42:02 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x108575490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1084eb570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffe4b482140>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108389d30>, '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 0x1081efdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108381b20>, '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.Zip9ggWnY3/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.rr6NnwXpHR/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/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 08:42:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 08:42:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 08:42:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Zip9ggWnY3/Render', 08:42:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:42:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:42:02 INFO - 'HOME': '/Users/cltbld', 08:42:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:42:02 INFO - 'LOGNAME': 'cltbld', 08:42:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:42:02 INFO - 'MOZ_NO_REMOTE': '1', 08:42:02 INFO - 'NO_EM_RESTART': '1', 08:42:02 INFO - 'PAGER': '/bin/cat', 08:42:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:42:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:42:02 INFO - 'PWD': '/builds/slave/test', 08:42:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:42:02 INFO - 'SHELL': '/bin/bash', 08:42:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.rr6NnwXpHR/Listeners', 08:42:02 INFO - 'TMPDIR': '/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/T/', 08:42:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:42:02 INFO - 'USER': 'cltbld', 08:42:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:42:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:42:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:42:02 INFO - 'XPC_FLAGS': '0x0', 08:42:02 INFO - 'XPC_SERVICE_NAME': '0', 08:42:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:42:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:42:03 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 08:42:03 INFO - Cleaning up... 08:42:03 INFO - Return code: 0 08:42:03 INFO - Running post-action listener: _resource_record_post_action 08:42:03 INFO - Running post-action listener: _start_resource_monitoring 08:42:03 INFO - Starting resource monitoring. 08:42:03 INFO - [mozharness: 2017-02-02 16:42:03.246173Z] Finished create-virtualenv step (success) 08:42:03 INFO - [mozharness: 2017-02-02 16:42:03.247140Z] Running install step. 08:42:03 INFO - Running pre-action listener: _resource_record_pre_action 08:42:03 INFO - Running main action method: install 08:42:03 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:42:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:42:03 INFO - Reading from file tmpfile_stdout 08:42:03 INFO - Detecting whether we're running mozinstall >=1.0... 08:42:03 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 08:42:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 08:42:03 INFO - Reading from file tmpfile_stdout 08:42:03 INFO - Output received: 08:42:03 INFO - Usage: mozinstall [options] installer 08:42:03 INFO - Options: 08:42:03 INFO - -h, --help show this help message and exit 08:42:03 INFO - -d DEST, --destination=DEST 08:42:03 INFO - Directory to install application into. [default: 08:42:03 INFO - "/builds/slave/test"] 08:42:03 INFO - --app=APP Application being installed. [default: firefox] 08:42:03 INFO - mkdir: /builds/slave/test/build/application 08:42:03 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-52.0.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 08:42:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-52.0.en-US.mac.dmg --destination /builds/slave/test/build/application 08:42:24 INFO - Reading from file tmpfile_stdout 08:42:24 INFO - Output received: 08:42:24 INFO - /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox 08:42:24 INFO - Running post-action listener: _resource_record_post_action 08:42:24 INFO - [mozharness: 2017-02-02 16:42:24.891242Z] Finished install step (success) 08:42:24 INFO - [mozharness: 2017-02-02 16:42:24.891394Z] Running run-tests step. 08:42:24 INFO - Running pre-action listener: _resource_record_pre_action 08:42:24 INFO - Running main action method: run_tests 08:42:24 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 08:42:24 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 08:42:24 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 08:42:24 INFO - 2017-02-02 08:42:24.905 screenresolution[1619:7342] starting screenresolution argv=screenresolution get 08:42:24 INFO - 2017-02-02 08:42:24.908 screenresolution[1619:7342] Display 0: 1600x1200x32@60 08:42:24 INFO - 2017-02-02 08:42:24.914 screenresolution[1620:7344] starting screenresolution argv=screenresolution list 08:42:24 INFO - Available Modes on Display 0 08:42:24 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 08:42:24 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 08:42:24 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 08:42:24 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 08:42:25 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 08:42:25 INFO - Intel Iris: 08:42:25 INFO - Chipset Model: Intel Iris 08:42:25 INFO - Type: GPU 08:42:25 INFO - Bus: Built-In 08:42:25 INFO - VRAM (Dynamic, Max): 1536 MB 08:42:25 INFO - Vendor: Intel (0x8086) 08:42:25 INFO - Device ID: 0x0a2e 08:42:25 INFO - Revision ID: 0x0009 08:42:25 INFO - Displays: 08:42:25 INFO - Display: 08:42:25 INFO - Resolution: 1600 x 1200 @ 60 Hz 08:42:25 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 08:42:25 INFO - Display Serial Number: 1600x1200 60 08:42:25 INFO - Main Display: Yes 08:42:25 INFO - Mirror: Off 08:42:25 INFO - Online: Yes 08:42:25 INFO - Rotation: Supported 08:42:25 INFO - Return code: 0 08:42:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 08:42:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 08:42:25 INFO - Python 2.7.3 08:42:25 INFO - Return code: 0 08:42:25 INFO - grabbing minidump binary from tooltool 08:42:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:42:25 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 0x108389d30>, '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 0x1081efdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108381b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 08:42:25 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 08:42:25 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 08:42:25 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 08:42:25 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 08:42:25 INFO - Return code: 0 08:42:25 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 08:42:25 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 08:42:25 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 08:42:25 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 08:42:25 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 08:42:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/TEyjGFceQTyCFc5rxFCMSg/artifacts/public/build/firefox-52.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0374', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 08:42:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Beta --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/TEyjGFceQTyCFc5rxFCMSg/artifacts/public/build/firefox-52.0.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0374 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 08:42:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Zip9ggWnY3/Render', 08:42:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:42:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:42:25 INFO - 'HOME': '/Users/cltbld', 08:42:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:42:25 INFO - 'LOGNAME': 'cltbld', 08:42:25 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 08:42:25 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 08:42:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:42:25 INFO - 'MOZ_NO_REMOTE': '1', 08:42:25 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 08:42:25 INFO - 'NO_EM_RESTART': '1', 08:42:25 INFO - 'PAGER': '/bin/cat', 08:42:25 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:42:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:42:25 INFO - 'PWD': '/builds/slave/test', 08:42:25 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 08:42:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:42:25 INFO - 'SHELL': '/bin/bash', 08:42:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.rr6NnwXpHR/Listeners', 08:42:25 INFO - 'TMPDIR': '/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/T/', 08:42:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:42:25 INFO - 'USER': 'cltbld', 08:42:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:42:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:42:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:42:25 INFO - 'XPC_FLAGS': '0x0', 08:42:25 INFO - 'XPC_SERVICE_NAME': '0', 08:42:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:42:25 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/TEyjGFceQTyCFc5rxFCMSg/artifacts/public/build/firefox-52.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0374', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 08:42:25 INFO - mozversion application_buildid: 20170202061309 08:42:25 INFO - mozversion application_changeset: 339082e4a33aa237def532c990aaab2b5a4b5723 08:42:25 INFO - mozversion application_display_name: Firefox 08:42:25 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 08:42:25 INFO - mozversion application_name: Firefox 08:42:25 INFO - mozversion application_remotingname: firefox 08:42:25 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 08:42:25 INFO - mozversion application_vendor: Mozilla 08:42:25 INFO - mozversion application_version: 52.0 08:42:25 INFO - mozversion platform_buildid: 20170202061309 08:42:25 INFO - mozversion platform_changeset: 339082e4a33aa237def532c990aaab2b5a4b5723 08:42:25 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 08:42:25 INFO - mozversion platform_version: 52.0 08:42:25 INFO - using testdate: 1486053745 08:42:25 INFO - actual date: 1486053745 08:42:25 INFO - starting webserver on 'localhost:49240' 08:42:25 INFO - SUITE-START | Running 2 tests 08:42:25 INFO - TEST-START | dromaeo_css 08:42:25 INFO - operating with platform_type : mac_ 08:42:25 INFO - Initialising browser for dromaeo_css test... 08:42:25 INFO - TEST-INFO | started process 1630 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/T/tmpuWZWCD/profile http://localhost:49240/getInfo.html) 08:42:28 INFO - PROCESS | 1630 | 2017-02-02 08:42:28.634 plugin-container[1633:7524] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4b3b, name = 'com.apple.tsm.portname' 08:42:28 INFO - PROCESS | 1630 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:42:28 INFO - PROCESS | 1630 | 2017-02-02 08:42:28.634 plugin-container[1633:7524] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4c0b, name = 'com.apple.CFPasteboardClient' 08:42:28 INFO - PROCESS | 1630 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:42:28 INFO - PROCESS | 1630 | 2017-02-02 08:42:28.634 plugin-container[1633:7524] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 08:42:28 INFO - PROCESS | 1630 | __metrics Screen width/height:1600/1200 08:42:28 INFO - PROCESS | 1630 | colorDepth:24 08:42:28 INFO - PROCESS | 1630 | Browser inner width/height: 1024/689 08:42:28 INFO - PROCESS | 1630 | __metrics 08:42:28 ERROR - PROCESS | 1630 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3197: TypeError: this.tabs is undefined 08:42:29 INFO - TEST-INFO | 1630: exit 0 08:42:29 INFO - Browser initialized. 08:42:29 INFO - Running cycle 1/1 for dromaeo_css test... 08:42:29 INFO - TEST-INFO | started process 1634 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/T/tmpuWZWCD/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:42:31 INFO - PROCESS | 1634 | 2017-02-02 08:42:31.896 plugin-container[1635:7655] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x434b, name = 'com.apple.tsm.portname' 08:42:31 INFO - PROCESS | 1634 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:42:31 INFO - PROCESS | 1634 | 2017-02-02 08:42:31.896 plugin-container[1635:7655] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4617, name = 'com.apple.CFPasteboardClient' 08:42:31 INFO - PROCESS | 1634 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:42:31 INFO - PROCESS | 1634 | 2017-02-02 08:42:31.896 plugin-container[1635:7655] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 08:42:41 INFO - PROCESS | 1634 | RSS: Main: 177254400 08:42:41 INFO - PROCESS | 1634 | 08:45:44 INFO - PROCESS | 1634 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49240/tests/dromaeo/cssquery-ext.html) 08:45:46 INFO - PROCESS | 1634 | RSS: Main: 181559296 08:45:46 INFO - PROCESS | 1634 | 08:48:48 INFO - PROCESS | 1634 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-ext.html (next: http://localhost:49240/tests/dromaeo/cssquery-jquery.html) 08:48:49 INFO - PROCESS | 1634 | RSS: Main: 193347584 08:48:49 INFO - PROCESS | 1634 | 08:51:51 INFO - PROCESS | 1634 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49240/tests/dromaeo/cssquery-mootools.html) 08:51:51 INFO - PROCESS | 1634 | RSS: Main: 196079616 08:51:51 INFO - PROCESS | 1634 | 08:54:53 INFO - PROCESS | 1634 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49240/tests/dromaeo/cssquery-prototype.html) 08:54:54 INFO - PROCESS | 1634 | RSS: Main: 200376320 08:54:54 INFO - PROCESS | 1634 | 08:57:56 INFO - PROCESS | 1634 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49240/tests/dromaeo/cssquery-yui.html) 08:57:57 INFO - PROCESS | 1634 | RSS: Main: 201781248 08:57:57 INFO - PROCESS | 1634 | 09:01:00 INFO - PROCESS | 1634 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-yui.html (next: http://localhost:49240/tests/dromaeo/cssquery-dojo.html) 09:01:00 INFO - PROCESS | 1634 | RSS: Main: 202575872 09:01:00 INFO - PROCESS | 1634 | 09:01:00 INFO - PROCESS | 1634 | __start_tp_report 09:01:00 INFO - PROCESS | 1634 | _x_x_mozilla_page_load 09:01:00 INFO - PROCESS | 1634 | _x_x_mozilla_page_load_details 09:01:00 INFO - PROCESS | 1634 | |i|pagename|runs| 09:01:00 INFO - PROCESS | 1634 | |0;dojo.html;3448.293099914517;3623.4202527595576;3648.6168952259995;3665.2852693724717;3686.3364594373;6043.972524670806;6078.7872424465095;6092.939070609293;6105.938940610593;6115.908261376072;4956.801727930879;6054.364291749369;6158.476529494998;6160.5687601867885;6169.660668663222;5449.64577302475;5500.119980803072;5503.532199763005;5513.50378465938;5572.219889215513;4814.301926220696;4913.606911447076;5543.916841247385;5572.3870374258795;5573.693446860421;5396.325459317585;5406;5565.805196818113;5573.721313934308;5579.69311687857;5466.808661696841;5494.066008778517;5496.285482887199;5503.587230957694;5510.393856675762;5346.4386239445075;5459.235707001023;5479.315085614298;5489.6980666063355;5511.22842802008;6088.269407671063;6109.7690694183475;6126.670355029888;6127.953177891112;6142.385761423822;6270.717817698169;6360.491160707133;6365.936340636581;6385.616862988236;6416.647084410356;6483.351664833526;6493.545451818374;6495.3179916109075;6502.219733631947;6513.804585862432;6030.909536356914;6115.453310713017;6120.571559990758;6194.659293738842;6202.565820392574;6558.967205163944;6595.406413422815;6647.0029495576055;6675.532712710161;6714.52998290115;6380.776672816429;6384.754972780263;6385.840353991094;6395.712192951329;6417.582857114366;6297.96851015742;6323.336049714806;6535.934640653628;6536.084948107269;6542.738290468423;6227.626342419469;6288.717007734663;6349.9682501587195;6407.231132264065;6429.517786166056;6369.20384951881;6414.775482858171;6432.228132624022;6435.22777266731;6438.710258038306;4249.192653395845;5089.770960306795;5235.581153507786;5245.606579506552;5279.894402112013;3778.205352457616;4147.419361289422;4569.588737013684;4572.611328037087;4669.042846216517;4873.488283730228;6191.102290167901;6238.469730072904;6257.655828929453;6273.498120150377;6290.182276304051;6491.448226900767;6536.640484773383;6538.607683539003;6551.050097735897;6107.15847211733;6141.271221159682;6142.560166306883;6209;6287.49700023997;5747.798827041033;5789.855253618609;5791.449812267828;5802.622829516068;5819.476247137778;6498.220213574212;6574.901376479261;6618.966905165444;6753.662316884136;6761.830954226085;6514.9348506514325;6567.27759946396;6574.145361103026;6583.835404114935;6592.8351791205605;6719.529632925648;6728.798136055926;6730.831729206809;6758.526903116931;6784.6946887389195;6219.564630475823;6310.495160387066;6372.044193371032;6444.065610486547;6445.617909045812;6392.20097487814;6433.710483028369;6472.223333200046;6544.9672751637845;6551.967240163769;6317.30509643948;6321.209848768904;6322.36776322364;6328.715207815567;6334.746610135543;6170.660613666291;6212.844678882883;6324.083007963912;6325.715342809676;6347.650879201688;6107.236595425572;6203.534734894991;6255.061740738925;6283.905741413974;6314;6349.174607301022;6350.90473642905;6363.268224154282;6390.712417941111;6440.033994900802;6208.751649934133;6284.214473190851;6304.464120549805;6309.432151106423;6331.113644089739 09:01:00 INFO - PROCESS | 1634 | |1;ext.html;466.17487047925175;490.5584973523829;496.01045913402754;500.9123403404408;501.78423277990447;38424.423633645485;43644.563554364446;43710.99999999996;43744.12511749763;43760;43248.13503729924;43628;43723;43753.56246437535;43802.561974380245;9669.806603867919;9870;9873.259505537078;9909.405435673883;9925.106740393363;9758.90241097587;9787.510624468783;9814.214862810994;9823.852642210373;9860.11258986691;9770.706878793648;9797.510124493783;9817;9843.409395436296;9868.21054315653;8347.91477107978;9762.658306959258;9834.065763752504;9838.458884761336;9838.655647052354;9693.127618514298;9784.168345690625;9787.070228328368;9797.510124493818;9801;33367.49948750746;33877.830610847035;40853.59146408528;41223.587764122574;41273;3055.4805683033715;3215.8070515768895;3371.679690429405;3486.093615659947;3817.8091095445116;31403.214919626964;31547.21131971696;31555.684443155504;31608.36783264345;31693.52459713106;3241.8216998065095;3268.7385009199443;3302.669733026702;3324.2852786650988;3329.467285234375;481211;489827;523694;526648;529716.3514182443;59609;59723;59797.70101149466;60049.399506005255;60084;58302;59848.70075649594;60991;61351.6932415335;61734.60459674761;16507.09210993407;16908.746368804477;17164.485065448058;17178.055206963487;17198.968061916326;12840.935795320964;12916.289604071679;12921.612351629467;12958.870411295955;13008.934955325352;13325;15069.54791356261;15247.932644714763;15443.91892567532;15670.294836732375;3283.113559338965;6010.9699451502465;6167.753289868445;6178.320384757673;6202.038684003986;882.2633101360308;949.7720547068792;987.8419452887647;1019.4138370437029;1053.2732414633879;30681.53977690292;30998;31116.91090811811;31143.844280778452;31380;973.2797729680127;1061.8247989081715;1105.6130354376064;1126.065365746445;1176.917615766888;14087.436502539784;15743.448979285899;15766.76349854753;16387.672246555;16445.506634800975;668236.6588167028;680556;686080;686662;688538;58812.70593647005;59267.407325926186;59332.70333648304;59381.70309148427;59479.40520594825;1388.2850332078988;1398.3008495752124;1421.729871324445;1424.5583869000643;1428.6285565752978;29470.557941631323;30095.548566771955;30312.242193945327;30488.84755576208;31085.844570777;49451;49560.256596151805;49671.751641241564;49854;49940;617730;618389;621070;621738;624892;26565.867170664023;26728.732712672623;26973.190804275902;27091.458170836868;27981.300467488476;46514;46825;47776.283355748994;47866.760666196446;47980;38121.88745638786;39032.024199395244;39779.40330895097;40346;40523.18953620852;34764.13089672779;38967;39876.00309992273;39972.40041399323;40199.19601607893;38248.61751382451;38904;39010.80494597509;39096.41355379615;39106.80446597749;29711.702882970894;31559;34190;35218.64781352154;35721.46417803683;21500.677489837977;25104.62343064892;26103.608445872946;26115.824787884972;26368.736312637397 09:01:00 INFO - PROCESS | 1634 | |2;jquery.html;380.8102634664392;381.9427085937111;383.9654431101201;393.2587073366704;413.18602353363883;14847.55457336279;19243.615127697456;19630.705539416886;20557.486062848435;20563.38309850703;19879.503012424695;20071.096800644005;20586.382408527767;20825.27111551096;20876.89561552194;5707.857303567403;6132.233470816148;6154.138420621117;6211.3167551569295;6213.161223234873;4977.651564390494;6011.490354884388;6090.14737936687;6141.140240366352;6170.136180934673;5988.131720900488;6042.274927008765;6069.059295809135;6071.1196876453105;6097.359777223394;5817.534597232232;6103.481204097657;6122;6146.723397447103;6172.506199504029;5346.251524786533;5349.22436246742;5354.41101478837;5355;5371.355437347504;24617.753822461724;25382.619260711104;25503.872480637663;25666.48667026649;25815.870920645277;2351.247600767755;2367.597508423572;2451.7548245175517;2549.6430499730054;3080.471375472042;12170.452329645188;15258.847411525854;15614.843851561565;15871.92064039684;16047.518574442785;1916.4154932745507;1973.1416833677397;2000.3898810862681;2017.5662232619911;2023.0693880814842;120240;126207;128831.35584322113;129161;129754.3512282442;10796.352218866736;11138.721531961766;11223.551057957753;11243.988041076342;11440.313581185155;12160.452779624939;12823.61529154127;13912.513062042983;13916.58250252494;13930.512432065032;7201.099862517185;8346.123657015925;8745.12548745133;8851.69019084329;8915.37592368541;7325.743598974011;7471.327580517671;8796.196779660155;8908.153725396078;9023.278137748965;6679.198496180489;6683.7660681875905;6727.865442691119;6804.6257455840405;6828.283030281848;1642.136175440883;1728.8157612035795;1869.2616416515673;1928.1419768203016;1965.8132477414622;940.9623615055458;993.2054356514759;996.1184351848522;1016.5323950982631;1043.2697112021463;20695.896520517603;20726;21522.139114435562;21959.376702844173;22127.225547105772;888.1784349476708;905.9320550958704;951.6968935176889;977.0766625538939;1003.6336737090909;10411.751878357745;10801.978580235123;10805.965122556387;10836.07322718096;10836.98821332243;592696;611377;613168;622856;629170;129962;138261;139146.91279630814;142797;148488;442.16651611712155;442.8317239448989;460.8433132734819;461.2228395154176;465.4554171619214;10815.567377305135;11117.332960022424;11279.943600281946;11340.376279304717;11368.829467558158;55464.445355545926;56132.71933640305;57058.85882282414;57778.84442311046;58559;81522;82071.17928820875;82666;84138;86515.1348486507;7135.286471353031;7677.155512893465;8316.459430137022;8320.12638672955;8336.333093352396;11305.75636679981;11416.828747568627;12580;12611.810822837848;12813.295268760308;9121.589528471368;10721.35671859691;11117.27737697047;11156.609518666808;11278.266912650652;10498.475076246004;10840.5121769519;11062.557497700001;11134.27627204229;11223;10355.43045132525;10812.405317707287;11266.605668801552;11442.656720298404;11785.646430607096;8233.38249631268;8345;8440.409171358191;8452.070272270168;8529.445586036887;5484.341878974548;8106.513609183467;8311.25198732117;8424.736289556371;10679.305845120043 09:01:00 INFO - PROCESS | 1634 | |3;mootools.html;1348.8313960754904;1456.8106146200992;1520.581839994002;1744.3022790883654;1866.0856180471574;7733.961330193355;8011.278984891359;8101.5544145072;8559.144085591437;8576.871346929802;8169.060558035828;8445.704400345989;8447.281981031605;8558.572071396437;8576.785580360494;2127.1810353014125;2343.5470008594693;2390.8445951013127;2392.9760702393014;2413.8534196256815;2402.5915594348917;2437.4515733959893;2439.0975339124548;2441.2066078524463;2442.6091825307954;2362.586547354215;2373.5252949410187;2374.8693821839793;2395.6765836612094;2417.55275274074;2112.176251262009;2261.8869056547105;2372.5136347048815;2403.6634871118144;2413.215704895907;2393.1743548475747;2395.4610212702178;2413.3242692046256;2416.178499310243;2426.126594426023;8517.403781735282;8550.48697078171;8605.010423801281;8612.48325100496;8686;4964.808445972976;5221.608379371524;5312.256284120226;5364.356277246717;5379.9731001345135;3285.687859653353;3483.477478378238;3529;3550.9112272193142;3637.9818100909592;1073.4257172412722;1073.667163179409;1107.479484642217;1108.3571528513444;1110.7112150840758;9280.814383712288;11081.833772493419;11142.108631309477;11149.66551003463;11162.274452160584;4566.292224705176;4682.859514214579;4752.097101550763;4758.809647614056;4856.587190088882;9677.467739274267;9683.709488715349;9706.466144361986;9754.804903901882;9873.703788886345;5306.681599104066;5356.678599284055;5370.516653501126;5376.4085950545405;5381.246625472437;4446.399736035593;4492.303692927601;4893.828715994923;4960.627952903546;4996.725834912074;3974.940375894364;3984.1234928316303;4051.8379264829077;4078.857239996645;4108.157827645386;1306.2554344023815;1315.8552559218479;1317.7166909114394;1323.3118778235457;1331.3942156318851;760.3309088002522;839.9412041157183;869.1786261982493;923.9676611318572;942.1144124522926;4937.7777999990085;5821.650700957956;5858.5898987070495;5910.143029260733;6013.51891848651;756.6254703921594;953.2183609440192;979.0943377375899;987.8913319534845;990.6186118344345;3775.6601905828607;3870.8645197417954;3883.456316115746;3949.486566746362;4011.618896204856;10074.194064474825;10428.217883658604;10440.903705111006;10476.738081548023;10572.52423640938;10249.026342497451;10442.738931526772;10490.527926243338;10538.315009524356;10614.310069845435;6036.5170786336985;6567.7701280454;6721.865562688817;8102.83794324122;8247.628856701464;4871.928175801318;5351.812686555952;5440.129579267298;5870.442307980735;5875.559333050124;9167.770805729644;9376.968533461177;9527.190188833749;9564.04359564038;9678.70963871085;9570.473623950471;9674.274429417963;9804.607815687292;9914.504274786088;9941.105300522988;6776.830579235559;7002;7156.642167891689;7159.284071592799;7448.776536703907;7912.0505539335645;8083.110857805755;8109.75670729879;8118.59407029658;8141.552214628252;7285.7814265572115;7360.411167106511;7457.105147382349;7537.982372379659;7558.281963213486;7217.24218957002;7271.709131634675;7297.16082650502;7310.96344518274;7314.890276645748;6842.452603791585;6937.82655433598;6952.895706564506;6952.930470695228;6977.1278590176225;4782.33047373361;4843.39457567804;4872.244802055687;4896.681715688469;5002.424721157114;4863.8540843774745;4902.744737420684;4991.725455100004;5003.949960500348;5027.64806463544 09:01:00 INFO - PROCESS | 1634 | |4;prototype.html;261.39878279956105;283.5902121434528;300.62271848829727;318.0315937968885;321.2018134934688;4817.831375901844;5452.454754524546;5532.972335138324;5596.188552659862;5635.971820140899;4423;4523.115035761378;4972.900541989167;5465.535429488498;5540.722963851811;3908.335582950891;4196.832126714928;4212.178625168093;4225.218334608099;4226.535081141072;4066.573009833969;4149.356849688304;4214.346776249687;4218.3250679891225;4230;4077.4903137107863;4141.109661422794;4147.937780933288;4149.9792501037455;4177.624013838754;3741.727812543721;3989.2221016901717;4102.138550904314;4103.712740108163;4147.419361289422;2770.5982632518376;3546.627604101558;3556.6265542117962;3578.481120237551;3610.1696609779633;5257.658252213594;5375.247465354853;5378.273933019071;5396.841263494594;5507.476789704971;3881.961180388188;4568.794404251784;4702.200625893572;4732.645051621143;4761.952380476186;5696.401877802854;5744.6840423776675;5769.682667453289;5789.247397838296;5820.650760954314;3158.0052283530613;3271.9509207361907;3297.9340413191603;3752.1832327723077;3949.3681011038384;4972;5075.390953085616;5661.867626474722;6152.077188421773;6156.661383623944;3988.4615576897327;3992.720509564361;4017.5781542938157;4018.3972404139613;4019.41718450823;3318.784279021856;3624.0396294981324;3628.5101511296166;3647.3434781739543;4044.7775372354213;4499.965008048167;4654.603618914312;4670.509596492388;4685.8594242172785;4715.882102947385;4985.731492171053;5760.049591817387;5786.566007549366;5865.97345464542;5886.793962211302;1071.3946620159586;1139.9316041037564;1279.296386987172;1294.643972907439;1303.1920209470188;836.8869403693074;862.1895418306772;883.2977782662798;888.7333799860016;892.6161750447241;4417.078570821703;4671.780904070607;4855.854324370274;5217.095760047555;5222.242774797709;4431.224535706238;4823.9517604824205;5225.686458812484;5236.528712415901;5527.364353099365;4815.3258543803895;5215.400228973641;5222.877081427511;5233.71214583194;5238.397584277781;2543.5548778963976;2669.5584384432577;2680.9597856032174;2982.224621598357;3028.197527655173;5082.491750824888;5678.097837224494;5900.20347253124;5971.775785963867;5977.940220597825;4714.232162938898;5289.788408463695;5293.961824697519;5428.647137936022;5496;5434.375046869661;5606.383297837317;5829.737661805144;5903.575925962931;5903.793367232126;4237.372828846135;5169.870753231199;5477.178423236546;5583.302087239095;5609.831705048855;4620.630349572093;4970.776315065758;5247.58019358458;5503.174523821459;5572.77708891656;4787.95212047889;5487.396386397413;5707.80136171392;5882.294124705063;5921.348651648402;3358.4718952875896;3757.380032294641;3779.3197224499854;3796.487474190949;3797.050737315671;5465.945340546703;5473.948152974336;5478.561715062865;5675.8864822704145;5693.459121383462;4959.7272150032095;5619.325680918316;5670.865826834567;5790.913136302875;5815.33123690764;5805.8548536286935;5820.621659592113;5849.268841394825;5853.624398266489;5869.413058694096;5009.974950125226;5152.922706159486;5586.385682389336;5745.827625171251;5784.756277400211;5079.822206222764;5720.685362305113;5770.798022069207;5789.392113828156;5824.621399609012;5186.688798672092;5686.185338257589;5697.401772813961;5699.743511542073;5735.963732874936 09:01:00 INFO - PROCESS | 1634 | |5;yui.html;659.870103531344;689.9068625735528;780.6799212322949;806.2340776262552;812.1999830167284;15028;15594.06435613864;15653.608659783496;15695.293711782968;15697.450589229366;14021.369038393272;14091.718165636707;14106.083104598194;14148.151110933351;14214.431422743079;838.119974027272;844.3203221406765;866.5970323799432;873.3929918706501;874.5976850648677;839.1608391608391;854.1885209051395;859.1751918158544;864.023653271802;866.3156106675718;889.8042430665244;900.3517467423445;917.8806755121825;918.9845221030765;919.2002957427014;888.506878682331;892.8794612727296;905.5879574793486;908.3913777768911;908.7410088124885;590.8788698316878;662.2681936460239;664.9002649602598;681.4922882452555;683.2279524137742;12428.875711242863;12729.936350318188;12796.104272700915;12947.417366218542;13551;11983.520659173622;12597.748045039049;12599.811002834966;12693.619191424272;13088.672783180402;12251.897329240412;12521.874781252161;12583.496660133584;12927.5475358362;13094.345282735825;4150.190712811003;4355.259605867011;4577.22187228172;4700.341952126705;4740.099381117577;100167.9983200166;100681;101457.49271253597;101577.49211253971;104476.95523044825;8846.690365837294;9770.120689138012;10480.266381353233;10579.894201058045;10834.67495975122;19347.709784353247;19514.926679032505;19617.803821961883;19781.40655780329;19820.801791982183;6527.836804079936;7353.264673532604;7584.81037974044;7601.239876012354;7646;4155.605217504332;4206.843925594825;4211.241976444269;4255.5318914919335;4359.258925982591;3266.722328602048;3337.5828021497314;3369.663033696611;3376.848041838123;3377.071305391037;493.4054464370425;626.1484381241507;627.0406278394064;637.3562701671345;640.3628389752133;581.2937281203274;591.6361437715774;607.9057746049369;608.5861614102364;616.7841255560608;8548.187922147386;8910.634824246175;8954.23888969432;8960.820783584422;9030.096990300917;604.5405491826156;618.264265524025;620.8955223880597;637.5250438423404;645.2643985856128;4209.17921005401;4209.684273679486;4469.597736203757;4489.595936365743;4490.640748740093;118972.21541676881;119204.40397798129;120289.19566206513;123813.38093309476;124952.75047249411;85796.85507862353;92678.2196534105;109415;110767.67696969103;110814;73565.52868942592;93637.5318123405;94364;96236.51881740546;96442;7160.498765086394;7501.6249187539315;8566.443181193203;8587.014952117317;8755.824883502422;60218.69890650519;60330.09504857343;60954.69522652358;61359;61802.69098654478;80553;87054;88672;90804;90965.54517227372;9810.411375317504;9969.50152492387;10114.494275286059;10152.64465743725;10570.048695617432;11682.556067832438;11911.3448760319;12000.418967245781;12031.518739250332;12085.15403921717;10402.323848949794;10528.36829790215;11061.612843550438;11067.612633557786;11423.600173993871;10279.743006424898;11486.655400338002;11509.53961841517;11716.589919352782;12201.389930503618;10486.26596138263;10656.360618362924;11440.828387574027;11480.138989575982;11764.882351176379;9150.267978561566;9189.678361257324;9330.066993300616;9392.765180870532;9599.616015359308;9726.36778609388;9754.414735115915;9763.115465707553;9765.902340976783;9857.852132218166 09:01:00 INFO - PROCESS | 1634 | __end_tp_report 09:01:00 INFO - PROCESS | 1634 | __start_cc_report 09:01:00 INFO - PROCESS | 1634 | _x_x_mozilla_cycle_collect,363 09:01:00 INFO - PROCESS | 1634 | __end_cc_report 09:01:00 INFO - PROCESS | 1634 | __startTimestamp1486054860268__endTimestamp 09:01:00 INFO - PROCESS | 1634 | 09:01:00 INFO - PROCESS | 1634 | ------- Summary: start ------- 09:01:00 INFO - PROCESS | 1634 | Number of tests: 6 09:01:00 INFO - PROCESS | 1634 | 09:01:00 INFO - PROCESS | 1634 | [#0] dojo.html Cycles:165 Average:6008.16 Median:6273.50 stddev:694.82 (11.1%) stddev-sans-first:667.30 09:01:00 INFO - PROCESS | 1634 | Values: 3448.3 3623.4 3648.6 3665.3 3686.3 6044.0 6078.8 6092.9 6105.9 6115.9 4956.8 6054.4 6158.5 6160.6 6169.7 5449.6 5500.1 5503.5 5513.5 5572.2 4814.3 4913.6 5543.9 5572.4 5573.7 5396.3 5406.0 5565.8 5573.7 5579.7 5466.8 5494.1 5496.3 5503.6 5510.4 5346.4 5459.2 5479.3 5489.7 5511.2 6088.3 6109.8 6126.7 6128.0 6142.4 6270.7 6360.5 6365.9 6385.6 6416.6 6483.4 6493.5 6495.3 6502.2 6513.8 6030.9 6115.5 6120.6 6194.7 6202.6 6559.0 6595.4 6647.0 6675.5 6714.5 6380.8 6384.8 6385.8 6395.7 6417.6 6298.0 6323.3 6535.9 6536.1 6542.7 6227.6 6288.7 6350.0 6407.2 6429.5 6369.2 6414.8 6432.2 6435.2 6438.7 4249.2 5089.8 5235.6 5245.6 5279.9 3778.2 4147.4 4569.6 4572.6 4669.0 4873.5 6191.1 6238.5 6257.7 6273.5 6290.2 6491.4 6536.6 6538.6 6551.1 6107.2 6141.3 6142.6 6209.0 6287.5 5747.8 5789.9 5791.4 5802.6 5819.5 6498.2 6574.9 6619.0 6753.7 6761.8 6514.9 6567.3 6574.1 6583.8 6592.8 6719.5 6728.8 6730.8 6758.5 6784.7 6219.6 6310.5 6372.0 6444.1 6445.6 6392.2 6433.7 6472.2 6545.0 6552.0 6317.3 6321.2 6322.4 6328.7 6334.7 6170.7 6212.8 6324.1 6325.7 6347.7 6107.2 6203.5 6255.1 6283.9 6314.0 6349.2 6350.9 6363.3 6390.7 6440.0 6208.8 6284.2 6304.5 6309.4 6331.1 09:01:00 INFO - PROCESS | 1634 | 09:01:00 INFO - PROCESS | 1634 | [#1] ext.html Cycles:180 Average:73197.03 Median:41038.59 stddev:162940.24 (397.0%) stddev-sans-first:163305.82 09:01:00 INFO - PROCESS | 1634 | Values: 466.2 490.6 496.0 500.9 501.8 38424.4 43644.6 43711.0 43744.1 43760.0 43248.1 43628.0 43723.0 43753.6 43802.6 9669.8 9870.0 9873.3 9909.4 9925.1 9758.9 9787.5 9814.2 9823.9 9860.1 9770.7 9797.5 9817.0 9843.4 9868.2 8347.9 9762.7 9834.1 9838.5 9838.7 9693.1 9784.2 9787.1 9797.5 9801.0 33367.5 33877.8 40853.6 41223.6 41273.0 3055.5 3215.8 3371.7 3486.1 3817.8 31403.2 31547.2 31555.7 31608.4 31693.5 3241.8 3268.7 3302.7 3324.3 3329.5 481211.0 489827.0 523694.0 526648.0 529716.4 59609.0 59723.0 59797.7 60049.4 60084.0 58302.0 59848.7 60991.0 61351.7 61734.6 16507.1 16908.7 17164.5 17178.1 17199.0 12840.9 12916.3 12921.6 12958.9 13008.9 13325.0 15069.5 15247.9 15443.9 15670.3 3283.1 6011.0 6167.8 6178.3 6202.0 882.3 949.8 987.8 1019.4 1053.3 30681.5 30998.0 31116.9 31143.8 31380.0 973.3 1061.8 1105.6 1126.1 1176.9 14087.4 15743.4 15766.8 16387.7 16445.5 668236.7 680556.0 686080.0 686662.0 688538.0 58812.7 59267.4 59332.7 59381.7 59479.4 1388.3 1398.3 1421.7 1424.6 1428.6 29470.6 30095.5 30312.2 30488.8 31085.8 49451.0 49560.3 49671.8 49854.0 49940.0 617730.0 618389.0 621070.0 621738.0 624892.0 26565.9 26728.7 26973.2 27091.5 27981.3 46514.0 46825.0 47776.3 47866.8 47980.0 38121.9 39032.0 39779.4 40346.0 40523.2 34764.1 38967.0 39876.0 39972.4 40199.2 38248.6 38904.0 39010.8 39096.4 39106.8 29711.7 31559.0 34190.0 35218.6 35721.5 21500.7 25104.6 26103.6 26115.8 26368.7 09:01:00 INFO - PROCESS | 1634 | 09:01:00 INFO - PROCESS | 1634 | [#2] jquery.html Cycles:180 Average:36110.85 Median:25741.18 stddev:102977.82 (400.1%) stddev-sans-first:103231.75 09:01:00 INFO - PROCESS | 1634 | Values: 380.8 381.9 384.0 393.3 413.2 14847.6 19243.6 19630.7 20557.5 20563.4 19879.5 20071.1 20586.4 20825.3 20876.9 5707.9 6132.2 6154.1 6211.3 6213.2 4977.7 6011.5 6090.1 6141.1 6170.1 5988.1 6042.3 6069.1 6071.1 6097.4 5817.5 6103.5 6122.0 6146.7 6172.5 5346.3 5349.2 5354.4 5355.0 5371.4 24617.8 25382.6 25503.9 25666.5 25815.9 2351.2 2367.6 2451.8 2549.6 3080.5 12170.5 15258.8 15614.8 15871.9 16047.5 1916.4 1973.1 2000.4 2017.6 2023.1 120240.0 126207.0 128831.4 129161.0 129754.4 10796.4 11138.7 11223.6 11244.0 11440.3 12160.5 12823.6 13912.5 13916.6 13930.5 7201.1 8346.1 8745.1 8851.7 8915.4 7325.7 7471.3 8796.2 8908.2 9023.3 6679.2 6683.8 6727.9 6804.6 6828.3 1642.1 1728.8 1869.3 1928.1 1965.8 941.0 993.2 996.1 1016.5 1043.3 20695.9 20726.0 21522.1 21959.4 22127.2 888.2 905.9 951.7 977.1 1003.6 10411.8 10802.0 10806.0 10836.1 10837.0 592696.0 611377.0 613168.0 622856.0 629170.0 129962.0 138261.0 139146.9 142797.0 148488.0 442.2 442.8 460.8 461.2 465.5 10815.6 11117.3 11279.9 11340.4 11368.8 55464.4 56132.7 57058.9 57778.8 58559.0 81522.0 82071.2 82666.0 84138.0 86515.1 7135.3 7677.2 8316.5 8320.1 8336.3 11305.8 11416.8 12580.0 12611.8 12813.3 9121.6 10721.4 11117.3 11156.6 11278.3 10498.5 10840.5 11062.6 11134.3 11223.0 10355.4 10812.4 11266.6 11442.7 11785.6 8233.4 8345.0 8440.4 8452.1 8529.4 5484.3 8106.5 8311.3 8424.7 10679.3 09:01:00 INFO - PROCESS | 1634 | 09:01:00 INFO - PROCESS | 1634 | [#3] mootools.html Cycles:180 Average:5556.66 Median:5015.80 stddev:3039.62 (60.6%) stddev-sans-first:3031.70 09:01:00 INFO - PROCESS | 1634 | Values: 1348.8 1456.8 1520.6 1744.3 1866.1 7734.0 8011.3 8101.6 8559.1 8576.9 8169.1 8445.7 8447.3 8558.6 8576.8 2127.2 2343.5 2390.8 2393.0 2413.9 2402.6 2437.5 2439.1 2441.2 2442.6 2362.6 2373.5 2374.9 2395.7 2417.6 2112.2 2261.9 2372.5 2403.7 2413.2 2393.2 2395.5 2413.3 2416.2 2426.1 8517.4 8550.5 8605.0 8612.5 8686.0 4964.8 5221.6 5312.3 5364.4 5380.0 3285.7 3483.5 3529.0 3550.9 3638.0 1073.4 1073.7 1107.5 1108.4 1110.7 9280.8 11081.8 11142.1 11149.7 11162.3 4566.3 4682.9 4752.1 4758.8 4856.6 9677.5 9683.7 9706.5 9754.8 9873.7 5306.7 5356.7 5370.5 5376.4 5381.2 4446.4 4492.3 4893.8 4960.6 4996.7 3974.9 3984.1 4051.8 4078.9 4108.2 1306.3 1315.9 1317.7 1323.3 1331.4 760.3 839.9 869.2 924.0 942.1 4937.8 5821.7 5858.6 5910.1 6013.5 756.6 953.2 979.1 987.9 990.6 3775.7 3870.9 3883.5 3949.5 4011.6 10074.2 10428.2 10440.9 10476.7 10572.5 10249.0 10442.7 10490.5 10538.3 10614.3 6036.5 6567.8 6721.9 8102.8 8247.6 4871.9 5351.8 5440.1 5870.4 5875.6 9167.8 9377.0 9527.2 9564.0 9678.7 9570.5 9674.3 9804.6 9914.5 9941.1 6776.8 7002.0 7156.6 7159.3 7448.8 7912.1 8083.1 8109.8 8118.6 8141.6 7285.8 7360.4 7457.1 7538.0 7558.3 7217.2 7271.7 7297.2 7311.0 7314.9 6842.5 6937.8 6952.9 6952.9 6977.1 4782.3 4843.4 4872.2 4896.7 5002.4 4863.9 4902.7 4991.7 5003.9 5027.6 09:01:00 INFO - PROCESS | 1634 | 09:01:00 INFO - PROCESS | 1634 | [#4] prototype.html Cycles:180 Average:4535.45 Median:5081.16 stddev:1413.14 (27.8%) stddev-sans-first:1380.21 09:01:00 INFO - PROCESS | 1634 | Values: 261.4 283.6 300.6 318.0 321.2 4817.8 5452.5 5533.0 5596.2 5636.0 4423.0 4523.1 4972.9 5465.5 5540.7 3908.3 4196.8 4212.2 4225.2 4226.5 4066.6 4149.4 4214.3 4218.3 4230.0 4077.5 4141.1 4147.9 4150.0 4177.6 3741.7 3989.2 4102.1 4103.7 4147.4 2770.6 3546.6 3556.6 3578.5 3610.2 5257.7 5375.2 5378.3 5396.8 5507.5 3882.0 4568.8 4702.2 4732.6 4762.0 5696.4 5744.7 5769.7 5789.2 5820.7 3158.0 3272.0 3297.9 3752.2 3949.4 4972.0 5075.4 5661.9 6152.1 6156.7 3988.5 3992.7 4017.6 4018.4 4019.4 3318.8 3624.0 3628.5 3647.3 4044.8 4500.0 4654.6 4670.5 4685.9 4715.9 4985.7 5760.0 5786.6 5866.0 5886.8 1071.4 1139.9 1279.3 1294.6 1303.2 836.9 862.2 883.3 888.7 892.6 4417.1 4671.8 4855.9 5217.1 5222.2 4431.2 4824.0 5225.7 5236.5 5527.4 4815.3 5215.4 5222.9 5233.7 5238.4 2543.6 2669.6 2681.0 2982.2 3028.2 5082.5 5678.1 5900.2 5971.8 5977.9 4714.2 5289.8 5294.0 5428.6 5496.0 5434.4 5606.4 5829.7 5903.6 5903.8 4237.4 5169.9 5477.2 5583.3 5609.8 4620.6 4970.8 5247.6 5503.2 5572.8 4788.0 5487.4 5707.8 5882.3 5921.3 3358.5 3757.4 3779.3 3796.5 3797.1 5465.9 5473.9 5478.6 5675.9 5693.5 4959.7 5619.3 5670.9 5790.9 5815.3 5805.9 5820.6 5849.3 5853.6 5869.4 5010.0 5152.9 5586.4 5745.8 5784.8 5079.8 5720.7 5770.8 5789.4 5824.6 5186.7 5686.2 5697.4 5699.7 5736.0 09:01:00 INFO - PROCESS | 1634 | 09:01:00 INFO - PROCESS | 1634 | [#5] yui.html Cycles:180 Average:21782.47 Median:586.09 stddev:33730.08 (5755.1%) stddev-sans-first:33787.41 09:01:00 INFO - PROCESS | 1634 | Values: 659.9 689.9 780.7 806.2 812.2 15028.0 15594.1 15653.6 15695.3 15697.5 14021.4 14091.7 14106.1 14148.2 14214.4 838.1 844.3 866.6 873.4 874.6 839.2 854.2 859.2 864.0 866.3 889.8 900.4 917.9 919.0 919.2 888.5 892.9 905.6 908.4 908.7 590.9 662.3 664.9 681.5 683.2 12428.9 12729.9 12796.1 12947.4 13551.0 11983.5 12597.7 12599.8 12693.6 13088.7 12251.9 12521.9 12583.5 12927.5 13094.3 4150.2 4355.3 4577.2 4700.3 4740.1 100168.0 100681.0 101457.5 101577.5 104477.0 8846.7 9770.1 10480.3 10579.9 10834.7 19347.7 19514.9 19617.8 19781.4 19820.8 6527.8 7353.3 7584.8 7601.2 7646.0 4155.6 4206.8 4211.2 4255.5 4359.3 3266.7 3337.6 3369.7 3376.8 3377.1 493.4 626.1 627.0 637.4 640.4 581.3 591.6 607.9 608.6 616.8 8548.2 8910.6 8954.2 8960.8 9030.1 604.5 618.3 620.9 637.5 645.3 4209.2 4209.7 4469.6 4489.6 4490.6 118972.2 119204.4 120289.2 123813.4 124952.8 85796.9 92678.2 109415.0 110767.7 110814.0 73565.5 93637.5 94364.0 96236.5 96442.0 7160.5 7501.6 8566.4 8587.0 8755.8 60218.7 60330.1 60954.7 61359.0 61802.7 80553.0 87054.0 88672.0 90804.0 90965.5 9810.4 9969.5 10114.5 10152.6 10570.0 11682.6 11911.3 12000.4 12031.5 12085.2 10402.3 10528.4 11061.6 11067.6 11423.6 10279.7 11486.7 11509.5 11716.6 12201.4 10486.3 10656.4 11440.8 11480.1 11764.9 9150.3 9189.7 9330.1 9392.8 9599.6 9726.4 9754.4 9763.1 9765.9 9857.9 09:01:00 INFO - PROCESS | 1634 | -------- Summary: end -------- 09:01:00 INFO - PROCESS | 1634 | 09:01:00 INFO - TEST-INFO | 1634: exit 0 09:01:00 INFO - TEST-OK | dromaeo_css | took 1115357ms 09:01:00 INFO - TEST-START | kraken 09:01:00 INFO - operating with platform_type : mac_ 09:01:00 INFO - Initialising browser for kraken test... 09:01:00 INFO - TEST-INFO | started process 1646 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/T/tmpzQ8ty0/profile http://localhost:49240/getInfo.html) 09:01:03 INFO - PROCESS | 1646 | 2017-02-02 09:01:03.581 plugin-container[1648:10050] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x873f, name = 'com.apple.tsm.portname' 09:01:03 INFO - PROCESS | 1646 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:01:03 INFO - PROCESS | 1646 | 2017-02-02 09:01:03.582 plugin-container[1648:10050] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8c0f, name = 'com.apple.CFPasteboardClient' 09:01:03 INFO - PROCESS | 1646 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:01:03 INFO - PROCESS | 1646 | 2017-02-02 09:01:03.582 plugin-container[1648:10050] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 09:01:03 INFO - PROCESS | 1646 | __metrics Screen width/height:1600/1200 09:01:03 INFO - PROCESS | 1646 | colorDepth:24 09:01:03 INFO - PROCESS | 1646 | Browser inner width/height: 1024/689 09:01:03 INFO - PROCESS | 1646 | __metrics 09:01:03 ERROR - PROCESS | 1646 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3197: TypeError: this.tabs is undefined 09:01:04 INFO - TEST-INFO | 1646: exit 0 09:01:04 INFO - Browser initialized. 09:01:04 INFO - Running cycle 1/1 for kraken test... 09:01:04 INFO - TEST-INFO | started process 1649 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/T/tmpzQ8ty0/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:01:06 INFO - PROCESS | 1649 | 2017-02-02 09:01:06.913 plugin-container[1650:10174] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x842f, name = 'com.apple.tsm.portname' 09:01:06 INFO - PROCESS | 1649 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:01:06 INFO - PROCESS | 1649 | 2017-02-02 09:01:06.914 plugin-container[1650:10174] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8b17, name = 'com.apple.CFPasteboardClient' 09:01:06 INFO - PROCESS | 1649 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:01:06 INFO - PROCESS | 1649 | 2017-02-02 09:01:06.914 plugin-container[1650:10174] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 09:01:16 INFO - PROCESS | 1649 | RSS: Main: 177233920 09:01:16 INFO - PROCESS | 1649 | 09:01:19 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49240/tests/kraken/driver.html?testName=audio-beat-detection) 09:01:20 INFO - PROCESS | 1649 | RSS: Main: 190205952 09:01:20 INFO - PROCESS | 1649 | 09:01:23 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49240/tests/kraken/driver.html?testName=audio-dft) 09:01:23 INFO - PROCESS | 1649 | RSS: Main: 198017024 09:01:23 INFO - PROCESS | 1649 | 09:01:26 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49240/tests/kraken/driver.html?testName=audio-fft) 09:01:27 INFO - PROCESS | 1649 | RSS: Main: 201777152 09:01:27 INFO - PROCESS | 1649 | 09:01:29 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49240/tests/kraken/driver.html?testName=audio-oscillator) 09:01:30 INFO - PROCESS | 1649 | RSS: Main: 203227136 09:01:30 INFO - PROCESS | 1649 | 09:01:32 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49240/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:01:32 INFO - PROCESS | 1649 | RSS: Main: 203116544 09:01:32 INFO - PROCESS | 1649 | 09:01:35 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49240/tests/kraken/driver.html?testName=imaging-darkroom) 09:01:36 INFO - PROCESS | 1649 | RSS: Main: 206561280 09:01:36 INFO - PROCESS | 1649 | 09:01:38 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49240/tests/kraken/driver.html?testName=imaging-desaturate) 09:01:39 INFO - PROCESS | 1649 | RSS: Main: 207437824 09:01:39 INFO - PROCESS | 1649 | 09:01:42 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49240/tests/kraken/driver.html?testName=json-parse-financial) 09:01:43 INFO - PROCESS | 1649 | RSS: Main: 207507456 09:01:43 INFO - PROCESS | 1649 | 09:01:44 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49240/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:01:45 INFO - PROCESS | 1649 | RSS: Main: 206422016 09:01:45 INFO - PROCESS | 1649 | 09:01:47 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:01:47 INFO - PROCESS | 1649 | RSS: Main: 206704640 09:01:47 INFO - PROCESS | 1649 | 09:01:49 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:01:50 INFO - PROCESS | 1649 | RSS: Main: 206712832 09:01:50 INFO - PROCESS | 1649 | 09:01:53 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:01:54 INFO - PROCESS | 1649 | RSS: Main: 206716928 09:01:54 INFO - PROCESS | 1649 | 09:01:57 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:01:57 INFO - PROCESS | 1649 | RSS: Main: 207093760 09:01:57 INFO - PROCESS | 1649 | 09:01:59 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49240/tests/kraken/driver.html?testName=ai-astar) 09:01:59 INFO - PROCESS | 1649 | RSS: Main: 208781312 09:01:59 INFO - PROCESS | 1649 | 09:01:59 INFO - PROCESS | 1649 | __start_tp_report 09:01:59 INFO - PROCESS | 1649 | _x_x_mozilla_page_load 09:01:59 INFO - PROCESS | 1649 | _x_x_mozilla_page_load_details 09:01:59 INFO - PROCESS | 1649 | |i|pagename|runs| 09:01:59 INFO - PROCESS | 1649 | |0;ai-astar;108;117;119;117;119;117;118;119;141;116 09:01:59 INFO - PROCESS | 1649 | |1;audio-beat-detection;143;112;111;145;140;107;146;141;174;104 09:01:59 INFO - PROCESS | 1649 | |2;audio-dft;196;195;195;197;196;193;199;198;231;243 09:01:59 INFO - PROCESS | 1649 | |3;audio-fft;78;57;60;88;73;79;82;59;78;59 09:01:59 INFO - PROCESS | 1649 | |4;audio-oscillator;87;70;88;70;71;69;83;68;69;85 09:01:59 INFO - PROCESS | 1649 | |5;imaging-gaussian-blur;88;86;88;85;88;84;92;122;87;101 09:01:59 INFO - PROCESS | 1649 | |6;imaging-darkroom;98;99;99;99;100;99;101;99;103;172 09:01:59 INFO - PROCESS | 1649 | |7;imaging-desaturate;87;90;86;86;89;91;139;142;89;89 09:01:59 INFO - PROCESS | 1649 | |8;json-parse-financial;56;56;56;59;59;61;60;60;60;60 09:01:59 INFO - PROCESS | 1649 | |9;json-stringify-tinderbox;69;77;68;68;79;71;67;69;72;70 09:01:59 INFO - PROCESS | 1649 | |10;stanford-crypto-aes;81;83;88;81;84;80;82;80;82;86 09:01:59 INFO - PROCESS | 1649 | |11;stanford-crypto-ccm;136;140;137;136;164;141;144;162;182;129 09:01:59 INFO - PROCESS | 1649 | |12;stanford-crypto-pbkdf2;165;179;205;163;164;164;178;229;167;165 09:01:59 INFO - PROCESS | 1649 | |13;stanford-crypto-sha256-iterative;67;65;69;70;78;86;91;67;59;82 09:01:59 INFO - PROCESS | 1649 | __end_tp_report 09:01:59 INFO - PROCESS | 1649 | __start_cc_report 09:01:59 INFO - PROCESS | 1649 | _x_x_mozilla_cycle_collect,911 09:01:59 INFO - PROCESS | 1649 | __end_cc_report 09:01:59 INFO - PROCESS | 1649 | __startTimestamp1486054919708__endTimestamp 09:01:59 INFO - PROCESS | 1649 | 09:01:59 INFO - PROCESS | 1649 | ------- Summary: start ------- 09:01:59 INFO - PROCESS | 1649 | Number of tests: 14 09:01:59 INFO - PROCESS | 1649 | 09:01:59 INFO - PROCESS | 1649 | [#0] ai-astar Cycles:10 Average:119.10 Median:118.50 stddev:8.35 (7.0%) stddev-sans-first:7.83 09:01:59 INFO - PROCESS | 1649 | Values: 108.0 117.0 119.0 117.0 119.0 117.0 118.0 119.0 141.0 116.0 09:01:59 INFO - PROCESS | 1649 | 09:01:59 INFO - PROCESS | 1649 | [#1] audio-beat-detection Cycles:10 Average:132.30 Median:142.00 stddev:22.72 (16.0%) stddev-sans-first:23.76 09:01:59 INFO - PROCESS | 1649 | Values: 143.0 112.0 111.0 145.0 140.0 107.0 146.0 141.0 174.0 104.0 09:01:59 INFO - PROCESS | 1649 | 09:01:59 INFO - PROCESS | 1649 | [#2] audio-dft Cycles:10 Average:204.30 Median:197.50 stddev:17.54 (8.9%) stddev-sans-first:18.35 09:01:59 INFO - PROCESS | 1649 | Values: 196.0 195.0 195.0 197.0 196.0 193.0 199.0 198.0 231.0 243.0 09:01:59 INFO - PROCESS | 1649 | 09:01:59 INFO - PROCESS | 1649 | [#3] audio-fft Cycles:10 Average:71.30 Median:78.00 stddev:11.45 (14.7%) stddev-sans-first:11.89 09:01:59 INFO - PROCESS | 1649 | Values: 78.0 57.0 60.0 88.0 73.0 79.0 82.0 59.0 78.0 59.0 09:01:59 INFO - PROCESS | 1649 | 09:01:59 INFO - PROCESS | 1649 | [#4] audio-oscillator Cycles:10 Average:76.00 Median:77.00 stddev:8.52 (11.1%) stddev-sans-first:8.06 09:01:59 INFO - PROCESS | 1649 | Values: 87.0 70.0 88.0 70.0 71.0 69.0 83.0 68.0 69.0 85.0 09:01:59 INFO - PROCESS | 1649 | 09:01:59 INFO - PROCESS | 1649 | [#5] imaging-gaussian-blur Cycles:10 Average:92.10 Median:87.50 stddev:11.56 (13.2%) stddev-sans-first:12.17 09:01:59 INFO - PROCESS | 1649 | Values: 88.0 86.0 88.0 85.0 88.0 84.0 92.0 122.0 87.0 101.0 09:01:59 INFO - PROCESS | 1649 | 09:01:59 INFO - PROCESS | 1649 | [#6] imaging-darkroom Cycles:10 Average:106.90 Median:99.00 stddev:22.92 (23.1%) stddev-sans-first:24.08 09:01:59 INFO - PROCESS | 1649 | Values: 98.0 99.0 99.0 99.0 100.0 99.0 101.0 99.0 103.0 172.0 09:01:59 INFO - PROCESS | 1649 | 09:01:59 INFO - PROCESS | 1649 | [#7] imaging-desaturate Cycles:10 Average:98.80 Median:89.00 stddev:22.05 (24.8%) stddev-sans-first:22.97 09:01:59 INFO - PROCESS | 1649 | Values: 87.0 90.0 86.0 86.0 89.0 91.0 139.0 142.0 89.0 89.0 09:01:59 INFO - PROCESS | 1649 | 09:01:59 INFO - PROCESS | 1649 | [#8] json-parse-financial Cycles:10 Average:58.70 Median:60.00 stddev:1.95 (3.2%) stddev-sans-first:1.80 09:01:59 INFO - PROCESS | 1649 | Values: 56.0 56.0 56.0 59.0 59.0 61.0 60.0 60.0 60.0 60.0 09:01:59 INFO - PROCESS | 1649 | 09:01:59 INFO - PROCESS | 1649 | [#9] json-stringify-tinderbox Cycles:10 Average:71.00 Median:70.50 stddev:4.00 (5.7%) stddev-sans-first:4.18 09:01:59 INFO - PROCESS | 1649 | Values: 69.0 77.0 68.0 68.0 79.0 71.0 67.0 69.0 72.0 70.0 09:01:59 INFO - PROCESS | 1649 | 09:01:59 INFO - PROCESS | 1649 | [#10] stanford-crypto-aes Cycles:10 Average:82.70 Median:82.50 stddev:2.63 (3.2%) stddev-sans-first:2.71 09:01:59 INFO - PROCESS | 1649 | Values: 81.0 83.0 88.0 81.0 84.0 80.0 82.0 80.0 82.0 86.0 09:01:59 INFO - PROCESS | 1649 | 09:01:59 INFO - PROCESS | 1649 | [#11] stanford-crypto-ccm Cycles:10 Average:147.10 Median:142.50 stddev:16.66 (11.7%) stddev-sans-first:17.18 09:01:59 INFO - PROCESS | 1649 | Values: 136.0 140.0 137.0 136.0 164.0 141.0 144.0 162.0 182.0 129.0 09:01:59 INFO - PROCESS | 1649 | 09:01:59 INFO - PROCESS | 1649 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:177.90 Median:172.50 stddev:22.13 (12.8%) stddev-sans-first:22.97 09:01:59 INFO - PROCESS | 1649 | Values: 165.0 179.0 205.0 163.0 164.0 164.0 178.0 229.0 167.0 165.0 09:01:59 INFO - PROCESS | 1649 | 09:01:59 INFO - PROCESS | 1649 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:73.40 Median:74.00 stddev:10.30 (13.9%) stddev-sans-first:10.66 09:01:59 INFO - PROCESS | 1649 | Values: 67.0 65.0 69.0 70.0 78.0 86.0 91.0 67.0 59.0 82.0 09:01:59 INFO - PROCESS | 1649 | -------- Summary: end -------- 09:01:59 INFO - PROCESS | 1649 | 09:02:00 INFO - TEST-INFO | 1649: exit 0 09:02:00 INFO - TEST-OK | kraken | took 59876ms 09:02:00 INFO - SUITE-END | took 1175s 09:02:00 INFO - Completed test suite (00:19:35) 09:02:00 INFO - javascript benchmark 09:02:00 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7286.18452495588, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3448.293099914517, 3623.4202527595576, 3648.6168952259995, 3665.2852693724717, 3686.3364594373, 6043.972524670806, 6078.7872424465095, 6092.939070609293, 6105.938940610593, 6115.908261376072, 4956.801727930879, 6054.364291749369, 6158.476529494998, 6160.5687601867885, 6169.660668663222, 5449.64577302475, 5500.119980803072, 5503.532199763005, 5513.50378465938, 5572.219889215513, 4814.301926220696, 4913.606911447076, 5543.916841247385, 5572.3870374258795, 5573.693446860421, 5396.325459317585, 5406.0, 5565.805196818113, 5573.721313934308, 5579.69311687857, 5466.808661696841, 5494.066008778517, 5496.285482887199, 5503.587230957694, 5510.393856675762, 5346.4386239445075, 5459.235707001023, 5479.315085614298, 5489.6980666063355, 5511.22842802008, 6088.269407671063, 6109.7690694183475, 6126.670355029888, 6127.953177891112, 6142.385761423822, 6270.717817698169, 6360.491160707133, 6365.936340636581, 6385.616862988236, 6416.647084410356, 6483.351664833526, 6493.545451818374, 6495.3179916109075, 6502.219733631947, 6513.804585862432, 6030.909536356914, 6115.453310713017, 6120.571559990758, 6194.659293738842, 6202.565820392574, 6558.967205163944, 6595.406413422815, 6647.0029495576055, 6675.532712710161, 6714.52998290115, 6380.776672816429, 6384.754972780263, 6385.840353991094, 6395.712192951329, 6417.582857114366, 6297.96851015742, 6323.336049714806, 6535.934640653628, 6536.084948107269, 6542.738290468423, 6227.626342419469, 6288.717007734663, 6349.9682501587195, 6407.231132264065, 6429.517786166056, 6369.20384951881, 6414.775482858171, 6432.228132624022, 6435.22777266731, 6438.710258038306, 4249.192653395845, 5089.770960306795, 5235.581153507786, 5245.606579506552, 5279.894402112013, 3778.205352457616, 4147.419361289422, 4569.588737013684, 4572.611328037087, 4669.042846216517, 4873.488283730228, 6191.102290167901, 6238.469730072904, 6257.655828929453, 6273.498120150377, 6290.182276304051, 6491.448226900767, 6536.640484773383, 6538.607683539003, 6551.050097735897, 6107.15847211733, 6141.271221159682, 6142.560166306883, 6209.0, 6287.49700023997, 5747.798827041033, 5789.855253618609, 5791.449812267828, 5802.622829516068, 5819.476247137778, 6498.220213574212, 6574.901376479261, 6618.966905165444, 6753.662316884136, 6761.830954226085, 6514.9348506514325, 6567.27759946396, 6574.145361103026, 6583.835404114935, 6592.8351791205605, 6719.529632925648, 6728.798136055926, 6730.831729206809, 6758.526903116931, 6784.6946887389195, 6219.564630475823, 6310.495160387066, 6372.044193371032, 6444.065610486547, 6445.617909045812, 6392.20097487814, 6433.710483028369, 6472.223333200046, 6544.9672751637845, 6551.967240163769, 6317.30509643948, 6321.209848768904, 6322.36776322364, 6328.715207815567, 6334.746610135543, 6170.660613666291, 6212.844678882883, 6324.083007963912, 6325.715342809676, 6347.650879201688, 6107.236595425572, 6203.534734894991, 6255.061740738925, 6283.905741413974, 6314.0, 6349.174607301022, 6350.90473642905, 6363.268224154282, 6390.712417941111, 6440.033994900802, 6208.751649934133, 6284.214473190851, 6304.464120549805, 6309.432151106423, 6331.113644089739], "value": 5963.678302991657, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [466.17487047925175, 490.5584973523829, 496.01045913402754, 500.9123403404408, 501.78423277990447, 38424.423633645485, 43644.563554364446, 43710.99999999996, 43744.12511749763, 43760.0, 43248.13503729924, 43628.0, 43723.0, 43753.56246437535, 43802.561974380245, 9669.806603867919, 9870.0, 9873.259505537078, 9909.405435673883, 9925.106740393363, 9758.90241097587, 9787.510624468783, 9814.214862810994, 9823.852642210373, 9860.11258986691, 9770.706878793648, 9797.510124493783, 9817.0, 9843.409395436296, 9868.21054315653, 8347.91477107978, 9762.658306959258, 9834.065763752504, 9838.458884761336, 9838.655647052354, 9693.127618514298, 9784.168345690625, 9787.070228328368, 9797.510124493818, 9801.0, 33367.49948750746, 33877.830610847035, 40853.59146408528, 41223.587764122574, 41273.0, 3055.4805683033715, 3215.8070515768895, 3371.679690429405, 3486.093615659947, 3817.8091095445116, 31403.214919626964, 31547.21131971696, 31555.684443155504, 31608.36783264345, 31693.52459713106, 3241.8216998065095, 3268.7385009199443, 3302.669733026702, 3324.2852786650988, 3329.467285234375, 481211.0, 489827.0, 523694.0, 526648.0, 529716.3514182443, 59609.0, 59723.0, 59797.70101149466, 60049.399506005255, 60084.0, 58302.0, 59848.70075649594, 60991.0, 61351.6932415335, 61734.60459674761, 16507.09210993407, 16908.746368804477, 17164.485065448058, 17178.055206963487, 17198.968061916326, 12840.935795320964, 12916.289604071679, 12921.612351629467, 12958.870411295955, 13008.934955325352, 13325.0, 15069.54791356261, 15247.932644714763, 15443.91892567532, 15670.294836732375, 3283.113559338965, 6010.9699451502465, 6167.753289868445, 6178.320384757673, 6202.038684003986, 882.2633101360308, 949.7720547068792, 987.8419452887647, 1019.4138370437029, 1053.2732414633879, 30681.53977690292, 30998.0, 31116.91090811811, 31143.844280778452, 31380.0, 973.2797729680127, 1061.8247989081715, 1105.6130354376064, 1126.065365746445, 1176.917615766888, 14087.436502539784, 15743.448979285899, 15766.76349854753, 16387.672246555, 16445.506634800975, 668236.6588167028, 680556.0, 686080.0, 686662.0, 688538.0, 58812.70593647005, 59267.407325926186, 59332.70333648304, 59381.70309148427, 59479.40520594825, 1388.2850332078988, 1398.3008495752124, 1421.729871324445, 1424.5583869000643, 1428.6285565752978, 29470.557941631323, 30095.548566771955, 30312.242193945327, 30488.84755576208, 31085.844570777, 49451.0, 49560.256596151805, 49671.751641241564, 49854.0, 49940.0, 617730.0, 618389.0, 621070.0, 621738.0, 624892.0, 26565.867170664023, 26728.732712672623, 26973.190804275902, 27091.458170836868, 27981.300467488476, 46514.0, 46825.0, 47776.283355748994, 47866.760666196446, 47980.0, 38121.88745638786, 39032.024199395244, 39779.40330895097, 40346.0, 40523.18953620852, 34764.13089672779, 38967.0, 39876.00309992273, 39972.40041399323, 40199.19601607893, 38248.61751382451, 38904.0, 39010.80494597509, 39096.41355379615, 39106.80446597749, 29711.702882970894, 31559.0, 34190.0, 35218.64781352154, 35721.46417803683, 21500.677489837977, 25104.62343064892, 26103.608445872946, 26115.824787884972, 26368.736312637397], "value": 19967.935267192435, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [380.8102634664392, 381.9427085937111, 383.9654431101201, 393.2587073366704, 413.18602353363883, 14847.55457336279, 19243.615127697456, 19630.705539416886, 20557.486062848435, 20563.38309850703, 19879.503012424695, 20071.096800644005, 20586.382408527767, 20825.27111551096, 20876.89561552194, 5707.857303567403, 6132.233470816148, 6154.138420621117, 6211.3167551569295, 6213.161223234873, 4977.651564390494, 6011.490354884388, 6090.14737936687, 6141.140240366352, 6170.136180934673, 5988.131720900488, 6042.274927008765, 6069.059295809135, 6071.1196876453105, 6097.359777223394, 5817.534597232232, 6103.481204097657, 6122.0, 6146.723397447103, 6172.506199504029, 5346.251524786533, 5349.22436246742, 5354.41101478837, 5355.0, 5371.355437347504, 24617.753822461724, 25382.619260711104, 25503.872480637663, 25666.48667026649, 25815.870920645277, 2351.247600767755, 2367.597508423572, 2451.7548245175517, 2549.6430499730054, 3080.471375472042, 12170.452329645188, 15258.847411525854, 15614.843851561565, 15871.92064039684, 16047.518574442785, 1916.4154932745507, 1973.1416833677397, 2000.3898810862681, 2017.5662232619911, 2023.0693880814842, 120240.0, 126207.0, 128831.35584322113, 129161.0, 129754.3512282442, 10796.352218866736, 11138.721531961766, 11223.551057957753, 11243.988041076342, 11440.313581185155, 12160.452779624939, 12823.61529154127, 13912.513062042983, 13916.58250252494, 13930.512432065032, 7201.099862517185, 8346.123657015925, 8745.12548745133, 8851.69019084329, 8915.37592368541, 7325.743598974011, 7471.327580517671, 8796.196779660155, 8908.153725396078, 9023.278137748965, 6679.198496180489, 6683.7660681875905, 6727.865442691119, 6804.6257455840405, 6828.283030281848, 1642.136175440883, 1728.8157612035795, 1869.2616416515673, 1928.1419768203016, 1965.8132477414622, 940.9623615055458, 993.2054356514759, 996.1184351848522, 1016.5323950982631, 1043.2697112021463, 20695.896520517603, 20726.0, 21522.139114435562, 21959.376702844173, 22127.225547105772, 888.1784349476708, 905.9320550958704, 951.6968935176889, 977.0766625538939, 1003.6336737090909, 10411.751878357745, 10801.978580235123, 10805.965122556387, 10836.07322718096, 10836.98821332243, 592696.0, 611377.0, 613168.0, 622856.0, 629170.0, 129962.0, 138261.0, 139146.91279630814, 142797.0, 148488.0, 442.16651611712155, 442.8317239448989, 460.8433132734819, 461.2228395154176, 465.4554171619214, 10815.567377305135, 11117.332960022424, 11279.943600281946, 11340.376279304717, 11368.829467558158, 55464.445355545926, 56132.71933640305, 57058.85882282414, 57778.84442311046, 58559.0, 81522.0, 82071.17928820875, 82666.0, 84138.0, 86515.1348486507, 7135.286471353031, 7677.155512893465, 8316.459430137022, 8320.12638672955, 8336.333093352396, 11305.75636679981, 11416.828747568627, 12580.0, 12611.810822837848, 12813.295268760308, 9121.589528471368, 10721.35671859691, 11117.27737697047, 11156.609518666808, 11278.266912650652, 10498.475076246004, 10840.5121769519, 11062.557497700001, 11134.27627204229, 11223.0, 10355.43045132525, 10812.405317707287, 11266.605668801552, 11442.656720298404, 11785.646430607096, 8233.38249631268, 8345.0, 8440.409171358191, 8452.070272270168, 8529.445586036887, 5484.341878974548, 8106.513609183467, 8311.25198732117, 8424.736289556371, 10679.305845120043], "value": 9452.80460928303, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1348.8313960754904, 1456.8106146200992, 1520.581839994002, 1744.3022790883654, 1866.0856180471574, 7733.961330193355, 8011.278984891359, 8101.5544145072, 8559.144085591437, 8576.871346929802, 8169.060558035828, 8445.704400345989, 8447.281981031605, 8558.572071396437, 8576.785580360494, 2127.1810353014125, 2343.5470008594693, 2390.8445951013127, 2392.9760702393014, 2413.8534196256815, 2402.5915594348917, 2437.4515733959893, 2439.0975339124548, 2441.2066078524463, 2442.6091825307954, 2362.586547354215, 2373.5252949410187, 2374.8693821839793, 2395.6765836612094, 2417.55275274074, 2112.176251262009, 2261.8869056547105, 2372.5136347048815, 2403.6634871118144, 2413.215704895907, 2393.1743548475747, 2395.4610212702178, 2413.3242692046256, 2416.178499310243, 2426.126594426023, 8517.403781735282, 8550.48697078171, 8605.010423801281, 8612.48325100496, 8686.0, 4964.808445972976, 5221.608379371524, 5312.256284120226, 5364.356277246717, 5379.9731001345135, 3285.687859653353, 3483.477478378238, 3529.0, 3550.9112272193142, 3637.9818100909592, 1073.4257172412722, 1073.667163179409, 1107.479484642217, 1108.3571528513444, 1110.7112150840758, 9280.814383712288, 11081.833772493419, 11142.108631309477, 11149.66551003463, 11162.274452160584, 4566.292224705176, 4682.859514214579, 4752.097101550763, 4758.809647614056, 4856.587190088882, 9677.467739274267, 9683.709488715349, 9706.466144361986, 9754.804903901882, 9873.703788886345, 5306.681599104066, 5356.678599284055, 5370.516653501126, 5376.4085950545405, 5381.246625472437, 4446.399736035593, 4492.303692927601, 4893.828715994923, 4960.627952903546, 4996.725834912074, 3974.940375894364, 3984.1234928316303, 4051.8379264829077, 4078.857239996645, 4108.157827645386, 1306.2554344023815, 1315.8552559218479, 1317.7166909114394, 1323.3118778235457, 1331.3942156318851, 760.3309088002522, 839.9412041157183, 869.1786261982493, 923.9676611318572, 942.1144124522926, 4937.7777999990085, 5821.650700957956, 5858.5898987070495, 5910.143029260733, 6013.51891848651, 756.6254703921594, 953.2183609440192, 979.0943377375899, 987.8913319534845, 990.6186118344345, 3775.6601905828607, 3870.8645197417954, 3883.456316115746, 3949.486566746362, 4011.618896204856, 10074.194064474825, 10428.217883658604, 10440.903705111006, 10476.738081548023, 10572.52423640938, 10249.026342497451, 10442.738931526772, 10490.527926243338, 10538.315009524356, 10614.310069845435, 6036.5170786336985, 6567.7701280454, 6721.865562688817, 8102.83794324122, 8247.628856701464, 4871.928175801318, 5351.812686555952, 5440.129579267298, 5870.442307980735, 5875.559333050124, 9167.770805729644, 9376.968533461177, 9527.190188833749, 9564.04359564038, 9678.70963871085, 9570.473623950471, 9674.274429417963, 9804.607815687292, 9914.504274786088, 9941.105300522988, 6776.830579235559, 7002.0, 7156.642167891689, 7159.284071592799, 7448.776536703907, 7912.0505539335645, 8083.110857805755, 8109.75670729879, 8118.59407029658, 8141.552214628252, 7285.7814265572115, 7360.411167106511, 7457.105147382349, 7537.982372379659, 7558.281963213486, 7217.24218957002, 7271.709131634675, 7297.16082650502, 7310.96344518274, 7314.890276645748, 6842.452603791585, 6937.82655433598, 6952.895706564506, 6952.930470695228, 6977.1278590176225, 4782.33047373361, 4843.39457567804, 4872.244802055687, 4896.681715688469, 5002.424721157114, 4863.8540843774745, 4902.744737420684, 4991.725455100004, 5003.949960500348, 5027.64806463544], "value": 4510.674217120987, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [261.39878279956105, 283.5902121434528, 300.62271848829727, 318.0315937968885, 321.2018134934688, 4817.831375901844, 5452.454754524546, 5532.972335138324, 5596.188552659862, 5635.971820140899, 4423.0, 4523.115035761378, 4972.900541989167, 5465.535429488498, 5540.722963851811, 3908.335582950891, 4196.832126714928, 4212.178625168093, 4225.218334608099, 4226.535081141072, 4066.573009833969, 4149.356849688304, 4214.346776249687, 4218.3250679891225, 4230.0, 4077.4903137107863, 4141.109661422794, 4147.937780933288, 4149.9792501037455, 4177.624013838754, 3741.727812543721, 3989.2221016901717, 4102.138550904314, 4103.712740108163, 4147.419361289422, 2770.5982632518376, 3546.627604101558, 3556.6265542117962, 3578.481120237551, 3610.1696609779633, 5257.658252213594, 5375.247465354853, 5378.273933019071, 5396.841263494594, 5507.476789704971, 3881.961180388188, 4568.794404251784, 4702.200625893572, 4732.645051621143, 4761.952380476186, 5696.401877802854, 5744.6840423776675, 5769.682667453289, 5789.247397838296, 5820.650760954314, 3158.0052283530613, 3271.9509207361907, 3297.9340413191603, 3752.1832327723077, 3949.3681011038384, 4972.0, 5075.390953085616, 5661.867626474722, 6152.077188421773, 6156.661383623944, 3988.4615576897327, 3992.720509564361, 4017.5781542938157, 4018.3972404139613, 4019.41718450823, 3318.784279021856, 3624.0396294981324, 3628.5101511296166, 3647.3434781739543, 4044.7775372354213, 4499.965008048167, 4654.603618914312, 4670.509596492388, 4685.8594242172785, 4715.882102947385, 4985.731492171053, 5760.049591817387, 5786.566007549366, 5865.97345464542, 5886.793962211302, 1071.3946620159586, 1139.9316041037564, 1279.296386987172, 1294.643972907439, 1303.1920209470188, 836.8869403693074, 862.1895418306772, 883.2977782662798, 888.7333799860016, 892.6161750447241, 4417.078570821703, 4671.780904070607, 4855.854324370274, 5217.095760047555, 5222.242774797709, 4431.224535706238, 4823.9517604824205, 5225.686458812484, 5236.528712415901, 5527.364353099365, 4815.3258543803895, 5215.400228973641, 5222.877081427511, 5233.71214583194, 5238.397584277781, 2543.5548778963976, 2669.5584384432577, 2680.9597856032174, 2982.224621598357, 3028.197527655173, 5082.491750824888, 5678.097837224494, 5900.20347253124, 5971.775785963867, 5977.940220597825, 4714.232162938898, 5289.788408463695, 5293.961824697519, 5428.647137936022, 5496.0, 5434.375046869661, 5606.383297837317, 5829.737661805144, 5903.575925962931, 5903.793367232126, 4237.372828846135, 5169.870753231199, 5477.178423236546, 5583.302087239095, 5609.831705048855, 4620.630349572093, 4970.776315065758, 5247.58019358458, 5503.174523821459, 5572.77708891656, 4787.95212047889, 5487.396386397413, 5707.80136171392, 5882.294124705063, 5921.348651648402, 3358.4718952875896, 3757.380032294641, 3779.3197224499854, 3796.487474190949, 3797.050737315671, 5465.945340546703, 5473.948152974336, 5478.561715062865, 5675.8864822704145, 5693.459121383462, 4959.7272150032095, 5619.325680918316, 5670.865826834567, 5790.913136302875, 5815.33123690764, 5805.8548536286935, 5820.621659592113, 5849.268841394825, 5853.624398266489, 5869.413058694096, 5009.974950125226, 5152.922706159486, 5586.385682389336, 5745.827625171251, 5784.756277400211, 5079.822206222764, 5720.685362305113, 5770.798022069207, 5789.392113828156, 5824.621399609012, 5186.688798672092, 5686.185338257589, 5697.401772813961, 5699.743511542073, 5735.963732874936], "value": 4076.110839949089, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [659.870103531344, 689.9068625735528, 780.6799212322949, 806.2340776262552, 812.1999830167284, 15028.0, 15594.06435613864, 15653.608659783496, 15695.293711782968, 15697.450589229366, 14021.369038393272, 14091.718165636707, 14106.083104598194, 14148.151110933351, 14214.431422743079, 838.119974027272, 844.3203221406765, 866.5970323799432, 873.3929918706501, 874.5976850648677, 839.1608391608391, 854.1885209051395, 859.1751918158544, 864.023653271802, 866.3156106675718, 889.8042430665244, 900.3517467423445, 917.8806755121825, 918.9845221030765, 919.2002957427014, 888.506878682331, 892.8794612727296, 905.5879574793486, 908.3913777768911, 908.7410088124885, 590.8788698316878, 662.2681936460239, 664.9002649602598, 681.4922882452555, 683.2279524137742, 12428.875711242863, 12729.936350318188, 12796.104272700915, 12947.417366218542, 13551.0, 11983.520659173622, 12597.748045039049, 12599.811002834966, 12693.619191424272, 13088.672783180402, 12251.897329240412, 12521.874781252161, 12583.496660133584, 12927.5475358362, 13094.345282735825, 4150.190712811003, 4355.259605867011, 4577.22187228172, 4700.341952126705, 4740.099381117577, 100167.9983200166, 100681.0, 101457.49271253597, 101577.49211253971, 104476.95523044825, 8846.690365837294, 9770.120689138012, 10480.266381353233, 10579.894201058045, 10834.67495975122, 19347.709784353247, 19514.926679032505, 19617.803821961883, 19781.40655780329, 19820.801791982183, 6527.836804079936, 7353.264673532604, 7584.81037974044, 7601.239876012354, 7646.0, 4155.605217504332, 4206.843925594825, 4211.241976444269, 4255.5318914919335, 4359.258925982591, 3266.722328602048, 3337.5828021497314, 3369.663033696611, 3376.848041838123, 3377.071305391037, 493.4054464370425, 626.1484381241507, 627.0406278394064, 637.3562701671345, 640.3628389752133, 581.2937281203274, 591.6361437715774, 607.9057746049369, 608.5861614102364, 616.7841255560608, 8548.187922147386, 8910.634824246175, 8954.23888969432, 8960.820783584422, 9030.096990300917, 604.5405491826156, 618.264265524025, 620.8955223880597, 637.5250438423404, 645.2643985856128, 4209.17921005401, 4209.684273679486, 4469.597736203757, 4489.595936365743, 4490.640748740093, 118972.21541676881, 119204.40397798129, 120289.19566206513, 123813.38093309476, 124952.75047249411, 85796.85507862353, 92678.2196534105, 109415.0, 110767.67696969103, 110814.0, 73565.52868942592, 93637.5318123405, 94364.0, 96236.51881740546, 96442.0, 7160.498765086394, 7501.6249187539315, 8566.443181193203, 8587.014952117317, 8755.824883502422, 60218.69890650519, 60330.09504857343, 60954.69522652358, 61359.0, 61802.69098654478, 80553.0, 87054.0, 88672.0, 90804.0, 90965.54517227372, 9810.411375317504, 9969.50152492387, 10114.494275286059, 10152.64465743725, 10570.048695617432, 11682.556067832438, 11911.3448760319, 12000.418967245781, 12031.518739250332, 12085.15403921717, 10402.323848949794, 10528.36829790215, 11061.612843550438, 11067.612633557786, 11423.600173993871, 10279.743006424898, 11486.655400338002, 11509.53961841517, 11716.589919352782, 12201.389930503618, 10486.26596138263, 10656.360618362924, 11440.828387574027, 11480.138989575982, 11764.882351176379, 9150.267978561566, 9189.678361257324, 9330.066993300616, 9392.765180870532, 9599.616015359308, 9726.36778609388, 9754.414735115915, 9763.115465707553, 9765.902340976783, 9857.852132218166], "value": 7228.691961860494, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1511.6000000000001, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [108.0, 117.0, 119.0, 117.0, 119.0, 117.0, 118.0, 119.0, 141.0, 116.0], "value": 119.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [143.0, 112.0, 111.0, 145.0, 140.0, 107.0, 146.0, 141.0, 174.0, 104.0], "value": 132.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [196.0, 195.0, 195.0, 197.0, 196.0, 193.0, 199.0, 198.0, 231.0, 243.0], "value": 204.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 57.0, 60.0, 88.0, 73.0, 79.0, 82.0, 59.0, 78.0, 59.0], "value": 71.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 70.0, 88.0, 70.0, 71.0, 69.0, 83.0, 68.0, 69.0, 85.0], "value": 76.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 86.0, 88.0, 85.0, 88.0, 84.0, 92.0, 122.0, 87.0, 101.0], "value": 92.1, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [98.0, 99.0, 99.0, 99.0, 100.0, 99.0, 101.0, 99.0, 103.0, 172.0], "value": 106.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 90.0, 86.0, 86.0, 89.0, 91.0, 139.0, 142.0, 89.0, 89.0], "value": 98.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 56.0, 56.0, 59.0, 59.0, 61.0, 60.0, 60.0, 60.0, 60.0], "value": 58.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 77.0, 68.0, 68.0, 79.0, 71.0, 67.0, 69.0, 72.0, 70.0], "value": 71.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 83.0, 88.0, 81.0, 84.0, 80.0, 82.0, 80.0, 82.0, 86.0], "value": 82.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [136.0, 140.0, 137.0, 136.0, 164.0, 141.0, 144.0, 162.0, 182.0, 129.0], "value": 147.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [165.0, 179.0, 205.0, 163.0, 164.0, 164.0, 178.0, 229.0, 167.0, 165.0], "value": 177.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 65.0, 69.0, 70.0, 78.0, 86.0, 91.0, 67.0, 59.0, 82.0], "value": 73.4, "unit": "score"}]}]} 09:02:00 INFO - Return code: 0 09:02:01 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 09:02:01 ERROR - # TBPL SUCCESS # 09:02:01 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:02:01 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:02:01 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:02:01 INFO - 2017-02-02 09:02:01.051 screenresolution[1654:10561] starting screenresolution argv=screenresolution get 09:02:01 INFO - 2017-02-02 09:02:01.054 screenresolution[1654:10561] Display 0: 1600x1200x32@60 09:02:01 INFO - 2017-02-02 09:02:01.060 screenresolution[1655:10564] starting screenresolution argv=screenresolution list 09:02:01 INFO - Available Modes on Display 0 09:02:01 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:02:01 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:02:01 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:02:01 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:02:01 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:02:01 INFO - Intel Iris: 09:02:01 INFO - Chipset Model: Intel Iris 09:02:01 INFO - Type: GPU 09:02:01 INFO - Bus: Built-In 09:02:01 INFO - VRAM (Dynamic, Max): 1536 MB 09:02:01 INFO - Vendor: Intel (0x8086) 09:02:01 INFO - Device ID: 0x0a2e 09:02:01 INFO - Revision ID: 0x0009 09:02:01 INFO - Displays: 09:02:01 INFO - Display: 09:02:01 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:02:01 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:02:01 INFO - Display Serial Number: 1600x1200 60 09:02:01 INFO - Main Display: Yes 09:02:01 INFO - Mirror: Off 09:02:01 INFO - Online: Yes 09:02:01 INFO - Rotation: Supported 09:02:01 INFO - Return code: 0 09:02:01 INFO - Running post-action listener: _resource_record_post_action 09:02:01 INFO - [mozharness: 2017-02-02 17:02:01.300585Z] Finished run-tests step (success) 09:02:01 INFO - Running post-run listener: _resource_record_post_run 09:02:01 INFO - Total resource usage - Wall time: 1197s; CPU: 54.0%; Read bytes: 39278592; Write bytes: 502522368; Read time: 246; Write time: 1628 09:02:01 INFO - TinderboxPrint: CPU usage
54.0% 09:02:01 INFO - TinderboxPrint: I/O read bytes / time
39,278,592 / 246 09:02:01 INFO - TinderboxPrint: I/O write bytes / time
502,522,368 / 1,628 09:02:01 INFO - TinderboxPrint: CPU idle
2,201.1 (46.0%) 09:02:01 INFO - TinderboxPrint: CPU system
965.5 (20.2%) 09:02:01 INFO - TinderboxPrint: CPU user
1,623.2 (33.9%) 09:02:01 INFO - TinderboxPrint: Swap in / out
231,124,992 / 0 09:02:01 INFO - install - Wall time: 22s; CPU: 54.0%; Read bytes: 185307648; Write bytes: 178681344; Read time: 16823; Write time: 398 09:02:01 INFO - run-tests - Wall time: 1176s; CPU: 54.0%; Read bytes: 37484544; Write bytes: 308390912; Read time: 232; Write time: 1179 09:02:01 INFO - Running post-run listener: _upload_blobber_files 09:02:01 INFO - Blob upload gear active. 09:02:01 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:02:01 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 09:02:01 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Beta', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 09:02:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Beta -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 09:02:02 INFO - (blobuploader) - INFO - Open directory for files ... 09:02:02 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 09:02:02 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:02:02 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:02:03 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 09:02:03 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:02:03 INFO - (blobuploader) - INFO - Done attempting. 09:02:03 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 09:02:03 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:02:03 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:02:04 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 09:02:04 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:02:04 INFO - (blobuploader) - INFO - Done attempting. 09:02:04 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 09:02:04 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:02:04 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:02:06 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 09:02:06 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:02:06 INFO - (blobuploader) - INFO - Done attempting. 09:02:06 INFO - (blobuploader) - INFO - Iteration through files over. 09:02:06 INFO - Return code: 0 09:02:06 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:02:06 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:02:06 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/f8ad5a33c9f62acc18659b6ce56c8d5236153533360720367a2b4c224552a22f0b5d39c8eec91179a2341b6c99be6a64281e1c8b5b87862a3ae1824d88389588", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/e3bd0a5eb9508e7430fb66bb9958c76542c6d3d6d62159e58c5bef35c9fade06ae2dc4ad08ce879faa47a0616ff4be2423a112485ce994bc97275ccb77a9935f", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/b3cbaaa7d2d1575b9f6306f7b32f6b47f8aa1132e8521084f27410651e1f4e7cbab2cc7031e1bf9e93c4bdcb822a3e1b55aeccc9f8d5d632a55872c76af64419"} 09:02:06 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:02:06 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:02:06 INFO - Contents: 09:02:06 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/f8ad5a33c9f62acc18659b6ce56c8d5236153533360720367a2b4c224552a22f0b5d39c8eec91179a2341b6c99be6a64281e1c8b5b87862a3ae1824d88389588", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/e3bd0a5eb9508e7430fb66bb9958c76542c6d3d6d62159e58c5bef35c9fade06ae2dc4ad08ce879faa47a0616ff4be2423a112485ce994bc97275ccb77a9935f", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/b3cbaaa7d2d1575b9f6306f7b32f6b47f8aa1132e8521084f27410651e1f4e7cbab2cc7031e1bf9e93c4bdcb822a3e1b55aeccc9f8d5d632a55872c76af64419"} 09:02:06 INFO - Running post-run listener: copy_logs_to_upload_dir 09:02:06 INFO - Copying logs to upload dir... 09:02:06 INFO - mkdir: /builds/slave/test/build/upload/logs 09:02:06 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1259.298875 ========= master_lag: 1.23 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 0 secs) (at 2017-02-02 09:02:08.137172) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-02-02 09:02:08.141788) ========= 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.Zip9ggWnY3/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.rr6NnwXpHR/Listeners TMPDIR=/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/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-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/f8ad5a33c9f62acc18659b6ce56c8d5236153533360720367a2b4c224552a22f0b5d39c8eec91179a2341b6c99be6a64281e1c8b5b87862a3ae1824d88389588", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/e3bd0a5eb9508e7430fb66bb9958c76542c6d3d6d62159e58c5bef35c9fade06ae2dc4ad08ce879faa47a0616ff4be2423a112485ce994bc97275ccb77a9935f", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/b3cbaaa7d2d1575b9f6306f7b32f6b47f8aa1132e8521084f27410651e1f4e7cbab2cc7031e1bf9e93c4bdcb822a3e1b55aeccc9f8d5d632a55872c76af64419"} build_url:https://queue.taskcluster.net/v1/task/TEyjGFceQTyCFc5rxFCMSg/artifacts/public/build/firefox-52.0.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011024 build_url: 'https://queue.taskcluster.net/v1/task/TEyjGFceQTyCFc5rxFCMSg/artifacts/public/build/firefox-52.0.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/f8ad5a33c9f62acc18659b6ce56c8d5236153533360720367a2b4c224552a22f0b5d39c8eec91179a2341b6c99be6a64281e1c8b5b87862a3ae1824d88389588", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/e3bd0a5eb9508e7430fb66bb9958c76542c6d3d6d62159e58c5bef35c9fade06ae2dc4ad08ce879faa47a0616ff4be2423a112485ce994bc97275ccb77a9935f", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/b3cbaaa7d2d1575b9f6306f7b32f6b47f8aa1132e8521084f27410651e1f4e7cbab2cc7031e1bf9e93c4bdcb822a3e1b55aeccc9f8d5d632a55872c76af64419"}' ========= master_lag: 0.64 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-02-02 09:02:08.797767) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-02-02 09:02:08.798101) ========= 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.Zip9ggWnY3/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.rr6NnwXpHR/Listeners TMPDIR=/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/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.005022 ========= master_lag: 0.02 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-02-02 09:02:08.825127) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-02-02 09:02:08.825441) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-02-02 09:02:08.825815) ========= ========= Total master_lag: 1.97 =========