builder: mozilla-aurora_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0229 starttime: 1488190915.16 results: success (0) buildid: 20170227014408 builduid: 1da6e370435e4e5ebce8cfa9db1b135b revision: 6ce37482387bc35b3724620b51c556eaa0312724 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-02-27 02:21:55.157489) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-02-27 02:21:55.178661) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-02-27 02:21:55.179033) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-02-27 02:21:55.251207) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-02-27 02:21:55.251864) ========= 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.g7Lu7AvFsV/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.yuHrJ5xx2q/Listeners TMPDIR=/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/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-27 02:21:55-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 15.7M=0.001s 2017-02-27 02:21:56 (15.7 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=1.141602 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-02-27 02:21:56.415921) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-02-27 02:21:56.416227) ========= 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.g7Lu7AvFsV/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.yuHrJ5xx2q/Listeners TMPDIR=/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/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.020886 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-02-27 02:21:56.467046) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 3 secs) (at 2017-02-27 02:21:56.467411) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 6ce37482387bc35b3724620b51c556eaa0312724 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 6ce37482387bc35b3724620b51c556eaa0312724 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.g7Lu7AvFsV/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.yuHrJ5xx2q/Listeners TMPDIR=/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/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-27 02:21:56,558 truncating revision to first 12 chars 2017-02-27 02:21:56,558 Setting DEBUG logging. 2017-02-27 02:21:56,558 attempt 1/10 2017-02-27 02:21:56,558 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/6ce37482387b?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-02-27 02:22:00,066 unpacking tar archive at: mozilla-aurora-6ce37482387b/testing/mozharness/ program finished with exit code 0 elapsedTime=3.849809 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 3 secs) (at 2017-02-27 02:22:00.343131) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-02-27 02:22:00.343505) ========= script_repo_revision: 6ce37482387bc35b3724620b51c556eaa0312724 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-02-27 02:22:00.343951) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-02-27 02:22:00.344377) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-02-27 02:22:00.365649) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 45 secs) (at 2017-02-27 02:22:00.366347) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Aurora', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.g7Lu7AvFsV/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.yuHrJ5xx2q/Listeners TMPDIR=/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/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 02:22:00 INFO - MultiFileLogger online at 20170227 02:22:00 in /builds/slave/test 02:22:00 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 02:22:00 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 02:22:00 INFO - {'append_to_log': False, 02:22:00 INFO - 'base_work_dir': '/builds/slave/test', 02:22:00 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 02:22:00 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 02:22:00 INFO - 'branch': 'Mozilla-Aurora', 02:22:00 INFO - 'buildbot_json_path': 'buildprops.json', 02:22:00 INFO - 'config_files': ('talos/mac_config.py',), 02:22:00 INFO - 'default_actions': ('clobber', 02:22:00 INFO - 'read-buildbot-config', 02:22:00 INFO - 'download-and-extract', 02:22:00 INFO - 'populate-webroot', 02:22:00 INFO - 'create-virtualenv', 02:22:00 INFO - 'install', 02:22:00 INFO - 'run-tests'), 02:22:00 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 02:22:00 INFO - 'download_minidump_stackwalk': True, 02:22:00 INFO - 'download_symbols': 'ondemand', 02:22:00 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 02:22:00 INFO - 'tooltool.py': '/tools/tooltool.py', 02:22:00 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 02:22:00 INFO - '/tools/misc-python/virtualenv.py')}, 02:22:00 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 02:22:00 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 02:22:00 INFO - 'installer_path': 'installer.exe', 02:22:00 INFO - 'log_level': 'info', 02:22:00 INFO - 'log_name': 'talos', 02:22:00 INFO - 'log_to_console': True, 02:22:00 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 02:22:00 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 02:22:00 INFO - 'opt_config_files': (), 02:22:00 INFO - 'pip_index': False, 02:22:00 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 02:22:00 INFO - 'cmd': ('bash', 02:22:00 INFO - '-c', 02:22:00 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 02:22:00 INFO - 'enabled': True, 02:22:00 INFO - 'halt_on_failure': False, 02:22:00 INFO - 'name': 'check_screen_resolution'},), 02:22:00 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 02:22:00 INFO - 'cmd': ('bash', 02:22:00 INFO - '-c', 02:22:00 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 02:22:00 INFO - 'enabled': True, 02:22:00 INFO - 'halt_on_failure': False, 02:22:00 INFO - 'name': 'check_screen_resolution'},), 02:22:00 INFO - 'run_cmd_checks_enabled': True, 02:22:00 INFO - 'sps_profile': False, 02:22:00 INFO - 'sps_profile_interval': 0, 02:22:00 INFO - 'suite': 'dromaeojs-e10s', 02:22:00 INFO - 'system_bits': '32', 02:22:00 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 02:22:00 INFO - 'title': 't-yosemite-r7-0229', 02:22:00 INFO - 'tooltool_cache': '/builds/tooltool_cache', 02:22:00 INFO - 'use_talos_json': True, 02:22:00 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 02:22:00 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 02:22:00 INFO - 'work_dir': 'build'} 02:22:00 INFO - [mozharness: 2017-02-27 10:22:00.575207Z] Running clobber step. 02:22:00 INFO - Running pre-action listener: _resource_record_pre_action 02:22:00 INFO - Running main action method: clobber 02:22:00 INFO - rmtree: /builds/slave/test/build 02:22:00 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 02:22:03 INFO - Running post-action listener: _resource_record_post_action 02:22:03 INFO - [mozharness: 2017-02-27 10:22:03.908594Z] Finished clobber step (success) 02:22:03 INFO - [mozharness: 2017-02-27 10:22:03.908727Z] Running read-buildbot-config step. 02:22:03 INFO - Running pre-action listener: _resource_record_pre_action 02:22:03 INFO - Running main action method: read_buildbot_config 02:22:03 INFO - Using buildbot properties: 02:22:03 INFO - { 02:22:03 INFO - "project": "", 02:22:03 INFO - "product": "firefox", 02:22:03 INFO - "script_repo_revision": "production", 02:22:03 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs-e10s", 02:22:03 INFO - "repository": "", 02:22:03 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs-e10s", 02:22:03 INFO - "buildid": "20170227014408", 02:22:03 INFO - "pgo_build": "False", 02:22:03 INFO - "basedir": "/builds/slave/test", 02:22:03 INFO - "buildnumber": 0, 02:22:03 INFO - "slavename": "t-yosemite-r7-0229", 02:22:03 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 02:22:03 INFO - "platform": "yosemite_r7", 02:22:03 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 02:22:03 INFO - "branch": "mozilla-aurora", 02:22:03 INFO - "repo_path": "releases/mozilla-aurora", 02:22:03 INFO - "revision": "6ce37482387bc35b3724620b51c556eaa0312724", 02:22:03 INFO - "stage_platform": "macosx64", 02:22:03 INFO - "builduid": "1da6e370435e4e5ebce8cfa9db1b135b", 02:22:03 INFO - "slavebuilddir": "test" 02:22:03 INFO - } 02:22:03 INFO - Found installer url https://queue.taskcluster.net/v1/task/CKNJm2wjQpqyrOoQPNJ8gw/artifacts/public/build/firefox-53.0a2.en-US.mac.dmg. 02:22:03 INFO - Running post-action listener: _resource_record_post_action 02:22:03 INFO - [mozharness: 2017-02-27 10:22:03.912186Z] Finished read-buildbot-config step (success) 02:22:03 INFO - [mozharness: 2017-02-27 10:22:03.912320Z] Running download-and-extract step. 02:22:03 INFO - Running pre-action listener: _resource_record_pre_action 02:22:03 INFO - Running main action method: download_and_extract 02:22:03 INFO - mkdir: /builds/slave/test/build/tests 02:22:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:22:03 INFO - trying https://queue.taskcluster.net/v1/task/CKNJm2wjQpqyrOoQPNJ8gw/artifacts/public/build/firefox-53.0a2.en-US.mac.test_packages.json 02:22:03 INFO - Downloading https://queue.taskcluster.net/v1/task/CKNJm2wjQpqyrOoQPNJ8gw/artifacts/public/build/firefox-53.0a2.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-53.0a2.en-US.mac.test_packages.json 02:22:03 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/CKNJm2wjQpqyrOoQPNJ8gw/artifacts/public/build/firefox-53.0a2.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-53.0a2.en-US.mac.test_packages.json'}, attempt #1 02:22:05 INFO - Downloaded 1199 bytes. 02:22:05 INFO - Reading from file /builds/slave/test/build/firefox-53.0a2.en-US.mac.test_packages.json 02:22:05 INFO - Using the following test package requirements: 02:22:05 INFO - {u'common': [u'firefox-53.0a2.en-US.mac.common.tests.zip'], 02:22:05 INFO - u'cppunittest': [u'firefox-53.0a2.en-US.mac.common.tests.zip', 02:22:05 INFO - u'firefox-53.0a2.en-US.mac.cppunittest.tests.zip'], 02:22:05 INFO - u'gtest': [u'firefox-53.0a2.en-US.mac.common.tests.zip', 02:22:05 INFO - u'firefox-53.0a2.en-US.mac.gtest.tests.zip'], 02:22:05 INFO - u'jittest': [u'firefox-53.0a2.en-US.mac.common.tests.zip', 02:22:05 INFO - u'jsshell-mac.zip'], 02:22:05 INFO - u'mochitest': [u'firefox-53.0a2.en-US.mac.common.tests.zip', 02:22:05 INFO - u'firefox-53.0a2.en-US.mac.mochitest.tests.zip'], 02:22:05 INFO - u'mozbase': [u'firefox-53.0a2.en-US.mac.common.tests.zip'], 02:22:05 INFO - u'reftest': [u'firefox-53.0a2.en-US.mac.common.tests.zip', 02:22:05 INFO - u'firefox-53.0a2.en-US.mac.reftest.tests.zip'], 02:22:05 INFO - u'talos': [u'firefox-53.0a2.en-US.mac.common.tests.zip', 02:22:05 INFO - u'firefox-53.0a2.en-US.mac.talos.tests.zip'], 02:22:05 INFO - u'web-platform': [u'firefox-53.0a2.en-US.mac.common.tests.zip', 02:22:05 INFO - u'firefox-53.0a2.en-US.mac.web-platform.tests.zip'], 02:22:05 INFO - u'xpcshell': [u'firefox-53.0a2.en-US.mac.common.tests.zip', 02:22:05 INFO - u'firefox-53.0a2.en-US.mac.xpcshell.tests.zip']} 02:22:05 INFO - Downloading packages: [u'firefox-53.0a2.en-US.mac.common.tests.zip'] for test suite category: common 02:22:05 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/CKNJm2wjQpqyrOoQPNJ8gw/artifacts/public/build/firefox-53.0a2.en-US.mac.common.tests.zip 02:22:05 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/CKNJm2wjQpqyrOoQPNJ8gw/artifacts/public/build/firefox-53.0a2.en-US.mac.common.tests.zip'}, attempt #1 02:22:05 INFO - Fetch https://queue.taskcluster.net/v1/task/CKNJm2wjQpqyrOoQPNJ8gw/artifacts/public/build/firefox-53.0a2.en-US.mac.common.tests.zip into memory 02:22:06 INFO - Expected file size: 16357785 02:22:06 INFO - Obtained file size: 16357785 02:22:12 INFO - Downloading packages: [u'firefox-53.0a2.en-US.mac.common.tests.zip', u'firefox-53.0a2.en-US.mac.talos.tests.zip'] for test suite category: talos 02:22:12 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/CKNJm2wjQpqyrOoQPNJ8gw/artifacts/public/build/firefox-53.0a2.en-US.mac.common.tests.zip 02:22:12 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/CKNJm2wjQpqyrOoQPNJ8gw/artifacts/public/build/firefox-53.0a2.en-US.mac.common.tests.zip'}, attempt #1 02:22:12 INFO - Fetch https://queue.taskcluster.net/v1/task/CKNJm2wjQpqyrOoQPNJ8gw/artifacts/public/build/firefox-53.0a2.en-US.mac.common.tests.zip into memory 02:22:16 INFO - Expected file size: 16357785 02:22:16 INFO - Obtained file size: 16357785 02:22:23 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/CKNJm2wjQpqyrOoQPNJ8gw/artifacts/public/build/firefox-53.0a2.en-US.mac.talos.tests.zip 02:22:23 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/CKNJm2wjQpqyrOoQPNJ8gw/artifacts/public/build/firefox-53.0a2.en-US.mac.talos.tests.zip'}, attempt #1 02:22:23 INFO - Fetch https://queue.taskcluster.net/v1/task/CKNJm2wjQpqyrOoQPNJ8gw/artifacts/public/build/firefox-53.0a2.en-US.mac.talos.tests.zip into memory 02:22:25 INFO - Expected file size: 14403472 02:22:25 INFO - Obtained file size: 14403472 02:22: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')]} 02:22:25 INFO - trying https://queue.taskcluster.net/v1/task/CKNJm2wjQpqyrOoQPNJ8gw/artifacts/public/build/firefox-53.0a2.en-US.mac.dmg 02:22:25 INFO - Downloading https://queue.taskcluster.net/v1/task/CKNJm2wjQpqyrOoQPNJ8gw/artifacts/public/build/firefox-53.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-53.0a2.en-US.mac.dmg 02:22:25 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/CKNJm2wjQpqyrOoQPNJ8gw/artifacts/public/build/firefox-53.0a2.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-53.0a2.en-US.mac.dmg'}, attempt #1 02:22:55 INFO - Downloaded 73928661 bytes. 02:22:55 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/CKNJm2wjQpqyrOoQPNJ8gw/artifacts/public/build/firefox-53.0a2.en-US.mac.dmg 02:22:55 INFO - mkdir: /builds/slave/test/properties 02:22:55 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 02:22:55 INFO - Writing to file /builds/slave/test/properties/build_url 02:22:55 INFO - Contents: 02:22:55 INFO - build_url:https://queue.taskcluster.net/v1/task/CKNJm2wjQpqyrOoQPNJ8gw/artifacts/public/build/firefox-53.0a2.en-US.mac.dmg 02:22:58 INFO - Running post-action listener: _resource_record_post_action 02:22:58 INFO - Running post-action listener: set_extra_try_arguments 02:22:58 INFO - [mozharness: 2017-02-27 10:22:58.250979Z] Finished download-and-extract step (success) 02:22:58 INFO - [mozharness: 2017-02-27 10:22:58.251082Z] Running populate-webroot step. 02:22:58 INFO - Running pre-action listener: _resource_record_pre_action 02:22:58 INFO - Running main action method: populate_webroot 02:22:58 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 02:22:58 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 02:22:58 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 02:22:58 INFO - '--tppagecycles', 02:22:58 INFO - '7'], 02:22:58 INFO - 'tests': ['tsvgm']}}, 02:22:58 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 02:22:58 INFO - 'tests': ['tresize', 'tcanvasmark']}, 02:22:58 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 02:22:58 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 02:22:58 INFO - 'tests': ['dromaeo_css', 'kraken']}, 02:22:58 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 02:22:58 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 02:22:58 INFO - 'talos_options': ['--disable-e10s'], 02:22:58 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 02:22:58 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 02:22:58 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 02:22:58 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 02:22:58 INFO - 'talos_options': ['--disable-e10s'], 02:22:58 INFO - 'tests': ['damp', 'tps']}, 02:22:58 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 02:22:58 INFO - 'tests': ['damp', 'tps']}, 02:22:58 INFO - 'g3': {'talos_options': ['--disable-e10s'], 02:22:58 INFO - 'tests': ['dromaeo_dom']}, 02:22:58 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 02:22:58 INFO - 'g4': {'talos_options': ['--disable-e10s'], 02:22:58 INFO - 'tests': ['basic_compositor_video']}, 02:22:58 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 02:22:58 INFO - 'other': {'talos_options': ['--disable-e10s'], 02:22:58 INFO - 'tests': ['a11yr', 02:22:58 INFO - 'ts_paint', 02:22:58 INFO - 'tpaint', 02:22:58 INFO - 'sessionrestore', 02:22:58 INFO - 'sessionrestore_no_auto_restore', 02:22:58 INFO - 'tabpaint']}, 02:22:58 INFO - 'other-e10s': {'tests': ['a11yr', 02:22:58 INFO - 'ts_paint', 02:22:58 INFO - 'tpaint', 02:22:58 INFO - 'sessionrestore', 02:22:58 INFO - 'sessionrestore_no_auto_restore', 02:22:58 INFO - 'tabpaint']}, 02:22:58 INFO - 'other-e10s_l64': {'tests': ['a11yr', 02:22:58 INFO - 'ts_paint', 02:22:58 INFO - 'tpaint', 02:22:58 INFO - 'sessionrestore', 02:22:58 INFO - 'sessionrestore_no_auto_restore', 02:22:58 INFO - 'tabpaint']}, 02:22:58 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 02:22:58 INFO - 'ts_paint', 02:22:58 INFO - 'tpaint', 02:22:58 INFO - 'sessionrestore', 02:22:58 INFO - 'sessionrestore_no_auto_restore', 02:22:58 INFO - 'tabpaint']}, 02:22:58 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 02:22:58 INFO - 'tests': ['a11yr', 02:22:58 INFO - 'ts_paint', 02:22:58 INFO - 'tpaint', 02:22:58 INFO - 'sessionrestore', 02:22:58 INFO - 'sessionrestore_no_auto_restore', 02:22:58 INFO - 'tabpaint']}, 02:22:58 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 02:22:58 INFO - 'tests': ['a11yr', 02:22:58 INFO - 'ts_paint', 02:22:58 INFO - 'tpaint', 02:22:58 INFO - 'sessionrestore', 02:22:58 INFO - 'sessionrestore_no_auto_restore', 02:22:58 INFO - 'tabpaint']}, 02:22:58 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 02:22:58 INFO - 'tests': ['tsvgx', 02:22:58 INFO - 'tsvgr_opacity', 02:22:58 INFO - 'tart', 02:22:58 INFO - 'tscrollx', 02:22:58 INFO - 'cart', 02:22:58 INFO - 'tsvg_static']}, 02:22:58 INFO - 'svgr-e10s': {'tests': ['tsvgx', 02:22:58 INFO - 'tsvgr_opacity', 02:22:58 INFO - 'tart', 02:22:58 INFO - 'tscrollx', 02:22:58 INFO - 'cart', 02:22:58 INFO - 'tsvg_static']}, 02:22:58 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 02:22:58 INFO - 'talos_options': ['--disable-e10s'], 02:22:58 INFO - 'tests': ['tp5o']}, 02:22:58 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 02:22:58 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 02:22:58 INFO - 'talos_options': ['--disable-e10s', 02:22:58 INFO - '--xperf_path', 02:22:58 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 02:22:58 INFO - 'tests': ['tp5n']}, 02:22:58 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 02:22:58 INFO - 'talos_options': ['--xperf_path', 02:22:58 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 02:22:58 INFO - 'tests': ['tp5n']}}, 02:22:58 INFO - 'talos.zip': {'path': '', 02:22:58 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 02:22:58 INFO - Running post-action listener: _resource_record_post_action 02:22:58 INFO - [mozharness: 2017-02-27 10:22:58.269729Z] Finished populate-webroot step (success) 02:22:58 INFO - [mozharness: 2017-02-27 10:22:58.269861Z] Running create-virtualenv step. 02:22:58 INFO - Running pre-action listener: _resource_record_pre_action 02:22:58 INFO - Running main action method: create_virtualenv 02:22:58 INFO - Creating virtualenv /builds/slave/test/build/venv 02:22:58 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 02:22:58 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 02:22:58 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 02:22:58 INFO - Using real prefix '/tools/python27' 02:22:58 INFO - New python executable in /builds/slave/test/build/venv/bin/python 02:22:59 INFO - Installing distribute.............................................................................................................................................................................................done. 02:23:01 INFO - Installing pip.................done. 02:23:01 INFO - Return code: 0 02:23:01 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 02:23:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 02:23:02 INFO - Reading from file tmpfile_stdout 02:23:02 INFO - Output received: 02:23:02 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) 02:23:02 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 02:23: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')]} 02:23:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:23:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:23: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')]} 02:23:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:23:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:23:03 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 0x101a42b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101a45768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbec2590b70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101820d30>, '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 0x101747db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101815be8>, '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.g7Lu7AvFsV/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.yuHrJ5xx2q/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/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 02:23:03 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 02:23:03 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 02:23:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.g7Lu7AvFsV/Render', 02:23:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:23:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:23:03 INFO - 'HOME': '/Users/cltbld', 02:23:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:23:03 INFO - 'LOGNAME': 'cltbld', 02:23:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:23:03 INFO - 'MOZ_NO_REMOTE': '1', 02:23:03 INFO - 'NO_EM_RESTART': '1', 02:23:03 INFO - 'PAGER': '/bin/cat', 02:23:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:23:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:23:03 INFO - 'PWD': '/builds/slave/test', 02:23:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:23:03 INFO - 'SHELL': '/bin/bash', 02:23:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.yuHrJ5xx2q/Listeners', 02:23:03 INFO - 'TMPDIR': '/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/T/', 02:23:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:23:03 INFO - 'USER': 'cltbld', 02:23:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:23:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:23:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:23:03 INFO - 'XPC_FLAGS': '0x0', 02:23:03 INFO - 'XPC_SERVICE_NAME': '0', 02:23:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:23:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:23:03 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 02:23:03 INFO - Cleaning up... 02:23:03 INFO - Return code: 0 02:23:03 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 02:23:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:23:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:23:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:23:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:23:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:23:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:23:03 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 0x101a42b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101a45768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbec2590b70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101820d30>, '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 0x101747db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101815be8>, '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.g7Lu7AvFsV/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.yuHrJ5xx2q/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/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 02:23:03 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 02:23:03 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 02:23:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.g7Lu7AvFsV/Render', 02:23:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:23:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:23:03 INFO - 'HOME': '/Users/cltbld', 02:23:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:23:03 INFO - 'LOGNAME': 'cltbld', 02:23:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:23:03 INFO - 'MOZ_NO_REMOTE': '1', 02:23:03 INFO - 'NO_EM_RESTART': '1', 02:23:03 INFO - 'PAGER': '/bin/cat', 02:23:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:23:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:23:03 INFO - 'PWD': '/builds/slave/test', 02:23:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:23:03 INFO - 'SHELL': '/bin/bash', 02:23:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.yuHrJ5xx2q/Listeners', 02:23:03 INFO - 'TMPDIR': '/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/T/', 02:23:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:23:03 INFO - 'USER': 'cltbld', 02:23:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:23:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:23:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:23:03 INFO - 'XPC_FLAGS': '0x0', 02:23:03 INFO - 'XPC_SERVICE_NAME': '0', 02:23:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:23:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:23:03 INFO - Downloading/unpacking psutil>=3.1.1 02:23:03 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 02:23:03 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 02:23:03 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 02:23:03 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 02:23:03 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 02:23:03 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 02:23:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 02:23:11 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 02:23:11 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 02:23:12 INFO - Installing collected packages: psutil 02:23:12 INFO - Running setup.py install for psutil 02:23:12 INFO - building 'psutil._psutil_osx' extension 02:23:12 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 02:23:12 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 02:23:12 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 02:23:12 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 02:23:12 INFO - building 'psutil._psutil_posix' extension 02:23:12 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 02:23:12 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 02:23:12 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 02:23:12 INFO - ^ 02:23:12 INFO - 1 warning generated. 02:23:12 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 02:23:12 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 02:23:12 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 02:23:12 INFO - Successfully installed psutil 02:23:12 INFO - Cleaning up... 02:23:12 INFO - Return code: 0 02:23:12 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 02:23:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:23:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:23:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:23:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:23:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:23:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:23:12 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 0x101a42b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101a45768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbec2590b70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101820d30>, '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 0x101747db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101815be8>, '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.g7Lu7AvFsV/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.yuHrJ5xx2q/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/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 02:23:12 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 02:23:12 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 02:23:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.g7Lu7AvFsV/Render', 02:23:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:23:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:23:12 INFO - 'HOME': '/Users/cltbld', 02:23:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:23:12 INFO - 'LOGNAME': 'cltbld', 02:23:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:23:12 INFO - 'MOZ_NO_REMOTE': '1', 02:23:12 INFO - 'NO_EM_RESTART': '1', 02:23:12 INFO - 'PAGER': '/bin/cat', 02:23:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:23:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:23:12 INFO - 'PWD': '/builds/slave/test', 02:23:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:23:12 INFO - 'SHELL': '/bin/bash', 02:23:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.yuHrJ5xx2q/Listeners', 02:23:12 INFO - 'TMPDIR': '/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/T/', 02:23:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:23:12 INFO - 'USER': 'cltbld', 02:23:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:23:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:23:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:23:12 INFO - 'XPC_FLAGS': '0x0', 02:23:12 INFO - 'XPC_SERVICE_NAME': '0', 02:23:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:23:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:23:13 INFO - Downloading/unpacking mozsystemmonitor==0.3 02:23:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:23:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:23:13 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:23:13 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:23:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:23:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:23:16 INFO - Downloading mozsystemmonitor-0.3.tar.gz 02:23:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 02:23:17 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 02:23:17 INFO - Installing collected packages: mozsystemmonitor 02:23:17 INFO - Running setup.py install for mozsystemmonitor 02:23:17 INFO - Successfully installed mozsystemmonitor 02:23:17 INFO - Cleaning up... 02:23:17 INFO - Return code: 0 02:23:17 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 02:23:17 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')]} 02:23:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:23:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:23:17 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')]} 02:23:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:23:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:23:17 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 0x101a42b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101a45768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbec2590b70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101820d30>, '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 0x101747db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101815be8>, '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.g7Lu7AvFsV/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.yuHrJ5xx2q/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/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 02:23:17 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 02:23:17 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 02:23:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.g7Lu7AvFsV/Render', 02:23:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:23:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:23:17 INFO - 'HOME': '/Users/cltbld', 02:23:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:23:17 INFO - 'LOGNAME': 'cltbld', 02:23:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:23:17 INFO - 'MOZ_NO_REMOTE': '1', 02:23:17 INFO - 'NO_EM_RESTART': '1', 02:23:17 INFO - 'PAGER': '/bin/cat', 02:23:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:23:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:23:17 INFO - 'PWD': '/builds/slave/test', 02:23:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:23:17 INFO - 'SHELL': '/bin/bash', 02:23:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.yuHrJ5xx2q/Listeners', 02:23:17 INFO - 'TMPDIR': '/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/T/', 02:23:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:23:17 INFO - 'USER': 'cltbld', 02:23:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:23:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:23:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:23:17 INFO - 'XPC_FLAGS': '0x0', 02:23:17 INFO - 'XPC_SERVICE_NAME': '0', 02:23:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:23:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:23:17 INFO - Downloading/unpacking jsonschema==2.5.1 02:23:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:23:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:23:17 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:23:17 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:23:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:23:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:23:20 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 02:23:20 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 02:23:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:23:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:23:20 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:23:20 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:23:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:23:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:23:21 INFO - Downloading functools32-3.2.3-2.tar.gz 02:23:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 02:23:21 INFO - warning: no files found matching '*.txt' 02:23:21 INFO - no previously-included directories found matching 'build' 02:23:21 INFO - no previously-included directories found matching 'dist' 02:23:21 INFO - no previously-included directories found matching '.git*' 02:23:21 INFO - Installing collected packages: jsonschema, functools32 02:23:21 INFO - Running setup.py install for functools32 02:23:21 INFO - warning: no files found matching '*.txt' 02:23:21 INFO - no previously-included directories found matching 'build' 02:23:21 INFO - no previously-included directories found matching 'dist' 02:23:21 INFO - no previously-included directories found matching '.git*' 02:23:21 INFO - Successfully installed jsonschema functools32 02:23:21 INFO - Cleaning up... 02:23:21 INFO - Return code: 0 02:23:21 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 02:23:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:23:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:23:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:23:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:23:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:23:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:23:21 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 0x101a42b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101a45768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbec2590b70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101820d30>, '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 0x101747db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101815be8>, '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.g7Lu7AvFsV/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.yuHrJ5xx2q/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/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 02:23:21 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 02:23:21 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 02:23:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.g7Lu7AvFsV/Render', 02:23:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:23:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:23:21 INFO - 'HOME': '/Users/cltbld', 02:23:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:23:21 INFO - 'LOGNAME': 'cltbld', 02:23:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:23:21 INFO - 'MOZ_NO_REMOTE': '1', 02:23:21 INFO - 'NO_EM_RESTART': '1', 02:23:21 INFO - 'PAGER': '/bin/cat', 02:23:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:23:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:23:21 INFO - 'PWD': '/builds/slave/test', 02:23:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:23:21 INFO - 'SHELL': '/bin/bash', 02:23:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.yuHrJ5xx2q/Listeners', 02:23:21 INFO - 'TMPDIR': '/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/T/', 02:23:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:23:21 INFO - 'USER': 'cltbld', 02:23:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:23:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:23:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:23:21 INFO - 'XPC_FLAGS': '0x0', 02:23:21 INFO - 'XPC_SERVICE_NAME': '0', 02:23:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:23:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:23:21 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 02:23:21 INFO - Cleaning up... 02:23:21 INFO - Return code: 0 02:23:21 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 02:23:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:23:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:23:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:23:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:23:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:23:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:23:21 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 0x101a42b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101a45768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbec2590b70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101820d30>, '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 0x101747db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101815be8>, '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.g7Lu7AvFsV/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.yuHrJ5xx2q/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/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 02:23:21 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 02:23:21 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 02:23:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.g7Lu7AvFsV/Render', 02:23:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:23:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:23:21 INFO - 'HOME': '/Users/cltbld', 02:23:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:23:21 INFO - 'LOGNAME': 'cltbld', 02:23:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:23:21 INFO - 'MOZ_NO_REMOTE': '1', 02:23:21 INFO - 'NO_EM_RESTART': '1', 02:23:21 INFO - 'PAGER': '/bin/cat', 02:23:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:23:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:23:21 INFO - 'PWD': '/builds/slave/test', 02:23:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:23:21 INFO - 'SHELL': '/bin/bash', 02:23:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.yuHrJ5xx2q/Listeners', 02:23:21 INFO - 'TMPDIR': '/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/T/', 02:23:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:23:21 INFO - 'USER': 'cltbld', 02:23:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:23:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:23:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:23:21 INFO - 'XPC_FLAGS': '0x0', 02:23:21 INFO - 'XPC_SERVICE_NAME': '0', 02:23:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:23:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:23:22 INFO - Downloading/unpacking blobuploader==1.2.4 02:23:22 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 02:23:22 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 02:23:22 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 02:23:22 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 02:23:22 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 02:23:22 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 02:23:27 INFO - Downloading blobuploader-1.2.4.tar.gz 02:23:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 02:23:28 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 02:23:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:23:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:23:28 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:23:28 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:23:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:23:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:23:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 02:23:28 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 02:23:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:23:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:23:28 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:23:28 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:23:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:23:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:23:29 INFO - Downloading docopt-0.6.1.tar.gz 02:23:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 02:23:29 INFO - Installing collected packages: blobuploader, requests, docopt 02:23:29 INFO - Running setup.py install for blobuploader 02:23:29 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 02:23:29 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 02:23:29 INFO - Running setup.py install for requests 02:23:29 INFO - Running setup.py install for docopt 02:23:30 INFO - Successfully installed blobuploader requests docopt 02:23:30 INFO - Cleaning up... 02:23:30 INFO - Return code: 0 02:23:30 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:23:30 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')]} 02:23:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:23:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:23:30 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')]} 02:23:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:23:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:23:30 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 0x101a42b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101a45768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbec2590b70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101820d30>, '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 0x101747db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101815be8>, '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.g7Lu7AvFsV/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.yuHrJ5xx2q/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/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 02:23:30 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 02:23:30 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 02:23:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.g7Lu7AvFsV/Render', 02:23:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:23:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:23:30 INFO - 'HOME': '/Users/cltbld', 02:23:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:23:30 INFO - 'LOGNAME': 'cltbld', 02:23:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:23:30 INFO - 'MOZ_NO_REMOTE': '1', 02:23:30 INFO - 'NO_EM_RESTART': '1', 02:23:30 INFO - 'PAGER': '/bin/cat', 02:23:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:23:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:23:30 INFO - 'PWD': '/builds/slave/test', 02:23:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:23:30 INFO - 'SHELL': '/bin/bash', 02:23:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.yuHrJ5xx2q/Listeners', 02:23:30 INFO - 'TMPDIR': '/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/T/', 02:23:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:23:30 INFO - 'USER': 'cltbld', 02:23:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:23:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:23:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:23:30 INFO - 'XPC_FLAGS': '0x0', 02:23:30 INFO - 'XPC_SERVICE_NAME': '0', 02:23:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:23:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:23:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 02:23:30 INFO - Running setup.py (path:/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/T/pip-Bhhgtu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 02:23:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 02:23:30 INFO - Running setup.py (path:/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/T/pip-G1EOCQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 02:23:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 02:23:30 INFO - Running setup.py (path:/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/T/pip-y0mDmj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 02:23:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 02:23:30 INFO - Running setup.py (path:/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/T/pip-NBnTXF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 02:23:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 02:23:30 INFO - Running setup.py (path:/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/T/pip-MGYxwB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 02:23:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 02:23:30 INFO - Running setup.py (path:/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/T/pip-XkNEFI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 02:23:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 02:23:31 INFO - Running setup.py (path:/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/T/pip-2Guln5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 02:23:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 02:23:31 INFO - Running setup.py (path:/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/T/pip-y5fGij-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 02:23:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 02:23:31 INFO - Running setup.py (path:/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/T/pip-8nYa5y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 02:23:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 02:23:31 INFO - Running setup.py (path:/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/T/pip-vdZMf0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 02:23:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 02:23:31 INFO - Running setup.py (path:/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/T/pip-rTl8yN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 02:23:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 02:23:31 INFO - Running setup.py (path:/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/T/pip-sly3UR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 02:23:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 02:23:31 INFO - Running setup.py (path:/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/T/pip-ZcI2Mj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 02:23:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 02:23:31 INFO - Running setup.py (path:/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/T/pip-qoX916-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 02:23:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 02:23:31 INFO - Running setup.py (path:/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/T/pip-BBwrSb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 02:23:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 02:23:32 INFO - Running setup.py (path:/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/T/pip-C0s3XQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 02:23:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 02:23:32 INFO - Running setup.py (path:/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/T/pip-E36sgh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 02:23:32 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 02:23:32 INFO - Running setup.py install for manifestparser 02:23:32 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 02:23:32 INFO - Running setup.py install for mozcrash 02:23:32 INFO - Running setup.py install for mozdebug 02:23:32 INFO - Running setup.py install for mozdevice 02:23:32 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 02:23:32 INFO - Installing dm script to /builds/slave/test/build/venv/bin 02:23:32 INFO - Running setup.py install for mozfile 02:23:32 INFO - Running setup.py install for mozhttpd 02:23:33 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 02:23:33 INFO - Running setup.py install for mozinfo 02:23:33 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 02:23:33 INFO - Running setup.py install for mozInstall 02:23:33 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 02:23:33 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 02:23:33 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 02:23:33 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 02:23:33 INFO - Running setup.py install for mozleak 02:23:33 INFO - Running setup.py install for mozlog 02:23:33 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 02:23:33 INFO - Running setup.py install for moznetwork 02:23:33 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 02:23:33 INFO - Running setup.py install for mozprocess 02:23:34 INFO - Running setup.py install for mozprofile 02:23:34 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 02:23:34 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 02:23:34 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 02:23:34 INFO - Running setup.py install for mozrunner 02:23:34 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 02:23:34 INFO - Running setup.py install for mozscreenshot 02:23:34 INFO - Running setup.py install for moztest 02:23:34 INFO - Running setup.py install for mozversion 02:23:34 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 02:23:34 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 02:23:34 INFO - Cleaning up... 02:23:34 INFO - Return code: 0 02:23:34 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:23: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')]} 02:23:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:23:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:23: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')]} 02:23:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:23:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:23:34 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 0x101a42b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101a45768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbec2590b70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101820d30>, '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 0x101747db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101815be8>, '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.g7Lu7AvFsV/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.yuHrJ5xx2q/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/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 02:23:34 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 02:23:34 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 02:23:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.g7Lu7AvFsV/Render', 02:23:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:23:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:23:34 INFO - 'HOME': '/Users/cltbld', 02:23:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:23:34 INFO - 'LOGNAME': 'cltbld', 02:23:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:23:34 INFO - 'MOZ_NO_REMOTE': '1', 02:23:34 INFO - 'NO_EM_RESTART': '1', 02:23:34 INFO - 'PAGER': '/bin/cat', 02:23:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:23:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:23:34 INFO - 'PWD': '/builds/slave/test', 02:23:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:23:34 INFO - 'SHELL': '/bin/bash', 02:23:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.yuHrJ5xx2q/Listeners', 02:23:34 INFO - 'TMPDIR': '/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/T/', 02:23:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:23:34 INFO - 'USER': 'cltbld', 02:23:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:23:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:23:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:23:34 INFO - 'XPC_FLAGS': '0x0', 02:23:34 INFO - 'XPC_SERVICE_NAME': '0', 02:23:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:23:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:23:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 02:23:35 INFO - Running setup.py (path:/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/T/pip-uV67Vs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 02:23:35 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)) 02:23:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 02:23:35 INFO - Running setup.py (path:/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/T/pip-_fElaN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 02:23:35 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)) 02:23:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 02:23:35 INFO - Running setup.py (path:/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/T/pip-YRPV44-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 02:23:35 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)) 02:23:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 02:23:35 INFO - Running setup.py (path:/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/T/pip-AXDxh2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 02:23:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 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)) 02:23:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 02:23:35 INFO - Running setup.py (path:/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/T/pip-lT50M8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 02:23:35 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)) 02:23:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 02:23:35 INFO - Running setup.py (path:/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/T/pip-qrFA8Q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 02:23:35 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)) 02:23:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 02:23:35 INFO - Running setup.py (path:/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/T/pip-Ze8_Pk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 02:23:35 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)) 02:23:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 02:23:35 INFO - Running setup.py (path:/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/T/pip-Nd0aRy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 02:23:35 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)) 02:23:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 02:23:35 INFO - Running setup.py (path:/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/T/pip-No7FKP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 02:23:36 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)) 02:23:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 02:23:36 INFO - Running setup.py (path:/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/T/pip-nxhSnM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 02:23:36 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)) 02:23:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 02:23:36 INFO - Running setup.py (path:/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/T/pip-5VKkOT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 02:23:36 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)) 02:23:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 02:23:36 INFO - Running setup.py (path:/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/T/pip-ACI6AG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 02:23:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.24 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)) 02:23:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 02:23:36 INFO - Running setup.py (path:/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/T/pip-WU2WU5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 02:23:36 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)) 02:23:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 02:23:36 INFO - Running setup.py (path:/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/T/pip-gZhTfu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 02:23:36 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)) 02:23:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 02:23:36 INFO - Running setup.py (path:/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/T/pip-ABgcPM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 02:23:36 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)) 02:23:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 02:23:36 INFO - Running setup.py (path:/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/T/pip-HltSvC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 02:23:36 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)) 02:23:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 02:23:36 INFO - Running setup.py (path:/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/T/pip-FvKgho-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 02:23:36 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)) 02:23:36 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)) 02:23:36 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)) 02:23:36 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)) 02:23:36 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.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 02:23:36 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.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 02:23:36 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 02:23:36 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 02:23:36 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 02:23:36 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 02:23:36 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 02:23:36 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 02:23:36 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 02:23:40 INFO - Downloading blessings-1.6.tar.gz 02:23:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 02:23:40 INFO - Installing collected packages: blessings 02:23:40 INFO - Running setup.py install for blessings 02:23:40 INFO - Successfully installed blessings 02:23:40 INFO - Cleaning up... 02:23:40 INFO - Return code: 0 02:23:40 INFO - Done creating virtualenv /builds/slave/test/build/venv. 02:23:40 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 02:23:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 02:23:41 INFO - Reading from file tmpfile_stdout 02:23:41 INFO - Current package versions: 02:23:41 INFO - blessings == 1.6 02:23:41 INFO - blobuploader == 1.2.4 02:23:41 INFO - docopt == 0.6.1 02:23:41 INFO - functools32 == 3.2.3-2 02:23:41 INFO - jsonschema == 2.5.1 02:23:41 INFO - manifestparser == 1.1 02:23:41 INFO - mozInstall == 1.12 02:23:41 INFO - mozcrash == 1.0 02:23:41 INFO - mozdebug == 0.1 02:23:41 INFO - mozdevice == 0.49 02:23:41 INFO - mozfile == 1.2 02:23:41 INFO - mozhttpd == 0.7 02:23:41 INFO - mozinfo == 0.9 02:23:41 INFO - mozleak == 0.1 02:23:41 INFO - mozlog == 3.4 02:23:41 INFO - moznetwork == 0.27 02:23:41 INFO - mozprocess == 0.24 02:23:41 INFO - mozprofile == 0.28 02:23:41 INFO - mozrunner == 6.13 02:23:41 INFO - mozscreenshot == 0.1 02:23:41 INFO - mozsystemmonitor == 0.3 02:23:41 INFO - moztest == 0.8 02:23:41 INFO - mozversion == 1.4 02:23:41 INFO - psutil == 3.1.1 02:23:41 INFO - requests == 1.2.3 02:23:41 INFO - wsgiref == 0.1.2 02:23:41 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:23:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:23:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:23:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:23:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:23:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:23:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:23:41 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 0x101a42b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101a45768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbec2590b70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101820d30>, '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 0x101747db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101815be8>, '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.g7Lu7AvFsV/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.yuHrJ5xx2q/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/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 02:23:41 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 02:23:41 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 02:23:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.g7Lu7AvFsV/Render', 02:23:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:23:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:23:41 INFO - 'HOME': '/Users/cltbld', 02:23:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:23:41 INFO - 'LOGNAME': 'cltbld', 02:23:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:23:41 INFO - 'MOZ_NO_REMOTE': '1', 02:23:41 INFO - 'NO_EM_RESTART': '1', 02:23:41 INFO - 'PAGER': '/bin/cat', 02:23:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:23:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:23:41 INFO - 'PWD': '/builds/slave/test', 02:23:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:23:41 INFO - 'SHELL': '/bin/bash', 02:23:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.yuHrJ5xx2q/Listeners', 02:23:41 INFO - 'TMPDIR': '/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/T/', 02:23:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:23:41 INFO - 'USER': 'cltbld', 02:23:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:23:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:23:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:23:41 INFO - 'XPC_FLAGS': '0x0', 02:23:41 INFO - 'XPC_SERVICE_NAME': '0', 02:23:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:23:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:23:41 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)) 02:23:41 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)) 02:23:41 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)) 02:23:41 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)) 02:23:41 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)) 02:23:41 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)) 02:23:41 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)) 02:23:41 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)) 02:23:41 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)) 02:23:41 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)) 02:23:41 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)) 02:23:41 INFO - Cleaning up... 02:23:41 INFO - Return code: 0 02:23:41 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 02:23:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:23:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:23:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:23:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:23:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:23:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:23:41 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 0x101a42b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101a45768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbec2590b70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101820d30>, '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 0x101747db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101815be8>, '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.g7Lu7AvFsV/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.yuHrJ5xx2q/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/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 02:23:41 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 02:23:41 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 02:23:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.g7Lu7AvFsV/Render', 02:23:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:23:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:23:41 INFO - 'HOME': '/Users/cltbld', 02:23:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:23:41 INFO - 'LOGNAME': 'cltbld', 02:23:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:23:41 INFO - 'MOZ_NO_REMOTE': '1', 02:23:41 INFO - 'NO_EM_RESTART': '1', 02:23:41 INFO - 'PAGER': '/bin/cat', 02:23:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:23:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:23:41 INFO - 'PWD': '/builds/slave/test', 02:23:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:23:41 INFO - 'SHELL': '/bin/bash', 02:23:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.yuHrJ5xx2q/Listeners', 02:23:41 INFO - 'TMPDIR': '/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/T/', 02:23:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:23:41 INFO - 'USER': 'cltbld', 02:23:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:23:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:23:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:23:41 INFO - 'XPC_FLAGS': '0x0', 02:23:41 INFO - 'XPC_SERVICE_NAME': '0', 02:23:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:23:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:23:41 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 02:23:41 INFO - Cleaning up... 02:23:41 INFO - Return code: 0 02:23:41 INFO - Running post-action listener: _resource_record_post_action 02:23:41 INFO - Running post-action listener: _start_resource_monitoring 02:23:41 INFO - Starting resource monitoring. 02:23:41 INFO - [mozharness: 2017-02-27 10:23:41.733915Z] Finished create-virtualenv step (success) 02:23:41 INFO - [mozharness: 2017-02-27 10:23:41.734869Z] Running install step. 02:23:41 INFO - Running pre-action listener: _resource_record_pre_action 02:23:41 INFO - Running main action method: install 02:23:41 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 02:23:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 02:23:41 INFO - Reading from file tmpfile_stdout 02:23:41 INFO - Detecting whether we're running mozinstall >=1.0... 02:23:41 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 02:23:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 02:23:42 INFO - Reading from file tmpfile_stdout 02:23:42 INFO - Output received: 02:23:42 INFO - Usage: mozinstall [options] installer 02:23:42 INFO - Options: 02:23:42 INFO - -h, --help show this help message and exit 02:23:42 INFO - -d DEST, --destination=DEST 02:23:42 INFO - Directory to install application into. [default: 02:23:42 INFO - "/builds/slave/test"] 02:23:42 INFO - --app=APP Application being installed. [default: firefox] 02:23:42 INFO - mkdir: /builds/slave/test/build/application 02:23:42 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-53.0a2.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 02:23:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-53.0a2.en-US.mac.dmg --destination /builds/slave/test/build/application 02:24:01 INFO - Reading from file tmpfile_stdout 02:24:01 INFO - Output received: 02:24:01 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 02:24:01 INFO - Running post-action listener: _resource_record_post_action 02:24:01 INFO - [mozharness: 2017-02-27 10:24:01.949200Z] Finished install step (success) 02:24:01 INFO - [mozharness: 2017-02-27 10:24:01.949329Z] Running run-tests step. 02:24:01 INFO - Running pre-action listener: _resource_record_pre_action 02:24:01 INFO - Running main action method: run_tests 02:24:01 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 02:24:01 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 02:24:01 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 02:24:01 INFO - 2017-02-27 02:24:01.963 screenresolution[1977:17656] starting screenresolution argv=screenresolution get 02:24:01 INFO - 2017-02-27 02:24:01.966 screenresolution[1977:17656] Display 0: 1600x1200x32@60 02:24:01 INFO - 2017-02-27 02:24:01.972 screenresolution[1978:17659] starting screenresolution argv=screenresolution list 02:24:01 INFO - Available Modes on Display 0 02:24:01 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 02:24:01 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 02:24:01 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 02:24:01 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 02:24:02 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 02:24:02 INFO - Intel Iris: 02:24:02 INFO - Chipset Model: Intel Iris 02:24:02 INFO - Type: GPU 02:24:02 INFO - Bus: Built-In 02:24:02 INFO - VRAM (Dynamic, Max): 1536 MB 02:24:02 INFO - Vendor: Intel (0x8086) 02:24:02 INFO - Device ID: 0x0a2e 02:24:02 INFO - Revision ID: 0x0009 02:24:02 INFO - Displays: 02:24:02 INFO - Display: 02:24:02 INFO - Resolution: 1600 x 1200 @ 60 Hz 02:24:02 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 02:24:02 INFO - Display Serial Number: 1600x1200 60 02:24:02 INFO - Main Display: Yes 02:24:02 INFO - Mirror: Off 02:24:02 INFO - Online: Yes 02:24:02 INFO - Rotation: Supported 02:24:02 INFO - Return code: 0 02:24:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 02:24:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 02:24:02 INFO - Python 2.7.3 02:24:02 INFO - Return code: 0 02:24:02 INFO - grabbing minidump binary from tooltool 02:24: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')]} 02:24:02 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 0x101820d30>, '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 0x101747db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101815be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 02:24:02 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 02:24:02 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 02:24:02 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 02:24:02 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 02:24:02 INFO - Return code: 0 02:24:02 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 02:24:02 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 02:24:02 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 02:24:02 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 02:24:02 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 02:24:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/CKNJm2wjQpqyrOoQPNJ8gw/artifacts/public/build/firefox-53.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0229', '--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 02:24:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/CKNJm2wjQpqyrOoQPNJ8gw/artifacts/public/build/firefox-53.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0229 --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 02:24:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.g7Lu7AvFsV/Render', 02:24:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:24:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:24:02 INFO - 'HOME': '/Users/cltbld', 02:24:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:24:02 INFO - 'LOGNAME': 'cltbld', 02:24:02 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 02:24:02 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 02:24:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:24:02 INFO - 'MOZ_NO_REMOTE': '1', 02:24:02 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 02:24:02 INFO - 'NO_EM_RESTART': '1', 02:24:02 INFO - 'PAGER': '/bin/cat', 02:24:02 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:24:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:24:02 INFO - 'PWD': '/builds/slave/test', 02:24:02 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 02:24:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:24:02 INFO - 'SHELL': '/bin/bash', 02:24:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.yuHrJ5xx2q/Listeners', 02:24:02 INFO - 'TMPDIR': '/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/T/', 02:24:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:24:02 INFO - 'USER': 'cltbld', 02:24:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:24:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:24:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:24:02 INFO - 'XPC_FLAGS': '0x0', 02:24:02 INFO - 'XPC_SERVICE_NAME': '0', 02:24:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:24:02 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/CKNJm2wjQpqyrOoQPNJ8gw/artifacts/public/build/firefox-53.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0229', '--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 02:24:02 INFO - mozversion application_buildid: 20170227014408 02:24:02 INFO - mozversion application_changeset: 6ce37482387bc35b3724620b51c556eaa0312724 02:24:02 INFO - mozversion application_display_name: FirefoxDeveloperEdition 02:24:02 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 02:24:02 INFO - mozversion application_name: Firefox 02:24:02 INFO - mozversion application_remotingname: firefox-dev 02:24:02 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 02:24:02 INFO - mozversion application_vendor: Mozilla 02:24:02 INFO - mozversion application_version: 53.0a2 02:24:02 INFO - mozversion platform_buildid: 20170227014408 02:24:02 INFO - mozversion platform_changeset: 6ce37482387bc35b3724620b51c556eaa0312724 02:24:02 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 02:24:02 INFO - mozversion platform_version: 53.0a2 02:24:02 INFO - using testdate: 1488191042 02:24:02 INFO - actual date: 1488191042 02:24:02 INFO - starting webserver on 'localhost:49347' 02:24:02 INFO - SUITE-START | Running 2 tests 02:24:02 INFO - TEST-START | dromaeo_css 02:24:02 INFO - operating with platform_type : mac_ 02:24:02 INFO - Initialising browser for dromaeo_css test... 02:24:02 INFO - TEST-INFO | started process 1988 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/T/tmp12sOss/profile http://localhost:49347/getInfo.html) 02:24:03 INFO - PROCESS | 1988 | 1488191043077 addons.xpi WARN Error loading bootstrap.js for disableSHA1rollout@mozilla.org: Error opening input stream (invalid filename?): jar:file:///builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/Resources/browser/features/disableSHA1rollout@mozilla.org.xpi!/bootstrap.js 02:24:03 INFO - PROCESS | 1988 | 1488191043078 addons.xpi WARN Add-on disableSHA1rollout@mozilla.org is missing bootstrap method install 02:24:03 INFO - PROCESS | 1988 | 1488191043113 addons.xpi WARN Add-on disableSHA1rollout@mozilla.org is missing bootstrap method startup 02:24:05 INFO - PROCESS | 1988 | 2017-02-27 02:24:05.339 plugin-container[1990:17822] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8d37, name = 'com.apple.tsm.portname' 02:24:05 INFO - PROCESS | 1988 | See /usr/include/servers/bootstrap_defs.h for the error codes. 02:24:05 INFO - PROCESS | 1988 | 2017-02-27 02:24:05.343 plugin-container[1990:17822] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4c1f, name = 'com.apple.CFPasteboardClient' 02:24:05 INFO - PROCESS | 1988 | See /usr/include/servers/bootstrap_defs.h for the error codes. 02:24:05 INFO - PROCESS | 1988 | 2017-02-27 02:24:05.343 plugin-container[1990:17822] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 02:24:05 INFO - PROCESS | 1988 | __metrics Screen width/height:1600/1200 02:24:05 INFO - PROCESS | 1988 | colorDepth:24 02:24:05 INFO - PROCESS | 1988 | Browser inner width/height: 1024/689 02:24:05 INFO - PROCESS | 1988 | __metrics 02:24:05 ERROR - PROCESS | 1988 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3277: TypeError: this.tabs is undefined 02:24:05 INFO - PROCESS | 1988 | 1488191045526 addons.xpi WARN Add-on disableSHA1rollout@mozilla.org is missing bootstrap method shutdown 02:24:06 INFO - TEST-INFO | 1988: exit 0 02:24:06 INFO - Browser initialized. 02:24:06 INFO - Running cycle 1/1 for dromaeo_css test... 02:24:06 INFO - TEST-INFO | started process 1991 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/T/tmp12sOss/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 02:24:06 INFO - PROCESS | 1991 | 1488191046666 addons.xpi WARN Error loading bootstrap.js for disableSHA1rollout@mozilla.org: Error opening input stream (invalid filename?): jar:file:///builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/Resources/browser/features/disableSHA1rollout@mozilla.org.xpi!/bootstrap.js 02:24:06 INFO - PROCESS | 1991 | 1488191046666 addons.xpi WARN Add-on disableSHA1rollout@mozilla.org is missing bootstrap method startup 02:24:08 INFO - PROCESS | 1991 | 2017-02-27 02:24:08.718 plugin-container[1992:17956] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x873f, name = 'com.apple.tsm.portname' 02:24:08 INFO - PROCESS | 1991 | See /usr/include/servers/bootstrap_defs.h for the error codes. 02:24:08 INFO - PROCESS | 1991 | 2017-02-27 02:24:08.719 plugin-container[1992:17956] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8b0b, name = 'com.apple.CFPasteboardClient' 02:24:08 INFO - PROCESS | 1991 | See /usr/include/servers/bootstrap_defs.h for the error codes. 02:24:08 INFO - PROCESS | 1991 | 2017-02-27 02:24:08.719 plugin-container[1992:17956] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 02:24:18 INFO - PROCESS | 1991 | RSS: Main: 218664960 02:24:18 INFO - PROCESS | 1991 | 02:27:20 INFO - PROCESS | 1991 | Cycle 1(1): loaded http://localhost:49347/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49347/tests/dromaeo/cssquery-ext.html) 02:27:22 INFO - PROCESS | 1991 | RSS: Main: 237637632 02:27:22 INFO - PROCESS | 1991 | 02:29:06 INFO - PROCESS | 1991 | ************************* 02:29:06 INFO - PROCESS | 1991 | A coding exception was thrown and uncaught in a Task. 02:29:06 INFO - PROCESS | 1991 | 02:29:06 ERROR - PROCESS | 1991 | Full message: TypeError: NetworkError when attempting to fetch resource. 02:29:06 INFO - PROCESS | 1991 | Full stack: 02:29:06 INFO - PROCESS | 1991 | ************************* 02:30:24 INFO - PROCESS | 1991 | Cycle 1(1): loaded http://localhost:49347/tests/dromaeo/cssquery-ext.html (next: http://localhost:49347/tests/dromaeo/cssquery-jquery.html) 02:30:25 INFO - PROCESS | 1991 | RSS: Main: 247238656 02:30:25 INFO - PROCESS | 1991 | 02:33:27 INFO - PROCESS | 1991 | Cycle 1(1): loaded http://localhost:49347/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49347/tests/dromaeo/cssquery-mootools.html) 02:33:27 INFO - PROCESS | 1991 | RSS: Main: 254320640 02:33:27 INFO - PROCESS | 1991 | 02:36:29 INFO - PROCESS | 1991 | Cycle 1(1): loaded http://localhost:49347/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49347/tests/dromaeo/cssquery-prototype.html) 02:36:30 INFO - PROCESS | 1991 | RSS: Main: 250847232 02:36:30 INFO - PROCESS | 1991 | 02:39:33 INFO - PROCESS | 1991 | Cycle 1(1): loaded http://localhost:49347/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49347/tests/dromaeo/cssquery-yui.html) 02:39:33 INFO - PROCESS | 1991 | RSS: Main: 255348736 02:39:33 INFO - PROCESS | 1991 | 02:42:36 INFO - PROCESS | 1991 | Cycle 1(1): loaded http://localhost:49347/tests/dromaeo/cssquery-yui.html (next: http://localhost:49347/tests/dromaeo/cssquery-dojo.html) 02:42:36 INFO - PROCESS | 1991 | RSS: Main: 256946176 02:42:36 INFO - PROCESS | 1991 | 02:42:36 INFO - PROCESS | 1991 | __start_tp_report 02:42:36 INFO - PROCESS | 1991 | _x_x_mozilla_page_load 02:42:36 INFO - PROCESS | 1991 | _x_x_mozilla_page_load_details 02:42:36 INFO - PROCESS | 1991 | |i|pagename|runs| 02:42:36 INFO - PROCESS | 1991 | |0;dojo.html;2847.5301575240087;3341.431956567383;3343.582052243468;3344.2809795893877;3374.696277335041;4352.890013046676;5701.942980570193;5772.480476757091;5802.535797136229;5819.941800581998;5597.804076857311;5709.4290570942885;5717.2281741964825;5729.512991395726;5745;5182.37811462625;5211.557017653504;5222.817201397952;5258.316418865542;5273.709945952971;5256.237845512398;5262.631615786897;5268.973655131719;5278.313819203518;5297.602679799012;5136.922946155811;5157.149070403398;5255.211718242275;5257.447967963366;5317.7607007684555;5222.373315202162;5248.343957005374;5273.920891186635;5310;5324.387695414999;5123.641345105844;5223.817166399158;5236.8690782730355;5238.423773384963;5334.786608535693;5588.022096133201;5664.971675141639;5752.194692743019;5872.5889187756875;5897.439743224387;6130.795626845448;6150.892839288927;6153.969230153865;6160.661163636043;6262.718177682015;6211.4409703126485;6212.254529456449;6214.875702485925;6214.9689251553455;6222.97320942044;5802.158686990405;5819.563532735062;5829;5843.094320380305;5873.4126587340925;5477.287952566141;5833.79581714638;6180.351063138396;6202.689865506707;6206.627602343874;6145.723442445101;6160.815175544741;6179.134921111048;6188.566800324024;6200.689965501797;6179.134921111048;6232.283287421914;6260.123582698426;6260.937390626126;6276.686165691697;6127.295361033449;6132.294322189902;6155.661438620829;6180.351063138396;6244.562880598314;6200.658963757037;6208.410201030895;6223.937760622336;6224.782132625336;6243.125962365272;4953.628477864175;4972.776225069881;4986.7257300848805;4995.575376093073;5017.347744793226;4386.8903277417685;4389.648828093745;4427.889302767457;4454.7549884756;4474.082813023322;5890.204822349014;5940.881182376328;5988.700564971821;6020.277566692025;6058.848528786727;6217.347178546188;6224.564280500322;6242.500599951993;6243.094751261042;6243.531735119885;5714.942850571441;5853.385394533598;5870.178175055496;5908;5909.3204281507315;5431.511163995259;5499.395066542677;5524.668519888739;5530.668159910419;5553.861153471195;5613.989481893217;6341.080543321193;6379.936200637934;6386.680665966683;6411.262704788823;6150.507959363151;6159.19930409044;6177.166082578794;6178.691065446799;6239.532035097295;6334;6350.809475715736;6358.205224346957;6359.841003974938;6407.8718425630295;5227.372715274191;6240.656763877849;6249.625022498664;6258.311585725658;6261.060840873905;6119.112728654409;6176.505879529716;6203.2245969253845;6248.625082495065;6255.843603909939;5972.761089556369;6156.1381406604005;6160;6160.075988601746;6165.753369865155;5112.288293665568;6160.229971253593;6174.66039367839;6176.320604733565;6222.657753823583;4849.2726091086615;5190.714510701948;5511;6012.729427175875;6054.515638748801;4700.882477938079;5407;6135.07973803933;6191.071339299141;6191.659458729813;6034.8995429467;6036.577439579363;6071.058985857199;6072.544559157993;6093.146959425772 02:42:36 INFO - PROCESS | 1991 | |1;ext.html;450.20089341418975;450.8241785703576;453.36302494994544;478.61710631494793;521.7339157029916;40898;42267;42297;42304;42331;43281.13437731243;44019;44032.45886393977;44068.55931440685;44112;7739.303462688357;8460.323174146084;9140.314476414278;9358.11097945694;9393.483358415284;11169;11483.655490335303;11530.135239857002;11642.068634509262;11644;11546.826797598002;11580.884191158108;11599.014083802887;11609.419529023515;11626.767464650744;11364.431778411088;11422.71443213918;11430.771384572345;11455.083593312556;11464.541418343257;11450.370229637367;11501;11532.654020379403;11566.768664626745;11574.421278936104;42080.94797630053;42481.57518424807;42614.57385426137;42760.3585946211;42773.57226427758;3231.676832316773;3940.7044471664453;3976.622220889011;4037.313656678372;4089.8364065437345;26366.60450093231;30142.84928575343;30933.91731289394;31742;32145;3095.7059079387423;3151.8896838610767;3244.3511297740547;3386.153461634591;3395.456726923705;381222;426882;493631;495402;500164;62522.68738656278;62717;62839.371606284265;62959;68246.65876670585;70691.2930870695;71274.28725712677;71613.56772864514;72038;72115;16857;17410.21654025572;17442.563935901704;17453.040082795313;17454;10074.647387341407;12498.562550310695;12920.612381628565;13128.212307261594;13201.73596528064;19365.903170484056;19383;19446.027698615297;19449.513762155777;19471.902640486707;5317.388500322435;5453.918191227135;6451.129172540005;6517.283098859121;6527.314631963671;842.8862103616055;844.1684940333766;854.231191927258;859.7377799771004;877.8990416102102;24152.75847241505;30051.398972020437;30761;31081.067567972997;31221.375572488876;915.5193523400135;941.7474758571084;1005.3163848582888;1029.9134413194151;1153.4175241502987;15471.458498952708;15843.445479408396;16037.358505659642;16073.517794466185;16309.42916997923;546879.5312046829;599679;601466;605138;607322;62206.68896655578;62240;62395.376046238955;62531;62581.68709156425;1375.3329635127157;1418.9290535473185;1435.6913263648157;1435.6913263648366;1444.1840360196666;28214;29133.417331653673;29606.703932960394;29819.956301529342;30289.848550757106;54453.72773136109;54884.725576371864;54997.725011374685;55015.44984550263;55102.89794204013;529242;532700;536452;537491;538798;42284;42645;43220;43264;43279.567204327555;46843;47274.52725472839;47747.761261193475;48346.758266208446;48699.53901382964;37805.81097094497;39101.82694519169;39154.82535523939;40246.79259622216;40288.79855600703;39018.609813902636;39368.66944636145;40408.59591404166;40438.59561404466;40575.797121014206;37291.254174915804;37578.87263382103;38282.61717382902;38401.61598384092;38436.80781596074;32525.512117318733;33996;34222;34670;34722.65277347194;25185.24444266675;25323;25368.365790854637;25375.36561585975;25509.489810203322 02:42:36 INFO - PROCESS | 1991 | |2;jquery.html;364.29508900277966;387.25646758224207;390.60182346775304;395.05581659832995;422.77804152819766;19529.609407811837;20749.792502074975;20761.896190519063;20777.480562985933;21771.564568708618;20604;21139.837308948;21426.678599820978;21557.568848623017;21680.02439890205;3705.999999999993;5105.106606343894;5729.0833466645345;5733.512651424613;5793.913091303634;4804.711717296974;5467.824417750183;5632.211490391349;5679.432056794329;5710.314762228518;5600.411956744544;5668.914966275509;5678.545716342683;5679.949209396254;5686.573506986972;5565.638233514808;5594.2447769551;5658.519025882805;5662.773489060432;5686.943130568683;5024.371953505812;5025.12060389434;5030.446650868402;5035.3454050973505;5041.924371134436;25680.871595641904;25812;26140;26146.346341341618;26280;2706.3369474478877;2768.584712293152;2771.4457108578363;2777.222377734238;2779.958300625492;15535.922320388325;15705.842941570552;15836.287367068395;15930.362785488567;16263.10552919589;1807.1145138881984;1844.170123444442;1849.8982555959417;1856.4245084023996;1861.3485280151974;120495.3975230127;122276.38861805634;122827.77172228253;124616.37691811574;131353.99999999904;11043.447827608587;11492.195546311677;11517.25137866036;11534.307941523535;11722.824157637642;12091.879081209076;12908.612741617766;13064.673383165496;14096.50662226817;14495.710085798224;8052.59737013147;8578.571071446402;8723.214910657944;8723.781905452288;8795.736127916172;7479.214682458372;8597.398182127192;8597.484150950839;8796.78008049791;8927.375083744206;7057.929420705727;7182.3894968928225;7190.820229494305;7194.244604316576;7201.567905925556;1896.8008359122093;1935.0614951748391;1940.3208876892975;1941.0973897137617;1942.4852414110271;873.3886279604302;894.2577660541734;899.7795540092719;911.4394647292;940.4498368454531;21565.137394504043;22224.77775222227;22330.77669223287;22460.213892513682;22481.43796405103;875.9956200218958;925.5048549026238;934.6401635370431;951.4861974533675;960.558143254111;10254.692359229235;10620.256582039337;10705.785884282272;10789.460526973777;10789.676309710721;486215;748629;757437;760308;770906;83079.16920830835;150998;158252;162460;166321;902.6434558349415;1068.6847380022757;1110.566878917223;1116.5980247111033;1117.2514415341902;18899.433017009513;19149;19462.708059379405;19590;19606.803931960498;57703.845923080466;59597.106043408516;59745.402545973986;60433;60692.87574934856;63828;66209.0068648961;88122;88837.55581222053;89671.30986070428;7215.314545118205;7540.396768258416;8311.875321870057;8328.833423331378;8444.66221351139;12037.819432708342;12259.938700306799;13110.672233194246;13150.408231629792;13460.05779595419;10938.343699378063;11234.045106166068;11243.943780281374;11444.42777861087;11474.196806223485;9137.543122843965;10852.620158294421;10903.509342079784;11157.10743140563;11609.071274298205;9963.202943764627;10139.543720532742;10428.58285668595;11316.038136758467;11631;6228.984615994987;7636.465447418849;7637.350825179922;7767.611619419119;7783.1827658097345;8155.63299651529;11050.613228536962;11162.38606876597;11220.775584488429;11348.602798901999 02:42:36 INFO - PROCESS | 1991 | |3;mootools.html;1393.7484574398577;1440.0783498560916;1442.1779585636184;1457.4680241711776;1460.5618314505648;7539.095308562966;7792.220777922205;8106.837863242732;8498.405111642187;8505.192006759356;8059.677612895477;8372.83254334913;8373.07896131425;8410.201030902055;8469.957650211756;2310.376198426424;2362.2558893948435;2364.408897775556;2367.0768400323886;2376;2330.9184178553755;2369.218158007862;2379.131616959806;2379.6073647848084;2389.5101504191684;1896.9810301896941;2042.28520017994;2334.253039027512;2354.0936739355384;2367.538330025646;1948.805119488054;1954.8436125109956;2295.8737269450176;2329.77867102625;2340.20433052762;2010.427028296943;2307.1348244408346;2319.5824751544715;2345.202631105415;2345.296411076687;8372.41393102483;8424.705135320295;8442.07137214906;8448.831023379498;8466.66133354665;5077;5234.7644356004075;5255.921161182585;5263.078961181778;5279.788808447657;2989.760819134464;3355.5470011548373;3431.330890476358;3507.1056880495566;3524.2422879080864;1220.3044264769085;1233.962981110568;1719.3810228317795;1749.457668122886;1835.3392778599693;10909.890901090967;10915;11051.944740276247;11064.944675276573;11065.391403472806;4587.885302867455;4782.521747825259;4785.353977213032;4786.904261914742;4830.130576496194;8781.473111613324;8816.382853200343;8817.515036673045;8830.823383532294;8848.336374771918;5141.640085194001;5154.020736060136;5155.200943853633;5159.7678104485385;5161.406438259573;4640.141573808856;4735.337052812609;4758.11974784659;4774.641901857305;4876.390451193601;3703.925921481555;3794.2601192767697;3808.961910380916;3837.9616203837604;3845.2309538092495;1022.2180032275315;1189.8631657359515;1190.345310079453;1197.0483465644834;1197.8323034775137;768.2317682317682;779.5946108023909;900.0144841398658;905.5834316214615;921.5115988526092;4625.190591646516;5278.656887302313;5327.334083239595;5399.325084364455;5405.540529055074;956.6651671914774;993.7913038262046;1004.9598016079422;1011.191047162282;1046.9162467002623;3686.7972261525338;3724.8137593120236;3847.0574709196426;3867.207222519377;3891.591382904811;9936.403815771077;10216.182705383551;10216.795664086825;10228.284020118668;10232.23258255619;10081.69754907354;10165.44090075053;10170.237232207613;10175.949120254352;10187.694369168938;7653.923460765321;7848.882266766003;7949.244821741925;7993.480423772437;8039.718609848745;5391.676499410048;5399.811006614907;5426.511613954763;5671.149327600893;5686.061799802987;7730.806729831799;7970.561619110773;8490.57547122629;8898.510581918057;9390.060993900555;8715.694950676949;8775.824483510167;9253.213476854542;9462.810743784948;9602.08780165883;7050.929490705027;7341;7350.411967042517;7355.448341374526;7368.631568421664;7687.654055567625;7928.127905930228;7949.244821741925;7971.880421793794;8049.114597394399;7042.119735033121;7057.541259818096;7083;7125.643717814192;7138.964305178441;6850.862982740417;6953.860922781415;6977.372036516738;6983.44132469391;7057.329553692391;6198.969005154945;6352.523560732871;6363.6499992499;6430.582012169381;6436.16329877113;4431.556844315543;4446.221911165469;4539.7049191802425;4973.502649734997;5010.173321402073;4359.891002724958;5027.245913113063;5068.82259120929;5077.39071311445;5135.974320128375 02:42:36 INFO - PROCESS | 1991 | |4;prototype.html;338.6207447658623;359.07179939855473;369.8834867016889;372.90118118698535;379.16016024505734;4043.3018132384395;4750;5423.91864122038;5514.145307477343;5532.391436941938;3983.83689044948;5026.924596131052;5040.395152581696;5305.761240744167;5432.70120143392;3881.3013657541633;3953.8813835584833;4307.289297265947;4347.456567929009;4360.321276308612;4015.1969606078756;4092.4600220808934;4350.086481838817;4350.347447882811;4366.257736184841;3602.8919132426067;3985.3623420252766;4023.7928621413607;4223.838444427776;4256.851010214643;3595.6404359564094;4211.347241177622;4240.2367573836855;4271.572842715735;4274.935875961863;3248.4315244832296;3799;3829.1958688675413;3852.672522835562;3866;4507.639388848885;5031.006551102088;5331.626786124973;5374.435684253136;5379.731013449351;4014.4781178446906;4905.950940490585;4940.308356830046;4974;4986.202207646796;4883.560479556857;5648.740503798473;5804.042333015047;5807.245058142415;5895.61678490901;3537.7877327360443;3751.8124093795473;4058.594140585947;4108.116754897711;4167.291560434734;5815.796447124372;6006.219191505076;6063.18147050147;6077.72367802762;6097.542684298695;4129;4215.915681686349;4234.407182994383;4262.616364527207;4291.67812414064;3440.7419443541835;3674.52231209941;3752.62473752628;4253.170631726845;4297.806598703066;4682.51471711675;4777.8088876445245;4840.370751802314;4851.248056551241;4948.134076536664;4653.697509661861;5270.525652691276;5934.715134673182;5957.331947054831;5981.641101533922;1251.9248845069326;1258.4337048328289;1294.6633875192329;1314.3362601886176;1353.9661508462368;839.1231163434226;962.9759256018656;973.4840534516718;987.9851802222972;1000.9299349045642;4589.885252868705;4687;5033.6224783141415;5193.324867767166;5240.633155679066;4905.901881962341;5387.572293342268;5679;5705.800296989669;5739.253896993364;4343.739375637472;4655.255159174516;4784.569388755029;5057.72182529957;5121.718305493159;2574.4336246025846;2887.6246088008843;2938.9559156612668;2946.690597487233;2975.7916945813586;5070.898582028339;5111.821086262037;5760.337561180434;5930.797369420465;5950.256217972754;5340.759665815048;5526.226328314039;5740.6268592541355;5822.369736473795;6071.817845464643;5922.674252916045;6220.098085777537;6259.436650701458;6265.402322407756;6337.714802833883;5486.396496385474;5574.721263936706;5602.730893987941;5652.463016013472;5940.2277703899945;4598.678092533491;4743.051389722138;5041.773120209526;5715.514181294637;5934.673592952429;5196;5504.834854954358;5959.374265702038;6206.317305096526;6232.657203853832;3803.7337386382687;3900.3369427196876;3940.5074365704286;3948.269570129535;3964.742291751027;4535;5888.293404791452;5904.8780731660845;5979.850503737267;5986.580939334205;5613.943860561342;5698.287714035746;5714.771154297809;5727.341355743976;5792.652440853562;4940.987097644902;4977.676451030671;5412.836240208374;5416.674999500043;5779.479846813807;4922.581580565692;5130.6152038598;5597.524210442158;5633.5916020994755;5674.517665998436;4836.46798852133;5704.229928959538;5704.80033198836;5725.398833122462;5775.48020678141;4975.253711943237;5524.779008839601;5621.775128994795;5665.753534222465;5674 02:42:36 INFO - PROCESS | 1991 | |5;yui.html;687.4706476013466;699.7970588529327;774.6049514747481;808;815.5473712089789;12739.044571657116;14712.411503539859;15260;15410.845891541096;15551.844481555196;13658.453661853513;14542.636434089127;14560.271986400665;14560.708785824278;14628.634284142901;846.8433339832135;869.074435725953;887.6449420231927;888.3958907942597;890.0387581412092;672.7813460625292;692.6709812838911;749.5053264845203;865.4331412924545;866.5493943149559;905.0135352465811;936.7002559181064;936.8266870628955;939.9154076133146;945.4232917920064;907.5507623726264;911.1526280559077;911.334725650279;913.8811954445946;916.193749500442;693.2616763147232;700.4431476975816;701.4949236549676;702.4345401951427;704.1303989572873;15252.923735381251;15597.064176149353;15620.687586248212;15670.764938525932;15744;14418.42326306957;14587.270636468134;14650.510165934726;14738.336774845051;14762.261886905611;11361;11592.420378981018;11667.474963626571;11674.29954202742;11708.531658733726;3636.3636363636256;3671.6695497405094;3710.517632707758;3711.0536813112753;3876.186000939806;100369.49445758319;114638;115372;115765;119034;11108.555657773599;11196.384198868978;11254.94372528132;11263.26788758728;11392.715182120513;23851.880740596185;24514.877425612758;24557.87721061419;24642.630360544605;24692.753072469404;7615.5430674159725;7653.081630204411;7733.961330193313;7838.176991415933;7993.640286187019;4197.874063778091;4232.365145228179;4303.053328267776;4309.655227581786;4319.265724826787;3154.3533575616943;3203.6315823680575;3203.8237896915894;3209.069369882708;3236.465983112807;642.0497663458108;651.1957732200823;669.7053296549502;669.7120238297579;681.6148875885109;618.77724019353;628.7736414890636;635.5328833307515;641.8459569703238;661.9503537234726;9737.17234035115;9793.265505087147;9850.458224797705;9865.161461275728;10007.149392301735;601;607.7751232044084;648.6853875870199;650.177525430331;668.7692746002622;4712.151812673752;4726.196546587088;4733.83431579893;4740.19416699162;4744.098621261949;101004;107706.384404234;108634;110726;110847.44576277067;92356.53821730983;95947;96658;97763;99545;66941;83967.74048389401;84166;84491;87249.1275087241;7856.410769192219;8319.417640765088;8358.289545388467;8358.498490090615;8406.495610263404;50801.983960321326;54066;54099.18851217314;54397.18404224019;54786;66563;70132.2507534063;71361.64319178578;71924.64037679778;74680.13299667551;11117.555297787998;11204.719882003015;11505.48225329879;11880.168388212742;12025.75948481008;11975.520979160736;11976.101792365433;11992.100592455776;12035.398230088636;12060.698482538006;11586.942065289957;11651.184417090722;11655.18413711032;11663;11676.94161529221;9481.573329200064;9733.80532389334;9823.754406139904;10063.194944404577;10067.798644027225;10946.945265273622;11017.944910275397;11317.320960742052;11382.317060976036;11520.366379849189;9298.488583127722;9419.481928493999;9449.94285315013;9669.516524173623;9733.805323893625;7815.92184078152;9189.494577798017;9379.108984646447;9476.194523465583;9478.952605237206 02:42:36 INFO - PROCESS | 1991 | __end_tp_report 02:42:36 INFO - PROCESS | 1991 | __start_cc_report 02:42:36 INFO - PROCESS | 1991 | _x_x_mozilla_cycle_collect,392 02:42:36 INFO - PROCESS | 1991 | __end_cc_report 02:42:36 INFO - PROCESS | 1991 | __startTimestamp1488192156783__endTimestamp 02:42:36 INFO - PROCESS | 1991 | 02:42:36 INFO - PROCESS | 1991 | ------- Summary: start ------- 02:42:36 INFO - PROCESS | 1991 | Number of tests: 6 02:42:36 INFO - PROCESS | 1991 | 02:42:36 INFO - PROCESS | 1991 | [#0] dojo.html Cycles:165 Average:5722.46 Median:5972.76 stddev:664.02 (11.1%) stddev-sans-first:626.58 02:42:36 INFO - PROCESS | 1991 | Values: 2847.5 3341.4 3343.6 3344.3 3374.7 4352.9 5701.9 5772.5 5802.5 5819.9 5597.8 5709.4 5717.2 5729.5 5745.0 5182.4 5211.6 5222.8 5258.3 5273.7 5256.2 5262.6 5269.0 5278.3 5297.6 5136.9 5157.1 5255.2 5257.4 5317.8 5222.4 5248.3 5273.9 5310.0 5324.4 5123.6 5223.8 5236.9 5238.4 5334.8 5588.0 5665.0 5752.2 5872.6 5897.4 6130.8 6150.9 6154.0 6160.7 6262.7 6211.4 6212.3 6214.9 6215.0 6223.0 5802.2 5819.6 5829.0 5843.1 5873.4 5477.3 5833.8 6180.4 6202.7 6206.6 6145.7 6160.8 6179.1 6188.6 6200.7 6179.1 6232.3 6260.1 6260.9 6276.7 6127.3 6132.3 6155.7 6180.4 6244.6 6200.7 6208.4 6223.9 6224.8 6243.1 4953.6 4972.8 4986.7 4995.6 5017.3 4386.9 4389.6 4427.9 4454.8 4474.1 5890.2 5940.9 5988.7 6020.3 6058.8 6217.3 6224.6 6242.5 6243.1 6243.5 5714.9 5853.4 5870.2 5908.0 5909.3 5431.5 5499.4 5524.7 5530.7 5553.9 5614.0 6341.1 6379.9 6386.7 6411.3 6150.5 6159.2 6177.2 6178.7 6239.5 6334.0 6350.8 6358.2 6359.8 6407.9 5227.4 6240.7 6249.6 6258.3 6261.1 6119.1 6176.5 6203.2 6248.6 6255.8 5972.8 6156.1 6160.0 6160.1 6165.8 5112.3 6160.2 6174.7 6176.3 6222.7 4849.3 5190.7 5511.0 6012.7 6054.5 4700.9 5407.0 6135.1 6191.1 6191.7 6034.9 6036.6 6071.1 6072.5 6093.1 02:42:36 INFO - PROCESS | 1991 | 02:42:36 INFO - PROCESS | 1991 | [#1] ext.html Cycles:180 Average:68360.91 Median:3958.66 stddev:141711.74 (3579.8%) stddev-sans-first:142017.55 02:42:36 INFO - PROCESS | 1991 | Values: 450.2 450.8 453.4 478.6 521.7 40898.0 42267.0 42297.0 42304.0 42331.0 43281.1 44019.0 44032.5 44068.6 44112.0 7739.3 8460.3 9140.3 9358.1 9393.5 11169.0 11483.7 11530.1 11642.1 11644.0 11546.8 11580.9 11599.0 11609.4 11626.8 11364.4 11422.7 11430.8 11455.1 11464.5 11450.4 11501.0 11532.7 11566.8 11574.4 42080.9 42481.6 42614.6 42760.4 42773.6 3231.7 3940.7 3976.6 4037.3 4089.8 26366.6 30142.8 30933.9 31742.0 32145.0 3095.7 3151.9 3244.4 3386.2 3395.5 381222.0 426882.0 493631.0 495402.0 500164.0 62522.7 62717.0 62839.4 62959.0 68246.7 70691.3 71274.3 71613.6 72038.0 72115.0 16857.0 17410.2 17442.6 17453.0 17454.0 10074.6 12498.6 12920.6 13128.2 13201.7 19365.9 19383.0 19446.0 19449.5 19471.9 5317.4 5453.9 6451.1 6517.3 6527.3 842.9 844.2 854.2 859.7 877.9 24152.8 30051.4 30761.0 31081.1 31221.4 915.5 941.7 1005.3 1029.9 1153.4 15471.5 15843.4 16037.4 16073.5 16309.4 546879.5 599679.0 601466.0 605138.0 607322.0 62206.7 62240.0 62395.4 62531.0 62581.7 1375.3 1418.9 1435.7 1435.7 1444.2 28214.0 29133.4 29606.7 29820.0 30289.8 54453.7 54884.7 54997.7 55015.4 55102.9 529242.0 532700.0 536452.0 537491.0 538798.0 42284.0 42645.0 43220.0 43264.0 43279.6 46843.0 47274.5 47747.8 48346.8 48699.5 37805.8 39101.8 39154.8 40246.8 40288.8 39018.6 39368.7 40408.6 40438.6 40575.8 37291.3 37578.9 38282.6 38401.6 38436.8 32525.5 33996.0 34222.0 34670.0 34722.7 25185.2 25323.0 25368.4 25375.4 25509.5 02:42:36 INFO - PROCESS | 1991 | 02:42:36 INFO - PROCESS | 1991 | [#2] jquery.html Cycles:180 Average:39043.27 Median:2737.46 stddev:118786.04 (4339.3%) stddev-sans-first:119083.76 02:42:36 INFO - PROCESS | 1991 | Values: 364.3 387.3 390.6 395.1 422.8 19529.6 20749.8 20761.9 20777.5 21771.6 20604.0 21139.8 21426.7 21557.6 21680.0 3706.0 5105.1 5729.1 5733.5 5793.9 4804.7 5467.8 5632.2 5679.4 5710.3 5600.4 5668.9 5678.5 5679.9 5686.6 5565.6 5594.2 5658.5 5662.8 5686.9 5024.4 5025.1 5030.4 5035.3 5041.9 25680.9 25812.0 26140.0 26146.3 26280.0 2706.3 2768.6 2771.4 2777.2 2780.0 15535.9 15705.8 15836.3 15930.4 16263.1 1807.1 1844.2 1849.9 1856.4 1861.3 120495.4 122276.4 122827.8 124616.4 131354.0 11043.4 11492.2 11517.3 11534.3 11722.8 12091.9 12908.6 13064.7 14096.5 14495.7 8052.6 8578.6 8723.2 8723.8 8795.7 7479.2 8597.4 8597.5 8796.8 8927.4 7057.9 7182.4 7190.8 7194.2 7201.6 1896.8 1935.1 1940.3 1941.1 1942.5 873.4 894.3 899.8 911.4 940.4 21565.1 22224.8 22330.8 22460.2 22481.4 876.0 925.5 934.6 951.5 960.6 10254.7 10620.3 10705.8 10789.5 10789.7 486215.0 748629.0 757437.0 760308.0 770906.0 83079.2 150998.0 158252.0 162460.0 166321.0 902.6 1068.7 1110.6 1116.6 1117.3 18899.4 19149.0 19462.7 19590.0 19606.8 57703.8 59597.1 59745.4 60433.0 60692.9 63828.0 66209.0 88122.0 88837.6 89671.3 7215.3 7540.4 8311.9 8328.8 8444.7 12037.8 12259.9 13110.7 13150.4 13460.1 10938.3 11234.0 11243.9 11444.4 11474.2 9137.5 10852.6 10903.5 11157.1 11609.1 9963.2 10139.5 10428.6 11316.0 11631.0 6229.0 7636.5 7637.4 7767.6 7783.2 8155.6 11050.6 11162.4 11220.8 11348.6 02:42:36 INFO - PROCESS | 1991 | 02:42:36 INFO - PROCESS | 1991 | [#3] mootools.html Cycles:180 Average:5409.28 Median:5072.91 stddev:2938.69 (57.9%) stddev-sans-first:2931.43 02:42:36 INFO - PROCESS | 1991 | Values: 1393.7 1440.1 1442.2 1457.5 1460.6 7539.1 7792.2 8106.8 8498.4 8505.2 8059.7 8372.8 8373.1 8410.2 8470.0 2310.4 2362.3 2364.4 2367.1 2376.0 2330.9 2369.2 2379.1 2379.6 2389.5 1897.0 2042.3 2334.3 2354.1 2367.5 1948.8 1954.8 2295.9 2329.8 2340.2 2010.4 2307.1 2319.6 2345.2 2345.3 8372.4 8424.7 8442.1 8448.8 8466.7 5077.0 5234.8 5255.9 5263.1 5279.8 2989.8 3355.5 3431.3 3507.1 3524.2 1220.3 1234.0 1719.4 1749.5 1835.3 10909.9 10915.0 11051.9 11064.9 11065.4 4587.9 4782.5 4785.4 4786.9 4830.1 8781.5 8816.4 8817.5 8830.8 8848.3 5141.6 5154.0 5155.2 5159.8 5161.4 4640.1 4735.3 4758.1 4774.6 4876.4 3703.9 3794.3 3809.0 3838.0 3845.2 1022.2 1189.9 1190.3 1197.0 1197.8 768.2 779.6 900.0 905.6 921.5 4625.2 5278.7 5327.3 5399.3 5405.5 956.7 993.8 1005.0 1011.2 1046.9 3686.8 3724.8 3847.1 3867.2 3891.6 9936.4 10216.2 10216.8 10228.3 10232.2 10081.7 10165.4 10170.2 10175.9 10187.7 7653.9 7848.9 7949.2 7993.5 8039.7 5391.7 5399.8 5426.5 5671.1 5686.1 7730.8 7970.6 8490.6 8898.5 9390.1 8715.7 8775.8 9253.2 9462.8 9602.1 7050.9 7341.0 7350.4 7355.4 7368.6 7687.7 7928.1 7949.2 7971.9 8049.1 7042.1 7057.5 7083.0 7125.6 7139.0 6850.9 6953.9 6977.4 6983.4 7057.3 6199.0 6352.5 6363.6 6430.6 6436.2 4431.6 4446.2 4539.7 4973.5 5010.2 4359.9 5027.2 5068.8 5077.4 5136.0 02:42:36 INFO - PROCESS | 1991 | 02:42:36 INFO - PROCESS | 1991 | [#4] prototype.html Cycles:180 Average:4612.77 Median:5006.56 stddev:1400.49 (28.0%) stddev-sans-first:1367.18 02:42:36 INFO - PROCESS | 1991 | Values: 338.6 359.1 369.9 372.9 379.2 4043.3 4750.0 5423.9 5514.1 5532.4 3983.8 5026.9 5040.4 5305.8 5432.7 3881.3 3953.9 4307.3 4347.5 4360.3 4015.2 4092.5 4350.1 4350.3 4366.3 3602.9 3985.4 4023.8 4223.8 4256.9 3595.6 4211.3 4240.2 4271.6 4274.9 3248.4 3799.0 3829.2 3852.7 3866.0 4507.6 5031.0 5331.6 5374.4 5379.7 4014.5 4906.0 4940.3 4974.0 4986.2 4883.6 5648.7 5804.0 5807.2 5895.6 3537.8 3751.8 4058.6 4108.1 4167.3 5815.8 6006.2 6063.2 6077.7 6097.5 4129.0 4215.9 4234.4 4262.6 4291.7 3440.7 3674.5 3752.6 4253.2 4297.8 4682.5 4777.8 4840.4 4851.2 4948.1 4653.7 5270.5 5934.7 5957.3 5981.6 1251.9 1258.4 1294.7 1314.3 1354.0 839.1 963.0 973.5 988.0 1000.9 4589.9 4687.0 5033.6 5193.3 5240.6 4905.9 5387.6 5679.0 5705.8 5739.3 4343.7 4655.3 4784.6 5057.7 5121.7 2574.4 2887.6 2939.0 2946.7 2975.8 5070.9 5111.8 5760.3 5930.8 5950.3 5340.8 5526.2 5740.6 5822.4 6071.8 5922.7 6220.1 6259.4 6265.4 6337.7 5486.4 5574.7 5602.7 5652.5 5940.2 4598.7 4743.1 5041.8 5715.5 5934.7 5196.0 5504.8 5959.4 6206.3 6232.7 3803.7 3900.3 3940.5 3948.3 3964.7 4535.0 5888.3 5904.9 5979.9 5986.6 5613.9 5698.3 5714.8 5727.3 5792.7 4941.0 4977.7 5412.8 5416.7 5779.5 4922.6 5130.6 5597.5 5633.6 5674.5 4836.5 5704.2 5704.8 5725.4 5775.5 4975.3 5524.8 5621.8 5665.8 5674.0 02:42:36 INFO - PROCESS | 1991 | 02:42:36 INFO - PROCESS | 1991 | [#5] yui.html Cycles:180 Average:20926.94 Median:54248.19 stddev:31458.71 (58.0%) stddev-sans-first:31510.25 02:42:36 INFO - PROCESS | 1991 | Values: 687.5 699.8 774.6 808.0 815.5 12739.0 14712.4 15260.0 15410.8 15551.8 13658.5 14542.6 14560.3 14560.7 14628.6 846.8 869.1 887.6 888.4 890.0 672.8 692.7 749.5 865.4 866.5 905.0 936.7 936.8 939.9 945.4 907.6 911.2 911.3 913.9 916.2 693.3 700.4 701.5 702.4 704.1 15252.9 15597.1 15620.7 15670.8 15744.0 14418.4 14587.3 14650.5 14738.3 14762.3 11361.0 11592.4 11667.5 11674.3 11708.5 3636.4 3671.7 3710.5 3711.1 3876.2 100369.5 114638.0 115372.0 115765.0 119034.0 11108.6 11196.4 11254.9 11263.3 11392.7 23851.9 24514.9 24557.9 24642.6 24692.8 7615.5 7653.1 7734.0 7838.2 7993.6 4197.9 4232.4 4303.1 4309.7 4319.3 3154.4 3203.6 3203.8 3209.1 3236.5 642.0 651.2 669.7 669.7 681.6 618.8 628.8 635.5 641.8 662.0 9737.2 9793.3 9850.5 9865.2 10007.1 601.0 607.8 648.7 650.2 668.8 4712.2 4726.2 4733.8 4740.2 4744.1 101004.0 107706.4 108634.0 110726.0 110847.4 92356.5 95947.0 96658.0 97763.0 99545.0 66941.0 83967.7 84166.0 84491.0 87249.1 7856.4 8319.4 8358.3 8358.5 8406.5 50802.0 54066.0 54099.2 54397.2 54786.0 66563.0 70132.3 71361.6 71924.6 74680.1 11117.6 11204.7 11505.5 11880.2 12025.8 11975.5 11976.1 11992.1 12035.4 12060.7 11586.9 11651.2 11655.2 11663.0 11676.9 9481.6 9733.8 9823.8 10063.2 10067.8 10946.9 11017.9 11317.3 11382.3 11520.4 9298.5 9419.5 9449.9 9669.5 9733.8 7815.9 9189.5 9379.1 9476.2 9479.0 02:42:36 INFO - PROCESS | 1991 | -------- Summary: end -------- 02:42:36 INFO - PROCESS | 1991 | 02:42:36 INFO - PROCESS | 1991 | 1488192156807 addons.xpi WARN Add-on disableSHA1rollout@mozilla.org is missing bootstrap method shutdown 02:42:37 INFO - TEST-INFO | 1991: exit 0 02:42:37 INFO - TEST-OK | dromaeo_css | took 1114864ms 02:42:37 INFO - TEST-START | kraken 02:42:37 INFO - operating with platform_type : mac_ 02:42:37 INFO - Initialising browser for kraken test... 02:42:37 INFO - TEST-INFO | started process 1996 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/T/tmpor_irD/profile http://localhost:49347/getInfo.html) 02:42:38 INFO - PROCESS | 1996 | 1488192157994 addons.xpi WARN Error loading bootstrap.js for disableSHA1rollout@mozilla.org: Error opening input stream (invalid filename?): jar:file:///builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/Resources/browser/features/disableSHA1rollout@mozilla.org.xpi!/bootstrap.js 02:42:38 INFO - PROCESS | 1996 | 1488192157996 addons.xpi WARN Add-on disableSHA1rollout@mozilla.org is missing bootstrap method install 02:42:38 INFO - PROCESS | 1996 | 1488192158052 addons.xpi WARN Add-on disableSHA1rollout@mozilla.org is missing bootstrap method startup 02:42:40 INFO - PROCESS | 1996 | 2017-02-27 02:42:40.181 plugin-container[1997:19997] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4647, name = 'com.apple.tsm.portname' 02:42:40 INFO - PROCESS | 1996 | See /usr/include/servers/bootstrap_defs.h for the error codes. 02:42:40 INFO - PROCESS | 1996 | 2017-02-27 02:42:40.186 plugin-container[1997:19997] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4323, name = 'com.apple.CFPasteboardClient' 02:42:40 INFO - PROCESS | 1996 | See /usr/include/servers/bootstrap_defs.h for the error codes. 02:42:40 INFO - PROCESS | 1996 | 2017-02-27 02:42:40.186 plugin-container[1997:19997] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 02:42:40 INFO - PROCESS | 1996 | __metrics Screen width/height:1600/1200 02:42:40 INFO - PROCESS | 1996 | colorDepth:24 02:42:40 INFO - PROCESS | 1996 | Browser inner width/height: 1024/689 02:42:40 INFO - PROCESS | 1996 | __metrics 02:42:40 ERROR - PROCESS | 1996 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3277: TypeError: this.tabs is undefined 02:42:40 INFO - PROCESS | 1996 | 1488192160360 addons.xpi WARN Add-on disableSHA1rollout@mozilla.org is missing bootstrap method shutdown 02:42:41 INFO - TEST-INFO | 1996: exit 0 02:42:41 INFO - Browser initialized. 02:42:41 INFO - Running cycle 1/1 for kraken test... 02:42:41 INFO - TEST-INFO | started process 1998 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/T/tmpor_irD/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 02:42:41 INFO - PROCESS | 1998 | 1488192161449 addons.xpi WARN Error loading bootstrap.js for disableSHA1rollout@mozilla.org: Error opening input stream (invalid filename?): jar:file:///builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/Resources/browser/features/disableSHA1rollout@mozilla.org.xpi!/bootstrap.js 02:42:41 INFO - PROCESS | 1998 | 1488192161449 addons.xpi WARN Add-on disableSHA1rollout@mozilla.org is missing bootstrap method startup 02:42:43 INFO - PROCESS | 1998 | 2017-02-27 02:42:43.755 plugin-container[1999:20129] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4647, name = 'com.apple.tsm.portname' 02:42:43 INFO - PROCESS | 1998 | See /usr/include/servers/bootstrap_defs.h for the error codes. 02:42:43 INFO - PROCESS | 1998 | 2017-02-27 02:42:43.755 plugin-container[1999:20129] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8f0b, name = 'com.apple.CFPasteboardClient' 02:42:43 INFO - PROCESS | 1998 | See /usr/include/servers/bootstrap_defs.h for the error codes. 02:42:43 INFO - PROCESS | 1998 | 2017-02-27 02:42:43.756 plugin-container[1999:20129] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 02:42:53 INFO - PROCESS | 1998 | RSS: Main: 218931200 02:42:53 INFO - PROCESS | 1998 | 02:42:56 INFO - PROCESS | 1998 | Cycle 1(1): loaded http://localhost:49347/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49347/tests/kraken/driver.html?testName=audio-beat-detection) 02:42:57 INFO - PROCESS | 1998 | RSS: Main: 233447424 02:42:57 INFO - PROCESS | 1998 | 02:43:00 INFO - PROCESS | 1998 | Cycle 1(1): loaded http://localhost:49347/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49347/tests/kraken/driver.html?testName=audio-dft) 02:43:00 INFO - PROCESS | 1998 | RSS: Main: 241369088 02:43:00 INFO - PROCESS | 1998 | 02:43:04 INFO - PROCESS | 1998 | Cycle 1(1): loaded http://localhost:49347/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49347/tests/kraken/driver.html?testName=audio-fft) 02:43:05 INFO - PROCESS | 1998 | RSS: Main: 243007488 02:43:05 INFO - PROCESS | 1998 | 02:43:07 INFO - PROCESS | 1998 | Cycle 1(1): loaded http://localhost:49347/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49347/tests/kraken/driver.html?testName=audio-oscillator) 02:43:07 INFO - PROCESS | 1998 | RSS: Main: 244830208 02:43:07 INFO - PROCESS | 1998 | 02:43:09 INFO - PROCESS | 1998 | Cycle 1(1): loaded http://localhost:49347/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49347/tests/kraken/driver.html?testName=imaging-gaussian-blur) 02:43:10 INFO - PROCESS | 1998 | RSS: Main: 246390784 02:43:10 INFO - PROCESS | 1998 | 02:43:13 INFO - PROCESS | 1998 | Cycle 1(1): loaded http://localhost:49347/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49347/tests/kraken/driver.html?testName=imaging-darkroom) 02:43:13 INFO - PROCESS | 1998 | RSS: Main: 250310656 02:43:13 INFO - PROCESS | 1998 | 02:43:16 INFO - PROCESS | 1998 | Cycle 1(1): loaded http://localhost:49347/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49347/tests/kraken/driver.html?testName=imaging-desaturate) 02:43:17 INFO - PROCESS | 1998 | RSS: Main: 249954304 02:43:17 INFO - PROCESS | 1998 | 02:43:20 INFO - PROCESS | 1998 | Cycle 1(1): loaded http://localhost:49347/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49347/tests/kraken/driver.html?testName=json-parse-financial) 02:43:21 INFO - PROCESS | 1998 | RSS: Main: 250023936 02:43:21 INFO - PROCESS | 1998 | 02:43:22 INFO - PROCESS | 1998 | Cycle 1(1): loaded http://localhost:49347/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49347/tests/kraken/driver.html?testName=json-stringify-tinderbox) 02:43:23 INFO - PROCESS | 1998 | RSS: Main: 249688064 02:43:23 INFO - PROCESS | 1998 | 02:43:25 INFO - PROCESS | 1998 | Cycle 1(1): loaded http://localhost:49347/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49347/tests/kraken/driver.html?testName=stanford-crypto-aes) 02:43:25 INFO - PROCESS | 1998 | RSS: Main: 249647104 02:43:25 INFO - PROCESS | 1998 | 02:43:28 INFO - PROCESS | 1998 | Cycle 1(1): loaded http://localhost:49347/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49347/tests/kraken/driver.html?testName=stanford-crypto-ccm) 02:43:28 INFO - PROCESS | 1998 | RSS: Main: 249610240 02:43:28 INFO - PROCESS | 1998 | 02:43:31 INFO - PROCESS | 1998 | Cycle 1(1): loaded http://localhost:49347/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49347/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 02:43:32 INFO - PROCESS | 1998 | RSS: Main: 250064896 02:43:32 INFO - PROCESS | 1998 | 02:43:35 INFO - PROCESS | 1998 | Cycle 1(1): loaded http://localhost:49347/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49347/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 02:43:35 INFO - PROCESS | 1998 | RSS: Main: 249847808 02:43:35 INFO - PROCESS | 1998 | 02:43:37 INFO - PROCESS | 1998 | Cycle 1(1): loaded http://localhost:49347/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49347/tests/kraken/driver.html?testName=ai-astar) 02:43:37 INFO - PROCESS | 1998 | RSS: Main: 251695104 02:43:37 INFO - PROCESS | 1998 | 02:43:37 INFO - PROCESS | 1998 | __start_tp_report 02:43:37 INFO - PROCESS | 1998 | _x_x_mozilla_page_load 02:43:37 INFO - PROCESS | 1998 | _x_x_mozilla_page_load_details 02:43:37 INFO - PROCESS | 1998 | |i|pagename|runs| 02:43:37 INFO - PROCESS | 1998 | |0;ai-astar;106;126;107;156;170;171;119;117;114;117 02:43:37 INFO - PROCESS | 1998 | |1;audio-beat-detection;148;119;112;153;113;117;116;117;124;146 02:43:37 INFO - PROCESS | 1998 | |2;audio-dft;198;200;201;212;259;202;279;281;214;202 02:43:37 INFO - PROCESS | 1998 | |3;audio-fft;83;59;61;88;61;58;60;60;98;83 02:43:37 INFO - PROCESS | 1998 | |4;audio-oscillator;92;73;84;74;69;70;81;70;72;86 02:43:37 INFO - PROCESS | 1998 | |5;imaging-gaussian-blur;98;113;103;87;89;86;89;86;89;84 02:43:37 INFO - PROCESS | 1998 | |6;imaging-darkroom;96;96;96;95;96;95;96;115;151;105 02:43:37 INFO - PROCESS | 1998 | |7;imaging-desaturate;85;147;144;96;92;95;96;98;142;144 02:43:37 INFO - PROCESS | 1998 | |8;json-parse-financial;60;58;57;64;60;63;63;61;61;64 02:43:37 INFO - PROCESS | 1998 | |9;json-stringify-tinderbox;76;69;72;80;67;71;74;70;76;74 02:43:37 INFO - PROCESS | 1998 | |10;stanford-crypto-aes;85;84;88;89;87;89;85;88;83;90 02:43:37 INFO - PROCESS | 1998 | |11;stanford-crypto-ccm;167;154;148;132;140;145;145;147;194;152 02:43:37 INFO - PROCESS | 1998 | |12;stanford-crypto-pbkdf2;171;210;187;173;173;171;172;173;171;173 02:43:37 INFO - PROCESS | 1998 | |13;stanford-crypto-sha256-iterative;68;68;68;66;70;68;68;66;66;67 02:43:37 INFO - PROCESS | 1998 | __end_tp_report 02:43:37 INFO - PROCESS | 1998 | __start_cc_report 02:43:37 INFO - PROCESS | 1998 | _x_x_mozilla_cycle_collect,971 02:43:37 INFO - PROCESS | 1998 | __end_cc_report 02:43:37 INFO - PROCESS | 1998 | __startTimestamp1488192217921__endTimestamp 02:43:37 INFO - PROCESS | 1998 | 02:43:37 INFO - PROCESS | 1998 | ------- Summary: start ------- 02:43:37 INFO - PROCESS | 1998 | Number of tests: 14 02:43:37 INFO - PROCESS | 1998 | 02:43:37 INFO - PROCESS | 1998 | [#0] ai-astar Cycles:10 Average:130.30 Median:122.50 stddev:25.37 (20.7%) stddev-sans-first:25.34 02:43:37 INFO - PROCESS | 1998 | Values: 106.0 126.0 107.0 156.0 170.0 171.0 119.0 117.0 114.0 117.0 02:43:37 INFO - PROCESS | 1998 | 02:43:37 INFO - PROCESS | 1998 | [#1] audio-beat-detection Cycles:10 Average:126.50 Median:121.50 stddev:15.95 (13.1%) stddev-sans-first:14.90 02:43:37 INFO - PROCESS | 1998 | Values: 148.0 119.0 112.0 153.0 113.0 117.0 116.0 117.0 124.0 146.0 02:43:37 INFO - PROCESS | 1998 | 02:43:37 INFO - PROCESS | 1998 | [#2] audio-dft Cycles:10 Average:224.80 Median:213.00 stddev:34.13 (16.0%) stddev-sans-first:34.80 02:43:37 INFO - PROCESS | 1998 | Values: 198.0 200.0 201.0 212.0 259.0 202.0 279.0 281.0 214.0 202.0 02:43:37 INFO - PROCESS | 1998 | 02:43:37 INFO - PROCESS | 1998 | [#3] audio-fft Cycles:10 Average:71.10 Median:72.00 stddev:15.13 (21.0%) stddev-sans-first:15.43 02:43:37 INFO - PROCESS | 1998 | Values: 83.0 59.0 61.0 88.0 61.0 58.0 60.0 60.0 98.0 83.0 02:43:37 INFO - PROCESS | 1998 | 02:43:37 INFO - PROCESS | 1998 | [#4] audio-oscillator Cycles:10 Average:77.10 Median:77.50 stddev:8.05 (10.4%) stddev-sans-first:6.48 02:43:37 INFO - PROCESS | 1998 | Values: 92.0 73.0 84.0 74.0 69.0 70.0 81.0 70.0 72.0 86.0 02:43:37 INFO - PROCESS | 1998 | 02:43:37 INFO - PROCESS | 1998 | [#5] imaging-gaussian-blur Cycles:10 Average:92.40 Median:88.00 stddev:9.34 (10.6%) stddev-sans-first:9.68 02:43:37 INFO - PROCESS | 1998 | Values: 98.0 113.0 103.0 87.0 89.0 86.0 89.0 86.0 89.0 84.0 02:43:37 INFO - PROCESS | 1998 | 02:43:37 INFO - PROCESS | 1998 | [#6] imaging-darkroom Cycles:10 Average:104.10 Median:96.00 stddev:17.68 (18.4%) stddev-sans-first:18.51 02:43:37 INFO - PROCESS | 1998 | Values: 96.0 96.0 96.0 95.0 96.0 95.0 96.0 115.0 151.0 105.0 02:43:37 INFO - PROCESS | 1998 | 02:43:37 INFO - PROCESS | 1998 | [#7] imaging-desaturate Cycles:10 Average:113.90 Median:93.50 stddev:26.38 (28.2%) stddev-sans-first:25.82 02:43:37 INFO - PROCESS | 1998 | Values: 85.0 147.0 144.0 96.0 92.0 95.0 96.0 98.0 142.0 144.0 02:43:37 INFO - PROCESS | 1998 | 02:43:37 INFO - PROCESS | 1998 | [#8] json-parse-financial Cycles:10 Average:61.10 Median:62.00 stddev:2.42 (3.9%) stddev-sans-first:2.54 02:43:37 INFO - PROCESS | 1998 | Values: 60.0 58.0 57.0 64.0 60.0 63.0 63.0 61.0 61.0 64.0 02:43:37 INFO - PROCESS | 1998 | 02:43:37 INFO - PROCESS | 1998 | [#9] json-stringify-tinderbox Cycles:10 Average:72.90 Median:74.00 stddev:3.87 (5.2%) stddev-sans-first:3.94 02:43:37 INFO - PROCESS | 1998 | Values: 76.0 69.0 72.0 80.0 67.0 71.0 74.0 70.0 76.0 74.0 02:43:37 INFO - PROCESS | 1998 | 02:43:37 INFO - PROCESS | 1998 | [#10] stanford-crypto-aes Cycles:10 Average:86.80 Median:88.00 stddev:2.39 (2.7%) stddev-sans-first:2.45 02:43:37 INFO - PROCESS | 1998 | Values: 85.0 84.0 88.0 89.0 87.0 89.0 85.0 88.0 83.0 90.0 02:43:37 INFO - PROCESS | 1998 | 02:43:37 INFO - PROCESS | 1998 | [#11] stanford-crypto-ccm Cycles:10 Average:152.40 Median:150.00 stddev:17.24 (11.5%) stddev-sans-first:17.46 02:43:37 INFO - PROCESS | 1998 | Values: 167.0 154.0 148.0 132.0 140.0 145.0 145.0 147.0 194.0 152.0 02:43:37 INFO - PROCESS | 1998 | 02:43:37 INFO - PROCESS | 1998 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:177.40 Median:173.00 stddev:12.40 (7.2%) stddev-sans-first:12.94 02:43:37 INFO - PROCESS | 1998 | Values: 171.0 210.0 187.0 173.0 173.0 171.0 172.0 173.0 171.0 173.0 02:43:37 INFO - PROCESS | 1998 | 02:43:37 INFO - PROCESS | 1998 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.50 Median:68.00 stddev:1.27 (1.9%) stddev-sans-first:1.33 02:43:37 INFO - PROCESS | 1998 | Values: 68.0 68.0 68.0 66.0 70.0 68.0 68.0 66.0 66.0 67.0 02:43:37 INFO - PROCESS | 1998 | -------- Summary: end -------- 02:43:37 INFO - PROCESS | 1998 | 02:43:37 INFO - PROCESS | 1998 | 1488192217943 addons.xpi WARN Add-on disableSHA1rollout@mozilla.org is missing bootstrap method shutdown 02:43:38 INFO - TEST-INFO | 1998: exit 0 02:43:38 INFO - TEST-OK | kraken | took 61420ms 02:43:38 INFO - SUITE-END | took 1176s 02:43:39 INFO - Completed test suite (00:19:36) 02:43:39 INFO - javascript benchmark 02:43:39 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7337.818379176244, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2847.5301575240087, 3341.431956567383, 3343.582052243468, 3344.2809795893877, 3374.696277335041, 4352.890013046676, 5701.942980570193, 5772.480476757091, 5802.535797136229, 5819.941800581998, 5597.804076857311, 5709.4290570942885, 5717.2281741964825, 5729.512991395726, 5745.0, 5182.37811462625, 5211.557017653504, 5222.817201397952, 5258.316418865542, 5273.709945952971, 5256.237845512398, 5262.631615786897, 5268.973655131719, 5278.313819203518, 5297.602679799012, 5136.922946155811, 5157.149070403398, 5255.211718242275, 5257.447967963366, 5317.7607007684555, 5222.373315202162, 5248.343957005374, 5273.920891186635, 5310.0, 5324.387695414999, 5123.641345105844, 5223.817166399158, 5236.8690782730355, 5238.423773384963, 5334.786608535693, 5588.022096133201, 5664.971675141639, 5752.194692743019, 5872.5889187756875, 5897.439743224387, 6130.795626845448, 6150.892839288927, 6153.969230153865, 6160.661163636043, 6262.718177682015, 6211.4409703126485, 6212.254529456449, 6214.875702485925, 6214.9689251553455, 6222.97320942044, 5802.158686990405, 5819.563532735062, 5829.0, 5843.094320380305, 5873.4126587340925, 5477.287952566141, 5833.79581714638, 6180.351063138396, 6202.689865506707, 6206.627602343874, 6145.723442445101, 6160.815175544741, 6179.134921111048, 6188.566800324024, 6200.689965501797, 6179.134921111048, 6232.283287421914, 6260.123582698426, 6260.937390626126, 6276.686165691697, 6127.295361033449, 6132.294322189902, 6155.661438620829, 6180.351063138396, 6244.562880598314, 6200.658963757037, 6208.410201030895, 6223.937760622336, 6224.782132625336, 6243.125962365272, 4953.628477864175, 4972.776225069881, 4986.7257300848805, 4995.575376093073, 5017.347744793226, 4386.8903277417685, 4389.648828093745, 4427.889302767457, 4454.7549884756, 4474.082813023322, 5890.204822349014, 5940.881182376328, 5988.700564971821, 6020.277566692025, 6058.848528786727, 6217.347178546188, 6224.564280500322, 6242.500599951993, 6243.094751261042, 6243.531735119885, 5714.942850571441, 5853.385394533598, 5870.178175055496, 5908.0, 5909.3204281507315, 5431.511163995259, 5499.395066542677, 5524.668519888739, 5530.668159910419, 5553.861153471195, 5613.989481893217, 6341.080543321193, 6379.936200637934, 6386.680665966683, 6411.262704788823, 6150.507959363151, 6159.19930409044, 6177.166082578794, 6178.691065446799, 6239.532035097295, 6334.0, 6350.809475715736, 6358.205224346957, 6359.841003974938, 6407.8718425630295, 5227.372715274191, 6240.656763877849, 6249.625022498664, 6258.311585725658, 6261.060840873905, 6119.112728654409, 6176.505879529716, 6203.2245969253845, 6248.625082495065, 6255.843603909939, 5972.761089556369, 6156.1381406604005, 6160.0, 6160.075988601746, 6165.753369865155, 5112.288293665568, 6160.229971253593, 6174.66039367839, 6176.320604733565, 6222.657753823583, 4849.2726091086615, 5190.714510701948, 5511.0, 6012.729427175875, 6054.515638748801, 4700.882477938079, 5407.0, 6135.07973803933, 6191.071339299141, 6191.659458729813, 6034.8995429467, 6036.577439579363, 6071.058985857199, 6072.544559157993, 6093.146959425772], "value": 5680.680628935168, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [450.20089341418975, 450.8241785703576, 453.36302494994544, 478.61710631494793, 521.7339157029916, 40898.0, 42267.0, 42297.0, 42304.0, 42331.0, 43281.13437731243, 44019.0, 44032.45886393977, 44068.55931440685, 44112.0, 7739.303462688357, 8460.323174146084, 9140.314476414278, 9358.11097945694, 9393.483358415284, 11169.0, 11483.655490335303, 11530.135239857002, 11642.068634509262, 11644.0, 11546.826797598002, 11580.884191158108, 11599.014083802887, 11609.419529023515, 11626.767464650744, 11364.431778411088, 11422.71443213918, 11430.771384572345, 11455.083593312556, 11464.541418343257, 11450.370229637367, 11501.0, 11532.654020379403, 11566.768664626745, 11574.421278936104, 42080.94797630053, 42481.57518424807, 42614.57385426137, 42760.3585946211, 42773.57226427758, 3231.676832316773, 3940.7044471664453, 3976.622220889011, 4037.313656678372, 4089.8364065437345, 26366.60450093231, 30142.84928575343, 30933.91731289394, 31742.0, 32145.0, 3095.7059079387423, 3151.8896838610767, 3244.3511297740547, 3386.153461634591, 3395.456726923705, 381222.0, 426882.0, 493631.0, 495402.0, 500164.0, 62522.68738656278, 62717.0, 62839.371606284265, 62959.0, 68246.65876670585, 70691.2930870695, 71274.28725712677, 71613.56772864514, 72038.0, 72115.0, 16857.0, 17410.21654025572, 17442.563935901704, 17453.040082795313, 17454.0, 10074.647387341407, 12498.562550310695, 12920.612381628565, 13128.212307261594, 13201.73596528064, 19365.903170484056, 19383.0, 19446.027698615297, 19449.513762155777, 19471.902640486707, 5317.388500322435, 5453.918191227135, 6451.129172540005, 6517.283098859121, 6527.314631963671, 842.8862103616055, 844.1684940333766, 854.231191927258, 859.7377799771004, 877.8990416102102, 24152.75847241505, 30051.398972020437, 30761.0, 31081.067567972997, 31221.375572488876, 915.5193523400135, 941.7474758571084, 1005.3163848582888, 1029.9134413194151, 1153.4175241502987, 15471.458498952708, 15843.445479408396, 16037.358505659642, 16073.517794466185, 16309.42916997923, 546879.5312046829, 599679.0, 601466.0, 605138.0, 607322.0, 62206.68896655578, 62240.0, 62395.376046238955, 62531.0, 62581.68709156425, 1375.3329635127157, 1418.9290535473185, 1435.6913263648157, 1435.6913263648366, 1444.1840360196666, 28214.0, 29133.417331653673, 29606.703932960394, 29819.956301529342, 30289.848550757106, 54453.72773136109, 54884.725576371864, 54997.725011374685, 55015.44984550263, 55102.89794204013, 529242.0, 532700.0, 536452.0, 537491.0, 538798.0, 42284.0, 42645.0, 43220.0, 43264.0, 43279.567204327555, 46843.0, 47274.52725472839, 47747.761261193475, 48346.758266208446, 48699.53901382964, 37805.81097094497, 39101.82694519169, 39154.82535523939, 40246.79259622216, 40288.79855600703, 39018.609813902636, 39368.66944636145, 40408.59591404166, 40438.59561404466, 40575.797121014206, 37291.254174915804, 37578.87263382103, 38282.61717382902, 38401.61598384092, 38436.80781596074, 32525.512117318733, 33996.0, 34222.0, 34670.0, 34722.65277347194, 25185.24444266675, 25323.0, 25368.365790854637, 25375.36561585975, 25509.489810203322], "value": 20668.53995736833, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [364.29508900277966, 387.25646758224207, 390.60182346775304, 395.05581659832995, 422.77804152819766, 19529.609407811837, 20749.792502074975, 20761.896190519063, 20777.480562985933, 21771.564568708618, 20604.0, 21139.837308948, 21426.678599820978, 21557.568848623017, 21680.02439890205, 3705.999999999993, 5105.106606343894, 5729.0833466645345, 5733.512651424613, 5793.913091303634, 4804.711717296974, 5467.824417750183, 5632.211490391349, 5679.432056794329, 5710.314762228518, 5600.411956744544, 5668.914966275509, 5678.545716342683, 5679.949209396254, 5686.573506986972, 5565.638233514808, 5594.2447769551, 5658.519025882805, 5662.773489060432, 5686.943130568683, 5024.371953505812, 5025.12060389434, 5030.446650868402, 5035.3454050973505, 5041.924371134436, 25680.871595641904, 25812.0, 26140.0, 26146.346341341618, 26280.0, 2706.3369474478877, 2768.584712293152, 2771.4457108578363, 2777.222377734238, 2779.958300625492, 15535.922320388325, 15705.842941570552, 15836.287367068395, 15930.362785488567, 16263.10552919589, 1807.1145138881984, 1844.170123444442, 1849.8982555959417, 1856.4245084023996, 1861.3485280151974, 120495.3975230127, 122276.38861805634, 122827.77172228253, 124616.37691811574, 131353.99999999904, 11043.447827608587, 11492.195546311677, 11517.25137866036, 11534.307941523535, 11722.824157637642, 12091.879081209076, 12908.612741617766, 13064.673383165496, 14096.50662226817, 14495.710085798224, 8052.59737013147, 8578.571071446402, 8723.214910657944, 8723.781905452288, 8795.736127916172, 7479.214682458372, 8597.398182127192, 8597.484150950839, 8796.78008049791, 8927.375083744206, 7057.929420705727, 7182.3894968928225, 7190.820229494305, 7194.244604316576, 7201.567905925556, 1896.8008359122093, 1935.0614951748391, 1940.3208876892975, 1941.0973897137617, 1942.4852414110271, 873.3886279604302, 894.2577660541734, 899.7795540092719, 911.4394647292, 940.4498368454531, 21565.137394504043, 22224.77775222227, 22330.77669223287, 22460.213892513682, 22481.43796405103, 875.9956200218958, 925.5048549026238, 934.6401635370431, 951.4861974533675, 960.558143254111, 10254.692359229235, 10620.256582039337, 10705.785884282272, 10789.460526973777, 10789.676309710721, 486215.0, 748629.0, 757437.0, 760308.0, 770906.0, 83079.16920830835, 150998.0, 158252.0, 162460.0, 166321.0, 902.6434558349415, 1068.6847380022757, 1110.566878917223, 1116.5980247111033, 1117.2514415341902, 18899.433017009513, 19149.0, 19462.708059379405, 19590.0, 19606.803931960498, 57703.845923080466, 59597.106043408516, 59745.402545973986, 60433.0, 60692.87574934856, 63828.0, 66209.0068648961, 88122.0, 88837.55581222053, 89671.30986070428, 7215.314545118205, 7540.396768258416, 8311.875321870057, 8328.833423331378, 8444.66221351139, 12037.819432708342, 12259.938700306799, 13110.672233194246, 13150.408231629792, 13460.05779595419, 10938.343699378063, 11234.045106166068, 11243.943780281374, 11444.42777861087, 11474.196806223485, 9137.543122843965, 10852.620158294421, 10903.509342079784, 11157.10743140563, 11609.071274298205, 9963.202943764627, 10139.543720532742, 10428.58285668595, 11316.038136758467, 11631.0, 6228.984615994987, 7636.465447418849, 7637.350825179922, 7767.611619419119, 7783.1827658097345, 8155.63299651529, 11050.613228536962, 11162.38606876597, 11220.775584488429, 11348.602798901999], "value": 9879.932053141005, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1393.7484574398577, 1440.0783498560916, 1442.1779585636184, 1457.4680241711776, 1460.5618314505648, 7539.095308562966, 7792.220777922205, 8106.837863242732, 8498.405111642187, 8505.192006759356, 8059.677612895477, 8372.83254334913, 8373.07896131425, 8410.201030902055, 8469.957650211756, 2310.376198426424, 2362.2558893948435, 2364.408897775556, 2367.0768400323886, 2376.0, 2330.9184178553755, 2369.218158007862, 2379.131616959806, 2379.6073647848084, 2389.5101504191684, 1896.9810301896941, 2042.28520017994, 2334.253039027512, 2354.0936739355384, 2367.538330025646, 1948.805119488054, 1954.8436125109956, 2295.8737269450176, 2329.77867102625, 2340.20433052762, 2010.427028296943, 2307.1348244408346, 2319.5824751544715, 2345.202631105415, 2345.296411076687, 8372.41393102483, 8424.705135320295, 8442.07137214906, 8448.831023379498, 8466.66133354665, 5077.0, 5234.7644356004075, 5255.921161182585, 5263.078961181778, 5279.788808447657, 2989.760819134464, 3355.5470011548373, 3431.330890476358, 3507.1056880495566, 3524.2422879080864, 1220.3044264769085, 1233.962981110568, 1719.3810228317795, 1749.457668122886, 1835.3392778599693, 10909.890901090967, 10915.0, 11051.944740276247, 11064.944675276573, 11065.391403472806, 4587.885302867455, 4782.521747825259, 4785.353977213032, 4786.904261914742, 4830.130576496194, 8781.473111613324, 8816.382853200343, 8817.515036673045, 8830.823383532294, 8848.336374771918, 5141.640085194001, 5154.020736060136, 5155.200943853633, 5159.7678104485385, 5161.406438259573, 4640.141573808856, 4735.337052812609, 4758.11974784659, 4774.641901857305, 4876.390451193601, 3703.925921481555, 3794.2601192767697, 3808.961910380916, 3837.9616203837604, 3845.2309538092495, 1022.2180032275315, 1189.8631657359515, 1190.345310079453, 1197.0483465644834, 1197.8323034775137, 768.2317682317682, 779.5946108023909, 900.0144841398658, 905.5834316214615, 921.5115988526092, 4625.190591646516, 5278.656887302313, 5327.334083239595, 5399.325084364455, 5405.540529055074, 956.6651671914774, 993.7913038262046, 1004.9598016079422, 1011.191047162282, 1046.9162467002623, 3686.7972261525338, 3724.8137593120236, 3847.0574709196426, 3867.207222519377, 3891.591382904811, 9936.403815771077, 10216.182705383551, 10216.795664086825, 10228.284020118668, 10232.23258255619, 10081.69754907354, 10165.44090075053, 10170.237232207613, 10175.949120254352, 10187.694369168938, 7653.923460765321, 7848.882266766003, 7949.244821741925, 7993.480423772437, 8039.718609848745, 5391.676499410048, 5399.811006614907, 5426.511613954763, 5671.149327600893, 5686.061799802987, 7730.806729831799, 7970.561619110773, 8490.57547122629, 8898.510581918057, 9390.060993900555, 8715.694950676949, 8775.824483510167, 9253.213476854542, 9462.810743784948, 9602.08780165883, 7050.929490705027, 7341.0, 7350.411967042517, 7355.448341374526, 7368.631568421664, 7687.654055567625, 7928.127905930228, 7949.244821741925, 7971.880421793794, 8049.114597394399, 7042.119735033121, 7057.541259818096, 7083.0, 7125.643717814192, 7138.964305178441, 6850.862982740417, 6953.860922781415, 6977.372036516738, 6983.44132469391, 7057.329553692391, 6198.969005154945, 6352.523560732871, 6363.6499992499, 6430.582012169381, 6436.16329877113, 4431.556844315543, 4446.221911165469, 4539.7049191802425, 4973.502649734997, 5010.173321402073, 4359.891002724958, 5027.245913113063, 5068.82259120929, 5077.39071311445, 5135.974320128375], "value": 4416.722311512564, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [338.6207447658623, 359.07179939855473, 369.8834867016889, 372.90118118698535, 379.16016024505734, 4043.3018132384395, 4750.0, 5423.91864122038, 5514.145307477343, 5532.391436941938, 3983.83689044948, 5026.924596131052, 5040.395152581696, 5305.761240744167, 5432.70120143392, 3881.3013657541633, 3953.8813835584833, 4307.289297265947, 4347.456567929009, 4360.321276308612, 4015.1969606078756, 4092.4600220808934, 4350.086481838817, 4350.347447882811, 4366.257736184841, 3602.8919132426067, 3985.3623420252766, 4023.7928621413607, 4223.838444427776, 4256.851010214643, 3595.6404359564094, 4211.347241177622, 4240.2367573836855, 4271.572842715735, 4274.935875961863, 3248.4315244832296, 3799.0, 3829.1958688675413, 3852.672522835562, 3866.0, 4507.639388848885, 5031.006551102088, 5331.626786124973, 5374.435684253136, 5379.731013449351, 4014.4781178446906, 4905.950940490585, 4940.308356830046, 4974.0, 4986.202207646796, 4883.560479556857, 5648.740503798473, 5804.042333015047, 5807.245058142415, 5895.61678490901, 3537.7877327360443, 3751.8124093795473, 4058.594140585947, 4108.116754897711, 4167.291560434734, 5815.796447124372, 6006.219191505076, 6063.18147050147, 6077.72367802762, 6097.542684298695, 4129.0, 4215.915681686349, 4234.407182994383, 4262.616364527207, 4291.67812414064, 3440.7419443541835, 3674.52231209941, 3752.62473752628, 4253.170631726845, 4297.806598703066, 4682.51471711675, 4777.8088876445245, 4840.370751802314, 4851.248056551241, 4948.134076536664, 4653.697509661861, 5270.525652691276, 5934.715134673182, 5957.331947054831, 5981.641101533922, 1251.9248845069326, 1258.4337048328289, 1294.6633875192329, 1314.3362601886176, 1353.9661508462368, 839.1231163434226, 962.9759256018656, 973.4840534516718, 987.9851802222972, 1000.9299349045642, 4589.885252868705, 4687.0, 5033.6224783141415, 5193.324867767166, 5240.633155679066, 4905.901881962341, 5387.572293342268, 5679.0, 5705.800296989669, 5739.253896993364, 4343.739375637472, 4655.255159174516, 4784.569388755029, 5057.72182529957, 5121.718305493159, 2574.4336246025846, 2887.6246088008843, 2938.9559156612668, 2946.690597487233, 2975.7916945813586, 5070.898582028339, 5111.821086262037, 5760.337561180434, 5930.797369420465, 5950.256217972754, 5340.759665815048, 5526.226328314039, 5740.6268592541355, 5822.369736473795, 6071.817845464643, 5922.674252916045, 6220.098085777537, 6259.436650701458, 6265.402322407756, 6337.714802833883, 5486.396496385474, 5574.721263936706, 5602.730893987941, 5652.463016013472, 5940.2277703899945, 4598.678092533491, 4743.051389722138, 5041.773120209526, 5715.514181294637, 5934.673592952429, 5196.0, 5504.834854954358, 5959.374265702038, 6206.317305096526, 6232.657203853832, 3803.7337386382687, 3900.3369427196876, 3940.5074365704286, 3948.269570129535, 3964.742291751027, 4535.0, 5888.293404791452, 5904.8780731660845, 5979.850503737267, 5986.580939334205, 5613.943860561342, 5698.287714035746, 5714.771154297809, 5727.341355743976, 5792.652440853562, 4940.987097644902, 4977.676451030671, 5412.836240208374, 5416.674999500043, 5779.479846813807, 4922.581580565692, 5130.6152038598, 5597.524210442158, 5633.5916020994755, 5674.517665998436, 4836.46798852133, 5704.229928959538, 5704.80033198836, 5725.398833122462, 5775.48020678141, 4975.253711943237, 5524.779008839601, 5621.775128994795, 5665.753534222465, 5674.0], "value": 4185.246833531456, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [687.4706476013466, 699.7970588529327, 774.6049514747481, 808.0, 815.5473712089789, 12739.044571657116, 14712.411503539859, 15260.0, 15410.845891541096, 15551.844481555196, 13658.453661853513, 14542.636434089127, 14560.271986400665, 14560.708785824278, 14628.634284142901, 846.8433339832135, 869.074435725953, 887.6449420231927, 888.3958907942597, 890.0387581412092, 672.7813460625292, 692.6709812838911, 749.5053264845203, 865.4331412924545, 866.5493943149559, 905.0135352465811, 936.7002559181064, 936.8266870628955, 939.9154076133146, 945.4232917920064, 907.5507623726264, 911.1526280559077, 911.334725650279, 913.8811954445946, 916.193749500442, 693.2616763147232, 700.4431476975816, 701.4949236549676, 702.4345401951427, 704.1303989572873, 15252.923735381251, 15597.064176149353, 15620.687586248212, 15670.764938525932, 15744.0, 14418.42326306957, 14587.270636468134, 14650.510165934726, 14738.336774845051, 14762.261886905611, 11361.0, 11592.420378981018, 11667.474963626571, 11674.29954202742, 11708.531658733726, 3636.3636363636256, 3671.6695497405094, 3710.517632707758, 3711.0536813112753, 3876.186000939806, 100369.49445758319, 114638.0, 115372.0, 115765.0, 119034.0, 11108.555657773599, 11196.384198868978, 11254.94372528132, 11263.26788758728, 11392.715182120513, 23851.880740596185, 24514.877425612758, 24557.87721061419, 24642.630360544605, 24692.753072469404, 7615.5430674159725, 7653.081630204411, 7733.961330193313, 7838.176991415933, 7993.640286187019, 4197.874063778091, 4232.365145228179, 4303.053328267776, 4309.655227581786, 4319.265724826787, 3154.3533575616943, 3203.6315823680575, 3203.8237896915894, 3209.069369882708, 3236.465983112807, 642.0497663458108, 651.1957732200823, 669.7053296549502, 669.7120238297579, 681.6148875885109, 618.77724019353, 628.7736414890636, 635.5328833307515, 641.8459569703238, 661.9503537234726, 9737.17234035115, 9793.265505087147, 9850.458224797705, 9865.161461275728, 10007.149392301735, 601.0, 607.7751232044084, 648.6853875870199, 650.177525430331, 668.7692746002622, 4712.151812673752, 4726.196546587088, 4733.83431579893, 4740.19416699162, 4744.098621261949, 101004.0, 107706.384404234, 108634.0, 110726.0, 110847.44576277067, 92356.53821730983, 95947.0, 96658.0, 97763.0, 99545.0, 66941.0, 83967.74048389401, 84166.0, 84491.0, 87249.1275087241, 7856.410769192219, 8319.417640765088, 8358.289545388467, 8358.498490090615, 8406.495610263404, 50801.983960321326, 54066.0, 54099.18851217314, 54397.18404224019, 54786.0, 66563.0, 70132.2507534063, 71361.64319178578, 71924.64037679778, 74680.13299667551, 11117.555297787998, 11204.719882003015, 11505.48225329879, 11880.168388212742, 12025.75948481008, 11975.520979160736, 11976.101792365433, 11992.100592455776, 12035.398230088636, 12060.698482538006, 11586.942065289957, 11651.184417090722, 11655.18413711032, 11663.0, 11676.94161529221, 9481.573329200064, 9733.80532389334, 9823.754406139904, 10063.194944404577, 10067.798644027225, 10946.945265273622, 11017.944910275397, 11317.320960742052, 11382.317060976036, 11520.366379849189, 9298.488583127722, 9419.481928493999, 9449.94285315013, 9669.516524173623, 9733.805323893625, 7815.92184078152, 9189.494577798017, 9379.108984646447, 9476.194523465583, 9478.952605237206], "value": 7278.944231249828, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1558.3000000000002, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [106.0, 126.0, 107.0, 156.0, 170.0, 171.0, 119.0, 117.0, 114.0, 117.0], "value": 130.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 119.0, 112.0, 153.0, 113.0, 117.0, 116.0, 117.0, 124.0, 146.0], "value": 126.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [198.0, 200.0, 201.0, 212.0, 259.0, 202.0, 279.0, 281.0, 214.0, 202.0], "value": 224.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 59.0, 61.0, 88.0, 61.0, 58.0, 60.0, 60.0, 98.0, 83.0], "value": 71.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 73.0, 84.0, 74.0, 69.0, 70.0, 81.0, 70.0, 72.0, 86.0], "value": 77.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [98.0, 113.0, 103.0, 87.0, 89.0, 86.0, 89.0, 86.0, 89.0, 84.0], "value": 92.4, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 96.0, 96.0, 95.0, 96.0, 95.0, 96.0, 115.0, 151.0, 105.0], "value": 104.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 147.0, 144.0, 96.0, 92.0, 95.0, 96.0, 98.0, 142.0, 144.0], "value": 113.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 58.0, 57.0, 64.0, 60.0, 63.0, 63.0, 61.0, 61.0, 64.0], "value": 61.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 69.0, 72.0, 80.0, 67.0, 71.0, 74.0, 70.0, 76.0, 74.0], "value": 72.9, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 84.0, 88.0, 89.0, 87.0, 89.0, 85.0, 88.0, 83.0, 90.0], "value": 86.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [167.0, 154.0, 148.0, 132.0, 140.0, 145.0, 145.0, 147.0, 194.0, 152.0], "value": 152.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [171.0, 210.0, 187.0, 173.0, 173.0, 171.0, 172.0, 173.0, 171.0, 173.0], "value": 177.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 68.0, 68.0, 66.0, 70.0, 68.0, 68.0, 66.0, 66.0, 67.0], "value": 67.5, "unit": "score"}]}]} 02:43:39 INFO - Return code: 0 02:43:39 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 02:43:39 ERROR - # TBPL SUCCESS # 02:43:39 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 02:43:39 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 02:43:39 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 02:43:39 INFO - 2017-02-27 02:43:39.266 screenresolution[2003:20508] starting screenresolution argv=screenresolution get 02:43:39 INFO - 2017-02-27 02:43:39.269 screenresolution[2003:20508] Display 0: 1600x1200x32@60 02:43:39 INFO - 2017-02-27 02:43:39.275 screenresolution[2004:20511] starting screenresolution argv=screenresolution list 02:43:39 INFO - Available Modes on Display 0 02:43:39 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 02:43:39 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 02:43:39 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 02:43:39 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 02:43:39 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 02:43:39 INFO - Intel Iris: 02:43:39 INFO - Chipset Model: Intel Iris 02:43:39 INFO - Type: GPU 02:43:39 INFO - Bus: Built-In 02:43:39 INFO - VRAM (Dynamic, Max): 1536 MB 02:43:39 INFO - Vendor: Intel (0x8086) 02:43:39 INFO - Device ID: 0x0a2e 02:43:39 INFO - Revision ID: 0x0009 02:43:39 INFO - Displays: 02:43:39 INFO - Display: 02:43:39 INFO - Resolution: 1600 x 1200 @ 60 Hz 02:43:39 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 02:43:39 INFO - Display Serial Number: 1600x1200 60 02:43:39 INFO - Main Display: Yes 02:43:39 INFO - Mirror: Off 02:43:39 INFO - Online: Yes 02:43:39 INFO - Rotation: Supported 02:43:39 INFO - Return code: 0 02:43:39 INFO - Running post-action listener: _resource_record_post_action 02:43:39 INFO - [mozharness: 2017-02-27 10:43:39.510828Z] Finished run-tests step (success) 02:43:39 INFO - Running post-run listener: _resource_record_post_run 02:43:39 INFO - Total resource usage - Wall time: 1197s; CPU: 55.0%; Read bytes: 458752; Write bytes: 513835008; Read time: 4; Write time: 1615 02:43:39 INFO - TinderboxPrint: CPU usage
54.5% 02:43:39 INFO - TinderboxPrint: I/O read bytes / time
458,752 / 4 02:43:39 INFO - TinderboxPrint: I/O write bytes / time
513,835,008 / 1,615 02:43:39 INFO - TinderboxPrint: CPU idle
2,177.1 (45.5%) 02:43:39 INFO - TinderboxPrint: CPU system
969.4 (20.2%) 02:43:39 INFO - TinderboxPrint: CPU user
1,643.3 (34.3%) 02:43:39 INFO - TinderboxPrint: Swap in / out
207,773,696 / 0 02:43:39 INFO - install - Wall time: 20s; CPU: 54.0%; Read bytes: 205402624; Write bytes: 202474496; Read time: 15646; Write time: 448 02:43:39 INFO - run-tests - Wall time: 1178s; CPU: 55.0%; Read bytes: 294912; Write bytes: 300293120; Read time: 2; Write time: 1131 02:43:39 INFO - Running post-run listener: _upload_blobber_files 02:43:39 INFO - Blob upload gear active. 02:43:39 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 02:43:39 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 02:43:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 02:43:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 02:43:40 INFO - (blobuploader) - INFO - Open directory for files ... 02:43:40 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 02:43:40 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:43:40 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:43:41 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 02:43:41 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:43:41 INFO - (blobuploader) - INFO - Done attempting. 02:43:41 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 02:43:41 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:43:41 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:43:42 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 02:43:42 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:43:42 INFO - (blobuploader) - INFO - Done attempting. 02:43:42 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 02:43:42 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:43:42 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:43:44 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 02:43:44 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:43:44 INFO - (blobuploader) - INFO - Done attempting. 02:43:44 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 02:43:44 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:43:44 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:43:46 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 02:43:46 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:43:46 INFO - (blobuploader) - INFO - Done attempting. 02:43:46 INFO - (blobuploader) - INFO - Iteration through files over. 02:43:46 INFO - Return code: 0 02:43:46 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 02:43:46 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 02:43:46 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/9698817f0d04103ef580038c89c9613d30435c1d5d45b6e40265bd27c828288d553e7df6e281080f0e66daceff32efa2742b13792b0a130617b6fa6ac66966af", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/5ba9ca84fdca04392a644f35284df9159c71ceecf55dc76d3af986c33716775a5b0a7b0bc2020e671a83b8b1b963c48a919baff508bbb46d51f5e48138e7fc88", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/6048b76b9858afb31278d5f376d490230e4aecc216c1df921bb255d1d57d28162481d3878cc82086e769fc93d1a7534786a1edd26c4379779bb0c81ece74e7d9", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c121f0afcd509e7398a4056dbb28d9c764b3eb1f5013bd82546526f827c434b5d472a425dc0f0324dd3a989422f5ea9ac2386365c6787a0749d7f418feb2c608"} 02:43:46 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 02:43:46 INFO - Writing to file /builds/slave/test/properties/blobber_files 02:43:46 INFO - Contents: 02:43:46 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/9698817f0d04103ef580038c89c9613d30435c1d5d45b6e40265bd27c828288d553e7df6e281080f0e66daceff32efa2742b13792b0a130617b6fa6ac66966af", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/5ba9ca84fdca04392a644f35284df9159c71ceecf55dc76d3af986c33716775a5b0a7b0bc2020e671a83b8b1b963c48a919baff508bbb46d51f5e48138e7fc88", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/6048b76b9858afb31278d5f376d490230e4aecc216c1df921bb255d1d57d28162481d3878cc82086e769fc93d1a7534786a1edd26c4379779bb0c81ece74e7d9", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c121f0afcd509e7398a4056dbb28d9c764b3eb1f5013bd82546526f827c434b5d472a425dc0f0324dd3a989422f5ea9ac2386365c6787a0749d7f418feb2c608"} 02:43:46 INFO - Running post-run listener: copy_logs_to_upload_dir 02:43:46 INFO - Copying logs to upload dir... 02:43:46 INFO - mkdir: /builds/slave/test/build/upload/logs 02:43:46 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1305.881759 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 45 secs) (at 2017-02-27 02:43:46.290926) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-02-27 02:43:46.294765) ========= 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.g7Lu7AvFsV/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.yuHrJ5xx2q/Listeners TMPDIR=/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/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-Aurora/sha512/9698817f0d04103ef580038c89c9613d30435c1d5d45b6e40265bd27c828288d553e7df6e281080f0e66daceff32efa2742b13792b0a130617b6fa6ac66966af", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/5ba9ca84fdca04392a644f35284df9159c71ceecf55dc76d3af986c33716775a5b0a7b0bc2020e671a83b8b1b963c48a919baff508bbb46d51f5e48138e7fc88", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/6048b76b9858afb31278d5f376d490230e4aecc216c1df921bb255d1d57d28162481d3878cc82086e769fc93d1a7534786a1edd26c4379779bb0c81ece74e7d9", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c121f0afcd509e7398a4056dbb28d9c764b3eb1f5013bd82546526f827c434b5d472a425dc0f0324dd3a989422f5ea9ac2386365c6787a0749d7f418feb2c608"} build_url:https://queue.taskcluster.net/v1/task/CKNJm2wjQpqyrOoQPNJ8gw/artifacts/public/build/firefox-53.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011298 build_url: 'https://queue.taskcluster.net/v1/task/CKNJm2wjQpqyrOoQPNJ8gw/artifacts/public/build/firefox-53.0a2.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/9698817f0d04103ef580038c89c9613d30435c1d5d45b6e40265bd27c828288d553e7df6e281080f0e66daceff32efa2742b13792b0a130617b6fa6ac66966af", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/5ba9ca84fdca04392a644f35284df9159c71ceecf55dc76d3af986c33716775a5b0a7b0bc2020e671a83b8b1b963c48a919baff508bbb46d51f5e48138e7fc88", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/6048b76b9858afb31278d5f376d490230e4aecc216c1df921bb255d1d57d28162481d3878cc82086e769fc93d1a7534786a1edd26c4379779bb0c81ece74e7d9", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c121f0afcd509e7398a4056dbb28d9c764b3eb1f5013bd82546526f827c434b5d472a425dc0f0324dd3a989422f5ea9ac2386365c6787a0749d7f418feb2c608"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-02-27 02:43:46.328580) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-02-27 02:43:46.328861) ========= 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.g7Lu7AvFsV/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.yuHrJ5xx2q/Listeners TMPDIR=/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/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.005543 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-02-27 02:43:46.377047) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-02-27 02:43:46.377336) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-02-27 02:43:46.377642) ========= ========= Total master_lag: 0.19 =========